Daily Usenet report for csiph.com: Jun 10 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	46126	39.1%	8.7 MB	55.5%
nnrpd	44484	37.7%	3.9 MB	24.9%
innd	24171	20.5%	2.5 MB	16.0%
innfeed	3101	2.6%	552.7 KB	3.4%
nocem	109	0.1%	10.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	117992	100.0%	15.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	527167	59.1%
Negative hits	319355	35.8%
Do not exist	45125	5.1%
Cache misses	520	0.1%
TOTAL: 4	892167	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.046	0.0%	297	0.000	3.522	43.125
article cleanup	00:00:00.265	0.0%	44589	0.000	0.006	0.048
article logging	00:00:00.638	0.0%	45107	0.000	0.014	0.099
article parse	00:00:01.629	0.0%	160831	0.000	0.010	0.023
article write	00:04:53.451	0.3%	25006	0.301	11.735	45.989
artlog/artcncl	00:00:00.002	0.0%	236	0.000	0.008	0.500
artlog/artparse	00:00:00.013	0.0%	799	0.000	0.016	1.000
data move	00:00:00.308	0.0%	843033	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.053	0.0%	291	0.000	0.182	3.000
hishave/artcncl	00:00:00.003	0.0%	297	0.000	0.010	0.125
hishave/artparse	00:00:00.001	0.0%	206	0.000	0.005	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.903	0.0%	896792	0.001	0.005	0.095
history sync	00:00:01.423	0.0%	355	0.000	4.008	69.667
history write	00:00:29.164	0.0%	45170	0.100	0.646	10.417
hiswrite/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hiswrite/artparse	00:00:00.111	0.0%	204	0.000	0.544	8.000
idle	23:51:20.388	99.3%	868052	68.165	98.935	373.984
nntp read	00:00:02.862	0.0%	881999	0.001	0.003	0.008
overview write	00:01:18.308	0.1%	25006	0.325	3.132	47.682
perl filter	00:01:48.358	0.1%	44435	1.265	2.439	4.048
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.419	0.0%	50114	0.000	0.008	0.030
TOTAL: 24:01:04.999	24:00:03.345	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.067	0.0%	25009	0.000	0.003	0.018
article prepare	00:00:00.005	0.0%	29356	0.000	0.000	0.013
article read	00:00:02.565	0.0%	3912	0.000	0.656	17.625
backlog stats	00:00:00.279	0.0%	603917	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.175	0.0%	434477	0.000	0.005	0.011
data write	00:00:02.884	0.0%	417753	0.002	0.007	0.013
idle	23:53:05.236	100.0%	603917	90.425	142.379	490.221
status file	00:00:00.350	0.0%	234	0.000	1.496	3.500
TOTAL: 23:53:21.997	23:53:13.561	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:01.273	0.0%	8090	0.106	0.157	0.226
idle	70:36:47.886	99.4%	115057	83.022	2209.408	600219.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.622	0.0%	231425	0.000	0.007	0.997
readart	00:01:38.830	0.0%	24901	0.000	3.969	66.000
TOTAL: 71:01:20.331	70:38:29.611	99.5%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	29182	6623	20820	1739	22%	23:44:47
2	csiph-out.feeder.erje.net	1151	141875	4591	127006	10278	3%	95:02:54
3	feeder.eternal-september.org	2	37635	3530	33191	914	9%	24:13:35
4	csiph-out.news.weretis.net	3	64581	2196	61696	689	3%	54:05:52
5	news-out.mixmin.net	2	28384	1837	26189	358	6%	24:08:55
6	news-out.aioe.org	4	24179	1698	22442	39	7%	24:09:39
7	newsfeed.neva.ru	148	64084	1400	56846	5838	2%	72:11:22
8	newsfeed.fu-berlin.de	63	28659	1254	26980	425	4%	35:47:34
9	newsfeeds.xmission.com	1	26336	740	25582	14	2%	25:41:05
10	news.freedyn.net	3	27695	562	27043	90	2%	24:08:39
11	news.fcku.it	1	10075	363	9677	35	3%	23:21:46
12	au2pb.net	1	26849	143	26679	27	0%	24:12:45
13	news.redatomik.org	1	21866	86	21778	2	0%	22:26:07
14	news.swapon.de	2	2610	78	2530	2	2%	31:42:31
15	news.bbs.geek.nz	1	24976	34	24941	1	0%	23:59:42
16	xanadu-bbs.net	11	724	28	676	20	3%	22:54:13
17	nntp.pasdenom.info	3	26167	17	26149	1	0%	24:08:26
18	localhost	38	4	4	0	0	100%	00:00:00
TOTAL: 18		1723	585881	25184	540225	20472	4%	575:59:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	20.6 MB	0.0 KB	21.3 MB	41.8 MB	49%	5.1 KB
2	csiph-out.feeder.erje.net	16.8 MB	47.5 KB	41.3 MB	58.2 MB	28%	4.0 KB
3	feeder.eternal-september.org	9.1 MB	37.0 KB	14.7 MB	23.8 MB	38%	5.5 KB
4	news-out.mixmin.net	7.2 MB	0.0 KB	1.1 MB	8.4 MB	86%	3.9 KB
5	csiph-out.news.weretis.net	6.0 MB	0.0 KB	8.5 MB	14.5 MB	41%	5.2 KB
6	news-out.aioe.org	3.8 MB	0.0 KB	69.3 KB	3.8 MB	98%	2.3 KB
7	newsfeed.neva.ru	3.3 MB	35.3 KB	49.8 MB	53.2 MB	6%	7.5 KB
8	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	971.5 KB	3.5 MB	72%	2.1 KB
9	news.freedyn.net	2.0 MB	0.0 KB	153.3 KB	2.2 MB	93%	3.4 KB
10	newsfeeds.xmission.com	1.8 MB	0.0 KB	509.8 KB	2.3 MB	78%	3.2 KB
11	news.fcku.it	690.6 KB	184.2 KB	696.5 KB	1.5 MB	43%	3.9 KB
12	au2pb.net	356.8 KB	0.0 KB	955.2 KB	1.3 MB	27%	7.7 KB
13	news.redatomik.org	193.1 KB	0.0 KB	55.5 KB	248.6 KB	77%	2.8 KB
14	news.swapon.de	174.3 KB	0.0 KB	90.7 KB	265.0 KB	65%	3.3 KB
15	xanadu-bbs.net	88.7 KB	0.0 KB	125.0 KB	213.6 KB	41%	4.5 KB
16	news.bbs.geek.nz	46.5 KB	1.7 KB	1.7 KB	49.9 KB	93%	1.4 KB
17	nntp.pasdenom.info	30.0 KB	0.0 KB	1.5 KB	31.5 KB	95%	1.8 KB
18	localhost	4.1 KB	0.0 KB	0.0 KB	4.1 KB	100%	1.0 KB
TOTAL: 18		74.8 MB	305.7 KB	140.3 MB	215.4 MB	34%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 10 04:15:00 - 04:59:59	453	1.8%	0.17	1.9 MB	2.5%	0.71
Jun 10 05:00:00 - 05:59:59	815	3.3%	0.23	3.0 MB	4.1%	0.86
Jun 10 06:00:00 - 06:59:59	910	3.6%	0.25	2.5 MB	3.4%	0.71
Jun 10 07:00:00 - 07:59:59	1118	4.5%	0.31	3.0 MB	4.0%	0.85
Jun 10 08:00:00 - 08:59:59	1134	4.5%	0.32	2.8 MB	3.7%	0.78
Jun 10 09:00:00 - 09:59:59	1189	4.8%	0.33	3.2 MB	4.3%	0.90
Jun 10 10:00:00 - 10:59:59	1154	4.6%	0.32	3.3 MB	4.4%	0.93
Jun 10 11:00:00 - 11:59:59	1092	4.4%	0.30	3.1 MB	4.2%	0.88
Jun 10 12:00:00 - 12:59:59	1160	4.6%	0.32	3.1 MB	4.2%	0.89
Jun 10 13:00:00 - 13:59:59	1311	5.2%	0.36	3.8 MB	5.1%	1.08
Jun 10 14:00:00 - 14:59:59	1420	5.7%	0.39	4.3 MB	5.8%	1.22
Jun 10 15:00:00 - 15:59:59	1280	5.1%	0.36	4.0 MB	5.4%	1.13
Jun 10 16:00:00 - 16:59:59	1284	5.1%	0.36	3.8 MB	5.2%	1.09
Jun 10 17:00:00 - 17:59:59	1419	5.7%	0.39	4.1 MB	5.5%	1.16
Jun 10 18:00:00 - 18:59:59	1402	5.6%	0.39	4.4 MB	5.9%	1.25
Jun 10 19:00:00 - 19:59:59	1297	5.2%	0.36	4.0 MB	5.4%	1.13
Jun 10 20:00:00 - 20:59:59	1148	4.6%	0.32	3.5 MB	4.7%	0.99
Jun 10 21:00:00 - 21:59:59	1210	4.8%	0.34	3.4 MB	4.6%	0.97
Jun 10 22:00:00 - 22:59:59	1073	4.3%	0.30	3.4 MB	4.6%	0.97
Jun 10 23:00:00 - 23:59:59	843	3.4%	0.23	2.4 MB	3.2%	0.68
Jun 11 00:00:00 - 00:59:59	676	2.7%	0.19	1.9 MB	2.5%	0.53
Jun 11 01:00:00 - 01:59:59	541	2.2%	0.15	1.9 MB	2.6%	0.55
Jun 11 02:00:00 - 02:59:59	533	2.1%	0.15	1.8 MB	2.4%	0.51
Jun 11 03:00:00 - 03:59:59	408	1.6%	0.11	1.4 MB	1.9%	0.41
Jun 11 04:00:00 - 04:15:00	121	0.5%	0.13	351.6 KB	0.5%	0.39
TOTAL: 24:00:00	24991	100.0%	0.29	74.2 MB	100.0%	0.88

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	10329	1062	4	0	9263
2	newsfeed.neva.ru	5858	976	2	0	4880
3	usenet.blueworldhosting.com	1747	465	0	1	1281
4	feeder.eternal-september.org	936	113	1	0	822
5	newsfeed.fu-berlin.de	753	35	0	0	718
6	csiph-out.news.weretis.net	631	202	0	0	429
7	news-out.mixmin.net	390	4	0	0	386
8	news.freedyn.net	89	1	0	0	88
9	news.fcku.it	53	0	5	0	48
10	news-out.aioe.org	44	38	0	0	6
11	au2pb.net	25	19	0	0	6
12	xanadu-bbs.net	20	0	0	0	20
13	newsfeeds.xmission.com	13	9	0	0	4
14	news.swapon.de	9	0	0	0	9
15	news.redatomik.org	4	0	0	0	4
16	nntp.pasdenom.info	1	0	0	0	1
TOTAL: 16		20902	2924	12	1	17965

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	736
lada.talk	189
mailing.freebsd.pkg-fallout	125
it-alt.sport.calcio.juventus	88
lada.auto	86
mailing.freebsd.ports-bugs	68
ut.test	58
mailing.freebsd.svn-src-all	58
alt.windows7.general	55
mailing.freebsd.svn-ports-all	54
mailing.freebsd.svn-ports-head	54
fa.freebsd.cvs-all	47
alt.philosophy	38
spline.eisfair	38
mailing.freebsd.svn-src-stable	38
mailing.gcc.gcc-cvs	37
mpc.lists.nanog	36
mailing.database.pgsql-general	32
mailing.openssl.cvs	31
mailing.freebsd.questions	28
TOTAL: 250	2924

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11635
EMP (phn path)	2028
PFM bot misc	1427
EMP (phl)	831
EMP (phn nph)	123
Too many newsgroups	101
Binary Payload	58
HTML Multipart	57
Scoring filter (18)	28
Scoring filter (12)	22
Scoring filter (9)	22
Scoring filter (10)	20
Scoring filter (16)	18
Scoring filter (8)	17
Scoring filter (14)	16
Scoring filter (15)	14
User-issued cancel	11
Scoring filter (11)	11
Scoring filter (19)	10
Scoring filter (17)	10
TOTAL: 36	16507

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	101	0	104	104
TOTAL: 1	101	0	104	104

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	140
	usenet.blueworldhosting.com	2
	TOTAL: 2	142
