Daily Usenet report for csiph.com: Nov 27 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	47432	58.5%	8.1 MB	68.9%
innd	30006	37.0%	3.1 MB	26.2%
innfeed	3176	3.9%	544.5 KB	4.5%
nnrpd	407	0.5%	44.4 KB	0.4%
nocem	49	0.1%	5.3 KB	0.0%
TOTAL: 5	81070	100.0%	11.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	429148	53.1%
Negative hits	329923	40.8%
Do not exist	45688	5.7%
Cache misses	3804	0.5%
TOTAL: 4	808563	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.234	0.0%	168	0.000	7.345	20.600
article cleanup	00:00:00.344	0.0%	46377	0.000	0.007	0.032
article logging	00:00:00.617	0.0%	46875	0.000	0.013	0.102
article parse	00:00:01.529	0.0%	190100	0.000	0.008	0.046
article write	00:04:52.568	0.3%	21297	0.472	13.738	64.482
artlog/artcncl	00:00:00.000	0.0%	143	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	173	0.000	0.000	0.000
data move	00:00:00.344	0.0%	729918	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.233	0.0%	167	0.000	1.395	9.304
hishave/artcncl	00:00:00.175	0.0%	168	0.000	1.042	7.391
hishave/artparse	00:00:00.000	0.0%	164	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:17.820	0.0%	810558	0.002	0.022	0.393
history sync	00:00:01.777	0.0%	356	0.000	4.992	167.333
history write	00:00:54.066	0.1%	45467	0.043	1.189	24.919
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.125	0.0%	164	0.000	0.762	14.000
idle	23:50:08.541	99.3%	723496	57.225	118.603	556.276
nntp read	00:00:03.408	0.0%	741029	0.001	0.005	0.009
overview write	00:01:27.134	0.1%	21297	0.200	4.091	61.167
perl filter	00:02:01.671	0.1%	45356	1.398	2.683	3.986
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.382	0.0%	42638	0.000	0.009	0.060
TOTAL: 24:00:53.603	23:59:51.968	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.062	0.0%	21248	0.000	0.003	0.020
article prepare	00:00:00.003	0.0%	37482	0.000	0.000	0.013
article read	00:00:05.927	0.0%	6710	0.000	0.883	6.158
backlog stats	00:00:00.323	0.0%	395971	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.379	0.0%	303719	0.003	0.008	0.016
data write	00:00:02.221	0.0%	286693	0.002	0.008	0.025
idle	23:54:17.034	100.0%	395971	120.161	217.332	731.817
status file	00:00:00.362	0.0%	288	0.000	1.257	4.000
TOTAL: 23:54:36.506	23:54:28.311	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	15:00:54.696	100.0%	260	1485.250	207902.677	600050.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.005	0.0%	565	0.000	0.009	0.167
TOTAL: 15:01:16.998	15:00:54.701	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1084	134645	6383	116785	11477	4%	91:12:52
2	csiph-out.news.weretis.net	4	52285	5337	45155	1793	10%	50:26:54
3	feeder.eternal-september.org	80	40223	2859	29719	7645	7%	42:10:53
4	news-out.aioe.org	3	20420	1599	18767	54	7%	24:09:54
5	news.redatomik.org	1	21054	1102	19878	74	5%	24:07:26
6	newsfeed.fu-berlin.de	23	23269	1055	21844	370	4%	28:53:53
7	newsfeed.neva.ru	139	52672	950	46984	4738	1%	72:03:30
8	newsfeeds.xmission.com	2	21334	726	20593	15	3%	25:52:40
9	news-out.mixmin.net	3	23795	626	22725	444	2%	24:08:43
10	news.fcku.it	2	8988	423	8505	60	4%	23:59:50
11	news.freedyn.net	3	28045	151	27671	223	0%	24:03:52
12	news.swapon.de	3	3320	100	3220	0	3%	44:16:04
13	xanadu-bbs.net	17	625	87	535	3	13%	22:08:15
14	nntp.pasdenom.info	2	39226	72	39055	99	0%	23:59:56
15	news.bbs.geek.nz	1	15683	16	15665	2	0%	23:59:49
16	localhost	32	0	0	0	0	0%	00:00:00
TOTAL: 16		1399	485584	21486	437101	26997	4%	545:34:31

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.4 MB	3.5 MB	61.1 MB	85.0 MB	23%	4.9 KB
2	csiph-out.news.weretis.net	15.8 MB	99.0 KB	22.3 MB	38.1 MB	41%	5.5 KB
3	feeder.eternal-september.org	7.5 MB	5.6 MB	96.5 MB	109.7 MB	6%	10.7 KB
4	news-out.aioe.org	4.0 MB	0.0 KB	82.8 KB	4.0 MB	97%	2.5 KB
5	news-out.mixmin.net	3.4 MB	0.0 KB	3.0 MB	6.4 MB	53%	6.1 KB
6	news.redatomik.org	2.8 MB	0.0 KB	1.5 MB	4.3 MB	65%	3.7 KB
7	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	777.9 KB	3.0 MB	74%	2.1 KB
8	newsfeed.neva.ru	2.2 MB	1.9 MB	47.9 MB	52.0 MB	4%	9.4 KB
9	newsfeeds.xmission.com	2.1 MB	0.0 KB	678.1 KB	2.8 MB	76%	3.8 KB
10	news.fcku.it	942.7 KB	0.0 KB	1.2 MB	2.1 MB	43%	4.5 KB
11	news.freedyn.net	715.0 KB	40.5 KB	1.9 MB	2.7 MB	26%	7.3 KB
12	news.swapon.de	253.5 KB	0.0 KB	0.0 KB	253.5 KB	100%	2.5 KB
13	xanadu-bbs.net	231.4 KB	0.0 KB	20.7 KB	252.0 KB	91%	2.8 KB
14	nntp.pasdenom.info	175.7 KB	0.0 KB	288.4 KB	464.0 KB	37%	2.7 KB
15	news.bbs.geek.nz	35.3 KB	0.0 KB	4.1 KB	39.3 KB	89%	2.2 KB
16	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		62.6 MB	11.1 MB	237.1 MB	310.9 MB	20%	6.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 27 04:15:01 - 04:59:59	378	1.8%	0.14	1.4 MB	2.2%	0.52
Nov 27 05:00:00 - 05:59:59	318	1.5%	0.09	966.1 KB	1.5%	0.27
Nov 27 06:00:00 - 06:59:59	448	2.1%	0.12	1.8 MB	2.9%	0.51
Nov 27 07:00:00 - 07:59:59	505	2.4%	0.14	1.5 MB	2.5%	0.44
Nov 27 08:00:00 - 08:59:59	695	3.3%	0.19	1.8 MB	2.9%	0.51
Nov 27 09:00:00 - 09:59:59	868	4.1%	0.24	2.4 MB	3.9%	0.69
Nov 27 10:00:00 - 10:59:59	916	4.3%	0.25	2.7 MB	4.3%	0.76
Nov 27 11:00:00 - 11:59:59	995	4.7%	0.28	3.4 MB	5.5%	0.98
Nov 27 12:00:00 - 12:59:59	917	4.3%	0.25	2.9 MB	4.6%	0.82
Nov 27 13:00:00 - 13:59:59	953	4.5%	0.26	2.7 MB	4.3%	0.76
Nov 27 14:00:00 - 14:59:59	1018	4.8%	0.28	3.0 MB	4.8%	0.84
Nov 27 15:00:00 - 15:59:59	1265	5.9%	0.35	3.3 MB	5.4%	0.95
Nov 27 16:00:00 - 16:59:59	1114	5.2%	0.31	2.8 MB	4.5%	0.79
Nov 27 17:00:00 - 17:59:59	1557	7.3%	0.43	3.6 MB	5.8%	1.02
Nov 27 18:00:00 - 18:59:59	1426	6.7%	0.40	3.9 MB	6.3%	1.11
Nov 27 19:00:00 - 19:59:59	1240	5.8%	0.34	4.1 MB	6.6%	1.17
Nov 27 20:00:00 - 20:59:59	1216	5.7%	0.34	3.7 MB	6.0%	1.05
Nov 27 21:00:00 - 21:59:59	1278	6.0%	0.35	3.4 MB	5.4%	0.96
Nov 27 22:00:00 - 22:59:59	1024	4.8%	0.28	3.2 MB	5.2%	0.92
Nov 27 23:00:00 - 23:59:59	829	3.9%	0.23	2.6 MB	4.2%	0.74
Nov 28 00:00:00 - 00:59:59	664	3.1%	0.18	1.9 MB	3.1%	0.55
Nov 28 01:00:00 - 01:59:59	680	3.2%	0.19	1.9 MB	3.0%	0.53
Nov 28 02:00:00 - 02:59:59	460	2.2%	0.13	1.4 MB	2.3%	0.41
Nov 28 03:00:00 - 03:59:59	423	2.0%	0.12	1.4 MB	2.3%	0.40
Nov 28 04:00:00 - 04:15:00	112	0.5%	0.12	415.9 KB	0.7%	0.46
TOTAL: 23:59:59	21299	100.0%	0.25	62.2 MB	100.0%	0.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	9755	244	302	1	9208
2	feeder.eternal-september.org	7786	82	481	0	7223
3	newsfeed.neva.ru	4808	122	166	0	4520
4	csiph-out.news.weretis.net	1889	97	8	0	1784
5	newsfeed.fu-berlin.de	729	82	0	0	647
6	news-out.mixmin.net	447	2	0	0	445
7	news.freedyn.net	223	0	3	0	220
8	nntp.pasdenom.info	97	6	0	0	91
9	news.redatomik.org	91	0	0	0	91
10	news.fcku.it	70	0	0	0	70
11	news-out.aioe.org	66	53	0	0	13
12	newsfeeds.xmission.com	17	2	0	0	15
13	news.swapon.de	7	0	0	0	7
14	xanadu-bbs.net	3	0	0	0	3
15	news.bbs.geek.nz	2	1	0	0	1
TOTAL: 15		25990	691	960	1	24338

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	133
ut.test	94
lada.talk	52
alt.survival	44
spline.eisfair	28
alt.windows7.general	23
abg.test	23
mozilla.general	14
mpc.lists.freebsd.office	12
gmane.linux.drivers.devicetree	12
alt.binaries.ebooks	11
lada.photo	10
gmane.comp.gis.openstreetmap.tagging	9
mpc.lists.freebsd.x11	9
alt.politics	8
alt.binaries.kindle	8
alt.politics.usa	7
alt.philosophy	7
mozilla.support.firefox	7
lada.auto	7
TOTAL: 110	691

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	16471
EMP (phn path)	5783
EMP (phl)	788
EMP (phn nph)	175
User-issued cancel	100
Too many newsgroups	81
Scoring filter (10)	42
Binary Payload	37
Scoring filter (12)	36
HTML Multipart	12
Scoring filter (11)	11
Scoring filter (8)	5
Scoring filter (9)	5
Scoring filter (22)	5
Scoring filter (14)	4
Scoring filter (13)	3
Scoring filter (21)	2
Scoring filter (15)	2
Binary Image: misplaced jpg	1
Binary Image: misplaced gif	1
TOTAL: 21	23565

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	101
	TOTAL: 1	101
