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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	41743	61.5%	8.0 MB	72.9%
innd	21813	32.1%	2.3 MB	21.1%
innfeed	3169	4.7%	546.5 KB	4.8%
nnrpd	1153	1.7%	130.0 KB	1.2%
nocem	42	0.1%	4.4 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	67921	100.0%	11.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	436798	55.6%
Negative hits	308034	39.2%
Do not exist	40705	5.2%
Cache misses	562	0.1%
TOTAL: 4	786099	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.448	0.0%	164	0.000	2.732	37.000
article cleanup	00:00:00.228	0.0%	40870	0.000	0.006	0.018
article logging	00:00:02.578	0.0%	41469	0.000	0.062	0.693
article parse	00:00:02.777	0.0%	124936	0.000	0.022	0.417
article write	00:01:36.783	0.1%	24240	0.168	3.993	21.933
artlog/artcncl	00:00:00.018	0.0%	149	0.000	0.121	0.600
artlog/artparse	00:00:00.030	0.0%	232	0.000	0.129	6.000
data move	00:00:00.295	0.0%	714567	0.000	0.000	0.011
hisgrep/artcncl	00:00:00.147	0.0%	163	0.000	0.902	30.000
hishave/artcncl	00:00:00.008	0.0%	164	0.000	0.049	6.000
hishave/artparse	00:00:00.000	0.0%	225	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:59.559	0.1%	791148	0.002	0.075	0.844
history sync	00:00:04.012	0.0%	359	0.000	11.175	86.667
history write	00:02:16.949	0.2%	40808	0.363	3.356	21.114
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.238	0.0%	225	0.000	5.502	60.000
idle	23:50:14.281	99.3%	712227	76.177	120.487	345.022
nntp read	00:00:03.279	0.0%	730350	0.002	0.004	0.007
overview write	00:02:52.711	0.2%	24240	0.674	7.125	67.080
perl filter	00:01:34.362	0.1%	40801	1.553	2.313	3.467
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.443	0.0%	48514	0.000	0.009	0.027
TOTAL: 24:00:55.431	23:59:50.146	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.068	0.0%	24213	0.000	0.003	0.024
article prepare	00:00:00.009	0.0%	47408	0.000	0.000	0.005
article read	00:00:02.453	0.0%	8825	0.058	0.278	8.976
backlog stats	00:00:00.264	0.0%	452206	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.371	0.0%	346820	0.004	0.007	0.014
data write	00:00:02.065	0.0%	329693	0.003	0.006	0.013
idle	23:53:55.436	100.0%	452206	121.583	190.257	495.700
status file	00:00:00.200	0.0%	284	0.000	0.704	3.000
TOTAL: 23:54:16.296	23:54:02.866	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.082	0.0%	99	0.000	0.828	14.000
idle	68:18:16.837	99.8%	580	360.480	423960.064	900225.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:16.233	0.0%	1639	0.000	9.904	34.032
readart	00:00:33.180	0.0%	19603	0.000	1.693	37.000
TOTAL: 68:28:24.738	68:19:06.332	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	128633	7883	110822	9928	6%	94:58:16
2	csiph-out.news.weretis.net	4	44123	4788	38302	1033	10%	40:18:43
3	feeder.eternal-september.org	3	35420	3329	29404	2687	9%	23:17:56
4	news-out.aioe.org	3	23885	2431	21408	46	10%	24:09:49
5	newsfeed.neva.ru	149	51847	1513	48065	2269	2%	72:40:05
6	news-out.mixmin.net	3	26021	1189	24580	252	4%	24:08:52
7	newsfeed.fu-berlin.de	48	23602	1085	22271	246	4%	31:09:28
8	newsfeeds.xmission.com	2	24335	908	23420	7	3%	25:47:14
9	news.redatomik.org	1	17457	433	17020	4	2%	16:37:46
10	news.freedyn.net	3	27302	171	27029	102	0%	24:09:50
11	xanadu-bbs.net	13	940	107	828	5	11%	27:15:26
12	news.fcku.it	1	10081	65	9937	79	0%	23:59:57
13	nntp.pasdenom.info	3	35139	39	35073	27	0%	24:08:58
14	news.bbs.geek.nz	1	17924	2	17921	1	0%	23:59:34
15	localhost	36	2	2	0	0	100%	00:00:01
16	news.swapon.de	1	0	0	0	0	0%	00:00:00
TOTAL: 16		1423	466711	23945	426080	16686	5%	476:41:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	25.1 MB	244.0 KB	60.1 MB	85.4 MB	29%	4.9 KB
2	csiph-out.news.weretis.net	13.5 MB	20.5 KB	12.4 MB	25.9 MB	51%	4.6 KB
3	feeder.eternal-september.org	8.9 MB	42.5 KB	37.3 MB	46.2 MB	19%	7.9 KB
4	news-out.aioe.org	5.7 MB	13.0 KB	75.6 KB	5.8 MB	98%	2.4 KB
5	news-out.mixmin.net	5.2 MB	12.5 KB	984.4 KB	6.2 MB	84%	4.4 KB
6	newsfeed.neva.ru	3.8 MB	120.4 KB	14.0 MB	17.9 MB	21%	4.8 KB
7	newsfeeds.xmission.com	2.7 MB	0.0 KB	500.8 KB	3.2 MB	84%	3.6 KB
8	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	670.3 KB	2.9 MB	77%	2.2 KB
9	news.redatomik.org	1.2 MB	5.5 KB	21.6 KB	1.3 MB	97%	2.9 KB
10	news.freedyn.net	645.5 KB	0.0 KB	1.3 MB	1.9 MB	32%	7.3 KB
11	xanadu-bbs.net	281.0 KB	2.7 KB	15.3 KB	299.1 KB	93%	2.7 KB
12	news.fcku.it	87.7 KB	0.0 KB	1.2 MB	1.3 MB	6%	9.1 KB
13	nntp.pasdenom.info	83.4 KB	5.5 KB	58.2 KB	147.1 KB	56%	2.2 KB
14	news.bbs.geek.nz	7.4 KB	2.7 KB	2.7 KB	12.8 KB	58%	4.3 KB
15	localhost	2.2 KB	0.0 KB	0.0 KB	2.2 KB	100%	1.1 KB
16	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		69.4 MB	469.2 KB	128.6 MB	198.5 MB	34%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 13 04:15:00 - 04:59:59	372	1.5%	0.14	1.3 MB	1.8%	0.48
Jan 13 05:00:00 - 05:59:59	587	2.4%	0.16	2.3 MB	3.2%	0.64
Jan 13 06:00:00 - 06:59:59	550	2.3%	0.15	1.7 MB	2.5%	0.49
Jan 13 07:00:00 - 07:59:59	753	3.1%	0.21	2.6 MB	3.7%	0.74
Jan 13 08:00:00 - 08:59:59	1000	4.1%	0.28	2.8 MB	3.9%	0.79
Jan 13 09:00:00 - 09:59:59	1156	4.8%	0.32	3.2 MB	4.5%	0.90
Jan 13 10:00:00 - 10:59:59	1210	5.0%	0.34	3.2 MB	4.6%	0.91
Jan 13 11:00:00 - 11:59:59	1066	4.4%	0.30	2.8 MB	4.0%	0.81
Jan 13 12:00:00 - 12:59:59	1200	5.0%	0.33	3.1 MB	4.4%	0.88
Jan 13 13:00:00 - 13:59:59	1295	5.4%	0.36	3.3 MB	4.7%	0.95
Jan 13 14:00:00 - 14:59:59	1221	5.0%	0.34	3.1 MB	4.4%	0.87
Jan 13 15:00:00 - 15:59:59	1445	6.0%	0.40	4.2 MB	6.0%	1.20
Jan 13 16:00:00 - 16:59:59	1424	5.9%	0.40	4.6 MB	6.6%	1.32
Jan 13 17:00:00 - 17:59:59	1373	5.7%	0.38	3.8 MB	5.4%	1.09
Jan 13 18:00:00 - 18:59:59	1393	5.8%	0.39	3.9 MB	5.5%	1.10
Jan 13 19:00:00 - 19:59:59	1315	5.4%	0.37	3.9 MB	5.5%	1.11
Jan 13 20:00:00 - 20:59:59	1174	4.9%	0.33	3.1 MB	4.5%	0.89
Jan 13 21:00:00 - 21:59:59	1298	5.4%	0.36	4.0 MB	5.6%	1.12
Jan 13 22:00:00 - 22:59:59	1098	4.5%	0.30	3.5 MB	5.0%	1.00
Jan 13 23:00:00 - 23:59:59	854	3.5%	0.24	2.8 MB	3.9%	0.79
Jan 14 00:00:00 - 00:59:59	770	3.2%	0.21	2.1 MB	3.0%	0.59
Jan 14 01:00:00 - 01:59:59	623	2.6%	0.17	1.8 MB	2.5%	0.50
Jan 14 02:00:00 - 02:59:59	463	1.9%	0.13	1.4 MB	1.9%	0.39
Jan 14 03:00:00 - 03:59:59	458	1.9%	0.13	1.6 MB	2.3%	0.45
Jan 14 04:00:00 - 04:15:00	106	0.4%	0.12	345.8 KB	0.5%	0.38
TOTAL: 24:00:00	24204	100.0%	0.28	70.3 MB	100.0%	0.83

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	csiph-out.feeder.erje.net	9952	329	32	0	9591
2	feeder.eternal-september.org	2785	51	7	0	2727
3	newsfeed.neva.ru	2261	534	13	0	1714
4	csiph-out.news.weretis.net	1074	179	5	0	890
5	newsfeed.fu-berlin.de	728	27	0	0	701
6	news-out.mixmin.net	272	2	3	0	267
7	news.freedyn.net	105	7	0	0	98
8	news.fcku.it	81	0	0	0	81
9	news-out.aioe.org	62	38	4	0	20
10	nntp.pasdenom.info	27	1	1	1	24
11	news.swapon.de	18	0	0	0	18
12	newsfeeds.xmission.com	10	0	0	0	10
13	news.redatomik.org	9	0	1	0	8
14	xanadu-bbs.net	3	0	1	0	2
15	news.bbs.geek.nz	1	0	1	0	0
TOTAL: 15		17388	1168	68	1	16151

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	361
ut.test	156
lada.auto	85
it-alt.sport.calcio.juventus	59
mpc.lists.freebsd.x11	40
alt.survival	29
alt.computer.workshop	24
mpc.lists.nanog	20
lada.kitchen	20
gmane.linux.alsa.devel	20
mpc.lists.freebsd.gecko	17
mozilla.support.thunderbird	13
mozilla.dev.l10n	13
cakewalk.audio	12
alt.windows7.general	11
mozilla.test	10
mpc.lists.freebsd.gnome	10
spline.eisfair.dev	9
oesterreich.tratsch	8
lada.commerce	8
TOTAL: 132	1168

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12771
EMP (phn path)	1666
EMP (phl)	566
EMP (phn nph)	143
Too many newsgroups	73
User-issued cancel	32
HTML Multipart	14
User-issued spam cancel	14
Binary Payload	12
Scoring filter (10)	5
Scoring filter (12)	5
Scoring filter (8)	5
HTML post	5
Scoring filter (9)	2
Binary Image: misplaced jpg	1
HTML Image Tags	1
Scoring filter (14)	1
TOTAL: 17	15316

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	35	0	36	36
TOTAL: 1	35	0	36	36

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	feeder.eternal-september.org	96
	csiph-out.news.weretis.net	77
	news.fcku.it	16
	news.freedyn.net	12
	news-out.mixmin.net	11
	newsfeeds.xmission.com	6
	news.redatomik.org	4
	newsfeed.fu-berlin.de	2
	news.swapon.de	1
	TOTAL: 9	225
