Daily Usenet report: Apr 22 04:15:05

Unknown entries from news log file:

First 28 / 28 lines (100.0%)

Apr 22 08:40:37 newsfeed nnrpd[22055]: SERVER perl filter not defined
Apr 22 08:43:02 newsfeed nnrpd[22090]: SERVER perl filter not defined
Apr 22 09:46:59 newsfeed nnrpd[24516]: SERVER perl filter not defined
Apr 22 09:49:25 newsfeed nnrpd[24558]: SERVER perl filter not defined
Apr 22 09:53:55 newsfeed nnrpd[24666]: SERVER perl filter not defined
Apr 22 10:40:41 newsfeed nnrpd[26396]: SERVER perl filter not defined
Apr 22 10:53:47 newsfeed overchan[11454]: input line too long, skipping
Apr 22 14:47:19 newsfeed nnrpd[3100]: SERVER perl filter not defined
Apr 22 14:51:37 newsfeed overchan[11454]: input line too long, skipping
Apr 22 15:08:04 newsfeed nnrpd[3867]: SERVER perl filter not defined
Apr 22 15:32:44 newsfeed nnrpd[4702]: SERVER perl filter not defined
Apr 22 16:22:37 newsfeed nnrpd[6538]: SERVER perl filter not defined
Apr 22 16:34:59 newsfeed overchan[11454]: input line too long, skipping
Apr 22 20:37:41 newsfeed overchan[11454]: input line too long, skipping
Apr 22 20:47:25 newsfeed overchan[11454]: input line too long, skipping
Apr 22 20:57:57 newsfeed overchan[11454]: input line too long, skipping
Apr 22 22:26:21 newsfeed overchan[11454]: input line too long, skipping
Apr 22 23:07:43 newsfeed overchan[11454]: input line too long, skipping
Apr 22 23:20:58 newsfeed nnrpd[22005]: message repeated 2 times: [ 159-205-248-173.adsl.inetia.pl group lodz.test 0]
Apr 22 23:57:24 newsfeed overchan[11454]: input line too long, skipping
Apr 22 23:57:44 newsfeed overchan[11454]: input line too long, skipping
Apr 22 23:58:02 newsfeed overchan[11454]: input line too long, skipping
Apr 23 00:00:00 newsfeed overchan[11454]: input line too long, skipping
Apr 23 00:01:03 newsfeed overchan[11454]: input line too long, skipping
Apr 23 00:01:38 newsfeed overchan[11454]: input line too long, skipping
Apr 23 00:03:32 newsfeed overchan[11454]: input line too long, skipping
Apr 23 00:03:33 newsfeed overchan[11454]: input line too long, skipping
Apr 23 00:11:39 newsfeed nnrpd[23934]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2805417	97.0%	328.0 MB	96.8%
innfeed	47357	1.6%	5.6 MB	1.6%
innd	32939	1.1%	4.6 MB	1.4%
nnrpd	4967	0.2%	531.3 KB	0.2%
overchan	17	0.0%	2.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2890698	100.0%	338.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2785926	97.0%
Negative hits	73903	2.6%
Positive hits	11626	0.4%
Cache misses	115	0.0%
TOTAL: 4	2871570	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.616	0.0%	214	0.000	12.224	437.000
article cleanup	00:00:34.310	0.0%	2804752	0.009	0.012	0.018
article logging	00:01:42.875	0.1%	2805266	0.026	0.037	0.214
article parse	01:10:41.971	4.9%	226238898	0.014	0.019	0.043
article write	00:32:24.400	2.3%	2785838	0.361	0.698	6.167
artlog/artcncl	00:00:00.003	0.0%	207	0.000	0.014	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:03:02.095	0.2%	244619627	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.459	0.0%	192	0.000	2.391	19.000
hishave/artcncl	00:00:00.201	0.0%	214	0.000	0.939	7.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:46:32.248	3.2%	2887933	0.085	0.967	8.277
history sync	00:00:00.022	0.0%	290	0.000	0.076	4.000
history write	02:02:02.140	8.5%	2799960	0.220	2.615	10.846
hiswrite/artcncl	00:00:00.128	0.0%	22	0.000	5.818	8.778
idle	11:50:01.541	49.3%	154135041	0.130	0.276	0.388
nntp read	01:24:22.195	5.9%	224012089	0.018	0.023	0.030
overview write	00:00:14.180	0.0%	2785838	0.004	0.005	0.007
perl filter	05:13:17.171	21.8%	2799959	5.925	6.713	7.696
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:02.086	0.3%	5631392	0.030	0.043	0.085
TOTAL: 24:00:01.617	23:09:00.641	96.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.337	0.0%	139956	0.000	0.002	0.011
article prepare	00:00:00.040	0.0%	171794	0.000	0.000	0.018
article read	00:00:57.775	0.1%	42932	0.000	1.346	313.750
backlog stats	00:00:00.303	0.0%	358214	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	6	0.000	0.000	0.000
data read	00:00:03.403	0.0%	187474	0.008	0.018	0.042
data write	00:00:49.292	0.1%	168987	0.010	0.292	0.566
idle	23:51:53.594	99.8%	358214	22.571	239.839	865.869
status file	00:00:14.676	0.0%	16627	0.552	0.883	4.383
TOTAL: 23:54:15.438	23:53:59.420	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:17:10.301	99.9%	3244	0.000	3646.825	386332.800
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.160	0.0%	7210	0.000	0.022	0.500
readart	00:00:00.463	0.0%	46	0.000	10.065	90.000
TOTAL: 03:17:25.575	03:17:10.924	99.9%	-	-	-	-

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	1042	2786105	2777890	3222	4993	99%	409:14:59
2	news.nask.pl	2	25194	7953	3294	13947	31%	24:01:17
3	news.icm.edu.pl	43	319	21	293	5	6%	17:57:44
4	localhost	12	11	11	0	0	100%	00:00:00
TOTAL: 4		1099	2811629	2785875	6809	18945	99%	451:14:00

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	926.5 GB	36.5 MB	116.3 MB	926.7 GB	99%	349.2 KB
2	news.nask.pl	34.5 MB	948.3 KB	159.9 MB	195.3 MB	17%	9.1 KB
3	news.icm.edu.pl	599.1 KB	17.6 KB	60.2 KB	676.9 KB	88%	26.0 KB
4	localhost	13.9 KB	0.0 KB	0.0 KB	13.9 KB	100%	1.3 KB
TOTAL: 4		926.6 GB	37.4 MB	276.2 MB	926.9 GB	99%	346.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 22 04:15:05 - 04:59:59	87747	3.1%	32.56	29.9 GB	3.2%	11633.06
Apr 22 05:00:00 - 05:59:59	111160	4.0%	30.88	38.2 GB	4.1%	11117.14
Apr 22 06:00:00 - 06:59:59	108040	3.9%	30.01	37.8 GB	4.1%	11010.84
Apr 22 07:00:00 - 07:59:59	115777	4.2%	32.16	40.8 GB	4.4%	11890.44
Apr 22 08:00:00 - 08:59:59	113852	4.1%	31.63	39.8 GB	4.3%	11598.41
Apr 22 09:00:00 - 09:59:59	103630	3.7%	28.79	36.8 GB	4.0%	10726.56
Apr 22 10:00:00 - 10:59:59	113298	4.1%	31.47	40.7 GB	4.4%	11853.68
Apr 22 11:00:00 - 11:59:59	123002	4.4%	34.17	39.6 GB	4.3%	11547.59
Apr 22 12:00:00 - 12:59:59	119510	4.3%	33.20	39.2 GB	4.2%	11418.94
Apr 22 13:00:00 - 13:59:59	113468	4.1%	31.52	40.2 GB	4.3%	11703.88
Apr 22 14:00:00 - 14:59:59	117521	4.2%	32.64	39.7 GB	4.3%	11573.52
Apr 22 15:00:00 - 15:59:59	113897	4.1%	31.64	40.0 GB	4.3%	11648.78
Apr 22 16:00:00 - 16:59:59	117155	4.2%	32.54	40.3 GB	4.4%	11733.08
Apr 22 17:00:00 - 17:59:59	123256	4.4%	34.24	38.9 GB	4.2%	11336.37
Apr 22 18:00:00 - 18:59:59	88097	3.2%	24.47	30.0 GB	3.2%	8747.18
Apr 22 19:00:00 - 19:59:59	99029	3.6%	27.51	34.0 GB	3.7%	9890.58
Apr 22 20:00:00 - 20:59:59	118980	4.3%	33.05	41.4 GB	4.5%	12064.41
Apr 22 21:00:00 - 21:59:59	124681	4.5%	34.63	41.7 GB	4.5%	12140.57
Apr 22 22:00:00 - 22:59:59	130918	4.7%	36.37	40.7 GB	4.4%	11845.84
Apr 22 23:00:00 - 23:59:59	131534	4.7%	36.54	40.2 GB	4.3%	11715.16
Apr 23 00:00:00 - 00:59:59	108383	3.9%	30.11	34.0 GB	3.7%	9889.63
Apr 23 01:00:00 - 01:59:59	132151	4.7%	36.71	39.9 GB	4.3%	11626.95
Apr 23 02:00:00 - 02:59:59	131992	4.7%	36.66	39.9 GB	4.3%	11619.26
Apr 23 03:00:00 - 03:59:59	111088	4.0%	30.86	33.7 GB	3.6%	9820.87
Apr 23 04:00:00 - 04:15:04	27615	1.0%	30.55	8.4 GB	0.9%	9783.42
TOTAL: 23:59:59	2785781	100.0%	32.24	925.9 GB	100.0%	11237.01

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	14103	17	366	6	13714
2	newsfeed.pionier.net.pl	5319	79	4425	1	814
3	news.icm.edu.pl	7	0	2	0	5
TOTAL: 3		19429	96	4793	7	14533

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.comp.os.win.windows10	25
uk.railway	20
it.tlc.cellulari.android	15
comp.sys.raspberry-pi	12
comp.mobile.android	8
nl.comp.sys.android	7
it.comp.os.win.windows7	6
it.discussioni.energia	2
de.comp.lang.vbclassic	1
TOTAL: 9	96

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13181
Binary in non-binary group	677
Scoring filter (8)	64
Scoring filter (9)	33
Poison newsgroup	22
Excessively crossposted test article	10
Scoring filter (10)	10
Scoring filter (12)	6
Scoring filter (15)	6
Too many newsgroups	4
HTML post	3
Scoring filter (11)	1
Scoring filter (17)	1
TOTAL: 13	14018

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	332
	news.nask.pl	180
	news.icm.edu.pl	2
	TOTAL: 3	514
