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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	19519	45.8%	2.5 MB	32.0%
inn	18571	43.5%	4.6 MB	58.9%
innfeed	3544	8.3%	619.7 KB	7.7%
nnrpd	990	2.3%	111.6 KB	1.4%
nocem	28	0.1%	3.3 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	42653	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	298942	56.4%
Negative hits	213189	40.2%
Do not exist	17989	3.4%
Cache misses	386	0.1%
TOTAL: 4	530506	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.590	0.0%	22	0.000	26.818	42.000
article cleanup	00:00:00.114	0.0%	17842	0.000	0.006	0.056
article logging	00:00:00.694	0.0%	18381	0.000	0.038	0.937
article parse	00:00:01.118	0.0%	50411	0.000	0.022	0.267
article write	00:01:03.350	0.1%	14349	0.441	4.415	87.191
artlog/artcncl	00:00:00.001	0.0%	22	0.000	0.045	1.000
artlog/artparse	00:00:00.007	0.0%	188	0.000	0.037	6.000
data move	00:00:00.160	0.0%	500748	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.083	0.0%	22	0.000	3.773	20.000
hishave/artcncl	00:00:00.012	0.0%	22	0.000	0.545	4.000
hishave/artparse	00:00:00.000	0.0%	182	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:41.719	0.0%	533408	0.002	0.078	0.277
history sync	00:00:01.111	0.0%	388	0.000	2.863	41.000
history write	00:00:55.035	0.1%	17810	0.487	3.090	12.485
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.660	0.0%	182	0.000	3.626	24.333
idle	23:55:27.849	99.6%	500706	98.566	172.013	506.126
nntp read	00:00:01.979	0.0%	508661	0.001	0.004	0.009
overview write	00:01:48.251	0.1%	14349	0.198	7.544	191.018
perl filter	00:00:24.655	0.0%	17804	1.092	1.385	2.879
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.233	0.0%	28725	0.000	0.008	0.028
TOTAL: 24:01:03.142	24:00:27.621	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.029	0.0%	15111	0.000	0.002	0.022
article prepare	00:00:00.003	0.0%	13616	0.000	0.000	0.014
article read	00:00:00.297	0.0%	1417	0.000	0.210	15.000
backlog stats	00:00:00.220	0.0%	333847	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.210	0.0%	260585	0.001	0.005	0.010
data write	00:00:01.513	0.0%	247708	0.000	0.006	0.013
idle	23:54:26.424	100.0%	333847	123.156	257.802	773.402
status file	00:00:00.222	0.0%	289	0.000	0.768	9.000
TOTAL: 23:54:38.076	23:54:29.918	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	42:43:51.624	99.9%	740	441.571	207880.573	600125.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	1751	0.000	0.005	0.167
readart	00:00:00.522	0.0%	80	0.000	6.525	42.000
TOTAL: 42:47:11.206	42:43:52.155	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	13827	3469	10318	40	25%	24:07:22
2	news-out.mixmin.net	2	15880	2448	13023	409	15%	23:59:24
3	feeder.eternal-september.org	5	17058	2164	14778	116	12%	25:31:42
4	csiph-out.news.xs4all.nl	1158	15470	1540	12701	1229	9%	63:43:13
5	news-out.aioe.org	2	13882	1340	12453	89	9%	23:59:53
6	newsfeed.fu-berlin.de	6	13934	906	12827	201	6%	24:03:23
7	csiph-out.news.weretis.net	4	27224	664	26278	282	2%	42:59:55
8	csiph-out.feeder.erje.net	1440	73410	658	72528	224	0%	118:47:59
9	newsfeed.neva.ru	150	14527	408	13745	374	2%	71:20:23
10	news-out.neodome.net	2	16233	200	15867	166	1%	23:59:45
11	news.fcku.it	13	1219	194	1022	3	15%	22:11:15
12	csiph-out.feed.uzoreto.com	864	22967	148	22500	319	0%	71:12:10
13	nntp.pasdenom.info	2	9017	100	8889	28	1%	23:58:44
14	news.swapon.de	8	1371	50	1321	0	3%	23:44:16
15	news.freedyn.net	2	17998	41	17883	74	0%	23:59:56
16	csiph-out.news.tnetconsulting.net	1	15471	18	15428	25	0%	23:59:53
17	localhost	38	12	12	0	0	100%	00:00:06
18	news.samoylyk.net	3	13930	5	13924	1	0%	23:19:30
19	news.bbs.nz	1	14936	3	14933	0	0%	23:59:52
20	xanadu-bbs.net	42	295	0	295	0	0%	19:57:39
TOTAL: 20		3746	318661	14368	300713	3580	4%	698:56:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.3 MB	0.0 KB	2.2 MB	13.4 MB	83%	3.9 KB
2	feeder.eternal-september.org	5.5 MB	1.6 KB	527.3 KB	6.0 MB	91%	2.7 KB
3	news-out.mixmin.net	5.1 MB	4.9 KB	957.4 KB	6.0 MB	84%	2.2 KB
4	news-out.aioe.org	4.5 MB	4.8 KB	245.8 KB	4.7 MB	94%	3.4 KB
5	csiph-out.feeder.erje.net	4.1 MB	11.5 KB	4.5 MB	8.6 MB	47%	10.0 KB
6	csiph-out.news.xs4all.nl	4.0 MB	0.0 KB	6.8 MB	10.9 MB	37%	4.0 KB
7	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	355.2 KB	2.5 MB	86%	2.3 KB
8	csiph-out.news.weretis.net	1.6 MB	7.2 KB	800.7 KB	2.4 MB	66%	2.6 KB
9	newsfeed.neva.ru	1.2 MB	13.0 KB	873.8 KB	2.0 MB	57%	2.7 KB
10	csiph-out.feed.uzoreto.com	596.6 KB	2.6 KB	5.9 MB	6.5 MB	8%	14.2 KB
11	news-out.neodome.net	527.0 KB	3.9 KB	366.8 KB	897.7 KB	58%	2.5 KB
12	news.fcku.it	340.8 KB	1.1 KB	2.9 KB	344.8 KB	98%	1.8 KB
13	news.freedyn.net	309.2 KB	4.9 KB	1.5 MB	1.8 MB	17%	15.7 KB
14	nntp.pasdenom.info	233.0 KB	0.0 KB	35.4 KB	268.4 KB	86%	2.1 KB
15	news.swapon.de	120.7 KB	0.0 KB	0.0 KB	120.7 KB	100%	2.4 KB
16	csiph-out.news.tnetconsulting.net	79.1 KB	0.0 KB	203.2 KB	282.3 KB	28%	6.6 KB
17	localhost	16.9 KB	0.0 KB	0.0 KB	16.9 KB	100%	1.4 KB
18	news.samoylyk.net	13.6 KB	2.1 KB	2.1 KB	17.9 KB	75%	3.0 KB
19	news.bbs.nz	3.1 KB	0.0 KB	0.0 KB	3.1 KB	100%	1.0 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		41.7 MB	57.7 KB	25.1 MB	66.8 MB	62%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 3 04:15:00 - 04:59:59	216	1.5%	0.08	667.4 KB	1.6%	0.25
Oct 3 05:00:00 - 05:59:59	328	2.3%	0.09	829.2 KB	2.0%	0.23
Oct 3 06:00:00 - 06:59:59	434	3.0%	0.12	1.1 MB	2.6%	0.31
Oct 3 07:00:00 - 07:59:59	558	3.9%	0.15	2.1 MB	5.1%	0.60
Oct 3 08:00:00 - 08:59:59	676	4.7%	0.19	1.9 MB	4.7%	0.55
Oct 3 09:00:00 - 09:59:59	700	4.9%	0.19	1.9 MB	4.6%	0.55
Oct 3 10:00:00 - 10:59:59	653	4.6%	0.18	1.8 MB	4.4%	0.52
Oct 3 11:00:00 - 11:59:59	683	4.8%	0.19	1.8 MB	4.5%	0.53
Oct 3 12:00:00 - 12:59:59	762	5.3%	0.21	2.0 MB	4.9%	0.58
Oct 3 13:00:00 - 13:59:59	724	5.1%	0.20	1.8 MB	4.5%	0.52
Oct 3 14:00:00 - 14:59:59	756	5.3%	0.21	2.6 MB	6.3%	0.74
Oct 3 15:00:00 - 15:59:59	807	5.6%	0.22	2.4 MB	5.9%	0.69
Oct 3 16:00:00 - 16:59:59	795	5.5%	0.22	2.1 MB	5.0%	0.58
Oct 3 17:00:00 - 17:59:59	826	5.8%	0.23	2.0 MB	4.9%	0.57
Oct 3 18:00:00 - 18:59:59	716	5.0%	0.20	1.9 MB	4.5%	0.53
Oct 3 19:00:00 - 19:59:59	755	5.3%	0.21	2.6 MB	6.2%	0.73
Oct 3 20:00:00 - 20:59:59	632	4.4%	0.18	1.7 MB	4.2%	0.50
Oct 3 21:00:00 - 21:59:59	712	5.0%	0.20	2.3 MB	5.5%	0.65
Oct 3 22:00:00 - 22:59:59	585	4.1%	0.16	1.9 MB	4.7%	0.55
Oct 3 23:00:00 - 23:59:59	468	3.3%	0.13	1.4 MB	3.3%	0.39
Oct 4 00:00:00 - 00:59:59	389	2.7%	0.11	1018.3 KB	2.4%	0.28
Oct 4 01:00:00 - 01:59:59	494	3.4%	0.14	1.3 MB	3.1%	0.36
Oct 4 02:00:00 - 02:59:59	310	2.2%	0.09	1.1 MB	2.7%	0.32
Oct 4 03:00:00 - 03:59:59	267	1.9%	0.07	807.3 KB	1.9%	0.22
Oct 4 04:00:00 - 04:15:00	88	0.6%	0.10	249.5 KB	0.6%	0.28
TOTAL: 24:00:00	14334	100.0%	0.17	41.4 MB	100.0%	0.49

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1246	68	0	1178
2	newsfeed.fu-berlin.de	591	34	0	557
3	news-out.mixmin.net	486	18	3	465
4	csiph-out.news.weretis.net	401	30	4	367
5	newsfeed.neva.ru	374	68	8	298
6	csiph-out.feed.uzoreto.com	321	9	2	310
7	csiph-out.feeder.erje.net	227	37	7	183
8	news-out.neodome.net	167	5	2	160
9	feeder.eternal-september.org	114	39	1	74
10	news-out.aioe.org	93	61	3	29
11	news.freedyn.net	72	1	3	68
12	newsfeeds.xmission.com	48	8	0	40
13	nntp.pasdenom.info	28	0	0	28
14	csiph-out.news.tnetconsulting.net	25	21	3	1
15	news.swapon.de	18	0	0	18
16	news.bbs.nz	3	0	0	3
17	news.fcku.it	3	2	1	0
18	news.samoylyk.net	1	0	1	0
TOTAL: 18		4218	401	38	3779

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	65
alt.computer.workshop	53
spline.eisfair	34
alt.bbs.synchronet	26
scinet.stats	25
alt.politics	17
it-alt.sport.calcio.juventus	15
mozilla.support.seamonkey	14
alt.survival	13
alt.politics.usa	12
alt.binaries.pictures.purebeauty	9
mozilla.support.thunderbird	9
alt.windows7.general	8
alt.math.undergrad	8
ba.forsale	8
alt.binaries.pictures.models.petite	7
mozilla.support.firefox	6
at.jobs	6
oesterreich.tratsch	5
alt.job	4
TOTAL: 59	401

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1959
EMP (phn path)	640
EMP (phn nph)	134
EMP (phl)	128
Too many newsgroups	74
0	71
HTML Multipart	13
Too many newsgroups (meow)	12
Scoring filter	9
Binary Payload	6
Binary: misplaced binary	5
HTML post	1
TOTAL: 12	3052

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	55
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	2
	TOTAL: 3	59
