Daily Usenet report: May 10 04:15:03

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 11 00:15:16 newsfeed nnrpd[26620]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1094292	97.6%	125.4 MB	97.0%
innd	12587	1.1%	2.1 MB	1.6%
nnrpd	7611	0.7%	814.5 KB	0.6%
innfeed	7054	0.6%	1.0 MB	0.8%
TOTAL: 4	1121544	100.0%	129.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1089264	92.5%
Negative hits	51494	4.4%
Positive hits	37033	3.1%
Cache misses	176	0.0%
TOTAL: 4	1177967	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.600	0.0%	366	0.000	23.497	705.000
article cleanup	00:00:18.357	0.0%	1093831	0.000	0.017	0.038
article logging	00:00:33.812	0.0%	1093939	0.010	0.031	0.399
article parse	00:49:24.756	3.4%	99746679	0.013	0.030	0.073
article write	00:11:52.005	0.8%	1087808	0.356	0.655	2.148
artlog/artcncl	00:00:00.012	0.0%	361	0.000	0.033	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:04:07.052	0.3%	106925542	0.001	0.002	0.593
hisgrep/artcncl	00:00:00.878	0.0%	244	0.000	3.598	23.000
hishave/artcncl	00:00:01.431	0.0%	366	0.000	3.910	25.400
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:02:49.282	8.5%	1178156	0.009	6.255	15.617
history sync	00:00:00.022	0.0%	293	0.000	0.075	1.000
history write	02:38:53.829	11.0%	1089567	1.014	8.750	32.224
hiswrite/artcncl	00:00:01.421	0.0%	122	0.000	11.648	249.000
idle	13:55:32.613	58.0%	74516426	0.335	0.673	5.981
nntp read	00:48:22.042	3.4%	98905779	0.014	0.029	0.233
overview write	00:00:07.374	0.0%	1087808	0.000	0.007	0.021
perl filter	02:51:49.008	11.9%	1089564	6.373	9.462	14.742
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:26.500	0.2%	2215133	0.043	0.066	0.289
TOTAL: 24:00:25.335	23:26:28.994	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.155	0.0%	39515	0.000	0.004	0.100
article prepare	00:00:00.009	0.0%	33032	0.000	0.000	0.250
article read	00:00:05.234	0.0%	7957	0.000	0.658	153.706
backlog stats	00:00:00.224	0.0%	295950	0.000	0.001	0.019
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:02.003	0.0%	171588	0.000	0.012	1.000
data write	00:00:07.349	0.0%	151051	0.000	0.049	24.000
idle	23:59:41.795	100.0%	295950	0.000	291.880	15599.000
status file	00:00:01.508	0.0%	214	0.000	7.047	220.500
TOTAL: 24:00:05.263	23:59:58.277	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	33:42:18.919	99.9%	678151	0.000	178.926	247046.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:21.143	0.0%	1358115	0.000	0.016	2.627
readart	00:00:00.389	0.0%	396	0.000	0.982	13.933
TOTAL: 33:43:40.863	33:42:40.451	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	765	1080835	1076028	1382	3425	99%	271:32:29
2	news.task.gda.pl	16	29699	7304	21171	1224	24%	47:04:56
3	news.nask.pl	3	16762	4508	10848	1406	26%	23:59:31
4	news.icm.edu.pl	46	689	16	658	15	2%	17:31:01
5	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 5		832	1127986	1087857	34059	6070	96%	360:07:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	394.9 GB	42.6 MB	112.3 MB	395.0 GB	99%	383.7 KB
2	news.nask.pl	23.5 MB	4.5 MB	4.6 MB	32.6 MB	72%	5.6 KB
3	news.task.gda.pl	13.8 MB	107.2 KB	3.2 MB	17.1 MB	80%	2.1 KB
4	news.icm.edu.pl	3.2 MB	0.0 KB	196.9 KB	3.4 MB	94%	110.8 KB
5	localhost	1.2 KB	0.0 KB	0.0 KB	1.2 KB	100%	1.2 KB
TOTAL: 5		394.9 GB	47.2 MB	120.3 MB	395.1 GB	99%	378.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 10 04:15:03 - 04:59:59	22354	2.1%	8.29	9.4 GB	2.4%	3672.62
May 10 05:00:00 - 05:59:59	24681	2.3%	6.86	12.0 GB	3.0%	3484.17
May 10 06:00:00 - 06:59:59	35476	3.3%	9.85	16.4 GB	4.2%	4785.77
May 10 07:00:00 - 07:59:59	35644	3.3%	9.90	15.4 GB	3.9%	4483.59
May 10 08:00:00 - 08:59:59	39930	3.7%	11.09	15.8 GB	4.0%	4616.62
May 10 09:00:00 - 09:59:59	41874	3.8%	11.63	15.8 GB	4.0%	4614.24
May 10 10:00:00 - 10:59:59	36913	3.4%	10.25	13.9 GB	3.5%	4061.75
May 10 11:00:00 - 11:59:59	35737	3.3%	9.93	12.6 GB	3.2%	3667.39
May 10 12:00:00 - 12:59:59	37817	3.5%	10.50	12.7 GB	3.2%	3697.57
May 10 13:00:00 - 13:59:59	39295	3.6%	10.92	14.7 GB	3.7%	4269.49
May 10 14:00:00 - 14:59:59	38683	3.6%	10.75	14.8 GB	3.7%	4297.04
May 10 15:00:00 - 15:59:59	39258	3.6%	10.90	14.9 GB	3.8%	4350.65
May 10 16:00:00 - 16:59:59	39112	3.6%	10.86	14.5 GB	3.7%	4214.44
May 10 17:00:00 - 17:59:59	40168	3.7%	11.16	14.5 GB	3.7%	4233.95
May 10 18:00:00 - 18:59:59	40578	3.7%	11.27	14.6 GB	3.7%	4261.64
May 10 19:00:00 - 19:59:59	42483	3.9%	11.80	15.1 GB	3.8%	4383.97
May 10 20:00:00 - 20:59:59	39622	3.6%	11.01	14.1 GB	3.6%	4092.63
May 10 21:00:00 - 21:59:59	35697	3.3%	9.92	12.7 GB	3.2%	3711.77
May 10 22:00:00 - 22:59:59	36984	3.4%	10.27	13.1 GB	3.3%	3805.05
May 10 23:00:00 - 23:59:59	41236	3.8%	11.45	15.1 GB	3.8%	4398.13
May 11 00:00:00 - 00:59:59	74414	6.8%	20.67	23.3 GB	5.9%	6779.14
May 11 01:00:00 - 01:59:59	73699	6.8%	20.47	24.0 GB	6.1%	7004.41
May 11 02:00:00 - 02:59:59	82022	7.5%	22.78	27.1 GB	6.9%	7891.13
May 11 03:00:00 - 03:59:59	92124	8.5%	25.59	30.6 GB	7.7%	8901.04
May 11 04:00:00 - 04:15:03	21997	2.0%	24.36	7.5 GB	1.9%	8720.33
TOTAL: 24:00:00	1087798	100.0%	12.59	394.6 GB	100.0%	4789.35

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3519	171	2819	2	527
2	news.nask.pl	1420	8	1385	1	26
3	news.task.gda.pl	1179	906	63	0	210
4	news.icm.edu.pl	15	0	0	0	15
TOTAL: 4		6133	1085	4267	3	778

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.ports-bugs	213
mailing.freebsd.svn-ports-all	84
mailing.freebsd.svn-ports-head	79
uk.railway	64
mailing.freebsd.svn-src-all	60
fa.freebsd.cvs-all	59
mailing.freebsd.svn-src-head	52
alt.windows7.general	42
mailing.openbsd.source-changes	37
mailing.freebsd.ports	33
it.tlc.cellulari.android	31
fr.usenet.abus.nocem	29
24hoursupport.helpdesk	27
alt.politics.scorched-earth	27
mailing.openbsd.ports-changes	24
mailing.unix.samba	21
de.rec.sf.startrek	16
mailing.openssl.dev	15
it.comp.os.win.windows8	14
nl.comp.os.linux.techniek	14
TOTAL: 58	1085

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

