Daily Usenet report for csiph.com: Nov 10 04:15:00

Unknown entries from news log file:

First 50 / 2825 lines (1.8%)

Nov 10 04:15:23 csiph nnrpd[93809]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:15:54 csiph nnrpd[93819]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:16:25 csiph nnrpd[93857]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:16:55 csiph nnrpd[93865]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:17:25 csiph nnrpd[93887]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:17:55 csiph nnrpd[93912]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:18:26 csiph nnrpd[93925]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:18:56 csiph nnrpd[93965]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:19:27 csiph nnrpd[93976]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:19:58 csiph nnrpd[94038]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:20:29 csiph nnrpd[94065]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:20:59 csiph nnrpd[94100]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:21:30 csiph nnrpd[94110]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:22:00 csiph nnrpd[94169]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:22:32 csiph nnrpd[94179]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:23:02 csiph nnrpd[94214]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:23:33 csiph nnrpd[94225]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:24:03 csiph nnrpd[94272]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:24:33 csiph nnrpd[94287]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:25:04 csiph nnrpd[94316]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:25:35 csiph nnrpd[94342]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:26:05 csiph nnrpd[94352]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:26:35 csiph nnrpd[94387]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:27:06 csiph nnrpd[94397]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:27:38 csiph nnrpd[94432]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:28:09 csiph nnrpd[94442]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:28:39 csiph nnrpd[94477]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:29:10 csiph nnrpd[94487]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:29:41 csiph nnrpd[94522]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:30:12 csiph nnrpd[94677]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:30:43 csiph nnrpd[94712]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:31:14 csiph nnrpd[94728]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:31:44 csiph nnrpd[94763]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:32:15 csiph nnrpd[94773]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:32:45 csiph nnrpd[94799]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:33:15 csiph nnrpd[94839]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:33:45 csiph nnrpd[94849]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:34:16 csiph nnrpd[94884]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:34:47 csiph nnrpd[94894]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:35:18 csiph nnrpd[94940]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:35:48 csiph nnrpd[94943]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:36:18 csiph nnrpd[94969]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:36:50 csiph nnrpd[94970]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:37:21 csiph nnrpd[94996]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:37:52 csiph nnrpd[94997]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:38:22 csiph nnrpd[95023]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:38:52 csiph nnrpd[95024]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:39:24 csiph nnrpd[95051]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:39:54 csiph nnrpd[95052]: snorky.mixmin.net noperm ihave without permission
Nov 10 04:40:25 csiph nnrpd[95212]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	58227	44.2%	8.4 MB	55.2%
nnrpd	44715	34.0%	4.0 MB	26.2%
innd	26852	20.4%	2.6 MB	16.8%
innfeed	1874	1.4%	282.2 KB	1.8%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	131669	100.0%	15.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	275790	45.5%
Negative hits	272341	45.0%
Do not exist	57310	9.5%
Cache misses	299	0.0%
TOTAL: 4	605740	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.871	0.0%	200	0.000	4.355	30.000
article cleanup	00:00:00.399	0.0%	52154	0.000	0.008	0.031
article logging	00:00:00.633	0.0%	52636	0.000	0.012	0.034
article parse	00:00:13.585	0.0%	826975	0.003	0.016	0.170
article write	00:01:03.006	0.1%	28553	0.173	2.207	7.939
artlog/artcncl	00:00:00.001	0.0%	163	0.000	0.006	0.100
artlog/artparse	00:00:00.065	0.0%	5303	0.000	0.012	0.333
data move	00:00:00.849	0.0%	1299247	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.150	0.0%	199	0.000	0.754	21.000
hishave/artcncl	00:00:00.002	0.0%	200	0.000	0.010	0.059
hishave/artparse	00:00:00.024	0.0%	5303	0.000	0.005	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:14.060	0.0%	602036	0.001	0.023	0.162
history sync	00:00:03.038	0.0%	330	0.000	9.206	112.500
history write	00:01:55.777	0.1%	52139	0.093	2.221	9.207
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:06.526	0.0%	5302	0.000	1.231	31.000
idle	23:52:09.490	99.4%	1280958	9.885	67.082	322.867
nntp read	00:00:04.674	0.0%	1281109	0.002	0.004	0.007
overview write	00:02:10.372	0.2%	28553	1.134	4.566	107.988
perl filter	00:01:45.982	0.1%	52139	0.961	2.033	3.762
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.536	0.0%	57106	0.000	0.009	0.028
TOTAL: 24:00:42.502	23:59:50.040	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.086	0.0%	28670	0.000	0.003	0.025
article prepare	00:00:00.000	0.0%	5836	0.000	0.000	0.000
article read	00:00:00.178	0.0%	1181	0.000	0.151	15.000
backlog stats	00:00:00.107	0.0%	310500	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.315	0.0%	241970	0.000	0.005	0.012
data write	00:00:01.625	0.0%	217988	0.002	0.007	0.014
idle	23:54:13.440	100.0%	310500	148.228	277.145	806.790
status file	00:00:00.083	0.0%	195	0.000	0.426	2.000
TOTAL: 23:54:20.807	23:54:16.834	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.767	0.0%	10705	0.000	0.072	0.167
idle	56:08:20.721	99.7%	48963	0.000	4127.621	600072.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.606	0.0%	99039	0.000	0.006	0.500
readart	00:00:00.367	0.0%	129	0.000	2.845	62.000
TOTAL: 56:17:40.365	56:08:22.461	99.7%	-	-	-	-

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	feeder.eternal-september.org	4	48468	7497	38280	2691	15%	24:49:21
2	news-out.mixmin.net	3	35433	5578	25407	4448	15%	24:17:57
3	au2pb.net	1	31656	5359	25070	1227	16%	23:50:22
4	xanadu-bbs.net	12	49352	3558	38189	7605	7%	23:01:50
5	newsfeed.neva.ru	152	61371	2425	50420	8526	3%	72:02:50
6	newsfeeds.xmission.com	1	30017	2412	27511	94	8%	24:16:45
7	newsfeed.fu-berlin.de	59	33941	1333	32258	350	3%	34:57:51
8	nntp.pasdenom.info	6	29589	214	29270	105	0%	24:12:42
9	news.swapon.de	3	2429	204	2223	2	8%	24:33:50
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		267	322256	28580	268628	25048	8%	276:03:28

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.0 MB	33.1 KB	21.4 MB	41.5 MB	48%	4.2 KB
2	news-out.mixmin.net	18.0 MB	8.4 KB	8.5 MB	26.5 MB	68%	2.7 KB
3	au2pb.net	14.6 MB	11.8 KB	7.0 MB	21.6 MB	67%	3.4 KB
4	xanadu-bbs.net	9.6 MB	11.8 KB	325.8 MB	335.5 MB	2%	30.8 KB
5	newsfeed.neva.ru	7.4 MB	76.2 KB	76.0 MB	83.4 MB	8%	7.8 KB
6	newsfeeds.xmission.com	6.9 MB	39.5 KB	2.6 MB	9.5 MB	72%	3.9 KB
7	newsfeed.fu-berlin.de	3.1 MB	0.0 KB	854.0 KB	3.9 MB	78%	2.4 KB
8	news.swapon.de	522.1 KB	0.0 KB	97.2 KB	619.3 KB	84%	3.0 KB
9	nntp.pasdenom.info	488.2 KB	0.0 KB	910.6 KB	1.4 MB	34%	4.4 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		80.8 MB	180.8 KB	443.1 MB	524.0 MB	15%	10.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 10 04:15:00 - 04:59:59	378	1.3%	0.14	1.3 MB	1.6%	0.48
Nov 10 05:00:00 - 05:59:59	556	1.9%	0.15	2.1 MB	2.5%	0.58
Nov 10 06:00:00 - 06:59:59	601	2.1%	0.17	2.1 MB	2.6%	0.60
Nov 10 07:00:00 - 07:59:59	804	2.8%	0.22	2.1 MB	2.5%	0.58
Nov 10 08:00:00 - 08:59:59	1246	4.4%	0.35	3.6 MB	4.5%	1.04
Nov 10 09:00:00 - 09:59:59	1233	4.3%	0.34	3.3 MB	4.1%	0.93
Nov 10 10:00:00 - 10:59:59	1295	4.5%	0.36	3.3 MB	4.0%	0.93
Nov 10 11:00:00 - 11:59:59	1210	4.2%	0.34	3.5 MB	4.4%	1.00
Nov 10 12:00:00 - 12:59:59	1332	4.7%	0.37	3.8 MB	4.7%	1.07
Nov 10 13:00:00 - 13:59:59	1487	5.2%	0.41	3.8 MB	4.7%	1.09
Nov 10 14:00:00 - 14:59:59	1489	5.2%	0.41	4.0 MB	4.9%	1.13
Nov 10 15:00:00 - 15:59:59	1614	5.7%	0.45	4.3 MB	5.3%	1.22
Nov 10 16:00:00 - 16:59:59	1862	6.5%	0.52	5.2 MB	6.5%	1.49
Nov 10 17:00:00 - 17:59:59	1773	6.2%	0.49	4.8 MB	5.9%	1.36
Nov 10 18:00:00 - 18:59:59	1714	6.0%	0.48	4.9 MB	6.1%	1.39
Nov 10 19:00:00 - 19:59:59	1523	5.3%	0.42	4.2 MB	5.2%	1.20
Nov 10 20:00:00 - 20:59:59	1496	5.2%	0.42	4.0 MB	5.0%	1.15
Nov 10 21:00:00 - 21:59:59	1573	5.5%	0.44	4.0 MB	5.0%	1.15
Nov 10 22:00:00 - 22:59:59	1362	4.8%	0.38	4.2 MB	5.2%	1.19
Nov 10 23:00:00 - 23:59:59	1050	3.7%	0.29	3.0 MB	3.7%	0.86
Nov 11 00:00:00 - 00:59:59	997	3.5%	0.28	3.0 MB	3.7%	0.86
Nov 11 01:00:00 - 01:59:59	727	2.5%	0.20	2.3 MB	2.9%	0.66
Nov 11 02:00:00 - 02:59:59	562	2.0%	0.16	1.8 MB	2.2%	0.51
Nov 11 03:00:00 - 03:59:59	537	1.9%	0.15	1.8 MB	2.2%	0.50
Nov 11 04:00:00 - 04:15:00	130	0.5%	0.14	394.7 KB	0.5%	0.44
TOTAL: 24:00:00	28551	100.0%	0.33	80.7 MB	100.0%	0.96

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	11646	625	1	11020
2	newsfeed.neva.ru	8468	1522	6	6940
3	news-out.mixmin.net	4463	15	2	4446
4	feeder.eternal-september.org	2741	357	4	2380
5	au2pb.net	1260	1180	1	79
6	newsfeed.fu-berlin.de	710	34	0	676
7	nntp.pasdenom.info	101	88	0	13
8	newsfeeds.xmission.com	97	67	1	29
9	news.swapon.de	26	0	0	26
TOTAL: 9		29512	3888	15	25609

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	721
mailing.freebsd.sysinstall	188
lada.talk	154
mailing.freebsd.ports-bugs	134
ut.test	118
alt.politics.scorched-earth	86
mailing.freebsd.bugs	84
it-alt.sport.calcio.juventus	75
alt.politics	75
alt.windows7.general	64
mailing.gcc.gcc-cvs	64
mailing.freebsd.pkg-fallout	64
fa.freebsd.bugs	60
pld.cvs.commit	60
gmane.linux.alsa.devel	58
mailing.database.pgsql-general	57
mailing.freebsd.svn-src-all	57
fa.freebsd.cvs-all	56
lada.auto	53
mailing.freebsd.svn-src-head	52
TOTAL: 291	3888

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14800
EMP (phn path)	3228
EMP (phl)	950
EMP (phn nph)	265
PFM bot misc	206
HTML Multipart	79
Too many newsgroups	36
Binary Image: misplaced jpg	24
HTML post	24
HTML Image Tags	10
User-issued spam cancel	5
Too many newsgroups (low_xpost)	2
Binary: misplaced sfv	1
Binary: non-binary distribution	1
Binary: misplaced nfo	1
Binary: misplaced srr	1
Binary Payload	1
TOTAL: 17	19634

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	5327
	feeder.eternal-september.org	63
	au2pb.net	51
	newsfeeds.xmission.com	23
	news-out.mixmin.net	21
	nntp.pasdenom.info	7
	news.swapon.de	1
	TOTAL: 7	5493
