Daily Usenet report for csiph.com: Jan 16 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	42216	44.3%	8.1 MB	60.8%
nnrpd	27777	29.2%	2.4 MB	17.6%
innd	21971	23.1%	2.3 MB	17.6%
innfeed	3160	3.3%	534.8 KB	3.9%
nocem	142	0.1%	14.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	95267	100.0%	13.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	489588	56.6%
Negative hits	333633	38.6%
Do not exist	41406	4.8%
Cache misses	488	0.1%
TOTAL: 4	865115	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.345	0.0%	337	0.000	1.024	15.100
article cleanup	00:00:00.259	0.0%	41178	0.000	0.006	0.026
article logging	00:00:00.543	0.0%	41795	0.000	0.013	0.051
article parse	00:00:01.725	0.0%	174354	0.002	0.010	0.078
article write	00:00:59.566	0.1%	24003	0.193	2.482	18.690
artlog/artcncl	00:00:00.001	0.0%	145	0.000	0.007	0.077
artlog/artparse	00:00:00.007	0.0%	300	0.000	0.023	1.000
data move	00:00:00.276	0.0%	873791	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.019	0.0%	337	0.000	0.056	1.000
hishave/artcncl	00:00:00.002	0.0%	337	0.000	0.006	0.111
hishave/artparse	00:00:00.000	0.0%	227	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.678	0.0%	866019	0.002	0.007	0.119
history sync	00:00:01.668	0.0%	346	0.000	4.821	72.000
history write	00:00:35.237	0.0%	41235	0.056	0.855	11.793
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.230	0.0%	227	0.000	1.013	13.333
idle	23:55:25.815	99.6%	888454	63.517	96.939	329.245
nntp read	00:00:02.921	0.0%	893894	0.001	0.003	0.006
overview write	00:00:52.205	0.1%	24003	0.385	2.175	29.862
perl filter	00:01:46.402	0.1%	41155	1.483	2.585	4.757
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.447	0.0%	48141	0.000	0.009	0.026
TOTAL: 24:00:48.722	23:59:53.346	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.084	0.0%	24038	0.000	0.003	0.026
article prepare	00:00:00.000	0.0%	29956	0.000	0.000	0.000
article read	00:00:00.544	0.0%	3008	0.000	0.181	22.500
backlog stats	00:00:00.283	0.0%	569999	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.069	0.0%	413407	0.001	0.005	0.010
data write	00:00:02.831	0.0%	394624	0.003	0.007	0.013
idle	23:52:44.774	100.0%	569999	95.607	150.816	383.292
status file	00:00:00.206	0.0%	268	0.000	0.769	4.222
TOTAL: 23:53:04.890	23:52:50.791	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.673	0.0%	61718	0.013	0.060	0.062
idle	124:42:09.321	100.0%	89032	415.000	5042.337	600198.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.166	0.0%	178865	0.000	0.007	0.500
readart	00:00:00.271	0.0%	79	0.000	3.430	72.000
TOTAL: 124:43:45.103	124:42:14.431	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	5
flushlogs	2
go	2
logmode	2
mode	145
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	165

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	28991	4980	21939	2072	17%	23:45:01
2	csiph-out.feeder.erje.net	1153	90187	4092	84374	1721	4%	95:05:41
3	feeder.eternal-september.org	5	36899	3136	32428	1335	8%	25:24:21
4	csiph-out.news.weretis.net	5	51029	2374	48421	234	4%	48:56:05
5	news-out.aioe.org	4	23365	2238	21057	70	9%	24:12:56
6	news-out.mixmin.net	4	28494	2195	24533	1766	7%	24:11:55
7	newsfeed.neva.ru	152	52192	1704	45939	4549	3%	72:05:46
8	newsfeed.fu-berlin.de	28	28108	1313	26590	205	4%	29:41:00
9	newsfeeds.xmission.com	1	25297	506	24773	18	2%	25:24:50
10	news.freedyn.net	3	29927	480	28915	532	1%	24:03:21
11	xanadu-bbs.net	1	39390	361	34133	4896	0%	23:23:36
12	news.redatomik.org	1	22902	306	22582	14	1%	23:17:54
13	news.fcku.it	1	3262	151	3111	0	4%	15:18:43
14	news.swapon.de	3	2012	109	1902	1	5%	23:48:37
15	au2pb.net	1	25522	77	25428	17	0%	24:24:16
16	nntp.pasdenom.info	3	23159	30	23117	12	0%	24:08:37
17	news.bbs.geek.nz	2	28616	3	28613	0	0%	23:59:40
18	localhost	34	0	0	0	0	0%	00:00:00
TOTAL: 18		1689	539352	24055	497855	17442	4%	551:12:19

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	15.6 MB	20.4 KB	5.3 MB	20.9 MB	74%	3.7 KB
2	usenet.blueworldhosting.com	14.2 MB	0.0 KB	22.8 MB	37.0 MB	38%	5.4 KB
3	feeder.eternal-september.org	8.0 MB	0.0 KB	13.2 MB	21.2 MB	37%	4.9 KB
4	csiph-out.news.weretis.net	6.9 MB	0.0 KB	3.3 MB	10.2 MB	67%	4.0 KB
5	news-out.mixmin.net	6.5 MB	2.9 KB	2.9 MB	9.4 MB	68%	2.4 KB
6	news-out.aioe.org	5.6 MB	0.0 KB	201.2 KB	5.8 MB	96%	2.6 KB
7	newsfeed.neva.ru	5.0 MB	43.3 KB	28.0 MB	33.1 MB	15%	5.4 KB
8	newsfeed.fu-berlin.de	3.0 MB	0.0 KB	388.0 KB	3.3 MB	88%	2.3 KB
9	newsfeeds.xmission.com	1.8 MB	0.0 KB	136.7 KB	1.9 MB	93%	3.8 KB
10	news.freedyn.net	1.7 MB	0.0 KB	1.5 MB	3.2 MB	51%	3.2 KB
11	xanadu-bbs.net	882.3 KB	0.0 KB	55.5 MB	56.4 MB	1%	11.0 KB
12	news.redatomik.org	716.5 KB	22.5 KB	113.3 KB	852.3 KB	84%	2.7 KB
13	news.swapon.de	288.6 KB	0.0 KB	80.3 KB	368.9 KB	78%	3.4 KB
14	news.fcku.it	266.2 KB	0.0 KB	0.0 KB	266.2 KB	100%	1.8 KB
15	au2pb.net	246.7 KB	2.4 KB	104.5 KB	353.6 KB	69%	3.8 KB
16	nntp.pasdenom.info	62.4 KB	0.0 KB	65.0 KB	127.4 KB	48%	3.0 KB
17	news.bbs.geek.nz	7.9 KB	0.0 KB	0.0 KB	7.9 KB	100%	2.6 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		70.5 MB	91.5 KB	133.7 MB	204.4 MB	34%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 16 04:15:00 - 04:59:59	328	1.4%	0.12	923.7 KB	1.3%	0.34
Jan 16 05:00:00 - 05:59:59	462	1.9%	0.13	1.5 MB	2.1%	0.42
Jan 16 06:00:00 - 06:59:59	547	2.3%	0.15	1.7 MB	2.5%	0.49
Jan 16 07:00:00 - 07:59:59	523	2.2%	0.15	1.9 MB	2.7%	0.55
Jan 16 08:00:00 - 08:59:59	991	4.1%	0.28	3.4 MB	4.9%	0.97
Jan 16 09:00:00 - 09:59:59	985	4.1%	0.27	2.5 MB	3.6%	0.72
Jan 16 10:00:00 - 10:59:59	1108	4.6%	0.31	3.0 MB	4.2%	0.84
Jan 16 11:00:00 - 11:59:59	1193	5.0%	0.33	3.8 MB	5.4%	1.08
Jan 16 12:00:00 - 12:59:59	1084	4.5%	0.30	2.7 MB	3.8%	0.77
Jan 16 13:00:00 - 13:59:59	1138	4.7%	0.32	3.1 MB	4.5%	0.89
Jan 16 14:00:00 - 14:59:59	1287	5.4%	0.36	3.6 MB	5.1%	1.02
Jan 16 15:00:00 - 15:59:59	1241	5.2%	0.34	3.7 MB	5.3%	1.05
Jan 16 16:00:00 - 16:59:59	1492	6.2%	0.41	4.5 MB	6.5%	1.29
Jan 16 17:00:00 - 17:59:59	1378	5.7%	0.38	3.8 MB	5.4%	1.08
Jan 16 18:00:00 - 18:59:59	1343	5.6%	0.37	3.9 MB	5.6%	1.11
Jan 16 19:00:00 - 19:59:59	1299	5.4%	0.36	3.6 MB	5.2%	1.03
Jan 16 20:00:00 - 20:59:59	1328	5.5%	0.37	4.0 MB	5.7%	1.14
Jan 16 21:00:00 - 21:59:59	1334	5.6%	0.37	3.7 MB	5.2%	1.04
Jan 16 22:00:00 - 22:59:59	1163	4.8%	0.32	3.3 MB	4.7%	0.93
Jan 16 23:00:00 - 23:59:59	983	4.1%	0.27	2.7 MB	3.8%	0.76
Jan 17 00:00:00 - 00:59:59	816	3.4%	0.23	2.8 MB	4.0%	0.80
Jan 17 01:00:00 - 01:59:59	754	3.1%	0.21	2.4 MB	3.4%	0.68
Jan 17 02:00:00 - 02:59:59	549	2.3%	0.15	1.6 MB	2.3%	0.46
Jan 17 03:00:00 - 03:59:59	541	2.3%	0.15	1.6 MB	2.2%	0.45
Jan 17 04:00:00 - 04:15:00	127	0.5%	0.14	433.7 KB	0.6%	0.48
TOTAL: 24:00:00	23994	100.0%	0.28	70.1 MB	100.0%	0.83

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	5025	13	0	5012
2	newsfeed.neva.ru	4569	818	7	3744
3	usenet.blueworldhosting.com	2078	598	0	1480
4	news-out.mixmin.net	1770	3	1	1766
5	csiph-out.feeder.erje.net	1755	979	7	769
6	feeder.eternal-september.org	1281	88	0	1193
7	newsfeed.fu-berlin.de	680	69	0	611
8	news.freedyn.net	534	1	0	533
9	csiph-out.news.weretis.net	222	118	0	104
10	news-out.aioe.org	79	39	0	40
11	news.swapon.de	24	0	0	24
12	newsfeeds.xmission.com	17	14	0	3
13	au2pb.net	15	9	1	5
14	news.redatomik.org	14	0	0	14
15	nntp.pasdenom.info	13	8	0	5
16	news.fcku.it	1	0	0	1
TOTAL: 16		18077	2757	16	15304

