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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	43288	45.9%	6.4 MB	57.8%
nnrpd	31827	33.7%	2.6 MB	23.8%
innd	17376	18.4%	1.8 MB	15.9%
innfeed	1900	2.0%	281.9 KB	2.5%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	94392	100.0%	11.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	231570	47.0%
Negative hits	216330	43.9%
Do not exist	42320	8.6%
Cache misses	2652	0.5%
TOTAL: 4	492872	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.463	0.0%	265	0.000	1.747	13.700
article cleanup	00:00:00.260	0.0%	41934	0.000	0.006	0.027
article logging	00:00:00.474	0.0%	42442	0.000	0.011	0.055
article parse	00:00:01.798	0.0%	211610	0.003	0.008	0.019
article write	00:00:05.899	0.0%	24614	0.147	0.240	0.395
artlog/artcncl	00:00:00.001	0.0%	161	0.000	0.006	0.091
artlog/artparse	00:00:00.012	0.0%	661	0.000	0.018	1.000
data move	00:00:00.247	0.0%	635226	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.007	0.0%	265	0.000	0.026	1.000
hishave/artcncl	00:00:00.002	0.0%	265	0.000	0.008	0.067
hishave/artparse	00:00:00.002	0.0%	661	0.000	0.003	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.236	0.0%	494805	0.001	0.002	0.008
history sync	00:00:00.584	0.0%	353	0.000	1.654	3.667
history write	00:00:11.199	0.0%	41931	0.073	0.267	0.353
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.202	0.0%	653	0.000	0.309	3.000
idle	23:58:29.905	99.8%	621262	32.023	138.927	446.138
nntp read	00:00:02.102	0.0%	619807	0.001	0.003	0.006
overview write	00:00:05.887	0.0%	24614	0.086	0.239	21.644
perl filter	00:01:18.241	0.1%	41931	0.966	1.866	3.667
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.449	0.0%	49228	0.000	0.009	0.032
TOTAL: 24:00:51.072	24:00:18.970	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.045	0.0%	24623	0.000	0.002	0.016
article prepare	00:00:00.001	0.0%	8056	0.000	0.000	0.017
article read	00:00:00.254	0.0%	1530	0.000	0.166	34.500
backlog stats	00:00:00.104	0.0%	277044	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.235	0.0%	212281	0.001	0.006	0.012
data write	00:00:01.581	0.0%	189282	0.003	0.008	0.017
idle	23:54:08.950	100.0%	277044	175.425	310.597	875.939
status file	00:00:00.085	0.0%	203	0.000	0.419	2.000
TOTAL: 23:54:18.118	23:54:12.255	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.001	0.0%	130	0.000	0.008	0.015
idle	14:03:34.675	99.9%	31453	0.000	1609.216	600124.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.135	0.0%	63801	0.000	0.018	6.073
readart	00:00:00.630	0.0%	4690	0.000	0.134	71.000
TOTAL: 14:04:46.050	14:03:36.441	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	7	39190	6516	30612	2062	16%	24:36:14
2	au2pb.net	1	25721	4594	20532	595	17%	23:29:58
3	xanadu-bbs.net	1	42484	3865	30442	8177	9%	24:06:16
4	news-out.mixmin.net	4	30741	3695	24355	2691	12%	24:09:27
5	newsfeed.neva.ru	143	50794	1941	44858	3995	3%	71:53:20
6	newsfeeds.xmission.com	1	24395	1573	22730	92	6%	22:56:56
7	newsfeed.fu-berlin.de	40	29316	1439	27622	255	4%	31:29:46
8	nntp.pasdenom.info	2	18691	583	18061	47	3%	17:45:17
9	news.swapon.de	2	1795	224	1566	5	12%	13:31:02
10	localhost	48	9671	0	9671	0	0%	00:00:58
TOTAL: 10		249	272798	24430	230449	17919	8%	253:59:14

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	17.6 MB	40.4 KB	16.3 MB	33.9 MB	51%	4.1 KB
2	au2pb.net	12.6 MB	0.0 KB	3.4 MB	16.0 MB	78%	3.2 KB
3	xanadu-bbs.net	10.9 MB	10.4 KB	108.1 MB	119.0 MB	9%	10.1 KB
4	news-out.mixmin.net	10.5 MB	40.3 KB	5.0 MB	15.5 MB	67%	2.5 KB
5	newsfeed.neva.ru	6.7 MB	0.0 KB	25.3 MB	32.0 MB	20%	5.5 KB
6	newsfeeds.xmission.com	5.0 MB	0.0 KB	2.0 MB	7.1 MB	71%	4.3 KB
7	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	785.1 KB	3.8 MB	79%	2.3 KB
8	nntp.pasdenom.info	1.3 MB	0.0 KB	222.3 KB	1.5 MB	85%	2.4 KB
9	news.swapon.de	579.7 KB	0.0 KB	625.6 KB	1.2 MB	48%	5.3 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		68.1 MB	91.1 KB	161.8 MB	230.0 MB	29%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 27 04:15:00 - 04:59:59	297	1.2%	0.11	1.0 MB	1.5%	0.38
Sep 27 05:00:00 - 05:59:59	496	2.0%	0.14	1.6 MB	2.3%	0.46
Sep 27 06:00:00 - 06:59:59	621	2.5%	0.17	1.6 MB	2.3%	0.45
Sep 27 07:00:00 - 07:59:59	747	3.0%	0.21	2.0 MB	2.9%	0.58
Sep 27 08:00:00 - 08:59:59	916	3.7%	0.25	2.5 MB	3.6%	0.72
Sep 27 09:00:00 - 09:59:59	1200	4.9%	0.33	3.2 MB	4.6%	0.91
Sep 27 10:00:00 - 10:59:59	1129	4.6%	0.31	3.0 MB	4.3%	0.85
Sep 27 11:00:00 - 11:59:59	1138	4.6%	0.32	3.4 MB	4.9%	0.97
Sep 27 12:00:00 - 12:59:59	1221	5.0%	0.34	3.2 MB	4.6%	0.92
Sep 27 13:00:00 - 13:59:59	1175	4.8%	0.33	3.0 MB	4.3%	0.85
Sep 27 14:00:00 - 14:59:59	1186	4.8%	0.33	3.1 MB	4.5%	0.90
Sep 27 15:00:00 - 15:59:59	1432	5.8%	0.40	4.2 MB	6.1%	1.20
Sep 27 16:00:00 - 16:59:59	1482	6.0%	0.41	4.2 MB	6.0%	1.19
Sep 27 17:00:00 - 17:59:59	1443	5.9%	0.40	3.8 MB	5.5%	1.07
Sep 27 18:00:00 - 18:59:59	1434	5.8%	0.40	3.8 MB	5.5%	1.09
Sep 27 19:00:00 - 19:59:59	1624	6.6%	0.45	4.2 MB	6.1%	1.20
Sep 27 20:00:00 - 20:59:59	1493	6.1%	0.41	3.9 MB	5.7%	1.12
Sep 27 21:00:00 - 21:59:59	1365	5.5%	0.38	3.8 MB	5.5%	1.08
Sep 27 22:00:00 - 22:59:59	1025	4.2%	0.28	3.0 MB	4.4%	0.87
Sep 27 23:00:00 - 23:59:59	785	3.2%	0.22	2.3 MB	3.3%	0.65
Sep 28 00:00:00 - 00:59:59	693	2.8%	0.19	2.1 MB	3.1%	0.61
Sep 28 01:00:00 - 01:59:59	548	2.2%	0.15	1.5 MB	2.2%	0.44
Sep 28 02:00:00 - 02:59:59	480	1.9%	0.13	1.7 MB	2.4%	0.48
Sep 28 03:00:00 - 03:59:59	495	2.0%	0.14	1.4 MB	2.1%	0.41
Sep 28 04:00:00 - 04:15:00	210	0.9%	0.23	1.5 MB	2.2%	1.72
TOTAL: 24:00:00	24635	100.0%	0.29	69.3 MB	100.0%	0.82

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8150	529	1	7620
2	newsfeed.neva.ru	3983	794	0	3189
3	news-out.mixmin.net	2766	1	1	2764
4	feeder.eternal-september.org	2171	195	1	1975
5	newsfeed.fu-berlin.de	637	19	0	618
6	au2pb.net	626	588	0	38
7	newsfeeds.xmission.com	85	64	0	21
8	nntp.pasdenom.info	61	39	0	22
9	news.swapon.de	14	0	0	14
TOTAL: 9		18493	2229	3	16261

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	219
ut.test	153
alt.politics	125
lada.talk	124
it-alt.sport.calcio.juventus	91
pld.cvs.commit	71
fa.openbsd.source-changes	61
mailing.openbsd.source-changes	61
mailing.freebsd.svn-ports-all	59
mailing.freebsd.svn-ports-head	59
lada.auto	45
easynews.support	44
mailing.openbsd.misc	42
mailing.freebsd.ports-bugs	35
mpc.lists.freebsd.ports.bugs	35
fa.freebsd.cvs-all	31
mailing.freebsd.svn-src-all	31
mailing.freebsd.pkg-fallout	30
mozilla.support.firefox	29
alt.survival	29
TOTAL: 205	2229

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11029
EMP (phn path)	2659
EMP (phl)	886
PFM bot misc	195
EMP (phn nph)	178
Too many newsgroups	66
HTML Multipart	22
Binary Image: misplaced jpg	17
User-issued spam cancel	14
Too many newsgroups (low_xpost)	12
HTML post	5
Binary: misplaced par2	3
Binary Payload	2
Binary Image: misplaced png	1
HTML Image Tags	1
TOTAL: 15	15090

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	427
	feeder.eternal-september.org	152
	news-out.mixmin.net	34
	au2pb.net	22
	newsfeeds.xmission.com	13
	nntp.pasdenom.info	11
	news.swapon.de	3
	newsfeed.fu-berlin.de	2
	TOTAL: 8	664
