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

Unknown entries from news log file:

First 50 / 2681 lines (1.9%)

Nov 7 04:15:12 csiph nnrpd[93860]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:15:42 csiph nnrpd[93921]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:16:14 csiph nnrpd[93933]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:16:45 csiph nnrpd[93950]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:17:16 csiph nnrpd[93951]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:17:47 csiph nnrpd[93977]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:18:17 csiph nnrpd[93978]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:18:48 csiph nnrpd[94007]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:19:19 csiph nnrpd[94096]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:19:50 csiph nnrpd[94139]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:20:20 csiph nnrpd[94176]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:20:51 csiph nnrpd[94209]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:21:21 csiph nnrpd[94210]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:21:52 csiph nnrpd[94236]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:22:22 csiph nnrpd[94258]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:22:52 csiph nnrpd[94324]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:23:22 csiph nnrpd[94371]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:23:53 csiph nnrpd[94447]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:24:23 csiph nnrpd[94514]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:24:55 csiph nnrpd[94565]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:25:26 csiph nnrpd[94647]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:25:57 csiph nnrpd[94656]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:26:28 csiph nnrpd[94682]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:26:58 csiph nnrpd[94683]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:27:29 csiph nnrpd[94709]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:28:00 csiph nnrpd[94710]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:28:32 csiph nnrpd[94871]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:29:02 csiph nnrpd[94872]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:29:33 csiph nnrpd[94898]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:30:04 csiph nnrpd[94909]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:30:35 csiph nnrpd[94935]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:31:05 csiph nnrpd[94942]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:31:36 csiph nnrpd[94962]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:32:07 csiph nnrpd[94969]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:32:38 csiph nnrpd[94974]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:33:09 csiph nnrpd[95019]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:33:39 csiph nnrpd[95020]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:34:11 csiph nnrpd[95046]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:34:41 csiph nnrpd[95047]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:35:12 csiph nnrpd[95083]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:35:43 csiph nnrpd[95084]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:36:14 csiph nnrpd[95110]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:36:44 csiph nnrpd[95112]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:37:14 csiph nnrpd[95138]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:37:46 csiph nnrpd[95139]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:38:17 csiph nnrpd[95165]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:38:48 csiph nnrpd[95301]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:39:18 csiph nnrpd[95327]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:39:49 csiph nnrpd[95328]: fleegle.mixmin.net noperm ihave without permission
Nov 7 04:40:19 csiph nnrpd[95347]: fleegle.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	53001	41.5%	7.3 MB	51.3%
nnrpd	45427	35.6%	4.0 MB	28.5%
innd	27430	21.5%	2.6 MB	18.2%
innfeed	1904	1.5%	288.0 KB	2.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	127763	100.0%	14.2 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	250751	41.7%
Positive hits	239304	39.8%
Cache misses	58916	9.8%
Do not exist	52154	8.7%
TOTAL: 4	601125	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.662	0.0%	251	0.000	2.637	27.500
article cleanup	00:00:00.344	0.0%	49844	0.000	0.007	0.028
article logging	00:00:00.630	0.0%	50345	0.000	0.013	0.052
article parse	00:00:05.575	0.0%	550611	0.001	0.010	0.089
article write	00:00:12.425	0.0%	23758	0.182	0.523	15.344
artlog/artcncl	00:00:00.001	0.0%	148	0.000	0.007	0.500
artlog/artparse	00:00:00.045	0.0%	2657	0.000	0.017	0.250
data move	00:00:00.523	0.0%	976755	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.097	0.0%	242	0.000	0.401	13.500
hishave/artcncl	00:00:00.000	0.0%	251	0.000	0.000	0.000
hishave/artparse	00:00:00.009	0.0%	2657	0.000	0.003	0.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.225	0.0%	601422	0.001	0.004	0.010
history sync	00:00:01.055	0.0%	349	0.000	3.023	27.500
history write	00:00:22.256	0.0%	49834	0.062	0.447	5.861
hiswrite/artcncl	00:00:00.008	0.0%	9	0.000	0.889	2.000
hiswrite/artparse	00:00:01.201	0.0%	2657	0.000	0.452	17.000
idle	23:56:36.267	99.7%	962471	11.726	89.557	435.853
nntp read	00:00:03.260	0.0%	959527	0.002	0.003	0.007
overview write	00:00:35.079	0.0%	23758	0.163	1.477	9.188
perl filter	00:01:54.014	0.1%	49834	1.030	2.288	4.116
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.428	0.0%	47516	0.000	0.009	0.022
TOTAL: 24:00:49.791	23:59:56.104	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.054	0.0%	23753	0.000	0.002	0.025
article prepare	00:00:00.003	0.0%	7106	0.000	0.000	0.023
article read	00:00:00.238	0.0%	1412	0.000	0.169	45.000
backlog stats	00:00:00.104	0.0%	266360	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.190	0.0%	204392	0.001	0.006	0.012
data write	00:00:01.446	0.0%	183782	0.002	0.008	0.018
idle	23:54:01.596	100.0%	266360	174.895	323.027	906.029
status file	00:00:00.101	0.0%	201	0.000	0.502	1.000
TOTAL: 23:54:08.268	23:54:04.732	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.429	0.0%	19014	0.000	0.023	0.333
idle	74:36:06.820	99.9%	58376	0.000	4600.638	600203.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.796	0.0%	117853	0.000	0.007	0.333
readart	00:00:00.298	0.0%	117	0.000	2.547	48.000
TOTAL: 74:41:56.997	74:36:08.343	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	1	46299	7331	33029	5939	15%	23:56:57
2	au2pb.net	1	25640	4713	20269	658	18%	24:07:46
3	news-out.mixmin.net	7	31143	2986	25308	2849	9%	24:24:31
4	xanadu-bbs.net	4	52163	2946	40201	9016	5%	26:01:47
5	newsfeeds.xmission.com	1	24872	2223	22481	168	8%	25:47:17
6	newsfeed.neva.ru	144	56138	1985	44715	9438	3%	71:33:16
7	newsfeed.fu-berlin.de	34	29451	1156	27935	360	3%	30:24:15
8	nntp.pasdenom.info	8	23292	302	22734	256	1%	24:08:56
9	news.swapon.de	2	2264	231	2025	8	10%	24:55:48
10	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 10		228	291262	23873	238697	28692	8%	275:20:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.0 MB	0.0 KB	48.3 MB	68.3 MB	29%	5.3 KB
2	au2pb.net	14.0 MB	0.0 KB	3.3 MB	17.3 MB	80%	3.3 KB
3	news-out.mixmin.net	10.2 MB	10.5 KB	5.8 MB	16.0 MB	63%	2.8 KB
4	xanadu-bbs.net	8.9 MB	0.0 KB	632.4 MB	641.3 MB	1%	54.9 KB
5	newsfeed.neva.ru	7.3 MB	140.3 KB	88.9 MB	96.3 MB	7%	8.6 KB
6	newsfeeds.xmission.com	6.4 MB	0.0 KB	1.1 MB	7.5 MB	85%	3.2 KB
7	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	846.4 KB	3.5 MB	76%	2.4 KB
8	nntp.pasdenom.info	721.5 KB	0.0 KB	1.2 MB	1.9 MB	37%	3.4 KB
9	news.swapon.de	580.7 KB	0.0 KB	300.2 KB	880.9 KB	65%	3.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		70.8 MB	150.8 KB	782.0 MB	852.9 MB	8%	16.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 7 04:15:00 - 04:59:59	287	1.2%	0.11	776.6 KB	1.1%	0.29
Nov 7 05:00:00 - 05:59:59	417	1.8%	0.12	1.3 MB	1.9%	0.37
Nov 7 06:00:00 - 06:59:59	599	2.5%	0.17	2.1 MB	3.0%	0.60
Nov 7 07:00:00 - 07:59:59	829	3.5%	0.23	2.3 MB	3.2%	0.65
Nov 7 08:00:00 - 08:59:59	896	3.8%	0.25	2.5 MB	3.6%	0.72
Nov 7 09:00:00 - 09:59:59	929	3.9%	0.26	2.5 MB	3.6%	0.72
Nov 7 10:00:00 - 10:59:59	1118	4.7%	0.31	3.5 MB	5.0%	1.00
Nov 7 11:00:00 - 11:59:59	1083	4.6%	0.30	3.1 MB	4.5%	0.89
Nov 7 12:00:00 - 12:59:59	1053	4.4%	0.29	2.9 MB	4.0%	0.81
Nov 7 13:00:00 - 13:59:59	1302	5.5%	0.36	3.8 MB	5.5%	1.09
Nov 7 14:00:00 - 14:59:59	1358	5.7%	0.38	5.1 MB	7.2%	1.44
Nov 7 15:00:00 - 15:59:59	1295	5.5%	0.36	3.5 MB	5.0%	1.00
Nov 7 16:00:00 - 16:59:59	1449	6.1%	0.40	4.6 MB	6.6%	1.32
Nov 7 17:00:00 - 17:59:59	1417	6.0%	0.39	4.2 MB	6.0%	1.20
Nov 7 18:00:00 - 18:59:59	1488	6.3%	0.41	4.2 MB	6.0%	1.19
Nov 7 19:00:00 - 19:59:59	1455	6.1%	0.40	4.1 MB	5.7%	1.15
Nov 7 20:00:00 - 20:59:59	1269	5.3%	0.35	3.5 MB	4.9%	0.99
Nov 7 21:00:00 - 21:59:59	1145	4.8%	0.32	3.2 MB	4.5%	0.90
Nov 7 22:00:00 - 22:59:59	1118	4.7%	0.31	3.2 MB	4.6%	0.92
Nov 7 23:00:00 - 23:59:59	841	3.5%	0.23	2.5 MB	3.5%	0.70
Nov 8 00:00:00 - 00:59:59	682	2.9%	0.19	1.9 MB	2.6%	0.53
Nov 8 01:00:00 - 01:59:59	567	2.4%	0.16	1.7 MB	2.5%	0.50
Nov 8 02:00:00 - 02:59:59	486	2.0%	0.14	1.5 MB	2.2%	0.44
Nov 8 03:00:00 - 03:59:59	526	2.2%	0.15	1.9 MB	2.8%	0.55
Nov 8 04:00:00 - 04:15:00	119	0.5%	0.13	471.7 KB	0.7%	0.52
TOTAL: 24:00:00	23728	100.0%	0.27	70.5 MB	100.0%	0.84

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	9493	1002	9	8482
2	xanadu-bbs.net	8985	478	0	8507
3	feeder.eternal-september.org	5955	248	0	5707
4	news-out.mixmin.net	2879	2	1	2876
5	newsfeed.fu-berlin.de	714	50	0	664
6	au2pb.net	645	599	0	46
7	nntp.pasdenom.info	255	217	0	38
8	newsfeeds.xmission.com	166	146	0	20
9	news.swapon.de	22	0	0	22
10	188.40.76.149	11	0	0	11
TOTAL: 10		29125	2742	10	26373

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	301
fa.linux.kernel	232
ut.test	156
mailing.freebsd.pkg-fallout	106
lada.auto	105
mailing.freebsd.svn-src-all	72
fa.freebsd.cvs-all	71
mailing.freebsd.svn-src-head	68
alt.politics	66
alt.politics.scorched-earth	64
mailing.freebsd.svn-ports-all	62
mailing.freebsd.svn-ports-head	60
pld.cvs.commit	60
nemo.test	58
mailing.freebsd.ports-bugs	57
nemo.dev.serveur	54
alt.survival	54
it-alt.sport.calcio.juventus	36
nemo.dev.client	35
mailing.gcc.gcc-cvs	35
TOTAL: 216	2742

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	17896
EMP (phn path)	3786
EMP (phl)	1051
EMP (phn nph)	216
PFM bot misc	113
Too many newsgroups	34
User-issued spam cancel	33
HTML Multipart	30
HTML post	22
Binary Image: misplaced jpg	13
Too many newsgroups (low_xpost)	13
HTML Image Tags	6
Too many newsgroups (meow)	1
Binary: misplaced PAR2	1
Binary: misplaced rar	1
TOTAL: 15	23216

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	2516
	feeder.eternal-september.org	101
	au2pb.net	23
	nntp.pasdenom.info	6
	newsfeeds.xmission.com	5
	news-out.mixmin.net	4
	news.swapon.de	1
	TOTAL: 7	2656
