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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	51613	49.1%	7.4 MB	60.4%
nnrpd	31235	29.7%	2.6 MB	21.1%
innd	20413	19.4%	2.0 MB	16.3%
innfeed	1893	1.8%	279.9 KB	2.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	105155	100.0%	12.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	280326	47.7%
Negative hits	249710	42.5%
Do not exist	50637	8.6%
Cache misses	6524	1.1%
TOTAL: 4	587197	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.245	0.0%	220	0.000	1.114	15.000
article cleanup	00:00:00.299	0.0%	50283	0.000	0.006	0.018
article logging	00:00:00.608	0.0%	50849	0.000	0.012	0.026
article parse	00:00:02.306	0.0%	260723	0.001	0.009	0.018
article write	00:00:07.001	0.0%	28394	0.136	0.247	0.552
artlog/artcncl	00:00:00.001	0.0%	163	0.000	0.006	0.071
artlog/artparse	00:00:00.004	0.0%	652	0.000	0.006	0.200
data move	00:00:00.314	0.0%	756812	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.002	0.0%	220	0.000	0.009	0.250
hishave/artcncl	00:00:00.000	0.0%	220	0.000	0.000	0.000
hishave/artparse	00:00:00.002	0.0%	652	0.000	0.003	0.125
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.493	0.0%	590354	0.001	0.003	0.011
history sync	00:00:00.629	0.0%	355	0.000	1.772	3.500
history write	00:00:14.000	0.0%	50281	0.072	0.278	0.359
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.199	0.0%	648	0.000	0.307	3.000
idle	23:58:04.238	99.8%	741885	44.181	116.304	341.572
nntp read	00:00:02.429	0.0%	740392	0.001	0.003	0.006
overview write	00:00:05.137	0.0%	28394	0.107	0.181	4.385
perl filter	00:01:31.187	0.1%	50280	1.004	1.814	3.532
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.521	0.0%	56788	0.000	0.009	0.023
TOTAL: 24:00:48.059	24:00:10.615	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	29398	0.000	0.002	0.020
article prepare	00:00:00.001	0.0%	6248	0.000	0.000	0.017
article read	00:00:00.198	0.0%	1139	0.000	0.174	5.333
backlog stats	00:00:00.113	0.0%	319690	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.290	0.0%	244557	0.000	0.005	0.015
data write	00:00:01.838	0.0%	218509	0.001	0.008	0.019
idle	23:54:05.936	100.0%	319690	155.926	269.154	739.921
status file	00:00:00.087	0.0%	196	0.000	0.444	1.000
TOTAL: 23:54:13.708	23:54:09.528	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.295	0.0%	13686	0.000	0.022	0.333
idle	53:15:23.021	100.0%	44338	0.000	4324.124	600142.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.105	0.0%	89456	0.000	0.012	37.700
readart	00:00:00.240	0.0%	2896	0.000	0.083	1.000
TOTAL: 53:16:31.700	53:15:24.661	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	12	46493	6764	37284	2445	14%	25:18:09
2	au2pb.net	1	31643	6387	24285	971	20%	24:44:12
3	news-out.mixmin.net	3	33862	4013	27096	2753	11%	24:08:39
4	xanadu-bbs.net	1	50426	3754	36288	10384	7%	23:43:46
5	newsfeed.neva.ru	147	66188	3085	57587	5516	4%	72:06:29
6	newsfeeds.xmission.com	1	30117	2224	27734	159	7%	25:58:59
7	newsfeed.fu-berlin.de	63	32374	1518	30645	211	4%	35:08:12
8	nntp.pasdenom.info	3	26969	600	26264	105	2%	22:45:37
9	news.swapon.de	0	2310	198	2109	3	8%	15:30:44
10	localhost	50	17708	0	17708	0	0%	00:01:02
TOTAL: 10		281	338090	28543	287000	22547	8%	269:25:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	19.2 MB	37.5 KB	24.3 MB	43.5 MB	44%	4.8 KB
2	au2pb.net	17.4 MB	0.0 KB	3.7 MB	21.1 MB	82%	2.9 KB
3	news-out.mixmin.net	11.8 MB	0.0 KB	6.8 MB	18.6 MB	63%	2.8 KB
4	xanadu-bbs.net	10.7 MB	0.0 KB	147.9 MB	158.6 MB	6%	11.5 KB
5	newsfeed.neva.ru	9.9 MB	0.0 KB	32.6 MB	42.5 MB	23%	5.1 KB
6	newsfeeds.xmission.com	6.8 MB	0.0 KB	1.6 MB	8.4 MB	80%	3.6 KB
7	newsfeed.fu-berlin.de	3.3 MB	0.0 KB	709.7 KB	4.0 MB	82%	2.4 KB
8	nntp.pasdenom.info	1.3 MB	0.0 KB	955.5 KB	2.2 MB	58%	3.2 KB
9	news.swapon.de	546.9 KB	0.0 KB	910.6 KB	1.4 MB	37%	7.3 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		80.9 MB	37.5 KB	219.4 MB	300.4 MB	26%	6.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 28 04:15:00 - 04:59:59	482	1.7%	0.18	1.6 MB	2.0%	0.61
Sep 28 05:00:00 - 05:59:59	635	2.2%	0.18	2.2 MB	2.8%	0.64
Sep 28 06:00:00 - 06:59:59	765	2.7%	0.21	2.0 MB	2.5%	0.56
Sep 28 07:00:00 - 07:59:59	1072	3.8%	0.30	2.9 MB	3.6%	0.81
Sep 28 08:00:00 - 08:59:59	1336	4.7%	0.37	3.7 MB	4.6%	1.04
Sep 28 09:00:00 - 09:59:59	1387	4.9%	0.39	3.8 MB	4.7%	1.07
Sep 28 10:00:00 - 10:59:59	1248	4.4%	0.35	3.3 MB	4.1%	0.94
Sep 28 11:00:00 - 11:59:59	1261	4.4%	0.35	3.6 MB	4.5%	1.02
Sep 28 12:00:00 - 12:59:59	1574	5.5%	0.44	3.8 MB	4.7%	1.07
Sep 28 13:00:00 - 13:59:59	1545	5.4%	0.43	4.2 MB	5.2%	1.18
Sep 28 14:00:00 - 14:59:59	1679	5.9%	0.47	4.6 MB	5.7%	1.30
Sep 28 15:00:00 - 15:59:59	1645	5.8%	0.46	4.2 MB	5.3%	1.20
Sep 28 16:00:00 - 16:59:59	1712	6.0%	0.48	5.1 MB	6.4%	1.46
Sep 28 17:00:00 - 17:59:59	1611	5.7%	0.45	4.4 MB	5.6%	1.26
Sep 28 18:00:00 - 18:59:59	1593	5.6%	0.44	4.3 MB	5.3%	1.21
Sep 28 19:00:00 - 19:59:59	1767	6.2%	0.49	5.0 MB	6.2%	1.41
Sep 28 20:00:00 - 20:59:59	1404	4.9%	0.39	3.6 MB	4.5%	1.03
Sep 28 21:00:00 - 21:59:59	1299	4.6%	0.36	3.8 MB	4.7%	1.07
Sep 28 22:00:00 - 22:59:59	992	3.5%	0.28	2.9 MB	3.6%	0.81
Sep 28 23:00:00 - 23:59:59	788	2.8%	0.22	2.3 MB	2.9%	0.67
Sep 29 00:00:00 - 00:59:59	746	2.6%	0.21	2.5 MB	3.1%	0.70
Sep 29 01:00:00 - 01:59:59	606	2.1%	0.17	2.1 MB	2.7%	0.60
Sep 29 02:00:00 - 02:59:59	540	1.9%	0.15	1.9 MB	2.4%	0.55
Sep 29 03:00:00 - 03:59:59	540	1.9%	0.15	1.7 MB	2.1%	0.48
Sep 29 04:00:00 - 04:15:00	139	0.5%	0.15	501.4 KB	0.6%	0.56
TOTAL: 24:00:00	28366	100.0%	0.33	79.8 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	xanadu-bbs.net	10505	660	0	0	9845
2	newsfeed.neva.ru	5521	1725	0	0	3796
3	news-out.mixmin.net	2751	7	0	0	2744
4	feeder.eternal-september.org	2381	323	2	0	2056
5	au2pb.net	961	916	0	1	44
6	newsfeed.fu-berlin.de	667	36	0	0	631
7	newsfeeds.xmission.com	155	135	0	0	20
8	nntp.pasdenom.info	102	87	0	0	15
9	news.swapon.de	42	0	0	0	42
TOTAL: 9		23085	3889	2	1	19193

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	886
lada.auto	179
lada.talk	160
ut.test	149
alt.politics	121
it-alt.sport.calcio.juventus	116
pld.cvs.commit	101
mailing.freebsd.svn-ports-all	94
mailing.freebsd.svn-ports-head	87
mailing.freebsd.ports-bugs	86
alt.job	81
mozilla.dev.tree-alerts	62
alt.windows7.general	60
alabam.test	58
alt.survival	54
mailing.openbsd.source-changes	50
fa.openbsd.source-changes	50
mailing.gcc.gcc-cvs	46
tin.it.sport.calcio	44
mpc.lists.nanog	40
TOTAL: 278	3889

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14453
EMP (phn path)	2162
EMP (phl)	964
EMP (phn nph)	192
Too many newsgroups	71
HTML Multipart	50
PFM bot misc	44
Binary Image: misplaced jpg	30
HTML post	9
User-issued spam cancel	6
HTML Image Tags	5
Binary Payload	1
TOTAL: 12	17987

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	507
	feeder.eternal-september.org	94
	au2pb.net	16
	newsfeeds.xmission.com	15
	news-out.mixmin.net	4
	newsfeed.fu-berlin.de	3
	news.swapon.de	1
	nntp.pasdenom.info	1
	TOTAL: 8	641