Including strange strings
	newsfeed.fu-berlin.de	394
	feeder.eternal-september.org	38
	news-out.mixmin.net	36
	au2pb.net	11
	news.swapon.de	9
	xanadu-bbs.net	8
	newsfeeds.xmission.com	7
	newsfeed.neva.ru	3
	nntp.pasdenom.info	1
	TOTAL: 9	507
TOTAL: 2		1171

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	29791	382	22935	668	30	1%	23:59:55
2	au2pb	21148	280	19333	341	1	1%	23:59:51
3	xanadu-bbs	22085	109	20613	54	7	0%	23:59:55
4	fu-berlin.de	22843	59	22749	34	0	0%	23:59:45
5	news.mixmin.net	21523	12	20770	14	1	0%	23:59:55
6	eternal-september	18769	10	17766	3	29	0%	23:59:55
7	xmission	27676	8	22899	0	0	0%	23:59:52
8	neva.ru	23749	4	22549	47	1	0%	23:59:55
9	news.swapon.de	1251	0	1244	2	983	0%	23:59:55
TOTAL: 9		188835	864	170858	1163	1052	0%	215:58:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.4 MB	852.3 KB	2.3 MB	3.7 KB	23:59:51
2	usenet.pasdenom.info	1.3 MB	1.4 MB	2.6 MB	2.6 KB	23:59:55
3	xanadu-bbs	812.5 KB	104.8 KB	917.3 KB	5.6 KB	23:59:55
4	fu-berlin.de	335.9 KB	43.5 KB	379.3 KB	4.1 KB	23:59:45
5	news.mixmin.net	63.2 KB	53.2 KB	116.4 KB	4.5 KB	23:59:55
6	eternal-september	41.2 KB	4.2 KB	45.5 KB	3.5 KB	23:59:55
7	neva.ru	28.0 KB	223.8 KB	251.8 KB	4.9 KB	23:59:55
8	xmission	16.8 KB	0.0 KB	16.8 KB	2.1 KB	23:59:52
9	news.swapon.de	0.0 KB	4.4 KB	4.4 KB	2.2 KB	23:59:55
TOTAL: 9		4.0 MB	2.6 MB	6.6 MB	3.3 KB	215:58:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	287	27	88.5 KB	27	04:48:53
2	pool-71-98-160-84.tampfl.dsl-w.verizon.net	13	11	20.7 KB	5	02:41:31
3	challenger.nielsenmedia.com	6	10	19.1 KB	3	01:30:41
4	92-108-162-83.dynamic.upc.nl	2	2	2.7 KB	1	00:03:49
TOTAL: 4		308	50	131.0 KB	36	14:04:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	316	27	88.5 KB	27	04:48:55
2	*.tampfl.dsl-w.verizon.net	13	11	20.7 KB	5	02:41:31
3	*.nielsenmedia.com	6	10	19.1 KB	3	01:30:41
4	*.dynamic.upc.nl	2	2	2.7 KB	1	00:03:49
5	*.hsd1.ca.comcast.net	2	0	0.0 KB	0	00:00:09
6	*.chello.nl	8	0	0.0 KB	0	03:20:27
7	*.tu.ok.cox.net	2	0	0.0 KB	0	01:00:10
8	*.bbox.io	8	0	0.0 KB	0	00:38:56
TOTAL: 8		357	50	131.0 KB	36	14:04:42

