Daily Usenet report for csiph.com: Sep 26 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43825	45.5%	6.3 MB	56.9%
nnrpd	31974	33.2%	2.7 MB	23.9%
innd	18645	19.4%	1.8 MB	16.6%
innfeed	1882	2.0%	297.9 KB	2.6%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 5	96327	100.0%	11.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	229821	47.2%
Negative hits	208330	42.8%
Do not exist	42816	8.8%
Cache misses	6048	1.2%
TOTAL: 4	487015	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.334	0.0%	302	0.000	1.106	44.000
article cleanup	00:00:00.251	0.0%	42172	0.000	0.006	0.026
article logging	00:00:00.522	0.0%	42725	0.000	0.012	0.070
article parse	00:00:02.009	0.0%	226200	0.003	0.009	0.016
article write	00:00:05.555	0.0%	23210	0.149	0.239	0.403
artlog/artcncl	00:00:00.000	0.0%	192	0.000	0.000	0.000
artlog/artparse	00:00:00.005	0.0%	723	0.000	0.007	0.250
data move	00:00:00.268	0.0%	637615	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.004	0.0%	301	0.000	0.013	1.000
hishave/artcncl	00:00:00.001	0.0%	302	0.000	0.003	0.143
hishave/artparse	00:00:00.001	0.0%	723	0.000	0.001	0.083
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.283	0.0%	486999	0.000	0.003	0.010
history sync	00:00:00.592	0.0%	365	0.000	1.622	3.333
history write	00:00:11.314	0.0%	42172	0.069	0.268	0.371
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.224	0.0%	715	0.000	0.313	1.667
idle	23:48:37.764	99.8%	625213	33.521	137.102	412.147
nntp read	00:00:02.000	0.0%	623631	0.002	0.003	0.005
overview write	00:00:04.130	0.0%	23210	0.111	0.178	0.522
perl filter	00:01:21.661	0.1%	42149	1.090	1.937	3.965
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.443	0.0%	46420	0.000	0.010	0.029
TOTAL: 23:50:57.937	23:50:28.361	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.069	0.0%	27055	0.000	0.003	0.015
article prepare	00:00:00.001	0.0%	8360	0.000	0.000	0.019
article read	00:00:00.241	0.0%	1615	0.000	0.149	11.500
backlog stats	00:00:00.083	0.0%	268796	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.063	0.0%	201591	0.000	0.005	0.017
data write	00:00:01.562	0.0%	179677	0.002	0.009	0.018
idle	23:54:52.871	100.0%	268796	121.130	320.291	827.732
status file	00:00:00.076	0.0%	194	0.000	0.392	1.000
TOTAL: 23:55:00.492	23:54:55.966	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.334	0.0%	11510	0.000	0.029	0.333
idle	38:50:21.341	99.9%	42917	1.000	3257.948	600235.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.745	0.0%	86697	0.000	0.009	2.291
readart	00:00:00.911	0.0%	2733	0.000	0.333	70.000
TOTAL: 38:51:40.150	38:50:23.331	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	146
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	165

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	au2pb.net	1	25575	6660	18124	791	26%	24:25:29
2	feeder.eternal-september.org	4	38699	5767	30170	2762	14%	24:26:03
3	news-out.mixmin.net	4	29109	2736	23737	2636	9%	24:09:22
4	xanadu-bbs.net	3	43066	2694	32402	7970	6%	22:24:53
5	newsfeed.neva.ru	141	53352	2087	46019	5246	3%	72:21:53
6	newsfeeds.xmission.com	1	22900	1349	21425	126	5%	22:56:56
7	newsfeed.fu-berlin.de	45	25091	1097	23770	224	4%	29:07:40
8	nntp.pasdenom.info	4	27043	571	26344	128	2%	29:17:22
9	news.swapon.de	7	2291	222	2063	6	9%	25:03:54
10	localhost	50	13032	0	13032	0	0%	00:01:03
TOTAL: 10		260	280158	23183	237086	19889	8%	274:14:35

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	au2pb.net	17.8 MB	4.1 MB	21.9 MB	81%	3.0 KB
2	feeder.eternal-september.org	15.7 MB	15.3 MB	30.9 MB	50%	3.7 KB
3	news-out.mixmin.net	9.5 MB	7.3 MB	16.8 MB	56%	3.2 KB
4	xanadu-bbs.net	7.4 MB	130.5 MB	137.9 MB	5%	13.2 KB
5	newsfeed.neva.ru	7.2 MB	35.8 MB	43.1 MB	16%	6.0 KB
6	newsfeeds.xmission.com	3.8 MB	1.0 MB	4.8 MB	78%	3.3 KB
7	newsfeed.fu-berlin.de	2.5 MB	1.1 MB	3.7 MB	69%	2.8 KB
8	nntp.pasdenom.info	1.2 MB	3.2 MB	4.5 MB	27%	6.5 KB
9	news.swapon.de	559.2 KB	18.1 KB	577.3 KB	96%	2.5 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		65.7 MB	198.3 MB	264.1 MB	24%	6.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 26 04:15:00 - 04:59:59	323	1.4%	0.12	1.0 MB	1.5%	0.38
Sep 26 05:00:00 - 05:59:59	452	1.9%	0.13	1.4 MB	2.1%	0.39
Sep 26 06:00:00 - 06:59:59	720	3.1%	0.20	2.0 MB	3.1%	0.58
Sep 26 07:00:00 - 07:59:59	921	4.0%	0.26	2.4 MB	3.6%	0.67
Sep 26 08:00:00 - 08:59:59	1107	4.8%	0.31	3.1 MB	4.8%	0.89
Sep 26 09:00:00 - 09:59:59	1164	5.0%	0.32	3.5 MB	5.2%	0.98
Sep 26 10:00:00 - 10:59:59	1087	4.7%	0.30	2.8 MB	4.3%	0.81
Sep 26 11:00:00 - 11:59:59	1103	4.7%	0.31	3.2 MB	4.8%	0.91
Sep 26 12:00:00 - 12:59:59	1200	5.2%	0.33	3.2 MB	4.9%	0.91
Sep 26 13:00:00 - 13:59:59	1152	4.9%	0.32	3.0 MB	4.5%	0.84
Sep 26 14:00:00 - 14:59:59	1246	5.4%	0.35	3.2 MB	4.9%	0.91
Sep 26 15:00:00 - 15:59:59	1284	5.5%	0.36	3.5 MB	5.3%	0.99
Sep 26 16:00:00 - 16:59:59	1189	5.1%	0.33	3.0 MB	4.5%	0.85
Sep 26 17:00:00 - 17:59:59	1336	5.7%	0.37	3.5 MB	5.3%	1.00
Sep 26 18:00:00 - 18:59:59	1203	5.2%	0.33	3.3 MB	5.0%	0.94
Sep 26 19:00:00 - 19:59:59	1189	5.1%	0.33	3.2 MB	4.8%	0.90
Sep 26 20:00:00 - 20:59:59	1158	5.0%	0.32	3.4 MB	5.2%	0.97
Sep 26 21:00:00 - 21:59:59	1357	5.8%	0.38	4.0 MB	6.1%	1.14
Sep 26 22:00:00 - 22:59:59	969	4.2%	0.27	3.0 MB	4.5%	0.85
Sep 26 23:00:00 - 23:59:59	770	3.3%	0.21	2.2 MB	3.3%	0.62
Sep 27 00:00:00 - 00:59:59	727	3.1%	0.20	2.1 MB	3.2%	0.59
Sep 27 01:00:00 - 01:59:59	486	2.1%	0.14	1.5 MB	2.2%	0.41
Sep 27 02:00:00 - 02:59:59	438	1.9%	0.12	1.6 MB	2.5%	0.46
Sep 27 03:00:00 - 03:59:59	545	2.3%	0.15	2.4 MB	3.7%	0.70
Sep 27 04:00:00 - 04:15:00	159	0.7%	0.18	607.5 KB	0.9%	0.67
TOTAL: 24:00:00	23285	100.0%	0.27	66.0 MB	100.0%	0.78

