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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	60293	49.0%	8.3 MB	59.4%
nnrpd	32246	26.2%	2.7 MB	19.1%
innd	28636	23.3%	2.8 MB	19.6%
innfeed	1916	1.6%	281.5 KB	2.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	123092	100.0%	14.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	287752	45.7%
Negative hits	282999	44.9%
Do not exist	59195	9.4%
Cache misses	163	0.0%
TOTAL: 4	630109	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.701	0.0%	199	0.000	3.523	30.500
article cleanup	00:00:00.357	0.0%	58831	0.000	0.006	0.029
article logging	00:00:00.751	0.0%	59409	0.000	0.013	0.040
article parse	00:00:02.908	0.0%	310506	0.004	0.009	0.020
article write	00:00:13.363	0.0%	28609	0.170	0.467	1.316
artlog/artcncl	00:00:00.002	0.0%	174	0.000	0.011	0.500
artlog/artparse	00:00:00.010	0.0%	814	0.000	0.012	0.500
data move	00:00:00.334	0.0%	790361	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.005	0.0%	198	0.000	0.025	1.000
hishave/artcncl	00:00:00.000	0.0%	199	0.000	0.000	0.000
hishave/artparse	00:00:00.003	0.0%	814	0.000	0.004	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.897	0.0%	633237	0.000	0.003	0.007
history sync	00:00:00.601	0.0%	304	0.000	1.977	4.000
history write	00:00:18.259	0.0%	58809	0.047	0.310	0.428
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.298	0.0%	813	0.000	0.367	1.500
idle	23:56:39.264	99.7%	769432	33.733	112.030	365.205
nntp read	00:00:02.529	0.0%	772522	0.002	0.003	0.007
overview write	00:00:12.068	0.0%	28609	0.104	0.422	1.675
perl filter	00:02:18.865	0.2%	58809	1.055	2.361	4.280
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.453	0.0%	57218	0.000	0.008	0.030
TOTAL: 24:00:29.601	23:59:52.668	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.087	0.0%	28711	0.000	0.003	0.022
article prepare	00:00:00.003	0.0%	6276	0.000	0.000	0.023
article read	00:00:00.214	0.0%	1254	0.000	0.171	11.250
backlog stats	00:00:00.110	0.0%	315472	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.347	0.0%	244072	0.001	0.006	0.011
data write	00:00:01.652	0.0%	220167	0.001	0.008	0.016
idle	23:53:55.661	100.0%	315472	163.483	272.720	681.669
status file	00:00:00.099	0.0%	210	0.000	0.471	2.000
TOTAL: 23:54:04.364	23:53:59.173	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.622	0.0%	22021	0.000	0.028	0.060
idle	85:19:48.135	99.9%	53498	0.000	5742.049	601081.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.669	0.0%	107955	0.000	0.006	0.143
readart	00:00:00.263	0.0%	74	0.000	3.554	72.000
TOTAL: 85:26:56.130	85:19:49.689	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	feeder.eternal-september.org	1	54136	8665	36713	8758	16%	24:00:18
2	au2pb.net	0	31587	6119	24554	914	19%	24:05:23
3	xanadu-bbs.net	1	60027	4273	47998	7756	7%	24:31:19
4	newsfeed.neva.ru	144	72762	2834	59017	10911	3%	72:14:28
5	news-out.mixmin.net	6	35498	2590	30941	1967	7%	24:22:24
6	newsfeeds.xmission.com	1	30012	2233	27615	164	7%	24:55:47
7	newsfeed.fu-berlin.de	58	34526	1354	32741	431	3%	34:53:34
8	news.swapon.de	2	2671	340	2309	22	12%	25:08:45
9	nntp.pasdenom.info	8	29429	330	28975	124	1%	24:20:28
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		247	350648	28738	290863	31047	8%	278:32:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	24.1 MB	66.5 KB	78.4 MB	102.6 MB	23%	6.0 KB
2	au2pb.net	17.2 MB	47.5 KB	5.7 MB	23.0 MB	74%	3.3 KB
3	xanadu-bbs.net	13.1 MB	6.8 KB	159.2 MB	172.4 MB	7%	14.7 KB
4	newsfeed.neva.ru	9.2 MB	20.3 KB	99.7 MB	108.9 MB	8%	8.1 KB
5	news-out.mixmin.net	8.8 MB	47.6 KB	4.1 MB	13.0 MB	67%	2.9 KB
6	newsfeeds.xmission.com	6.5 MB	1.0 KB	2.4 MB	9.0 MB	72%	3.8 KB
7	newsfeed.fu-berlin.de	3.1 MB	0.0 KB	1.1 MB	4.2 MB	73%	2.4 KB
8	news.swapon.de	886.8 KB	0.0 KB	998.6 KB	1.8 MB	47%	5.2 KB
9	nntp.pasdenom.info	728.2 KB	5.7 KB	1.0 MB	1.7 MB	41%	3.9 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		83.7 MB	195.5 KB	352.8 MB	436.7 MB	19%	7.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 5 04:15:00 - 04:59:59	403	1.4%	0.15	1.3 MB	1.6%	0.49
Nov 5 05:00:00 - 05:59:59	679	2.4%	0.19	3.0 MB	3.6%	0.85
Nov 5 06:00:00 - 06:59:59	646	2.3%	0.18	2.1 MB	2.5%	0.59
Nov 5 07:00:00 - 07:59:59	903	3.2%	0.25	2.8 MB	3.4%	0.80
Nov 5 08:00:00 - 08:59:59	1182	4.1%	0.33	3.1 MB	3.7%	0.88
Nov 5 09:00:00 - 09:59:59	1377	4.8%	0.38	4.2 MB	5.0%	1.19
Nov 5 10:00:00 - 10:59:59	1512	5.3%	0.42	4.2 MB	5.1%	1.20
Nov 5 11:00:00 - 11:59:59	1283	4.5%	0.36	3.6 MB	4.3%	1.01
Nov 5 12:00:00 - 12:59:59	1240	4.3%	0.34	3.2 MB	3.8%	0.91
Nov 5 13:00:00 - 13:59:59	1359	4.8%	0.38	3.9 MB	4.6%	1.10
Nov 5 14:00:00 - 14:59:59	1654	5.8%	0.46	4.4 MB	5.2%	1.24
Nov 5 15:00:00 - 15:59:59	1574	5.5%	0.44	4.0 MB	4.8%	1.13
Nov 5 16:00:00 - 16:59:59	1764	6.2%	0.49	4.9 MB	5.9%	1.39
Nov 5 17:00:00 - 17:59:59	1720	6.0%	0.48	5.0 MB	6.0%	1.42
Nov 5 18:00:00 - 18:59:59	1543	5.4%	0.43	4.4 MB	5.2%	1.24
Nov 5 19:00:00 - 19:59:59	1466	5.1%	0.41	4.4 MB	5.3%	1.27
Nov 5 20:00:00 - 20:59:59	1501	5.3%	0.42	3.8 MB	4.6%	1.08
Nov 5 21:00:00 - 21:59:59	1534	5.4%	0.43	4.5 MB	5.4%	1.28
Nov 5 22:00:00 - 22:59:59	1371	4.8%	0.38	3.8 MB	4.5%	1.07
Nov 5 23:00:00 - 23:59:59	1131	4.0%	0.31	3.7 MB	4.5%	1.06
Nov 6 00:00:00 - 00:59:59	807	2.8%	0.22	2.4 MB	2.9%	0.69
Nov 6 01:00:00 - 01:59:59	700	2.5%	0.19	2.5 MB	3.0%	0.70
Nov 6 02:00:00 - 02:59:59	574	2.0%	0.16	1.9 MB	2.3%	0.55
Nov 6 03:00:00 - 03:59:59	488	1.7%	0.14	1.8 MB	2.2%	0.51
Nov 6 04:00:00 - 04:15:00	153	0.5%	0.17	713.5 KB	0.8%	0.79
TOTAL: 24:00:00	28564	100.0%	0.33	83.5 MB	100.0%	0.99

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	10891	1698	6	9187
2	feeder.eternal-september.org	8896	389	6	8501
3	xanadu-bbs.net	7735	752	2	6981
4	news-out.mixmin.net	1978	6	3	1969
5	au2pb.net	921	850	3	68
6	newsfeed.fu-berlin.de	782	40	0	742
7	newsfeeds.xmission.com	177	139	1	37
8	nntp.pasdenom.info	124	109	1	14
9	news.swapon.de	51	0	0	51
TOTAL: 9		31555	3983	22	27550

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	828
lada.talk	227
ut.test	177
alt.politics.scorched-earth	166
lada.auto	132
pld.cvs.commit	100
it-alt.sport.calcio.juventus	92
mailing.freebsd.pkg-fallout	87
gmane.linux.alsa.devel	83
mailing.freebsd.ports-bugs	77
mailing.openbsd.source-changes	73
fa.freebsd.cvs-all	71
mailing.freebsd.svn-src-all	71
alt.politics	70
mailing.freebsd.svn-ports-all	65
mailing.freebsd.svn-ports-head	63
mailing.freebsd.svn-src-head	62
mailing.openbsd.tech	56
fa.openbsd.tech	55
fa.openbsd.source-changes	54
TOTAL: 278	3983

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	20847
EMP (phn path)	3911
EMP (phl)	858
PFM bot misc	210
EMP (phn nph)	143
HTML Multipart	80
Too many newsgroups	71
Binary Image: misplaced jpg	26
HTML Image Tags	6
User-issued spam cancel	3
HTML post	2
Too many newsgroups (low_xpost)	1
TOTAL: 12	26158

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	700
	feeder.eternal-september.org	37
	au2pb.net	34
	newsfeeds.xmission.com	19
	news-out.mixmin.net	12
	nntp.pasdenom.info	8
	newsfeed.fu-berlin.de	3
	news.swapon.de	2
	TOTAL: 8	815
