Daily Usenet report for csiph.com: Jan 9 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	24769	61.8%	5.3 MB	72.3%
innd	12159	30.3%	1.5 MB	20.6%
innfeed	2823	7.0%	495.0 KB	6.6%
nnrpd	338	0.8%	37.5 KB	0.5%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 5	40090	100.0%	7.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	323349	55.4%
Negative hits	235303	40.3%
Do not exist	24092	4.1%
Cache misses	565	0.1%
TOTAL: 4	583309	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.402	0.0%	43	0.000	9.349	41.500
article cleanup	00:00:00.135	0.0%	22453	0.000	0.006	0.031
article logging	00:00:00.319	0.0%	22947	0.000	0.014	0.136
article parse	00:00:02.270	0.0%	121378	0.000	0.019	0.439
article write	00:00:05.276	0.0%	18666	0.145	0.283	2.885
artlog/artcncl	00:00:00.001	0.0%	43	0.000	0.023	0.100
artlog/artparse	00:00:00.029	0.0%	1794	0.000	0.016	0.250
data move	00:00:00.194	0.0%	612612	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.011	0.0%	43	0.000	0.256	1.000
hishave/artcncl	00:00:00.005	0.0%	43	0.000	0.116	1.000
hishave/artparse	00:00:00.002	0.0%	1793	0.000	0.001	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.233	0.0%	584221	0.002	0.006	0.020
history sync	00:00:00.938	0.0%	377	0.000	2.488	6.667
history write	00:00:10.652	0.0%	22388	0.065	0.476	1.147
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.015	0.0%	1793	0.000	0.566	17.600
idle	23:59:04.864	99.9%	608758	55.497	141.838	399.211
nntp read	00:00:02.469	0.0%	609617	0.001	0.004	0.016
overview write	00:00:18.948	0.0%	18666	0.102	1.015	66.652
perl filter	00:00:36.152	0.0%	22270	1.288	1.623	2.838
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.277	0.0%	37333	0.000	0.007	0.029
TOTAL: 24:01:05.386	24:00:27.192	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.052	0.0%	18643	0.000	0.003	0.040
article prepare	00:00:00.001	0.0%	16252	0.000	0.000	0.003
article read	00:00:00.286	0.0%	1984	0.000	0.144	9.400
backlog stats	00:00:00.185	0.0%	350655	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.319	0.0%	267038	0.000	0.005	0.011
data write	00:00:01.522	0.0%	254044	0.002	0.006	0.014
idle	23:53:43.805	100.0%	350655	83.355	245.323	618.626
status file	00:00:00.154	0.0%	252	0.000	0.611	2.000
TOTAL: 23:53:53.267	23:53:47.324	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	13:33:00.717	100.0%	250	369.000	195122.868	600183.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	556	0.000	0.007	0.167
readart	00:00:00.184	0.0%	11	0.000	16.727	46.000
TOTAL: 13:33:11.154	13:33:00.905	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	6	61239	4084	54248	2907	6%	70:57:22
2	csiph-out.feeder.erje.net	1440	93471	3039	89806	626	3%	118:50:49
3	news-out.mixmin.net	3	19474	2517	16297	660	12%	24:08:32
4	feeder.eternal-september.org	5	19797	2193	17459	145	11%	23:04:09
5	news-out.aioe.org	3	18366	1788	16510	68	9%	24:08:58
6	news.redatomik.org	3	14409	1432	12903	74	9%	24:08:49
7	newsfeed.neva.ru	144	22215	1390	20312	513	6%	72:09:17
8	newsfeed.fu-berlin.de	16	17300	966	16122	212	5%	25:12:14
9	news.freedyn.net	3	21209	518	20420	271	2%	23:20:15
10	news.fcku.it	13	2279	312	1961	6	13%	22:14:41
11	newsfeeds.xmission.com	2	18001	149	17848	4	0%	23:59:46
12	nntp.pasdenom.info	2	17667	47	17611	9	0%	23:58:47
13	news.bbs.nz	2	18061	5	18056	0	0%	23:59:28
14	localhost	30	4	4	0	0	100%	00:00:01
15	xanadu-bbs.net	13	657	3	654	0	0%	23:25:44
16	news.swapon.de	2	48	0	48	0	0%	02:13:04
TOTAL: 16		1687	344197	18447	320255	5495	5%	525:51:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	13.4 MB	0.0 KB	182.4 MB	195.8 MB	6%	28.7 KB
2	csiph-out.feeder.erje.net	12.3 MB	177.4 KB	10.0 MB	22.6 MB	54%	6.3 KB
3	news-out.mixmin.net	6.6 MB	7.4 KB	1.7 MB	8.3 MB	79%	2.7 KB
4	feeder.eternal-september.org	6.5 MB	22.8 KB	2.1 MB	8.6 MB	75%	3.8 KB
5	news-out.aioe.org	4.3 MB	39.8 KB	450.2 KB	4.8 MB	90%	2.7 KB
6	news.redatomik.org	4.2 MB	2.7 KB	263.2 KB	4.4 MB	94%	3.0 KB
7	newsfeed.neva.ru	3.7 MB	44.8 KB	2.4 MB	6.1 MB	60%	3.3 KB
8	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	546.8 KB	2.7 MB	80%	2.3 KB
9	news.freedyn.net	1.9 MB	0.0 KB	4.1 MB	6.0 MB	32%	7.8 KB
10	news.fcku.it	528.3 KB	0.0 KB	101.9 KB	630.2 KB	83%	2.0 KB
11	newsfeeds.xmission.com	347.8 KB	2.8 KB	189.4 KB	540.1 KB	64%	3.5 KB
12	nntp.pasdenom.info	100.6 KB	0.0 KB	95.5 KB	196.1 KB	51%	3.5 KB
13	xanadu-bbs.net	52.9 KB	0.0 KB	0.0 KB	52.9 KB	100%	17.6 KB
14	news.bbs.nz	8.8 KB	0.0 KB	0.0 KB	8.8 KB	100%	1.8 KB
15	localhost	6.0 KB	0.0 KB	0.0 KB	6.0 KB	100%	1.5 KB
16	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		56.2 MB	297.7 KB	204.3 MB	260.8 MB	21%	11.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 9 04:15:01 - 04:59:59	271	1.5%	0.10	834.5 KB	1.4%	0.31
Jan 9 05:00:00 - 05:59:59	414	2.2%	0.12	1.7 MB	3.0%	0.49
Jan 9 06:00:00 - 06:59:59	468	2.5%	0.13	1.4 MB	2.5%	0.41
Jan 9 07:00:00 - 07:59:59	523	2.8%	0.15	1.8 MB	3.1%	0.50
Jan 9 08:00:00 - 08:59:59	1236	6.6%	0.34	3.4 MB	5.9%	0.96
Jan 9 09:00:00 - 09:59:59	783	4.2%	0.22	2.1 MB	3.7%	0.60
Jan 9 10:00:00 - 10:59:59	758	4.1%	0.21	2.2 MB	3.8%	0.61
Jan 9 11:00:00 - 11:59:59	860	4.6%	0.24	2.7 MB	4.8%	0.77
Jan 9 12:00:00 - 12:59:59	753	4.0%	0.21	2.1 MB	3.7%	0.60
Jan 9 13:00:00 - 13:59:59	846	4.5%	0.23	2.3 MB	4.1%	0.66
Jan 9 14:00:00 - 14:59:59	857	4.6%	0.24	2.4 MB	4.2%	0.67
Jan 9 15:00:00 - 15:59:59	1084	5.8%	0.30	3.7 MB	6.5%	1.05
Jan 9 16:00:00 - 16:59:59	1112	6.0%	0.31	3.6 MB	6.3%	1.02
Jan 9 17:00:00 - 17:59:59	1068	5.7%	0.30	3.2 MB	5.5%	0.90
Jan 9 18:00:00 - 18:59:59	1136	6.1%	0.32	3.4 MB	5.9%	0.95
Jan 9 19:00:00 - 19:59:59	1075	5.8%	0.30	3.3 MB	5.9%	0.95
Jan 9 20:00:00 - 20:59:59	928	5.0%	0.26	2.7 MB	4.7%	0.76
Jan 9 21:00:00 - 21:59:59	913	4.9%	0.25	2.7 MB	4.7%	0.77
Jan 9 22:00:00 - 22:59:59	784	4.2%	0.22	2.5 MB	4.5%	0.72
Jan 9 23:00:00 - 23:59:59	747	4.0%	0.21	2.7 MB	4.7%	0.76
Jan 10 00:00:00 - 00:59:59	565	3.0%	0.16	1.7 MB	3.0%	0.48
Jan 10 01:00:00 - 01:59:59	557	3.0%	0.15	1.7 MB	2.9%	0.47
Jan 10 02:00:00 - 02:59:59	485	2.6%	0.13	1.4 MB	2.5%	0.40
Jan 10 03:00:00 - 03:59:59	347	1.9%	0.10	1.3 MB	2.3%	0.37
Jan 10 04:00:00 - 04:15:00	83	0.4%	0.09	210.3 KB	0.4%	0.23
TOTAL: 23:59:59	18653	100.0%	0.22	56.8 MB	100.0%	0.67

