Daily Usenet report for csiph.com: Jan 11 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45677	60.5%	8.5 MB	71.6%
innd	25529	33.8%	2.7 MB	22.8%
innfeed	3173	4.2%	537.7 KB	4.4%
nnrpd	1064	1.4%	120.1 KB	1.0%
nocem	116	0.2%	12.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	75560	100.0%	11.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	454142	54.9%
Negative hits	328479	39.7%
Do not exist	44396	5.4%
Cache misses	711	0.1%
TOTAL: 4	827728	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.955	0.0%	312	0.000	3.061	39.000
article cleanup	00:00:00.266	0.0%	44392	0.000	0.006	0.033
article logging	00:00:05.673	0.0%	44994	0.000	0.126	0.423
article parse	00:00:02.532	0.0%	140732	0.000	0.018	0.200
article write	00:03:08.446	0.2%	24140	0.200	7.806	18.000
artlog/artcncl	00:00:00.029	0.0%	256	0.000	0.113	1.500
artlog/artparse	00:00:00.001	0.0%	177	0.000	0.006	0.071
data move	00:00:00.264	0.0%	743173	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.107	0.0%	296	0.000	0.361	15.000
hishave/artcncl	00:00:00.037	0.0%	312	0.000	0.119	10.000
hishave/artparse	00:00:00.000	0.0%	170	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:15.832	0.2%	829187	0.002	0.164	0.393
history sync	00:00:08.163	0.0%	334	0.000	24.440	137.500
history write	00:04:47.539	0.3%	44284	0.088	6.493	11.689
hiswrite/artcncl	00:00:00.039	0.0%	16	0.000	2.438	7.000
hiswrite/artparse	00:00:00.949	0.0%	170	0.000	5.582	15.333
idle	23:32:12.459	98.7%	725110	73.924	116.855	360.827
nntp read	00:00:03.367	0.0%	758795	0.002	0.004	0.008
overview write	00:04:50.955	0.3%	24140	0.686	12.053	64.644
perl filter	00:01:45.047	0.1%	44146	1.566	2.380	3.358
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.446	0.0%	48388	0.000	0.009	0.027
TOTAL: 23:50:42.177	23:49:23.106	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.052	0.0%	24201	0.000	0.002	0.019
article prepare	00:00:00.002	0.0%	34666	0.000	0.000	0.004
article read	00:00:04.034	0.0%	6636	0.000	0.608	4.220
backlog stats	00:00:00.261	0.0%	434496	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.216	0.0%	339717	0.001	0.007	0.014
data write	00:00:01.980	0.0%	325056	0.002	0.006	0.012
idle	23:53:49.735	100.0%	434496	120.469	197.999	611.669
status file	00:00:00.256	0.0%	275	0.000	0.931	14.333
TOTAL: 23:54:12.129	23:53:58.536	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.288	0.0%	1734	0.000	0.743	19.000
idle	68:50:52.935	99.8%	2152	113.000	115173.297	900265.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.011	0.0%	4378	0.000	0.003	0.250
readart	00:00:00.156	0.0%	17	0.000	9.176	37.000
TOTAL: 69:00:10.766	68:50:54.390	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	136327	6390	118121	11816	4%	94:57:35
2	csiph-out.news.weretis.net	3	49989	5616	43297	1076	11%	47:27:59
3	feeder.eternal-september.org	5	39204	3265	32027	3912	8%	24:20:09
4	news-out.aioe.org	3	23762	3246	20479	37	13%	24:09:56
5	newsfeed.neva.ru	146	54442	1325	50156	2961	2%	71:51:59
6	news-out.mixmin.net	4	25945	1164	24473	308	4%	24:17:28
7	newsfeed.fu-berlin.de	68	21173	1096	19882	195	5%	31:24:17
8	newsfeeds.xmission.com	2	27632	920	26707	5	3%	30:17:34
9	news.redatomik.org	1	25841	692	25103	46	2%	26:38:12
10	news.freedyn.net	3	27407	207	27035	165	0%	24:09:13
11	news.fcku.it	2	10553	146	10397	10	1%	23:59:56
12	xanadu-bbs.net	14	887	113	770	4	12%	23:03:25
13	news.swapon.de	2	2132	106	2023	3	4%	23:30:35
14	nntp.pasdenom.info	2	38702	34	38653	15	0%	24:09:00
15	localhost	36	1	1	0	0	100%	00:00:09
16	news.bbs.geek.nz	1	18542	0	18542	0	0%	23:59:30
TOTAL: 16		1444	502539	24321	457665	20553	4%	518:16:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	21.1 MB	812.0 KB	67.4 MB	89.3 MB	23%	5.0 KB
2	csiph-out.news.weretis.net	15.9 MB	0.0 KB	11.9 MB	27.8 MB	57%	4.3 KB
3	feeder.eternal-september.org	8.6 MB	62.3 KB	54.6 MB	63.2 MB	13%	9.0 KB
4	news-out.aioe.org	8.3 MB	3.9 KB	62.8 KB	8.4 MB	99%	2.6 KB
5	news-out.mixmin.net	6.3 MB	2.5 KB	1.4 MB	7.6 MB	82%	5.3 KB
6	newsfeed.neva.ru	3.6 MB	276.3 KB	25.0 MB	28.9 MB	12%	6.9 KB
7	newsfeeds.xmission.com	2.5 MB	0.0 KB	186.6 KB	2.7 MB	93%	3.0 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	342.2 KB	2.6 MB	87%	2.1 KB
9	news.redatomik.org	1.8 MB	1.4 KB	1.5 MB	3.3 MB	53%	4.6 KB
10	news.freedyn.net	552.6 KB	0.0 KB	1.3 MB	1.9 MB	29%	5.1 KB
11	xanadu-bbs.net	351.5 KB	0.0 KB	11.9 KB	363.4 KB	96%	3.1 KB
12	news.swapon.de	272.6 KB	0.0 KB	7.4 KB	280.0 KB	97%	2.6 KB
13	news.fcku.it	262.3 KB	13.1 KB	199.5 KB	475.0 KB	55%	3.0 KB
14	nntp.pasdenom.info	68.8 KB	1.4 KB	37.7 KB	107.9 KB	63%	2.2 KB
15	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
16	news.bbs.geek.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		71.8 MB	1.1 MB	163.9 MB	236.9 MB	30%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 11 04:15:00 - 04:59:59	383	1.6%	0.14	1.0 MB	1.5%	0.40
Jan 11 05:00:00 - 05:59:59	493	2.0%	0.14	1.6 MB	2.3%	0.47
Jan 11 06:00:00 - 06:59:59	531	2.2%	0.15	1.8 MB	2.6%	0.52
Jan 11 07:00:00 - 07:59:59	727	3.0%	0.20	2.7 MB	3.8%	0.78
Jan 11 08:00:00 - 08:59:59	960	4.0%	0.27	2.7 MB	3.8%	0.78
Jan 11 09:00:00 - 09:59:59	1201	5.0%	0.33	3.6 MB	5.0%	1.01
Jan 11 10:00:00 - 10:59:59	1215	5.0%	0.34	3.0 MB	4.2%	0.85
Jan 11 11:00:00 - 11:59:59	1029	4.2%	0.29	3.0 MB	4.1%	0.84
Jan 11 12:00:00 - 12:59:59	1074	4.4%	0.30	2.8 MB	3.9%	0.80
Jan 11 13:00:00 - 13:59:59	1248	5.2%	0.35	3.5 MB	4.8%	0.99
Jan 11 14:00:00 - 14:59:59	1195	4.9%	0.33	3.2 MB	4.4%	0.90
Jan 11 15:00:00 - 15:59:59	1494	6.2%	0.41	4.7 MB	6.5%	1.32
Jan 11 16:00:00 - 16:59:59	1528	6.3%	0.42	5.0 MB	7.0%	1.43
Jan 11 17:00:00 - 17:59:59	1395	5.8%	0.39	4.1 MB	5.8%	1.18
Jan 11 18:00:00 - 18:59:59	1430	5.9%	0.40	4.1 MB	5.8%	1.18
Jan 11 19:00:00 - 19:59:59	1484	6.1%	0.41	4.3 MB	6.0%	1.21
Jan 11 20:00:00 - 20:59:59	1249	5.2%	0.35	3.3 MB	4.6%	0.94
Jan 11 21:00:00 - 21:59:59	1292	5.3%	0.36	3.8 MB	5.3%	1.07
Jan 11 22:00:00 - 22:59:59	1156	4.8%	0.32	3.4 MB	4.8%	0.97
Jan 11 23:00:00 - 23:59:59	821	3.4%	0.23	2.5 MB	3.5%	0.71
Jan 12 00:00:00 - 00:59:59	778	3.2%	0.22	2.1 MB	3.0%	0.61
Jan 12 01:00:00 - 01:59:59	509	2.1%	0.14	1.5 MB	2.0%	0.42
Jan 12 02:00:00 - 02:59:59	448	1.9%	0.12	2.1 MB	2.9%	0.60
Jan 12 03:00:00 - 03:59:59	449	1.9%	0.12	1.4 MB	1.9%	0.39
Jan 12 04:00:00 - 04:15:00	127	0.5%	0.14	350.5 KB	0.5%	0.39
TOTAL: 24:00:00	24216	100.0%	0.28	71.6 MB	100.0%	0.85

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	11891	497	79	0	11315
2	feeder.eternal-september.org	3963	62	9	1	3891
3	newsfeed.neva.ru	2961	393	19	0	2549
4	csiph-out.news.weretis.net	1084	176	0	0	908
5	newsfeed.fu-berlin.de	662	38	0	0	624
6	news-out.mixmin.net	312	2	2	0	308
7	news.freedyn.net	167	7	0	0	160
8	news-out.aioe.org	79	31	3	0	45
9	news.redatomik.org	32	0	1	0	31
10	nntp.pasdenom.info	16	0	1	0	15
11	news.swapon.de	12	0	0	0	12
12	news.fcku.it	11	0	1	0	10
13	newsfeeds.xmission.com	11	1	0	0	10
14	xanadu-bbs.net	4	0	0	0	4
TOTAL: 14		21205	1207	115	1	19882

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.auto	151
ut.test	130
lada.talk	126
it.annunci.varie	69
it.annunci.commerciali	67
it.annunci.immobiliari	67
alt.windows7.general	52
alt.survival	31
it-alt.sport.calcio.juventus	27
gmane.linux.alsa.devel	23
mozilla.support.seamonkey	22
lada.movies	20
mpc.lists.nanog	19
mozilla.dev.security.policy	18
gmane.comp.gis.openstreetmap.tagging	17
alt.philosophy	16
spline.eisfair	15
alt.politics.scorched-earth	14
lada.commerce	14
alt.computer.workshop	13
TOTAL: 127	1207

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
alt.fan.pratchett.old-farts	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16428
EMP (phn path)	1704
EMP (phl)	524
EMP (phn nph)	128
Too many newsgroups	74
HTML Multipart	18
User-issued cancel	17
Binary Payload	15
Scoring filter (8)	14
HTML post	13
Scoring filter (13)	5
Scoring filter (12)	5
User-issued spam cancel	5
Binary: misplaced par2	5
Scoring filter (9)	5
HTML Image Tags	2
Scoring filter (10)	1
Scoring filter (11)	1
Binary: misplaced JPG	1
Poison newsgroup	1
TOTAL: 20	18966

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	108	0	113	113
TOTAL: 1	108	0	113	113

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	131
	TOTAL: 1	131
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	feeder.eternal-september.org	103
	csiph-out.news.weretis.net	45
	news-out.mixmin.net	13
	news.redatomik.org	4
	news.freedyn.net	3
	newsfeeds.xmission.com	2
	news.fcku.it	1
	newsfeed.fu-berlin.de	1
	TOTAL: 8	172
