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

Unknown entries from news log file:

First 50 / 2827 lines (1.8%)

Nov 17 04:15:09 csiph nnrpd[66294]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:15:40 csiph nnrpd[66406]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:16:11 csiph nnrpd[66407]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:16:42 csiph nnrpd[66433]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:17:13 csiph nnrpd[66434]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:17:43 csiph nnrpd[66460]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:18:14 csiph nnrpd[66461]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:18:45 csiph nnrpd[66478]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:19:15 csiph nnrpd[66493]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:19:45 csiph nnrpd[66494]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:20:15 csiph nnrpd[66556]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:20:46 csiph nnrpd[66584]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:21:16 csiph nnrpd[66628]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:21:46 csiph nnrpd[66647]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:22:17 csiph nnrpd[66716]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:22:48 csiph nnrpd[66739]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:23:19 csiph nnrpd[66778]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:23:50 csiph nnrpd[66791]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:24:20 csiph nnrpd[66817]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:24:51 csiph nnrpd[66818]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:25:21 csiph nnrpd[66854]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:25:51 csiph nnrpd[66855]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:26:22 csiph nnrpd[66861]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:26:53 csiph nnrpd[66882]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:27:24 csiph nnrpd[66883]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:27:54 csiph nnrpd[66909]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:28:25 csiph nnrpd[66910]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:28:55 csiph nnrpd[66936]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:29:26 csiph nnrpd[66937]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:29:57 csiph nnrpd[66963]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:30:27 csiph nnrpd[66974]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:30:57 csiph nnrpd[67000]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:31:28 csiph nnrpd[67007]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:31:59 csiph nnrpd[67033]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:32:30 csiph nnrpd[67034]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:33:01 csiph nnrpd[67081]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:33:31 csiph nnrpd[67217]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:34:01 csiph nnrpd[67218]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:34:33 csiph nnrpd[67244]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:35:03 csiph nnrpd[67255]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:35:33 csiph nnrpd[67281]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:36:04 csiph nnrpd[67282]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:36:34 csiph nnrpd[67308]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:37:04 csiph nnrpd[67309]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:37:35 csiph nnrpd[67335]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:38:05 csiph nnrpd[67336]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:38:35 csiph nnrpd[67362]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:39:06 csiph nnrpd[67363]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:39:36 csiph nnrpd[67389]: snorky.mixmin.net noperm ihave without permission
Nov 17 04:40:08 csiph nnrpd[67400]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	64553	44.0%	9.3 MB	55.1%
nnrpd	45403	31.0%	4.1 MB	23.9%
innd	34793	23.7%	3.3 MB	19.4%
innfeed	1880	1.3%	275.4 KB	1.6%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	146634	100.0%	17.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	260857	44.6%
Negative hits	246727	42.2%
Do not exist	69326	11.9%
Cache misses	7646	1.3%
TOTAL: 4	584556	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.341	0.0%	137	0.000	2.489	26.000
article cleanup	00:00:00.346	0.0%	48215	0.000	0.007	0.029
article logging	00:00:00.664	0.0%	48693	0.000	0.014	0.038
article parse	00:00:34.389	0.0%	3042764	0.006	0.011	0.054
article write	00:01:25.444	0.1%	27266	0.182	3.134	10.068
artlog/artcncl	00:00:00.003	0.0%	120	0.000	0.025	0.100
artlog/artparse	00:00:00.244	0.0%	15489	0.000	0.016	0.311
data move	00:00:02.905	0.0%	3496476	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.040	0.0%	136	0.000	0.294	10.000
hishave/artcncl	00:00:00.000	0.0%	137	0.000	0.000	0.000
hishave/artparse	00:00:00.056	0.0%	15467	0.000	0.004	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.982	0.0%	571325	0.001	0.005	0.058
history sync	00:00:02.154	0.0%	304	0.000	7.086	122.500
history write	00:00:54.372	0.1%	48206	0.090	1.128	8.587
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:10.173	0.0%	15464	0.000	0.658	15.333
idle	23:42:54.364	99.5%	3450006	5.822	24.746	217.929
nntp read	00:00:13.630	0.0%	3452664	0.002	0.004	0.006
overview write	00:01:11.699	0.1%	27266	0.457	2.630	11.213
perl filter	00:01:32.565	0.1%	48206	1.015	1.920	3.737
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.476	0.0%	54533	0.000	0.009	0.029
TOTAL: 23:50:16.837	23:49:06.847	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.087	0.0%	27391	0.000	0.003	0.024
article prepare	00:00:00.001	0.0%	8056	0.000	0.000	0.015
article read	00:00:00.246	0.0%	1595	0.000	0.154	23.000
backlog stats	00:00:00.102	0.0%	315697	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.332	0.0%	237348	0.001	0.006	0.014
data write	00:00:01.634	0.0%	216413	0.003	0.008	0.015
idle	23:53:18.708	100.0%	315697	154.977	272.409	724.622
status file	00:00:00.085	0.0%	191	0.000	0.445	1.000
TOTAL: 23:53:27.360	23:53:22.195	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.202	0.0%	7919	0.000	0.026	0.333
idle	40:28:39.217	99.8%	47189	0.000	3087.991	600092.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.620	0.0%	95355	0.000	0.007	0.333
readart	00:00:00.011	0.0%	93	0.000	0.118	1.000
TOTAL: 40:34:18.494	40:28:40.050	99.8%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
newsmaster@carnet.hr	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	6	44832	7177	34064	3591	16%	24:57:28
2	au2pb.net	1	30959	6339	23394	1226	20%	24:48:55
3	news-out.mixmin.net	2	34093	4472	25497	4124	13%	24:08:55
4	newsfeeds.xmission.com	1	28319	4284	23803	232	15%	23:29:58
5	newsfeed.neva.ru	143	53634	2492	42213	8929	4%	72:01:38
6	newsfeed.fu-berlin.de	62	30501	1255	28851	395	4%	30:45:52
7	xanadu-bbs.net	35	64731	934	45409	18388	1%	86:50:07
8	news.swapon.de	2	2641	219	2397	25	8%	23:19:16
9	nntp.pasdenom.info	6	29065	215	28693	157	0%	24:16:53
10	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 10		284	318775	27387	254321	37067	8%	334:39:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.1 MB	13.3 KB	28.8 MB	48.9 MB	41%	4.7 KB
2	au2pb.net	17.3 MB	5.1 KB	5.3 MB	22.7 MB	76%	3.1 KB
3	news-out.mixmin.net	15.8 MB	4.8 KB	10.0 MB	25.8 MB	61%	3.1 KB
4	newsfeeds.xmission.com	11.8 MB	0.0 KB	2.3 MB	14.1 MB	83%	3.2 KB
5	newsfeed.neva.ru	8.4 MB	5.4 KB	83.7 MB	92.1 MB	9%	8.3 KB
6	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	1.3 MB	4.2 MB	68%	2.6 KB
7	xanadu-bbs.net	2.5 MB	0.0 KB	5.2 GB	5.2 GB	0%	283.6 KB
8	news.swapon.de	520.0 KB	0.0 KB	1.4 MB	1.9 MB	26%	8.1 KB
9	nntp.pasdenom.info	450.3 KB	0.0 KB	3.4 MB	3.9 MB	11%	10.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		79.8 MB	28.6 KB	5.4 GB	5.4 GB	1%	88.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 17 04:15:00 - 04:59:59	410	1.5%	0.15	1.3 MB	1.6%	0.49
Nov 17 05:00:00 - 05:59:59	547	2.0%	0.15	2.1 MB	2.6%	0.59
Nov 17 06:00:00 - 06:59:59	670	2.4%	0.19	2.9 MB	3.6%	0.82
Nov 17 07:00:00 - 07:59:59	810	3.0%	0.23	2.6 MB	3.3%	0.75
Nov 17 08:00:00 - 08:59:59	1063	3.9%	0.30	3.0 MB	3.8%	0.87
Nov 17 09:00:00 - 09:59:59	1371	5.0%	0.38	3.9 MB	5.0%	1.12
Nov 17 10:00:00 - 10:59:59	1384	5.1%	0.38	3.9 MB	5.0%	1.12
Nov 17 11:00:00 - 11:59:59	1245	4.5%	0.35	3.5 MB	4.4%	1.00
Nov 17 12:00:00 - 12:59:59	1093	4.0%	0.30	3.0 MB	3.7%	0.84
Nov 17 13:00:00 - 13:59:59	1287	4.7%	0.36	3.4 MB	4.3%	0.97
Nov 17 14:00:00 - 14:59:59	1314	4.8%	0.36	3.5 MB	4.4%	1.00
Nov 17 15:00:00 - 15:59:59	1515	5.5%	0.42	4.1 MB	5.2%	1.18
Nov 17 16:00:00 - 16:59:59	1761	6.4%	0.49	5.1 MB	6.4%	1.45
Nov 17 17:00:00 - 17:59:59	1650	6.0%	0.46	4.4 MB	5.6%	1.26
Nov 17 18:00:00 - 18:59:59	1595	5.8%	0.44	4.7 MB	5.9%	1.33
Nov 17 19:00:00 - 19:59:59	1606	5.9%	0.45	4.5 MB	5.7%	1.29
Nov 17 20:00:00 - 20:59:59	1726	6.3%	0.48	4.8 MB	6.0%	1.36
Nov 17 21:00:00 - 21:59:59	1389	5.1%	0.39	3.8 MB	4.7%	1.07
Nov 17 22:00:00 - 22:59:59	1288	4.7%	0.36	3.6 MB	4.6%	1.03
Nov 17 23:00:00 - 23:59:59	951	3.5%	0.26	2.8 MB	3.6%	0.80
Nov 18 00:00:00 - 00:59:59	764	2.8%	0.21	2.2 MB	2.8%	0.64
Nov 18 01:00:00 - 01:59:59	721	2.6%	0.20	2.1 MB	2.7%	0.61
Nov 18 02:00:00 - 02:59:59	526	1.9%	0.15	1.5 MB	1.9%	0.44
Nov 18 03:00:00 - 03:59:59	478	1.7%	0.13	1.4 MB	1.7%	0.39
Nov 18 04:00:00 - 04:15:00	203	0.7%	0.23	942.5 KB	1.2%	1.05
TOTAL: 24:00:00	27367	100.0%	0.32	79.4 MB	100.0%	0.94

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	17975	196	0	17779
2	newsfeed.neva.ru	8865	1663	3	7199
3	news-out.mixmin.net	4173	9	2	4162
4	feeder.eternal-september.org	3610	415	2	3193
5	au2pb.net	1214	1132	2	80
6	newsfeed.fu-berlin.de	780	54	0	726
7	newsfeeds.xmission.com	241	199	0	42
8	nntp.pasdenom.info	155	111	0	44
9	news.swapon.de	53	0	0	53
TOTAL: 9		37066	3779	9	33278

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	986
lada.auto	133
alt.politics.scorched-earth	126
ut.test	114
mailing.freebsd.pkg-fallout	111
mailing.freebsd.ports-bugs	108
lada.talk	100
pld.cvs.commit	89
alt.politics	81
alt.survival	70
mailing.gcc.gcc-cvs	64
alt.windows7.general	64
mailing.openbsd.ports-changes	53
it-alt.sport.calcio.juventus	52
mozilla.dev.tree-alerts	51
mailing.openbsd.source-changes	50
mailing.freebsd.svn-ports-head	44
mailing.freebsd.svn-ports-all	44
mailing.freebsd.svn-src-all	39
fa.freebsd.cvs-all	38
TOTAL: 276	3779

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12287
EMP (phn path)	3108
EMP (phl)	861
Binary: misplaced rar	347
EMP (phn nph)	228
PFM bot misc	228
Too many newsgroups	88
HTML post	60
HTML Multipart	51
Binary: misplaced rev	12
Binary: misplaced par2	12
Binary Payload	2
Binary: misplaced srr	2
Binary: misplaced nfo	2
Binary: misplaced sfv	2
User-issued spam cancel	1
HTML Image Tags	1
Binary Image: misplaced jpg	1
TOTAL: 18	17293

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad command received
	xanadu-bbs.net	217
	TOTAL: 1	217
