Daily Usenet report: May 22 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 22 13:56:46 newsfeed nnrpd[14141]: SERVER perl filter not defined
May 22 14:57:37 newsfeed nnrpd[16163]: SERVER perl filter not defined
May 22 14:57:54 newsfeed nnrpd[16164]: SERVER perl filter not defined
May 22 15:01:54 newsfeed nnrpd[16414]: SERVER perl filter not defined
May 22 19:06:18 newsfeed nnrpd[24509]: SERVER perl filter not defined
May 23 03:34:42 newsfeed nnrpd[9226]: cpc6-shef10-2-0-cust236.barn.cable.virginm.net noperm ihave without permission
May 23 03:34:50 newsfeed nnrpd[9229]: cpc6-shef10-2-0-cust236.barn.cable.virginm.net noperm ihave without permission
May 23 03:34:52 newsfeed nnrpd[9230]: cpc6-shef10-2-0-cust236.barn.cable.virginm.net noperm ihave without permission
May 23 04:15:01 newsfeed nnrpd[10716]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2287159	98.6%	276.8 MB	98.3%
innd	16791	0.7%	2.9 MB	1.0%
innfeed	7449	0.3%	1.1 MB	0.4%
nnrpd	7258	0.3%	772.4 KB	0.3%
TOTAL: 4	2318657	100.0%	281.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2280875	96.6%
Positive hits	40108	1.7%
Negative hits	39016	1.7%
Cache misses	211	0.0%
TOTAL: 4	2360210	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.268	0.0%	301	0.000	20.824	418.000
article cleanup	00:00:25.807	0.0%	2285454	0.000	0.011	0.025
article logging	00:01:02.264	0.1%	2285599	0.012	0.027	0.688
article parse	00:41:07.548	2.9%	258571489	0.005	0.010	0.030
article write	00:12:43.802	0.9%	2279667	0.208	0.335	1.474
artlog/artcncl	00:00:00.006	0.0%	297	0.000	0.020	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:02:09.516	0.1%	273158956	0.000	0.000	0.001
hisgrep/artcncl	00:00:01.086	0.0%	232	0.000	4.681	88.000
hishave/artcncl	00:00:00.244	0.0%	301	0.000	0.811	27.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:39:34.223	6.9%	2360673	1.033	2.531	8.860
history sync	00:00:00.010	0.0%	295	0.000	0.034	1.000
history write	03:27:45.817	14.4%	2281522	3.268	5.464	22.838
hiswrite/artcncl	00:00:00.830	0.0%	69	0.000	12.029	214.000
idle	13:12:38.651	55.1%	206454923	0.127	0.230	0.733
nntp read	00:45:36.883	3.2%	256913059	0.007	0.011	0.026
overview write	00:00:11.462	0.0%	2279667	0.000	0.005	0.014
perl filter	03:11:25.781	13.3%	2281519	3.260	5.034	8.214
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:15.207	0.3%	4596229	0.006	0.056	0.099
TOTAL: 23:59:26.305	23:19:05.405	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.114	0.0%	36909	0.000	0.003	0.125
article prepare	00:00:00.002	0.0%	29436	0.000	0.000	0.036
article read	00:00:00.476	0.0%	7198	0.000	0.066	1.125
backlog stats	00:00:00.188	0.0%	308181	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.548	0.0%	174446	0.000	0.009	0.067
data write	00:00:03.726	0.0%	149191	0.000	0.025	0.527
idle	23:59:22.726	100.0%	308181	0.000	280.234	11710.000
status file	00:00:00.207	0.0%	202	0.000	1.025	20.000
TOTAL: 23:59:34.456	23:59:28.987	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:45:25.179	99.0%	4352	0.000	626.190	444085.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.183	0.0%	10624	0.000	0.017	1.333
readart	00:00:00.520	0.0%	418	0.000	1.244	30.000
TOTAL: 00:45:51.559	00:45:25.882	99.1%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	365	2274807	2270356	754	3697	99%	217:39:39
2	news.task.gda.pl	12	28510	5438	21715	1357	19%	46:45:01
3	news.nask.pl	2	18073	4527	12873	673	25%	23:49:11
4	localhost	6	5	5	0	0	100%	00:00:00
5	news.icm.edu.pl	45	247	3	224	20	1%	17:46:17
TOTAL: 5		430	2321642	2280329	35566	5747	98%	306:00:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	542.1 GB	95.2 MB	111.2 MB	542.3 GB	99%	250.1 KB
2	news.nask.pl	17.9 MB	2.0 MB	2.1 MB	22.0 MB	81%	4.3 KB
3	news.task.gda.pl	12.0 MB	46.8 KB	4.5 MB	16.5 MB	72%	2.5 KB
4	news.icm.edu.pl	30.9 KB	0.0 KB	312.7 KB	343.7 KB	9%	14.9 KB
5	localhost	7.6 KB	0.0 KB	0.0 KB	7.6 KB	100%	1.5 KB
TOTAL: 5		542.1 GB	97.2 MB	118.1 MB	542.4 GB	99%	248.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 22 04:15:02 - 04:59:59	68531	3.0%	25.40	15.7 GB	2.9%	6087.02
May 22 05:00:00 - 05:59:59	87009	3.8%	24.17	19.1 GB	3.5%	5555.45
May 22 06:00:00 - 06:59:59	99900	4.4%	27.75	24.1 GB	4.4%	7019.16
May 22 07:00:00 - 07:59:59	88972	3.9%	24.71	20.8 GB	3.8%	6056.34
May 22 08:00:00 - 08:59:59	87085	3.8%	24.19	20.3 GB	3.7%	5922.39
May 22 09:00:00 - 09:59:59	98036	4.3%	27.23	22.8 GB	4.2%	6650.66
May 22 10:00:00 - 10:59:59	82905	3.6%	23.03	19.3 GB	3.5%	5624.36
May 22 11:00:00 - 11:59:59	85295	3.7%	23.69	19.8 GB	3.6%	5760.92
May 22 12:00:00 - 12:59:59	89316	3.9%	24.81	20.9 GB	3.8%	6083.12
May 22 13:00:00 - 13:59:59	92862	4.1%	25.80	21.9 GB	4.0%	6370.46
May 22 14:00:00 - 14:59:59	88789	3.9%	24.66	20.5 GB	3.8%	5966.00
May 22 15:00:00 - 15:59:59	81177	3.6%	22.55	16.7 GB	3.1%	4858.30
May 22 16:00:00 - 16:59:59	84323	3.7%	23.42	18.2 GB	3.3%	5304.17
May 22 17:00:00 - 17:59:59	96329	4.2%	26.76	23.8 GB	4.4%	6927.03
May 22 18:00:00 - 18:59:59	100714	4.4%	27.98	25.3 GB	4.6%	7361.55
May 22 19:00:00 - 19:59:59	89858	3.9%	24.96	21.9 GB	4.0%	6365.60
May 22 20:00:00 - 20:59:59	99379	4.4%	27.61	22.5 GB	4.1%	6539.68
May 22 21:00:00 - 21:59:59	95059	4.2%	26.41	21.0 GB	3.9%	6126.59
May 22 22:00:00 - 22:59:59	113538	5.0%	31.54	24.7 GB	4.5%	7187.95
May 22 23:00:00 - 23:59:59	68884	3.0%	19.13	19.9 GB	3.7%	5792.59
May 23 00:00:00 - 00:59:59	106753	4.7%	29.65	27.4 GB	5.0%	7989.31
May 23 01:00:00 - 01:59:59	104898	4.6%	29.14	27.9 GB	5.1%	8112.75
May 23 02:00:00 - 02:59:59	120094	5.3%	33.36	31.2 GB	5.7%	9076.34
May 23 03:00:00 - 03:59:59	121778	5.3%	33.83	31.2 GB	5.7%	9076.14
May 23 04:00:00 - 04:15:02	29442	1.3%	32.64	7.8 GB	1.4%	9077.13
TOTAL: 24:00:00	2280926	100.0%	26.40	544.4 GB	100.0%	6606.79

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3831	175	3254	0	402
2	news.task.gda.pl	1401	1233	24	0	144
3	news.nask.pl	684	3	656	4	21
4	news.icm.edu.pl	20	0	0	0	20
TOTAL: 4		5936	1411	3934	4	587

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	135
mailing.freebsd.svn-ports-head	134
mailing.gcc.gcc-cvs	107
mozilla.dev.tree-management	83
uk.railway	76
mailing.freebsd.ports-bugs	73
mailing.unix.samba	72
alt.windows7.general	49
mailing.freebsd.svn-src-all	49
fa.freebsd.cvs-all	49
mailing.freebsd.svn-src-head	37
it.tlc.cellulari.android	35
mailing.unix.samba-technical	34
mailing.unix.spamassassin-talk	34
mailing.openbsd.ports-changes	33
mozilla.dev.gaia	27
mailing.database.pgsql-general	27
mailing.openbsd.source-changes	26
gnu.hurd.bug	25
it.comp.os.win.windows8	21
TOTAL: 70	1411

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

