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

Unknown entries from news log file:

First 50 / 2832 lines (1.8%)

Nov 14 04:15:28 csiph nnrpd[62359]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:15:59 csiph nnrpd[62385]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:16:29 csiph nnrpd[62386]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:17:00 csiph nnrpd[62403]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:17:31 csiph nnrpd[62415]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:18:02 csiph nnrpd[62416]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:18:32 csiph nnrpd[62442]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:19:02 csiph nnrpd[62448]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:19:32 csiph nnrpd[62474]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:20:04 csiph nnrpd[62585]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:20:34 csiph nnrpd[62611]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:21:04 csiph nnrpd[62638]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:21:35 csiph nnrpd[62671]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:22:06 csiph nnrpd[62694]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:22:36 csiph nnrpd[62720]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:23:06 csiph nnrpd[62721]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:23:37 csiph nnrpd[62747]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:24:07 csiph nnrpd[62748]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:24:38 csiph nnrpd[62773]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:25:09 csiph nnrpd[62800]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:25:39 csiph nnrpd[62801]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:26:10 csiph nnrpd[62827]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:26:41 csiph nnrpd[62828]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:27:12 csiph nnrpd[62854]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:27:43 csiph nnrpd[62855]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:28:14 csiph nnrpd[62881]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:28:45 csiph nnrpd[62882]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:29:15 csiph nnrpd[62908]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:29:45 csiph nnrpd[62909]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:30:17 csiph nnrpd[62945]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:30:48 csiph nnrpd[62946]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:31:18 csiph nnrpd[62978]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:31:49 csiph nnrpd[63114]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:32:19 csiph nnrpd[63129]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:32:49 csiph nnrpd[63141]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:33:20 csiph nnrpd[63163]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:33:50 csiph nnrpd[63189]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:34:21 csiph nnrpd[63190]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:34:52 csiph nnrpd[63216]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:35:22 csiph nnrpd[63227]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:35:53 csiph nnrpd[63253]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:36:23 csiph nnrpd[63254]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:36:53 csiph nnrpd[63281]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:37:25 csiph nnrpd[63282]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:37:56 csiph nnrpd[63308]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:38:26 csiph nnrpd[63309]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:38:57 csiph nnrpd[63335]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:39:28 csiph nnrpd[63336]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:39:58 csiph nnrpd[63346]: snorky.mixmin.net noperm ihave without permission
Nov 14 04:40:30 csiph nnrpd[63382]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	60915	42.6%	8.7 MB	53.2%
nnrpd	46169	32.3%	4.1 MB	25.3%
innd	34184	23.9%	3.2 MB	19.9%
innfeed	1861	1.3%	265.6 KB	1.6%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	143130	100.0%	16.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	244965	44.6%
Negative hits	237251	43.2%
Do not exist	62291	11.3%
Cache misses	4858	0.9%
TOTAL: 4	549365	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.399	0.0%	237	0.000	1.684	29.000
article cleanup	00:00:00.329	0.0%	46526	0.000	0.007	0.025
article logging	00:00:00.632	0.0%	47093	0.000	0.013	0.147
article parse	00:00:35.074	0.0%	2888482	0.000	0.012	0.100
article write	00:01:16.417	0.1%	24925	0.198	3.066	9.813
artlog/artcncl	00:00:00.001	0.0%	146	0.000	0.007	1.000
artlog/artparse	00:00:00.163	0.0%	13676	0.000	0.012	0.059
data move	00:00:02.489	0.0%	3331032	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.083	0.0%	230	0.000	0.361	17.000
hishave/artcncl	00:00:00.000	0.0%	237	0.000	0.000	0.000
hishave/artparse	00:00:00.048	0.0%	13676	0.000	0.004	0.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.969	0.0%	535886	0.001	0.006	0.057
history sync	00:00:02.079	0.0%	320	0.000	6.497	75.500
history write	00:00:50.737	0.1%	46516	0.057	1.091	8.833
hiswrite/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hiswrite/artparse	00:00:12.847	0.0%	13672	0.000	0.940	20.000
idle	23:53:36.241	99.5%	3266390	6.878	26.334	430.047
nntp read	00:00:12.003	0.0%	3267380	0.001	0.004	0.008
overview write	00:01:08.440	0.1%	24925	0.450	2.746	20.067
perl filter	00:01:29.817	0.1%	46516	0.898	1.931	3.567
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.473	0.0%	49850	0.000	0.009	0.026
TOTAL: 24:00:31.592	23:59:31.241	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.064	0.0%	25185	0.000	0.003	0.018
article prepare	00:00:00.002	0.0%	7348	0.000	0.000	0.050
article read	00:00:00.515	0.0%	1493	0.000	0.345	12.545
backlog stats	00:00:00.113	0.0%	290767	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.159	0.0%	218570	0.001	0.005	0.011
data write	00:00:01.528	0.0%	196834	0.003	0.008	0.015
idle	23:52:34.163	100.0%	290767	185.067	295.612	803.315
status file	00:00:00.079	0.0%	186	0.000	0.425	1.000
TOTAL: 23:52:42.503	23:52:37.623	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.479	0.0%	20277	0.000	0.024	0.062
idle	58:09:50.216	99.8%	60157	0.000	3480.729	601080.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.795	0.0%	121159	0.000	0.007	0.333
readart	00:00:00.057	0.0%	56	0.000	1.018	37.000
TOTAL: 58:16:03.541	58:09:51.547	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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	5	42486	6389	32536	3561	15%	25:16:33
2	au2pb.net	1	26816	5537	20368	911	20%	24:00:29
3	newsfeeds.xmission.com	2	25141	4436	20503	202	17%	23:58:25
4	news-out.mixmin.net	2	33022	3725	24642	4655	11%	24:08:55
5	newsfeed.neva.ru	142	51457	1974	40325	9158	3%	73:08:33
6	newsfeed.fu-berlin.de	26	28967	1281	27242	444	4%	26:50:26
7	xanadu-bbs.net	6	60067	896	43044	16127	1%	60:37:10
8	nntp.pasdenom.info	7	24910	366	24354	190	1%	24:11:25
9	news.swapon.de	1	2537	229	2299	9	9%	22:18:58
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		218	295403	24833	235313	35257	8%	304:30:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	15.7 MB	0.0 KB	34.7 MB	50.4 MB	31%	5.2 KB
2	au2pb.net	14.1 MB	0.0 KB	5.5 MB	19.6 MB	71%	3.1 KB
3	newsfeeds.xmission.com	11.8 MB	0.0 KB	4.1 MB	15.9 MB	74%	3.5 KB
4	news-out.mixmin.net	11.2 MB	0.0 KB	8.8 MB	20.1 MB	55%	2.5 KB
5	newsfeed.neva.ru	5.6 MB	182.9 KB	91.4 MB	97.2 MB	5%	8.9 KB
6	newsfeed.fu-berlin.de	3.1 MB	0.0 KB	904.9 KB	3.9 MB	77%	2.3 KB
7	xanadu-bbs.net	2.5 MB	0.0 KB	4.6 GB	4.6 GB	0%	285.6 KB
8	nntp.pasdenom.info	755.5 KB	0.0 KB	2.2 MB	2.9 MB	25%	5.4 KB
9	news.swapon.de	626.0 KB	0.0 KB	97.2 KB	723.2 KB	86%	3.0 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		65.4 MB	182.9 KB	4.8 GB	4.8 GB	1%	84.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 14 04:15:00 - 04:59:59	361	1.4%	0.13	1.0 MB	1.5%	0.38
Nov 14 05:00:00 - 05:59:59	533	2.1%	0.15	1.7 MB	2.5%	0.48
Nov 14 06:00:00 - 06:59:59	523	2.1%	0.15	1.3 MB	2.0%	0.38
Nov 14 07:00:00 - 07:59:59	726	2.9%	0.20	1.9 MB	2.8%	0.53
Nov 14 08:00:00 - 08:59:59	1017	4.1%	0.28	2.6 MB	3.9%	0.73
Nov 14 09:00:00 - 09:59:59	1186	4.8%	0.33	3.0 MB	4.5%	0.84
Nov 14 10:00:00 - 10:59:59	1273	5.1%	0.35	3.3 MB	5.0%	0.94
Nov 14 11:00:00 - 11:59:59	1090	4.4%	0.30	2.4 MB	3.7%	0.69
Nov 14 12:00:00 - 12:59:59	1162	4.7%	0.32	2.5 MB	3.9%	0.73
Nov 14 13:00:00 - 13:59:59	1298	5.2%	0.36	3.4 MB	5.2%	0.97
Nov 14 14:00:00 - 14:59:59	1258	5.0%	0.35	3.1 MB	4.7%	0.89
Nov 14 15:00:00 - 15:59:59	1385	5.6%	0.38	3.6 MB	5.5%	1.03
Nov 14 16:00:00 - 16:59:59	1394	5.6%	0.39	3.4 MB	5.2%	0.97
Nov 14 17:00:00 - 17:59:59	1439	5.8%	0.40	3.7 MB	5.6%	1.05
Nov 14 18:00:00 - 18:59:59	1457	5.8%	0.40	3.8 MB	5.9%	1.09
Nov 14 19:00:00 - 19:59:59	1295	5.2%	0.36	3.2 MB	4.8%	0.90
Nov 14 20:00:00 - 20:59:59	1329	5.3%	0.37	3.3 MB	5.1%	0.95
Nov 14 21:00:00 - 21:59:59	1275	5.1%	0.35	3.6 MB	5.5%	1.03
Nov 14 22:00:00 - 22:59:59	1303	5.2%	0.36	3.7 MB	5.6%	1.04
Nov 14 23:00:00 - 23:59:59	1002	4.0%	0.28	2.8 MB	4.2%	0.78
Nov 15 00:00:00 - 00:59:59	687	2.8%	0.19	2.1 MB	3.1%	0.59
Nov 15 01:00:00 - 01:59:59	579	2.3%	0.16	1.9 MB	2.9%	0.54
Nov 15 02:00:00 - 02:59:59	735	3.0%	0.20	2.4 MB	3.6%	0.68
Nov 15 03:00:00 - 03:59:59	515	2.1%	0.14	1.7 MB	2.7%	0.50
Nov 15 04:00:00 - 04:15:00	90	0.4%	0.10	257.5 KB	0.4%	0.29
TOTAL: 24:00:00	24912	100.0%	0.29	65.7 MB	100.0%	0.78

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	16123	177	0	15946
2	newsfeed.neva.ru	9172	893	10	8269
3	news-out.mixmin.net	4653	19	0	4634
4	feeder.eternal-september.org	3628	295	0	3333
5	au2pb.net	930	845	0	85
6	newsfeed.fu-berlin.de	900	68	0	832
7	newsfeeds.xmission.com	208	155	0	53
8	nntp.pasdenom.info	197	167	0	30
9	news.swapon.de	46	0	0	46
TOTAL: 9		35857	2619	10	33228

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	211
alt.politics.scorched-earth	142
mailing.freebsd.pkg-fallout	131
mailing.freebsd.svn-ports-all	124
mailing.freebsd.svn-ports-head	122
ut.test	112
pld.cvs.commit	110
lada.talk	100
alt.politics	70
mailing.openbsd.source-changes	63
alt.survival	56
alt.windows7.general	47
mpc.lists.nanog	47
fa.openbsd.source-changes	44
mailing.freebsd.ports-bugs	40
mpc.lists.freebsd.ports.bugs	38
lada.auto	37
fa.freebsd.cvs-all	31
mailing.freebsd.svn-src-all	31
mailing.openbsd.ports-changes	30
TOTAL: 210	2619

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13152
EMP (phn path)	3682
EMP (phl)	1128
Binary: misplaced rar	465
PFM bot misc	253
EMP (phn nph)	77
Too many newsgroups	58
HTML Multipart	53
HTML post	38
Binary: misplaced rev	25
Binary Image: misplaced jpg	21
Binary: misplaced par2	15
Binary: non-binary distribution	11
Too many newsgroups (meow)	4
User-issued spam cancel	2
HTML Image Tags	1
Too many newsgroups (low_xpost)	1
TOTAL: 17	18986

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	13387
	feeder.eternal-september.org	179
	newsfeeds.xmission.com	45
	au2pb.net	31
	news-out.mixmin.net	19
	nntp.pasdenom.info	14
	news.swapon.de	1
	TOTAL: 7	13676