Unwanted newsgroups [Top 20]:

Newsgroup	Count
ut.test	218
fa.linux.kernel	200
mailing.freebsd.ports-bugs	128
mozilla.general	105
alt.biology	102
mailing.freebsd.svn-ports-all	89
mailing.freebsd.svn-ports-head	87
lada.talk	82
lada.auto	76
mailing.freebsd.pkg-fallout	68
mailing.freebsd.svn-src-all	65
mailing.openssl.dev	64
fa.freebsd.cvs-all	62
pld.cvs.commit	52
alt.survival	50
spline.eisfair	47
alt.politics.scorched-earth	45
mailing.freebsd.svn-src-stable	40
mailing.freebsd.svn-src-stable-10	40
mozilla.dev.tree-alerts	39
TOTAL: 227	2757

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10195
EMP (phn path)	2135
EMP (phl)	1026
EMP (phn nph)	257
PFM bot misc	224
User-issued cancel	78
Scoring filter (26)	60
Too many newsgroups	54
HTML Multipart	44
Scoring filter (24)	25
Scoring filter (22)	23
Scoring filter (27)	19
Scoring filter (21)	18
Scoring filter (19)	16
Scoring filter (29)	16
Scoring filter (17)	15
Scoring filter (18)	14
Scoring filter (16)	13
Scoring filter (32)	13
Binary Payload	12
TOTAL: 42	14380

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	135	0	148	148
TOTAL: 1	135	0	148	148

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	6
	usenet.blueworldhosting.com	1
	TOTAL: 2	7
