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

Unknown entries from news log file:

First 50 / 2259 lines (2.2%)

Nov 19 04:15:30 csiph nnrpd[8960]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:16:00 csiph nnrpd[9001]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:16:31 csiph nnrpd[9005]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:17:02 csiph nnrpd[9031]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:17:33 csiph nnrpd[9033]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:18:04 csiph nnrpd[9059]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:18:35 csiph nnrpd[9061]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:19:05 csiph nnrpd[9092]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:19:36 csiph nnrpd[9096]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:20:07 csiph nnrpd[9162]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:20:38 csiph nnrpd[9171]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:21:08 csiph nnrpd[9206]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:21:38 csiph nnrpd[9208]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:22:08 csiph nnrpd[9255]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:22:39 csiph nnrpd[9257]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:23:10 csiph nnrpd[9274]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:23:41 csiph nnrpd[9285]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:24:12 csiph nnrpd[9433]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:24:42 csiph nnrpd[9459]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:25:13 csiph nnrpd[9471]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:25:44 csiph nnrpd[9498]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:26:15 csiph nnrpd[9499]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:26:46 csiph nnrpd[9526]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:27:16 csiph nnrpd[9529]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:27:47 csiph nnrpd[9556]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:28:17 csiph nnrpd[9557]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:28:47 csiph nnrpd[9583]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:29:18 csiph nnrpd[9587]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:29:49 csiph nnrpd[9617]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:30:20 csiph nnrpd[9628]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:30:50 csiph nnrpd[9647]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:31:21 csiph nnrpd[9663]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:31:52 csiph nnrpd[9664]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:32:22 csiph nnrpd[9690]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:32:54 csiph nnrpd[9691]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:33:24 csiph nnrpd[9739]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:33:55 csiph nnrpd[9740]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:34:25 csiph nnrpd[9904]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:34:57 csiph nnrpd[9905]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:35:27 csiph nnrpd[9941]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:35:58 csiph nnrpd[9942]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:36:30 csiph nnrpd[9968]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:37:01 csiph nnrpd[9972]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:37:31 csiph nnrpd[9998]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:38:02 csiph nnrpd[9999]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:38:33 csiph nnrpd[10025]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:39:04 csiph nnrpd[10029]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:39:34 csiph nnrpd[10039]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:40:05 csiph nnrpd[10066]: snorky.mixmin.net noperm ihave without permission
Nov 19 04:40:35 csiph nnrpd[10076]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	56649	44.0%	8.3 MB	55.3%
nnrpd	43454	33.8%	3.8 MB	25.7%
innd	26659	20.7%	2.6 MB	17.2%
innfeed	1880	1.5%	280.8 KB	1.8%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	128643	100.0%	14.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	262689	46.7%
Negative hits	243852	43.4%
Do not exist	55475	9.9%
Cache misses	389	0.1%
TOTAL: 4	562405	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.702	0.0%	213	0.000	3.296	17.000
article cleanup	00:00:00.371	0.0%	47472	0.000	0.008	0.033
article logging	00:00:00.619	0.0%	48054	0.000	0.013	0.035
article parse	00:00:23.954	0.0%	1799887	0.003	0.013	0.039
article write	00:01:20.377	0.1%	27197	0.307	2.955	12.333
artlog/artcncl	00:00:00.004	0.0%	182	0.000	0.022	1.000
artlog/artparse	00:00:00.128	0.0%	8276	0.000	0.015	0.200
data move	00:00:01.519	0.0%	2261610	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.060	0.0%	213	0.000	0.282	7.000
hishave/artcncl	00:00:00.000	0.0%	213	0.000	0.000	0.000
hishave/artparse	00:00:00.028	0.0%	8276	0.000	0.003	0.059
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.434	0.0%	556375	0.001	0.004	0.042
history sync	00:00:01.309	0.0%	325	0.000	4.028	111.000
history write	00:00:35.044	0.0%	47406	0.092	0.739	8.880
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:09.969	0.0%	8276	0.000	1.205	6.450
idle	23:54:26.827	99.6%	2216359	11.169	38.833	258.506
nntp read	00:00:07.314	0.0%	2216213	0.001	0.003	0.006
overview write	00:01:04.941	0.1%	27197	0.306	2.388	48.171
perl filter	00:01:26.614	0.1%	47406	0.983	1.827	3.212
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.482	0.0%	54394	0.000	0.009	0.026
TOTAL: 24:00:36.816	23:59:42.696	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.076	0.0%	27442	0.000	0.003	0.022
article prepare	00:00:00.000	0.0%	8128	0.000	0.000	0.000
article read	00:00:00.278	0.0%	1459	0.000	0.191	11.250
backlog stats	00:00:00.102	0.0%	304882	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.287	0.0%	233238	0.001	0.006	0.013
data write	00:00:01.520	0.0%	210859	0.003	0.007	0.014
idle	23:53:34.131	100.0%	304882	165.407	282.123	738.597
status file	00:00:00.096	0.0%	191	0.000	0.503	1.000
TOTAL: 23:53:42.613	23:53:37.490	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.676	0.0%	20492	0.000	0.082	1.389
idle	73:54:35.715	99.7%	58475	1.000	4550.247	600157.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.725	0.0%	118061	0.000	0.006	0.333
readart	00:00:00.071	0.0%	111	0.000	0.640	46.000
TOTAL: 74:08:32.398	73:54:38.187	99.7%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	147
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	166

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	2	43482	7779	32034	3669	17%	23:39:32
2	newsfeeds.xmission.com	2	28070	5086	22656	328	18%	24:12:56
3	news-out.mixmin.net	3	34594	4906	25230	4458	14%	24:17:56
4	au2pb.net	1	30442	3222	26400	820	10%	24:26:18
5	xanadu-bbs.net	16	50538	2502	37809	10227	4%	30:21:05
6	newsfeed.neva.ru	139	58431	1931	49975	6525	3%	74:44:12
7	newsfeed.fu-berlin.de	61	33826	1396	32019	411	4%	36:17:12
8	news.swapon.de	2	2730	262	2442	26	9%	26:18:59
9	nntp.pasdenom.info	3	28369	252	28031	86	0%	24:08:35
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		255	310482	27336	256596	26550	8%	288:26:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	21.4 MB	134.4 KB	27.2 MB	48.7 MB	43%	4.4 KB
2	news-out.mixmin.net	15.6 MB	0.0 KB	8.3 MB	23.9 MB	65%	2.6 KB
3	newsfeeds.xmission.com	14.1 MB	0.0 KB	3.6 MB	17.7 MB	79%	3.3 KB
4	au2pb.net	8.9 MB	31.7 KB	4.9 MB	13.8 MB	64%	3.5 KB
5	xanadu-bbs.net	6.4 MB	11.3 KB	2.0 GB	2.1 GB	0%	169.1 KB
6	newsfeed.neva.ru	6.3 MB	676.0 KB	59.6 MB	66.5 MB	9%	8.1 KB
7	newsfeed.fu-berlin.de	3.4 MB	0.0 KB	1.1 MB	4.5 MB	75%	2.5 KB
8	news.swapon.de	660.7 KB	0.0 KB	85.3 KB	746.0 KB	88%	2.6 KB
9	nntp.pasdenom.info	576.2 KB	0.0 KB	817.3 KB	1.4 MB	41%	4.1 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		77.2 MB	853.4 KB	2.1 GB	2.2 GB	3%	43.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 19 04:15:00 - 04:59:59	349	1.3%	0.13	1.2 MB	1.6%	0.47
Nov 19 05:00:00 - 05:59:59	562	2.1%	0.16	1.7 MB	2.2%	0.47
Nov 19 06:00:00 - 06:59:59	681	2.5%	0.19	2.3 MB	3.0%	0.65
Nov 19 07:00:00 - 07:59:59	819	3.0%	0.23	2.4 MB	3.2%	0.68
Nov 19 08:00:00 - 08:59:59	1146	4.2%	0.32	3.2 MB	4.2%	0.90
Nov 19 09:00:00 - 09:59:59	1299	4.8%	0.36	3.8 MB	4.9%	1.07
Nov 19 10:00:00 - 10:59:59	1353	5.0%	0.38	3.5 MB	4.6%	1.00
Nov 19 11:00:00 - 11:59:59	1405	5.2%	0.39	3.9 MB	5.1%	1.10
Nov 19 12:00:00 - 12:59:59	1248	4.6%	0.35	3.1 MB	4.1%	0.89
Nov 19 13:00:00 - 13:59:59	1401	5.2%	0.39	3.5 MB	4.6%	1.00
Nov 19 14:00:00 - 14:59:59	1575	5.8%	0.44	4.3 MB	5.6%	1.22
Nov 19 15:00:00 - 15:59:59	1596	5.9%	0.44	4.4 MB	5.7%	1.25
Nov 19 16:00:00 - 16:59:59	1660	6.1%	0.46	4.3 MB	5.7%	1.23
Nov 19 17:00:00 - 17:59:59	1477	5.4%	0.41	3.8 MB	5.0%	1.09
Nov 19 18:00:00 - 18:59:59	1465	5.4%	0.41	3.9 MB	5.1%	1.11
Nov 19 19:00:00 - 19:59:59	1408	5.2%	0.39	3.7 MB	4.9%	1.06
Nov 19 20:00:00 - 20:59:59	1405	5.2%	0.39	3.8 MB	5.0%	1.09
Nov 19 21:00:00 - 21:59:59	1336	4.9%	0.37	3.7 MB	4.9%	1.07
Nov 19 22:00:00 - 22:59:59	1288	4.7%	0.36	3.8 MB	5.0%	1.09
Nov 19 23:00:00 - 23:59:59	1004	3.7%	0.28	3.2 MB	4.2%	0.92
Nov 20 00:00:00 - 00:59:59	719	2.6%	0.20	2.1 MB	2.7%	0.59
Nov 20 01:00:00 - 01:59:59	724	2.7%	0.20	2.4 MB	3.1%	0.68
Nov 20 02:00:00 - 02:59:59	530	1.9%	0.15	1.5 MB	2.0%	0.43
Nov 20 03:00:00 - 03:59:59	604	2.2%	0.17	2.3 MB	3.0%	0.66
Nov 20 04:00:00 - 04:15:00	144	0.5%	0.16	401.2 KB	0.5%	0.45
TOTAL: 24:00:00	27198	100.0%	0.31	76.3 MB	100.0%	0.90

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	12345	389	1	11955
2	newsfeed.neva.ru	6592	1606	51	4935
3	news-out.mixmin.net	4449	25	0	4424
4	feeder.eternal-september.org	3800	465	12	3323
5	au2pb.net	804	735	2	67
6	newsfeed.fu-berlin.de	768	61	0	707
7	newsfeeds.xmission.com	353	295	0	58
8	nntp.pasdenom.info	85	73	0	12
9	news.swapon.de	73	0	0	73
TOTAL: 9		29269	3649	66	25554

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	869
lada.auto	213
lada.talk	188
alt.politics.scorched-earth	180
ut.test	158
mailing.freebsd.svn-ports-all	93
mailing.freebsd.svn-ports-head	89
mailing.openbsd.source-changes	65
alt.politics	59
mailing.freebsd.ports-bugs	55
gmane.comp.gis.openstreetmap	51
mailing.gcc.gcc-cvs	49
alt.windows7.general	49
alt.survival	48
fa.openbsd.source-changes	41
mailing.freebsd.svn-src-all	40
fa.freebsd.cvs-all	40
pld.cvs.commit	39
mailing.unix.spamassassin-talk	39
gmane.linux.alsa.devel	35
TOTAL: 260	3649

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11622
EMP (phn path)	3215
EMP (phl)	916
Binary: misplaced rar	321
PFM bot misc	199
EMP (phn nph)	141
HTML Multipart	92
Too many newsgroups	46
Binary: misplaced rev	21
Binary: misplaced par2	12
HTML post	11
Binary Payload	2
Too many newsgroups (meow)	2
Binary: misplaced sfv	1
Binary Image: misplaced jpg	1
Binary: misplaced nfo	1
Binary: misplaced r23	1
Binary: misplaced srr	1
TOTAL: 18	16605

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	8212
	feeder.eternal-september.org	80
	newsfeeds.xmission.com	30
	news-out.mixmin.net	19
	au2pb.net	19
	nntp.pasdenom.info	7
	TOTAL: 6	8367
