Daily Usenet report for csiph.com: Jul 8 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20334	48.2%	5.3 MB	63.6%
innd	17298	41.0%	2.3 MB	27.5%
innfeed	3551	8.4%	651.2 KB	7.7%
nnrpd	933	2.2%	100.1 KB	1.2%
nocem	39	0.1%	4.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	42156	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	319760	56.8%
Negative hits	223556	39.7%
Do not exist	19793	3.5%
Cache misses	307	0.1%
TOTAL: 4	563416	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.582	0.0%	33	0.000	17.636	41.100
article cleanup	00:00:00.115	0.0%	19444	0.000	0.006	0.050
article logging	00:00:00.375	0.0%	19865	0.000	0.019	0.458
article parse	00:00:01.629	0.0%	57908	0.000	0.028	0.718
article write	00:00:24.177	0.0%	16315	0.135	1.482	33.538
artlog/artcncl	00:00:00.001	0.0%	33	0.000	0.030	0.100
artlog/artparse	00:00:00.020	0.0%	427	0.000	0.047	7.500
data move	00:00:00.156	0.0%	522072	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.018	0.0%	30	0.000	0.600	0.875
hishave/artcncl	00:00:00.031	0.0%	33	0.000	0.939	3.833
hishave/artparse	00:00:00.000	0.0%	418	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:08.280	0.0%	564867	0.002	0.015	0.205
history sync	00:00:00.919	0.0%	367	0.000	2.504	12.333
history write	00:00:14.984	0.0%	19439	0.197	0.771	7.430
hiswrite/artcncl	00:00:00.001	0.0%	3	0.000	0.333	0.500
hiswrite/artparse	00:00:01.048	0.0%	418	0.000	2.507	23.719
idle	23:58:04.950	99.8%	527548	97.064	163.558	539.136
nntp read	00:00:02.040	0.0%	529371	0.001	0.004	0.008
overview write	00:00:57.737	0.1%	16315	0.108	3.539	115.960
perl filter	00:00:26.150	0.0%	19430	1.032	1.346	1.825
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.263	0.0%	32668	0.000	0.008	0.042
TOTAL: 24:00:58.129	24:00:23.476	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	16583	0.000	0.002	0.019
article prepare	00:00:00.003	0.0%	11624	0.000	0.000	0.014
article read	00:00:00.280	0.0%	1394	0.000	0.201	9.333
backlog stats	00:00:00.206	0.0%	381268	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.357	0.0%	301813	0.001	0.004	0.009
data write	00:00:01.683	0.0%	287942	0.002	0.006	0.011
idle	23:54:32.081	100.0%	381268	133.456	225.752	604.596
status file	00:00:00.185	0.0%	286	0.000	0.647	2.000
TOTAL: 23:54:44.132	23:54:35.826	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	42:32:46.862	99.8%	678	71.500	225909.826	600164.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.012	0.0%	1595	0.000	0.008	0.167
readart	00:00:00.377	0.0%	48	0.000	7.854	43.000
TOTAL: 42:36:54.358	42:32:47.251	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	15841	4030	11765	46	25%	24:34:32
2	news-out.aioe.org	2	15993	3769	12078	146	23%	23:59:49
3	feeder.eternal-september.org	1	18954	2495	16173	286	13%	25:24:09
4	csiph-out.news.weretis.net	5	36489	1191	35111	187	3%	50:46:51
5	news-out.mixmin.net	3	17578	963	16078	537	5%	24:08:42
6	csiph-out.feeder.erje.net	1440	82076	946	80705	425	1%	118:46:54
7	newsfeed.fu-berlin.de	13	19396	836	18382	178	4%	32:10:45
8	newsfeed.neva.ru	144	16790	807	15620	363	4%	71:39:14
9	csiph-out.news.xs4all.nl	861	18150	598	16917	635	3%	71:28:52
10	csiph-out.feed.uzoreto.com	864	25980	363	24830	787	1%	71:11:28
11	news.fcku.it	10	1856	259	1589	8	13%	21:29:30
12	news-out.neodome.net	1	18069	175	17865	29	0%	23:59:54
13	nntp.pasdenom.info	4	11262	110	11083	69	0%	23:49:08
14	csiph-out.news.tnetconsulting.net	1	17031	95	16898	38	0%	23:59:58
15	news.swapon.de	11	1348	52	1291	5	3%	23:35:00
16	news.bbs.nz	2	16602	6	16596	0	0%	23:59:52
17	localhost	32	6	6	0	0	100%	00:00:03
18	news.samoylyk.net	1	15783	6	15776	1	0%	23:59:52
19	xanadu-bbs.net	30	414	0	414	0	0%	21:41:32
TOTAL: 19		3428	349618	16707	329171	3740	4%	700:46:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.9 MB	0.0 KB	2.5 MB	14.4 MB	82%	3.6 KB
2	news-out.aioe.org	9.5 MB	3.4 KB	394.2 KB	9.8 MB	96%	2.6 KB
3	feeder.eternal-september.org	6.9 MB	3.4 KB	1.5 MB	8.5 MB	81%	3.1 KB
4	csiph-out.feeder.erje.net	5.0 MB	6.9 KB	4.2 MB	9.1 MB	54%	6.8 KB
5	csiph-out.news.weretis.net	3.0 MB	3.4 KB	3.3 MB	6.3 MB	47%	4.7 KB
6	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	524.3 KB	2.7 MB	80%	2.7 KB
7	news-out.mixmin.net	2.1 MB	3.4 KB	1.1 MB	3.2 MB	66%	2.2 KB
8	newsfeed.neva.ru	1.6 MB	10.2 KB	930.3 KB	2.5 MB	63%	2.2 KB
9	csiph-out.news.xs4all.nl	1.5 MB	0.0 KB	5.5 MB	7.0 MB	21%	5.8 KB
10	csiph-out.feed.uzoreto.com	1.4 MB	2.8 KB	18.3 MB	19.7 MB	6%	17.5 KB
11	news-out.neodome.net	576.6 KB	3.4 KB	44.9 KB	624.8 KB	92%	3.1 KB
12	news.fcku.it	419.4 KB	0.0 KB	109.3 KB	528.6 KB	79%	2.0 KB
13	csiph-out.news.tnetconsulting.net	300.8 KB	0.0 KB	797.9 KB	1.1 MB	27%	8.3 KB
14	nntp.pasdenom.info	243.5 KB	0.0 KB	107.5 KB	351.0 KB	69%	2.0 KB
15	news.swapon.de	147.9 KB	0.0 KB	9.2 KB	157.0 KB	94%	2.8 KB
16	news.samoylyk.net	30.9 KB	3.4 KB	3.4 KB	37.7 KB	81%	5.4 KB
17	localhost	19.6 KB	0.0 KB	0.0 KB	19.6 KB	100%	3.3 KB
18	news.bbs.nz	16.5 KB	0.0 KB	0.0 KB	16.5 KB	100%	2.8 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		46.7 MB	40.2 KB	39.2 MB	86.0 MB	54%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 8 04:15:00 - 04:59:59	224	1.4%	0.08	898.3 KB	1.9%	0.33
Jul 8 05:00:00 - 05:59:59	495	3.0%	0.14	1.6 MB	3.6%	0.46
Jul 8 06:00:00 - 06:59:59	564	3.5%	0.16	1.6 MB	3.6%	0.47
Jul 8 07:00:00 - 07:59:59	665	4.1%	0.18	1.6 MB	3.5%	0.45
Jul 8 08:00:00 - 08:59:59	706	4.3%	0.20	2.4 MB	5.2%	0.67
Jul 8 09:00:00 - 09:59:59	713	4.4%	0.20	1.9 MB	4.2%	0.55
Jul 8 10:00:00 - 10:59:59	796	4.9%	0.22	2.1 MB	4.5%	0.59
Jul 8 11:00:00 - 11:59:59	765	4.7%	0.21	2.0 MB	4.3%	0.56
Jul 8 12:00:00 - 12:59:59	804	4.9%	0.22	1.9 MB	4.3%	0.55
Jul 8 13:00:00 - 13:59:59	868	5.3%	0.24	2.2 MB	4.7%	0.61
Jul 8 14:00:00 - 14:59:59	998	6.1%	0.28	2.6 MB	5.7%	0.73
Jul 8 15:00:00 - 15:59:59	937	5.7%	0.26	2.6 MB	5.6%	0.73
Jul 8 16:00:00 - 16:59:59	938	5.7%	0.26	2.4 MB	5.3%	0.69
Jul 8 17:00:00 - 17:59:59	963	5.9%	0.27	2.7 MB	5.8%	0.76
Jul 8 18:00:00 - 18:59:59	862	5.3%	0.24	2.5 MB	5.5%	0.71
Jul 8 19:00:00 - 19:59:59	863	5.3%	0.24	2.5 MB	5.5%	0.71
Jul 8 20:00:00 - 20:59:59	845	5.2%	0.23	2.3 MB	5.0%	0.65
Jul 8 21:00:00 - 21:59:59	741	4.5%	0.21	2.2 MB	4.7%	0.62
Jul 8 22:00:00 - 22:59:59	671	4.1%	0.19	2.1 MB	4.6%	0.59
Jul 8 23:00:00 - 23:59:59	344	2.1%	0.10	1.1 MB	2.5%	0.32
Jul 9 00:00:00 - 00:59:59	366	2.2%	0.10	1.1 MB	2.3%	0.30
Jul 9 01:00:00 - 01:59:59	386	2.4%	0.11	1.2 MB	2.6%	0.34
Jul 9 02:00:00 - 02:59:59	358	2.2%	0.10	1.0 MB	2.2%	0.29
Jul 9 03:00:00 - 03:59:59	351	2.2%	0.10	960.4 KB	2.1%	0.27
Jul 9 04:00:00 - 04:15:00	93	0.6%	0.10	458.1 KB	1.0%	0.51
TOTAL: 24:00:00	16316	100.0%	0.19	45.7 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feed.uzoreto.com	788	43	2	743
2	csiph-out.news.xs4all.nl	643	33	0	610
3	news-out.mixmin.net	551	2	1	548
4	newsfeed.fu-berlin.de	440	24	0	416
5	newsfeed.neva.ru	367	64	3	300
6	csiph-out.feeder.erje.net	294	54	2	238
7	feeder.eternal-september.org	286	49	1	236
8	news-out.aioe.org	225	116	1	108
9	csiph-out.news.weretis.net	183	77	1	105
10	nntp.pasdenom.info	69	0	0	69
11	newsfeeds.xmission.com	48	16	0	32
12	csiph-out.news.tnetconsulting.net	39	38	1	0
13	news-out.neodome.net	30	2	1	27
14	news.swapon.de	14	0	0	14
15	news.fcku.it	8	7	0	1
16	news.samoylyk.net	1	0	1	0
TOTAL: 16		3986	525	14	3447

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	124
it-alt.sport.calcio.juventus	54
scinet.stats	26
lada.auto	25
lada.talk	25
alt.politics	25
mozilla.support.seamonkey	23
alt.bbs.synchronet	18
trollnet.cinema	14
alt.windows7.general	12
alt.paranormal.spells	10
mozilla.general	9
lada.kitchen	9
alt.politics.usa	8
cakewalk.audio	8
att.jobs	7
oesterreich.tratsch	7
alt.lawyers	7
alt.culture	6
mozilla.support.firefox	6
TOTAL: 74	525

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1967
EMP (phl)	215
EMP (phn path)	171
0	115
Too many newsgroups	70
HTML Multipart	28
EMP (phn nph)	19
Binary: misplaced binary	5
Scoring filter	4
Too many newsgroups (meow)	3
HTML post	1
TOTAL: 11	2598

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	55
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	2
	TOTAL: 3	59
