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

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jan 5 09:58:52 csiph nnrpd[32768]: 46.165.242.75 noperm ihave without permission
Jan 5 10:03:25 csiph nnrpd[33039]: 46.165.242.75 noperm ihave without permission
Jan 5 10:05:22 csiph nnrpd[33235]: 46.165.242.75 noperm ihave without permission
Jan 5 10:06:11 csiph nnrpd[33266]: 46.165.242.75 noperm ihave without permission
Jan 5 10:11:19 csiph nnrpd[33456]: 46.165.242.75 noperm ihave without permission
Jan 5 10:12:11 csiph nnrpd[33482]: 46.165.242.75 noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	50269	45.0%	9.9 MB	62.8%
nnrpd	35840	32.1%	3.0 MB	19.0%
innd	22149	19.8%	2.3 MB	14.7%
innfeed	3323	3.0%	560.7 KB	3.5%
nocem	102	0.1%	10.4 KB	0.1%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	111685	100.0%	15.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	616388	62.3%
Negative hits	323157	32.6%
Do not exist	49352	5.0%
Cache misses	1176	0.1%
TOTAL: 4	990073	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.366	0.0%	290	0.000	1.262	16.000
article cleanup	00:00:00.269	0.0%	47973	0.000	0.006	0.022
article logging	00:00:00.711	0.0%	48524	0.000	0.015	0.172
article parse	00:00:02.660	0.0%	204242	0.003	0.013	0.119
article write	00:01:15.885	0.1%	29375	0.236	2.583	7.855
artlog/artcncl	00:00:00.002	0.0%	253	0.000	0.008	0.100
artlog/artparse	00:00:00.016	0.0%	1496	0.000	0.011	0.500
data move	00:00:00.306	0.0%	924951	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.042	0.0%	290	0.000	0.145	12.000
hishave/artcncl	00:00:00.002	0.0%	290	0.000	0.007	0.125
hishave/artparse	00:00:00.000	0.0%	220	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:14.949	0.0%	990705	0.002	0.015	0.089
history sync	00:00:03.357	0.0%	340	0.000	9.874	227.500
history write	00:01:38.206	0.1%	49190	0.067	1.996	14.956
hiswrite/artparse	00:00:00.877	0.0%	220	0.000	3.986	72.000
idle	23:52:39.358	99.4%	949953	37.585	90.488	314.822
nntp read	00:00:03.157	0.0%	961106	0.001	0.003	0.006
overview write	00:01:35.961	0.1%	29375	0.539	3.267	28.109
perl filter	00:02:12.267	0.2%	47782	1.668	2.768	4.588
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.460	0.0%	58847	0.000	0.008	0.022
TOTAL: 24:00:45.744	23:59:48.851	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.199	0.0%	106515	0.000	0.002	0.023
article prepare	00:00:00.009	0.0%	39578	0.000	0.000	0.007
article read	00:00:00.903	0.0%	5371	0.000	0.168	1.957
backlog stats	00:00:00.302	0.0%	758664	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.646	0.0%	534034	0.001	0.005	0.012
data write	00:00:03.305	0.0%	506024	0.003	0.007	0.013
idle	23:42:01.936	100.0%	758664	48.626	112.463	556.873
status file	00:00:00.239	0.0%	321	0.000	0.745	33.000
TOTAL: 23:42:25.958	23:42:09.539	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.083	0.0%	20160	0.000	0.054	0.102
idle	138:57:50.553	100.0%	54801	0.000	9128.858	600159.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.666	0.0%	111152	0.000	0.006	0.500
readart	00:00:02.885	0.0%	288	0.000	10.017	95.000
TOTAL: 138:59:52.037	138:57:55.187	100.0%	-	-	-	-

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
shutdown	3
TOTAL: 11	169

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	289	45945	10160	23686	12099	22%	23:44:19
2	csiph-out.feeder.erje.net	1156	193256	4300	184862	4094	2%	94:51:43
3	feeder.eternal-september.org	6	42440	3934	38000	506	9%	24:15:25
4	news-out.mixmin.net	4	31124	2622	27921	581	8%	24:08:07
5	news-out.aioe.org	15	22722	2224	20472	26	9%	17:12:09
6	newsfeed.fu-berlin.de	66	30634	1449	29125	60	4%	34:46:37
7	csiph-out.news.weretis.net	5	24666	862	23710	94	3%	16:35:11
8	newsfeed.neva.ru	49	35285	636	33072	1577	1%	23:45:07
9	news.redatomik.org	3	30248	616	29377	255	2%	20:23:23
10	au2pb.net	3	30361	608	29646	107	2%	24:17:24
11	xanadu-bbs.net	2	48912	514	47802	596	1%	27:02:58
12	newsfeeds.xmission.com	1	25705	424	25232	49	1%	21:03:11
13	news.freedyn.net	17	32358	360	31953	45	1%	24:06:55
14	news.fcku.it	1	8178	292	7886	0	3%	23:56:54
15	news.swapon.de	3	3152	174	2978	0	5%	29:55:01
16	nntp.pasdenom.info	4	27787	36	27724	27	0%	24:07:44
17	news.bbs.geek.nz	2	10562	4	10555	3	0%	12:44:06
18	localhost	33	2	2	0	0	100%	00:00:00
TOTAL: 18		1659	643337	29217	594001	20119	4%	466:56:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	26.4 MB	12.9 KB	121.9 MB	148.3 MB	17%	6.8 KB
2	csiph-out.feeder.erje.net	19.4 MB	104.1 KB	16.3 MB	35.9 MB	54%	4.4 KB
3	feeder.eternal-september.org	10.5 MB	19.5 KB	5.4 MB	15.9 MB	65%	3.7 KB
4	news-out.mixmin.net	9.5 MB	26.7 KB	1.1 MB	10.7 MB	89%	3.4 KB
5	news-out.aioe.org	6.2 MB	0.0 KB	53.6 KB	6.2 MB	99%	2.8 KB
6	newsfeed.fu-berlin.de	3.3 MB	0.0 KB	182.5 KB	3.5 MB	94%	2.4 KB
7	csiph-out.news.weretis.net	2.5 MB	7.6 KB	925.1 KB	3.4 MB	72%	3.6 KB
8	news.freedyn.net	1.8 MB	0.0 KB	946.3 KB	2.7 MB	65%	6.8 KB
9	newsfeed.neva.ru	1.7 MB	101.3 KB	6.5 MB	8.3 MB	20%	3.8 KB
10	news.redatomik.org	1.5 MB	1.2 KB	31.4 MB	32.9 MB	4%	38.7 KB
11	au2pb.net	1.5 MB	11.3 KB	492.1 KB	1.9 MB	74%	2.8 KB
12	xanadu-bbs.net	1.4 MB	3.6 KB	6.6 MB	8.0 MB	17%	7.4 KB
13	newsfeeds.xmission.com	1.2 MB	0.0 KB	570.5 KB	1.8 MB	69%	3.9 KB
14	news.fcku.it	548.8 KB	0.0 KB	0.0 KB	548.8 KB	100%	1.9 KB
15	news.swapon.de	436.8 KB	0.0 KB	0.0 KB	436.8 KB	100%	2.5 KB
16	nntp.pasdenom.info	87.2 KB	0.0 KB	107.3 KB	194.5 KB	44%	3.1 KB
17	news.bbs.geek.nz	7.9 KB	0.0 KB	5.4 KB	13.3 KB	59%	1.9 KB
18	localhost	5.0 KB	0.0 KB	0.0 KB	5.0 KB	100%	2.5 KB
TOTAL: 18		87.9 MB	288.2 KB	192.5 MB	280.7 MB	31%	5.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 5 04:15:00 - 04:59:59	328	1.1%	0.12	1.0 MB	1.1%	0.38
Jan 5 05:00:00 - 05:59:59	517	1.8%	0.14	1.8 MB	2.0%	0.51
Jan 5 06:00:00 - 06:59:59	539	1.8%	0.15	1.8 MB	2.0%	0.51
Jan 5 07:00:00 - 07:59:59	692	2.4%	0.19	1.9 MB	2.1%	0.54
Jan 5 08:00:00 - 08:59:59	1137	3.9%	0.32	3.8 MB	4.3%	1.09
Jan 5 09:00:00 - 09:59:59	1243	4.2%	0.35	3.6 MB	4.1%	1.03
Jan 5 10:00:00 - 10:59:59	1430	4.9%	0.40	4.2 MB	4.8%	1.20
Jan 5 11:00:00 - 11:59:59	1373	4.7%	0.38	4.2 MB	4.7%	1.18
Jan 5 12:00:00 - 12:59:59	1381	4.7%	0.38	3.6 MB	4.1%	1.03
Jan 5 13:00:00 - 13:59:59	1349	4.6%	0.37	3.7 MB	4.2%	1.05
Jan 5 14:00:00 - 14:59:59	1428	4.9%	0.40	3.9 MB	4.4%	1.10
Jan 5 15:00:00 - 15:59:59	1603	5.5%	0.45	4.4 MB	5.0%	1.25
Jan 5 16:00:00 - 16:59:59	1826	6.2%	0.51	5.5 MB	6.3%	1.57
Jan 5 17:00:00 - 17:59:59	1674	5.7%	0.47	4.9 MB	5.5%	1.39
Jan 5 18:00:00 - 18:59:59	1899	6.5%	0.53	5.6 MB	6.4%	1.60
Jan 5 19:00:00 - 19:59:59	1845	6.3%	0.51	6.1 MB	6.9%	1.75
Jan 5 20:00:00 - 20:59:59	1923	6.5%	0.53	6.6 MB	7.5%	1.89
Jan 5 21:00:00 - 21:59:59	1714	5.8%	0.48	5.0 MB	5.6%	1.42
Jan 5 22:00:00 - 22:59:59	1317	4.5%	0.37	3.7 MB	4.2%	1.06
Jan 5 23:00:00 - 23:59:59	1076	3.7%	0.30	3.2 MB	3.6%	0.90
Jan 6 00:00:00 - 00:59:59	885	3.0%	0.25	2.8 MB	3.1%	0.78
Jan 6 01:00:00 - 01:59:59	814	2.8%	0.23	2.6 MB	2.9%	0.73
Jan 6 02:00:00 - 02:59:59	599	2.0%	0.17	2.0 MB	2.2%	0.56
Jan 6 03:00:00 - 03:59:59	621	2.1%	0.17	2.1 MB	2.4%	0.60
Jan 6 04:00:00 - 04:15:00	165	0.6%	0.18	540.4 KB	0.6%	0.60
TOTAL: 24:00:00	29378	100.0%	0.34	88.6 MB	100.0%	1.05

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	usenet.blueworldhosting.com	12126	890	1	0	11235
2	csiph-out.feeder.erje.net	4101	1228	14	0	2859
3	newsfeed.neva.ru	1581	987	16	0	578
4	news-out.mixmin.net	591	5	5	0	581
5	xanadu-bbs.net	544	30	1	0	513
6	feeder.eternal-september.org	531	243	2	0	286
7	newsfeed.fu-berlin.de	479	37	0	0	442
8	news.redatomik.org	258	0	1	0	257
9	csiph-out.news.weretis.net	112	82	3	0	27
10	au2pb.net	105	92	4	0	9
11	newsfeeds.xmission.com	55	51	0	0	4
12	news.freedyn.net	49	2	0	0	47
13	news-out.aioe.org	43	13	0	3	27
14	nntp.pasdenom.info	27	25	0	0	2
15	news.swapon.de	22	0	0	0	22
16	news.bbs.geek.nz	9	8	1	0	0
17	news.fcku.it	5	0	0	0	5
TOTAL: 17		20638	3693	48	3	16894

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	1013
mailing.freebsd.pkg-fallout	254
ut.test	178
lada.talk	103
pld.cvs.commit	103
mailing.freebsd.ports-bugs	78
lada.auto	76
mpc.lists.freebsd.ports.bugs	75
mailing.database.pgsql-general	69
mailing.freebsd.svn-ports-all	63
mozilla.general	58
mailing.freebsd.svn-ports-head	53
alt.windows7.general	46
gmane.linux.alsa.devel	40
mailing.openbsd.ports-changes	35
it-alt.sport.calcio.juventus	31
alt.politics.scorched-earth	31
mailing.freebsd.svn-src-all	31
fa.freebsd.cvs-all	30
fa.openbsd.source-changes	29
TOTAL: 269	3693

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	8942
EMP (phn path)	4485
EMP (phl)	768
Too many newsgroups	112
Scoring filter (10)	54
HTML Multipart	45
Scoring filter (12)	45
User-issued cancel	27
Scoring filter (18)	25
Scoring filter (22)	24
Scoring filter (11)	23
Binary Payload	23
Scoring filter (14)	21
Scoring filter (8)	18
Scoring filter (19)	15
Scoring filter (13)	12
HTML Image Tags	11
Scoring filter (20)	9
Scoring filter (9)	9
Scoring filter (17)	8
TOTAL: 26	14704

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	128
	usenet.blueworldhosting.com	4
	TOTAL: 2	132
