Daily Usenet report for csiph.com: Aug 27 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	52453	48.3%	7.4 MB	59.3%
nnrpd	31925	29.4%	2.7 MB	21.2%
innd	22395	20.6%	2.2 MB	17.3%
innfeed	1877	1.7%	285.7 KB	2.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	108651	100.0%	12.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	276281	47.2%
Negative hits	252592	43.2%
Do not exist	51480	8.8%
Cache misses	4928	0.8%
TOTAL: 4	585281	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.219	0.0%	303	0.000	0.723	14.000
article cleanup	00:00:00.285	0.0%	50541	0.000	0.006	0.034
article logging	00:00:00.617	0.0%	51137	0.000	0.012	0.044
article parse	00:00:02.635	0.0%	302264	0.002	0.009	0.018
article write	00:00:06.316	0.0%	26911	0.150	0.235	0.370
artlog/artcncl	00:00:00.002	0.0%	233	0.000	0.009	0.056
artlog/artparse	00:00:00.006	0.0%	1089	0.000	0.006	0.043
data move	00:00:00.315	0.0%	768762	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.004	0.0%	290	0.000	0.014	1.000
hishave/artcncl	00:00:00.002	0.0%	303	0.000	0.007	0.056
hishave/artparse	00:00:00.003	0.0%	1089	0.000	0.003	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.388	0.0%	585524	0.001	0.002	0.006
history sync	00:00:00.472	0.0%	305	0.000	1.548	3.000
history write	00:00:12.760	0.0%	50535	0.089	0.252	0.340
hiswrite/artcncl	00:00:00.002	0.0%	13	0.000	0.154	2.000
hiswrite/artparse	00:00:00.291	0.0%	1086	0.000	0.268	2.000
idle	23:57:34.861	99.8%	758487	28.658	113.720	383.966
nntp read	00:00:02.540	0.0%	757428	0.001	0.003	0.006
overview write	00:00:04.517	0.0%	26911	0.100	0.168	1.287
perl filter	00:01:42.429	0.1%	50535	1.003	2.027	3.726
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.511	0.0%	53822	0.000	0.009	0.037
TOTAL: 24:00:28.019	23:59:50.175	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.067	0.0%	26849	0.000	0.002	0.022
article prepare	00:00:00.002	0.0%	7668	0.000	0.000	0.021
article read	00:00:00.228	0.0%	1485	0.000	0.154	15.667
backlog stats	00:00:00.113	0.0%	301609	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.244	0.0%	230473	0.000	0.005	0.012
data write	00:00:01.721	0.0%	206133	0.004	0.008	0.014
idle	23:54:23.697	100.0%	301609	121.583	285.349	693.361
status file	00:00:00.100	0.0%	185	0.000	0.541	1.000
TOTAL: 23:54:31.009	23:54:27.172	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.566	0.0%	38224	0.010	0.015	0.047
idle	33:04:40.210	99.9%	69671	0.000	1709.179	600160.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.796	0.0%	140418	0.000	0.006	0.333
readart	00:00:00.015	0.0%	118	0.000	0.127	1.000
TOTAL: 33:05:54.534	33:04:41.587	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	5	47063	6660	37924	2479	14%	24:36:20
2	au2pb.net	1	30215	5523	23589	1103	18%	24:07:44
3	xanadu-bbs.net	2	51579	4669	37278	9632	9%	24:28:41
4	news-out.mixmin.net	4	32880	3553	26771	2556	10%	24:09:25
5	newsfeed.neva.ru	144	64917	2791	53619	8507	4%	71:40:01
6	newsfeeds.xmission.com	1	28803	1964	26776	63	6%	25:50:52
7	newsfeed.fu-berlin.de	64	28697	1205	27336	156	4%	30:20:39
8	nntp.pasdenom.info	2	30609	352	30184	73	1%	28:27:42
9	news.swapon.de	1	2603	249	2345	9	9%	24:15:20
10	localhost	50	14983	0	14983	0	0%	00:01:01
TOTAL: 10		274	332349	26966	280805	24578	8%	277:57:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	19.1 MB	0.0 KB	16.7 MB	35.8 MB	53%	4.0 KB
2	au2pb.net	14.0 MB	14.9 KB	6.5 MB	20.6 MB	68%	3.2 KB
3	xanadu-bbs.net	13.3 MB	3.3 KB	189.0 MB	202.3 MB	6%	14.5 KB
4	news-out.mixmin.net	9.2 MB	3.2 KB	6.8 MB	16.0 MB	57%	2.7 KB
5	newsfeed.neva.ru	8.2 MB	3.3 KB	65.3 MB	73.5 MB	11%	6.7 KB
6	newsfeeds.xmission.com	5.7 MB	3.3 KB	1.2 MB	6.9 MB	82%	3.5 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	444.9 KB	3.4 MB	87%	2.5 KB
8	nntp.pasdenom.info	784.8 KB	0.0 KB	406.0 KB	1.2 MB	65%	2.8 KB
9	news.swapon.de	770.8 KB	0.0 KB	24.2 KB	795.0 KB	96%	3.1 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		74.1 MB	28.0 KB	286.4 MB	360.5 MB	20%	7.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 27 04:15:00 - 04:59:59	420	1.6%	0.16	1.2 MB	1.6%	0.45
Aug 27 05:00:00 - 05:59:59	649	2.4%	0.18	2.1 MB	2.8%	0.58
Aug 27 06:00:00 - 06:59:59	748	2.8%	0.21	1.9 MB	2.6%	0.55
Aug 27 07:00:00 - 07:59:59	1103	4.1%	0.31	2.7 MB	3.7%	0.78
Aug 27 08:00:00 - 08:59:59	1157	4.3%	0.32	2.9 MB	3.9%	0.82
Aug 27 09:00:00 - 09:59:59	1251	4.6%	0.35	3.2 MB	4.4%	0.92
Aug 27 10:00:00 - 10:59:59	1606	6.0%	0.45	5.1 MB	6.9%	1.44
Aug 27 11:00:00 - 11:59:59	1248	4.6%	0.35	3.5 MB	4.7%	0.99
Aug 27 12:00:00 - 12:59:59	1347	5.0%	0.37	3.3 MB	4.5%	0.95
Aug 27 13:00:00 - 13:59:59	1508	5.6%	0.42	4.0 MB	5.4%	1.14
Aug 27 14:00:00 - 14:59:59	1419	5.3%	0.39	3.5 MB	4.7%	0.99
Aug 27 15:00:00 - 15:59:59	1457	5.4%	0.40	4.0 MB	5.4%	1.13
Aug 27 16:00:00 - 16:59:59	1569	5.8%	0.44	4.2 MB	5.7%	1.20
Aug 27 17:00:00 - 17:59:59	1611	6.0%	0.45	4.5 MB	6.0%	1.27
Aug 27 18:00:00 - 18:59:59	1469	5.5%	0.41	4.1 MB	5.6%	1.17
Aug 27 19:00:00 - 19:59:59	1339	5.0%	0.37	3.7 MB	5.0%	1.05
Aug 27 20:00:00 - 20:59:59	1375	5.1%	0.38	3.5 MB	4.8%	1.01
Aug 27 21:00:00 - 21:59:59	1284	4.8%	0.36	3.6 MB	4.9%	1.02
Aug 27 22:00:00 - 22:59:59	1007	3.7%	0.28	3.0 MB	4.0%	0.84
Aug 27 23:00:00 - 23:59:59	780	2.9%	0.22	2.0 MB	2.7%	0.57
Aug 28 00:00:00 - 00:59:59	668	2.5%	0.19	2.1 MB	2.9%	0.61
Aug 28 01:00:00 - 01:59:59	573	2.1%	0.16	1.6 MB	2.2%	0.47
Aug 28 02:00:00 - 02:59:59	601	2.2%	0.17	2.1 MB	2.9%	0.60
Aug 28 03:00:00 - 03:59:59	542	2.0%	0.15	1.6 MB	2.1%	0.44
Aug 28 04:00:00 - 04:15:00	179	0.7%	0.20	595.4 KB	0.8%	0.66
TOTAL: 24:00:00	26910	100.0%	0.31	73.9 MB	100.0%	0.88

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	9735	796	1	8938
2	newsfeed.neva.ru	8571	1743	1	6827
3	news-out.mixmin.net	2584	12	1	2571
4	feeder.eternal-september.org	2535	354	0	2181
5	au2pb.net	1117	1019	2	96
6	newsfeed.fu-berlin.de	590	33	0	557
7	newsfeeds.xmission.com	75	46	1	28
8	nntp.pasdenom.info	67	64	0	3
9	news.swapon.de	37	0	0	37
TOTAL: 9		25311	4067	6	21238

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	806
lada.auto	227
it-alt.sport.calcio.juventus	209
ut.test	174
lada.talk	134
alt.politics	133
alt.politics.scorched-earth	127
mailing.gcc.gcc	73
mailing.freebsd.svn-src-all	60
mailing.freebsd.svn-ports-all	60
fa.freebsd.cvs-all	60
mailing.freebsd.pkg-fallout	59
mailing.freebsd.svn-ports-head	58
mailing.freebsd.svn-src-head	49
fa.openbsd.source-changes	48
mailing.openbsd.source-changes	48
free.it.calcio.juventus	48
mailing.freebsd.questions	43
alt.survival	42
mailing.gcc.gcc-cvs	41
TOTAL: 276	4067

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15659
EMP (phn path)	2801
EMP (phl)	675
PFM bot misc	113
HTML Multipart	111
EMP (phn nph)	80
Too many newsgroups	67
Too many newsgroups (low_xpost)	20
Binary Image: misplaced jpg	18
HTML Image Tags	5
HTML post	2
Binary Image: misplaced png	1
Binary: misplaced obj	1
TOTAL: 13	19553

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	944
	news-out.mixmin.net	48
	feeder.eternal-september.org	43
	au2pb.net	38
	newsfeeds.xmission.com	12
	nntp.pasdenom.info	3
	newsfeed.fu-berlin.de	1
	TOTAL: 7	1089
