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

Unknown entries from news log file:

First 50 / 9910 lines (0.5%)

Dec 18 05:35:03 csiph nnrpd[15806]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:35:06 csiph nnrpd[15805]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:40:02 csiph nnrpd[16089]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:40:02 csiph nnrpd[16090]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:40:02 csiph nnrpd[16091]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:45:02 csiph nnrpd[16249]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:45:02 csiph nnrpd[16248]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:45:02 csiph nnrpd[16250]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:50:02 csiph nnrpd[16511]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:50:02 csiph nnrpd[16512]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:50:02 csiph nnrpd[16513]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:50:02 csiph nnrpd[16514]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:55:02 csiph nnrpd[16678]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:55:02 csiph nnrpd[16674]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:55:02 csiph nnrpd[16676]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:55:02 csiph nnrpd[16677]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 05:55:02 csiph nnrpd[16675]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:00:02 csiph nnrpd[16956]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:00:02 csiph nnrpd[16954]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:00:02 csiph nnrpd[16955]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:00:02 csiph nnrpd[16953]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:00:02 csiph nnrpd[16957]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:00:02 csiph nnrpd[16958]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:05:03 csiph nnrpd[17176]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:05:03 csiph nnrpd[17173]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:05:03 csiph nnrpd[17172]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:05:03 csiph nnrpd[17178]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:05:03 csiph nnrpd[17177]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:05:03 csiph nnrpd[17175]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:05:03 csiph nnrpd[17174]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17475]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17473]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17476]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17474]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17477]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17478]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17479]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:10:02 csiph nnrpd[17480]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17622]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17620]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17619]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17623]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17621]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17616]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17618]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:15:02 csiph nnrpd[17617]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:20:01 csiph nnrpd[17905]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:20:01 csiph nnrpd[17904]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:20:01 csiph nnrpd[17902]: feeder01.blueworldhosting.com noperm ihave without permission
Dec 18 06:20:01 csiph nnrpd[17903]: feeder01.blueworldhosting.com noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	81583	58.8%	8.0 MB	49.7%
inn	41960	30.2%	6.4 MB	39.9%
innd	13381	9.6%	1.4 MB	8.5%
innfeed	1908	1.4%	315.6 KB	1.9%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 5	138834	100.0%	16.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	236138	49.1%
Negative hits	203698	42.3%
Do not exist	41065	8.5%
Cache misses	265	0.1%
TOTAL: 4	481166	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.481	0.0%	245	0.000	1.963	138.000
article cleanup	00:00:00.270	0.0%	40448	0.000	0.007	0.025
article logging	00:00:00.558	0.0%	40929	0.000	0.014	0.033
article parse	00:00:03.896	0.0%	201051	0.003	0.019	0.124
article write	00:01:06.222	0.1%	26025	0.190	2.545	10.939
artlog/artcncl	00:00:00.001	0.0%	213	0.000	0.005	0.056
artlog/artparse	00:00:00.012	0.0%	852	0.000	0.014	0.500
data move	00:00:00.291	0.0%	622240	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.103	0.0%	242	0.000	0.426	27.000
hishave/artcncl	00:00:00.001	0.0%	245	0.000	0.004	0.056
hishave/artparse	00:00:00.002	0.0%	852	0.000	0.002	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.795	0.0%	482859	0.001	0.006	0.051
history sync	00:00:01.583	0.0%	358	0.000	4.422	49.000
history write	00:00:27.805	0.0%	40443	0.091	0.688	6.294
hiswrite/artcncl	00:00:00.004	0.0%	3	0.000	1.333	4.000
hiswrite/artparse	00:00:02.172	0.0%	852	0.000	2.549	13.000
idle	23:55:14.236	99.6%	620934	60.818	138.685	513.081
nntp read	00:00:02.151	0.0%	604409	0.002	0.004	0.008
overview write	00:01:00.704	0.1%	26025	0.349	2.333	32.759
perl filter	00:01:09.809	0.1%	40443	1.107	1.726	3.043
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.447	0.0%	52050	0.000	0.009	0.030
TOTAL: 24:00:58.899	23:59:13.543	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.073	0.0%	27823	0.000	0.003	0.024
article prepare	00:00:00.000	0.0%	5352	0.000	0.000	0.000
article read	00:00:00.458	0.0%	1004	0.000	0.456	44.000
backlog stats	00:00:00.099	0.0%	278324	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.166	0.0%	217492	0.001	0.005	0.010
data write	00:00:01.470	0.0%	195908	0.000	0.008	0.015
idle	23:53:30.507	100.0%	278324	161.333	309.030	791.901
status file	00:00:00.088	0.0%	193	0.000	0.456	4.000
TOTAL: 23:53:37.945	23:53:33.861	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.562	0.0%	36647	0.035	0.097	0.133
idle	97:28:01.860	99.4%	97892	0.000	3584.377	600292.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.583	0.0%	207287	0.000	0.008	0.712
readart	00:00:51.403	0.0%	95937	0.000	0.536	62.000
TOTAL: 98:00:50.126	97:28:58.408	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	au2pb.net	1	28683	6225	21795	663	21%	24:15:17
2	feeder.eternal-september.org	4	37605	5975	30335	1295	15%	24:34:33
3	xanadu-bbs.net	2	41071	3901	29467	7703	9%	24:02:22
4	news-out.mixmin.net	3	30345	3469	24803	2073	11%	24:08:50
5	newsfeed.neva.ru	146	56183	2770	50454	2959	4%	71:55:55
6	newsfeeds.xmission.com	1	27626	2419	24866	341	8%	25:16:42
7	newsfeed.fu-berlin.de	48	28031	1212	26628	191	4%	30:42:08
8	news.swapon.de	3	2394	155	2232	7	6%	23:27:24
9	nntp.pasdenom.info	4	26881	151	26675	55	0%	23:12:27
10	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 10		238	278819	26277	237255	15287	9%	271:35:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	16.4 MB	15.9 KB	12.1 MB	28.5 MB	57%	4.0 KB
2	au2pb.net	15.2 MB	0.0 KB	2.8 MB	18.0 MB	84%	2.7 KB
3	news-out.mixmin.net	12.5 MB	0.0 KB	3.8 MB	16.3 MB	76%	3.0 KB
4	xanadu-bbs.net	11.7 MB	0.0 KB	147.7 MB	159.3 MB	7%	14.1 KB
5	newsfeed.neva.ru	8.6 MB	21.6 KB	11.4 MB	20.0 MB	42%	3.6 KB
6	newsfeeds.xmission.com	7.4 MB	10.8 KB	1.8 MB	9.2 MB	80%	3.4 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	601.9 KB	3.5 MB	83%	2.6 KB
8	news.swapon.de	415.3 KB	0.0 KB	1.1 MB	1.5 MB	27%	9.4 KB
9	nntp.pasdenom.info	335.6 KB	0.0 KB	467.7 KB	803.3 KB	41%	3.9 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		75.5 MB	48.3 KB	181.7 MB	257.2 MB	29%	6.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:00 - 04:59:59	401	1.5%	0.15	1.2 MB	1.6%	0.44
Dec 18 05:00:00 - 05:59:59	613	2.4%	0.17	1.9 MB	2.6%	0.54
Dec 18 06:00:00 - 06:59:59	601	2.3%	0.17	1.8 MB	2.4%	0.51
Dec 18 07:00:00 - 07:59:59	821	3.2%	0.23	2.7 MB	3.6%	0.76
Dec 18 08:00:00 - 08:59:59	1168	4.5%	0.32	3.3 MB	4.4%	0.93
Dec 18 09:00:00 - 09:59:59	1182	4.5%	0.33	3.6 MB	4.9%	1.03
Dec 18 10:00:00 - 10:59:59	1329	5.1%	0.37	3.4 MB	4.6%	0.98
Dec 18 11:00:00 - 11:59:59	1229	4.7%	0.34	3.3 MB	4.4%	0.94
Dec 18 12:00:00 - 12:59:59	1160	4.5%	0.32	3.3 MB	4.4%	0.92
Dec 18 13:00:00 - 13:59:59	1422	5.5%	0.40	4.3 MB	5.7%	1.22
Dec 18 14:00:00 - 14:59:59	1526	5.9%	0.42	4.2 MB	5.6%	1.20
Dec 18 15:00:00 - 15:59:59	1763	6.8%	0.49	5.0 MB	6.7%	1.43
Dec 18 16:00:00 - 16:59:59	1535	5.9%	0.43	4.6 MB	6.2%	1.32
Dec 18 17:00:00 - 17:59:59	1529	5.9%	0.42	4.7 MB	6.3%	1.34
Dec 18 18:00:00 - 18:59:59	1448	5.6%	0.40	4.2 MB	5.6%	1.19
Dec 18 19:00:00 - 19:59:59	1357	5.2%	0.38	3.6 MB	4.8%	1.02
Dec 18 20:00:00 - 20:59:59	1148	4.4%	0.32	3.0 MB	4.0%	0.84
Dec 18 21:00:00 - 21:59:59	1211	4.7%	0.34	3.4 MB	4.5%	0.96
Dec 18 22:00:00 - 22:59:59	1128	4.3%	0.31	3.1 MB	4.2%	0.88
Dec 18 23:00:00 - 23:59:59	954	3.7%	0.27	2.8 MB	3.7%	0.78
Dec 19 00:00:00 - 00:59:59	767	2.9%	0.21	2.2 MB	2.9%	0.61
Dec 19 01:00:00 - 01:59:59	622	2.4%	0.17	1.8 MB	2.4%	0.50
Dec 19 02:00:00 - 02:59:59	509	2.0%	0.14	1.6 MB	2.2%	0.46
Dec 19 03:00:00 - 03:59:59	454	1.7%	0.13	1.4 MB	1.9%	0.40
Dec 19 04:00:00 - 04:15:00	123	0.5%	0.14	431.0 KB	0.6%	0.48
TOTAL: 24:00:00	26000	100.0%	0.30	74.7 MB	100.0%	0.89

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	7720	663	0	7057
2	newsfeed.neva.ru	2958	1532	2	1424
3	news-out.mixmin.net	2091	7	0	2084
4	feeder.eternal-september.org	1322	226	2	1094
5	au2pb.net	673	625	0	48
6	newsfeed.fu-berlin.de	571	43	0	528
7	newsfeeds.xmission.com	337	319	1	17
8	nntp.pasdenom.info	53	42	0	11
9	news.swapon.de	22	0	0	22
TOTAL: 9		15747	3457	5	12285

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	819
ut.test	208
mailing.freebsd.pkg-fallout	160
lada.talk	157
alt.politics	130
lada.auto	128
mailing.freebsd.ports-bugs	107
mozilla.dev.tree-alerts	64
gmane.linux.alsa.devel	62
mailing.freebsd.svn-src-all	53
fa.freebsd.cvs-all	51
alt.windows7.general	49
lada.commerce	48
mailing.freebsd.svn-ports-all	48
mailing.gcc.gcc-cvs	47
mailing.openbsd.source-changes	47
mailing.freebsd.svn-ports-head	47
mailing.freebsd.svn-src-head	40
mailing.openbsd.ports-changes	38
it-alt.sport.calcio.juventus	35
TOTAL: 258	3457

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	5283
EMP (phn path)	4503
EMP (phl)	774
EMP (phn nph)	164
PFM bot misc	101
Too many newsgroups	53
HTML Multipart	40
Too many newsgroups (low_xpost)	12
Binary Image: misplaced jpg	7
HTML post	6
HTML Image Tags	5
User-issued spam cancel	3
Binary Payload	2
Binary: misplaced nfo	1
Binary: misplaced rar	1
TOTAL: 15	10955

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	746
	feeder.eternal-september.org	63
	au2pb.net	13
	newsfeeds.xmission.com	11
	news-out.mixmin.net	10
	nntp.pasdenom.info	5
	newsfeed.fu-berlin.de	2
	news.swapon.de	2
	TOTAL: 8	852
