Daily Usenet report for csiph.com: Feb 9 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45283	51.5%	8.5 MB	64.2%
innd	24595	28.0%	2.6 MB	19.8%
nnrpd	14691	16.7%	1.6 MB	11.9%
innfeed	3210	3.7%	537.0 KB	4.0%
nocem	92	0.1%	9.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	87872	100.0%	13.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	449175	56.3%
Negative hits	304056	38.1%
Do not exist	44111	5.5%
Cache misses	697	0.1%
TOTAL: 4	798039	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.313	0.0%	276	0.000	4.757	23.611
article cleanup	00:00:00.270	0.0%	44326	0.000	0.006	0.019
article logging	00:00:04.680	0.0%	44903	0.000	0.104	0.795
article parse	00:00:02.417	0.0%	144484	0.002	0.017	0.194
article write	00:02:16.661	0.2%	24733	0.207	5.525	25.551
artlog/artcncl	00:00:00.030	0.0%	251	0.000	0.120	1.200
artlog/artparse	00:00:00.004	0.0%	176	0.000	0.023	0.500
data move	00:00:00.301	0.0%	730669	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.496	0.0%	271	0.000	1.830	14.833
hishave/artcncl	00:00:00.084	0.0%	276	0.000	0.304	3.278
hishave/artparse	00:00:00.000	0.0%	175	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:56.905	0.1%	801708	0.003	0.146	0.538
history sync	00:00:04.948	0.0%	350	0.000	14.137	126.667
history write	00:03:43.740	0.3%	44141	0.087	5.069	27.125
hiswrite/artcncl	00:00:00.005	0.0%	5	0.000	1.000	5.000
hiswrite/artparse	00:00:00.744	0.0%	174	0.000	4.276	37.000
idle	23:44:30.361	98.9%	714116	73.942	119.687	294.185
nntp read	00:00:03.305	0.0%	745436	0.002	0.004	0.007
overview write	00:04:48.752	0.3%	24733	1.226	11.675	85.945
perl filter	00:01:48.799	0.1%	44044	1.451	2.470	4.269
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.459	0.0%	49551	0.000	0.009	0.028
TOTAL: 24:00:47.133	23:59:24.274	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.082	0.0%	24992	0.000	0.003	0.021
article prepare	00:00:00.004	0.0%	44968	0.000	0.000	0.006
article read	00:00:01.464	0.0%	8860	0.027	0.165	1.600
backlog stats	00:00:00.288	0.0%	460315	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.402	0.0%	353428	0.001	0.007	0.013
data write	00:00:02.074	0.0%	335333	0.002	0.006	0.013
idle	23:54:47.342	100.0%	460315	123.892	187.018	524.110
status file	00:00:00.242	0.0%	283	0.000	0.855	19.000
TOTAL: 23:55:04.380	23:54:53.898	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.725	0.0%	2215	0.000	1.682	13.000
idle	46:09:23.436	99.8%	30785	78.929	5397.545	900139.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.312	0.0%	61868	0.000	0.005	0.167
readart	00:00:43.893	0.0%	11871	0.000	3.697	61.000
TOTAL: 46:15:23.247	46:10:11.366	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	142599	6748	123574	12277	4%	94:33:55
2	csiph-out.news.weretis.net	4	48129	5727	41625	777	11%	44:48:20
3	feeder.eternal-september.org	2	39970	3609	32021	4340	9%	24:29:17
4	news-out.aioe.org	3	23926	2636	21254	36	11%	24:09:52
5	newsfeed.fu-berlin.de	50	27088	1371	25258	459	5%	35:14:06
6	newsfeed.neva.ru	140	57406	1338	52718	3350	2%	71:39:39
7	newsfeeds.xmission.com	2	24475	954	23520	1	3%	25:30:36
8	news-out.mixmin.net	4	26707	761	25704	242	2%	24:09:05
9	news.redatomik.org	1	21499	685	20808	6	3%	22:02:44
10	news.fcku.it	1	10844	543	10240	61	5%	23:59:57
11	xanadu-bbs.net	11	1586	193	1357	36	12%	36:27:49
12	news.freedyn.net	3	27757	119	27535	103	0%	24:09:48
13	news.swapon.de	2	2360	90	2260	10	3%	24:47:21
14	nntp.pasdenom.info	4	23894	53	23823	18	0%	23:54:46
15	news.bbs.geek.nz	2	19449	24	19423	2	0%	23:59:38
16	localhost	40	4	4	0	0	100%	00:00:01
TOTAL: 16		1421	497693	24855	451120	21718	4%	523:56:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	21.2 MB	1.9 MB	63.8 MB	86.9 MB	24%	4.7 KB
2	csiph-out.news.weretis.net	17.0 MB	20.6 KB	9.9 MB	27.0 MB	63%	4.2 KB
3	feeder.eternal-september.org	9.7 MB	59.3 KB	57.9 MB	67.7 MB	14%	8.7 KB
4	news-out.aioe.org	7.1 MB	23.4 KB	86.6 KB	7.2 MB	98%	2.8 KB
5	news-out.mixmin.net	4.1 MB	20.1 KB	874.9 KB	5.0 MB	82%	5.1 KB
6	newsfeed.neva.ru	3.4 MB	158.0 KB	28.9 MB	32.5 MB	10%	7.1 KB
7	newsfeed.fu-berlin.de	3.1 MB	4.8 KB	1.2 MB	4.2 MB	72%	2.4 KB
8	newsfeeds.xmission.com	3.0 MB	0.0 KB	1.5 KB	3.0 MB	99%	3.2 KB
9	news.redatomik.org	1.9 MB	0.0 KB	1.6 MB	3.5 MB	55%	5.1 KB
10	news.fcku.it	1.1 MB	4.8 KB	830.6 KB	2.0 MB	58%	3.3 KB
11	xanadu-bbs.net	554.0 KB	0.0 KB	331.6 KB	885.6 KB	62%	3.9 KB
12	news.freedyn.net	410.2 KB	12.1 KB	812.3 KB	1.2 MB	33%	5.6 KB
13	news.swapon.de	231.6 KB	0.0 KB	591.2 KB	822.8 KB	28%	8.2 KB
14	nntp.pasdenom.info	159.9 KB	15.1 KB	49.1 KB	224.1 KB	71%	3.2 KB
15	news.bbs.geek.nz	51.0 KB	6.5 KB	8.3 KB	65.8 KB	77%	2.5 KB
16	localhost	5.0 KB	0.0 KB	0.0 KB	5.0 KB	100%	1.2 KB
TOTAL: 16		73.1 MB	2.2 MB	166.8 MB	242.0 MB	30%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 9 04:15:00 - 04:59:59	417	1.7%	0.15	1.6 MB	2.1%	0.59
Feb 9 05:00:00 - 05:59:59	622	2.5%	0.17	2.3 MB	3.2%	0.65
Feb 9 06:00:00 - 06:59:59	612	2.5%	0.17	2.9 MB	4.0%	0.82
Feb 9 07:00:00 - 07:59:59	666	2.7%	0.18	1.9 MB	2.6%	0.55
Feb 9 08:00:00 - 08:59:59	1016	4.1%	0.28	2.8 MB	3.8%	0.79
Feb 9 09:00:00 - 09:59:59	1186	4.8%	0.33	3.2 MB	4.4%	0.91
Feb 9 10:00:00 - 10:59:59	1210	4.9%	0.34	3.4 MB	4.7%	0.98
Feb 9 11:00:00 - 11:59:59	1080	4.4%	0.30	3.1 MB	4.2%	0.88
Feb 9 12:00:00 - 12:59:59	1143	4.6%	0.32	3.0 MB	4.1%	0.85
Feb 9 13:00:00 - 13:59:59	1263	5.1%	0.35	3.3 MB	4.6%	0.95
Feb 9 14:00:00 - 14:59:59	1332	5.4%	0.37	3.6 MB	4.9%	1.02
Feb 9 15:00:00 - 15:59:59	1415	5.7%	0.39	4.2 MB	5.7%	1.19
Feb 9 16:00:00 - 16:59:59	1373	5.6%	0.38	3.7 MB	5.1%	1.05
Feb 9 17:00:00 - 17:59:59	1362	5.5%	0.38	3.7 MB	5.1%	1.07
Feb 9 18:00:00 - 18:59:59	1409	5.7%	0.39	3.7 MB	5.1%	1.06
Feb 9 19:00:00 - 19:59:59	1328	5.4%	0.37	4.0 MB	5.4%	1.13
Feb 9 20:00:00 - 20:59:59	1230	5.0%	0.34	3.6 MB	4.9%	1.02
Feb 9 21:00:00 - 21:59:59	1160	4.7%	0.32	3.5 MB	4.9%	1.01
Feb 9 22:00:00 - 22:59:59	1211	4.9%	0.34	3.3 MB	4.6%	0.95
Feb 9 23:00:00 - 23:59:59	990	4.0%	0.28	3.2 MB	4.4%	0.90
Feb 10 00:00:00 - 00:59:59	762	3.1%	0.21	2.5 MB	3.4%	0.70
Feb 10 01:00:00 - 01:59:59	696	2.8%	0.19	2.6 MB	3.5%	0.73
Feb 10 02:00:00 - 02:59:59	623	2.5%	0.17	1.9 MB	2.7%	0.55
Feb 10 03:00:00 - 03:59:59	509	2.1%	0.14	1.5 MB	2.1%	0.44
Feb 10 04:00:00 - 04:15:00	103	0.4%	0.11	262.8 KB	0.4%	0.29
TOTAL: 24:00:00	24718	100.0%	0.29	72.8 MB	100.0%	0.86

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	10323	454	150	1	9718
2	feeder.eternal-september.org	4379	61	6	0	4312
3	newsfeed.neva.ru	3350	314	11	0	3025
4	csiph-out.news.weretis.net	859	217	4	0	638
5	newsfeed.fu-berlin.de	850	59	2	0	789
6	news-out.mixmin.net	253	0	3	0	250
7	news.freedyn.net	101	19	2	0	80
8	news.fcku.it	63	1	1	0	61
9	news-out.aioe.org	48	30	4	0	14
10	xanadu-bbs.net	31	0	0	0	31
11	news.swapon.de	24	0	0	0	24
12	nntp.pasdenom.info	18	9	2	0	7
13	news.redatomik.org	11	0	0	0	11
14	newsfeeds.xmission.com	6	0	0	0	6
15	news.bbs.geek.nz	2	0	1	0	1
TOTAL: 15		20318	1164	186	1	18967

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	166
ut.test	134
mozilla.general	83
it-alt.sport.calcio.juventus	75
spline.eisfair	48
it.annunci.varie	45
it.annunci.immobiliari	39
lada.auto	39
it.annunci.commerciali	38
alt.windows7.general	35
alt.survival	30
gmane.linux.alsa.devel	25
mozilla.dev.platform	24
lada.photo	20
mozilla.support.seamonkey	20
lada.commerce	19
mpc.lists.nanog	17
mozilla.dev.security.policy	15
cakewalk.audio	14
mpc.lists.freebsd.gecko	12
TOTAL: 132	1164

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