Including strange strings
	newsfeed.fu-berlin.de	407
	feeder.eternal-september.org	55
	xanadu-bbs.net	50
	news.swapon.de	27
	news-out.mixmin.net	26
	newsfeeds.xmission.com	13
	au2pb.net	13
	newsfeed.neva.ru	5
	TOTAL: 8	596
TOTAL: 2		1685

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	33055	322	25352	891	4	0%	23:59:57
2	au2pb	22420	210	21033	152	0	0%	23:59:57
3	xanadu-bbs	23667	87	22143	25	8	0%	23:59:58
4	fu-berlin.de	25208	47	25117	44	0	0%	23:59:53
5	news.mixmin.net	23911	18	23220	19	0	0%	23:59:52
6	xmission	30735	10	24842	8	0	0%	23:59:58
7	eternal-september	20881	8	19749	2	0	0%	23:59:51
8	neva.ru	25111	4	23874	72	0	0%	23:59:55
9	news.swapon.de	2503	0	2487	1	5	0%	23:59:57
TOTAL: 9		207491	706	187817	1214	17	0%	215:59:18

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.0 MB	1.9 MB	2.9 MB	2.4 KB	23:59:57
2	au2pb	820.9 KB	698.8 KB	1.5 MB	4.2 KB	23:59:57
3	xanadu-bbs	572.9 KB	44.0 KB	616.9 KB	5.5 KB	23:59:58
4	fu-berlin.de	409.2 KB	53.5 KB	462.7 KB	5.1 KB	23:59:53
5	news.mixmin.net	70.4 KB	34.2 KB	104.6 KB	2.8 KB	23:59:52
6	eternal-september	53.5 KB	3.7 KB	57.2 KB	5.7 KB	23:59:51
7	xmission	19.2 KB	193.6 KB	212.8 KB	11.8 KB	23:59:58
8	neva.ru	10.9 KB	320.5 KB	331.4 KB	4.4 KB	23:59:55
9	news.swapon.de	0.0 KB	5.4 KB	5.4 KB	5.4 KB	23:59:57
TOTAL: 9		2.9 MB	3.2 MB	6.1 MB	3.3 KB	215:59:18

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	116	351.1 KB	107	04:26:33
2	pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	1	1.4 KB	1	01:08:17
3	challenger.nielsenmedia.com	6	1	1.4 KB	1	02:07:57
TOTAL: 3		297	118	353.9 KB	109	33:05:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	321	116	351.1 KB	107	14:17:18
2	*.nielsenmedia.com	6	1	1.4 KB	1	02:07:57
3	*.tampfl.dsl-w.verizon.net	3	1	1.4 KB	1	01:08:17
4	*.br.br.cox.net	1	0	0.0 KB	0	00:00:45
5	*.ok.ok.cox.net	2	0	0.0 KB	0	01:02:07
6	*.bbox.io	5	0	0.0 KB	0	00:30:24
7	*.chello.nl	6	0	0.0 KB	0	02:10:56
8	*.dynamic.caiway.nl	8	0	0.0 KB	0	11:48:03
TOTAL: 8		352	118	353.9 KB	109	33:05:50

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
041-020-158-163.dynamic.caiway.nl	1.429	1.265	0.000	11:48:03
162.212.88.198	0.711	0.264	0.000	09:50:42
202.89.176.220	29.726	5.681	0.701	04:26:33
dhcp-089-099-203-254.chello.nl	0.491	0.088	0.000	02:10:56
challenger.nielsenmedia.com	0.600	0.101	0.000	02:07:57
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.316	0.045	0.000	01:08:17
ip68-14-171-134.ok.ok.cox.net	0.149	0.074	0.000	01:02:07
tempe0.bbox.io	0.344	0.064	0.000	00:30:24
ip70-172-227-76.br.br.cox.net	0.153	0.008	0.000	00:00:45
69.160.37.54	1.929	0.356	0.000	00:00:02
TOTAL: 10	35.848	7.946	0.701	33:05:50

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
162.212.88.198	8
041-020-158-163.dynamic.caiway.nl	8
dhcp-089-099-203-254.chello.nl	6
tempe0.bbox.io	5
ip68-14-171-134.ok.ok.cox.net	2
ip70-172-227-76.br.br.cox.net	1
TOTAL: 7	55

NNRP client timeouts [Top 20]:

System	Conn	Peer
041-020-158-163.dynamic.caiway.nl	8	0
dhcp-089-099-203-254.chello.nl	4	0
162.212.88.198	4	0
challenger.nielsenmedia.com	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
ip68-14-171-134.ok.ok.cox.net	2	0
tempe0.bbox.io	1	0
TOTAL: 7	24	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	51
2	comp.os.vms	40
3	comp.sys.ibm.pc.games.war-historical	16
4	comp.sys.ibm.ps2.hardware	4
5	comp.lang.awk	2
6	comp.sys.ibm.pc.games.action	2
7	comp.os.os2.misc	1
8	comp.os.linux.networking	1
9	comp.sys.ibm.as400.misc	1
TOTAL: 9		118

Daily Usenet report for csiph.com

Aug 27 04:15:00 -- Aug 28 04:15:00