Sites sending bad articles:

Server		Total	Group	Duplic	TooOld	Other
1	csiph-out.news.weretis.net	2988	164	0	0	2824
2	news-out.mixmin.net	676	2	1	0	673
3	newsfeed.fu-berlin.de	647	34	0	0	613
4	csiph-out.feeder.erje.net	636	101	37	0	498
5	newsfeed.neva.ru	513	167	18	0	328
6	news.freedyn.net	272	9	0	108	155
7	feeder.eternal-september.org	152	17	3	0	132
8	news.redatomik.org	82	0	1	0	81
9	news-out.aioe.org	74	54	5	0	15
10	news.swapon.de	10	0	0	0	10
11	nntp.pasdenom.info	9	0	0	6	3
12	news.fcku.it	6	5	0	0	1
13	newsfeeds.xmission.com	6	0	1	0	5
14	news.bbs.nz	2	2	0	0	0
TOTAL: 14		6073	555	66	114	5338

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	102
gmane.comp.gis.openstreetmap.tagging	56
gmane.linux.alsa.devel	53
spline.eisfair	30
lada.auto	27
mozilla.support.firefox	20
gmane.linux.kernel	18
lada.kitchen	14
alt.politics	12
mozilla.support.thunderbird	11
alt.politics.usa	11
lada.commerce	10
mozilla.test	10
alt.bbs.synchronet	10
mozilla.dev.l10n.new-locales	9
alt.survival	9
at.jobs	7
it-alt.sport.calcio.juventus	7
oesterreich.tratsch	7
alt.windows7.general	6
TOTAL: 90	555

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2373
EMP (phn path)	226
Too many newsgroups	97
User-issued cancel	86
Binary: misplaced mp4	68
Binary: misplaced rar	54
EMP (phl)	40
HTML Multipart	36
Binary: misplaced par2	16
Binary Image: misplaced jpg	9
Binary Payload	8
HTML post	7
Scoring filter (10)	6
Scoring filter (8)	5
Scoring filter (9)	3
Scoring filter (12)	2
Scoring filter (14)	2
Scoring filter (11)	2
EMP (phn nph)	2
Too many newsgroups (low_xpost)	2
TOTAL: 24	3048

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	50
	TOTAL: 1	50
