Daily Usenet report: May 14 04:15:03

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 14 11:09:10 newsfeed nnrpd[3251]: SERVER perl filter not defined
May 14 13:08:05 newsfeed nnrpd[7470]: SERVER perl filter not defined
May 14 13:08:59 newsfeed nnrpd[7475]: SERVER perl filter not defined
May 14 15:04:32 newsfeed nnrpd[11462]: SERVER perl filter not defined
May 14 15:13:15 newsfeed nnrpd[11792]: SERVER perl filter not defined
May 14 16:43:10 newsfeed nnrpd[14816]: SERVER perl filter not defined
May 14 19:31:34 newsfeed nnrpd[20351]: SERVER perl filter not defined
May 14 19:42:08 newsfeed pgpverify[20826]: pgpverify: verification failed
May 14 21:58:50 newsfeed nnrpd[25295]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1245088	97.8%	144.0 MB	97.3%
innd	13356	1.0%	2.2 MB	1.5%
nnrpd	7585	0.6%	791.3 KB	0.5%
innfeed	7188	0.6%	1.1 MB	0.7%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.0 KB	0.0%
TOTAL: 6	1273221	100.0%	148.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1238938	92.3%
Negative hits	63626	4.7%
Positive hits	40154	3.0%
Cache misses	173	0.0%
TOTAL: 4	1342891	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.606	0.0%	339	0.000	25.386	1164.000
article cleanup	00:00:21.643	0.0%	1244037	0.002	0.017	0.044
article logging	00:00:38.601	0.0%	1244157	0.008	0.031	0.475
article parse	00:48:28.557	3.4%	122003775	0.009	0.024	0.080
article write	00:10:45.837	0.7%	1238056	0.258	0.522	1.667
artlog/artcncl	00:00:00.009	0.0%	339	0.000	0.027	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:03:49.210	0.3%	129947781	0.000	0.002	0.069
hisgrep/artcncl	00:00:01.104	0.0%	272	0.000	4.059	313.000
hishave/artcncl	00:00:00.482	0.0%	339	0.000	1.422	75.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:56:22.409	3.9%	1344395	0.010	2.516	8.796
history sync	00:00:00.010	0.0%	292	0.000	0.034	1.000
history write	02:22:53.000	9.9%	1240275	0.265	6.912	19.401
hiswrite/artcncl	00:00:00.750	0.0%	67	0.000	11.194	258.000
idle	15:16:40.111	63.7%	93845657	0.283	0.586	1.469
nntp read	00:49:10.060	3.4%	121068633	0.011	0.024	0.057
overview write	00:00:08.147	0.0%	1238056	0.000	0.007	0.022
perl filter	02:46:56.890	11.6%	1240272	4.521	8.076	15.401
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:58.292	0.2%	2514458	0.040	0.071	0.148
TOTAL: 23:59:21.612	23:19:23.718	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.149	0.0%	38511	0.000	0.004	0.143
article prepare	00:00:00.014	0.0%	45878	0.000	0.000	0.036
article read	00:00:01.433	0.0%	11290	0.000	0.127	15.000
backlog stats	00:00:00.230	0.0%	294318	0.000	0.001	0.016
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:01.914	0.0%	175041	0.000	0.011	0.066
data write	00:00:04.559	0.0%	145407	0.000	0.031	1.000
idle	24:00:23.391	100.0%	294318	0.000	293.640	14521.000
status file	00:00:00.319	0.0%	196	0.000	1.628	44.667
TOTAL: 24:00:38.963	24:00:32.009	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	24:41:29.798	99.9%	548907	0.000	161.940	208945.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:16.384	0.0%	1099930	0.000	0.015	1.200
readart	00:00:00.779	0.0%	546	0.000	1.427	18.667
TOTAL: 24:42:40.602	24:41:46.961	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
grisbi-control@grisbi.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	715	1232264	1226209	2456	3599	99%	248:53:01
2	news.task.gda.pl	11	30434	6343	22363	1728	20%	46:30:55
3	news.nask.pl	4	18068	6066	11342	660	33%	24:07:54
4	localhost	9	8	8	0	0	100%	00:00:01
5	news.icm.edu.pl	51	194	2	188	4	1%	16:13:05
TOTAL: 5		790	1280968	1238628	36349	5991	96%	335:44:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	374.7 GB	16.6 MB	29.1 MB	374.7 GB	99%	319.5 KB
2	news.nask.pl	26.7 MB	1.8 MB	2.0 MB	30.6 MB	87%	4.7 KB
3	news.task.gda.pl	14.3 MB	35.7 KB	5.3 MB	19.7 MB	72%	2.5 KB
4	news.icm.edu.pl	81.5 KB	0.0 KB	42.8 KB	124.3 KB	65%	20.7 KB
5	localhost	19.1 KB	0.0 KB	0.0 KB	19.1 KB	100%	2.4 KB
TOTAL: 5		374.7 GB	18.5 MB	36.5 MB	374.8 GB	99%	315.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 14 04:15:03 - 04:59:59	50157	4.0%	18.60	18.2 GB	4.9%	7090.88
May 14 05:00:00 - 05:59:59	35589	2.9%	9.89	12.7 GB	3.4%	3697.52
May 14 06:00:00 - 06:59:59	42971	3.5%	11.94	12.4 GB	3.3%	3625.00
May 14 07:00:00 - 07:59:59	37993	3.1%	10.55	13.3 GB	3.6%	3873.41
May 14 08:00:00 - 08:59:59	28803	2.3%	8.00	12.0 GB	3.2%	3488.05
May 14 09:00:00 - 09:59:59	31512	2.5%	8.75	11.3 GB	3.0%	3292.35
May 14 10:00:00 - 10:59:59	42513	3.4%	11.81	12.2 GB	3.3%	3547.80
May 14 11:00:00 - 11:59:59	50755	4.1%	14.10	12.8 GB	3.4%	3718.07
May 14 12:00:00 - 12:59:59	49963	4.0%	13.88	13.2 GB	3.5%	3838.89
May 14 13:00:00 - 13:59:59	67144	5.4%	18.65	20.1 GB	5.4%	5868.96
May 14 14:00:00 - 14:59:59	67221	5.4%	18.67	19.6 GB	5.2%	5694.98
May 14 15:00:00 - 15:59:59	66412	5.4%	18.45	18.2 GB	4.9%	5299.35
May 14 16:00:00 - 16:59:59	57363	4.6%	15.93	14.8 GB	4.0%	4316.42
May 14 17:00:00 - 17:59:59	55639	4.5%	15.46	15.1 GB	4.0%	4393.75
May 14 18:00:00 - 18:59:59	45822	3.7%	12.73	12.8 GB	3.4%	3715.98
May 14 19:00:00 - 19:59:59	34292	2.8%	9.53	12.4 GB	3.3%	3608.68
May 14 20:00:00 - 20:59:59	47218	3.8%	13.12	15.3 GB	4.1%	4456.42
May 14 21:00:00 - 21:59:59	47267	3.8%	13.13	15.3 GB	4.1%	4464.43
May 14 22:00:00 - 22:59:59	51404	4.2%	14.28	15.9 GB	4.2%	4628.01
May 14 23:00:00 - 23:59:59	52984	4.3%	14.72	16.1 GB	4.3%	4702.07
May 15 00:00:00 - 00:59:59	55136	4.5%	15.32	16.8 GB	4.5%	4904.73
May 15 01:00:00 - 01:59:59	61739	5.0%	17.15	19.1 GB	5.1%	5553.09
May 15 02:00:00 - 02:59:59	76967	6.2%	21.38	20.9 GB	5.6%	6099.50
May 15 03:00:00 - 03:59:59	68024	5.5%	18.90	19.5 GB	5.2%	5667.09
May 15 04:00:00 - 04:15:03	13757	1.1%	15.23	4.5 GB	1.2%	5275.35
TOTAL: 24:00:00	1238645	100.0%	14.34	374.5 GB	100.0%	4545.42

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3697	164	3104	1	428
2	news.task.gda.pl	1727	1140	17	1	569
3	news.nask.pl	676	13	644	0	19
4	news.icm.edu.pl	4	0	0	0	4
TOTAL: 4		6104	1317	3765	2	1020

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-src-all	93
mailing.freebsd.svn-ports-all	86
fa.freebsd.cvs-all	84
mailing.freebsd.svn-ports-head	81
mailing.freebsd.ports-bugs	75
uk.railway	59
mailing.freebsd.current	51
mailing.unix.samba-technical	48
mailing.gcc.gcc-cvs	46
mailing.freebsd.tinderbox	46
alt.politics.scorched-earth	40
mailing.openbsd.misc	31
mozilla.dev.tree-management	31
gmane.linux.alsa.devel	30
comp.sys.raspberry-pi	30
mailing.openbsd.ports-changes	29
fr.usenet.abus.nocem	29
alt.windows7.general	28
mailing.openbsd.source-changes	26
mailing.postfix.users	25
TOTAL: 76	1317

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

