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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Sep 6 10:45:03 csiph pgpverify[53486]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20829	48.9%	5.3 MB	64.8%
innd	17930	42.1%	2.2 MB	27.5%
innfeed	3297	7.7%	587.0 KB	7.0%
nnrpd	487	1.1%	54.3 KB	0.7%
nocem	46	0.1%	4.8 KB	0.1%
controlchan	12	0.0%	2.2 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	42602	100.0%	8.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	295952	55.2%
Negative hits	218811	40.8%
Do not exist	20294	3.8%
Cache misses	656	0.1%
TOTAL: 4	535713	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.443	0.0%	122	0.000	3.631	20.200
article cleanup	00:00:00.110	0.0%	19614	0.000	0.006	0.036
article logging	00:00:00.402	0.0%	20046	0.000	0.020	0.309
article parse	00:00:01.436	0.0%	73648	0.000	0.019	0.273
article write	00:00:17.658	0.0%	16120	0.109	1.095	29.707
artlog/artcncl	00:00:00.002	0.0%	95	0.000	0.021	0.125
artlog/artparse	00:00:00.009	0.0%	723	0.000	0.012	0.333
data move	00:00:00.130	0.0%	511019	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.031	0.0%	116	0.000	0.267	2.000
hishave/artcncl	00:00:00.062	0.0%	122	0.000	0.508	4.700
hishave/artparse	00:00:00.003	0.0%	721	0.000	0.004	0.059
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:10.752	0.0%	535801	0.002	0.020	0.110
history sync	00:00:00.820	0.0%	379	0.000	2.164	8.000
history write	00:00:17.142	0.0%	19611	0.230	0.874	4.289
hiswrite/artcncl	00:00:00.013	0.0%	6	0.000	2.167	4.000
hiswrite/artparse	00:00:00.776	0.0%	720	0.000	1.078	19.909
idle	23:58:24.386	99.8%	518926	93.062	166.313	544.914
nntp read	00:00:02.015	0.0%	519816	0.001	0.004	0.012
overview write	00:00:37.435	0.0%	16120	0.099	2.322	29.954
perl filter	00:00:33.380	0.0%	19609	1.266	1.702	5.227
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.274	0.0%	32284	0.000	0.008	0.026
TOTAL: 24:01:04.637	24:00:27.279	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.040	0.0%	22819	0.000	0.002	0.026
article prepare	00:00:00.002	0.0%	17260	0.000	0.000	0.003
article read	00:00:01.657	0.0%	1901	0.000	0.872	12.000
backlog stats	00:00:00.180	0.0%	342552	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.308	0.0%	264672	0.000	0.005	0.011
data write	00:00:01.561	0.0%	251596	0.000	0.006	0.013
idle	23:53:37.312	100.0%	342552	104.612	251.107	862.228
status file	00:00:00.442	0.0%	248	0.000	1.782	14.750
TOTAL: 23:53:50.386	23:53:42.502	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	20:31:27.593	100.0%	349	297.071	211712.301	600182.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.387	0.0%	764	0.000	0.507	9.575
readart	00:00:00.188	0.0%	13	0.000	14.462	48.000
TOTAL: 20:31:47.683	20:31:28.168	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	128
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	148

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	15753	3487	12260	6	22%	25:22:12
2	feeder.eternal-september.org	3	17915	2270	15545	100	12%	24:27:47
3	news.freedyn.net	3	19729	2003	16750	976	10%	24:08:57
4	news-out.aioe.org	2	15650	1601	14043	6	10%	24:09:03
5	csiph-out.news.xs4all.nl	861	18311	1356	16060	895	7%	71:26:11
6	csiph-out.feeder.erje.net	1440	77709	1340	76018	351	1%	118:48:20
7	news-out.mixmin.net	1	16617	1058	15328	231	6%	23:59:53
8	newsfeed.fu-berlin.de	14	14692	866	13763	63	5%	24:36:38
9	newsfeed.neva.ru	139	15623	765	13864	994	4%	71:08:55
10	csiph-out.news.weretis.net	3	18812	658	18070	84	3%	30:13:12
11	csiph-out.feed.uzoreto.com	864	26420	383	25560	477	1%	71:11:18
12	news.fcku.it	9	1975	284	1691	0	14%	21:55:55
13	news.swapon.de	3	1456	31	1425	0	2%	24:16:59
14	nntp.pasdenom.info	2	10992	25	10954	13	0%	23:58:38
15	news.samoylyk.net	1	15483	17	15465	1	0%	23:59:45
16	news.bbs.nz	1	15358	7	15351	0	0%	23:59:53
17	xanadu-bbs.net	11	799	5	793	1	0%	19:37:37
18	localhost	32	2	2	0	0	100%	00:00:04
TOTAL: 18		3391	303296	16158	282940	4198	5%	647:21:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.6 MB	0.0 KB	70.2 KB	9.7 MB	99%	2.8 KB
2	csiph-out.feeder.erje.net	8.8 MB	50.1 KB	12.0 MB	20.8 MB	42%	12.6 KB
3	feeder.eternal-september.org	6.1 MB	0.0 KB	667.5 KB	6.7 MB	90%	2.9 KB
4	news.freedyn.net	5.1 MB	0.0 KB	46.1 MB	51.2 MB	10%	17.6 KB
5	csiph-out.news.xs4all.nl	4.3 MB	0.0 KB	8.1 MB	12.4 MB	34%	5.6 KB
6	news-out.aioe.org	4.1 MB	0.0 KB	21.4 KB	4.1 MB	99%	2.6 KB
7	news-out.mixmin.net	3.1 MB	0.0 KB	458.8 KB	3.6 MB	87%	2.8 KB
8	csiph-out.news.weretis.net	2.4 MB	73.4 KB	1.0 MB	3.5 MB	69%	4.8 KB
9	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	151.3 KB	2.1 MB	92%	2.3 KB
10	newsfeed.neva.ru	1.9 MB	0.0 KB	3.7 MB	5.6 MB	33%	3.3 KB
11	csiph-out.feed.uzoreto.com	1.6 MB	0.0 KB	7.1 MB	8.7 MB	18%	10.3 KB
12	news.fcku.it	573.0 KB	0.0 KB	0.0 KB	573.0 KB	100%	2.0 KB
13	news.swapon.de	90.6 KB	0.0 KB	0.0 KB	90.6 KB	100%	2.9 KB
14	nntp.pasdenom.info	57.8 KB	0.0 KB	18.6 KB	76.4 KB	75%	2.0 KB
15	news.samoylyk.net	55.5 KB	0.0 KB	48.6 KB	104.1 KB	53%	5.8 KB
16	news.bbs.nz	14.6 KB	0.0 KB	0.0 KB	14.6 KB	100%	2.1 KB
17	xanadu-bbs.net	9.1 KB	0.0 KB	16.0 KB	25.1 KB	36%	4.2 KB
18	localhost	6.5 KB	0.0 KB	0.0 KB	6.5 KB	100%	3.2 KB
TOTAL: 18		49.7 MB	123.5 KB	79.4 MB	129.3 MB	38%	6.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 6 04:15:00 - 04:59:59	265	1.6%	0.10	1.2 MB	2.4%	0.44
Sep 6 05:00:00 - 05:59:59	454	2.8%	0.13	2.0 MB	4.1%	0.57
Sep 6 06:00:00 - 06:59:59	515	3.2%	0.14	1.7 MB	3.4%	0.48
Sep 6 07:00:00 - 07:59:59	640	4.0%	0.18	1.7 MB	3.5%	0.49
Sep 6 08:00:00 - 08:59:59	830	5.2%	0.23	2.2 MB	4.6%	0.64
Sep 6 09:00:00 - 09:59:59	775	4.8%	0.22	2.2 MB	4.5%	0.63
Sep 6 10:00:00 - 10:59:59	863	5.4%	0.24	2.5 MB	5.0%	0.70
Sep 6 11:00:00 - 11:59:59	774	4.8%	0.21	2.3 MB	4.6%	0.64
Sep 6 12:00:00 - 12:59:59	878	5.5%	0.24	2.3 MB	4.8%	0.66
Sep 6 13:00:00 - 13:59:59	857	5.3%	0.24	2.1 MB	4.4%	0.61
Sep 6 14:00:00 - 14:59:59	896	5.6%	0.25	3.0 MB	6.2%	0.86
Sep 6 15:00:00 - 15:59:59	955	5.9%	0.27	3.0 MB	6.0%	0.84
Sep 6 16:00:00 - 16:59:59	917	5.7%	0.25	3.0 MB	6.2%	0.87
Sep 6 17:00:00 - 17:59:59	892	5.5%	0.25	2.5 MB	5.2%	0.73
Sep 6 18:00:00 - 18:59:59	823	5.1%	0.23	2.4 MB	4.9%	0.68
Sep 6 19:00:00 - 19:59:59	796	4.9%	0.22	2.3 MB	4.6%	0.65
Sep 6 20:00:00 - 20:59:59	754	4.7%	0.21	2.3 MB	4.6%	0.64
Sep 6 21:00:00 - 21:59:59	727	4.5%	0.20	2.0 MB	4.0%	0.56
Sep 6 22:00:00 - 22:59:59	582	3.6%	0.16	2.2 MB	4.5%	0.63
Sep 6 23:00:00 - 23:59:59	479	3.0%	0.13	1.4 MB	2.8%	0.39
Sep 7 00:00:00 - 00:59:59	351	2.2%	0.10	1.1 MB	2.3%	0.32
Sep 7 01:00:00 - 01:59:59	379	2.4%	0.11	1.3 MB	2.6%	0.37
Sep 7 02:00:00 - 02:59:59	316	2.0%	0.09	1.1 MB	2.3%	0.32
Sep 7 03:00:00 - 03:59:59	295	1.8%	0.08	915.7 KB	1.8%	0.25
Sep 7 04:00:00 - 04:15:00	92	0.6%	0.10	265.1 KB	0.5%	0.29
TOTAL: 24:00:00	16105	100.0%	0.19	49.0 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	1005	201	0	804
2	news.freedyn.net	958	21	0	937
3	csiph-out.news.xs4all.nl	900	24	0	876
4	csiph-out.feed.uzoreto.com	477	14	0	463
5	newsfeed.fu-berlin.de	447	12	0	435
6	csiph-out.feeder.erje.net	352	49	2	301
7	news-out.mixmin.net	242	0	0	242
8	csiph-out.news.weretis.net	113	53	3	57
9	feeder.eternal-september.org	98	12	0	86
10	nntp.pasdenom.info	13	0	0	13
11	news-out.aioe.org	9	5	0	4
12	news.swapon.de	8	0	0	8
13	newsfeeds.xmission.com	6	2	0	4
14	xanadu-bbs.net	1	0	0	1
15	news.samoylyk.net	1	0	0	1
TOTAL: 15		4630	393	5	4232

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	90
lada.auto	85
alt.binaries.pictures.purebeauty	20
alt.bbs.synchronet	19
alt.windows7.general	14
lada.photo	11
oesterreich.tratsch	9
mozilla.dev.platform	9
att.jobs	7
alt.survival	6
it-alt.sport.calcio.juventus	6
mozilla.dev.ports.os2	6
mozilla.support.bugzilla	5
mozilla.dev.security.policy	5
mozilla.dev.tech.crypto	4
lada.soft	4
aus.jobs	4
mozilla.support.thunderbird	4
alt.politics	4
mozilla.support.firefox	4
TOTAL: 72	393

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2714
EMP (phl)	119
EMP (phn path)	80
Too many newsgroups	47
EMP (phn nph)	37
User-issued cancel	23
Binary Payload	13
HTML Multipart	11
User-issued spam cancel	10
Binary: misplaced rar	8
Scoring filter (10)	7
Scoring filter (8)	5
Too many newsgroups (meow)	4
HTML Image Tags	2
Scoring filter (9)	1
Binary: misplaced arj	1
Too many newsgroups (low_xpost)	1
TOTAL: 17	3083

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	35	0	38	38
TOTAL: 1	35	8	38	38

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	TOTAL: 1	50
Huge articles
	news.freedyn.net	354
	csiph-out.feeder.erje.net	215
	csiph-out.feed.uzoreto.com	68
	csiph-out.news.xs4all.nl	66
	csiph-out.news.weretis.net	6
	feeder.eternal-september.org	3
	news.samoylyk.net	1
	newsfeeds.xmission.com	1
	TOTAL: 8	714
