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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	22861	52.4%	5.0 MB	66.8%
innd	11860	27.2%	1.5 MB	20.3%
nnrpd	5898	13.5%	504.1 KB	6.6%
innfeed	2916	6.7%	477.2 KB	6.2%
nocem	60	0.1%	6.6 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	43596	100.0%	7.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	321702	58.2%
Negative hits	208674	37.7%
Do not exist	22122	4.0%
Cache misses	344	0.1%
TOTAL: 4	552842	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.270	0.0%	98	0.000	2.755	19.000
article cleanup	00:00:00.168	0.0%	21809	0.000	0.008	0.045
article logging	00:00:00.395	0.0%	22341	0.000	0.018	0.072
article parse	00:00:01.013	0.0%	61200	0.000	0.017	0.064
article write	00:00:05.882	0.0%	18298	0.131	0.321	3.296
artlog/artcncl	00:00:00.001	0.0%	93	0.000	0.011	0.167
artlog/artparse	00:00:00.005	0.0%	431	0.000	0.012	0.167
data move	00:00:00.141	0.0%	528365	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.031	0.0%	98	0.000	0.316	2.900
hishave/artcncl	00:00:00.012	0.0%	98	0.000	0.122	1.000
hishave/artparse	00:00:00.001	0.0%	430	0.000	0.002	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.441	0.0%	555287	0.002	0.006	0.045
history sync	00:00:00.790	0.0%	376	0.000	2.101	5.500
history write	00:00:09.283	0.0%	21805	0.044	0.426	1.318
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.188	0.0%	430	0.000	0.437	5.000
idle	23:59:02.988	99.9%	531486	90.231	162.456	484.962
nntp read	00:00:02.944	0.0%	533135	0.001	0.006	0.016
overview write	00:00:10.547	0.0%	18298	0.134	0.576	30.099
perl filter	00:00:43.285	0.1%	21798	1.416	1.986	3.785
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.329	0.0%	36652	0.000	0.009	0.028
TOTAL: 24:00:58.572	24:00:21.714	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.048	0.0%	18494	0.000	0.003	0.043
article prepare	00:00:00.003	0.0%	16216	0.000	0.000	0.008
article read	00:00:00.335	0.0%	1877	0.000	0.178	1.958
backlog stats	00:00:00.221	0.0%	360684	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.785	0.0%	268883	0.001	0.007	0.013
data write	00:00:01.687	0.0%	253132	0.003	0.007	0.013
idle	23:53:14.957	100.0%	360684	140.742	238.422	597.113
status file	00:00:00.195	0.0%	281	0.000	0.694	2.000
TOTAL: 23:53:26.393	23:53:19.231	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	12:54:48.147	99.9%	5869	411.177	7920.966	600180.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.078	0.0%	12313	0.000	0.006	0.125
readart	00:00:00.216	0.0%	160	0.000	1.350	61.000
TOTAL: 12:55:15.116	12:54:48.441	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	37718	3997	33201	520	10%	48:10:09
2	csiph-out.feeder.erje.net	1728	113048	2732	108959	1357	2%	142:29:59
3	feeder.eternal-september.org	2	19963	2482	17033	448	12%	23:43:04
4	news.redatomik.org	3	17518	1836	15600	82	10%	24:08:23
5	newsfeeds.xmission.com	2	17877	1775	16088	14	9%	25:10:20
6	news-out.aioe.org	3	17850	1750	16023	77	9%	24:09:56
7	newsfeed.fu-berlin.de	10	17648	1089	16345	214	6%	26:10:59
8	newsfeed.neva.ru	147	20785	933	19243	609	4%	71:43:47
9	news-out.mixmin.net	3	19108	899	17912	297	4%	24:08:10
10	news.fcku.it	2	8650	576	7860	214	6%	23:59:42
11	news.freedyn.net	2	15512	118	15306	88	0%	18:00:33
12	nntp.pasdenom.info	2	17325	94	17227	4	0%	23:59:10
13	news.swapon.de	9	1622	60	1555	7	3%	25:11:00
14	xanadu-bbs.net	13	720	2	717	1	0%	23:38:58
15	news.bbs.nz	2	18810	2	18808	0	0%	23:59:51
16	localhost	32	1	1	0	0	100%	00:00:00
TOTAL: 16		1965	344155	18346	321877	3932	5%	548:44:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	12.8 MB	0.0 KB	11.6 MB	24.5 MB	52%	5.5 KB
2	csiph-out.feeder.erje.net	12.8 MB	10.8 KB	11.8 MB	24.7 MB	52%	6.2 KB
3	feeder.eternal-september.org	7.2 MB	2.1 KB	6.4 MB	13.7 MB	53%	4.8 KB
4	newsfeeds.xmission.com	5.7 MB	0.0 KB	857.7 KB	6.5 MB	87%	3.7 KB
5	news.redatomik.org	5.2 MB	0.0 KB	818.4 KB	6.0 MB	86%	3.2 KB
6	news-out.aioe.org	4.2 MB	0.0 KB	356.3 KB	4.6 MB	92%	2.6 KB
7	news-out.mixmin.net	3.5 MB	0.0 KB	1.0 MB	4.5 MB	77%	3.9 KB
8	newsfeed.fu-berlin.de	2.4 MB	27.9 KB	548.3 KB	3.0 MB	81%	2.4 KB
9	newsfeed.neva.ru	2.4 MB	0.0 KB	2.4 MB	4.8 MB	49%	3.2 KB
10	news.fcku.it	1.5 MB	4.3 KB	4.0 MB	5.5 MB	26%	7.1 KB
11	news.freedyn.net	314.3 KB	0.0 KB	304.6 KB	618.9 KB	50%	3.0 KB
12	nntp.pasdenom.info	213.7 KB	0.0 KB	10.6 KB	224.3 KB	95%	2.3 KB
13	news.swapon.de	166.7 KB	0.0 KB	47.6 KB	214.3 KB	77%	3.2 KB
14	xanadu-bbs.net	44.3 KB	0.0 KB	22.1 KB	66.4 KB	66%	22.1 KB
15	news.bbs.nz	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.7 KB
16	localhost	1.5 KB	0.0 KB	0.0 KB	1.5 KB	100%	1.5 KB
TOTAL: 16		58.5 MB	45.2 KB	40.2 MB	98.7 MB	59%	4.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 3 04:15:00 - 04:59:59	348	1.9%	0.13	2.0 MB	3.3%	0.74
Aug 3 05:00:00 - 05:59:59	491	2.7%	0.14	1.8 MB	3.1%	0.51
Aug 3 06:00:00 - 06:59:59	670	3.7%	0.19	2.7 MB	4.5%	0.76
Aug 3 07:00:00 - 07:59:59	764	4.2%	0.21	2.0 MB	3.4%	0.57
Aug 3 08:00:00 - 08:59:59	741	4.1%	0.21	1.9 MB	3.3%	0.55
Aug 3 09:00:00 - 09:59:59	1075	5.9%	0.30	2.7 MB	4.7%	0.78
Aug 3 10:00:00 - 10:59:59	919	5.0%	0.26	2.5 MB	4.3%	0.71
Aug 3 11:00:00 - 11:59:59	890	4.9%	0.25	2.4 MB	4.1%	0.69
Aug 3 12:00:00 - 12:59:59	837	4.6%	0.23	2.8 MB	4.8%	0.80
Aug 3 13:00:00 - 13:59:59	999	5.5%	0.28	2.6 MB	4.5%	0.75
Aug 3 14:00:00 - 14:59:59	1117	6.1%	0.31	3.7 MB	6.3%	1.05
Aug 3 15:00:00 - 15:59:59	1005	5.5%	0.28	3.3 MB	5.6%	0.94
Aug 3 16:00:00 - 16:59:59	1010	5.5%	0.28	3.2 MB	5.5%	0.92
Aug 3 17:00:00 - 17:59:59	1119	6.1%	0.31	4.3 MB	7.4%	1.23
Aug 3 18:00:00 - 18:59:59	908	5.0%	0.25	3.1 MB	5.3%	0.89
Aug 3 19:00:00 - 19:59:59	880	4.8%	0.24	2.5 MB	4.2%	0.71
Aug 3 20:00:00 - 20:59:59	945	5.2%	0.26	2.8 MB	4.8%	0.80
Aug 3 21:00:00 - 21:59:59	757	4.1%	0.21	2.2 MB	3.7%	0.63
Aug 3 22:00:00 - 22:59:59	640	3.5%	0.18	2.0 MB	3.3%	0.56
Aug 3 23:00:00 - 23:59:59	560	3.1%	0.16	1.8 MB	3.0%	0.51
Aug 4 00:00:00 - 00:59:59	401	2.2%	0.11	1.3 MB	2.1%	0.36
Aug 4 01:00:00 - 01:59:59	374	2.0%	0.10	1.1 MB	1.9%	0.32
Aug 4 02:00:00 - 02:59:59	399	2.2%	0.11	2.0 MB	3.4%	0.58
Aug 4 03:00:00 - 03:59:59	319	1.7%	0.09	1.7 MB	2.9%	0.49
Aug 4 04:00:00 - 04:15:00	122	0.7%	0.14	461.9 KB	0.8%	0.51
TOTAL: 24:00:00	18290	100.0%	0.21	59.0 MB	100.0%	0.70

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.feeder.erje.net	1371	114	3	1254
2	newsfeed.fu-berlin.de	657	25	0	632
3	newsfeed.neva.ru	609	111	0	498
4	csiph-out.news.weretis.net	519	168	0	351
5	feeder.eternal-september.org	464	35	1	428
6	news-out.mixmin.net	310	1	0	309
7	news.fcku.it	208	10	1	197
8	news.redatomik.org	121	0	0	121
9	news-out.aioe.org	92	79	0	13
10	news.freedyn.net	91	7	0	84
11	news.swapon.de	18	0	0	18
12	newsfeeds.xmission.com	14	4	0	10
13	nntp.pasdenom.info	4	3	0	1
14	xanadu-bbs.net	1	0	0	1
TOTAL: 14		4479	557	5	3917

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	55
lada.talk	46
lada.auto	43
gmane.linux.alsa.devel	34
alt.survival	29
alt.english.usage	28
alt.support.domestic-violence	26
alt.bbs.synchronet	22
spline.eisfair	22
gmane.comp.gis.openstreetmap.tagging	21
gmane.linux.kernel	16
alt.politics	12
gmane.comp.gis.openstreetmap	10
trollnet.cinema	9
lada.commerce	8
oesterreich.tratsch	8
cakewalk.audio	8
alt.slack.goathead	6
mozilla.general	6
alt.politics.usa	6
TOTAL: 92	557

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2230
EMP (phn path)	455
EMP (phl)	93
Too many newsgroups	42
User-issued cancel	31
Too many newsgroups (low_xpost)	29
HTML Multipart	18
Binary Payload	8
EMP (phn nph)	8
Scoring filter (13)	7
Binary: misplaced rar	6
Binary: misplaced par2	4
Binary Image: misplaced jpg	3
User-issued spam cancel	3
Scoring filter (10)	2
Too many newsgroups (meow)	2
Scoring filter (14)	1
Scoring filter (8)	1
Scoring filter (9)	1
Scoring filter (15)	1
TOTAL: 23	2948

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	17	0	17	17
TOTAL: 1	17	39	17	17

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feeder.erje.net	6
	TOTAL: 1	6