NNRP auth users [Top 20]:

User	Conn
moussa	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
202.89.176.220	25.404	5.802	0.719	04:48:53
dhcp-089-099-203-254.chello.nl	0.610	0.192	0.000	03:20:27
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1.303	0.259	0.000	02:41:31
challenger.nielsenmedia.com	0.688	0.095	0.000	01:30:41
ip72-222-107-224.tu.ok.cox.net	0.127	0.038	0.000	01:00:10
tempe0.bbox.io	0.547	0.100	0.000	00:38:56
92-108-162-83.dynamic.upc.nl	0.199	0.058	0.000	00:03:49
c-67-181-92-188.hsd1.ca.comcast.net	0.282	0.266	0.000	00:00:09
69.160.37.54	1.827	0.432	0.000	00:00:02
TOTAL: 9	30.987	7.242	0.719	14:04:42

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
dhcp-089-099-203-254.chello.nl	8
tempe0.bbox.io	8
162.212.88.198	4
ip72-222-107-224.tu.ok.cox.net	2
c-67-181-92-188.hsd1.ca.comcast.net	2
TOTAL: 6	49

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	6	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	5	0
challenger.nielsenmedia.com	3	0
ip72-222-107-224.tu.ok.cox.net	2	0
tempe0.bbox.io	1	0
TOTAL: 5	17	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.lang.awk	21
2	comp.arch.embedded	7
3	comp.arch.fpga	7
4	comp.sys.ibm.pc.hardware.storage	6
5	comp.os.vms	4
6	comp.sys.ibm.ps2.hardware	2
7	comp.sys.ibm.pc.hardware.chips	1
8	comp.ai.philosophy	1
9	comp.ai.neural-nets	1
TOTAL: 9		50

Daily Usenet report for csiph.com

Sep 27 04:15:00 -- Sep 28 04:15:00