Huge articles
	csiph-out.feeder.erje.net	82
	csiph-out.news.xs4all.nl	28
	newsfeeds.xmission.com	28
	csiph-out.feed.uzoreto.com	24
	news.freedyn.net	13
	news-out.mixmin.net	4
	feeder.eternal-september.org	2
	news-out.neodome.net	1
	TOTAL: 8	182
Including strange strings
	newsfeed.fu-berlin.de	392
	news-out.mixmin.net	69
	news.swapon.de	18
	csiph-out.news.xs4all.nl	17
	csiph-out.news.weretis.net	16
	news-out.aioe.org	12
	newsfeeds.xmission.com	9
	csiph-out.feeder.erje.net	3
	csiph-out.feed.uzoreto.com	2
	news-out.neodome.net	1
	TOTAL: 10	539
No colon-space in header
	csiph-out.news.xs4all.nl	6
	TOTAL: 1	6
TOTAL: 4		786

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18179	929	13367	38	334	5%	23:59:52
2	news.bbs.nz	26484	558	13270	15	1	2%	23:59:53
3	fu-berlin.de	13204	427	12777	0	0	3%	23:59:46
4	news.samoylyk.net	14821	403	13928	8	388	2%	23:58:26
5	tnet	15035	162	13692	0	0	1%	23:50:06
6	neva.ru	13438	101	13099	140	0	0%	23:59:53
7	news.freedyn.net	14462	75	13912	5	0	0%	23:59:48
8	usenet.pasdenom.info	14422	45	13770	9	13	0%	23:59:53
9	newsfeed.xs4all.nl	13232	43	12675	0	59	0%	23:59:53
10	news.uzoreto.com	13632	36	13271	132	0	0%	23:59:53
11	xmission	12846	23	10387	0	0	0%	23:59:54
12	erje	13577	19	13350	145	0	0%	23:59:53
13	news.mixmin.net	11580	19	11248	0	0	0%	23:59:53
14	neodome.net	14428	17	14023	0	0	0%	23:50:05
15	aioe	10487	17	9983	0	0	0%	23:59:39
16	eternal-september	12034	16	11693	0	0	0%	23:59:46
17	weretis.net	13421	14	13136	0	0	0%	23:59:54
18	news.swapon.de	1353	12	1332	0	0	0%	23:42:15
19	news.fcku.it	965	0	965	0	0	0%	20:53:51
TOTAL: 19		247600	2916	219878	492	795	1%	452:12:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.1 MB	145.4 KB	5.3 MB	0.1 KB/s	5.6 KB	23:59:52
2	fu-berlin.de	3.6 MB	0.0 KB	3.6 MB	0.0 KB/s	8.5 KB	23:59:46
3	news.samoylyk.net	3.5 MB	8.3 KB	3.5 MB	0.0 KB/s	8.8 KB	23:58:26
4	news.bbs.nz	1.5 MB	82.0 KB	1.5 MB	0.0 KB/s	2.8 KB	23:59:53
5	news.freedyn.net	747.5 KB	11.6 KB	759.1 KB	0.0 KB/s	9.5 KB	23:59:48
6	tnet	400.6 KB	0.0 KB	400.6 KB	0.0 KB/s	2.5 KB	23:50:06
7	neva.ru	208.3 KB	345.8 KB	554.1 KB	0.0 KB/s	2.3 KB	23:59:53
8	news.uzoreto.com	104.3 KB	303.1 KB	407.4 KB	0.0 KB/s	2.4 KB	23:59:53
9	usenet.pasdenom.info	102.4 KB	16.4 KB	118.8 KB	0.0 KB/s	2.2 KB	23:59:53
10	newsfeed.xs4all.nl	81.3 KB	0.0 KB	81.3 KB	0.0 KB/s	1.9 KB	23:59:53
11	news.mixmin.net	67.4 KB	0.0 KB	67.4 KB	0.0 KB/s	3.5 KB	23:59:53
12	erje	38.1 KB	461.5 KB	499.6 KB	0.0 KB/s	3.0 KB	23:59:53
13	xmission	36.5 KB	0.0 KB	36.5 KB	0.0 KB/s	1.6 KB	23:59:54
14	neodome.net	30.2 KB	0.0 KB	30.2 KB	0.0 KB/s	1.8 KB	23:50:05
15	aioe	25.4 KB	0.0 KB	25.4 KB	0.0 KB/s	1.5 KB	23:59:39
16	eternal-september	22.8 KB	0.0 KB	22.8 KB	0.0 KB/s	1.4 KB	23:59:46
17	weretis.net	19.2 KB	0.0 KB	19.2 KB	0.0 KB/s	1.4 KB	23:59:54
18	news.swapon.de	16.9 KB	0.0 KB	16.9 KB	0.0 KB/s	1.4 KB	23:42:15
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:53:51
TOTAL: 19		15.5 MB	1.3 MB	16.8 MB	0.0 KB/s	5.1 KB	452:12:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-172-192.home.otenet.gr	1	39	65.4 KB	1	0	0	00:00:20
2	a131033.upc-a.chello.nl	43	21	32.1 KB	6	0	0	11:01:44
3	096-042-018-108.res.spectrum.com	33	20	32.3 KB	15	12	9	06:32:59
TOTAL: 3		77	80	129.8 KB	22	12	9	42:47:10

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	1	39	65.4 KB	1	0	0	00:00:20
2	*.upc-a.chello.nl	43	21	32.1 KB	6	0	0	11:01:44
3	*.res.spectrum.com	33	20	32.3 KB	15	12	9	06:32:59
4	unresolved	42	0	0.0 KB	0	0	0	13:43:15
5	*.ph.ph.cox.net	5	0	0.0 KB	0	0	0	02:30:20
6	*.net.upcbroadband.cz	28	0	0.0 KB	0	0	0	08:58:30
TOTAL: 6		152	80	129.8 KB	22	12	9	42:47:10

NNRP auth users [Top 20]:

User	Conn
ohlandl	9
TOTAL: 1	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	2.837	0.703	0.000	13:43:15
a131033.upc-a.chello.nl	3.243	0.658	0.000	11:01:44
ip-78-102-198-192.net.upcbroadband.cz	1.853	0.410	0.000	08:58:30
096-042-018-108.res.spectrum.com	2.919	0.486	0.022	06:32:59
ip72-222-200-117.ph.ph.cox.net	0.523	0.093	0.000	02:30:20
ppp-2-84-172-192.home.otenet.gr	0.101	0.023	0.000	00:00:20
TOTAL: 6	11.476	2.373	0.022	42:47:10

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	42
ip-78-102-198-192.net.upcbroadband.cz	28
ip72-222-200-117.ph.ph.cox.net	5
TOTAL: 3	75

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	5	0
096-042-018-108.res.spectrum.com	4	0
TOTAL: 2	9	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	80
TOTAL: 1		80

Daily Usenet report for csiph.com

Oct 3 04:15:00 -- Oct 4 04:15:00