Huge articles
	csiph-out.news.weretis.net	75
	news-out.mixmin.net	43
	news.redatomik.org	15
	feeder.eternal-september.org	15
	newsfeeds.xmission.com	9
	news.fcku.it	4
	newsfeed.fu-berlin.de	2
	news.freedyn.net	1
	TOTAL: 8	164
Including strange strings
	newsfeed.fu-berlin.de	363
	csiph-out.feeder.erje.net	45
	csiph-out.news.weretis.net	26
	news.redatomik.org	17
	news-out.aioe.org	12
	news.fcku.it	9
	feeder.eternal-september.org	7
	news.swapon.de	7
	newsfeeds.xmission.com	6
	news-out.mixmin.net	3
	TOTAL: 12	498
No colon-space in header
	csiph-out.feeder.erje.net	8
	TOTAL: 1	8
TOTAL: 4		771

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.geek.nz	36015	4511	15991	40	1	1	12%	23:59:55
2	news.freedyn.net	21645	972	20167	9	0	0	4%	23:59:51
3	xanadu-bbs	23635	824	20379	2	0	0	3%	23:59:48
4	fu-berlin.de	20023	653	19370	0	0	0	3%	23:59:54
5	xmission	24165	131	19731	7	0	0	0%	23:59:55
6	usenet.pasdenom.info	22682	86	19098	2040	0	0	0%	23:59:55
7	news.redatomik.org	19840	22	19335	12	0	0	0%	23:59:48
8	weretis.net	15078	15	14674	4	0	0	0%	23:59:42
9	aioe	18832	14	17894	4	0	0	0%	23:59:51
10	news.mixmin.net	20303	5	19442	0	0	0	0%	23:59:53
11	eternal-september	18645	5	17378	1	0	0	0%	23:59:55
12	neva.ru	20036	1	19405	8	0	0	0%	23:59:54
13	erje	14707	0	14625	14	0	0	0%	23:59:55
14	news.fcku.it	1739	0	1715	0	0	0	0%	22:06:52
15	bwh	0	0	0	0	0	20497	0%	23:58:41
16	news.swapon.de	1886	0	1833	0	0	0	0%	23:50:07
17	au2pb	0	0	0	0	0	20556	0%	23:58:42
TOTAL: 17		279231	7239	241037	2141	1	41054	2%	405:52:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	12.3 MB	113.2 KB	12.4 MB	0.1 KB/s	2.8 KB	23:59:55
2	news.freedyn.net	6.3 MB	44.6 KB	6.3 MB	0.1 KB/s	6.6 KB	23:59:51
3	xanadu-bbs	5.8 MB	4.0 KB	5.8 MB	0.1 KB/s	7.2 KB	23:59:48
4	fu-berlin.de	5.3 MB	0.0 KB	5.3 MB	0.1 KB/s	8.4 KB	23:59:54
5	usenet.pasdenom.info	323.4 KB	7.9 MB	8.2 MB	0.1 KB/s	3.9 KB	23:59:55
6	xmission	299.6 KB	14.9 KB	314.5 KB	0.0 KB/s	2.3 KB	23:59:55
7	news.redatomik.org	66.4 KB	94.8 KB	161.2 KB	0.0 KB/s	4.7 KB	23:59:48
8	weretis.net	37.4 KB	11.1 KB	48.6 KB	0.0 KB/s	2.6 KB	23:59:42
9	aioe	29.9 KB	12.3 KB	42.1 KB	0.0 KB/s	2.3 KB	23:59:51
10	news.mixmin.net	15.1 KB	0.0 KB	15.1 KB	0.0 KB/s	3.0 KB	23:59:53
11	eternal-september	10.4 KB	3.1 KB	13.5 KB	0.0 KB/s	2.2 KB	23:59:55
12	neva.ru	6.3 KB	28.1 KB	34.4 KB	0.0 KB/s	3.8 KB	23:59:54
13	erje	0.0 KB	42.2 KB	42.2 KB	0.0 KB/s	3.0 KB	23:59:55
14	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:06:52
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:41
16	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		30.5 MB	8.2 MB	38.7 MB	0.0 KB/s	4.2 KB	405:52:38

