Daily Usenet report for csiph.com: May 22 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	292136	84.2%	29.9 MB	75.4%
inn	34590	10.0%	7.3 MB	18.3%
innd	17027	4.9%	1.9 MB	4.9%
innfeed	3218	0.9%	556.2 KB	1.4%
nocem	85	0.0%	8.5 KB	0.0%
TOTAL: 5	347056	100.0%	39.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	403566	56.0%
Negative hits	283764	39.4%
Do not exist	33340	4.6%
Cache misses	427	0.1%
TOTAL: 4	721097	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.217	0.0%	241	0.000	5.050	43.300
article cleanup	00:00:00.232	0.0%	32880	0.000	0.007	0.032
article logging	00:00:05.374	0.0%	33465	0.000	0.161	2.528
article parse	00:00:06.272	0.0%	109573	0.003	0.057	0.276
article write	00:02:59.551	0.2%	23132	0.207	7.762	67.923
artlog/artcncl	00:00:00.005	0.0%	159	0.000	0.031	0.250
artlog/artparse	00:00:00.175	0.0%	644	0.000	0.272	7.000
data move	00:00:00.217	0.0%	690249	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.542	0.0%	241	0.000	2.249	21.722
hishave/artcncl	00:00:00.204	0.0%	241	0.000	0.846	15.400
hishave/artparse	00:00:00.001	0.0%	644	0.000	0.002	0.007
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:21.913	0.2%	724383	0.002	0.196	1.368
history sync	00:00:03.755	0.0%	300	0.000	12.517	95.000
history write	00:03:35.346	0.2%	32871	0.085	6.551	51.252
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:04.723	0.0%	644	0.000	7.334	43.000
idle	23:41:21.798	98.7%	676047	33.959	126.148	371.338
nntp read	00:00:03.412	0.0%	702607	0.002	0.005	0.018
overview write	00:06:52.732	0.5%	23132	0.932	17.842	245.174
perl filter	00:01:10.930	0.1%	32723	1.644	2.168	9.000
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.450	0.0%	46337	0.000	0.010	0.028
TOTAL: 24:00:13.991	23:58:48.849	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.049	0.0%	23056	0.000	0.002	0.024
article prepare	00:00:00.003	0.0%	23528	0.000	0.000	0.009
article read	00:00:01.202	0.0%	3742	0.000	0.321	18.200
backlog stats	00:00:00.256	0.0%	402493	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.192	0.0%	316676	0.002	0.007	0.014
data write	00:00:01.851	0.0%	301943	0.003	0.006	0.013
idle	23:53:42.480	100.0%	402493	121.538	213.724	669.561
status file	00:00:00.421	0.0%	279	0.000	1.509	25.500
TOTAL: 23:53:58.107	23:53:48.454	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	101:55:17.119	98.5%	1346540	98.000	272.489	600414.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:19.739	0.0%	3503227	0.000	0.006	0.167
readart	01:26:43.593	1.4%	1006295	0.000	5.171	142.500
TOTAL: 103:25:31.889	103:22:20.451	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1440	132644	7732	119146	5766	5%	118:43:05
2	feeder.eternal-september.org	7	31694	3296	26950	1448	10%	25:31:01
3	news-out.aioe.org	2	23173	2444	20648	81	10%	24:09:56
4	csiph-out.news.weretis.net	3	44770	2156	42135	479	4%	43:09:05
5	news-out.mixmin.net	2	24866	2077	21810	979	8%	24:08:54
6	newsfeed.neva.ru	146	30446	1660	27724	1062	5%	72:02:31
7	newsfeeds.xmission.com	2	22625	1300	21309	16	5%	25:09:02
8	newsfeed.fu-berlin.de	61	24163	999	22935	229	4%	35:25:14
9	news.redatomik.org	1	24273	804	23464	5	3%	27:57:12
10	news.freedyn.net	3	25654	335	25076	243	1%	24:09:52
11	news.fcku.it	1	10406	208	10131	67	1%	23:59:58
12	xanadu-bbs.net	22	659	65	570	24	9%	23:15:17
13	news.swapon.de	2	1914	60	1854	0	3%	24:31:20
14	nntp.pasdenom.info	2	21850	24	21822	4	0%	23:59:21
15	localhost	40	6	6	0	0	100%	00:00:01
16	news.bbs.geek.nz	2	19114	3	19111	0	0%	23:59:47
TOTAL: 16		1736	438257	23169	404685	10403	5%	540:11:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	28.2 MB	16.3 KB	32.7 MB	60.9 MB	46%	4.6 KB
2	feeder.eternal-september.org	9.3 MB	74.5 KB	47.0 MB	56.4 MB	16%	12.2 KB
3	news-out.mixmin.net	7.8 MB	0.0 KB	3.1 MB	10.9 MB	71%	3.7 KB
4	csiph-out.news.weretis.net	6.9 MB	0.0 KB	6.7 MB	13.6 MB	50%	5.3 KB
5	news-out.aioe.org	6.5 MB	0.0 KB	168.0 KB	6.7 MB	97%	2.7 KB
6	newsfeeds.xmission.com	3.8 MB	0.0 KB	506.5 KB	4.3 MB	88%	3.4 KB
7	newsfeed.neva.ru	3.8 MB	2.6 KB	4.3 MB	8.1 MB	46%	3.1 KB
8	news.redatomik.org	2.4 MB	0.0 KB	162.8 KB	2.5 MB	93%	3.2 KB
9	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	542.9 KB	2.7 MB	80%	2.3 KB
10	news.freedyn.net	921.2 KB	0.0 KB	1.1 MB	2.0 MB	44%	3.6 KB
11	news.fcku.it	366.7 KB	0.0 KB	1.2 MB	1.6 MB	22%	5.9 KB
12	xanadu-bbs.net	186.2 KB	0.0 KB	70.1 KB	256.3 KB	72%	2.9 KB
13	news.swapon.de	139.1 KB	0.0 KB	0.0 KB	139.1 KB	100%	2.3 KB
14	nntp.pasdenom.info	63.5 KB	0.0 KB	9.7 KB	73.2 KB	86%	2.6 KB
15	localhost	6.3 KB	0.0 KB	0.0 KB	6.3 KB	100%	1.1 KB
16	news.bbs.geek.nz	4.9 KB	0.0 KB	0.0 KB	4.9 KB	100%	1.6 KB
TOTAL: 16		72.6 MB	93.4 KB	97.7 MB	170.3 MB	42%	5.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 22 04:15:01 - 04:59:59	516	2.2%	0.19	2.2 MB	3.0%	0.84
May 22 05:00:00 - 05:59:59	555	2.4%	0.15	2.2 MB	3.0%	0.61
May 22 06:00:00 - 06:59:59	667	2.9%	0.19	2.1 MB	2.9%	0.59
May 22 07:00:00 - 07:59:59	856	3.7%	0.24	2.3 MB	3.2%	0.66
May 22 08:00:00 - 08:59:59	940	4.1%	0.26	3.0 MB	4.1%	0.86
May 22 09:00:00 - 09:59:59	991	4.3%	0.28	2.7 MB	3.7%	0.77
May 22 10:00:00 - 10:59:59	939	4.1%	0.26	2.8 MB	3.8%	0.79
May 22 11:00:00 - 11:59:59	975	4.2%	0.27	2.7 MB	3.7%	0.77
May 22 12:00:00 - 12:59:59	1025	4.4%	0.28	3.0 MB	4.2%	0.86
May 22 13:00:00 - 13:59:59	1134	4.9%	0.32	3.5 MB	4.8%	1.00
May 22 14:00:00 - 14:59:59	1530	6.6%	0.42	4.9 MB	6.8%	1.40
May 22 15:00:00 - 15:59:59	1441	6.2%	0.40	4.2 MB	5.8%	1.20
May 22 16:00:00 - 16:59:59	1429	6.2%	0.40	5.1 MB	7.1%	1.46
May 22 17:00:00 - 17:59:59	1332	5.8%	0.37	4.2 MB	5.7%	1.18
May 22 18:00:00 - 18:59:59	1231	5.3%	0.34	3.6 MB	4.9%	1.01
May 22 19:00:00 - 19:59:59	1320	5.7%	0.37	3.9 MB	5.4%	1.11
May 22 20:00:00 - 20:59:59	1325	5.7%	0.37	3.9 MB	5.4%	1.11
May 22 21:00:00 - 21:59:59	1001	4.3%	0.28	2.9 MB	4.0%	0.82
May 22 22:00:00 - 22:59:59	902	3.9%	0.25	3.1 MB	4.2%	0.87
May 22 23:00:00 - 23:59:59	665	2.9%	0.18	2.0 MB	2.7%	0.56
May 23 00:00:00 - 00:59:59	695	3.0%	0.19	2.3 MB	3.2%	0.67
May 23 01:00:00 - 01:59:59	537	2.3%	0.15	1.8 MB	2.5%	0.53
May 23 02:00:00 - 02:59:59	485	2.1%	0.13	1.7 MB	2.4%	0.49
May 23 03:00:00 - 03:59:59	448	1.9%	0.12	1.4 MB	2.0%	0.41
May 23 04:00:00 - 04:15:00	200	0.9%	0.22	1023.3 KB	1.4%	1.14
TOTAL: 23:59:59	23139	100.0%	0.27	72.6 MB	100.0%	0.86

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	5855	390	4	5461
2	feeder.eternal-september.org	1640	61	12	1567
3	newsfeed.neva.ru	1101	241	1	859
4	news-out.mixmin.net	1016	2	0	1014
5	newsfeed.fu-berlin.de	610	43	0	567
6	csiph-out.news.weretis.net	594	195	0	399
7	news.freedyn.net	244	21	0	223
8	news-out.aioe.org	95	50	0	45
9	news.fcku.it	59	4	0	55
10	xanadu-bbs.net	24	0	0	24
11	newsfeeds.xmission.com	21	6	0	15
12	news.redatomik.org	9	0	0	9
13	news.swapon.de	7	0	0	7
14	nntp.pasdenom.info	4	0	0	4
TOTAL: 14		11279	1013	17	10249

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	165
ut.test	119
lada.talk	91
spline.eisfair	48
lada.auto	44
mpc.lists.freebsd.ports.bugs	38
it.annunci.immobiliari	32
it.annunci.commerciali	31
mozilla.dev.security.policy	30
gmane.linux.alsa.devel	29
it.annunci.varie	28
mozilla.support.firefox	26
mozilla.test	24
alt.windows7.general	19
alt.survival	14
mozilla.support.thunderbird	13
alt.philosophy	12
alt.binaries.e-books.flood	12
mozilla.support.seamonkey	11
alt.politics	11
TOTAL: 130	1013

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7267
EMP (phn path)	1153
EMP (phl)	157
EMP (phn nph)	76
Too many newsgroups	55
HTML Multipart	29
User-issued cancel	27
Binary Payload	21
Scoring filter (10)	18
Scoring filter (8)	15
Scoring filter (9)	11
Scoring filter (14)	9
Scoring filter (12)	8
Scoring filter (13)	7
Scoring filter (11)	6
HTML post	6
Scoring filter (15)	3
HTML Image Tags	1
Too many newsgroups (low_xpost)	1
Scoring filter (16)	1
TOTAL: 22	8873

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	148
	TOTAL: 1	148
