Daily Usenet report for csiph.com: Apr 27 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22179	47.3%	5.8 MB	63.6%
innd	17072	36.4%	2.3 MB	24.8%
innfeed	6866	14.6%	1004.9 KB	10.7%
nnrpd	725	1.5%	78.8 KB	0.8%
nocem	24	0.1%	2.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	46867	100.0%	9.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	362016	55.5%
Negative hits	251404	38.6%
Do not exist	21535	3.3%
Cache misses	16936	2.6%
TOTAL: 4	651891	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.371	0.0%	24	0.000	15.458	28.625
article cleanup	00:00:00.123	0.0%	21126	0.000	0.006	0.033
article logging	00:00:00.418	0.0%	21723	0.000	0.019	0.509
article parse	00:00:01.569	0.0%	67949	0.000	0.023	0.240
article write	00:00:20.408	0.0%	18324	0.180	1.114	45.882
artlog/artcncl	00:00:00.000	0.0%	24	0.000	0.000	0.000
artlog/artparse	00:00:00.013	0.0%	437	0.000	0.030	0.750
data move	00:00:00.175	0.0%	588195	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.096	0.0%	24	0.000	4.000	17.000
hishave/artcncl	00:00:00.097	0.0%	24	0.000	4.042	9.200
hishave/artparse	00:00:00.001	0.0%	424	0.000	0.002	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:38.690	0.0%	651916	0.001	0.059	0.517
history sync	00:00:01.043	0.0%	368	0.000	2.834	23.000
history write	00:00:57.082	0.1%	21130	0.500	2.701	14.632
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.955	0.0%	424	0.000	2.252	17.000
idle	23:53:58.334	99.5%	592348	80.828	145.250	545.456
nntp read	00:00:02.224	0.0%	597374	0.002	0.004	0.008
overview write	00:03:54.396	0.3%	18324	0.117	12.792	3590.689
perl filter	00:00:28.161	0.0%	21092	1.096	1.335	1.786
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.308	0.0%	36672	0.000	0.008	0.025
TOTAL: 24:01:00.066	24:00:24.464	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.663	0.0%	958342	0.000	0.001	0.020
article prepare	00:00:00.003	0.0%	12570	0.000	0.000	0.007
article read	00:00:07.108	0.0%	2108	0.000	3.372	42.000
backlog stats	00:00:00.324	0.0%	777700	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.324	0.0%	553803	0.001	0.004	0.011
data write	00:00:02.462	0.0%	538644	0.003	0.005	0.017
idle	23:53:17.609	100.0%	777700	7.571	110.579	668.990
status file	00:00:00.994	0.0%	1695	0.000	0.586	50.000
TOTAL: 23:53:50.799	23:53:31.487	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	35:29:38.624	99.8%	429	65.000	297852.270	600204.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.012	0.0%	1026	0.000	0.012	0.167
readart	00:00:00.362	0.0%	30	0.000	12.067	55.000
TOTAL: 35:34:03.014	35:29:38.998	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	18010	4704	13281	25	26%	24:22:29
2	news-out.aioe.org	4	18698	3439	15099	160	18%	24:05:45
3	feeder.eternal-september.org	4	18831	2513	16200	118	13%	21:34:11
4	csiph-out.news.xs4all.nl	861	19815	1279	17454	1082	6%	71:29:53
5	csiph-out.feeder.erje.net	1440	91067	1234	89515	318	1%	118:42:11
6	csiph-out.news.weretis.net	3	40070	1134	38707	229	2%	50:32:01
7	newsfeed.neva.ru	149	19045	1036	17694	315	5%	72:01:02
8	news-out.mixmin.net	3	19311	953	18031	327	4%	24:07:59
9	newsfeed.fu-berlin.de	20	19911	946	18751	214	4%	31:08:52
10	news.fcku.it	11	2487	406	2079	2	16%	21:47:47
11	csiph-out.feed.uzoreto.com	864	34065	395	33268	402	1%	71:12:27
12	news-out.neodome.net	2	19561	143	19391	27	0%	23:59:48
13	nntp.pasdenom.info	2	13216	58	13123	35	0%	23:58:21
14	news.swapon.de	5	1699	47	1646	6	2%	23:33:45
15	csiph-out.news.tnetconsulting.net	5	17448	13	17427	8	0%	03:56:55
16	news.samoylyk.net	1	18351	11	18340	0	0%	23:59:44
17	news.bbs.nz	1	7786	5	7781	0	0%	13:09:18
18	localhost	29	3	3	0	0	100%	00:00:04
19	xanadu-bbs.net	4	0	0	0	0	0%	03:10:06
TOTAL: 19		3411	379374	18319	357787	3268	4%	646:52:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	14.1 MB	0.0 KB	1.2 MB	15.3 MB	91%	3.3 KB
2	news-out.aioe.org	8.4 MB	0.0 KB	308.3 KB	8.7 MB	96%	2.5 KB
3	feeder.eternal-september.org	6.4 MB	8.1 KB	6.1 MB	12.5 MB	51%	4.9 KB
4	csiph-out.feeder.erje.net	6.1 MB	0.0 KB	4.1 MB	10.2 MB	59%	6.7 KB
5	csiph-out.news.xs4all.nl	3.5 MB	0.0 KB	15.9 MB	19.4 MB	18%	8.4 KB
6	csiph-out.news.weretis.net	3.2 MB	7.6 KB	2.4 MB	5.6 MB	57%	4.2 KB
7	news-out.mixmin.net	2.7 MB	1.2 KB	590.7 KB	3.2 MB	82%	2.6 KB
8	newsfeed.neva.ru	2.4 MB	0.0 KB	929.2 KB	3.3 MB	72%	2.5 KB
9	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	409.0 KB	2.5 MB	84%	2.2 KB
10	csiph-out.feed.uzoreto.com	1.1 MB	0.0 KB	6.1 MB	7.2 MB	14%	9.3 KB
11	news.fcku.it	736.8 KB	0.0 KB	5.5 KB	742.3 KB	99%	1.8 KB
12	news-out.neodome.net	549.6 KB	2.6 KB	626.3 KB	1.2 MB	46%	6.9 KB
13	news.swapon.de	141.9 KB	0.0 KB	2.9 MB	3.0 MB	4%	58.3 KB
14	nntp.pasdenom.info	119.8 KB	0.0 KB	45.4 KB	165.2 KB	72%	1.8 KB
15	csiph-out.news.tnetconsulting.net	31.2 KB	0.0 KB	16.1 KB	47.3 KB	65%	2.3 KB
16	news.samoylyk.net	30.2 KB	0.0 KB	0.0 KB	30.2 KB	100%	2.7 KB
17	news.bbs.nz	10.0 KB	0.0 KB	0.0 KB	10.0 KB	100%	2.0 KB
18	localhost	3.7 KB	0.0 KB	0.0 KB	3.7 KB	100%	1.2 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		51.6 MB	19.5 KB	41.6 MB	93.2 MB	55%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 27 04:15:00 - 04:59:59	206	1.1%	0.08	597.1 KB	1.1%	0.22
Apr 27 05:00:00 - 05:59:59	442	2.4%	0.12	1.5 MB	3.0%	0.44
Apr 27 06:00:00 - 06:59:59	559	3.1%	0.16	1.7 MB	3.3%	0.48
Apr 27 07:00:00 - 07:59:59	793	4.3%	0.22	2.9 MB	5.6%	0.82
Apr 27 08:00:00 - 08:59:59	833	4.5%	0.23	2.4 MB	4.6%	0.67
Apr 27 09:00:00 - 09:59:59	893	4.9%	0.25	2.3 MB	4.5%	0.67
Apr 27 10:00:00 - 10:59:59	868	4.7%	0.24	2.2 MB	4.2%	0.61
Apr 27 11:00:00 - 11:59:59	926	5.1%	0.26	2.4 MB	4.7%	0.69
Apr 27 12:00:00 - 12:59:59	1000	5.5%	0.28	2.6 MB	5.0%	0.73
Apr 27 13:00:00 - 13:59:59	1064	5.8%	0.30	2.7 MB	5.2%	0.77
Apr 27 14:00:00 - 14:59:59	1058	5.8%	0.29	2.8 MB	5.4%	0.79
Apr 27 15:00:00 - 15:59:59	1175	6.4%	0.33	3.0 MB	5.8%	0.85
Apr 27 16:00:00 - 16:59:59	1132	6.2%	0.31	3.0 MB	5.8%	0.85
Apr 27 17:00:00 - 17:59:59	1050	5.7%	0.29	2.8 MB	5.5%	0.81
Apr 27 18:00:00 - 18:59:59	977	5.3%	0.27	2.6 MB	5.0%	0.73
Apr 27 19:00:00 - 19:59:59	940	5.1%	0.26	2.7 MB	5.2%	0.75
Apr 27 20:00:00 - 20:59:59	921	5.0%	0.26	2.5 MB	4.8%	0.70
Apr 27 21:00:00 - 21:59:59	832	4.5%	0.23	2.4 MB	4.6%	0.67
Apr 27 22:00:00 - 22:59:59	579	3.2%	0.16	1.7 MB	3.3%	0.48
Apr 27 23:00:00 - 23:59:59	482	2.6%	0.13	1.6 MB	3.1%	0.45
Apr 28 00:00:00 - 00:59:59	457	2.5%	0.13	1.5 MB	2.9%	0.42
Apr 28 01:00:00 - 01:59:59	372	2.0%	0.10	1.2 MB	2.3%	0.33
Apr 28 02:00:00 - 02:59:59	347	1.9%	0.10	1.1 MB	2.1%	0.31
Apr 28 03:00:00 - 03:59:59	314	1.7%	0.09	1.0 MB	2.0%	0.29
Apr 28 04:00:00 - 04:15:00	100	0.5%	0.11	525.5 KB	1.0%	0.58
TOTAL: 24:00:00	18320	100.0%	0.21	51.5 MB	100.0%	0.61