Including strange strings
	newsfeed.fu-berlin.de	378
	xanadu-bbs.net	100
	news.swapon.de	29
	feeder.eternal-september.org	29
	newsfeeds.xmission.com	15
	news-out.mixmin.net	10
	newsfeed.neva.ru	10
	au2pb.net	6
	TOTAL: 8	577
TOTAL: 2		1392

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	23437	235	22056	167	0	1%	23:59:57
2	usenet.pasdenom.info	33258	164	27384	686	32	0%	23:59:57
3	xanadu-bbs	26338	97	24197	43	26	0%	23:59:57
4	news.mixmin.net	27629	31	25890	0	129	0%	23:59:57
5	fu-berlin.de	26882	28	26826	28	0	0%	23:59:34
6	xmission	33505	26	26067	4	0	0%	23:59:46
7	eternal-september	20582	10	19367	6	0	0%	23:59:57
8	neva.ru	26739	1	25555	43	0	0%	23:59:50
9	news.swapon.de	2202	1	2184	3	0	0%	23:57:10
TOTAL: 9		220572	593	199526	980	187	0%	215:56:05

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1020.5 KB	438.7 KB	1.4 MB	3.6 KB	23:59:57
2	usenet.pasdenom.info	632.3 KB	1.6 MB	2.2 MB	2.7 KB	23:59:57
3	xanadu-bbs	631.4 KB	80.7 KB	712.1 KB	5.1 KB	23:59:57
4	fu-berlin.de	135.4 KB	34.6 KB	170.1 KB	3.0 KB	23:59:34
5	news.mixmin.net	133.7 KB	0.0 KB	133.7 KB	4.3 KB	23:59:57
6	xmission	53.5 KB	25.1 KB	78.6 KB	2.6 KB	23:59:46
7	eternal-september	30.3 KB	10.0 KB	40.3 KB	2.5 KB	23:59:57
8	neva.ru	23.2 KB	281.0 KB	304.2 KB	6.9 KB	23:59:50
9	news.swapon.de	2.9 KB	4.1 KB	7.0 KB	1.8 KB	23:57:10
TOTAL: 9		2.6 MB	2.4 MB	5.0 MB	3.3 KB	215:56:05

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	52	110.8 KB	49	04:34:42
2	challenger.nielsenmedia.com	16	9	22.1 KB	5	03:39:43
3	tempe0.bbox.io	4	8	16.0 KB	3	02:00:26
4	pool-71-98-160-84.tampfl.dsl-w.verizon.net	7	4	8.5 KB	2	01:29:00
5	dhcp-089-099-203-254.chello.nl	20	1	1.4 KB	1	04:10:56
TOTAL: 5		335	74	158.8 KB	60	85:26:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	382	52	110.8 KB	49	72:06:14
2	*.nielsenmedia.com	16	9	22.1 KB	5	03:39:43
3	*.bbox.io	4	8	16.0 KB	3	02:00:26
4	*.tampfl.dsl-w.verizon.net	7	4	8.5 KB	2	01:29:00
5	*.chello.nl	20	1	1.4 KB	1	04:10:56
6	*.147.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	02:00:30
TOTAL: 6		431	74	158.8 KB	60	85:26:51

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
63.142.120.8	6.150	1.672	0.000	45:31:31
162.212.88.214	0.407	0.369	0.000	21:59:57
202.89.176.220	28.351	5.706	0.739	04:34:42
dhcp-089-099-203-254.chello.nl	1.742	0.274	0.000	04:10:56
challenger.nielsenmedia.com	1.540	0.238	0.000	03:39:43
h168.147.22.98.dynamic.ip.windstream.net	0.159	0.081	0.000	02:00:30
tempe0.bbox.io	0.483	0.063	0.000	02:00:26
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.767	0.120	0.000	01:29:00
69.160.37.54	1.987	0.433	0.000	00:00:02
TOTAL: 9	41.586	8.956	0.739	85:26:51

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	65
69.160.37.54	25
162.212.88.214	4
h168.147.22.98.dynamic.ip.windstream.net	2
TOTAL: 4	96

NNRP client timeouts [Top 20]:

System	Conn	Peer
challenger.nielsenmedia.com	7	0
h168.147.22.98.dynamic.ip.windstream.net	2	0
tempe0.bbox.io	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 4	12	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	72	97.3%
2	news	2	2.7%
TOTAL: 2		74	100.0%

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

Newsgroup		Count
1	comp.lang.awk	13
2	comp.os.vms	12
3	comp.sys.ibm.pc.games.war-historical	10
4	comp.arch.embedded	8
5	comp.sys.ibm.pc.hardware.storage	7
6	comp.unix.bsd.freebsd.misc	5
7	comp.ai.neural-nets	4
8	comp.sys.ibm.ps2.hardware	3
9	comp.os.os2.apps	3
10	comp.os.linux.networking	3
11	news.software.nntp	2
12	comp.arch	1
13	comp.sys.ibm.pc.games.action	1
14	comp.os.os2.misc	1
15	comp.ai.philosophy	1
TOTAL: 15		74

Daily Usenet report for csiph.com

Nov 5 04:15:00 -- Nov 6 04:15:00