Daily Usenet report: Jun 5 04:15:03

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Jun 5 14:24:52 newsfeed nnrpd[28154]: SERVER perl filter not defined
Jun 5 14:26:44 newsfeed nnrpd[28205]: SERVER perl filter not defined
Jun 5 14:29:11 newsfeed nnrpd[28246]: SERVER perl filter not defined
Jun 5 15:30:23 newsfeed nnrpd[30322]: SERVER perl filter not defined
Jun 5 15:31:40 newsfeed nnrpd[30326]: SERVER perl filter not defined
Jun 5 17:17:44 newsfeed nnrpd[1578]: SERVER perl filter not defined
Jun 5 20:44:08 newsfeed nnrpd[8405]: SERVER perl filter not defined
Jun 5 20:49:13 newsfeed nnrpd[8644]: SERVER perl filter not defined
Jun 5 22:02:16 newsfeed nnrpd[11066]: SERVER perl filter not defined
Jun 5 22:07:46 newsfeed nnrpd[11323]: SERVER perl filter not defined
Jun 5 23:15:22 newsfeed nnrpd[13804]: SERVER perl filter not defined
Jun 5 23:21:16 newsfeed nnrpd[13904]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1113268	97.7%	125.2 MB	97.0%
innd	11261	1.0%	1.9 MB	1.5%
nnrpd	8176	0.7%	874.2 KB	0.7%
innfeed	7102	0.6%	1.1 MB	0.8%
TOTAL: 4	1139807	100.0%	129.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1108168	93.4%
Positive hits	40443	3.4%
Negative hits	37324	3.1%
Cache misses	1069	0.1%
TOTAL: 4	1187004	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.731	0.0%	565	0.000	18.993	325.000
article cleanup	00:00:17.056	0.0%	1111864	0.000	0.015	0.038
article logging	00:00:34.870	0.0%	1112041	0.010	0.031	0.591
article parse	00:45:31.532	3.2%	170744466	0.007	0.016	0.055
article write	00:11:11.342	0.8%	1105799	0.237	0.607	2.456
artlog/artcncl	00:00:00.009	0.0%	560	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	11	0.000	0.000	0.000
data move	00:02:22.510	0.2%	179002017	0.000	0.001	0.011
hisgrep/artcncl	00:00:01.578	0.0%	227	0.000	6.952	50.000
hishave/artcncl	00:00:01.252	0.0%	565	0.000	2.216	19.375
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:49:48.959	3.5%	1186642	0.150	2.519	8.320
history sync	00:00:00.016	0.0%	291	0.000	0.055	1.000
history write	03:17:28.860	13.7%	1107972	0.819	10.694	27.094
hiswrite/artcncl	00:00:02.819	0.0%	338	0.000	8.340	40.000
idle	14:12:59.189	59.3%	148206079	0.129	0.345	4.349
nntp read	00:54:28.723	3.8%	170050331	0.010	0.019	0.038
overview write	00:00:07.125	0.0%	1105799	0.000	0.006	0.023
perl filter	02:47:31.924	11.6%	1107961	5.472	9.072	11.456
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:15.764	0.2%	2249829	0.025	0.060	0.118
TOTAL: 23:59:28.395	23:04:54.259	96.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.140	0.0%	38295	0.000	0.004	0.118
article prepare	00:00:00.012	0.0%	38588	0.000	0.000	0.125
article read	00:00:01.179	0.0%	9381	0.000	0.126	16.000
backlog stats	00:00:00.219	0.0%	311314	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.911	0.0%	175375	0.000	0.011	0.087
data write	00:00:06.650	0.0%	155252	0.000	0.043	2.000
idle	23:59:37.523	100.0%	311314	0.000	277.461	13881.000
status file	00:00:00.367	0.0%	219	0.000	1.676	46.667
TOTAL: 23:59:54.602	23:59:48.001	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	09:41:57.364	99.9%	5254	0.000	6645.863	243619.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.281	0.0%	12676	0.000	0.022	0.667
readart	00:00:00.635	0.0%	503	0.000	1.262	89.000
TOTAL: 09:42:25.327	09:41:58.280	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	446	1101615	1096800	1543	3272	99%	177:00:36
2	news.task.gda.pl	9	29126	5435	22132	1559	18%	44:53:48
3	news.nask.pl	2	18391	3913	13314	1164	21%	23:54:07
4	localhost	13	12	12	0	0	100%	00:00:00
5	news.icm.edu.pl	42	298	3	216	79	1%	17:29:45
TOTAL: 5		512	1149442	1106163	37205	6074	96%	263:18:16

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	410.8 GB	53.4 MB	67.1 MB	410.9 GB	99%	391.6 KB
2	news.task.gda.pl	12.5 MB	33.1 KB	4.9 MB	17.4 MB	71%	2.6 KB
3	news.nask.pl	12.2 MB	2.9 MB	3.2 MB	18.3 MB	66%	3.7 KB
4	news.icm.edu.pl	28.4 KB	0.0 KB	1007.2 KB	1.0 MB	2%	12.6 KB
5	localhost	20.9 KB	0.0 KB	0.0 KB	20.9 KB	100%	1.7 KB
TOTAL: 5		410.8 GB	56.4 MB	76.2 MB	410.9 GB	99%	387.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 5 04:15:03 - 04:59:59	60476	5.5%	22.42	21.2 GB	5.2%	8253.03
Jun 5 05:00:00 - 05:59:59	51171	4.6%	14.21	20.0 GB	4.9%	5815.13
Jun 5 06:00:00 - 06:59:59	35809	3.2%	9.95	15.3 GB	3.7%	4443.02
Jun 5 07:00:00 - 07:59:59	36460	3.3%	10.13	15.0 GB	3.6%	4369.56
Jun 5 08:00:00 - 08:59:59	40759	3.7%	11.32	15.6 GB	3.8%	4557.60
Jun 5 09:00:00 - 09:59:59	44456	4.0%	12.35	15.6 GB	3.8%	4546.45
Jun 5 10:00:00 - 10:59:59	45578	4.1%	12.66	15.6 GB	3.8%	4532.55
Jun 5 11:00:00 - 11:59:59	43541	3.9%	12.09	15.5 GB	3.8%	4514.68
Jun 5 12:00:00 - 12:59:59	39499	3.6%	10.97	14.2 GB	3.5%	4150.32
Jun 5 13:00:00 - 13:59:59	36389	3.3%	10.11	13.8 GB	3.4%	4014.36
Jun 5 14:00:00 - 14:59:59	38218	3.5%	10.62	14.9 GB	3.6%	4338.88
Jun 5 15:00:00 - 15:59:59	40698	3.7%	11.30	16.5 GB	4.0%	4805.86
Jun 5 16:00:00 - 16:59:59	37834	3.4%	10.51	15.2 GB	3.7%	4432.95
Jun 5 17:00:00 - 17:59:59	45355	4.1%	12.60	16.4 GB	4.0%	4769.23
Jun 5 18:00:00 - 18:59:59	43024	3.9%	11.95	15.5 GB	3.8%	4528.26
Jun 5 19:00:00 - 19:59:59	41155	3.7%	11.43	15.6 GB	3.8%	4544.57
Jun 5 20:00:00 - 20:59:59	40891	3.7%	11.36	14.3 GB	3.5%	4159.13
Jun 5 21:00:00 - 21:59:59	38436	3.5%	10.68	13.3 GB	3.2%	3865.91
Jun 5 22:00:00 - 22:59:59	29806	2.7%	8.28	9.7 GB	2.4%	2824.51
Jun 5 23:00:00 - 23:59:59	36726	3.3%	10.20	13.0 GB	3.2%	3776.79
Jun 6 00:00:00 - 00:59:59	61163	5.5%	16.99	21.1 GB	5.1%	6155.73
Jun 6 01:00:00 - 01:59:59	64370	5.8%	17.88	24.8 GB	6.0%	7225.86
Jun 6 02:00:00 - 02:59:59	57613	5.2%	16.00	22.8 GB	5.5%	6631.92
Jun 6 03:00:00 - 03:59:59	77208	7.0%	21.45	28.8 GB	7.0%	8394.26
Jun 6 04:00:00 - 04:15:03	19819	1.8%	21.95	7.7 GB	1.9%	8922.74
TOTAL: 24:00:00	1106454	100.0%	12.81	411.4 GB	100.0%	4992.44

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3438	207	2763	3	465
2	news.task.gda.pl	1570	1385	18	3	164
3	news.nask.pl	1167	11	1122	3	31
4	news.icm.edu.pl	79	0	0	0	79
TOTAL: 4		6254	1603	3903	9	739

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	157
mailing.freebsd.svn-ports-head	150
uk.railway	93
24hoursupport.helpdesk	84
mailing.unix.samba-technical	83
mailing.freebsd.ports-bugs	79
mailing.unix.samba	61
mailing.freebsd.svn-src-all	54
fa.freebsd.cvs-all	49
mozilla.dev.platform	43
mailing.gcc.gcc-cvs	43
mailing.openbsd.misc	41
alt.windows7.general	36
alt.politics.scorched-earth	35
it.tlc.cellulari.android	35
mailing.unix.dovecot	33
mailing.freebsd.ports	32
mozilla.dev.gaia	31
mailing.freebsd.svn-src-head	31
mailing.openssl.dev	30
TOTAL: 79	1603

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

