Daily Usenet report: Jan 10 04:15:03

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jan 10 12:58:10 newsfeed nnrpd[12469]: SERVER perl filter not defined
Jan 10 15:55:17 newsfeed nnrpd[18200]: SERVER perl filter not defined
Jan 10 16:53:07 newsfeed nnrpd[20191]: SERVER perl filter not defined
Jan 10 17:25:00 newsfeed nnrpd[21347]: SERVER perl filter not defined
Jan 10 20:41:37 newsfeed nnrpd[27777]: SERVER perl filter not defined
Jan 10 21:18:41 newsfeed nnrpd[29066]: SERVER perl filter not defined
Jan 10 21:36:25 newsfeed nnrpd[29674]: SERVER perl filter not defined
Jan 10 21:38:45 newsfeed nnrpd[29719]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1907892	98.4%	210.2 MB	97.9%
innd	16913	0.9%	2.8 MB	1.3%
nnrpd	7063	0.4%	729.5 KB	0.3%
innfeed	6410	0.3%	951.9 KB	0.4%
TOTAL: 4	1938278	100.0%	214.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1899979	96.9%
Negative hits	43145	2.2%
Positive hits	16478	0.8%
Cache misses	274	0.0%
TOTAL: 4	1959876	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.144	0.0%	320	0.000	25.450	441.000
article cleanup	00:00:28.216	0.0%	1904802	0.003	0.015	0.030
article logging	00:00:53.663	0.1%	1907105	0.011	0.028	0.251
article parse	01:10:01.641	4.9%	113124943	0.012	0.037	0.071
article write	00:19:11.119	1.3%	1899538	0.313	0.606	3.209
artlog/artcncl	00:00:00.004	0.0%	252	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:23.323	0.2%	124285531	0.001	0.001	0.014
hisgrep/artcncl	00:00:01.269	0.0%	272	0.000	4.665	44.000
hishave/artcncl	00:00:00.868	0.0%	320	0.000	2.712	37.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:38:29.676	15.2%	1960701	1.589	6.686	13.029
history sync	00:00:00.015	0.0%	291	0.000	0.052	2.000
history write	03:26:25.506	14.3%	1900941	3.689	6.515	22.889
hiswrite/artcncl	00:00:00.174	0.0%	48	0.000	3.625	14.000
idle	09:18:15.289	38.8%	61767376	0.306	0.542	2.358
nntp read	00:52:05.009	3.6%	111452917	0.013	0.028	0.040
overview write	00:00:12.583	0.0%	1899538	0.000	0.007	0.017
perl filter	04:34:12.253	19.0%	1900939	5.417	8.655	11.204
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:45.601	0.3%	3833339	0.012	0.059	0.094
TOTAL: 23:59:38.459	23:26:34.353	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.137	0.0%	41441	0.000	0.003	0.250
article prepare	00:00:00.004	0.0%	27940	0.000	0.000	0.062
article read	00:01:59.109	0.1%	6928	0.000	17.192	167.528
backlog stats	00:00:00.184	0.0%	224839	0.000	0.001	0.024
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.492	0.0%	131011	0.000	0.011	1.000
data write	00:00:11.040	0.0%	120506	0.000	0.092	4.000
idle	23:58:16.253	99.8%	224839	0.000	383.814	23489.000
status file	00:00:02.373	0.0%	206	0.000	11.519	286.667
TOTAL: 24:00:36.377	24:00:30.592	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	05:28:24.603	99.8%	68089	0.000	289.395	600041.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:12.114	0.1%	258874	0.000	0.047	1.667
readart	00:00:00.637	0.0%	410	0.000	1.554	23.400
TOTAL: 05:29:02.015	05:28:37.354	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	711	1900594	1895977	699	3918	99%	412:34:42
2	news.nask.pl	2	14975	3141	10609	1225	20%	23:28:12
3	news.icm.edu.pl	45	1874	520	1237	117	27%	15:04:20
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		767	1917451	1899646	12545	5260	99%	451:07:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	592.9 GB	110.9 MB	303.2 MB	593.3 GB	99%	327.4 KB
2	news.icm.edu.pl	141.1 MB	9.8 MB	13.6 MB	164.5 MB	85%	264.5 KB
3	news.nask.pl	21.7 MB	3.2 MB	3.3 MB	28.2 MB	76%	6.6 KB
4	localhost	9.1 KB	0.0 KB	0.0 KB	9.1 KB	100%	1.1 KB
TOTAL: 4		593.0 GB	124.0 MB	320.0 MB	593.5 GB	99%	326.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 10 04:15:03 - 04:59:59	65153	3.4%	24.16	20.7 GB	3.5%	8054.59
Jan 10 05:00:00 - 05:59:59	94134	5.0%	26.15	30.8 GB	5.2%	8983.48
Jan 10 06:00:00 - 06:59:59	68663	3.6%	19.07	21.9 GB	3.7%	6366.72
Jan 10 07:00:00 - 07:59:59	79184	4.2%	22.00	27.9 GB	4.7%	8128.50
Jan 10 08:00:00 - 08:59:59	90511	4.8%	25.14	30.2 GB	5.1%	8791.57
Jan 10 09:00:00 - 09:59:59	90526	4.8%	25.15	28.5 GB	4.8%	8308.31
Jan 10 10:00:00 - 10:59:59	56408	3.0%	15.67	17.9 GB	3.0%	5214.45
Jan 10 11:00:00 - 11:59:59	88365	4.7%	24.55	28.1 GB	4.7%	8172.04
Jan 10 12:00:00 - 12:59:59	88105	4.6%	24.47	27.5 GB	4.6%	8012.98
Jan 10 13:00:00 - 13:59:59	87813	4.6%	24.39	27.9 GB	4.7%	8113.41
Jan 10 14:00:00 - 14:59:59	96786	5.1%	26.89	29.9 GB	5.0%	8713.79
Jan 10 15:00:00 - 15:59:59	98000	5.2%	27.22	29.1 GB	4.9%	8465.62
Jan 10 16:00:00 - 16:59:59	97439	5.1%	27.07	29.5 GB	5.0%	8594.36
Jan 10 17:00:00 - 17:59:59	94510	5.0%	26.25	29.7 GB	5.0%	8664.25
Jan 10 18:00:00 - 18:59:59	97054	5.1%	26.96	30.3 GB	5.1%	8819.14
Jan 10 19:00:00 - 19:59:59	45798	2.4%	12.72	13.4 GB	2.3%	3909.28
Jan 10 20:00:00 - 20:59:59	62608	3.3%	17.39	19.0 GB	3.2%	5546.54
Jan 10 21:00:00 - 21:59:59	81066	4.3%	22.52	25.4 GB	4.3%	7410.38
Jan 10 22:00:00 - 22:59:59	83551	4.4%	23.21	25.9 GB	4.4%	7539.94
Jan 10 23:00:00 - 23:59:59	52446	2.8%	14.57	15.2 GB	2.6%	4437.44
Jan 11 00:00:00 - 00:59:59	52823	2.8%	14.67	16.1 GB	2.7%	4690.74
Jan 11 01:00:00 - 01:59:59	63170	3.3%	17.55	18.9 GB	3.2%	5498.60
Jan 11 02:00:00 - 02:59:59	71399	3.8%	19.83	21.0 GB	3.5%	6107.81
Jan 11 03:00:00 - 03:59:59	77575	4.1%	21.55	23.3 GB	3.9%	6782.56
Jan 11 04:00:00 - 04:15:02	16984	0.9%	18.83	5.1 GB	0.9%	5971.31
TOTAL: 23:59:59	1900071	100.0%	21.99	593.3 GB	100.0%	7200.18

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6208	103	2603	0	3502
2	news.nask.pl	1244	5	1219	2	18
3	news.icm.edu.pl	117	0	41	0	76
TOTAL: 3		7569	108	3863	2	3596

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	41
it.tlc.cellulari.android	22
it.comp.os.win.windows7	10
uk.rec.cycling.moderated	5
comp.mobile.android	5
it.comp.os.win.windows8	5
comp.sys.raspberry-pi	4
comp.mobile.ipad	4
nl.comp.sys.android	3
hr.ponuda.hardver.periferije	2
hr.ponuda.hardver.komponente	2
de.comp.office-pakete.ms-office.excel	1
uk.net.providers.gradwell	1
hr.rec.glazba.hifi	1
uk.current-events.charlie-hebdo	1
it.discussioni.energia	1
TOTAL: 16	108

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