Groups	Count
nz.politics.announce	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15760
EMP (phn path)	1428
EMP (phl)	495
EMP (phn nph)	248
Too many newsgroups	71
Binary Payload	38
HTML Multipart	16
User-issued cancel	16
Scoring filter (8)	7
Scoring filter (14)	6
Scoring filter (11)	5
Scoring filter (13)	5
Scoring filter (19)	5
Scoring filter (12)	5
Scoring filter (9)	4
Too many newsgroups (low_xpost)	3
Scoring filter (17)	2
Scoring filter (20)	1
Scoring filter (21)	1
HTML Image Tags	1
TOTAL: 22	18119

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	83	0	90	90
TOTAL: 1	83	0	90	90

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	96
	TOTAL: 1	96
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	TOTAL: 1	4
Huge articles
	feeder.eternal-september.org	96
	csiph-out.news.weretis.net	56
	news-out.mixmin.net	6
	news.fcku.it	5
	news.redatomik.org	5
	newsfeed.fu-berlin.de	2
	news.swapon.de	2
	news.freedyn.net	1
	xanadu-bbs.net	1
	TOTAL: 9	174
Including strange strings
	newsfeed.fu-berlin.de	439
	csiph-out.feeder.erje.net	39
	feeder.eternal-september.org	26
	csiph-out.news.weretis.net	15
	news.swapon.de	14
	news-out.aioe.org	12
	newsfeed.neva.ru	10
	news-out.mixmin.net	8
	news.redatomik.org	5
	newsfeeds.xmission.com	5
	TOTAL: 13	577
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 5		852

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	40097	7376	16569	103	181	18%	23:55:23
2	news.freedyn.net	25637	787	23793	21	1	3%	23:59:54
3	xanadu-bbs	27649	716	23860	2	0	2%	23:59:55
4	fu-berlin.de	23122	606	22516	0	0	2%	23:59:54
5	usenet.pasdenom.info	27394	146	23247	1270	120	0%	23:59:54
6	xmission	28589	111	22924	6	2	0%	23:59:55
7	news.redatomik.org	24104	21	23272	7	0	0%	23:50:10
8	aioe	21426	19	20137	1	0	0%	23:50:09
9	weretis.net	17441	11	16660	2	0	0%	23:49:06
10	eternal-september	21819	10	20080	0	1	0%	23:59:38
11	erje	17911	8	17841	11	0	0%	23:59:55
12	news.mixmin.net	22950	5	22101	0	0	0%	23:59:54
13	neva.ru	23510	4	22356	12	1	0%	23:59:48
14	news.swapon.de	2346	3	2265	3	0	0%	23:56:32
15	news.fcku.it	2786	1	2771	0	0	0%	22:33:29
16	au2pb	0	0	0	0	24087	0%	23:58:42
17	bwh	0	0	0	0	24066	0%	23:58:42
TOTAL: 17		326781	9824	280392	1438	48459	3%	405:51:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	19.9 MB	343.9 KB	20.2 MB	0.2 KB/s	2.8 KB	23:55:23
2	news.freedyn.net	5.8 MB	93.8 KB	5.9 MB	0.1 KB/s	7.5 KB	23:59:54
3	xanadu-bbs	5.6 MB	6.2 KB	5.6 MB	0.1 KB/s	8.0 KB	23:59:55
4	fu-berlin.de	5.3 MB	0.0 KB	5.3 MB	0.1 KB/s	9.0 KB	23:59:54
5	usenet.pasdenom.info	440.5 KB	6.5 MB	6.9 MB	0.1 KB/s	5.0 KB	23:59:54
6	xmission	277.8 KB	9.4 KB	287.2 KB	0.0 KB/s	2.5 KB	23:59:55
7	aioe	81.2 KB	10.2 KB	91.4 KB	0.0 KB/s	4.6 KB	23:50:09
8	news.redatomik.org	53.3 KB	86.0 KB	139.3 KB	0.0 KB/s	5.0 KB	23:50:10
9	weretis.net	20.1 KB	26.7 KB	46.8 KB	0.0 KB/s	3.6 KB	23:49:06
10	eternal-september	15.9 KB	0.0 KB	15.9 KB	0.0 KB/s	1.6 KB	23:59:38
11	erje	11.4 KB	71.7 KB	83.2 KB	0.0 KB/s	4.4 KB	23:59:55
12	news.mixmin.net	11.4 KB	0.0 KB	11.4 KB	0.0 KB/s	2.3 KB	23:59:54
13	neva.ru	10.2 KB	39.4 KB	49.6 KB	0.0 KB/s	3.1 KB	23:59:48
14	news.swapon.de	4.0 KB	12.8 KB	16.8 KB	0.0 KB/s	2.8 KB	23:56:32
15	news.fcku.it	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	2.0 KB	22:33:29
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		37.5 MB	7.1 MB	44.6 MB	0.0 KB/s	4.1 KB	405:51:00

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	300	109	436.3 KB	71	0	0	00:49:23
2	194-73-239-109.cust.centrio.cz	20	13	35.8 KB	7	0	0	02:49:21
3	68-185-181-64.dhcp.mdsn.wi.charter.com	28	4	5.0 KB	4	4	4	07:36:05
TOTAL: 3		348	126	477.0 KB	82	4	4	46:15:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	300	109	436.3 KB	71	0	0	00:49:23
2	*.cust.centrio.cz	20	13	35.8 KB	7	0	0	02:49:21
3	*.dhcp.mdsn.wi.charter.com	28	4	5.0 KB	4	4	4	07:36:05
4	*.chello.nl	50	0	0.0 KB	0	0	0	08:20:19
5	unresolved	49	0	0.0 KB	0	0	0	24:52:43
6	*.bbox.io	8	0	0.0 KB	0	0	0	01:47:25
TOTAL: 6		455	126	477.0 KB	82	4	4	46:15:19

