Daily Usenet report for csiph.com: Dec 5 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19965	48.6%	5.1 MB	63.5%
innd	16825	41.0%	2.3 MB	28.1%
innfeed	3395	8.3%	591.3 KB	7.2%
nnrpd	848	2.1%	92.8 KB	1.1%
nocem	47	0.1%	5.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41081	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	321927	59.1%
Negative hits	202681	37.2%
Do not exist	19390	3.6%
Cache misses	460	0.1%
TOTAL: 4	544458	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.479	0.0%	39	0.000	12.282	18.167
article cleanup	00:00:00.130	0.0%	18972	0.000	0.007	0.042
article logging	00:00:00.292	0.0%	19407	0.000	0.015	0.203
article parse	00:00:00.831	0.0%	59229	0.000	0.014	0.116
article write	00:00:11.637	0.0%	16579	0.146	0.702	18.569
artlog/artcncl	00:00:00.000	0.0%	39	0.000	0.000	0.000
artlog/artparse	00:00:00.004	0.0%	474	0.000	0.008	0.200
data move	00:00:00.161	0.0%	502773	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.013	0.0%	39	0.000	0.333	1.000
hishave/artcncl	00:00:00.009	0.0%	39	0.000	0.231	1.000
hishave/artparse	00:00:00.003	0.0%	467	0.000	0.006	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.115	0.0%	545670	0.002	0.009	0.285
history sync	00:00:00.682	0.0%	330	0.000	2.067	6.000
history write	00:00:10.198	0.0%	18970	0.056	0.538	4.617
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.313	0.0%	467	0.000	0.670	5.000
idle	23:47:34.201	99.8%	514501	78.652	166.480	541.909
nntp read	00:00:01.909	0.0%	514817	0.001	0.004	0.007
overview write	00:01:35.264	0.1%	16579	0.167	5.746	1298.750
perl filter	00:00:27.065	0.0%	18961	1.158	1.427	2.143
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.265	0.0%	33205	0.000	0.008	0.036
TOTAL: 23:50:41.191	23:50:08.571	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	18018	0.000	0.002	0.020
article prepare	00:00:00.003	0.0%	14820	0.000	0.000	0.023
article read	00:00:00.276	0.0%	1491	0.000	0.185	11.750
backlog stats	00:00:00.188	0.0%	384196	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.393	0.0%	293520	0.001	0.005	0.010
data write	00:00:01.766	0.0%	280085	0.002	0.006	0.013
idle	23:54:34.483	100.0%	384196	136.254	224.038	666.018
status file	00:00:00.187	0.0%	289	0.000	0.647	2.000
TOTAL: 23:54:44.242	23:54:38.331	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	38:48:20.543	100.0%	641	136.000	217941.565	600122.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.012	0.0%	1479	0.000	0.008	0.167
readart	00:00:00.255	0.0%	52	0.000	4.904	42.000
TOTAL: 38:48:43.662	38:48:20.810	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	79299	4314	74547	438	5%	118:45:49
2	news-out.aioe.org	1	15926	3225	12649	52	20%	23:59:57
3	feeder.eternal-september.org	2	19089	2164	16719	206	11%	26:26:08
4	newsfeeds.xmission.com	2	15743	1716	13985	42	10%	23:56:45
5	newsfeed.neva.ru	155	24634	1042	23367	225	4%	73:47:57
6	newsfeed.fu-berlin.de	9	17290	886	16378	26	5%	28:42:45
7	csiph-out.news.xs4all.nl	861	17691	873	15902	916	4%	71:28:41
8	news-out.mixmin.net	1	17160	741	16146	273	4%	23:59:50
9	csiph-out.news.weretis.net	4	31167	660	30422	85	2%	43:53:44
10	news.freedyn.net	1	18602	570	17795	237	3%	23:59:56
11	news.fcku.it	12	2102	277	1823	2	13%	22:30:05
12	csiph-out.feed.uzoreto.com	864	24714	196	24157	361	0%	71:17:09
13	nntp.pasdenom.info	3	11002	53	10934	15	0%	23:57:52
14	csiph-out.news.tnetconsulting.net	2	16280	34	16226	20	0%	23:59:55
15	news.swapon.de	7	1434	25	1408	1	1%	23:03:20
16	news.samoylyk.net	3	16106	16	16090	0	0%	24:37:16
17	localhost	34	8	8	0	0	100%	00:00:00
18	news.bbs.nz	2	15908	7	15901	0	0%	23:59:52
19	xanadu-bbs.net	34	412	0	412	0	0%	21:24:28
TOTAL: 19		3437	344567	16807	324861	2899	4%	693:51:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.2 MB	24.9 KB	11.3 MB	31.5 MB	64%	6.8 KB
2	news-out.aioe.org	7.8 MB	0.0 KB	167.8 KB	7.9 MB	97%	2.5 KB
3	feeder.eternal-september.org	6.0 MB	6.5 KB	1.3 MB	7.2 MB	82%	3.1 KB
4	newsfeeds.xmission.com	5.7 MB	0.0 KB	2.3 MB	8.0 MB	71%	4.7 KB
5	csiph-out.news.xs4all.nl	2.5 MB	0.0 KB	6.8 MB	9.3 MB	27%	5.3 KB
6	newsfeed.neva.ru	2.4 MB	1.2 KB	650.0 KB	3.1 MB	79%	2.5 KB
7	news-out.mixmin.net	2.3 MB	0.0 KB	453.1 KB	2.8 MB	84%	2.8 KB
8	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	84.2 KB	2.3 MB	96%	2.6 KB
9	csiph-out.news.weretis.net	1.9 MB	1.3 KB	1.6 MB	3.5 MB	54%	4.8 KB
10	news.freedyn.net	1.8 MB	0.0 KB	1.0 MB	2.8 MB	63%	3.5 KB
11	csiph-out.feed.uzoreto.com	756.6 KB	0.0 KB	5.6 MB	6.3 MB	11%	11.7 KB
12	news.fcku.it	483.3 KB	0.0 KB	2.2 KB	485.5 KB	99%	1.7 KB
13	csiph-out.news.tnetconsulting.net	156.2 KB	3.6 KB	299.4 KB	459.1 KB	34%	8.5 KB
14	nntp.pasdenom.info	109.1 KB	0.0 KB	25.5 KB	134.6 KB	81%	2.0 KB
15	news.swapon.de	65.6 KB	0.0 KB	19.1 KB	84.7 KB	77%	3.3 KB
16	news.samoylyk.net	45.9 KB	0.0 KB	0.0 KB	45.9 KB	100%	2.9 KB
17	news.bbs.nz	21.2 KB	0.0 KB	0.0 KB	21.2 KB	100%	3.0 KB
18	localhost	11.0 KB	0.0 KB	0.0 KB	11.0 KB	100%	1.4 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		54.5 MB	37.6 KB	31.6 MB	86.1 MB	63%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 5 04:15:00 - 04:59:59	231	1.4%	0.09	913.5 KB	1.7%	0.34
Dec 5 05:00:00 - 05:59:59	357	2.1%	0.10	1.7 MB	3.1%	0.48
Dec 5 06:00:00 - 06:59:59	494	3.0%	0.14	2.1 MB	3.9%	0.60
Dec 5 07:00:00 - 07:59:59	534	3.2%	0.15	2.1 MB	3.9%	0.60
Dec 5 08:00:00 - 08:59:59	661	4.0%	0.18	1.6 MB	3.1%	0.47
Dec 5 09:00:00 - 09:59:59	741	4.5%	0.21	2.6 MB	4.9%	0.75
Dec 5 10:00:00 - 10:59:59	759	4.6%	0.21	2.2 MB	4.0%	0.62
Dec 5 11:00:00 - 11:59:59	829	5.0%	0.23	2.6 MB	4.8%	0.74
Dec 5 12:00:00 - 12:59:59	852	5.1%	0.24	3.0 MB	5.6%	0.86
Dec 5 13:00:00 - 13:59:59	866	5.2%	0.24	2.4 MB	4.5%	0.68
Dec 5 14:00:00 - 14:59:59	891	5.4%	0.25	2.4 MB	4.5%	0.68
Dec 5 15:00:00 - 15:59:59	970	5.8%	0.27	3.2 MB	5.9%	0.91
Dec 5 16:00:00 - 16:59:59	1069	6.4%	0.30	3.4 MB	6.4%	0.98
Dec 5 17:00:00 - 17:59:59	1007	6.1%	0.28	3.5 MB	6.5%	0.99
Dec 5 18:00:00 - 18:59:59	904	5.4%	0.25	2.4 MB	4.5%	0.68
Dec 5 19:00:00 - 19:59:59	834	5.0%	0.23	2.7 MB	5.0%	0.76
Dec 5 20:00:00 - 20:59:59	891	5.4%	0.25	2.6 MB	4.8%	0.73
Dec 5 21:00:00 - 21:59:59	754	4.5%	0.21	2.5 MB	4.7%	0.72
Dec 5 22:00:00 - 22:59:59	632	3.8%	0.18	1.8 MB	3.4%	0.52
Dec 5 23:00:00 - 23:59:59	686	4.1%	0.19	2.8 MB	5.2%	0.79
Dec 6 00:00:00 - 00:59:59	485	2.9%	0.13	1.4 MB	2.7%	0.41
Dec 6 01:00:00 - 01:59:59	449	2.7%	0.12	1.3 MB	2.4%	0.36
Dec 6 02:00:00 - 02:59:59	314	1.9%	0.09	975.5 KB	1.8%	0.27
Dec 6 03:00:00 - 03:59:59	338	2.0%	0.09	1.4 MB	2.6%	0.39
Dec 6 04:00:00 - 04:15:00	64	0.4%	0.07	165.0 KB	0.3%	0.18
TOTAL: 24:00:00	16612	100.0%	0.19	53.8 MB	100.0%	0.64

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	919	25	0	894
2	csiph-out.feeder.erje.net	438	115	5	318
3	csiph-out.feed.uzoreto.com	366	14	0	352
4	newsfeed.fu-berlin.de	320	15	0	305
5	news-out.mixmin.net	282	0	0	282
6	news.freedyn.net	240	8	0	232
7	newsfeed.neva.ru	226	78	1	147
8	feeder.eternal-september.org	201	20	2	179
9	news-out.aioe.org	154	38	0	116
10	csiph-out.news.weretis.net	99	34	1	64
11	newsfeeds.xmission.com	43	0	0	43
12	nntp.pasdenom.info	15	0	0	15
13	csiph-out.news.tnetconsulting.net	6	6	0	0
14	news.swapon.de	3	0	0	3
15	news.fcku.it	2	2	0	0
TOTAL: 15		3314	355	9	2950

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	51
alt.binaries.ctb	21
alt.binaries.pictures.purebeauty	18
mozilla.support.firefox	17
alt.bbs.synchronet	15
alt.survival	14
test.post	13
mozilla.dev.platform	13
alt.windows7.general	13
it-alt.sport.calcio.juventus	12
lada.auto	11
mozilla.support.seamonkey	11
alt.binaries.ebooks	10
mozilla.support.thunderbird	10
lada.commerce	8
oesterreich.tratsch	6
alt.music.pink-floyd.publius	6
alt.tv.star-trek	5
alt.religion	4
att.jobs	4
TOTAL: 83	355

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1724
EMP (phn nph)	118
0	75
Too many newsgroups	56
EMP (phl)	22
Scoring filter	20
HTML Multipart	20
Binary: misplaced binary	3
U2 violation - invalid distribution	1
TOTAL: 9	2039

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	2
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	203
	csiph-out.news.xs4all.nl	98
	csiph-out.feed.uzoreto.com	94
	newsfeeds.xmission.com	42
	csiph-out.news.weretis.net	18
	news.freedyn.net	8
	feeder.eternal-september.org	4
	TOTAL: 7	467
