Daily Usenet report for csiph.com: Jan 6 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	50752	45.7%	9.8 MB	62.9%
nnrpd	33216	29.9%	2.8 MB	18.0%
innd	23803	21.4%	2.5 MB	16.1%
innfeed	3224	2.9%	476.7 KB	3.0%
nocem	68	0.1%	7.2 KB	0.0%
TOTAL: 5	111063	100.0%	15.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	604502	62.1%
Negative hits	317581	32.6%
Do not exist	49542	5.1%
Cache misses	1769	0.2%
TOTAL: 4	973394	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.082	0.0%	270	0.000	4.007	332.000
article cleanup	00:00:00.271	0.0%	48769	0.000	0.006	0.020
article logging	00:00:00.605	0.0%	49400	0.000	0.012	0.139
article parse	00:00:02.069	0.0%	198161	0.004	0.010	0.080
article write	00:01:26.748	0.1%	28350	0.289	3.060	16.424
artlog/artcncl	00:00:00.005	0.0%	254	0.000	0.020	0.200
artlog/artparse	00:00:00.008	0.0%	866	0.000	0.009	1.000
data move	00:00:00.253	0.0%	906112	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.393	0.0%	269	0.000	1.461	165.000
hishave/artcncl	00:00:00.000	0.0%	270	0.000	0.000	0.000
hishave/artparse	00:00:00.002	0.0%	230	0.000	0.009	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:08.269	0.0%	973375	0.001	0.008	0.132
history sync	00:00:02.250	0.0%	335	0.000	6.716	60.333
history write	00:01:10.624	0.1%	49324	0.081	1.432	14.958
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.290	0.0%	230	0.000	1.261	48.000
idle	23:43:16.419	99.5%	932224	54.369	91.605	233.588
nntp read	00:00:03.128	0.0%	942371	0.001	0.003	0.007
overview write	00:01:21.764	0.1%	28350	0.516	2.884	18.951
perl filter	00:02:09.292	0.2%	48544	1.606	2.663	3.885
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.518	0.0%	56765	0.000	0.009	0.026
TOTAL: 23:50:43.866	23:49:43.990	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.471	0.0%	347912	0.000	0.001	0.021
article prepare	00:00:00.002	0.0%	30524	0.000	0.000	0.005
article read	00:00:00.826	0.0%	3773	0.000	0.219	6.385
backlog stats	00:00:00.378	0.0%	878751	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.906	0.0%	609060	0.001	0.005	0.008
data write	00:00:03.494	0.0%	580409	0.002	0.006	0.013
idle	23:51:29.931	100.0%	878751	38.171	97.741	352.134
status file	00:00:00.231	0.0%	332	0.000	0.696	1.000
TOTAL: 23:52:00.533	23:51:38.239	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:07.933	0.0%	108931	0.000	0.073	0.968
idle	105:41:31.869	99.8%	141096	136.370	2696.688	600180.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.522	0.0%	283385	0.000	0.005	0.500
readart	00:00:00.078	0.0%	121	0.000	0.645	49.000
TOTAL: 105:51:35.288	105:41:41.402	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	usenet.blueworldhosting.com	288	46876	9709	24216	12951	20%	23:49:42
2	csiph-out.feeder.erje.net	1152	194428	4247	186079	4102	2%	95:16:45
3	feeder.eternal-september.org	11	43485	4207	38698	580	9%	25:42:57
4	news-out.aioe.org	5	27103	2413	24676	14	8%	24:19:52
5	news-out.mixmin.net	4	31165	1853	28801	511	5%	24:18:01
6	newsfeed.fu-berlin.de	53	29750	1539	28126	85	5%	31:27:31
7	au2pb.net	2	32043	865	31025	153	2%	24:33:21
8	csiph-out.news.weretis.net	3	31198	710	30410	78	2%	25:08:04
9	news.redatomik.org	3	35439	684	34526	229	1%	21:13:50
10	newsfeed.neva.ru	48	36408	605	33731	2072	1%	23:42:22
11	newsfeeds.xmission.com	1	27493	539	26890	64	1%	23:38:21
12	xanadu-bbs.net	1	44256	300	43485	471	0%	21:57:35
13	news.fcku.it	1	6943	255	6687	1	3%	22:07:55
14	news.freedyn.net	14	11848	201	11605	42	1%	10:33:54
15	news.swapon.de	2	2626	115	2509	2	4%	21:47:56
16	nntp.pasdenom.info	2	27989	38	27925	26	0%	24:09:23
17	localhost	31	1	1	0	0	100%	00:00:00
18	news.bbs.geek.nz	2	48475	1	48463	11	0%	38:52:07
TOTAL: 18		1623	677526	28282	627852	21392	4%	482:39:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	usenet.blueworldhosting.com	25.6 MB	1.7 KB	120.3 MB	145.9 MB	17%	6.6 KB
2	csiph-out.feeder.erje.net	19.2 MB	221.3 KB	15.8 MB	35.3 MB	54%	4.3 KB
3	feeder.eternal-september.org	11.9 MB	73.8 KB	8.7 MB	20.6 MB	57%	4.4 KB
4	news-out.mixmin.net	6.8 MB	20.7 KB	1.1 MB	7.9 MB	85%	3.4 KB
5	news-out.aioe.org	6.4 MB	3.9 KB	18.5 KB	6.4 MB	99%	2.7 KB
6	newsfeed.fu-berlin.de	3.5 MB	6.4 KB	390.0 KB	3.9 MB	89%	2.4 KB
7	csiph-out.news.weretis.net	2.2 MB	23.2 KB	991.4 KB	3.2 MB	69%	4.2 KB
8	au2pb.net	1.9 MB	18.9 KB	559.6 KB	2.5 MB	77%	2.5 KB
9	news.redatomik.org	1.8 MB	10.0 KB	4.6 MB	6.3 MB	27%	7.1 KB
10	newsfeed.neva.ru	1.8 MB	42.5 KB	15.1 MB	16.9 MB	10%	6.5 KB
11	newsfeeds.xmission.com	1.7 MB	3.7 KB	695.6 KB	2.4 MB	71%	4.0 KB
12	news.freedyn.net	1.2 MB	7.1 KB	515.3 KB	1.7 MB	69%	7.1 KB
13	xanadu-bbs.net	763.4 KB	17.1 KB	5.8 MB	6.6 MB	11%	8.8 KB
14	news.fcku.it	473.4 KB	0.0 KB	1.0 KB	474.4 KB	99%	1.9 KB
15	news.swapon.de	308.8 KB	0.0 KB	5.6 KB	314.5 KB	98%	2.7 KB
16	nntp.pasdenom.info	101.3 KB	0.0 KB	139.5 KB	240.8 KB	42%	3.8 KB
17	news.bbs.geek.nz	1.1 KB	40.2 KB	53.0 KB	94.4 KB	1%	7.9 KB
18	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
TOTAL: 18		85.5 MB	490.5 KB	174.7 MB	260.7 MB	32%	5.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 6 04:15:00 - 04:59:59	431	1.5%	0.16	1.3 MB	1.5%	0.50
Jan 6 05:00:00 - 05:59:59	625	2.2%	0.17	2.0 MB	2.4%	0.58
Jan 6 06:00:00 - 06:59:59	647	2.3%	0.18	3.2 MB	3.7%	0.91
Jan 6 07:00:00 - 07:59:59	725	2.5%	0.20	2.3 MB	2.6%	0.64
Jan 6 08:00:00 - 08:59:59	1079	3.8%	0.30	3.1 MB	3.6%	0.88
Jan 6 09:00:00 - 09:59:59	926	3.3%	0.26	2.5 MB	2.9%	0.72
Jan 6 10:00:00 - 10:59:59	1209	4.3%	0.34	3.3 MB	3.9%	0.95
Jan 6 11:00:00 - 11:59:59	1314	4.6%	0.36	3.8 MB	4.5%	1.09
Jan 6 12:00:00 - 12:59:59	1299	4.6%	0.36	4.3 MB	5.0%	1.22
Jan 6 13:00:00 - 13:59:59	1313	4.6%	0.36	3.8 MB	4.4%	1.08
Jan 6 14:00:00 - 14:59:59	1399	4.9%	0.39	3.9 MB	4.6%	1.12
Jan 6 15:00:00 - 15:59:59	1499	5.3%	0.42	4.4 MB	5.1%	1.25
Jan 6 16:00:00 - 16:59:59	1816	6.4%	0.50	5.4 MB	6.3%	1.55
Jan 6 17:00:00 - 17:59:59	1742	6.1%	0.48	5.3 MB	6.2%	1.51
Jan 6 18:00:00 - 18:59:59	1709	6.0%	0.47	5.4 MB	6.3%	1.54
Jan 6 19:00:00 - 19:59:59	1694	6.0%	0.47	5.9 MB	6.9%	1.68
Jan 6 20:00:00 - 20:59:59	1583	5.6%	0.44	4.3 MB	5.0%	1.22
Jan 6 21:00:00 - 21:59:59	1824	6.4%	0.51	4.8 MB	5.6%	1.37
Jan 6 22:00:00 - 22:59:59	1367	4.8%	0.38	3.8 MB	4.4%	1.07
Jan 6 23:00:00 - 23:59:59	1286	4.5%	0.36	3.6 MB	4.2%	1.02
Jan 7 00:00:00 - 00:59:59	917	3.2%	0.25	2.9 MB	3.4%	0.83
Jan 7 01:00:00 - 01:59:59	789	2.8%	0.22	2.5 MB	2.9%	0.70
Jan 7 02:00:00 - 02:59:59	552	1.9%	0.15	1.7 MB	1.9%	0.47
Jan 7 03:00:00 - 03:59:59	524	1.8%	0.15	1.7 MB	1.9%	0.47
Jan 7 04:00:00 - 04:15:00	166	0.6%	0.18	538.0 KB	0.6%	0.60
TOTAL: 24:00:00	28435	100.0%	0.33	85.8 MB	100.0%	1.02

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	usenet.blueworldhosting.com	12985	1238	1	11746
2	csiph-out.feeder.erje.net	4117	1026	23	3068
3	newsfeed.neva.ru	2076	967	20	1089
4	feeder.eternal-september.org	601	256	10	335
5	newsfeed.fu-berlin.de	588	40	3	545
6	news-out.mixmin.net	518	2	7	509
7	xanadu-bbs.net	476	24	3	449
8	news.redatomik.org	266	0	4	262
9	au2pb.net	159	113	3	43
10	csiph-out.news.weretis.net	81	60	3	18
11	newsfeeds.xmission.com	63	58	1	4
12	news-out.aioe.org	39	8	1	30
13	news.freedyn.net	39	1	2	36
14	nntp.pasdenom.info	26	24	0	2
15	news.swapon.de	22	0	0	22
16	news.fcku.it	5	0	0	5
17	news.bbs.geek.nz	2	2	0	0
TOTAL: 17		22063	3819	81	18163

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	860
ut.test	216
lada.talk	169
mailing.freebsd.pkg-fallout	133
mailing.freebsd.ports-bugs	124
mpc.lists.freebsd.ports.bugs	121
mailing.freebsd.svn-ports-all	79
mailing.freebsd.svn-src-all	76
mailing.database.pgsql-general	74
alt.survival	74
fa.freebsd.cvs-all	73
mailing.freebsd.svn-ports-head	72
alt.binaries.multimedia.tatu	72
mozilla.general	69
mailing.openbsd.ports-changes	64
alt.politics.scorched-earth	57
lada.auto	48
pld.cvs.commit	46
mailing.openbsd.source-changes	46
fa.openbsd.source-changes	46
TOTAL: 253	3819

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	13062
EMP (phn path)	1919
EMP (phl)	816
Binary: misplaced rar	279
HTML Multipart	65
Too many newsgroups	63
Scoring filter (26)	55
User-issued cancel	29
Scoring filter (18)	28
Scoring filter (19)	25
Scoring filter (23)	21
Scoring filter (21)	14
Scoring filter (10)	13
Binary Payload	12
Scoring filter (27)	12
Scoring filter (28)	11
Scoring filter (12)	9
Scoring filter (9)	9
Scoring filter (8)	9
Scoring filter (20)	7
TOTAL: 39	16520

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	137
	usenet.blueworldhosting.com	6
	TOTAL: 2	143
