Daily Usenet report for csiph.com: Oct 15 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	49582	49.3%	7.4 MB	61.4%
nnrpd	32081	31.9%	2.7 MB	21.9%
innd	17055	17.0%	1.7 MB	14.3%
innfeed	1858	1.8%	290.9 KB	2.3%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	100577	100.0%	12.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	272287	47.9%
Negative hits	245775	43.2%
Do not exist	48321	8.5%
Cache misses	2558	0.4%
TOTAL: 4	568941	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.755	0.0%	348	0.000	5.043	35.000
article cleanup	00:00:00.304	0.0%	48118	0.000	0.006	0.022
article logging	00:00:00.587	0.0%	48831	0.000	0.012	0.054
article parse	00:00:02.053	0.0%	223001	0.001	0.009	0.042
article write	00:01:16.296	0.1%	29173	0.168	2.615	12.047
artlog/artcncl	00:00:00.002	0.0%	308	0.000	0.006	0.333
artlog/artparse	00:00:00.005	0.0%	435	0.000	0.011	0.333
data move	00:00:00.234	0.0%	713501	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.301	0.0%	345	0.000	0.872	26.000
hishave/artcncl	00:00:00.001	0.0%	348	0.000	0.003	0.032
hishave/artparse	00:00:00.001	0.0%	435	0.000	0.002	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.365	0.0%	570707	0.001	0.004	0.055
history sync	00:00:01.623	0.0%	360	0.000	4.508	41.667
history write	00:00:31.105	0.0%	48117	0.072	0.646	5.634
hiswrite/artcncl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hiswrite/artparse	00:00:00.277	0.0%	435	0.000	0.637	6.143
idle	23:56:03.420	99.7%	698188	68.566	123.410	340.602
nntp read	00:00:02.200	0.0%	697851	0.001	0.003	0.006
overview write	00:00:51.575	0.1%	29173	0.177	1.768	9.565
perl filter	00:01:19.968	0.1%	48117	1.015	1.662	2.770
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.525	0.0%	58346	0.000	0.009	0.026
TOTAL: 24:00:53.058	24:00:14.597	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.072	0.0%	29138	0.000	0.002	0.019
article prepare	00:00:00.001	0.0%	8282	0.000	0.000	0.010
article read	00:00:00.313	0.0%	1573	0.000	0.199	22.500
backlog stats	00:00:00.123	0.0%	319024	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.360	0.0%	248250	0.001	0.005	0.014
data write	00:00:01.878	0.0%	224000	0.002	0.008	0.018
idle	23:53:09.928	100.0%	319024	127.077	269.541	774.638
status file	00:00:00.079	0.0%	183	0.000	0.432	2.000
TOTAL: 23:53:19.018	23:53:13.754	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.783	0.0%	28788	0.009	0.027	0.062
idle	79:17:03.640	100.0%	60285	0.000	4734.571	600187.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.799	0.0%	121663	0.000	0.007	0.333
readart	00:00:12.641	0.0%	3322	0.000	3.805	8.000
TOTAL: 79:19:00.824	79:17:17.863	100.0%	-	-	-	-

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	1	44911	8021	33949	2941	17%	24:49:31
2	xanadu-bbs.net	1	48658	5328	35020	8310	10%	24:13:36
3	news-out.mixmin.net	6	35042	4655	27751	2636	13%	24:29:27
4	au2pb.net	0	32655	4244	27408	1003	12%	25:11:49
5	newsfeed.neva.ru	150	60238	2898	53189	4151	4%	71:22:15
6	newsfeeds.xmission.com	1	30900	2217	28581	102	7%	25:32:15
7	newsfeed.fu-berlin.de	74	34043	1431	32292	320	4%	37:25:11
8	nntp.pasdenom.info	2	26418	383	25962	73	1%	21:48:49
9	news.swapon.de	1	2346	292	2038	16	12%	22:18:58
10	localhost	50	7151	0	7151	0	0%	00:01:01
TOTAL: 10		286	322362	29469	273341	19552	9%	277:12:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	23.0 MB	2.0 KB	15.4 MB	38.4 MB	59%	3.6 KB
2	xanadu-bbs.net	16.0 MB	0.0 KB	115.1 MB	131.1 MB	12%	9.8 KB
3	news-out.mixmin.net	15.5 MB	0.0 KB	4.8 MB	20.3 MB	76%	2.8 KB
4	au2pb.net	11.3 MB	0.0 KB	7.8 MB	19.1 MB	59%	3.7 KB
5	newsfeed.neva.ru	9.6 MB	0.0 KB	16.8 MB	26.3 MB	36%	3.8 KB
6	newsfeeds.xmission.com	7.1 MB	0.0 KB	1.3 MB	8.4 MB	84%	3.7 KB
7	newsfeed.fu-berlin.de	3.3 MB	0.0 KB	844.3 KB	4.1 MB	79%	2.4 KB
8	nntp.pasdenom.info	886.9 KB	0.0 KB	355.0 KB	1.2 MB	71%	2.7 KB
9	news.swapon.de	718.5 KB	0.0 KB	895.6 KB	1.6 MB	44%	5.2 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		87.3 MB	2.0 KB	163.2 MB	250.5 MB	34%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 15 04:15:00 - 04:59:59	400	1.4%	0.15	1.2 MB	1.4%	0.47
Oct 15 05:00:00 - 05:59:59	625	2.1%	0.17	1.7 MB	2.0%	0.49
Oct 15 06:00:00 - 06:59:59	885	3.0%	0.25	2.4 MB	2.8%	0.69
Oct 15 07:00:00 - 07:59:59	1202	4.1%	0.33	3.5 MB	4.0%	0.98
Oct 15 08:00:00 - 08:59:59	1367	4.7%	0.38	3.6 MB	4.2%	1.03
Oct 15 09:00:00 - 09:59:59	1374	4.7%	0.38	3.4 MB	3.9%	0.97
Oct 15 10:00:00 - 10:59:59	1509	5.2%	0.42	4.1 MB	4.7%	1.17
Oct 15 11:00:00 - 11:59:59	1332	4.6%	0.37	4.0 MB	4.6%	1.12
Oct 15 12:00:00 - 12:59:59	1448	5.0%	0.40	3.8 MB	4.4%	1.09
Oct 15 13:00:00 - 13:59:59	1306	4.5%	0.36	3.1 MB	3.6%	0.89
Oct 15 14:00:00 - 14:59:59	1405	4.8%	0.39	3.8 MB	4.4%	1.07
Oct 15 15:00:00 - 15:59:59	1697	5.8%	0.47	4.7 MB	5.4%	1.34
Oct 15 16:00:00 - 16:59:59	1706	5.8%	0.47	4.4 MB	5.0%	1.24
Oct 15 17:00:00 - 17:59:59	1521	5.2%	0.42	4.3 MB	4.9%	1.21
Oct 15 18:00:00 - 18:59:59	2136	7.3%	0.59	8.8 MB	10.2%	2.51
Oct 15 19:00:00 - 19:59:59	2025	6.9%	0.56	7.7 MB	8.9%	2.19
Oct 15 20:00:00 - 20:59:59	1403	4.8%	0.39	3.8 MB	4.4%	1.09
Oct 15 21:00:00 - 21:59:59	1312	4.5%	0.36	3.9 MB	4.5%	1.12
Oct 15 22:00:00 - 22:59:59	1000	3.4%	0.28	3.1 MB	3.6%	0.87
Oct 15 23:00:00 - 23:59:59	833	2.9%	0.23	2.8 MB	3.2%	0.79
Oct 16 00:00:00 - 00:59:59	836	2.9%	0.23	2.5 MB	2.9%	0.71
Oct 16 01:00:00 - 01:59:59	607	2.1%	0.17	2.3 MB	2.6%	0.64
Oct 16 02:00:00 - 02:59:59	497	1.7%	0.14	1.4 MB	1.7%	0.41
Oct 16 03:00:00 - 03:59:59	563	1.9%	0.16	1.6 MB	1.8%	0.45
Oct 16 04:00:00 - 04:15:00	208	0.7%	0.23	581.5 KB	0.7%	0.65
TOTAL: 24:00:00	29197	100.0%	0.34	86.5 MB	100.0%	1.03

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8304	904	0	7400
2	newsfeed.neva.ru	4173	1797	0	2376
3	feeder.eternal-september.org	2970	288	1	2681
4	news-out.mixmin.net	2662	28	0	2634
5	au2pb.net	985	912	0	73
6	newsfeed.fu-berlin.de	709	27	0	682
7	newsfeeds.xmission.com	122	85	0	37
8	nntp.pasdenom.info	91	66	0	25
9	news.swapon.de	57	0	0	57
TOTAL: 9		20073	4107	1	15965

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	848
lada.talk	259
mailing.freebsd.pkg-fallout	203
lada.auto	175
ut.test	154
alt.politics.scorched-earth	126
mailing.freebsd.ports-bugs	126
mailing.freebsd.svn-ports-all	113
mailing.openbsd.source-changes	110
mailing.freebsd.svn-ports-head	110
mailing.openbsd.ports-changes	68
mailing.freebsd.svn-src-all	66
alt.politics	62
mailing.database.pgsql-general	59
fa.freebsd.cvs-all	57
mailing.freebsd.svn-src-head	52
alt.windows7.general	46
mpc.lists.nanog	44
alt.philosophy	38
pld.cvs.commit	38
TOTAL: 273	4107

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10983
EMP (phn path)	2149
EMP (phl)	902
EMP (phn nph)	364
PFM bot misc	226
HTML Multipart	70
Too many newsgroups	45
Too many newsgroups (low_xpost)	39
Binary Image: misplaced jpg	33
HTML post	4
HTML Image Tags	3
Binary: misplaced po	1
Binary: misplaced gz	1
User-issued spam cancel	1
TOTAL: 14	14821

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	310
	feeder.eternal-september.org	55
	au2pb.net	46
	newsfeeds.xmission.com	10
	news-out.mixmin.net	3
	newsfeed.fu-berlin.de	3
	news.swapon.de	2
	nntp.pasdenom.info	2
	TOTAL: 8	431
