Daily Usenet report: Dec 1 04:15:03

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Dec 1 09:00:17 newsfeed nnrpd[17893]: SERVER perl filter not defined
Dec 1 09:26:51 newsfeed nnrpd[18751]: SERVER perl filter not defined
Dec 1 09:39:36 newsfeed nnrpd[19208]: SERVER perl filter not defined
Dec 1 10:56:09 newsfeed nnrpd[22155]: SERVER perl filter not defined
Dec 1 11:50:24 newsfeed nnrpd[27701]: SERVER perl filter not defined
Dec 1 12:00:05 newsfeed nnrpd[27931]: SERVER perl filter not defined
Dec 1 12:01:35 newsfeed overchan[6277]: input line too long, skipping
Dec 1 12:02:51 newsfeed overchan[6277]: input line too long, skipping
Dec 1 12:04:32 newsfeed nnrpd[28121]: SERVER perl filter not defined
Dec 1 14:18:11 newsfeed nnrpd[522]: SERVER perl filter not defined
Dec 1 15:59:21 newsfeed overchan[6277]: input line too long, skipping
Dec 1 16:15:59 newsfeed nnrpd[4943]: SERVER perl filter not defined
Dec 1 17:05:02 newsfeed nnrpd[6735]: SERVER perl filter not defined
Dec 1 17:12:33 newsfeed overchan[6277]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3251440	98.4%	375.6 MB	98.1%
innfeed	26479	0.8%	3.1 MB	0.8%
innd	22584	0.7%	3.7 MB	1.0%
nnrpd	4847	0.1%	512.1 KB	0.1%
overchan	5	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3305356	100.0%	382.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3230174	96.3%
Negative hits	105024	3.1%
Positive hits	10722	0.3%
Cache misses	6824	0.2%
TOTAL: 4	3352744	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.379	0.0%	189	0.000	28.460	458.500
article cleanup	00:00:43.060	0.0%	3243166	0.009	0.013	0.019
article logging	00:02:04.504	0.1%	3243756	0.024	0.038	0.081
article parse	00:56:41.763	3.9%	104254319	0.022	0.033	0.046
article write	00:20:01.322	1.4%	3237759	0.185	0.371	0.603
artlog/artcncl	00:00:00.004	0.0%	162	0.000	0.025	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:05.386	0.1%	120778717	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.893	0.0%	185	0.000	4.827	92.500
hishave/artcncl	00:00:00.234	0.0%	189	0.000	1.238	13.429
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:51:29.786	7.7%	3363955	0.374	1.989	7.902
history sync	00:00:00.011	0.0%	290	0.000	0.038	1.000
history write	04:47:02.538	19.9%	3240823	0.951	5.314	14.022
hiswrite/artcncl	00:00:00.026	0.0%	4	0.000	6.500	15.000
idle	08:47:37.203	36.6%	69744308	0.189	0.454	1.003
nntp read	01:01:23.699	4.3%	101611143	0.027	0.036	0.054
overview write	00:00:16.452	0.0%	3237759	0.004	0.005	0.007
perl filter	05:33:12.069	23.1%	3240810	4.065	6.169	8.590
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:17.237	0.4%	6494218	0.033	0.049	0.077
TOTAL: 24:00:04.057	23:28:01.566	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.127	0.0%	41662	0.000	0.003	0.022
article prepare	00:00:00.002	0.0%	9858	0.000	0.000	0.038
article read	00:01:07.030	0.1%	2527	0.250	26.526	174.500
backlog stats	00:00:00.121	0.0%	132716	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	60	0.000	0.000	0.000
data read	00:00:01.156	0.0%	73163	0.002	0.016	0.035
data write	00:00:02.889	0.0%	54739	0.019	0.053	0.096
idle	23:53:57.648	99.9%	132716	415.036	648.284	1424.834
status file	00:00:08.821	0.0%	8914	0.633	0.990	10.267
TOTAL: 23:55:26.289	23:55:17.794	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:35:03.755	99.2%	20383	0.000	103.211	10002.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.207	0.1%	41436	0.000	0.029	1.000
readart	00:00:00.003	0.0%	62	0.000	0.048	0.500
TOTAL: 00:35:21.284	00:35:04.965	99.2%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	724	3251941	3235653	13681	2607	99%	497:39:34
2	news.nask.pl	3	13480	9524	1133	2823	70%	23:58:34
3	news.icm.edu.pl	42	273	24	243	6	8%	17:17:41
4	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 4		780	3265704	3245211	15057	5436	99%	538:55:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	795.8 GB	32.4 MB	52.9 MB	795.9 GB	99%	257.7 KB
2	news.nask.pl	44.8 MB	849.2 KB	19.9 MB	65.5 MB	68%	5.4 KB
3	news.icm.edu.pl	363.0 KB	12.1 KB	99.4 KB	474.5 KB	76%	15.8 KB
4	localhost	14.0 KB	0.0 KB	0.0 KB	14.0 KB	100%	1.4 KB
TOTAL: 4		795.9 GB	33.3 MB	72.8 MB	796.0 GB	99%	256.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 1 04:15:03 - 04:59:59	59621	1.8%	22.11	15.7 GB	2.0%	6118.08
Dec 1 05:00:00 - 05:59:59	72087	2.2%	20.02	21.2 GB	2.7%	6176.38
Dec 1 06:00:00 - 06:59:59	76972	2.4%	21.38	21.6 GB	2.7%	6294.72
Dec 1 07:00:00 - 07:59:59	76501	2.4%	21.25	22.0 GB	2.8%	6412.56
Dec 1 08:00:00 - 08:59:59	62858	1.9%	17.46	19.7 GB	2.5%	5732.73
Dec 1 09:00:00 - 09:59:59	69005	2.1%	19.17	21.6 GB	2.7%	6299.89
Dec 1 10:00:00 - 10:59:59	65999	2.0%	18.33	19.8 GB	2.5%	5754.32
Dec 1 11:00:00 - 11:59:59	65333	2.0%	18.15	21.4 GB	2.7%	6222.07
Dec 1 12:00:00 - 12:59:59	117415	3.6%	32.62	36.2 GB	4.6%	10543.89
Dec 1 13:00:00 - 13:59:59	137972	4.3%	38.33	35.8 GB	4.5%	10417.71
Dec 1 14:00:00 - 14:59:59	151208	4.7%	42.00	39.0 GB	4.9%	11346.64
Dec 1 15:00:00 - 15:59:59	176034	5.4%	48.90	38.1 GB	4.8%	11105.22
Dec 1 16:00:00 - 16:59:59	152312	4.7%	42.31	40.6 GB	5.1%	11835.60
Dec 1 17:00:00 - 17:59:59	143116	4.4%	39.75	39.2 GB	4.9%	11426.57
Dec 1 18:00:00 - 18:59:59	172701	5.3%	47.97	37.8 GB	4.8%	11010.16
Dec 1 19:00:00 - 19:59:59	195994	6.0%	54.44	42.7 GB	5.4%	12429.87
Dec 1 20:00:00 - 20:59:59	272702	8.4%	75.75	38.1 GB	4.8%	11089.11
Dec 1 21:00:00 - 21:59:59	187132	5.8%	51.98	41.0 GB	5.2%	11954.89
Dec 1 22:00:00 - 22:59:59	140514	4.3%	39.03	41.8 GB	5.3%	12166.71
Dec 1 23:00:00 - 23:59:59	173607	5.3%	48.22	41.3 GB	5.2%	12043.66
Dec 2 00:00:00 - 00:59:59	149752	4.6%	41.60	33.6 GB	4.2%	9795.99
Dec 2 01:00:00 - 01:59:59	172442	5.3%	47.90	39.0 GB	4.9%	11373.58
Dec 2 02:00:00 - 02:59:59	179893	5.5%	49.97	42.9 GB	5.4%	12493.45
Dec 2 03:00:00 - 03:59:59	141067	4.3%	39.19	35.6 GB	4.5%	10381.61
Dec 2 04:00:00 - 04:15:03	33019	1.0%	36.57	9.4 GB	1.2%	10952.82
TOTAL: 24:00:00	3245256	100.0%	37.56	795.3 GB	100.0%	9651.59

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3027	41	202	6	2778
2	newsfeed.pionier.net.pl	2988	270	2161	4	553
3	news.icm.edu.pl	7	0	1	0	6
TOTAL: 3		6022	311	2364	10	3337

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	193
it.comp.os.win.windows10	43
comp.mobile.android	38
it.tlc.cellulari.android	15
nl.comp.os.linux.techniek	6
comp.sys.raspberry-pi	5
comp.mobile.ipad	4
de.comp.lang.vbclassic	2
de.comp.office-pakete.ms-office.excel	2
it.comp.os.win.windows7	2
de.comm.software.mailreader	1
TOTAL: 11	311

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
pl.praca.oferowana	7
pl.sci.historia	2
bit.listserv.catholic	1
TOTAL: 3	10

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2456
Binary in non-binary group	200
EMP (phl)	33
Scoring filter (8)	18
Excessively crossposted test article	6
HTML post	5
Scoring filter (9)	4
Scoring filter (10)	4
Scoring filter (12)	1
Scoring filter (13)	1
Scoring filter (11)	1
TOTAL: 11	2729

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	386
	news.nask.pl	208
	news.icm.edu.pl	1
	TOTAL: 3	595
