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

Unknown entries from news log file:

First 50 / 2820 lines (1.8%)

Nov 12 04:15:13 csiph nnrpd[27663]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:15:44 csiph nnrpd[27689]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:16:15 csiph nnrpd[27690]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:16:45 csiph nnrpd[27718]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:17:16 csiph nnrpd[27719]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:17:47 csiph nnrpd[27732]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:18:17 csiph nnrpd[27748]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:18:48 csiph nnrpd[27751]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:19:19 csiph nnrpd[27780]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:19:49 csiph nnrpd[27812]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:20:21 csiph nnrpd[27855]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:20:52 csiph nnrpd[27859]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:21:22 csiph nnrpd[27885]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:21:53 csiph nnrpd[27886]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:22:23 csiph nnrpd[27933]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:22:54 csiph nnrpd[27934]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:23:25 csiph nnrpd[27960]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:23:56 csiph nnrpd[27973]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:24:27 csiph nnrpd[28002]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:24:58 csiph nnrpd[28003]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:25:28 csiph nnrpd[28033]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:25:58 csiph nnrpd[28040]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:26:29 csiph nnrpd[28041]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:27:00 csiph nnrpd[28067]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:27:31 csiph nnrpd[28071]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:28:02 csiph nnrpd[28097]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:28:33 csiph nnrpd[28098]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:29:04 csiph nnrpd[28126]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:29:34 csiph nnrpd[28127]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:30:04 csiph nnrpd[28163]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:30:35 csiph nnrpd[28164]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:31:05 csiph nnrpd[28331]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:31:35 csiph nnrpd[28332]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:32:06 csiph nnrpd[28358]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:32:36 csiph nnrpd[28359]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:33:06 csiph nnrpd[28392]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:33:37 csiph nnrpd[28407]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:34:07 csiph nnrpd[28408]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:34:37 csiph nnrpd[28434]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:35:07 csiph nnrpd[28445]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:35:38 csiph nnrpd[28471]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:36:08 csiph nnrpd[28472]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:36:40 csiph nnrpd[28498]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:37:11 csiph nnrpd[28499]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:37:42 csiph nnrpd[28525]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:38:12 csiph nnrpd[28526]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:38:42 csiph nnrpd[28552]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:39:13 csiph nnrpd[28553]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:39:45 csiph nnrpd[28579]: snorky.mixmin.net noperm ihave without permission
Nov 12 04:40:15 csiph nnrpd[28590]: snorky.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53049	43.2%	7.7 MB	54.2%
nnrpd	46269	37.6%	4.1 MB	29.0%
innd	21767	17.7%	2.1 MB	14.9%
innfeed	1853	1.5%	280.7 KB	1.9%
TOTAL: 4	122938	100.0%	14.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	270823	45.8%
Negative hits	268090	45.3%
Do not exist	52139	8.8%
Cache misses	118	0.0%
TOTAL: 4	591170	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.558	0.0%	194	0.000	2.876	24.800
article cleanup	00:00:00.358	0.0%	50832	0.000	0.007	0.029
article logging	00:00:00.758	0.0%	51467	0.000	0.015	0.042
article parse	00:00:07.826	0.0%	390043	0.003	0.020	0.274
article write	00:01:24.353	0.1%	28265	0.202	2.984	8.305
artlog/artcncl	00:00:00.002	0.0%	181	0.000	0.011	1.000
artlog/artparse	00:00:00.019	0.0%	1400	0.000	0.014	0.333
data move	00:00:00.473	0.0%	867392	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.047	0.0%	192	0.000	0.245	4.600
hishave/artcncl	00:00:00.000	0.0%	194	0.000	0.000	0.000
hishave/artparse	00:00:00.003	0.0%	1400	0.000	0.002	0.059
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.055	0.0%	590410	0.002	0.007	0.061
history sync	00:00:02.020	0.0%	355	0.000	5.690	148.500
history write	00:00:48.278	0.1%	50819	0.079	0.950	6.538
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:04.668	0.0%	1399	0.000	3.337	22.802
idle	23:54:39.630	99.6%	853889	54.174	100.809	289.692
nntp read	00:00:03.132	0.0%	851000	0.002	0.004	0.006
overview write	00:01:10.539	0.1%	28265	0.362	2.496	12.537
perl filter	00:01:39.661	0.1%	50819	1.000	1.961	3.736
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.506	0.0%	56530	0.000	0.009	0.036
TOTAL: 24:00:56.036	24:00:06.886	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.096	0.0%	28334	0.000	0.003	0.019
article prepare	00:00:00.001	0.0%	5104	0.000	0.000	0.019
article read	00:00:00.167	0.0%	1000	0.000	0.167	45.000
backlog stats	00:00:00.112	0.0%	304666	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.354	0.0%	238950	0.001	0.006	0.011
data write	00:00:01.620	0.0%	215086	0.003	0.008	0.018
idle	23:54:06.509	100.0%	304666	165.748	282.429	860.658
status file	00:00:00.086	0.0%	185	0.000	0.465	1.000
TOTAL: 23:54:13.805	23:54:09.945	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.460	0.0%	11592	0.000	0.040	0.077
idle	58:11:26.939	99.8%	51577	0.000	4061.635	600112.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.688	0.0%	104042	0.000	0.007	0.333
readart	00:00:00.195	0.0%	72	0.000	2.708	49.000
TOTAL: 58:17:53.680	58:11:28.282	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	47774	7680	37452	2642	16%	24:52:17
2	au2pb.net	1	31142	5335	24664	1143	17%	23:23:32
3	news-out.mixmin.net	4	34708	4806	26492	3410	13%	24:20:59
4	xanadu-bbs.net	3	52586	3350	40742	8494	6%	24:45:31
5	newsfeed.neva.ru	137	61460	2495	51157	7808	4%	71:49:44
6	newsfeeds.xmission.com	1	29671	2368	27149	154	7%	24:11:08
7	newsfeed.fu-berlin.de	72	34046	1535	32206	305	4%	36:56:37
8	nntp.pasdenom.info	5	29535	377	29063	95	1%	24:07:54
9	news.swapon.de	1	2420	256	2156	8	10%	22:18:58
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		255	323342	28202	271081	24059	8%	276:46:40

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	21.0 MB	37.1 KB	21.3 MB	42.3 MB	49%	4.2 KB
2	news-out.mixmin.net	15.4 MB	5.7 KB	5.9 MB	21.2 MB	72%	2.6 KB
3	au2pb.net	14.3 MB	27.5 KB	4.3 MB	18.6 MB	76%	2.9 KB
4	xanadu-bbs.net	8.9 MB	24.6 KB	400.1 MB	409.1 MB	2%	35.4 KB
5	newsfeed.neva.ru	7.3 MB	3.1 KB	63.6 MB	70.9 MB	10%	7.0 KB
6	newsfeeds.xmission.com	6.7 MB	0.0 KB	1.4 MB	8.1 MB	82%	3.3 KB
7	newsfeed.fu-berlin.de	3.7 MB	0.0 KB	734.6 KB	4.4 MB	83%	2.4 KB
8	nntp.pasdenom.info	824.7 KB	1.6 KB	1.0 MB	1.8 MB	44%	3.9 KB
9	news.swapon.de	617.9 KB	0.0 KB	642.9 KB	1.2 MB	49%	4.8 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		78.7 MB	99.6 KB	498.9 MB	577.7 MB	13%	11.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 12 04:15:00 - 04:59:59	348	1.2%	0.13	1.1 MB	1.4%	0.41
Nov 12 05:00:00 - 05:59:59	509	1.8%	0.14	1.5 MB	1.8%	0.41
Nov 12 06:00:00 - 06:59:59	551	1.9%	0.15	1.6 MB	2.0%	0.45
Nov 12 07:00:00 - 07:59:59	784	2.8%	0.22	2.3 MB	2.8%	0.64
Nov 12 08:00:00 - 08:59:59	1144	4.0%	0.32	3.6 MB	4.6%	1.03
Nov 12 09:00:00 - 09:59:59	1144	4.0%	0.32	2.8 MB	3.5%	0.79
Nov 12 10:00:00 - 10:59:59	1316	4.7%	0.37	3.5 MB	4.4%	1.00
Nov 12 11:00:00 - 11:59:59	1490	5.3%	0.41	4.2 MB	5.3%	1.18
Nov 12 12:00:00 - 12:59:59	1232	4.4%	0.34	3.3 MB	4.2%	0.95
Nov 12 13:00:00 - 13:59:59	1300	4.6%	0.36	3.5 MB	4.4%	0.98
Nov 12 14:00:00 - 14:59:59	1596	5.6%	0.44	4.5 MB	5.6%	1.27
Nov 12 15:00:00 - 15:59:59	1632	5.8%	0.45	4.0 MB	5.0%	1.13
Nov 12 16:00:00 - 16:59:59	1684	6.0%	0.47	4.5 MB	5.7%	1.28
Nov 12 17:00:00 - 17:59:59	1780	6.3%	0.49	4.9 MB	6.2%	1.39
Nov 12 18:00:00 - 18:59:59	1757	6.2%	0.49	4.7 MB	6.0%	1.34
Nov 12 19:00:00 - 19:59:59	1687	6.0%	0.47	4.7 MB	5.9%	1.33
Nov 12 20:00:00 - 20:59:59	1470	5.2%	0.41	4.1 MB	5.2%	1.16
Nov 12 21:00:00 - 21:59:59	1412	5.0%	0.39	3.8 MB	4.8%	1.08
Nov 12 22:00:00 - 22:59:59	1231	4.4%	0.34	3.5 MB	4.5%	1.01
Nov 12 23:00:00 - 23:59:59	1193	4.2%	0.33	3.8 MB	4.7%	1.07
Nov 13 00:00:00 - 00:59:59	908	3.2%	0.25	2.7 MB	3.5%	0.78
Nov 13 01:00:00 - 01:59:59	810	2.9%	0.23	2.5 MB	3.1%	0.70
Nov 13 02:00:00 - 02:59:59	592	2.1%	0.16	1.9 MB	2.4%	0.54
Nov 13 03:00:00 - 03:59:59	556	2.0%	0.15	2.1 MB	2.6%	0.59
Nov 13 04:00:00 - 04:15:00	140	0.5%	0.16	439.6 KB	0.5%	0.49
TOTAL: 24:00:00	28266	100.0%	0.33	79.3 MB	100.0%	0.94

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8424	550	3	7871
2	newsfeed.neva.ru	7812	1554	2	6256
3	news-out.mixmin.net	3474	16	2	3456
4	feeder.eternal-september.org	2636	368	3	2265
5	au2pb.net	1186	1141	2	43
6	newsfeed.fu-berlin.de	749	29	0	720
7	newsfeeds.xmission.com	182	135	0	47
8	nntp.pasdenom.info	96	74	1	21
9	news.swapon.de	43	0	0	43
TOTAL: 9		24602	3867	13	20722

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	938
mailing.freebsd.svn-ports-all	163
mailing.freebsd.svn-ports-head	162
lada.talk	158
ut.test	139
lada.auto	131
mailing.freebsd.ports-bugs	120
alt.politics.scorched-earth	88
mailing.gcc.gcc-cvs	82
mailing.openbsd.source-changes	74
mailing.freebsd.pkg-fallout	65
it-alt.sport.calcio.juventus	64
alt.politics	62
mailing.openbsd.ports-changes	57
pld.cvs.commit	55
mailing.freebsd.svn-src-all	52
fa.freebsd.cvs-all	51
fa.openbsd.source-changes	47
alt.survival	42
fa.openbsd.tech	39
TOTAL: 271	3867

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13970
EMP (phn path)	3102
EMP (phl)	1041
EMP (phn nph)	236
PFM bot misc	175
HTML Multipart	80
Too many newsgroups	54
User-issued spam cancel	9
HTML Image Tags	7
HTML post	7
Binary Payload	5
Too many newsgroups (meow)	1
Binary: misplaced com	1
TOTAL: 13	18688

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	1232
	feeder.eternal-september.org	79
	newsfeeds.xmission.com	34
	news-out.mixmin.net	23
	au2pb.net	21
	nntp.pasdenom.info	7
	news.swapon.de	4
	TOTAL: 7	1400
