Daily Usenet report for csiph.com: Sep 11 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18312	44.7%	4.7 MB	60.2%
innd	18130	44.2%	2.4 MB	30.8%
innfeed	3535	8.6%	608.8 KB	7.6%
nnrpd	1000	2.4%	109.8 KB	1.4%
nocem	27	0.1%	3.1 KB	0.0%
TOTAL: 5	41004	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	297054	53.9%
Negative hits	236335	42.9%
Do not exist	17681	3.2%
Cache misses	314	0.1%
TOTAL: 4	551384	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.249	0.0%	33	0.000	7.545	18.000
article cleanup	00:00:00.118	0.0%	17591	0.000	0.007	0.035
article logging	00:00:00.278	0.0%	18021	0.000	0.015	0.136
article parse	00:00:00.676	0.0%	50915	0.000	0.013	0.074
article write	00:00:09.904	0.0%	14871	0.180	0.666	21.460
artlog/artcncl	00:00:00.000	0.0%	33	0.000	0.000	0.000
artlog/artparse	00:00:00.004	0.0%	228	0.000	0.018	0.500
data move	00:00:00.155	0.0%	513331	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.024	0.0%	33	0.000	0.727	4.000
hishave/artcncl	00:00:00.008	0.0%	33	0.000	0.242	1.000
hishave/artparse	00:00:00.000	0.0%	225	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:07.687	0.0%	554944	0.002	0.014	0.095
history sync	00:00:00.707	0.0%	344	0.000	2.055	8.000
history write	00:00:13.409	0.0%	17594	0.094	0.762	2.845
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.187	0.0%	225	0.000	0.831	16.000
idle	23:57:24.787	99.8%	521583	96.817	165.352	413.335
nntp read	00:00:01.933	0.0%	522119	0.001	0.004	0.007
overview write	00:01:46.660	0.1%	14871	0.075	7.172	773.606
perl filter	00:00:25.088	0.0%	17590	1.110	1.426	3.585
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.224	0.0%	29769	0.000	0.008	0.026
TOTAL: 24:00:47.499	24:00:12.098	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	15341	0.000	0.003	0.032
article prepare	00:00:00.004	0.0%	17628	0.000	0.000	0.007
article read	00:00:00.302	0.0%	1771	0.000	0.171	2.227
backlog stats	00:00:00.201	0.0%	345736	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.234	0.0%	272833	0.001	0.005	0.009
data write	00:00:01.586	0.0%	259711	0.000	0.006	0.012
idle	23:54:03.484	100.0%	345736	145.150	248.870	639.112
status file	00:00:00.202	0.0%	278	0.000	0.727	10.500
TOTAL: 23:54:13.967	23:54:07.060	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	45:52:59.766	99.8%	672	296.417	245803.223	600157.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.811	0.0%	1563	0.000	0.519	25.156
readart	00:00:00.522	0.0%	53	0.000	9.849	42.000
TOTAL: 45:57:55.343	45:53:01.099	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	138
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	158

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.mixmin.net	2	15474	3112	12067	295	20%	24:08:36
2	feeder.eternal-september.org	4	16388	2013	14318	57	12%	24:31:10
3	newsfeeds.xmission.com	2	14020	1949	12018	53	13%	23:21:33
4	news-out.aioe.org	3	14377	1441	12868	68	10%	24:09:00
5	csiph-out.news.weretis.net	3	43724	1382	42085	257	3%	66:20:27
6	csiph-out.news.xs4all.nl	1188	16553	1340	14160	1053	8%	90:54:24
7	csiph-out.feeder.erje.net	1440	72830	930	71753	147	1%	118:48:02
8	newsfeed.fu-berlin.de	12	14185	776	13275	134	5%	25:06:35
9	newsfeed.neva.ru	142	9965	765	8811	389	7%	71:54:20
10	csiph-out.feed.uzoreto.com	864	23030	387	22219	424	1%	71:12:20
11	news.fcku.it	12	1669	315	1354	0	18%	21:37:47
12	news-out.neodome.net	1	15727	145	15576	6	0%	23:59:54
13	nntp.pasdenom.info	5	9690	115	9565	10	1%	23:55:46
14	csiph-out.news.tnetconsulting.net	2	15308	65	15234	9	0%	24:39:18
15	news.freedyn.net	2	17611	52	17494	65	0%	23:59:51
16	news.swapon.de	6	1158	42	1116	0	3%	23:12:13
17	news.samoylyk.net	2	14493	16	14475	2	0%	23:57:15
18	localhost	34	8	8	0	0	100%	00:00:05
19	news.bbs.nz	1	14681	6	14675	0	0%	23:59:52
20	xanadu-bbs.net	26	399	0	399	0	0%	20:55:03
TOTAL: 20		3751	331290	14859	313462	2969	4%	750:43:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	7.5 MB	0.0 KB	2.7 MB	10.2 MB	73%	5.2 KB
2	news-out.mixmin.net	6.1 MB	4.9 KB	798.7 KB	6.9 MB	88%	2.1 KB
3	feeder.eternal-september.org	5.3 MB	4.9 KB	395.3 KB	5.7 MB	93%	2.8 KB
4	news-out.aioe.org	5.2 MB	4.8 KB	190.3 KB	5.4 MB	96%	3.6 KB
5	csiph-out.feeder.erje.net	4.2 MB	0.0 KB	2.6 MB	6.9 MB	61%	6.5 KB
6	csiph-out.news.weretis.net	4.2 MB	9.8 KB	3.6 MB	7.8 MB	53%	4.9 KB
7	csiph-out.news.xs4all.nl	3.8 MB	0.0 KB	6.3 MB	10.1 MB	37%	4.3 KB
8	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	388.1 KB	2.3 MB	83%	2.6 KB
9	csiph-out.feed.uzoreto.com	1.9 MB	0.0 KB	8.1 MB	10.0 MB	19%	12.7 KB
10	newsfeed.neva.ru	1.8 MB	9.7 KB	1.1 MB	2.8 MB	62%	2.5 KB
11	news.fcku.it	545.8 KB	0.0 KB	0.0 KB	545.8 KB	100%	1.7 KB
12	news-out.neodome.net	444.8 KB	4.9 KB	14.2 KB	463.9 KB	95%	3.1 KB
13	news.freedyn.net	358.2 KB	4.9 KB	1.1 MB	1.5 MB	23%	13.1 KB
14	nntp.pasdenom.info	225.7 KB	0.0 KB	12.7 KB	238.4 KB	94%	1.9 KB
15	csiph-out.news.tnetconsulting.net	199.9 KB	4.9 KB	211.4 KB	416.2 KB	48%	5.6 KB
16	news.swapon.de	111.4 KB	0.0 KB	0.0 KB	111.4 KB	100%	2.7 KB
17	news.samoylyk.net	40.1 KB	4.9 KB	5.8 KB	50.9 KB	78%	2.8 KB
18	localhost	12.3 KB	0.0 KB	0.0 KB	12.3 KB	100%	1.5 KB
19	news.bbs.nz	9.2 KB	0.0 KB	0.0 KB	9.2 KB	100%	1.5 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		43.8 MB	53.6 KB	27.6 MB	71.4 MB	61%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 11 04:15:00 - 04:59:59	377	2.5%	0.14	1.3 MB	2.9%	0.48
Sep 11 05:00:00 - 05:59:59	399	2.7%	0.11	1.3 MB	3.0%	0.38
Sep 11 06:00:00 - 06:59:59	435	2.9%	0.12	1.3 MB	3.1%	0.38
Sep 11 07:00:00 - 07:59:59	643	4.3%	0.18	1.7 MB	4.0%	0.49
Sep 11 08:00:00 - 08:59:59	644	4.3%	0.18	1.8 MB	4.1%	0.51
Sep 11 09:00:00 - 09:59:59	659	4.4%	0.18	1.7 MB	3.9%	0.49
Sep 11 10:00:00 - 10:59:59	654	4.4%	0.18	1.9 MB	4.4%	0.55
Sep 11 11:00:00 - 11:59:59	689	4.6%	0.19	1.9 MB	4.4%	0.54
Sep 11 12:00:00 - 12:59:59	759	5.1%	0.21	2.2 MB	5.0%	0.62
Sep 11 13:00:00 - 13:59:59	895	6.0%	0.25	2.5 MB	5.7%	0.71
Sep 11 14:00:00 - 14:59:59	796	5.3%	0.22	2.3 MB	5.1%	0.64
Sep 11 15:00:00 - 15:59:59	783	5.3%	0.22	2.1 MB	4.9%	0.61
Sep 11 16:00:00 - 16:59:59	888	6.0%	0.25	2.5 MB	5.7%	0.71
Sep 11 17:00:00 - 17:59:59	816	5.5%	0.23	2.6 MB	5.9%	0.73
Sep 11 18:00:00 - 18:59:59	774	5.2%	0.21	2.2 MB	5.1%	0.64
Sep 11 19:00:00 - 19:59:59	817	5.5%	0.23	2.3 MB	5.2%	0.65
Sep 11 20:00:00 - 20:59:59	753	5.1%	0.21	2.6 MB	6.0%	0.75
Sep 11 21:00:00 - 21:59:59	597	4.0%	0.17	1.8 MB	4.0%	0.50
Sep 11 22:00:00 - 22:59:59	574	3.9%	0.16	1.8 MB	4.2%	0.52
Sep 11 23:00:00 - 23:59:59	435	2.9%	0.12	1.3 MB	2.9%	0.36
Sep 12 00:00:00 - 00:59:59	341	2.3%	0.09	1.0 MB	2.3%	0.29
Sep 12 01:00:00 - 01:59:59	365	2.4%	0.10	1.1 MB	2.5%	0.32
Sep 12 02:00:00 - 02:59:59	323	2.2%	0.09	992.8 KB	2.2%	0.28
Sep 12 03:00:00 - 03:59:59	333	2.2%	0.09	1.1 MB	2.6%	0.32
Sep 12 04:00:00 - 04:15:00	158	1.1%	0.18	402.4 KB	0.9%	0.45
TOTAL: 24:00:00	14907	100.0%	0.17	43.8 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	1065	44	1021
2	csiph-out.feed.uzoreto.com	435	21	414
3	newsfeed.fu-berlin.de	414	35	379
4	newsfeed.neva.ru	389	75	314
5	news-out.mixmin.net	372	1	371
6	csiph-out.news.weretis.net	234	76	158
7	csiph-out.feeder.erje.net	146	50	96
8	news-out.aioe.org	98	62	36
9	newsfeeds.xmission.com	68	3	65
10	news.freedyn.net	66	0	66
11	feeder.eternal-september.org	57	17	40
12	nntp.pasdenom.info	10	0	10
13	news.swapon.de	9	0	9
14	news-out.neodome.net	6	2	4
15	news.samoylyk.net	3	0	3
16	csiph-out.news.tnetconsulting.net	1	0	1
TOTAL: 16		3373	386	2987

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	48
alt.tv.comedy-central.daily-show	31
scinet.stats	28
spline.eisfair	26
alt.bbs.synchronet	23
alt.philosophy	18
mozilla.support.thunderbird	18
alt.politics.usa	12
lada.auto	10
alt.survival	10
alt.job	9
mozilla.dev.platform	8
att.jobs	8
it-alt.sport.calcio.juventus	7
soc.culture.germany	6
at.jobs	6
mozilla.support.firefox	6
alt.math.undergrad	6
oesterreich.tratsch	6
alt.politics	5
TOTAL: 72	386

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1728
EMP (phl)	224
EMP (phn path)	196
EMP (phn nph)	62
Too many newsgroups	57
0	36
Binary: misplaced binary	10
Scoring filter	5
HTML Multipart	4
U2 violation - invalid distribution	1
Too many newsgroups (meow)	1
TOTAL: 11	2324

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	65
	csiph-out.feed.uzoreto.com	5
	csiph-out.news.xs4all.nl	4
	TOTAL: 3	74
