Daily Usenet report for csiph.com: Sep 19 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22173	49.6%	5.5 MB	65.3%
innd	18896	42.3%	2.4 MB	27.7%
innfeed	3281	7.3%	574.2 KB	6.6%
nnrpd	311	0.7%	34.8 KB	0.4%
nocem	59	0.1%	6.0 KB	0.1%
TOTAL: 5	44720	100.0%	8.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	312580	58.2%
Negative hits	202904	37.8%
Do not exist	21482	4.0%
Cache misses	330	0.1%
TOTAL: 4	537296	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.548	0.0%	132	0.000	4.152	89.000
article cleanup	00:00:00.116	0.0%	20657	0.000	0.006	0.022
article logging	00:00:00.459	0.0%	21094	0.000	0.022	0.518
article parse	00:00:02.679	0.0%	83417	0.000	0.032	0.857
article write	00:00:32.734	0.0%	16548	0.218	1.978	38.583
artlog/artcncl	00:00:00.004	0.0%	116	0.000	0.034	0.500
artlog/artparse	00:00:00.222	0.0%	980	0.000	0.227	23.333
data move	00:00:00.122	0.0%	519837	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.102	0.0%	129	0.000	0.791	78.000
hishave/artcncl	00:00:00.032	0.0%	132	0.000	0.242	2.900
hishave/artparse	00:00:00.000	0.0%	976	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:24.491	0.0%	538797	0.001	0.045	0.510
history sync	00:00:00.941	0.0%	378	0.000	2.489	22.667
history write	00:00:35.092	0.0%	20643	0.271	1.700	22.819
hiswrite/artcncl	00:00:00.002	0.0%	3	0.000	0.667	1.000
hiswrite/artparse	00:00:01.692	0.0%	974	0.000	1.737	149.000
idle	23:56:53.611	99.7%	527942	93.089	163.301	467.323
nntp read	00:00:02.337	0.0%	530066	0.001	0.004	0.016
overview write	00:01:22.458	0.1%	16548	0.103	4.983	295.571
perl filter	00:00:33.483	0.0%	20639	1.296	1.622	3.565
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.273	0.0%	33149	0.000	0.008	0.041
TOTAL: 24:01:07.165	24:00:31.398	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	16630	0.000	0.003	0.039
article prepare	00:00:00.001	0.0%	16688	0.000	0.000	0.018
article read	00:00:00.549	0.0%	1445	0.000	0.380	13.429
backlog stats	00:00:00.211	0.0%	372426	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.394	0.0%	279606	0.000	0.005	0.010
data write	00:00:01.652	0.0%	266659	0.002	0.006	0.013
idle	23:54:21.895	100.0%	372426	134.680	231.085	692.332
status file	00:00:00.350	0.0%	258	0.000	1.357	14.000
TOTAL: 23:54:34.953	23:54:26.099	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	12:08:25.386	100.0%	275	59.000	158928.676	600143.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.001	0.0%	533	0.000	0.002	0.167
readart	00:00:00.189	0.0%	14	0.000	13.500	48.000
TOTAL: 12:08:34.397	12:08:25.576	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	123
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	143

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	78427	3327	74709	391	4%	118:41:25
2	csiph-out.news.weretis.net	4	32829	2500	30138	191	7%	46:58:24
3	feeder.eternal-september.org	4	17354	1947	15328	79	11%	21:55:18
4	news.freedyn.net	3	20620	1669	17537	1414	8%	24:08:46
5	csiph-out.news.xs4all.nl	863	19223	1480	16164	1579	7%	71:27:43
6	news-out.aioe.org	7	16553	1413	15091	49	8%	24:18:28
7	news-out.mixmin.net	1	17084	1013	15918	153	5%	23:59:54
8	newsfeed.neva.ru	150	25361	859	23793	709	3%	71:47:22
9	newsfeed.fu-berlin.de	11	15362	809	14503	50	5%	24:47:01
10	newsfeeds.xmission.com	2	13890	534	13353	3	3%	19:48:33
11	news.fcku.it	10	2267	308	1938	21	13%	22:34:28
12	csiph-out.feed.uzoreto.com	864	26889	201	26313	375	0%	71:06:34
13	nntp.pasdenom.info	2	10968	56	10906	6	0%	23:58:29
14	news.swapon.de	7	1507	35	1472	0	2%	23:51:33
15	news.samoylyk.net	5	16200	14	16186	0	0%	26:12:49
16	localhost	37	4	4	0	0	100%	00:00:00
17	news.bbs.nz	1	16435	2	16433	0	0%	23:59:53
18	xanadu-bbs.net	23	502	1	501	0	0%	22:08:03
TOTAL: 18		3434	331475	16172	310283	5020	4%	661:44:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	14.2 MB	20.4 KB	10.8 MB	25.1 MB	56%	6.9 KB
2	csiph-out.news.weretis.net	8.0 MB	6.1 KB	5.1 MB	13.1 MB	61%	5.0 KB
3	news.freedyn.net	5.0 MB	5.8 KB	56.9 MB	61.9 MB	8%	20.6 KB
4	feeder.eternal-september.org	4.9 MB	2.6 KB	631.0 KB	5.5 MB	88%	2.8 KB
5	csiph-out.news.xs4all.nl	4.0 MB	0.0 KB	15.0 MB	19.1 MB	21%	6.4 KB
6	news-out.mixmin.net	3.1 MB	35.8 KB	512.3 KB	3.6 MB	85%	3.2 KB
7	news-out.aioe.org	3.1 MB	0.0 KB	88.9 KB	3.2 MB	97%	2.2 KB
8	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	205.9 KB	2.2 MB	90%	2.7 KB
9	newsfeed.neva.ru	1.8 MB	3.7 KB	2.3 MB	4.1 MB	43%	2.7 KB
10	newsfeeds.xmission.com	1.5 MB	0.0 KB	234.9 KB	1.7 MB	86%	3.3 KB
11	csiph-out.feed.uzoreto.com	711.0 KB	4.8 KB	6.7 MB	7.4 MB	9%	13.2 KB
12	news.fcku.it	525.5 KB	2.3 KB	25.7 KB	553.5 KB	94%	1.7 KB
13	nntp.pasdenom.info	129.5 KB	0.0 KB	11.5 KB	141.0 KB	91%	2.3 KB
14	news.swapon.de	88.9 KB	0.0 KB	0.0 KB	88.9 KB	100%	2.5 KB
15	news.samoylyk.net	50.9 KB	0.0 KB	0.0 KB	50.9 KB	100%	3.6 KB
16	localhost	23.5 KB	0.0 KB	0.0 KB	23.5 KB	100%	5.9 KB
17	xanadu-bbs.net	9.4 KB	0.0 KB	0.0 KB	9.4 KB	100%	9.4 KB
18	news.bbs.nz	5.3 KB	0.0 KB	0.0 KB	5.3 KB	100%	2.7 KB
TOTAL: 18		49.1 MB	81.5 KB	98.5 MB	147.7 MB	33%	7.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 19 04:15:00 - 04:59:59	378	2.3%	0.14	1.8 MB	3.6%	0.68
Sep 19 05:00:00 - 05:59:59	346	2.1%	0.10	890.7 KB	1.7%	0.25
Sep 19 06:00:00 - 06:59:59	568	3.4%	0.16	1.8 MB	3.6%	0.52
Sep 19 07:00:00 - 07:59:59	723	4.4%	0.20	2.0 MB	4.0%	0.57
Sep 19 08:00:00 - 08:59:59	839	5.1%	0.23	2.4 MB	4.8%	0.68
Sep 19 09:00:00 - 09:59:59	774	4.7%	0.21	2.3 MB	4.7%	0.66
Sep 19 10:00:00 - 10:59:59	715	4.3%	0.20	2.1 MB	4.1%	0.59
Sep 19 11:00:00 - 11:59:59	775	4.7%	0.22	2.0 MB	4.1%	0.58
Sep 19 12:00:00 - 12:59:59	911	5.5%	0.25	2.5 MB	4.9%	0.70
Sep 19 13:00:00 - 13:59:59	954	5.8%	0.27	2.3 MB	4.7%	0.66
Sep 19 14:00:00 - 14:59:59	931	5.6%	0.26	3.3 MB	6.6%	0.94
Sep 19 15:00:00 - 15:59:59	906	5.5%	0.25	2.6 MB	5.1%	0.73
Sep 19 16:00:00 - 16:59:59	936	5.7%	0.26	2.9 MB	5.8%	0.82
Sep 19 17:00:00 - 17:59:59	891	5.4%	0.25	2.3 MB	4.7%	0.67
Sep 19 18:00:00 - 18:59:59	894	5.4%	0.25	2.9 MB	5.8%	0.82
Sep 19 19:00:00 - 19:59:59	767	4.6%	0.21	1.9 MB	3.8%	0.55
Sep 19 20:00:00 - 20:59:59	802	4.8%	0.22	2.4 MB	4.8%	0.68
Sep 19 21:00:00 - 21:59:59	663	4.0%	0.18	2.2 MB	4.4%	0.62
Sep 19 22:00:00 - 22:59:59	680	4.1%	0.19	2.5 MB	5.0%	0.70
Sep 19 23:00:00 - 23:59:59	509	3.1%	0.14	1.7 MB	3.4%	0.48
Sep 20 00:00:00 - 00:59:59	419	2.5%	0.12	1.2 MB	2.4%	0.34
Sep 20 01:00:00 - 01:59:59	410	2.5%	0.11	1.3 MB	2.7%	0.38
Sep 20 02:00:00 - 02:59:59	345	2.1%	0.10	1.4 MB	2.9%	0.41
Sep 20 03:00:00 - 03:59:59	306	1.8%	0.09	898.3 KB	1.8%	0.25
Sep 20 04:00:00 - 04:15:00	105	0.6%	0.12	359.7 KB	0.7%	0.40
TOTAL: 24:00:00	16547	100.0%	0.19	50.0 MB	100.0%	0.59

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1632	46	0	1586
2	news.freedyn.net	1427	40	2	1385
3	newsfeed.neva.ru	710	147	1	562
4	newsfeed.fu-berlin.de	402	17	0	385
5	csiph-out.feeder.erje.net	398	88	7	303
6	csiph-out.feed.uzoreto.com	381	9	1	371
7	csiph-out.news.weretis.net	207	86	2	119
8	news-out.mixmin.net	166	0	1	165
9	feeder.eternal-september.org	94	15	1	78
10	news-out.aioe.org	52	17	0	35
11	news.fcku.it	22	9	1	12
12	news.swapon.de	7	0	0	7
13	newsfeeds.xmission.com	7	0	1	6
14	nntp.pasdenom.info	6	0	0	6
15	news.bbs.nz	1	0	1	0
TOTAL: 15		5512	474	18	5020

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	120
alt.bbs.synchronet	56
it-alt.sport.calcio.juventus	39
alt.binaries.pictures.purebeauty	33
mozilla.general	28
mozilla.support.thunderbird	28
lada.auto	19
mozilla.dev.security.policy	11
alt.survival	10
alt.politics	10
alt.politics.usa	8
att.jobs	8
mozilla.support.seamonkey	7
rocksolid.shared.linux	7
mozilla.support.firefox	6
oesterreich.tratsch	6
spline.eisfair	5
mozilla.dev.platform	4
alt.job	4
aus.jobs	4
TOTAL: 66	474

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3272
EMP (phn nph)	94
EMP (phl)	76
Too many newsgroups	40
HTML Multipart	32
EMP (phn path)	28
Binary: misplaced rar	21
Binary Payload	15
User-issued cancel	8
Scoring filter (10)	6
Scoring filter (12)	6
HTML post	4
User-issued spam cancel	3
Too many newsgroups (low_xpost)	2
Scoring filter (8)	2
Scoring filter (9)	1
Malformed article	1
TOTAL: 17	3611

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	35	0	37	37
TOTAL: 1	35	18	37	37

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	468
	csiph-out.feeder.erje.net	198
	csiph-out.news.xs4all.nl	142
	csiph-out.feed.uzoreto.com	73
	csiph-out.news.weretis.net	73
	newsfeeds.xmission.com	5
	feeder.eternal-september.org	4
	news-out.mixmin.net	3
	newsfeed.fu-berlin.de	2
	TOTAL: 9	968