Including strange strings
	newsfeed.fu-berlin.de	363
	feeder.eternal-september.org	76
	news-out.mixmin.net	21
	news.swapon.de	14
	newsfeeds.xmission.com	9
	au2pb.net	7
	newsfeed.neva.ru	6
	xanadu-bbs.net	5
	TOTAL: 8	501
TOTAL: 2		3157

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	au2pb	20025	147	18784	125	0	0	0%	23:59:58
2	usenet.pasdenom.info	28026	133	22401	891	0	67	0%	23:59:58
3	news.mixmin.net	21621	103	20378	16	0	0	0%	23:59:57
4	xanadu-bbs	22798	98	20617	67	1	0	0%	23:59:54
5	fu-berlin.de	22291	58	22206	27	0	0	0%	23:59:57
6	xmission	27144	30	21353	1	0	0	0%	23:59:54
7	neva.ru	22521	11	21608	57	0	0	0%	23:59:53
8	news.swapon.de	2018	2	2001	8	0	0	0%	23:53:15
9	eternal-september	17004	1	16057	2	0	0	0%	23:59:55
TOTAL: 9		183448	583	165405	1194	1	67	0%	215:52:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	509.2 KB	322.0 KB	831.2 KB	3.1 KB	23:59:58
2	usenet.pasdenom.info	466.0 KB	2.5 MB	2.9 MB	2.9 KB	23:59:58
3	xanadu-bbs	425.2 KB	134.8 KB	560.0 KB	3.4 KB	23:59:54
4	news.mixmin.net	421.3 KB	194.3 KB	615.6 KB	5.2 KB	23:59:57
5	fu-berlin.de	272.3 KB	35.5 KB	307.9 KB	3.6 KB	23:59:57
6	neva.ru	86.1 KB	373.6 KB	459.8 KB	6.8 KB	23:59:53
7	xmission	74.3 KB	2.1 KB	76.4 KB	2.5 KB	23:59:54
8	news.swapon.de	2.2 KB	13.0 KB	15.2 KB	1.5 KB	23:53:15
9	eternal-september	1.7 KB	2.9 KB	4.7 KB	1.6 KB	23:59:55
TOTAL: 9		2.2 MB	3.5 MB	5.7 MB	3.3 KB	215:52:41

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	286	93	365.3 KB	85	04:42:12
2	challenger.nielsenmedia.com	17	10	29.5 KB	6	03:46:55
3	pool-71-98-160-84.tampfl.dsl-w.verizon.net	20	6	13.3 KB	3	03:20:58
4	tempe0.bbox.io	14	3	3.8 KB	1	03:03:02
5	dhcp-089-099-203-254.chello.nl	21	3	3.8 KB	2	02:55:55
6	138.108.60.60	3	2	4.2 KB	1	00:13:24
TOTAL: 6		361	117	419.9 KB	98	74:41:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	318	95	369.5 KB	86	55:51:16
2	*.nielsenmedia.com	17	10	29.5 KB	6	03:46:55
3	*.tampfl.dsl-w.verizon.net	20	6	13.3 KB	3	03:20:58
4	*.bbox.io	14	3	3.8 KB	1	03:03:02
5	*.chello.nl	21	3	3.8 KB	2	02:55:55
6	*.147.22.98.dynamic.ip.windstream.net	2	0	0.0 KB	0	05:08:42
7	*.ip-188-165-239.eu	37	0	0.0 KB	0	00:05:57
8	*.br.br.cox.net	1	0	0.0 KB	0	00:02:31
9	*.mixmin.net	2681	0	0.0 KB	0	00:26:10
TOTAL: 9		3111	117	419.9 KB	98	74:41:30

