Daily Usenet report for csiph.com: Aug 12 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19328	45.0%	4.2 MB	60.3%
innd	11642	27.1%	1.5 MB	21.5%
nnrpd	9038	21.0%	822.4 KB	11.6%
innfeed	2889	6.7%	470.5 KB	6.6%
nocem	53	0.1%	5.8 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	42951	100.0%	7.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	261707	57.8%
Negative hits	172287	38.1%
Do not exist	18520	4.1%
Cache misses	184	0.0%
TOTAL: 4	452698	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.192	0.0%	114	0.000	1.684	12.700
article cleanup	00:00:00.124	0.0%	18259	0.000	0.007	0.041
article logging	00:00:00.292	0.0%	18801	0.000	0.016	0.046
article parse	00:00:00.729	0.0%	50270	0.000	0.015	0.052
article write	00:00:04.803	0.0%	15122	0.172	0.318	2.113
artlog/artcncl	00:00:00.002	0.0%	80	0.000	0.025	1.000
artlog/artparse	00:00:00.007	0.0%	313	0.000	0.022	0.250
data move	00:00:00.152	0.0%	430677	0.000	0.000	0.006
hisgrep/artcncl	00:00:00.006	0.0%	110	0.000	0.055	0.500
hishave/artcncl	00:00:00.001	0.0%	114	0.000	0.009	0.100
hishave/artparse	00:00:00.000	0.0%	313	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:11.978	0.0%	453174	0.001	0.026	2.663
history sync	00:00:00.409	0.0%	365	0.000	1.121	4.000
history write	00:00:06.200	0.0%	18240	0.054	0.340	0.614
hiswrite/artcncl	00:00:00.001	0.0%	4	0.000	0.250	0.500
hiswrite/artparse	00:00:00.096	0.0%	311	0.000	0.309	2.000
idle	23:49:06.837	99.9%	434796	86.779	197.212	660.980
nntp read	00:00:02.486	0.0%	435541	0.002	0.006	0.026
overview write	00:00:11.269	0.0%	15122	0.115	0.745	5.419
perl filter	00:00:35.185	0.0%	18231	1.355	1.930	3.143
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.271	0.0%	30291	0.000	0.009	0.035
TOTAL: 23:50:55.738	23:50:21.040	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.043	0.0%	15461	0.000	0.003	0.026
article prepare	00:00:00.003	0.0%	19136	0.000	0.000	0.008
article read	00:00:00.489	0.0%	2418	0.000	0.202	4.556
backlog stats	00:00:00.161	0.0%	312095	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.540	0.0%	226292	0.002	0.007	0.014
data write	00:00:01.487	0.0%	213261	0.002	0.007	0.014
idle	23:54:25.967	100.0%	312095	159.955	275.768	714.855
status file	00:00:00.161	0.0%	245	0.000	0.657	2.000
TOTAL: 23:54:37.380	23:54:29.851	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.496	0.0%	10268	0.029	0.048	0.120
idle	54:06:37.535	100.0%	18734	36.333	10398.075	600028.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.444	0.0%	38226	0.000	0.012	3.750
readart	00:00:04.689	0.0%	9566	0.000	0.490	37.000
TOTAL: 54:07:50.564	54:06:43.164	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	3	30312	3377	26639	296	11%	48:27:10
2	csiph-out.feeder.erje.net	1728	92919	2821	89091	1007	3%	142:26:15
3	feeder.eternal-september.org	2	15924	1926	13585	413	12%	22:44:00
4	newsfeeds.xmission.com	2	14492	1686	12793	13	11%	24:34:00
5	news-out.aioe.org	2	14563	1615	12914	34	11%	24:09:56
6	news-out.mixmin.net	3	15510	1156	13737	617	7%	24:09:08
7	newsfeed.fu-berlin.de	9	13970	1028	12780	162	7%	24:54:15
8	newsfeed.neva.ru	148	17204	473	16193	538	2%	71:28:38
9	news.redatomik.org	2	14181	468	13690	23	3%	23:59:41
10	news.fcku.it	1	6208	317	5590	301	5%	23:59:58
11	news.freedyn.net	4	26811	159	26572	80	0%	38:16:51
12	nntp.pasdenom.info	3	13305	79	13210	16	0%	23:59:01
13	news.swapon.de	4	1565	63	1502	0	4%	24:01:54
14	news.bbs.nz	2	14781	6	14773	2	0%	23:59:51
15	localhost	34	2	2	0	0	100%	00:00:02
16	xanadu-bbs.net	31	446	0	446	0	0%	23:25:12
TOTAL: 16		1978	292193	15176	273515	3502	5%	564:35:52

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	11.9 MB	10.9 KB	10.8 MB	22.7 MB	52%	6.1 KB
2	csiph-out.news.weretis.net	11.0 MB	4.5 KB	6.8 MB	17.8 MB	61%	5.0 KB
3	newsfeeds.xmission.com	5.6 MB	0.0 KB	619.8 KB	6.2 MB	90%	3.7 KB
4	feeder.eternal-september.org	5.1 MB	2.3 KB	2.7 MB	7.8 MB	65%	3.4 KB
5	news-out.aioe.org	4.0 MB	2.2 KB	162.8 KB	4.1 MB	96%	2.6 KB
6	news-out.mixmin.net	3.1 MB	2.2 KB	1.4 MB	4.5 MB	69%	2.6 KB
7	newsfeed.fu-berlin.de	2.4 MB	2.2 KB	417.6 KB	2.8 MB	85%	2.4 KB
8	news.redatomik.org	1.3 MB	2.2 KB	495.7 KB	1.8 MB	73%	3.8 KB
9	newsfeed.neva.ru	1.3 MB	6.7 KB	1.7 MB	3.0 MB	42%	3.1 KB
10	news.fcku.it	921.5 KB	2.2 KB	2.7 MB	3.6 MB	25%	5.9 KB
11	news.freedyn.net	392.6 KB	2.2 KB	307.1 KB	701.9 KB	55%	2.9 KB
12	nntp.pasdenom.info	161.3 KB	2.3 KB	32.3 KB	195.9 KB	82%	2.1 KB
13	news.swapon.de	156.6 KB	0.0 KB	0.0 KB	156.6 KB	100%	2.5 KB
14	news.bbs.nz	13.2 KB	2.2 KB	3.3 KB	18.7 KB	70%	2.3 KB
15	localhost	2.4 KB	0.0 KB	0.0 KB	2.4 KB	100%	1.2 KB
16	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 16		47.2 MB	42.2 KB	28.1 MB	75.3 MB	62%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 12 04:15:00 - 04:59:59	216	1.4%	0.08	876.3 KB	1.8%	0.32
Aug 12 05:00:00 - 05:59:59	286	1.9%	0.08	970.4 KB	2.0%	0.27
Aug 12 06:00:00 - 06:59:59	536	3.5%	0.15	2.4 MB	5.1%	0.69
Aug 12 07:00:00 - 07:59:59	760	5.0%	0.21	3.1 MB	6.5%	0.88
Aug 12 08:00:00 - 08:59:59	752	4.9%	0.21	2.0 MB	4.3%	0.58
Aug 12 09:00:00 - 09:59:59	635	4.2%	0.18	1.8 MB	3.8%	0.51
Aug 12 10:00:00 - 10:59:59	662	4.3%	0.18	1.9 MB	4.0%	0.55
Aug 12 11:00:00 - 11:59:59	645	4.2%	0.18	1.6 MB	3.3%	0.45
Aug 12 12:00:00 - 12:59:59	676	4.4%	0.19	1.9 MB	4.1%	0.55
Aug 12 13:00:00 - 13:59:59	848	5.6%	0.24	2.6 MB	5.5%	0.75
Aug 12 14:00:00 - 14:59:59	914	6.0%	0.25	3.3 MB	6.9%	0.93
Aug 12 15:00:00 - 15:59:59	902	5.9%	0.25	2.4 MB	5.1%	0.69
Aug 12 16:00:00 - 16:59:59	987	6.5%	0.27	2.7 MB	5.7%	0.77
Aug 12 17:00:00 - 17:59:59	735	4.8%	0.20	2.1 MB	4.4%	0.59
Aug 12 18:00:00 - 18:59:59	836	5.5%	0.23	2.3 MB	4.9%	0.66
Aug 12 19:00:00 - 19:59:59	814	5.3%	0.23	2.3 MB	4.9%	0.66
Aug 12 20:00:00 - 20:59:59	720	4.7%	0.20	2.4 MB	5.0%	0.68
Aug 12 21:00:00 - 21:59:59	661	4.3%	0.18	1.9 MB	4.0%	0.54
Aug 12 22:00:00 - 22:59:59	552	3.6%	0.15	1.8 MB	3.8%	0.51
Aug 12 23:00:00 - 23:59:59	436	2.9%	0.12	1.4 MB	2.9%	0.39
Aug 13 00:00:00 - 00:59:59	429	2.8%	0.12	1.4 MB	2.9%	0.39
Aug 13 01:00:00 - 01:59:59	451	3.0%	0.13	1.4 MB	2.9%	0.39
Aug 13 02:00:00 - 02:59:59	344	2.3%	0.10	1.2 MB	2.6%	0.35
Aug 13 03:00:00 - 03:59:59	279	1.8%	0.08	1023.5 KB	2.1%	0.28
Aug 13 04:00:00 - 04:15:00	146	1.0%	0.16	736.0 KB	1.5%	0.82
TOTAL: 24:00:00	15222	100.0%	0.18	47.4 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	1031	70	5	956
2	newsfeed.fu-berlin.de	625	26	1	598
3	news-out.mixmin.net	603	2	1	600
4	newsfeed.neva.ru	538	80	3	455
5	feeder.eternal-september.org	398	28	1	369
6	csiph-out.news.weretis.net	317	93	2	222
7	news.fcku.it	300	2	1	297
8	news.freedyn.net	77	1	1	75
9	news-out.aioe.org	41	31	1	9
10	news.redatomik.org	33	0	1	32
11	news.swapon.de	20	0	0	20
12	nntp.pasdenom.info	17	6	1	10
13	newsfeeds.xmission.com	16	2	0	14
14	news.bbs.nz	1	0	1	0
TOTAL: 14		4017	341	19	3657

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	69
it-alt.sport.calcio.juventus	29
alt.windows7.general	19
spline.eisfair.dev	14
gmane.linux.alsa.devel	13
gmane.comp.gis.openstreetmap.tagging	12
alt.2600	11
alt.pl.nieruchomosci.gielda	11
mozilla.support.firefox	11
oesterreich.tratsch	10
alt.politics	10
alt.english.usage	8
alt.binaries.e-books.flood	7
alt.survival	6
local.spam.fr	5
alt.binaries.ebooks	4
lada.auto	4
lada.photo	4
lada.commerce	4
alt.politics.scorched-earth	3
TOTAL: 88	341

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2293
EMP (phn path)	248
EMP (phl)	80
User-issued cancel	62
Too many newsgroups	52
HTML Multipart	18
Binary Payload	13
User-issued spam cancel	8
Binary: misplaced rar	6
Scoring filter (11)	3
Scoring filter (8)	3
Binary: misplaced par2	2
Scoring filter (10)	1
Too many newsgroups (meow)	1
Scoring filter (9)	1
TOTAL: 15	2791

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	8
	TOTAL: 1	8
