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

Unknown entries from news log file:

First 50 / 2825 lines (1.8%)

Nov 13 04:15:29 csiph nnrpd[95482]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:15:59 csiph nnrpd[95483]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:16:32 csiph nnrpd[95509]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:17:02 csiph nnrpd[95510]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:17:33 csiph nnrpd[95536]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:18:04 csiph nnrpd[95537]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:18:34 csiph nnrpd[95563]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:19:04 csiph nnrpd[95569]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:19:35 csiph nnrpd[95593]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:20:05 csiph nnrpd[95632]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:20:36 csiph nnrpd[95640]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:21:07 csiph nnrpd[95666]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:21:37 csiph nnrpd[95668]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:22:07 csiph nnrpd[95715]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:22:38 csiph nnrpd[95716]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:23:08 csiph nnrpd[95742]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:23:40 csiph nnrpd[95743]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:24:10 csiph nnrpd[95781]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:24:40 csiph nnrpd[95782]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:25:12 csiph nnrpd[95818]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:25:42 csiph nnrpd[95819]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:26:12 csiph nnrpd[95849]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:26:43 csiph nnrpd[95850]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:27:13 csiph nnrpd[95869]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:27:44 csiph nnrpd[95877]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:28:14 csiph nnrpd[95878]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:28:45 csiph nnrpd[95904]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:29:16 csiph nnrpd[95905]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:29:47 csiph nnrpd[95934]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:30:18 csiph nnrpd[95945]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:30:49 csiph nnrpd[95971]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:31:20 csiph nnrpd[96113]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:31:51 csiph nnrpd[96139]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:32:22 csiph nnrpd[96140]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:32:53 csiph nnrpd[96166]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:33:24 csiph nnrpd[96188]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:33:55 csiph nnrpd[96214]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:34:25 csiph nnrpd[96215]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:34:56 csiph nnrpd[96244]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:35:27 csiph nnrpd[96255]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:35:58 csiph nnrpd[96267]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:36:28 csiph nnrpd[96282]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:36:59 csiph nnrpd[96283]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:37:29 csiph nnrpd[96309]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:38:00 csiph nnrpd[96310]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:38:31 csiph nnrpd[96336]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:39:02 csiph nnrpd[96337]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:39:32 csiph nnrpd[96363]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:40:04 csiph nnrpd[96374]: snorky.mixmin.net noperm ihave without permission
Nov 13 04:40:35 csiph nnrpd[96409]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	51852	42.8%	7.5 MB	53.9%
nnrpd	46496	38.4%	4.1 MB	29.8%
innd	20851	17.2%	2.0 MB	14.4%
innfeed	1851	1.5%	271.2 KB	1.9%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	121051	100.0%	13.9 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	268003	45.9%
Positive hits	265169	45.4%
Do not exist	51148	8.8%
Cache misses	111	0.0%
TOTAL: 4	584431	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.541	0.0%	135	0.000	4.007	30.143
article cleanup	00:00:00.359	0.0%	50494	0.000	0.007	0.022
article logging	00:00:00.706	0.0%	51040	0.000	0.014	0.040
article parse	00:00:03.290	0.0%	254943	0.003	0.013	0.073
article write	00:01:12.224	0.1%	28064	0.211	2.574	8.076
artlog/artcncl	00:00:00.001	0.0%	124	0.000	0.008	0.143
artlog/artparse	00:00:00.007	0.0%	733	0.000	0.010	0.077
data move	00:00:00.311	0.0%	725925	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.106	0.0%	134	0.000	0.791	9.000
hishave/artcncl	00:00:00.000	0.0%	135	0.000	0.000	0.000
hishave/artparse	00:00:00.000	0.0%	733	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:12.295	0.0%	584352	0.001	0.021	0.151
history sync	00:00:02.925	0.0%	354	0.000	8.263	105.500
history write	00:01:21.463	0.1%	50487	0.079	1.614	6.947
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.048	0.0%	733	0.000	1.430	23.400
idle	23:53:55.377	99.5%	716003	35.575	120.161	353.758
nntp read	00:00:02.465	0.0%	713826	0.002	0.003	0.008
overview write	00:01:22.894	0.1%	28064	0.433	2.954	14.133
perl filter	00:01:38.832	0.1%	50487	0.976	1.958	3.922
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.531	0.0%	56128	0.000	0.009	0.027
TOTAL: 24:00:50.807	23:59:55.375	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.071	0.0%	28037	0.000	0.003	0.014
article prepare	00:00:00.002	0.0%	5412	0.000	0.000	0.062
article read	00:00:00.218	0.0%	1099	0.000	0.198	67.000
backlog stats	00:00:00.098	0.0%	304211	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.357	0.0%	238196	0.000	0.006	0.012
data write	00:00:01.547	0.0%	214686	0.001	0.007	0.018
idle	23:54:51.033	100.0%	304211	157.396	282.998	895.261
status file	00:00:00.080	0.0%	190	0.000	0.421	1.000
TOTAL: 23:54:59.627	23:54:54.406	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:05.578	0.0%	36655	0.000	0.152	0.385
idle	85:20:50.958	99.9%	76674	0.000	4007.238	600092.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.969	0.0%	154277	0.000	0.006	0.333
readart	00:00:00.012	0.0%	75	0.000	0.160	2.000
TOTAL: 85:27:03.803	85:20:57.517	99.9%	-	-	-	-

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	46921	7402	36730	2789	15%	24:55:53
2	news-out.mixmin.net	2	35782	5514	25762	4506	15%	23:59:49
3	au2pb.net	1	31440	4577	25812	1051	14%	24:23:48
4	xanadu-bbs.net	0	50755	3983	39542	7230	7%	23:47:46
5	newsfeeds.xmission.com	1	29356	2412	26728	216	8%	23:50:43
6	newsfeed.neva.ru	139	56090	2383	46833	6874	4%	71:32:49
7	newsfeed.fu-berlin.de	60	34011	1456	32140	415	4%	35:18:41
8	nntp.pasdenom.info	3	30046	266	29624	156	0%	24:09:32
9	news.swapon.de	2	2613	241	2367	5	9%	25:43:21
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		239	317014	28234	265538	23242	8%	277:42:22

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	19.7 MB	1.8 KB	18.3 MB	37.9 MB	51%	3.8 KB
2	news-out.mixmin.net	17.5 MB	0.0 KB	8.4 MB	25.9 MB	67%	2.7 KB
3	au2pb.net	12.3 MB	39.4 KB	5.2 MB	17.5 MB	70%	3.2 KB
4	xanadu-bbs.net	10.3 MB	39.2 KB	170.1 MB	180.5 MB	5%	16.5 KB
5	newsfeeds.xmission.com	7.1 MB	0.0 KB	4.8 MB	11.9 MB	59%	4.6 KB
6	newsfeed.neva.ru	7.1 MB	13.2 KB	58.4 MB	65.5 MB	10%	7.2 KB
7	newsfeed.fu-berlin.de	3.6 MB	0.0 KB	1.0 MB	4.6 MB	77%	2.5 KB
8	nntp.pasdenom.info	625.8 KB	31.6 KB	889.2 KB	1.5 MB	40%	3.7 KB
9	news.swapon.de	617.3 KB	0.0 KB	513.4 KB	1.1 MB	54%	4.6 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		78.7 MB	125.2 KB	267.6 MB	346.5 MB	22%	6.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 13 04:15:00 - 04:59:59	374	1.3%	0.14	1.1 MB	1.4%	0.42
Nov 13 05:00:00 - 05:59:59	571	2.0%	0.16	2.0 MB	2.6%	0.57
Nov 13 06:00:00 - 06:59:59	601	2.1%	0.17	1.8 MB	2.3%	0.51
Nov 13 07:00:00 - 07:59:59	953	3.4%	0.26	2.6 MB	3.4%	0.75
Nov 13 08:00:00 - 08:59:59	1077	3.8%	0.30	2.9 MB	3.7%	0.81
Nov 13 09:00:00 - 09:59:59	1300	4.6%	0.36	3.5 MB	4.5%	1.00
Nov 13 10:00:00 - 10:59:59	1471	5.2%	0.41	3.8 MB	4.9%	1.09
Nov 13 11:00:00 - 11:59:59	1197	4.3%	0.33	3.1 MB	4.0%	0.88
Nov 13 12:00:00 - 12:59:59	1356	4.8%	0.38	3.5 MB	4.5%	1.00
Nov 13 13:00:00 - 13:59:59	1510	5.4%	0.42	3.8 MB	4.9%	1.09
Nov 13 14:00:00 - 14:59:59	1543	5.5%	0.43	4.5 MB	5.7%	1.27
Nov 13 15:00:00 - 15:59:59	1660	5.9%	0.46	4.8 MB	6.1%	1.35
Nov 13 16:00:00 - 16:59:59	1624	5.8%	0.45	4.4 MB	5.7%	1.27
Nov 13 17:00:00 - 17:59:59	1609	5.7%	0.45	4.4 MB	5.7%	1.26
Nov 13 18:00:00 - 18:59:59	1563	5.6%	0.43	4.2 MB	5.4%	1.19
Nov 13 19:00:00 - 19:59:59	1518	5.4%	0.42	4.2 MB	5.4%	1.19
Nov 13 20:00:00 - 20:59:59	1317	4.7%	0.37	3.5 MB	4.5%	1.00
Nov 13 21:00:00 - 21:59:59	1292	4.6%	0.36	3.3 MB	4.2%	0.93
Nov 13 22:00:00 - 22:59:59	1556	5.5%	0.43	4.4 MB	5.6%	1.25
Nov 13 23:00:00 - 23:59:59	1129	4.0%	0.31	3.2 MB	4.1%	0.90
Nov 14 00:00:00 - 00:59:59	892	3.2%	0.25	2.6 MB	3.4%	0.75
Nov 14 01:00:00 - 01:59:59	727	2.6%	0.20	2.6 MB	3.3%	0.74
Nov 14 02:00:00 - 02:59:59	576	2.1%	0.16	1.6 MB	2.1%	0.46
Nov 14 03:00:00 - 03:59:59	518	1.8%	0.14	1.7 MB	2.2%	0.48
Nov 14 04:00:00 - 04:15:00	131	0.5%	0.15	366.3 KB	0.5%	0.41
TOTAL: 24:00:00	28065	100.0%	0.32	77.9 MB	100.0%	0.92

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	xanadu-bbs.net	7241	606	3	0	6632
2	newsfeed.neva.ru	6866	1575	1	1	5289
3	news-out.mixmin.net	4518	19	0	0	4499
4	feeder.eternal-september.org	2813	339	1	0	2473
5	au2pb.net	1035	982	1	0	52
6	newsfeed.fu-berlin.de	782	45	0	0	737
7	newsfeeds.xmission.com	198	171	0	0	27
8	nntp.pasdenom.info	153	135	1	0	17
9	news.swapon.de	57	0	0	0	57
TOTAL: 9		23663	3872	7	1	19783

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	811
lada.talk	189
lada.auto	142
ut.test	140
alt.politics.scorched-earth	124
mailing.freebsd.svn-src-all	113
fa.freebsd.cvs-all	111
mailing.freebsd.ports-bugs	98
mailing.freebsd.svn-ports-all	90
mailing.freebsd.svn-ports-head	89
mailing.gcc.gcc-cvs	83
mozilla.dev.tree-alerts	73
alt.politics	62
mailing.openbsd.source-changes	60
pld.cvs.commit	59
mailing.freebsd.svn-src-head	58
mailing.freebsd.pkg-fallout	56
mailing.freebsd.svn-src-stable	55
mailing.freebsd.svn-src-stable-10	52
alt.windows7.general	42
TOTAL: 256	3872

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

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13376
EMP (phn path)	3413
EMP (phl)	1072
EMP (phn nph)	249
PFM bot misc	197
HTML Multipart	91
Too many newsgroups	66
HTML post	23
HTML Image Tags	9
User-issued spam cancel	5
Too many newsgroups (meow)	4
Binary Payload	1
Binary Image: misplaced jpg	1
Binary: misplaced rar	1
TOTAL: 14	18508

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	608
	feeder.eternal-september.org	63
	au2pb.net	31
	newsfeeds.xmission.com	16
	news-out.mixmin.net	5
	news.swapon.de	3
	nntp.pasdenom.info	2
	TOTAL: 7	728