Huge articles
	xanadu-bbs.net	80
	usenet.blueworldhosting.com	77
	feeder.eternal-september.org	30
	csiph-out.news.weretis.net	25
	news.freedyn.net	6
	news-out.mixmin.net	3
	newsfeeds.xmission.com	3
	news.redatomik.org	1
	au2pb.net	1
	news.swapon.de	1
	TOTAL: 10	227
Including strange strings
	newsfeed.fu-berlin.de	496
	csiph-out.feeder.erje.net	32
	news.swapon.de	23
	feeder.eternal-september.org	21
	news-out.aioe.org	15
	news-out.mixmin.net	9
	csiph-out.news.weretis.net	8
	newsfeed.neva.ru	7
	usenet.blueworldhosting.com	2
	news.redatomik.org	2
	TOTAL: 12	617
TOTAL: 3		851

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	27716	1528	21980	5	0	5%	23:59:50
2	xanadu-bbs	25881	1215	22406	1	0	4%	23:59:50
3	fu-berlin.de	22384	951	21433	0	0	4%	23:59:50
4	news.bbs.geek.nz	44080	887	22043	15	108	2%	24:00:00
5	aioe	22652	535	19345	18	0	2%	23:59:46
6	usenet.pasdenom.info	30752	221	22339	1404	1	0%	23:59:50
7	news.redatomik.org	28646	219	22371	55	0	0%	23:59:40
8	au2pb	23561	117	22746	6	0	0%	23:59:50
9	xmission	25506	22	22512	0	0	0%	23:59:50
10	neva.ru	25524	17	20891	173	0	0%	23:59:40
11	news.mixmin.net	23575	15	20508	0	0	0%	23:59:49
12	eternal-september	23910	12	19447	0	0	0%	23:59:50
13	news.fcku.it	2005	8	1714	0	0	0%	22:32:11
14	weretis.net	20131	6	19554	3	0	0%	23:59:46
15	erje	22888	6	19764	68	0	0%	23:59:47
16	news.swapon.de	2237	1	2022	3	0	0%	23:50:39
17	bwh	18973	0	17895	0	0	0%	23:59:50
TOTAL: 17		390421	5760	318970	1751	109	1%	406:19:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.0 MB	16.9 KB	10.0 MB	0.1 KB/s	6.7 KB	23:59:50
2	xanadu-bbs	8.8 MB	1.7 KB	8.8 MB	0.1 KB/s	7.4 KB	23:59:50
3	fu-berlin.de	8.1 MB	0.0 KB	8.1 MB	0.1 KB/s	8.7 KB	23:59:50
4	news.bbs.geek.nz	2.4 MB	77.5 KB	2.5 MB	0.0 KB/s	2.8 KB	24:00:00
5	aioe	1.5 MB	108.7 KB	1.6 MB	0.0 KB/s	3.0 KB	23:59:46
6	usenet.pasdenom.info	842.2 KB	8.7 MB	9.5 MB	0.1 KB/s	6.0 KB	23:59:50
7	news.redatomik.org	706.1 KB	178.7 KB	884.8 KB	0.0 KB/s	3.2 KB	23:59:40
8	au2pb	446.4 KB	69.9 KB	516.3 KB	0.0 KB/s	4.2 KB	23:59:50
9	neva.ru	77.2 KB	656.4 KB	733.6 KB	0.0 KB/s	3.9 KB	23:59:40
10	news.mixmin.net	65.8 KB	0.0 KB	65.8 KB	0.0 KB/s	4.4 KB	23:59:49
11	xmission	53.5 KB	0.0 KB	53.5 KB	0.0 KB/s	2.4 KB	23:59:50
12	eternal-september	38.9 KB	0.0 KB	38.9 KB	0.0 KB/s	3.2 KB	23:59:50
13	weretis.net	23.3 KB	80.1 KB	103.3 KB	0.0 KB/s	11.5 KB	23:59:46
14	news.fcku.it	17.5 KB	0.0 KB	17.5 KB	0.0 KB/s	2.2 KB	22:32:11
15	erje	13.1 KB	482.5 KB	495.6 KB	0.0 KB/s	6.7 KB	23:59:47
16	news.swapon.de	1.1 KB	6.7 KB	7.8 KB	0.0 KB/s	1.9 KB	23:50:39
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:50
TOTAL: 17		33.0 MB	10.4 MB	43.4 MB	0.0 KB/s	5.9 KB	406:19:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	232	64	155.7 KB	59	06:23:01
2	tempe0.bbox.io	50	7	10.5 KB	4	07:12:22
3	92-108-162-83.dynamic.upc.nl	2	6	9.2 KB	1	00:05:03
4	dhcp-089-099-203-254.chello.nl	29	2	2.9 KB	2	13:27:54
TOTAL: 4		313	79	178.3 KB	66	124:43:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	236	64	155.7 KB	59	19:27:16
2	*.bbox.io	50	7	10.5 KB	4	07:12:22
3	*.dynamic.upc.nl	2	6	9.2 KB	1	00:05:03
4	*.chello.nl	29	2	2.9 KB	2	13:27:54
5	*.nielsenmedia.com	2	0	0.0 KB	0	01:23:02
6	*.146.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	00:00:00
7	*.tampfl.dsl-w.verizon.net	2	0	0.0 KB	0	01:00:04
TOTAL: 7		322	79	178.3 KB	66	124:43:41