Bad Message-ID's offered
	csiph-out.feeder.erje.net	5
	TOTAL: 1	5
Huge articles
	feeder.eternal-september.org	288
	csiph-out.feeder.erje.net	203
	csiph-out.news.weretis.net	104
	news.fcku.it	16
	news-out.mixmin.net	13
	newsfeeds.xmission.com	10
	news.freedyn.net	6
	news.redatomik.org	1
	TOTAL: 8	641
Including strange strings
	newsfeed.fu-berlin.de	375
	csiph-out.news.weretis.net	61
	csiph-out.feeder.erje.net	52
	news-out.mixmin.net	44
	news-out.aioe.org	18
	newsfeed.neva.ru	14
	news.swapon.de	7
	feeder.eternal-september.org	7
	news.redatomik.org	4
	newsfeeds.xmission.com	3
	TOTAL: 11	587
TOTAL: 4		1381

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	42129	2019	20448	89	0	4%	23:59:49
2	news.freedyn.net	23838	785	22020	0	0	3%	23:59:53
3	xanadu-bbs	24043	612	22456	2	0	2%	23:59:45
4	fu-berlin.de	21922	571	21351	0	0	2%	23:59:53
5	usenet.pasdenom.info	24009	183	21402	1530	2	0%	23:59:53
6	news.redatomik.org	22350	19	21736	1	0	0%	23:59:53
7	xmission	23097	9	21123	3	0	0%	23:59:53
8	weretis.net	16167	9	16040	1	0	0%	23:59:52
9	eternal-september	19919	9	18976	0	0	0%	23:59:49
10	aioe	19532	9	18677	0	0	0%	23:59:53
11	news.swapon.de	1900	7	1873	0	0	0%	23:54:12
12	neva.ru	21062	6	20588	12	0	0%	23:59:38
13	news.mixmin.net	20646	6	20129	1	0	0%	23:59:45
14	erje	15449	5	15293	16	0	0%	23:59:51
15	au2pb	0	0	0	0	22558	0%	23:58:41
16	bwh	0	0	0	0	22501	0%	23:58:41
17	news.fcku.it	2837	0	2799	1	0	0%	22:26:43
TOTAL: 17		298900	4249	264911	1656	45061	1%	406:16:04

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	8.7 MB	0.0 KB	8.7 MB	0.1 KB/s	11.4 KB	23:59:53
2	xanadu-bbs	8.2 MB	3.8 KB	8.2 MB	0.1 KB/s	13.6 KB	23:59:45
3	fu-berlin.de	8.1 MB	0.0 KB	8.1 MB	0.1 KB/s	14.5 KB	23:59:53
4	news.bbs.geek.nz	5.0 MB	401.6 KB	5.4 MB	0.1 KB/s	2.6 KB	23:59:49
5	usenet.pasdenom.info	603.0 KB	9.6 MB	10.2 MB	0.1 KB/s	6.1 KB	23:59:53
6	news.redatomik.org	62.6 KB	13.6 KB	76.1 KB	0.0 KB/s	3.8 KB	23:59:53
7	eternal-september	14.8 KB	0.0 KB	14.8 KB	0.0 KB/s	1.6 KB	23:59:49
8	aioe	14.1 KB	0.0 KB	14.1 KB	0.0 KB/s	1.6 KB	23:59:53
9	weretis.net	12.3 KB	2.3 KB	14.6 KB	0.0 KB/s	1.5 KB	23:59:52
10	xmission	11.4 KB	9.4 KB	20.8 KB	0.0 KB/s	1.7 KB	23:59:53
11	news.swapon.de	9.5 KB	0.0 KB	9.5 KB	0.0 KB/s	1.4 KB	23:54:12
12	neva.ru	6.3 KB	31.9 KB	38.3 KB	0.0 KB/s	2.1 KB	23:59:38
13	news.mixmin.net	6.3 KB	3.1 KB	9.4 KB	0.0 KB/s	1.3 KB	23:59:45
14	erje	5.1 KB	44.7 KB	49.8 KB	0.0 KB/s	2.4 KB	23:59:51
15	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:41
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:41
17	news.fcku.it	0.0 KB	4.1 KB	4.1 KB	0.0 KB/s	4.1 KB	22:26:43
TOTAL: 17		30.7 MB	10.1 MB	40.9 MB	0.0 KB/s	7.1 KB	406:16:04

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	348	404602	741.4 MB	589	0	0	57:48:02
2	144.138.90.254	287	64	228.5 KB	61	0	0	03:37:34
3	204-73-239-109.cust.centrio.cz	28	11	30.2 KB	6	0	0	05:19:02
4	96-42-16-100.dhcp.mdsn.wi.charter.com	26	6	6.3 KB	6	6	6	06:31:58
5	dhcp-089-099-203-254.chello.nl	87	6	6.8 KB	5	0	0	27:04:39
6	g235242.upc-g.chello.nl	2	3	3.4 KB	1	0	0	00:03:43
TOTAL: 6		778	404692	741.7 MB	668	6	6	103:25:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dsl.bell.ca	348	404602	741.4 MB	589	0	0	57:48:02
2	unresolved	290	64	228.5 KB	61	0	0	03:37:41
3	*.cust.centrio.cz	28	11	30.2 KB	6	0	0	05:19:02
4	*.dhcp.mdsn.wi.charter.com	26	6	6.3 KB	6	6	6	06:31:58
5	*.chello.nl	87	6	6.8 KB	5	0	0	27:04:39
6	*.upc-g.chello.nl	2	3	3.4 KB	1	0	0	00:03:43
7	*.bbox.io	4	0	0.0 KB	0	0	0	02:00:07
8	*.hsd1.wa.comcast.net	2	0	0.0 KB	0	0	0	01:00:02
9	*.fr.clara.net	2	0	0.0 KB	0	0	0	00:00:05
TOTAL: 9		789	404692	741.7 MB	668	6	6	103:25:24

