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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	45212	43.9%	9.1 MB	61.9%
nnrpd	33740	32.8%	2.8 MB	19.2%
innd	20786	20.2%	2.2 MB	15.2%
innfeed	3183	3.1%	555.0 KB	3.7%
nocem	58	0.1%	5.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	102980	100.0%	14.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	559256	57.3%
Negative hits	370485	38.0%
Do not exist	44821	4.6%
Cache misses	670	0.1%
TOTAL: 4	975232	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.447	0.0%	214	0.000	2.089	29.000
article cleanup	00:00:00.267	0.0%	43698	0.000	0.006	0.033
article logging	00:00:00.613	0.0%	44341	0.000	0.014	0.069
article parse	00:00:01.920	0.0%	174903	0.002	0.011	0.083
article write	00:01:36.998	0.1%	27900	0.183	3.477	11.682
artlog/artcncl	00:00:00.006	0.0%	200	0.000	0.030	1.000
artlog/artparse	00:00:00.009	0.0%	706	0.000	0.013	0.333
data move	00:00:00.324	0.0%	970287	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.104	0.0%	209	0.000	0.498	11.500
hishave/artcncl	00:00:00.005	0.0%	214	0.000	0.023	0.250
hishave/artparse	00:00:00.000	0.0%	351	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:11.308	0.0%	979003	0.001	0.012	0.102
history sync	00:00:02.536	0.0%	341	0.000	7.437	61.667
history write	00:01:08.088	0.1%	44039	0.093	1.546	10.235
hiswrite/artcncl	00:00:00.009	0.0%	5	0.000	1.800	5.000
hiswrite/artparse	00:00:00.493	0.0%	351	0.000	1.405	24.333
idle	23:53:16.945	99.5%	979596	55.387	87.788	295.570
nntp read	00:00:03.269	0.0%	989077	0.002	0.003	0.008
overview write	00:01:41.567	0.1%	27900	0.540	3.640	35.645
perl filter	00:01:43.973	0.1%	43657	1.652	2.382	3.725
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.496	0.0%	55850	0.000	0.009	0.021
TOTAL: 24:00:51.116	23:59:49.377	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.088	0.0%	28197	0.000	0.003	0.026
article prepare	00:00:00.007	0.0%	35924	0.000	0.000	0.004
article read	00:00:00.762	0.0%	4533	0.000	0.168	3.379
backlog stats	00:00:00.322	0.0%	671854	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.360	0.0%	485882	0.001	0.005	0.008
data write	00:00:03.305	0.0%	465542	0.003	0.007	0.012
idle	23:52:53.383	100.0%	671854	78.173	127.964	346.224
status file	00:00:00.206	0.0%	259	0.000	0.795	4.500
TOTAL: 23:53:09.854	23:53:00.433	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.911	0.0%	11068	0.052	0.082	0.385
idle	70:13:27.962	99.4%	44399	296.846	5694.001	600239.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:31.212	0.0%	89970	0.000	0.347	98.389
readart	00:00:23.714	0.0%	14607	0.000	1.623	70.000
TOTAL: 70:36:54.720	70:14:23.799	99.5%	-	-	-	-

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	31893	6331	23274	2288	19%	23:44:43
2	feeder.eternal-september.org	4	47326	4252	41752	1322	8%	23:38:32
3	csiph-out.feeder.erje.net	1152	103842	3756	96986	3100	3%	94:57:28
4	news-out.mixmin.net	2	31077	2630	26359	2088	8%	23:39:31
5	csiph-out.news.weretis.net	3	64895	2515	61546	834	3%	47:12:56
6	news-out.aioe.org	3	26647	2492	24121	34	9%	24:09:38
7	newsfeed.neva.ru	151	55918	1817	49933	4168	3%	73:09:04
8	newsfeed.fu-berlin.de	53	33278	1481	31401	396	4%	36:38:23
9	news.fcku.it	1	12582	604	11974	4	4%	26:19:53
10	newsfeeds.xmission.com	1	28813	512	28275	26	1%	24:21:59
11	xanadu-bbs.net	1	44455	505	40147	3803	1%	25:03:15
12	news.redatomik.org	1	37253	289	36955	9	0%	32:59:55
13	news.freedyn.net	4	31044	255	30673	116	0%	24:09:06
14	news.swapon.de	1	2408	183	2219	6	7%	24:07:46
15	au2pb.net	1	14591	88	14479	24	0%	12:14:48
16	nntp.pasdenom.info	3	27732	68	27639	25	0%	24:08:35
17	news.bbs.geek.nz	1	17741	60	17678	3	0%	17:52:27
18	localhost	49	14	14	0	0	100%	00:00:00
TOTAL: 18		1719	611509	27852	565411	18246	4%	558:27:59

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	18.5 MB	0.0 KB	23.1 MB	41.6 MB	44%	4.9 KB
2	csiph-out.feeder.erje.net	15.1 MB	16.2 KB	3.8 MB	19.0 MB	79%	2.8 KB
3	feeder.eternal-september.org	10.6 MB	3.2 KB	13.1 MB	23.7 MB	44%	4.4 KB
4	news-out.mixmin.net	8.5 MB	7.3 KB	3.9 MB	12.3 MB	68%	2.7 KB
5	csiph-out.news.weretis.net	8.4 MB	1.8 KB	13.2 MB	21.5 MB	38%	6.6 KB
6	news-out.aioe.org	6.3 MB	0.0 KB	69.2 KB	6.3 MB	98%	2.6 KB
7	newsfeed.neva.ru	4.8 MB	112.2 KB	32.9 MB	37.8 MB	12%	6.5 KB
8	newsfeed.fu-berlin.de	3.4 MB	0.0 KB	926.7 KB	4.3 MB	78%	2.3 KB
9	newsfeeds.xmission.com	1.3 MB	12.2 KB	567.1 KB	1.9 MB	70%	3.6 KB
10	xanadu-bbs.net	1.3 MB	35.2 KB	36.7 MB	38.1 MB	3%	9.1 KB
11	news.fcku.it	1.1 MB	0.0 KB	19.8 KB	1.1 MB	98%	1.9 KB
12	news.redatomik.org	687.8 KB	0.0 KB	1.0 MB	1.7 MB	39%	5.9 KB
13	news.freedyn.net	687.3 KB	0.0 KB	897.2 KB	1.5 MB	43%	4.3 KB
14	news.swapon.de	476.3 KB	5.1 KB	12.0 KB	493.4 KB	96%	2.6 KB
15	au2pb.net	256.1 KB	0.0 KB	102.1 KB	358.1 KB	71%	3.2 KB
16	nntp.pasdenom.info	237.5 KB	0.0 KB	99.4 KB	336.9 KB	70%	3.6 KB
17	news.bbs.geek.nz	126.6 KB	0.0 KB	5.8 KB	132.4 KB	95%	2.1 KB
18	localhost	52.6 KB	0.0 KB	0.0 KB	52.6 KB	100%	3.8 KB
TOTAL: 18		81.8 MB	193.3 KB	130.3 MB	212.3 MB	38%	4.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 24 04:15:00 - 04:59:59	352	1.3%	0.13	1.3 MB	1.5%	0.48
Mar 24 05:00:00 - 05:59:59	569	2.0%	0.16	2.3 MB	2.8%	0.65
Mar 24 06:00:00 - 06:59:59	721	2.6%	0.20	2.4 MB	2.9%	0.69
Mar 24 07:00:00 - 07:59:59	959	3.4%	0.27	2.6 MB	3.2%	0.75
Mar 24 08:00:00 - 08:59:59	1292	4.6%	0.36	4.5 MB	5.5%	1.28
Mar 24 09:00:00 - 09:59:59	1286	4.6%	0.36	3.3 MB	4.0%	0.93
Mar 24 10:00:00 - 10:59:59	1388	5.0%	0.39	3.8 MB	4.6%	1.07
Mar 24 11:00:00 - 11:59:59	1485	5.3%	0.41	3.7 MB	4.4%	1.04
Mar 24 12:00:00 - 12:59:59	1406	5.0%	0.39	3.8 MB	4.7%	1.09
Mar 24 13:00:00 - 13:59:59	1509	5.4%	0.42	3.8 MB	4.7%	1.09
Mar 24 14:00:00 - 14:59:59	1551	5.6%	0.43	4.3 MB	5.2%	1.23
Mar 24 15:00:00 - 15:59:59	1611	5.8%	0.45	4.3 MB	5.2%	1.21
Mar 24 16:00:00 - 16:59:59	1589	5.7%	0.44	4.9 MB	5.9%	1.39
Mar 24 17:00:00 - 17:59:59	1596	5.7%	0.44	4.3 MB	5.3%	1.24
Mar 24 18:00:00 - 18:59:59	1497	5.4%	0.42	4.0 MB	4.9%	1.15
Mar 24 19:00:00 - 19:59:59	1441	5.2%	0.40	3.8 MB	4.6%	1.08
Mar 24 20:00:00 - 20:59:59	1289	4.6%	0.36	4.2 MB	5.1%	1.18
Mar 24 21:00:00 - 21:59:59	1286	4.6%	0.36	3.4 MB	4.2%	0.98
Mar 24 22:00:00 - 22:59:59	1288	4.6%	0.36	4.7 MB	5.7%	1.35
Mar 24 23:00:00 - 23:59:59	1010	3.6%	0.28	3.3 MB	4.1%	0.95
Mar 25 00:00:00 - 00:59:59	803	2.9%	0.22	2.7 MB	3.3%	0.78
Mar 25 01:00:00 - 01:59:59	617	2.2%	0.17	2.2 MB	2.7%	0.62
Mar 25 02:00:00 - 02:59:59	589	2.1%	0.16	1.9 MB	2.3%	0.55
Mar 25 03:00:00 - 03:59:59	594	2.1%	0.17	2.1 MB	2.6%	0.61
Mar 25 04:00:00 - 04:15:00	167	0.6%	0.19	629.8 KB	0.7%	0.70
TOTAL: 24:00:00	27895	100.0%	0.32	82.4 MB	100.0%	0.98

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.neva.ru	4176	1036	5	0	3135
2	xanadu-bbs.net	3762	33	2	0	3727
3	usenet.blueworldhosting.com	2292	944	0	1	1347
4	news-out.mixmin.net	2158	11	4	0	2143
5	csiph-out.feeder.erje.net	1420	355	2	0	1063
6	feeder.eternal-september.org	1358	114	0	0	1244
7	csiph-out.news.weretis.net	833	192	1	0	640
8	newsfeed.fu-berlin.de	824	24	0	0	800
9	news.freedyn.net	116	0	0	0	116
10	au2pb.net	44	39	0	0	5
11	news-out.aioe.org	43	20	0	0	23
12	news.swapon.de	30	0	0	0	30
13	nntp.pasdenom.info	25	17	0	0	8
14	newsfeeds.xmission.com	21	12	0	0	9
15	news.fcku.it	8	0	0	0	8
16	news.redatomik.org	6	0	0	0	6
17	news.bbs.geek.nz	1	1	0	0	0
TOTAL: 17		17117	2798	14	1	14304

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	649
mailing.freebsd.pkg-fallout	152
lada.talk	120
mailing.freebsd.svn-ports-all	72
mailing.freebsd.ports-bugs	72
mailing.freebsd.svn-ports-head	71
lada.auto	68
ut.test	67
mailing.freebsd.svn-src-all	57
fa.freebsd.cvs-all	56
it-alt.sport.calcio.juventus	49
mozilla.support.firefox	45
mailing.freebsd.svn-src-head	42
lada.commerce	40
mailing.openssl.dev	39
fa.netbsd.bugs	38
alt.politics.scorched-earth	37
mozilla.dev.platform	34
mailing.freebsd.questions	31
mailing.freebsd.kde	29
TOTAL: 238	2798

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

