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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	26565	55.2%	5.8 MB	69.7%
innd	12646	26.3%	1.5 MB	18.3%
nnrpd	5654	11.7%	477.0 KB	5.6%
innfeed	3214	6.7%	541.3 KB	6.4%
nocem	47	0.1%	5.2 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	48127	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	297956	55.4%
Negative hits	213644	39.7%
Do not exist	25703	4.8%
Cache misses	330	0.1%
TOTAL: 4	537633	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.858	0.0%	166	0.000	11.193	153.000
article cleanup	00:00:00.181	0.0%	24811	0.000	0.007	0.030
article logging	00:00:09.487	0.0%	25347	0.000	0.374	3.493
article parse	00:00:25.691	0.0%	77466	0.000	0.332	2.984
article write	00:06:43.261	0.5%	18872	0.236	21.368	140.515
artlog/artcncl	00:00:00.146	0.0%	144	0.000	1.014	6.500
artlog/artparse	00:00:00.997	0.0%	1088	0.000	0.916	7.071
data move	00:00:00.165	0.0%	522458	0.000	0.000	0.004
hisgrep/artcncl	00:00:00.222	0.0%	160	0.000	1.387	68.000
hishave/artcncl	00:00:00.067	0.0%	166	0.000	0.404	26.000
hishave/artparse	00:00:00.002	0.0%	1087	0.000	0.002	0.013
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:41.275	0.3%	540823	0.002	0.520	2.137
history sync	00:00:09.563	0.0%	352	0.000	27.168	161.000
history write	00:08:41.360	0.6%	24809	1.382	21.015	123.506
hiswrite/artcncl	00:00:00.018	0.0%	6	0.000	3.000	9.000
hiswrite/artparse	00:00:23.512	0.0%	1087	0.000	21.630	191.000
idle	23:19:26.862	97.1%	484094	86.962	173.452	652.476
nntp read	00:00:02.406	0.0%	533378	0.001	0.005	0.020
overview write	00:17:40.785	1.2%	18872	1.305	56.209	560.586
perl filter	00:00:48.212	0.1%	24736	1.414	1.949	4.624
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.355	0.0%	37784	0.000	0.009	0.036
TOTAL: 24:01:01.316	23:59:16.425	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.045	0.0%	18849	0.000	0.002	0.020
article prepare	00:00:00.002	0.0%	23674	0.000	0.000	0.007
article read	00:00:02.093	0.0%	3077	0.000	0.680	78.000
backlog stats	00:00:00.182	0.0%	355331	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.757	0.0%	269689	0.001	0.007	0.015
data write	00:00:01.543	0.0%	254857	0.001	0.006	0.013
idle	23:54:42.650	100.0%	355331	117.804	242.260	981.928
status file	00:00:01.688	0.0%	312	0.000	5.410	126.000
TOTAL: 23:54:59.285	23:54:49.960	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:10.155	0.0%	5077	2.000	2.000	2.000
idle	13:39:14.120	99.9%	10617	377.196	4629.756	600158.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.072	0.0%	21447	0.000	0.003	0.100
readart	00:00:00.507	0.0%	28	0.000	18.107	147.000
TOTAL: 13:40:04.269	13:39:24.854	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	83107	6297	73588	3222	7%	94:58:56
2	csiph-out.news.weretis.net	5	36964	3409	33170	385	9%	45:55:48
3	feeder.eternal-september.org	2	22509	2359	19554	596	10%	25:46:26
4	news-out.aioe.org	4	18693	1464	17170	59	7%	24:09:39
5	newsfeeds.xmission.com	2	17626	1277	16337	12	7%	23:55:36
6	newsfeed.neva.ru	142	22959	1210	20438	1311	5%	71:52:15
7	newsfeed.fu-berlin.de	23	17463	994	16186	283	5%	26:32:14
8	news.redatomik.org	1	18538	530	17950	58	2%	24:04:45
9	news.fcku.it	2	7309	507	6549	253	6%	23:43:51
10	news-out.mixmin.net	1	20770	471	19703	596	2%	23:59:50
11	news.swapon.de	9	1475	41	1434	0	2%	23:25:17
12	news.freedyn.net	3	20568	35	20491	42	0%	24:08:51
13	nntp.pasdenom.info	4	16899	35	16859	5	0%	23:58:11
14	xanadu-bbs.net	35	433	6	424	3	1%	20:30:19
15	news.bbs.geek.nz	1	18672	3	18669	0	0%	23:59:51
16	localhost	36	2	2	0	0	100%	00:00:01
TOTAL: 16		1422	323987	18640	298522	6825	5%	501:01:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	24.8 MB	48.5 KB	52.7 MB	77.6 MB	32%	8.3 KB
2	csiph-out.news.weretis.net	10.5 MB	0.0 KB	6.4 MB	16.9 MB	62%	4.6 KB
3	feeder.eternal-september.org	6.1 MB	0.0 KB	1.8 MB	7.9 MB	76%	2.7 KB
4	newsfeeds.xmission.com	4.2 MB	0.0 KB	610.7 KB	4.8 MB	87%	3.8 KB
5	news-out.aioe.org	3.4 MB	0.0 KB	131.2 KB	3.6 MB	96%	2.4 KB
6	newsfeed.neva.ru	3.4 MB	28.0 KB	4.3 MB	7.7 MB	43%	3.1 KB
7	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	495.0 KB	2.7 MB	82%	2.2 KB
8	news-out.mixmin.net	2.0 MB	0.0 KB	1.5 MB	3.5 MB	56%	3.4 KB
9	news.fcku.it	1.4 MB	0.0 KB	5.2 MB	6.6 MB	21%	8.9 KB
10	news.redatomik.org	1.3 MB	0.0 KB	136.1 KB	1.4 MB	90%	2.4 KB
11	news.swapon.de	102.8 KB	0.0 KB	0.0 KB	102.8 KB	100%	2.5 KB
12	nntp.pasdenom.info	83.6 KB	0.0 KB	11.5 KB	95.1 KB	87%	2.4 KB
13	news.freedyn.net	79.9 KB	0.0 KB	2.0 MB	2.0 MB	3%	27.0 KB
14	xanadu-bbs.net	12.9 KB	0.0 KB	5.7 KB	18.6 KB	69%	2.1 KB
15	news.bbs.geek.nz	6.7 KB	0.0 KB	0.0 KB	6.7 KB	100%	2.2 KB
16	localhost	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.7 KB
TOTAL: 16		59.6 MB	76.5 KB	75.3 MB	135.0 MB	44%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 4 04:15:01 - 04:59:59	360	1.9%	0.13	1.1 MB	1.8%	0.42
Nov 4 05:00:00 - 05:59:59	329	1.7%	0.09	921.9 KB	1.5%	0.26
Nov 4 06:00:00 - 06:59:59	379	2.0%	0.11	1021.9 KB	1.7%	0.28
Nov 4 07:00:00 - 07:59:59	510	2.7%	0.14	2.2 MB	3.7%	0.64
Nov 4 08:00:00 - 08:59:59	811	4.3%	0.23	3.2 MB	5.3%	0.90
Nov 4 09:00:00 - 09:59:59	925	4.9%	0.26	4.0 MB	6.6%	1.14
Nov 4 10:00:00 - 10:59:59	866	4.6%	0.24	3.0 MB	4.9%	0.84
Nov 4 11:00:00 - 11:59:59	1015	5.4%	0.28	3.5 MB	5.9%	1.01
Nov 4 12:00:00 - 12:59:59	880	4.7%	0.24	3.0 MB	5.0%	0.86
Nov 4 13:00:00 - 13:59:59	947	5.0%	0.26	2.9 MB	4.8%	0.82
Nov 4 14:00:00 - 14:59:59	964	5.1%	0.27	2.5 MB	4.2%	0.72
Nov 4 15:00:00 - 15:59:59	1103	5.8%	0.31	3.4 MB	5.6%	0.96
Nov 4 16:00:00 - 16:59:59	1084	5.7%	0.30	3.2 MB	5.4%	0.92
Nov 4 17:00:00 - 17:59:59	999	5.3%	0.28	2.7 MB	4.5%	0.78
Nov 4 18:00:00 - 18:59:59	1052	5.6%	0.29	3.0 MB	5.0%	0.86
Nov 4 19:00:00 - 19:59:59	1043	5.5%	0.29	3.0 MB	5.0%	0.86
Nov 4 20:00:00 - 20:59:59	1006	5.3%	0.28	2.8 MB	4.6%	0.78
Nov 4 21:00:00 - 21:59:59	1020	5.4%	0.28	2.9 MB	4.9%	0.83
Nov 4 22:00:00 - 22:59:59	797	4.2%	0.22	2.7 MB	4.5%	0.78
Nov 4 23:00:00 - 23:59:59	739	3.9%	0.21	2.8 MB	4.6%	0.79
Nov 5 00:00:00 - 00:59:59	553	2.9%	0.15	1.4 MB	2.3%	0.40
Nov 5 01:00:00 - 01:59:59	494	2.6%	0.14	1.5 MB	2.6%	0.44
Nov 5 02:00:00 - 02:59:59	455	2.4%	0.13	1.5 MB	2.5%	0.43
Nov 5 03:00:00 - 03:59:59	420	2.2%	0.12	1.6 MB	2.6%	0.45
Nov 5 04:00:00 - 04:15:00	109	0.6%	0.12	323.1 KB	0.5%	0.36
TOTAL: 23:59:59	18860	100.0%	0.22	60.2 MB	100.0%	0.71

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	3282	157	2	3123
2	newsfeed.neva.ru	1314	136	1	1177
3	newsfeed.fu-berlin.de	683	66	0	617
4	feeder.eternal-september.org	610	46	0	564
5	news-out.mixmin.net	606	2	0	604
6	csiph-out.news.weretis.net	439	109	0	330
7	news.fcku.it	430	1	0	429
8	news.redatomik.org	66	0	0	66
9	news-out.aioe.org	62	7	0	55
10	news.freedyn.net	41	0	0	41
11	newsfeeds.xmission.com	13	2	0	11
12	news.swapon.de	7	0	0	7
13	nntp.pasdenom.info	5	3	0	2
14	xanadu-bbs.net	3	0	0	3
TOTAL: 14		7561	529	3	7029

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	92
spline.eisfair.dev	46
alt.survival	40
spline.eisfair	33
ut.test	23
alt.politics	22
alt.windows7.general	20
mozilla.support.seamonkey	19
gmane.comp.gis.openstreetmap.tagging	16
mozilla.support.firefox	16
mpc.lists.freebsd.ports.bugs	15
alt.binaries.tvshows	9
free.it.religioni.scientology	9
mozilla.dev.platform	8
alt.philosophy	6
it-alt.sport.calcio.juventus	6
lada.auto	6
oesterreich.tratsch	6
alt.politics.usa	6
alt.binaries.ebook	5
TOTAL: 100	529

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	4301
EMP (phn path)	593
EMP (phn nph)	228
Too many newsgroups	43
EMP (phl)	28
Binary Payload	27
User-issued cancel	17
HTML Multipart	16
Scoring filter (10)	9
Binary: misplaced 002	9
Scoring filter (9)	7
Scoring filter (14)	7
Binary Image: misplaced png	6
HTML post	6
Scoring filter (8)	6
Binary: misplaced par2	4
Scoring filter (12)	4
Scoring filter (15)	3
Scoring filter (16)	3
Binary: misplaced 003	3
TOTAL: 29	5333

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	71
	TOTAL: 1	71
