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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	40157	42.1%	8.2 MB	60.3%
nnrpd	33151	34.8%	2.8 MB	20.5%
innd	18731	19.6%	2.1 MB	15.3%
innfeed	3250	3.4%	534.7 KB	3.8%
nocem	79	0.1%	8.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	95369	100.0%	13.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	502115	56.5%
Negative hits	346216	39.0%
Do not exist	39090	4.4%
Cache misses	563	0.1%
TOTAL: 4	887984	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.460	0.0%	245	0.000	1.878	35.000
article cleanup	00:00:00.212	0.0%	38942	0.000	0.005	0.024
article logging	00:00:00.542	0.0%	39482	0.000	0.014	0.144
article parse	00:00:01.993	0.0%	139793	0.002	0.014	0.200
article write	00:01:29.474	0.1%	25075	0.306	3.568	9.599
artlog/artcncl	00:00:00.002	0.0%	189	0.000	0.011	0.125
artlog/artparse	00:00:00.002	0.0%	385	0.000	0.005	0.167
data move	00:00:00.253	0.0%	870834	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.005	0.0%	243	0.000	0.021	1.000
hishave/artcncl	00:00:00.006	0.0%	245	0.000	0.024	6.000
hishave/artparse	00:00:00.000	0.0%	381	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:20.439	0.0%	891708	0.002	0.023	0.129
history sync	00:00:02.494	0.0%	358	0.000	6.966	93.500
history write	00:01:26.722	0.1%	38883	0.058	2.230	13.017
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
hiswrite/artparse	00:00:00.830	0.0%	381	0.000	2.178	20.500
idle	23:53:51.177	99.5%	879537	57.209	97.814	293.221
nntp read	00:00:02.788	0.0%	888968	0.000	0.003	0.006
overview write	00:01:24.403	0.1%	25075	0.752	3.366	63.835
perl filter	00:01:22.372	0.1%	38861	0.667	2.120	4.900
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.440	0.0%	50225	0.000	0.009	0.024
TOTAL: 24:00:55.492	24:00:04.614	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.072	0.0%	25054	0.000	0.003	0.025
article prepare	00:00:00.004	0.0%	27588	0.000	0.000	0.018
article read	00:00:00.470	0.0%	2727	0.000	0.172	4.400
backlog stats	00:00:00.227	0.0%	563504	0.000	0.000	0.001
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.086	0.0%	420727	0.002	0.005	0.013
data write	00:00:02.854	0.0%	401693	0.004	0.007	0.014
idle	23:52:34.984	100.0%	563504	77.449	152.537	452.461
status file	00:00:00.192	0.0%	301	0.000	0.638	1.000
TOTAL: 23:52:49.457	23:52:40.889	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.042	0.0%	1780	0.018	0.024	0.030
idle	18:36:54.558	99.9%	34603	391.292	1936.669	600144.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.460	0.0%	70113	0.000	0.007	0.328
readart	00:00:00.190	0.0%	86	0.000	2.209	28.333
TOTAL: 18:37:58.635	18:36:55.250	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	29597	5697	22574	1326	19%	23:44:59
2	csiph-out.news.weretis.net	9	50981	3483	47207	291	6%	45:20:40
3	csiph-out.feeder.erje.net	1152	94360	3378	89124	1858	3%	94:57:54
4	feeder.eternal-september.org	13	35854	2802	31249	1803	7%	27:44:56
5	news-out.aioe.org	3	24030	2599	21343	88	10%	24:08:38
6	newsfeed.neva.ru	137	48967	1514	43732	3721	3%	72:19:05
7	news-out.mixmin.net	3	29250	1394	26192	1664	4%	24:08:52
8	newsfeed.fu-berlin.de	26	29986	1323	28435	228	4%	30:41:04
9	news.redatomik.org	1	27545	809	26711	25	2%	27:16:11
10	xanadu-bbs.net	1	36970	543	33801	2626	1%	24:01:48
11	news.freedyn.net	3	28062	508	26977	577	1%	24:10:08
12	newsfeeds.xmission.com	1	20647	389	20251	7	1%	19:49:18
13	news.fcku.it	1	7497	348	7149	0	4%	24:23:25
14	news.swapon.de	9	2187	176	2005	6	8%	22:52:22
15	au2pb.net	1	26706	73	26591	42	0%	24:24:16
16	nntp.pasdenom.info	3	24860	42	24807	11	0%	24:08:37
17	localhost	32	0	0	0	0	0%	00:00:01
18	news.bbs.geek.nz	1	25182	0	25182	0	0%	23:59:44
TOTAL: 18		1684	542681	25078	503330	14273	4%	558:11:58

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	16.6 MB	0.0 KB	16.2 MB	32.8 MB	50%	4.8 KB
2	csiph-out.feeder.erje.net	13.8 MB	48.0 KB	6.2 MB	20.0 MB	68%	3.9 KB
3	csiph-out.news.weretis.net	10.7 MB	1.7 KB	4.5 MB	15.2 MB	70%	4.1 KB
4	feeder.eternal-september.org	7.6 MB	91.5 KB	18.8 MB	26.5 MB	28%	5.9 KB
5	news-out.aioe.org	6.0 MB	0.0 KB	214.9 KB	6.2 MB	96%	2.4 KB
6	news-out.mixmin.net	4.5 MB	2.5 KB	3.0 MB	7.6 MB	59%	2.5 KB
7	newsfeed.neva.ru	4.0 MB	205.0 KB	14.1 MB	18.3 MB	21%	3.6 KB
8	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	616.7 KB	3.5 MB	82%	2.3 KB
9	news.freedyn.net	1.8 MB	38.8 KB	2.1 MB	3.9 MB	45%	3.7 KB
10	news.redatomik.org	1.7 MB	0.0 KB	486.5 KB	2.2 MB	78%	2.7 KB
11	xanadu-bbs.net	1.4 MB	3.1 KB	24.9 MB	26.3 MB	5%	8.5 KB
12	newsfeeds.xmission.com	1.2 MB	0.0 KB	20.2 KB	1.3 MB	98%	3.3 KB
13	news.fcku.it	532.7 KB	0.0 KB	0.0 KB	532.7 KB	100%	1.5 KB
14	news.swapon.de	445.1 KB	0.0 KB	10.1 KB	455.2 KB	97%	2.5 KB
15	au2pb.net	218.5 KB	15.1 KB	219.9 KB	453.4 KB	48%	3.9 KB
16	nntp.pasdenom.info	89.2 KB	0.0 KB	24.9 KB	114.1 KB	78%	2.2 KB
17	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
18	news.bbs.geek.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		73.6 MB	405.7 KB	91.3 MB	165.2 MB	44%	4.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 24 04:15:00 - 04:59:59	391	1.6%	0.14	1.5 MB	2.0%	0.57
Jan 24 05:00:00 - 05:59:59	433	1.7%	0.12	1.3 MB	1.7%	0.37
Jan 24 06:00:00 - 06:59:59	432	1.7%	0.12	1.4 MB	1.9%	0.41
Jan 24 07:00:00 - 07:59:59	631	2.5%	0.18	2.4 MB	3.2%	0.67
Jan 24 08:00:00 - 08:59:59	769	3.1%	0.21	2.7 MB	3.7%	0.77
Jan 24 09:00:00 - 09:59:59	1006	4.0%	0.28	3.7 MB	5.0%	1.04
Jan 24 10:00:00 - 10:59:59	1040	4.2%	0.29	2.7 MB	3.7%	0.78
Jan 24 11:00:00 - 11:59:59	1136	4.5%	0.32	3.1 MB	4.2%	0.89
Jan 24 12:00:00 - 12:59:59	1133	4.5%	0.31	3.1 MB	4.2%	0.89
Jan 24 13:00:00 - 13:59:59	1420	5.7%	0.39	4.3 MB	5.9%	1.23
Jan 24 14:00:00 - 14:59:59	1222	4.9%	0.34	3.2 MB	4.4%	0.92
Jan 24 15:00:00 - 15:59:59	1270	5.1%	0.35	3.3 MB	4.4%	0.93
Jan 24 16:00:00 - 16:59:59	1505	6.0%	0.42	4.2 MB	5.7%	1.20
Jan 24 17:00:00 - 17:59:59	1518	6.1%	0.42	4.4 MB	6.0%	1.26
Jan 24 18:00:00 - 18:59:59	1545	6.2%	0.43	4.4 MB	6.0%	1.26
Jan 24 19:00:00 - 19:59:59	1471	5.9%	0.41	4.2 MB	5.6%	1.18
Jan 24 20:00:00 - 20:59:59	1403	5.6%	0.39	3.5 MB	4.8%	1.00
Jan 24 21:00:00 - 21:59:59	1538	6.1%	0.43	4.2 MB	5.7%	1.20
Jan 24 22:00:00 - 22:59:59	1512	6.0%	0.42	4.3 MB	5.8%	1.22
Jan 24 23:00:00 - 23:59:59	954	3.8%	0.27	2.7 MB	3.6%	0.76
Jan 25 00:00:00 - 00:59:59	705	2.8%	0.20	2.2 MB	3.0%	0.62
Jan 25 01:00:00 - 01:59:59	674	2.7%	0.19	2.3 MB	3.2%	0.66
Jan 25 02:00:00 - 02:59:59	629	2.5%	0.17	2.1 MB	2.9%	0.60
Jan 25 03:00:00 - 03:59:59	586	2.3%	0.16	1.8 MB	2.4%	0.51
Jan 25 04:00:00 - 04:15:00	137	0.5%	0.15	640.2 KB	0.8%	0.71
TOTAL: 24:00:00	25060	100.0%	0.29	73.8 MB	100.0%	0.87

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	3739	891	26	0	2822
2	xanadu-bbs.net	2653	13	2	0	2638
3	feeder.eternal-september.org	1869	135	9	0	1725
4	csiph-out.feeder.erje.net	1866	972	12	2	880
5	news-out.mixmin.net	1667	8	1	0	1658
6	usenet.blueworldhosting.com	1339	500	0	0	839
7	newsfeed.fu-berlin.de	630	28	0	0	602
8	news.freedyn.net	581	1	3	0	577
9	csiph-out.news.weretis.net	368	223	1	0	144
10	news-out.aioe.org	114	84	0	0	30
11	news.redatomik.org	26	0	0	0	26
12	newsfeeds.xmission.com	13	10	0	0	3
13	news.swapon.de	12	0	0	0	12
14	au2pb.net	12	8	0	0	4
15	nntp.pasdenom.info	11	10	0	0	1
16	news.fcku.it	10	0	0	0	10
TOTAL: 16		14910	2883	54	2	11971

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	350
ut.test	219
it-alt.sport.calcio.juventus	130
mailing.freebsd.svn-ports-all	113
alt.politics.scorched-earth	110
mailing.freebsd.ports-bugs	107
mailing.freebsd.pkg-fallout	101
mailing.freebsd.svn-ports-head	84
lada.talk	83
pld.cvs.commit	68
mailing.database.pgsql-general	61
mailing.freebsd.svn-src-all	54
fa.freebsd.cvs-all	51
24hoursupport.helpdesk	46
alt.survival	40
alt.binaries.multimedia.tatu	39
fa.freebsd.bugs	39
mailing.freebsd.bugs	39
mozilla.dev.tree-alerts	38
mailing.freebsd.svn-src-head	32
TOTAL: 222	2883

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
nz.politics.announce	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7300
EMP (phn path)	1576
EMP (phl)	881
User-issued cancel	520
PFM bot misc	223
EMP (phn nph)	185
Too many newsgroups	66
Scoring filter (21)	32
Scoring filter (18)	29
HTML Multipart	22
Scoring filter (10)	20
Scoring filter (17)	19
Binary Payload	14
Scoring filter (11)	12
Scoring filter (14)	11
Scoring filter (19)	11
Scoring filter (12)	11
Scoring filter (16)	11
Scoring filter (20)	10
Scoring filter (13)	8
TOTAL: 43	11022

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	74	0	77	77
TOTAL: 1	74	0	77	77

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	13
	usenet.blueworldhosting.com	5
	TOTAL: 2	18