NNRP auth users [Top 20]:

User	Conn
moussa	287
ctomczyk	15
ohlandl	6
TOTAL: 3	308

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	94.847	138.399	0.000	57:48:02
dhcp-089-099-203-254.chello.nl	7.038	1.398	0.000	27:04:39
96-42-16-100.dhcp.mdsn.wi.charter.com	2.279	0.424	0.022	06:31:58
204-73-239-109.cust.centrio.cz	2.133	0.432	0.046	05:19:02
144.138.90.254	25.238	6.566	1.082	03:37:34
tempe0.bbox.io	0.357	0.056	0.000	02:00:07
c-174-61-130-222.hsd1.wa.comcast.net	0.099	0.030	0.000	01:00:02
g235242.upc-g.chello.nl	0.144	0.072	0.000	00:03:43
unused-89-185-44-204.fr.clara.net	0.136	0.032	0.000	00:00:05
195.157.14.204	0.122	0.026	0.000	00:00:05
195.22.4.225	0.086	0.016	0.000	00:00:01
TOTAL: 11	132.479	147.451	1.150	103:25:24

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	4
unused-89-185-44-204.fr.clara.net	2
c-174-61-130-222.hsd1.wa.comcast.net	2
195.157.14.204	2
195.22.4.225	1
TOTAL: 5	11