Groups	Count
rec.radio.amateur.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	9185
EMP (phn path)	1891
EMP (phl)	762
PFM bot misc	234
EMP (phn nph)	198
User-issued cancel	71
Scoring filter (17)	58
Binary: misplaced rar	42
Too many newsgroups	41
HTML Multipart	41
Scoring filter (25)	34
Scoring filter (8)	29
Scoring filter (9)	20
Scoring filter (20)	19
HTML post	18
User-issued spam cancel	18
Scoring filter (10)	18
Binary Payload	16
Scoring filter (14)	15
Scoring filter (16)	14
TOTAL: 47	12928

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	17
	usenet.blueworldhosting.com	10
	TOTAL: 2	27
Bad Message-ID's offered
	csiph-out.feeder.erje.net	3
	usenet.blueworldhosting.com	1
	TOTAL: 2	4
Huge articles
	csiph-out.news.weretis.net	106
	xanadu-bbs.net	89
	usenet.blueworldhosting.com	85
	feeder.eternal-september.org	48
	news-out.mixmin.net	8
	news.freedyn.net	8
	newsfeeds.xmission.com	5
	news.redatomik.org	2
	newsfeed.fu-berlin.de	1
	TOTAL: 9	352
Including strange strings
	newsfeed.fu-berlin.de	470
	feeder.eternal-september.org	41
	csiph-out.news.weretis.net	29
	news.swapon.de	27
	news-out.mixmin.net	25
	csiph-out.feeder.erje.net	24
	news-out.aioe.org	10
	newsfeed.neva.ru	5
	news.fcku.it	5
	newsfeeds.xmission.com	3
	TOTAL: 13	643