Huge articles
	xanadu-bbs.net	185
	usenet.blueworldhosting.com	103
	csiph-out.news.weretis.net	40
	feeder.eternal-september.org	30
	news-out.mixmin.net	9
	news.freedyn.net	8
	newsfeed.fu-berlin.de	2
	newsfeeds.xmission.com	2
	news.redatomik.org	1
	au2pb.net	1
	TOTAL: 10	381
Including strange strings
	newsfeed.fu-berlin.de	408
	csiph-out.news.weretis.net	34
	news-out.aioe.org	26
	feeder.eternal-september.org	19
	news-out.mixmin.net	12
	csiph-out.feeder.erje.net	10
	news.fcku.it	10
	news.redatomik.org	6
	news.swapon.de	6
	newsfeed.neva.ru	6
	TOTAL: 13	540
No colon-space in header
	usenet.blueworldhosting.com	4
	TOTAL: 1	4
TOTAL: 4		943

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	27025	1445	23091	7	0	5%	23:59:55
2	xanadu-bbs	26592	1181	23331	2	12	4%	23:59:56
3	fu-berlin.de	23483	954	22529	0	0	4%	23:59:55
4	news.bbs.geek.nz	46197	856	23162	11	0	1%	23:59:55
5	aioe	22541	275	19965	19	0	1%	23:59:55
6	usenet.pasdenom.info	32053	197	23280	1539	7	0%	23:59:55
7	au2pb	24629	127	23826	6	0	0%	23:59:56
8	news.redatomik.org	25891	67	23203	3	0	0%	23:59:44
9	xmission	26433	18	23446	0	0	0%	23:59:51
10	neva.ru	26791	14	22218	111	0	0%	23:59:53
11	weretis.net	20508	12	19973	5	0	0%	23:59:51
12	news.mixmin.net	24698	10	22416	0	0	0%	23:59:51
13	news.fcku.it	2611	6	2281	0	0	0%	22:42:18
14	news.swapon.de	2251	4	2091	0	0	0%	23:50:59
15	eternal-september	23771	4	20346	0	0	0%	23:59:55
16	bwh	19272	1	18232	0	36	0%	23:59:56
17	erje	23110	0	21613	36	0	0%	23:59:53
TOTAL: 17		397856	5171	335003	1739	55	1%	406:31:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.6 MB	65.6 KB	9.7 MB	0.1 KB/s	6.8 KB	23:59:55
2	xanadu-bbs	9.0 MB	3.4 KB	9.0 MB	0.1 KB/s	7.8 KB	23:59:56
3	fu-berlin.de	8.2 MB	0.0 KB	8.2 MB	0.1 KB/s	8.8 KB	23:59:55
4	news.bbs.geek.nz	2.3 MB	47.8 KB	2.4 MB	0.0 KB/s	2.8 KB	23:59:55
5	usenet.pasdenom.info	950.8 KB	9.3 MB	10.3 MB	0.1 KB/s	6.0 KB	23:59:55
6	aioe	778.7 KB	103.6 KB	882.2 KB	0.0 KB/s	3.0 KB	23:59:55
7	au2pb	495.8 KB	51.2 KB	547.1 KB	0.0 KB/s	4.1 KB	23:59:56
8	news.redatomik.org	216.6 KB	16.7 KB	233.3 KB	0.0 KB/s	3.3 KB	23:59:44
9	neva.ru	52.4 KB	441.7 KB	494.2 KB	0.0 KB/s	4.0 KB	23:59:53
10	news.mixmin.net	45.0 KB	0.0 KB	45.0 KB	0.0 KB/s	4.5 KB	23:59:51
11	xmission	40.3 KB	0.0 KB	40.3 KB	0.0 KB/s	2.2 KB	23:59:51
12	weretis.net	22.5 KB	24.0 KB	46.5 KB	0.0 KB/s	2.7 KB	23:59:51
13	news.fcku.it	18.7 KB	0.0 KB	18.7 KB	0.0 KB/s	3.1 KB	22:42:18
14	news.swapon.de	12.5 KB	0.0 KB	12.5 KB	0.0 KB/s	3.1 KB	23:50:59
15	eternal-september	12.2 KB	0.0 KB	12.2 KB	0.0 KB/s	3.1 KB	23:59:55
16	bwh	2.3 KB	0.0 KB	2.3 KB	0.0 KB/s	2.3 KB	23:59:56
17	erje	0.0 KB	172.9 KB	172.9 KB	0.0 KB/s	4.8 KB	23:59:53
TOTAL: 17		31.7 MB	10.2 MB	41.9 MB	0.0 KB/s	6.2 KB	406:31:38

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	60	120.0 KB	56	04:22:39
2	challenger.nielsenmedia.com	5	9	24.8 KB	3	01:31:00
3	pool-71-98-160-84.tampfl.dsl-w.verizon.net	8	9	24.8 KB	3	02:41:41
4	38.98.46.140	1	8	11.4 KB	2	00:01:31
TOTAL: 4		302	86	181.0 KB	64	18:37:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	293	68	131.4 KB	58	10:34:39
2	*.nielsenmedia.com	5	9	24.8 KB	3	01:31:00
3	*.tampfl.dsl-w.verizon.net	8	9	24.8 KB	3	02:41:41
4	*.bbox.io	2	0	0.0 KB	0	01:00:01
5	*.chello.nl	7	0	0.0 KB	0	02:50:31
TOTAL: 5		315	86	181.0 KB	64	18:37:55

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
162.212.88.221	0.395	0.152	0.000	06:10:29
202.89.176.220	28.184	6.582	0.736	04:22:39
dhcp-089-099-203-254.chello.nl	0.557	0.147	0.000	02:50:31
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.947	0.185	0.000	02:41:41
challenger.nielsenmedia.com	0.581	0.065	0.000	01:31:00
tempe0.bbox.io	0.227	0.031	0.000	01:00:01
38.98.46.140	0.148	0.031	0.000	00:01:31
TOTAL: 7	31.039	7.193	0.736	18:37:55

Curious NNRP server explorers [Top 20]:

System	Conn
dhcp-089-099-203-254.chello.nl	7
162.212.88.221	4
tempe0.bbox.io	2
TOTAL: 3	13

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	79	91.9%
2	news	7	8.1%
TOTAL: 2		86	100.0%

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

Newsgroup		Count
1	comp.lang.awk	18
2	comp.arch	16
3	comp.ai.philosophy	15
4	comp.os.vms	13
5	news.admin.peering	7
6	comp.sys.hp.hpux	4
7	comp.sys.ibm.pc.games.action	3
8	comp.sys.ibm.as400.misc	2
9	comp.sys.ibm.pc.hardware.storage	2
10	comp.sys.ibm.pc.games.war-historical	2
11	comp.arch.embedded	2
12	comp.sys.ibm.pc.games.rpg	1
13	comp.windows.x.kde	1
TOTAL: 13		86

Daily Usenet report for csiph.com

Jan 24 04:15:00 -- Jan 25 04:15:00