Daily Usenet report: Mar 9 04:15:03

Unknown entries from news log file:

First 22 / 22 lines (100.0%)

Mar 9 10:14:39 newsfeed nnrpd[4769]: SERVER perl filter not defined
Mar 9 10:52:16 newsfeed nnrpd[6049]: SERVER perl filter not defined
Mar 9 11:01:47 newsfeed nnrpd[6395]: SERVER perl filter not defined
Mar 9 11:54:14 newsfeed nnrpd[7992]: SERVER perl filter not defined
Mar 9 11:55:16 newsfeed nnrpd[8049]: SERVER perl filter not defined
Mar 9 12:34:37 newsfeed nnrpd[9314]: SERVER perl filter not defined
Mar 9 12:38:51 newsfeed nnrpd[9539]: SERVER perl filter not defined
Mar 9 12:52:49 newsfeed nnrpd[9946]: SERVER perl filter not defined
Mar 9 14:07:21 newsfeed nnrpd[12476]: SERVER perl filter not defined
Mar 9 14:27:15 newsfeed nnrpd[13127]: SERVER perl filter not defined
Mar 9 14:28:13 newsfeed nnrpd[13128]: SERVER perl filter not defined
Mar 9 14:50:41 newsfeed nnrpd[13832]: SERVER perl filter not defined
Mar 9 15:15:46 newsfeed nnrpd[14598]: SERVER perl filter not defined
Mar 9 16:30:53 newsfeed nnrpd[17090]: SERVER perl filter not defined
Mar 9 16:55:35 newsfeed nnrpd[17809]: SERVER perl filter not defined
Mar 9 17:42:52 newsfeed nnrpd[19377]: SERVER perl filter not defined
Mar 9 17:44:12 newsfeed nnrpd[19378]: SERVER perl filter not defined
Mar 9 18:03:14 newsfeed nnrpd[20041]: SERVER perl filter not defined
Mar 9 18:56:26 newsfeed nnrpd[21866]: SERVER perl filter not defined
Mar 9 18:58:07 newsfeed nnrpd[21868]: SERVER perl filter not defined
Mar 9 19:10:45 newsfeed nnrpd[22280]: SERVER perl filter not defined
Mar 9 19:10:59 newsfeed nnrpd[22281]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1467396	97.9%	167.7 MB	97.4%
innd	16680	1.1%	2.7 MB	1.6%
nnrpd	7633	0.5%	787.2 KB	0.4%
innfeed	6991	0.5%	1.0 MB	0.6%
TOTAL: 4	1498700	100.0%	172.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1460816	94.2%
Negative hits	52006	3.4%
Positive hits	37804	2.4%
Cache misses	651	0.0%
TOTAL: 4	1551277	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.928	0.0%	313	0.000	38.109	704.000
article cleanup	00:00:24.950	0.0%	1467970	0.000	0.017	0.044
article logging	00:00:53.730	0.1%	1468141	0.006	0.037	0.660
article parse	00:52:45.159	3.7%	120516515	0.003	0.026	0.085
article write	00:15:23.607	1.1%	1457097	0.197	0.634	2.518
artlog/artcncl	00:00:00.004	0.0%	293	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:33.239	0.2%	129533560	0.000	0.002	0.024
hisgrep/artcncl	00:00:02.826	0.0%	192	0.000	14.719	316.000
hishave/artcncl	00:00:01.203	0.0%	313	0.000	3.843	74.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:48:31.819	11.7%	1552760	0.014	6.512	15.323
history sync	00:00:00.025	0.0%	291	0.000	0.086	2.000
history write	03:36:11.140	15.0%	1462318	0.073	8.870	17.576
hiswrite/artcncl	00:00:00.545	0.0%	121	0.000	4.504	18.000
idle	11:40:43.240	48.6%	83074690	0.195	0.506	6.219
nntp read	00:44:58.909	3.1%	119390668	0.004	0.023	0.049
overview write	00:00:10.454	0.0%	1457097	0.000	0.007	0.018
perl filter	03:20:25.027	13.9%	1462317	2.677	8.223	11.174
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:33.987	0.2%	2956792	0.021	0.072	0.241
TOTAL: 24:00:36.798	23:27:51.792	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.165	0.0%	42587	0.000	0.004	0.133
article prepare	00:00:00.025	0.0%	61054	0.000	0.000	0.083
article read	00:00:05.025	0.0%	15155	0.000	0.332	202.000
backlog stats	00:00:00.249	0.0%	320486	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.196	0.0%	179898	0.000	0.012	0.208
data write	00:00:11.935	0.0%	166477	0.000	0.072	40.000
idle	23:58:59.516	100.0%	320486	0.000	269.402	16049.000
status file	00:00:01.796	0.0%	247	0.000	7.271	158.500
TOTAL: 23:59:28.974	23:59:20.907	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	09:14:56.532	99.9%	4922	0.000	6764.838	600058.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.359	0.0%	11920	0.000	0.030	4.333
readart	00:00:00.284	0.0%	627	0.000	0.453	24.000
TOTAL: 09:15:45.734	09:14:57.175	99.9%	-	-	-	-

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	487	1455586	1445853	1073	8660	99%	333:52:01
2	news.task.gda.pl	23	26232	5720	19217	1295	21%	44:51:42
3	news.nask.pl	2	17088	4651	11661	776	27%	24:02:41
4	localhost	23	22	22	0	0	100%	00:00:00
5	news.icm.edu.pl	36	399	12	292	95	3%	20:11:43
TOTAL: 5		571	1499327	1456258	32243	10826	97%	422:58:07

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	471.8 GB	855.7 MB	1.2 GB	473.8 GB	99%	341.6 KB
2	news.nask.pl	56.4 MB	2.7 MB	2.9 MB	62.0 MB	91%	11.7 KB
3	news.task.gda.pl	11.0 MB	88.4 KB	3.2 MB	14.2 MB	77%	2.1 KB
4	news.icm.edu.pl	422.3 KB	62.5 KB	1.5 MB	2.0 MB	20%	18.9 KB
5	localhost	48.7 KB	0.0 KB	0.0 KB	48.7 KB	100%	2.2 KB
TOTAL: 5		471.8 GB	858.5 MB	1.2 GB	473.9 GB	99%	338.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 9 04:15:03 - 04:59:59	63185	4.3%	23.43	20.8 GB	4.4%	8103.36
Mar 9 05:00:00 - 05:59:59	90325	6.2%	25.09	29.9 GB	6.3%	8723.21
Mar 9 06:00:00 - 06:59:59	85077	5.8%	23.63	28.4 GB	6.0%	8281.20
Mar 9 07:00:00 - 07:59:59	63134	4.3%	17.54	21.0 GB	4.5%	6124.13
Mar 9 08:00:00 - 08:59:59	84990	5.8%	23.61	27.4 GB	5.8%	7973.32
Mar 9 09:00:00 - 09:59:59	48585	3.3%	13.50	15.8 GB	3.4%	4605.21
Mar 9 10:00:00 - 10:59:59	38157	2.6%	10.60	12.3 GB	2.6%	3594.16
Mar 9 11:00:00 - 11:59:59	45431	3.1%	12.62	14.2 GB	3.0%	4145.13
Mar 9 12:00:00 - 12:59:59	62592	4.3%	17.39	16.5 GB	3.5%	4811.39
Mar 9 13:00:00 - 13:59:59	49134	3.4%	13.65	15.4 GB	3.3%	4473.01
Mar 9 14:00:00 - 14:59:59	54311	3.7%	15.09	16.3 GB	3.4%	4734.04
Mar 9 15:00:00 - 15:59:59	60096	4.1%	16.69	15.3 GB	3.2%	4461.55
Mar 9 16:00:00 - 16:59:59	46647	3.2%	12.96	14.9 GB	3.1%	4326.36
Mar 9 17:00:00 - 17:59:59	46754	3.2%	12.99	15.9 GB	3.4%	4624.81
Mar 9 18:00:00 - 18:59:59	46165	3.2%	12.82	14.3 GB	3.0%	4172.53
Mar 9 19:00:00 - 19:59:59	44084	3.0%	12.25	14.4 GB	3.1%	4196.03
Mar 9 20:00:00 - 20:59:59	39613	2.7%	11.00	12.6 GB	2.7%	3664.86
Mar 9 21:00:00 - 21:59:59	39184	2.7%	10.88	13.0 GB	2.8%	3783.56
Mar 9 22:00:00 - 22:59:59	47298	3.2%	13.14	16.3 GB	3.5%	4757.80
Mar 9 23:00:00 - 23:59:59	42188	2.9%	11.72	15.0 GB	3.2%	4373.70
Mar 10 00:00:00 - 00:59:59	77361	5.3%	21.49	26.7 GB	5.7%	7782.83
Mar 10 01:00:00 - 01:59:59	98166	6.7%	27.27	29.5 GB	6.2%	8584.45
Mar 10 02:00:00 - 02:59:59	93358	6.4%	25.93	33.8 GB	7.2%	9830.81
Mar 10 03:00:00 - 03:59:59	70227	4.8%	19.51	24.9 GB	5.3%	7261.49
Mar 10 04:00:00 - 04:15:03	19998	1.4%	22.15	7.0 GB	1.5%	8099.46
TOTAL: 24:00:00	1456060	100.0%	16.85	471.7 GB	100.0%	5724.50

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	8800	200	4878	10	3712
2	news.task.gda.pl	1357	991	42	0	324
3	news.nask.pl	791	16	730	8	37
4	news.icm.edu.pl	95	0	1	0	94
TOTAL: 4		11043	1207	5651	18	4167

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	179
mailing.freebsd.svn-ports-head	175
mailing.openbsd.ports-changes	127
uk.railway	115
alt.windows7.general	55
mailing.freebsd.ports-bugs	51
24hoursupport.helpdesk	47
mailing.freebsd.ports	36
gmane.comp.gis.openstreetmap	28
mailing.freebsd.svn-src-all	26
fa.freebsd.cvs-all	26
gmane.linux.alsa.devel	24
mailing.dragonflybsd.commits	21
mailing.openbsd.misc	20
it.comp.os.win.windows7	18
mailing.openbsd.source-changes	18
mailing.freebsd.svn-src-head	16
fr.usenet.abus.nocem	14
comp.sys.raspberry-pi	12
alt.politics.scorched-earth	11
TOTAL: 66	1207

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

