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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	37674	59.1%	7.1 MB	70.4%
innd	21523	33.8%	2.3 MB	23.1%
innfeed	3237	5.1%	519.4 KB	5.0%
nnrpd	1243	1.9%	138.5 KB	1.3%
nocem	72	0.1%	8.0 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	63750	100.0%	10.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	380884	56.1%
Negative hits	260433	38.4%
Do not exist	36791	5.4%
Cache misses	604	0.1%
TOTAL: 4	678712	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.636	0.0%	244	0.000	2.607	26.000
article cleanup	00:00:00.255	0.0%	36761	0.000	0.007	0.028
article logging	00:00:03.338	0.0%	37249	0.000	0.090	0.759
article parse	00:00:02.977	0.0%	115069	0.003	0.026	0.366
article write	00:02:13.587	0.2%	20940	0.123	6.380	17.599
artlog/artcncl	00:00:00.011	0.0%	199	0.000	0.055	0.500
artlog/artparse	00:00:00.032	0.0%	303	0.000	0.106	1.211
data move	00:00:00.214	0.0%	635163	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.184	0.0%	243	0.000	0.757	10.000
hishave/artcncl	00:00:00.002	0.0%	244	0.000	0.008	0.059
hishave/artparse	00:00:00.001	0.0%	299	0.000	0.003	0.067
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:30.958	0.1%	681566	0.001	0.133	0.435
history sync	00:00:03.971	0.0%	374	0.000	10.618	57.500
history write	00:02:40.097	0.2%	36700	0.101	4.362	11.412
hiswrite/artcncl	00:00:00.015	0.0%	1	15.000	15.000	15.000
hiswrite/artparse	00:00:01.678	0.0%	299	0.000	5.612	28.000
idle	23:48:21.190	99.1%	623891	80.717	137.366	399.432
nntp read	00:00:02.959	0.0%	647174	0.002	0.005	0.008
overview write	00:03:28.973	0.2%	20940	0.488	9.980	148.187
perl filter	00:01:24.492	0.1%	36634	1.527	2.306	3.537
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.385	0.0%	41945	0.000	0.009	0.031
TOTAL: 24:01:02.687	23:59:55.955	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.047	0.0%	21111	0.000	0.002	0.023
article prepare	00:00:00.005	0.0%	40688	0.000	0.000	0.004
article read	00:00:01.940	0.0%	7519	0.000	0.258	11.157
backlog stats	00:00:00.233	0.0%	392845	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.889	0.0%	300008	0.003	0.006	0.013
data write	00:00:01.749	0.0%	283167	0.002	0.006	0.016
idle	23:53:14.907	100.0%	392845	133.909	218.903	814.262
status file	00:00:00.468	0.0%	302	0.000	1.550	182.000
TOTAL: 23:53:30.792	23:53:21.238	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.459	0.0%	101	0.000	4.545	97.000
idle	74:34:24.485	99.8%	658	187.500	408000.737	900276.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.013	0.0%	1492	0.000	0.009	0.250
readart	00:00:00.860	0.0%	60	0.000	14.333	61.000
TOTAL: 74:43:50.506	74:34:25.817	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.feeder.erje.net	1152	115863	6566	100331	8966	5%	95:17:17
2	csiph-out.news.weretis.net	6	43552	4614	37654	1284	10%	46:26:54
3	feeder.eternal-september.org	3	32827	3171	26598	3058	9%	24:49:00
4	news-out.aioe.org	2	20080	1653	18374	53	8%	24:09:58
5	news-out.mixmin.net	4	23325	1111	21863	351	4%	24:16:32
6	newsfeed.fu-berlin.de	37	21876	1092	20511	273	4%	30:53:42
7	newsfeeds.xmission.com	3	20710	941	19765	4	4%	25:47:39
8	newsfeed.neva.ru	143	48485	916	45571	1998	1%	72:06:56
9	news.redatomik.org	3	18938	383	18531	24	2%	23:18:17
10	news.swapon.de	2	4399	152	4247	0	3%	45:02:00
11	news.freedyn.net	3	23994	149	23701	144	0%	24:09:47
12	news.fcku.it	2	8268	134	8093	41	1%	23:59:15
13	xanadu-bbs.net	26	543	51	488	4	9%	22:16:35
14	nntp.pasdenom.info	7	24292	31	24259	2	0%	23:54:11
15	localhost	38	4	4	0	0	100%	00:00:00
16	news.bbs.geek.nz	1	14249	0	14249	0	0%	23:59:31
TOTAL: 16		1432	421405	20968	384235	16202	4%	530:27:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	20.9 MB	365.6 KB	48.7 MB	70.0 MB	29%	4.6 KB
2	csiph-out.news.weretis.net	13.8 MB	0.0 KB	17.7 MB	31.5 MB	43%	5.5 KB
3	feeder.eternal-september.org	8.5 MB	68.7 KB	40.5 MB	49.1 MB	17%	8.1 KB
4	news-out.mixmin.net	5.1 MB	0.0 KB	1.3 MB	6.4 MB	79%	4.4 KB
5	news-out.aioe.org	4.2 MB	11.0 KB	75.0 KB	4.3 MB	98%	2.6 KB
6	newsfeeds.xmission.com	2.7 MB	0.0 KB	244.0 KB	2.9 MB	91%	3.2 KB
7	newsfeed.neva.ru	2.4 MB	20.6 KB	13.2 MB	15.7 MB	15%	5.5 KB
8	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	664.9 KB	3.0 MB	78%	2.3 KB
9	news.redatomik.org	1.0 MB	0.0 KB	87.8 KB	1.1 MB	92%	2.8 KB
10	news.freedyn.net	555.6 KB	0.0 KB	4.6 MB	5.1 MB	10%	18.0 KB
11	news.swapon.de	416.5 KB	0.0 KB	0.0 KB	416.5 KB	100%	2.7 KB
12	news.fcku.it	260.8 KB	0.0 KB	729.5 KB	990.3 KB	26%	5.7 KB
13	xanadu-bbs.net	128.4 KB	0.0 KB	16.4 KB	144.8 KB	88%	2.6 KB
14	nntp.pasdenom.info	71.9 KB	0.0 KB	22.3 KB	94.2 KB	76%	2.9 KB
15	localhost	6.5 KB	0.0 KB	0.0 KB	6.5 KB	100%	1.6 KB
16	news.bbs.geek.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		62.4 MB	466.0 KB	127.8 MB	190.7 MB	32%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 15 04:15:00 - 04:59:59	313	1.5%	0.12	1007.1 KB	1.6%	0.37
Jan 15 05:00:00 - 05:59:59	336	1.6%	0.09	1.0 MB	1.6%	0.29
Jan 15 06:00:00 - 06:59:59	456	2.2%	0.13	1.6 MB	2.6%	0.46
Jan 15 07:00:00 - 07:59:59	426	2.0%	0.12	1.3 MB	2.1%	0.37
Jan 15 08:00:00 - 08:59:59	676	3.2%	0.19	1.9 MB	3.1%	0.54
Jan 15 09:00:00 - 09:59:59	937	4.5%	0.26	2.8 MB	4.4%	0.79
Jan 15 10:00:00 - 10:59:59	1052	5.0%	0.29	3.7 MB	6.0%	1.06
Jan 15 11:00:00 - 11:59:59	1006	4.8%	0.28	3.1 MB	5.0%	0.88
Jan 15 12:00:00 - 12:59:59	900	4.3%	0.25	2.3 MB	3.6%	0.64
Jan 15 13:00:00 - 13:59:59	985	4.7%	0.27	2.7 MB	4.3%	0.77
Jan 15 14:00:00 - 14:59:59	1062	5.1%	0.29	3.2 MB	5.1%	0.91
Jan 15 15:00:00 - 15:59:59	1205	5.8%	0.33	4.5 MB	7.2%	1.28
Jan 15 16:00:00 - 16:59:59	1112	5.3%	0.31	3.5 MB	5.6%	0.99
Jan 15 17:00:00 - 17:59:59	1237	5.9%	0.34	3.2 MB	5.2%	0.91
Jan 15 18:00:00 - 18:59:59	1222	5.8%	0.34	3.2 MB	5.1%	0.91
Jan 15 19:00:00 - 19:59:59	1287	6.1%	0.36	3.5 MB	5.6%	0.99
Jan 15 20:00:00 - 20:59:59	1242	5.9%	0.34	3.7 MB	5.9%	1.05
Jan 15 21:00:00 - 21:59:59	1150	5.5%	0.32	3.2 MB	5.1%	0.91
Jan 15 22:00:00 - 22:59:59	984	4.7%	0.27	2.6 MB	4.2%	0.74
Jan 15 23:00:00 - 23:59:59	785	3.7%	0.22	2.4 MB	3.8%	0.67
Jan 16 00:00:00 - 00:59:59	701	3.3%	0.19	2.0 MB	3.2%	0.56
Jan 16 01:00:00 - 01:59:59	687	3.3%	0.19	2.2 MB	3.5%	0.62
Jan 16 02:00:00 - 02:59:59	536	2.6%	0.15	1.6 MB	2.5%	0.45
Jan 16 03:00:00 - 03:59:59	541	2.6%	0.15	1.8 MB	2.9%	0.51
Jan 16 04:00:00 - 04:15:00	111	0.5%	0.12	442.2 KB	0.7%	0.49
TOTAL: 24:00:00	20949	100.0%	0.24	62.3 MB	100.0%	0.74

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.feeder.erje.net	8984	170	50	1	8763
2	feeder.eternal-september.org	2963	61	8	0	2894
3	newsfeed.neva.ru	1975	239	4	0	1732
4	csiph-out.news.weretis.net	1310	123	0	0	1187
5	newsfeed.fu-berlin.de	625	47	0	0	578
6	news-out.mixmin.net	360	0	0	0	360
7	news.freedyn.net	144	6	0	0	138
8	news-out.aioe.org	60	48	3	0	9
9	news.fcku.it	41	0	0	0	41
10	news.redatomik.org	31	0	0	0	31
11	news.swapon.de	19	0	0	0	19
12	newsfeeds.xmission.com	8	0	0	0	8
13	xanadu-bbs.net	5	0	0	0	5
14	nntp.pasdenom.info	1	0	0	0	1
TOTAL: 14		16526	694	65	1	15766

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	135
it-alt.sport.calcio.juventus	99
lada.auto	56
ut.test	36
spline.eisfair	31
alt.survival	21
lada.commerce	20
mpc.lists.freebsd.ports.bugs	16
mtl.forsale-vendre	16
lada.kitchen	14
mozilla.test	12
alt.computer.workshop	11
spline.eisfair.dev	10
mozilla.support.firefox	8
mozilla.support.seamonkey	8
mpc.lists.freebsd.x11	8
alt.politics	7
abg.test	7
alt.2600	7
gmane.comp.bacula.user	7
TOTAL: 98	694

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