Including strange strings
	newsfeed.fu-berlin.de	341
	csiph-out.news.xs4all.nl	49
	csiph-out.news.weretis.net	12
	news-out.mixmin.net	11
	news.swapon.de	7
	csiph-out.feed.uzoreto.com	6
	news.freedyn.net	5
	csiph-out.feeder.erje.net	4
	news-out.aioe.org	2
	TOTAL: 9	437
No colon-space in header
	csiph-out.news.xs4all.nl	3
	TOTAL: 1	3
TOTAL: 4		1453

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	20124	772	15774	0	0	3%	23:59:53
2	news.freedyn.net	15235	547	14196	14	0	3%	23:59:53
3	news.samoylyk.net	17412	504	15949	0	67	2%	23:50:19
4	fu-berlin.de	15528	490	15038	0	0	3%	23:59:53
5	news.bbs.nz	31228	372	15607	15	0	1%	23:52:15
6	erje	15504	240	12766	71	0	1%	23:59:53
7	usenet.pasdenom.info	17060	181	15762	546	13	1%	23:59:53
8	aioe	14603	38	13907	2	0	0%	23:54:11
9	newsfeed.xs4all.nl	15638	31	14418	11	46	0%	23:56:26
10	neva.ru	16030	26	14804	157	0	0%	23:59:53
11	news.uzoreto.com	16257	21	15796	130	0	0%	23:50:07
12	news.mixmin.net	15542	10	14918	0	0	0%	23:59:43
13	weretis.net	14613	7	13261	1	0	0%	23:50:22
14	xmission	20793	5	15378	1	0	0%	23:59:48
15	eternal-september	14500	4	13856	9	0	0%	23:59:53
16	news.swapon.de	1687	4	1624	0	0	0%	23:43:23
17	news.fcku.it	1857	0	1829	0	0	0%	21:45:50
18	news.redatomik.org	0	0	0	0	15984	0%	23:50:07
TOTAL: 18		263611	3252	224883	957	16110	1%	428:31:42

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	9.1 KB	23:59:53
2	news.freedyn.net	6.5 MB	25.6 KB	6.5 MB	0.1 KB/s	11.9 KB	23:59:53
3	fu-berlin.de	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	13.1 KB	23:59:53
4	news.samoylyk.net	6.2 MB	0.0 KB	6.2 MB	0.1 KB/s	12.6 KB	23:50:19
5	news.bbs.nz	895.4 KB	46.0 KB	941.5 KB	0.0 KB/s	2.4 KB	23:52:15
6	erje	720.9 KB	320.0 KB	1.0 MB	0.0 KB/s	3.3 KB	23:59:53
7	usenet.pasdenom.info	499.0 KB	6.5 MB	7.0 MB	0.1 KB/s	9.8 KB	23:59:53
8	newsfeed.xs4all.nl	121.3 KB	28.6 KB	149.9 KB	0.0 KB/s	3.6 KB	23:56:26
9	aioe	89.5 KB	11.1 KB	100.6 KB	0.0 KB/s	2.5 KB	23:54:11
10	neva.ru	89.1 KB	322.7 KB	411.8 KB	0.0 KB/s	2.3 KB	23:59:53
11	news.uzoreto.com	44.5 KB	385.2 KB	429.7 KB	0.0 KB/s	2.8 KB	23:50:07
12	news.mixmin.net	36.1 KB	0.0 KB	36.1 KB	0.0 KB/s	3.6 KB	23:59:43
13	weretis.net	28.3 KB	1.2 KB	29.6 KB	0.0 KB/s	3.7 KB	23:50:22
14	xmission	25.7 KB	1.5 KB	27.2 KB	0.0 KB/s	4.5 KB	23:59:48
15	eternal-september	23.8 KB	51.5 KB	75.3 KB	0.0 KB/s	5.8 KB	23:59:53
16	news.swapon.de	23.5 KB	0.0 KB	23.5 KB	0.0 KB/s	5.9 KB	23:43:23
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:45:50
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
TOTAL: 18		28.4 MB	7.6 MB	36.0 MB	0.0 KB/s	8.8 KB	428:31:42

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	19	8	36.9 KB	4	0	0	04:50:32
2	71-82-166-98.dhcp.mdsn.wi.charter.com	16	6	29.8 KB	5	4	4	06:17:49
TOTAL: 2		35	14	66.7 KB	9	4	4	12:08:34

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	19	8	36.9 KB	4	0	0	04:50:32
2	*.dhcp.mdsn.wi.charter.com	16	6	29.8 KB	5	4	4	06:17:49
3	unresolved	3	0	0.0 KB	0	0	0	00:00:04
4	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:06
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
TOTAL: 5		41	14	66.7 KB	9	4	4	12:08:34

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
71-82-166-98.dhcp.mdsn.wi.charter.com	1.213	0.331	0.008	06:17:49
a131033.upc-a.chello.nl	1.522	0.324	0.000	04:50:32
ip72-222-200-117.ph.ph.cox.net	0.227	0.034	0.000	01:00:06
94.163.167.234	0.049	0.032	0.000	00:00:01
94.161.72.90	0.063	0.016	0.000	00:00:01
94.163.232.38	0.064	0.016	0.000	00:00:01
scan-141.security.ipip.net	0.071	0.009	0.000	00:00:01
TOTAL: 7	3.209	0.762	0.008	12:08:34

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	2
94.163.167.234	1
94.163.232.38	1
94.161.72.90	1
scan-141.security.ipip.net	1
TOTAL: 5	6

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
GET / HTTP/1.1	1
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-166-98.dhcp.mdsn.wi.charter.com	7	0
ip72-222-200-117.ph.ph.cox.net	2	0
TOTAL: 2	9	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 19 04:15:00 -- Sep 20 04:15:00