Daily Usenet report: Sep 28 04:15:04

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 28 13:59:36 newsfeed nnrpd[4047]: SERVER perl filter not defined
Sep 28 14:01:00 newsfeed nnrpd[4126]: SERVER perl filter not defined
Sep 28 15:42:02 newsfeed nnrpd[7235]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1149157	97.3%	128.8 MB	96.6%
innd	17268	1.5%	2.7 MB	2.0%
innfeed	7995	0.7%	1.2 MB	0.9%
nnrpd	6848	0.6%	711.9 KB	0.5%
TOTAL: 4	1181268	100.0%	133.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1144336	91.8%
Negative hits	64860	5.2%
Positive hits	36424	2.9%
Cache misses	461	0.0%
TOTAL: 4	1246081	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.319	0.0%	387	0.000	24.080	363.500
article cleanup	00:00:15.176	0.0%	1148300	0.000	0.013	0.038
article logging	00:00:37.659	0.0%	1148405	0.010	0.033	0.480
article parse	00:32:16.528	2.2%	37431693	0.018	0.052	0.093
article write	00:12:04.637	0.8%	1138117	0.248	0.637	3.267
artlog/artcncl	00:00:00.008	0.0%	342	0.000	0.023	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:51.386	0.1%	44309532	0.000	0.001	0.004
hisgrep/artcncl	00:00:02.766	0.0%	316	0.000	8.753	180.000
hishave/artcncl	00:00:01.485	0.0%	387	0.000	3.837	258.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:44:49.550	11.4%	1246561	1.613	7.933	36.670
history sync	00:00:00.018	0.0%	301	0.000	0.060	1.000
history write	03:20:43.168	13.9%	1144984	6.021	10.518	40.091
hiswrite/artcncl	00:00:01.159	0.0%	71	0.000	16.324	145.000
idle	13:37:26.290	56.8%	24554913	0.161	1.997	12.077
nntp read	00:19:47.036	1.4%	36635589	0.017	0.032	0.049
overview write	00:00:07.616	0.0%	1138117	0.000	0.007	0.020
perl filter	02:53:22.388	12.0%	1144983	4.699	9.085	12.949
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:26.221	0.2%	2311610	0.018	0.063	0.107
TOTAL: 23:59:42.115	23:45:02.410	99.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.249	0.0%	85328	0.000	0.003	0.100
article prepare	00:00:00.016	0.0%	53540	0.000	0.000	0.062
article read	00:01:51.589	0.1%	13119	0.000	8.506	247.000
backlog stats	00:00:00.238	0.0%	378706	0.000	0.001	0.013
callbacks	00:00:00.000	0.0%	78	0.000	0.000	0.000
data read	00:00:02.448	0.0%	206109	0.000	0.012	1.000
data write	00:00:08.741	0.0%	195957	0.000	0.045	1.000
idle	23:57:58.119	99.8%	378706	0.000	227.823	14131.000
status file	00:00:00.782	0.0%	238	0.000	3.286	157.500
TOTAL: 24:00:11.356	24:00:02.182	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:13:22.961	99.8%	110787	0.000	137.227	11782.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.297	0.0%	223201	0.000	0.015	0.500
readart	00:00:00.864	0.0%	388	0.000	2.227	282.000
TOTAL: 04:13:50.644	04:13:27.122	99.8%	-	-	-	-

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	527	1138393	1129279	724	8390	99%	245:20:17
2	news.task.gda.pl	18	26411	5635	19893	883	21%	43:37:26
3	news.nask.pl	2	16181	3442	11900	839	21%	23:34:25
4	news.icm.edu.pl	42	306	21	251	34	6%	18:19:47
5	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 5		593	1181294	1138380	32768	10146	96%	330:51:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	388.2 GB	51.3 MB	794.6 MB	389.0 GB	99%	358.5 KB
2	news.nask.pl	70.9 MB	2.2 MB	2.3 MB	75.4 MB	94%	18.0 KB
3	news.task.gda.pl	10.7 MB	147.5 KB	2.1 MB	12.9 MB	82%	2.0 KB
4	news.icm.edu.pl	223.7 KB	13.1 KB	490.8 KB	727.6 KB	30%	13.2 KB
5	localhost	4.1 KB	0.0 KB	0.0 KB	4.1 KB	100%	1.4 KB
TOTAL: 5		388.2 GB	53.6 MB	799.5 MB	389.1 GB	99%	355.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 28 04:15:04 - 04:59:59	61871	5.4%	22.95	22.6 GB	5.8%	8805.17
Sep 28 05:00:00 - 05:59:59	90782	8.0%	25.22	34.7 GB	8.9%	10104.79
Sep 28 06:00:00 - 06:59:59	81126	7.1%	22.54	31.2 GB	8.0%	9087.08
Sep 28 07:00:00 - 07:59:59	41723	3.7%	11.59	15.2 GB	3.9%	4428.29
Sep 28 08:00:00 - 08:59:59	44311	3.9%	12.31	16.2 GB	4.2%	4715.62
Sep 28 09:00:00 - 09:59:59	42834	3.8%	11.90	14.8 GB	3.8%	4308.20
Sep 28 10:00:00 - 10:59:59	36316	3.2%	10.09	12.2 GB	3.1%	3555.08
Sep 28 11:00:00 - 11:59:59	45013	4.0%	12.50	15.3 GB	4.0%	4467.02
Sep 28 12:00:00 - 12:59:59	43030	3.8%	11.95	15.4 GB	4.0%	4473.37
Sep 28 13:00:00 - 13:59:59	28653	2.5%	7.96	9.4 GB	2.4%	2730.82
Sep 28 14:00:00 - 14:59:59	23296	2.0%	6.47	6.3 GB	1.6%	1849.45
Sep 28 15:00:00 - 15:59:59	23477	2.1%	6.52	6.6 GB	1.7%	1925.46
Sep 28 16:00:00 - 16:59:59	22996	2.0%	6.39	6.1 GB	1.6%	1762.29
Sep 28 17:00:00 - 17:59:59	21123	1.9%	5.87	5.2 GB	1.3%	1510.98
Sep 28 18:00:00 - 18:59:59	19049	1.7%	5.29	4.9 GB	1.3%	1421.34
Sep 28 19:00:00 - 19:59:59	20174	1.8%	5.60	5.2 GB	1.3%	1514.15
Sep 28 20:00:00 - 20:59:59	20844	1.8%	5.79	5.4 GB	1.4%	1572.07
Sep 28 21:00:00 - 21:59:59	28664	2.5%	7.96	8.9 GB	2.3%	2602.99
Sep 28 22:00:00 - 22:59:59	29236	2.6%	8.12	8.5 GB	2.2%	2473.64
Sep 28 23:00:00 - 23:59:59	40760	3.6%	11.32	14.1 GB	3.6%	4118.47
Sep 29 00:00:00 - 00:59:59	84472	7.4%	23.46	29.6 GB	7.6%	8630.96
Sep 29 01:00:00 - 01:59:59	79472	7.0%	22.08	27.3 GB	7.0%	7952.46
Sep 29 02:00:00 - 02:59:59	87264	7.7%	24.24	30.3 GB	7.8%	8829.41
Sep 29 03:00:00 - 03:59:59	99607	8.7%	27.67	34.8 GB	9.0%	10124.11
Sep 29 04:00:00 - 04:15:03	22435	2.0%	24.84	7.9 GB	2.0%	9185.53
TOTAL: 23:59:59	1138528	100.0%	13.18	388.1 GB	100.0%	4710.73

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	8489	187	2436	4	5862
2	news.task.gda.pl	905	635	61	0	209
3	news.nask.pl	859	9	818	2	30
4	news.icm.edu.pl	34	0	1	0	33
TOTAL: 4		10287	831	3316	6	6134

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	100
mailing.freebsd.svn-ports-head	100
uk.railway	52
alt.windows7.general	48
mailing.freebsd.ports-bugs	47
comp.mobile.ipad	39
uk.rec.cycling.moderated	37
alt.politics.scorched-earth	36
24hoursupport.helpdesk	33
mailing.openbsd.source-changes	32
fr.usenet.abus.nocem	24
mailing.openbsd.ports-changes	19
mailing.database.pgsql-general	19
it.tlc.cellulari.android	18
mailing.freebsd.current	16
mailing.unix.samba	14
mailing.freebsd.ports	12
mailing.freebsd.svn-src-all	12
fa.freebsd.cvs-all	12
mailing.openbsd.misc	10
TOTAL: 64	831

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

