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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	46650	44.4%	9.3 MB	62.2%
nnrpd	33806	32.2%	2.8 MB	19.0%
innd	21208	20.2%	2.3 MB	15.1%
innfeed	3189	3.0%	557.4 KB	3.6%
nocem	106	0.1%	10.9 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	104960	100.0%	14.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	543036	56.5%
Negative hits	372173	38.7%
Do not exist	45728	4.8%
Cache misses	559	0.1%
TOTAL: 4	961496	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.612	0.0%	384	0.000	1.594	34.000
article cleanup	00:00:00.258	0.0%	45280	0.000	0.006	0.021
article logging	00:00:00.584	0.0%	45899	0.000	0.013	0.042
article parse	00:00:02.348	0.0%	184730	0.001	0.013	0.090
article write	00:01:42.476	0.1%	28077	0.188	3.650	14.541
artlog/artcncl	00:00:00.008	0.0%	259	0.000	0.031	1.000
artlog/artparse	00:00:00.007	0.0%	491	0.000	0.014	0.250
data move	00:00:00.323	0.0%	949874	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.057	0.0%	331	0.000	0.172	15.000
hishave/artcncl	00:00:00.025	0.0%	384	0.000	0.065	16.000
hishave/artparse	00:00:00.001	0.0%	379	0.000	0.003	0.083
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:15.616	0.0%	962134	0.001	0.016	0.126
history sync	00:00:03.258	0.0%	354	0.000	9.203	115.333
history write	00:01:20.857	0.1%	45317	0.082	1.784	11.728
hiswrite/artcncl	00:00:00.252	0.0%	53	0.000	4.755	6.000
hiswrite/artparse	00:00:00.775	0.0%	379	0.000	2.045	20.000
idle	23:53:10.152	99.5%	959748	54.553	89.597	226.600
nntp read	00:00:03.076	0.0%	969418	0.001	0.003	0.005
overview write	00:01:20.533	0.1%	28077	0.537	2.868	18.369
perl filter	00:01:51.930	0.1%	45173	1.672	2.478	4.105
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.523	0.0%	56255	0.000	0.009	0.024
TOTAL: 24:00:54.439	23:59:53.671	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.084	0.0%	28128	0.000	0.003	0.028
article prepare	00:00:00.005	0.0%	28436	0.000	0.000	0.005
article read	00:00:00.482	0.0%	3154	0.000	0.153	7.143
backlog stats	00:00:00.312	0.0%	653831	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.490	0.0%	480067	0.002	0.005	0.011
data write	00:00:03.207	0.0%	461485	0.003	0.007	0.012
idle	23:53:38.814	100.0%	653831	69.535	131.561	341.561
status file	00:00:00.212	0.0%	278	0.000	0.763	4.000
TOTAL: 23:53:55.280	23:53:45.606	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.608	0.0%	38662	0.000	0.042	2.360
idle	99:45:16.603	99.8%	71496	0.000	5022.891	600218.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.780	0.0%	144115	0.000	0.005	0.500
readart	00:00:00.150	0.0%	113	0.000	1.327	66.000
TOTAL: 99:55:41.680	99:45:19.141	99.8%	-	-	-	-

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	33325	6139	25050	2136	18%	23:44:55
2	feeder.eternal-september.org	5	41066	4216	35614	1236	10%	25:07:15
3	csiph-out.feeder.erje.net	1152	104113	3472	98894	1747	3%	94:57:19
4	csiph-out.news.weretis.net	4	58092	2942	54818	332	5%	44:42:57
5	news-out.aioe.org	4	27555	2875	24621	59	10%	24:19:40
6	newsfeed.neva.ru	145	56599	2527	48726	5346	4%	71:58:43
7	news-out.mixmin.net	3	32005	2437	27554	2014	7%	24:08:49
8	newsfeed.fu-berlin.de	61	30840	1344	29215	281	4%	34:01:51
9	news.fcku.it	1	8906	433	8470	3	4%	23:11:16
10	news.redatomik.org	1	25731	390	25331	10	1%	23:17:53
11	newsfeeds.xmission.com	0	26089	389	25684	16	1%	20:59:12
12	xanadu-bbs.net	2	43748	299	39228	4221	0%	24:35:14
13	news.swapon.de	4	2533	157	2367	9	6%	24:37:22
14	news.freedyn.net	2	5739	138	5550	51	2%	06:32:55
15	au2pb.net	1	30490	86	30386	18	0%	24:24:16
16	nntp.pasdenom.info	2	28901	13	28885	3	0%	23:59:31
17	news.bbs.geek.nz	2	26811	2	26808	1	0%	23:59:44
18	localhost	32	0	0	0	0	0%	00:00:01
TOTAL: 18		1709	582543	27859	537201	17483	4%	538:38:53

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	17.5 MB	0.0 KB	22.2 MB	39.8 MB	44%	4.9 KB
2	csiph-out.feeder.erje.net	12.7 MB	25.9 KB	5.2 MB	17.9 MB	70%	3.5 KB
3	feeder.eternal-september.org	10.3 MB	2.6 KB	14.4 MB	24.7 MB	41%	4.6 KB
4	news-out.mixmin.net	8.7 MB	20.6 KB	3.9 MB	12.6 MB	69%	2.9 KB
5	csiph-out.news.weretis.net	8.1 MB	1.8 KB	6.4 MB	14.4 MB	55%	4.5 KB
6	newsfeed.neva.ru	7.1 MB	1.1 MB	33.3 MB	41.5 MB	17%	5.4 KB
7	news-out.aioe.org	7.1 MB	0.0 KB	76.8 KB	7.2 MB	98%	2.5 KB
8	newsfeed.fu-berlin.de	2.8 MB	1.8 KB	504.1 KB	3.3 MB	85%	2.1 KB
9	newsfeeds.xmission.com	1.2 MB	0.0 KB	478.5 KB	1.6 MB	71%	4.1 KB
10	news.redatomik.org	972.7 KB	1.8 KB	141.0 KB	1.1 MB	87%	2.8 KB
11	xanadu-bbs.net	736.8 KB	0.0 KB	49.2 MB	49.9 MB	1%	11.3 KB
12	news.fcku.it	684.6 KB	0.0 KB	139.5 KB	824.1 KB	83%	1.9 KB
13	news.freedyn.net	555.6 KB	1.6 KB	155.9 KB	713.1 KB	77%	3.8 KB
14	news.swapon.de	417.8 KB	0.0 KB	21.1 KB	438.9 KB	95%	2.6 KB
15	au2pb.net	211.5 KB	3.6 KB	241.8 KB	457.0 KB	46%	4.4 KB
16	nntp.pasdenom.info	24.3 KB	0.0 KB	51.4 KB	75.7 KB	32%	4.7 KB
17	news.bbs.geek.nz	2.9 KB	1.8 KB	1.8 KB	6.5 KB	44%	2.2 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		79.1 MB	1.1 MB	136.3 MB	216.5 MB	36%	4.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 18 04:15:00 - 04:59:59	494	1.8%	0.18	1.8 MB	2.2%	0.67
Jan 18 05:00:00 - 05:59:59	562	2.0%	0.16	1.9 MB	2.4%	0.55
Jan 18 06:00:00 - 06:59:59	551	2.0%	0.15	2.1 MB	2.6%	0.60
Jan 18 07:00:00 - 07:59:59	812	2.9%	0.23	2.4 MB	3.0%	0.69
Jan 18 08:00:00 - 08:59:59	1211	4.3%	0.34	3.4 MB	4.3%	0.98
Jan 18 09:00:00 - 09:59:59	1367	4.9%	0.38	3.6 MB	4.6%	1.04
Jan 18 10:00:00 - 10:59:59	1340	4.8%	0.37	3.4 MB	4.2%	0.96
Jan 18 11:00:00 - 11:59:59	1299	4.6%	0.36	3.2 MB	4.0%	0.91
Jan 18 12:00:00 - 12:59:59	1299	4.6%	0.36	3.8 MB	4.8%	1.09
Jan 18 13:00:00 - 13:59:59	1363	4.9%	0.38	3.5 MB	4.4%	0.99
Jan 18 14:00:00 - 14:59:59	1277	4.5%	0.35	3.5 MB	4.3%	0.99
Jan 18 15:00:00 - 15:59:59	1589	5.7%	0.44	4.7 MB	5.8%	1.33
Jan 18 16:00:00 - 16:59:59	1571	5.6%	0.44	4.5 MB	5.6%	1.28
Jan 18 17:00:00 - 17:59:59	1664	5.9%	0.46	4.2 MB	5.3%	1.21
Jan 18 18:00:00 - 18:59:59	1655	5.9%	0.46	5.3 MB	6.7%	1.52
Jan 18 19:00:00 - 19:59:59	1729	6.2%	0.48	4.6 MB	5.7%	1.30
Jan 18 20:00:00 - 20:59:59	1632	5.8%	0.45	4.6 MB	5.7%	1.30
Jan 18 21:00:00 - 21:59:59	1586	5.6%	0.44	4.0 MB	5.0%	1.13
Jan 18 22:00:00 - 22:59:59	1236	4.4%	0.34	3.5 MB	4.3%	0.98
Jan 18 23:00:00 - 23:59:59	972	3.5%	0.27	2.8 MB	3.5%	0.80
Jan 19 00:00:00 - 00:59:59	817	2.9%	0.23	2.4 MB	3.0%	0.69
Jan 19 01:00:00 - 01:59:59	794	2.8%	0.22	2.5 MB	3.1%	0.72
Jan 19 02:00:00 - 02:59:59	545	1.9%	0.15	1.7 MB	2.2%	0.50
Jan 19 03:00:00 - 03:59:59	552	2.0%	0.15	1.9 MB	2.4%	0.54
Jan 19 04:00:00 - 04:15:00	157	0.6%	0.17	498.2 KB	0.6%	0.55
TOTAL: 24:00:00	28074	100.0%	0.32	79.9 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	5373	1310	55	0	4008
2	xanadu-bbs.net	4315	20	0	0	4295
3	usenet.blueworldhosting.com	2148	788	0	0	1360
4	news-out.mixmin.net	2027	6	8	0	2013
5	csiph-out.feeder.erje.net	1758	1113	8	1	636
6	feeder.eternal-september.org	1281	153	2	0	1126
7	newsfeed.fu-berlin.de	788	55	0	0	733
8	csiph-out.news.weretis.net	409	200	0	0	209
9	news-out.aioe.org	70	52	0	0	18
10	news.freedyn.net	50	0	1	0	49
11	news.swapon.de	43	0	0	0	43
12	newsfeeds.xmission.com	17	5	0	0	12
13	au2pb.net	17	13	1	0	3
14	news.redatomik.org	12	0	0	0	12
15	news.fcku.it	6	0	0	0	6
16	nntp.pasdenom.info	3	1	0	0	2
TOTAL: 16		18317	3716	75	1	14525

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	622
lada.talk	222
ut.test	212
alt.biology	135
mailing.freebsd.ports-bugs	122
alt.survival	95
mailing.freebsd.svn-ports-all	91
alt.binaries.multimedia.tatu	90
mailing.freebsd.svn-ports-head	89
mozilla.dev.tree-alerts	84
mailing.freebsd.pkg-fallout	78
lada.auto	69
mailing.freebsd.svn-src-all	69
fa.freebsd.cvs-all	69
mozilla.general	68
mailing.openbsd.ports-changes	57
it-alt.sport.calcio.juventus	55
mailing.gcc.gcc-cvs	53
gmane.linux.alsa.devel	44
mailing.freebsd.svn-src-head	43
TOTAL: 278	3716

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)	9519
EMP (phn path)	2213
EMP (phl)	857
PFM bot misc	244
EMP (phn nph)	154
User-issued cancel	94
Too many newsgroups	63
HTML Multipart	39
Scoring filter (21)	23
Scoring filter (16)	19
Binary Payload	19
Scoring filter (20)	18
HTML Image Tags	13
Scoring filter (18)	10
Scoring filter (10)	10
Scoring filter (8)	10
Scoring filter (13)	8
Scoring filter (15)	6
Scoring filter (22)	6
Scoring filter (11)	5
TOTAL: 48	13386

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	101	0	163	163
TOTAL: 1	101	0	163	163

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	25
	usenet.blueworldhosting.com	7
	TOTAL: 2	32
