Daily Usenet report: Dec 13 04:15:03

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 13 17:54:19 newsfeed nnrpd[20050]: message repeated 2 times: [ 78.9.185.61 group lodz.test 0]
Dec 14 02:13:27 newsfeed nnrpd[5151]: SERVER perl filter not defined
Dec 14 02:17:15 newsfeed nnrpd[5151]: message repeated 4 times: [ r8ds.p.lodz.pl group pl.test 0]
Dec 14 02:22:58 newsfeed nnrpd[5452]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2843281	97.5%	332.9 MB	97.1%
innfeed	45783	1.6%	5.4 MB	1.6%
innd	22044	0.8%	3.8 MB	1.1%
nnrpd	6250	0.2%	695.1 KB	0.2%
TOTAL: 4	2917358	100.0%	342.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2837728	98.6%
Negative hits	23587	0.8%
Positive hits	14234	0.5%
Cache misses	1146	0.0%
TOTAL: 4	2876695	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.618	0.0%	657	0.000	17.683	603.000
article cleanup	00:00:43.539	0.1%	2842948	0.005	0.015	0.030
article logging	00:01:17.919	0.1%	2843361	0.014	0.027	0.541
article parse	01:16:29.462	5.3%	267859271	0.007	0.017	0.078
article write	00:26:24.057	1.8%	2839419	0.289	0.558	2.924
artlog/artcncl	00:00:00.011	0.0%	589	0.000	0.019	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:05:38.879	0.4%	285896539	0.000	0.001	0.006
hisgrep/artcncl	00:00:03.179	0.0%	614	0.000	5.178	256.000
hishave/artcncl	00:00:00.152	0.0%	657	0.000	0.231	9.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:36:04.544	2.5%	2878276	0.102	0.752	14.993
history sync	00:00:00.012	0.0%	291	0.000	0.041	2.000
history write	00:51:15.765	3.6%	2839808	0.164	1.083	23.290
hiswrite/artcncl	00:00:00.077	0.0%	43	0.000	1.791	27.000
idle	12:31:58.757	52.2%	185021601	0.097	0.244	0.483
nntp read	01:27:33.123	6.1%	265597310	0.009	0.020	0.043
overview write	00:00:16.190	0.0%	2839419	0.001	0.006	0.013
perl filter	05:32:52.323	23.1%	2839807	4.475	7.033	9.663
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:56.005	0.4%	5718679	0.017	0.062	0.112
TOTAL: 24:00:13.629	22:56:45.612	95.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.243	0.0%	82259	0.000	0.003	0.091
article prepare	00:00:00.005	0.0%	22566	0.000	0.000	0.083
article read	00:00:49.534	0.1%	5625	0.000	8.806	781.000
backlog stats	00:00:00.128	0.0%	205280	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	15	0.000	0.000	0.000
data read	00:00:01.153	0.0%	112014	0.000	0.010	0.075
data write	00:00:03.388	0.0%	74820	0.000	0.045	0.333
idle	23:58:20.763	99.9%	205280	34.178	420.405	1400.636
status file	00:00:15.502	0.0%	15714	0.000	0.987	102.583
TOTAL: 23:59:42.099	23:59:30.716	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:13:58.689	99.4%	15369	0.000	288.808	33233.143
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:05.789	0.1%	152929	0.000	0.038	67.655
readart	00:00:01.063	0.0%	119	0.000	8.933	113.333
TOTAL: 01:14:27.264	01:14:05.541	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1085	2838325	2834501	674	3150	99%	329:50:16
2	news.nask.pl	2	14173	3302	10584	287	23%	23:10:58
3	news.icm.edu.pl	44	233	37	111	85	15%	15:26:08
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		1134	2852733	2837842	11369	3522	99%	368:27:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	810.4 GB	20.4 MB	34.6 MB	810.5 GB	99%	299.5 KB
2	news.nask.pl	11.3 MB	726.7 KB	852.4 KB	12.8 MB	87%	3.7 KB
3	news.icm.edu.pl	1.6 MB	0.0 KB	3.8 MB	5.4 MB	29%	45.5 KB
4	localhost	2.0 KB	0.0 KB	0.0 KB	2.0 KB	100%	1.0 KB
TOTAL: 4		810.4 GB	21.1 MB	39.3 MB	810.5 GB	99%	299.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 13 04:15:03 - 04:59:59	99149	3.5%	36.76	26.7 GB	3.3%	10379.84
Dec 13 05:00:00 - 05:59:59	125204	4.4%	34.78	34.4 GB	4.2%	10017.61
Dec 13 06:00:00 - 06:59:59	137359	4.8%	38.16	38.2 GB	4.7%	11112.54
Dec 13 07:00:00 - 07:59:59	139034	4.9%	38.62	38.4 GB	4.7%	11181.74
Dec 13 08:00:00 - 08:59:59	88946	3.1%	24.71	28.7 GB	3.5%	8358.37
Dec 13 09:00:00 - 09:59:59	82483	2.9%	22.91	27.4 GB	3.4%	7984.87
Dec 13 10:00:00 - 10:59:59	111185	3.9%	30.88	33.9 GB	4.2%	9868.31
Dec 13 11:00:00 - 11:59:59	114945	4.0%	31.93	34.2 GB	4.2%	9960.14
Dec 13 12:00:00 - 12:59:59	121769	4.3%	33.82	36.4 GB	4.5%	10611.86
Dec 13 13:00:00 - 13:59:59	138160	4.9%	38.38	38.3 GB	4.7%	11149.73
Dec 13 14:00:00 - 14:59:59	123544	4.4%	34.32	35.8 GB	4.4%	10431.19
Dec 13 15:00:00 - 15:59:59	115177	4.1%	31.99	35.0 GB	4.3%	10207.74
Dec 13 16:00:00 - 16:59:59	140292	4.9%	38.97	36.4 GB	4.5%	10589.88
Dec 13 17:00:00 - 17:59:59	105179	3.7%	29.22	30.4 GB	3.8%	8857.37
Dec 13 18:00:00 - 18:59:59	113229	4.0%	31.45	28.8 GB	3.5%	8379.60
Dec 13 19:00:00 - 19:59:59	125620	4.4%	34.89	36.0 GB	4.4%	10484.75
Dec 13 20:00:00 - 20:59:59	118516	4.2%	32.92	32.9 GB	4.1%	9593.48
Dec 13 21:00:00 - 21:59:59	133393	4.7%	37.05	37.0 GB	4.6%	10769.33
Dec 13 22:00:00 - 22:59:59	141119	5.0%	39.20	37.6 GB	4.6%	10958.15
Dec 13 23:00:00 - 23:59:59	118596	4.2%	32.94	35.0 GB	4.3%	10191.77
Dec 14 00:00:00 - 00:59:59	84080	3.0%	23.36	26.8 GB	3.3%	7816.48
Dec 14 01:00:00 - 01:59:59	109239	3.8%	30.34	30.0 GB	3.7%	8729.14
Dec 14 02:00:00 - 02:59:59	109901	3.9%	30.53	29.5 GB	3.6%	8601.96
Dec 14 03:00:00 - 03:59:59	114174	4.0%	31.71	33.6 GB	4.1%	9773.19
Dec 14 04:00:00 - 04:15:02	28457	1.0%	31.55	9.3 GB	1.1%	10826.88
TOTAL: 23:59:59	2838750	100.0%	32.86	810.6 GB	100.0%	9838.37

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3517	156	2866	1	494
2	news.nask.pl	345	5	273	19	48
3	news.icm.edu.pl	80	0	1	0	79
TOTAL: 3		3942	161	3140	20	621

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	57
it.comp.os.win.windows10	45
it.tlc.cellulari.android	23
comp.sys.raspberry-pi	10
comp.mobile.android	10
comp.mobile.ipad	4
it.comp.os.win.windows7	3
uk.rec.cycling.moderated	3
hr.ponuda.hardver.konfiguracije	2
de.comp.office-pakete.ms-office.word	2
it.comp.os.linux.ubuntu	1
it.comp.os.win.windows8	1
TOTAL: 12	161

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