Including strange strings
	newsfeed.fu-berlin.de	442
	feeder.eternal-september.org	54
	news.swapon.de	37
	au2pb.net	14
	news-out.mixmin.net	12
	newsfeeds.xmission.com	3
	newsfeed.neva.ru	2
	xanadu-bbs.net	2
	TOTAL: 8	566
TOTAL: 2		14242

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	20053	184	19100	94	0	0%	23:59:56
2	xanadu-bbs	31151	177	23380	475	6	0%	23:59:57
3	usenet.pasdenom.info	29456	170	23771	603	39	0%	23:59:56
4	fu-berlin.de	23264	36	23210	18	0	0%	23:59:56
5	xmission	24860	14	20298	0	0	0%	23:59:24
6	news.mixmin.net	22007	8	21086	0	0	0%	23:59:50
7	eternal-september	19344	4	17907	3	207	0%	23:59:56
8	neva.ru	23947	3	22759	49	63	0%	23:59:56
9	news.swapon.de	2364	0	2338	0	0	0%	23:59:50
TOTAL: 9		196446	596	173849	1242	315	0%	215:58:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	867.4 KB	1.0 MB	1.9 MB	2.9 KB	23:59:57
2	au2pb	843.1 KB	269.2 KB	1.1 MB	4.0 KB	23:59:56
3	usenet.pasdenom.info	670.5 KB	1.2 MB	1.9 MB	2.5 KB	23:59:56
4	fu-berlin.de	148.4 KB	26.1 KB	174.5 KB	3.2 KB	23:59:56
5	xmission	26.6 KB	0.0 KB	26.6 KB	1.9 KB	23:59:24
6	eternal-september	25.5 KB	4.3 KB	29.8 KB	4.3 KB	23:59:56
7	news.mixmin.net	23.4 KB	0.0 KB	23.4 KB	2.9 KB	23:59:50
8	neva.ru	18.3 KB	253.0 KB	271.3 KB	5.2 KB	23:59:56
9	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0	23:59:50
TOTAL: 9		2.6 MB	2.8 MB	5.4 MB	3.0 KB	215:58:41

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	50	129.4 KB	49	04:22:02
2	92-108-162-83.dynamic.upc.nl	2	6	7.8 KB	1	00:07:14
TOTAL: 2		290	56	137.2 KB	50	58:15:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	321	50	129.4 KB	49	40:31:40
2	*.dynamic.upc.nl	2	6	7.8 KB	1	00:07:14
3	*.chello.nl	10	0	0.0 KB	0	01:35:02
4	*.nielsenmedia.com	5	0	0.0 KB	0	01:43:08
5	*.bbox.io	43	0	0.0 KB	0	08:22:03
6	*.tampfl.dsl-w.verizon.net	8	0	0.0 KB	0	01:00:42
7	*.156.22.98.dynamic.ip.windstream.net	3	0	0.0 KB	0	04:28:35
8	*.mixmin.net	2832	0	0.0 KB	0	00:27:08
TOTAL: 8		3224	56	137.2 KB	50	58:15:36

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.214	1.311	0.933	0.000	36:09:35
tempe0.bbox.io	3.572	0.675	0.000	08:22:03
h143.156.22.98.dynamic.ip.windstream.net	0.282	0.229	0.000	04:28:35
202.89.176.220	27.771	5.921	0.719	04:22:02
challenger.nielsenmedia.com	0.505	0.100	0.000	01:43:08
dhcp-089-099-203-254.chello.nl	0.728	0.173	0.000	01:35:02
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.572	0.181	0.000	01:00:42
snorky.mixmin.net	235.918	46.700	0.000	00:27:08
92-108-162-83.dynamic.upc.nl	0.212	0.048	0.000	00:07:14
69.160.37.54	1.913	0.372	0.000	00:00:02
TOTAL: 10	272.784	55.332	0.719	58:15:36

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2832
tempe0.bbox.io	43
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	10
162.212.88.214	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	8
challenger.nielsenmedia.com	5
h143.156.22.98.dynamic.ip.windstream.net	3
TOTAL: 8	2934

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	11	0
h143.156.22.98.dynamic.ip.windstream.net	3	0
challenger.nielsenmedia.com	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
202.89.176.220	2	0
TOTAL: 5	21	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.pc.games.war-historical	22
2	comp.arch	11
3	comp.sys.ibm.pc.games.action	11
4	comp.sys.ibm.ps2.hardware	6
5	comp.ai.edu	3
6	comp.os.os2.programmer.misc	1
7	comp.arch.embedded	1
8	comp.ai.neural-nets	1
TOTAL: 8		56

Daily Usenet report for csiph.com

Nov 14 04:15:00 -- Nov 15 04:15:00