Bad Message-ID's offered
	csiph-out.feeder.erje.net	72
	TOTAL: 1	72
Huge articles
	csiph-out.feeder.erje.net	155
	csiph-out.news.weretis.net	76
	news.fcku.it	39
	feeder.eternal-september.org	27
	newsfeeds.xmission.com	7
	news.redatomik.org	5
	news-out.mixmin.net	4
	news.freedyn.net	2
	TOTAL: 8	315
Including strange strings
	newsfeed.fu-berlin.de	464
	csiph-out.feeder.erje.net	24
	news.swapon.de	20
	news.redatomik.org	9
	csiph-out.news.weretis.net	8
	news-out.aioe.org	6
	news-out.mixmin.net	6
	newsfeeds.xmission.com	5
	TOTAL: 8	542
TOTAL: 4		937

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	erje	16732	958	11307	32	0	5%	23:59:55
2	news.freedyn.net	16076	782	14282	1	0	4%	23:59:21
3	xanadu-bbs	17493	698	14522	1	159	3%	23:59:55
4	fu-berlin.de	14014	583	13431	0	0	4%	23:59:46
5	usenet.pasdenom.info	16650	183	13759	1201	2	1%	23:59:46
6	news.bbs.nz	28543	177	14402	12	0	0%	23:59:55
7	aioe	13282	19	12177	0	0	0%	23:59:07
8	weretis.net	11794	15	10806	6	0	0%	23:59:55
9	xmission	15503	12	12836	2	0	0%	23:59:55
10	news.redatomik.org	15266	8	14098	0	4	0%	23:59:55
11	neva.ru	14131	5	13748	159	137	0%	23:59:55
12	news.mixmin.net	13832	4	13215	0	0	0%	23:59:54
13	eternal-september	13942	3	12489	0	0	0%	23:59:55
14	news.swapon.de	1634	2	1552	0	0	0%	23:53:00
15	news.fcku.it	959	0	941	0	0	0%	22:19:52
TOTAL: 15		209851	3449	173565	1414	302	1%	358:10:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	6.1 MB	4.7 KB	6.1 MB	0.1 KB/s	8.0 KB	23:59:21
2	xanadu-bbs	6.0 MB	1.1 KB	6.0 MB	0.1 KB/s	8.8 KB	23:59:55
3	fu-berlin.de	5.6 MB	0.0 KB	5.6 MB	0.1 KB/s	9.8 KB	23:59:46
4	erje	3.0 MB	196.0 KB	3.2 MB	0.0 KB/s	3.3 KB	23:59:55
5	news.bbs.nz	525.9 KB	59.6 KB	585.5 KB	0.0 KB/s	3.1 KB	23:59:55
6	usenet.pasdenom.info	480.4 KB	6.7 MB	7.1 MB	0.1 KB/s	5.3 KB	23:59:46
7	aioe	62.9 KB	0.0 KB	62.9 KB	0.0 KB/s	3.3 KB	23:59:07
8	weretis.net	31.0 KB	11.7 KB	42.7 KB	0.0 KB/s	2.0 KB	23:59:55
9	xmission	24.7 KB	6.7 KB	31.5 KB	0.0 KB/s	2.2 KB	23:59:55
10	news.redatomik.org	19.2 KB	0.0 KB	19.2 KB	0.0 KB/s	2.4 KB	23:59:55
11	news.mixmin.net	8.6 KB	0.0 KB	8.6 KB	0.0 KB/s	2.1 KB	23:59:54
12	neva.ru	6.6 KB	788.8 KB	795.4 KB	0.0 KB/s	4.9 KB	23:59:55
13	eternal-september	4.1 KB	0.0 KB	4.1 KB	0.0 KB/s	1.4 KB	23:59:55
14	news.swapon.de	2.4 KB	0.0 KB	2.4 KB	0.0 KB/s	1.2 KB	23:53:00
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:19:52
TOTAL: 15		21.9 MB	7.7 MB	29.6 MB	0.0 KB/s	6.2 KB	358:10:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	48	106	314.8 KB	51	0	0	00:42:49
2	ip72-222-200-117.ph.ph.cox.net	299	24	68.4 KB	6	1	1	51:20:46
3	208-71-239-109.cust.centrio.cz	2	9	19.9 KB	2	0	0	00:14:52
4	p5dee90d4.dip0.t-ipconnect.de	1	6	6.8 KB	1	0	0	00:00:06
5	71-90-72-96.dhcp.ftbg.wi.charter.com	5	6	10.8 KB	3	1	1	00:52:44
6	athedsl-08563.home.otenet.gr	2	2	4.6 KB	1	0	0	00:28:15
TOTAL: 6		357	153	425.2 KB	64	2	2	54:07:46

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	49	106	314.8 KB	51	0	0	00:42:59
2	*.ph.ph.cox.net	299	24	68.4 KB	6	1	1	51:20:46
3	*.cust.centrio.cz	2	9	19.9 KB	2	0	0	00:14:52
4	*.dip0.t-ipconnect.de	1	6	6.8 KB	1	0	0	00:00:06
5	*.dhcp.ftbg.wi.charter.com	5	6	10.8 KB	3	1	1	00:52:44
6	*.home.otenet.gr	2	2	4.6 KB	1	0	0	00:28:15
7	*.dhcp.chtrptr.net	7	0	0.0 KB	0	0	0	00:28:01
TOTAL: 7		365	153	425.2 KB	64	2	2	54:07:46

