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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 16 02:22:50 csiph nnrpd[31717]: cpc73515-shef13-2-0-cust748.17-1.cable.virginm.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	46198	45.1%	9.5 MB	63.5%
nnrpd	33189	32.4%	2.8 MB	19.0%
innd	19629	19.2%	2.1 MB	14.1%
innfeed	3207	3.1%	509.2 KB	3.3%
nocem	143	0.1%	14.8 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	102367	100.0%	14.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	566472	57.3%
Negative hits	375948	38.0%
Do not exist	45244	4.6%
Cache misses	1185	0.1%
TOTAL: 4	988849	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.570	0.0%	338	0.000	1.686	30.000
article cleanup	00:00:00.275	0.0%	44925	0.000	0.006	0.020
article logging	00:00:00.773	0.0%	45456	0.000	0.017	0.140
article parse	00:00:02.675	0.0%	171972	0.001	0.016	0.119
article write	00:01:25.930	0.1%	29674	0.274	2.896	8.580
artlog/artcncl	00:00:00.005	0.0%	251	0.000	0.020	0.500
artlog/artparse	00:00:00.004	0.0%	494	0.000	0.008	0.250
data move	00:00:00.317	0.0%	966229	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.135	0.0%	334	0.000	0.404	23.000
hishave/artcncl	00:00:00.022	0.0%	338	0.000	0.065	1.750
hishave/artparse	00:00:00.000	0.0%	483	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:37.249	0.0%	991498	0.002	0.038	0.304
history sync	00:00:03.081	0.0%	342	0.000	9.009	67.500
history write	00:02:14.031	0.2%	44901	0.090	2.985	12.058
hiswrite/artcncl	00:00:00.003	0.0%	4	0.000	0.750	3.000
hiswrite/artparse	00:00:01.128	0.0%	483	0.000	2.335	12.571
idle	23:51:35.055	99.4%	969769	55.360	88.573	207.395
nntp read	00:00:03.304	0.0%	983909	0.002	0.003	0.006
overview write	00:01:51.305	0.1%	29674	0.970	3.751	20.910
perl filter	00:01:44.511	0.1%	44890	1.630	2.328	4.084
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.523	0.0%	59486	0.000	0.009	0.028
TOTAL: 24:00:41.888	23:59:40.896	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.078	0.0%	32734	0.000	0.002	0.020
article prepare	00:00:00.007	0.0%	31422	0.000	0.000	0.016
article read	00:00:00.517	0.0%	3333	0.000	0.155	14.667
backlog stats	00:00:00.323	0.0%	677600	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.469	0.0%	497976	0.002	0.005	0.009
data write	00:00:03.254	0.0%	481032	0.001	0.007	0.013
idle	23:52:32.763	100.0%	677600	72.749	126.849	470.384
status file	00:00:00.199	0.0%	292	0.000	0.682	1.000
TOTAL: 23:52:52.409	23:52:39.610	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:04.711	0.0%	5900	0.018	0.798	1.041
idle	39:21:19.914	99.9%	38656	175.333	3665.147	600221.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.474	0.0%	78248	0.000	0.006	0.100
readart	00:00:00.223	0.0%	80	0.000	2.788	49.000
TOTAL: 39:23:03.019	39:21:25.322	99.9%	-	-	-	-

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	34249	6528	25597	2124	19%	23:44:55
2	csiph-out.feeder.erje.net	1153	107239	4993	101026	1220	4%	94:58:37
3	feeder.eternal-september.org	5	40641	4024	35389	1228	9%	25:17:26
4	news-out.mixmin.net	3	33903	2964	28881	2058	8%	24:11:54
5	news-out.aioe.org	4	29047	2846	26154	47	9%	24:12:45
6	csiph-out.news.weretis.net	4	61117	2496	58244	377	4%	46:19:10
7	newsfeed.neva.ru	131	55549	2468	48725	4356	4%	67:08:47
8	newsfeed.fu-berlin.de	70	29654	1128	28235	291	3%	34:05:27
9	xanadu-bbs.net	1	44512	527	40108	3877	1%	24:35:59
10	news.fcku.it	1	10897	469	10428	0	4%	27:33:24
11	newsfeeds.xmission.com	1	30663	459	30186	18	1%	25:24:50
12	news.redatomik.org	1	28126	445	27645	36	1%	23:17:53
13	news.freedyn.net	7	13869	174	13577	118	1%	11:38:41
14	news.swapon.de	3	2136	80	2055	1	3%	24:19:35
15	au2pb.net	1	31641	66	31546	29	0%	24:24:16
16	nntp.pasdenom.info	3	29604	17	29575	12	0%	24:08:36
17	news.bbs.geek.nz	1	29555	2	29553	0	0%	23:59:46
18	localhost	32	0	0	0	0	0%	00:00:00
TOTAL: 18		1709	612402	29686	566924	15792	4%	549:22:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	19.9 MB	56.4 KB	3.3 MB	23.3 MB	85%	3.8 KB
2	usenet.blueworldhosting.com	18.6 MB	0.0 KB	19.4 MB	38.1 MB	48%	4.5 KB
3	feeder.eternal-september.org	10.3 MB	20.8 KB	13.3 MB	23.6 MB	43%	4.6 KB
4	news-out.mixmin.net	9.8 MB	0.0 KB	3.5 MB	13.4 MB	73%	2.7 KB
5	newsfeed.neva.ru	7.4 MB	88.7 KB	20.8 MB	28.2 MB	26%	4.2 KB
6	csiph-out.news.weretis.net	7.4 MB	0.0 KB	5.8 MB	13.2 MB	56%	4.7 KB
7	news-out.aioe.org	7.2 MB	0.0 KB	113.8 KB	7.3 MB	98%	2.6 KB
8	newsfeed.fu-berlin.de	2.6 MB	0.0 KB	510.1 KB	3.1 MB	83%	2.2 KB
9	newsfeeds.xmission.com	1.4 MB	0.0 KB	173.5 KB	1.5 MB	88%	3.3 KB
10	xanadu-bbs.net	1.3 MB	0.0 KB	47.2 MB	48.5 MB	2%	11.3 KB
11	news.redatomik.org	1.2 MB	0.0 KB	135.1 KB	1.4 MB	90%	2.9 KB
12	news.fcku.it	776.7 KB	0.0 KB	0.0 KB	776.7 KB	100%	1.7 KB
13	news.freedyn.net	700.6 KB	0.0 KB	439.4 KB	1.1 MB	61%	3.9 KB
14	au2pb.net	349.0 KB	17.5 KB	253.2 KB	619.7 KB	56%	6.5 KB
15	news.swapon.de	225.1 KB	0.0 KB	38.4 KB	263.5 KB	85%	3.3 KB
16	nntp.pasdenom.info	29.9 KB	0.0 KB	198.6 KB	228.5 KB	13%	7.9 KB
17	news.bbs.geek.nz	2.3 KB	0.0 KB	0.0 KB	2.3 KB	100%	1.2 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		89.1 MB	183.4 KB	115.1 MB	204.4 MB	43%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:00 - 04:59:59	333	1.1%	0.12	1017.9 KB	1.1%	0.38
Jan 15 05:00:00 - 05:59:59	656	2.2%	0.18	2.4 MB	2.7%	0.68
Jan 15 06:00:00 - 06:59:59	625	2.1%	0.17	2.4 MB	2.7%	0.68
Jan 15 07:00:00 - 07:59:59	833	2.8%	0.23	2.8 MB	3.2%	0.81
Jan 15 08:00:00 - 08:59:59	1185	4.0%	0.33	3.3 MB	3.7%	0.94
Jan 15 09:00:00 - 09:59:59	1271	4.3%	0.35	3.1 MB	3.5%	0.89
Jan 15 10:00:00 - 10:59:59	1313	4.4%	0.36	3.6 MB	4.1%	1.04
Jan 15 11:00:00 - 11:59:59	1562	5.3%	0.43	4.4 MB	4.9%	1.24
Jan 15 12:00:00 - 12:59:59	1405	4.7%	0.39	3.8 MB	4.3%	1.09
Jan 15 13:00:00 - 13:59:59	1476	5.0%	0.41	3.9 MB	4.4%	1.12
Jan 15 14:00:00 - 14:59:59	1665	5.6%	0.46	4.6 MB	5.2%	1.31
Jan 15 15:00:00 - 15:59:59	1749	5.9%	0.49	5.6 MB	6.3%	1.60
Jan 15 16:00:00 - 16:59:59	1802	6.1%	0.50	5.7 MB	6.4%	1.61
Jan 15 17:00:00 - 17:59:59	1735	5.8%	0.48	5.0 MB	5.6%	1.42
Jan 15 18:00:00 - 18:59:59	1552	5.2%	0.43	4.5 MB	5.1%	1.29
Jan 15 19:00:00 - 19:59:59	1733	5.8%	0.48	5.7 MB	6.4%	1.63
Jan 15 20:00:00 - 20:59:59	1448	4.9%	0.40	4.5 MB	5.1%	1.28
Jan 15 21:00:00 - 21:59:59	1759	5.9%	0.49	5.0 MB	5.6%	1.41
Jan 15 22:00:00 - 22:59:59	1325	4.5%	0.37	4.2 MB	4.7%	1.19
Jan 15 23:00:00 - 23:59:59	953	3.2%	0.26	2.8 MB	3.2%	0.81
Jan 16 00:00:00 - 00:59:59	985	3.3%	0.27	2.8 MB	3.2%	0.80
Jan 16 01:00:00 - 01:59:59	951	3.2%	0.26	3.5 MB	3.9%	0.99
Jan 16 02:00:00 - 02:59:59	591	2.0%	0.16	1.9 MB	2.1%	0.54
Jan 16 03:00:00 - 03:59:59	629	2.1%	0.17	2.0 MB	2.3%	0.58
Jan 16 04:00:00 - 04:15:00	126	0.4%	0.14	450.2 KB	0.5%	0.50
TOTAL: 24:00:00	29662	100.0%	0.34	89.1 MB	100.0%	1.06

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	4399	1498	14	0	2887
2	xanadu-bbs.net	3781	22	0	0	3759
3	usenet.blueworldhosting.com	2122	978	0	0	1144
4	news-out.mixmin.net	2074	2	0	0	2072
5	feeder.eternal-september.org	1287	137	2	0	1148
6	csiph-out.feeder.erje.net	1229	556	17	2	654
7	newsfeed.fu-berlin.de	696	43	0	0	653
8	csiph-out.news.weretis.net	401	214	0	0	187
9	news.freedyn.net	119	0	0	0	119
10	news-out.aioe.org	72	43	0	0	29
11	au2pb.net	29	22	1	0	6
12	news.redatomik.org	26	0	1	0	25
13	news.swapon.de	18	0	0	0	18
14	newsfeeds.xmission.com	17	15	0	0	2
15	nntp.pasdenom.info	12	8	0	0	4
16	news.fcku.it	3	0	0	0	3
TOTAL: 16		16285	3538	35	2	12710

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	979
ut.test	179
lada.talk	144
mozilla.dev.tree-alerts	120
mozilla.general	76
mailing.freebsd.svn-ports-all	68
mailing.freebsd.ports-bugs	66
mailing.freebsd.svn-ports-head	66
mailing.gcc.gcc-cvs	64
alt.survival	60
lada.auto	57
alt.binaries.multimedia.tatu	56
mailing.freebsd.svn-src-all	53
fa.freebsd.cvs-all	52
lada.politics	49
mailing.openbsd.source-changes	49
mailing.freebsd.svn-src-head	43
mailing.openssl.dev	32
alt.windows7.general	31
pld.cvs.commit	31
TOTAL: 253	3538

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