Huge articles
	csiph-out.feeder.erje.net	863
	news.fcku.it	101
	csiph-out.news.weretis.net	60
	news.freedyn.net	33
	feeder.eternal-september.org	12
	newsfeeds.xmission.com	11
	news-out.mixmin.net	5
	news.redatomik.org	1
	newsfeed.fu-berlin.de	1
	TOTAL: 9	1087
Including strange strings
	newsfeed.fu-berlin.de	388
	csiph-out.feeder.erje.net	58
	csiph-out.news.weretis.net	56
	news.redatomik.org	11
	news-out.mixmin.net	7
	news.swapon.de	7
	newsfeed.neva.ru	4
	news-out.aioe.org	3
	news.fcku.it	2
	TOTAL: 9	536
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		1695

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.freedyn.net	20351	1185	17588	14	1	0	5%	23:52:43
2	xanadu-bbs	21219	876	17977	0	0	0	4%	23:59:52
3	news.bbs.geek.nz	30972	743	17309	57	0	0	2%	23:50:09
4	fu-berlin.de	17616	651	16965	0	0	0	3%	23:56:51
5	aioe	18189	148	15683	7	0	0	0%	23:50:09
6	usenet.pasdenom.info	19555	102	16859	1864	0	13	0%	23:59:54
7	erje	12693	46	12297	39	0	0	0%	23:49:28
8	news.redatomik.org	19604	34	17464	8	0	0	0%	23:50:09
9	eternal-september	18022	17	15701	3	0	0	0%	23:59:54
10	news.mixmin.net	17883	5	16882	0	0	0	0%	23:59:46
11	neva.ru	17059	5	16599	107	0	0	0%	23:59:54
12	news.swapon.de	1591	4	1480	0	0	0	0%	23:15:47
13	xmission	17812	3	16739	0	0	0	0%	23:59:54
14	weretis.net	15125	2	14145	0	0	0	0%	23:58:08
15	news.fcku.it	1339	1	1229	0	0	0	0%	21:36:09
16	au2pb	0	0	0	0	0	18169	0%	23:58:42
17	bwh	0	0	0	0	0	18119	0%	23:50:06
TOTAL: 17		249030	3822	214917	2099	1	36301	1%	403:47:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	10.7 MB	62.9 KB	10.7 MB	0.1 KB/s	9.2 KB	23:52:43
2	xanadu-bbs	9.9 MB	0.0 KB	9.9 MB	0.1 KB/s	11.6 KB	23:59:52
3	fu-berlin.de	9.3 MB	0.0 KB	9.3 MB	0.1 KB/s	14.6 KB	23:56:51
4	news.bbs.geek.nz	2.0 MB	181.4 KB	2.2 MB	0.0 KB/s	2.8 KB	23:50:09
5	aioe	403.8 KB	17.7 KB	421.5 KB	0.0 KB/s	2.7 KB	23:50:09
6	usenet.pasdenom.info	259.9 KB	11.2 MB	11.5 MB	0.1 KB/s	6.0 KB	23:59:54
7	erje	159.9 KB	130.0 KB	289.9 KB	0.0 KB/s	3.4 KB	23:49:28
8	news.redatomik.org	100.1 KB	20.8 KB	120.9 KB	0.0 KB/s	2.9 KB	23:50:09
9	eternal-september	43.8 KB	7.2 KB	51.0 KB	0.0 KB/s	2.6 KB	23:59:54
10	news.mixmin.net	16.1 KB	0.0 KB	16.1 KB	0.0 KB/s	3.2 KB	23:59:46
11	neva.ru	9.7 KB	288.1 KB	297.9 KB	0.0 KB/s	2.7 KB	23:59:54
12	news.swapon.de	8.0 KB	0.0 KB	8.0 KB	0.0 KB/s	2.0 KB	23:15:47
13	xmission	5.9 KB	0.0 KB	5.9 KB	0.0 KB/s	2.0 KB	23:59:54
14	weretis.net	3.5 KB	0.0 KB	3.5 KB	0.0 KB/s	1.7 KB	23:58:08
15	news.fcku.it	1.5 KB	0.0 KB	1.5 KB	0.0 KB/s	1.5 KB	21:36:09
16	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
17	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:06
TOTAL: 17		32.8 MB	11.9 MB	44.7 MB	0.0 KB/s	7.7 KB	403:47:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	47	19	47.1 KB	16	1	0	00:37:36
2	24.178.46.210	7	3	5.1 KB	2	1	1	03:03:47
3	a131033.upc-a.chello.nl	23	3	5.1 KB	3	0	0	06:15:21
4	194-73-239-109.cust.centrio.cz	2	3	6.5 KB	2	0	0	00:04:04
TOTAL: 4		79	28	63.8 KB	23	2	1	13:40:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	54	22	52.2 KB	18	2	1	03:41:23
2	*.upc-a.chello.nl	23	3	5.1 KB	3	0	0	06:15:21
3	*.cust.centrio.cz	2	3	6.5 KB	2	0	0	00:04:04
4	*.bbox.io	5	0	0.0 KB	0	0	0	02:30:12
5	*.163.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:38:47
6	*.drr02.clwr.fl.frontiernet.net	1	0	0.0 KB	0	0	0	00:30:14
TOTAL: 6		86	28	63.8 KB	23	2	1	13:40:03

