Daily Usenet report for csiph.com: Jul 1 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	43398	38.9%	3.8 MB	25.1%
inn	42921	38.5%	8.4 MB	55.7%
innd	21889	19.6%	2.4 MB	15.6%
innfeed	3148	2.8%	546.4 KB	3.5%
nocem	70	0.1%	6.5 KB	0.0%
TOTAL: 5	111426	100.0%	15.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	511762	58.0%
Negative hits	328139	37.2%
Do not exist	41944	4.8%
Cache misses	664	0.1%
TOTAL: 4	882509	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.182	0.0%	237	0.000	0.768	18.000
article cleanup	00:00:00.276	0.0%	41875	0.000	0.007	0.029
article logging	00:00:00.585	0.0%	42467	0.000	0.014	0.143
article parse	00:00:01.548	0.0%	144485	0.004	0.011	0.028
article write	00:00:49.176	0.1%	24511	0.147	2.006	30.486
artlog/artcncl	00:00:00.001	0.0%	217	0.000	0.005	0.071
artlog/artparse	00:00:00.006	0.0%	307	0.000	0.020	1.000
data move	00:00:00.285	0.0%	842402	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.015	0.0%	232	0.000	0.065	8.000
hishave/artcncl	00:00:00.001	0.0%	237	0.000	0.004	0.125
hishave/artparse	00:00:00.001	0.0%	306	0.000	0.003	0.091
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.761	0.0%	886188	0.002	0.004	0.127
history sync	00:00:01.230	0.0%	346	0.000	3.555	55.333
history write	00:00:19.913	0.0%	41865	0.080	0.476	7.186
hiswrite/artcncl	00:00:00.005	0.0%	5	0.000	1.000	2.500
hiswrite/artparse	00:00:00.127	0.0%	306	0.000	0.415	4.000
idle	23:55:20.109	99.6%	860999	59.866	100.023	282.899
nntp read	00:00:02.959	0.0%	866188	0.002	0.003	0.006
overview write	00:01:17.114	0.1%	24511	0.198	3.146	93.970
perl filter	00:01:36.735	0.1%	41747	1.536	2.317	3.571
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.423	0.0%	49085	0.000	0.009	0.031
TOTAL: 24:00:48.453	23:59:34.452	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.078	0.0%	24823	0.000	0.003	0.035
article prepare	00:00:00.004	0.0%	21986	0.000	0.000	0.015
article read	00:00:00.758	0.0%	2679	0.000	0.283	9.000
backlog stats	00:00:00.283	0.0%	567823	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.180	0.0%	418629	0.001	0.005	0.009
data write	00:00:02.735	0.0%	402449	0.004	0.007	0.013
idle	23:53:04.352	100.0%	567823	83.397	151.428	368.701
status file	00:00:00.187	0.0%	254	0.000	0.736	12.000
TOTAL: 23:53:25.103	23:53:10.577	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.273	0.0%	6231	0.014	0.044	0.111
idle	54:31:47.760	98.4%	62513	79.624	3140.271	600140.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.794	0.0%	126069	0.000	0.006	0.167
readart	00:00:14.777	0.0%	11037	0.000	1.339	72.000
TOTAL: 55:24:42.524	54:32:03.604	98.4%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	28838	5794	21185	1859	20%	23:44:53
2	feeder.eternal-september.org	1	36484	3677	31947	860	10%	23:59:55
3	csiph-out.feeder.erje.net	1152	135179	3609	123120	8450	2%	95:07:34
4	csiph-out.news.weretis.net	4	59178	3481	53293	2404	5%	45:00:28
5	news-out.aioe.org	3	24773	1730	23018	25	6%	24:09:44
6	news-out.mixmin.net	3	27046	1663	25037	346	6%	24:08:50
7	newsfeed.neva.ru	137	52861	1575	48019	3267	2%	68:00:31
8	newsfeed.fu-berlin.de	48	25526	1135	24065	326	4%	30:49:33
9	newsfeeds.xmission.com	1	25177	510	24650	17	2%	24:17:22
10	news.freedyn.net	5	26727	502	26200	25	1%	24:09:53
11	news.fcku.it	1	9009	322	8648	39	3%	21:34:16
12	au2pb.net	1	26622	131	26460	31	0%	24:12:48
13	news.redatomik.org	1	21675	126	21547	2	0%	22:26:07
14	news.swapon.de	1	2105	61	2043	1	2%	24:41:03
15	xanadu-bbs.net	13	657	28	622	7	4%	17:40:42
16	nntp.pasdenom.info	2	25828	22	25801	5	0%	23:58:52
17	localhost	48	14	14	0	0	100%	00:00:00
18	news.bbs.geek.nz	2	24945	7	24937	1	0%	23:59:49
TOTAL: 18		1711	552644	24387	510592	17665	4%	542:02:20

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	16.2 MB	0.0 KB	21.3 MB	37.4 MB	43%	5.0 KB
2	csiph-out.feeder.erje.net	13.3 MB	8.2 KB	26.2 MB	39.5 MB	33%	3.4 KB
3	feeder.eternal-september.org	9.6 MB	0.0 KB	12.1 MB	21.7 MB	44%	4.9 KB
4	csiph-out.news.weretis.net	9.3 MB	0.0 KB	30.2 MB	39.5 MB	23%	6.9 KB
5	news-out.mixmin.net	7.5 MB	0.0 KB	1.2 MB	8.8 MB	85%	4.5 KB
6	newsfeed.neva.ru	4.7 MB	18.0 KB	22.2 MB	27.0 MB	17%	5.7 KB
7	news-out.aioe.org	4.2 MB	0.0 KB	30.5 KB	4.2 MB	99%	2.4 KB
8	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	686.6 KB	3.0 MB	77%	2.1 KB
9	news.freedyn.net	1.9 MB	0.0 KB	267.9 KB	2.2 MB	88%	4.3 KB
10	newsfeeds.xmission.com	1.4 MB	0.0 KB	775.2 KB	2.2 MB	65%	4.2 KB
11	news.fcku.it	716.9 KB	144.4 KB	1.7 MB	2.6 MB	27%	7.2 KB
12	au2pb.net	438.7 KB	0.0 KB	151.9 KB	590.6 KB	74%	3.6 KB
13	news.redatomik.org	274.9 KB	0.0 KB	22.8 KB	297.7 KB	92%	2.3 KB
14	news.swapon.de	166.7 KB	0.0 KB	9.7 KB	176.4 KB	94%	2.8 KB
15	xanadu-bbs.net	65.6 KB	0.0 KB	17.3 KB	83.0 KB	79%	2.4 KB
16	nntp.pasdenom.info	41.4 KB	0.0 KB	9.1 KB	50.5 KB	82%	1.9 KB
17	localhost	14.7 KB	0.0 KB	0.0 KB	14.7 KB	100%	1.1 KB
18	news.bbs.geek.nz	9.6 KB	0.0 KB	1.1 KB	10.7 KB	89%	1.3 KB
TOTAL: 18		72.1 MB	170.5 KB	116.8 MB	189.1 MB	38%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 1 04:15:00 - 04:59:59	480	2.0%	0.18	2.4 MB	3.4%	0.93
Jul 1 05:00:00 - 05:59:59	528	2.2%	0.15	1.8 MB	2.4%	0.50
Jul 1 06:00:00 - 06:59:59	743	3.0%	0.21	2.1 MB	2.9%	0.59
Jul 1 07:00:00 - 07:59:59	992	4.1%	0.28	3.0 MB	4.1%	0.85
Jul 1 08:00:00 - 08:59:59	1181	4.8%	0.33	3.0 MB	4.1%	0.85
Jul 1 09:00:00 - 09:59:59	1243	5.1%	0.35	3.3 MB	4.5%	0.93
Jul 1 10:00:00 - 10:59:59	1239	5.1%	0.34	3.3 MB	4.6%	0.94
Jul 1 11:00:00 - 11:59:59	1159	4.7%	0.32	3.0 MB	4.1%	0.85
Jul 1 12:00:00 - 12:59:59	1315	5.4%	0.37	3.4 MB	4.6%	0.96
Jul 1 13:00:00 - 13:59:59	1370	5.6%	0.38	4.3 MB	6.0%	1.24
Jul 1 14:00:00 - 14:59:59	1550	6.3%	0.43	4.8 MB	6.6%	1.36
Jul 1 15:00:00 - 15:59:59	1634	6.7%	0.45	5.3 MB	7.4%	1.52
Jul 1 16:00:00 - 16:59:59	1294	5.3%	0.36	3.3 MB	4.6%	0.95
Jul 1 17:00:00 - 17:59:59	1199	4.9%	0.33	3.4 MB	4.7%	0.96
Jul 1 18:00:00 - 18:59:59	1242	5.1%	0.34	3.6 MB	5.0%	1.03
Jul 1 19:00:00 - 19:59:59	1172	4.8%	0.33	3.9 MB	5.4%	1.11
Jul 1 20:00:00 - 20:59:59	1105	4.5%	0.31	3.4 MB	4.7%	0.97
Jul 1 21:00:00 - 21:59:59	1082	4.4%	0.30	3.1 MB	4.2%	0.87
Jul 1 22:00:00 - 22:59:59	862	3.5%	0.24	2.5 MB	3.5%	0.72
Jul 1 23:00:00 - 23:59:59	737	3.0%	0.20	2.0 MB	2.8%	0.58
Jul 2 00:00:00 - 00:59:59	668	2.7%	0.19	2.1 MB	2.9%	0.60
Jul 2 01:00:00 - 01:59:59	592	2.4%	0.16	1.8 MB	2.5%	0.51
Jul 2 02:00:00 - 02:59:59	501	2.0%	0.14	1.5 MB	2.0%	0.42
Jul 2 03:00:00 - 03:59:59	472	1.9%	0.13	1.8 MB	2.5%	0.52
Jul 2 04:00:00 - 04:15:00	120	0.5%	0.13	411.0 KB	0.6%	0.46
TOTAL: 24:00:00	24480	100.0%	0.28	72.5 MB	100.0%	0.86

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	8463	1006	2	0	7455
2	newsfeed.neva.ru	3277	1019	5	0	2253
3	csiph-out.news.weretis.net	2441	293	0	0	2148
4	usenet.blueworldhosting.com	1858	557	0	2	1299
5	feeder.eternal-september.org	880	97	0	0	783
6	newsfeed.fu-berlin.de	775	24	0	0	751
7	news-out.mixmin.net	362	1	0	0	361
8	news.fcku.it	43	0	2	0	41
9	news-out.aioe.org	36	22	0	0	14
10	news.freedyn.net	26	2	0	0	24
11	au2pb.net	21	15	2	0	4
12	newsfeeds.xmission.com	20	3	0	0	17
13	xanadu-bbs.net	8	0	0	0	8
14	nntp.pasdenom.info	5	0	0	0	5
15	news.swapon.de	5	0	0	0	5
16	news.redatomik.org	5	0	0	0	5
TOTAL: 16		18225	3039	11	2	15173

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	721
lada.talk	191
mailing.freebsd.pkg-fallout	134
ut.test	111
lada.auto	92
mailing.freebsd.ports-bugs	84
it-alt.sport.calcio.juventus	80
mailing.freebsd.bugs	52
fa.freebsd.bugs	52
mailing.freebsd.svn-ports-all	49
mailing.freebsd.svn-ports-head	47
mailing.gcc.gcc-cvs	38
mailing.openssl.cvs	38
mozilla.support.thunderbird	34
mailing.freebsd.fs	33
gmane.linux.alsa.devel	33
alt.windows7.general	31
mailing.freebsd.questions	30
mailing.freebsd.ports	26
mailing.database.pgsql-general	25
TOTAL: 345	3039

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