NNRP client timeouts [Top 20]:

System	Conn	Peer
204-73-239-109.cust.centrio.cz	10	0
96-42-16-100.dhcp.mdsn.wi.charter.com	4	0
tempe0.bbox.io	4	0
mtrlpq2314w-lp140-06-70-55-72-7.dsl.bell.ca	3	3
c-174-61-130-222.hsd1.wa.comcast.net	2	0
TOTAL: 5	23	3

Newsgroup request counts (by category):

Category		Count	Pct
1	free	346507	87.3%
2	alt	50188	12.6%
3	demon	139	0.0%
4	comp	90	0.0%
TOTAL: 4		396924	100.0%

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

Newsgroup		Count
1	free.usenet	346507
2	alt.arts.poetry.comments	50187
3	demon.local	139
4	comp.os.vms	30
5	comp.sys.ibm.ps2.hardware	21
6	comp.lang.javascript	11
7	comp.arch.fpga	6
8	comp.os.msdos.programmer	5
9	comp.arch.embedded	4
10	comp.arch	3
11	comp.sys.ibm.pc.games.action	3
12	comp.sys.ibm.pc.hardware.storage	2
13	alt.home.repair	1
14	comp.os.msdos.djgpp	1
15	comp.sys.hp.hardware	1
16	comp.os.os2.apps	1
17	comp.os.linux.networking	1
18	comp.sys.ibm.pc.soundcard.music	1
TOTAL: 18		396924

Daily Usenet report for csiph.com

May 22 04:15:01 -- May 23 04:15:00