Sites sending bad articles:

Server		Total	Group	TooOld	Other
1	xanadu-bbs.net	7932	518	0	7414
2	newsfeed.neva.ru	5206	1101	0	4105
3	feeder.eternal-september.org	2803	244	0	2559
4	news-out.mixmin.net	2651	3	0	2648
5	au2pb.net	805	717	23	65
6	newsfeed.fu-berlin.de	646	14	0	632
7	newsfeeds.xmission.com	151	131	0	20
8	nntp.pasdenom.info	112	59	0	53
9	news.swapon.de	33	0	0	33
TOTAL: 9		20339	2787	23	17529

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	465
it-alt.sport.calcio.juventus	219
ut.test	151
alt.politics	134
mailing.freebsd.pkg-fallout	124
mailing.freebsd.svn-ports-head	99
mailing.freebsd.svn-ports-all	99
lada.talk	77
lada.auto	67
mailing.freebsd.ports-bugs	61
mozilla.dev.tree-alerts	60
fa.freebsd.cvs-all	56
mailing.freebsd.svn-src-all	55
mailing.freebsd.svn-src-head	47
alt.job	47
pld.cvs.commit	46
fa.openbsd.source-changes	37
mailing.openbsd.source-changes	37
mozilla.support.firefox	30
alt.windows7.general	29
TOTAL: 205	2787

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12291
EMP (phn path)	2401
EMP (phl)	981
EMP (phn nph)	224
PFM bot misc	113
Too many newsgroups (low_xpost)	74
Too many newsgroups	66
HTML Multipart	25
HTML post	25
Binary Image: misplaced jpg	21
HTML Image Tags	14
User-issued spam cancel	5
Binary Payload	2
Binary: misplaced nws	1
TOTAL: 14	16243

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	572
	feeder.eternal-september.org	51
	news-out.mixmin.net	42
	nntp.pasdenom.info	32
	au2pb.net	18
	newsfeeds.xmission.com	16
	newsfeed.fu-berlin.de	1
	TOTAL: 7	732
