Daily Usenet report for csiph.com: Jun 20 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22129	48.8%	5.4 MB	64.3%
innd	19227	42.4%	2.4 MB	28.4%
innfeed	3382	7.5%	563.5 KB	6.5%
nnrpd	534	1.2%	59.8 KB	0.7%
nocem	81	0.2%	8.4 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	45354	100.0%	8.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	302843	53.8%
Negative hits	214297	38.0%
Cache misses	24708	4.4%
Do not exist	21456	3.8%
TOTAL: 4	563304	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.123	0.0%	177	0.000	6.345	31.000
article cleanup	00:00:00.129	0.0%	20110	0.000	0.006	0.032
article logging	00:00:00.458	0.0%	20542	0.000	0.022	1.390
article parse	00:00:03.361	0.0%	104284	0.000	0.032	0.326
article write	00:03:00.248	0.2%	16007	0.212	11.261	76.867
artlog/artcncl	00:00:00.003	0.0%	161	0.000	0.019	0.143
artlog/artparse	00:00:00.036	0.0%	1436	0.000	0.025	2.200
data move	00:00:00.172	0.0%	560342	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.036	0.0%	176	0.000	0.205	1.667
hishave/artcncl	00:00:00.022	0.0%	177	0.000	0.124	5.000
hishave/artparse	00:00:00.005	0.0%	1436	0.000	0.003	0.154
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:23.889	0.0%	564342	0.005	0.042	0.201
history sync	00:00:01.522	0.0%	368	0.000	4.136	54.333
history write	00:00:39.812	0.0%	20109	0.243	1.980	8.240
hiswrite/artcncl	00:00:00.001	0.0%	1	1.000	1.000	1.000
hiswrite/artparse	00:00:02.282	0.0%	1432	0.000	1.594	15.909
idle	23:51:58.166	99.4%	554160	58.177	155.042	530.574
nntp read	00:00:02.739	0.0%	566157	0.001	0.005	0.099
overview write	00:03:25.546	0.2%	16007	0.596	12.841	415.550
perl filter	00:00:35.037	0.0%	20101	1.300	1.743	3.045
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.256	0.0%	32087	0.000	0.008	0.036
TOTAL: 24:00:54.728	24:00:14.843	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.051	0.0%	17218	0.000	0.003	0.031
article prepare	00:00:00.003	0.0%	17568	0.000	0.000	0.021
article read	00:00:06.674	0.0%	1863	0.000	3.582	62.800
backlog stats	00:00:00.211	0.0%	339382	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.579	0.0%	262428	0.002	0.006	0.012
data write	00:00:01.642	0.0%	249940	0.001	0.007	0.014
idle	23:54:10.077	100.0%	339382	84.737	253.549	690.621
status file	00:00:00.895	0.0%	276	0.000	3.243	92.000
TOTAL: 23:54:30.673	23:54:21.132	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	17:13:56.141	99.9%	456	60.500	136044.169	600115.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	1033	0.000	0.009	0.143
readart	00:00:00.383	0.0%	35	0.000	10.943	48.000
TOTAL: 17:14:46.563	17:13:56.533	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	4	32199	2997	28946	256	9%	47:55:57
2	csiph-out.feeder.erje.net	1440	78767	2752	75531	484	3%	118:41:40
3	feeder.eternal-september.org	3	18277	2112	16021	144	11%	24:33:24
4	news-out.mixmin.net	2	16684	1411	14991	282	8%	24:08:58
5	news-out.aioe.org	2	15683	1390	14267	26	8%	24:09:04
6	news.freedyn.net	3	20501	1356	17385	1760	6%	24:08:47
7	newsfeed.neva.ru	150	17664	949	15702	1013	5%	71:36:57
8	newsfeed.fu-berlin.de	6	14509	924	13505	80	6%	22:59:41
9	csiph-out.news.xs4all.nl	861	18726	823	17113	790	4%	71:25:22
10	newsfeeds.xmission.com	1	15693	561	15126	6	3%	24:27:33
11	csiph-out.feed.uzoreto.com	864	32936	314	32132	490	0%	71:16:50
12	news.fcku.it	9	2083	257	1812	14	12%	22:23:23
13	nntp.pasdenom.info	5	41499	37	41428	34	0%	23:34:59
14	news.swapon.de	6	1507	28	1479	0	1%	23:42:11
15	localhost	49	14	14	0	0	100%	00:00:01
16	news.samoylyk.net	2	15651	13	15637	1	0%	24:54:59
17	news.bbs.nz	3	16510	9	16501	0	0%	26:03:41
18	xanadu-bbs.net	17	770	2	732	36	0%	23:34:22
TOTAL: 18		3427	359673	15949	338308	5416	4%	669:37:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	12.9 MB	0.0 KB	14.9 MB	27.8 MB	46%	8.8 KB
2	csiph-out.news.weretis.net	9.6 MB	0.0 KB	7.3 MB	16.9 MB	56%	5.3 KB
3	feeder.eternal-september.org	5.2 MB	0.0 KB	2.1 MB	7.3 MB	71%	3.3 KB
4	news-out.mixmin.net	3.9 MB	0.0 KB	645.7 KB	4.5 MB	86%	2.7 KB
5	news.freedyn.net	3.7 MB	0.0 KB	119.2 MB	122.9 MB	2%	40.4 KB
6	news-out.aioe.org	3.4 MB	0.0 KB	38.9 KB	3.5 MB	98%	2.5 KB
7	newsfeed.neva.ru	2.4 MB	0.0 KB	2.7 MB	5.1 MB	46%	2.7 KB
8	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	244.1 KB	2.4 MB	90%	2.5 KB
9	newsfeeds.xmission.com	2.1 MB	0.0 KB	428.0 KB	2.6 MB	83%	4.6 KB
10	csiph-out.news.xs4all.nl	2.1 MB	3.0 KB	4.1 MB	6.2 MB	33%	3.9 KB
11	csiph-out.feed.uzoreto.com	1.0 MB	0.0 KB	6.7 MB	7.7 MB	13%	9.8 KB
12	news.fcku.it	414.8 KB	0.0 KB	16.3 KB	431.0 KB	96%	1.6 KB
13	nntp.pasdenom.info	83.4 KB	0.0 KB	80.6 KB	164.0 KB	50%	2.3 KB
14	news.swapon.de	78.8 KB	0.0 KB	0.0 KB	78.8 KB	100%	2.8 KB
15	news.bbs.nz	58.9 KB	0.0 KB	0.0 KB	58.9 KB	100%	6.5 KB
16	news.samoylyk.net	37.6 KB	0.0 KB	808.2 KB	845.7 KB	4%	60.4 KB
17	localhost	21.3 KB	0.0 KB	0.0 KB	21.3 KB	100%	1.5 KB
18	xanadu-bbs.net	3.6 KB	0.0 KB	1.3 MB	1.3 MB	0%	36.2 KB
TOTAL: 18		49.2 MB	3.0 KB	160.6 MB	209.8 MB	23%	10.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 20 04:15:00 - 04:59:59	244	1.5%	0.09	1.1 MB	2.3%	0.42
Jun 20 05:00:00 - 05:59:59	453	2.8%	0.13	1.7 MB	3.5%	0.49
Jun 20 06:00:00 - 06:59:59	442	2.8%	0.12	1.5 MB	3.1%	0.43
Jun 20 07:00:00 - 07:59:59	621	3.9%	0.17	1.7 MB	3.4%	0.47
Jun 20 08:00:00 - 08:59:59	596	3.7%	0.17	1.8 MB	3.6%	0.50
Jun 20 09:00:00 - 09:59:59	712	4.5%	0.20	1.9 MB	3.9%	0.54
Jun 20 10:00:00 - 10:59:59	691	4.3%	0.19	2.1 MB	4.3%	0.60
Jun 20 11:00:00 - 11:59:59	697	4.4%	0.19	2.0 MB	4.0%	0.56
Jun 20 12:00:00 - 12:59:59	733	4.6%	0.20	1.8 MB	3.7%	0.52
Jun 20 13:00:00 - 13:59:59	833	5.2%	0.23	2.0 MB	4.1%	0.58
Jun 20 14:00:00 - 14:59:59	1170	7.3%	0.33	3.5 MB	7.1%	0.99
Jun 20 15:00:00 - 15:59:59	872	5.5%	0.24	3.0 MB	6.0%	0.84
Jun 20 16:00:00 - 16:59:59	997	6.2%	0.28	3.6 MB	7.3%	1.02
Jun 20 17:00:00 - 17:59:59	990	6.2%	0.28	2.9 MB	5.8%	0.81
Jun 20 18:00:00 - 18:59:59	926	5.8%	0.26	3.0 MB	6.1%	0.86
Jun 20 19:00:00 - 19:59:59	911	5.7%	0.25	2.7 MB	5.6%	0.78
Jun 20 20:00:00 - 20:59:59	842	5.3%	0.23	2.5 MB	5.0%	0.71
Jun 20 21:00:00 - 21:59:59	743	4.6%	0.21	2.0 MB	4.1%	0.57
Jun 20 22:00:00 - 22:59:59	587	3.7%	0.16	2.2 MB	4.4%	0.61
Jun 20 23:00:00 - 23:59:59	499	3.1%	0.14	1.4 MB	2.9%	0.41
Jun 21 00:00:00 - 00:59:59	397	2.5%	0.11	1.2 MB	2.4%	0.34
Jun 21 01:00:00 - 01:59:59	336	2.1%	0.09	1.0 MB	2.1%	0.29
Jun 21 02:00:00 - 02:59:59	345	2.2%	0.10	1.5 MB	3.0%	0.42
Jun 21 03:00:00 - 03:59:59	277	1.7%	0.08	841.9 KB	1.7%	0.23
Jun 21 04:00:00 - 04:15:00	85	0.5%	0.09	338.7 KB	0.7%	0.38
TOTAL: 24:00:00	15999	100.0%	0.19	49.2 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	news.freedyn.net	1851	16	0	0	1835
2	newsfeed.neva.ru	1011	257	0	0	754
3	csiph-out.news.xs4all.nl	798	22	1	0	775
4	csiph-out.feed.uzoreto.com	494	6	0	0	488
5	csiph-out.feeder.erje.net	490	43	0	0	447
6	newsfeed.fu-berlin.de	476	16	0	0	460
7	news-out.mixmin.net	302	3	0	0	299
8	csiph-out.news.weretis.net	286	101	0	0	185
9	feeder.eternal-september.org	145	24	0	0	121
10	xanadu-bbs.net	36	0	0	0	36
11	nntp.pasdenom.info	29	0	0	5	24
12	news-out.aioe.org	27	25	0	0	2
13	news.fcku.it	14	14	0	0	0
14	newsfeeds.xmission.com	7	0	0	0	7
15	news.samoylyk.net	2	0	0	0	2
16	news.swapon.de	1	0	0	0	1
TOTAL: 16		5969	527	1	5	5436

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	234
it-alt.sport.calcio.juventus	55
alt.testing	19
alt.windows7.general	15
alt.bbs.synchronet	15
lada.commerce	12
mozilla.general	10
alt.sports.hockey.nhl	10
spline.eisfair	9
alt.music.pink-floyd.publius	9
mozilla.support.seamonkey	7
mozilla.support.bugzilla	7
lada.auto	7
alt.english.usage	6
alt.politics.usa	5
alt.sex.pictu	4
rocksolid.shared.test	4
oesterreich.tratsch	4
mozilla.support.firefox	4
alt.computer.workshop	3
TOTAL: 88	527

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3044
EMP (phn nph)	155
EMP (phn path)	93
EMP (phl)	70
Too many newsgroups	36
Binary Payload	30
Binary: misplaced rar	25
Binary: misplaced par2	23
User-issued spam cancel	21
User-issued cancel	21
HTML Multipart	12
Scoring filter (8)	10
Scoring filter (11)	5
Scoring filter (10)	5
Binary: misplaced nzb	4
Too many newsgroups (low_xpost)	3
Too many newsgroups (meow)	3
Scoring filter (12)	2
Scoring filter (13)	2
HTML post	1
TOTAL: 21	3566

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	52	0	59	59
TOTAL: 1	52	21	59	59

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	1052
	csiph-out.feeder.erje.net	261
	csiph-out.news.weretis.net	73
	csiph-out.feed.uzoreto.com	18
	xanadu-bbs.net	14
	newsfeeds.xmission.com	7
	feeder.eternal-september.org	4
	csiph-out.news.xs4all.nl	3
	news.samoylyk.net	2
	news-out.mixmin.net	1
	TOTAL: 10	1435