Groups	Count
uk.radio.amateur.moderated	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8080
EMP (phn path)	1705
EMP (phl)	929
EMP (phn nph)	228
PFM bot misc	222
User-issued cancel	79
Scoring filter (21)	62
Too many newsgroups	49
Scoring filter (18)	48
HTML Multipart	40
Scoring filter (17)	29
Scoring filter (19)	25
Scoring filter (16)	20
Scoring filter (14)	17
Scoring filter (13)	14
Scoring filter (26)	14
Scoring filter (11)	14
Scoring filter (12)	12
Scoring filter (8)	12
Scoring filter (15)	11
TOTAL: 38	11688

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	138	0	141	141
TOTAL: 1	138	0	141	141

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	245
	usenet.blueworldhosting.com	105
	feeder.eternal-september.org	77
	csiph-out.news.weretis.net	37
	news-out.mixmin.net	7
	news.freedyn.net	3
	nntp.pasdenom.info	3
	news.redatomik.org	2
	newsfeeds.xmission.com	1
	au2pb.net	1
	TOTAL: 10	481
Including strange strings
	newsfeed.fu-berlin.de	421
	news-out.aioe.org	19
	csiph-out.news.weretis.net	18
	news-out.mixmin.net	18
	news.swapon.de	17
	feeder.eternal-september.org	16
	csiph-out.feeder.erje.net	11
	newsfeed.neva.ru	5
	news.fcku.it	3
	newsfeeds.xmission.com	1
	TOTAL: 11	530
