Daily Usenet report: Jun 1 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jun 1 08:00:48 newsfeed nnrpd[13490]: SERVER perl filter not defined
Jun 1 11:15:00 newsfeed nnrpd[19539]: SERVER perl filter not defined
Jun 1 13:19:56 newsfeed nnrpd[23634]: SERVER perl filter not defined
Jun 1 13:22:46 newsfeed nnrpd[23698]: SERVER perl filter not defined
Jun 1 16:25:47 newsfeed nnrpd[29457]: SERVER perl filter not defined
Jun 1 23:11:24 newsfeed nnrpd[9918]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2735453	98.4%	310.3 MB	98.0%
innd	28384	1.0%	4.4 MB	1.4%
nnrpd	9826	0.4%	1.0 MB	0.3%
innfeed	7231	0.3%	1.1 MB	0.3%
TOTAL: 4	2780894	100.0%	316.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2723727	95.3%
Negative hits	92865	3.3%
Positive hits	39458	1.4%
Cache misses	766	0.0%
TOTAL: 4	2856816	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:12.766	0.0%	368	0.000	34.690	547.000
article cleanup	00:00:37.968	0.0%	2734966	0.004	0.014	0.344
article logging	00:01:44.303	0.1%	2735134	0.016	0.038	0.969
article parse	01:17:33.005	5.4%	68907061	0.027	0.068	0.267
article write	00:28:49.904	2.0%	2714766	0.310	0.637	5.557
artlog/artcncl	00:00:00.003	0.0%	341	0.000	0.009	0.500
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:02:29.537	0.2%	82857033	0.001	0.002	0.035
hisgrep/artcncl	00:00:02.570	0.0%	296	0.000	8.682	149.000
hishave/artcncl	00:00:01.107	0.0%	368	0.000	3.008	212.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:59:14.618	16.6%	2858042	0.060	5.023	29.934
history sync	00:00:00.012	0.0%	296	0.000	0.041	1.000
history write	05:40:52.392	23.7%	2724902	0.073	7.506	36.601
hiswrite/artcncl	00:00:00.982	0.0%	72	0.000	13.639	112.000
idle	04:20:27.152	18.1%	28706491	0.175	0.544	7.070
nntp read	00:41:22.525	2.9%	66643260	0.018	0.037	0.150
overview write	00:00:19.620	0.0%	2714766	0.001	0.007	0.157
perl filter	06:54:37.866	28.8%	2724899	5.841	9.130	27.046
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:07:52.678	0.5%	5468465	0.027	0.086	0.900
TOTAL: 24:00:09.653	23:36:19.008	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.198	0.0%	38938	0.000	0.005	1.364
article prepare	00:00:00.014	0.0%	31526	0.000	0.000	0.083
article read	00:00:00.774	0.0%	7551	0.000	0.103	5.400
backlog stats	00:00:00.342	0.0%	279054	0.000	0.001	0.376
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.088	0.0%	169861	0.000	0.018	9.673
data write	00:00:05.665	0.0%	140259	0.000	0.040	19.000
idle	23:59:34.612	100.0%	279054	0.000	309.527	23539.000
status file	00:00:04.276	0.0%	242	1.000	17.669	262.000
TOTAL: 23:59:59.792	23:59:48.969	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:09.605	0.0%	4910	0.342	1.956	4.485
idle	63:31:58.129	100.0%	54569	0.000	4191.356	600061.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.207	0.0%	111149	0.000	0.038	3.333
readart	00:00:04.473	0.0%	3506	0.000	1.276	30.923
TOTAL: 63:33:00.884	63:32:16.414	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	592	2717230	2699550	1375	16305	99%	508:31:54
2	news.nask.pl	2	18972	8751	7364	2857	46%	24:35:21
3	news.task.gda.pl	19	28667	6748	20941	978	23%	45:12:19
4	localhost	7	6	6	0	0	100%	00:00:00
5	news.icm.edu.pl	38	337	3	266	68	0%	17:51:37
TOTAL: 5		658	2765212	2715058	29946	20208	98%	596:11:11

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	797.7 GB	155.1 MB	1.7 GB	799.6 GB	99%	308.7 KB
2	news.nask.pl	173.4 MB	28.6 MB	35.9 MB	238.0 MB	72%	21.0 KB
3	news.task.gda.pl	12.4 MB	82.2 KB	2.5 MB	15.0 MB	83%	2.0 KB
4	news.icm.edu.pl	147.4 KB	13.1 KB	2.7 MB	2.8 MB	5%	40.9 KB
5	localhost	10.0 KB	0.0 KB	0.0 KB	10.0 KB	100%	1.7 KB
TOTAL: 5		797.9 GB	183.8 MB	1.7 GB	799.8 GB	99%	306.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 1 04:15:03 - 04:59:59	54745	2.0%	20.30	16.8 GB	2.1%	6522.79
Jun 1 05:00:00 - 05:59:59	93245	3.4%	25.90	29.6 GB	3.7%	8619.67
Jun 1 06:00:00 - 06:59:59	92975	3.4%	25.83	29.0 GB	3.6%	8458.98
Jun 1 07:00:00 - 07:59:59	113567	4.2%	31.55	32.4 GB	4.1%	9433.30
Jun 1 08:00:00 - 08:59:59	112972	4.2%	31.38	32.7 GB	4.1%	9534.13
Jun 1 09:00:00 - 09:59:59	102711	3.8%	28.53	29.2 GB	3.7%	8494.81
Jun 1 10:00:00 - 10:59:59	130806	4.8%	36.34	35.0 GB	4.4%	10193.40
Jun 1 11:00:00 - 11:59:59	134797	5.0%	37.44	36.4 GB	4.6%	10602.33
Jun 1 12:00:00 - 12:59:59	130411	4.8%	36.23	31.8 GB	4.0%	9253.65
Jun 1 13:00:00 - 13:59:59	126159	4.6%	35.04	29.1 GB	3.6%	8469.16
Jun 1 14:00:00 - 14:59:59	112878	4.2%	31.36	31.3 GB	3.9%	9124.36
Jun 1 15:00:00 - 15:59:59	125681	4.6%	34.91	38.6 GB	4.8%	11230.60
Jun 1 16:00:00 - 16:59:59	122382	4.5%	33.99	38.0 GB	4.8%	11080.38
Jun 1 17:00:00 - 17:59:59	112676	4.2%	31.30	35.6 GB	4.5%	10370.55
Jun 1 18:00:00 - 18:59:59	126735	4.7%	35.20	34.8 GB	4.4%	10134.13
Jun 1 19:00:00 - 19:59:59	116561	4.3%	32.38	29.9 GB	3.8%	8723.40
Jun 1 20:00:00 - 20:59:59	116146	4.3%	32.26	33.6 GB	4.2%	9795.01
Jun 1 21:00:00 - 21:59:59	109903	4.0%	30.53	35.2 GB	4.4%	10240.46
Jun 1 22:00:00 - 22:59:59	112007	4.1%	31.11	35.4 GB	4.4%	10318.74
Jun 1 23:00:00 - 23:59:59	116247	4.3%	32.29	37.0 GB	4.6%	10762.68
Jun 2 00:00:00 - 00:59:59	95452	3.5%	26.51	30.5 GB	3.8%	8874.65
Jun 2 01:00:00 - 01:59:59	122365	4.5%	33.99	40.1 GB	5.0%	11675.21
Jun 2 02:00:00 - 02:59:59	108142	4.0%	30.04	35.3 GB	4.4%	10271.91
Jun 2 03:00:00 - 03:59:59	105542	3.9%	29.32	33.7 GB	4.2%	9812.91
Jun 2 04:00:00 - 04:15:03	19662	0.7%	21.77	6.7 GB	0.8%	7831.62
TOTAL: 24:00:00	2714767	100.0%	31.42	797.6 GB	100.0%	9680.23

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	16403	292	7320	0	8791
2	news.nask.pl	2877	33	2701	0	143
3	news.task.gda.pl	970	764	41	4	161
4	news.icm.edu.pl	95	0	1	0	94
TOTAL: 4		20345	1089	10063	4	9189

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.openbsd.source-changes	110
uk.railway	106
mailing.freebsd.svn-ports-all	62
mailing.freebsd.svn-ports-head	62
uk.rec.cycling.moderated	51
mailing.freebsd.ports-bugs	43
mailing.openbsd.ports-changes	42
alt.politics.scorched-earth	42
it.comp.os.win.windows8	35
comp.mobile.ipad	27
fa.freebsd.cvs-all	26
it.comp.os.win.windows7	22
mailing.database.pgsql-general	22
mailing.freebsd.svn-src-all	22
gnu.hurd.bug	21
mailing.freebsd.ports	21
mailing.postfix.users	20
mailing.gcc.gcc-cvs	17
gmane.linux.alsa.devel	16
mailing.unix.dovecot	16
TOTAL: 87	1089

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

