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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42395	61.0%	7.9 MB	72.2%
innd	23283	33.5%	2.5 MB	22.5%
innfeed	3232	4.7%	539.8 KB	4.8%
nnrpd	492	0.7%	53.1 KB	0.5%
nocem	62	0.1%	6.4 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	69465	100.0%	10.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	420238	54.2%
Negative hits	310418	40.1%
Do not exist	40742	5.3%
Cache misses	3286	0.4%
TOTAL: 4	774684	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.166	0.0%	199	0.000	5.859	38.750
article cleanup	00:00:00.330	0.0%	41225	0.000	0.008	0.023
article logging	00:00:00.609	0.0%	41902	0.000	0.015	0.038
article parse	00:00:01.247	0.0%	153733	0.000	0.008	0.025
article write	00:03:04.796	0.2%	23075	0.618	8.008	70.604
artlog/artcncl	00:00:00.003	0.0%	151	0.000	0.020	0.167
artlog/artparse	00:00:00.002	0.0%	125	0.000	0.016	1.000
data move	00:00:00.288	0.0%	699450	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.359	0.0%	197	0.000	1.822	20.000
hishave/artcncl	00:00:00.050	0.0%	199	0.000	0.251	2.450
hishave/artparse	00:00:00.000	0.0%	82	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:17.438	0.0%	774722	0.002	0.023	0.418
history sync	00:00:01.301	0.0%	349	0.000	3.728	51.500
history write	00:00:44.228	0.1%	40559	0.055	1.090	14.208
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.046	0.0%	82	0.000	0.561	6.000
idle	23:42:11.023	99.4%	697172	63.177	122.396	377.884
nntp read	00:00:03.261	0.0%	709462	0.002	0.005	0.008
overview write	00:01:45.238	0.1%	23075	0.146	4.561	95.024
perl filter	00:01:35.140	0.1%	40484	1.439	2.350	3.545
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.433	0.0%	46204	0.000	0.009	0.030
TOTAL: 23:50:46.793	23:49:46.958	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.085	0.0%	29264	0.000	0.003	0.022
article prepare	00:00:00.004	0.0%	22776	0.000	0.000	0.021
article read	00:00:01.957	0.0%	3401	0.000	0.575	17.438
backlog stats	00:00:00.339	0.0%	403686	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.413	0.0%	314719	0.002	0.008	0.014
data write	00:00:02.049	0.0%	300169	0.002	0.007	0.016
idle	23:54:40.976	100.0%	403686	119.333	213.237	704.417
status file	00:00:00.215	0.0%	301	0.000	0.714	29.000
TOTAL: 23:54:56.261	23:54:48.038	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	18:29:39.259	99.9%	390	489.200	170716.049	600106.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.687	0.0%	1086	0.000	7.999	44.974
readart	00:00:05.195	0.0%	7460	0.000	0.696	49.000
TOTAL: 18:30:19.064	18:29:53.141	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	125695	5687	111609	8399	4%	94:59:06
2	csiph-out.news.weretis.net	4	51499	5149	45576	774	9%	50:14:46
3	feeder.eternal-september.org	68	36196	3306	26219	6671	9%	39:51:31
4	news-out.aioe.org	2	22602	3139	19439	24	13%	24:09:57
5	newsfeed.neva.ru	149	47453	1405	44101	1947	2%	72:07:39
6	newsfeed.fu-berlin.de	69	24797	1301	23371	125	5%	38:27:54
7	news-out.mixmin.net	3	24642	1065	23333	244	4%	24:08:50
8	news.redatomik.org	1	23591	871	22705	15	3%	24:53:53
9	newsfeeds.xmission.com	2	21788	775	21001	12	3%	22:22:47
10	xanadu-bbs.net	9	826	196	620	10	23%	23:28:13
11	news.fcku.it	2	9922	123	9781	18	1%	23:59:53
12	news.swapon.de	5	2136	94	2040	2	4%	25:06:19
13	news.freedyn.net	2	24383	90	24185	108	0%	23:59:50
14	nntp.pasdenom.info	2	36477	64	36380	33	0%	24:09:00
15	news.bbs.geek.nz	4	20599	4	20595	0	0%	26:42:30
16	localhost	35	1	1	0	0	100%	00:00:01
TOTAL: 16		1509	472607	23270	430955	18382	4%	538:42:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	16.7 MB	2.2 MB	48.7 MB	67.5 MB	24%	4.9 KB
2	csiph-out.news.weretis.net	15.1 MB	32.0 KB	8.0 MB	23.1 MB	65%	4.0 KB
3	feeder.eternal-september.org	9.0 MB	6.0 MB	85.6 MB	100.7 MB	8%	10.3 KB
4	news-out.aioe.org	8.0 MB	0.0 KB	46.1 KB	8.0 MB	99%	2.6 KB
5	news-out.mixmin.net	6.3 MB	0.0 KB	1.4 MB	7.7 MB	81%	6.1 KB
6	newsfeed.neva.ru	3.9 MB	632.5 KB	17.5 MB	22.0 MB	17%	6.7 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	380.6 KB	3.3 MB	88%	2.4 KB
8	news.redatomik.org	2.7 MB	0.0 KB	643.6 KB	3.3 MB	80%	3.8 KB
9	newsfeeds.xmission.com	2.5 MB	0.0 KB	356.6 KB	2.9 MB	87%	3.7 KB
10	xanadu-bbs.net	513.5 KB	0.0 KB	28.8 KB	542.3 KB	94%	2.6 KB
11	news.freedyn.net	493.2 KB	0.0 KB	1.3 MB	1.8 MB	26%	9.5 KB
12	news.swapon.de	252.7 KB	0.0 KB	3.4 KB	256.1 KB	98%	2.7 KB
13	news.fcku.it	183.5 KB	0.0 KB	1.4 MB	1.6 MB	11%	11.4 KB
14	nntp.pasdenom.info	131.8 KB	0.0 KB	87.9 KB	219.7 KB	59%	2.3 KB
15	news.bbs.geek.nz	6.5 KB	0.0 KB	0.0 KB	6.5 KB	100%	1.6 KB
16	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
TOTAL: 16		68.6 MB	8.8 MB	165.5 MB	242.8 MB	28%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 14 04:15:00 - 04:59:59	336	1.5%	0.12	1.1 MB	1.6%	0.40
Dec 14 05:00:00 - 05:59:59	535	2.3%	0.15	1.8 MB	2.6%	0.51
Dec 14 06:00:00 - 06:59:59	529	2.3%	0.15	1.7 MB	2.5%	0.48
Dec 14 07:00:00 - 07:59:59	673	2.9%	0.19	1.9 MB	2.8%	0.55
Dec 14 08:00:00 - 08:59:59	1016	4.4%	0.28	2.9 MB	4.2%	0.82
Dec 14 09:00:00 - 09:59:59	1016	4.4%	0.28	2.7 MB	3.9%	0.76
Dec 14 10:00:00 - 10:59:59	1143	4.9%	0.32	3.2 MB	4.8%	0.92
Dec 14 11:00:00 - 11:59:59	1150	5.0%	0.32	3.6 MB	5.3%	1.02
Dec 14 12:00:00 - 12:59:59	1129	4.9%	0.31	3.2 MB	4.7%	0.91
Dec 14 13:00:00 - 13:59:59	1090	4.7%	0.30	2.8 MB	4.1%	0.79
Dec 14 14:00:00 - 14:59:59	1181	5.1%	0.33	3.0 MB	4.4%	0.86
Dec 14 15:00:00 - 15:59:59	1260	5.4%	0.35	4.0 MB	5.9%	1.14
Dec 14 16:00:00 - 16:59:59	1346	5.8%	0.37	4.1 MB	6.0%	1.15
Dec 14 17:00:00 - 17:59:59	1289	5.6%	0.36	3.6 MB	5.3%	1.02
Dec 14 18:00:00 - 18:59:59	1302	5.6%	0.36	4.0 MB	5.8%	1.12
Dec 14 19:00:00 - 19:59:59	1412	6.1%	0.39	4.1 MB	6.0%	1.16
Dec 14 20:00:00 - 20:59:59	1138	4.9%	0.32	3.5 MB	5.1%	0.99
Dec 14 21:00:00 - 21:59:59	1158	5.0%	0.32	3.2 MB	4.6%	0.90
Dec 14 22:00:00 - 22:59:59	1238	5.4%	0.34	3.6 MB	5.3%	1.03
Dec 14 23:00:00 - 23:59:59	905	3.9%	0.25	2.9 MB	4.2%	0.82
Dec 15 00:00:00 - 00:59:59	773	3.3%	0.21	2.6 MB	3.7%	0.73
Dec 15 01:00:00 - 01:59:59	541	2.3%	0.15	1.9 MB	2.8%	0.54
Dec 15 02:00:00 - 02:59:59	438	1.9%	0.12	1.3 MB	1.9%	0.37
Dec 15 03:00:00 - 03:59:59	425	1.8%	0.12	1.4 MB	2.1%	0.41
Dec 15 04:00:00 - 04:15:00	110	0.5%	0.12	355.4 KB	0.5%	0.39
TOTAL: 24:00:00	23133	100.0%	0.27	68.2 MB	100.0%	0.81

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	8475	400	186	7889
2	feeder.eternal-september.org	6802	62	474	6266
3	newsfeed.neva.ru	1955	341	48	1566
4	csiph-out.news.weretis.net	746	191	1	554
5	newsfeed.fu-berlin.de	585	33	0	552
6	news-out.mixmin.net	245	1	0	244
7	news.freedyn.net	109	0	0	109
8	news-out.aioe.org	54	20	0	34
9	nntp.pasdenom.info	34	19	0	15
10	newsfeeds.xmission.com	33	4	0	29
11	news.swapon.de	26	0	0	26
12	news.redatomik.org	21	0	0	21
13	news.fcku.it	15	0	0	15
14	xanadu-bbs.net	10	0	0	10
15	news.bbs.geek.nz	1	0	0	1
TOTAL: 15		19111	1071	709	17331

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	169
ut.test	82
lada.auto	61
it.annunci.commerciali	47
it.annunci.immobiliari	46
it.annunci.varie	46
alt.windows7.general	35
it-alt.sport.calcio.juventus	25
mozilla.support.thunderbird	24
mozilla.support.firefox	22
lada.kitchen	21
spline.eisfair	18
alt.courts.pretrial-services	16
gmane.comp.freedesktop.xorg.drivers.intel	15
lada.commerce	15
eagle.support.eng	14
cakewalk.audio	14
gmane.linux.alsa.devel	14
alt.politics.scorched-earth	13
mozilla.support.seamonkey	13
TOTAL: 147	1071

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14616
EMP (phn path)	1238
EMP (phl)	354
Binary Payload	55
User-issued cancel	45
EMP (phn nph)	44
Too many newsgroups	41
HTML Multipart	28
Scoring filter (10)	14
Scoring filter (15)	10
Scoring filter (9)	8
Scoring filter (16)	5
Scoring filter (21)	5
Scoring filter (17)	5
User-issued spam cancel	5
Scoring filter (8)	3
Binary: misplaced nfo	2
Scoring filter (11)	2
HTML post	2
Scoring filter (13)	2
TOTAL: 28	16493

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	53	0	64	64
TOTAL: 1	53	0	64	64

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	32
	TOTAL: 1	32