Groups	Count
pl.sci.historia	13
pl.sci.matematyka	3
sci.virtual-worlds	2
TOTAL: 3	18

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3510
EMP (fsl)	250
Attached HTML file	79
EMP (md5)	75
EMP (phl)	62
HTML post	9
Scoring filter (11)	4
Scoring filter (9)	4
Scoring filter (12)	1
Scoring filter (8)	1
TOTAL: 10	3995

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	56697	5438	28775	7722	3	9	9%	23:59:59
2	panorama	43317	1736	40507	244	0	0	4%	23:56:35
3	poznan	11436	46	9853	24	0	0	0%	23:59:56
4	task	1481	26	786	3	0	4	1%	21:28:41
5	nask	19851	16	13461	0	0	0	0%	24:01:35
6	okapi	0	0	0	0	0	1602	0%	24:00:03
TOTAL: 6		132782	7262	93382	7993	3	1615	5%	141:26:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	613.6 MB	1.8 GB	2.4 GB	29.5 KB/s	193.5 KB	23:59:59
2	panorama	9.4 MB	917.5 KB	10.3 MB	0.1 KB/s	5.3 KB	23:56:35
3	poznan	154.3 KB	42.7 KB	197.1 KB	0.0 KB/s	2.8 KB	23:59:56
4	task	62.5 KB	6.1 KB	68.6 KB	0.0 KB/s	2.4 KB	21:28:41
5	nask	53.3 KB	0.0 KB	53.3 KB	0.0 KB/s	3.3 KB	24:01:35
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 6		623.2 MB	1.8 GB	2.4 GB	5.0 KB/s	167.7 KB	141:26:49

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	vegas.cm.p.lodz.pl	286	500	959.0 KB	286	0	00:00:21
2	web.man.lodz.pl	288	110	112.2 KB	43	0	00:00:33
3	staticline-31-183-29-17.toya.net.pl	2	13	19.4 KB	1	0	00:36:48
4	ecl54.neoplus.adsl.tpnet.pl	1	2	5.0 KB	1	0	00:01:58
5	ip-176.105.135.22.tvsat364.lodz.pl	5	1	1.5 KB	1	0	01:15:47
6	staticline-31-182-68-170.toya.net.pl	2	1	2.0 KB	1	0	01:01:16
7	isvcs01.man.lodz.pl	22	0	0.0 KB	0	22	00:00:02
TOTAL: 7		606	627	1.1 MB	333	22	09:15:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	286	500	959.0 KB	286	0	00:00:21
2	*.man.lodz.pl	694	110	112.2 KB	43	22	00:00:45
3	*.toya.net.pl	12	14	21.4 KB	2	0	01:38:08
4	*.neoplus.adsl.tpnet.pl	1	2	5.0 KB	1	0	00:01:58
5	*.105.135.22.tvsat364.lodz.pl	5	1	1.5 KB	1	0	01:15:47
6	*.pa.nsw.optusnet.com.au	83	0	0.0 KB	0	0	00:07:07
7	*.wroclaw.mm.pl	40	0	0.0 KB	0	0	06:11:34
8	*.dip0.t-ipconnect.de	2	0	0.0 KB	0	0	00:00:01
TOTAL: 8		1123	627	1.1 MB	333	22	09:15:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.072	0.352	06:11:34
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.076	01:15:47
staticline-31-182-68-170.toya.net.pl	0.000	0.032	01:01:16
staticline-31-183-29-17.toya.net.pl	0.016	0.072	00:36:48
pa49-181-247-0.pa.nsw.optusnet.com.au	0.212	0.980	00:07:07
ecl54.neoplus.adsl.tpnet.pl	0.004	0.012	00:01:58
web.man.lodz.pl	0.736	3.772	00:00:33
vegas.cm.p.lodz.pl	0.596	3.332	00:00:21
mon.man.lodz.pl	0.476	3.396	00:00:07
staticline41435.toya.net.pl	0.024	0.088	00:00:02
isvcs01.man.lodz.pl	0.080	0.312	00:00:02
david.man.lodz.pl	0.196	1.104	00:00:02
p4fe06f5c.dip0.t-ipconnect.de	0.024	0.040	00:00:01
TOTAL: 13	2.440	13.568	09:15:44

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
pa49-181-247-0.pa.nsw.optusnet.com.au	83
host-89-231-50-15.wroclaw.mm.pl	40
staticline41435.toya.net.pl	8
p4fe06f5c.dip0.t-ipconnect.de	2
TOTAL: 6	517

NNRP client timeouts [Top 20]:

System	Conn	Peer
staticline-31-182-68-170.toya.net.pl	2	0
ip-176.105.135.22.tvsat364.lodz.pl	2	0
staticline-31-183-29-17.toya.net.pl	1	0
TOTAL: 3	5	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	515	82.1%
2	pl	111	17.7%
3	lodz	1	0.2%
TOTAL: 3		627	100.0%

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

Newsgroup		Count
1	lodman.info	515
2	pl.comp.pecet	94
3	pl.comp.os.ms-windows.winnt	14
4	pl.internet.polip	2
5	pl.soc.prawo	1
6	lodz.ogloszenia	1
TOTAL: 6		627

Daily Usenet report

Mar 9 04:15:03 -- Mar 10 04:15:03