Including strange strings
	newsfeed.fu-berlin.de	443
	xanadu-bbs.net	60
	feeder.eternal-september.org	40
	news.swapon.de	35
	news-out.mixmin.net	32
	newsfeeds.xmission.com	12
	au2pb.net	7
	newsfeed.neva.ru	4
	nntp.pasdenom.info	1
	TOTAL: 9	634
TOTAL: 2		2034

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	32062	163	27111	613	23	0%	23:59:57
2	au2pb	23689	117	22620	112	0	0%	23:59:57
3	xanadu-bbs	27047	66	24815	64	6	0%	23:59:57
4	fu-berlin.de	26305	42	26239	24	0	0%	23:59:57
5	xmission	31915	9	25714	1	0	0%	23:59:57
6	neva.ru	26633	7	25556	46	45	0%	23:59:57
7	news.mixmin.net	24272	5	23370	0	0	0%	23:59:55
8	eternal-september	20977	1	19860	1	37	0%	23:59:57
9	news.swapon.de	2514	0	2468	6	8	0%	23:59:57
TOTAL: 9		215414	410	197753	867	119	0%	215:59:31

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	652.2 KB	1.4 MB	2.0 MB	2.6 KB	23:59:57
2	au2pb	553.1 KB	286.8 KB	839.9 KB	3.7 KB	23:59:57
3	xanadu-bbs	336.4 KB	113.9 KB	450.3 KB	3.5 KB	23:59:57
4	fu-berlin.de	184.3 KB	35.2 KB	219.5 KB	3.3 KB	23:59:57
5	news.mixmin.net	44.0 KB	0.0 KB	44.0 KB	8.8 KB	23:59:55
6	neva.ru	41.3 KB	249.3 KB	290.5 KB	5.5 KB	23:59:57
7	xmission	16.4 KB	1.3 KB	17.7 KB	1.8 KB	23:59:57
8	eternal-september	7.5 KB	0.9 KB	8.4 KB	4.2 KB	23:59:57
9	news.swapon.de	0.0 KB	13.2 KB	13.2 KB	2.2 KB	23:59:57
TOTAL: 9		1.8 MB	2.0 MB	3.8 MB	3.1 KB	215:59:31

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	53	138.8 KB	50	04:42:06
2	tempe0.bbox.io	35	17	46.9 KB	5	05:55:02
3	dhcp-089-099-203-254.chello.nl	36	2	2.0 KB	2	09:45:55
TOTAL: 3		359	72	187.7 KB	57	58:17:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	317	53	138.8 KB	50	34:58:39
2	*.bbox.io	35	17	46.9 KB	5	05:55:02
3	*.chello.nl	36	2	2.0 KB	2	09:45:55
4	*.nielsenmedia.com	17	0	0.0 KB	0	02:14:51
5	*.tampfl.dsl-w.verizon.net	6	0	0.0 KB	0	01:11:32
6	*.mixmin.net	2820	0	0.0 KB	0	00:27:06
7	*.156.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	01:22:50
8	*.139.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	02:21:26
TOTAL: 8		3233	72	187.7 KB	57	58:17:26

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.539	0.473	0.000	30:16:30
dhcp-089-099-203-254.chello.nl	3.044	0.570	0.000	09:45:55
tempe0.bbox.io	3.049	0.559	0.000	05:55:02
202.89.176.220	27.889	5.871	0.715	04:42:06
h10.139.22.98.dynamic.ip.windstream.net	0.190	0.147	0.000	02:21:26
challenger.nielsenmedia.com	1.446	0.310	0.000	02:14:51
h143.156.22.98.dynamic.ip.windstream.net	0.156	0.028	0.000	01:22:50
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.534	0.095	0.000	01:11:32
snorky.mixmin.net	234.165	46.094	0.000	00:27:06
69.160.37.54	1.906	0.379	0.000	00:00:02
TOTAL: 10	272.918	54.526	0.715	58:17:26

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	2820
69.160.37.54	25
challenger.nielsenmedia.com	17
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6
162.212.88.214	4
h10.139.22.98.dynamic.ip.windstream.net	1
h143.156.22.98.dynamic.ip.windstream.net	1
TOTAL: 7	2874

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	10	0
challenger.nielsenmedia.com	3	0
dhcp-089-099-203-254.chello.nl	2	0
202.89.176.220	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
h10.139.22.98.dynamic.ip.windstream.net	2	0
h143.156.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 7	22	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	30
2	comp.sys.ibm.pc.games.war-historical	15
3	comp.os.vms	8
4	comp.arch.embedded	6
5	comp.sys.ibm.ps2.hardware	6
6	comp.sys.ibm.pc.games.action	4
7	comp.sys.ibm.as400.misc	2
8	comp.os.os2.apps	1
TOTAL: 8		72

Daily Usenet report for csiph.com

Nov 12 04:15:00 -- Nov 13 04:15:00