Huge articles
	csiph-out.news.weretis.net	1565
	csiph-out.feeder.erje.net	163
	news.freedyn.net	25
	feeder.eternal-september.org	19
	news-out.mixmin.net	8
	newsfeeds.xmission.com	5
	news.redatomik.org	3
	news.swapon.de	2
	newsfeed.fu-berlin.de	1
	news.fcku.it	1
	TOTAL: 10	1792
Including strange strings
	newsfeed.fu-berlin.de	420
	csiph-out.news.weretis.net	25
	news-out.mixmin.net	16
	csiph-out.feeder.erje.net	10
	news.redatomik.org	8
	news.swapon.de	7
	news-out.aioe.org	6
	newsfeed.neva.ru	1
	feeder.eternal-september.org	1
	TOTAL: 9	494
TOTAL: 3		2336

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	20187	584	18069	0	0	2%	23:59:54
2	news.freedyn.net	18267	557	17560	1	0	3%	23:59:54
3	fu-berlin.de	17486	496	16990	0	0	2%	23:59:54
4	news.bbs.nz	35535	476	17643	15	1	1%	23:59:54
5	erje	18427	186	14995	293	0	1%	23:59:54
6	usenet.pasdenom.info	19037	83	17424	1100	11	0%	23:59:47
7	aioe	15779	67	15455	3	0	0%	23:59:16
8	news.redatomik.org	16935	17	16690	6	3	0%	23:59:54
9	neva.ru	16623	12	16280	131	0	0%	23:59:45
10	xmission	24614	9	17932	2	0	0%	23:59:54
11	news.swapon.de	1664	7	1650	0	0	0%	23:55:03
12	news.mixmin.net	15661	6	15419	2	0	0%	23:59:47
13	eternal-september	15979	5	15732	0	0	0%	23:59:54
14	weretis.net	13834	5	13721	0	0	0%	23:59:54
15	news.fcku.it	1928	0	1927	0	0	0%	22:07:55
TOTAL: 15		251956	2510	217487	1553	15	0%	358:00:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.3 MB	0.0 KB	5.3 MB	0.1 KB/s	9.3 KB	23:59:54
2	news.freedyn.net	5.2 MB	6.5 KB	5.2 MB	0.1 KB/s	9.6 KB	23:59:54
3	fu-berlin.de	5.1 MB	0.0 KB	5.1 MB	0.1 KB/s	10.5 KB	23:59:54
4	news.bbs.nz	1.2 MB	55.4 KB	1.3 MB	0.0 KB/s	2.7 KB	23:59:54
5	erje	459.0 KB	1.0 MB	1.4 MB	0.0 KB/s	3.1 KB	23:59:54
6	usenet.pasdenom.info	180.4 KB	6.1 MB	6.3 MB	0.1 KB/s	5.4 KB	23:59:47
7	aioe	151.7 KB	2.3 KB	154.1 KB	0.0 KB/s	2.2 KB	23:59:16
8	weretis.net	40.5 KB	0.0 KB	40.5 KB	0.0 KB/s	8.1 KB	23:59:54
9	news.redatomik.org	36.9 KB	21.0 KB	57.8 KB	0.0 KB/s	2.5 KB	23:59:54
10	neva.ru	27.4 KB	309.9 KB	337.3 KB	0.0 KB/s	2.4 KB	23:59:45
11	xmission	13.0 KB	5.7 KB	18.7 KB	0.0 KB/s	1.7 KB	23:59:54
12	news.swapon.de	10.8 KB	0.0 KB	10.8 KB	0.0 KB/s	1.5 KB	23:55:03
13	news.mixmin.net	9.5 KB	9.5 KB	19.0 KB	0.0 KB/s	2.4 KB	23:59:47
14	eternal-september	7.9 KB	0.0 KB	7.9 KB	0.0 KB/s	1.6 KB	23:59:54
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:07:55
TOTAL: 15		17.7 MB	7.5 MB	25.2 MB	0.0 KB/s	6.3 KB	358:00:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	71-82-226-171.dhcp.stpt.wi.charter.com	13	8	13.8 KB	6	4	4	03:34:52
2	a131033.upc-a.chello.nl	32	3	4.9 KB	3	0	0	08:28:08
TOTAL: 2		45	11	18.7 KB	9	4	4	13:33:10

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.stpt.wi.charter.com	13	8	13.8 KB	6	4	4	03:34:52
2	*.upc-a.chello.nl	32	3	4.9 KB	3	0	0	08:28:08
3	unresolved	1	0	0.0 KB	0	0	0	00:00:01
4	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
5	*.ph.ph.cox.net	2	0	0.0 KB	0	0	0	01:00:06
TOTAL: 5		49	11	18.7 KB	9	4	4	13:33:10

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
TOTAL: 1	4

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	2.326	0.483	0.000	08:28:08
71-82-226-171.dhcp.stpt.wi.charter.com	1.235	0.159	0.009	03:34:52
ip72-222-200-117.ph.ph.cox.net	0.222	0.039	0.000	01:00:06
c-67-160-62-214.hsd1.wa.comcast.net	0.063	0.016	0.000	00:30:00
94.162.226.246	0.069	0.008	0.000	00:00:01
TOTAL: 5	3.915	0.705	0.009	13:33:10

Curious NNRP server explorers [Top 20]:

System	Conn
ip72-222-200-117.ph.ph.cox.net	2
c-67-160-62-214.hsd1.wa.comcast.net	1
94.162.226.246	1
TOTAL: 3	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
71-82-226-171.dhcp.stpt.wi.charter.com	5	0
ip72-222-200-117.ph.ph.cox.net	2	0
c-67-160-62-214.hsd1.wa.comcast.net	1	0
TOTAL: 3	8	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	11
TOTAL: 1		11

Daily Usenet report for csiph.com

Jan 9 04:15:01 -- Jan 10 04:15:00