Sites sending bad articles:

Server		Total	Group	Dist	Duplic	Other
1	csiph-out.news.xs4all.nl	1102	29	0	0	1073
2	newsfeed.fu-berlin.de	582	32	0	0	550
3	csiph-out.feed.uzoreto.com	411	9	1	0	401
4	news-out.mixmin.net	345	0	0	1	344
5	newsfeed.neva.ru	328	79	0	0	249
6	csiph-out.feeder.erje.net	325	59	4	0	262
7	news-out.aioe.org	264	30	0	0	234
8	csiph-out.news.weretis.net	188	57	0	4	127
9	feeder.eternal-september.org	149	18	0	3	128
10	nntp.pasdenom.info	35	0	0	0	35
11	newsfeeds.xmission.com	35	2	0	0	33
12	csiph-out.news.tnetconsulting.net	31	25	0	0	6
13	news-out.neodome.net	28	1	0	1	26
14	news.swapon.de	13	0	0	0	13
15	news.fcku.it	1	1	0	0	0
TOTAL: 15		3837	342	5	9	3481

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	45
alt.survival	29
spline.eisfair	27
it-alt.sport.calcio.juventus	19
lada.kitchen	16
free.it.religioni.scientology	13
alt.binaries.pictures.purebeauty	12
alt.bbs.synchronet	11
mozilla.test	11
alt.religion	10
alt.binaries.ebooks	10
oesterreich.tratsch	10
alt.politics	6
mozilla.dev.platform	6
lada.auto	5
it-alt.comp.software.openoffice	5
cakewalk.audio	5
mozilla.support.firefox	4
spline.eisfair.dev	4
it-alt.comp.software.mozilla	4
TOTAL: 87	342