Huge articles
	news.redatomik.org	77
	usenet.blueworldhosting.com	68
	feeder.eternal-september.org	31
	xanadu-bbs.net	23
	news-out.mixmin.net	6
	news.freedyn.net	5
	csiph-out.news.weretis.net	4
	newsfeeds.xmission.com	4
	newsfeed.fu-berlin.de	1
	au2pb.net	1
	TOTAL: 10	220
Including strange strings
	newsfeed.fu-berlin.de	428
	feeder.eternal-september.org	32
	usenet.blueworldhosting.com	23
	news.swapon.de	22
	news-out.aioe.org	17
	news-out.mixmin.net	12
	csiph-out.feeder.erje.net	7
	news.fcku.it	4
	newsfeed.neva.ru	2
	csiph-out.news.weretis.net	2
	TOTAL: 12	551
No colon-space in header
	csiph-out.feeder.erje.net	1054
	usenet.blueworldhosting.com	222
	TOTAL: 2	1276
TOTAL: 4		2179

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.freedyn.net	30232	3361	25546	62	0	80	11%	23:56:17
2	news.bbs.geek.nz	53797	1197	26929	185	3	1	2%	23:59:03
3	xanadu-bbs	30380	1117	27654	123	0	315	3%	23:59:31
4	fu-berlin.de	27353	901	26430	22	0	0	3%	23:59:04
5	aioe	22492	379	19398	13	0	0	1%	18:00:03
6	news.redatomik.org	390532	252	27412	15	0	76986	0%	23:59:04
7	usenet.pasdenom.info	36546	234	27416	1692	0	3	0%	23:59:31
8	au2pb	28660	74	27594	74	0	0	0%	23:59:04
9	erje	23343	45	20931	101	0	33	0%	23:54:03
10	xmission	33766	42	27776	4	0	0	0%	23:59:31
11	weretis.net	25105	9	24111	4	0	0	0%	23:59:04
12	news.mixmin.net	29754	9	24858	0	0	0	0%	23:59:04
13	eternal-september	28359	6	23897	5	0	0	0%	23:59:31
14	bwh	18596	4	18045	3	0	0	0%	23:59:04
15	news.fcku.it	2623	4	2176	0	0	0	0%	22:36:14
16	neva.ru	0	0	0	0	0	27496	0%	23:58:51
17	news.swapon.de	2712	0	2577	2	0	0	0%	23:49:04
TOTAL: 17		784250	7634	352750	2305	3	104914	0%	400:06:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	16.8 MB	340.6 KB	17.1 MB	0.2 KB/s	5.1 KB	23:56:17
2	xanadu-bbs	8.6 MB	244.7 KB	8.8 MB	0.1 KB/s	7.3 KB	23:59:31
3	fu-berlin.de	8.0 MB	29.3 KB	8.0 MB	0.1 KB/s	8.9 KB	23:59:04
4	news.bbs.geek.nz	3.6 MB	597.7 KB	4.2 MB	0.0 KB/s	3.1 KB	23:59:03
5	aioe	1.1 MB	28.8 KB	1.1 MB	0.0 KB/s	2.9 KB	18:00:03
6	usenet.pasdenom.info	896.6 KB	9.5 MB	10.3 MB	0.1 KB/s	5.5 KB	23:59:31
7	news.redatomik.org	821.9 KB	40.9 KB	862.8 KB	0.0 KB/s	3.2 KB	23:59:04
8	au2pb	369.1 KB	200.9 KB	570.0 KB	0.0 KB/s	3.9 KB	23:59:04
9	erje	188.0 KB	673.3 KB	861.2 KB	0.0 KB/s	5.9 KB	23:54:03
10	xmission	127.4 KB	6.0 KB	133.4 KB	0.0 KB/s	2.9 KB	23:59:31
11	news.mixmin.net	68.0 KB	0.0 KB	68.0 KB	0.0 KB/s	7.6 KB	23:59:04
12	weretis.net	29.7 KB	72.2 KB	101.9 KB	0.0 KB/s	7.8 KB	23:59:04
13	eternal-september	26.1 KB	8.3 KB	34.4 KB	0.0 KB/s	3.1 KB	23:59:31
14	bwh	6.9 KB	7.8 KB	14.7 KB	0.0 KB/s	2.1 KB	23:59:04
15	news.fcku.it	5.4 KB	0.0 KB	5.4 KB	0.0 KB/s	1.3 KB	22:36:14
16	neva.ru	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:51
17	news.swapon.de	0.0 KB	7.4 KB	7.4 KB	0.0 KB/s	3.7 KB	23:49:04
TOTAL: 17		40.6 MB	11.7 MB	52.2 MB	0.0 KB/s	5.4 KB	400:06:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	439	162	435.4 KB	18	2	2	59:55:40
2	202.89.176.220	288	96	248.4 KB	91	0	0	04:08:13
3	challenger.nielsenmedia.com	5	30	33.8 KB	1	0	0	01:15:25
TOTAL: 3		732	288	717.7 KB	110	2	2	138:59:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	439	162	435.4 KB	18	2	2	59:55:40
2	unresolved	300	96	248.4 KB	91	0	0	73:02:48
3	*.nielsenmedia.com	5	30	33.8 KB	1	0	0	01:15:25
4	*.ip-188-165-239.eu	4	0	0.0 KB	0	0	0	00:02:00
5	*.dynamic.upc.nl	2	0	0.0 KB	0	0	0	00:00:14
6	*.tampfl.dsl-w.verizon.net	3	0	0.0 KB	0	0	0	00:30:25
7	*.chello.nl	10	0	0.0 KB	0	0	0	04:13:09
TOTAL: 7		763	288	717.7 KB	110	2	2	138:59:44

