Daily Usenet report: Apr 23 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Apr 23 10:05:15 newsfeed nnrpd[27757]: SERVER perl filter not defined
Apr 23 12:24:03 newsfeed nnrpd[32372]: SERVER perl filter not defined
Apr 23 12:24:31 newsfeed nnrpd[32373]: SERVER perl filter not defined
Apr 23 14:19:55 newsfeed nnrpd[4009]: SERVER perl filter not defined
Apr 23 14:22:31 newsfeed nnrpd[4413]: SERVER perl filter not defined
Apr 23 14:23:31 newsfeed nnrpd[4423]: SERVER perl filter not defined
Apr 23 14:23:49 newsfeed nnrpd[4424]: SERVER perl filter not defined
Apr 23 18:48:59 newsfeed nnrpd[13223]: SERVER perl filter not defined
Apr 23 19:53:05 newsfeed nnrpd[15457]: SERVER perl filter not defined
Apr 23 22:00:04 newsfeed nnrpd[19653]: SERVER perl filter not defined
Apr 23 23:39:17 newsfeed nnrpd[22930]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1322526	97.8%	148.4 MB	97.2%
innd	12937	1.0%	2.2 MB	1.4%
nnrpd	9240	0.7%	1011.3 KB	0.6%
innfeed	7272	0.5%	1.1 MB	0.7%
TOTAL: 4	1351975	100.0%	152.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1316237	91.7%
Negative hits	57164	4.0%
Positive hits	39598	2.8%
Cache misses	22852	1.6%
TOTAL: 4	1435851	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.267	0.0%	425	0.000	19.452	376.000
article cleanup	00:00:22.284	0.0%	1321585	0.003	0.017	0.038
article logging	00:00:43.868	0.1%	1321701	0.013	0.033	0.486
article parse	00:54:51.476	3.8%	98051584	0.014	0.034	0.085
article write	00:12:40.698	0.9%	1315604	0.285	0.578	1.898
artlog/artcncl	00:00:00.009	0.0%	400	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:03:05.469	0.2%	106324766	0.001	0.002	0.048
hisgrep/artcncl	00:00:00.961	0.0%	309	0.000	3.110	184.000
hishave/artcncl	00:00:01.448	0.0%	425	0.000	3.407	295.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:00:22.119	8.4%	1437171	0.006	5.025	12.599
history sync	00:00:00.283	0.0%	293	0.000	0.966	270.000
history write	02:45:21.012	11.5%	1317516	0.905	7.530	22.028
hiswrite/artcncl	00:00:00.750	0.0%	116	0.000	6.466	213.000
idle	13:21:50.790	55.7%	69131797	0.271	0.696	7.085
nntp read	00:44:53.785	3.1%	97015582	0.015	0.028	0.058
overview write	00:00:09.289	0.0%	1315604	0.000	0.007	0.029
perl filter	03:17:42.888	13.7%	1317513	5.776	9.004	11.439
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:06.706	0.2%	2670749	0.024	0.070	0.127
TOTAL: 23:59:28.988	23:25:22.102	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.152	0.0%	39560	0.000	0.004	0.111
article prepare	00:00:00.011	0.0%	29678	0.000	0.000	0.125
article read	00:00:01.692	0.0%	7244	0.000	0.234	29.250
backlog stats	00:00:00.285	0.0%	305295	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.022	0.0%	179980	0.000	0.011	1.000
data write	00:00:05.816	0.0%	153039	0.000	0.038	1.027
idle	24:00:07.969	100.0%	305295	0.000	283.031	15075.000
status file	00:00:00.736	0.0%	233	0.000	3.159	105.333
TOTAL: 24:00:26.705	24:00:18.683	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.001	0.0%	1	1.000	1.000	1.000
idle	05:51:07.391	99.6%	68786	0.000	306.274	250639.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.193	0.0%	140227	0.000	0.016	0.667
readart	00:00:03.366	0.0%	623	0.000	5.403	33.000
TOTAL: 05:52:37.531	05:51:12.951	99.6%	-	-	-	-

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	577	1332944	1305559	23644	3741	97%	274:38:09
2	news.task.gda.pl	13	27710	5402	21083	1225	19%	45:00:38
3	news.nask.pl	2	18425	4672	12810	943	25%	24:39:52
4	localhost	12	11	11	0	0	100%	00:00:00
5	news.icm.edu.pl	43	204	0	191	13	0%	15:55:44
TOTAL: 5		647	1379294	1315644	57728	5922	95%	360:14:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	445.6 GB	27.8 MB	47.1 MB	445.6 GB	99%	356.9 KB
2	news.nask.pl	25.4 MB	3.1 MB	3.2 MB	31.7 MB	79%	5.8 KB
3	news.task.gda.pl	12.7 MB	50.3 KB	3.4 MB	16.2 MB	78%	2.5 KB
4	localhost	26.1 KB	0.0 KB	0.0 KB	26.1 KB	100%	2.4 KB
5	news.icm.edu.pl	0.0 KB	0.0 KB	759.0 KB	759.0 KB	0%	58.4 KB
TOTAL: 5		445.6 GB	31.0 MB	54.5 MB	445.7 GB	99%	353.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 23 04:15:03 - 04:59:59	59305	4.5%	21.99	21.6 GB	4.8%	8399.57
Apr 23 05:00:00 - 05:59:59	74913	5.7%	20.81	27.5 GB	6.2%	8011.34
Apr 23 06:00:00 - 06:59:59	63455	4.8%	17.63	23.1 GB	5.2%	6741.80
Apr 23 07:00:00 - 07:59:59	62861	4.8%	17.46	23.1 GB	5.2%	6741.75
Apr 23 08:00:00 - 08:59:59	81060	6.2%	22.52	27.4 GB	6.2%	7984.06
Apr 23 09:00:00 - 09:59:59	46662	3.5%	12.96	17.2 GB	3.9%	5014.20
Apr 23 10:00:00 - 10:59:59	35926	2.7%	9.98	12.9 GB	2.9%	3758.61
Apr 23 11:00:00 - 11:59:59	36979	2.8%	10.27	13.7 GB	3.1%	3988.67
Apr 23 12:00:00 - 12:59:59	49913	3.8%	13.86	15.4 GB	3.5%	4483.23
Apr 23 13:00:00 - 13:59:59	83630	6.4%	23.23	19.4 GB	4.4%	5661.86
Apr 23 14:00:00 - 14:59:59	53046	4.0%	14.73	16.1 GB	3.6%	4686.57
Apr 23 15:00:00 - 15:59:59	32377	2.5%	8.99	12.0 GB	2.7%	3497.20
Apr 23 16:00:00 - 16:59:59	38510	2.9%	10.70	15.6 GB	3.5%	4533.68
Apr 23 17:00:00 - 17:59:59	55559	4.2%	15.43	17.7 GB	4.0%	5149.13
Apr 23 18:00:00 - 18:59:59	64635	4.9%	17.95	19.0 GB	4.3%	5521.98
Apr 23 19:00:00 - 19:59:59	40639	3.1%	11.29	15.2 GB	3.4%	4430.50
Apr 23 20:00:00 - 20:59:59	33680	2.6%	9.36	12.4 GB	2.8%	3618.99
Apr 23 21:00:00 - 21:59:59	23968	1.8%	6.66	7.6 GB	1.7%	2227.96
Apr 23 22:00:00 - 22:59:59	25808	2.0%	7.17	8.3 GB	1.9%	2413.28
Apr 23 23:00:00 - 23:59:59	35176	2.7%	9.77	13.6 GB	3.0%	3955.84
Apr 24 00:00:00 - 00:59:59	59603	4.5%	16.56	20.7 GB	4.6%	6027.36
Apr 24 01:00:00 - 01:59:59	74152	5.6%	20.60	24.0 GB	5.4%	6986.52
Apr 24 02:00:00 - 02:59:59	87056	6.6%	24.18	28.7 GB	6.4%	8346.81
Apr 24 03:00:00 - 03:59:59	81765	6.2%	22.71	27.3 GB	6.1%	7948.26
Apr 24 04:00:00 - 04:15:02	15349	1.2%	17.02	6.0 GB	1.4%	7032.57
TOTAL: 23:59:59	1316027	100.0%	15.23	445.6 GB	100.0%	5407.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3840	190	3133	4	513
2	news.task.gda.pl	1288	1036	25	5	222
3	news.nask.pl	949	7	914	4	24
4	news.icm.edu.pl	22	0	0	0	22
TOTAL: 4		6099	1233	4072	13	781

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.openbsd.source-changes	95
uk.railway	89
mailing.freebsd.svn-ports-all	74
mailing.freebsd.svn-src-all	70
mailing.freebsd.ports-bugs	69
mailing.freebsd.svn-ports-head	64
fa.freebsd.cvs-all	61
mailing.gcc.gcc-cvs	59
mailing.freebsd.svn-src-head	46
mozilla.dev.gaia	43
fr.usenet.abus.nocem	38
alt.windows7.general	35
alt.politics.scorched-earth	29
mailing.unix.dovecot	27
mailing.openssl.dev	26
comp.mobile.ipad	25
mailing.openbsd.misc	24
mailing.openbsd.ports-changes	22
24hoursupport.helpdesk	22
mailing.unix.samba	21
TOTAL: 77	1233

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