Groups	Count
comp.binaries.cbm	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12676
EMP (phn path)	1211
EMP (phl)	696
Too many newsgroups	123
EMP (phn nph)	117
User-issued cancel	15
Binary Payload	13
HTML Multipart	10
User-issued spam cancel	9
Scoring filter (12)	7
Scoring filter (8)	6
Scoring filter (10)	5
Binary Image: misplaced jpg	5
Scoring filter (11)	5
HTML post	4
Scoring filter (14)	3
HTML Image Tags	2
Scoring filter (9)	2
Scoring filter (17)	1
Scoring filter (13)	1
TOTAL: 22	14913

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	62
	TOTAL: 1	62
Huge articles
	feeder.eternal-september.org	122
	csiph-out.news.weretis.net	93
	news.freedyn.net	50
	news.fcku.it	16
	news-out.mixmin.net	10
	newsfeeds.xmission.com	4
	news.redatomik.org	2
	newsfeed.fu-berlin.de	2
	TOTAL: 8	299
Including strange strings
	newsfeed.fu-berlin.de	357
	csiph-out.feeder.erje.net	33
	feeder.eternal-september.org	24
	csiph-out.news.weretis.net	22
	news.swapon.de	19
	newsfeed.neva.ru	12
	news-out.mixmin.net	9
	news-out.aioe.org	6
	newsfeeds.xmission.com	4
	news.redatomik.org	2
	TOTAL: 10	488
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		850

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	33874	6029	14061	84	0	17%	23:50:08
2	news.freedyn.net	21291	842	19939	1	0	3%	23:58:31
3	xanadu-bbs	23492	725	20162	1	0	3%	23:59:49
4	fu-berlin.de	19610	678	18932	0	0	3%	23:59:51
5	usenet.pasdenom.info	24689	230	19288	1382	137	0%	23:59:54
6	news.redatomik.org	20831	43	19732	4	64	0%	23:55:33
7	aioe	18320	41	17466	1	0	0%	23:58:03
8	eternal-september	18228	21	16818	1	0	0%	23:59:54
9	xmission	22721	19	19278	2	0	0%	23:59:54
10	weretis.net	14781	13	14336	1	1	0%	23:58:22
11	news.swapon.de	2034	6	1999	0	0	0%	23:57:15
12	news.mixmin.net	19270	6	18611	0	20	0%	23:59:54
13	neva.ru	19981	1	19011	27	0	0%	23:59:49
14	erje	14461	1	14272	16	0	0%	23:58:21
15	bwh	0	0	0	0	20173	0%	23:50:05
16	news.fcku.it	1689	0	1668	0	0	0%	21:30:54
17	au2pb	0	0	0	0	20251	0%	23:58:42
TOTAL: 17		275272	8655	235573	1520	40646	3%	404:54:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	16.7 MB	274.2 KB	16.9 MB	0.2 KB/s	2.8 KB	23:50:08
2	news.freedyn.net	5.4 MB	1.8 KB	5.4 MB	0.1 KB/s	6.5 KB	23:58:31
3	xanadu-bbs	5.1 MB	4.1 KB	5.1 MB	0.1 KB/s	7.3 KB	23:59:49
4	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	7.5 KB	23:59:51
5	usenet.pasdenom.info	606.4 KB	6.1 MB	6.7 MB	0.1 KB/s	4.2 KB	23:59:54
6	news.redatomik.org	110.4 KB	8.6 KB	119.0 KB	0.0 KB/s	2.5 KB	23:55:33
7	aioe	103.0 KB	10.8 KB	113.8 KB	0.0 KB/s	2.7 KB	23:58:03
8	eternal-september	74.9 KB	1.3 KB	76.2 KB	0.0 KB/s	3.5 KB	23:59:54
9	xmission	45.2 KB	4.1 KB	49.3 KB	0.0 KB/s	2.3 KB	23:59:54
10	weretis.net	31.1 KB	35.7 KB	66.7 KB	0.0 KB/s	4.8 KB	23:58:22
11	news.mixmin.net	16.4 KB	0.0 KB	16.4 KB	0.0 KB/s	2.7 KB	23:59:54
12	news.swapon.de	9.6 KB	0.0 KB	9.6 KB	0.0 KB/s	1.6 KB	23:57:15
13	neva.ru	1.9 KB	75.4 KB	77.2 KB	0.0 KB/s	2.8 KB	23:59:49
14	erje	1.9 KB	87.0 KB	88.9 KB	0.0 KB/s	5.2 KB	23:58:21
15	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
16	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:30:54
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:42
TOTAL: 17		33.1 MB	6.6 MB	39.7 MB	0.0 KB/s	4.0 KB	404:54:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	tempe0.bbox.io	30	50	97.9 KB	10	3	3	07:23:04
2	dhcp-089-099-203-254.chello.nl	44	4	5.4 KB	4	0	0	10:08:00
3	68-185-181-64.dhcp.mdsn.wi.charter.com	13	4	6.5 KB	3	1	1	04:07:26
4	194-73-239-109.cust.centrio.cz	9	2	4.8 KB	2	0	0	03:00:43
TOTAL: 4		96	60	114.6 KB	19	4	4	74:43:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.bbox.io	30	50	97.9 KB	10	3	3	07:23:04
2	*.dhcp.mdsn.wi.charter.com	13	4	6.5 KB	3	1	1	04:07:26
3	*.chello.nl	44	4	5.4 KB	4	0	0	10:08:00
4	*.cust.centrio.cz	9	2	4.8 KB	2	0	0	03:00:43
5	*.132.151.153.ap.dti.ne.jp	2	0	0.0 KB	0	0	0	00:02:11
6	unresolved	101	0	0.0 KB	0	0	0	50:02:21
TOTAL: 6		199	60	114.6 KB	19	4	4	74:43:48

