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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	47341	48.5%	7.1 MB	60.7%
nnrpd	31591	32.4%	2.6 MB	22.5%
innd	16751	17.2%	1.7 MB	14.6%
innfeed	1885	1.9%	261.4 KB	2.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	97569	100.0%	11.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	268368	48.7%
Negative hits	230282	41.8%
Do not exist	46496	8.4%
Cache misses	5941	1.1%
TOTAL: 4	551087	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.360	0.0%	216	0.000	1.667	20.000
article cleanup	00:00:00.303	0.0%	45886	0.000	0.007	0.052
article logging	00:00:00.604	0.0%	46460	0.000	0.013	0.031
article parse	00:00:02.080	0.0%	229082	0.003	0.009	0.019
article write	00:00:07.431	0.0%	28094	0.152	0.265	0.435
artlog/artcncl	00:00:00.003	0.0%	188	0.000	0.016	1.000
artlog/artparse	00:00:00.011	0.0%	716	0.000	0.015	0.154
data move	00:00:00.218	0.0%	705009	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	214	0.000	0.009	0.333
hishave/artcncl	00:00:00.000	0.0%	216	0.000	0.000	0.000
hishave/artparse	00:00:00.001	0.0%	716	0.000	0.001	0.050
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.723	0.0%	551313	0.001	0.003	0.009
history sync	00:00:00.605	0.0%	340	0.000	1.779	3.667
history write	00:00:13.088	0.0%	45884	0.069	0.285	0.383
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.233	0.0%	711	0.000	0.328	4.000
idle	23:58:09.985	99.8%	690359	28.271	124.993	323.757
nntp read	00:00:02.170	0.0%	688813	0.001	0.003	0.007
overview write	00:00:12.173	0.0%	28094	0.098	0.433	53.381
perl filter	00:01:18.187	0.1%	45884	1.030	1.704	3.544
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.529	0.0%	56188	0.000	0.009	0.032
TOTAL: 24:00:46.584	24:00:09.706	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.072	0.0%	28160	0.000	0.003	0.023
article prepare	00:00:00.004	0.0%	9204	0.000	0.000	0.018
article read	00:00:00.285	0.0%	1849	0.000	0.154	6.429
backlog stats	00:00:00.118	0.0%	317665	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.363	0.0%	243496	0.000	0.006	0.012
data write	00:00:01.772	0.0%	219178	0.001	0.008	0.016
idle	23:54:17.433	100.0%	317665	165.764	270.906	893.231
status file	00:00:00.085	0.0%	190	0.000	0.447	1.000
TOTAL: 23:54:26.402	23:54:21.132	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.626	0.0%	31500	0.000	0.020	0.030
idle	105:36:57.120	100.0%	62462	0.000	6087.175	600240.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.307	0.0%	125809	0.000	0.010	4.611
readart	00:00:00.429	0.0%	3722	0.000	0.115	50.000
TOTAL: 105:38:19.147	105:36:59.482	100.0%	-	-	-	-

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	feeder.eternal-september.org	1	42577	7475	32176	2926	17%	23:59:54
2	au2pb.net	1	29036	5907	22325	804	20%	22:31:07
3	xanadu-bbs.net	1	46060	4352	33806	7902	9%	23:40:09
4	news-out.mixmin.net	6	34011	4040	27217	2754	11%	24:15:26
5	newsfeed.neva.ru	140	58691	2603	52585	3503	4%	72:10:20
6	newsfeeds.xmission.com	1	28468	1766	26603	99	6%	24:07:43
7	newsfeed.fu-berlin.de	67	28069	1242	26593	234	4%	29:30:35
8	news.swapon.de	1	2604	312	2289	3	11%	25:22:57
9	nntp.pasdenom.info	3	18539	176	18299	64	0%	16:49:26
10	localhost	50	17675	0	17675	0	0%	00:01:02
TOTAL: 10		271	305730	27873	259568	18289	9%	262:28:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	21.0 MB	5.2 KB	9.2 MB	30.2 MB	69%	3.0 KB
2	au2pb.net	16.0 MB	0.0 KB	4.4 MB	20.4 MB	78%	3.1 KB
3	news-out.mixmin.net	13.2 MB	0.0 KB	7.7 MB	20.9 MB	63%	3.2 KB
4	xanadu-bbs.net	12.9 MB	0.0 KB	131.6 MB	144.5 MB	8%	12.1 KB
5	newsfeed.neva.ru	8.5 MB	0.0 KB	16.4 MB	25.0 MB	34%	4.2 KB
6	newsfeeds.xmission.com	5.3 MB	0.0 KB	1.3 MB	6.6 MB	80%	3.6 KB
7	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	667.1 KB	3.3 MB	80%	2.3 KB
8	news.swapon.de	809.1 KB	0.0 KB	264.4 KB	1.0 MB	75%	3.4 KB
9	nntp.pasdenom.info	420.8 KB	0.0 KB	418.6 KB	839.4 KB	50%	3.5 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		80.8 MB	5.2 KB	172.0 MB	252.8 MB	31%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 9 04:15:00 - 04:59:59	465	1.7%	0.17	1.8 MB	2.2%	0.67
Oct 9 05:00:00 - 05:59:59	620	2.2%	0.17	2.1 MB	2.6%	0.61
Oct 9 06:00:00 - 06:59:59	821	2.9%	0.23	2.4 MB	2.9%	0.68
Oct 9 07:00:00 - 07:59:59	1200	4.3%	0.33	3.3 MB	4.1%	0.94
Oct 9 08:00:00 - 08:59:59	1429	5.1%	0.40	3.7 MB	4.6%	1.06
Oct 9 09:00:00 - 09:59:59	1516	5.4%	0.42	3.9 MB	4.9%	1.12
Oct 9 10:00:00 - 10:59:59	1321	4.7%	0.37	3.4 MB	4.2%	0.97
Oct 9 11:00:00 - 11:59:59	1288	4.6%	0.36	3.5 MB	4.3%	1.00
Oct 9 12:00:00 - 12:59:59	1451	5.2%	0.40	5.6 MB	6.9%	1.59
Oct 9 13:00:00 - 13:59:59	1509	5.4%	0.42	4.7 MB	5.8%	1.34
Oct 9 14:00:00 - 14:59:59	1463	5.2%	0.41	3.8 MB	4.7%	1.09
Oct 9 15:00:00 - 15:59:59	1724	6.1%	0.48	4.5 MB	5.5%	1.27
Oct 9 16:00:00 - 16:59:59	1656	5.9%	0.46	4.5 MB	5.5%	1.27
Oct 9 17:00:00 - 17:59:59	1711	6.1%	0.48	4.6 MB	5.7%	1.31
Oct 9 18:00:00 - 18:59:59	1614	5.7%	0.45	4.8 MB	5.8%	1.35
Oct 9 19:00:00 - 19:59:59	1523	5.4%	0.42	3.9 MB	4.9%	1.12
Oct 9 20:00:00 - 20:59:59	1351	4.8%	0.38	3.7 MB	4.5%	1.05
Oct 9 21:00:00 - 21:59:59	1161	4.1%	0.32	3.4 MB	4.2%	0.96
Oct 9 22:00:00 - 22:59:59	1112	4.0%	0.31	3.3 MB	4.1%	0.95
Oct 9 23:00:00 - 23:59:59	762	2.7%	0.21	2.2 MB	2.6%	0.61
Oct 10 00:00:00 - 00:59:59	648	2.3%	0.18	2.3 MB	2.9%	0.66
Oct 10 01:00:00 - 01:59:59	591	2.1%	0.16	2.1 MB	2.6%	0.59
Oct 10 02:00:00 - 02:59:59	542	1.9%	0.15	1.9 MB	2.3%	0.53
Oct 10 03:00:00 - 03:59:59	448	1.6%	0.12	1.4 MB	1.8%	0.41
Oct 10 04:00:00 - 04:15:00	163	0.6%	0.18	464.7 KB	0.6%	0.52
TOTAL: 24:00:00	28089	100.0%	0.33	81.3 MB	100.0%	0.96

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8017	610	0	7407
2	newsfeed.neva.ru	3504	1590	0	1914
3	feeder.eternal-september.org	2965	278	2	2685
4	news-out.mixmin.net	2780	9	0	2771
5	au2pb.net	833	780	0	53
6	newsfeed.fu-berlin.de	722	33	0	689
7	newsfeeds.xmission.com	108	89	0	19
8	nntp.pasdenom.info	99	75	0	24
9	news.swapon.de	37	0	0	37
TOTAL: 9		19065	3464	2	15599

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	733
lada.auto	183
lada.talk	153
alt.politics	136
mailing.freebsd.pkg-fallout	134
it-alt.sport.calcio.juventus	103
mailing.freebsd.svn-ports-all	77
mailing.freebsd.svn-ports-head	75
ut.test	73
alt.survival	69
mailing.freebsd.ports-bugs	59
mpc.lists.freebsd.ports.bugs	59
pld.cvs.commit	55
mailing.openbsd.source-changes	53
mailing.freebsd.svn-src-all	47
fa.freebsd.cvs-all	43
gmane.linux.alsa.devel	43
mailing.gcc.gcc-cvs	42
lada.photo	40
alt.job	39
TOTAL: 253	3464

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10972
EMP (phn path)	2075
EMP (phl)	841
EMP (phn nph)	157
PFM bot misc	113
Too many newsgroups	59
HTML Multipart	38
HTML post	30
Binary Image: misplaced jpg	16
HTML Image Tags	4
Too many newsgroups (low_xpost)	3
Binary: misplaced zip	1
Binary Payload	1
TOTAL: 13	14310

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	570
	news-out.mixmin.net	48
	au2pb.net	36
	feeder.eternal-september.org	31
	nntp.pasdenom.info	18
	newsfeeds.xmission.com	10
	newsfeed.fu-berlin.de	2
	news.swapon.de	1
	TOTAL: 8	716