Including strange strings
	newsfeed.fu-berlin.de	468
	csiph-out.feeder.erje.net	26
	csiph-out.news.weretis.net	26
	feeder.eternal-september.org	24
	news-out.aioe.org	18
	news.swapon.de	15
	news-out.mixmin.net	13
	newsfeed.neva.ru	7
	newsfeeds.xmission.com	4
	news.redatomik.org	1
	TOTAL: 11	603
No colon-space in header
	csiph-out.feeder.erje.net	6
	TOTAL: 1	6
TOTAL: 3		834

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.geek.nz	39712	6801	16597	62	0	17%	23:50:09
2	news.freedyn.net	24863	834	23132	1	0	3%	23:50:09
3	xanadu-bbs	27079	747	23304	0	0	2%	23:50:06
4	fu-berlin.de	22726	649	22077	0	0	2%	23:50:09
5	erje	17153	366	15813	13	0	2%	23:50:09
6	usenet.pasdenom.info	27667	160	22037	1930	0	0%	23:54:06
7	xmission	27344	65	22463	2	0	0%	23:50:09
8	aioe	20737	53	19571	0	0	0%	23:59:38
9	news.redatomik.org	23704	51	22851	0	0	0%	23:50:09
10	eternal-september	21470	15	19833	0	0	0%	23:55:45
11	weretis.net	17715	14	17366	2	0	0%	23:57:59
12	news.swapon.de	2288	4	2207	4	0	0%	23:50:09
13	neva.ru	22878	3	21514	56	0	0%	23:50:09
14	news.mixmin.net	22249	2	21416	0	0	0%	23:52:35
15	news.fcku.it	2893	1	2840	0	0	0%	22:50:07
16	bwh	0	0	0	0	23455	0%	23:50:05
17	au2pb	0	0	0	0	23461	0%	23:50:05
TOTAL: 17		320478	9765	273021	2070	46916	3%	404:41:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.bbs.geek.nz	18.5 MB	222.4 KB	18.7 MB	0.2 KB/s	2.8 KB	23:50:09
2	news.freedyn.net	5.8 MB	1.0 KB	5.8 MB	0.1 KB/s	7.1 KB	23:50:09
3	xanadu-bbs	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	7.7 KB	23:50:06
4	fu-berlin.de	5.3 MB	0.0 KB	5.3 MB	0.1 KB/s	8.3 KB	23:50:09
5	erje	1.1 MB	52.3 KB	1.2 MB	0.0 KB/s	3.2 KB	23:50:09
6	usenet.pasdenom.info	483.4 KB	7.4 MB	7.8 MB	0.1 KB/s	3.8 KB	23:54:06
7	aioe	210.9 KB	0.0 KB	210.9 KB	0.0 KB/s	4.0 KB	23:59:38
8	xmission	180.2 KB	3.9 KB	184.1 KB	0.0 KB/s	2.7 KB	23:50:09
9	news.redatomik.org	148.8 KB	0.0 KB	148.8 KB	0.0 KB/s	2.9 KB	23:50:09
10	eternal-september	38.7 KB	0.0 KB	38.7 KB	0.0 KB/s	2.6 KB	23:55:45
11	weretis.net	37.1 KB	35.4 KB	72.5 KB	0.0 KB/s	4.5 KB	23:57:59
12	news.swapon.de	5.4 KB	28.1 KB	33.5 KB	0.0 KB/s	4.2 KB	23:50:09
13	neva.ru	4.0 KB	227.6 KB	231.6 KB	0.0 KB/s	3.9 KB	23:50:09
14	news.mixmin.net	2.2 KB	0.0 KB	2.2 KB	0.0 KB/s	1.1 KB	23:52:35
15	news.fcku.it	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	22:50:07
16	bwh	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
17	au2pb	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
TOTAL: 17		37.4 MB	7.9 MB	45.3 MB	0.0 KB/s	3.9 KB	404:41:38

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	2	7	19.1 KB	2	0	0	00:06:47
2	68-185-181-64.dhcp.mdsn.wi.charter.com	16	2	2.2 KB	2	2	1	04:21:24
3	tempe0.bbox.io	12	1	1.0 KB	1	0	0	04:24:32
TOTAL: 3		30	10	22.3 KB	5	2	1	68:28:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.19-1.cable.virginm.net	2	7	19.1 KB	2	0	0	00:06:47
2	*.dhcp.mdsn.wi.charter.com	16	2	2.2 KB	2	2	1	04:21:24
3	*.bbox.io	12	1	1.0 KB	1	0	0	04:24:32
4	unresolved	100	0	0.0 KB	0	0	0	48:57:10
5	*.136.22.98.dynamic.ip.windstream.net	1	0	0.0 KB	0	0	0	00:00:00
6	*.cust.centrio.cz	6	0	0.0 KB	0	0	0	01:00:44
7	*.chello.nl	59	0	0.0 KB	0	0	0	09:37:42
TOTAL: 7		196	10	22.3 KB	5	2	1	68:28:22

