Daily Usenet report: Dec 13 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Dec 13 08:20:41 newsfeed nnrpd[29362]: SERVER perl filter not defined
Dec 13 08:21:58 newsfeed nnrpd[29395]: SERVER perl filter not defined
Dec 13 08:22:56 newsfeed overchan[20483]: input line too long, skipping
Dec 13 08:46:53 newsfeed overchan[20483]: input line too long, skipping
Dec 13 09:30:13 newsfeed nnrpd[31704]: SERVER perl filter not defined
Dec 13 09:51:20 newsfeed nnrpd[32397]: SERVER perl filter not defined
Dec 13 10:07:58 newsfeed nnrpd[558]: SERVER perl filter not defined
Dec 13 10:23:28 newsfeed nnrpd[1032]: SERVER perl filter not defined
Dec 13 10:32:31 newsfeed nnrpd[1355]: SERVER perl filter not defined
Dec 13 12:45:49 newsfeed nnrpd[5794]: SERVER perl filter not defined
Dec 13 21:09:35 newsfeed overchan[20483]: input line too long, skipping
Dec 14 01:04:25 newsfeed overchan[20483]: input line too long, skipping
Dec 14 03:17:24 newsfeed overchan[20483]: input line too long, skipping
Dec 14 03:49:23 newsfeed overchan[20483]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2334590	98.0%	271.0 MB	97.7%
innfeed	24839	1.0%	3.0 MB	1.1%
innd	18797	0.8%	3.0 MB	1.1%
nnrpd	4896	0.2%	517.9 KB	0.2%
overchan	7	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2383130	100.0%	277.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2313722	97.4%
Negative hits	51271	2.2%
Positive hits	10475	0.4%
Cache misses	68	0.0%
TOTAL: 4	2375536	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.208	0.0%	72	0.000	58.444	225.000
article cleanup	00:00:30.047	0.0%	2329973	0.009	0.013	0.017
article logging	00:01:33.813	0.1%	2330455	0.031	0.040	0.056
article parse	00:40:25.994	2.8%	108942294	0.011	0.022	0.044
article write	00:14:49.398	1.0%	2323574	0.267	0.383	0.590
artlog/artcncl	00:00:00.001	0.0%	71	0.000	0.014	0.500
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:52.646	0.1%	122642678	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.506	0.0%	72	0.000	7.028	37.000
hishave/artcncl	00:00:00.176	0.0%	72	0.000	2.444	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:19:26.428	9.7%	2388269	0.802	3.503	9.093
history sync	00:00:00.009	0.0%	290	0.000	0.031	1.000
history write	03:26:07.110	14.3%	2325973	1.805	5.317	12.546
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	12:03:43.618	50.3%	81291375	0.323	0.534	1.103
nntp read	01:05:41.537	4.6%	107036739	0.026	0.037	0.051
overview write	00:00:11.389	0.0%	2323574	0.003	0.005	0.008
perl filter	03:31:01.578	14.7%	2325972	4.308	5.444	7.389
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:29.519	0.3%	4664582	0.038	0.058	0.079
TOTAL: 24:00:03.019	23:29:57.977	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.102	0.0%	44793	0.000	0.002	0.013
article prepare	00:00:00.000	0.0%	2104	0.000	0.000	0.000
article read	00:00:04.699	0.0%	520	0.000	9.037	553.000
backlog stats	00:00:00.111	0.0%	122778	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.435	0.0%	68437	0.003	0.021	0.046
data write	00:00:03.169	0.0%	51240	0.021	0.062	0.397
idle	23:55:37.216	100.0%	122778	356.627	701.569	1817.130
status file	00:00:10.329	0.0%	8261	0.000	1.250	12.617
TOTAL: 23:56:07.379	23:55:57.061	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:14.421	54.9%	3072	0.000	4.694	69.111
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.341	1.3%	6760	0.000	0.050	1.842
readart	00:00:00.001	0.0%	38	0.000	0.026	0.250
TOTAL: 00:00:26.266	00:00:14.763	56.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	1085	2327543	2324214	955	2374	99%	447:13:16
2	news.nask.pl	2	12803	3083	5582	4138	24%	25:00:24
3	news.icm.edu.pl	40	286	65	209	12	22%	19:14:48
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1136	2340640	2327370	6746	6524	99%	491:28:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	693.1 GB	21.7 MB	55.9 MB	693.2 GB	99%	312.4 KB
2	news.nask.pl	24.5 MB	53.6 MB	71.3 MB	149.4 MB	16%	21.2 KB
3	news.icm.edu.pl	1.4 MB	34.9 KB	149.5 KB	1.5 MB	88%	20.4 KB
4	localhost	12.1 KB	0.0 KB	0.0 KB	12.1 KB	100%	1.5 KB
TOTAL: 4		693.1 GB	75.4 MB	127.3 MB	693.3 GB	99%	311.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 13 04:15:02 - 04:59:59	52815	2.3%	19.58	16.5 GB	2.4%	6418.47
Dec 13 05:00:00 - 05:59:59	85398	3.7%	23.72	29.0 GB	4.2%	8457.57
Dec 13 06:00:00 - 06:59:59	94581	4.1%	26.27	32.5 GB	4.7%	9471.99
Dec 13 07:00:00 - 07:59:59	90963	3.9%	25.27	30.3 GB	4.4%	8825.87
Dec 13 08:00:00 - 08:59:59	62422	2.7%	17.34	21.1 GB	3.0%	6132.60
Dec 13 09:00:00 - 09:59:59	86061	3.7%	23.91	27.6 GB	4.0%	8035.83
Dec 13 10:00:00 - 10:59:59	119410	5.1%	33.17	32.9 GB	4.7%	9572.44
Dec 13 11:00:00 - 11:59:59	76404	3.3%	21.22	22.7 GB	3.3%	6610.00
Dec 13 12:00:00 - 12:59:59	93736	4.0%	26.04	29.0 GB	4.2%	8452.65
Dec 13 13:00:00 - 13:59:59	77252	3.3%	21.46	23.0 GB	3.3%	6690.06
Dec 13 14:00:00 - 14:59:59	106012	4.6%	29.45	31.2 GB	4.5%	9080.21
Dec 13 15:00:00 - 15:59:59	120582	5.2%	33.49	33.8 GB	4.9%	9846.09
Dec 13 16:00:00 - 16:59:59	103179	4.4%	28.66	27.9 GB	4.0%	8126.31
Dec 13 17:00:00 - 17:59:59	112860	4.8%	31.35	33.9 GB	4.9%	9865.92
Dec 13 18:00:00 - 18:59:59	115233	5.0%	32.01	35.5 GB	5.1%	10347.96
Dec 13 19:00:00 - 19:59:59	101346	4.4%	28.15	32.3 GB	4.7%	9415.05
Dec 13 20:00:00 - 20:59:59	113139	4.9%	31.43	30.5 GB	4.4%	8886.86
Dec 13 21:00:00 - 21:59:59	120623	5.2%	33.51	30.1 GB	4.3%	8753.49
Dec 13 22:00:00 - 22:59:59	93067	4.0%	25.85	25.6 GB	3.7%	7443.36
Dec 13 23:00:00 - 23:59:59	111851	4.8%	31.07	35.3 GB	5.1%	10276.53
Dec 14 00:00:00 - 00:59:59	82070	3.5%	22.80	19.8 GB	2.9%	5776.18
Dec 14 01:00:00 - 01:59:59	85039	3.7%	23.62	24.1 GB	3.5%	7024.62
Dec 14 02:00:00 - 02:59:59	101180	4.3%	28.11	30.1 GB	4.3%	8763.29
Dec 14 03:00:00 - 03:59:59	92466	4.0%	25.68	30.5 GB	4.4%	8889.32
Dec 14 04:00:00 - 04:15:02	29954	1.3%	33.21	7.5 GB	1.1%	8664.31
TOTAL: 24:00:00	2327643	100.0%	26.94	692.6 GB	100.0%	8405.22

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	4062	9	2117	1	1935
2	newsfeed.pionier.net.pl	2801	243	1876	1	681
3	news.icm.edu.pl	13	0	2	0	11
TOTAL: 3		6876	252	3995	2	2627

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	161
it.comp.os.win.windows10	27
comp.sys.raspberry-pi	18
comp.mobile.android	16
it.tlc.cellulari.android	15
uk.rec.cycling.moderated	7
it.comp.os.win.windows7	5
nl.comp.sys.android	3
TOTAL: 8	252

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