Groups	Count
fr.comp.securite	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	8674
EMP (fsl)	147
EMP (md5)	120
Attached HTML file	47
HTML post	28
Scoring filter (8)	1
Scoring filter (12)	1
TOTAL: 7	9018

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	42227	3196	34808	964	0	0	7%	24:03:53
2	icm	56043	2010	32577	1475	1	8	3%	23:56:41
3	poznan	16078	110	13839	51	0	0	0%	24:01:48
4	task	1937	68	1020	6	0	0	3%	21:32:35
5	nask	15363	7	11238	0	0	0	0%	23:54:10
6	okapi	0	0	0	0	0	2310	0%	24:00:01
TOTAL: 6		131648	5391	93482	2496	1	2318	4%	141:29:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	232.0 MB	222.0 MB	454.0 MB	5.4 KB/s	133.4 KB	23:56:41
2	panorama	17.0 MB	8.9 MB	25.8 MB	0.3 KB/s	6.4 KB	24:03:53
3	poznan	350.0 KB	88.9 KB	438.9 KB	0.0 KB/s	2.7 KB	24:01:48
4	task	134.9 KB	11.7 KB	146.7 KB	0.0 KB/s	2.0 KB	21:32:35
5	nask	13.5 KB	0.0 KB	13.5 KB	0.0 KB/s	1.9 KB	23:54:10
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		249.4 MB	231.0 MB	480.4 MB	1.0 KB/s	62.4 KB	141:29:08

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	360	630.4 KB	288	0	00:00:11
2	web.man.lodz.pl	288	126	151.6 KB	45	0	00:00:21
3	ebs95.neoplus.adsl.tpnet.pl	7	4	6.2 KB	3	0	00:34:59
4	kujawiak.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 4		589	490	788.2 KB	336	6	63:32:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	360	630.4 KB	288	0	00:00:11
2	*.man.lodz.pl	678	126	151.6 KB	45	6	00:00:32
3	*.neoplus.adsl.tpnet.pl	7	4	6.2 KB	3	0	00:34:59
4	*.207.15.62.static.jazztel.es	14	0	0.0 KB	0	0	14:27:17
5	*.105.135.22.tvsat364.lodz.pl	1	0	0.0 KB	0	0	00:30:01
6	unresolved	286	0	0.0 KB	0	0	47:59:56
TOTAL: 6		1274	490	788.2 KB	336	6	63:32:58

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	1.184	11.468	47:59:56
82.207.15.62.static.jazztel.es	5.832	11.560	14:27:17
ebs95.neoplus.adsl.tpnet.pl	0.012	0.240	00:34:59
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.032	00:30:01
web.man.lodz.pl	2.632	25.392	00:00:21
vegas.cm.p.lodz.pl	1.512	14.528	00:00:11
f-oper-v100.man.lodz.pl	1.400	19.868	00:00:06
david.man.lodz.pl	0.468	4.820	00:00:02
kujawiak.man.lodz.pl	0.028	0.356	00:00:01
TOTAL: 9	13.068	88.264	63:32:58

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
212.191.78.190	286
david.man.lodz.pl	96
82.207.15.62.static.jazztel.es	14
ip-176.105.135.22.tvsat364.lodz.pl	1
TOTAL: 5	685

NNRP client timeouts [Top 20]:

System	Conn	Peer
ebs95.neoplus.adsl.tpnet.pl	1	0
82.207.15.62.static.jazztel.es	1	1
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 3	3	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	364	74.3%
2	pl	126	25.7%
TOTAL: 2		490	100.0%

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

Newsgroup		Count
1	lodman.info	364
2	pl.comp.pecet	116
3	pl.comp.os.ms-windows.winnt	10
TOTAL: 3		490

Daily Usenet report

Jun 1 04:15:03 -- Jun 2 04:15:03