Huge articles
	csiph-out.feed.uzoreto.com	212
	csiph-out.feeder.erje.net	72
	csiph-out.news.xs4all.nl	58
	csiph-out.news.weretis.net	32
	newsfeeds.xmission.com	29
	feeder.eternal-september.org	10
	news-out.mixmin.net	4
	newsfeed.fu-berlin.de	2
	news.fcku.it	1
	TOTAL: 9	420
Including strange strings
	newsfeed.fu-berlin.de	287
	news-out.aioe.org	89
	csiph-out.news.weretis.net	22
	news.swapon.de	9
	news-out.mixmin.net	5
	csiph-out.feeder.erje.net	3
	newsfeed.neva.ru	3
	csiph-out.news.xs4all.nl	1
	feeder.eternal-september.org	1
	TOTAL: 9	420
No colon-space in header
	csiph-out.news.xs4all.nl	5
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	8
TOTAL: 4		907

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19378	617	15694	5	0	3%	23:59:54
2	news.bbs.nz	30876	535	15308	20	0	1%	23:59:51
3	fu-berlin.de	15424	446	14978	0	0	2%	23:59:54
4	news.samoylyk.net	16797	437	15867	6	0	2%	23:59:54
5	tnet	17227	125	15741	3	0	0%	23:59:38
6	news.freedyn.net	16631	114	16201	1	0	0%	23:59:46
7	newsfeed.xs4all.nl	16138	71	15551	11	153	0%	23:59:53
8	usenet.pasdenom.info	16423	50	15684	26	146	0%	23:59:54
9	xmission	13208	39	11785	1	0	0%	23:59:37
10	aioe	11767	22	11273	1	0	0%	23:59:04
11	neva.ru	15024	19	14785	124	0	0%	23:59:54
12	neodome.net	16653	11	16133	0	0	0%	23:59:53
13	weretis.net	14848	9	14558	1	0	0%	23:59:54
14	news.mixmin.net	15017	9	14565	0	0	0%	23:59:40
15	news.uzoreto.com	15902	9	15523	79	0	0%	23:59:37
16	eternal-september	13761	7	13321	0	0	0%	23:59:54
17	news.swapon.de	1322	6	1302	0	0	0%	23:14:17
18	erje	18805	6	15047	93	0	0%	23:59:49
19	news.fcku.it	1484	0	1481	0	0	0%	21:20:40
TOTAL: 19		286685	2532	254797	371	299	0%	452:31:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	4.1 MB	18.9 KB	4.1 MB	6.7 KB	23:59:54
2	fu-berlin.de	3.6 MB	0.0 KB	3.6 MB	8.2 KB	23:59:54
3	news.samoylyk.net	3.5 MB	6.4 KB	3.5 MB	8.2 KB	23:59:54
4	news.bbs.nz	1.3 MB	76.6 KB	1.4 MB	2.6 KB	23:59:51
5	news.freedyn.net	812.4 KB	6.5 KB	819.0 KB	7.1 KB	23:59:46
6	newsfeed.xs4all.nl	302.7 KB	69.3 KB	372.0 KB	4.5 KB	23:59:53
7	tnet	270.6 KB	6.6 KB	277.2 KB	2.2 KB	23:59:38
8	usenet.pasdenom.info	141.3 KB	77.7 KB	219.0 KB	2.9 KB	23:59:54
9	xmission	131.8 KB	4.7 KB	136.5 KB	3.4 KB	23:59:37
10	aioe	55.0 KB	1.2 KB	56.2 KB	2.4 KB	23:59:04
11	neodome.net	41.8 KB	0.0 KB	41.8 KB	3.8 KB	23:59:53
12	neva.ru	32.5 KB	269.6 KB	302.0 KB	2.1 KB	23:59:54
13	news.mixmin.net	30.6 KB	0.0 KB	30.6 KB	3.4 KB	23:59:40
14	weretis.net	26.3 KB	21.4 KB	47.7 KB	4.8 KB	23:59:54
15	eternal-september	20.1 KB	0.0 KB	20.1 KB	2.9 KB	23:59:54
16	news.swapon.de	19.6 KB	0.0 KB	19.6 KB	3.3 KB	23:14:17
17	news.uzoreto.com	19.0 KB	331.3 KB	350.3 KB	4.0 KB	23:59:37
18	erje	12.5 KB	429.0 KB	441.6 KB	4.5 KB	23:59:49
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	21:20:40
TOTAL: 19		14.4 MB	1.3 MB	15.7 MB	5.5 KB	452:31:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	30	19	45.7 KB	6	0	0	05:57:29
2	ppp-2-84-165-16.home.otenet.gr	1	15	37.1 KB	1	0	0	00:00:15
3	071-013-248-209.res.spectrum.com	33	14	42.8 KB	9	6	6	11:26:28
TOTAL: 3		64	48	125.6 KB	16	6	6	42:36:53

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	30	19	45.7 KB	6	0	0	05:57:29
2	*.home.otenet.gr	1	15	37.1 KB	1	0	0	00:00:15
3	*.res.spectrum.com	33	14	42.8 KB	9	6	6	11:26:28
4	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
5	*.ph.ph.cox.net	5	0	0.0 KB	0	0	0	02:30:15
6	*.net.upcbroadband.cz	9	0	0.0 KB	0	0	0	02:59:23
7	unresolved	61	0	0.0 KB	0	0	0	19:42:59
TOTAL: 7		140	48	125.6 KB	16	6	6	42:36:53