Groups	Count
pl.sci.historia	5
bit.listserv.catholic	1
TOTAL: 2	6

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	5719
EMP (fsl)	174
EMP (md5)	71
Attached HTML file	49
EMP (phl)	8
HTML post	4
Scoring filter (8)	3
TOTAL: 7	6028

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	103318	4150	73172	6780	104	13	4%	23:53:33
2	panorama	36518	1664	33183	518	0	0	4%	23:58:17
3	task	1780	47	890	37	0	0	2%	20:55:00
4	poznan	9882	30	8664	26	0	0	0%	24:00:22
5	nask	20752	16	13902	38	0	0	0%	23:59:59
6	okapi	0	0	0	0	0	1857	0%	24:00:00
TOTAL: 6		172250	5907	129811	7399	104	1870	3%	140:47:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	295.2 MB	2.0 GB	2.3 GB	27.7 KB/s	217.8 KB	23:53:33
2	panorama	4.6 MB	2.3 MB	6.9 MB	0.1 KB/s	3.2 KB	23:58:17
3	nask	293.6 KB	23.7 MB	24.0 MB	0.3 KB/s	454.5 KB	23:59:59
4	poznan	258.2 KB	46.1 KB	304.3 KB	0.0 KB/s	5.4 KB	24:00:22
5	task	111.6 KB	85.3 KB	196.8 KB	0.0 KB/s	2.3 KB	20:55:00
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		300.4 MB	2.0 GB	2.3 GB	4.8 KB/s	181.4 KB	140:47:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	vegas.cm.p.lodz.pl	288	314	450.7 KB	288	0	00:00:08
2	web.man.lodz.pl	288	74	67.0 KB	33	0	00:00:20
3	kujawiak.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		579	388	517.7 KB	321	3	04:13:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	314	450.7 KB	288	0	00:00:08
2	*.man.lodz.pl	675	74	67.0 KB	33	3	00:00:27
3	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:35
4	*.181.14.62.static.jazztel.es	8	0	0.0 KB	0	0	04:12:38
TOTAL: 4		972	388	517.7 KB	321	3	04:13:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
87.181.14.62.static.jazztel.es	6.116	7.252	04:12:38
ecg49.neoplus.adsl.tpnet.pl	0.000	0.020	00:00:35
web.man.lodz.pl	0.708	5.220	00:00:20
vegas.cm.p.lodz.pl	0.660	4.940	00:00:08
f-oper-v100.man.lodz.pl	0.720	4.840	00:00:05
david.man.lodz.pl	0.196	1.520	00:00:01
kujawiak.man.lodz.pl	0.020	0.056	00:00:00
TOTAL: 7	8.420	23.848	04:13:50

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
87.181.14.62.static.jazztel.es	8
ecg49.neoplus.adsl.tpnet.pl	1
TOTAL: 4	393

NNRP client timeouts [Top 20]:

System	Conn	Peer
87.181.14.62.static.jazztel.es	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	314	80.9%
2	pl	74	19.1%
TOTAL: 2		388	100.0%

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

Newsgroup		Count
1	lodman.info	314
2	pl.comp.pecet	38
3	pl.comp.os.ms-windows.winnt	36
TOTAL: 3		388

Daily Usenet report

Sep 28 04:15:04 -- Sep 29 04:15:03