NNRP readership statistics:

System		Conn	Elapsed
1	47-192-187-101.drr02.clwr.fl.frontiernet.net	5	02:01:09
2	68-185-181-64.dhcp.mdsn.wi.charter.com	5	01:30:18
3	dhcp-089-099-203-254.chello.nl	38	06:14:40
4	tempe0.bbox.io	19	05:15:08
TOTAL: 4		67	15:01:16

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.drr02.clwr.fl.frontiernet.net	5	02:01:09
2	*.bbox.io	19	05:15:08
3	*.chello.nl	38	06:14:40
4	*.dhcp.mdsn.wi.charter.com	5	01:30:18
TOTAL: 4		67	15:01:16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
dhcp-089-099-203-254.chello.nl	2.548	0.677	06:14:40
tempe0.bbox.io	1.433	0.394	05:15:08
47-192-187-101.drr02.clwr.fl.frontiernet.net	0.262	0.073	02:01:09
68-185-181-64.dhcp.mdsn.wi.charter.com	0.316	0.116	01:30:18
TOTAL: 4	4.559	1.260	15:01:16

NNRP client timeouts [Top 20]:

System	Conn	Peer
tempe0.bbox.io	10	0
47-192-187-101.drr02.clwr.fl.frontiernet.net	4	0
68-185-181-64.dhcp.mdsn.wi.charter.com	3	0
TOTAL: 3	17	0

Daily Usenet report for csiph.com

Nov 27 04:15:01 -- Nov 28 04:15:00