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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	29404	56.6%	6.3 MB	70.7%
innd	13364	25.7%	1.6 MB	17.7%
nnrpd	5891	11.3%	496.3 KB	5.4%
innfeed	3201	6.2%	554.8 KB	6.1%
nocem	45	0.1%	4.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	51906	100.0%	8.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	325689	55.6%
Negative hits	231597	39.5%
Do not exist	28480	4.9%
Cache misses	464	0.1%
TOTAL: 4	586230	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.302	0.0%	213	0.000	1.418	14.000
article cleanup	00:00:00.205	0.0%	28200	0.000	0.007	0.041
article logging	00:00:00.586	0.0%	28850	0.000	0.020	0.500
article parse	00:00:01.699	0.0%	77282	0.004	0.022	0.192
article write	00:00:30.302	0.0%	20457	0.226	1.481	20.495
artlog/artcncl	00:00:00.001	0.0%	189	0.000	0.005	0.500
artlog/artparse	00:00:00.007	0.0%	392	0.000	0.018	1.000
data move	00:00:00.163	0.0%	565119	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.072	0.0%	205	0.000	0.351	3.000
hishave/artcncl	00:00:00.065	0.0%	213	0.000	0.305	4.462
hishave/artparse	00:00:00.003	0.0%	391	0.000	0.008	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:34.048	0.0%	587794	0.005	0.058	0.591
history sync	00:00:01.568	0.0%	375	0.000	4.181	34.667
history write	00:00:50.611	0.1%	28181	0.228	1.796	11.184
hiswrite/artcncl	00:00:00.007	0.0%	8	0.000	0.875	1.400
hiswrite/artparse	00:00:00.653	0.0%	386	0.000	1.692	17.000
idle	23:52:15.075	99.4%	567062	88.746	151.544	446.428
nntp read	00:00:02.664	0.0%	573008	0.002	0.005	0.009
overview write	00:05:12.258	0.4%	20457	0.398	15.264	2222.119
perl filter	00:00:48.575	0.1%	27962	1.237	1.737	2.811
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.368	0.0%	40955	0.000	0.009	0.041
TOTAL: 24:01:01.675	24:00:19.232	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.053	0.0%	20456	0.000	0.003	0.026
article prepare	00:00:00.004	0.0%	17568	0.000	0.000	0.010
article read	00:00:00.405	0.0%	2551	0.000	0.159	7.800
backlog stats	00:00:00.228	0.0%	369216	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.968	0.0%	287870	0.003	0.007	0.016
data write	00:00:01.518	0.0%	273040	0.001	0.006	0.013
idle	23:53:23.112	100.0%	369216	126.235	232.934	595.951
status file	00:00:00.258	0.0%	275	0.000	0.938	4.000
TOTAL: 23:53:37.520	23:53:27.546	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	08:39:24.025	99.9%	5916	312.475	5267.753	600142.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.062	0.0%	12427	0.000	0.005	0.071
readart	00:00:00.192	0.0%	147	0.000	1.306	36.000
TOTAL: 08:39:50.385	08:39:24.279	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	6	42346	5873	35829	644	13%	47:36:59
2	feeder.eternal-september.org	5	24442	2585	21004	853	10%	24:37:02
3	csiph-out.feeder.erje.net	1152	87822	2501	82609	2712	2%	94:57:13
4	news-out.mixmin.net	2	21654	2339	18679	636	10%	24:08:58
5	news-out.aioe.org	2	20082	1762	18268	52	8%	24:09:02
6	newsfeed.neva.ru	146	26657	1495	22570	2592	5%	71:24:11
7	newsfeeds.xmission.com	2	22202	1425	20771	6	6%	26:53:37
8	newsfeed.fu-berlin.de	21	20020	1190	18538	292	5%	26:42:57
9	news.fcku.it	2	9059	556	8222	281	6%	25:43:59
10	news.redatomik.org	2	17697	468	17182	47	2%	21:21:20
11	news.freedyn.net	2	23410	66	23301	43	0%	24:34:31
12	news.swapon.de	5	1686	44	1642	0	2%	25:18:53
13	nntp.pasdenom.info	3	19111	35	19074	2	0%	24:08:26
14	xanadu-bbs.net	13	710	7	703	0	0%	24:15:05
15	news.bbs.geek.nz	1	19248	5	19242	1	0%	23:59:48
16	localhost	33	0	0	0	0	0%	00:00:04
TOTAL: 16		1397	356146	20351	327634	8161	5%	509:52:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	16.8 MB	6.3 KB	18.5 MB	35.3 MB	47%	5.5 KB
2	csiph-out.feeder.erje.net	12.8 MB	24.9 KB	13.3 MB	26.2 MB	48%	5.1 KB
3	news-out.mixmin.net	6.7 MB	5.7 KB	1.4 MB	8.1 MB	82%	2.8 KB
4	feeder.eternal-september.org	6.5 MB	7.4 KB	3.3 MB	9.8 MB	66%	2.9 KB
5	news-out.aioe.org	4.1 MB	0.0 KB	89.3 KB	4.2 MB	97%	2.3 KB
6	newsfeeds.xmission.com	4.0 MB	0.0 KB	178.7 KB	4.1 MB	95%	3.0 KB
7	newsfeed.neva.ru	3.1 MB	8.6 KB	19.5 MB	22.6 MB	13%	5.7 KB
8	newsfeed.fu-berlin.de	2.7 MB	2.0 KB	601.9 KB	3.3 MB	82%	2.3 KB
9	news.fcku.it	1.2 MB	0.0 KB	1.2 MB	2.5 MB	50%	3.0 KB
10	news.redatomik.org	1.2 MB	1.3 KB	202.0 KB	1.4 MB	85%	2.8 KB
11	news.freedyn.net	184.8 KB	0.0 KB	271.8 KB	456.6 KB	40%	4.2 KB
12	news.swapon.de	118.9 KB	0.0 KB	0.0 KB	118.9 KB	100%	2.7 KB
13	nntp.pasdenom.info	93.7 KB	0.0 KB	5.1 KB	98.8 KB	94%	2.7 KB
14	xanadu-bbs.net	27.2 KB	0.0 KB	0.0 KB	27.2 KB	100%	3.9 KB
15	news.bbs.geek.nz	8.2 KB	2.8 KB	2.8 KB	13.7 KB	59%	2.3 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		59.6 MB	58.9 KB	58.6 MB	118.2 MB	50%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 21 04:15:00 - 04:59:59	282	1.4%	0.10	1.1 MB	1.8%	0.42
Dec 21 05:00:00 - 05:59:59	505	2.5%	0.14	2.4 MB	4.0%	0.68
Dec 21 06:00:00 - 06:59:59	473	2.3%	0.13	1.5 MB	2.6%	0.44
Dec 21 07:00:00 - 07:59:59	654	3.2%	0.18	2.7 MB	4.6%	0.78
Dec 21 08:00:00 - 08:59:59	785	3.8%	0.22	2.1 MB	3.6%	0.61
Dec 21 09:00:00 - 09:59:59	1030	5.0%	0.29	2.6 MB	4.4%	0.75
Dec 21 10:00:00 - 10:59:59	885	4.3%	0.25	2.0 MB	3.3%	0.57
Dec 21 11:00:00 - 11:59:59	1063	5.2%	0.30	2.8 MB	4.7%	0.80
Dec 21 12:00:00 - 12:59:59	985	4.8%	0.27	2.7 MB	4.6%	0.78
Dec 21 13:00:00 - 13:59:59	1015	5.0%	0.28	2.8 MB	4.7%	0.79
Dec 21 14:00:00 - 14:59:59	1090	5.3%	0.30	2.5 MB	4.3%	0.73
Dec 21 15:00:00 - 15:59:59	1143	5.6%	0.32	3.9 MB	6.5%	1.10
Dec 21 16:00:00 - 16:59:59	1215	5.9%	0.34	3.2 MB	5.3%	0.91
Dec 21 17:00:00 - 17:59:59	1258	6.2%	0.35	3.7 MB	6.2%	1.05
Dec 21 18:00:00 - 18:59:59	1092	5.3%	0.30	2.9 MB	4.9%	0.83
Dec 21 19:00:00 - 19:59:59	1176	5.8%	0.33	4.0 MB	6.6%	1.12
Dec 21 20:00:00 - 20:59:59	1015	5.0%	0.28	2.8 MB	4.7%	0.79
Dec 21 21:00:00 - 21:59:59	1079	5.3%	0.30	2.8 MB	4.7%	0.80
Dec 21 22:00:00 - 22:59:59	983	4.8%	0.27	2.9 MB	4.9%	0.82
Dec 21 23:00:00 - 23:59:59	795	3.9%	0.22	2.5 MB	4.2%	0.71
Dec 22 00:00:00 - 00:59:59	602	2.9%	0.17	1.8 MB	3.0%	0.51
Dec 22 01:00:00 - 01:59:59	467	2.3%	0.13	1.3 MB	2.1%	0.36
Dec 22 02:00:00 - 02:59:59	401	2.0%	0.11	1.2 MB	2.1%	0.35
Dec 22 03:00:00 - 03:59:59	355	1.7%	0.10	1.2 MB	2.0%	0.34
Dec 22 04:00:00 - 04:15:00	85	0.4%	0.09	218.3 KB	0.4%	0.24
TOTAL: 24:00:00	20433	100.0%	0.24	59.7 MB	100.0%	0.71

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	2760	167	8	2585
2	newsfeed.neva.ru	2618	219	2	2397
3	feeder.eternal-september.org	845	20	3	822
4	newsfeed.fu-berlin.de	730	24	1	705
5	csiph-out.news.weretis.net	722	253	3	466
6	news-out.mixmin.net	696	1	2	693
7	news.fcku.it	274	1	0	273
8	news-out.aioe.org	59	21	0	38
9	news.redatomik.org	58	0	1	57
10	news.swapon.de	13	0	0	13
11	news.freedyn.net	11	0	0	11
12	newsfeeds.xmission.com	7	4	0	3
13	nntp.pasdenom.info	2	1	0	1
TOTAL: 13		8795	711	20	8064

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	128
mpc.lists.freebsd.ports.bugs	49
mozilla.general	39
lada.auto	32
it.annunci.varie	27
mozilla.support.seamonkey	27
gmane.linux.alsa.devel	24
lada.photo	20
free.it.religioni.scientology	19
mpc.lists.nanog	18
it-alt.sport.calcio.juventus	17
mpc.lists.freebsd.x11	15
it-alt.comp.software.mozilla	15
spline.eisfair	14
it.annunci.commerciali	13
mozilla.support.firefox	12
alt.survival	11
oesterreich.tratsch	9
lada.mobile	8
it.annunci.immobiliari	8
TOTAL: 127	711

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5310
EMP (phn path)	468
Binary: misplaced rar	442
EMP (phn nph)	305
EMP (phl)	70
Too many newsgroups	47
HTML Multipart	34
User-issued cancel	30
Scoring filter (10)	24
Binary Payload	18
Scoring filter (12)	14
Scoring filter (9)	8
Scoring filter (11)	8
Scoring filter (13)	6
Scoring filter (8)	5
Scoring filter (15)	3
Scoring filter (14)	3
HTML post	2
Binary: misplaced par2	2
Binary Image: misplaced jpg	2
TOTAL: 26	6808

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	38	0	52	52
TOTAL: 1	38	0	52	52

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	217
	TOTAL: 1	217
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	csiph-out.news.weretis.net	190
	csiph-out.feeder.erje.net	168
	feeder.eternal-september.org	12
	news.fcku.it	6
	news-out.mixmin.net	5
	newsfeeds.xmission.com	3
	news.freedyn.net	3
	news.redatomik.org	3
	TOTAL: 8	390