Huge articles
	csiph-out.feed.uzoreto.com	76
	csiph-out.feeder.erje.net	43
	newsfeeds.xmission.com	42
	csiph-out.news.xs4all.nl	27
	csiph-out.news.weretis.net	19
	news.freedyn.net	14
	news-out.mixmin.net	4
	feeder.eternal-september.org	2
	csiph-out.news.tnetconsulting.net	1
	TOTAL: 9	228
Including strange strings
	newsfeed.fu-berlin.de	283
	news-out.mixmin.net	80
	news-out.aioe.org	29
	newsfeeds.xmission.com	12
	news.swapon.de	9
	csiph-out.news.xs4all.nl	7
	csiph-out.news.weretis.net	5
	csiph-out.feed.uzoreto.com	3
	csiph-out.feeder.erje.net	2
	newsfeed.neva.ru	1
	TOTAL: 11	432
No colon-space in header
	csiph-out.news.xs4all.nl	3
	TOTAL: 1	3
TOTAL: 4		737

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17346	1125	13765	16	47	6%	23:59:55
2	news.bbs.nz	27623	1109	13389	15	2	4%	23:59:55
3	tnet	16830	434	14081	3	202	2%	23:59:55
4	fu-berlin.de	13974	389	13585	0	0	2%	23:59:06
5	news.samoylyk.net	15229	378	14508	5	63	2%	23:59:55
6	neva.ru	13632	134	13324	100	0	0%	23:59:55
7	erje	15956	81	13460	299	0	0%	23:59:55
8	newsfeed.xs4all.nl	13960	50	13415	7	167	0%	23:59:54
9	news.freedyn.net	14802	41	14479	0	0	0%	23:59:55
10	aioe	10428	19	10041	0	0	0%	23:59:54
11	usenet.pasdenom.info	14860	17	14393	5	42	0%	23:59:55
12	news.uzoreto.com	14214	17	13900	106	0	0%	23:59:37
13	news.mixmin.net	11516	13	11234	0	0	0%	23:59:55
14	news.swapon.de	1255	12	1237	0	0	0%	23:52:07
15	xmission	13827	12	12273	0	0	0%	23:59:55
16	neodome.net	15003	10	14682	0	0	0%	23:59:48
17	eternal-september	12742	10	12384	7	0	0%	23:59:55
18	weretis.net	13349	8	13178	0	0	0%	23:59:55
19	news.fcku.it	1305	0	1304	0	0	0%	20:56:37
TOTAL: 19		257851	3859	228632	563	523	1%	452:46:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.6 MB	129.4 KB	5.7 MB	0.1 KB/s	5.1 KB	23:59:55
2	news.bbs.nz	3.3 MB	54.2 KB	3.4 MB	0.0 KB/s	3.1 KB	23:59:55
3	fu-berlin.de	2.8 MB	0.0 KB	2.8 MB	0.0 KB/s	7.4 KB	23:59:06
4	news.samoylyk.net	2.8 MB	5.4 KB	2.8 MB	0.0 KB/s	7.5 KB	23:59:55
5	tnet	1.4 MB	6.3 KB	1.4 MB	0.0 KB/s	3.3 KB	23:59:55
6	neva.ru	321.1 KB	270.6 KB	591.7 KB	0.0 KB/s	2.5 KB	23:59:55
7	news.freedyn.net	235.6 KB	0.0 KB	235.6 KB	0.0 KB/s	5.7 KB	23:59:55
8	erje	160.5 KB	1.5 MB	1.6 MB	0.0 KB/s	4.3 KB	23:59:55
9	newsfeed.xs4all.nl	130.0 KB	18.1 KB	148.1 KB	0.0 KB/s	2.6 KB	23:59:54
10	usenet.pasdenom.info	49.0 KB	11.8 KB	60.7 KB	0.0 KB/s	2.8 KB	23:59:55
11	xmission	48.5 KB	0.0 KB	48.5 KB	0.0 KB/s	4.0 KB	23:59:55
12	aioe	36.4 KB	0.0 KB	36.4 KB	0.0 KB/s	1.9 KB	23:59:54
13	news.uzoreto.com	27.4 KB	248.8 KB	276.2 KB	0.0 KB/s	2.2 KB	23:59:37
14	news.mixmin.net	22.4 KB	0.0 KB	22.4 KB	0.0 KB/s	1.7 KB	23:59:55
15	eternal-september	20.8 KB	35.6 KB	56.4 KB	0.0 KB/s	3.3 KB	23:59:55
16	news.swapon.de	20.0 KB	0.0 KB	20.0 KB	0.0 KB/s	1.7 KB	23:52:07
17	neodome.net	14.0 KB	0.0 KB	14.0 KB	0.0 KB/s	1.4 KB	23:59:48
18	weretis.net	12.4 KB	0.0 KB	12.4 KB	0.0 KB/s	1.5 KB	23:59:55
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:56:37
TOTAL: 19		17.0 MB	2.2 MB	19.2 MB	0.0 KB/s	4.4 KB	452:46:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	024-178-047-152.res.spectrum.com	39	20	36.4 KB	11	8	8	09:33:23
2	ip72-222-200-117.ph.ph.cox.net	6	18	32.5 KB	4	0	0	01:51:41
3	a131033.upc-a.chello.nl	37	15	28.4 KB	5	0	0	10:01:55
TOTAL: 3		82	53	97.3 KB	20	8	8	45:57:54

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	39	20	36.4 KB	11	8	8	09:33:23
2	*.ph.ph.cox.net	6	18	32.5 KB	4	0	0	01:51:41
3	*.upc-a.chello.nl	37	15	28.4 KB	5	0	0	10:01:55
4	*.dip0.t-ipconnect.de	1	0	0.0 KB	0	0	0	00:00:05
5	unresolved	72	0	0.0 KB	0	0	0	24:00:46
TOTAL: 5		155	53	97.3 KB	20	8	8	45:57:54

NNRP auth users [Top 20]:

User	Conn
ohlandl	8
TOTAL: 1	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	5.015	1.100	0.000	24:00:46
a131033.upc-a.chello.nl	2.535	0.647	0.000	10:01:55
024-178-047-152.res.spectrum.com	3.083	0.506	0.018	09:33:23
ip72-222-200-117.ph.ph.cox.net	0.596	0.078	0.000	01:51:41
c-73-140-175-55.hsd1.wa.comcast.net	0.050	0.033	0.000	00:30:00
p2e5b5dc6.dip0.t-ipconnect.de	0.115	0.049	0.000	00:00:05
TOTAL: 6	11.394	2.413	0.018	45:57:54

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
p2e5b5dc6.dip0.t-ipconnect.de	1
TOTAL: 2	73

NNRP client timeouts [Top 20]:

System	Conn	Peer
024-178-047-152.res.spectrum.com	11	0
ip72-222-200-117.ph.ph.cox.net	3	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 3	15	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	48
2	comp.unix.bsd.freebsd.misc	3
3	comp.arch	2
TOTAL: 3		53

Daily Usenet report for csiph.com

Sep 11 04:15:00 -- Sep 12 04:15:00