Huge articles
	xanadu-bbs.net	105
	usenet.blueworldhosting.com	95
	csiph-out.news.weretis.net	79
	feeder.eternal-september.org	66
	news-out.mixmin.net	15
	newsfeeds.xmission.com	6
	news.redatomik.org	3
	news.fcku.it	3
	au2pb.net	2
	news.freedyn.net	1
	TOTAL: 11	376
Including strange strings
	newsfeed.fu-berlin.de	483
	news.swapon.de	34
	feeder.eternal-september.org	28
	csiph-out.news.weretis.net	27
	news-out.aioe.org	15
	csiph-out.feeder.erje.net	12
	news-out.mixmin.net	9
	newsfeed.neva.ru	5
	news.fcku.it	3
	usenet.blueworldhosting.com	1
	TOTAL: 12	619
No colon-space in header
	csiph-out.feeder.erje.net	112
	TOTAL: 1	112
TOTAL: 4		1139

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.freedyn.net	32869	1327	26611	6	0	0	4%	23:59:55
2	news.bbs.geek.nz	52217	1114	26070	18	1	0	2%	23:59:55
3	xanadu-bbs	30749	1031	26736	3	0	15	3%	23:59:55
4	fu-berlin.de	26398	791	25607	0	0	0	2%	23:59:45
5	aioe	26029	317	22580	16	1	0	1%	23:59:53
6	usenet.pasdenom.info	35500	224	26572	1267	0	5	0%	23:59:55
7	news.redatomik.org	31887	206	26422	13	0	99	0%	23:59:55
8	au2pb	28213	154	26957	10	0	0	0%	23:59:55
9	xmission	30470	73	26530	5	0	0	0%	23:59:55
10	weretis.net	23945	33	23209	5	0	0	0%	23:59:54
11	neva.ru	29554	28	24182	170	0	0	0%	23:59:55
12	news.mixmin.net	27725	23	24424	0	0	0	0%	23:59:55
13	erje	26841	21	24304	226	0	0	0%	23:59:53
14	eternal-september	27539	19	22492	0	0	0	0%	23:59:37
15	news.fcku.it	4352	10	3688	0	0	0	0%	22:54:07
16	news.swapon.de	2661	2	2357	10	0	0	0%	23:58:38
17	bwh	22264	1	20864	0	0	0	0%	23:59:55
TOTAL: 17		459213	5374	379605	1749	2	119	1%	406:50:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.6 MB	27.9 KB	7.7 MB	0.1 KB/s	5.9 KB	23:59:55
2	xanadu-bbs	7.0 MB	20.0 KB	7.0 MB	0.1 KB/s	6.9 KB	23:59:55
3	fu-berlin.de	6.1 MB	0.0 KB	6.1 MB	0.1 KB/s	7.9 KB	23:59:45
4	news.bbs.geek.nz	3.0 MB	79.8 KB	3.1 MB	0.0 KB/s	2.8 KB	23:59:55
5	aioe	957.0 KB	51.6 KB	1008.6 KB	0.0 KB/s	3.0 KB	23:59:53
6	usenet.pasdenom.info	838.7 KB	7.0 MB	7.8 MB	0.1 KB/s	5.4 KB	23:59:55
7	news.redatomik.org	641.0 KB	54.5 KB	695.4 KB	0.0 KB/s	3.2 KB	23:59:55
8	au2pb	629.6 KB	70.4 KB	700.0 KB	0.0 KB/s	4.3 KB	23:59:55
9	xmission	220.1 KB	9.3 KB	229.4 KB	0.0 KB/s	2.9 KB	23:59:55
10	news.mixmin.net	103.7 KB	0.0 KB	103.7 KB	0.0 KB/s	4.5 KB	23:59:55
11	neva.ru	98.0 KB	500.5 KB	598.4 KB	0.0 KB/s	3.0 KB	23:59:55
12	weretis.net	95.1 KB	86.1 KB	181.2 KB	0.0 KB/s	4.8 KB	23:59:54
13	eternal-september	77.2 KB	0.0 KB	77.2 KB	0.0 KB/s	4.1 KB	23:59:37
14	erje	44.2 KB	883.8 KB	928.0 KB	0.0 KB/s	3.8 KB	23:59:53
15	news.fcku.it	16.4 KB	0.0 KB	16.4 KB	0.0 KB/s	1.6 KB	22:54:07
16	news.swapon.de	2.5 KB	21.1 KB	23.5 KB	0.0 KB/s	2.0 KB	23:58:38
17	bwh	1.3 KB	0.0 KB	1.3 KB	0.0 KB/s	1.3 KB	23:59:55
TOTAL: 17		27.4 MB	8.7 MB	36.1 MB	0.0 KB/s	5.2 KB	406:50:57

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	283	101	297.9 KB	93	05:57:20
2	tempe0.bbox.io	69	11	15.8 KB	5	11:11:13
3	dhcp-089-099-203-254.chello.nl	15	1	1.7 KB	1	06:05:08
TOTAL: 3		367	113	315.4 KB	99	99:55:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	388	101	297.9 KB	93	31:01:32
2	*.bbox.io	69	11	15.8 KB	5	11:11:13
3	*.chello.nl	15	1	1.7 KB	1	06:05:08
4	*.146.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	43:26:44
5	*.dynamic.caiway.nl	8	0	0.0 KB	0	04:01:21
6	*.tampfl.dsl-w.verizon.net	9	0	0.0 KB	0	04:09:36
TOTAL: 6		490	113	315.4 KB	99	99:55:36