Groups	Count
pl.sci.historia	6
fr.comp.lang.php	3
christnet.christnews	1
fr.comp.securite	1
pl.praca.oferowana	1
pl.sci.matematyka	1
TOTAL: 6	13

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	416
EMP (fsl)	184
EMP (md5)	36
HTML post	23
Scoring filter (8)	1
Scoring filter (12)	1
Attached HTML file	1
TOTAL: 7	662

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	64812	3016	33510	2510	1	8	4%	24:00:19
2	panorama	40530	1423	37758	267	0	0	3%	24:02:45
3	task	2252	70	1114	8	0	0	3%	22:02:37
4	poznan	11271	23	9639	7	0	0	0%	23:59:42
5	nask	26544	19	18257	0	0	0	0%	24:02:08
6	okapi	0	0	0	0	0	2909	0%	24:00:02
TOTAL: 6		145409	4551	100278	2792	1	2917	3%	142:07:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	297.0 MB	309.4 MB	606.4 MB	7.2 KB/s	112.4 KB	24:00:19
2	panorama	7.7 MB	1.5 MB	9.2 MB	0.1 KB/s	5.6 KB	24:02:45
3	task	149.9 KB	16.7 KB	166.6 KB	0.0 KB/s	2.1 KB	22:02:37
4	nask	88.2 KB	0.0 KB	88.2 KB	0.0 KB/s	4.6 KB	24:02:08
5	poznan	39.8 KB	26.2 KB	66.0 KB	0.0 KB/s	2.2 KB	23:59:42
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 6		304.9 MB	311.0 MB	615.9 MB	1.2 KB/s	85.9 KB	142:07:33

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	287	389	790.8 KB	287	0	00:00:21
2	lze200.lze.lodz.pl	7	131	253.0 KB	9	0	00:47:53
3	web.man.lodz.pl	288	96	111.0 KB	43	0	00:00:56
4	ip-176.105.135.22.tvsat364.lodz.pl	4	7	10.3 KB	1	0	00:41:35
5	isvcs01.man.lodz.pl	11	0	0.0 KB	0	11	00:00:00
TOTAL: 5		597	623	1.1 MB	340	11	05:52:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	287	389	790.8 KB	287	0	00:00:21
2	*.lze.lodz.pl	7	131	253.0 KB	9	0	00:47:53
3	*.man.lodz.pl	684	96	111.0 KB	43	11	00:01:56
4	*.105.135.22.tvsat364.lodz.pl	4	7	10.3 KB	1	0	00:41:35
5	*.pa.nsw.optusnet.com.au	490	0	0.0 KB	0	0	00:38:19
6	*.cbmm.lodz.pl	2	0	0.0 KB	0	0	00:00:03
7	unresolved	3	0	0.0 KB	0	0	00:00:46
8	*.206.15.62.static.jazztel.es	4	0	0.0 KB	0	0	03:41:32
9	*.toya.net.pl	61	0	0.0 KB	0	0	00:00:06
TOTAL: 9		1542	623	1.1 MB	340	11	05:52:34

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
160.206.15.62.static.jazztel.es	3.464	4.128	03:41:32
lze200.lze.lodz.pl	0.048	0.104	00:47:53
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.056	00:41:35
pa49-181-246-162.pa.nsw.optusnet.com.au	1.040	5.224	00:38:19
web.man.lodz.pl	0.664	3.676	00:00:56
mbox.man.lodz.pl	0.000	0.012	00:00:36
212.51.203.199	0.016	0.024	00:00:29
pc-skrzep2.cm.p.lodz.pl	0.728	3.396	00:00:21
mon.man.lodz.pl	0.604	3.228	00:00:20
74.205.216.175	0.000	0.012	00:00:16
staticline41435.toya.net.pl	0.096	0.700	00:00:06
router2.cbmm.lodz.pl	0.008	0.024	00:00:03
david.man.lodz.pl	0.168	1.080	00:00:01
isvcs01.man.lodz.pl	0.048	0.152	00:00:00
?	0.004	0.044	00:00:00
TOTAL: 15	6.888	21.860	05:52:34

Curious NNRP server explorers [Top 20]:

System	Conn
pa49-181-246-162.pa.nsw.optusnet.com.au	490
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	61
160.206.15.62.static.jazztel.es	4
router2.cbmm.lodz.pl	2
212.51.203.199	2
74.205.216.175	1
mbox.man.lodz.pl	1
TOTAL: 9	945

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	4
TOTAL: 1	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
160.206.15.62.static.jazztel.es	5	1
pa49-181-246-162.pa.nsw.optusnet.com.au	2	0
lze200.lze.lodz.pl	1	2
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 4	9	3

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	389	62.4%
2	pl	234	37.6%
TOTAL: 2		623	100.0%

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

Newsgroup		Count
1	lodman.info	389
2	pl.comp.pecet	98
3	pl.rec.kuchnia	44
4	pl.misc.telefonia.gsm	43
5	pl.comp.os.ms-windows.winnt	30
6	pl.regionalne.lodz	10
7	pl.soc.prawo	7
8	pl.rec.dom	2
TOTAL: 8		623

Daily Usenet report

Apr 23 04:15:03 -- Apr 24 04:15:02