Huge articles
	usenet.blueworldhosting.com	81
	feeder.eternal-september.org	53
	xanadu-bbs.net	39
	news.redatomik.org	33
	news-out.mixmin.net	8
	csiph-out.news.weretis.net	5
	newsfeeds.xmission.com	4
	newsfeed.fu-berlin.de	3
	news.freedyn.net	2
	nntp.pasdenom.info	1
	TOTAL: 11	230
Including strange strings
	newsfeed.fu-berlin.de	497
	feeder.eternal-september.org	29
	usenet.blueworldhosting.com	28
	news-out.aioe.org	25
	news.swapon.de	20
	csiph-out.feeder.erje.net	12
	news-out.mixmin.net	8
	news.fcku.it	5
	au2pb.net	5
	newsfeed.neva.ru	3
	TOTAL: 11	633
No colon-space in header
	csiph-out.feeder.erje.net	526
	usenet.blueworldhosting.com	110
	TOTAL: 2	636
TOTAL: 4		1642

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	30794	1337	26847	9	17	4%	23:54:49
2	news.bbs.geek.nz	51849	1256	26076	70	0	2%	23:50:06
3	xanadu-bbs	29523	1068	26952	100	11	3%	23:59:47
4	fu-berlin.de	26178	870	25286	21	0	3%	23:50:06
5	news.redatomik.org	689606	371	26290	19	319376	0%	23:52:02
6	aioe	26657	332	23279	19	119	1%	23:55:35
7	usenet.pasdenom.info	35148	258	26588	1551	5	0%	23:59:47
8	xmission	32553	85	26842	7	0	0%	23:59:40
9	au2pb	27151	73	26360	68	0	0%	23:50:06
10	news.mixmin.net	28931	15	25141	0	0	0%	23:54:21
11	weretis.net	25144	10	24088	1	0	0%	23:54:01
12	news.fcku.it	2706	8	2295	0	0	0%	22:39:44
13	eternal-september	26733	7	22765	3	0	0%	23:59:47
14	erje	21264	4	19903	75	21	0%	23:52:07
15	bwh	18072	1	17537	3	0	0%	23:51:39
16	news.swapon.de	2660	0	2570	3	0	0%	23:45:58
17	neva.ru	0	0	0	0	26636	0%	23:50:02
TOTAL: 17		1074969	5695	348819	1949	346185	0%	404:59:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	9.6 MB	75.6 KB	9.6 MB	0.1 KB/s	7.3 KB	23:54:49
2	xanadu-bbs	8.8 MB	243.8 KB	9.0 MB	0.1 KB/s	7.9 KB	23:59:47
3	fu-berlin.de	7.9 MB	26.3 KB	8.0 MB	0.1 KB/s	9.1 KB	23:50:06
4	news.bbs.geek.nz	3.8 MB	228.6 KB	4.0 MB	0.0 KB/s	3.1 KB	23:50:06
5	news.redatomik.org	1.2 MB	42.4 KB	1.2 MB	0.0 KB/s	3.2 KB	23:52:02
6	usenet.pasdenom.info	1.1 MB	9.0 MB	10.1 MB	0.1 KB/s	5.7 KB	23:59:47
7	aioe	1.1 MB	69.3 KB	1.1 MB	0.0 KB/s	3.3 KB	23:55:35
8	au2pb	390.3 KB	240.3 KB	630.6 KB	0.0 KB/s	4.5 KB	23:50:06
9	xmission	311.9 KB	15.5 KB	327.4 KB	0.0 KB/s	3.6 KB	23:59:40
10	news.mixmin.net	66.1 KB	0.0 KB	66.1 KB	0.0 KB/s	4.4 KB	23:54:21
11	weretis.net	27.9 KB	23.8 KB	51.7 KB	0.0 KB/s	4.7 KB	23:54:01
12	news.fcku.it	23.3 KB	0.0 KB	23.3 KB	0.0 KB/s	2.9 KB	22:39:44
13	eternal-september	22.0 KB	3.7 KB	25.7 KB	0.0 KB/s	2.6 KB	23:59:47
14	erje	13.4 KB	443.2 KB	456.7 KB	0.0 KB/s	5.8 KB	23:52:07
15	bwh	0.9 KB	5.8 KB	6.7 KB	0.0 KB/s	1.7 KB	23:51:39
16	news.swapon.de	0.0 KB	3.8 KB	3.8 KB	0.0 KB/s	1.3 KB	23:45:58
17	neva.ru	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:02
TOTAL: 17		34.2 MB	10.4 MB	44.6 MB	0.0 KB/s	6.0 KB	404:59:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	202.89.176.220	288	108	281.4 KB	100	0	0	03:59:35
2	tempe0.bbox.io	115	13	22.1 KB	2	1	1	13:20:47
TOTAL: 2		403	121	303.5 KB	102	1	1	105:51:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	398	108	281.4 KB	100	0	0	87:01:07
2	*.bbox.io	115	13	22.1 KB	2	1	1	13:20:47
3	*.ip-188-165-239.eu	21	0	0.0 KB	0	0	0	00:15:27
4	*.tampfl.dsl-w.verizon.net	2	0	0.0 KB	0	0	0	01:00:01
5	*.chello.nl	11	0	0.0 KB	0	0	0	04:14:05
TOTAL: 5		547	121	303.5 KB	102	1	1	105:51:30