No colon-space in header
	usenet.blueworldhosting.com	3
	TOTAL: 1	3
TOTAL: 3		1014

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.freedyn.net	34449	1654	27831	4	0	49	4%	23:59:52
2	xanadu-bbs	32080	1368	27762	10	0	13	4%	23:59:53
3	fu-berlin.de	28042	982	27060	0	0	0	3%	23:59:39
4	news.bbs.geek.nz	54567	916	27512	16	1	0	1%	23:59:51
5	aioe	27311	458	23452	10	0	0	1%	23:59:47
6	usenet.pasdenom.info	37581	204	27835	1606	0	9	0%	23:59:52
7	au2pb	29504	129	28242	14	0	21	0%	23:59:53
8	news.redatomik.org	34287	96	27920	2	0	0	0%	23:59:52
9	xmission	32679	27	28184	10	0	0	0%	23:59:53
10	news.mixmin.net	29280	13	25066	1	0	0	0%	23:59:52
11	news.fcku.it	3974	10	3360	0	0	0	0%	23:16:27
12	neva.ru	29827	8	25526	77	0	3032	0%	23:59:52
13	weretis.net	24922	6	24436	3	0	0	0%	23:59:50
14	erje	28162	5	24386	227	0	0	0%	23:59:52
15	eternal-september	29348	4	24010	1	0	0	0%	23:59:46
16	news.swapon.de	2335	1	2105	3	0	0	0%	23:54:24
17	bwh	23039	0	21820	0	0	0	0%	23:59:53
TOTAL: 17		481387	5881	396507	1984	1	3124	1%	407:08:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	11.3 MB	22.9 KB	11.4 MB	0.1 KB/s	7.0 KB	23:59:52
2	xanadu-bbs	10.3 MB	74.4 KB	10.4 MB	0.1 KB/s	7.7 KB	23:59:53
3	fu-berlin.de	9.2 MB	0.0 KB	9.2 MB	0.1 KB/s	9.6 KB	23:59:39
4	news.bbs.geek.nz	2.5 MB	59.4 KB	2.6 MB	0.0 KB/s	2.8 KB	23:59:51
5	aioe	1.3 MB	47.9 KB	1.4 MB	0.0 KB/s	3.0 KB	23:59:47
6	usenet.pasdenom.info	776.8 KB	10.8 MB	11.6 MB	0.1 KB/s	6.6 KB	23:59:52
7	au2pb	421.4 KB	63.4 KB	484.8 KB	0.0 KB/s	3.4 KB	23:59:53
8	news.redatomik.org	299.1 KB	7.0 KB	306.2 KB	0.0 KB/s	3.1 KB	23:59:52
9	xmission	74.1 KB	28.8 KB	103.0 KB	0.0 KB/s	2.8 KB	23:59:53
10	news.mixmin.net	58.1 KB	8.1 KB	66.2 KB	0.0 KB/s	4.7 KB	23:59:52
11	neva.ru	39.5 KB	212.0 KB	251.5 KB	0.0 KB/s	3.0 KB	23:59:52
12	news.fcku.it	27.7 KB	0.0 KB	27.7 KB	0.0 KB/s	2.8 KB	23:16:27
13	eternal-september	20.7 KB	8.1 KB	28.8 KB	0.0 KB/s	5.8 KB	23:59:46
14	weretis.net	18.4 KB	33.4 KB	51.8 KB	0.0 KB/s	5.8 KB	23:59:50
15	erje	7.5 KB	819.2 KB	826.6 KB	0.0 KB/s	3.6 KB	23:59:52
16	news.swapon.de	0.7 KB	6.3 KB	7.0 KB	0.0 KB/s	1.8 KB	23:54:24
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 17		36.5 MB	12.2 MB	48.7 MB	0.0 KB/s	6.3 KB	407:08:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	202.89.176.220	287	73	183.8 KB	66	0	06:50:03
2	tempe0.bbox.io	21	6	10.4 KB	3	0	04:03:16
3	dhcp-089-099-203-254.chello.nl	16	1	1.4 KB	1	0	06:44:16
4	cpc73515-shef13-2-0-cust748.17-1.cable.virginm.net	2	0	0.0 KB	0	1	00:00:01
TOTAL: 4		326	80	195.6 KB	70	1	39:22:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Rej	Elapsed
1	unresolved	302	73	183.8 KB	66	0	28:35:24
2	*.bbox.io	21	6	10.4 KB	3	0	04:03:16
3	*.chello.nl	16	1	1.4 KB	1	0	06:44:16
4	*.nielsenmedia.com	1	0	0.0 KB	0	0	00:00:00
5	*.17-1.cable.virginm.net	2	0	0.0 KB	0	1	00:00:01
TOTAL: 5		342	80	195.6 KB	70	1	39:22:59