NNRP auth users [Top 20]:

User	Conn
ctomczyk	3
ohlandl	1
TOTAL: 2	4

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
216.239.174.218	7.499	1.523	0.000	48:56:11
dhcp-089-099-203-254.chello.nl	4.298	0.831	0.000	09:37:42
tempe0.bbox.io	1.108	0.215	0.000	04:24:32
68-185-181-64.dhcp.mdsn.wi.charter.com	1.238	0.313	0.010	04:21:24
194-73-239-109.cust.centrio.cz	0.466	0.137	0.016	01:00:44
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net	0.197	0.045	0.000	00:06:47
196.52.20.224	0.692	2.164	0.000	00:00:59
TOTAL: 7	15.498	5.228	0.026	68:28:22

Curious NNRP server explorers [Top 20]:

System	Conn
216.239.174.218	99
dhcp-089-099-203-254.chello.nl	59
194-73-239-109.cust.centrio.cz	6
h60.136.22.98.dynamic.ip.windstream.net	1
196.52.20.224	1
TOTAL: 5	166

NNRP client timeouts [Top 20]:

System	Conn	Peer
216.239.174.218	96	0
tempe0.bbox.io	7	0
68-185-181-64.dhcp.mdsn.wi.charter.com	5	0
194-73-239-109.cust.centrio.cz	2	0
TOTAL: 4	110	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	7	70.0%
2	comp	3	30.0%
TOTAL: 2		10	100.0%

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

Newsgroup		Count
1	alt.os.linux.mint	7
2	comp.sys.ibm.ps2.hardware	3
TOTAL: 2		10

Daily Usenet report for csiph.com

Jan 13 04:15:00 -- Jan 14 04:15:00