Including strange strings
	newsfeed.fu-berlin.de	390
	news.swapon.de	52
	feeder.eternal-september.org	38
	news-out.mixmin.net	38
	newsfeeds.xmission.com	11
	au2pb.net	9
	xanadu-bbs.net	5
	newsfeed.neva.ru	4
	TOTAL: 8	547
TOTAL: 2		1275

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	24561	155	23323	79	0	0%	23:59:56
2	usenet.pasdenom.info	32427	129	26924	746	1	0%	23:59:56
3	xanadu-bbs	26563	75	23952	34	20	0%	23:59:56
4	fu-berlin.de	26214	43	26159	11	0	0%	23:59:44
5	news.mixmin.net	23458	17	22472	0	0	0%	23:59:44
6	xmission	32184	12	25575	3	0	0%	23:59:56
7	neva.ru	26214	7	25474	26	0	0%	23:59:56
8	eternal-september	21030	6	20025	2	0	0%	23:59:56
9	news.swapon.de	2429	1	2399	7	0	0%	23:58:04
TOTAL: 9		215080	445	196303	908	21	0%	215:57:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	701.1 KB	255.1 KB	956.2 KB	4.1 KB	23:59:56
2	usenet.pasdenom.info	587.4 KB	1.5 MB	2.0 MB	2.4 KB	23:59:56
3	xanadu-bbs	487.3 KB	69.5 KB	556.8 KB	5.1 KB	23:59:56
4	fu-berlin.de	260.7 KB	14.7 KB	275.4 KB	5.1 KB	23:59:44
5	news.mixmin.net	91.3 KB	0.0 KB	91.3 KB	5.4 KB	23:59:44
6	neva.ru	52.0 KB	177.5 KB	229.5 KB	7.0 KB	23:59:56
7	xmission	28.3 KB	6.4 KB	34.7 KB	2.3 KB	23:59:56
8	eternal-september	22.1 KB	2.6 KB	24.7 KB	3.1 KB	23:59:56
9	news.swapon.de	4.2 KB	15.5 KB	19.7 KB	2.5 KB	23:58:04
TOTAL: 9		2.2 MB	2.0 MB	4.2 MB	3.2 KB	215:57:08

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	68	165.3 KB	65	04:33:03
2	tempe0.bbox.io	38	5	12.0 KB	1	07:36:07
3	challenger.nielsenmedia.com	12	1	1.2 KB	1	01:16:08
4	pool-71-98-160-84.tampfl.dsl-w.verizon.net	9	1	1.2 KB	1	01:25:21
TOTAL: 4		347	75	179.7 KB	68	85:26:35

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	325	68	165.3 KB	65	68:16:04
2	*.bbox.io	38	5	12.0 KB	1	07:36:07
3	*.nielsenmedia.com	12	1	1.2 KB	1	01:16:08
4	*.tampfl.dsl-w.verizon.net	9	1	1.2 KB	1	01:25:21
5	*.ip-188-165-239.eu	53	0	0.0 KB	0	00:39:38
6	*.mixmin.net	2825	0	0.0 KB	0	00:27:03
7	*.chello.nl	23	0	0.0 KB	0	03:37:09
8	*.156.22.98.dynamic.ip.windstream.net	3	0	0.0 KB	0	02:09:01
TOTAL: 8		3288	75	179.7 KB	68	85:26:35

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.102	1.074	0.000	63:42:58
tempe0.bbox.io	3.162	0.648	0.000	07:36:07
202.89.176.220	28.438	6.135	0.735	04:33:03
dhcp-089-099-203-254.chello.nl	1.969	0.351	0.000	03:37:09
h143.156.22.98.dynamic.ip.windstream.net	0.298	0.083	0.000	02:09:01
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.654	0.139	0.000	01:25:21
challenger.nielsenmedia.com	0.877	0.152	0.000	01:16:08
ns342002.ip-188-165-239.eu	4.582	1.473	0.000	00:39:38
snorky.mixmin.net	235.797	45.691	0.000	00:27:03
69.160.37.54	1.907	0.371	0.000	00:00:02
TOTAL: 10	278.786	56.117	0.735	85:26:35

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2825
ns342002.ip-188-165-239.eu	53
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	23
162.212.88.214	12
h143.156.22.98.dynamic.ip.windstream.net	3
TOTAL: 6	2941

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.pc.games.war-historical	24
2	comp.arch	23
3	comp.sys.ibm.pc.games.action	11
4	comp.arch.embedded	4
5	comp.os.vms	3
6	comp.os.os2.apps	3
7	comp.ai.philosophy	2
8	comp.lang.awk	2
9	comp.unix.aix	1
10	comp.sys.ibm.pc.hardware.storage	1
11	comp.sys.hp.hardware	1
TOTAL: 11		75

Daily Usenet report for csiph.com

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