Daily Usenet report for csiph.com: Sep 8 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	58329	49.3%	8.2 MB	60.3%
nnrpd	32653	27.6%	2.7 MB	19.8%
innd	25559	21.6%	2.4 MB	17.9%
innfeed	1869	1.6%	273.1 KB	2.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	118411	100.0%	13.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	299809	47.5%
Negative hits	268756	42.5%
Do not exist	57646	9.1%
Cache misses	5539	0.9%
TOTAL: 4	631750	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.465	0.0%	352	0.000	1.321	17.400
article cleanup	00:00:00.327	0.0%	56830	0.000	0.006	0.021
article logging	00:00:00.693	0.0%	57294	0.000	0.012	0.034
article parse	00:00:02.644	0.0%	306405	0.003	0.009	0.024
article write	00:00:07.152	0.0%	30123	0.175	0.237	0.367
artlog/artcncl	00:00:00.001	0.0%	217	0.000	0.005	0.111
artlog/artparse	00:00:00.006	0.0%	841	0.000	0.007	1.000
data move	00:00:00.336	0.0%	801860	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.002	0.0%	351	0.000	0.006	1.000
hishave/artcncl	00:00:00.000	0.0%	352	0.000	0.000	0.000
hishave/artparse	00:00:00.003	0.0%	841	0.000	0.004	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.559	0.0%	631552	0.001	0.002	0.008
history sync	00:00:00.546	0.0%	338	0.000	1.615	3.000
history write	00:00:14.606	0.0%	56826	0.075	0.257	0.341
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.217	0.0%	840	0.000	0.258	1.500
idle	23:57:21.239	99.8%	778179	23.579	110.824	338.075
nntp read	00:00:02.636	0.0%	778479	0.001	0.003	0.007
overview write	00:00:05.033	0.0%	30123	0.092	0.167	0.352
perl filter	00:02:11.143	0.2%	56826	1.091	2.308	4.862
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.582	0.0%	60246	0.000	0.010	0.023
TOTAL: 24:00:47.172	24:00:09.190	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.070	0.0%	30081	0.000	0.002	0.021
article prepare	00:00:00.000	0.0%	8056	0.000	0.000	0.000
article read	00:00:00.242	0.0%	1454	0.000	0.166	22.667
backlog stats	00:00:00.113	0.0%	340903	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.412	0.0%	260308	0.001	0.005	0.012
data write	00:00:02.004	0.0%	232940	0.004	0.009	0.015
idle	23:53:08.927	100.0%	340903	152.760	252.239	676.943
status file	00:00:00.081	0.0%	180	0.000	0.450	1.000
TOTAL: 23:53:16.899	23:53:12.849	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.042	0.0%	45937	0.000	0.023	0.152
idle	88:44:23.232	100.0%	77457	1.000	4124.395	450804.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.910	0.0%	155984	0.000	0.006	0.032
readart	00:00:00.116	0.0%	82	0.000	1.415	12.500
TOTAL: 88:45:51.404	88:44:25.300	100.0%	-	-	-	-

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	15	54081	7394	39252	7435	13%	27:40:50
2	au2pb.net	1	31338	6939	23462	937	22%	24:10:13
3	xanadu-bbs.net	1	57928	4784	44929	8215	8%	24:25:48
4	news-out.mixmin.net	5	34012	3976	27900	2136	11%	24:19:19
5	newsfeed.neva.ru	154	75975	2988	64351	8636	3%	72:29:31
6	newsfeeds.xmission.com	1	28481	1729	26586	166	6%	22:54:49
7	newsfeed.fu-berlin.de	70	32746	1599	31063	84	4%	34:44:41
8	nntp.pasdenom.info	2	24733	426	24247	60	1%	21:19:00
9	news.swapon.de	1	2010	200	1810	0	9%	15:47:00
10	localhost	50	14951	0	14951	0	0%	00:01:06
TOTAL: 10		300	356255	30035	298551	27669	8%	267:52:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	21.2 MB	25.2 KB	80.6 MB	101.8 MB	20%	7.0 KB
2	au2pb.net	17.8 MB	11.6 KB	5.3 MB	23.1 MB	77%	3.0 KB
3	xanadu-bbs.net	13.9 MB	11.6 KB	124.1 MB	138.1 MB	10%	10.9 KB
4	news-out.mixmin.net	13.2 MB	0.0 KB	6.3 MB	19.5 MB	67%	3.3 KB
5	newsfeed.neva.ru	10.1 MB	0.0 KB	74.2 MB	84.3 MB	11%	7.4 KB
6	newsfeeds.xmission.com	5.5 MB	0.0 KB	1.5 MB	7.1 MB	78%	3.8 KB
7	newsfeed.fu-berlin.de	3.4 MB	0.0 KB	320.4 KB	3.8 MB	91%	2.3 KB
8	nntp.pasdenom.info	969.5 KB	0.0 KB	499.2 KB	1.4 MB	66%	3.0 KB
9	news.swapon.de	549.0 KB	0.0 KB	0.0 KB	549.0 KB	100%	2.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		86.8 MB	48.4 KB	292.8 MB	379.7 MB	22%	6.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 8 04:15:00 - 04:59:59	416	1.4%	0.15	1.3 MB	1.5%	0.48
Sep 8 05:00:00 - 05:59:59	736	2.4%	0.20	2.6 MB	3.0%	0.73
Sep 8 06:00:00 - 06:59:59	890	3.0%	0.25	2.5 MB	2.9%	0.72
Sep 8 07:00:00 - 07:59:59	1158	3.8%	0.32	3.3 MB	3.8%	0.94
Sep 8 08:00:00 - 08:59:59	1370	4.5%	0.38	3.7 MB	4.2%	1.04
Sep 8 09:00:00 - 09:59:59	1461	4.9%	0.41	3.7 MB	4.2%	1.04
Sep 8 10:00:00 - 10:59:59	1478	4.9%	0.41	4.5 MB	5.2%	1.28
Sep 8 11:00:00 - 11:59:59	1486	4.9%	0.41	3.9 MB	4.5%	1.11
Sep 8 12:00:00 - 12:59:59	1554	5.2%	0.43	4.4 MB	5.1%	1.25
Sep 8 13:00:00 - 13:59:59	1646	5.5%	0.46	4.3 MB	5.0%	1.23
Sep 8 14:00:00 - 14:59:59	1698	5.6%	0.47	5.0 MB	5.8%	1.43
Sep 8 15:00:00 - 15:59:59	1701	5.6%	0.47	4.7 MB	5.5%	1.35
Sep 8 16:00:00 - 16:59:59	1942	6.4%	0.54	5.3 MB	6.1%	1.52
Sep 8 17:00:00 - 17:59:59	1716	5.7%	0.48	4.6 MB	5.3%	1.30
Sep 8 18:00:00 - 18:59:59	1603	5.3%	0.45	6.1 MB	7.0%	1.73
Sep 8 19:00:00 - 19:59:59	1741	5.8%	0.48	4.8 MB	5.5%	1.36
Sep 8 20:00:00 - 20:59:59	1517	5.0%	0.42	4.2 MB	4.8%	1.19
Sep 8 21:00:00 - 21:59:59	1414	4.7%	0.39	3.9 MB	4.5%	1.11
Sep 8 22:00:00 - 22:59:59	1152	3.8%	0.32	3.3 MB	3.8%	0.94
Sep 8 23:00:00 - 23:59:59	810	2.7%	0.23	2.5 MB	2.9%	0.72
Sep 9 00:00:00 - 00:59:59	712	2.4%	0.20	2.6 MB	3.0%	0.75
Sep 9 01:00:00 - 01:59:59	603	2.0%	0.17	1.8 MB	2.1%	0.53
Sep 9 02:00:00 - 02:59:59	586	1.9%	0.16	1.7 MB	2.0%	0.48
Sep 9 03:00:00 - 03:59:59	567	1.9%	0.16	1.8 MB	2.0%	0.50
Sep 9 04:00:00 - 04:15:00	155	0.5%	0.17	401.9 KB	0.5%	0.45
TOTAL: 24:00:00	30112	100.0%	0.35	86.9 MB	100.0%	1.03

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	8598	1672	0	6926
2	xanadu-bbs.net	8112	807	1	7304
3	feeder.eternal-september.org	7436	235	2	7199
4	news-out.mixmin.net	2165	13	0	2152
5	au2pb.net	939	874	1	64
6	newsfeed.fu-berlin.de	481	38	0	443
7	newsfeeds.xmission.com	177	152	0	25
8	nntp.pasdenom.info	72	66	0	6
9	news.swapon.de	21	0	0	21
TOTAL: 9		28001	3857	4	24140

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	762
lada.auto	245
ut.test	189
mailing.freebsd.pkg-fallout	157
lada.talk	132
alt.politics	132
mailing.freebsd.svn-ports-all	119
alt.survival	111
mailing.freebsd.svn-ports-head	110
mozilla.dev.tree-alerts	62
alt.job	59
fa.netbsd.bugs	59
it-alt.sport.calcio.juventus	57
alt.politics.scorched-earth	49
mailing.gcc.gcc-cvs	44
mailing.freebsd.ports-bugs	42
mpc.lists.freebsd.ports.bugs	42
gmane.comp.gis.openstreetmap	42
alt.windows7.general	41
fa.freebsd.cvs-all	39
TOTAL: 280	3857

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	18901
EMP (phn path)	2868
EMP (phl)	833
PFM bot misc	115
HTML Multipart	58
Too many newsgroups	24
Binary Image: misplaced jpg	20
Too many newsgroups (low_xpost)	9
HTML post	6
Too many newsgroups (meow)	1
Binary: misplaced zip	1
TOTAL: 11	22836

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	662
	feeder.eternal-september.org	68
	news-out.mixmin.net	49
	au2pb.net	37
	newsfeeds.xmission.com	15
	nntp.pasdenom.info	5
	newsfeed.fu-berlin.de	2
	TOTAL: 7	838
