Daily Usenet report for csiph.com: Apr 4 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	29079	57.2%	6.1 MB	70.9%
innd	13106	25.8%	1.5 MB	17.9%
nnrpd	5703	11.2%	487.5 KB	5.5%
innfeed	2849	5.6%	493.4 KB	5.6%
nocem	105	0.2%	12.0 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	50843	100.0%	8.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	316329	55.0%
Negative hits	230507	40.1%
Do not exist	28045	4.9%
Cache misses	392	0.1%
TOTAL: 4	575273	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.882	0.0%	277	0.000	3.184	188.000
article cleanup	00:00:00.166	0.0%	25625	0.000	0.006	0.045
article logging	00:00:00.629	0.0%	26334	0.000	0.024	0.769
article parse	00:00:16.627	0.0%	133845	0.000	0.124	0.535
article write	00:01:19.848	0.1%	20325	0.221	3.929	17.621
artlog/artcncl	00:00:00.000	0.0%	202	0.000	0.000	0.000
artlog/artparse	00:00:00.061	0.0%	2539	0.000	0.024	1.211
data move	00:00:00.273	0.0%	630293	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.097	0.0%	268	0.000	0.362	7.000
hishave/artcncl	00:00:00.111	0.0%	277	0.000	0.401	5.800
hishave/artparse	00:00:00.008	0.0%	2534	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:10.795	0.1%	575050	0.005	0.123	0.477
history sync	00:00:02.300	0.0%	341	0.000	6.745	68.000
history write	00:01:15.308	0.1%	25600	0.371	2.942	12.403
hiswrite/artcncl	00:00:00.014	0.0%	9	1.000	1.556	1.714
hiswrite/artparse	00:00:13.952	0.0%	2534	0.000	5.506	17.000
idle	23:50:02.962	99.3%	612741	45.542	140.031	583.736
nntp read	00:00:03.285	0.0%	620988	0.001	0.005	0.008
overview write	00:04:54.407	0.3%	20325	0.379	14.485	2100.293
perl filter	00:00:48.685	0.1%	25453	1.280	1.913	3.224
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.375	0.0%	40749	0.000	0.009	0.026
TOTAL: 24:00:44.269	24:00:10.785	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	20296	0.000	0.002	0.017
article prepare	00:00:00.005	0.0%	31856	0.000	0.000	0.005
article read	00:00:00.957	0.0%	5953	0.000	0.161	1.467
backlog stats	00:00:00.218	0.0%	371328	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.962	0.0%	289926	0.003	0.007	0.014
data write	00:00:01.622	0.0%	272853	0.001	0.006	0.012
idle	23:53:07.380	100.0%	371328	108.129	231.567	792.587
status file	00:00:00.187	0.0%	250	0.000	0.748	2.000
TOTAL: 23:53:20.090	23:53:12.366	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	11:32:37.533	99.9%	5641	0.000	7367.051	600208.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.068	0.0%	11607	0.000	0.006	0.167
readart	00:00:00.036	0.0%	2	18.000	18.000	18.000
TOTAL: 11:32:59.227	11:32:37.637	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	41662	6391	34941	330	15%	48:43:45
2	csiph-out.feeder.erje.net	1152	85747	3118	80852	1777	3%	94:57:42
3	feeder.eternal-september.org	5	23236	2701	20209	326	11%	25:23:53
4	news-out.aioe.org	4	20666	2414	18065	187	11%	24:05:50
5	news-out.mixmin.net	3	21154	1618	19082	454	7%	24:08:47
6	newsfeed.fu-berlin.de	15	21478	1287	20001	190	5%	28:47:05
7	newsfeed.neva.ru	144	24778	1255	21397	2126	5%	71:51:23
8	news.redatomik.org	2	18848	1025	17773	50	5%	22:13:37
9	news.fcku.it	1	11911	589	8895	2427	4%	23:59:56
10	news.swapon.de	3	1748	48	1698	2	2%	22:33:24
11	news.freedyn.net	2	22584	25	22535	24	0%	25:41:35
12	newsfeeds.xmission.com	2	5476	24	5451	1	0%	08:29:51
13	news.bbs.geek.nz	1	15315	4	15306	5	0%	23:59:46
14	nntp.pasdenom.info	2	18844	3	18836	5	0%	23:59:12
15	xanadu-bbs.net	16	706	0	700	6	0%	24:57:43
16	localhost	33	0	0	0	0	0%	00:00:00
TOTAL: 16		1390	334153	20502	305741	7910	6%	493:53:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	20.6 MB	1.3 KB	5.5 MB	26.2 MB	78%	4.0 KB
2	csiph-out.feeder.erje.net	14.7 MB	48.2 KB	12.5 MB	27.3 MB	54%	5.7 KB
3	feeder.eternal-september.org	6.9 MB	4.9 KB	3.3 MB	10.1 MB	67%	3.4 KB
4	news-out.aioe.org	6.0 MB	4.9 KB	688.9 KB	6.7 MB	89%	2.6 KB
5	news-out.mixmin.net	5.1 MB	4.7 KB	992.4 KB	6.1 MB	84%	3.0 KB
6	newsfeed.neva.ru	3.5 MB	243.5 KB	7.0 MB	10.7 MB	32%	3.2 KB
7	newsfeed.fu-berlin.de	2.9 MB	4.7 KB	521.5 KB	3.5 MB	85%	2.4 KB
8	news.redatomik.org	2.4 MB	4.9 KB	266.4 KB	2.7 MB	90%	2.6 KB
9	news.fcku.it	1.4 MB	0.0 KB	382.9 MB	384.4 MB	0%	130.5 KB
10	news.swapon.de	116.3 KB	0.0 KB	839.2 KB	955.5 KB	12%	19.1 KB
11	newsfeeds.xmission.com	94.6 KB	4.9 KB	4.9 KB	104.3 KB	90%	4.2 KB
12	news.freedyn.net	68.4 KB	9.7 KB	231.9 KB	310.0 KB	22%	6.3 KB
13	news.bbs.geek.nz	12.8 KB	4.9 KB	301.3 KB	319.0 KB	4%	35.4 KB
14	nntp.pasdenom.info	11.1 KB	0.0 KB	11.2 KB	22.3 KB	49%	2.8 KB
15	xanadu-bbs.net	0.0 KB	0.0 KB	67.4 KB	67.4 KB	0%	11.2 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		63.9 MB	336.6 KB	415.1 MB	479.3 MB	13%	17.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 4 04:15:00 - 04:59:59	357	1.8%	0.13	1.6 MB	2.5%	0.61
Apr 4 05:00:00 - 05:59:59	531	2.6%	0.15	1.9 MB	3.1%	0.55
Apr 4 06:00:00 - 06:59:59	757	3.7%	0.21	3.2 MB	5.0%	0.91
Apr 4 07:00:00 - 07:59:59	906	4.5%	0.25	2.5 MB	4.0%	0.72
Apr 4 08:00:00 - 08:59:59	893	4.4%	0.25	2.6 MB	4.1%	0.74
Apr 4 09:00:00 - 09:59:59	928	4.6%	0.26	2.6 MB	4.1%	0.73
Apr 4 10:00:00 - 10:59:59	888	4.4%	0.25	2.6 MB	4.1%	0.74
Apr 4 11:00:00 - 11:59:59	952	4.7%	0.26	3.0 MB	4.7%	0.84
Apr 4 12:00:00 - 12:59:59	960	4.7%	0.27	2.4 MB	3.8%	0.68
Apr 4 13:00:00 - 13:59:59	1019	5.0%	0.28	2.8 MB	4.4%	0.80
Apr 4 14:00:00 - 14:59:59	1101	5.4%	0.31	3.3 MB	5.2%	0.94
Apr 4 15:00:00 - 15:59:59	1459	7.2%	0.41	5.7 MB	9.1%	1.63
Apr 4 16:00:00 - 16:59:59	1278	6.3%	0.35	3.8 MB	6.0%	1.08
Apr 4 17:00:00 - 17:59:59	1194	5.9%	0.33	3.9 MB	6.1%	1.10
Apr 4 18:00:00 - 18:59:59	1226	6.0%	0.34	3.6 MB	5.7%	1.03
Apr 4 19:00:00 - 19:59:59	1098	5.4%	0.30	2.8 MB	4.5%	0.80
Apr 4 20:00:00 - 20:59:59	1028	5.1%	0.29	3.0 MB	4.8%	0.86
Apr 4 21:00:00 - 21:59:59	867	4.3%	0.24	2.3 MB	3.6%	0.64
Apr 4 22:00:00 - 22:59:59	723	3.6%	0.20	2.5 MB	4.0%	0.71
Apr 4 23:00:00 - 23:59:59	543	2.7%	0.15	1.7 MB	2.7%	0.49
Apr 5 00:00:00 - 00:59:59	376	1.8%	0.10	1.1 MB	1.7%	0.31
Apr 5 01:00:00 - 01:59:59	444	2.2%	0.12	1.5 MB	2.4%	0.44
Apr 5 02:00:00 - 02:59:59	337	1.7%	0.09	1.3 MB	2.1%	0.37
Apr 5 03:00:00 - 03:59:59	335	1.6%	0.09	1.2 MB	1.8%	0.33
Apr 5 04:00:00 - 04:15:00	128	0.6%	0.14	426.2 KB	0.7%	0.47
TOTAL: 24:00:00	20328	100.0%	0.24	63.4 MB	100.0%	0.75

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.fcku.it	2399	2	0	2397
2	newsfeed.neva.ru	2125	121	10	1994
3	csiph-out.feeder.erje.net	1815	151	11	1653
4	newsfeed.fu-berlin.de	725	35	1	689
5	news-out.mixmin.net	480	3	1	476
6	csiph-out.news.weretis.net	362	216	1	145
7	feeder.eternal-september.org	325	55	1	269
8	news-out.aioe.org	196	105	1	90
9	news.redatomik.org	88	0	1	87
10	news.swapon.de	13	0	0	13
11	news.freedyn.net	6	0	1	5
12	xanadu-bbs.net	6	0	0	6
13	nntp.pasdenom.info	5	5	0	0
14	news.bbs.geek.nz	3	0	1	2
15	newsfeeds.xmission.com	2	0	1	1
TOTAL: 15		8550	693	30	7827

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	92
it.annunci.varie	53
it.annunci.immobiliari	52
lada.talk	51
it.annunci.commerciali	43
gmane.linux.alsa.devel	30
alt.survival	30
gmane.comp.gis.openstreetmap.tagging	24
mozilla.dev.security.policy	21
lada.auto	17
mpc.lists.nanog	17
alt.politics.usa	17
mpc.lists.freebsd.office	17
it-alt.comp.software.mozilla	15
oesterreich.tratsch	14
mpc.lists.freebsd.x11	14
alt.politics	13
alt.windows7.general	12
gmane.comp.gis.openstreetmap	10
lada.commerce	10
TOTAL: 89	693

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3804
EMP (phn path)	272
EMP (phn nph)	142
Binary: misplaced rar	47
EMP (phl)	40
Too many newsgroups	38
User-issued cancel	21
HTML Multipart	20
Binary Payload	12
Scoring filter (8)	11
EMP (fsl)	9
Scoring filter (10)	5
Binary: misplaced par2	5
Too many newsgroups (meow)	2
HTML post	2
Scoring filter (11)	1
Binary: misplaced 207	1
Scoring filter (9)	1
Binary Image: misplaced png	1
Cancel for rejected article	1
TOTAL: 22	4437

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	142
	TOTAL: 1	142