Including strange strings
	newsfeed.fu-berlin.de	390
	csiph-out.news.weretis.net	15
	news-out.mixmin.net	10
	csiph-out.news.xs4all.nl	8
	csiph-out.feed.uzoreto.com	4
	csiph-out.feeder.erje.net	3
	news.swapon.de	1
	news-out.aioe.org	1
	TOTAL: 8	432
TOTAL: 4		1913

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	xanadu-bbs	18354	628	15370	0	0	76	3%	23:59:54
2	news.freedyn.net	15109	546	14053	35	0	0	3%	23:59:08
3	news.samoylyk.net	16523	524	15433	0	0	28	3%	23:54:54
4	fu-berlin.de	14870	498	14372	0	0	0	3%	23:59:52
5	usenet.pasdenom.info	17680	311	14365	1284	2	757	1%	23:59:52
6	news.bbs.nz	29293	262	15174	1	0	344	0%	23:53:25
7	newsfeed.xs4all.nl	15805	32	14951	4	0	45	0%	23:56:38
8	aioe	14021	28	13522	0	0	0	0%	23:58:52
9	eternal-september	13694	18	13232	1	0	0	0%	23:58:39
10	news.mixmin.net	14190	17	13793	0	0	0	0%	23:59:42
11	news.swapon.de	1806	15	1717	0	0	0	0%	23:49:01
12	xmission	17910	14	14877	1	0	0	0%	23:59:54
13	weretis.net	12516	14	12048	0	0	0	0%	23:56:01
14	neva.ru	14389	12	14163	121	0	0	0%	23:59:35
15	news.uzoreto.com	15582	12	15287	25	0	0	0%	23:53:51
16	erje	13416	6	13140	20	0	0	0%	23:59:54
17	news.fcku.it	1667	1	1653	0	0	0	0%	21:38:46
18	news.redatomik.org	0	0	0	0	0	15424	0%	23:50:03
TOTAL: 18		246825	2938	217150	1492	2	16674	1%	428:48:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	0.0 KB	7.2 MB	0.1 KB/s	11.7 KB	23:59:54
2	news.freedyn.net	6.9 MB	68.3 KB	7.0 MB	0.1 KB/s	12.4 KB	23:59:08
3	fu-berlin.de	6.8 MB	0.0 KB	6.8 MB	0.1 KB/s	13.9 KB	23:59:52
4	news.samoylyk.net	6.7 MB	0.0 KB	6.7 MB	0.1 KB/s	13.1 KB	23:54:54
5	usenet.pasdenom.info	862.7 KB	8.7 MB	9.5 MB	0.1 KB/s	6.1 KB	23:59:52
6	news.bbs.nz	674.4 KB	1.6 KB	676.0 KB	0.0 KB/s	2.6 KB	23:53:25
7	newsfeed.xs4all.nl	178.3 KB	14.3 KB	192.7 KB	0.0 KB/s	5.4 KB	23:56:38
8	eternal-september	76.8 KB	2.1 KB	78.9 KB	0.0 KB/s	4.2 KB	23:58:39
9	aioe	53.4 KB	0.0 KB	53.4 KB	0.0 KB/s	1.9 KB	23:58:52
10	news.mixmin.net	27.9 KB	0.0 KB	27.9 KB	0.0 KB/s	1.6 KB	23:59:42
11	news.swapon.de	23.6 KB	0.0 KB	23.6 KB	0.0 KB/s	1.6 KB	23:49:01
12	xmission	21.4 KB	1.7 KB	23.1 KB	0.0 KB/s	1.5 KB	23:59:54
13	weretis.net	21.4 KB	0.0 KB	21.4 KB	0.0 KB/s	1.5 KB	23:56:01
14	news.uzoreto.com	19.2 KB	163.4 KB	182.6 KB	0.0 KB/s	4.9 KB	23:53:51
15	neva.ru	17.7 KB	318.7 KB	336.3 KB	0.0 KB/s	2.5 KB	23:59:35
16	news.fcku.it	16.7 KB	0.0 KB	16.7 KB	0.0 KB/s	16.7 KB	21:38:46
17	erje	11.4 KB	49.7 KB	61.1 KB	0.0 KB/s	2.4 KB	23:59:54
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:03
TOTAL: 18		29.6 MB	9.3 MB	38.9 MB	0.0 KB/s	9.0 KB	428:48:01

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	68-185-188-189.dhcp.mdsn.wi.charter.com	31	23	37.6 KB	18	14	11	09:08:41
2	a131033.upc-a.chello.nl	23	12	23.0 KB	5	0	0	05:28:57
TOTAL: 2		54	35	60.6 KB	23	14	11	17:14:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.mdsn.wi.charter.com	31	23	37.6 KB	18	14	11	09:08:41
2	*.upc-a.chello.nl	23	12	23.0 KB	5	0	0	05:28:57
3	unresolved	4	0	0.0 KB	0	0	0	00:36:44
4	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:20
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:02
6	*.nielsenmedia.com	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 6		64	35	60.6 KB	23	14	11	17:14:46

NNRP auth users [Top 20]:

User	Conn
ohlandl	11
ctomczyk	4
TOTAL: 2	15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
68-185-188-189.dhcp.mdsn.wi.charter.com	2.728	0.625	0.033	09:08:41
a131033.upc-a.chello.nl	1.870	0.498	0.000	05:28:57
ip72-222-200-117.ph.ph.cox.net	0.445	0.095	0.000	02:00:20
109.239.71.208	0.247	0.095	0.011	00:36:44
scan-141.security.ipip.net	0.074	0.008	0.000	00:00:02
TOTAL: 5	5.364	1.321	0.044	17:14:46

Curious NNRP server explorers [Top 20]:

System	Conn
109.239.71.208	4
ip72-222-200-117.ph.ph.cox.net	4
challenger.nielsenmedia.com	1
scan-141.security.ipip.net	1
TOTAL: 4	10

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Connection: close	1
Host: 69.160.37.58:119	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-188-189.dhcp.mdsn.wi.charter.com	7	0
ip72-222-200-117.ph.ph.cox.net	4	0
a131033.upc-a.chello.nl	2	0
109.239.71.208	1	0
TOTAL: 4	14	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Jun 20 04:15:00 -- Jun 21 04:15:00