Huge articles
	feeder.eternal-september.org	26
	news-out.mixmin.net	18
	csiph-out.news.weretis.net	15
	newsfeeds.xmission.com	10
	news.redatomik.org	6
	news.freedyn.net	4
	news.fcku.it	2
	news.bbs.geek.nz	1
	newsfeed.fu-berlin.de	1
	TOTAL: 9	83
Including strange strings
	newsfeed.fu-berlin.de	469
	csiph-out.feeder.erje.net	79
	csiph-out.news.weretis.net	32
	news-out.aioe.org	30
	news.swapon.de	24
	newsfeeds.xmission.com	17
	feeder.eternal-september.org	13
	news-out.mixmin.net	6
	news.redatomik.org	3
	news.fcku.it	2
	TOTAL: 11	677
No colon-space in header
	csiph-out.feeder.erje.net	42
	TOTAL: 1	42
TOTAL: 4		834

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	36388	1923	20689	47	6143	5%	23:59:56
2	news.freedyn.net	23838	1021	22025	3	0	4%	23:59:56
3	xanadu-bbs	25014	500	22428	1	0	1%	23:59:56
4	fu-berlin.de	21627	449	21178	0	0	2%	23:59:45
5	usenet.pasdenom.info	24905	275	21560	1234	0	1%	23:59:56
6	news.redatomik.org	22626	50	21675	13	0	0%	23:59:47
7	aioe	19369	27	18290	7	0	0%	23:59:56
8	xmission	25449	24	21712	4	0	0%	23:59:54
9	weretis.net	15890	21	15400	2	0	0%	23:59:53
10	news.mixmin.net	21422	4	20218	0	0	0%	23:59:56
11	neva.ru	21872	3	20871	44	0	0%	23:59:56
12	eternal-september	20564	3	19060	0	0	0%	23:59:56
13	news.fcku.it	2842	2	2747	0	0	0%	22:16:27
14	news.swapon.de	2146	1	2070	2	0	0%	23:40:04
15	au2pb	0	0	0	0	22658	0%	23:58:43
16	bwh	0	0	0	0	22653	0%	23:58:42
17	erje	17496	0	17335	53	0	0%	23:59:56
TOTAL: 17		301448	4303	267258	1410	51454	1%	405:52:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	5.3 MB	146.8 KB	5.4 MB	0.1 KB/s	2.8 KB	23:59:56
2	news.freedyn.net	4.8 MB	16.7 KB	4.8 MB	0.1 KB/s	4.8 KB	23:59:56
3	xanadu-bbs	3.3 MB	2.2 KB	3.3 MB	0.0 KB/s	6.7 KB	23:59:56
4	fu-berlin.de	3.1 MB	0.0 KB	3.1 MB	0.0 KB/s	7.1 KB	23:59:45
5	usenet.pasdenom.info	898.9 KB	4.6 MB	5.5 MB	0.1 KB/s	3.7 KB	23:59:56
6	news.redatomik.org	132.3 KB	40.9 KB	173.2 KB	0.0 KB/s	2.7 KB	23:59:47
7	aioe	87.2 KB	26.4 KB	113.6 KB	0.0 KB/s	3.3 KB	23:59:56
8	xmission	76.3 KB	13.3 KB	89.7 KB	0.0 KB/s	3.2 KB	23:59:54
9	weretis.net	66.2 KB	12.9 KB	79.1 KB	0.0 KB/s	3.4 KB	23:59:53
10	eternal-september	39.9 KB	0.0 KB	39.9 KB	0.0 KB/s	13.3 KB	23:59:56
11	news.mixmin.net	22.2 KB	0.0 KB	22.2 KB	0.0 KB/s	5.5 KB	23:59:56
12	news.fcku.it	6.5 KB	0.0 KB	6.5 KB	0.0 KB/s	3.2 KB	22:16:27
13	neva.ru	5.1 KB	137.7 KB	142.9 KB	0.0 KB/s	3.0 KB	23:59:56
14	news.swapon.de	0.9 KB	5.3 KB	6.2 KB	0.0 KB/s	2.1 KB	23:40:04
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:43
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	erje	0.0 KB	292.5 KB	292.5 KB	0.0 KB/s	5.5 KB	23:59:56
TOTAL: 17		17.8 MB	5.3 MB	23.1 MB	0.0 KB/s	4.1 KB	405:52:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	194-73-239-109.cust.centrio.cz	7	50	148.3 KB	7	0	0	01:08:17
2	challenger.nielsenmedia.com	13	2	4.9 KB	2	0	0	03:15:35
3	68-185-181-64.dhcp.mdsn.wi.charter.com	5	1	0.9 KB	1	1	1	01:51:05
4	dhcp-089-099-203-254.chello.nl	25	1	0.9 KB	1	0	0	05:00:22
TOTAL: 4		50	54	155.0 KB	11	1	1	18:30:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cust.centrio.cz	7	50	148.3 KB	7	0	0	01:08:17
2	*.nielsenmedia.com	13	2	4.9 KB	2	0	0	03:15:35
3	*.dhcp.mdsn.wi.charter.com	5	1	0.9 KB	1	1	1	01:51:05
4	*.chello.nl	25	1	0.9 KB	1	0	0	05:00:22
5	*.bbox.io	13	0	0.0 KB	0	0	0	03:39:47
6	unresolved	1	0	0.0 KB	0	0	0	00:00:26
7	*.hsd1.wa.comcast.net	3	0	0.0 KB	0	0	0	02:00:03
8	*.drr02.clwr.fl.frontiernet.net	4	0	0.0 KB	0	0	0	01:34:40
TOTAL: 8		71	54	155.0 KB	11	1	1	18:30:18

