Daily Usenet report: Oct 20 04:15:04

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Oct 20 08:44:16 newsfeed overchan[2600]: input line too long, skipping
Oct 20 11:49:22 newsfeed nnrpd[28986]: SERVER perl filter not defined
Oct 20 13:11:43 newsfeed nnrpd[31674]: SERVER perl filter not defined
Oct 20 14:30:20 newsfeed nnrpd[1841]: SERVER perl filter not defined
Oct 20 14:30:43 newsfeed nnrpd[1842]: SERVER perl filter not defined
Oct 20 19:10:45 newsfeed overchan[2600]: input line too long, skipping
Oct 21 02:25:18 newsfeed overchan[2600]: input line too long, skipping
Oct 21 02:56:25 newsfeed overchan[2600]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2231478	98.3%	254.0 MB	97.8%
innd	22511	1.0%	3.6 MB	1.4%
nnrpd	10046	0.4%	1.0 MB	0.4%
innfeed	6802	0.3%	1.0 MB	0.4%
overchan	4	0.0%	0.7 KB	0.0%
TOTAL: 5	2270841	100.0%	259.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2224505	95.4%
Negative hits	70114	3.0%
Positive hits	37131	1.6%
Cache misses	323	0.0%
TOTAL: 4	2332073	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:14.025	0.0%	318	0.000	44.104	888.000
article cleanup	00:00:29.550	0.0%	2230531	0.005	0.013	0.026
article logging	00:01:07.356	0.1%	2230657	0.012	0.030	0.289
article parse	01:06:47.033	4.6%	70186290	0.020	0.057	0.096
article write	00:24:38.521	1.7%	2220837	0.325	0.666	2.397
artlog/artcncl	00:00:00.008	0.0%	314	0.000	0.025	1.000
artlog/artparse	00:00:00.000	0.0%	6	0.000	0.000	0.000
data move	00:05:14.763	0.4%	83306594	0.001	0.004	0.069
hisgrep/artcncl	00:00:01.561	0.0%	252	0.000	6.194	295.000
hishave/artcncl	00:00:01.749	0.0%	318	0.000	5.500	287.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	03:12:33.237	13.4%	2333710	0.009	4.951	26.424
history sync	00:00:00.014	0.0%	291	0.000	0.048	1.000
history write	05:26:26.780	22.7%	2225782	0.423	8.800	42.980
hiswrite/artcncl	00:00:00.540	0.0%	66	0.000	8.182	206.000
idle	07:11:24.861	30.0%	33913283	0.237	0.763	4.000
nntp read	00:34:28.602	2.4%	68341345	0.014	0.030	0.085
overview write	00:00:13.162	0.0%	2220837	0.001	0.006	0.014
perl filter	05:32:01.789	23.1%	2225776	5.412	8.950	12.143
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:18.937	0.3%	4505456	0.033	0.057	0.137
TOTAL: 23:59:47.757	23:40:02.488	98.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.250	0.0%	64220	0.000	0.004	1.000
article prepare	00:00:00.026	0.0%	120818	0.000	0.000	0.042
article read	00:00:37.553	0.0%	29740	0.000	1.263	102.000
backlog stats	00:00:00.390	0.0%	480019	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.353	0.0%	259807	0.000	0.013	0.086
data write	00:00:18.043	0.0%	264952	0.000	0.068	40.000
idle	23:58:07.898	99.9%	480019	0.000	179.759	26099.000
status file	00:00:02.579	0.0%	237	0.000	10.882	264.000
TOTAL: 23:59:20.655	23:59:10.092	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:27.005	0.0%	348524	0.042	0.077	2.702
idle	74:28:34.336	99.9%	621645	0.000	431.298	600058.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:22.233	0.0%	1245268	0.000	0.018	0.667
readart	00:01:21.900	0.0%	348720	0.000	0.235	269.000
TOTAL: 74:32:16.408	74:30:45.474	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	1072	2217787	2209137	962	7688	99%	446:02:01
2	news.task.gda.pl	15	28129	6857	20163	1109	24%	45:54:21
3	news.nask.pl	5	16697	5060	10775	862	30%	24:08:54
4	news.icm.edu.pl	38	281	11	253	17	3%	17:17:18
5	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 5		1135	2262898	2221069	32153	9676	98%	533:22:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	805.2 GB	141.2 MB	678.0 MB	806.0 GB	99%	381.3 KB
2	news.nask.pl	100.7 MB	3.0 MB	10.3 MB	114.0 MB	88%	19.7 KB
3	news.task.gda.pl	11.9 MB	143.5 KB	3.1 MB	15.1 MB	78%	1.9 KB
4	news.icm.edu.pl	614.5 KB	62.3 KB	333.2 KB	1009.9 KB	60%	36.1 KB
5	localhost	4.9 KB	0.0 KB	0.0 KB	4.9 KB	100%	1.2 KB
TOTAL: 5		805.3 GB	144.4 MB	691.7 MB	806.1 GB	99%	378.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 20 04:15:04 - 04:59:59	59389	2.7%	22.03	21.8 GB	2.7%	8460.01
Oct 20 05:00:00 - 05:59:59	90479	4.1%	25.13	36.0 GB	4.5%	10474.60
Oct 20 06:00:00 - 06:59:59	87564	3.9%	24.32	35.2 GB	4.4%	10240.62
Oct 20 07:00:00 - 07:59:59	89258	4.0%	24.79	35.7 GB	4.4%	10410.39
Oct 20 08:00:00 - 08:59:59	89620	4.0%	24.89	37.4 GB	4.6%	10882.59
Oct 20 09:00:00 - 09:59:59	77509	3.5%	21.53	29.8 GB	3.7%	8681.69
Oct 20 10:00:00 - 10:59:59	82146	3.7%	22.82	31.1 GB	3.9%	9069.95
Oct 20 11:00:00 - 11:59:59	85860	3.9%	23.85	32.4 GB	4.0%	9438.18
Oct 20 12:00:00 - 12:59:59	81511	3.7%	22.64	30.1 GB	3.7%	8771.92
Oct 20 13:00:00 - 13:59:59	87793	4.0%	24.39	31.6 GB	3.9%	9198.11
Oct 20 14:00:00 - 14:59:59	87695	3.9%	24.36	30.2 GB	3.8%	8803.94
Oct 20 15:00:00 - 15:59:59	97887	4.4%	27.19	36.4 GB	4.5%	10598.37
Oct 20 16:00:00 - 16:59:59	99357	4.5%	27.60	34.2 GB	4.2%	9960.58
Oct 20 17:00:00 - 17:59:59	102652	4.6%	28.51	35.0 GB	4.4%	10205.66
Oct 20 18:00:00 - 18:59:59	93388	4.2%	25.94	34.5 GB	4.3%	10037.57
Oct 20 19:00:00 - 19:59:59	101121	4.6%	28.09	34.6 GB	4.3%	10069.55
Oct 20 20:00:00 - 20:59:59	94951	4.3%	26.38	32.5 GB	4.0%	9473.64
Oct 20 21:00:00 - 21:59:59	102030	4.6%	28.34	34.0 GB	4.2%	9913.16
Oct 20 22:00:00 - 22:59:59	98741	4.4%	27.43	35.2 GB	4.4%	10260.67
Oct 20 23:00:00 - 23:59:59	101736	4.6%	28.26	36.6 GB	4.5%	10660.96
Oct 21 00:00:00 - 00:59:59	82847	3.7%	23.01	27.2 GB	3.4%	7924.09
Oct 21 01:00:00 - 01:59:59	99034	4.5%	27.51	33.2 GB	4.1%	9677.26
Oct 21 02:00:00 - 02:59:59	108101	4.9%	30.03	37.3 GB	4.6%	10862.95
Oct 21 03:00:00 - 03:59:59	96140	4.3%	26.71	33.6 GB	4.2%	9782.15
Oct 21 04:00:00 - 04:15:02	24527	1.1%	27.19	9.1 GB	1.1%	10537.14
TOTAL: 23:59:58	2221336	100.0%	25.71	804.7 GB	100.0%	9765.82

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	7796	196	3897	2	3701
2	news.task.gda.pl	1131	889	62	0	180
3	news.nask.pl	884	15	796	5	68
4	news.icm.edu.pl	17	0	2	0	15
TOTAL: 4		9828	1100	4757	7	3964

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	126
mailing.freebsd.svn-ports-head	126
uk.railway	81
mailing.openbsd.source-changes	61
mailing.freebsd.ports-bugs	52
it.comp.os.win.windows8	47
alt.windows7.general	43
alt.politics.scorched-earth	42
mozilla.dev.tree-management	26
fr.rec.loisirs-creatifs	26
mailing.postfix.users	25
mailing.freebsd.ports	25
mailing.freebsd.svn-src-all	25
fa.freebsd.cvs-all	25
mailing.openbsd.misc	25
mailing.freebsd.svn-src-head	21
mailing.unix.dovecot	20
rec.arts.tv.x-factor	18
comp.mobile.ipad	17
fr.usenet.abus.nocem	17
TOTAL: 73	1100

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