Huge articles
	usenet.blueworldhosting.com	114
	feeder.eternal-september.org	31
	csiph-out.news.weretis.net	29
	news.fcku.it	17
	news-out.mixmin.net	8
	newsfeeds.xmission.com	4
	news.redatomik.org	2
	news.swapon.de	1
	TOTAL: 8	206
Including strange strings
	newsfeed.fu-berlin.de	380
	csiph-out.feeder.erje.net	48
	feeder.eternal-september.org	34
	news-out.mixmin.net	19
	csiph-out.news.weretis.net	16
	news.swapon.de	7
	news-out.aioe.org	5
	news.fcku.it	4
	newsfeed.neva.ru	3
	news.redatomik.org	1
	TOTAL: 10	517
No colon-space in header
	csiph-out.feeder.erje.net	590
	TOTAL: 1	590
TOTAL: 4		1455

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	45459	2280	21791	42	58	5%	23:59:56
2	news.freedyn.net	27986	1078	23339	9	1	3%	23:59:56
3	xanadu-bbs	28651	935	24014	0	9	3%	23:59:56
4	fu-berlin.de	23497	707	22790	0	0	3%	23:59:56
5	weretis.net	23008	162	20996	34	0	0%	23:59:45
6	aioe	24525	148	20735	19	1	0%	23:59:56
7	xmission	28455	145	23388	1	0	0%	23:59:56
8	usenet.pasdenom.info	32722	137	23646	1188	3	0%	23:59:43
9	erje	20616	134	20087	114	0	0%	23:59:34
10	au2pb	26562	87	23934	0	0	0%	23:59:56
11	news.redatomik.org	28483	83	23793	5	1	0%	23:59:56
12	news.mixmin.net	25407	10	21867	0	1	0%	23:59:56
13	bwh	19989	8	17165	0	0	0%	23:59:56
14	neva.ru	28087	6	22399	26	1	0%	23:59:55
15	eternal-september	25916	6	20320	0	1	0%	23:59:56
16	news.swapon.de	2339	4	2058	0	0	0%	23:58:22
17	news.fcku.it	3215	0	2903	0	0	0%	22:29:54
TOTAL: 17		414917	5930	335225	1438	76	1%	406:26:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.0 MB	62.6 KB	8.1 MB	0.1 KB/s	7.6 KB	23:59:56
2	xanadu-bbs	7.5 MB	0.0 KB	7.5 MB	0.1 KB/s	8.3 KB	23:59:56
3	fu-berlin.de	6.9 MB	0.0 KB	6.9 MB	0.1 KB/s	10.0 KB	23:59:56
4	news.bbs.geek.nz	6.0 MB	191.0 KB	6.2 MB	0.1 KB/s	2.7 KB	23:59:56
5	xmission	442.9 KB	1.2 KB	444.0 KB	0.0 KB/s	3.0 KB	23:59:56
6	weretis.net	442.6 KB	208.2 KB	650.7 KB	0.0 KB/s	3.3 KB	23:59:45
7	usenet.pasdenom.info	351.6 KB	8.2 MB	8.6 MB	0.1 KB/s	6.6 KB	23:59:43
8	erje	338.8 KB	616.3 KB	955.2 KB	0.0 KB/s	3.9 KB	23:59:34
9	aioe	299.3 KB	33.1 KB	332.3 KB	0.0 KB/s	2.0 KB	23:59:56
10	news.redatomik.org	197.5 KB	43.4 KB	240.9 KB	0.0 KB/s	2.7 KB	23:59:56
11	au2pb	162.6 KB	0.0 KB	162.6 KB	0.0 KB/s	1.9 KB	23:59:56
12	eternal-september	28.4 KB	0.0 KB	28.4 KB	0.0 KB/s	4.7 KB	23:59:56
13	news.mixmin.net	25.8 KB	0.0 KB	25.8 KB	0.0 KB/s	2.6 KB	23:59:56
14	bwh	11.8 KB	0.0 KB	11.8 KB	0.0 KB/s	1.5 KB	23:59:56
15	neva.ru	6.8 KB	137.0 KB	143.8 KB	0.0 KB/s	4.5 KB	23:59:55
16	news.swapon.de	4.1 KB	0.0 KB	4.1 KB	0.0 KB/s	1.0 KB	23:58:22
17	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:29:54
TOTAL: 17		30.8 MB	9.5 MB	40.3 MB	0.0 KB/s	5.6 KB	406:26:29

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	56	167.2 KB	54	0	0	04:50:36
2	mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	125	23	48.9 KB	19	0	0	02:00:36
3	68-185-181-64.dhcp.mdsn.wi.charter.com	21	4	4.1 KB	4	4	4	06:57:06
4	challenger.nielsenmedia.com	15	2	3.1 KB	2	0	0	03:49:44
5	dhcp-089-099-203-254.chello.nl	73	2	2.6 KB	2	0	0	30:47:39
TOTAL: 5		522	87	225.9 KB	81	4	4	71:01:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	289	56	167.2 KB	54	0	0	26:56:05
2	*.dsl.bell.ca	125	23	48.9 KB	19	0	0	02:00:36
3	*.dhcp.mdsn.wi.charter.com	21	4	4.1 KB	4	4	4	06:57:06
4	*.nielsenmedia.com	15	2	3.1 KB	2	0	0	03:49:44
5	*.chello.nl	73	2	2.6 KB	2	0	0	30:47:39
6	*.sub-70-193-244.myvzw.com	1	0	0.0 KB	0	0	0	00:30:01
TOTAL: 6		524	87	225.9 KB	81	4	4	71:01:15

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	4
TOTAL: 2	292

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	6.189	1.400	0.000	30:47:39
216.239.174.71	0.431	0.533	0.000	22:02:30
68-185-181-64.dhcp.mdsn.wi.charter.com	1.946	0.494	0.009	06:57:06
202.89.176.220	29.703	7.541	0.794	04:50:36
challenger.nielsenmedia.com	1.261	0.282	0.000	03:49:44
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca	16.711	5.707	0.000	02:00:36
227.sub-70-193-244.myvzw.com	0.097	0.016	0.000	00:30:01
196.52.22.50	0.508	1.427	0.000	00:02:58
TOTAL: 8	56.846	17.400	0.803	71:01:15

