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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	17200	45.4%	4.3 MB	59.9%
innd	16660	44.0%	2.2 MB	30.8%
innfeed	3316	8.8%	603.0 KB	8.2%
nnrpd	638	1.7%	69.9 KB	0.9%
nocem	54	0.1%	6.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	37869	100.0%	7.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	261713	54.9%
Negative hits	197744	41.5%
Do not exist	16752	3.5%
Cache misses	232	0.0%
TOTAL: 4	476441	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.537	0.0%	32	0.000	16.781	56.857
article cleanup	00:00:00.093	0.0%	16309	0.000	0.006	0.043
article logging	00:00:00.253	0.0%	16716	0.000	0.015	0.167
article parse	00:00:00.856	0.0%	50917	0.000	0.017	0.271
article write	00:00:11.724	0.0%	14021	0.174	0.836	42.804
artlog/artcncl	00:00:00.000	0.0%	32	0.000	0.000	0.000
artlog/artparse	00:00:00.009	0.0%	464	0.000	0.019	0.333
data move	00:00:00.150	0.0%	442626	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.016	0.0%	29	0.000	0.552	1.500
hishave/artcncl	00:00:00.002	0.0%	32	0.000	0.062	0.667
hishave/artparse	00:00:00.001	0.0%	457	0.000	0.002	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.632	0.0%	476427	0.001	0.008	0.113
history sync	00:00:00.594	0.0%	388	0.000	1.531	5.000
history write	00:00:08.053	0.0%	16303	0.180	0.494	3.761
hiswrite/artcncl	00:00:00.001	0.0%	3	0.333	0.333	0.333
hiswrite/artparse	00:00:00.413	0.0%	457	0.000	0.904	10.053
idle	23:59:18.281	99.9%	452249	106.730	190.953	678.961
nntp read	00:00:01.721	0.0%	452455	0.001	0.004	0.008
overview write	00:00:29.902	0.0%	14021	0.127	2.133	67.000
perl filter	00:00:21.878	0.0%	16296	1.062	1.343	2.175
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.217	0.0%	28095	0.000	0.008	0.032
TOTAL: 24:01:11.394	24:00:38.333	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	14048	0.000	0.003	0.027
article prepare	00:00:00.003	0.0%	18808	0.000	0.000	0.012
article read	00:00:00.324	0.0%	1969	0.000	0.165	14.667
backlog stats	00:00:00.164	0.0%	327978	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.167	0.0%	250010	0.001	0.005	0.014
data write	00:00:01.313	0.0%	236060	0.000	0.006	0.011
idle	23:54:23.837	100.0%	327978	148.815	262.407	851.180
status file	00:00:00.149	0.0%	255	0.000	0.584	2.000
TOTAL: 23:54:33.296	23:54:26.996	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	22:53:08.899	100.0%	515	63.000	159978.445	600158.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	1211	0.000	0.006	0.100
readart	00:00:00.387	0.0%	56	0.000	6.911	42.000
TOTAL: 22:53:23.170	22:53:09.293	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	13110	2468	10577	65	18%	24:08:40
2	csiph-out.feeder.erje.net	1440	66676	1899	64405	372	2%	118:42:27
3	newsfeeds.xmission.com	2	12539	1812	10685	42	14%	22:08:35
4	feeder.eternal-september.org	1	15390	1687	13620	83	10%	23:30:29
5	csiph-out.news.xs4all.nl	861	15146	1595	12798	753	10%	71:27:55
6	news.freedyn.net	1	15907	1007	14298	602	6%	23:59:48
7	newsfeed.neva.ru	154	12931	770	11843	318	5%	72:49:08
8	newsfeed.fu-berlin.de	5	12913	742	12120	51	5%	23:03:18
9	news-out.mixmin.net	2	14590	735	13679	176	5%	23:59:03
10	csiph-out.news.weretis.net	3	17917	708	17159	50	3%	34:12:22
11	csiph-out.feed.uzoreto.com	864	21485	209	21084	192	0%	71:12:32
12	news.fcku.it	8	1191	94	1095	2	7%	21:22:42
13	nntp.pasdenom.info	2	8734	53	8656	25	0%	23:59:48
14	news.swapon.de	7	1239	21	1218	0	1%	24:11:07
15	csiph-out.news.tnetconsulting.net	1	4662	14	4647	1	0%	11:12:29
16	news.samoylyk.net	1	13058	10	13047	1	0%	23:59:50
17	localhost	36	10	10	0	0	100%	00:00:05
18	news.bbs.nz	1	4846	5	4841	0	0%	11:55:31
19	xanadu-bbs.net	35	360	0	360	0	0%	21:23:46
TOTAL: 19		3427	252704	13839	236132	2733	5%	647:19:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	10.3 MB	0.0 KB	11.8 MB	22.1 MB	46%	10.0 KB
2	news-out.aioe.org	6.4 MB	3.0 KB	146.5 KB	6.6 MB	97%	2.7 KB
3	newsfeeds.xmission.com	5.6 MB	0.0 KB	2.1 MB	7.7 MB	72%	4.3 KB
4	feeder.eternal-september.org	4.7 MB	10.5 KB	450.6 KB	5.2 MB	91%	3.0 KB
5	csiph-out.news.xs4all.nl	4.5 MB	0.0 KB	6.9 MB	11.4 MB	39%	5.0 KB
6	news.freedyn.net	2.6 MB	1.3 KB	1.7 MB	4.3 MB	59%	2.7 KB
7	newsfeed.neva.ru	2.3 MB	1.4 KB	1.4 MB	3.7 MB	61%	3.5 KB
8	news-out.mixmin.net	2.0 MB	14.0 KB	368.6 KB	2.4 MB	84%	2.6 KB
9	csiph-out.news.weretis.net	2.0 MB	6.4 KB	793.2 KB	2.7 MB	71%	3.7 KB
10	newsfeed.fu-berlin.de	1.7 MB	2.7 KB	181.8 KB	1.9 MB	90%	2.4 KB
11	csiph-out.feed.uzoreto.com	897.2 KB	0.0 KB	3.1 MB	3.9 MB	22%	10.1 KB
12	news.fcku.it	164.9 KB	0.0 KB	3.4 KB	168.3 KB	97%	1.8 KB
13	nntp.pasdenom.info	109.4 KB	0.0 KB	44.3 KB	153.7 KB	71%	2.0 KB
14	news.swapon.de	86.3 KB	0.0 KB	0.0 KB	86.3 KB	100%	4.1 KB
15	csiph-out.news.tnetconsulting.net	43.3 KB	0.0 KB	2.2 KB	45.5 KB	95%	3.0 KB
16	news.samoylyk.net	27.7 KB	0.0 KB	1.4 KB	29.1 KB	95%	2.6 KB
17	localhost	13.1 KB	0.0 KB	0.0 KB	13.1 KB	100%	1.3 KB
18	news.bbs.nz	9.9 KB	0.0 KB	0.0 KB	9.9 KB	100%	2.0 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 19		43.3 MB	39.3 KB	29.0 MB	72.3 MB	59%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 1 04:15:00 - 04:59:59	203	1.4%	0.08	686.7 KB	1.5%	0.25
Dec 1 05:00:00 - 05:59:59	238	1.7%	0.07	650.9 KB	1.4%	0.18
Dec 1 06:00:00 - 06:59:59	303	2.2%	0.08	1008.6 KB	2.2%	0.28
Dec 1 07:00:00 - 07:59:59	536	3.8%	0.15	3.1 MB	7.0%	0.87
Dec 1 08:00:00 - 08:59:59	583	4.2%	0.16	2.3 MB	5.1%	0.64
Dec 1 09:00:00 - 09:59:59	600	4.3%	0.17	1.7 MB	3.9%	0.49
Dec 1 10:00:00 - 10:59:59	638	4.6%	0.18	1.8 MB	4.2%	0.52
Dec 1 11:00:00 - 11:59:59	619	4.4%	0.17	2.0 MB	4.7%	0.58
Dec 1 12:00:00 - 12:59:59	579	4.1%	0.16	2.0 MB	4.6%	0.57
Dec 1 13:00:00 - 13:59:59	699	5.0%	0.19	1.8 MB	4.1%	0.51
Dec 1 14:00:00 - 14:59:59	754	5.4%	0.21	2.4 MB	5.4%	0.67
Dec 1 15:00:00 - 15:59:59	730	5.2%	0.20	2.3 MB	5.1%	0.64
Dec 1 16:00:00 - 16:59:59	767	5.5%	0.21	1.9 MB	4.4%	0.55
Dec 1 17:00:00 - 17:59:59	812	5.8%	0.23	2.3 MB	5.3%	0.66
Dec 1 18:00:00 - 18:59:59	854	6.1%	0.24	2.1 MB	4.8%	0.60
Dec 1 19:00:00 - 19:59:59	868	6.2%	0.24	2.7 MB	6.1%	0.77
Dec 1 20:00:00 - 20:59:59	783	5.6%	0.22	2.2 MB	4.9%	0.62
Dec 1 21:00:00 - 21:59:59	772	5.5%	0.21	2.3 MB	5.3%	0.67
Dec 1 22:00:00 - 22:59:59	643	4.6%	0.18	1.8 MB	4.0%	0.50
Dec 1 23:00:00 - 23:59:59	572	4.1%	0.16	2.0 MB	4.5%	0.57
Dec 2 00:00:00 - 00:59:59	449	3.2%	0.12	1.2 MB	2.7%	0.33
Dec 2 01:00:00 - 01:59:59	302	2.2%	0.08	1.0 MB	2.4%	0.29
Dec 2 02:00:00 - 02:59:59	273	1.9%	0.08	911.0 KB	2.0%	0.25
Dec 2 03:00:00 - 03:59:59	370	2.6%	0.10	1.6 MB	3.7%	0.46
Dec 2 04:00:00 - 04:15:00	65	0.5%	0.07	187.7 KB	0.4%	0.21
TOTAL: 24:00:00	14012	100.0%	0.16	43.9 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	767	39	0	728
2	news.freedyn.net	615	4	1	610
3	newsfeed.fu-berlin.de	383	31	0	352
4	csiph-out.feeder.erje.net	374	46	0	328
5	newsfeed.neva.ru	318	68	1	249
6	csiph-out.feed.uzoreto.com	192	10	0	182
7	news-out.mixmin.net	183	1	5	177
8	news-out.aioe.org	98	38	1	59
9	feeder.eternal-september.org	81	24	2	55
10	csiph-out.news.weretis.net	73	45	3	25
11	newsfeeds.xmission.com	42	4	0	38
12	nntp.pasdenom.info	25	0	0	25
13	news.fcku.it	3	2	0	1
14	news.samoylyk.net	1	0	0	1
15	csiph-out.news.tnetconsulting.net	1	1	0	0
TOTAL: 15		3156	313	13	2830

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	41
it-alt.sport.calcio.juventus	38
lada.movies	13
alt.bbs.synchronet	13
alt.survival	12
alt.politics	11
rocksolid.shared.rocksolid	11
spline.eisfair	10
mozilla.support.seamonkey	9
mozilla.test	8
spline.eisfair.dev	8
alt.binaries.ebooks	8
spline.fli4l.dev	8
oesterreich.tratsch	7
alt.computer.workshop	7
alt.windows7.general	7
alt.binaries.pictures.purebeauty	6
mozilla.support.firefox	6
rocksolid.nodes.help	5
rocksolid.shared.test	5
TOTAL: 68	313

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1602
EMP (phn nph)	110
0	96
EMP (phl)	93
HTML Multipart	21
Too many newsgroups	14
Binary Payload	9
Binary: misplaced binary	8
Scoring filter	4
Too many newsgroups (meow)	1
TOTAL: 10	1958

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	216
	csiph-out.news.xs4all.nl	108
	csiph-out.feed.uzoreto.com	63
	newsfeeds.xmission.com	37
	news.freedyn.net	15
	csiph-out.news.weretis.net	11
	feeder.eternal-september.org	6
	newsfeed.fu-berlin.de	2
	TOTAL: 8	458
