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

Unknown entries from news log file:

First 50 / 2827 lines (1.8%)

Nov 9 04:15:14 csiph nnrpd[27068]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:15:45 csiph nnrpd[27094]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:16:16 csiph nnrpd[27095]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:16:47 csiph nnrpd[27114]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:17:18 csiph nnrpd[27122]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:17:49 csiph nnrpd[27123]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:18:20 csiph nnrpd[27149]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:18:51 csiph nnrpd[27155]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:19:21 csiph nnrpd[27181]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:19:51 csiph nnrpd[27209]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:20:22 csiph nnrpd[27254]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:20:52 csiph nnrpd[27255]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:21:22 csiph nnrpd[27281]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:21:53 csiph nnrpd[27282]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:22:24 csiph nnrpd[27329]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:22:55 csiph nnrpd[27330]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:23:26 csiph nnrpd[27356]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:23:57 csiph nnrpd[27369]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:24:27 csiph nnrpd[27391]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:24:57 csiph nnrpd[27396]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:25:28 csiph nnrpd[27407]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:26:00 csiph nnrpd[27433]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:26:31 csiph nnrpd[27434]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:27:02 csiph nnrpd[27460]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:27:32 csiph nnrpd[27461]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:28:03 csiph nnrpd[27487]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:28:34 csiph nnrpd[27490]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:29:04 csiph nnrpd[27516]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:29:35 csiph nnrpd[27652]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:30:05 csiph nnrpd[27688]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:30:35 csiph nnrpd[27689]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:31:05 csiph nnrpd[27721]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:31:36 csiph nnrpd[27722]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:32:06 csiph nnrpd[27739]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:32:36 csiph nnrpd[27749]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:33:06 csiph nnrpd[27771]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:33:37 csiph nnrpd[27797]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:34:07 csiph nnrpd[27798]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:34:37 csiph nnrpd[27824]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:35:08 csiph nnrpd[27835]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:35:39 csiph nnrpd[27861]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:36:09 csiph nnrpd[27862]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:36:39 csiph nnrpd[27888]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:37:10 csiph nnrpd[27891]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:37:41 csiph nnrpd[27917]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:38:12 csiph nnrpd[27918]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:38:42 csiph nnrpd[27941]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:39:14 csiph nnrpd[27945]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:39:45 csiph nnrpd[28090]: snorky.mixmin.net noperm ihave without permission
Nov 9 04:40:15 csiph nnrpd[28117]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	58803	43.6%	8.4 MB	54.4%
nnrpd	46902	34.8%	4.2 MB	27.2%
innd	27294	20.2%	2.6 MB	16.8%
innfeed	1863	1.4%	270.9 KB	1.7%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	134863	100.0%	15.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	280888	45.7%
Negative hits	276055	44.9%
Do not exist	57844	9.4%
Cache misses	368	0.1%
TOTAL: 4	615155	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.560	0.0%	292	0.000	5.342	25.833
article cleanup	00:00:00.370	0.0%	53416	0.000	0.007	0.030
article logging	00:00:00.691	0.0%	53977	0.000	0.013	0.104
article parse	00:00:12.193	0.0%	768361	0.003	0.016	0.086
article write	00:01:26.004	0.1%	28631	0.165	3.004	11.274
artlog/artcncl	00:00:00.002	0.0%	229	0.000	0.009	0.133
artlog/artparse	00:00:00.054	0.0%	4560	0.000	0.012	0.250
data move	00:00:00.705	0.0%	1252179	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.288	0.0%	290	0.000	0.993	12.000
hishave/artcncl	00:00:00.001	0.0%	292	0.000	0.003	0.250
hishave/artparse	00:00:00.025	0.0%	4560	0.000	0.005	0.037
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.290	0.0%	611902	0.001	0.007	0.105
history sync	00:00:02.390	0.0%	302	0.000	7.914	115.500
history write	00:01:02.318	0.1%	53411	0.092	1.167	6.540
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:05.897	0.0%	4559	0.000	1.293	33.667
idle	23:52:54.466	99.5%	1230812	10.559	69.852	316.128
nntp read	00:00:04.295	0.0%	1229529	0.001	0.003	0.006
overview write	00:01:34.047	0.1%	28631	0.882	3.285	22.043
perl filter	00:01:48.883	0.1%	53410	0.970	2.039	4.031
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.511	0.0%	57262	0.000	0.009	0.028
TOTAL: 24:00:12.975	23:59:18.990	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.095	0.0%	28728	0.000	0.003	0.036
article prepare	00:00:00.001	0.0%	6592	0.000	0.000	0.013
article read	00:00:00.219	0.0%	1328	0.000	0.165	15.000
backlog stats	00:00:00.107	0.0%	312189	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.343	0.0%	242897	0.000	0.006	0.011
data write	00:00:01.711	0.0%	219015	0.003	0.008	0.016
idle	23:54:47.881	100.0%	312189	144.571	275.756	770.829
status file	00:00:00.091	0.0%	192	0.000	0.474	1.000
TOTAL: 23:54:56.258	23:54:51.448	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.856	0.0%	28771	0.000	0.065	1.000
idle	103:09:39.983	99.8%	68982	1.000	5383.723	600384.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.805	0.0%	139060	0.000	0.006	0.182
readart	00:00:00.283	0.0%	92	0.000	3.076	68.000
TOTAL: 103:20:27.968	103:09:42.927	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	4	49139	7443	38351	3345	15%	23:58:22
2	au2pb.net	1	32246	5531	25549	1166	17%	24:59:09
3	news-out.mixmin.net	3	36266	4544	27851	3871	12%	24:08:52
4	xanadu-bbs.net	7	58000	3925	42550	11525	6%	33:17:37
5	newsfeed.neva.ru	145	64268	2923	52507	8838	4%	71:37:59
6	newsfeeds.xmission.com	1	30701	2568	27999	134	8%	25:32:58
7	newsfeed.fu-berlin.de	66	33120	1293	31515	312	3%	34:30:13
8	nntp.pasdenom.info	7	28962	304	28527	131	1%	24:12:48
9	news.swapon.de	1	2229	234	1993	2	10%	22:18:58
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		261	334931	28765	276842	29324	8%	284:36:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.3 MB	38.5 KB	24.0 MB	44.4 MB	45%	4.2 KB
2	news-out.mixmin.net	15.2 MB	12.0 KB	7.7 MB	23.0 MB	66%	2.8 KB
3	au2pb.net	14.5 MB	18.5 KB	7.4 MB	21.9 MB	66%	3.4 KB
4	xanadu-bbs.net	10.4 MB	0.0 KB	1.0 GB	1.1 GB	0%	71.5 KB
5	newsfeed.neva.ru	9.2 MB	2.6 KB	76.8 MB	86.0 MB	10%	7.5 KB
6	newsfeeds.xmission.com	7.2 MB	18.5 KB	2.5 MB	9.7 MB	74%	3.7 KB
7	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	834.6 KB	3.8 MB	78%	2.4 KB
8	nntp.pasdenom.info	731.3 KB	0.0 KB	1.9 MB	2.6 MB	27%	6.1 KB
9	news.swapon.de	596.5 KB	0.0 KB	271.4 KB	867.9 KB	68%	3.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		81.1 MB	90.1 KB	1.2 GB	1.2 GB	6%	22.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 9 04:15:00 - 04:59:59	362	1.3%	0.13	1.1 MB	1.3%	0.41
Nov 9 05:00:00 - 05:59:59	570	2.0%	0.16	2.0 MB	2.5%	0.57
Nov 9 06:00:00 - 06:59:59	623	2.2%	0.17	2.5 MB	3.2%	0.72
Nov 9 07:00:00 - 07:59:59	797	2.8%	0.22	2.3 MB	2.9%	0.65
Nov 9 08:00:00 - 08:59:59	1175	4.1%	0.33	3.0 MB	3.8%	0.86
Nov 9 09:00:00 - 09:59:59	1380	4.8%	0.38	3.4 MB	4.2%	0.96
Nov 9 10:00:00 - 10:59:59	1435	5.0%	0.40	3.7 MB	4.6%	1.06
Nov 9 11:00:00 - 11:59:59	1191	4.2%	0.33	3.1 MB	3.8%	0.87
Nov 9 12:00:00 - 12:59:59	1234	4.3%	0.34	3.2 MB	4.0%	0.92
Nov 9 13:00:00 - 13:59:59	1458	5.1%	0.41	3.7 MB	4.6%	1.04
Nov 9 14:00:00 - 14:59:59	1544	5.4%	0.43	3.9 MB	4.8%	1.10
Nov 9 15:00:00 - 15:59:59	1710	6.0%	0.47	4.5 MB	5.6%	1.28
Nov 9 16:00:00 - 16:59:59	1834	6.4%	0.51	5.0 MB	6.2%	1.43
Nov 9 17:00:00 - 17:59:59	1650	5.8%	0.46	4.4 MB	5.4%	1.24
Nov 9 18:00:00 - 18:59:59	1766	6.2%	0.49	4.8 MB	6.0%	1.37
Nov 9 19:00:00 - 19:59:59	1616	5.6%	0.45	4.4 MB	5.4%	1.24
Nov 9 20:00:00 - 20:59:59	1571	5.5%	0.44	4.7 MB	5.8%	1.33
Nov 9 21:00:00 - 21:59:59	1542	5.4%	0.43	4.3 MB	5.4%	1.23
Nov 9 22:00:00 - 22:59:59	1222	4.3%	0.34	3.6 MB	4.4%	1.01
Nov 9 23:00:00 - 23:59:59	1049	3.7%	0.29	3.1 MB	3.8%	0.87
Nov 10 00:00:00 - 00:59:59	929	3.2%	0.26	3.2 MB	3.9%	0.90
Nov 10 01:00:00 - 01:59:59	660	2.3%	0.18	2.2 MB	2.8%	0.63
Nov 10 02:00:00 - 02:59:59	637	2.2%	0.18	2.4 MB	3.0%	0.69
Nov 10 03:00:00 - 03:59:59	525	1.8%	0.15	1.7 MB	2.1%	0.48
Nov 10 04:00:00 - 04:15:00	135	0.5%	0.15	401.2 KB	0.5%	0.45
TOTAL: 24:00:00	28615	100.0%	0.33	80.4 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	xanadu-bbs.net	11515	603	0	1	10911
2	newsfeed.neva.ru	8913	1548	1	0	7364
3	news-out.mixmin.net	3890	13	1	0	3876
4	feeder.eternal-september.org	3432	379	1	0	3052
5	au2pb.net	1172	1030	1	0	141
6	newsfeed.fu-berlin.de	720	38	0	0	682
7	newsfeeds.xmission.com	143	104	1	0	38
8	nntp.pasdenom.info	136	108	0	0	28
9	news.swapon.de	38	0	0	0	38
TOTAL: 9		29959	3823	5	1	26130

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	855
lada.talk	203
mailing.freebsd.svn-ports-all	123
mailing.freebsd.svn-ports-head	120
alt.politics.scorched-earth	111
it-alt.sport.calcio.juventus	105
mailing.gcc.gcc-cvs	97
mailing.freebsd.svn-src-all	89
fa.freebsd.cvs-all	85
mailing.freebsd.pkg-fallout	83
lada.auto	77
mailing.freebsd.ports-bugs	73
ut.test	72
mailing.freebsd.svn-src-head	59
alt.windows7.general	53
alt.politics	51
pld.cvs.commit	46
alt.survival	46
mailing.openbsd.tech	42
fa.openbsd.tech	40
TOTAL: 273	3823

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	15851
EMP (phn path)	3516
EMP (phl)	1042
PFM bot misc	195
EMP (phn nph)	168
HTML Multipart	112
Too many newsgroups	45
Binary: misplaced rar	28
Binary Image: misplaced jpg	24
User-issued spam cancel	11
HTML post	6
Too many newsgroups (low_xpost)	5
HTML Image Tags	3
Binary: misplaced nzb	1
Binary: misplaced txt	1
Binary Payload	1
TOTAL: 16	21009

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	4358
	feeder.eternal-september.org	91
	au2pb.net	46
	news-out.mixmin.net	25
	newsfeeds.xmission.com	20
	nntp.pasdenom.info	19
	news.swapon.de	2
	newsfeed.fu-berlin.de	1
	TOTAL: 8	4562
