Daily Usenet report: Jan 6 04:15:04

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 6 09:05:26 newsfeed nnrpd[18211]: SERVER perl filter not defined
Jan 6 09:40:10 newsfeed nnrpd[19264]: SERVER perl filter not defined
Jan 6 14:01:42 newsfeed nnrpd[27924]: SERVER perl filter not defined
Jan 6 14:51:24 newsfeed nnrpd[29527]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2054401	98.4%	244.4 MB	98.1%
innd	17450	0.8%	2.9 MB	1.2%
nnrpd	8448	0.4%	877.7 KB	0.3%
innfeed	7197	0.3%	1.1 MB	0.4%
controlchan	2	0.0%	0.4 KB	0.0%
TOTAL: 5	2087498	100.0%	249.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2046654	95.2%
Negative hits	60887	2.8%
Positive hits	40896	1.9%
Cache misses	2458	0.1%
TOTAL: 4	2150895	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:01:35.765	0.1%	2726	0.000	35.130	828.000
article cleanup	00:00:28.996	0.0%	2051359	0.001	0.014	0.029
article logging	00:01:02.391	0.1%	2051514	0.006	0.030	0.511
article parse	01:04:44.627	4.5%	138302090	0.003	0.028	0.087
article write	00:15:57.889	1.1%	2044443	0.206	0.469	40.854
artlog/artcncl	00:00:00.060	0.0%	2676	0.000	0.022	1.000
artlog/artparse	00:00:00.000	0.0%	16	0.000	0.000	0.000
data move	00:03:21.805	0.2%	150401413	0.000	0.001	0.004
hisgrep/artcncl	00:00:25.715	0.0%	1297	0.000	19.827	218.000
hishave/artcncl	00:00:18.358	0.0%	2726	0.000	6.734	59.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:01:00.987	12.6%	2149551	1.085	5.053	11.104
history sync	00:00:00.013	0.0%	293	0.000	0.044	1.000
history write	05:04:56.084	21.2%	2046455	4.406	8.940	44.826
hiswrite/artcncl	00:00:13.416	0.0%	1429	0.000	9.388	185.000
idle	08:19:15.779	34.7%	89614541	0.160	0.334	1.204
nntp read	00:59:17.830	4.1%	136697151	0.006	0.026	0.046
overview write	00:00:13.127	0.0%	2044443	0.000	0.006	0.016
perl filter	04:29:14.091	18.7%	2046439	3.419	7.894	10.442
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:25.596	0.3%	4128951	0.008	0.064	0.091
TOTAL: 23:59:58.813	23:26:32.529	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.149	0.0%	41848	0.000	0.004	1.000
article prepare	00:00:00.007	0.0%	33712	0.000	0.000	0.062
article read	00:00:00.731	0.0%	8203	0.000	0.089	1.000
backlog stats	00:00:00.182	0.0%	293841	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.984	0.0%	175578	0.000	0.011	1.000
data write	00:00:05.604	0.0%	145243	0.000	0.039	1.977
idle	23:59:43.243	100.0%	293841	0.000	293.980	16078.000
status file	00:00:01.007	0.0%	222	0.000	4.536	302.000
TOTAL: 23:59:59.301	23:59:52.907	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.062	0.0%	626	0.099	0.099	0.099
idle	41:46:29.968	100.0%	178395	0.000	843.017	600278.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:05.939	0.0%	358662	0.000	0.017	1.222
readart	00:00:01.485	0.0%	1031	0.000	1.440	257.000
TOTAL: 41:47:19.202	41:46:37.454	100.0%	-	-	-	-

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