Including strange strings
	newsfeed.fu-berlin.de	406
	news.swapon.de	50
	xanadu-bbs.net	46
	feeder.eternal-september.org	35
	newsfeeds.xmission.com	25
	news-out.mixmin.net	12
	newsfeed.neva.ru	5
	au2pb.net	3
	TOTAL: 8	582
TOTAL: 2		8949

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	32803	307	26102	536	2	0%	23:59:56
2	au2pb	25544	293	23432	306	0	1%	23:59:56
3	xanadu-bbs	29313	140	24275	296	23	0%	23:59:56
4	fu-berlin.de	25456	39	25373	44	0	0%	23:59:56
5	news.mixmin.net	23155	15	22213	0	0	0%	23:59:44
6	neva.ru	25979	7	24903	43	277	0%	23:59:56
7	xmission	26133	6	21806	0	0	0%	23:59:56
8	eternal-september	20052	3	18602	1	0	0%	23:59:56
9	news.swapon.de	2376	0	2335	6	0	0%	23:58:23
TOTAL: 9		210811	810	189041	1232	302	0%	215:57:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.1 MB	703.4 KB	1.8 MB	3.1 KB	23:59:56
2	usenet.pasdenom.info	965.0 KB	1.2 MB	2.1 MB	2.6 KB	23:59:56
3	xanadu-bbs	719.4 KB	624.0 KB	1.3 MB	3.1 KB	23:59:56
4	fu-berlin.de	159.6 KB	57.0 KB	216.6 KB	2.6 KB	23:59:56
5	news.mixmin.net	42.4 KB	0.0 KB	42.4 KB	2.8 KB	23:59:44
6	neva.ru	38.5 KB	176.7 KB	215.2 KB	4.3 KB	23:59:56
7	eternal-september	15.5 KB	1.5 KB	17.0 KB	4.3 KB	23:59:56
8	xmission	11.0 KB	0.0 KB	11.0 KB	1.8 KB	23:59:56
9	news.swapon.de	0.0 KB	18.8 KB	18.8 KB	3.1 KB	23:58:23
TOTAL: 9		3.0 MB	2.7 MB	5.8 MB	2.9 KB	215:57:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	284	105	292.3 KB	98	04:10:10
2	tempe0.bbox.io	22	2	6.2 KB	1	06:17:01
3	dhcp-089-099-203-254.chello.nl	10	2	3.1 KB	2	04:08:44
4	challenger.nielsenmedia.com	22	1	1.7 KB	1	04:13:58
5	pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	1	1.7 KB	1	01:00:22
TOTAL: 5		342	111	305.0 KB	103	74:08:07

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	417	105	292.3 KB	98	54:37:09
2	*.chello.nl	10	2	3.1 KB	2	04:08:44
3	*.bbox.io	22	2	6.2 KB	1	06:17:01
4	*.nielsenmedia.com	22	1	1.7 KB	1	04:13:58
5	*.tampfl.dsl-w.verizon.net	4	1	1.7 KB	1	01:00:22
6	*.154.22.98.dynamic.ip.windstream.net	3	0	0.0 KB	0	03:28:58
7	*.mixmin.net	2259	0	0.0 KB	0	00:21:53
TOTAL: 7		2737	111	305.0 KB	103	74:08:07

