Daily Usenet report for csiph.com: May 2 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	69962	44.2%	7.0 MB	34.8%
inn	43408	27.5%	8.0 MB	39.7%
innd	26463	16.7%	2.9 MB	14.5%
innfeed	18208	11.5%	2.2 MB	11.0%
nocem	79	0.0%	8.2 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	158121	100.0%	20.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	401073	52.8%
Negative hits	303542	40.0%
Do not exist	42119	5.5%
Cache misses	12674	1.7%
TOTAL: 4	759408	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:06.312	0.0%	571	0.000	11.054	38.500
article cleanup	00:00:00.313	0.0%	40827	0.000	0.008	0.032
article logging	00:00:06.847	0.0%	41356	0.000	0.166	1.744
article parse	00:00:14.996	0.0%	164421	0.003	0.091	0.605
article write	00:03:14.712	0.2%	22100	0.239	8.810	43.575
artlog/artcncl	00:00:00.068	0.0%	521	0.000	0.131	3.875
artlog/artparse	00:00:00.424	0.0%	1565	0.000	0.271	11.000
data move	00:00:00.466	0.0%	720701	0.000	0.001	0.011
hisgrep/artcncl	00:00:00.529	0.0%	568	0.000	0.931	16.500
hishave/artcncl	00:00:00.289	0.0%	571	0.000	0.506	12.500
hishave/artparse	00:00:00.008	0.0%	1563	0.000	0.005	0.077
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:31.955	0.2%	759431	0.002	0.200	0.825
history sync	00:00:05.661	0.0%	344	0.000	16.456	102.000
history write	00:04:42.521	0.3%	40669	0.392	6.947	36.687
hiswrite/artcncl	00:00:00.001	0.0%	3	0.000	0.333	0.500
hiswrite/artparse	00:00:12.418	0.0%	1562	0.000	7.950	40.000
idle	23:39:49.411	98.5%	704451	66.676	120.930	315.514
nntp read	00:00:03.505	0.0%	728697	0.002	0.005	0.014
overview write	00:06:17.269	0.4%	22100	1.697	17.071	171.550
perl filter	00:01:44.598	0.1%	40553	1.568	2.579	4.857
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.415	0.0%	44274	0.000	0.009	0.032
TOTAL: 24:00:53.614	23:59:12.718	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.095	0.0%	43524	0.000	0.002	0.032
article prepare	00:00:00.004	0.0%	38424	0.000	0.000	0.042
article read	00:00:29.036	0.0%	8220	0.000	3.532	42.500
backlog stats	00:00:00.268	0.0%	389334	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:02.058	0.0%	301410	0.000	0.007	0.013
data write	00:00:01.700	0.0%	284154	0.001	0.006	0.011
idle	23:53:06.115	99.9%	389334	46.672	220.854	662.476
status file	00:00:04.706	0.0%	6600	0.000	0.713	40.000
TOTAL: 23:53:59.761	23:53:43.982	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:04.965	0.0%	2495	1.674	1.990	2.300
idle	32:13:55.687	99.6%	133553	79.008	868.836	600081.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.648	0.0%	268079	0.000	0.006	0.167
readart	00:02:34.479	0.1%	9755	0.000	15.836	309.000
TOTAL: 32:21:21.148	32:16:36.779	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	47266	5932	40041	1293	12%	48:57:07
2	csiph-out.feeder.erje.net	1442	148958	5230	135591	8137	3%	118:58:24
3	feeder.eternal-september.org	12	38250	3117	28969	6164	8%	26:59:57
4	news-out.aioe.org	3	22835	1786	21017	32	7%	24:09:40
5	news-out.mixmin.net	3	24164	1637	21906	621	6%	24:08:25
6	newsfeed.neva.ru	145	34797	1190	30276	3331	3%	71:52:26
7	newsfeed.fu-berlin.de	28	23283	1123	21735	425	4%	30:23:23
8	newsfeeds.xmission.com	3	21292	712	20572	8	3%	23:56:45
9	news.redatomik.org	1	19090	639	18446	5	3%	21:54:44
10	news.freedyn.net	3	25418	421	24666	331	1%	24:08:57
11	news.fcku.it	1	9637	178	9436	23	1%	23:59:58
12	xanadu-bbs.net	10	1041	171	867	3	16%	25:26:31
13	news.swapon.de	1	965	28	937	0	2%	13:48:38
14	news.bbs.geek.nz	1	21090	11	21079	0	0%	23:59:49
15	localhost	42	8	8	0	0	100%	00:00:14
16	nntp.pasdenom.info	3	9292	0	9291	1	0%	06:01:23
TOTAL: 16		1703	447386	22183	404829	20374	4%	508:46:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.3 MB	1.4 MB	111.3 MB	133.1 MB	15%	10.2 KB
2	csiph-out.news.weretis.net	16.6 MB	0.0 KB	11.6 MB	28.2 MB	58%	4.0 KB
3	feeder.eternal-september.org	8.5 MB	83.7 KB	81.7 MB	90.3 MB	9%	10.0 KB
4	news-out.mixmin.net	6.1 MB	78.9 KB	1.6 MB	7.8 MB	78%	3.5 KB
5	news-out.aioe.org	4.3 MB	1.3 KB	47.6 KB	4.4 MB	98%	2.5 KB
6	newsfeed.neva.ru	2.8 MB	82.3 KB	31.7 MB	34.7 MB	8%	7.8 KB
7	newsfeed.fu-berlin.de	2.7 MB	2.7 KB	1.2 MB	3.9 MB	69%	2.5 KB
8	newsfeeds.xmission.com	2.0 MB	5.2 KB	427.3 KB	2.4 MB	82%	3.4 KB
9	news.redatomik.org	1.8 MB	0.0 KB	158.9 KB	2.0 MB	92%	3.1 KB
10	news.freedyn.net	1.4 MB	0.0 KB	3.5 MB	4.9 MB	29%	6.6 KB
11	news.fcku.it	459.9 KB	0.0 KB	889.4 KB	1.3 MB	34%	6.7 KB
12	xanadu-bbs.net	455.6 KB	0.0 KB	12.4 KB	468.0 KB	97%	2.7 KB
13	news.swapon.de	72.0 KB	0.0 KB	0.0 KB	72.0 KB	100%	2.6 KB
14	news.bbs.geek.nz	48.9 KB	0.0 KB	0.0 KB	48.9 KB	100%	4.4 KB
15	localhost	35.3 KB	0.0 KB	0.0 KB	35.3 KB	100%	4.4 KB
16	nntp.pasdenom.info	0.0 KB	0.0 KB	2.4 KB	2.4 KB	0%	2.4 KB
TOTAL: 16		67.6 MB	1.7 MB	244.2 MB	313.4 MB	21%	7.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 2 04:15:00 - 04:59:59	622	2.8%	0.23	2.8 MB	4.2%	1.07
May 2 05:00:00 - 05:59:59	560	2.5%	0.16	2.1 MB	3.2%	0.61
May 2 06:00:00 - 06:59:59	614	2.8%	0.17	2.0 MB	2.9%	0.56
May 2 07:00:00 - 07:59:59	807	3.7%	0.22	2.2 MB	3.2%	0.62
May 2 08:00:00 - 08:59:59	898	4.1%	0.25	2.2 MB	3.2%	0.62
May 2 09:00:00 - 09:59:59	953	4.3%	0.26	2.8 MB	4.1%	0.78
May 2 10:00:00 - 10:59:59	922	4.2%	0.26	2.8 MB	4.2%	0.80
May 2 11:00:00 - 11:59:59	991	4.5%	0.28	2.7 MB	4.0%	0.77
May 2 12:00:00 - 12:59:59	1113	5.0%	0.31	3.4 MB	5.1%	0.98
May 2 13:00:00 - 13:59:59	1194	5.4%	0.33	3.1 MB	4.7%	0.90
May 2 14:00:00 - 14:59:59	1186	5.4%	0.33	3.8 MB	5.6%	1.07
May 2 15:00:00 - 15:59:59	1279	5.8%	0.36	3.7 MB	5.5%	1.05
May 2 16:00:00 - 16:59:59	1289	5.8%	0.36	4.3 MB	6.4%	1.22
May 2 17:00:00 - 17:59:59	1387	6.3%	0.39	4.4 MB	6.5%	1.25
May 2 18:00:00 - 18:59:59	1310	5.9%	0.36	4.0 MB	5.9%	1.13
May 2 19:00:00 - 19:59:59	1163	5.3%	0.32	3.1 MB	4.6%	0.89
May 2 20:00:00 - 20:59:59	1189	5.4%	0.33	3.4 MB	5.1%	0.97
May 2 21:00:00 - 21:59:59	1000	4.5%	0.28	2.8 MB	4.2%	0.81
May 2 22:00:00 - 22:59:59	824	3.7%	0.23	2.4 MB	3.5%	0.68
May 2 23:00:00 - 23:59:59	734	3.3%	0.20	2.4 MB	3.6%	0.69
May 3 00:00:00 - 00:59:59	575	2.6%	0.16	1.9 MB	2.9%	0.55
May 3 01:00:00 - 01:59:59	479	2.2%	0.13	1.5 MB	2.3%	0.44
May 3 02:00:00 - 02:59:59	436	2.0%	0.12	1.6 MB	2.4%	0.46
May 3 03:00:00 - 03:59:59	405	1.8%	0.11	1.2 MB	1.7%	0.33
May 3 04:00:00 - 04:15:00	170	0.8%	0.19	683.8 KB	1.0%	0.76
TOTAL: 24:00:00	22100	100.0%	0.26	67.5 MB	100.0%	0.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	TooOld	Other
1	csiph-out.feeder.erje.net	8136	283	129	1	0	7723
2	feeder.eternal-september.org	6081	52	7	0	0	6022
3	newsfeed.neva.ru	3318	325	10	0	0	2983
4	csiph-out.news.weretis.net	1316	230	0	0	0	1086
5	newsfeed.fu-berlin.de	799	36	2	0	0	761
6	news-out.mixmin.net	666	5	11	0	0	650
7	news.freedyn.net	377	18	0	0	0	359
8	news-out.aioe.org	36	21	0	0	0	15
9	news.fcku.it	29	1	0	0	0	28
10	news.swapon.de	10	0	0	0	0	10
11	news.redatomik.org	10	0	0	0	0	10
12	newsfeeds.xmission.com	10	0	1	0	0	9
13	nntp.pasdenom.info	4	0	0	0	4	0
14	xanadu-bbs.net	2	0	0	0	0	2
TOTAL: 14		20794	971	160	1	4	19658

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	159
ut.test	96
lada.auto	64
alt.windows7.general	46
mpc.lists.freebsd.ports.bugs	40
it.annunci.immobiliari	31
it.annunci.commerciali	30
it-alt.sport.calcio.juventus	29
it.annunci.varie	28
alt.survival	23
mozilla.support.firefox	21
mozilla.dev.security.policy	21
mpc.lists.nanog	21
spline.eisfair	19
lada.commerce	17
mozilla.support.seamonkey	15
lada.movies	15
mozilla.general	13
gmane.comp.gis.openstreetmap.tagging	11
gmane.linux.alsa.devel	11
TOTAL: 126	971

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

