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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	16113	45.4%	2.1 MB	30.9%
inn	15206	42.8%	4.1 MB	58.8%
innfeed	3508	9.9%	653.0 KB	9.3%
nnrpd	615	1.7%	65.8 KB	0.9%
nocem	50	0.1%	5.8 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	35493	100.0%	6.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	254330	59.0%
Negative hits	162125	37.6%
Do not exist	14717	3.4%
Cache misses	140	0.0%
TOTAL: 4	431312	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.139	0.0%	27	0.000	5.148	11.000
article cleanup	00:00:00.076	0.0%	14288	0.000	0.005	0.091
article logging	00:00:00.215	0.0%	14697	0.000	0.015	0.270
article parse	00:00:00.999	0.0%	48274	0.000	0.021	0.231
article write	00:00:05.449	0.0%	12705	0.062	0.429	8.370
artlog/artcncl	00:00:00.000	0.0%	27	0.000	0.000	0.000
artlog/artparse	00:00:00.002	0.0%	494	0.000	0.004	0.071
data move	00:00:00.139	0.0%	404313	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.004	0.0%	27	0.000	0.148	0.667
hishave/artcncl	00:00:00.001	0.0%	27	0.000	0.037	0.125
hishave/artparse	00:00:00.002	0.0%	488	0.000	0.004	0.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.926	0.0%	432080	0.000	0.011	0.364
history sync	00:00:00.650	0.0%	391	0.000	1.662	10.333
history write	00:00:07.107	0.0%	14280	0.043	0.498	6.825
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.566	0.0%	488	0.000	1.160	8.000
idle	23:58:42.440	99.8%	415026	119.011	207.993	668.046
nntp read	00:00:01.592	0.0%	414297	0.001	0.004	0.008
overview write	00:01:09.096	0.1%	12705	0.124	5.438	810.217
perl filter	00:00:19.349	0.0%	14274	1.038	1.356	1.957
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.194	0.0%	25459	0.000	0.008	0.038
TOTAL: 24:01:02.343	24:00:32.946	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.025	0.0%	12781	0.000	0.002	0.037
article prepare	00:00:00.001	0.0%	11204	0.000	0.000	0.009
article read	00:00:00.194	0.0%	1070	0.000	0.181	4.400
backlog stats	00:00:00.165	0.0%	307614	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.027	0.0%	236301	0.001	0.004	0.011
data write	00:00:01.398	0.0%	226930	0.000	0.006	0.021
idle	23:55:06.425	100.0%	307614	158.483	279.917	845.065
status file	00:00:00.182	0.0%	283	0.000	0.643	2.000
TOTAL: 23:55:14.677	23:55:09.417	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	27:37:32.831	100.0%	526	59.000	189073.823	600139.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	1243	0.000	0.003	0.167
readart	00:00:00.946	0.0%	82	0.000	11.537	56.000
TOTAL: 27:37:58.070	27:37:33.781	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	59063	3611	55164	288	6%	118:48:00
2	news-out.aioe.org	4	12279	2622	9627	30	21%	24:09:39
3	feeder.eternal-september.org	2	13726	1500	12050	176	10%	24:12:13
4	newsfeeds.xmission.com	2	12377	1274	11074	29	10%	24:41:14
5	newsfeed.neva.ru	146	15185	886	14079	220	5%	71:44:25
6	csiph-out.news.weretis.net	4	35129	686	34311	132	1%	66:29:17
7	newsfeed.fu-berlin.de	19	11713	573	11097	43	4%	25:15:05
8	csiph-out.news.xs4all.nl	861	13032	563	11970	499	4%	71:29:33
9	news-out.mixmin.net	1	13197	502	12366	329	3%	23:59:50
10	news.freedyn.net	1	14132	249	13812	71	1%	23:59:57
11	csiph-out.feed.uzoreto.com	862	18843	107	18543	193	0%	71:22:00
12	news-out.neodome.net	1	13131	90	13013	28	0%	23:59:55
13	csiph-out.news.tnetconsulting.net	2	11086	90	10989	7	0%	23:59:31
14	nntp.pasdenom.info	2	7805	85	7689	31	1%	23:58:29
15	news.fcku.it	16	923	49	871	3	5%	21:10:16
16	news.swapon.de	9	1009	22	987	0	2%	22:53:23
17	news.samoylyk.net	1	12373	4	12362	7	0%	23:59:54
18	localhost	29	3	3	0	0	100%	00:00:04
19	news.bbs.nz	1	10947	0	10947	0	0%	23:59:52
20	xanadu-bbs.net	33	328	0	328	0	0%	19:42:10
TOTAL: 20		3436	276281	12916	261279	2086	4%	729:54:47

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	13.8 MB	3.5 KB	8.1 MB	21.9 MB	63%	5.8 KB
2	news-out.aioe.org	6.2 MB	1.8 KB	88.5 KB	6.3 MB	98%	2.4 KB
3	feeder.eternal-september.org	4.4 MB	3.9 KB	4.6 MB	9.0 MB	48%	5.5 KB
4	newsfeeds.xmission.com	4.0 MB	0.0 KB	1.7 MB	5.7 MB	70%	4.5 KB
5	newsfeed.neva.ru	2.2 MB	0.0 KB	968.6 KB	3.2 MB	70%	2.9 KB
6	csiph-out.news.weretis.net	1.9 MB	3.5 KB	1.4 MB	3.4 MB	57%	4.2 KB
7	news-out.mixmin.net	1.6 MB	1.8 KB	585.4 KB	2.2 MB	74%	2.7 KB
8	csiph-out.news.xs4all.nl	1.6 MB	0.0 KB	10.0 MB	11.7 MB	14%	11.3 KB
9	newsfeed.fu-berlin.de	1.4 MB	0.0 KB	306.6 KB	1.7 MB	82%	2.8 KB
10	news.freedyn.net	1.0 MB	1.8 KB	1.1 MB	2.1 MB	47%	6.8 KB
11	csiph-out.feed.uzoreto.com	378.6 KB	3.4 KB	4.9 MB	5.3 MB	7%	18.0 KB
12	csiph-out.news.tnetconsulting.net	269.4 KB	4.2 KB	87.4 KB	360.9 KB	74%	3.7 KB
13	news-out.neodome.net	265.7 KB	1.8 KB	1.1 MB	1.3 MB	19%	11.6 KB
14	nntp.pasdenom.info	192.1 KB	0.0 KB	39.8 KB	231.9 KB	82%	2.0 KB
15	news.fcku.it	126.5 KB	0.0 KB	9.5 KB	136.0 KB	93%	2.6 KB
16	news.swapon.de	53.9 KB	0.0 KB	0.0 KB	53.9 KB	100%	2.4 KB
17	news.samoylyk.net	19.8 KB	1.8 KB	320.0 KB	341.6 KB	5%	31.1 KB
18	localhost	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	2.4 KB
19	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		39.6 MB	27.4 KB	35.3 MB	74.9 MB	52%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 26 04:15:00 - 04:59:59	195	1.5%	0.07	528.8 KB	1.3%	0.20
Dec 26 05:00:00 - 05:59:59	230	1.8%	0.06	682.6 KB	1.7%	0.19
Dec 26 06:00:00 - 06:59:59	251	2.0%	0.07	755.9 KB	1.9%	0.21
Dec 26 07:00:00 - 07:59:59	295	2.3%	0.08	1.2 MB	3.0%	0.34
Dec 26 08:00:00 - 08:59:59	419	3.3%	0.12	1.3 MB	3.3%	0.36
Dec 26 09:00:00 - 09:59:59	540	4.3%	0.15	2.2 MB	5.5%	0.61
Dec 26 10:00:00 - 10:59:59	664	5.2%	0.18	2.2 MB	5.6%	0.62
Dec 26 11:00:00 - 11:59:59	666	5.2%	0.18	1.9 MB	4.8%	0.53
Dec 26 12:00:00 - 12:59:59	643	5.1%	0.18	2.1 MB	5.2%	0.58
Dec 26 13:00:00 - 13:59:59	617	4.9%	0.17	1.5 MB	3.9%	0.43
Dec 26 14:00:00 - 14:59:59	643	5.1%	0.18	1.8 MB	4.7%	0.52
Dec 26 15:00:00 - 15:59:59	685	5.4%	0.19	2.2 MB	5.7%	0.64
Dec 26 16:00:00 - 16:59:59	820	6.5%	0.23	2.5 MB	6.3%	0.70
Dec 26 17:00:00 - 17:59:59	644	5.1%	0.18	1.6 MB	4.1%	0.46
Dec 26 18:00:00 - 18:59:59	675	5.3%	0.19	1.7 MB	4.4%	0.49
Dec 26 19:00:00 - 19:59:59	747	5.9%	0.21	2.4 MB	6.2%	0.69
Dec 26 20:00:00 - 20:59:59	660	5.2%	0.18	1.9 MB	4.9%	0.54
Dec 26 21:00:00 - 21:59:59	684	5.4%	0.19	1.8 MB	4.7%	0.52
Dec 26 22:00:00 - 22:59:59	619	4.9%	0.17	1.8 MB	4.7%	0.52
Dec 26 23:00:00 - 23:59:59	583	4.6%	0.16	2.3 MB	5.8%	0.64
Dec 27 00:00:00 - 00:59:59	386	3.0%	0.11	1.4 MB	3.6%	0.40
Dec 27 01:00:00 - 01:59:59	397	3.1%	0.11	1.2 MB	3.0%	0.33
Dec 27 02:00:00 - 02:59:59	320	2.5%	0.09	942.9 KB	2.4%	0.26
Dec 27 03:00:00 - 03:59:59	245	1.9%	0.07	1.1 MB	2.8%	0.31
Dec 27 04:00:00 - 04:15:00	65	0.5%	0.07	273.3 KB	0.7%	0.30
TOTAL: 24:00:00	12693	100.0%	0.15	39.1 MB	100.0%	0.46

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	497	15	0	482
2	newsfeed.fu-berlin.de	346	32	0	314
3	news-out.mixmin.net	338	1	1	336
4	csiph-out.feeder.erje.net	289	68	2	219
5	newsfeed.neva.ru	222	33	0	189
6	csiph-out.feed.uzoreto.com	191	1	2	188
7	feeder.eternal-september.org	184	13	2	169
8	csiph-out.news.weretis.net	124	26	1	97
9	news-out.aioe.org	115	24	1	90
10	news.freedyn.net	73	1	1	71
11	nntp.pasdenom.info	31	0	0	31
12	newsfeeds.xmission.com	30	0	0	30
13	news-out.neodome.net	28	1	1	26
14	news.samoylyk.net	7	0	1	6
15	csiph-out.news.tnetconsulting.net	6	1	2	3
16	news.fcku.it	3	0	0	3
17	news.swapon.de	2	0	0	2
TOTAL: 17		2486	216	14	2256

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.survival	32
spline.eisfair	17
mozilla.support.thunderbird	14
lada.auto	12
mozilla.general	12
oesterreich.tratsch	10
lada.talk	7
alt.binaries.pictures.purebeauty	7
lada.commerce	6
alt.slack.goathead	5
lada.photo	5
alt.binaries.ebooks	5
alt.paranormal.spells	4
alt.music.pink-floyd.publius	4
mozilla.support.firefox	4
alt.christian.religion	3
alt.tv.star-trek	3
mozilla.support.seamonkey	3
alt.bbs.synchronet	3
borland.public.kylix.marketing	2
TOTAL: 68	216

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1083
EMP (phn path)	113
0	70
Too many newsgroups	39
EMP (phl)	32
HTML Multipart	11
Binary: misplaced binary	5
Too many newsgroups (meow)	3
HTML post	2
Scoring filter	1
TOTAL: 10	1359

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feeder.erje.net	148
	csiph-out.news.xs4all.nl	128
	csiph-out.feed.uzoreto.com	64
	feeder.eternal-september.org	55
	newsfeeds.xmission.com	30
	news-out.neodome.net	20
	csiph-out.news.weretis.net	13
	news.freedyn.net	13
	news.samoylyk.net	6
	csiph-out.news.tnetconsulting.net	3
	TOTAL: 11	483