Groups	Count
pl.praca.oferowana	1
bit.listserv.catholic	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1726
Binary in non-binary group	304
EMP (phl)	43
Scoring filter (16)	25
Scoring filter (8)	19
Scoring filter (9)	12
Scoring filter (18)	5
Scoring filter (10)	4
Scoring filter (11)	3
Poison newsgroup	2
Scoring filter (17)	1
Too many newsgroups	1
TOTAL: 12	2145

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	427
	news.nask.pl	52
	news.icm.edu.pl	2
	TOTAL: 3	481
TOTAL: 1		481

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	29531	237	15550	50	29039	0%	23:59:57
2	poznan	4233	46	3001	29	0	1%	23:58:22
3	icm	22052	32	17104	10	0	0%	23:59:35
4	nask	9021	23	7727	0	0	0%	23:59:57
5	okapi	0	0	0	0	976	0%	23:57:50
TOTAL: 5		64837	338	43382	89	30015	0%	119:55:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	8.4 MB	0.0 KB	8.4 MB	0.1 KB/s	372.6 KB	23:59:57
2	icm	3.0 MB	2.6 MB	5.6 MB	0.1 KB/s	135.6 KB	23:59:35
3	panorama	1.2 MB	157.7 KB	1.3 MB	0.0 KB/s	4.7 KB	23:59:57
4	poznan	180.7 KB	225.6 KB	406.4 KB	0.0 KB/s	5.4 KB	23:58:22
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		12.7 MB	2.9 MB	15.6 MB	0.0 KB/s	37.5 KB	119:55:41

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	38	36.5 KB	16	0	00:00:19
2	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 2		296	38	36.5 KB	16	8	00:00:25

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	584	38	36.5 KB	16	8	00:00:24
2	unresolved	96	0	0.0 KB	0	0	00:00:01
TOTAL: 2		680	38	36.5 KB	16	8	00:00:25

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.528	5.404	00:00:19
mon.man.lodz.pl	1.092	4.668	00:00:03
212.51.192.185	0.404	1.596	00:00:01
isvcs01.man.lodz.pl	0.096	0.152	00:00:01
TOTAL: 4	3.120	11.820	00:00:25

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
TOTAL: 2	384

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	36
2	pl.comp.pecet	2
TOTAL: 2		38

Daily Usenet report

Dec 13 04:15:02 -- Dec 14 04:15:02