No colon-space in header
	csiph-out.feeder.erje.net	353
	usenet.blueworldhosting.com	2
	TOTAL: 2	355
TOTAL: 5		1381

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	51101	1770	25077	17	2	3%	23:59:55
2	xanadu-bbs	29721	1529	25839	4	243	5%	23:59:56
3	news.freedyn.net	32792	1429	26199	10	0	4%	23:59:55
4	fu-berlin.de	26160	872	25287	0	0	3%	23:59:55
5	aioe	26287	577	22407	25	0	2%	23:59:53
6	xmission	35272	240	26056	7	1	0%	23:59:56
7	usenet.pasdenom.info	35803	237	26188	1405	1	0%	23:59:53
8	news.redatomik.org	32635	156	26332	8	0	0%	23:59:55
9	au2pb	27619	123	26554	13	0	0%	23:59:56
10	erje	24150	86	23758	132	0	0%	23:59:56
11	neva.ru	30607	51	24571	150	82	0%	23:59:55
12	weretis.net	24212	50	23500	7	0	0%	23:59:55
13	news.mixmin.net	28379	32	23735	0	0	0%	23:59:55
14	news.fcku.it	4155	26	3488	0	0	0%	22:52:38
15	eternal-september	27869	21	22087	0	0	0%	23:59:47
16	bwh	21806	20	20419	0	0	0%	23:59:38
17	news.swapon.de	2604	10	2261	9	0	0%	23:58:38
TOTAL: 17		461172	7229	373758	1787	329	1%	406:49:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.5 MB	86.4 KB	9.6 MB	0.1 KB/s	6.8 KB	23:59:55
2	xanadu-bbs	9.4 MB	11.9 KB	9.4 MB	0.1 KB/s	6.3 KB	23:59:56
3	fu-berlin.de	7.9 MB	0.0 KB	7.9 MB	0.1 KB/s	9.2 KB	23:59:55
4	news.bbs.geek.nz	4.7 MB	59.6 KB	4.8 MB	0.1 KB/s	2.7 KB	23:59:55
5	aioe	1.6 MB	162.0 KB	1.7 MB	0.0 KB/s	2.9 KB	23:59:53
6	usenet.pasdenom.info	925.1 KB	8.7 MB	9.6 MB	0.1 KB/s	6.0 KB	23:59:53
7	xmission	671.1 KB	14.8 KB	685.9 KB	0.0 KB/s	2.8 KB	23:59:56
8	news.redatomik.org	478.4 KB	24.7 KB	503.1 KB	0.0 KB/s	3.1 KB	23:59:55
9	erje	341.7 KB	416.0 KB	757.6 KB	0.0 KB/s	3.5 KB	23:59:56
10	au2pb	341.7 KB	40.1 KB	381.8 KB	0.0 KB/s	2.8 KB	23:59:56
11	weretis.net	148.3 KB	51.2 KB	199.4 KB	0.0 KB/s	3.5 KB	23:59:55
12	neva.ru	138.6 KB	611.6 KB	750.2 KB	0.0 KB/s	3.7 KB	23:59:55
13	news.mixmin.net	103.0 KB	0.0 KB	103.0 KB	0.0 KB/s	3.2 KB	23:59:55
14	bwh	68.4 KB	0.0 KB	68.4 KB	0.0 KB/s	3.4 KB	23:59:38
15	eternal-september	67.0 KB	0.0 KB	67.0 KB	0.0 KB/s	3.2 KB	23:59:47
16	news.fcku.it	56.0 KB	0.0 KB	56.0 KB	0.0 KB/s	2.2 KB	22:52:38
17	news.swapon.de	16.8 KB	47.6 KB	64.5 KB	0.0 KB/s	3.4 KB	23:58:38
TOTAL: 17		36.3 MB	10.2 MB	46.5 MB	0.0 KB/s	5.3 KB	406:49:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	56	167.6 KB	51	0	0	04:58:04
2	68-185-181-64.dhcp.mdsn.wi.charter.com	31	19	62.6 KB	13	14	12	06:34:38
3	tempe0.bbox.io	44	18	61.5 KB	4	0	0	10:15:58
4	dhcp-089-099-203-254.chello.nl	22	17	26.5 KB	5	0	0	08:35:14
TOTAL: 4		385	110	318.2 KB	73	14	12	70:36:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	303	56	167.6 KB	51	0	0	42:24:07
2	*.dhcp.mdsn.wi.charter.com	31	19	62.6 KB	13	14	12	06:34:38
3	*.bbox.io	44	18	61.5 KB	4	0	0	10:15:58
4	*.chello.nl	22	17	26.5 KB	5	0	0	08:35:14
5	*.nielsenmedia.com	8	0	0.0 KB	0	0	0	02:46:51
TOTAL: 5		408	110	318.2 KB	73	14	12	70:36:50