Groups	Count
bit.listserv.catholic	1
pl.sci.historia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1204
EMP (md5)	80
Scoring filter (8)	4
EMP (fsl)	1
HTML post	1
Attached HTML file	1
TOTAL: 6	1291

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	34666	1032	32975	159	0	0	2%	23:55:23
2	nask	28124	257	20199	1375	0	873	0%	23:59:43
3	icm	31795	194	23329	4348	2	472	0%	23:58:15
4	poznan	4205	66	3127	30	0	0	1%	23:53:30
5	okapi	0	0	0	0	0	1614	0%	24:00:01
TOTAL: 5		98790	1549	79630	5912	2	2959	1%	119:46:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	66.2 MB	598.0 MB	664.2 MB	7.9 KB/s	416.8 KB	23:59:43
2	icm	23.3 MB	1.6 GB	1.6 GB	19.9 KB/s	378.3 KB	23:58:15
3	poznan	7.3 MB	3.5 MB	10.8 MB	0.1 KB/s	115.0 KB	23:53:30
4	panorama	5.6 MB	431.9 KB	6.0 MB	0.1 KB/s	5.2 KB	23:55:23
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		102.4 MB	2.2 GB	2.3 GB	5.6 KB/s	323.8 KB	119:46:52

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	360	476.2 KB	288	0	00:00:07
2	web.man.lodz.pl	288	36	31.2 KB	18	0	00:00:24
3	wireless.ics.p.lodz.pl	8	14	12.5 KB	6	2	01:16:50
4	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:00
TOTAL: 4		590	410	519.9 KB	312	8	05:29:01

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	360	476.2 KB	288	0	00:00:07
2	*.man.lodz.pl	678	36	31.2 KB	18	6	00:00:31
3	*.ics.p.lodz.pl	8	14	12.5 KB	6	2	01:16:50
4	*.neoplus.adsl.tpnet.pl	3	0	0.0 KB	0	0	00:00:07
5	unresolved	4	0	0.0 KB	0	0	03:34:04
6	*.toya.net.pl	41	0	0.0 KB	0	0	00:37:19
TOTAL: 6		1022	410	519.9 KB	312	8	05:29:01

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.136	3.146	4.361	03:34:04
wireless.ics.p.lodz.pl	0.665	0.346	01:16:50
staticline-31-183-120-41.toya.net.pl	0.025	0.054	00:37:08
web.man.lodz.pl	0.935	5.661	00:00:24
staticline-31-183-4-166.toya.net.pl	0.013	0.104	00:00:08
pc-skrzep2.cm.p.lodz.pl	1.019	5.750	00:00:07
cez196.neoplus.adsl.tpnet.pl	0.008	0.058	00:00:07
mon.man.lodz.pl	0.945	5.785	00:00:04
staticline41435.toya.net.pl	0.133	0.680	00:00:02
david.man.lodz.pl	0.329	1.908	00:00:01
isvcs01.man.lodz.pl	0.038	0.134	00:00:00
TOTAL: 11	7.256	24.841	05:29:01

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	34
staticline-31-183-4-166.toya.net.pl	5
89.38.41.136	4
cez196.neoplus.adsl.tpnet.pl	3
staticline-31-183-120-41.toya.net.pl	2
TOTAL: 7	432

NNRP client timeouts [Top 20]:

System	Conn	Peer
89.38.41.136	2	0
staticline-31-183-120-41.toya.net.pl	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	360	87.8%
2	pl	50	12.2%
TOTAL: 2		410	100.0%

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

Newsgroup		Count
1	lodman.info	360
2	pl.comp.os.ms-windows.winnt	22
3	pl.test	14
4	pl.comp.pecet	14
TOTAL: 4		410

Daily Usenet report

Jan 10 04:15:03 -- Jan 11 04:15:02