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

Unknown entries from news log file:

First 50 / 693 lines (7.2%)

Aug 13 04:15:07 csiph nnrpd[22710]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:15:27 csiph nnrpd[22834]: swapon.de noperm ihave without permission
Aug 13 04:15:38 csiph nnrpd[22858]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:15:57 csiph nnrpd[22859]: swapon.de noperm ihave without permission
Aug 13 04:16:09 csiph nnrpd[22860]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:16:28 csiph nnrpd[22861]: swapon.de noperm ihave without permission
Aug 13 04:16:39 csiph nnrpd[22879]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:16:58 csiph nnrpd[22886]: swapon.de noperm ihave without permission
Aug 13 04:17:09 csiph nnrpd[22887]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:17:28 csiph nnrpd[22888]: swapon.de noperm ihave without permission
Aug 13 04:17:39 csiph nnrpd[22915]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:17:59 csiph nnrpd[22939]: swapon.de noperm ihave without permission
Aug 13 04:18:09 csiph nnrpd[22947]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:18:30 csiph nnrpd[22948]: swapon.de noperm ihave without permission
Aug 13 04:18:39 csiph nnrpd[22949]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:19:01 csiph nnrpd[22973]: swapon.de noperm ihave without permission
Aug 13 04:19:09 csiph nnrpd[22974]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:19:31 csiph nnrpd[22975]: swapon.de noperm ihave without permission
Aug 13 04:19:39 csiph nnrpd[22988]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:20:02 csiph nnrpd[23021]: swapon.de noperm ihave without permission
Aug 13 04:20:11 csiph nnrpd[23023]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:20:33 csiph nnrpd[23024]: swapon.de noperm ihave without permission
Aug 13 04:20:41 csiph nnrpd[23025]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:21:04 csiph nnrpd[23049]: swapon.de noperm ihave without permission
Aug 13 04:21:11 csiph nnrpd[23050]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:21:35 csiph nnrpd[23186]: swapon.de noperm ihave without permission
Aug 13 04:21:42 csiph nnrpd[23187]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:22:06 csiph nnrpd[23233]: swapon.de noperm ihave without permission
Aug 13 04:22:12 csiph nnrpd[23236]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:22:37 csiph nnrpd[23237]: swapon.de noperm ihave without permission
Aug 13 04:22:42 csiph nnrpd[23238]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:23:07 csiph nnrpd[23245]: swapon.de noperm ihave without permission
Aug 13 04:23:13 csiph nnrpd[23263]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:23:38 csiph nnrpd[23264]: swapon.de noperm ihave without permission
Aug 13 04:23:43 csiph nnrpd[23265]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:24:08 csiph nnrpd[23266]: swapon.de noperm ihave without permission
Aug 13 04:24:13 csiph nnrpd[23277]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:24:39 csiph nnrpd[23291]: swapon.de noperm ihave without permission
Aug 13 04:24:43 csiph nnrpd[23292]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:25:10 csiph nnrpd[23303]: swapon.de noperm ihave without permission
Aug 13 04:25:13 csiph nnrpd[23304]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:25:41 csiph nnrpd[23328]: swapon.de noperm ihave without permission
Aug 13 04:25:43 csiph nnrpd[23329]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:26:12 csiph nnrpd[23330]: swapon.de noperm ihave without permission
Aug 13 04:26:14 csiph nnrpd[23331]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:26:43 csiph nnrpd[23355]: swapon.de noperm ihave without permission
Aug 13 04:26:45 csiph nnrpd[23356]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:27:14 csiph nnrpd[23357]: swapon.de noperm ihave without permission
Aug 13 04:27:15 csiph nnrpd[23358]: snorky.mixmin.net noperm ihave without permission
Aug 13 04:27:45 csiph nnrpd[23382]: swapon.de noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	52945	47.1%	7.2 MB	57.5%
nnrpd	35760	31.8%	3.0 MB	23.7%
innd	21978	19.5%	2.1 MB	16.6%
innfeed	1760	1.6%	296.6 KB	2.3%
controlchan	3	0.0%	0.2 KB	0.0%
TOTAL: 5	112446	100.0%	12.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	241743	42.9%
Negative hits	234850	41.7%
Do not exist	51881	9.2%
Cache misses	34731	6.2%
TOTAL: 4	563205	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.750	0.0%	346	0.000	2.168	70.000
article cleanup	00:00:00.333	0.0%	50780	0.000	0.007	0.029
article logging	00:00:00.620	0.0%	51138	0.000	0.012	0.095
article parse	00:00:03.611	0.0%	430620	0.003	0.008	0.021
article write	00:00:06.645	0.0%	27987	0.167	0.237	0.360
artlog/artcncl	00:00:00.003	0.0%	330	0.000	0.009	0.333
artlog/artparse	00:00:00.014	0.0%	1542	0.000	0.009	0.333
data move	00:00:00.349	0.0%	851564	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.032	0.0%	334	0.000	0.096	1.389
hishave/artcncl	00:00:00.000	0.0%	346	0.000	0.000	0.000
hishave/artparse	00:00:00.006	0.0%	1542	0.000	0.004	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.418	0.0%	564140	0.000	0.003	0.009
history sync	00:00:00.573	0.0%	353	0.000	1.623	10.667
history write	00:00:13.977	0.0%	50664	0.065	0.276	1.686
hiswrite/artcncl	00:00:00.001	0.0%	12	0.000	0.083	0.111
hiswrite/artparse	00:00:00.531	0.0%	1540	0.000	0.345	2.000
idle	23:57:38.125	99.8%	837949	7.639	102.940	419.536
nntp read	00:00:02.622	0.0%	835978	0.001	0.003	0.006
overview write	00:00:12.022	0.0%	27987	0.084	0.430	2.088
perl filter	00:01:59.703	0.1%	50664	1.027	2.363	5.145
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.520	0.0%	55974	0.000	0.009	0.034
TOTAL: 24:00:55.933	24:00:21.855	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.070	0.0%	28602	0.000	0.002	0.017
article prepare	00:00:00.004	0.0%	8224	0.000	0.000	0.017
article read	00:00:00.428	0.0%	1667	0.000	0.257	13.600
backlog stats	00:00:00.095	0.0%	277337	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.106	0.0%	206254	0.000	0.005	0.015
data write	00:00:01.619	0.0%	180797	0.003	0.009	0.024
idle	23:44:30.275	100.0%	277337	158.925	308.182	865.036
status file	00:00:00.073	0.0%	194	0.000	0.376	1.000
TOTAL: 23:44:38.920	23:44:33.670	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.032	0.0%	1830	0.000	0.017	0.038
idle	35:23:41.488	99.9%	35515	1.000	3587.822	602724.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.475	0.0%	71839	0.000	0.007	0.333
readart	00:00:00.078	0.0%	44	0.000	1.773	53.000
TOTAL: 35:26:04.272	35:23:42.073	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	149
pause	2
paused	2
reload	2
reserve	2
shutdown	6
TOTAL: 11	174

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	21	47023	7261	35588	4174	15%	27:09:35
2	xanadu-bbs.net	7	52384	5429	38153	8802	10%	26:42:47
3	au2pb.net	3	30168	3984	25391	793	13%	26:14:35
4	news-out.mixmin.net	6	54042	3906	48733	1403	7%	20:52:38
5	newsfeed.neva.ru	147	71546	3211	59818	8517	4%	71:52:20
6	newsfeeds.xmission.com	2	26316	3202	22722	392	12%	22:48:41
7	nntp.pasdenom.info	9	24617	442	24111	64	1%	21:29:10
8	news.swapon.de	3	3467	410	3056	1	11%	13:14:38
9	localhost	50	15310	1	15309	0	0%	00:01:02
TOTAL: 9		248	324873	27846	272881	24146	8%	230:25:26

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	22.2 MB	222.2 KB	41.3 MB	63.7 MB	34%	5.7 KB
2	xanadu-bbs.net	17.2 MB	51.3 KB	337.1 MB	354.4 MB	4%	25.5 KB
3	au2pb.net	10.3 MB	21.2 KB	5.6 MB	15.9 MB	64%	3.4 KB
4	news-out.mixmin.net	10.1 MB	0.0 KB	2.4 MB	12.5 MB	80%	2.4 KB
5	newsfeed.neva.ru	9.9 MB	1015.9 KB	79.1 MB	90.0 MB	11%	7.9 KB
6	newsfeeds.xmission.com	9.9 MB	0.0 KB	2.7 MB	12.5 MB	78%	3.6 KB
7	news.swapon.de	1006.6 KB	0.0 KB	5.3 KB	1011.9 KB	99%	2.5 KB
8	nntp.pasdenom.info	961.0 KB	0.0 KB	1015.5 KB	1.9 MB	48%	3.9 KB
9	localhost	2.1 KB	0.0 KB	0.0 KB	2.1 KB	100%	2.1 KB
TOTAL: 9		81.5 MB	1.3 MB	469.1 MB	551.9 MB	14%	10.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 13 04:15:00 - 04:59:59	438	1.6%	0.16	1.2 MB	1.5%	0.47
Aug 13 05:00:00 - 05:59:59	636	2.3%	0.18	1.9 MB	2.3%	0.55
Aug 13 06:00:00 - 06:59:59	775	2.8%	0.22	2.1 MB	2.6%	0.61
Aug 13 07:00:00 - 07:59:59	1314	4.7%	0.36	5.2 MB	6.3%	1.48
Aug 13 08:00:00 - 08:59:59	1438	5.1%	0.40	5.4 MB	6.5%	1.54
Aug 13 09:00:00 - 09:59:59	1352	4.8%	0.38	3.7 MB	4.4%	1.04
Aug 13 10:00:00 - 10:59:59	1415	5.1%	0.39	3.8 MB	4.6%	1.08
Aug 13 11:00:00 - 11:59:59	1421	5.1%	0.39	4.0 MB	4.9%	1.14
Aug 13 12:00:00 - 12:59:59	1329	4.7%	0.37	3.5 MB	4.2%	0.99
Aug 13 13:00:00 - 13:59:59	1498	5.4%	0.42	3.7 MB	4.5%	1.07
Aug 13 14:00:00 - 14:59:59	1447	5.2%	0.40	3.9 MB	4.7%	1.11
Aug 13 15:00:00 - 15:59:59	1588	5.7%	0.44	4.3 MB	5.2%	1.23
Aug 13 16:00:00 - 16:59:59	1530	5.5%	0.42	3.9 MB	4.7%	1.11
Aug 13 17:00:00 - 17:59:59	1639	5.9%	0.46	4.6 MB	5.5%	1.30
Aug 13 18:00:00 - 18:59:59	1479	5.3%	0.41	4.1 MB	5.0%	1.17
Aug 13 19:00:00 - 19:59:59	1577	5.6%	0.44	4.4 MB	5.3%	1.25
Aug 13 20:00:00 - 20:59:59	1371	4.9%	0.38	3.5 MB	4.2%	1.00
Aug 13 21:00:00 - 21:59:59	1310	4.7%	0.36	3.8 MB	4.6%	1.08
Aug 13 22:00:00 - 22:59:59	1067	3.8%	0.30	3.1 MB	3.8%	0.89
Aug 13 23:00:00 - 23:59:59	800	2.9%	0.22	2.6 MB	3.1%	0.73
Aug 14 00:00:00 - 00:59:59	572	2.0%	0.16	2.0 MB	2.4%	0.57
Aug 14 01:00:00 - 01:59:59	522	1.9%	0.14	1.8 MB	2.1%	0.50
Aug 14 02:00:00 - 02:59:59	702	2.5%	0.20	2.5 MB	3.0%	0.70
Aug 14 03:00:00 - 03:59:59	602	2.2%	0.17	1.9 MB	2.3%	0.55
Aug 14 04:00:00 - 04:15:00	166	0.6%	0.18	1.7 MB	2.0%	1.90
TOTAL: 24:00:00	27988	100.0%	0.32	82.6 MB	100.0%	0.98

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8826	827	5	7994
2	newsfeed.neva.ru	8578	1486	90	7002
3	feeder.eternal-september.org	4292	377	20	3895
4	news-out.mixmin.net	1520	23	0	1497
5	au2pb.net	821	739	1	81
6	newsfeeds.xmission.com	432	374	0	58
7	news.swapon.de	90	0	0	90
8	nntp.pasdenom.info	71	58	0	13
TOTAL: 8		24630	3884	116	20630

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	835
lada.talk	192
mailing.freebsd.pkg-fallout	183
mailing.freebsd.svn-ports-all	158
mailing.freebsd.svn-ports-head	153
alt.politics	130
alt.windows7.general	113
alt.politics.scorched-earth	104
lada.auto	97
mozilla.dev.tree-alerts	75
ut.test	68
mailing.freebsd.svn-src-all	61
fa.freebsd.cvs-all	58
alt.job	54
mailing.freebsd.svn-src-head	42
mailing.database.pgsql-general	42
mozilla.support.firefox	40
mpc.lists.freebsd.ports.bugs	38
mailing.freebsd.ports-bugs	38
mailing.gcc.gcc-cvs	36
TOTAL: 269	3884

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13448
EMP (phn path)	4498
EMP (phl)	532
PFM bot misc	133
HTML Multipart	48
Too many newsgroups	36
Binary Image: misplaced jpg	14
HTML Image Tags	5
Too many newsgroups (meow)	4
HTML post	3
Too many newsgroups (low_xpost)	2
Binary: misplaced PAR2	2
Binary: misplaced par2	2
Too many test groups in crosspost	1
Binary: misplaced pdf	1
TOTAL: 15	18729

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	1374
	feeder.eternal-september.org	44
	news-out.mixmin.net	43
	au2pb.net	36
	newsfeeds.xmission.com	35
	nntp.pasdenom.info	10
	TOTAL: 6	1542