Control Channel:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	795	2038909	2032767	2051	4091	99%	359:34:38
2	news.task.gda.pl	16	31505	7120	23085	1300	22%	44:29:17
3	news.nask.pl	3	17822	4755	11560	1507	26%	24:17:39
4	news.icm.edu.pl	46	229	7	207	15	3%	17:10:20
5	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 5		865	2088469	2044653	36903	6913	97%	445:31:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	574.9 GB	75.5 MB	116.6 MB	575.0 GB	99%	296.0 KB
2	news.nask.pl	17.5 MB	4.1 MB	4.2 MB	25.7 MB	67%	4.2 KB
3	news.task.gda.pl	15.2 MB	42.1 KB	4.2 MB	19.4 MB	78%	2.4 KB
4	news.icm.edu.pl	325.5 KB	44.1 KB	349.3 KB	718.8 KB	45%	32.7 KB
5	localhost	6.4 KB	0.0 KB	0.0 KB	6.4 KB	100%	1.6 KB
TOTAL: 5		574.9 GB	79.6 MB	125.4 MB	575.1 GB	99%	293.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 6 04:15:04 - 04:59:59	67337	3.3%	24.98	20.7 GB	3.6%	8035.57
Jan 6 05:00:00 - 05:59:59	88525	4.3%	24.59	29.8 GB	5.2%	8682.44
Jan 6 06:00:00 - 06:59:59	87333	4.3%	24.26	28.7 GB	5.0%	8363.65
Jan 6 07:00:00 - 07:59:59	88885	4.3%	24.69	29.5 GB	5.1%	8604.75
Jan 6 08:00:00 - 08:59:59	100456	4.9%	27.90	34.0 GB	5.9%	9907.29
Jan 6 09:00:00 - 09:59:59	105692	5.2%	29.36	32.9 GB	5.7%	9581.91
Jan 6 10:00:00 - 10:59:59	52059	2.5%	14.46	15.4 GB	2.7%	4486.70
Jan 6 11:00:00 - 11:59:59	55846	2.7%	15.51	17.5 GB	3.0%	5098.69
Jan 6 12:00:00 - 12:59:59	88795	4.3%	24.67	21.8 GB	3.8%	6347.59
Jan 6 13:00:00 - 13:59:59	93664	4.6%	26.02	21.1 GB	3.7%	6138.64
Jan 6 14:00:00 - 14:59:59	91384	4.5%	25.38	19.9 GB	3.5%	5799.86
Jan 6 15:00:00 - 15:59:59	99199	4.9%	27.56	23.1 GB	4.0%	6714.74
Jan 6 16:00:00 - 16:59:59	90861	4.4%	25.24	20.8 GB	3.6%	6057.31
Jan 6 17:00:00 - 17:59:59	91914	4.5%	25.53	21.5 GB	3.7%	6255.31
Jan 6 18:00:00 - 18:59:59	53867	2.6%	14.96	18.4 GB	3.2%	5361.32
Jan 6 19:00:00 - 19:59:59	75104	3.7%	20.86	20.2 GB	3.5%	5897.24
Jan 6 20:00:00 - 20:59:59	74366	3.6%	20.66	19.8 GB	3.4%	5755.11
Jan 6 21:00:00 - 21:59:59	79108	3.9%	21.97	19.4 GB	3.4%	5640.92
Jan 6 22:00:00 - 22:59:59	79235	3.9%	22.01	20.4 GB	3.6%	5949.17
Jan 6 23:00:00 - 23:59:59	75173	3.7%	20.88	19.0 GB	3.3%	5545.16
Jan 7 00:00:00 - 00:59:59	83349	4.1%	23.15	25.3 GB	4.4%	7362.68
Jan 7 01:00:00 - 01:59:59	95245	4.7%	26.46	28.3 GB	4.9%	8242.15
Jan 7 02:00:00 - 02:59:59	111040	5.4%	30.84	32.8 GB	5.7%	9552.24
Jan 7 03:00:00 - 03:59:59	95596	4.7%	26.55	28.2 GB	4.9%	8220.26
Jan 7 04:00:00 - 04:15:02	20605	1.0%	22.84	6.3 GB	1.1%	7308.04
TOTAL: 23:59:58	2044638	100.0%	23.67	574.8 GB	100.0%	6975.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4217	200	3425	5	587
2	news.nask.pl	1542	16	1470	3	53
3	news.task.gda.pl	1313	1095	24	1	193
4	news.icm.edu.pl	15	0	1	0	14
TOTAL: 4		7087	1311	4920	9	847

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-head	124
mailing.freebsd.svn-ports-all	123
uk.railway	75
mozilla.dev.platform	62
gmane.linux.alsa.devel	48
mailing.database.pgsql-general	45
mailing.openbsd.source-changes	43
mailing.freebsd.ports-bugs	41
mozilla.dev.gaia	33
mailing.freebsd.svn-src-all	32
mailing.freebsd.svn-src-head	31
mailing.freebsd.current	30
fa.freebsd.cvs-all	30
mailing.gcc.gcc-cvs	29
mailing.postfix.users	29
comp.mobile.ipad	28
mailing.unix.samba-technical	26
alt.windows7.general	23
mailing.unix.spamassassin-talk	23
mailing.openssl.dev	22
TOTAL: 82	1311

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