NNRP auth users [Top 20]:

User	Conn
moussa	47
ctomczyk	2
ohlandl	1
TOTAL: 3	50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	1.931	0.265	0.000	06:15:21
24.178.46.210	0.643	0.104	0.003	03:03:47
tempe0.bbox.io	0.461	0.137	0.000	02:30:12
h119.163.22.98.dynamic.ip.windstream.net	0.205	0.441	0.000	00:38:47
141.168.117.108	4.196	1.107	0.235	00:37:36
47-192-184-201.drr02.clwr.fl.frontiernet.net	0.050	0.017	0.000	00:30:14
194-73-239-109.cust.centrio.cz	0.250	0.047	0.007	00:04:04
TOTAL: 7	7.736	2.118	0.245	13:40:03

Curious NNRP server explorers [Top 20]:

System	Conn
tempe0.bbox.io	5
h119.163.22.98.dynamic.ip.windstream.net	1
47-192-184-201.drr02.clwr.fl.frontiernet.net	1
TOTAL: 3	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
24.178.46.210	5	0
tempe0.bbox.io	5	0
47-192-184-201.drr02.clwr.fl.frontiernet.net	1	0
h119.163.22.98.dynamic.ip.windstream.net	1	0
TOTAL: 4	12	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.os.vms	10
2	comp.sys.ibm.ps2.hardware	8
3	comp.arch	4
4	comp.lang.javascript	3
5	comp.ai.nat-lang	1
6	comp.ai.philosophy	1
7	comp.ai.doc-analysis.misc	1
TOTAL: 7		28

Daily Usenet report for csiph.com

Nov 4 04:15:01 -- Nov 5 04:15:00