Including strange strings
	newsfeed.fu-berlin.de	302
	news-out.aioe.org	84
	news-out.mixmin.net	9
	csiph-out.news.weretis.net	7
	news.freedyn.net	4
	news.swapon.de	2
	csiph-out.news.xs4all.nl	1
	feeder.eternal-september.org	1
	TOTAL: 8	410
No colon-space in header
	csiph-out.news.xs4all.nl	3
	csiph-out.feeder.erje.net	1
	TOTAL: 2	4
TOTAL: 4		942

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	16879	561	12130	1	0	3%	23:59:33
2	news.bbs.nz	23935	435	11882	11	1	1%	23:59:56
3	news.freedyn.net	12751	401	12007	18	0	3%	23:59:56
4	news.samoylyk.net	13226	353	12334	2	0	2%	23:59:56
5	fu-berlin.de	12018	339	11678	0	0	2%	23:59:56
6	usenet.pasdenom.info	12744	149	12047	49	16	1%	23:59:56
7	tnet	14626	67	12264	0	0	0%	23:59:46
8	neva.ru	11464	44	11109	110	0	0%	23:59:56
9	aioe	9399	36	8986	2	0	0%	23:59:30
10	news.uzoreto.com	12677	23	12244	82	1	0%	23:59:39
11	newsfeed.xs4all.nl	12531	16	12009	10	84	0%	23:59:55
12	neodome.net	13062	14	12522	0	0	0%	23:59:51
13	news.mixmin.net	12152	7	11683	0	0	0%	23:59:56
14	xmission	12890	6	11068	1	0	0%	23:59:56
15	weretis.net	12712	5	11727	0	0	0%	23:59:40
16	eternal-september	11155	5	10749	0	0	0%	23:59:55
17	news.swapon.de	1037	3	1017	0	0	0%	23:09:30
18	erje	8992	2	8874	48	0	0%	23:59:49
19	news.fcku.it	760	1	756	0	0	0%	20:53:49
TOTAL: 19		225010	2467	197086	334	102	1%	452:00:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.7 MB	4.8 KB	4.7 MB	0.1 KB/s	8.5 KB	23:59:33
2	news.freedyn.net	4.4 MB	32.2 KB	4.4 MB	0.1 KB/s	10.8 KB	23:59:56
3	fu-berlin.de	4.2 MB	0.0 KB	4.2 MB	0.0 KB/s	12.6 KB	23:59:56
4	news.samoylyk.net	4.1 MB	59.8 KB	4.2 MB	0.0 KB/s	12.1 KB	23:59:56
5	news.bbs.nz	1.0 MB	74.5 KB	1.1 MB	0.0 KB/s	2.6 KB	23:59:56
6	usenet.pasdenom.info	383.0 KB	132.3 KB	515.3 KB	0.0 KB/s	2.6 KB	23:59:56
7	tnet	134.9 KB	0.0 KB	134.9 KB	0.0 KB/s	2.0 KB	23:59:46
8	neva.ru	79.6 KB	228.8 KB	308.5 KB	0.0 KB/s	2.0 KB	23:59:56
9	aioe	67.0 KB	2.5 KB	69.5 KB	0.0 KB/s	1.8 KB	23:59:30
10	news.uzoreto.com	50.8 KB	272.9 KB	323.7 KB	0.0 KB/s	3.1 KB	23:59:39
11	newsfeed.xs4all.nl	49.0 KB	22.4 KB	71.4 KB	0.0 KB/s	2.7 KB	23:59:55
12	eternal-september	34.8 KB	0.0 KB	34.8 KB	0.0 KB/s	7.0 KB	23:59:55
13	neodome.net	21.2 KB	0.0 KB	21.2 KB	0.0 KB/s	1.5 KB	23:59:51
14	xmission	14.9 KB	1.8 KB	16.8 KB	0.0 KB/s	2.4 KB	23:59:56
15	news.mixmin.net	14.8 KB	0.0 KB	14.8 KB	0.0 KB/s	2.1 KB	23:59:56
16	weretis.net	13.3 KB	0.0 KB	13.3 KB	0.0 KB/s	2.7 KB	23:59:40
17	news.swapon.de	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	2.4 KB	23:09:30
18	erje	3.7 KB	118.1 KB	121.8 KB	0.0 KB/s	2.4 KB	23:59:49
19	news.fcku.it	2.1 KB	0.0 KB	2.1 KB	0.0 KB/s	2.1 KB	20:53:49
TOTAL: 19		19.3 MB	950.2 KB	20.2 MB	0.0 KB/s	7.4 KB	452:00:25

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	4	48	193.2 KB	3	0	0	02:55:25
2	a131033.upc-a.chello.nl	67	19	42.6 KB	10	0	0	17:54:55
3	71-13-20-234.dhcp.dlth.mn.charter.com	16	14	33.6 KB	6	3	3	04:43:24
4	208-71-239-109.cust.centrio.cz	6	1	31.8 KB	1	0	0	02:04:08
TOTAL: 4		93	82	301.2 KB	20	3	3	27:37:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	4	48	193.2 KB	3	0	0	02:55:25
2	*.upc-a.chello.nl	67	19	42.6 KB	10	0	0	17:54:55
3	*.dhcp.dlth.mn.charter.com	16	14	33.6 KB	6	3	3	04:43:24
4	*.cust.centrio.cz	6	1	31.8 KB	1	0	0	02:04:08
5	unresolved	1	0	0.0 KB	0	0	0	00:00:01
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
TOTAL: 6		95	82	301.2 KB	20	3	3	27:37:57

NNRP auth users [Top 20]:

User	Conn
ctomczyk	6
ohlandl	3
TOTAL: 2	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	4.839	1.015	0.000	17:54:55
71-13-20-234.dhcp.dlth.mn.charter.com	1.269	0.263	0.007	04:43:24
ip72-222-200-117.ph.ph.cox.net	0.599	0.144	0.000	02:55:25
208-71-239-109.cust.centrio.cz	0.441	0.107	0.014	02:04:08
94.167.176.155	0.051	0.025	0.000	00:00:01
scan-141.security.ipip.net	0.048	0.028	0.000	00:00:01
TOTAL: 6	7.247	1.582	0.021	27:37:57

Curious NNRP server explorers [Top 20]:

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

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-13-20-234.dhcp.dlth.mn.charter.com	6	0
ip72-222-200-117.ph.ph.cox.net	5	0
208-71-239-109.cust.centrio.cz	4	0
TOTAL: 3	15	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	46	97.9%
2	pl	1	2.1%
TOTAL: 2		47	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	45
2	pl.comp.www	1
3	comp.sys.sun.hardware	1
TOTAL: 3		47

Daily Usenet report for csiph.com

Dec 26 04:15:00 -- Dec 27 04:15:00