NNRP auth users [Top 20]:

User	Conn
moussa	288
kev009	2
TOTAL: 2	290

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.221	0.721	0.971	0.000	68:53:52
tempe0.bbox.io	35.872	6.958	0.005	59:55:40
dhcp-089-099-203-254.chello.nl	0.921	0.159	0.000	04:13:09
202.89.176.220	30.228	5.777	0.761	04:08:13
challenger.nielsenmedia.com	0.527	0.063	0.000	01:15:25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.258	0.033	0.000	00:30:25
ns342002.ip-188-165-239.eu	0.340	0.082	0.000	00:02:00
46.165.242.75	0.478	0.070	0.000	00:00:31
92-108-162-83.dynamic.upc.nl	0.213	0.040	0.000	00:00:14
46.165.242.91	0.133	0.016	0.000	00:00:10
TOTAL: 10	69.691	14.169	0.766	138:59:44

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	10
46.165.242.75	6
162.212.88.221	4
ns342002.ip-188-165-239.eu	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3
46.165.242.91	2
92-108-162-83.dynamic.upc.nl	2
TOTAL: 7	31

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	8	0
tempe0.bbox.io	8	0
challenger.nielsenmedia.com	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 4	19	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	149	51.7%
2	bit	128	44.4%
3	news	11	3.8%
TOTAL: 3		288	100.0%

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

Newsgroup		Count
1	bit.listserv.ibm-main	128
2	comp.os.vms	36
3	comp.lang.awk	30
4	comp.arch	27
5	comp.lang.asm370	22
6	comp.arch.embedded	16
7	news.admin.peering	7
8	news.software.nntp	4
9	comp.ai.philosophy	3
10	comp.arch.fpga	3
11	comp.sys.ibm.pc.games.action	3
12	comp.ai.neural-nets	2
13	comp.os.os2.misc	2
14	comp.sys.ibm.pc.games.rpg	1
15	comp.sys.ibm.pc.games.war-historical	1
16	comp.sys.ibm.as400.misc	1
17	comp.sys.ibm.ps2.hardware	1
18	comp.sys.ibm.pc.hardware.storage	1
TOTAL: 18		288

Daily Usenet report for csiph.com

Jan 5 04:15:00 -- Jan 6 04:15:00