Daily Usenet report: Sep 11 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Sep 11 13:12:44 newsfeed nnrpd[20015]: SERVER perl filter not defined
Sep 11 13:15:06 newsfeed nnrpd[20079]: SERVER perl filter not defined
Sep 11 13:23:21 newsfeed nnrpd[20340]: SERVER perl filter not defined
Sep 11 13:31:01 newsfeed nnrpd[20482]: SERVER perl filter not defined
Sep 11 22:00:42 newsfeed nnrpd[4407]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1970117	98.0%	222.4 MB	97.5%
innd	25505	1.3%	3.9 MB	1.7%
nnrpd	7427	0.4%	777.1 KB	0.3%
innfeed	7085	0.4%	1.0 MB	0.5%
TOTAL: 4	2010134	100.0%	228.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1962536	94.2%
Negative hits	77457	3.7%
Positive hits	43386	2.1%
Cache misses	255	0.0%
TOTAL: 4	2083634	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.383	0.0%	457	0.000	13.967	252.000
article cleanup	00:00:26.038	0.0%	1968550	0.003	0.013	0.027
article logging	00:00:59.420	0.1%	1968740	0.012	0.030	0.864
article parse	01:08:30.313	4.8%	93708625	0.017	0.044	0.088
article write	00:21:10.381	1.5%	1953462	0.311	0.650	3.193
artlog/artcncl	00:00:00.004	0.0%	438	0.000	0.009	0.333
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:10.245	0.3%	106259517	0.001	0.002	0.009
hisgrep/artcncl	00:00:01.314	0.0%	330	0.000	3.982	37.556
hishave/artcncl	00:00:01.542	0.0%	457	0.000	3.374	233.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:06:36.925	8.8%	2084701	0.253	3.644	18.251
history sync	00:00:00.008	0.0%	292	0.000	0.027	1.000
history write	03:17:50.839	13.7%	1963767	0.465	6.045	19.829
hiswrite/artcncl	00:00:00.313	0.0%	127	0.000	2.465	19.000
idle	10:20:45.061	43.1%	64874139	0.224	0.574	2.607
nntp read	01:00:24.190	4.2%	92176471	0.020	0.039	0.050
overview write	00:00:11.704	0.0%	1953462	0.000	0.006	0.016
perl filter	05:10:30.736	21.6%	1963767	5.835	9.487	12.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:15.715	0.2%	3950811	0.030	0.050	0.127
TOTAL: 23:59:31.550	23:35:01.131	98.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.153	0.0%	44177	0.000	0.003	0.118
article prepare	00:00:00.013	0.0%	43750	0.000	0.000	0.062
article read	00:00:01.350	0.0%	10438	0.000	0.129	3.038
backlog stats	00:00:00.213	0.0%	341651	0.000	0.001	0.013
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.071	0.0%	197825	0.000	0.010	0.077
data write	00:00:05.405	0.0%	169627	0.000	0.032	0.161
idle	23:59:49.228	100.0%	341651	0.000	252.858	13669.000
status file	00:00:01.842	0.0%	208	0.000	8.856	257.000
TOTAL: 24:00:06.912	24:00:00.275	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:12:16.059	99.8%	5718	0.000	2647.090	600160.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.260	0.0%	13505	0.000	0.019	1.000
readart	00:00:00.289	0.0%	494	0.000	0.585	22.000
TOTAL: 04:12:39.713	04:12:16.608	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	815	1957091	1943279	1374	12438	99%	311:43:47
2	news.task.gda.pl	11	31151	5792	24079	1280	18%	46:23:07
3	news.nask.pl	2	19881	5479	13020	1382	27%	24:26:03
4	localhost	6	5	5	0	0	100%	00:00:00
5	news.icm.edu.pl	38	293	3	274	16	1%	19:31:55
TOTAL: 5		872	2008421	1954558	38747	15116	97%	402:04:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	721.3 GB	108.6 MB	1.3 GB	722.7 GB	99%	387.5 KB
2	news.nask.pl	132.8 MB	5.4 MB	10.3 MB	148.5 MB	89%	22.2 KB
3	news.task.gda.pl	12.9 MB	50.4 KB	3.9 MB	16.9 MB	76%	2.4 KB
4	news.icm.edu.pl	51.4 KB	11.3 KB	322.8 KB	385.5 KB	13%	20.3 KB
5	localhost	6.5 KB	0.0 KB	0.0 KB	6.5 KB	100%	1.3 KB
TOTAL: 5		721.4 GB	114.1 MB	1.3 GB	722.9 GB	99%	384.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 11 04:15:02 - 04:59:59	55441	2.8%	20.55	20.6 GB	2.9%	8017.59
Sep 11 05:00:00 - 05:59:59	67730	3.5%	18.81	25.6 GB	3.6%	7461.81
Sep 11 06:00:00 - 06:59:59	80800	4.1%	22.44	32.9 GB	4.6%	9594.78
Sep 11 07:00:00 - 07:59:59	93446	4.8%	25.96	38.5 GB	5.3%	11209.98
Sep 11 08:00:00 - 08:59:59	80494	4.1%	22.36	32.8 GB	4.6%	9562.15
Sep 11 09:00:00 - 09:59:59	84484	4.3%	23.47	31.5 GB	4.4%	9170.50
Sep 11 10:00:00 - 10:59:59	78954	4.0%	21.93	30.3 GB	4.2%	8819.74
Sep 11 11:00:00 - 11:59:59	91635	4.7%	25.45	34.8 GB	4.8%	10148.35
Sep 11 12:00:00 - 12:59:59	82269	4.2%	22.85	31.0 GB	4.3%	9029.85
Sep 11 13:00:00 - 13:59:59	86260	4.4%	23.96	29.7 GB	4.1%	8656.29
Sep 11 14:00:00 - 14:59:59	102056	5.2%	28.35	33.5 GB	4.6%	9758.57
Sep 11 15:00:00 - 15:59:59	103483	5.3%	28.75	35.3 GB	4.9%	10278.46
Sep 11 16:00:00 - 16:59:59	99113	5.1%	27.53	35.2 GB	4.9%	10241.43
Sep 11 17:00:00 - 17:59:59	66014	3.4%	18.34	23.6 GB	3.3%	6861.45
Sep 11 18:00:00 - 18:59:59	67217	3.4%	18.67	23.4 GB	3.2%	6819.23
Sep 11 19:00:00 - 19:59:59	92736	4.7%	25.76	32.0 GB	4.4%	9329.46
Sep 11 20:00:00 - 20:59:59	55539	2.8%	15.43	21.5 GB	3.0%	6257.17
Sep 11 21:00:00 - 21:59:59	46487	2.4%	12.91	16.6 GB	2.3%	4833.06
Sep 11 22:00:00 - 22:59:59	50309	2.6%	13.97	19.8 GB	2.7%	5770.19
Sep 11 23:00:00 - 23:59:59	49108	2.5%	13.64	18.6 GB	2.6%	5430.03
Sep 12 00:00:00 - 00:59:59	84100	4.3%	23.36	30.8 GB	4.3%	8965.42
Sep 12 01:00:00 - 01:59:59	98106	5.0%	27.25	36.1 GB	5.0%	10514.66
Sep 12 02:00:00 - 02:59:59	107209	5.5%	29.78	39.0 GB	5.4%	11368.87
Sep 12 03:00:00 - 03:59:59	105157	5.4%	29.21	38.5 GB	5.3%	11218.18
Sep 12 04:00:00 - 04:15:04	26249	1.3%	29.04	9.6 GB	1.3%	11082.32
TOTAL: 24:00:02	1954396	100.0%	22.62	721.3 GB	100.0%	8753.60

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	12591	227	3409	1	8954
2	news.nask.pl	1394	13	1340	0	41
3	news.task.gda.pl	1282	1026	24	1	231
4	news.icm.edu.pl	16	0	1	0	15
TOTAL: 4		15283	1266	4774	2	9241

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	81
mailing.freebsd.svn-ports-head	81
it.tlc.cellulari.android	65
mailing.postfix.users	63
alt.windows7.general	61
uk.railway	51
mailing.freebsd.ports-bugs	48
fr.usenet.abus.nocem	43
fr.rec.loisirs-creatifs	42
mozilla.dev.tree-management	41
mailing.openbsd.misc	39
mailing.database.pgsql-general	38
fr.comp.os.android	36
mailing.freebsd.current	34
mailing.openbsd.source-changes	28
mailing.gcc.gcc-cvs	26
uk.rec.cycling.moderated	23
mailing.unix.samba-technical	23
mailing.www.horde-bugs	23
mailing.freebsd.svn-src-all	21
TOTAL: 82	1266

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