NNRP auth users [Top 20]:

User	Conn
moussa	284
TOTAL: 1	284

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.214	0.463	0.490	0.000	29:42:02
63.142.120.8	8.020	2.343	0.000	20:44:54
tempe0.bbox.io	2.253	0.316	0.000	06:17:01
challenger.nielsenmedia.com	1.789	0.354	0.000	04:13:58
202.89.176.220	29.956	6.007	0.718	04:10:10
dhcp-089-099-203-254.chello.nl	0.950	0.163	0.000	04:08:44
h112.154.22.98.dynamic.ip.windstream.net	0.258	0.170	0.000	03:28:58
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.414	0.064	0.000	01:00:22
snorky.mixmin.net	188.370	36.302	0.000	00:21:53
69.160.37.54	2.026	0.402	0.000	00:00:02
TOTAL: 10	234.499	46.611	0.718	74:08:07

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2259
63.142.120.8	108
69.160.37.54	25
h112.154.22.98.dynamic.ip.windstream.net	3
TOTAL: 4	2395

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	10	0
challenger.nielsenmedia.com	7	0
dhcp-089-099-203-254.chello.nl	7	0
h112.154.22.98.dynamic.ip.windstream.net	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
TOTAL: 5	29	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	39
2	comp.sys.ibm.pc.games.action	14
3	comp.os.vms	14
4	comp.arch.embedded	13
5	comp.sys.ibm.pc.games.war-historical	7
6	comp.ai.neural-nets	6
7	comp.sys.ibm.pc.hardware.chips	2
8	comp.sys.ibm.ps2.hardware	2
9	comp.arch.fpga	2
10	comp.sys.ibm.pc.games.rpg	2
11	comp.unix.bsd.freebsd.misc	2
12	comp.lang.awk	2
13	comp.ai.philosophy	2
14	comp.ai.nat-lang	1
15	comp.ai.genetic	1
16	comp.sys.ibm.as400.misc	1
17	comp.os.msdos.misc	1
TOTAL: 17		111

Daily Usenet report for csiph.com

Nov 19 04:15:00 -- Nov 20 04:15:00