Including strange strings
	newsfeed.fu-berlin.de	368
	feeder.eternal-september.org	45
	news.swapon.de	24
	news-out.mixmin.net	15
	xanadu-bbs.net	14
	au2pb.net	8
	newsfeed.neva.ru	5
	newsfeeds.xmission.com	3
	TOTAL: 8	482
TOTAL: 2		5975

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	24056	158	22897	98	0	0%	23:59:57
2	usenet.pasdenom.info	32588	125	27442	768	58	0%	23:59:57
3	xanadu-bbs	27704	74	24796	76	65	0%	23:59:53
4	fu-berlin.de	26836	45	26773	18	0	0%	23:59:44
5	news.mixmin.net	23889	26	22803	0	0	0%	23:59:46
6	xmission	32126	12	25945	1	0	0%	23:59:58
7	neva.ru	27221	5	25889	45	43	0%	23:59:57
8	eternal-september	21300	4	20180	0	0	0%	23:59:51
9	news.swapon.de	2433	0	2416	4	0	0%	23:48:30
TOTAL: 9		218153	449	199141	1010	166	0%	215:47:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	632.4 KB	199.2 KB	831.6 KB	3.2 KB	23:59:57
2	usenet.pasdenom.info	526.2 KB	1.4 MB	1.9 MB	2.2 KB	23:59:57
3	xanadu-bbs	338.4 KB	200.8 KB	539.2 KB	3.6 KB	23:59:53
4	fu-berlin.de	227.8 KB	20.4 KB	248.2 KB	3.9 KB	23:59:44
5	news.mixmin.net	131.6 KB	0.0 KB	131.6 KB	5.1 KB	23:59:46
6	eternal-september	40.7 KB	0.0 KB	40.7 KB	10.2 KB	23:59:51
7	xmission	39.2 KB	2.8 KB	42.0 KB	3.2 KB	23:59:58
8	neva.ru	32.0 KB	171.3 KB	203.4 KB	4.1 KB	23:59:57
9	news.swapon.de	0.0 KB	7.8 KB	7.8 KB	1.9 KB	23:48:30
TOTAL: 9		1.9 MB	2.0 MB	3.9 MB	2.8 KB	215:47:33

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	272	100	331.9 KB	83	04:32:19
2	challenger.nielsenmedia.com	7	21	33.6 KB	3	02:05:48
3	tempe0.bbox.io	55	5	10.6 KB	4	08:45:54
4	dhcp-089-099-203-254.chello.nl	36	3	5.3 KB	3	08:40:54
TOTAL: 4		370	129	381.4 KB	93	56:17:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	351	100	331.9 KB	83	34:43:35
2	*.nielsenmedia.com	7	21	33.6 KB	3	02:05:48
3	*.bbox.io	55	5	10.6 KB	4	08:45:54
4	*.chello.nl	36	3	5.3 KB	3	08:40:54
5	*.ip-188-165-239.eu	6	0	0.0 KB	0	00:02:07
6	*.mixmin.net	2825	0	0.0 KB	0	00:27:32
7	*.139.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	01:00:36
8	*.br.br.cox.net	1	0	0.0 KB	0	00:00:43
TOTAL: 8		3283	129	381.4 KB	93	56:17:12