Including strange strings
	news.swapon.de	89
	feeder.eternal-september.org	79
	news-out.mixmin.net	75
	xanadu-bbs.net	49
	au2pb.net	33
	newsfeeds.xmission.com	21
	newsfeed.neva.ru	10
	nntp.pasdenom.info	3
	TOTAL: 8	359
TOTAL: 2		1901

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	34166	416	26287	822	11	1%	23:59:37
2	au2pb	25257	302	23649	180	1	1%	23:59:38
3	xanadu-bbs	24149	123	22441	46	5	0%	23:59:38
4	news.mixmin.net	21503	19	20524	11	12	0%	20:11:28
5	xmission	27982	9	24254	2	95	0%	23:59:38
6	neva.ru	26259	8	24279	109	2	0%	23:59:36
7	eternal-september	21676	7	20129	2	700	0%	23:59:37
8	news.swapon.de	1904	2	1866	4	56	0%	20:57:17
TOTAL: 8		182896	886	163429	1176	882	0%	185:06:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.6 MB	1.8 MB	3.4 MB	2.8 KB	23:59:37
2	au2pb	1.3 MB	681.5 KB	2.0 MB	4.1 KB	23:59:38
3	xanadu-bbs	784.3 KB	82.7 KB	867.0 KB	5.1 KB	23:59:38
4	news.mixmin.net	116.6 KB	21.1 KB	137.7 KB	4.6 KB	20:11:28
5	neva.ru	35.8 KB	537.9 KB	573.7 KB	4.9 KB	23:59:36
6	eternal-september	35.8 KB	16.3 KB	52.1 KB	5.8 KB	23:59:37
7	xmission	18.9 KB	52.9 KB	71.8 KB	6.5 KB	23:59:38
8	news.swapon.de	4.0 KB	9.7 KB	13.7 KB	2.3 KB	20:57:17
TOTAL: 8		3.8 MB	3.2 MB	7.0 MB	3.5 KB	185:06:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	202.89.176.220	288	37	108.4 KB	36	1	04:25:01
2	tempe0.bbox.io	34	6	12.4 KB	3	0	04:44:29
3	dhcp-089-099-203-254.chello.nl	44	1	2.1 KB	1	0	18:21:58
TOTAL: 3		366	44	122.9 KB	40	1	35:25:53

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	321	37	108.4 KB	36	1	08:03:10
2	*.bbox.io	34	6	12.4 KB	3	0	04:44:29
3	*.chello.nl	44	1	2.1 KB	1	0	18:21:58
4	*.nielsenmedia.com	11	0	0.0 KB	0	0	01:09:09
5	*.tmodns.net	1	0	0.0 KB	0	0	00:00:03
6	*.dynamic.upc.nl	2	0	0.0 KB	0	0	00:00:10
7	*.mixmin.net	348	0	0.0 KB	0	0	00:03:08
8	*.ip-188-165-239.eu	6	0	0.0 KB	0	0	00:23:08
9	*.de	345	0	0.0 KB	0	0	00:03:24
10	*.tampfl.dsl-w.verizon.net	8	0	0.0 KB	0	0	02:07:09
11	*.188.193.178.dynamic.wline.res.cust.swisscom.ch	1	0	0.0 KB	0	0	00:30:00
TOTAL: 11		1121	44	122.9 KB	40	1	35:25:53

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
dhcp-089-099-203-254.chello.nl	3.640	0.598	0.000	18:21:58
tempe0.bbox.io	2.585	0.502	0.000	04:44:29
202.89.176.220	26.103	5.295	0.677	04:25:01
162.212.88.198	0.372	0.108	0.000	03:38:06
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.675	0.149	0.000	02:07:09
challenger.nielsenmedia.com	0.730	0.129	0.000	01:09:09
167.188.193.178.dynamic.wline.res.cust.swisscom.ch	0.065	0.006	0.000	00:30:00
ns342002.ip-188-165-239.eu	0.474	0.111	0.000	00:23:08
swapon.de	26.944	4.167	0.000	00:03:24
snorky.mixmin.net	27.363	4.372	0.000	00:03:08
92-108-162-83.dynamic.upc.nl	0.171	0.025	0.000	00:00:10
ma80436d0.tmodns.net	0.088	0.016	0.000	00:00:03
69.160.37.54	1.909	0.347	0.000	00:00:02
TOTAL: 13	91.119	15.825	0.677	35:25:53