Huge articles
	xanadu-bbs.net	15292
	feeder.eternal-september.org	93
	nntp.pasdenom.info	35
	news-out.mixmin.net	28
	au2pb.net	25
	newsfeeds.xmission.com	22
	newsfeed.fu-berlin.de	3
	news.swapon.de	3
	TOTAL: 8	15501
Including strange strings
	newsfeed.fu-berlin.de	381
	feeder.eternal-september.org	38
	news.swapon.de	28
	newsfeeds.xmission.com	13
	news-out.mixmin.net	12
	au2pb.net	5
	xanadu-bbs.net	2
	newsfeed.neva.ru	1
	TOTAL: 8	480
TOTAL: 3		16198

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	35754	240	25740	477	29	0%	23:59:56
2	usenet.pasdenom.info	32443	203	26305	645	52	0%	23:59:56
3	au2pb	22062	170	20813	108	0	0%	23:59:56
4	fu-berlin.de	25712	46	25644	22	0	0%	23:59:56
5	news.mixmin.net	23769	24	22759	0	0	0%	23:59:52
6	xmission	28733	10	22687	1	0	0%	23:59:56
7	neva.ru	25668	8	24672	49	0	0%	23:59:56
8	eternal-september	20659	2	19327	7	0	0%	23:59:54
9	news.swapon.de	2545	2	2509	1	0	0%	23:57:00
TOTAL: 9		217345	705	190456	1310	81	0%	215:56:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	1.6 MB	1.2 MB	2.7 MB	3.9 KB	23:59:56
2	au2pb	843.2 KB	256.0 KB	1.1 MB	4.0 KB	23:59:56
3	usenet.pasdenom.info	767.3 KB	1.6 MB	2.3 MB	2.8 KB	23:59:56
4	fu-berlin.de	199.9 KB	26.2 KB	226.0 KB	3.3 KB	23:59:56
5	news.mixmin.net	93.6 KB	0.0 KB	93.6 KB	3.9 KB	23:59:52
6	neva.ru	41.3 KB	271.5 KB	312.8 KB	5.5 KB	23:59:56
7	xmission	19.1 KB	1.3 KB	20.5 KB	1.9 KB	23:59:56
8	eternal-september	7.2 KB	9.8 KB	17.0 KB	1.9 KB	23:59:54
9	news.swapon.de	3.0 KB	1.9 KB	4.9 KB	1.6 KB	23:57:00
TOTAL: 9		3.5 MB	3.3 MB	6.8 MB	3.4 KB	215:56:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	282	92	282.5 KB	86	04:02:52
2	tempe0.bbox.io	6	1	1.6 KB	1	02:16:16
TOTAL: 2		288	93	284.0 KB	87	40:33:51

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	311	92	282.5 KB	86	30:53:37
2	*.bbox.io	6	1	1.6 KB	1	02:16:16
3	*.nielsenmedia.com	7	0	0.0 KB	0	02:38:01
4	*.tampfl.dsl-w.verizon.net	6	0	0.0 KB	0	00:37:54
5	*.ip-188-165-239.eu	6	0	0.0 KB	0	00:03:24
6	*.154.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	00:30:17
7	*.chello.nl	19	0	0.0 KB	0	03:07:22
8	*.mixmin.net	2826	0	0.0 KB	0	00:26:57
TOTAL: 8		3182	93	284.0 KB	87	40:33:51