NNRP auth users [Top 20]:

User	Conn
moussa	272
TOTAL: 1	272

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.214	0.372	0.423	0.000	25:01:49
tempe0.bbox.io	4.870	0.893	0.000	08:45:54
dhcp-089-099-203-254.chello.nl	3.064	0.631	0.000	08:40:54
63.142.120.8	3.480	1.030	0.000	05:09:23
202.89.176.220	27.754	5.360	0.697	04:32:19
challenger.nielsenmedia.com	0.655	0.111	0.000	02:05:48
h10.139.22.98.dynamic.ip.windstream.net	0.118	0.044	0.000	01:00:36
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.082	0.030	0.000	00:30:00
snorky.mixmin.net	235.430	44.146	0.000	00:27:32
ns342002.ip-188-165-239.eu	0.586	0.103	0.000	00:02:07
ip70-186-183-52.br.br.cox.net	0.128	0.038	0.000	00:00:43
69.160.37.54	1.940	0.339	0.000	00:00:02
TOTAL: 12	278.479	53.148	0.697	56:17:12

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2825
63.142.120.8	50
69.160.37.54	25
ns342002.ip-188-165-239.eu	6
162.212.88.214	4
h10.139.22.98.dynamic.ip.windstream.net	2
ip70-186-183-52.br.br.cox.net	1
TOTAL: 7	2913

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	11	0
challenger.nielsenmedia.com	4	0
202.89.176.220	2	0
h10.139.22.98.dynamic.ip.windstream.net	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 5	20	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	129	100.0%
TOTAL: 1		129	100.0%

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

Newsgroup		Count
1	comp.arch	54
2	comp.lang.awk	21
3	comp.sys.ibm.pc.games.action	14
4	comp.os.vms	12
5	comp.sys.ibm.ps2.hardware	7
6	comp.arch.embedded	5
7	comp.sys.ibm.pc.games.war-historical	4
8	comp.ai.neural-nets	4
9	comp.ai.philosophy	2
10	comp.os.os2.apps	1
11	comp.ai.genetic	1
12	comp.os.os2.ecomstation	1
13	comp.os.os2.programmer.misc	1
14	comp.ai.edu	1
15	comp.sys.ibm.pc.games.rpg	1
TOTAL: 15		129

Daily Usenet report for csiph.com

Nov 10 04:15:00 -- Nov 11 04:15:00