Curious NNRP server explorers [Top 20]:

System	Conn
snorky.mixmin.net	348
swapon.de	345
69.160.37.54	25
challenger.nielsenmedia.com	11
pool-71-98-160-84.tampfl.dsl-w.verizon.net	8
162.212.88.198	8
ns342002.ip-188-165-239.eu	6
92-108-162-83.dynamic.upc.nl	2
167.188.193.178.dynamic.wline.res.cust.swisscom.ch	1
ma80436d0.tmodns.net	1
TOTAL: 10	755

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	25	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	4	0
tempe0.bbox.io	4	0
challenger.nielsenmedia.com	2	0
167.188.193.178.dynamic.wline.res.cust.swisscom.ch	1	0
TOTAL: 5	36	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	43	97.7%
2	news	1	2.3%
TOTAL: 2		44	100.0%

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

Newsgroup		Count
1	comp.os.vms	18
2	comp.arch.fpga	11
3	comp.sys.ibm.ps2.hardware	6
4	comp.arch	4
5	comp.os.msdos.misc	2
6	news.admin.peering	1
7	comp.sys.ibm.as400.misc	1
8	comp.ai.neural-nets	1
TOTAL: 8		44

Daily Usenet report for csiph.com

Aug 13 04:15:00 -- Aug 14 04:15:00