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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53064	50.1%	7.9 MB	62.2%
nnrpd	32698	30.9%	2.7 MB	21.2%
innd	18168	17.2%	1.8 MB	14.2%
innfeed	1902	1.8%	316.5 KB	2.4%
controlchan	2	0.0%	0.3 KB	0.0%
TOTAL: 5	105834	100.0%	12.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	285077	48.2%
Negative hits	249855	42.2%
Do not exist	51826	8.8%
Cache misses	4711	0.8%
TOTAL: 4	591469	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.462	0.0%	220	0.000	2.100	68.000
article cleanup	00:00:00.294	0.0%	51291	0.000	0.006	0.023
article logging	00:00:00.646	0.0%	51919	0.000	0.012	0.036
article parse	00:00:02.029	0.0%	242887	0.003	0.008	0.016
article write	00:00:07.407	0.0%	31095	0.164	0.238	0.383
artlog/artcncl	00:00:00.001	0.0%	179	0.000	0.006	0.333
artlog/artparse	00:00:00.003	0.0%	699	0.000	0.004	0.067
data move	00:00:00.265	0.0%	744640	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	220	0.000	0.009	1.000
hishave/artcncl	00:00:00.001	0.0%	220	0.000	0.005	0.250
hishave/artparse	00:00:00.002	0.0%	699	0.000	0.003	0.091
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.852	0.0%	592549	0.001	0.003	0.008
history sync	00:00:00.629	0.0%	352	0.000	1.787	3.500
history write	00:00:14.264	0.0%	51289	0.068	0.278	0.526
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.164	0.0%	699	0.000	0.235	3.000
idle	23:48:01.340	99.8%	727961	28.964	117.700	334.249
nntp read	00:00:02.313	0.0%	726374	0.001	0.003	0.006
overview write	00:00:11.154	0.0%	31095	0.097	0.359	3.413
perl filter	00:01:27.061	0.1%	51289	1.020	1.697	4.832
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.570	0.0%	62190	0.000	0.009	0.023
TOTAL: 23:50:47.327	23:50:10.459	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.083	0.0%	31259	0.000	0.003	0.018
article prepare	00:00:00.002	0.0%	9016	0.000	0.000	0.019
article read	00:00:00.347	0.0%	1837	0.000	0.189	4.889
backlog stats	00:00:00.135	0.0%	346859	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.389	0.0%	267290	0.001	0.005	0.011
data write	00:00:02.000	0.0%	241654	0.001	0.008	0.017
idle	23:54:53.837	100.0%	346859	136.004	248.210	765.178
status file	00:00:00.081	0.0%	185	0.000	0.438	1.000
TOTAL: 23:55:02.640	23:54:57.874	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.024	0.0%	40243	0.000	0.025	0.136
idle	146:46:58.154	100.0%	71734	0.000	7366.356	600125.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.996	0.0%	144805	0.000	0.014	3.440
readart	00:00:26.512	0.0%	12155	0.000	2.181	7.000
TOTAL: 146:48:46.331	146:47:27.686	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	4
reserve	2
shutdown	3
TOTAL: 11	170

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	7	48166	8208	37246	2712	17%	25:44:47
2	au2pb.net	2	32879	6264	25429	1186	19%	24:17:52
3	xanadu-bbs.net	1	51945	5253	36801	9891	10%	23:51:45
4	news-out.mixmin.net	5	35706	4713	28594	2399	13%	24:19:07
5	newsfeed.neva.ru	145	67386	3352	59462	4572	4%	71:34:52
6	newsfeed.fu-berlin.de	64	35769	1814	33741	214	5%	37:33:00
7	newsfeeds.xmission.com	1	30816	1499	29241	76	4%	24:11:09
8	news.swapon.de	1	2244	265	1975	4	11%	24:46:14
9	nntp.pasdenom.info	3	17246	90	17139	17	0%	17:05:41
10	localhost	51	10513	1	10512	0	0%	00:01:03
TOTAL: 10		280	332670	31459	280140	21071	9%	273:25:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	23.1 MB	2.4 KB	18.3 MB	41.4 MB	55%	3.9 KB
2	au2pb.net	16.7 MB	0.0 KB	5.1 MB	21.9 MB	76%	3.0 KB
3	xanadu-bbs.net	16.2 MB	0.0 KB	121.4 MB	137.6 MB	11%	9.3 KB
4	news-out.mixmin.net	14.0 MB	0.0 KB	6.5 MB	20.4 MB	68%	2.9 KB
5	newsfeed.neva.ru	12.0 MB	12.2 KB	23.2 MB	35.2 MB	34%	4.5 KB
6	newsfeeds.xmission.com	4.8 MB	0.0 KB	1007.9 KB	5.8 MB	82%	3.8 KB
7	newsfeed.fu-berlin.de	4.2 MB	0.0 KB	666.7 KB	4.9 MB	86%	2.5 KB
8	news.swapon.de	714.5 KB	0.0 KB	56.4 KB	770.9 KB	92%	2.9 KB
9	nntp.pasdenom.info	205.6 KB	0.0 KB	128.2 KB	333.8 KB	61%	3.1 KB
10	localhost	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
TOTAL: 10		91.8 MB	14.5 KB	176.3 MB	268.2 MB	34%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 5 04:15:00 - 04:59:59	503	1.6%	0.19	1.6 MB	1.7%	0.59
Oct 5 05:00:00 - 05:59:59	567	1.8%	0.16	1.8 MB	2.0%	0.52
Oct 5 06:00:00 - 06:59:59	767	2.5%	0.21	3.1 MB	3.4%	0.89
Oct 5 07:00:00 - 07:59:59	1074	3.4%	0.30	3.0 MB	3.3%	0.85
Oct 5 08:00:00 - 08:59:59	1384	4.4%	0.38	4.0 MB	4.4%	1.14
Oct 5 09:00:00 - 09:59:59	1441	4.6%	0.40	4.0 MB	4.4%	1.15
Oct 5 10:00:00 - 10:59:59	1382	4.4%	0.38	4.5 MB	5.0%	1.29
Oct 5 11:00:00 - 11:59:59	1305	4.2%	0.36	3.7 MB	4.0%	1.04
Oct 5 12:00:00 - 12:59:59	1630	5.2%	0.45	4.4 MB	4.8%	1.25
Oct 5 13:00:00 - 13:59:59	1719	5.5%	0.48	5.5 MB	6.1%	1.58
Oct 5 14:00:00 - 14:59:59	1892	6.1%	0.53	5.1 MB	5.6%	1.45
Oct 5 15:00:00 - 15:59:59	2129	6.8%	0.59	5.3 MB	5.8%	1.51
Oct 5 16:00:00 - 16:59:59	2048	6.6%	0.57	5.4 MB	5.9%	1.53
Oct 5 17:00:00 - 17:59:59	2007	6.4%	0.56	5.7 MB	6.3%	1.63
Oct 5 18:00:00 - 18:59:59	1862	6.0%	0.52	4.8 MB	5.3%	1.37
Oct 5 19:00:00 - 19:59:59	1823	5.8%	0.51	5.2 MB	5.7%	1.49
Oct 5 20:00:00 - 20:59:59	1603	5.1%	0.45	4.6 MB	5.0%	1.31
Oct 5 21:00:00 - 21:59:59	1405	4.5%	0.39	4.1 MB	4.5%	1.16
Oct 5 22:00:00 - 22:59:59	1204	3.9%	0.33	3.7 MB	4.0%	1.04
Oct 5 23:00:00 - 23:59:59	863	2.8%	0.24	2.5 MB	2.8%	0.72
Oct 6 00:00:00 - 00:59:59	702	2.2%	0.20	2.4 MB	2.7%	0.70
Oct 6 01:00:00 - 01:59:59	680	2.2%	0.19	2.4 MB	2.6%	0.67
Oct 6 02:00:00 - 02:59:59	527	1.7%	0.15	2.0 MB	2.2%	0.57
Oct 6 03:00:00 - 03:59:59	613	2.0%	0.17	1.8 MB	2.0%	0.51
Oct 6 04:00:00 - 04:15:00	128	0.4%	0.14	406.6 KB	0.4%	0.45
TOTAL: 24:00:00	31258	100.0%	0.36	91.1 MB	100.0%	1.08

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	xanadu-bbs.net	9943	965	0	0	8978
2	newsfeed.neva.ru	4581	2002	1	3	2575
3	feeder.eternal-september.org	2701	372	1	0	2328
4	news-out.mixmin.net	2433	7	0	0	2426
5	au2pb.net	1163	1124	0	0	39
6	newsfeed.fu-berlin.de	675	79	0	0	596
7	newsfeeds.xmission.com	82	64	0	0	18
8	nntp.pasdenom.info	24	23	0	0	1
9	news.swapon.de	22	0	0	0	22
TOTAL: 9		21624	4636	2	3	16983

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	790
lada.talk	319
mailing.freebsd.svn-src-all	216
fa.freebsd.cvs-all	213
mailing.freebsd.svn-src-stable-10	143
mailing.freebsd.svn-src-stable	143
alt.politics	120
it-alt.sport.calcio.juventus	117
tin.it.sport.calcio	92
ut.test	90
alt.job	86
mailing.openbsd.source-changes	83
pld.cvs.commit	77
fa.openbsd.source-changes	75
free.it.calcio.juventus	75
alt.politics.scorched-earth	73
mailing.openbsd.misc	72
lada.auto	71
mailing.freebsd.svn-src-head	71
gmane.linux.alsa.devel	57
TOTAL: 276	4636

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

