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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	61040	48.2%	9.0 MB	60.1%
nnrpd	32072	25.3%	2.7 MB	17.9%
innd	31604	25.0%	3.1 MB	20.4%
innfeed	1855	1.5%	253.3 KB	1.6%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	126572	100.0%	15.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	255502	46.9%
Negative hits	225235	41.4%
Do not exist	61273	11.2%
Cache misses	2690	0.5%
TOTAL: 4	544700	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.373	0.0%	311	0.000	1.199	15.000
article cleanup	00:00:00.299	0.0%	44033	0.000	0.007	0.026
article logging	00:00:00.604	0.0%	44598	0.000	0.014	0.060
article parse	00:00:37.269	0.0%	3670732	0.007	0.010	0.066
article write	00:00:48.823	0.1%	26553	0.363	1.839	10.017
artlog/artcncl	00:00:00.003	0.0%	262	0.000	0.011	0.500
artlog/artparse	00:00:00.183	0.0%	16146	0.000	0.011	0.167
data move	00:00:02.999	0.0%	4141899	0.000	0.001	0.001
hisgrep/artcncl	00:00:00.023	0.0%	303	0.000	0.076	13.000
hishave/artcncl	00:00:00.003	0.0%	311	0.000	0.010	1.000
hishave/artparse	00:00:00.055	0.0%	16145	0.000	0.003	0.025
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.921	0.0%	530553	0.001	0.004	0.018
history sync	00:00:01.267	0.0%	309	0.000	4.100	63.000
history write	00:00:22.351	0.0%	44016	0.086	0.508	10.142
hiswrite/artcncl	00:00:00.002	0.0%	8	0.000	0.250	2.000
hiswrite/artparse	00:00:09.840	0.0%	16145	0.000	0.609	13.196
idle	23:55:03.450	99.6%	4051979	8.733	21.250	280.656
nntp read	00:00:12.126	0.0%	4056452	0.000	0.003	0.004
overview write	00:00:47.353	0.1%	26553	0.303	1.783	18.587
perl filter	00:01:16.432	0.1%	44016	1.042	1.736	2.892
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.465	0.0%	53106	0.000	0.009	0.024
TOTAL: 24:00:22.859	23:59:25.841	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	26560	0.000	0.002	0.022
article prepare	00:00:00.002	0.0%	5668	0.000	0.000	0.023
article read	00:00:00.249	0.0%	1244	0.000	0.200	45.000
backlog stats	00:00:00.095	0.0%	300715	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.252	0.0%	227591	0.002	0.006	0.012
data write	00:00:01.572	0.0%	207180	0.003	0.008	0.015
idle	23:52:47.121	100.0%	300715	158.510	285.876	680.323
status file	00:00:00.091	0.0%	187	0.000	0.487	1.000
TOTAL: 23:52:57.397	23:52:50.447	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.541	0.0%	35815	0.012	0.071	0.145
idle	106:01:15.022	99.9%	67399	0.000	5662.918	600121.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.759	0.0%	136079	0.000	0.006	0.167
readart	00:00:00.063	0.0%	164	0.000	0.384	48.000
TOTAL: 106:06:42.707	106:01:18.385	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	2	40123	6675	30340	3108	16%	24:24:42
2	newsfeeds.xmission.com	0	27606	5887	21143	576	21%	24:07:06
3	au2pb.net	1	29818	5499	23159	1160	18%	24:46:19
4	news-out.mixmin.net	3	31881	3023	26085	2773	9%	24:08:48
5	newsfeed.neva.ru	148	54900	3019	44532	7349	5%	71:22:43
6	newsfeed.fu-berlin.de	57	30074	1285	28514	275	4%	34:02:31
7	xanadu-bbs.net	18	58055	845	39613	17597	1%	65:01:44
8	news.swapon.de	2	2409	194	2210	5	8%	24:41:10
9	nntp.pasdenom.info	3	27551	185	27242	124	0%	24:09:29
10	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 10		260	302417	26612	242838	32967	8%	316:44:32

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	19.3 MB	129.3 KB	29.4 MB	48.8 MB	39%	5.1 KB
2	newsfeeds.xmission.com	16.7 MB	0.0 KB	6.7 MB	23.4 MB	71%	3.7 KB
3	au2pb.net	14.2 MB	0.0 KB	7.2 MB	21.3 MB	66%	3.3 KB
4	news-out.mixmin.net	10.8 MB	9.5 KB	5.5 MB	16.4 MB	66%	2.9 KB
5	newsfeed.neva.ru	9.3 MB	12.4 KB	68.9 MB	78.2 MB	11%	7.7 KB
6	newsfeed.fu-berlin.de	2.9 MB	2.0 KB	880.4 KB	3.8 MB	77%	2.5 KB
7	xanadu-bbs.net	2.2 MB	1.9 KB	5.6 GB	5.6 GB	0%	321.1 KB
8	nntp.pasdenom.info	457.1 KB	0.0 KB	1.9 MB	2.4 MB	18%	7.8 KB
9	news.swapon.de	453.8 KB	0.0 KB	12.5 KB	466.4 KB	97%	2.3 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		76.2 MB	155.2 KB	5.8 GB	5.8 GB	1%	102.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 8 04:15:00 - 04:59:59	462	1.7%	0.17	1.5 MB	2.0%	0.59
Dec 8 05:00:00 - 05:59:59	602	2.3%	0.17	1.8 MB	2.4%	0.52
Dec 8 06:00:00 - 06:59:59	681	2.6%	0.19	2.3 MB	3.0%	0.64
Dec 8 07:00:00 - 07:59:59	718	2.7%	0.20	2.3 MB	3.1%	0.66
Dec 8 08:00:00 - 08:59:59	972	3.7%	0.27	2.8 MB	3.7%	0.81
Dec 8 09:00:00 - 09:59:59	1052	4.0%	0.29	3.2 MB	4.2%	0.91
Dec 8 10:00:00 - 10:59:59	1069	4.0%	0.30	2.8 MB	3.6%	0.78
Dec 8 11:00:00 - 11:59:59	1041	3.9%	0.29	2.7 MB	3.6%	0.78
Dec 8 12:00:00 - 12:59:59	1291	4.9%	0.36	3.5 MB	4.7%	1.01
Dec 8 13:00:00 - 13:59:59	1402	5.3%	0.39	3.7 MB	4.9%	1.05
Dec 8 14:00:00 - 14:59:59	1426	5.4%	0.40	3.9 MB	5.2%	1.11
Dec 8 15:00:00 - 15:59:59	1499	5.6%	0.42	4.6 MB	6.1%	1.32
Dec 8 16:00:00 - 16:59:59	1710	6.4%	0.47	5.0 MB	6.6%	1.43
Dec 8 17:00:00 - 17:59:59	1793	6.8%	0.50	4.7 MB	6.2%	1.33
Dec 8 18:00:00 - 18:59:59	1612	6.1%	0.45	4.6 MB	6.0%	1.30
Dec 8 19:00:00 - 19:59:59	1506	5.7%	0.42	4.1 MB	5.4%	1.16
Dec 8 20:00:00 - 20:59:59	1284	4.8%	0.36	3.4 MB	4.5%	0.97
Dec 8 21:00:00 - 21:59:59	1428	5.4%	0.40	4.2 MB	5.5%	1.20
Dec 8 22:00:00 - 22:59:59	1306	4.9%	0.36	3.6 MB	4.8%	1.04
Dec 8 23:00:00 - 23:59:59	901	3.4%	0.25	2.6 MB	3.4%	0.74
Dec 9 00:00:00 - 00:59:59	739	2.8%	0.21	2.2 MB	2.9%	0.63
Dec 9 01:00:00 - 01:59:59	690	2.6%	0.19	1.9 MB	2.5%	0.55
Dec 9 02:00:00 - 02:59:59	641	2.4%	0.18	2.3 MB	3.0%	0.64
Dec 9 03:00:00 - 03:59:59	574	2.2%	0.16	1.7 MB	2.2%	0.47
Dec 9 04:00:00 - 04:15:00	133	0.5%	0.15	435.6 KB	0.6%	0.48
TOTAL: 24:00:00	26532	100.0%	0.31	75.9 MB	100.0%	0.90

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	18304	182	2	18120
2	newsfeed.neva.ru	7403	1565	1	5837
3	feeder.eternal-september.org	3174	294	10	2870
4	news-out.mixmin.net	2776	6	3	2767
5	au2pb.net	1139	1037	0	102
6	newsfeed.fu-berlin.de	708	48	1	659
7	newsfeeds.xmission.com	584	506	0	78
8	nntp.pasdenom.info	132	108	0	24
9	news.swapon.de	30	0	0	30
TOTAL: 9		34250	3746	17	30487

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	857
ut.test	174
mailing.freebsd.pkg-fallout	163
lada.talk	157
alt.politics	125
mailing.freebsd.svn-ports-all	120
mailing.freebsd.svn-ports-head	114
mailing.freebsd.ports-bugs	103
it-alt.sport.calcio.juventus	94
mailing.openbsd.source-changes	82
gmane.linux.alsa.devel	79
lada.auto	58
alt.survival	57
alt.windows7.general	56
alt.politics.scorched-earth	54
fa.openbsd.source-changes	51
mailing.gcc.gcc-cvs	46
mailing.freebsd.questions	43
mailing.openbsd.misc	38
mailing.openbsd.tech	37
TOTAL: 245	3746

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9553
EMP (phn path)	1895
EMP (phl)	957
Binary: misplaced rar	802
EMP (phn nph)	232
PFM bot misc	113
HTML Multipart	72
Too many newsgroups	34
Binary: misplaced rev	24
User-issued spam cancel	23
HTML Image Tags	16
HTML post	12
Binary: misplaced par2	11
Too many newsgroups (low_xpost)	10
Binary Payload	1
Binary: misplaced zip	1
TOTAL: 16	13756

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad command received
	xanadu-bbs.net	18
	TOTAL: 1	18
