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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20970	49.2%	5.4 MB	65.2%
innd	17834	41.9%	2.3 MB	27.6%
innfeed	3397	8.0%	562.7 KB	6.7%
nnrpd	334	0.8%	38.4 KB	0.5%
nocem	43	0.1%	4.8 KB	0.1%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	42579	100.0%	8.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	299860	56.4%
Negative hits	211103	39.7%
Do not exist	20355	3.8%
Cache misses	789	0.1%
TOTAL: 4	532107	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.871	0.0%	89	0.000	21.022	171.000
article cleanup	00:00:00.152	0.0%	19878	0.000	0.008	0.041
article logging	00:00:00.596	0.0%	20355	0.000	0.029	2.596
article parse	00:00:05.383	0.0%	71730	0.000	0.075	1.057
article write	00:02:32.611	0.2%	16402	0.352	9.304	59.079
artlog/artcncl	00:00:00.004	0.0%	85	0.000	0.047	0.500
artlog/artparse	00:00:00.011	0.0%	602	0.000	0.018	0.333
data move	00:00:00.144	0.0%	504873	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.535	0.0%	89	0.000	6.011	169.500
hishave/artcncl	00:00:00.467	0.0%	89	0.000	5.247	36.083
hishave/artparse	00:00:00.000	0.0%	602	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:37.627	0.1%	534133	0.001	0.183	0.557
history sync	00:00:03.514	0.0%	371	0.000	9.472	122.000
history write	00:02:02.013	0.1%	19877	0.286	6.138	18.681
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:04.738	0.0%	602	0.000	7.870	101.000
idle	23:45:48.910	99.0%	491041	82.356	174.219	553.418
nntp read	00:00:02.194	0.0%	512294	0.001	0.004	0.007
overview write	00:07:18.843	0.5%	16402	2.193	26.755	1281.533
perl filter	00:00:36.857	0.0%	19876	1.343	1.854	4.468
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.290	0.0%	32844	0.000	0.009	0.038
TOTAL: 24:00:56.170	24:00:16.760	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	22975	0.000	0.003	0.039
article prepare	00:00:00.008	0.0%	34804	0.000	0.000	0.016
article read	00:00:54.421	0.1%	6635	0.000	8.202	589.000
backlog stats	00:00:00.190	0.0%	323626	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.410	0.0%	257509	0.000	0.005	0.012
data write	00:00:01.487	0.0%	244610	0.002	0.006	0.014
idle	23:55:09.681	99.9%	323626	89.288	266.078	697.192
status file	00:00:01.502	0.0%	296	0.000	5.074	168.000
TOTAL: 23:56:18.703	23:56:08.761	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	12:06:01.228	100.0%	200	334.333	217806.140	600070.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.001	0.0%	472	0.000	0.002	0.125
readart	00:00:00.048	0.0%	6	0.000	8.000	47.000
TOTAL: 12:06:17.800	12:06:01.277	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	33760	3212	30290	258	9%	50:11:14
2	csiph-out.feeder.erje.net	1440	79360	2102	76967	291	2%	119:06:39
3	feeder.eternal-september.org	1	17049	1987	14977	85	11%	21:14:33
4	news.freedyn.net	2	19904	1928	16710	1266	9%	24:09:02
5	newsfeeds.xmission.com	2	16137	1452	14682	3	8%	24:39:06
6	news-out.aioe.org	3	16333	1383	14918	32	8%	24:09:01
7	csiph-out.news.xs4all.nl	875	18495	1367	16028	1100	7%	71:27:22
8	newsfeed.neva.ru	141	20258	999	18781	478	4%	72:00:01
9	newsfeed.fu-berlin.de	13	14981	821	14063	97	5%	24:01:24
10	news-out.mixmin.net	2	17081	773	16128	180	4%	23:59:49
11	news.fcku.it	10	2085	247	1833	5	11%	21:17:30
12	csiph-out.feed.uzoreto.com	864	25784	198	25296	290	0%	71:12:18
13	nntp.pasdenom.info	2	11061	39	11013	9	0%	23:59:30
14	news.swapon.de	11	1262	26	1235	1	2%	23:00:28
15	news.samoylyk.net	4	16032	12	16018	2	0%	25:49:21
16	localhost	32	1	1	0	0	100%	00:00:00
17	news.bbs.nz	1	15005	0	15005	0	0%	22:58:49
18	xanadu-bbs.net	26	438	0	438	0	0%	20:36:50
TOTAL: 18		3434	325026	16547	304382	4097	5%	663:52:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	9.9 MB	0.0 KB	4.4 MB	14.2 MB	69%	6.1 KB
2	csiph-out.news.weretis.net	9.5 MB	32.2 KB	6.0 MB	15.5 MB	61%	4.6 KB
3	feeder.eternal-september.org	5.4 MB	0.0 KB	374.5 KB	5.8 MB	93%	2.9 KB
4	news.freedyn.net	5.4 MB	0.0 KB	53.8 MB	59.2 MB	9%	19.0 KB
5	newsfeeds.xmission.com	4.3 MB	0.0 KB	186.7 KB	4.5 MB	95%	3.2 KB
6	csiph-out.news.xs4all.nl	3.7 MB	0.0 KB	7.7 MB	11.4 MB	32%	4.7 KB
7	news-out.aioe.org	3.0 MB	0.0 KB	55.1 KB	3.1 MB	98%	2.2 KB
8	news-out.mixmin.net	2.7 MB	0.0 KB	572.5 KB	3.2 MB	82%	3.5 KB
9	newsfeed.neva.ru	2.3 MB	0.0 KB	1.4 MB	3.7 MB	62%	2.6 KB
10	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	207.4 KB	2.4 MB	91%	2.7 KB
11	csiph-out.feed.uzoreto.com	815.0 KB	0.0 KB	3.4 MB	4.2 MB	19%	8.8 KB
12	news.fcku.it	417.9 KB	0.0 KB	39.9 KB	457.8 KB	91%	1.8 KB
13	nntp.pasdenom.info	78.3 KB	0.0 KB	14.8 KB	93.2 KB	84%	1.9 KB
14	news.swapon.de	72.7 KB	0.0 KB	48.5 KB	121.2 KB	59%	4.5 KB
15	news.samoylyk.net	38.6 KB	0.0 KB	130.1 KB	168.8 KB	22%	12.1 KB
16	localhost	1.5 KB	0.0 KB	0.0 KB	1.5 KB	100%	1.5 KB
17	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		49.8 MB	32.2 KB	78.2 MB	128.1 MB	38%	6.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 10 04:15:00 - 04:59:59	306	1.9%	0.11	1.9 MB	3.9%	0.73
Oct 10 05:00:00 - 05:59:59	332	2.0%	0.09	1.3 MB	2.7%	0.38
Oct 10 06:00:00 - 06:59:59	464	2.8%	0.13	1.5 MB	3.1%	0.43
Oct 10 07:00:00 - 07:59:59	604	3.7%	0.17	1.6 MB	3.2%	0.45
Oct 10 08:00:00 - 08:59:59	742	4.5%	0.21	2.0 MB	4.0%	0.56
Oct 10 09:00:00 - 09:59:59	817	5.0%	0.23	2.3 MB	4.8%	0.67
Oct 10 10:00:00 - 10:59:59	917	5.6%	0.25	2.4 MB	4.8%	0.68
Oct 10 11:00:00 - 11:59:59	770	4.7%	0.21	2.5 MB	5.2%	0.72
Oct 10 12:00:00 - 12:59:59	911	5.6%	0.25	2.3 MB	4.8%	0.66
Oct 10 13:00:00 - 13:59:59	929	5.7%	0.26	2.3 MB	4.7%	0.65
Oct 10 14:00:00 - 14:59:59	986	6.0%	0.27	3.1 MB	6.3%	0.88
Oct 10 15:00:00 - 15:59:59	921	5.6%	0.26	2.8 MB	5.8%	0.81
Oct 10 16:00:00 - 16:59:59	921	5.6%	0.26	2.5 MB	5.1%	0.71
Oct 10 17:00:00 - 17:59:59	913	5.6%	0.25	2.5 MB	5.1%	0.71
Oct 10 18:00:00 - 18:59:59	864	5.3%	0.24	2.9 MB	6.0%	0.84
Oct 10 19:00:00 - 19:59:59	868	5.3%	0.24	2.4 MB	4.8%	0.67
Oct 10 20:00:00 - 20:59:59	779	4.8%	0.22	2.6 MB	5.2%	0.73
Oct 10 21:00:00 - 21:59:59	734	4.5%	0.20	2.3 MB	4.8%	0.66
Oct 10 22:00:00 - 22:59:59	646	3.9%	0.18	2.0 MB	4.0%	0.56
Oct 10 23:00:00 - 23:59:59	488	3.0%	0.14	1.4 MB	2.9%	0.40
Oct 11 00:00:00 - 00:59:59	339	2.1%	0.09	855.8 KB	1.7%	0.24
Oct 11 01:00:00 - 01:59:59	300	1.8%	0.08	1006.2 KB	2.0%	0.28
Oct 11 02:00:00 - 02:59:59	403	2.5%	0.11	1.2 MB	2.5%	0.35
Oct 11 03:00:00 - 03:59:59	323	2.0%	0.09	1.1 MB	2.2%	0.30
Oct 11 04:00:00 - 04:15:00	79	0.5%	0.09	265.8 KB	0.5%	0.30
TOTAL: 24:00:00	16356	100.0%	0.19	49.1 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.freedyn.net	1223	19	0	1204
2	csiph-out.news.xs4all.nl	1127	27	0	1100
3	newsfeed.fu-berlin.de	498	30	0	468
4	newsfeed.neva.ru	480	105	0	375
5	csiph-out.feed.uzoreto.com	290	13	0	277
6	csiph-out.feeder.erje.net	290	65	0	225
7	csiph-out.news.weretis.net	264	136	1	127
8	news-out.mixmin.net	188	3	0	185
9	feeder.eternal-september.org	95	40	0	55
10	news-out.aioe.org	39	11	0	28
11	newsfeeds.xmission.com	15	0	0	15
12	nntp.pasdenom.info	8	0	0	8
13	news.fcku.it	6	4	0	2
14	news.swapon.de	5	0	0	5
15	news.samoylyk.net	2	0	0	2
TOTAL: 15		4530	453	1	4076

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	52
alt.computer.workshop	37
lada.auto	37
alt.windows7.general	32
rocksolid.shared.test	24
mozilla.support.seamonkey	21
it-alt.sport.calcio.juventus	17
alt.english.usage	16
ibm.software.network.directory-integrator	14
mozilla.general	12
alt.binaries.pictures.purebeauty	10
mozilla.support.thunderbird	9
mozilla.test	8
alt.bbs.synchronet	8
mozilla.tools.treeherder	7
fido7.ru.android	7
cakewalk.audio	5
alt.politics.usa	5
alt.tv.star-trek	5
mozilla.support.firefox	5
TOTAL: 94	453

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2473
EMP (phn path)	158
EMP (phl)	153
EMP (phn nph)	75
Too many newsgroups	51
HTML Multipart	21
User-issued cancel	15
Binary: misplaced rar	12
Binary Payload	12
Scoring filter (15)	11
Scoring filter (10)	5
User-issued spam cancel	4
Scoring filter (11)	3
Too many newsgroups (low_xpost)	3
HTML post	2
Scoring filter (8)	2
Scoring filter (12)	1
Scoring filter (9)	1
HTML Image Tags	1
TOTAL: 19	3003

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	22	0	26	26
TOTAL: 1	22	17	26	26

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	444
	csiph-out.feeder.erje.net	73
	csiph-out.news.weretis.net	53
	csiph-out.news.xs4all.nl	9
	csiph-out.feed.uzoreto.com	6
	news-out.mixmin.net	4
	newsfeeds.xmission.com	3
	news.samoylyk.net	2
	feeder.eternal-september.org	1
	news.swapon.de	1
	TOTAL: 10	596