Including strange strings
	newsfeed.fu-berlin.de	444
	news.swapon.de	39
	news-out.mixmin.net	33
	feeder.eternal-september.org	16
	au2pb.net	16
	xanadu-bbs.net	10
	newsfeeds.xmission.com	4
	newsfeed.neva.ru	3
	TOTAL: 8	565
TOTAL: 2		1206

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	33961	367	26827	524	6	1%	23:59:57
2	au2pb	22959	191	21760	114	0	0%	23:59:48
3	xanadu-bbs	26243	109	24421	64	69	0%	23:59:58
4	fu-berlin.de	26416	50	26340	25	0	0%	23:59:57
5	xmission	32089	13	26087	2	0	0%	23:59:56
6	news.mixmin.net	25232	12	24223	23	0	0%	23:59:57
7	eternal-september	22810	9	21164	4	28	0%	23:59:57
8	neva.ru	26882	8	25054	49	0	0%	23:59:42
9	news.swapon.de	3093	2	3050	1	0	0%	23:55:10
TOTAL: 9		219685	761	198926	806	103	0%	215:54:22

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.3 MB	1.3 MB	2.7 MB	3.1 KB	23:59:57
2	au2pb	888.6 KB	323.8 KB	1.2 MB	4.0 KB	23:59:48
3	xanadu-bbs	735.7 KB	128.5 KB	864.2 KB	5.0 KB	23:59:58
4	fu-berlin.de	278.4 KB	31.6 KB	310.0 KB	4.1 KB	23:59:57
5	news.mixmin.net	60.6 KB	38.6 KB	99.2 KB	2.8 KB	23:59:57
6	eternal-september	35.3 KB	5.8 KB	41.1 KB	3.2 KB	23:59:57
7	xmission	27.4 KB	2.5 KB	29.9 KB	2.0 KB	23:59:56
8	neva.ru	24.3 KB	242.3 KB	266.7 KB	4.7 KB	23:59:42
9	news.swapon.de	9.6 KB	3.6 KB	13.2 KB	4.4 KB	23:55:10
TOTAL: 9		3.4 MB	2.1 MB	5.4 MB	3.6 KB	215:54:22

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	282	18	76.9 KB	18	0	0	04:57:11
TOTAL: 1		282	18	76.9 KB	18	0	0	53:16:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	318	18	76.9 KB	18	48:02:55
2	*.chello.nl	8	0	0.0 KB	0	02:53:14
3	*.nielsenmedia.com	8	0	0.0 KB	0	01:05:49
4	*.br.br.cox.net	3	0	0.0 KB	0	00:01:19
5	*.ip-188-165-239.eu	27	0	0.0 KB	0	00:03:12
6	*.tampfl.dsl-w.verizon.net	11	0	0.0 KB	0	00:39:50
TOTAL: 6		375	18	76.9 KB	18	53:16:27