Including strange strings
	newsfeed.fu-berlin.de	295
	news-out.aioe.org	106
	csiph-out.news.weretis.net	11
	news-out.mixmin.net	8
	news.freedyn.net	5
	csiph-out.news.xs4all.nl	4
	csiph-out.feed.uzoreto.com	4
	news.swapon.de	2
	TOTAL: 8	435
No colon-space in header
	csiph-out.news.xs4all.nl	3
	TOTAL: 1	3
TOTAL: 5		952

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	21652	927	15681	3	0	4%	23:59:46
2	news.freedyn.net	16314	622	15381	20	0	3%	23:59:56
3	news.samoylyk.net	17187	577	16019	0	1015	3%	23:59:56
4	fu-berlin.de	15633	563	15070	0	0	3%	23:59:40
5	news.bbs.nz	31223	376	15639	20	0	1%	23:59:53
6	usenet.pasdenom.info	16721	143	15793	48	32	0%	23:59:56
7	neva.ru	15403	109	14607	36	326	0%	23:59:56
8	tnet	19865	32	16011	0	0	0%	23:59:38
9	newsfeed.xs4all.nl	16116	31	15566	3	50	0%	23:59:55
10	aioe	12330	23	11869	3	0	0%	23:59:56
11	news.uzoreto.com	16143	17	15834	63	0	0%	23:59:40
12	news.mixmin.net	15605	15	15112	0	0	0%	23:59:48
13	xmission	17697	13	14320	0	0	0%	23:59:05
14	eternal-september	14337	11	13852	0	0	0%	23:59:52
15	weretis.net	16112	9	15179	0	0	0%	23:59:56
16	news.swapon.de	1489	8	1467	0	0	0%	23:35:01
17	erje	12322	3	12189	33	0	0%	23:59:39
18	news.fcku.it	1740	1	1726	0	0	0%	21:36:37
TOTAL: 18		277889	3480	241315	229	1423	1%	429:08:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	9.8 MB	50.8 KB	9.9 MB	0.1 KB/s	10.9 KB	23:59:46
2	news.freedyn.net	8.9 MB	36.4 KB	8.9 MB	0.1 KB/s	14.3 KB	23:59:56
3	news.samoylyk.net	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	15.5 KB	23:59:56
4	fu-berlin.de	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	15.8 KB	23:59:40
5	news.bbs.nz	989.3 KB	73.5 KB	1.0 MB	0.0 KB/s	2.7 KB	23:59:53
6	usenet.pasdenom.info	398.1 KB	146.8 KB	544.9 KB	0.0 KB/s	2.9 KB	23:59:56
7	neva.ru	261.3 KB	89.0 KB	350.3 KB	0.0 KB/s	2.4 KB	23:59:56
8	newsfeed.xs4all.nl	95.2 KB	11.7 KB	106.9 KB	0.0 KB/s	3.1 KB	23:59:55
9	tnet	82.5 KB	0.0 KB	82.5 KB	0.0 KB/s	2.6 KB	23:59:38
10	news.uzoreto.com	42.7 KB	293.7 KB	336.4 KB	0.0 KB/s	4.2 KB	23:59:40
11	aioe	33.5 KB	4.4 KB	37.8 KB	0.0 KB/s	1.5 KB	23:59:56
12	news.mixmin.net	32.4 KB	0.0 KB	32.4 KB	0.0 KB/s	2.2 KB	23:59:48
13	xmission	23.2 KB	0.0 KB	23.2 KB	0.0 KB/s	1.8 KB	23:59:05
14	eternal-september	17.2 KB	0.0 KB	17.2 KB	0.0 KB/s	1.6 KB	23:59:52
15	weretis.net	14.3 KB	0.0 KB	14.3 KB	0.0 KB/s	1.6 KB	23:59:56
16	news.swapon.de	11.0 KB	0.0 KB	11.0 KB	0.0 KB/s	1.4 KB	23:35:01
17	erje	5.4 KB	121.9 KB	127.3 KB	0.0 KB/s	3.5 KB	23:59:39
18	news.fcku.it	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	2.0 KB	21:36:37
TOTAL: 18		38.1 MB	828.2 KB	39.0 MB	0.0 KB/s	10.8 KB	429:08:10

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	10	20	29.7 KB	3	0	0	04:15:57
2	71-90-73-106.dhcp.ftbg.wi.charter.com	31	18	27.2 KB	14	8	8	10:41:25
3	a131033.upc-a.chello.nl	77	14	22.2 KB	6	0	0	23:10:22
TOTAL: 3		118	52	79.1 KB	23	8	8	38:48:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	10	20	29.7 KB	3	0	0	04:15:57
2	*.dhcp.ftbg.wi.charter.com	31	18	27.2 KB	14	8	8	10:41:25
3	*.upc-a.chello.nl	77	14	22.2 KB	6	0	0	23:10:22
4	unresolved	6	0	0.0 KB	0	0	0	00:00:08
5	*.dhcp.stpt.wi.charter.com	2	0	0.0 KB	0	0	0	00:40:49
TOTAL: 5		126	52	79.1 KB	23	8	8	38:48:42

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
a131033.upc-a.chello.nl	5.356	1.151	0.000	23:10:22
71-90-73-106.dhcp.ftbg.wi.charter.com	2.400	0.560	0.022	10:41:25
ip72-222-200-117.ph.ph.cox.net	0.926	0.203	0.000	04:15:57
71-82-225-84.dhcp.stpt.wi.charter.com	0.172	0.060	0.000	00:40:49
94.162.133.52	0.201	0.031	0.000	00:00:03
122.228.19.80	0.072	0.008	0.000	00:00:03
94.165.147.0	0.068	0.008	0.000	00:00:01
94.162.157.124	0.051	0.026	0.000	00:00:01
TOTAL: 8	9.246	2.047	0.022	38:48:42

Curious NNRP server explorers [Top 20]:

System	Conn
94.162.133.52	3
71-82-225-84.dhcp.stpt.wi.charter.com	2
94.165.147.0	1
94.162.157.124	1
122.228.19.80	1
TOTAL: 5	8

NNRP unrecognized commands (by host):

System	Conn
122.228.19.80	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Accept: /	1
Host: 69.160.37.58:119	1
Connection: keep-alive	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-90-73-106.dhcp.ftbg.wi.charter.com	14	0
ip72-222-200-117.ph.ph.cox.net	7	0
TOTAL: 2	21	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	49	94.2%
2	news	3	5.8%
TOTAL: 2		52	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	49
2	news.software.nntp	3
TOTAL: 2		52

Daily Usenet report for csiph.com

Dec 5 04:15:00 -- Dec 6 04:15:00