Including strange strings
	newsfeed.fu-berlin.de	397
	csiph-out.news.xs4all.nl	30
	csiph-out.news.weretis.net	25
	news-out.mixmin.net	7
	news-out.aioe.org	6
	news.swapon.de	4
	newsfeed.neva.ru	3
	csiph-out.feeder.erje.net	1
	csiph-out.feed.uzoreto.com	1
	news.fcku.it	1
	TOTAL: 11	476
TOTAL: 3		1117

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.nz	24393	5734	10015	158	20	6512	23%	23:59:54
2	xanadu-bbs	19224	717	15637	1	0	3	3%	23:59:54
3	news.freedyn.net	14623	455	13936	7	0	0	3%	23:59:54
4	news.samoylyk.net	16933	429	15913	2	0	34	2%	23:59:53
5	fu-berlin.de	15339	423	14916	0	0	0	2%	23:59:54
6	usenet.pasdenom.info	16616	79	15802	436	0	0	0%	23:59:54
7	aioe	14323	21	13928	0	0	0	0%	23:58:52
8	newsfeed.xs4all.nl	15402	16	14585	5	0	35	0%	23:59:53
9	neva.ru	15010	9	14592	113	0	0	0%	23:59:54
10	xmission	18052	5	14526	0	0	0	0%	23:59:54
11	news.mixmin.net	15318	3	15007	0	0	0	0%	23:59:51
12	weretis.net	13196	3	12742	2	0	46	0%	23:59:54
13	eternal-september	14032	2	13695	0	0	0	0%	23:59:24
14	news.uzoreto.com	15946	2	15750	16	0	0	0%	23:59:36
15	erje	14332	1	14124	53	0	0	0%	23:59:54
16	news.swapon.de	1314	1	1299	0	0	0	0%	23:36:36
17	news.redatomik.org	0	0	0	0	0	15928	0%	23:59:54
18	news.fcku.it	1778	0	1770	0	0	0	0%	20:50:02
TOTAL: 18		245831	7900	218237	793	20	22558	3%	428:23:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.nz	14.7 MB	552.9 KB	15.3 MB	0.2 KB/s	2.7 KB	23:59:54
2	xanadu-bbs	5.7 MB	2.5 KB	5.7 MB	0.1 KB/s	8.1 KB	23:59:54
3	news.freedyn.net	5.0 MB	10.9 KB	5.0 MB	0.1 KB/s	11.1 KB	23:59:54
4	news.samoylyk.net	4.9 MB	2.1 KB	4.9 MB	0.1 KB/s	11.7 KB	23:59:53
5	fu-berlin.de	4.9 MB	0.0 KB	4.9 MB	0.1 KB/s	11.8 KB	23:59:54
6	usenet.pasdenom.info	302.9 KB	4.9 MB	5.2 MB	0.1 KB/s	10.4 KB	23:59:54
7	aioe	64.5 KB	0.0 KB	64.5 KB	0.0 KB/s	3.1 KB	23:58:52
8	newsfeed.xs4all.nl	62.9 KB	21.3 KB	84.2 KB	0.0 KB/s	4.0 KB	23:59:53
9	neva.ru	36.5 KB	278.6 KB	315.1 KB	0.0 KB/s	2.6 KB	23:59:54
10	news.uzoreto.com	16.8 KB	51.7 KB	68.5 KB	0.0 KB/s	3.8 KB	23:59:36
11	news.mixmin.net	13.4 KB	0.0 KB	13.4 KB	0.0 KB/s	4.5 KB	23:59:51
12	xmission	10.4 KB	0.0 KB	10.4 KB	0.0 KB/s	2.1 KB	23:59:54
13	weretis.net	8.4 KB	7.1 KB	15.5 KB	0.0 KB/s	3.1 KB	23:59:54
14	eternal-september	4.2 KB	0.0 KB	4.2 KB	0.0 KB/s	2.1 KB	23:59:24
15	erje	1.5 KB	196.3 KB	197.8 KB	0.0 KB/s	3.7 KB	23:59:54
16	news.swapon.de	1.5 KB	0.0 KB	1.5 KB	0.0 KB/s	1.5 KB	23:36:36
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:50:02
TOTAL: 18		35.7 MB	6.0 MB	41.8 MB	0.0 KB/s	4.9 KB	428:23:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	22	4	5.8 KB	2	0	0	03:18:31
2	208-71-239-109.cust.centrio.cz	1	1	2.5 KB	1	0	0	00:30:06
3	68-114-222-246.dhcp.mdsn.wi.charter.com	25	1	1.5 KB	1	1	1	07:46:24
TOTAL: 3		48	6	9.8 KB	4	1	1	12:06:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	22	4	5.8 KB	2	0	0	03:18:31
2	*.dhcp.mdsn.wi.charter.com	25	1	1.5 KB	1	1	1	07:46:24
3	*.cust.centrio.cz	1	1	2.5 KB	1	0	0	00:30:06
4	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:04
5	unresolved	5	0	0.0 KB	0	0	0	00:00:37
6	*.red.bezeqint.net	1	0	0.0 KB	0	0	0	00:00:32
TOTAL: 6		55	6	9.8 KB	4	1	1	12:06:17

