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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	55504	47.7%	10.5 MB	64.0%
nnrpd	32088	27.6%	2.7 MB	16.5%
innd	25393	21.8%	2.7 MB	16.3%
innfeed	3179	2.7%	537.2 KB	3.2%
nocem	76	0.1%	7.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	116241	100.0%	16.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	692054	58.0%
Negative hits	444965	37.3%
Do not exist	54529	4.6%
Cache misses	1002	0.1%
TOTAL: 4	1192550	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.579	0.0%	240	0.000	2.413	53.000
article cleanup	00:00:00.316	0.0%	50323	0.000	0.006	0.034
article logging	00:00:00.694	0.0%	50844	0.000	0.014	0.090
article parse	00:01:00.045	0.1%	291861	0.004	0.206	0.653
article write	00:01:23.430	0.1%	29299	0.292	2.848	9.510
artlog/artcncl	00:00:00.001	0.0%	205	0.000	0.005	0.077
artlog/artparse	00:00:00.064	0.0%	4486	0.000	0.014	0.306
data move	00:00:00.389	0.0%	1208392	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.209	0.0%	238	0.000	0.878	27.000
hishave/artcncl	00:00:00.001	0.0%	240	0.000	0.004	0.056
hishave/artparse	00:00:00.002	0.0%	1929	0.000	0.001	0.013
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:10.929	0.0%	1195754	0.002	0.009	0.069
history sync	00:00:01.928	0.0%	329	0.000	5.860	55.500
history write	00:01:20.120	0.1%	52821	0.103	1.517	6.976
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:04.662	0.0%	1927	0.000	2.419	15.286
idle	23:51:44.682	99.4%	1230000	39.144	69.841	175.637
nntp read	00:00:04.020	0.0%	1241387	0.001	0.003	0.005
overview write	00:01:34.347	0.1%	29299	0.756	3.220	169.413
perl filter	00:02:08.772	0.1%	50193	1.556	2.566	3.922
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.509	0.0%	58668	0.000	0.009	0.027
TOTAL: 24:00:42.772	23:59:35.699	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.097	0.0%	29395	0.000	0.003	0.026
article prepare	00:00:00.008	0.0%	31106	0.000	0.000	0.018
article read	00:00:00.651	0.0%	4169	0.000	0.156	4.091
backlog stats	00:00:00.352	0.0%	679590	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.456	0.0%	501256	0.002	0.005	0.010
data write	00:00:03.250	0.0%	482578	0.001	0.007	0.013
idle	23:52:45.261	100.0%	679590	72.730	126.496	296.279
status file	00:00:00.186	0.0%	274	0.000	0.679	1.000
TOTAL: 23:53:04.920	23:52:52.261	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.030	0.0%	817	0.000	0.037	0.049
idle	27:55:27.675	99.9%	32394	45.000	3103.281	600197.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.391	0.0%	65860	0.000	0.006	0.500
readart	00:00:00.234	0.0%	122	0.000	1.918	49.000
TOTAL: 27:57:04.249	27:55:28.330	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	38993	6656	25591	6746	17%	23:45:20
2	csiph-out.feeder.erje.net	1152	191412	5355	180077	5980	2%	95:07:48
3	feeder.eternal-september.org	5	44021	4092	39306	623	9%	24:37:40
4	news-out.aioe.org	6	30395	2865	27487	43	9%	24:19:19
5	csiph-out.news.weretis.net	4	58934	2856	55691	387	4%	43:33:48
6	newsfeed.neva.ru	150	67834	2012	60819	5003	2%	72:23:57
7	news-out.mixmin.net	3	33809	1531	31480	798	4%	24:11:52
8	newsfeed.fu-berlin.de	73	31441	1344	29877	220	4%	33:04:43
9	xanadu-bbs.net	1	49274	549	43142	5583	1%	24:00:44
10	news.fcku.it	1	8712	451	8261	0	5%	23:05:10
11	newsfeeds.xmission.com	1	31042	442	30567	33	1%	25:27:57
12	news.freedyn.net	11	34663	319	34311	33	0%	24:08:29
13	news.redatomik.org	1	21809	310	21490	9	1%	19:48:07
14	news.swapon.de	1	2492	131	2348	13	5%	21:47:55
15	au2pb.net	2	32284	125	32130	29	0%	24:34:15
16	nntp.pasdenom.info	5	30115	23	30080	12	0%	24:06:31
17	news.bbs.geek.nz	2	29882	3	29879	0	0%	23:59:32
18	localhost	33	0	0	0	0	0%	00:00:00
TOTAL: 18		1739	737112	29064	682536	25512	3%	552:03:07

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.4 MB	324.0 KB	25.6 MB	46.3 MB	44%	4.2 KB
2	usenet.blueworldhosting.com	18.7 MB	0.0 KB	251.5 MB	270.2 MB	6%	20.6 KB
3	feeder.eternal-september.org	10.2 MB	61.7 KB	8.4 MB	18.6 MB	54%	4.0 KB
4	news-out.aioe.org	7.9 MB	0.0 KB	153.9 KB	8.0 MB	98%	2.8 KB
5	csiph-out.news.weretis.net	7.7 MB	2.9 KB	7.0 MB	14.7 MB	52%	4.7 KB
6	news-out.mixmin.net	5.8 MB	8.7 KB	1.5 MB	7.3 MB	79%	3.2 KB
7	newsfeed.neva.ru	5.3 MB	136.9 KB	36.8 MB	42.3 MB	12%	6.2 KB
8	newsfeed.fu-berlin.de	2.8 MB	0.0 KB	526.9 KB	3.3 MB	84%	2.2 KB
9	news.freedyn.net	1.5 MB	4.5 KB	522.0 KB	2.0 MB	73%	5.7 KB
10	xanadu-bbs.net	1.4 MB	13.5 KB	58.2 MB	59.5 MB	2%	9.9 KB
11	newsfeeds.xmission.com	1.2 MB	0.0 KB	146.9 KB	1.4 MB	89%	3.0 KB
12	news.redatomik.org	878.8 KB	0.0 KB	379.1 KB	1.2 MB	69%	3.9 KB
13	news.fcku.it	789.8 KB	0.0 KB	0.0 KB	789.8 KB	100%	1.8 KB
14	au2pb.net	423.5 KB	40.5 KB	219.2 KB	683.2 KB	61%	4.4 KB
15	news.swapon.de	293.9 KB	0.0 KB	377.5 KB	671.5 KB	43%	4.7 KB
16	nntp.pasdenom.info	44.2 KB	0.0 KB	69.1 KB	113.3 KB	39%	3.2 KB
17	news.bbs.geek.nz	4.4 KB	0.0 KB	0.0 KB	4.4 KB	100%	1.5 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		85.2 MB	592.8 KB	391.3 MB	477.1 MB	17%	9.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 12 04:15:00 - 04:59:59	411	1.4%	0.15	1.3 MB	1.5%	0.50
Jan 12 05:00:00 - 05:59:59	613	2.1%	0.17	2.4 MB	2.8%	0.68
Jan 12 06:00:00 - 06:59:59	628	2.1%	0.17	2.0 MB	2.3%	0.57
Jan 12 07:00:00 - 07:59:59	837	2.9%	0.23	2.6 MB	3.1%	0.75
Jan 12 08:00:00 - 08:59:59	1340	4.6%	0.37	4.3 MB	5.0%	1.22
Jan 12 09:00:00 - 09:59:59	1333	4.6%	0.37	3.6 MB	4.2%	1.02
Jan 12 10:00:00 - 10:59:59	1384	4.7%	0.38	3.8 MB	4.4%	1.09
Jan 12 11:00:00 - 11:59:59	1267	4.3%	0.35	3.6 MB	4.1%	1.01
Jan 12 12:00:00 - 12:59:59	1346	4.6%	0.37	4.2 MB	4.9%	1.20
Jan 12 13:00:00 - 13:59:59	1372	4.7%	0.38	3.7 MB	4.3%	1.04
Jan 12 14:00:00 - 14:59:59	1437	4.9%	0.40	3.7 MB	4.3%	1.06
Jan 12 15:00:00 - 15:59:59	1659	5.7%	0.46	4.5 MB	5.2%	1.27
Jan 12 16:00:00 - 16:59:59	1770	6.0%	0.49	5.4 MB	6.3%	1.54
Jan 12 17:00:00 - 17:59:59	1967	6.7%	0.55	6.1 MB	7.2%	1.75
Jan 12 18:00:00 - 18:59:59	1715	5.9%	0.48	5.2 MB	6.0%	1.47
Jan 12 19:00:00 - 19:59:59	1564	5.3%	0.43	4.7 MB	5.5%	1.35
Jan 12 20:00:00 - 20:59:59	1825	6.2%	0.51	4.8 MB	5.6%	1.37
Jan 12 21:00:00 - 21:59:59	1476	5.0%	0.41	4.0 MB	4.6%	1.13
Jan 12 22:00:00 - 22:59:59	1402	4.8%	0.39	4.1 MB	4.7%	1.15
Jan 12 23:00:00 - 23:59:59	992	3.4%	0.28	2.8 MB	3.3%	0.80
Jan 13 00:00:00 - 00:59:59	877	3.0%	0.24	2.6 MB	3.0%	0.73
Jan 13 01:00:00 - 01:59:59	743	2.5%	0.21	2.3 MB	2.7%	0.65
Jan 13 02:00:00 - 02:59:59	563	1.9%	0.16	1.8 MB	2.1%	0.50
Jan 13 03:00:00 - 03:59:59	647	2.2%	0.18	2.0 MB	2.3%	0.56
Jan 13 04:00:00 - 04:15:00	118	0.4%	0.13	442.4 KB	0.5%	0.49
TOTAL: 24:00:00	29286	100.0%	0.34	85.9 MB	100.0%	1.02

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	usenet.blueworldhosting.com	6729	1562	0	0	5167
2	csiph-out.feeder.erje.net	6007	1246	37	1	4723
3	xanadu-bbs.net	5567	79	1	0	5487
4	newsfeed.neva.ru	4969	1550	12	0	3407
5	news-out.mixmin.net	800	1	3	0	796
6	newsfeed.fu-berlin.de	657	47	0	0	610
7	feeder.eternal-september.org	629	99	4	0	526
8	csiph-out.news.weretis.net	429	284	1	0	144
9	news-out.aioe.org	64	33	0	0	31
10	newsfeeds.xmission.com	36	34	0	0	2
11	au2pb.net	36	34	0	0	2
12	news.freedyn.net	32	3	1	0	28
13	news.swapon.de	32	0	0	0	32
14	news.redatomik.org	12	0	0	0	12
15	nntp.pasdenom.info	12	9	0	0	3
16	news.fcku.it	2	0	0	0	2
TOTAL: 16		26013	4981	59	1	20972

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1027
microsoft.public.windows.chromeffects	773
mailing.freebsd.svn-ports-all	199
mailing.freebsd.svn-ports-head	196
lada.talk	190
ut.test	153
mailing.freebsd.pkg-fallout	143
mailing.freebsd.ports-bugs	115
mailing.freebsd.svn-src-all	106
fa.freebsd.cvs-all	104
lada.auto	100
mailing.database.pgsql-general	81
mailing.freebsd.svn-src-head	73
mailing.gcc.gcc-cvs	56
spline.eisfair	45
mailing.openbsd.source-changes	41
alt.politics.scorched-earth	41
mozilla.general	38
mailing.openbsd.ports-changes	38
alt.binaries.multimedia.tatu	36
TOTAL: 269	4981

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

