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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19742	47.1%	5.1 MB	62.4%
innd	17702	42.3%	2.3 MB	28.9%
innfeed	3641	8.7%	634.0 KB	7.6%
nnrpd	748	1.8%	83.0 KB	1.0%
nocem	38	0.1%	4.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41872	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	302546	56.4%
Negative hits	205149	38.2%
Do not exist	19224	3.6%
Cache misses	9723	1.8%
TOTAL: 4	536642	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.235	0.0%	25	0.000	9.400	21.400
article cleanup	00:00:00.122	0.0%	18723	0.000	0.007	0.034
article logging	00:00:00.315	0.0%	19173	0.000	0.016	0.229
article parse	00:00:01.105	0.0%	59953	0.000	0.018	0.143
article write	00:00:07.647	0.0%	15486	0.138	0.494	5.246
artlog/artcncl	00:00:00.000	0.0%	25	0.000	0.000	0.000
artlog/artparse	00:00:00.007	0.0%	557	0.000	0.013	0.333
data move	00:00:00.189	0.0%	493948	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.038	0.0%	25	0.000	1.520	3.000
hishave/artcncl	00:00:00.020	0.0%	25	0.000	0.800	3.000
hishave/artparse	00:00:00.003	0.0%	554	0.000	0.005	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.935	0.0%	536898	0.002	0.011	0.080
history sync	00:00:01.154	0.0%	376	0.000	3.069	8.000
history write	00:00:14.778	0.0%	18706	0.013	0.790	2.093
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.523	0.0%	554	0.000	0.944	14.250
idle	23:58:21.125	99.8%	501835	87.000	171.971	456.122
nntp read	00:00:01.913	0.0%	501483	0.001	0.004	0.007
overview write	00:01:04.590	0.1%	15486	0.078	4.171	672.017
perl filter	00:00:24.580	0.0%	18703	1.083	1.314	1.938
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.250	0.0%	31009	0.000	0.008	0.040
TOTAL: 24:01:00.305	24:00:24.529	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.044	0.0%	26395	0.000	0.002	0.024
article prepare	00:00:00.001	0.0%	15112	0.000	0.000	0.004
article read	00:00:00.329	0.0%	2348	0.000	0.140	3.071
backlog stats	00:00:00.173	0.0%	364135	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.303	0.0%	287870	0.001	0.005	0.010
data write	00:00:01.651	0.0%	274732	0.001	0.006	0.012
idle	23:54:26.226	100.0%	364135	85.644	236.358	569.982
status file	00:00:00.214	0.0%	321	0.000	0.667	2.000
TOTAL: 23:54:37.950	23:54:29.941	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	35:59:44.857	99.8%	461	61.000	281095.134	600096.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.012	0.0%	1129	0.000	0.011	0.167
readart	00:00:00.252	0.0%	39	0.000	6.462	42.000
TOTAL: 36:05:00.215	35:59:45.121	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	2	14794	3902	10841	51	26%	23:24:23
2	news-out.aioe.org	3	14383	3473	10808	102	24%	22:04:09
3	feeder.eternal-september.org	5	17712	2171	15387	154	12%	24:13:28
4	csiph-out.news.weretis.net	3	30360	916	29217	227	3%	45:24:46
5	newsfeed.fu-berlin.de	13	15782	905	14701	176	5%	26:11:39
6	newsfeed.neva.ru	145	16807	839	15497	471	4%	71:14:16
7	news-out.mixmin.net	2	16574	799	15211	564	4%	23:59:40
8	csiph-out.feeder.erje.net	1440	76221	734	75120	367	0%	118:47:12
9	csiph-out.news.xs4all.nl	861	17552	642	16120	790	3%	71:27:34
10	csiph-out.feed.uzoreto.com	864	25409	364	24160	885	1%	71:12:36
11	news-out.neodome.net	2	16921	204	16708	9	1%	23:59:31
12	news.fcku.it	6	1715	172	1521	22	10%	22:05:49
13	csiph-out.news.tnetconsulting.net	2	16016	151	15833	32	0%	23:59:55
14	nntp.pasdenom.info	2	10455	107	10336	12	1%	23:58:51
15	news.swapon.de	8	1447	35	1409	3	2%	24:43:03
16	news.samoylyk.net	2	22336	12	22321	3	0%	18:47:14
17	news.bbs.nz	2	15391	6	15385	0	0%	23:59:45
18	localhost	31	5	5	0	0	100%	00:00:09
19	xanadu-bbs.net	29	419	0	419	0	0%	21:55:30
TOTAL: 19		3422	330299	15437	310994	3868	4%	681:29:30

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.0 MB	0.0 KB	3.7 MB	14.8 MB	74%	3.8 KB
2	news-out.aioe.org	8.0 MB	14.8 KB	244.0 KB	8.3 MB	96%	2.4 KB
3	feeder.eternal-september.org	5.9 MB	9.6 KB	3.5 MB	9.4 MB	62%	4.2 KB
4	csiph-out.feeder.erje.net	4.6 MB	0.0 KB	4.5 MB	9.2 MB	50%	8.5 KB
5	csiph-out.news.weretis.net	2.4 MB	5.5 KB	2.7 MB	5.1 MB	47%	4.6 KB
6	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	446.3 KB	2.5 MB	82%	2.4 KB
7	news-out.mixmin.net	2.1 MB	5.5 KB	1.0 MB	3.1 MB	67%	2.3 KB
8	csiph-out.news.xs4all.nl	1.7 MB	0.0 KB	9.4 MB	11.1 MB	15%	7.9 KB
9	newsfeed.neva.ru	1.6 MB	16.5 KB	1.3 MB	3.0 MB	55%	2.3 KB
10	csiph-out.feed.uzoreto.com	1.3 MB	0.0 KB	23.2 MB	24.5 MB	5%	20.1 KB
11	news-out.neodome.net	658.4 KB	9.4 KB	20.4 KB	688.3 KB	95%	3.2 KB
12	csiph-out.news.tnetconsulting.net	449.4 KB	0.0 KB	193.8 KB	643.2 KB	69%	3.5 KB
13	news.fcku.it	285.1 KB	0.0 KB	28.5 KB	313.6 KB	90%	1.6 KB
14	nntp.pasdenom.info	248.1 KB	0.0 KB	17.3 KB	265.4 KB	93%	2.2 KB
15	news.swapon.de	79.4 KB	0.0 KB	6.7 KB	86.1 KB	92%	2.3 KB
16	news.samoylyk.net	27.5 KB	8.2 KB	10.5 KB	46.2 KB	59%	3.1 KB
17	news.bbs.nz	7.1 KB	0.0 KB	0.0 KB	7.1 KB	100%	1.2 KB
18	localhost	6.4 KB	0.0 KB	0.0 KB	6.4 KB	100%	1.3 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		42.7 MB	69.5 KB	50.3 MB	93.1 MB	45%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 21 04:15:00 - 04:59:59	290	1.9%	0.11	1.0 MB	2.4%	0.38
Jul 21 05:00:00 - 05:59:59	420	2.7%	0.12	1.1 MB	2.5%	0.31
Jul 21 06:00:00 - 06:59:59	436	2.8%	0.12	1.4 MB	3.3%	0.40
Jul 21 07:00:00 - 07:59:59	611	3.9%	0.17	1.6 MB	3.8%	0.46
Jul 21 08:00:00 - 08:59:59	663	4.3%	0.18	1.9 MB	4.4%	0.54
Jul 21 09:00:00 - 09:59:59	582	3.8%	0.16	1.4 MB	3.2%	0.39
Jul 21 10:00:00 - 10:59:59	686	4.4%	0.19	1.7 MB	3.9%	0.47
Jul 21 11:00:00 - 11:59:59	712	4.6%	0.20	1.7 MB	4.1%	0.49
Jul 21 12:00:00 - 12:59:59	746	4.8%	0.21	1.7 MB	4.0%	0.49
Jul 21 13:00:00 - 13:59:59	769	5.0%	0.21	2.1 MB	4.9%	0.59
Jul 21 14:00:00 - 14:59:59	893	5.8%	0.25	2.2 MB	5.0%	0.61
Jul 21 15:00:00 - 15:59:59	861	5.6%	0.24	2.3 MB	5.5%	0.67
Jul 21 16:00:00 - 16:59:59	859	5.5%	0.24	2.1 MB	4.9%	0.60
Jul 21 17:00:00 - 17:59:59	860	5.6%	0.24	2.3 MB	5.4%	0.66
Jul 21 18:00:00 - 18:59:59	894	5.8%	0.25	3.0 MB	7.0%	0.85
Jul 21 19:00:00 - 19:59:59	981	6.3%	0.27	2.9 MB	6.9%	0.84
Jul 21 20:00:00 - 20:59:59	842	5.4%	0.23	2.2 MB	5.2%	0.64
Jul 21 21:00:00 - 21:59:59	715	4.6%	0.20	2.0 MB	4.7%	0.58
Jul 21 22:00:00 - 22:59:59	577	3.7%	0.16	1.7 MB	4.0%	0.48
Jul 21 23:00:00 - 23:59:59	497	3.2%	0.14	1.8 MB	4.3%	0.52
Jul 22 00:00:00 - 00:59:59	455	2.9%	0.13	1.3 MB	3.1%	0.37
Jul 22 01:00:00 - 01:59:59	374	2.4%	0.10	1.0 MB	2.4%	0.29
Jul 22 02:00:00 - 02:59:59	336	2.2%	0.09	938.0 KB	2.1%	0.26
Jul 22 03:00:00 - 03:59:59	328	2.1%	0.09	1.0 MB	2.4%	0.29
Jul 22 04:00:00 - 04:15:00	94	0.6%	0.10	296.1 KB	0.7%	0.33
TOTAL: 24:00:00	15481	100.0%	0.18	42.8 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feed.uzoreto.com	881	9	0	872
2	csiph-out.news.xs4all.nl	790	47	0	743
3	news-out.mixmin.net	563	0	1	562
4	newsfeed.fu-berlin.de	557	29	0	528
5	newsfeed.neva.ru	471	27	3	441
6	csiph-out.news.weretis.net	279	61	2	216
7	csiph-out.feeder.erje.net	243	51	0	192
8	news-out.aioe.org	165	94	4	67
9	feeder.eternal-september.org	161	30	3	128
10	newsfeeds.xmission.com	46	2	0	44
11	news.fcku.it	23	23	0	0
12	csiph-out.news.tnetconsulting.net	23	22	1	0
13	nntp.pasdenom.info	12	0	0	12
14	news-out.neodome.net	10	0	4	6
15	news.swapon.de	10	0	0	10
16	news.samoylyk.net	3	0	2	1
TOTAL: 16		4237	395	20	3822

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	60
scinet.stats	29
spline.eisfair	23
trollnet.cinema	23
mozilla.support.thunderbird	22
alt.computer.workshop	18
alt.politics.usa	12
lada.auto	11
mozilla.general	9
alt.politics	9
free.it.religioni.scientology	8
alt.bbs.synchronet	8
oesterreich.tratsch	7
lada.movies	7
alt.windows7.general	6
mozilla.support.firefox	6
mozilla.support.seamonkey	5
alt.binaries.pictures.purebeauty	4
alt.binaries.ebooks	4
trollnet.jogos	4
TOTAL: 95	395

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2206
EMP (phn path)	262
EMP (phl)	154
0	67
Too many newsgroups	51
EMP (phn nph)	44
HTML Multipart	19
Binary: misplaced binary	3
Too many newsgroups (meow)	3
Binary Payload	2
Too many newsgroups (low_xpost)	1
HTML post	1
Scoring filter	1
U2 violation - invalid distribution	1
TOTAL: 14	2815

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	116
	csiph-out.feed.uzoreto.com	17
	csiph-out.news.xs4all.nl	16
	TOTAL: 3	149