Groups	Count
it.scienza.astronomia	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	10527
EMP (phn path)	1635
EMP (phl)	686
PFM bot misc	673
EMP (phn nph)	123
Scoring filter (21)	67
Too many newsgroups	49
Scoring filter (10)	37
Scoring filter (18)	34
Binary Payload	25
Scoring filter (19)	25
Scoring filter (17)	24
Scoring filter (22)	23
User-issued spam cancel	23
HTML Multipart	20
Scoring filter (14)	19
Scoring filter (15)	18
Scoring filter (16)	18
Scoring filter (11)	18
Scoring filter (9)	18
TOTAL: 33	14159

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	63	0	68	68
TOTAL: 1	63	0	68	68

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	115
	usenet.blueworldhosting.com	2
	TOTAL: 2	117
Bad Message-ID's offered
	csiph-out.feeder.erje.net	4
	usenet.blueworldhosting.com	1
	TOTAL: 2	5
Huge articles
	usenet.blueworldhosting.com	112
	csiph-out.news.weretis.net	91
	feeder.eternal-september.org	57
	news.fcku.it	14
	news-out.mixmin.net	13
	newsfeeds.xmission.com	13
	news.redatomik.org	4
	news.freedyn.net	1
	TOTAL: 8	305
Including strange strings
	newsfeed.fu-berlin.de	453
	feeder.eternal-september.org	36
	csiph-out.news.weretis.net	35
	csiph-out.feeder.erje.net	25
	news-out.mixmin.net	15
	news-out.aioe.org	11
	newsfeed.neva.ru	9
	news.swapon.de	4
	usenet.blueworldhosting.com	2
	newsfeeds.xmission.com	1
	TOTAL: 10	591