NNRP auth users [Top 20]:

User	Conn
ctomczyk	6
ohlandl	1
TOTAL: 2	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	1.747	0.400	0.000	05:00:22
tempe0.bbox.io	1.031	0.324	0.000	03:39:47
challenger.nielsenmedia.com	0.917	0.316	0.000	03:15:35
c-174-61-130-222.hsd1.wa.comcast.net	0.280	0.077	0.000	02:00:03
68-185-181-64.dhcp.mdsn.wi.charter.com	0.382	0.097	0.003	01:51:05
47-192-187-101.drr02.clwr.fl.frontiernet.net	0.269	0.056	0.000	01:34:40
194-73-239-109.cust.centrio.cz	0.749	0.157	0.012	01:08:17
196.52.16.31	0.238	0.412	0.000	00:00:26
TOTAL: 8	5.613	1.839	0.015	18:30:18

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	13
47-192-187-101.drr02.clwr.fl.frontiernet.net	4
c-174-61-130-222.hsd1.wa.comcast.net	3
196.52.16.31	1
TOTAL: 4	21

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	7	0
challenger.nielsenmedia.com	6	1
c-174-61-130-222.hsd1.wa.comcast.net	4	0
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
194-73-239-109.cust.centrio.cz	1	0
47-192-187-101.drr02.clwr.fl.frontiernet.net	1	0
TOTAL: 6	22	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.lang.javascript	50
2	comp.lang.awk	2
3	comp.sys.ibm.ps2.hardware	2
TOTAL: 3		54

Daily Usenet report for csiph.com

Dec 14 04:15:00 -- Dec 15 04:15:00