Huge articles
	csiph-out.feed.uzoreto.com	271
	csiph-out.news.xs4all.nl	96
	csiph-out.feeder.erje.net	78
	newsfeeds.xmission.com	43
	feeder.eternal-september.org	36
	csiph-out.news.weretis.net	28
	news-out.mixmin.net	1
	newsfeed.fu-berlin.de	1
	TOTAL: 8	554
Including strange strings
	newsfeed.fu-berlin.de	365
	news-out.aioe.org	60
	csiph-out.news.weretis.net	8
	news.swapon.de	7
	csiph-out.news.xs4all.nl	3
	news-out.mixmin.net	3
	csiph-out.feed.uzoreto.com	2
	csiph-out.feeder.erje.net	2
	TOTAL: 8	450
No colon-space in header
	csiph-out.feeder.erje.net	1
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	3
TOTAL: 4		1156

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18322	1614	13867	0	14	8%	23:59:54
2	news.samoylyk.net	24601	508	23579	21	1980	2%	23:59:54
3	news.bbs.nz	29496	482	14596	21	0	1%	23:59:51
4	fu-berlin.de	14438	377	14061	0	0	2%	23:59:46
5	tnet	16339	106	14996	2	0	0%	23:59:54
6	news.freedyn.net	15785	72	15407	0	0	0%	23:59:42
7	newsfeed.xs4all.nl	15323	71	14645	19	304	0%	23:59:49
8	erje	16214	56	14450	95	0	0%	23:59:52
9	xmission	12566	55	11022	0	0	0%	23:59:54
10	neva.ru	14199	38	13983	67	0	0%	23:59:54
11	usenet.pasdenom.info	15751	25	14966	13	19	0%	23:59:54
12	neodome.net	15765	11	15249	1	0	0%	23:59:54
13	aioe	11292	10	10744	0	0	0%	23:59:54
14	news.mixmin.net	14431	8	13994	1	0	0%	23:59:54
15	eternal-september	13370	8	12814	0	0	0%	23:59:54
16	weretis.net	14385	7	14083	0	0	0%	23:59:54
17	news.swapon.de	1397	5	1380	0	0	0%	23:23:09
18	news.uzoreto.com	15033	4	14754	13	0	0%	23:59:50
19	news.fcku.it	1434	0	1431	0	0	0%	21:57:35
TOTAL: 19		280141	3457	250021	253	2317	1%	453:18:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.9 MB	0.0 KB	6.9 MB	0.1 KB/s	4.4 KB	23:59:54
2	news.samoylyk.net	4.4 MB	21.4 KB	4.4 MB	0.1 KB/s	8.5 KB	23:59:54
3	fu-berlin.de	3.5 MB	0.0 KB	3.5 MB	0.0 KB/s	9.6 KB	23:59:46
4	news.bbs.nz	1.2 MB	89.5 KB	1.3 MB	0.0 KB/s	2.6 KB	23:59:51
5	news.freedyn.net	679.5 KB	0.0 KB	679.5 KB	0.0 KB/s	9.4 KB	23:59:42
6	newsfeed.xs4all.nl	289.6 KB	76.5 KB	366.2 KB	0.0 KB/s	4.1 KB	23:59:49
7	tnet	224.7 KB	5.9 KB	230.6 KB	0.0 KB/s	2.1 KB	23:59:54
8	erje	116.3 KB	398.5 KB	514.8 KB	0.0 KB/s	3.4 KB	23:59:52
9	xmission	105.5 KB	0.0 KB	105.5 KB	0.0 KB/s	1.9 KB	23:59:54
10	usenet.pasdenom.info	88.7 KB	38.8 KB	127.5 KB	0.0 KB/s	3.4 KB	23:59:54
11	neva.ru	80.4 KB	169.1 KB	249.5 KB	0.0 KB/s	2.4 KB	23:59:54
12	news.uzoreto.com	36.7 KB	35.0 KB	71.6 KB	0.0 KB/s	4.2 KB	23:59:50
13	neodome.net	29.0 KB	1.4 KB	30.4 KB	0.0 KB/s	2.5 KB	23:59:54
14	aioe	21.3 KB	0.0 KB	21.3 KB	0.0 KB/s	2.1 KB	23:59:54
15	eternal-september	18.9 KB	0.0 KB	18.9 KB	0.0 KB/s	2.4 KB	23:59:54
16	weretis.net	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	1.6 KB	23:59:54
17	news.mixmin.net	10.4 KB	1.6 KB	12.0 KB	0.0 KB/s	1.3 KB	23:59:54
18	news.swapon.de	6.4 KB	0.0 KB	6.4 KB	0.0 KB/s	1.3 KB	23:23:09
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:57:35
TOTAL: 19		17.7 MB	837.7 KB	18.5 MB	0.0 KB/s	5.1 KB	453:18:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-165-16.home.otenet.gr	2	15	22.8 KB	1	0	0	00:00:12
2	071-013-248-209.res.spectrum.com	24	13	19.2 KB	8	5	4	06:29:56
3	a131033.upc-a.chello.nl	21	11	18.0 KB	3	0	0	05:34:45
TOTAL: 3		47	39	59.9 KB	12	5	4	36:04:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	2	15	22.8 KB	1	0	0	00:00:12
2	*.res.spectrum.com	24	13	19.2 KB	8	5	4	06:29:56
3	*.upc-a.chello.nl	21	11	18.0 KB	3	0	0	05:34:45
4	unresolved	73	0	0.0 KB	0	0	0	23:59:52
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
6	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	0	0	00:00:11
TOTAL: 6		122	39	59.9 KB	12	5	4	36:04:59

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
TOTAL: 1	4

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.969	1.205	0.000	23:59:42
071-013-248-209.res.spectrum.com	1.927	0.460	0.010	06:29:56
a131033.upc-a.chello.nl	1.537	0.276	0.000	05:34:45
ppp-2-84-165-16.home.otenet.gr	0.165	0.030	0.000	00:00:12
34.20.97.83.ro.ovo.sc	0.051	0.025	0.000	00:00:11
162.243.129.17	0.075	0.008	0.000	00:00:09
scan-141.security.ipip.net	0.072	0.008	0.000	00:00:01
TOTAL: 7	8.796	2.012	0.010	36:04:59

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
34.20.97.83.ro.ovo.sc	1
162.243.129.17	1
scan-141.security.ipip.net	1
TOTAL: 4	75

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-248-209.res.spectrum.com	8	0
TOTAL: 1	8	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Jul 21 04:15:00 -- Jul 22 04:15:00