Daily Usenet report for csiph.com: Oct 3 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42676	45.8%	6.3 MB	57.8%
nnrpd	31543	33.9%	2.6 MB	24.2%
innd	17097	18.3%	1.7 MB	15.6%
innfeed	1865	2.0%	268.5 KB	2.4%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	93182	100.0%	10.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	232755	48.3%
Negative hits	202537	42.0%
Do not exist	42018	8.7%
Cache misses	4842	1.0%
TOTAL: 4	482152	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.621	0.0%	610	0.000	1.018	19.000
article cleanup	00:00:00.261	0.0%	41132	0.000	0.006	0.021
article logging	00:00:00.534	0.0%	41565	0.000	0.013	0.032
article parse	00:00:01.666	0.0%	202008	0.001	0.008	0.017
article write	00:00:06.424	0.0%	23795	0.143	0.270	0.456
artlog/artcncl	00:00:00.004	0.0%	462	0.000	0.009	0.250
artlog/artparse	00:00:00.007	0.0%	682	0.000	0.010	0.500
data move	00:00:00.232	0.0%	615833	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.054	0.0%	315	0.000	0.171	11.500
hishave/artcncl	00:00:00.001	0.0%	610	0.000	0.002	0.003
hishave/artparse	00:00:00.003	0.0%	682	0.000	0.004	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:01.364	0.0%	481769	0.000	0.003	0.021
history sync	00:00:00.605	0.0%	357	0.000	1.695	3.333
history write	00:00:12.358	0.0%	41122	0.066	0.301	1.294
hiswrite/artcncl	00:00:00.054	0.0%	295	0.183	0.183	0.183
hiswrite/artparse	00:00:00.135	0.0%	682	0.000	0.198	5.500
idle	23:58:25.213	99.8%	600861	33.952	143.636	460.153
nntp read	00:00:01.958	0.0%	599644	0.000	0.003	0.006
overview write	00:00:17.283	0.0%	23795	0.106	0.726	2.962
perl filter	00:01:15.714	0.1%	41122	1.088	1.841	3.328
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.443	0.0%	47590	0.000	0.009	0.044
TOTAL: 24:00:55.493	24:00:24.934	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.065	0.0%	23821	0.000	0.003	0.026
article prepare	00:00:00.000	0.0%	8212	0.000	0.000	0.000
article read	00:00:00.237	0.0%	1560	0.000	0.152	8.800
backlog stats	00:00:00.093	0.0%	272865	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.075	0.0%	206123	0.000	0.005	0.012
data write	00:00:01.624	0.0%	185519	0.002	0.009	0.021
idle	23:54:44.426	100.0%	272865	133.333	315.484	929.065
status file	00:00:00.076	0.0%	180	0.000	0.422	1.000
TOTAL: 23:54:51.196	23:54:47.596	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.050	0.0%	1381	0.003	0.036	0.056
idle	15:50:47.498	99.9%	32488	0.000	1755.956	600085.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:06.705	0.0%	159982	0.000	0.042	0.067
readart	00:00:00.010	0.0%	57	0.000	0.175	1.000
TOTAL: 15:51:42.735	15:50:54.263	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	14	37780	6419	28566	2795	16%	26:31:35
2	au2pb.net	1	24707	4956	19150	601	20%	23:02:25
3	xanadu-bbs.net	1	41643	3726	30160	7757	8%	24:03:31
4	news-out.mixmin.net	3	29896	3160	24271	2465	10%	24:09:27
5	newsfeed.neva.ru	145	51737	2374	45555	3808	4%	72:12:56
6	newsfeeds.xmission.com	1	23378	1531	21662	185	6%	22:28:52
7	newsfeed.fu-berlin.de	40	26568	1074	25149	345	4%	29:17:11
8	nntp.pasdenom.info	2	19820	92	19709	19	0%	17:24:12
9	localhost	51	15662	1	15661	0	0%	00:01:04
10	news.swapon.de	1	17	0	17	0	0%	00:55:41
TOTAL: 10		259	271208	23333	229900	17975	8%	240:06:54

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	17.5 MB	30.7 KB	11.0 MB	28.5 MB	61%	3.2 KB
2	au2pb.net	13.8 MB	1.4 KB	3.3 MB	17.0 MB	80%	3.1 KB
3	news-out.mixmin.net	11.1 MB	1.7 KB	6.6 MB	17.7 MB	62%	3.2 KB
4	xanadu-bbs.net	10.3 MB	0.0 KB	111.5 MB	121.8 MB	8%	10.9 KB
5	newsfeed.neva.ru	9.0 MB	29.0 KB	20.4 MB	29.4 MB	30%	4.9 KB
6	newsfeeds.xmission.com	4.8 MB	0.0 KB	1.8 MB	6.6 MB	73%	3.9 KB
7	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	947.7 KB	3.4 MB	72%	2.5 KB
8	nntp.pasdenom.info	201.1 KB	5.6 KB	91.9 KB	298.6 KB	67%	2.7 KB
9	localhost	1.0 KB	0.0 KB	0.0 KB	1.0 KB	100%	1.0 KB
10	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		69.2 MB	68.3 KB	155.6 MB	224.8 MB	30%	5.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 3 04:15:00 - 04:59:59	318	1.3%	0.12	1.1 MB	1.6%	0.42
Oct 3 05:00:00 - 05:59:59	562	2.4%	0.16	1.9 MB	2.7%	0.55
Oct 3 06:00:00 - 06:59:59	789	3.3%	0.22	3.2 MB	4.5%	0.90
Oct 3 07:00:00 - 07:59:59	898	3.8%	0.25	2.4 MB	3.5%	0.69
Oct 3 08:00:00 - 08:59:59	1151	4.8%	0.32	3.3 MB	4.7%	0.94
Oct 3 09:00:00 - 09:59:59	1145	4.8%	0.32	3.3 MB	4.6%	0.92
Oct 3 10:00:00 - 10:59:59	1130	4.8%	0.31	3.1 MB	4.5%	0.90
Oct 3 11:00:00 - 11:59:59	1133	4.8%	0.31	3.1 MB	4.4%	0.87
Oct 3 12:00:00 - 12:59:59	1100	4.6%	0.31	3.1 MB	4.4%	0.88
Oct 3 13:00:00 - 13:59:59	1151	4.8%	0.32	3.1 MB	4.4%	0.87
Oct 3 14:00:00 - 14:59:59	1146	4.8%	0.32	3.0 MB	4.3%	0.85
Oct 3 15:00:00 - 15:59:59	1359	5.7%	0.38	4.6 MB	6.6%	1.31
Oct 3 16:00:00 - 16:59:59	1369	5.8%	0.38	3.6 MB	5.1%	1.03
Oct 3 17:00:00 - 17:59:59	1456	6.1%	0.40	4.1 MB	5.9%	1.17
Oct 3 18:00:00 - 18:59:59	1460	6.1%	0.41	4.0 MB	5.7%	1.15
Oct 3 19:00:00 - 19:59:59	1244	5.2%	0.35	3.3 MB	4.7%	0.93
Oct 3 20:00:00 - 20:59:59	1365	5.7%	0.38	4.6 MB	6.6%	1.32
Oct 3 21:00:00 - 21:59:59	1061	4.5%	0.29	3.2 MB	4.6%	0.92
Oct 3 22:00:00 - 22:59:59	932	3.9%	0.26	2.6 MB	3.7%	0.74
Oct 3 23:00:00 - 23:59:59	676	2.8%	0.19	2.1 MB	2.9%	0.58
Oct 4 00:00:00 - 00:59:59	632	2.7%	0.18	2.1 MB	3.0%	0.60
Oct 4 01:00:00 - 01:59:59	676	2.8%	0.19	2.2 MB	3.1%	0.61
Oct 4 02:00:00 - 02:59:59	440	1.9%	0.12	1.6 MB	2.2%	0.44
Oct 4 03:00:00 - 03:59:59	456	1.9%	0.13	1.3 MB	1.8%	0.36
Oct 4 04:00:00 - 04:15:00	134	0.6%	0.15	400.1 KB	0.6%	0.44
TOTAL: 24:00:00	23783	100.0%	0.28	70.1 MB	100.0%	0.83

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	7789	672	0	7117
2	newsfeed.neva.ru	3795	815	4	2976
3	feeder.eternal-september.org	2816	292	3	2521
4	news-out.mixmin.net	2475	5	1	2469
5	newsfeed.fu-berlin.de	716	29	0	687
6	au2pb.net	621	567	1	53
7	newsfeeds.xmission.com	183	165	0	18
8	news.swapon.de	19	0	0	19
9	nntp.pasdenom.info	18	9	1	8
TOTAL: 9		18432	2554	10	15868

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	280
mailing.freebsd.svn-src-all	116
ut.test	114
fa.freebsd.cvs-all	113
alt.politics	110
mailing.freebsd.svn-ports-all	107
mailing.freebsd.svn-ports-head	105
it-alt.sport.calcio.juventus	100
alt.politics.scorched-earth	99
lada.talk	70
mailing.freebsd.svn-src-stable	65
mozilla.dev.tree-alerts	64
mailing.freebsd.svn-src-stable-10	63
pld.cvs.commit	59
alt.survival	52
mailing.freebsd.svn-src-head	51
free.it.calcio.juventus	50
mailing.openbsd.source-changes	46
mpc.lists.nanog	42
mailing.freebsd.pkg-fallout	36
TOTAL: 209	2554

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10800
EMP (phn path)	2326
EMP (phl)	916
EMP (phn nph)	316
PFM bot misc	226
Too many newsgroups	55
Too many newsgroups (low_xpost)	37
HTML Multipart	32
Binary Image: misplaced jpg	25
HTML post	14
HTML Image Tags	2
User-issued spam cancel	1
Binary: misplaced arj	1
TOTAL: 13	14751

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	554
	news-out.mixmin.net	38
	feeder.eternal-september.org	38
	au2pb.net	37
	newsfeeds.xmission.com	11
	nntp.pasdenom.info	5
	newsfeed.fu-berlin.de	1
	TOTAL: 7	684