Unwanted distributions [Top 20]:

Distribution	Count
local	5
TOTAL: 1	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1516
EMP (phn path)	322
EMP (phl)	218
EMP (phn nph)	115
Too many newsgroups	83
0	78
Binary Payload	38
Scoring filter	27
HTML Multipart	21
Too many newsgroups (meow)	4
Binary: misplaced binary	3
TOTAL: 11	2425

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.news.xs4all.nl	182
	csiph-out.feed.uzoreto.com	72
	csiph-out.feeder.erje.net	72
	feeder.eternal-september.org	43
	newsfeeds.xmission.com	18
	csiph-out.news.weretis.net	14
	news-out.neodome.net	10
	news.swapon.de	6
	csiph-out.news.tnetconsulting.net	3
	news-out.mixmin.net	1
	TOTAL: 10	421
Including strange strings
	newsfeed.fu-berlin.de	390
	news-out.aioe.org	99
	csiph-out.news.weretis.net	25
	csiph-out.news.xs4all.nl	19
	news-out.mixmin.net	18
	newsfeed.neva.ru	13
	csiph-out.feed.uzoreto.com	10
	newsfeeds.xmission.com	10
	news.swapon.de	7
	feeder.eternal-september.org	3
	TOTAL: 12	597
No colon-space in header
	csiph-out.news.xs4all.nl	8
	csiph-out.feeder.erje.net	3
	csiph-out.feed.uzoreto.com	2
	TOTAL: 3	13