Including strange strings
	newsfeed.fu-berlin.de	426
	csiph-out.feeder.erje.net	58
	news-out.aioe.org	41
	feeder.eternal-september.org	22
	csiph-out.news.weretis.net	15
	newsfeed.neva.ru	13
	news-out.mixmin.net	12
	news.swapon.de	9
	newsfeeds.xmission.com	6
	news.redatomik.org	3
	TOTAL: 11	606
No colon-space in header
	csiph-out.feeder.erje.net	7
	TOTAL: 1	7
TOTAL: 5		920

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	41787	4788	18833	71	1	0	11%	23:57:49
2	news.freedyn.net	24528	661	23263	4	0	0	2%	23:50:02
3	xanadu-bbs	27021	524	23569	0	0	0	1%	23:59:47
4	fu-berlin.de	22614	458	22155	0	0	0	2%	23:50:02
5	erje	18392	270	17388	39	0	27	1%	23:55:25
6	aioe	20065	57	19140	4	0	0	0%	23:51:13
7	usenet.pasdenom.info	26540	41	22448	1689	0	0	0%	23:59:50
8	xmission	26511	21	22887	1	0	0	0%	23:59:46
9	news.redatomik.org	23540	10	22989	2	0	0	0%	23:50:02
10	weretis.net	17285	9	16920	0	0	0	0%	23:53:17
11	eternal-september	21353	8	20007	0	0	0	0%	23:59:50
12	neva.ru	23108	6	22027	14	0	0	0%	23:59:50
13	news.mixmin.net	21733	5	21114	0	0	7	0%	23:58:21
14	news.swapon.de	2134	1	2106	1	0	0	0%	23:48:09
15	bwh	0	0	0	0	0	23627	0%	23:50:04
16	au2pb	0	0	0	0	0	23642	0%	23:50:06
17	news.fcku.it	3097	0	3039	0	0	0	0%	23:18:49
TOTAL: 17		319708	6859	277885	1825	1	47303	2%	405:52:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	13.3 MB	299.8 KB	13.6 MB	0.2 KB/s	2.9 KB	23:57:49
2	news.freedyn.net	4.8 MB	18.0 KB	4.9 MB	0.1 KB/s	7.5 KB	23:50:02
3	xanadu-bbs	4.5 MB	0.0 KB	4.5 MB	0.1 KB/s	8.9 KB	23:59:47
4	fu-berlin.de	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	9.8 KB	23:50:02
5	erje	1.1 MB	162.1 KB	1.3 MB	0.0 KB/s	4.2 KB	23:55:25
6	aioe	172.0 KB	8.8 KB	180.8 KB	0.0 KB/s	3.0 KB	23:51:13
7	usenet.pasdenom.info	151.7 KB	6.5 MB	6.7 MB	0.1 KB/s	3.9 KB	23:59:50
8	neva.ru	86.8 KB	113.2 KB	199.9 KB	0.0 KB/s	10.0 KB	23:59:50
9	xmission	67.4 KB	2.6 KB	70.0 KB	0.0 KB/s	3.2 KB	23:59:46
10	eternal-september	26.3 KB	0.0 KB	26.3 KB	0.0 KB/s	3.3 KB	23:59:50
11	weretis.net	23.0 KB	0.0 KB	23.0 KB	0.0 KB/s	2.6 KB	23:53:17
12	news.redatomik.org	22.8 KB	12.3 KB	35.1 KB	0.0 KB/s	2.9 KB	23:50:02
13	news.mixmin.net	10.4 KB	0.0 KB	10.4 KB	0.0 KB/s	2.1 KB	23:58:21
14	news.swapon.de	0.9 KB	1.5 KB	2.4 KB	0.0 KB/s	1.2 KB	23:48:09
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:04
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:18:49
TOTAL: 17		28.7 MB	7.1 MB	35.8 MB	0.0 KB/s	4.2 KB	405:52:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	12	8	47.0 KB	1	0	0	05:12:16
2	194-73-239-109.cust.centrio.cz	10	8	22.2 KB	5	0	0	02:12:11
3	68-185-181-64.dhcp.mdsn.wi.charter.com	13	1	0.9 KB	1	1	1	03:29:46
TOTAL: 3		35	17	70.2 KB	7	1	1	69:00:09

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cust.centrio.cz	10	8	22.2 KB	5	0	0	02:12:11
2	*.bbox.io	12	8	47.0 KB	1	0	0	05:12:16
3	*.dhcp.mdsn.wi.charter.com	13	1	0.9 KB	1	1	1	03:29:46
4	*.251.91.75.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	02:13:42
5	unresolved	100	0	0.0 KB	0	0	0	49:21:23
6	*.chello.nl	40	0	0.0 KB	0	0	0	06:30:48
TOTAL: 6		176	17	70.2 KB	7	1	1	69:00:09