Including strange strings
	newsfeed.fu-berlin.de	348
	feeder.eternal-september.org	33
	news.swapon.de	19
	au2pb.net	12
	news-out.mixmin.net	10
	xanadu-bbs.net	5
	newsfeed.neva.ru	3
	newsfeeds.xmission.com	3
	TOTAL: 8	433
TOTAL: 2		1117

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	usenet.pasdenom.info	30218	347	22408	897	70	1%	23:59:44
2	au2pb	19825	276	18277	165	0	1%	23:59:44
3	xanadu-bbs	21973	141	19867	58	5	0%	23:59:58
4	fu-berlin.de	22405	37	22346	22	0	0%	23:59:13
5	xmission	27984	10	21959	1	0	0%	23:59:58
6	news.mixmin.net	21411	10	20485	8	0	0%	23:59:57
7	neva.ru	22277	5	21233	73	0	0%	23:59:56
8	eternal-september	18147	3	17047	1	0	0%	23:59:56
9	news.swapon.de	1730	0	1716	2	0	0%	23:53:40
TOTAL: 9		185970	829	165338	1227	75	0%	215:52:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	usenet.pasdenom.info	1.4 MB	1.8 MB	3.2 MB	2.6 KB	23:59:44
2	au2pb	1.0 MB	477.8 KB	1.5 MB	3.5 KB	23:59:44
3	xanadu-bbs	793.7 KB	130.0 KB	923.6 KB	4.6 KB	23:59:58
4	fu-berlin.de	166.6 KB	30.4 KB	197.0 KB	3.3 KB	23:59:13
5	news.mixmin.net	62.9 KB	14.2 KB	77.0 KB	4.3 KB	23:59:57
6	eternal-september	29.3 KB	1.0 KB	30.3 KB	7.6 KB	23:59:56
7	xmission	28.0 KB	3.2 KB	31.3 KB	2.8 KB	23:59:58
8	neva.ru	15.3 KB	272.9 KB	288.1 KB	3.7 KB	23:59:56
9	news.swapon.de	0.0 KB	3.3 KB	3.3 KB	1.6 KB	23:53:40
TOTAL: 9		3.5 MB	2.8 MB	6.2 MB	3.1 KB	215:52:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	202.89.176.220	286	55	144.8 KB	52	0	06:02:46
2	tempe0.bbox.io	9	2	4.4 KB	4	1	02:14:07
TOTAL: 2		295	57	149.3 KB	56	1	15:51:39

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	319	55	144.8 KB	52	0	10:50:53
2	*.tampfl.dsl-w.verizon.net	6	0	0.0 KB	0	0	02:46:38
TOTAL: 2		325	57	149.3 KB	56	1	13:37:32

