Daily Usenet report: Oct 21 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Oct 21 09:09:02 newsfeed nnrpd[30124]: SERVER perl filter not defined
Oct 21 10:03:44 newsfeed nnrpd[32068]: SERVER perl filter not defined
Oct 21 10:04:22 newsfeed nnrpd[32093]: SERVER perl filter not defined
Oct 21 10:04:33 newsfeed nnrpd[32099]: SERVER perl filter not defined
Oct 21 20:07:17 newsfeed overchan[2634]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3207897	98.6%	375.5 MB	98.3%
innd	37190	1.1%	5.2 MB	1.4%
innfeed	4923	0.2%	713.7 KB	0.2%
nnrpd	4827	0.1%	495.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	3254839	100.0%	381.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3199968	96.7%
Negative hits	94167	2.8%
Positive hits	14871	0.4%
Cache misses	165	0.0%
TOTAL: 4	3309171	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.398	0.0%	170	0.000	8.224	277.000
article cleanup	00:00:42.931	0.0%	3206546	0.004	0.013	0.031
article logging	00:01:41.750	0.1%	3207083	0.015	0.032	0.998
article parse	01:10:05.109	4.9%	163941648	0.014	0.026	0.075
article write	00:31:27.871	2.2%	3189290	0.308	0.592	2.646
artlog/artcncl	00:00:00.001	0.0%	164	0.000	0.006	0.500
data move	00:02:47.843	0.2%	182008009	0.000	0.001	0.005
hisgrep/artcncl	00:00:00.197	0.0%	149	0.000	1.322	41.000
hishave/artcncl	00:00:00.330	0.0%	170	0.000	1.941	254.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:25:18.947	1.8%	3310891	0.055	0.459	6.958
history sync	00:00:00.010	0.0%	297	0.000	0.034	1.000
history write	00:38:20.254	2.7%	3201755	0.146	0.718	8.933
hiswrite/artcncl	00:00:00.036	0.0%	21	0.000	1.714	23.000
idle	13:36:04.527	56.7%	121173637	0.157	0.404	8.779
nntp read	00:57:01.894	4.0%	161321210	0.014	0.021	0.035
overview write	00:00:17.474	0.0%	3189290	0.001	0.005	0.012
perl filter	06:03:08.702	25.2%	3201755	4.841	6.805	8.552
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:05.640	0.4%	6418624	0.033	0.048	0.078
TOTAL: 23:58:30.791	23:32:04.914	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.179	0.0%	44558	0.000	0.004	0.133
article prepare	00:00:00.019	0.0%	55354	0.000	0.000	0.062
article read	00:00:16.325	0.0%	13823	0.000	1.181	100.000
backlog stats	00:00:00.224	0.0%	231601	0.000	0.001	0.023
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.854	0.0%	126756	0.000	0.015	0.075
data write	00:00:10.769	0.0%	104076	0.000	0.103	0.825
idle	23:58:07.821	100.0%	231601	73.081	372.571	1771.353
status file	00:00:00.811	0.0%	998	0.000	0.813	12.000
TOTAL: 23:58:44.581	23:58:38.002	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	11:36:39.066	99.9%	383584	0.000	108.970	27981.933
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:15.495	0.0%	767869	0.000	0.020	0.667
readart	00:00:00.601	0.0%	82	0.000	7.329	20.560
TOTAL: 11:37:31.735	11:36:55.162	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1395	3187075	3177519	4835	4721	99%	390:44:11
2	news.nask.pl	2	29404	11813	5103	12488	40%	24:03:53
3	localhost	5	4	4	0	0	100%	00:00:00
4	news.icm.edu.pl	48	167	3	157	7	1%	14:14:04
TOTAL: 4		1450	3216650	3189339	10095	17216	99%	429:02:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	828.7 GB	43.0 MB	87.9 MB	828.8 GB	99%	273.1 KB
2	news.nask.pl	72.6 MB	2.5 MB	144.0 MB	219.1 MB	33%	9.2 KB
3	news.icm.edu.pl	157.3 KB	73.7 KB	267.7 KB	498.7 KB	31%	49.9 KB
4	localhost	5.7 KB	0.0 KB	0.0 KB	5.7 KB	100%	1.4 KB
TOTAL: 4		828.8 GB	45.6 MB	232.2 MB	829.1 GB	99%	271.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 21 04:15:02 - 04:59:59	94696	3.0%	35.10	25.1 GB	3.0%	9755.12
Oct 21 05:00:00 - 05:59:59	99746	3.1%	27.71	27.0 GB	3.3%	7877.80
Oct 21 06:00:00 - 06:59:59	122541	3.8%	34.04	32.4 GB	3.9%	9423.40
Oct 21 07:00:00 - 07:59:59	131108	4.1%	36.42	35.3 GB	4.3%	10275.89
Oct 21 08:00:00 - 08:59:59	114888	3.6%	31.91	29.8 GB	3.6%	8688.12
Oct 21 09:00:00 - 09:59:59	63841	2.0%	17.73	16.3 GB	2.0%	4755.19
Oct 21 10:00:00 - 10:59:59	62484	2.0%	17.36	15.0 GB	1.8%	4367.48
Oct 21 11:00:00 - 11:59:59	68189	2.1%	18.94	15.9 GB	1.9%	4623.19
Oct 21 12:00:00 - 12:59:59	104857	3.3%	29.13	28.1 GB	3.4%	8198.85
Oct 21 13:00:00 - 13:59:59	151386	4.7%	42.05	40.0 GB	4.8%	11659.71
Oct 21 14:00:00 - 14:59:59	158540	5.0%	44.04	41.7 GB	5.0%	12159.52
Oct 21 15:00:00 - 15:59:59	139483	4.4%	38.75	34.6 GB	4.2%	10076.72
Oct 21 16:00:00 - 16:59:59	158477	5.0%	44.02	41.0 GB	5.0%	11948.61
Oct 21 17:00:00 - 17:59:59	167312	5.2%	46.48	44.1 GB	5.3%	12853.31
Oct 21 18:00:00 - 18:59:59	167075	5.2%	46.41	43.6 GB	5.3%	12697.49
Oct 21 19:00:00 - 19:59:59	146102	4.6%	40.58	38.9 GB	4.7%	11316.57
Oct 21 20:00:00 - 20:59:59	137651	4.3%	38.24	35.5 GB	4.3%	10338.46
Oct 21 21:00:00 - 21:59:59	134351	4.2%	37.32	34.2 GB	4.1%	9968.91
Oct 21 22:00:00 - 22:59:59	157478	4.9%	43.74	39.6 GB	4.8%	11528.15
Oct 21 23:00:00 - 23:59:59	166034	5.2%	46.12	42.0 GB	5.1%	12234.30
Oct 22 00:00:00 - 00:59:59	137943	4.3%	38.32	35.7 GB	4.3%	10391.87
Oct 22 01:00:00 - 01:59:59	164518	5.2%	45.70	42.2 GB	5.1%	12303.82
Oct 22 02:00:00 - 02:59:59	160390	5.0%	44.55	41.5 GB	5.0%	12079.25
Oct 22 03:00:00 - 03:59:59	142982	4.5%	39.72	38.0 GB	4.6%	11067.03
Oct 22 04:00:00 - 04:15:02	37865	1.2%	41.98	9.9 GB	1.2%	11552.90
TOTAL: 24:00:00	3189937	100.0%	36.92	827.5 GB	100.0%	10043.30

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12680	7	550	11	12112
2	newsfeed.pionier.net.pl	5100	190	4236	2	672
3	news.icm.edu.pl	16	0	3	0	13
TOTAL: 3		17796	197	4789	13	12797

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	112
it.tlc.cellulari.android	51
it.comp.os.win.windows10	10
comp.mobile.android	8
it.discussioni.energia	6
it.comp.os.win.windows7	3
de.comp.lang.php	2
de.comp.lang.perl	1
hr.rec.glazba.hifi	1
it.comp.virtualizzazione	1
uk.net.providers.gradwell	1
hr.ponuda.hardver.periferije	1
TOTAL: 12	197

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11439
Binary in non-binary group	437
Scoring filter (8)	165
Scoring filter (9)	56
Scoring filter (10)	36
EMP (fsl)	20
Scoring filter (20)	19
Scoring filter (11)	19
EMP (phl)	14
Scoring filter (13)	11
Scoring filter (12)	10
Too many newsgroups	10
Poison newsgroup	9
Scoring filter (19)	6
HTML post	3
Scoring filter (14)	2
Scoring filter (16)	1
Scoring filter (17)	1
Angle-bracket bot	1
Too many newsgroups (low_xpost)	1
TOTAL: 20	12260

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	7214	984	4602	132	5	39093	13%	24:00:46
2	icm	45667	335	26163	11910	0	0	0%	23:50:00
3	poznan	16751	243	11423	19	0	1	1%	23:47:41
4	nask	10173	40	6442	94	0	0	0%	23:50:00
5	okapi	0	0	0	0	0	2502	0%	23:50:01
TOTAL: 5		79805	1602	48630	12155	5	41596	2%	119:18:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.8 MB	646.8 KB	9.5 MB	0.1 KB/s	8.7 KB	24:00:46
2	icm	4.3 MB	2.6 GB	2.6 GB	31.8 KB/s	223.1 KB	23:50:00
3	poznan	1.1 MB	99.6 KB	1.2 MB	0.0 KB/s	4.5 KB	23:47:41
4	nask	311.8 KB	46.5 MB	46.8 MB	0.6 KB/s	357.7 KB	23:50:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		14.5 MB	2.6 GB	2.7 GB	6.5 KB/s	202.9 KB	119:18:28

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	287	40	34.2 KB	19	0	00:00:20
2	lze200.lze.lodz.pl	2	25	40.5 KB	3	0	00:14:01
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 3		293	65	74.7 KB	22	4	11:37:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	675	40	34.2 KB	19	4	00:00:26
2	*.lze.lodz.pl	2	25	40.5 KB	3	0	00:14:01
3	unresolved	6	0	0.0 KB	0	0	11:22:40
4	*.ipv.net.pl	1	0	0.0 KB	0	0	00:00:23
TOTAL: 4		684	65	74.7 KB	22	4	11:37:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.133	19.721	30.278	11:22:38
lze200.lze.lodz.pl	0.018	0.034	00:14:01
ip-193-151-49-235.ipv.net.pl	0.027	0.018	00:00:23
web.man.lodz.pl	1.132	3.676	00:00:20
mon.man.lodz.pl	0.961	3.494	00:00:03
196.52.18.39	0.009	0.012	00:00:02
david.man.lodz.pl	0.267	1.138	00:00:01
isvcs01.man.lodz.pl	0.029	0.050	00:00:00
TOTAL: 8	22.164	38.700	11:37:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.193.133	5
196.52.18.39	1
ip-193-151-49-235.ipv.net.pl	1
TOTAL: 5	391

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	33
2	pl.comp.os.ms-windows.winnt	20
3	pl.comp.os.linux	12
TOTAL: 3		65

Daily Usenet report

Oct 21 04:15:02 -- Oct 22 04:15:02