Groups	Count
pl.sci.matematyka	2
hr.alt.hackers	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	411
EMP (fsl)	176
EMP (md5)	38
Attached HTML file	35
EMP (phl)	7
TOTAL: 5	667

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	58080	3095	30702	3307	6	0	5%	24:06:01
2	panorama	40557	1490	37759	254	0	0	3%	23:59:35
3	task	1672	61	842	5	0	1	3%	21:43:02
4	poznan	13048	28	11397	16	0	0	0%	24:04:08
5	nask	21642	9	15144	10	0	0	0%	23:55:27
6	okapi	0	0	0	0	0	1908	0%	24:00:00
TOTAL: 6		134999	4683	95844	3592	6	1909	3%	141:48:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	359.0 MB	814.4 MB	1.1 GB	13.8 KB/s	187.7 KB	24:06:01
2	panorama	11.6 MB	4.3 MB	15.9 MB	0.2 KB/s	9.3 KB	23:59:35
3	task	166.3 KB	8.5 KB	174.7 KB	0.0 KB/s	2.6 KB	21:43:02
4	poznan	85.4 KB	31.5 KB	116.9 KB	0.0 KB/s	2.7 KB	24:04:08
5	nask	40.6 KB	3.5 MB	3.6 MB	0.0 KB/s	191.9 KB	23:55:27
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		370.9 MB	822.3 MB	1.2 GB	2.4 KB/s	147.7 KB	141:48:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	300	625.6 KB	288	0	00:00:12
2	web.man.lodz.pl	288	74	64.1 KB	37	0	00:00:21
3	ip-176.105.135.22.tvsat364.lodz.pl	2	15	26.2 KB	1	0	00:12:24
4	proxy.filmschool.lodz.pl	2	7	8.9 KB	1	0	00:06:51
5	isvcs01.man.lodz.pl	1	0	0.0 KB	0	1	00:00:00
TOTAL: 5		581	396	724.7 KB	327	1	33:43:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	300	625.6 KB	288	0	00:00:12
2	*.man.lodz.pl	673	74	64.1 KB	37	1	00:00:27
3	*.105.135.22.tvsat364.lodz.pl	2	15	26.2 KB	1	0	00:12:24
4	*.filmschool.lodz.pl	2	7	8.9 KB	1	0	00:06:51
5	*.pa.nsw.optusnet.com.au	148	0	0.0 KB	0	0	00:20:41
6	*.191.14.62.static.jazztel.es	9	0	0.0 KB	0	0	28:09:38
7	*.wroclaw.mm.pl	30	0	0.0 KB	0	0	04:53:22
TOTAL: 7		1152	396	724.7 KB	327	1	33:43:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
242.191.14.62.static.jazztel.es	33.228	47.184	28:09:38
host-89-231-50-15.wroclaw.mm.pl	0.056	0.304	04:53:22
pa49-181-247-9.pa.nsw.optusnet.com.au	0.292	1.524	00:20:41
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.032	00:12:24
proxy.filmschool.lodz.pl	0.012	0.020	00:06:51
web.man.lodz.pl	0.724	3.416	00:00:21
pc-skrzep2.cm.p.lodz.pl	0.636	3.088	00:00:12
mon.man.lodz.pl	0.632	2.892	00:00:04
david.man.lodz.pl	0.180	1.016	00:00:01
isvcs01.man.lodz.pl	0.000	0.012	00:00:00
TOTAL: 10	35.760	59.488	33:43:38

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
pa49-181-247-9.pa.nsw.optusnet.com.au	148
david.man.lodz.pl	96
host-89-231-50-15.wroclaw.mm.pl	30
242.191.14.62.static.jazztel.es	9
TOTAL: 5	571

NNRP client timeouts [Top 20]:

System	Conn	Peer
242.191.14.62.static.jazztel.es	2	0
pa49-181-247-9.pa.nsw.optusnet.com.au	2	0
TOTAL: 2	4	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	300	75.8%
2	pl	96	24.2%
TOTAL: 2		396	100.0%

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

Newsgroup		Count
1	lodman.info	300
2	pl.comp.pecet	54
3	pl.comp.os.ms-windows.winnt	18
4	pl.soc.prawo	15
5	pl.rec.foto.cyfrowa	7
6	pl.internet.polip	2
TOTAL: 6		396

Daily Usenet report

May 10 04:15:03 -- May 11 04:15:03