Groups	Count
uk.radio.amateur.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11778
EMP (phn path)	2507
EMP (phl)	959
EMP (phn nph)	180
User-issued cancel	114
PFM bot misc	93
Too many newsgroups	36
HTML Multipart	34
Scoring filter (18)	24
Scoring filter (20)	18
Scoring filter (19)	14
Binary Payload	14
Scoring filter (10)	13
Scoring filter (8)	12
Scoring filter (21)	11
Scoring filter (12)	11
Scoring filter (14)	11
HTML post	10
Scoring filter (16)	9
Scoring filter (11)	7
TOTAL: 35	15892

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	70	0	81	81
TOTAL: 1	70	0	81	81

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	67
	usenet.blueworldhosting.com	4
	TOTAL: 2	71
Huge articles
	usenet.blueworldhosting.com	1659
	xanadu-bbs.net	149
	csiph-out.news.weretis.net	63
	feeder.eternal-september.org	54
	news-out.mixmin.net	5
	news.freedyn.net	2
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	1
	news.swapon.de	1
	nntp.pasdenom.info	1
	TOTAL: 11	1938
Including strange strings
	newsfeed.fu-berlin.de	441
	news.swapon.de	20
	news-out.aioe.org	18
	csiph-out.feeder.erje.net	17
	csiph-out.news.weretis.net	10
	news-out.mixmin.net	5
	feeder.eternal-september.org	4
	usenet.blueworldhosting.com	2
	newsfeed.neva.ru	1
	news.fcku.it	1
	TOTAL: 11	520