Bad Message-ID's offered
	csiph-out.feeder.erje.net	72
	TOTAL: 1	72
Huge articles
	csiph-out.feeder.erje.net	162
	csiph-out.news.weretis.net	126
	feeder.eternal-september.org	66
	news.fcku.it	52
	newsfeeds.xmission.com	9
	news.redatomik.org	7
	news-out.mixmin.net	6
	news.freedyn.net	2
	TOTAL: 8	430
Including strange strings
	newsfeed.fu-berlin.de	438
	news.redatomik.org	39
	news.swapon.de	18
	csiph-out.feeder.erje.net	16
	news-out.aioe.org	12
	news-out.mixmin.net	5
	csiph-out.news.weretis.net	2
	news.freedyn.net	1
	feeder.eternal-september.org	1
	TOTAL: 9	532
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 5		1041

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.freedyn.net	19388	795	17372	3	0	4%	23:59:33
2	xanadu-bbs	21263	675	17603	10	232	3%	23:59:55
3	fu-berlin.de	17020	596	16424	0	1	3%	23:59:53
4	news.bbs.nz	34649	236	17436	8	0	0%	23:59:55
5	usenet.pasdenom.info	21118	220	16791	1185	7	1%	23:59:48
6	erje	16601	47	15466	12	3	0%	23:59:55
7	xmission	19340	19	15743	1	0	0%	23:59:54
8	weretis.net	14823	16	13391	18	0	0%	23:59:53
9	aioe	15953	14	14957	0	0	0%	23:59:55
10	news.mixmin.net	17098	8	16488	0	0	0%	23:59:48
11	eternal-september	16213	7	15024	0	0	0%	23:59:55
12	news.swapon.de	1719	5	1651	0	0	0%	23:55:33
13	neva.ru	16790	5	16372	175	0	0%	23:59:44
14	news.redatomik.org	16879	3	15845	0	10	0%	23:59:55
15	news.fcku.it	1872	2	1849	0	0	0%	22:27:41
TOTAL: 15		250726	2648	212412	1412	253	1%	358:21:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.freedyn.net	7.1 MB	13.6 KB	7.1 MB	0.1 KB/s	9.1 KB	23:59:33
2	xanadu-bbs	6.6 MB	52.8 KB	6.7 MB	0.1 KB/s	10.0 KB	23:59:55
3	fu-berlin.de	6.4 MB	0.0 KB	6.4 MB	0.1 KB/s	10.9 KB	23:59:53
4	usenet.pasdenom.info	700.9 KB	7.3 MB	8.0 MB	0.1 KB/s	5.8 KB	23:59:48
5	news.bbs.nz	672.2 KB	21.0 KB	693.3 KB	0.0 KB/s	2.8 KB	23:59:55
6	erje	156.5 KB	169.5 KB	326.0 KB	0.0 KB/s	5.5 KB	23:59:55
7	xmission	43.1 KB	1.5 KB	44.5 KB	0.0 KB/s	2.2 KB	23:59:54
8	weretis.net	41.8 KB	169.4 KB	211.2 KB	0.0 KB/s	6.2 KB	23:59:53
9	aioe	40.6 KB	0.0 KB	40.6 KB	0.0 KB/s	2.9 KB	23:59:55
10	news.swapon.de	40.6 KB	0.0 KB	40.6 KB	0.0 KB/s	8.1 KB	23:55:33
11	neva.ru	39.7 KB	631.4 KB	671.1 KB	0.0 KB/s	3.7 KB	23:59:44
12	news.mixmin.net	21.1 KB	0.0 KB	21.1 KB	0.0 KB/s	2.6 KB	23:59:48
13	eternal-september	16.6 KB	0.0 KB	16.6 KB	0.0 KB/s	2.4 KB	23:59:55
14	news.redatomik.org	8.0 KB	0.0 KB	8.0 KB	0.0 KB/s	2.7 KB	23:59:55
15	news.fcku.it	5.2 KB	0.0 KB	5.2 KB	0.0 KB/s	2.6 KB	22:27:41
TOTAL: 15		21.8 MB	8.3 MB	30.1 MB	0.0 KB/s	7.6 KB	358:21:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	141.168.117.108	48	139	455.2 KB	61	0	0	00:46:56
2	ppp-94-64-162-113.home.otenet.gr	5	12	21.7 KB	4	0	0	01:25:56
3	challenger.nielsenmedia.com	2	3	6.3 KB	1	0	0	01:01:39
4	68-185-181-172.dhcp.mdsn.wi.charter.com	8	2	3.2 KB	2	1	1	01:19:32
5	athedsl-91529.home.otenet.gr	3	2	5.1 KB	1	0	0	00:24:23
6	ip72-222-200-117.ph.ph.cox.net	20	2	3.2 KB	1	0	0	07:56:45
TOTAL: 6		86	160	494.7 KB	70	1	1	12:55:14

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	48	139	455.2 KB	61	0	0	00:46:56
2	*.home.otenet.gr	8	14	26.8 KB	5	0	0	01:50:20
3	*.nielsenmedia.com	2	3	6.3 KB	1	0	0	01:01:39
4	*.dhcp.mdsn.wi.charter.com	8	2	3.2 KB	2	1	1	01:19:32
5	*.ph.ph.cox.net	20	2	3.2 KB	1	0	0	07:56:45
TOTAL: 5		86	160	494.7 KB	70	1	1	12:55:14