NNRP auth users [Top 20]:

User	Conn
moussa	282
TOTAL: 1	282

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.198	0.471	0.606	0.000	38:10:52
202.89.176.220	24.314	5.828	0.686	04:57:11
71.94.21.184	0.526	0.236	0.000	04:54:46
dhcp-089-099-203-254.chello.nl	0.638	0.103	0.000	02:53:14
challenger.nielsenmedia.com	0.613	0.152	0.000	01:05:49
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.611	0.200	0.000	00:39:50
tempe0.bbox.io	0.080	0.024	0.000	00:30:04
ns342002.ip-188-165-239.eu	2.190	0.385	0.000	00:03:12
ip70-186-183-22.br.br.cox.net	0.303	0.125	0.000	00:01:19
173.0.0.237	0.176	0.184	0.000	00:00:02
69.160.37.54	1.899	0.407	0.000	00:00:02
TOTAL: 11	31.821	8.250	0.686	53:16:27

Curious NNRP server explorers [Top 20]:

System	Conn
ns342002.ip-188-165-239.eu	27
69.160.37.54	25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	11
dhcp-089-099-203-254.chello.nl	8
challenger.nielsenmedia.com	8
71.94.21.184	6
162.212.88.198	4
ip70-186-183-22.br.br.cox.net	3
173.0.0.237	1
TOTAL: 9	93

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	6
TOTAL: 1	6

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	6
TOTAL: 1	6

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	5	0
challenger.nielsenmedia.com	2	0
tempe0.bbox.io	1	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 4	9	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.arch.fpga	4
2	comp.sys.ibm.pc.hardware.storage	4
3	comp.arch.embedded	4
4	comp.ai.philosophy	1
5	comp.answers	1
6	comp.sys.ibm.as400.misc	1
7	comp.sys.ibm.ps2.hardware	1
8	comp.os.os2.announce	1
9	comp.arch	1
TOTAL: 9		18

Daily Usenet report for csiph.com

Sep 28 04:15:00 -- Sep 29 04:15:00