NNRP auth users [Top 20]:

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

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip72-222-200-117.ph.ph.cox.net	22.862	4.350	0.004	51:20:46
71-90-72-96.dhcp.ftbg.wi.charter.com	0.538	0.064	0.004	00:52:44
141.168.117.108	4.988	0.961	0.000	00:42:49
athedsl-08563.home.otenet.gr	0.207	0.030	0.006	00:28:15
172-221-041-102.dhcp.chtrptr.net	1.052	1.636	0.000	00:28:01
208-71-239-109.cust.centrio.cz	0.256	0.062	0.006	00:14:52
192.241.196.128	0.055	0.008	0.000	00:00:09
p5dee90d4.dip0.t-ipconnect.de	0.080	0.036	0.000	00:00:06
TOTAL: 8	30.038	7.147	0.020	54:07:46

Curious NNRP server explorers [Top 20]:

System	Conn
172-221-041-102.dhcp.chtrptr.net	7
192.241.196.128	1
TOTAL: 2	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	5	0
TOTAL: 1	5	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	72	47.1%
2	alt	69	45.1%
3	muc	11	7.2%
4	soc	1	0.7%
TOTAL: 4		153	100.0%

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

Newsgroup		Count
1	alt.comp.freeware	63
2	comp.sys.ibm.ps2.hardware	30
3	comp.arch	11
4	muc.lists.freebsd.current	11
5	comp.lang.javascript	11
6	comp.os.vms	7
7	alt.free.newsservers	6
8	comp.os.os2.advocacy	4
9	comp.os.linux.networking	3
10	comp.sys.ibm.pc.hardware.storage	2
11	comp.sys.ibm.pc.games.action	2
12	comp.os.os2.ecomstation	1
13	soc.culture.lebanon	1
14	comp.os.cpm	1
TOTAL: 14		153

Daily Usenet report for csiph.com

Aug 12 04:15:00 -- Aug 13 04:15:00