Groups	Count
pl.sci.historia	4
fr.comp.lang.php	1
fr.bienvenue	1
bit.listserv.catholic	1
fr.comp.applications.libres	1
pl.sci.matematyka	1
TOTAL: 6	9

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	281
EMP (fsl)	150
EMP (md5)	109
HTML post	5
Scoring filter (8)	5
Attached HTML file	1
TOTAL: 6	551

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	56725	4438	28728	3247	7	59	7%	24:00:00
2	panorama	43757	1511	36319	305	0	0	3%	23:53:54
3	task	1641	53	847	6	0	0	3%	21:41:02
4	nask	24361	40	14958	1	1	0	0%	23:58:59
5	poznan	10908	34	9201	14	0	0	0%	23:56:10
6	okapi	0	0	0	0	0	2758	0%	24:00:01
TOTAL: 6		137392	6076	90053	3573	8	2817	4%	141:30:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	43.4 MB	997.8 MB	1.0 GB	12.3 KB/s	138.7 KB	24:00:00
2	panorama	4.8 MB	1.1 MB	5.8 MB	0.1 KB/s	3.3 KB	23:53:54
3	nask	136.5 KB	519.2 KB	655.6 KB	0.0 KB/s	16.0 KB	23:58:59
4	task	106.9 KB	16.2 KB	123.1 KB	0.0 KB/s	2.1 KB	21:41:02
5	poznan	91.5 KB	32.2 KB	123.8 KB	0.0 KB/s	2.6 KB	23:56:10
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		48.5 MB	999.4 MB	1.0 GB	2.1 KB/s	111.2 KB	141:30:06

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	pc-skrzep2.cm.p.lodz.pl	288	422	686.2 KB	288	0	00:00:08
2	web.man.lodz.pl	288	48	56.0 KB	23	0	00:00:23
3	lze200.lze.lodz.pl	2	30	68.1 KB	3	0	00:20:06
4	194.33.77.50	1	2	3.1 KB	1	0	00:01:04
5	proxy.filmschool.lodz.pl	2	1	2.5 KB	1	0	00:11:35
6	isvcs01.man.lodz.pl	12	0	0.0 KB	0	12	00:00:02
TOTAL: 6		593	503	815.9 KB	316	12	09:42:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	422	686.2 KB	288	0	00:00:08
2	*.man.lodz.pl	684	48	56.0 KB	23	12	00:00:31
3	*.lze.lodz.pl	2	30	68.1 KB	3	0	00:20:06
4	unresolved	1	2	3.1 KB	1	0	00:01:04
5	*.filmschool.lodz.pl	2	1	2.5 KB	1	0	00:11:35
6	*.pa.nsw.optusnet.com.au	155	0	0.0 KB	0	0	00:24:33
7	*.wroclaw.mm.pl	54	0	0.0 KB	0	0	08:44:15
8	*.toya.net.pl	92	0	0.0 KB	0	0	00:00:08
TOTAL: 8		1278	503	815.9 KB	316	12	09:42:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
host-89-231-50-15.wroclaw.mm.pl	0.080	0.716	08:44:15
pa49-181-247-10.pa.nsw.optusnet.com.au	0.372	2.068	00:24:33
lze200.lze.lodz.pl	0.008	0.052	00:20:06
proxy.filmschool.lodz.pl	0.004	0.036	00:11:35
194.33.77.50	0.000	0.016	00:01:04
web.man.lodz.pl	0.672	4.468	00:00:23
staticline41435.toya.net.pl	0.240	1.268	00:00:08
pc-skrzep2.cm.p.lodz.pl	0.680	4.004	00:00:08
mon.man.lodz.pl	0.596	4.012	00:00:04
isvcs01.man.lodz.pl	0.060	0.184	00:00:02
david.man.lodz.pl	0.208	1.316	00:00:01
TOTAL: 11	2.920	18.140	09:42:24

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-247-10.pa.nsw.optusnet.com.au	155
david.man.lodz.pl	96
staticline41435.toya.net.pl	92
host-89-231-50-15.wroclaw.mm.pl	54
TOTAL: 5	685

NNRP client timeouts [Top 20]:

System	Conn	Peer
pa49-181-247-10.pa.nsw.optusnet.com.au	3	1
TOTAL: 1	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	424	84.3%
2	pl	79	15.7%
TOTAL: 2		503	100.0%

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

Newsgroup		Count
1	lodman.info	424
2	pl.comp.os.ms-windows.winnt	32
3	pl.comp.pecet	20
4	pl.rec.kuchnia	13
5	pl.misc.telefonia.gsm	13
6	pl.rec.foto.cyfrowa	1
TOTAL: 6		503

Daily Usenet report

Jun 5 04:15:03 -- Jun 6 04:15:03