Groups	Count
uk.legal.moderated	2
it.scienza.astronomia	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10973
EMP (phn path)	3492
EMP (phl)	747
PFM bot misc	213
EMP (phn nph)	55
HTML Multipart	49
Too many newsgroups	49
HTML post	47
Binary Image: misplaced jpg	24
Too many newsgroups (low_xpost)	4
HTML Image Tags	1
UUencoded exe	1
TOTAL: 12	15655

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	538
	feeder.eternal-september.org	84
	news-out.mixmin.net	44
	au2pb.net	20
	newsfeeds.xmission.com	9
	newsfeed.fu-berlin.de	3
	news.swapon.de	1
	TOTAL: 7	699
Including strange strings
	newsfeed.fu-berlin.de	506
	news-out.mixmin.net	33
	feeder.eternal-september.org	31
	news.swapon.de	18
	xanadu-bbs.net	12
	newsfeed.neva.ru	11
	au2pb.net	11
	newsfeeds.xmission.com	6
	nntp.pasdenom.info	1
	TOTAL: 9	629
TOTAL: 2		1328

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	26362	393	24342	325	0	1%	23:59:47
2	usenet.pasdenom.info	38083	174	29978	972	7	0%	23:59:46
3	xanadu-bbs	27996	116	25836	35	0	0%	23:59:47
4	fu-berlin.de	28970	52	28887	31	0	0%	23:59:45
5	news.mixmin.net	27337	26	26384	16	0	0%	23:59:45
6	xmission	36990	22	29347	4	2	0%	23:59:47
7	neva.ru	29488	20	27704	57	0	0%	23:59:46
8	eternal-september	24015	6	22603	2	40	0%	23:59:47
9	news.swapon.de	2156	1	2136	4	0	0%	23:59:45
TOTAL: 9		241397	810	217217	1446	49	0%	215:57:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.6 MB	794.1 KB	2.4 MB	3.4 KB	23:59:47
2	usenet.pasdenom.info	811.5 KB	2.0 MB	2.8 MB	2.5 KB	23:59:46
3	xanadu-bbs	719.4 KB	64.0 KB	783.4 KB	5.2 KB	23:59:47
4	fu-berlin.de	277.6 KB	38.6 KB	316.2 KB	3.8 KB	23:59:45
5	news.mixmin.net	115.5 KB	37.3 KB	152.9 KB	3.6 KB	23:59:45
6	neva.ru	85.9 KB	310.5 KB	396.4 KB	5.1 KB	23:59:46
7	xmission	67.8 KB	7.4 KB	75.2 KB	2.9 KB	23:59:47
8	eternal-september	23.2 KB	3.2 KB	26.4 KB	3.3 KB	23:59:47
9	news.swapon.de	1.5 KB	7.4 KB	8.9 KB	1.8 KB	23:59:45
TOTAL: 9		3.6 MB	3.3 MB	6.9 MB	3.1 KB	215:57:55

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	202.89.176.220	288	63	158.2 KB	58	1	04:29:04
2	tempe0.bbox.io	14	8	15.1 KB	5	0	01:33:47
3	ip70-186-183-22.br.br.cox.net	1	1	2.8 KB	1	0	00:10:01
4	dhcp-089-099-203-254.chello.nl	4	1	2.5 KB	1	0	01:17:39
TOTAL: 4		307	73	178.6 KB	65	1	146:48:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	499	63	158.2 KB	58	1	138:09:25
2	*.bbox.io	14	8	15.1 KB	5	0	01:33:47
3	*.br.br.cox.net	1	1	2.8 KB	1	0	00:10:01
4	*.chello.nl	4	1	2.5 KB	1	0	01:17:39
5	*.tu.ok.cox.net	1	0	0.0 KB	0	0	01:35:42
6	*.ip-188-165-239.eu	2	0	0.0 KB	0	0	00:01:00
7	*.tampfl.dsl-w.verizon.net	7	0	0.0 KB	0	0	02:14:21
8	*.nielsenmedia.com	5	0	0.0 KB	0	0	01:46:42
TOTAL: 8		533	73	178.6 KB	65	1	146:48:41

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.695	0.820	0.000	65:50:41
108.175.241.207	10.843	3.470	0.000	45:35:27
71.94.21.184	1.972	0.604	0.000	22:11:37
202.89.176.220	26.751	5.814	0.719	04:29:04
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.407	0.063	0.000	02:14:21
challenger.nielsenmedia.com	0.407	0.092	0.000	01:46:42
ip72-222-107-224.tu.ok.cox.net	0.103	0.103	0.000	01:35:42
tempe0.bbox.io	1.243	0.226	0.000	01:33:47
dhcp-089-099-203-254.chello.nl	0.369	0.069	0.000	01:17:39
ip70-186-183-22.br.br.cox.net	0.132	0.025	0.000	00:10:01
173.0.15.167	0.536	0.829	0.000	00:02:32
ns342002.ip-188-165-239.eu	0.143	0.017	0.000	00:01:00
69.160.37.54	1.833	0.351	0.000	00:00:01
TOTAL: 13	45.434	12.483	0.719	146:48:41

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	157
69.160.37.54	25
71.94.21.184	24
pool-71-98-160-84.tampfl.dsl-w.verizon.net	7
challenger.nielsenmedia.com	5
162.212.88.198	4
ns342002.ip-188-165-239.eu	2
ip72-222-107-224.tu.ok.cox.net	1
173.0.15.167	1
TOTAL: 9	226

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	24
TOTAL: 1	24

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	24
TOTAL: 1	24

NNRP client timeouts [Top 20]:

System	Conn	Peer
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	0
tempe0.bbox.io	3	0
challenger.nielsenmedia.com	2	0
dhcp-089-099-203-254.chello.nl	2	0
ip70-186-183-22.br.br.cox.net	1	1
TOTAL: 5	12	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	70	95.9%
2	news	3	4.1%
TOTAL: 2		73	100.0%

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

Newsgroup		Count
1	comp.os.vms	30
2	comp.sys.ibm.ps2.hardware	10
3	comp.sys.ibm.pc.games.action	9
4	comp.arch.fpga	7
5	news.software.nntp	3
6	comp.os.linux.networking	3
7	comp.sys.ibm.pc.hardware.storage	3
8	comp.sys.ibm.pc.games.war-historical	2
9	comp.sys.ibm.as400.misc	2
10	comp.os.os2.programmer.misc	1
11	comp.os.msdos.misc	1
12	comp.sys.apple2	1
13	comp.sys.ibm.pc.games.strategic	1
TOTAL: 13		73

Daily Usenet report for csiph.com

Oct 5 04:15:00 -- Oct 6 04:15:00