NNRP auth users [Top 20]:

User	Conn
ohlandl	1
ctomczyk	1
TOTAL: 2	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
68-114-222-246.dhcp.mdsn.wi.charter.com	1.687	0.418	0.003	07:46:24
a131033.upc-a.chello.nl	1.596	0.303	0.000	03:18:31
208-71-239-109.cust.centrio.cz	0.118	0.015	0.003	00:30:06
c-73-140-175-55.hsd1.wa.comcast.net	0.072	0.008	0.000	00:30:04
bzq-199-13.red.bezeqint.net	0.079	0.000	0.000	00:00:32
201.111.19.128	0.066	0.017	0.000	00:00:32
94.166.156.228	0.144	0.023	0.000	00:00:02
94.162.20.150	0.155	0.008	0.000	00:00:02
TOTAL: 8	3.917	0.792	0.006	12:06:17

Curious NNRP server explorers [Top 20]:

System	Conn
94.166.156.228	2
94.162.20.150	2
c-73-140-175-55.hsd1.wa.comcast.net	1
201.111.19.128	1
bzq-199-13.red.bezeqint.net	1
TOTAL: 5	7

NNRP unrecognized commands (by host):

System	Conn
201.111.19.128	10
bzq-199-13.red.bezeqint.net	10
TOTAL: 2	20

NNRP unrecognized commands (by command):

Command	Count
User-Agent: ApiTool	2
Connection: close	2
Accept-Language: en-us	2
Cache-Control: max-age=0	2
Content-Length: 586	2
Accept: /	2
Authorization: Basic YWRtaW46ezEyMjEzQkQ...	2
Accept-Encoding: identity	2
Content-Type: text/xml	2
Host: 69.160.37.58	1
Host: 179.52.151.168	1
TOTAL: 11	20

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-114-222-246.dhcp.mdsn.wi.charter.com	9	0
208-71-239-109.cust.centrio.cz	1	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 3	11	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	5
2	comp.lang.javascript	1
TOTAL: 2		6

Daily Usenet report for csiph.com

Oct 10 04:15:00 -- Oct 11 04:15:00