Including strange strings
	newsfeed.fu-berlin.de	384
	feeder.eternal-september.org	59
	news.swapon.de	36
	au2pb.net	29
	news-out.mixmin.net	23
	newsfeeds.xmission.com	13
	newsfeed.neva.ru	8
	xanadu-bbs.net	7
	TOTAL: 8	559
TOTAL: 2		5121

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	24238	166	22868	175	0	0%	23:59:57
2	usenet.pasdenom.info	32913	158	27372	783	57	0%	23:59:57
3	xanadu-bbs	27302	93	24553	76	92	0%	23:59:57
4	fu-berlin.de	26861	45	26782	33	0	0%	23:59:56
5	news.mixmin.net	24890	24	23859	0	0	0%	23:59:53
6	xmission	32580	24	25883	0	0	0%	23:59:57
7	eternal-september	21483	13	20330	2	0	0%	23:59:57
8	neva.ru	26463	6	25436	47	0	0%	23:59:57
9	news.swapon.de	2384	0	2362	6	0	0%	23:59:57
TOTAL: 9		219114	529	199445	1122	149	0%	215:59:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	592.5 KB	356.7 KB	949.2 KB	2.8 KB	23:59:57
2	usenet.pasdenom.info	550.0 KB	1.4 MB	2.0 MB	2.1 KB	23:59:57
3	xanadu-bbs	400.8 KB	166.4 KB	567.3 KB	3.4 KB	23:59:57
4	fu-berlin.de	244.0 KB	43.4 KB	287.5 KB	3.7 KB	23:59:56
5	news.mixmin.net	100.9 KB	0.0 KB	100.9 KB	4.2 KB	23:59:53
6	eternal-september	84.0 KB	2.8 KB	86.8 KB	5.8 KB	23:59:57
7	xmission	53.7 KB	0.0 KB	53.7 KB	2.2 KB	23:59:57
8	neva.ru	39.2 KB	195.7 KB	234.9 KB	4.4 KB	23:59:57
9	news.swapon.de	0.0 KB	47.9 KB	47.9 KB	8.0 KB	23:59:57
TOTAL: 9		2.0 MB	2.2 MB	4.2 MB	2.6 KB	215:59:28

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	84	249.6 KB	81	04:38:10
2	dhcp-089-099-203-254.chello.nl	65	4	6.4 KB	4	20:41:51
3	tempe0.bbox.io	16	4	6.4 KB	2	03:11:17
TOTAL: 3		369	92	262.5 KB	87	103:19:59

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	394	84	249.6 KB	81	71:31:21
2	*.chello.nl	65	4	6.4 KB	4	20:41:51
3	*.bbox.io	16	4	6.4 KB	2	03:11:17
4	*.mixmin.net	2827	0	0.0 KB	0	00:27:10
5	*.nielsenmedia.com	5	0	0.0 KB	0	01:30:22
6	*.tampfl.dsl-w.verizon.net	15	0	0.0 KB	0	02:04:58
7	*.147.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	03:35:10
8	*.ip-188-165-239.eu	34	0	0.0 KB	0	00:17:48
TOTAL: 8		3358	92	262.5 KB	87	103:19:59

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	0.658	0.756	0.000	55:52:59
dhcp-089-099-203-254.chello.nl	5.619	1.014	0.000	20:41:51
63.142.120.8	4.833	0.990	0.000	10:04:10
202.89.176.220	29.150	6.003	0.710	04:38:10
h168.147.22.98.dynamic.ip.windstream.net	0.154	0.211	0.000	03:35:10
tempe0.bbox.io	1.735	0.182	0.000	03:11:17
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.251	0.213	0.000	02:04:58
challenger.nielsenmedia.com	0.439	0.080	0.000	01:30:22
138.108.60.60	0.611	0.094	0.000	00:51:38
snorky.mixmin.net	234.659	44.662	0.000	00:27:10
ns342002.ip-188-165-239.eu	2.931	0.755	0.000	00:17:48
85.97.30.75	1.620	0.361	0.000	00:04:18
69.160.37.54	1.927	0.364	0.000	00:00:02
TOTAL: 13	285.587	55.685	0.710	103:19:59

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2827
63.142.120.8	50
ns342002.ip-188-165-239.eu	34
69.160.37.54	25
85.97.30.75	20
pool-71-98-160-84.tampfl.dsl-w.verizon.net	15
138.108.60.60	7
challenger.nielsenmedia.com	5
162.212.88.214	4
h168.147.22.98.dynamic.ip.windstream.net	2
TOTAL: 10	2989

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	6	0
challenger.nielsenmedia.com	3	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3	0
h168.147.22.98.dynamic.ip.windstream.net	2	0
138.108.60.60	1	0
TOTAL: 5	15	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	90	97.8%
2	news	2	2.2%
TOTAL: 2		92	100.0%

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

Newsgroup		Count
1	comp.arch	29
2	comp.sys.ibm.pc.games.war-historical	17
3	comp.sys.ibm.ps2.hardware	12
4	comp.arch.embedded	11
5	comp.os.vms	11
6	comp.sys.ibm.pc.games.action	6
7	comp.ai.philosophy	2
8	news.admin.peering	2
9	comp.sys.ibm.as400.misc	1
10	comp.answers	1
TOTAL: 10		92

Daily Usenet report for csiph.com

Nov 9 04:15:00 -- Nov 10 04:15:00