NNRP auth users [Top 20]:

User	Conn
ctomczyk	8
ohlandl	1
TOTAL: 2	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.770	1.448	0.000	49:21:23
dhcp-089-099-203-254.chello.nl	3.053	0.639	0.000	06:30:48
tempe0.bbox.io	0.997	0.197	0.000	05:12:16
68-185-181-64.dhcp.mdsn.wi.charter.com	0.955	0.269	0.004	03:29:46
h23.251.91.75.dynamic.ip.windstream.net	0.100	0.151	0.000	02:13:42
194-73-239-109.cust.centrio.cz	0.939	0.203	0.055	02:12:11
TOTAL: 6	13.814	2.907	0.059	69:00:09

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	100
dhcp-089-099-203-254.chello.nl	40
h23.251.91.75.dynamic.ip.windstream.net	1
TOTAL: 3	141

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	97	0
tempe0.bbox.io	10	0
68-185-181-64.dhcp.mdsn.wi.charter.com	4	0
194-73-239-109.cust.centrio.cz	4	0
h23.251.91.75.dynamic.ip.windstream.net	1	0
TOTAL: 5	116	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	9	52.9%
2	muc	8	47.1%
TOTAL: 2		17	100.0%

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

Newsgroup		Count
1	muc.lists.freebsd.current	8
2	comp.lang.javascript	8
3	comp.sys.ibm.ps2.hardware	1
TOTAL: 3		17

Daily Usenet report for csiph.com

Jan 11 04:15:00 -- Jan 12 04:15:00