No colon-space in header
	csiph-out.feeder.erje.net	547
	TOTAL: 1	547
TOTAL: 4		3076

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	53701	2002	26357	33	1	25	3%	23:59:53
2	news.freedyn.net	32411	1122	27825	6	0	1	3%	23:59:53
3	xanadu-bbs	31439	944	27781	7	0	12	3%	23:59:53
4	fu-berlin.de	27431	831	26600	0	0	0	3%	23:59:49
5	aioe	27601	563	23394	17	0	1	2%	23:59:50
6	usenet.pasdenom.info	37318	299	27617	1347	0	106	0%	23:59:53
7	news.redatomik.org	34604	161	27811	16	0	1	0%	23:59:52
8	au2pb	29266	130	28142	16	0	0	0%	23:59:53
9	neva.ru	31264	26	25893	139	0	2	0%	23:59:53
10	xmission	32029	17	27869	1	0	1	0%	23:59:49
11	weretis.net	24944	12	24373	11	0	0	0%	23:59:41
12	news.fcku.it	4099	10	3475	0	0	0	0%	23:11:01
13	news.mixmin.net	30639	9	26350	0	0	1	0%	23:59:53
14	eternal-september	29826	8	23774	0	0	2	0%	23:59:53
15	erje	27645	4	23596	48	0	1	0%	23:59:53
16	news.swapon.de	2636	2	2332	1	0	0	0%	23:57:57
17	bwh	23036	1	21627	2	0	0	0%	23:59:53
TOTAL: 17		479889	6141	394816	1644	1	153	1%	407:06:49

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.6 MB	20.7 KB	8.6 MB	0.1 KB/s	7.8 KB	23:59:53
2	xanadu-bbs	7.8 MB	31.8 KB	7.8 MB	0.1 KB/s	8.4 KB	23:59:53
3	fu-berlin.de	7.6 MB	0.0 KB	7.6 MB	0.1 KB/s	9.4 KB	23:59:49
4	news.bbs.geek.nz	5.3 MB	161.5 KB	5.5 MB	0.1 KB/s	2.8 KB	23:59:53
5	aioe	1.6 MB	77.3 KB	1.7 MB	0.0 KB/s	3.0 KB	23:59:50
6	usenet.pasdenom.info	962.3 KB	8.6 MB	9.5 MB	0.1 KB/s	5.9 KB	23:59:53
7	news.redatomik.org	480.8 KB	58.0 KB	538.8 KB	0.0 KB/s	3.0 KB	23:59:52
8	au2pb	440.1 KB	83.6 KB	523.6 KB	0.0 KB/s	3.6 KB	23:59:53
9	neva.ru	107.3 KB	468.0 KB	575.3 KB	0.0 KB/s	3.5 KB	23:59:53
10	xmission	39.2 KB	3.5 KB	42.7 KB	0.0 KB/s	2.4 KB	23:59:49
11	news.mixmin.net	32.2 KB	0.0 KB	32.2 KB	0.0 KB/s	3.6 KB	23:59:53
12	weretis.net	30.0 KB	280.0 KB	310.0 KB	0.0 KB/s	13.5 KB	23:59:41
13	news.fcku.it	26.6 KB	0.0 KB	26.6 KB	0.0 KB/s	2.7 KB	23:11:01
14	eternal-september	21.9 KB	0.0 KB	21.9 KB	0.0 KB/s	2.7 KB	23:59:53
15	erje	7.3 KB	217.5 KB	224.8 KB	0.0 KB/s	4.3 KB	23:59:53
16	news.swapon.de	3.3 KB	1.3 KB	4.6 KB	0.0 KB/s	1.5 KB	23:57:57
17	bwh	1.1 KB	3.0 KB	4.1 KB	0.0 KB/s	1.4 KB	23:59:53
TOTAL: 17		33.0 MB	9.9 MB	43.0 MB	0.0 KB/s	5.6 KB	407:06:49

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	202.89.176.220	288	109	337.5 KB	97	0	05:01:24
2	92-108-162-83.dynamic.upc.nl	2	6	9.3 KB	1	0	00:05:31
3	tempe0.bbox.io	53	5	8.9 KB	4	0	06:32:23
4	dhcp-089-099-203-254.chello.nl	25	2	3.9 KB	2	0	11:23:18
5	60.6.238.3	1	0	0.0 KB	0	1	00:00:02
TOTAL: 5		369	122	359.4 KB	104	1	27:57:00

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	unresolved	293	109	337.5 KB	97	1	07:56:50
2	*.dynamic.upc.nl	2	6	9.3 KB	1	0	00:05:31
3	*.bbox.io	53	5	8.9 KB	4	0	06:32:23
4	*.chello.nl	25	2	3.9 KB	2	0	11:23:18
5	*.nielsenmedia.com	1	0	0.0 KB	0	0	01:30:02
6	*.ip-188-165-239.eu	1	0	0.0 KB	0	0	00:00:09
7	*.tampfl.dsl-w.verizon.net	1	0	0.0 KB	0	0	00:28:43
8	*.208-100-26.static.steadfastdns.net	2	0	0.0 KB	0	0	00:00:01
TOTAL: 8		378	122	359.4 KB	104	1	27:57:00

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	2.088	0.467	0.000	11:23:18
tempe0.bbox.io	4.523	0.949	0.000	06:32:23
202.89.176.220	29.909	6.759	0.768	05:01:24
162.212.88.221	0.390	0.083	0.000	02:55:24
challenger.nielsenmedia.com	0.229	0.083	0.000	01:30:02
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.057	0.024	0.000	00:28:43
92-108-162-83.dynamic.upc.nl	0.177	0.031	0.000	00:05:31
ns342002.ip-188-165-239.eu	0.092	0.023	0.000	00:00:09
60.6.238.3	0.075	0.023	0.000	00:00:02
ip232.208-100-26.static.steadfastdns.net	0.189	0.032	0.000	00:00:01
TOTAL: 10	37.729	8.474	0.768	27:57:00

Curious NNRP server explorers [Top 20]:

System	Conn
162.212.88.221	4
ip232.208-100-26.static.steadfastdns.net	2
challenger.nielsenmedia.com	1
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1
ns342002.ip-188-165-239.eu	1
TOTAL: 5	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	21	0
tempe0.bbox.io	9	0
challenger.nielsenmedia.com	3	0
TOTAL: 3	33	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	120	98.4%
2	news	2	1.6%
TOTAL: 2		122	100.0%

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

Newsgroup		Count
1	comp.os.vms	47
2	comp.arch	40
3	comp.sys.ibm.ps2.hardware	12
4	comp.sys.ibm.pc.games.action	9
5	comp.arch.embedded	8
6	comp.sys.ibm.pc.hardware.chips	4
7	news.software.nntp	2
TOTAL: 7		122

Daily Usenet report for csiph.com

Jan 12 04:15:00 -- Jan 13 04:15:00