NNRP auth users [Top 20]:

User	Conn
moussa	232
TOTAL: 1	232

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
h39.138.22.98.dynamic.ip.windstream.net	1.357	2.388	0.000	82:07:58
dhcp-089-099-203-254.chello.nl	2.424	0.568	0.000	13:27:54
162.212.88.221	0.406	0.213	0.000	13:04:14
tempe0.bbox.io	4.330	0.828	0.000	07:12:22
202.89.176.220	23.432	5.459	0.617	06:23:01
challenger.nielsenmedia.com	0.234	0.070	0.000	01:23:02
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.150	0.039	0.000	01:00:04
92-108-162-83.dynamic.upc.nl	0.229	0.039	0.000	00:05:03
TOTAL: 8	32.562	9.604	0.617	124:43:41

Curious NNRP server explorers [Top 20]:

System	Conn
162.212.88.221	4
challenger.nielsenmedia.com	2
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2
h229.146.22.98.dynamic.ip.windstream.net	1
TOTAL: 4	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	24	0
tempe0.bbox.io	12	0
challenger.nielsenmedia.com	2	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
h39.138.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 5	41	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	78	98.7%
2	news	1	1.3%
TOTAL: 2		79	100.0%

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

Newsgroup		Count
1	comp.arch	23
2	comp.os.vms	12
3	comp.sys.ibm.ps2.hardware	10
4	comp.sys.ibm.pc.games.action	8
5	comp.sys.ibm.pc.hardware.storage	6
6	comp.os.os2.apps	6
7	comp.sys.hp.hpux	5
8	comp.os.os2.misc	3
9	comp.os.linux.networking	2
10	news.software.nntp	1
11	comp.os.cpm	1
12	comp.ai.neural-nets	1
13	comp.ai.philosophy	1
TOTAL: 13		79

Daily Usenet report for csiph.com

Jan 16 04:15:00 -- Jan 17 04:15:00