NNRP auth users [Top 20]:

User	Conn
ctomczyk	9
ohlandl	4
TOTAL: 2	13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	3.454	0.743	0.000	22:29:30
dhcp-089-099-203-254.chello.nl	3.739	0.806	0.000	08:20:19
68-185-181-64.dhcp.mdsn.wi.charter.com	2.352	0.635	0.015	07:36:05
194-73-239-109.cust.centrio.cz	1.626	0.367	0.033	02:49:21
50.96.153.220	0.191	0.188	0.000	02:23:12
tempe0.bbox.io	0.687	0.135	0.000	01:47:25
mtrlpq2314w-lp130-01-70-49-68-214.dsl.bell.ca	28.523	6.825	0.000	00:49:23
TOTAL: 7	40.572	9.699	0.048	46:15:19

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	50
216.239.174.218	47
tempe0.bbox.io	8
50.96.153.220	2
TOTAL: 4	107

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	43	0
68-185-181-64.dhcp.mdsn.wi.charter.com	7	0
194-73-239-109.cust.centrio.cz	5	0
tempe0.bbox.io	3	0
TOTAL: 4	58	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	67	53.2%
2	comp	17	13.5%
3	fr	15	11.9%
4	uk	9	7.1%
5	can	5	4.0%
6	talk	3	2.4%
7	soc	3	2.4%
8	aus	2	1.6%
9	rec	1	0.8%
10	linux	1	0.8%
11	sci	1	0.8%
12	misc	1	0.8%
13	dictator	1	0.8%
TOTAL: 13		126	100.0%

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

Newsgroup		Count
1	alt.usenet.kooks	17
2	comp.lang.javascript	13
3	fr.soc.politique	12
4	alt.fan.rush-limbaugh	10
5	alt.home.repair	9
6	alt.california	9
7	alt.society.liberalism	6
8	alt.atheism	6
9	uk.rec.drugs.cannabis	5
10	alt.christnet.christianlife	5
11	can.politics	5
12	comp.sys.ibm.ps2.hardware	4
13	fr.rec.photo	3
14	soc.culture.usa	3
15	talk.politics.misc	3
16	uk.legal	2
17	uk.politics.misc	2
18	alt.slack	2
19	aus.religion.christian	2
20	rec.bicycles.tech	1
21	sci.math	1
22	dictator.europe	1
23	linux.kernel	1
24	alt.life.sucks	1
25	misc.survivalism	1
26	alt.conspiracy	1
27	alt.russian.z1	1
TOTAL: 27		126

Daily Usenet report for csiph.com

Feb 9 04:15:00 -- Feb 10 04:15:00