Including strange strings
	newsfeed.fu-berlin.de	446
	csiph-out.news.weretis.net	74
	news-out.mixmin.net	59
	csiph-out.feeder.erje.net	37
	news.swapon.de	13
	news.redatomik.org	8
	news-out.aioe.org	7
	feeder.eternal-september.org	2
	news.fcku.it	1
	TOTAL: 9	647
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 5		1259

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	37665	1198	18685	69	3	3%	24:02:47
2	news.freedyn.net	21785	651	19810	3	0	2%	24:04:10
3	xanadu-bbs	22360	570	19855	0	1	2%	23:59:52
4	fu-berlin.de	18961	478	18482	0	0	2%	23:50:09
5	usenet.pasdenom.info	21220	91	19159	1150	4	0%	23:59:53
6	aioe	17856	39	17331	0	0	0%	23:52:06
7	news.redatomik.org	20107	21	19430	0	45	0%	23:58:48
8	erje	18437	5	17802	1	1	0%	23:58:55
9	xmission	20217	4	18374	0	1	0%	23:59:51
10	eternal-september	17632	3	17096	0	1	0%	23:59:53
11	news.swapon.de	1769	3	1755	0	0	0%	23:47:16
12	weretis.net	13921	3	13560	1	0	0%	24:03:31
13	neva.ru	18463	2	18031	110	0	0%	23:59:53
14	news.fcku.it	1924	1	1906	2	0	0%	22:09:33
15	bwh	0	0	0	0	19926	0%	23:58:38
16	news.mixmin.net	17763	0	17393	0	0	0%	23:56:17
17	au2pb	0	0	0	0	19868	0%	23:50:12
TOTAL: 17		270080	3069	238669	1336	39850	1%	405:31:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.8 MB	35.5 KB	7.8 MB	0.1 KB/s	12.2 KB	24:04:10
2	xanadu-bbs	7.5 MB	0.0 KB	7.5 MB	0.1 KB/s	13.5 KB	23:59:52
3	fu-berlin.de	7.3 MB	0.0 KB	7.3 MB	0.1 KB/s	15.7 KB	23:50:09
4	news.bbs.geek.nz	3.2 MB	221.7 KB	3.5 MB	0.0 KB/s	2.8 KB	24:02:47
5	usenet.pasdenom.info	315.6 KB	8.4 MB	8.7 MB	0.1 KB/s	7.2 KB	23:59:53
6	aioe	89.8 KB	0.0 KB	89.8 KB	0.0 KB/s	2.3 KB	23:52:06
7	news.redatomik.org	45.5 KB	0.0 KB	45.5 KB	0.0 KB/s	2.2 KB	23:58:48
8	eternal-september	12.8 KB	0.0 KB	12.8 KB	0.0 KB/s	4.3 KB	23:59:53
9	erje	9.1 KB	2.9 KB	12.0 KB	0.0 KB/s	2.0 KB	23:58:55
10	xmission	7.9 KB	0.0 KB	7.9 KB	0.0 KB/s	2.0 KB	23:59:51
11	news.swapon.de	6.4 KB	0.0 KB	6.4 KB	0.0 KB/s	2.1 KB	23:47:16
12	weretis.net	5.2 KB	2.3 KB	7.5 KB	0.0 KB/s	1.9 KB	24:03:31
13	neva.ru	2.8 KB	251.6 KB	254.4 KB	0.0 KB/s	2.3 KB	23:59:53
14	news.fcku.it	1.3 KB	5.8 KB	7.0 KB	0.0 KB/s	2.3 KB	22:09:33
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:38
16	news.mixmin.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:56:17
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:12
TOTAL: 17		26.4 MB	8.9 MB	35.3 MB	0.0 KB/s	8.2 KB	405:31:44

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	141.168.117.108	48	139	445.5 KB	87	00:36:49
2	challenger.nielsenmedia.com	5	7	16.5 KB	4	01:17:10
3	194-73-239-109.cust.centrio.cz	4	1	1.8 KB	1	01:01:54
TOTAL: 3		57	147	463.9 KB	92	08:39:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	50	139	445.5 KB	87	00:37:10
2	*.nielsenmedia.com	5	7	16.5 KB	4	01:17:10
3	*.cust.centrio.cz	4	1	1.8 KB	1	01:01:54
4	*.upc-a.chello.nl	15	0	0.0 KB	0	02:11:57
5	*.bbox.io	3	0	0.0 KB	0	01:30:06
6	*.dhcp.mdsn.wi.charter.com	4	0	0.0 KB	0	02:01:29
TOTAL: 6		81	147	463.9 KB	92	08:39:49