Including strange strings
	newsfeed.fu-berlin.de	440
	feeder.eternal-september.org	43
	news.swapon.de	35
	news-out.mixmin.net	22
	au2pb.net	12
	xanadu-bbs.net	11
	newsfeeds.xmission.com	7
	newsfeed.neva.ru	3
	TOTAL: 8	573
TOTAL: 2		1289

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	23011	487	21268	254	0	2%	23:59:50
2	usenet.pasdenom.info	35148	215	26772	992	12	0%	23:59:56
3	xanadu-bbs	25685	148	23554	13	30	0%	23:59:57
4	fu-berlin.de	26316	62	26221	33	0	0%	23:59:56
5	xmission	33640	12	26131	3	0	0%	23:59:57
6	news.mixmin.net	24742	10	23906	14	0	0%	23:59:56
7	eternal-september	21410	9	20171	1	0	0%	23:59:56
8	neva.ru	26518	6	25302	55	88	0%	23:59:56
9	news.swapon.de	2303	0	2272	1	0	0%	23:56:39
TOTAL: 9		218773	949	195597	1366	130	0%	215:56:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.9 MB	747.8 KB	2.6 MB	3.6 KB	23:59:50
2	usenet.pasdenom.info	977.5 KB	2.2 MB	3.1 MB	2.7 KB	23:59:56
3	xanadu-bbs	912.8 KB	32.5 KB	945.2 KB	5.9 KB	23:59:57
4	fu-berlin.de	397.8 KB	39.9 KB	437.7 KB	4.6 KB	23:59:56
5	news.mixmin.net	43.4 KB	25.4 KB	68.8 KB	2.9 KB	23:59:56
6	neva.ru	41.3 KB	255.4 KB	296.7 KB	4.9 KB	23:59:56
7	eternal-september	33.8 KB	1.6 KB	35.4 KB	3.5 KB	23:59:56
8	xmission	29.8 KB	8.4 KB	38.1 KB	2.5 KB	23:59:57
9	news.swapon.de	0.0 KB	1.8 KB	1.8 KB	1.8 KB	23:56:39
TOTAL: 9		4.2 MB	3.3 MB	7.5 MB	3.3 KB	215:56:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	284	44	106.8 KB	44	04:19:06
2	ip70-186-183-22.br.br.cox.net	1	2	3.6 KB	1	00:00:50
3	dhcp-089-099-203-254.chello.nl	69	1	1.6 KB	1	28:37:30
TOTAL: 3		354	47	112.0 KB	46	105:38:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	313	44	106.8 KB	44	65:51:34
2	*.br.br.cox.net	1	2	3.6 KB	1	00:00:50
3	*.chello.nl	69	1	1.6 KB	1	28:37:30
4	*.ip-188-165-239.eu	5	0	0.0 KB	0	00:02:55
5	*.tampfl.dsl-w.verizon.net	4	0	0.0 KB	0	01:10:33
6	*.tu.ok.cox.net	3	0	0.0 KB	0	07:56:28
7	*.nielsenmedia.com	5	0	0.0 KB	0	01:58:21
TOTAL: 7		400	47	112.0 KB	46	105:38:15