NNRP auth users [Top 20]:

User	Conn
moussa	288
ohlandl	12
TOTAL: 2	300

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.153	0.981	0.706	0.000	37:24:45
tempe0.bbox.io	4.156	0.836	0.000	10:15:58
dhcp-089-099-203-254.chello.nl	2.315	0.363	0.000	08:35:14
68-185-181-64.dhcp.mdsn.wi.charter.com	3.253	0.607	0.026	06:34:38
202.89.176.220	28.783	7.298	0.743	04:58:04
challenger.nielsenmedia.com	0.660	0.181	0.000	02:46:51
199.189.26.12	0.444	0.793	0.000	00:00:56
199.189.26.7	0.197	0.181	0.000	00:00:11
94.167.61.251	0.137	0.024	0.000	00:00:03
5.87.187.180	0.060	0.015	0.000	00:00:02
5.86.253.186	0.097	0.016	0.000	00:00:01
94.165.243.108	0.066	0.017	0.000	00:00:01
TOTAL: 12	41.149	11.037	0.769	70:36:50

Curious NNRP server explorers [Top 20]:

System	Conn
162.212.88.153	8
challenger.nielsenmedia.com	8
94.167.61.251	2
199.189.26.12	1
199.189.26.7	1
5.87.187.180	1
5.86.253.186	1
94.165.243.108	1
TOTAL: 8	23

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	16	0
tempe0.bbox.io	16	0
68-185-181-64.dhcp.mdsn.wi.charter.com	7	0
challenger.nielsenmedia.com	5	0
TOTAL: 4	44	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	110	100.0%
TOTAL: 1		110	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	72
2	comp.os.vms	18
3	comp.arch.embedded	9
4	comp.ai.neural-nets	7
5	comp.sys.ibm.pc.games.action	2
6	comp.os.os2.announce	1
7	comp.ai.philosophy	1
TOTAL: 7		110

Daily Usenet report for csiph.com

Mar 24 04:15:00 -- Mar 25 04:15:00