Including strange strings
	newsfeed.fu-berlin.de	378
	feeder.eternal-september.org	29
	au2pb.net	20
	xanadu-bbs.net	15
	news-out.mixmin.net	15
	news.swapon.de	15
	newsfeeds.xmission.com	4
	newsfeed.neva.ru	2
	TOTAL: 8	478
TOTAL: 2		1330

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	30115	221	25104	533	1873	0%	23:59:57
2	xanadu-bbs	23396	105	21905	77	1	0%	23:59:56
3	au2pb	20538	100	19568	153	0	0%	23:59:52
4	xmission	27937	31	23338	1	1	0%	23:59:55
5	fu-berlin.de	24466	15	24435	16	0	0%	23:59:57
6	news.mixmin.net	23444	10	22474	0	0	0%	23:59:57
7	neva.ru	24361	8	22910	56	0	0%	23:59:38
8	eternal-september	20728	1	19364	2	0	0%	23:59:57
9	news.swapon.de	2298	0	2253	8	0	0%	23:51:12
TOTAL: 9		197283	491	181351	846	1875	0%	215:50:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	837.0 KB	1.2 MB	2.0 MB	2.7 KB	23:59:57
2	xanadu-bbs	610.2 KB	134.9 KB	745.1 KB	4.1 KB	23:59:56
3	au2pb	560.5 KB	321.2 KB	881.7 KB	3.5 KB	23:59:52
4	xmission	114.9 KB	1.9 KB	116.8 KB	3.6 KB	23:59:55
5	news.mixmin.net	96.1 KB	0.0 KB	96.1 KB	9.6 KB	23:59:57
6	fu-berlin.de	77.1 KB	22.5 KB	99.5 KB	3.2 KB	23:59:57
7	neva.ru	41.6 KB	237.9 KB	279.5 KB	4.4 KB	23:59:38
8	eternal-september	3.6 KB	2.9 KB	6.4 KB	2.1 KB	23:59:57
9	news.swapon.de	0.0 KB	16.5 KB	16.5 KB	2.1 KB	23:51:12
TOTAL: 9		2.3 MB	1.9 MB	4.2 MB	3.2 KB	215:50:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	93	237.7 KB	83	04:17:42
2	tempe0.bbox.io	20	3	5.0 KB	2	05:18:07
3	71.94.21.184	6	3	10.0 KB	3	00:00:54
4	dhcp-089-099-203-254.chello.nl	24	3	3.8 KB	2	11:32:19
TOTAL: 4		338	102	256.5 KB	90	97:59:33

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	323	96	247.7 KB	86	78:21:51
2	*.bbox.io	20	3	5.0 KB	2	05:18:07
3	*.chello.nl	24	3	3.8 KB	2	11:32:19
4	*.tampfl.dsl-w.verizon.net	4	0	0.0 KB	0	02:00:01
5	*.blueworldhosting.com	9910	0	0.0 KB	0	00:47:13
TOTAL: 5		10281	102	256.5 KB	90	97:59:33

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
162.212.88.221	1.500	1.720	0.000	74:03:12
dhcp-089-099-203-254.chello.nl	2.157	0.353	0.000	11:32:19
tempe0.bbox.io	1.856	0.376	0.000	05:18:07
202.89.176.220	23.676	5.900	0.612	04:17:42
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.255	0.067	0.000	02:00:01
feeder01.blueworldhosting.com	808.473	184.233	0.000	00:47:13
71.94.21.184	2.813	3.271	0.000	00:00:54
69.160.37.54	1.909	0.393	0.000	00:00:02
TOTAL: 8	842.639	196.313	0.612	97:59:33

Curious NNRP server explorers [Top 20]:

System	Conn
feeder01.blueworldhosting.com	9910
69.160.37.54	25
162.212.88.221	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4
TOTAL: 4	9943

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	21	0
tempe0.bbox.io	8	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	0
71.94.21.184	2	2
TOTAL: 4	35	2

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	97	95.1%
2	alt	3	2.9%
3	news	2	2.0%
TOTAL: 3		102	100.0%

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

Newsgroup		Count
1	comp.os.vms	47
2	comp.arch	18
3	comp.sys.ibm.pc.games.action	8
4	comp.arch.embedded	7
5	comp.sys.ibm.ps2.hardware	5
6	comp.sys.ibm.pc.games.war-historical	4
7	alt.test	3
8	news.admin.peering	2
9	comp.windows.x.kde	2
10	comp.sys.ibm.pc.games.naval	1
11	comp.sys.ibm.as400.misc	1
12	comp.ai.philosophy	1
13	comp.ai.neural-nets	1
14	comp.os.os2.misc	1
15	comp.unix.aix	1
TOTAL: 15		102

Daily Usenet report for csiph.com

Dec 18 04:15:00 -- Dec 19 04:15:00