TOTAL: 4		1076

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	tnet	975770	1156	31560	8	1	924622	0%	23:51:49
2	news.bbs.nz	34846	467	17451	8	0	0	1%	23:59:52
3	fu-berlin.de	17259	382	16877	0	0	0	2%	23:59:30
4	news.samoylyk.net	18917	379	17928	4	0	0	2%	23:59:42
5	erje	17771	89	16639	129	0	0	0%	23:59:53
6	neva.ru	16849	52	16544	117	0	0	0%	23:59:54
7	news.freedyn.net	18752	45	18275	0	0	0	0%	23:59:54
8	newsfeed.xs4all.nl	17457	39	16851	13	0	95	0%	23:55:29
9	news.uzoreto.com	17369	24	17045	190	0	0	0%	23:59:54
10	xmission	14658	13	13299	0	0	0	0%	23:59:54
11	aioe	14459	9	13742	0	0	0	0%	23:59:29
12	usenet.pasdenom.info	18559	6	17863	5	0	18	0%	23:59:54
13	neodome.net	18637	5	18038	0	0	0	0%	23:50:04
14	news.mixmin.net	17327	5	16714	0	0	0	0%	23:59:54
15	weretis.net	17043	4	16797	2	0	0	0%	23:59:54
16	news.swapon.de	1718	3	1689	0	0	0	0%	23:43:14
17	eternal-september	15683	3	15048	0	0	0	0%	23:59:42
18	news.fcku.it	1980	0	1970	0	0	0	0%	21:28:03
19	xanadu-bbs	482	0	0	0	0	18802	0%	23:58:43
TOTAL: 19		1255536	2681	284330	476	1	943537	0%	452:44:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.samoylyk.net	3.7 MB	4.4 KB	3.7 MB	10.0 KB	23:59:42
2	fu-berlin.de	3.7 MB	0.0 KB	3.7 MB	10.0 KB	23:59:30
3	tnet	3.1 MB	17.6 KB	3.1 MB	2.7 KB	23:51:49
4	news.bbs.nz	1.2 MB	20.5 KB	1.2 MB	2.6 KB	23:59:52
5	news.freedyn.net	448.8 KB	0.0 KB	448.8 KB	10.0 KB	23:59:54
6	erje	209.4 KB	446.6 KB	656.1 KB	3.0 KB	23:59:53
7	newsfeed.xs4all.nl	146.1 KB	130.5 KB	276.6 KB	5.3 KB	23:55:29
8	neva.ru	139.2 KB	313.1 KB	452.3 KB	2.7 KB	23:59:54
9	news.uzoreto.com	58.1 KB	764.3 KB	822.4 KB	3.8 KB	23:59:54
10	xmission	23.6 KB	0.0 KB	23.6 KB	1.8 KB	23:59:54
11	aioe	16.9 KB	0.0 KB	16.9 KB	1.9 KB	23:59:29
12	usenet.pasdenom.info	13.1 KB	13.3 KB	26.3 KB	2.4 KB	23:59:54
13	neodome.net	6.2 KB	0.0 KB	6.2 KB	1.2 KB	23:50:04
14	news.mixmin.net	6.2 KB	0.0 KB	6.2 KB	1.2 KB	23:59:54
15	weretis.net	4.9 KB	2.2 KB	7.1 KB	1.2 KB	23:59:54
16	news.swapon.de	3.7 KB	0.0 KB	3.7 KB	1.2 KB	23:43:14
17	eternal-september	3.7 KB	0.0 KB	3.7 KB	1.2 KB	23:59:42
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	21:28:03
19	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0	23:58:43
TOTAL: 19		12.8 MB	1.7 MB	14.5 MB	4.7 KB	452:44:48

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	28	12	15.6 KB	6	0	0	07:21:27
2	131.93.176.45	15	11	14.7 KB	7	3	2	05:09:08
3	5-168-143-94.cust.centrio.cz	2	3	4.1 KB	2	0	0	01:00:06
4	ppp-2-84-163-204.home.otenet.gr	1	3	4.1 KB	1	0	0	00:00:02
5	c-73-44-208-223.hsd1.il.comcast.net	1	1	1.9 KB	1	0	0	00:02:56
TOTAL: 5		47	30	40.4 KB	17	3	2	35:34:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	28	12	15.6 KB	6	0	0	07:21:27
2	unresolved	83	11	14.7 KB	7	3	2	27:09:28
3	*.home.otenet.gr	1	3	4.1 KB	1	0	0	00:00:02
4	*.cust.centrio.cz	2	3	4.1 KB	2	0	0	01:00:06
5	*.hsd1.il.comcast.net	1	1	1.9 KB	1	0	0	00:02:56
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
TOTAL: 6		116	30	40.4 KB	17	3	2	35:34:02