Groups	Count
pl.sci.matematyka	4
pl.sci.historia	3
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3616
EMP (fsl)	165
Attached HTML file	29
EMP (md5)	14
Scoring filter (8)	4
HTML post	2
Angle-bracket bot	1
Scoring filter (12)	1
TOTAL: 8	3832

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	65245	2504	60634	635	1	0	3%	23:54:46
2	icm	76152	971	32394	25649	0	448	1%	24:00:01
3	task	1736	121	840	20	0	3	6%	22:25:50
4	poznan	12643	37	10971	19	0	0	0%	24:02:50
5	nask	19359	28	13876	220	0	0	0%	23:57:12
6	okapi	0	0	0	0	0	1834	0%	24:00:00
TOTAL: 6		175135	3661	118715	26543	1	2285	2%	142:20:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	34.7 MB	7.2 GB	7.2 GB	87.2 KB/s	283.2 KB	24:00:01
2	panorama	8.7 MB	9.2 MB	17.9 MB	0.2 KB/s	5.8 KB	23:54:46
3	task	273.8 KB	73.1 KB	346.9 KB	0.0 KB/s	2.5 KB	22:25:50
4	nask	212.6 KB	137.5 MB	137.7 MB	1.6 KB/s	568.5 KB	23:57:12
5	poznan	102.0 KB	43.6 KB	145.6 KB	0.0 KB/s	2.6 KB	24:02:50
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		44.0 MB	7.3 GB	7.3 GB	15.0 KB/s	254.9 KB	142:20:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	326	479.6 KB	288	0	00:00:13
2	web.man.lodz.pl	288	66	69.6 KB	28	0	00:00:25
3	ip-176.105.135.22.tvsat364.lodz.pl	11	1	2.8 KB	1	0	04:02:05
4	kujawiak.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 4		591	393	552.1 KB	317	4	74:32:14

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	326	479.6 KB	288	0	00:00:13
2	*.man.lodz.pl	676	66	69.6 KB	28	4	00:00:36
3	*.105.135.22.tvsat364.lodz.pl	11	1	2.8 KB	1	0	04:02:05
4	*.191.14.62.static.jazztel.es	13	0	0.0 KB	0	0	22:30:34
5	unresolved	288	0	0.0 KB	0	0	47:58:37
6	*.toya.net.pl	59	0	0.0 KB	0	0	00:00:07
TOTAL: 6		1335	393	552.1 KB	317	4	74:32:14

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.676	3.736	47:58:37
16.191.14.62.static.jazztel.es	42.992	153.172	22:30:34
ip-176.105.135.22.tvsat364.lodz.pl	0.028	0.136	04:02:05
web.man.lodz.pl	0.688	3.600	00:00:25
vegas.cm.p.lodz.pl	0.556	3.792	00:00:13
f-oper-v100.man.lodz.pl	0.616	3.748	00:00:08
staticline41435.toya.net.pl	0.108	0.696	00:00:07
david.man.lodz.pl	0.184	1.072	00:00:01
kujawiak.man.lodz.pl	0.016	0.064	00:00:00
TOTAL: 9	45.864	170.016	74:32:14

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
212.191.78.190	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	59
16.191.14.62.static.jazztel.es	13
TOTAL: 5	744

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip-176.105.135.22.tvsat364.lodz.pl	6	0
TOTAL: 1	6	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	326	83.0%
2	pl	67	17.0%
TOTAL: 2		393	100.0%

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

Newsgroup		Count
1	lodman.info	326
2	pl.comp.os.ms-windows.winnt	42
3	pl.comp.pecet	24
4	pl.regionalne.lodz	1
TOTAL: 4		393

Daily Usenet report

Oct 20 04:15:04 -- Oct 21 04:15:02