TOTAL: 1		595

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	24759	1083	8489	286	139	31748	4%	23:58:54
2	poznan	14874	304	9207	10	0	2	2%	23:59:50
3	icm	21932	53	18114	17	0	0	0%	23:59:57
4	nask	3282	5	2749	0	0	0	0%	23:59:28
5	okapi	0	0	0	0	0	1163	0%	23:57:51
TOTAL: 5		64847	1445	38559	313	139	32913	2%	119:56:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.7 MB	1.3 MB	7.0 MB	0.1 KB/s	5.2 KB	23:58:54
2	icm	5.0 MB	2.4 MB	7.4 MB	0.1 KB/s	107.7 KB	23:59:57
3	poznan	1.3 MB	71.3 KB	1.4 MB	0.0 KB/s	4.4 KB	23:59:50
4	nask	11.5 KB	0.0 KB	11.5 KB	0.0 KB/s	2.3 KB	23:59:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		12.0 MB	3.7 MB	15.7 MB	0.0 KB/s	9.2 KB	119:56:00

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	62	56.7 KB	24	0	00:00:28
2	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	00:00:03
TOTAL: 2		298	62	56.7 KB	24	10	00:35:20

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	682	62	56.7 KB	24	10	00:00:36
2	unresolved	2	0	0.0 KB	0	0	00:34:41
3	*.snarked.org	2	0	0.0 KB	0	0	00:00:02
TOTAL: 3		686	62	56.7 KB	24	10	00:35:20

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.35	0.976	1.704	00:34:41
web.man.lodz.pl	1.424	7.920	00:00:28
mon.man.lodz.pl	1.184	7.416	00:00:03
isvcs01.man.lodz.pl	0.084	0.280	00:00:03
news.snarked.org	0.056	0.068	00:00:02
david.man.lodz.pl	0.352	2.392	00:00:01
TOTAL: 6	4.076	19.780	00:35:20

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
news.snarked.org	2
185.48.193.35	2
TOTAL: 4	388

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.193.35	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	62	100.0%
TOTAL: 1		62	100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

Newsgroup		Count
1	pl.comp.pecet	32
2	pl.comp.os.ms-windows.winnt	30
TOTAL: 2		62

Daily Usenet report

Dec 1 04:15:03 -- Dec 2 04:15:03