Groups	Count
alt.fan.q	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15253
EMP (phn path)	1725
EMP (phl)	172
EMP (phn nph)	63
Too many newsgroups	61
User-issued cancel	38
Binary Payload	36
HTML Multipart	21
Scoring filter (8)	19
Scoring filter (10)	18
Scoring filter (9)	9
Scoring filter (18)	6
Scoring filter (14)	6
Scoring filter (11)	5
Scoring filter (16)	4
Scoring filter (21)	4
Too many newsgroups (low_xpost)	4
HTML Image Tags	2
Too many newsgroups (meow)	2
Angle-bracket bot	1
TOTAL: 25	17454

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	71	0	84	84
TOTAL: 1	71	0	84	84

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	110
	TOTAL: 1	110
Bad Message-ID's offered
	csiph-out.feeder.erje.net	10
	TOTAL: 1	10
Huge articles
	csiph-out.feeder.erje.net	1289
	feeder.eternal-september.org	137
	news.freedyn.net	63
	csiph-out.news.weretis.net	39
	news-out.mixmin.net	11
	news.fcku.it	7
	newsfeeds.xmission.com	7
	newsfeed.fu-berlin.de	5
	news.swapon.de	3
	news.redatomik.org	2
	TOTAL: 10	1563
Including strange strings
	newsfeed.fu-berlin.de	390
	csiph-out.news.weretis.net	47
	news-out.mixmin.net	32
	csiph-out.feeder.erje.net	32
	newsfeed.neva.ru	8
	news.swapon.de	7
	news-out.aioe.org	6
	news.redatomik.org	3
	newsfeeds.xmission.com	2
	news.fcku.it	1
	TOTAL: 11	529