Groups	Count
pl.sci.historia	4
TOTAL: 1	4

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	273
EMP (fsl)	127
EMP (md5)	27
HTML post	10
Scoring filter (9)	1
TOTAL: 5	438

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	61762	2531	32497	1657	7	4%	23:59:54
2	panorama	44802	2295	34118	447	0	5%	23:59:34
3	nask	23955	228	14225	2	1	0%	23:55:27
4	poznan	12005	80	9896	24	0	0%	24:04:07
5	task	2017	10	1069	6	0	0%	22:43:52
6	okapi	0	0	0	0	2691	0%	24:00:03
TOTAL: 6		144541	5144	91805	2136	2699	3%	142:42:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	322.7 MB	29.1 MB	351.8 MB	4.2 KB/s	86.0 KB	23:59:54
2	panorama	9.9 MB	1.4 MB	11.2 MB	0.1 KB/s	4.2 KB	23:59:34
3	nask	583.1 KB	2.9 KB	586.0 KB	0.0 KB/s	2.5 KB	23:55:27
4	poznan	407.7 KB	46.2 KB	453.9 KB	0.0 KB/s	4.4 KB	24:04:07
5	task	24.0 KB	12.7 KB	36.7 KB	0.0 KB/s	2.3 KB	22:43:52
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 6		333.5 MB	30.6 MB	364.1 MB	0.7 KB/s	51.2 KB	142:42:57

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	338	491.0 KB	288	0	0	00:00:10
2	web.man.lodz.pl	288	80	73.9 KB	36	0	0	00:00:24
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	0	00:00:02
4	cpc6-shef10-2-0-cust236.barn.cable.virginm.net	7	0	0.0 KB	0	0	3	00:00:01
TOTAL: 4		588	418	564.9 KB	324	5	3	00:45:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	338	491.0 KB	288	0	0	00:00:10
2	*.man.lodz.pl	677	80	73.9 KB	36	5	0	00:00:32
3	*.pa.nsw.optusnet.com.au	40	0	0.0 KB	0	0	0	00:01:06
4	unresolved	2	0	0.0 KB	0	0	0	00:00:46
5	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	0	00:00:10
6	*.barn.cable.virginm.net	7	0	0.0 KB	0	0	3	00:00:01
7	*.internetdsl.tpnet.pl	4	0	0.0 KB	0	0	0	00:42:59
8	*.toya.net.pl	51	0	0.0 KB	0	0	0	00:00:03
TOTAL: 8		1071	418	564.9 KB	324	5	3	00:45:51

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
acj62.internetdsl.tpnet.pl	0.036	0.048	00:42:59
pa49-181-246-101.pa.nsw.optusnet.com.au	0.052	0.440	00:01:06
212.51.203.199	0.000	0.016	00:00:46
web.man.lodz.pl	0.608	4.508	00:00:24
pc-skrzep2.cm.p.lodz.pl	0.404	3.444	00:00:10
242.191.14.62.static.jazztel.es	0.004	0.016	00:00:10
mon.man.lodz.pl	0.368	3.448	00:00:04
staticline41435.toya.net.pl	0.104	0.616	00:00:03
isvcs01.man.lodz.pl	0.024	0.092	00:00:02
cpc6-shef10-2-0-cust236.barn.cable.virginm.net	0.004	0.052	00:00:01
david.man.lodz.pl	0.132	1.132	00:00:01
TOTAL: 11	1.736	13.812	00:45:51

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	51
pa49-181-246-101.pa.nsw.optusnet.com.au	40
acj62.internetdsl.tpnet.pl	4
242.191.14.62.static.jazztel.es	2
212.51.203.199	2
TOTAL: 7	483

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	338	80.9%
2	pl	80	19.1%
TOTAL: 2		418	100.0%

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

Newsgroup		Count
1	lodman.info	338
2	pl.comp.os.ms-windows.winnt	56
3	pl.comp.pecet	24
TOTAL: 3		418

Daily Usenet report

May 22 04:15:02 -- May 23 04:15:02