NNRP auth users [Top 20]:

User	Conn
ohlandl	6
TOTAL: 1	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.168	0.930	0.000	19:38:57
071-013-248-209.res.spectrum.com	2.524	0.558	0.014	11:26:28
a131033.upc-a.chello.nl	2.275	0.437	0.000	05:57:29
ip-89-176-128-154.net.upcbroadband.cz	0.675	0.107	0.000	02:59:23
ip72-222-200-117.ph.ph.cox.net	0.571	0.048	0.000	02:30:15
185.245.87.149	0.116	0.040	0.000	00:04:02
ppp-2-84-165-16.home.otenet.gr	0.105	0.015	0.000	00:00:15
scan-141.security.ipip.net	0.052	0.026	0.000	00:00:00
TOTAL: 8	10.486	2.161	0.014	42:36:53

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	59
ip-89-176-128-154.net.upcbroadband.cz	9
ip72-222-200-117.ph.ph.cox.net	5
185.245.87.149	2
scan-141.security.ipip.net	1
TOTAL: 5	76

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
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
Host: 69.160.37.58:119	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-248-209.res.spectrum.com	9	0
ip72-222-200-117.ph.ph.cox.net	5	0
TOTAL: 2	14	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Jul 8 04:15:00 -- Jul 9 04:15:00