NNRP auth users [Top 20]:

User	Conn
moussa	284
TOTAL: 1	284

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.198	0.579	0.774	0.000	57:04:34
dhcp-089-099-203-254.chello.nl	5.577	1.202	0.000	28:37:30
ip72-222-107-224.tu.ok.cox.net	0.547	0.338	0.000	07:56:28
71.94.21.184	0.466	0.280	0.000	04:27:47
202.89.176.220	25.443	6.075	0.690	04:19:06
challenger.nielsenmedia.com	0.463	0.056	0.000	01:58:21
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.302	0.060	0.000	01:10:33
ns342002.ip-188-165-239.eu	0.432	0.096	0.000	00:02:55
ip70-186-183-22.br.br.cox.net	0.125	0.029	0.000	00:00:50
173.0.15.147	0.238	0.238	0.000	00:00:04
69.160.37.54	1.964	0.359	0.000	00:00:02
TOTAL: 11	36.136	9.507	0.690	105:38:15

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
challenger.nielsenmedia.com	5
ns342002.ip-188-165-239.eu	5
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4
71.94.21.184	3
ip72-222-107-224.tu.ok.cox.net	3
173.0.15.147	1
TOTAL: 7	46

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	3
TOTAL: 1	3

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	3
TOTAL: 1	3

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch.embedded	10
2	comp.sys.ibm.pc.hardware.storage	9
3	comp.os.vms	8
4	comp.sys.ibm.pc.games.war-historical	6
5	comp.arch.fpga	3
6	comp.arch	3
7	comp.sys.ibm.ps2.hardware	2
8	comp.sys.ibm.pc.games.action	2
9	comp.sys.apple2	2
10	comp.ai.neural-nets	1
11	comp.os.linux.networking	1
TOTAL: 11		47

Daily Usenet report for csiph.com

Oct 9 04:15:00 -- Oct 10 04:15:00