NNRP auth users [Top 20]:

User	Conn
moussa	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	1.062	0.512	0.000	20:37:08
202.89.176.220	28.463	6.656	0.764	06:50:03
dhcp-089-099-203-254.chello.nl	1.473	0.297	0.000	06:44:16
tempe0.bbox.io	2.063	0.417	0.000	04:03:16
138.108.60.60	0.246	0.065	0.000	01:08:12
cpc73515-shef13-2-0-cust748.17-1.cable.virginm.net	0.125	0.033	0.000	00:00:01
TOTAL: 6	33.432	7.980	0.764	39:22:59

Curious NNRP server explorers [Top 20]:

System	Conn
162.212.88.221	12
138.108.60.60	3
challenger.nielsenmedia.com	1
TOTAL: 3	16

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	12	0
tempe0.bbox.io	7	0
138.108.60.60	2	0
TOTAL: 3	21	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	76	95.0%
2	news	4	5.0%
TOTAL: 2		80	100.0%

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

Newsgroup		Count
1	comp.os.vms	28
2	comp.arch	14
3	comp.sys.ibm.pc.hardware.storage	12
4	comp.os.linux.networking	7
5	comp.sys.ibm.ps2.hardware	5
6	comp.sys.ibm.pc.games.action	4
7	news.software.nntp	4
8	comp.arch.embedded	3
9	comp.ai.neural-nets	1
10	comp.os.os2.apps	1
11	comp.ai.philosophy	1
TOTAL: 11		80

Daily Usenet report for csiph.com

Jan 15 04:15:00 -- Jan 16 04:15:00