TOTAL: 1		514

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	62792	39962	17648	1220	0	0	63%	23:59:57
2	panorama	84868	625	56237	320	15	82938	0%	23:59:57
3	poznan	11982	215	7656	88	0	0	1%	23:59:57
4	nask	6846	16	4671	3	0	0	0%	23:59:57
5	okapi	0	0	0	0	0	1075	0%	23:57:49
TOTAL: 5		166488	40818	86212	1631	15	84013	24%	119:57:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	8.7 GB	221.5 MB	8.9 GB	108.3 KB/s	227.2 KB	23:59:57
2	panorama	2.1 MB	1.1 MB	3.2 MB	0.0 KB/s	3.4 KB	23:59:57
3	poznan	915.7 KB	372.2 KB	1.3 MB	0.0 KB/s	4.3 KB	23:59:57
4	nask	36.7 KB	5.9 KB	42.6 KB	0.0 KB/s	2.2 KB	23:59:57
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
TOTAL: 5		8.7 GB	223.0 MB	8.9 GB	21.7 KB/s	220.6 KB	119:57:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	159-205-248-173.adsl.inetia.pl	10	18	14.9 KB	6	0	4	01:29:04
2	web.man.lodz.pl	288	16	14.5 KB	8	0	0	00:00:21
3	aapp82.neoplus.adsl.tpnet.pl	4	9	7.6 KB	2	0	3	00:41:10
4	pc-212-191-92-136.p.lodz.pl	1	3	2.8 KB	2	1	0	00:33:57
5	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	0	00:00:01
TOTAL: 5		313	46	39.8 KB	18	11	7	03:17:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.adsl.inetia.pl	10	18	14.9 KB	6	0	4	01:29:04
2	*.man.lodz.pl	682	16	14.5 KB	8	10	0	00:00:28
3	*.neoplus.adsl.tpnet.pl	4	9	7.6 KB	2	0	3	00:41:10
4	*.p.lodz.pl	2	3	2.8 KB	2	1	0	01:06:40
5	unresolved	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 5		699	46	39.8 KB	18	11	7	03:17:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
159-205-248-173.adsl.inetia.pl	0.124	0.192	01:29:04
aapp82.neoplus.adsl.tpnet.pl	0.064	0.076	00:41:10
pc-212-191-92-136.p.lodz.pl	0.012	0.020	00:33:57
pc-212-191-92-138.p.lodz.pl	0.004	0.012	00:32:43
web.man.lodz.pl	1.272	4.512	00:00:21
mon.man.lodz.pl	1.024	4.052	00:00:04
isvcs01.man.lodz.pl	0.048	0.160	00:00:01
david.man.lodz.pl	0.372	1.404	00:00:01
71.6.146.186	0.008	0.012	00:00:00
TOTAL: 9	2.928	10.440	03:17:24

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
71.6.146.186	1
pc-212-191-92-138.p.lodz.pl	1
TOTAL: 4	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-191-92-138.p.lodz.pl	1	0
pc-212-191-92-136.p.lodz.pl	1	0
aapp82.neoplus.adsl.tpnet.pl	1	0
TOTAL: 3	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodz	27	58.7%
2	pl	16	34.8%
3	alt	3	6.5%
TOTAL: 3		46	100.0%

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

Newsgroup		Count
1	lodz.test	27
2	pl.comp.pecet	14
3	alt.test.pl	3
4	pl.comp.os.ms-windows.winnt	2
TOTAL: 4		46

Daily Usenet report

Apr 22 04:15:05 -- Apr 23 04:15:04