NNRP auth users [Top 20]:

User	Conn
moussa	283
TOTAL: 1	283

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
h229.146.22.98.dynamic.ip.windstream.net	0.791	1.336	0.000	43:26:44
63.142.120.8	8.134	1.907	0.000	16:59:32
tempe0.bbox.io	5.555	1.273	0.000	11:11:13
162.212.88.221	0.414	0.192	0.000	08:04:40
dhcp-089-099-203-254.chello.nl	1.290	0.232	0.000	06:05:08
202.89.176.220	29.348	6.627	0.854	05:57:20
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.802	0.155	0.000	04:09:36
041-020-158-163.dynamic.caiway.nl	0.703	0.206	0.000	04:01:21
TOTAL: 8	47.037	11.928	0.854	99:55:36

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	100
pool-71-98-160-84.tampfl.dsl-w.verizon.net	9
041-020-158-163.dynamic.caiway.nl	8
162.212.88.221	5
h229.146.22.98.dynamic.ip.windstream.net	1
TOTAL: 5	123

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	17	0
dhcp-089-099-203-254.chello.nl	11	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	8	0
041-020-158-163.dynamic.caiway.nl	8	0
h229.146.22.98.dynamic.ip.windstream.net	1	1
TOTAL: 5	45	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	107	94.7%
2	news	6	5.3%
TOTAL: 2		113	100.0%

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

Newsgroup		Count
1	comp.os.vms	36
2	comp.arch	30
3	comp.sys.ibm.pc.games.action	9
4	comp.arch.embedded	8
5	comp.sys.ibm.ps2.hardware	7
6	news.software.nntp	6
7	comp.arch.fpga	4
8	comp.ai.philosophy	3
9	comp.archives.ms-windows.discuss	2
10	comp.os.msdos.programmer	2
11	comp.sys.ibm.pc.hardware.storage	2
12	comp.os.cpm	1
13	comp.os.msdos.djgpp	1
14	comp.answers	1
15	comp.os.msdos.misc	1
TOTAL: 15		113

Daily Usenet report for csiph.com

Jan 18 04:15:00 -- Jan 19 04:15:00