Including strange strings
	newsfeed.fu-berlin.de	358
	feeder.eternal-september.org	36
	news-out.mixmin.net	26
	news.swapon.de	21
	xanadu-bbs.net	10
	newsfeeds.xmission.com	8
	au2pb.net	4
	newsfeed.neva.ru	2
	nntp.pasdenom.info	1
	TOTAL: 9	466
TOTAL: 2		1304

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	36739	367	28494	707	7	0%	23:59:58
2	au2pb	24201	271	22754	162	0	1%	23:59:58
3	xanadu-bbs	27390	160	25181	48	22	0%	23:59:49
4	fu-berlin.de	27995	66	27890	39	0	0%	23:59:57
5	xmission	35638	33	28026	17	0	0%	23:59:56
6	eternal-september	23605	18	22309	4	0	0%	23:59:58
7	news.mixmin.net	26817	15	25921	17	0	0%	23:59:58
8	neva.ru	28214	12	26861	78	0	0%	23:59:58
9	news.swapon.de	2777	2	2751	6	0	0%	23:59:20
TOTAL: 9		233376	944	210187	1078	29	0%	215:58:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.2 MB	1.4 MB	2.6 MB	2.5 KB	23:59:58
2	au2pb	1.0 MB	835.1 KB	1.9 MB	4.4 KB	23:59:58
3	xanadu-bbs	1.0 MB	92.4 KB	1.1 MB	5.6 KB	23:59:49
4	fu-berlin.de	430.0 KB	50.9 KB	480.9 KB	4.6 KB	23:59:57
5	xmission	71.8 KB	46.1 KB	117.9 KB	2.4 KB	23:59:56
6	news.mixmin.net	61.9 KB	32.0 KB	93.9 KB	2.9 KB	23:59:58
7	eternal-september	55.1 KB	5.6 KB	60.7 KB	2.8 KB	23:59:58
8	neva.ru	44.7 KB	386.5 KB	431.2 KB	4.8 KB	23:59:58
9	news.swapon.de	6.5 KB	11.4 KB	18.0 KB	2.2 KB	23:59:20
TOTAL: 9		3.9 MB	2.9 MB	6.8 MB	3.4 KB	215:58:52

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	73	226.8 KB	67	04:25:34
2	23.160.61.188.dynamic.wline.res.cust.swisscom.ch	1	7	13.9 KB	1	00:30:38
3	tempe0.bbox.io	10	2	3.5 KB	1	01:04:58
TOTAL: 3		299	82	244.1 KB	69	88:45:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	478	73	226.8 KB	67	85:26:48
2	*.160.61.188.dynamic.wline.res.cust.swisscom.ch	1	7	13.9 KB	1	00:30:38
3	*.bbox.io	10	2	3.5 KB	1	01:04:58
4	*.br.br.cox.net	1	0	0.0 KB	0	00:00:24
5	*.tampfl.dsl-w.verizon.net	5	0	0.0 KB	0	00:25:54
6	*.nielsenmedia.com	16	0	0.0 KB	0	01:17:02
TOTAL: 6		511	82	244.1 KB	69	88:45:46

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
108.175.241.207	12.573	4.417	0.000	66:25:18
71.94.21.184	0.372	0.212	0.000	07:30:36
162.212.88.198	0.663	0.211	0.000	07:05:13
202.89.176.220	27.203	5.886	0.689	04:25:34
challenger.nielsenmedia.com	1.178	0.278	0.000	01:17:02
tempe0.bbox.io	0.801	0.104	0.000	01:04:58
23.160.61.188.dynamic.wline.res.cust.swisscom.ch	0.165	0.008	0.000	00:30:38
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.420	0.071	0.000	00:25:54
ip70-186-189-146.br.br.cox.net	0.094	0.008	0.000	00:00:24
123.58.137.161	0.070	0.000	0.000	00:00:03
69.160.37.54	2.013	0.338	0.000	00:00:02
TOTAL: 11	45.552	11.533	0.689	88:45:46

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	150
69.160.37.54	25
challenger.nielsenmedia.com	16
162.212.88.198	12
pool-71-98-160-84.tampfl.dsl-w.verizon.net	5
71.94.21.184	2
123.58.137.161	1
ip70-186-189-146.br.br.cox.net	1
TOTAL: 8	212

NNRP unrecognized commands (by host):

System	Conn
123.58.137.161	2
71.94.21.184	2
TOTAL: 2	4

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	2
GET / HTTP/1.1	1
Host: 69.160.37.58:119	1
TOTAL: 3	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
162.212.88.198	4	4
tempe0.bbox.io	2	0
challenger.nielsenmedia.com	2	0
23.160.61.188.dynamic.wline.res.cust.swisscom.ch	1	0
TOTAL: 4	9	4

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	80	97.6%
2	news	2	2.4%
TOTAL: 2		82	100.0%

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

Newsgroup		Count
1	comp.arch.embedded	30
2	comp.os.vms	15
3	comp.arch.fpga	9
4	comp.sys.ibm.pc.games.action	9
5	comp.sys.ibm.ps2.hardware	7
6	comp.arch	3
7	comp.os.linux.networking	2
8	comp.sys.ibm.pc.hardware.storage	2
9	news.admin.technical	2
10	comp.sys.hp.hardware	1
11	comp.windows.x.motif	1
12	comp.sys.ibm.as400.misc	1
TOTAL: 12		82

Daily Usenet report for csiph.com

Sep 8 04:15:00 -- Sep 9 04:15:00