NNRP auth users [Top 20]:

User	Conn
ctomczyk	8
ohlandl	1
TOTAL: 2	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip72-222-200-117.ph.ph.cox.net	1.715	0.344	0.000	07:56:45
ppp-94-64-162-113.home.otenet.gr	0.624	0.105	0.019	01:25:56
68-185-181-172.dhcp.mdsn.wi.charter.com	0.704	0.168	0.003	01:19:32
challenger.nielsenmedia.com	0.181	0.024	0.000	01:01:39
141.168.117.108	5.316	0.955	0.000	00:46:56
athedsl-91529.home.otenet.gr	0.290	0.079	0.014	00:24:23
TOTAL: 6	8.830	1.675	0.036	12:55:14

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	13	0
challenger.nielsenmedia.com	2	0
ppp-94-64-162-113.home.otenet.gr	2	0
TOTAL: 3	17	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	80	50.0%
2	alt	66	41.2%
3	pl	14	8.8%
TOTAL: 3		160	100.0%

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

Newsgroup		Count
1	alt.comp.freeware	66
2	comp.os.vms	28
3	comp.sys.ibm.pc.games.action	14
4	pl.comp.lang.javascript	14
5	comp.ai.philosophy	11
6	comp.arch.embedded	8
7	comp.sys.ibm.ps2.hardware	6
8	comp.arch	5
9	comp.os.cpm	4
10	comp.lang.awk	3
11	comp.os.os2.ecomstation	1
TOTAL: 11		160

Daily Usenet report for csiph.com

Aug 3 04:15:00 -- Aug 4 04:15:00