NNRP auth users [Top 20]:

User	Conn
moussa	286
TOTAL: 1	286

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.214	0.626	0.654	0.000	50:55:37
h168.147.22.98.dynamic.ip.windstream.net	0.306	0.266	0.000	05:08:42
202.89.176.220	29.578	5.680	0.728	04:42:12
challenger.nielsenmedia.com	1.677	0.195	0.000	03:46:55
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.688	0.368	0.000	03:20:58
tempe0.bbox.io	1.271	0.257	0.000	03:03:02
dhcp-089-099-203-254.chello.nl	1.824	0.282	0.000	02:55:55
fleegle.mixmin.net	224.044	40.533	0.000	00:26:10
138.108.60.60	0.255	0.048	0.000	00:13:24
ns342002.ip-188-165-239.eu	3.087	0.587	0.000	00:05:57
ip70-186-183-52.br.br.cox.net	0.171	0.008	0.000	00:02:31
69.160.37.54	1.784	0.377	0.000	00:00:01
TOTAL: 12	266.311	49.255	0.728	74:41:30

Curious NNRP server explorers [Top 20]:

System	Conn
fleegle.mixmin.net	2681
ns342002.ip-188-165-239.eu	37
69.160.37.54	25
162.212.88.214	4
h168.147.22.98.dynamic.ip.windstream.net	2
ip70-186-183-52.br.br.cox.net	1
TOTAL: 6	2750

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	116	99.1%
2	news	1	0.9%
TOTAL: 2		117	100.0%

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

Newsgroup		Count
1	comp.arch.embedded	30
2	comp.arch	26
3	comp.lang.awk	18
4	comp.sys.ibm.pc.games.war-historical	11
5	comp.sys.ibm.ps2.hardware	8
6	comp.os.vms	7
7	comp.sys.ibm.pc.games.action	6
8	comp.ai.philosophy	2
9	comp.sys.ibm.pc.hardware.chips	2
10	comp.os.linux.networking	2
11	comp.ai.neural-nets	2
12	comp.os.os2.apps	2
13	news.admin.peering	1
TOTAL: 13		117

Daily Usenet report for csiph.com

Nov 7 04:15:00 -- Nov 8 04:15:00