Huge articles
	news.fcku.it	2260
	csiph-out.feeder.erje.net	170
	csiph-out.news.weretis.net	65
	feeder.eternal-september.org	26
	news-out.mixmin.net	6
	news.redatomik.org	2
	news.bbs.geek.nz	2
	news.swapon.de	2
	newsfeeds.xmission.com	1
	xanadu-bbs.net	1
	TOTAL: 11	2536
Including strange strings
	newsfeed.fu-berlin.de	546
	csiph-out.feeder.erje.net	38
	news.redatomik.org	38
	news-out.mixmin.net	32
	csiph-out.news.weretis.net	31
	news.swapon.de	11
	news-out.aioe.org	5
	newsfeed.neva.ru	5
	feeder.eternal-september.org	3
	TOTAL: 9	709
No colon-space in header
	csiph-out.feeder.erje.net	5
	TOTAL: 1	5
TOTAL: 4		3392

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	33829	4847	14892	28	1	14%	23:59:55
2	news.freedyn.net	21647	620	19696	0	0	2%	23:59:55
3	xanadu-bbs	21727	607	19720	0	0	2%	23:59:55
4	fu-berlin.de	18964	536	18427	0	0	2%	23:59:52
5	usenet.pasdenom.info	22577	82	19187	1049	8	0%	23:59:55
6	aioe	16929	41	16587	0	0	0%	23:59:54
7	erje	18247	33	17077	9	0	0%	23:59:47
8	news.redatomik.org	18944	22	18713	0	19	0%	23:59:55
9	weretis.net	12598	12	12195	9	0	0%	23:59:48
10	news.mixmin.net	18054	9	17828	0	0	0%	23:59:53
11	neva.ru	18703	5	18247	84	0	0%	23:59:46
12	xmission	22213	3	19549	1	0	0%	23:59:55
13	news.swapon.de	1800	1	1785	0	0	0%	23:51:54
14	eternal-september	17285	0	16955	0	0	0%	23:59:55
15	news.fcku.it	2467	0	2451	0	0	0%	20:53:54
TOTAL: 15		265984	6818	233309	1180	28	2%	356:44:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	14.8 MB	130.6 KB	14.9 MB	0.2 KB/s	3.1 KB	23:59:55
2	news.freedyn.net	7.5 MB	0.0 KB	7.5 MB	0.1 KB/s	12.3 KB	23:59:55
3	xanadu-bbs	7.4 MB	0.0 KB	7.4 MB	0.1 KB/s	12.5 KB	23:59:55
4	fu-berlin.de	7.2 MB	0.0 KB	7.2 MB	0.1 KB/s	13.8 KB	23:59:52
5	usenet.pasdenom.info	230.3 KB	8.1 MB	8.3 MB	0.1 KB/s	7.5 KB	23:59:55
6	aioe	118.9 KB	0.0 KB	118.9 KB	0.0 KB/s	2.9 KB	23:59:54
7	erje	100.7 KB	108.8 KB	209.4 KB	0.0 KB/s	5.0 KB	23:59:47
8	news.redatomik.org	96.6 KB	0.0 KB	96.6 KB	0.0 KB/s	4.4 KB	23:59:55
9	news.mixmin.net	52.6 KB	0.0 KB	52.6 KB	0.0 KB/s	5.8 KB	23:59:53
10	weretis.net	45.7 KB	49.6 KB	95.3 KB	0.0 KB/s	4.5 KB	23:59:48
11	neva.ru	34.6 KB	204.3 KB	238.9 KB	0.0 KB/s	2.7 KB	23:59:46
12	xmission	11.2 KB	1.5 KB	12.7 KB	0.0 KB/s	3.2 KB	23:59:55
13	news.swapon.de	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.9 KB	23:51:54
14	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:53:54
TOTAL: 15		37.6 MB	8.5 MB	46.1 MB	0.0 KB/s	5.9 KB	356:44:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	94.160.190.139	3	2	1.4 KB	1	0	0	00:01:49
TOTAL: 1		3	2	1.4 KB	1	0	0	11:32:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	51	2	1.4 KB	1	00:39:47
2	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	00:00:00
3	*.upc-a.chello.nl	33	0	0.0 KB	0	05:16:16
4	*.nielsenmedia.com	1	0	0.0 KB	0	00:30:10
5	*.ph.ph.cox.net	4	0	0.0 KB	0	02:02:21
6	*.dhcp.mdsn.wi.charter.com	10	0	0.0 KB	0	03:04:22
TOTAL: 6		100	2	1.4 KB	1	11:32:58