Including strange strings
	newsfeed.fu-berlin.de	331
	news-out.aioe.org	37
	csiph-out.news.xs4all.nl	20
	news-out.mixmin.net	7
	csiph-out.news.weretis.net	4
	news.freedyn.net	4
	csiph-out.feeder.erje.net	2
	newsfeed.neva.ru	1
	news.fcku.it	1
	TOTAL: 9	407
No colon-space in header
	csiph-out.news.xs4all.nl	5
	TOTAL: 1	5
TOTAL: 4		915

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.nz	25375	1265	12171	26	1	4%	23:59:55
2	xanadu-bbs	17093	912	13084	15	8	5%	23:59:55
3	news.samoylyk.net	14268	554	13448	0	0	3%	23:59:55
4	news.freedyn.net	13159	539	12416	33	0	4%	23:59:48
5	fu-berlin.de	13093	535	12558	0	0	4%	23:59:39
6	tnet	16240	253	13213	1	0	1%	23:59:55
7	usenet.pasdenom.info	13620	88	13315	10	0	0%	23:59:32
8	erje	15005	70	11723	115	0	0%	23:59:55
9	newsfeed.xs4all.nl	12470	31	12194	0	46	0%	23:59:55
10	aioe	10448	27	10245	3	0	0%	23:59:18
11	neva.ru	12605	25	12431	103	0	0%	23:59:52
12	xmission	15201	21	11591	1	0	0%	23:59:50
13	news.swapon.de	1288	16	1266	0	0	1%	23:50:46
14	news.mixmin.net	12725	14	12503	2	0	0%	23:59:35
15	news.uzoreto.com	13507	14	13316	19	0	0%	23:59:55
16	weretis.net	12806	11	12580	5	0	0%	23:59:42
17	eternal-september	11880	9	11668	0	0	0%	23:59:55
18	news.fcku.it	968	0	968	0	0	0%	21:43:54
TOTAL: 18		231751	4384	200690	333	55	1%	429:31:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.5 MB	85.1 KB	7.6 MB	0.1 KB/s	8.4 KB	23:59:55
2	news.freedyn.net	6.6 MB	46.3 KB	6.6 MB	0.1 KB/s	11.8 KB	23:59:48
3	news.samoylyk.net	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	12.1 KB	23:59:55
4	fu-berlin.de	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	12.5 KB	23:59:39
5	news.bbs.nz	3.1 MB	57.9 KB	3.2 MB	0.0 KB/s	2.5 KB	23:59:55
6	tnet	682.6 KB	6.0 KB	688.6 KB	0.0 KB/s	2.7 KB	23:59:55
7	usenet.pasdenom.info	287.1 KB	19.9 KB	307.0 KB	0.0 KB/s	3.1 KB	23:59:32
8	erje	127.0 KB	393.7 KB	520.7 KB	0.0 KB/s	2.8 KB	23:59:55
9	newsfeed.xs4all.nl	60.4 KB	0.0 KB	60.4 KB	0.0 KB/s	1.9 KB	23:59:55
10	news.uzoreto.com	48.6 KB	90.6 KB	139.1 KB	0.0 KB/s	4.2 KB	23:59:55
11	neva.ru	46.0 KB	207.1 KB	253.1 KB	0.0 KB/s	2.0 KB	23:59:52
12	aioe	45.8 KB	2.3 KB	48.1 KB	0.0 KB/s	1.6 KB	23:59:18
13	xmission	36.9 KB	1.2 KB	38.0 KB	0.0 KB/s	1.7 KB	23:59:50
14	news.swapon.de	24.5 KB	0.0 KB	24.5 KB	0.0 KB/s	1.5 KB	23:50:46
15	news.mixmin.net	22.8 KB	3.0 KB	25.8 KB	0.0 KB/s	1.6 KB	23:59:35
16	weretis.net	14.8 KB	31.3 KB	46.1 KB	0.0 KB/s	2.9 KB	23:59:42
17	eternal-september	12.0 KB	0.0 KB	12.0 KB	0.0 KB/s	1.3 KB	23:59:55
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:43:54
TOTAL: 18		31.6 MB	944.5 KB	32.6 MB	0.0 KB/s	7.1 KB	429:31:16

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	38	23	42.6 KB	6	0	0	09:45:45
2	66-188-80-139.dhcp.mdsn.wi.charter.com	30	19	33.0 KB	12	9	7	09:23:05
3	ip72-222-200-117.ph.ph.cox.net	11	14	26.9 KB	5	1	1	03:44:26
TOTAL: 3		79	56	102.5 KB	23	10	8	22:53:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	38	23	42.6 KB	6	0	0	09:45:45
2	*.dhcp.mdsn.wi.charter.com	30	19	33.0 KB	12	9	7	09:23:05
3	*.ph.ph.cox.net	11	14	26.9 KB	5	1	1	03:44:26
4	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
5	unresolved	5	0	0.0 KB	0	0	0	00:00:04
TOTAL: 5		85	56	102.5 KB	23	10	8	22:53:22

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
kev009	1
TOTAL: 2	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.712	0.541	0.000	09:45:45
66-188-80-139.dhcp.mdsn.wi.charter.com	2.233	0.500	0.017	09:23:05
ip72-222-200-117.ph.ph.cox.net	1.079	0.178	0.002	03:44:26
94.166.147.156	0.120	0.030	0.000	00:00:02
94.161.149.229	0.099	0.053	0.000	00:00:02
scan-141.security.ipip.net	0.057	0.016	0.000	00:00:00
45.136.108.22	0.060	0.015	0.000	00:00:00
TOTAL: 7	6.360	1.333	0.019	22:53:22

Curious NNRP server explorers [Top 20]:

System	Conn
94.166.147.156	2
94.161.149.229	2
45.136.108.22	1
scan-141.security.ipip.net	1
TOTAL: 4	6

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
66-188-80-139.dhcp.mdsn.wi.charter.com	8	0
ip72-222-200-117.ph.ph.cox.net	6	0
45.136.108.22	1	1
TOTAL: 3	15	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Dec 1 04:15:00 -- Dec 2 04:15:00