Groups	Count
christnet.writers	12
pl.sci.historia	7
bit.listserv.catholic	1
TOTAL: 3	20

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	126
EMP (md5)	60
Too many newsgroups	19
Scoring filter (8)	1
Angle-bracket bot	1
TOTAL: 5	207

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	49609	1166	21883	498	35	58674	2%	23:59:39
2	icm	37743	1151	22357	1821	0	4	3%	23:59:48
3	poznan	4881	74	3276	66	0	0	1%	23:59:34
4	okapi	0	0	0	0	0	1622	0%	24:00:00
5	nask	0	0	0	0	0	13234	0%	24:00:00
TOTAL: 5		92233	2391	47516	2385	35	73534	2%	119:59:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	6.6 MB	502.1 MB	508.8 MB	6.0 KB/s	175.3 KB	23:59:48
2	panorama	4.2 MB	3.0 MB	7.2 MB	0.1 KB/s	4.4 KB	23:59:39
3	poznan	299.9 KB	150.1 KB	450.0 KB	0.0 KB/s	3.2 KB	23:59:34
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		11.1 MB	505.3 MB	516.4 MB	1.2 KB/s	110.7 KB	119:59:01

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	94	91.7 KB	43	0	0	00:00:30
2	78.9.185.61	14	20	18.1 KB	6	0	4	00:09:56
3	195.26.22.130	7	4	6.0 KB	1	0	0	00:05:23
4	r8ds.p.lodz.pl	2	1	1.0 KB	1	2	0	00:14:31
TOTAL: 4		311	119	116.8 KB	51	2	4	01:14:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	960	94	91.7 KB	43	0	0	00:00:37
2	unresolved	23	24	24.1 KB	7	0	4	00:59:15
3	*.p.lodz.pl	2	1	1.0 KB	1	2	0	00:14:31
4	*.toya.net.pl	36	0	0.0 KB	0	0	0	00:00:02
TOTAL: 4		1021	119	116.8 KB	51	2	4	01:14:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.40.196	0.628	0.724	00:43:56
r8ds.p.lodz.pl	0.461	0.177	00:14:31
78.9.185.61	0.099	0.181	00:09:56
195.26.22.130	0.052	0.111	00:05:23
web.man.lodz.pl	0.911	3.861	00:00:30
mon.man.lodz.pl	1.833	7.086	00:00:06
staticline41435.toya.net.pl	0.098	0.472	00:00:02
david.man.lodz.pl	0.265	1.171	00:00:00
TOTAL: 8	4.347	13.783	01:14:26

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	576
david.man.lodz.pl	96
staticline41435.toya.net.pl	36
89.38.40.196	2
TOTAL: 4	710

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	95	79.8%
2	lodz	20	16.8%
3	lodman	4	3.4%
TOTAL: 3		119	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	72
2	pl.comp.pecet	22
3	lodz.test	20
4	lodman.info	4
5	pl.test	1
TOTAL: 5		119

Daily Usenet report

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