Groups	Count
pl.sci.matematyka	5
pl.sci.historia	4
TOTAL: 2	9

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	375
EMP (fsl)	146
Attached HTML file	101
EMP (md5)	35
HTML post	13
Scoring filter (8)	6
TOTAL: 6	676

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	61427	4511	33104	1805	1777	7%	24:00:00
2	panorama	41525	1301	38485	288	0	3%	24:01:27
3	task	2051	94	1059	10	6	4%	20:48:16
4	poznan	12438	46	10970	26	0	0%	23:58:47
5	nask	21238	26	14071	242	0	0%	24:03:53
6	okapi	0	0	0	0	2951	0%	24:00:01
TOTAL: 6		138679	5978	97689	2371	4734	4%	140:52:24

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	521.7 MB	14.7 MB	536.5 MB	6.4 KB/s	87.0 KB	24:00:00
2	panorama	4.8 MB	1.4 MB	6.2 MB	0.1 KB/s	4.0 KB	24:01:27
3	task	215.6 KB	60.6 KB	276.3 KB	0.0 KB/s	2.7 KB	20:48:16
4	poznan	121.8 KB	42.3 KB	164.1 KB	0.0 KB/s	2.3 KB	23:58:47
5	nask	80.5 KB	132.7 MB	132.7 MB	1.6 KB/s	507.2 KB	24:03:53
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		526.9 MB	148.9 MB	675.8 MB	1.4 KB/s	82.9 KB	140:52:24

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	vegas.cm.p.lodz.pl	288	336	562.3 KB	288	0	00:00:13
2	web.man.lodz.pl	288	34	37.1 KB	16	0	00:00:24
3	212.191.78.190	129	23	37.1 KB	13	0	18:40:32
4	ip-176.105.135.22.tvsat364.lodz.pl	11	16	29.5 KB	4	0	02:55:34
5	kujawiak.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 5		720	409	665.9 KB	321	4	41:47:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	562.3 KB	288	0	00:00:13
2	*.man.lodz.pl	676	34	37.1 KB	16	4	00:00:31
3	unresolved	129	23	37.1 KB	13	0	18:40:32
4	*.105.135.22.tvsat364.lodz.pl	11	16	29.5 KB	4	0	02:55:34
5	*.lsanca.dsl-w.verizon.net	2	0	0.0 KB	0	0	00:00:02
6	*.181.14.62.static.jazztel.es	38	0	0.0 KB	0	0	20:10:20
7	*.toya.net.pl	31	0	0.0 KB	0	0	00:00:03
TOTAL: 7		1175	409	665.9 KB	321	4	41:47:18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
117.181.14.62.static.jazztel.es	9.252	14.296	20:10:20
212.191.78.190	0.300	1.636	18:40:32
ip-176.105.135.22.tvsat364.lodz.pl	0.044	0.136	02:55:34
web.man.lodz.pl	0.592	3.864	00:00:24
vegas.cm.p.lodz.pl	0.636	3.944	00:00:13
mon.man.lodz.pl	0.604	3.592	00:00:04
staticline41435.toya.net.pl	0.060	0.372	00:00:03
pool-71-105-105-145.lsanca.dsl-w.verizon.net	0.036	0.036	00:00:02
david.man.lodz.pl	0.232	1.188	00:00:01
kujawiak.man.lodz.pl	0.020	0.096	00:00:00
TOTAL: 10	11.776	29.160	41:47:18

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
117.181.14.62.static.jazztel.es	38
staticline41435.toya.net.pl	31
pool-71-105-105-145.lsanca.dsl-w.verizon.net	2
TOTAL: 5	455

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	4	0
117.181.14.62.static.jazztel.es	1	1
TOTAL: 2	5	4

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	336	82.2%
2	pl	71	17.4%
3	alt	2	0.5%
TOTAL: 3		409	100.0%

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

Newsgroup		Count
1	lodman.info	336
2	pl.comp.pecet	26
3	pl.soc.prawo	15
4	pl.misc.kolej	9
5	pl.comp.os.ms-windows.winnt	8
6	pl.comp.os.linux	7
7	pl.rec.rowery	5
8	alt.pl.praca.oferowana	2
9	pl.soc.dzieci	1
TOTAL: 9		409

Daily Usenet report

Jan 6 04:15:04 -- Jan 7 04:15:02