Including strange strings
	newsfeed.fu-berlin.de	420
	feeder.eternal-september.org	43
	au2pb.net	36
	news.swapon.de	27
	news-out.mixmin.net	18
	newsfeed.neva.ru	5
	newsfeeds.xmission.com	3
	xanadu-bbs.net	3
	TOTAL: 8	555
TOTAL: 2		1287

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	28805	360	21634	729	0	1%	23:59:57
2	au2pb	17225	257	16003	402	0	1%	23:59:50
3	xanadu-bbs	21665	76	20457	67	3798	0%	23:59:57
4	fu-berlin.de	21795	48	21709	38	0	0%	23:59:56
5	xmission	27478	11	21676	1	0	0%	23:59:54
6	news.mixmin.net	21040	11	20404	16	0	0%	23:59:57
7	neva.ru	22694	9	21053	57	0	0%	23:59:56
8	eternal-september	18223	8	17206	1	0	0%	23:59:55
9	news.swapon.de	2143	0	2121	6	0	0%	23:28:05
TOTAL: 9		181068	780	162263	1317	3798	0%	215:27:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.1 MB	964.4 KB	2.1 MB	3.2 KB	23:59:50
2	usenet.pasdenom.info	1.1 MB	1.6 MB	2.7 MB	2.5 KB	23:59:57
3	xanadu-bbs	453.8 KB	141.2 KB	595.0 KB	4.2 KB	23:59:57
4	fu-berlin.de	279.1 KB	51.6 KB	330.7 KB	3.8 KB	23:59:56
5	eternal-september	46.7 KB	1.4 KB	48.1 KB	5.3 KB	23:59:55
6	news.mixmin.net	45.4 KB	29.2 KB	74.6 KB	2.8 KB	23:59:57
7	neva.ru	41.1 KB	210.5 KB	251.6 KB	3.8 KB	23:59:56
8	xmission	28.8 KB	5.1 KB	33.9 KB	2.8 KB	23:59:54
9	news.swapon.de	0.0 KB	12.7 KB	12.7 KB	2.1 KB	23:28:05
TOTAL: 9		3.1 MB	3.0 MB	6.1 MB	3.0 KB	215:27:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	24	58.6 KB	24	04:44:00
2	challenger.nielsenmedia.com	26	18	41.6 KB	9	01:35:26
3	pool-71-98-160-84.tampfl.dsl-w.verizon.net	15	17	35.0 KB	5	03:04:07
4	92-108-162-83.dynamic.upc.nl	2	2	3.4 KB	1	00:01:16
TOTAL: 4		331	61	138.6 KB	39	38:51:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	318	24	58.6 KB	24	29:00:21
2	*.nielsenmedia.com	26	18	41.6 KB	9	01:35:26
3	*.tampfl.dsl-w.verizon.net	15	17	35.0 KB	5	03:04:07
4	*.dynamic.upc.nl	2	2	3.4 KB	1	00:01:16
5	*.chello.nl	4	0	0.0 KB	0	01:31:15
6	*.tu.ok.cox.net	2	0	0.0 KB	0	01:49:12
7	*.ip-188-165-239.eu	1	0	0.0 KB	0	00:00:08
8	*.hsd1.ca.comcast.net	2	0	0.0 KB	0	00:00:05
9	*.bbox.io	10	0	0.0 KB	0	01:49:41
TOTAL: 9		380	61	138.6 KB	39	38:51:36

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.198	0.437	0.239	0.000	12:47:30
71.94.21.184	0.711	0.410	0.000	11:28:47
202.89.176.220	25.060	6.113	0.708	04:44:00
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.511	0.273	0.000	03:04:07
tempe0.bbox.io	0.793	0.161	0.000	01:49:41
ip72-222-107-224.tu.ok.cox.net	0.186	0.063	0.000	01:49:12
challenger.nielsenmedia.com	2.051	0.330	0.000	01:35:26
dhcp-089-099-203-254.chello.nl	0.259	0.078	0.000	01:31:15
92-108-162-83.dynamic.upc.nl	0.222	0.046	0.000	00:01:16
ns342002.ip-188-165-239.eu	0.056	0.016	0.000	00:00:08
c-67-181-92-188.hsd1.ca.comcast.net	0.256	0.130	0.000	00:00:05
69.160.37.54	1.945	0.383	0.000	00:00:02
TOTAL: 12	33.487	8.242	0.708	38:51:36

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
tempe0.bbox.io	10
71.94.21.184	5
dhcp-089-099-203-254.chello.nl	4
ip72-222-107-224.tu.ok.cox.net	2
c-67-181-92-188.hsd1.ca.comcast.net	2
ns342002.ip-188-165-239.eu	1
TOTAL: 7	49

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	5
TOTAL: 1	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
pool-71-98-160-84.tampfl.dsl-w.verizon.net	5	0
challenger.nielsenmedia.com	3	1
dhcp-089-099-203-254.chello.nl	3	0
tempe0.bbox.io	3	0
ip72-222-107-224.tu.ok.cox.net	2	0
TOTAL: 5	16	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	61	100.0%
TOTAL: 1		61	100.0%

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

Newsgroup		Count
1	comp.lang.awk	35
2	comp.arch	8
3	comp.arch.fpga	5
4	comp.sys.ibm.pc.hardware.storage	5
5	comp.os.vms	3
6	comp.sys.ibm.ps2.hardware	2
7	comp.sys.ibm.as400.misc	1
8	comp.arch.embedded	1
9	comp.sys.ibm.pc.games.war-historical	1
TOTAL: 9		61

Daily Usenet report for csiph.com

Sep 26 04:15:00 -- Sep 27 04:15:00