Including strange strings
	newsfeed.fu-berlin.de	373
	csiph-out.news.xs4all.nl	19
	news-out.mixmin.net	10
	newsfeed.neva.ru	8
	news.swapon.de	8
	csiph-out.news.weretis.net	5
	csiph-out.feeder.erje.net	5
	news-out.aioe.org	3
	csiph-out.feed.uzoreto.com	1
	feeder.eternal-september.org	1
	TOTAL: 10	433
No colon-space in header
	csiph-out.news.xs4all.nl	2
	TOTAL: 1	2
TOTAL: 4		1199

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	23695	1589	19027	0	2175	6%	23:59:45
2	news.samoylyk.net	16494	499	15526	2	0	3%	23:51:04
3	news.freedyn.net	14425	462	13525	43	0	3%	23:59:45
4	fu-berlin.de	15027	458	14568	0	0	3%	23:59:54
5	news.bbs.nz	30255	271	15286	6	0	0%	23:54:27
6	aioe	13884	64	13225	0	0	0%	23:50:11
7	usenet.pasdenom.info	16435	53	15461	566	12	0%	23:59:54
8	newsfeed.xs4all.nl	15067	42	14300	6	53	0%	23:56:39
9	news.uzoreto.com	15496	10	15211	58	0	0%	23:50:11
10	neva.ru	14745	9	14513	150	0	0%	23:59:54
11	news.mixmin.net	14941	6	14397	0	0	0%	23:59:54
12	erje	14860	4	14535	13	1	0%	23:59:54
13	xmission	15568	3	12190	0	0	0%	23:59:54
14	news.swapon.de	1553	2	1518	0	0	0%	23:53:18
15	eternal-september	13743	2	13181	0	0	0%	23:59:53
16	weretis.net	14984	2	14839	2	0	0%	23:50:11
17	news.redatomik.org	0	0	0	0	15542	0%	23:50:11
18	news.fcku.it	1620	0	1617	0	0	0%	21:39:26
TOTAL: 18		252792	3476	222919	846	17783	1%	428:34:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.8 MB	0.0 KB	9.8 MB	0.1 KB/s	6.3 KB	23:59:45
2	news.samoylyk.net	6.2 MB	38.9 KB	6.2 MB	0.1 KB/s	12.8 KB	23:51:04
3	news.freedyn.net	6.2 MB	61.3 KB	6.3 MB	0.1 KB/s	12.7 KB	23:59:45
4	fu-berlin.de	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	13.8 KB	23:59:54
5	news.bbs.nz	666.5 KB	23.5 KB	690.0 KB	0.0 KB/s	2.5 KB	23:54:27
6	usenet.pasdenom.info	166.2 KB	6.4 MB	6.5 MB	0.1 KB/s	10.8 KB	23:59:54
7	aioe	144.4 KB	0.0 KB	144.4 KB	0.0 KB/s	2.3 KB	23:50:11
8	newsfeed.xs4all.nl	118.2 KB	16.2 KB	134.4 KB	0.0 KB/s	2.8 KB	23:56:39
9	news.mixmin.net	51.2 KB	0.0 KB	51.2 KB	0.0 KB/s	8.5 KB	23:59:54
10	news.uzoreto.com	27.1 KB	219.8 KB	246.8 KB	0.0 KB/s	3.6 KB	23:50:11
11	neva.ru	21.4 KB	388.0 KB	409.4 KB	0.0 KB/s	2.6 KB	23:59:54
12	xmission	9.2 KB	0.0 KB	9.2 KB	0.0 KB/s	3.1 KB	23:59:54
13	erje	8.3 KB	83.8 KB	92.1 KB	0.0 KB/s	5.4 KB	23:59:54
14	news.swapon.de	6.5 KB	0.0 KB	6.5 KB	0.0 KB/s	3.2 KB	23:53:18
15	eternal-september	6.5 KB	0.0 KB	6.5 KB	0.0 KB/s	3.2 KB	23:59:53
16	weretis.net	6.5 KB	7.4 KB	13.9 KB	0.0 KB/s	3.5 KB	23:50:11
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:39:26
TOTAL: 18		29.6 MB	7.2 MB	36.8 MB	0.0 KB/s	8.7 KB	428:34:25

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	59	7	14.3 KB	3	0	0	16:11:28
2	68-190-164-204.dhcp.eucl.wi.charter.com	11	5	14.3 KB	4	2	2	03:19:45
3	p5dee926a.dip0.t-ipconnect.de	1	1	6.9 KB	1	0	0	00:00:06
TOTAL: 3		71	13	35.6 KB	8	2	2	20:31:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	59	7	14.3 KB	3	0	0	16:11:28
2	*.dhcp.eucl.wi.charter.com	11	5	14.3 KB	4	2	2	03:19:45
3	*.dip0.t-ipconnect.de	1	1	6.9 KB	1	0	0	00:00:06
4	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:07
5	unresolved	8	0	0.0 KB	0	0	0	00:00:18
TOTAL: 5		81	13	35.6 KB	8	2	2	20:31:46

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	4.041	0.859	0.000	16:11:28
68-190-164-204.dhcp.eucl.wi.charter.com	0.891	0.187	0.005	03:19:45
ip72-222-200-117.ph.ph.cox.net	0.225	0.041	0.000	01:00:07
159.203.199.236	0.077	0.000	0.000	00:00:09
p5dee926a.dip0.t-ipconnect.de	0.120	0.032	0.000	00:00:06
94.162.88.17	0.071	0.008	0.000	00:00:01
5.86.196.114	0.076	0.000	0.000	00:00:01
5.86.246.36	0.068	0.014	0.000	00:00:01
94.162.28.221	0.063	0.016	0.000	00:00:01
94.162.126.74	0.062	0.018	0.000	00:00:01
5.86.245.132	0.062	0.015	0.000	00:00:01
5.86.250.168	0.073	0.007	0.000	00:00:01
TOTAL: 12	5.829	1.197	0.005	20:31:46

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	2
94.162.88.17	1
94.162.126.74	1
94.162.28.221	1
5.86.196.114	1
5.86.250.168	1
5.86.245.132	1
159.203.199.236	1
5.86.246.36	1
TOTAL: 9	10

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-190-164-204.dhcp.eucl.wi.charter.com	5	0
ip72-222-200-117.ph.ph.cox.net	2	0
TOTAL: 2	7	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	12
2	comp.robotics.misc	1
TOTAL: 2		13

Daily Usenet report for csiph.com

Sep 6 04:15:00 -- Sep 7 04:15:00