Groups	Count
fr.comp.securite	1
pl.sci.historia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	8800
EMP (fsl)	204
EMP (md5)	29
HTML post	11
Attached HTML file	5
Scoring filter (8)	2
Scoring filter (9)	1
TOTAL: 7	9052

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	47307	2883	40208	893	0	248	6%	24:02:50
2	icm	64849	2625	33565	4319	7	23	4%	23:54:48
3	poznan	12211	86	10163	47	0	0	0%	24:00:21
4	task	2365	50	1226	1	0	0	2%	22:31:07
5	nask	25683	21	16150	1	0	0	0%	24:00:59
6	okapi	0	0	0	0	0	2904	0%	24:00:01
TOTAL: 6		152415	5665	101312	5261	7	3175	3%	142:30:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	14.7 MB	1.1 GB	1.1 GB	13.2 KB/s	163.6 KB	23:54:48
2	panorama	7.9 MB	8.0 MB	15.9 MB	0.2 KB/s	4.3 KB	24:02:50
3	poznan	306.6 KB	80.9 KB	387.5 KB	0.0 KB/s	2.9 KB	24:00:21
4	nask	131.1 KB	249.1 KB	380.1 KB	0.0 KB/s	17.3 KB	24:00:59
5	task	118.1 KB	1.3 KB	119.4 KB	0.0 KB/s	2.3 KB	22:31:07
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 6		23.2 MB	1.1 GB	1.1 GB	2.2 KB/s	105.6 KB	142:30:06

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	338	472.0 KB	288	0	00:00:08
2	web.man.lodz.pl	288	98	91.1 KB	43	0	00:00:20
3	lze200.lze.lodz.pl	11	45	81.1 KB	14	0	02:35:51
4	ebs52.neoplus.adsl.tpnet.pl	3	11	17.1 KB	2	0	00:01:21
5	h-87-199-18-28.dolsat.pl	3	1	1.5 KB	1	0	00:04:07
6	ip-176.105.135.22.tvsat364.lodz.pl	2	1	1.6 KB	1	0	01:00:03
7	kujawiak.man.lodz.pl	5	0	0.0 KB	0	5	00:00:01
TOTAL: 7		600	494	664.4 KB	349	5	04:12:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	338	472.0 KB	288	0	00:00:08
2	*.man.lodz.pl	677	98	91.1 KB	43	5	00:00:27
3	*.lze.lodz.pl	11	45	81.1 KB	14	0	02:35:51
4	*.neoplus.adsl.tpnet.pl	3	11	17.1 KB	2	0	00:01:21
5	*.105.135.22.tvsat364.lodz.pl	2	1	1.6 KB	1	0	01:00:03
6	*.dolsat.pl	3	1	1.5 KB	1	0	00:04:07
7	*.cbmm.lodz.pl	1	0	0.0 KB	0	0	00:27:24
8	unresolved	2	0	0.0 KB	0	0	00:00:21
9	*.181.14.62.static.jazztel.es	6	0	0.0 KB	0	0	00:02:34
10	*.internetdsl.tpnet.pl	2	0	0.0 KB	0	0	00:00:00
11	*.toya.net.pl	83	0	0.0 KB	0	0	00:00:18
TOTAL: 11		1078	494	664.4 KB	349	5	04:12:39

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.028	0.128	02:35:51
ip-176.105.135.22.tvsat364.lodz.pl	0.000	0.024	01:00:03
router2.cbmm.lodz.pl	0.008	0.004	00:27:24
h-87-199-18-28.dolsat.pl	0.028	0.048	00:04:07
205.181.14.62.static.jazztel.es	0.048	0.184	00:02:34
ebs52.neoplus.adsl.tpnet.pl	0.012	0.028	00:01:21
212.51.203.199	0.004	0.020	00:00:21
web.man.lodz.pl	0.780	2.752	00:00:20
staticline41435.toya.net.pl	0.200	0.852	00:00:18
vegas.cm.p.lodz.pl	0.660	2.816	00:00:08
f-oper-v100.man.lodz.pl	0.684	2.388	00:00:03
david.man.lodz.pl	0.204	0.980	00:00:02
kujawiak.man.lodz.pl	0.024	0.056	00:00:01
gcq193.internetdsl.tpnet.pl	0.004	0.020	00:00:00
?	0.000	0.012	00:00:00
TOTAL: 15	2.684	10.312	04:12:39

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper-v100.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	83
205.181.14.62.static.jazztel.es	6
gcq193.internetdsl.tpnet.pl	2
212.51.203.199	2
router2.cbmm.lodz.pl	1
TOTAL: 7	478

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	6	1
ip-176.105.135.22.tvsat364.lodz.pl	2	0
TOTAL: 2	8	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	349	70.6%
2	pl	144	29.1%
3	lodz	1	0.2%
TOTAL: 3		494	100.0%

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

Newsgroup		Count
1	lodman.info	349
2	pl.comp.pecet	95
3	pl.comp.os.ms-windows.winnt	20
4	pl.comp.os.linux	20
5	pl.rec.kuchnia	6
6	pl.rec.dom	2
7	lodz.ogloszenia	1
8	pl.regionalne.lodz	1
TOTAL: 8		494

Daily Usenet report

Sep 11 04:15:02 -- Sep 12 04:15:04