No colon-space in header
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
TOTAL: 5		2214

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	usenet.pasdenom.info	24523	4356	15566	2092	36	21307	17%	23:59:54
2	news.bbs.geek.nz	40678	1021	20435	52	0	1	2%	23:59:54
3	news.freedyn.net	22473	724	20933	12	0	54	3%	23:59:54
4	xanadu-bbs	22980	635	21280	16	0	90	2%	23:59:54
5	fu-berlin.de	20800	560	20240	0	0	0	2%	23:59:54
6	aioe	19833	29	18948	6	0	0	0%	23:59:54
7	news.redatomik.org	21363	21	20762	8	0	0	0%	23:59:54
8	xmission	22211	12	20738	1	0	0	0%	23:59:54
9	erje	16828	10	16749	0	0	0	0%	23:59:50
10	weretis.net	15395	10	15185	1	0	0	0%	23:59:54
11	eternal-september	19112	8	18117	0	0	0	0%	23:59:25
12	news.mixmin.net	20178	8	19649	0	0	0	0%	23:59:35
13	news.swapon.de	2201	8	2149	0	0	41	0%	23:59:54
14	neva.ru	20521	7	20100	15	0	0	0%	23:59:54
15	news.fcku.it	2539	3	2497	0	0	0	0%	23:30:32
16	bwh	0	0	0	0	0	21512	0%	23:58:42
17	au2pb	0	0	0	0	0	21523	0%	23:58:42
TOTAL: 17		291635	7412	253348	2203	36	64528	2%	407:25:40

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	usenet.pasdenom.info	13.4 MB	9.9 MB	23.3 MB	0.3 KB/s	3.7 KB	23:59:54
2	news.freedyn.net	7.2 MB	68.9 KB	7.3 MB	0.1 KB/s	10.1 KB	23:59:54
3	xanadu-bbs	6.8 MB	29.2 KB	6.8 MB	0.1 KB/s	10.7 KB	23:59:54
4	fu-berlin.de	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	12.1 KB	23:59:54
5	news.bbs.geek.nz	2.6 MB	174.9 KB	2.8 MB	0.0 KB/s	2.7 KB	23:59:54
6	aioe	82.4 KB	17.3 KB	99.8 KB	0.0 KB/s	2.9 KB	23:59:54
7	news.redatomik.org	74.9 KB	48.5 KB	123.4 KB	0.0 KB/s	4.3 KB	23:59:54
8	xmission	45.8 KB	2.0 KB	47.8 KB	0.0 KB/s	3.7 KB	23:59:54
9	erje	40.2 KB	0.0 KB	40.2 KB	0.0 KB/s	4.0 KB	23:59:50
10	weretis.net	40.2 KB	33.4 KB	73.6 KB	0.0 KB/s	6.7 KB	23:59:54
11	eternal-september	35.3 KB	0.0 KB	35.3 KB	0.0 KB/s	4.4 KB	23:59:25
12	news.mixmin.net	35.3 KB	0.0 KB	35.3 KB	0.0 KB/s	4.4 KB	23:59:35
13	news.swapon.de	35.3 KB	0.0 KB	35.3 KB	0.0 KB/s	4.4 KB	23:59:54
14	neva.ru	12.1 KB	91.5 KB	103.6 KB	0.0 KB/s	4.7 KB	23:59:54
15	news.fcku.it	4.3 KB	0.0 KB	4.3 KB	0.0 KB/s	1.4 KB	23:30:32
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		37.1 MB	10.3 MB	47.4 MB	0.0 KB/s	5.0 KB	407:25:40

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	144.138.90.254	288	113	321.9 KB	98	0	0	03:36:28
2	mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	2900	14	27.3 KB	5	0	0	04:26:58
3	68-185-181-64.dhcp.mdsn.wi.charter.com	24	9	37.3 KB	9	8	7	06:33:10
4	204-73-239-109.cust.centrio.cz	4	9	16.0 KB	3	0	0	00:14:10
5	dhcp-089-099-203-254.chello.nl	54	5	30.7 KB	5	0	0	13:57:43
TOTAL: 5		3270	150	433.2 KB	120	8	7	32:20:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	288	113	321.9 KB	98	0	0	03:36:28
2	*.dsl.bell.ca	2900	14	27.3 KB	5	0	0	04:26:58
3	*.cust.centrio.cz	4	9	16.0 KB	3	0	0	00:14:10
4	*.dhcp.mdsn.wi.charter.com	24	9	37.3 KB	9	8	7	06:33:10
5	*.chello.nl	54	5	30.7 KB	5	0	0	13:57:43
6	*.2.89.75.dynamic.ip.windstream.net	2	0	0.0 KB	0	0	0	03:32:23
TOTAL: 6		3272	150	433.2 KB	120	8	7	32:20:56

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	7
ctomczyk	4
TOTAL: 3	299

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	4.343	0.957	0.000	13:57:43
68-185-181-64.dhcp.mdsn.wi.charter.com	2.345	0.499	0.024	06:33:10
mtrlpq2314w-lp130-04-70-54-156-183.dsl.bell.ca	235.745	48.592	0.000	04:26:58
144.138.90.254	26.552	6.969	1.046	03:36:28
h63.2.89.75.dynamic.ip.windstream.net	0.266	0.212	0.000	03:32:23
204-73-239-109.cust.centrio.cz	0.363	0.064	0.012	00:14:10
TOTAL: 6	269.614	57.293	1.082	32:20:56

Curious NNRP server explorers [Top 20]:

System	Conn
h63.2.89.75.dynamic.ip.windstream.net	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	8	0
h63.2.89.75.dynamic.ip.windstream.net	2	0
TOTAL: 2	10	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	137	91.3%
2	uk	10	6.7%
3	alt	3	2.0%
TOTAL: 3		150	100.0%

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

Newsgroup		Count
1	comp.os.vms	49
2	comp.sys.ibm.ps2.hardware	23
3	comp.arch.fpga	20
4	comp.arch	17
5	uk.rec.drugs.cannabis	10
6	comp.sys.ibm.pc.games.action	9
7	comp.lang.javascript	9
8	comp.os.msdos.djgpp	4
9	comp.arch.embedded	3
10	alt.life.sucks	2
11	alt.comp.os.windows-10	1
12	comp.ai.philosophy	1
13	comp.os.cpm	1
14	comp.misc	1
TOTAL: 14		150

Daily Usenet report for csiph.com

May 2 04:15:00 -- May 3 04:15:00