NNRP auth users [Top 20]:

User	Conn
moussa	286
kev009	4
TOTAL: 2	290

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
202.89.176.220	25.768	6.115	0.707	06:02:46
162.212.88.198	0.406	0.107	0.000	04:48:04
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.487	0.127	0.000	02:46:38
tempe0.bbox.io	1.222	0.981	0.007	02:14:07
69.160.37.54	1.937	0.428	0.000	00:00:02
TOTAL: 5	29.820	7.758	0.714	15:51:39

Curious NNRP server explorers [Top 20]:

System	Conn
69.160.37.54	25
162.212.88.198	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6
TOTAL: 3	39

NNRP no permission clients:

System	Conn
tempe0.bbox.io	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
pool-71-98-160-84.tampfl.dsl-w.verizon.net	5	0
tempe0.bbox.io	3	0
TOTAL: 2	8	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	63	86.3%
2	news	10	13.7%
TOTAL: 2		73	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.pc.hardware.storage	14
2	comp.arch.fpga	10
3	news.software.nntp	10
4	comp.sys.ibm.pc.games.action	9
5	comp.os.vms	8
6	comp.unix.aix	8
7	comp.arch.embedded	5
8	comp.arch	4
9	comp.sys.ibm.ps2.hardware	2
10	comp.os.linux.networking	1
11	comp.sys.ibm.pc.demos	1
12	comp.answers	1
TOTAL: 12		73

Daily Usenet report for csiph.com

Oct 3 04:15:00 -- Oct 4 04:15:00