Huge articles
	xanadu-bbs.net	15947
	feeder.eternal-september.org	88
	newsfeeds.xmission.com	63
	au2pb.net	34
	news-out.mixmin.net	20
	nntp.pasdenom.info	10
	newsfeed.fu-berlin.de	3
	TOTAL: 7	16165
Including strange strings
	newsfeed.fu-berlin.de	446
	feeder.eternal-september.org	54
	news.swapon.de	25
	news-out.mixmin.net	13
	au2pb.net	13
	newsfeeds.xmission.com	9
	newsfeed.neva.ru	5
	xanadu-bbs.net	1
	TOTAL: 8	566
TOTAL: 3		16749

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	32385	152	25254	260	27	0%	23:59:54
2	usenet.pasdenom.info	31590	131	25611	602	3	0%	23:59:54
3	au2pb	21678	110	20897	84	0	0%	23:59:37
4	fu-berlin.de	24865	16	24844	5	0	0%	23:59:54
5	news.mixmin.net	24669	10	23425	0	0	0%	23:59:54
6	xmission	24779	8	20516	0	0	0%	23:59:29
7	neva.ru	24897	3	23164	31	61	0%	23:59:54
8	eternal-september	21058	3	19222	1	0	0%	23:59:53
9	news.swapon.de	2261	0	2231	1	0	0%	23:57:58
TOTAL: 9		208182	433	185164	984	91	0%	215:56:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	829.9 KB	777.0 KB	1.6 MB	3.9 KB	23:59:54
2	usenet.pasdenom.info	631.4 KB	1.3 MB	1.9 MB	2.6 KB	23:59:54
3	au2pb	526.9 KB	274.1 KB	801.0 KB	4.1 KB	23:59:37
4	fu-berlin.de	142.7 KB	4.6 KB	147.3 KB	7.0 KB	23:59:54
5	news.mixmin.net	64.9 KB	0.0 KB	64.9 KB	6.5 KB	23:59:54
6	neva.ru	32.0 KB	181.7 KB	213.7 KB	6.3 KB	23:59:54
7	xmission	17.9 KB	0.0 KB	17.9 KB	2.2 KB	23:59:29
8	eternal-september	16.5 KB	1.8 KB	18.3 KB	4.6 KB	23:59:53
9	news.swapon.de	0.0 KB	2.1 KB	2.1 KB	2.1 KB	23:57:58
TOTAL: 9		2.2 MB	2.5 MB	4.7 MB	3.4 KB	215:56:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	155	431.2 KB	130	04:37:16
2	challenger.nielsenmedia.com	10	8	13.1 KB	6	03:34:30
3	92-108-162-83.dynamic.upc.nl	3	1	1.6 KB	1	00:00:22
TOTAL: 3		301	164	445.9 KB	137	106:06:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	321	155	431.2 KB	130	93:32:33
2	*.nielsenmedia.com	10	8	13.1 KB	6	03:34:30
3	*.dynamic.upc.nl	3	1	1.6 KB	1	00:00:22
4	*.chello.nl	4	0	0.0 KB	0	01:10:55
5	*.bbox.io	11	0	0.0 KB	0	05:28:36
6	*.248.91.75.dynamic.ip.windstream.net	3	0	0.0 KB	0	01:33:02
7	*.tampfl.dsl-w.verizon.net	3	0	0.0 KB	0	00:46:37
TOTAL: 7		355	164	445.9 KB	137	106:06:38

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
63.142.120.8	4.397	3.241	0.000	75:35:37
216.246.166.29	0.618	0.259	0.000	13:19:37
tempe0.bbox.io	1.178	0.153	0.000	05:28:36
202.89.176.220	31.266	6.701	0.737	04:37:16
challenger.nielsenmedia.com	1.112	0.207	0.000	03:34:30
h130.248.91.75.dynamic.ip.windstream.net	0.215	0.103	0.000	01:33:02
dhcp-089-099-203-254.chello.nl	0.349	0.056	0.000	01:10:55
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.294	0.038	0.000	00:46:37
92-108-162-83.dynamic.upc.nl	0.270	0.048	0.000	00:00:22
69.160.37.54	1.762	0.394	0.000	00:00:01
TOTAL: 10	41.461	11.200	0.737	106:06:38

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
tempe0.bbox.io	11
216.246.166.29	8
dhcp-089-099-203-254.chello.nl	4
pool-71-98-160-84.tampfl.dsl-w.verizon.net	3
h130.248.91.75.dynamic.ip.windstream.net	3
TOTAL: 6	54

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	10	0
challenger.nielsenmedia.com	7	0
216.246.166.29	4	0
h130.248.91.75.dynamic.ip.windstream.net	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	1	0
TOTAL: 7	29	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch	85
2	comp.os.vms	43
3	comp.lang.awk	8
4	comp.os.os2.misc	7
5	comp.ai.philosophy	4
6	comp.sys.ibm.pc.games.action	4
7	comp.sys.ibm.as400.misc	3
8	comp.arch.embedded	3
9	comp.sys.ibm.pc.games.war-historical	3
10	comp.sys.ibm.pc.hardware.chips	1
11	comp.ai.nat-lang	1
12	comp.sys.ibm.ps2.hardware	1
13	comp.unix.aix	1
TOTAL: 13		164

Daily Usenet report for csiph.com

Dec 8 04:15:00 -- Dec 9 04:15:00