NNRP auth users [Top 20]:

User	Conn
moussa	282
TOTAL: 1	282

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.214	0.427	0.418	0.000	26:50:43
202.89.176.220	28.794	6.175	0.711	04:02:52
dhcp-089-099-203-254.chello.nl	1.568	0.292	0.000	03:07:22
challenger.nielsenmedia.com	0.542	0.109	0.000	02:38:01
tempe0.bbox.io	0.646	0.122	0.000	02:16:16
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.376	0.095	0.000	00:37:54
h112.154.22.98.dynamic.ip.windstream.net	0.086	0.031	0.000	00:30:17
snorky.mixmin.net	235.923	47.254	0.000	00:26:57
ns342002.ip-188-165-239.eu	0.502	0.088	0.000	00:03:24
69.160.37.54	2.074	0.413	0.000	00:00:02
TOTAL: 10	270.938	54.997	0.711	40:33:51

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2826
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	19
challenger.nielsenmedia.com	7
ns342002.ip-188-165-239.eu	6
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6
162.212.88.214	4
h112.154.22.98.dynamic.ip.windstream.net	1
TOTAL: 8	2894

NNRP client timeouts [Top 20]:

System	Conn	Peer
challenger.nielsenmedia.com	5	0
tempe0.bbox.io	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
h112.154.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 4	10	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	35
2	comp.sys.ibm.pc.hardware.storage	11
3	comp.sys.ibm.pc.games.action	10
4	comp.os.vms	10
5	comp.sys.ibm.pc.games.war-historical	8
6	comp.arch.embedded	5
7	comp.os.os2.advocacy	3
8	comp.ai.neural-nets	3
9	comp.os.os2.misc	2
10	comp.sys.ibm.as400.misc	2
11	comp.os.os2.ecomstation	2
12	comp.sys.ibm.ps2.hardware	2
TOTAL: 12		93

Daily Usenet report for csiph.com

Nov 17 04:15:00 -- Nov 18 04:15:00