TOTAL: 4		1018

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	45619	1351	22507	36	1	0	2%	23:59:50
2	news.freedyn.net	27868	862	23104	11	0	0	3%	23:59:50
3	xanadu-bbs	26877	682	23757	9	0	33	2%	23:59:50
4	fu-berlin.de	23067	568	22498	0	0	0	2%	23:59:50
5	aioe	23452	235	20712	11	0	0	1%	23:59:41
6	usenet.pasdenom.info	31960	154	23433	870	0	27	0%	23:59:50
7	news.redatomik.org	25037	139	23464	9	0	0	0%	23:59:41
8	au2pb	25533	118	23695	3	0	0	0%	23:59:44
9	weretis.net	21110	118	19870	17	0	0	0%	23:59:49
10	xmission	27564	68	23280	0	0	1	0%	23:59:42
11	news.mixmin.net	24260	43	21893	0	0	0	0%	23:59:50
12	eternal-september	23424	29	19864	0	0	0	0%	23:59:42
13	bwh	19485	15	17861	0	0	0	0%	23:59:46
14	neva.ru	26288	13	21931	78	0	328	0%	23:59:50
15	news.swapon.de	2236	10	2048	5	0	0	0%	23:59:13
16	news.fcku.it	3531	6	2887	0	0	0	0%	22:18:00
17	erje	23809	5	20730	49	0	0	0%	23:59:50
TOTAL: 17		401120	4416	333534	1098	1	389	1%	406:13:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.5 MB	69.2 KB	6.5 MB	0.1 KB/s	7.7 KB	23:59:50
2	xanadu-bbs	5.8 MB	24.4 KB	5.8 MB	0.1 KB/s	8.7 KB	23:59:50
3	fu-berlin.de	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	10.0 KB	23:59:50
4	news.bbs.geek.nz	3.6 MB	126.1 KB	3.7 MB	0.0 KB/s	2.7 KB	23:59:50
5	aioe	520.0 KB	33.9 KB	553.9 KB	0.0 KB/s	2.3 KB	23:59:41
6	usenet.pasdenom.info	404.4 KB	6.3 MB	6.7 MB	0.1 KB/s	6.7 KB	23:59:50
7	weretis.net	341.4 KB	97.7 KB	439.1 KB	0.0 KB/s	3.3 KB	23:59:49
8	news.redatomik.org	335.0 KB	27.0 KB	362.0 KB	0.0 KB/s	2.4 KB	23:59:41
9	au2pb	264.1 KB	25.9 KB	290.0 KB	0.0 KB/s	2.4 KB	23:59:44
10	xmission	157.5 KB	0.0 KB	157.5 KB	0.0 KB/s	2.3 KB	23:59:42
11	news.mixmin.net	97.0 KB	0.0 KB	97.0 KB	0.0 KB/s	2.3 KB	23:59:50
12	eternal-september	42.5 KB	0.0 KB	42.5 KB	0.0 KB/s	1.5 KB	23:59:42
13	neva.ru	16.4 KB	296.6 KB	313.0 KB	0.0 KB/s	3.4 KB	23:59:50
14	bwh	16.0 KB	0.0 KB	16.0 KB	0.0 KB/s	1.1 KB	23:59:46
15	news.fcku.it	13.9 KB	0.0 KB	13.9 KB	0.0 KB/s	2.3 KB	22:18:00
16	news.swapon.de	10.4 KB	6.9 KB	17.2 KB	0.0 KB/s	1.1 KB	23:59:13
17	erje	10.0 KB	213.1 KB	223.1 KB	0.0 KB/s	4.1 KB	23:59:50
TOTAL: 17		23.6 MB	7.2 MB	30.8 MB	0.0 KB/s	5.7 KB	406:13:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	29	44.8 KB	29	0	0	05:25:47
2	dhcp-089-099-203-254.chello.nl	41	17	23.6 KB	6	0	0	11:00:05
3	68-185-181-64.dhcp.mdsn.wi.charter.com	42	16	18.8 KB	15	14	14	08:59:19
4	mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	115	8	23.5 KB	8	0	0	00:38:58
5	92-108-162-83.dynamic.upc.nl	3	3	3.4 KB	2	0	0	01:01:33
6	challenger.nielsenmedia.com	10	2	2.8 KB	2	0	0	03:17:35
TOTAL: 6		499	75	116.9 KB	62	14	14	55:24:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	309	29	44.8 KB	29	0	0	27:51:46
2	*.chello.nl	41	17	23.6 KB	6	0	0	11:00:05
3	*.dhcp.mdsn.wi.charter.com	42	16	18.8 KB	15	14	14	08:59:19
4	*.dsl.bell.ca	115	8	23.5 KB	8	0	0	00:38:58
5	*.dynamic.upc.nl	3	3	3.4 KB	2	0	0	01:01:33
6	*.nielsenmedia.com	10	2	2.8 KB	2	0	0	03:17:35
7	*.bbox.io	8	0	0.0 KB	0	0	0	02:35:17
TOTAL: 7		528	75	116.9 KB	62	14	14	55:24:37

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	14
TOTAL: 2	302

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.246.166.30	0.990	0.533	0.000	20:11:39
dhcp-089-099-203-254.chello.nl	3.976	0.874	0.000	11:00:05
68-185-181-64.dhcp.mdsn.wi.charter.com	4.500	0.992	0.038	08:59:19
202.89.176.220	29.511	7.748	0.807	05:25:47
challenger.nielsenmedia.com	0.893	0.184	0.000	03:17:35
tempe0.bbox.io	0.845	0.187	0.000	02:35:17
71.98.160.36	0.378	0.093	0.000	02:14:18
92-108-162-83.dynamic.upc.nl	0.418	0.048	0.000	01:01:33
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	14.097	3.782	0.000	00:38:58
TOTAL: 9	55.608	14.441	0.845	55:24:37

Curious NNRP server explorers [Top 20]:

System	Conn
216.246.166.30	16
tempe0.bbox.io	8
71.98.160.36	5
TOTAL: 3	29

NNRP client timeouts [Top 20]:

System	Conn	Peer
68-185-181-64.dhcp.mdsn.wi.charter.com	7	0
challenger.nielsenmedia.com	5	0
tempe0.bbox.io	5	0
71.98.160.36	4	0
92-108-162-83.dynamic.upc.nl	2	0
TOTAL: 5	23	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	67	89.3%
2	rec	3	4.0%
3	sci	3	4.0%
4	uk	2	2.7%
TOTAL: 4		75	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	50
2	comp.os.cpm	6
3	comp.ai.fuzzy	3
4	uk.legal	2
5	comp.lang.awk	2
6	sci.physics.relativity	2
7	rec.food.cooking	2
8	comp.os.vms	2
9	rec.arts.tv	1
10	comp.ai.neural-nets	1
11	sci.physics	1
12	comp.os.msdos.programmer	1
13	comp.ai.philosophy	1
14	comp.ai.nat-lang	1
TOTAL: 14		75

Daily Usenet report for csiph.com

Jul 1 04:15:00 -- Jul 2 04:15:00