NNRP auth users [Top 20]:

User	Conn
ctomczyk	2
ohlandl	2
TOTAL: 2	4

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.644	0.989	0.000	22:00:17
a131033.upc-a.chello.nl	2.181	0.445	0.000	07:21:27
131.93.176.45	1.110	0.216	0.006	05:09:08
5-168-143-94.cust.centrio.cz	0.202	0.040	0.005	01:00:06
c-73-44-208-223.hsd1.il.comcast.net	0.108	0.015	0.000	00:02:56
ppp-2-84-163-204.home.otenet.gr	0.103	0.016	0.000	00:00:02
151.44.130.171	0.072	0.008	0.000	00:00:01
scan-141.security.ipip.net	0.062	0.015	0.000	00:00:01
94.163.83.95	0.055	0.024	0.000	00:00:01
TOTAL: 9	8.537	1.768	0.011	35:34:02

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	66
94.163.83.95	1
scan-141.security.ipip.net	1
151.44.130.171	1
TOTAL: 4	69

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
Connection: close	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
131.93.176.45	7	0
5-168-143-94.cust.centrio.cz	2	0
TOTAL: 2	9	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	29	96.7%
2	muc	1	3.3%
TOTAL: 2		30	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	26
2	comp.lang.javascript	3
3	muc.lists.freebsd.questions	1
TOTAL: 3		30

Daily Usenet report for csiph.com

Apr 27 04:15:00 -- Apr 28 04:15:00