Curious NNRP server explorers [Top 20]:

System	Conn
227.sub-70-193-244.myvzw.com	1
196.52.22.50	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	51	0
68-185-181-64.dhcp.mdsn.wi.charter.com	9	0
challenger.nielsenmedia.com	6	3
227.sub-70-193-244.myvzw.com	1	0
TOTAL: 4	67	3

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	66	75.9%
2	uk	11	12.6%
3	alt	6	6.9%
4	rec	4	4.6%
TOTAL: 4		87	100.0%

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

Newsgroup		Count
1	comp.os.vms	18
2	comp.sys.ibm.ps2.hardware	10
3	comp.arch	8
4	comp.sys.ibm.pc.games.action	6
5	comp.arch.fpga	6
6	comp.os.cpm	3
7	comp.arch.embedded	3
8	uk.d-i-y	3
9	comp.sys.ibm.pc.games.rpg	3
10	rec.arts.tv	3
11	comp.ai.neural-nets	2
12	uk.media.radio.archers	2
13	comp.lang.python	2
14	uk.legal	2
15	comp.lang.awk	2
16	alt.happy.valley	2
17	alt.os.linux.ubuntu	1
18	alt.home.repair	1
19	alt.tv.general-hospital	1
20	uk.tech.digital-tv	1
21	uk.rec.sheds	1
22	uk.rec.audio	1
23	comp.sys.ibm.pc.games.adventure	1
24	comp.ai.nat-lang	1
25	uk.comp.sys.mac	1
26	rec.boats	1
27	comp.sys.ibm.pc.games.war-historical	1
28	alt.os.linux.mageia	1
TOTAL: 28		87

Daily Usenet report for csiph.com

Jun 10 04:15:00 -- Jun 11 04:15:00