NNRP auth users [Top 20]:

User	Conn
moussa	46
TOTAL: 1	46

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.301	0.558	0.000	05:16:16
96-42-18-226.dhcp.mdsn.wi.charter.com	0.757	0.138	0.000	03:04:22
ip72-222-200-59.ph.ph.cox.net	0.326	0.064	0.000	02:02:21
141.168.117.108	5.041	1.150	0.164	00:37:53
challenger.nielsenmedia.com	0.053	0.030	0.000	00:30:10
94.160.190.139	0.188	0.045	0.000	00:01:49
94.161.87.72	0.091	0.008	0.000	00:00:02
94.161.84.158	0.091	0.008	0.000	00:00:01
ec2-54-191-101-186.us-west-2.compute.amazonaws.com	0.059	0.008	0.000	00:00:00
TOTAL: 9	8.907	2.009	0.164	11:32:58

Curious NNRP server explorers [Top 20]:

System	Conn
141.168.117.108	46
a131033.upc-a.chello.nl	33
96-42-18-226.dhcp.mdsn.wi.charter.com	10
ip72-222-200-59.ph.ph.cox.net	4
94.161.84.158	1
ec2-54-191-101-186.us-west-2.compute.amazonaws.com	1
94.161.87.72	1
challenger.nielsenmedia.com	1
TOTAL: 8	97

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-18-226.dhcp.mdsn.wi.charter.com	5	0
ip72-222-200-59.ph.ph.cox.net	4	0
challenger.nielsenmedia.com	1	0
TOTAL: 3	10	0

Newsgroup request counts (by category):

Category		Count	Pct
1	aioe	2	100.0%
TOTAL: 1		2	100.0%

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

Newsgroup		Count
1	aioe.test.tor	2
TOTAL: 1		2

Daily Usenet report for csiph.com

Apr 4 04:15:00 -- Apr 5 04:15:00