NNRP auth users [Top 20]:

User	Conn
moussa	288
kev009	1
TOTAL: 2	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
63.142.120.8	10.225	6.892	0.000	73:18:24
tempe0.bbox.io	9.138	1.547	0.003	13:20:47
162.212.88.221	0.672	0.238	0.000	08:12:11
dhcp-089-099-203-254.chello.nl	0.823	0.165	0.000	04:14:05
202.89.176.220	30.132	6.176	0.760	03:59:35
50.96.157.120	0.140	0.070	0.000	01:30:55
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.172	0.016	0.000	01:00:01
ns342002.ip-188-165-239.eu	1.567	0.485	0.000	00:15:27
TOTAL: 8	52.869	15.589	0.763	105:51:30

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	100
ns342002.ip-188-165-239.eu	21
dhcp-089-099-203-254.chello.nl	11
162.212.88.221	8
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2
50.96.157.120	2
TOTAL: 6	144

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	8	0
tempe0.bbox.io	5	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
TOTAL: 3	15	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	120	99.2%
2	bit	1	0.8%
TOTAL: 2		121	100.0%

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

Newsgroup		Count
1	comp.os.vms	38
2	comp.arch	37
3	comp.arch.embedded	16
4	comp.os.os2.misc	11
5	comp.arch.fpga	5
6	comp.sys.ibm.pc.games.action	5
7	comp.ai.philosophy	4
8	comp.sys.ibm.ps2.hardware	1
9	comp.ai.fuzzy	1
10	bit.listserv.ibm-main	1
11	comp.sys.ibm.pc.hardware.storage	1
12	comp.answers	1
TOTAL: 12		121

Daily Usenet report for csiph.com

Jan 6 04:15:00 -- Jan 7 04:15:00