Including strange strings
	newsfeed.fu-berlin.de	415
	feeder.eternal-september.org	88
	xanadu-bbs.net	84
	news.swapon.de	41
	news-out.mixmin.net	26
	newsfeeds.xmission.com	23
	au2pb.net	19
	newsfeed.neva.ru	15
	nntp.pasdenom.info	2
	TOTAL: 9	713
TOTAL: 2		1144

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	au2pb	26746	320	24503	244	0	0	1%	23:59:52
2	usenet.pasdenom.info	33922	230	27699	832	0	3	0%	23:59:56
3	xanadu-bbs	25908	132	23698	50	0	14	0%	23:59:51
4	fu-berlin.de	27345	69	27233	42	0	0	0%	23:59:56
5	news.mixmin.net	25257	27	24355	27	0	0	0%	23:59:56
6	xmission	33214	15	26898	3	1	0	0%	23:59:56
7	neva.ru	27394	7	26098	57	0	0	0%	23:59:45
8	eternal-september	21911	5	20775	5	0	0	0%	23:59:56
9	news.swapon.de	2280	4	2259	1	0	0	0%	23:59:02
TOTAL: 9		223977	809	203518	1261	1	17	0%	215:58:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.5 MB	722.1 KB	2.2 MB	4.0 KB	23:59:52
2	usenet.pasdenom.info	1.0 MB	1.6 MB	2.7 MB	2.6 KB	23:59:56
3	xanadu-bbs	869.9 KB	102.8 KB	972.7 KB	5.3 KB	23:59:51
4	fu-berlin.de	410.8 KB	59.1 KB	469.9 KB	4.2 KB	23:59:56
5	news.mixmin.net	165.6 KB	57.0 KB	222.6 KB	4.1 KB	23:59:56
6	xmission	32.0 KB	7.6 KB	39.5 KB	2.2 KB	23:59:56
7	eternal-september	28.2 KB	7.4 KB	35.6 KB	3.6 KB	23:59:56
8	neva.ru	27.8 KB	254.3 KB	282.2 KB	4.4 KB	23:59:45
9	news.swapon.de	10.1 KB	1.7 KB	11.8 KB	2.4 KB	23:59:02
TOTAL: 9		4.0 MB	2.8 MB	6.8 MB	3.4 KB	215:58:10

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	100	273.0 KB	94	0	0	04:15:31
TOTAL: 1		288	100	273.0 KB	94	0	0	79:18:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	323	100	273.0 KB	94	63:03:19
2	*.hsd1.nj.comcast.net	22	0	0.0 KB	0	02:29:10
3	*.ip-188-165-239.eu	6	0	0.0 KB	0	00:03:21
4	*.br.br.cox.net	3	0	0.0 KB	0	00:02:26
5	*.tu.ok.cox.net	3	0	0.0 KB	0	03:16:54
6	*.chello.nl	27	0	0.0 KB	0	10:23:44
TOTAL: 6		384	100	273.0 KB	94	79:18:56

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.214	0.890	0.768	0.000	48:12:46
71.94.21.184	0.272	0.356	0.000	10:34:43
dhcp-089-099-203-254.chello.nl	2.202	0.357	0.000	10:23:44
202.89.176.220	28.667	5.910	0.705	04:15:31
ip72-222-107-224.tu.ok.cox.net	0.423	0.290	0.000	03:16:54
c-73-195-247-24.hsd1.nj.comcast.net	1.557	0.391	0.000	02:29:10
ns342002.ip-188-165-239.eu	0.464	0.165	0.000	00:03:21
ip70-186-188-109.br.br.cox.net	0.336	0.053	0.000	00:02:26
173.0.15.165	0.217	0.337	0.000	00:00:16
69.160.37.54	1.938	0.382	0.000	00:00:02
TOTAL: 10	36.966	9.009	0.705	79:18:56

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	27
69.160.37.54	25
c-73-195-247-24.hsd1.nj.comcast.net	22
162.212.88.214	8
ns342002.ip-188-165-239.eu	6
ip70-186-188-109.br.br.cox.net	3
ip72-222-107-224.tu.ok.cox.net	3
71.94.21.184	1
173.0.15.165	1
TOTAL: 9	96

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	18	0
162.212.88.214	4	4
c-73-195-247-24.hsd1.nj.comcast.net	4	0
ip72-222-107-224.tu.ok.cox.net	3	0
71.94.21.184	1	0
TOTAL: 5	30	4

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	99	99.0%
2	news	1	1.0%
TOTAL: 2		100	100.0%

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

Newsgroup		Count
1	comp.arch.embedded	35
2	comp.os.vms	22
3	comp.sys.ibm.pc.games.war-historical	15
4	comp.arch	11
5	comp.sys.ibm.as400.misc	9
6	comp.sys.ibm.pc.hardware.storage	2
7	comp.ai.neural-nets	1
8	comp.sys.ibm.pc.hardware.chips	1
9	comp.sys.ibm.pc.games.action	1
10	comp.ai.philosophy	1
11	comp.sys.ibm.ps2.hardware	1
12	news.admin.peering	1
TOTAL: 12		100

Daily Usenet report for csiph.com

Oct 15 04:15:00 -- Oct 16 04:15:00