NNRP auth users [Top 20]:

User	Conn
ctomczyk	6
kev009	3
ohlandl	1
TOTAL: 3	10

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.653	1.485	0.000	50:02:21
dhcp-089-099-203-254.chello.nl	3.496	0.665	0.000	10:08:00
tempe0.bbox.io	2.500	0.609	0.011	07:23:04
68-185-181-64.dhcp.mdsn.wi.charter.com	1.154	0.258	0.004	04:07:26
194-73-239-109.cust.centrio.cz	0.861	0.160	0.026	03:00:43
240.132.151.153.ap.dti.ne.jp	0.184	0.039	0.000	00:02:11
TOTAL: 6	15.848	3.216	0.041	74:43:48

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	101
240.132.151.153.ap.dti.ne.jp	2
TOTAL: 2	103

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	98	0
tempe0.bbox.io	11	0
68-185-181-64.dhcp.mdsn.wi.charter.com	7	0
194-73-239-109.cust.centrio.cz	6	0
TOTAL: 4	122	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	56	93.3%
2	muc	4	6.7%
TOTAL: 2		60	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	54
2	muc.lists.freebsd.stable	2
3	comp.lang.javascript	2
4	muc.lists.freebsd.current	2
TOTAL: 4		60

Daily Usenet report for csiph.com

Jan 15 04:15:00 -- Jan 16 04:15:00