NNRP auth users [Top 20]:

User	Conn
moussa	48
ctomczyk	4
TOTAL: 2	52

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	1.103	0.245	0.000	02:11:57
96-42-18-226.dhcp.mdsn.wi.charter.com	0.257	0.062	0.000	02:01:29
tempe0.bbox.io	0.326	0.065	0.000	01:30:06
challenger.nielsenmedia.com	0.602	0.094	0.000	01:17:10
194-73-239-109.cust.centrio.cz	0.380	0.070	0.012	01:01:54
141.168.117.108	5.401	1.144	0.161	00:36:49
92.206.168.128	0.100	0.023	0.000	00:00:20
TOTAL: 7	8.169	1.703	0.173	08:39:49

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	15
96-42-18-226.dhcp.mdsn.wi.charter.com	4
tempe0.bbox.io	3
92.206.168.128	2
TOTAL: 4	24

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-18-226.dhcp.mdsn.wi.charter.com	4	0
tempe0.bbox.io	3	0
challenger.nielsenmedia.com	2	0
194-73-239-109.cust.centrio.cz	2	0
TOTAL: 4	11	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	119	81.0%
2	alt	28	19.0%
TOTAL: 2		147	100.0%

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

Newsgroup		Count
1	comp.arch	29
2	alt.comp.freeware	28
3	comp.sys.ibm.pc.games.action	26
4	comp.os.vms	25
5	comp.ai.philosophy	23
6	comp.lang.awk	7
7	comp.os.cpm	5
8	comp.windows.x	1
9	comp.sys.ibm.pc.games.adventure	1
10	comp.lang.javascript	1
11	comp.arch.fpga	1
TOTAL: 11		147

Daily Usenet report for csiph.com

Dec 21 04:15:00 -- Dec 22 04:15:00