Groups	Count
fr.comp.securite	1
pl.sci.historia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	344
Binary in non-binary group	316
EMP (fsl)	205
Attached HTML file	19
EMP (md5)	6
HTML post	3
Scoring filter (8)	3
Scoring filter (12)	1
TOTAL: 8	897

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	58642	5281	28837	2979	17	149	9%	23:57:03
2	panorama	40027	2573	35314	475	0	0	6%	23:59:34
3	task	2261	55	1169	9	0	0	2%	22:38:33
4	poznan	14729	48	12156	23	0	0	0%	23:54:08
5	nask	22050	13	14775	14	0	0	0%	24:00:02
6	okapi	0	0	0	0	0	2950	0%	24:00:00
TOTAL: 6		137709	7970	92251	3500	17	3099	5%	142:29:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	170.3 MB	105.0 MB	275.4 MB	3.3 KB/s	34.1 KB	23:57:03
2	panorama	11.9 MB	2.7 MB	14.6 MB	0.2 KB/s	4.9 KB	23:59:34
3	poznan	180.2 KB	54.0 KB	234.2 KB	0.0 KB/s	3.3 KB	23:54:08
4	task	125.1 KB	15.4 KB	140.5 KB	0.0 KB/s	2.2 KB	22:38:33
5	nask	50.4 KB	10.0 MB	10.1 MB	0.1 KB/s	382.1 KB	24:00:02
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		182.5 MB	117.8 MB	300.4 MB	0.6 KB/s	26.8 KB	142:29:20

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	384	817.9 KB	288	0	00:00:10
2	web.man.lodz.pl	288	98	91.6 KB	46	0	00:00:27
3	lze200.lze.lodz.pl	4	61	108.2 KB	8	0	01:24:49
4	ip-176.105.135.22.tvsat364.lodz.pl	3	3	4.9 KB	1	0	00:01:02
5	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 5		591	546	1022.7 KB	343	8	24:42:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	384	817.9 KB	288	0	00:00:10
2	*.man.lodz.pl	680	98	91.6 KB	46	8	00:00:34
3	*.lze.lodz.pl	4	61	108.2 KB	8	0	01:24:49
4	*.105.135.22.tvsat364.lodz.pl	3	3	4.9 KB	1	0	00:01:02
5	*.pa.nsw.optusnet.com.au	55	0	0.0 KB	0	0	00:01:35
6	*.cbmm.lodz.pl	1	0	0.0 KB	0	0	00:00:18
7	unresolved	2	0	0.0 KB	0	0	00:00:41
8	*.wroclaw.mm.pl	24	0	0.0 KB	0	0	03:33:59
9	*.191.14.62.static.jazztel.es	7	0	0.0 KB	0	0	19:39:17
10	*.toya.net.pl	64	0	0.0 KB	0	0	00:00:09
TOTAL: 10		1128	546	1022.7 KB	343	8	24:42:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
242.191.14.62.static.jazztel.es	26.088	36.288	19:39:17
host-89-231-50-15.wroclaw.mm.pl	0.052	0.288	03:33:59
lze200.lze.lodz.pl	0.024	0.076	01:24:49
pa49-181-246-84.pa.nsw.optusnet.com.au	0.136	0.564	00:01:35
ip-176.105.135.22.tvsat364.lodz.pl	0.008	0.032	00:01:02
212.51.203.199	0.000	0.028	00:00:41
web.man.lodz.pl	0.716	3.832	00:00:27
router2.cbmm.lodz.pl	0.004	0.012	00:00:18
pc-skrzep2.cm.p.lodz.pl	0.584	3.252	00:00:10
staticline41435.toya.net.pl	0.152	0.684	00:00:09
mon.man.lodz.pl	0.468	3.064	00:00:04
david.man.lodz.pl	0.224	0.984	00:00:01
isvcs01.man.lodz.pl	0.036	0.092	00:00:01
TOTAL: 13	28.492	49.196	24:42:39

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	64
pa49-181-246-84.pa.nsw.optusnet.com.au	55
host-89-231-50-15.wroclaw.mm.pl	24
242.191.14.62.static.jazztel.es	7
212.51.203.199	2
router2.cbmm.lodz.pl	1
TOTAL: 8	537

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	0
242.191.14.62.static.jazztel.es	1	1
TOTAL: 2	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	384	70.3%
2	pl	162	29.7%
TOTAL: 2		546	100.0%

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

Newsgroup		Count
1	lodman.info	384
2	pl.comp.pecet	63
3	pl.comp.os.ms-windows.winnt	53
4	pl.misc.telefonia.gsm	15
5	pl.comp.os.linux.sieci	14
6	pl.comp.os.linux	8
7	pl.internet.polip	4
8	pl.soc.prawo	3
9	pl.rec.dom	2
TOTAL: 9		546

Daily Usenet report

May 14 04:15:03 -- May 15 04:15:03