Daily Usenet report for csiph.com: Oct 2 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	20928	48.4%	5.3 MB	64.6%
innd	18063	41.7%	2.3 MB	27.4%
innfeed	3298	7.6%	566.6 KB	6.7%
nnrpd	923	2.1%	106.2 KB	1.3%
nocem	53	0.1%	5.7 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	43266	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	304975	58.2%
Negative hits	198698	37.9%
Do not exist	20307	3.9%
Cache misses	320	0.1%
TOTAL: 4	524300	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.266	0.0%	116	0.000	2.293	18.000
article cleanup	00:00:00.114	0.0%	19572	0.000	0.006	0.036
article logging	00:00:00.352	0.0%	19934	0.000	0.018	0.479
article parse	00:00:02.200	0.0%	83693	0.000	0.026	0.222
article write	00:00:59.545	0.1%	16289	0.191	3.656	50.136
artlog/artcncl	00:00:00.001	0.0%	76	0.000	0.013	0.083
artlog/artparse	00:00:00.036	0.0%	907	0.000	0.040	2.667
data move	00:00:00.194	0.0%	517043	0.000	0.000	0.009
hisgrep/artcncl	00:00:00.050	0.0%	116	0.000	0.431	5.571
hishave/artcncl	00:00:00.021	0.0%	116	0.000	0.181	2.571
hishave/artparse	00:00:00.002	0.0%	907	0.000	0.002	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:16.259	0.0%	528070	0.001	0.031	0.342
history sync	00:00:00.863	0.0%	382	0.000	2.259	14.000
history write	00:00:25.118	0.0%	19572	0.281	1.283	13.333
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.408	0.0%	907	0.000	1.552	14.667
idle	23:56:43.148	99.7%	521243	83.969	165.380	621.919
nntp read	00:00:02.096	0.0%	524145	0.001	0.004	0.007
overview write	00:01:15.241	0.1%	16289	0.149	4.619	67.955
perl filter	00:00:32.156	0.0%	19570	1.211	1.643	3.510
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.251	0.0%	32630	0.000	0.008	0.033
TOTAL: 24:01:00.628	24:00:19.321	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	16341	0.000	0.003	0.028
article prepare	00:00:00.000	0.0%	14584	0.000	0.000	0.000
article read	00:00:00.582	0.0%	1227	0.000	0.474	67.000
backlog stats	00:00:00.214	0.0%	341525	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.369	0.0%	267475	0.001	0.005	0.012
data write	00:00:01.627	0.0%	253727	0.001	0.006	0.012
idle	23:53:34.137	100.0%	341525	138.445	251.853	811.601
status file	00:00:00.708	0.0%	253	0.000	2.798	50.000
TOTAL: 23:53:44.970	23:53:38.683	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:03.567	0.0%	16295	0.000	0.219	0.800
idle	38:04:01.036	100.0%	16941	0.000	8089.312	600075.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.713	0.0%	34471	0.000	0.079	63.725
readart	00:00:00.635	0.0%	37	0.000	17.162	52.000
TOTAL: 38:04:48.082	38:04:07.951	100.0%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	4	32945	3547	29058	340	10%	48:46:37
2	feeder.eternal-september.org	2	17650	2012	15566	72	11%	24:09:52
3	newsfeeds.xmission.com	3	18390	1891	16487	12	10%	30:45:21
4	csiph-out.feeder.erje.net	1440	77964	1530	76136	298	1%	118:41:33
5	news-out.aioe.org	1	15736	1510	14205	21	9%	23:59:57
6	csiph-out.news.xs4all.nl	861	18141	1438	15384	1319	7%	71:26:10
7	news.freedyn.net	3	19729	1160	17451	1118	5%	24:08:59
8	newsfeed.neva.ru	144	23855	985	22422	448	4%	71:42:25
9	news-out.mixmin.net	2	16807	854	15791	162	5%	23:58:50
10	newsfeed.fu-berlin.de	6	14049	732	13162	155	5%	21:53:18
11	news.fcku.it	9	2406	352	2044	10	14%	22:08:18
12	csiph-out.feed.uzoreto.com	864	25625	180	25229	216	0%	71:17:04
13	nntp.pasdenom.info	2	11501	40	11447	14	0%	23:58:22
14	news.swapon.de	6	1136	17	1115	4	1%	23:12:07
15	localhost	42	14	14	0	0	100%	00:00:06
16	news.samoylyk.net	3	15763	10	15744	9	0%	25:49:30
17	news.bbs.nz	2	15451	9	15442	0	0%	23:59:40
18	xanadu-bbs.net	31	458	0	458	0	0%	22:16:06
TOTAL: 18		3425	327620	16281	307141	4198	4%	672:14:15

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.weretis.net	10.3 MB	8.8 MB	19.1 MB	53%	5.0 KB
2	csiph-out.feeder.erje.net	8.3 MB	5.2 MB	13.5 MB	61%	7.6 KB
3	newsfeeds.xmission.com	5.5 MB	466.4 KB	5.9 MB	92%	3.2 KB
4	feeder.eternal-september.org	5.1 MB	610.5 KB	5.7 MB	89%	2.8 KB
5	csiph-out.news.xs4all.nl	3.6 MB	14.5 MB	18.1 MB	19%	6.7 KB
6	news-out.aioe.org	3.4 MB	28.4 KB	3.4 MB	99%	2.3 KB
7	news.freedyn.net	3.1 MB	62.7 MB	65.8 MB	4%	29.6 KB
8	news-out.mixmin.net	2.6 MB	328.5 KB	3.0 MB	89%	3.0 KB
9	newsfeed.neva.ru	2.1 MB	2.6 MB	4.8 MB	45%	3.4 KB
10	newsfeed.fu-berlin.de	1.8 MB	440.3 KB	2.2 MB	80%	2.5 KB
11	csiph-out.feed.uzoreto.com	601.3 KB	5.6 MB	6.2 MB	9%	16.1 KB
12	news.fcku.it	566.9 KB	13.9 KB	580.8 KB	97%	1.6 KB
13	nntp.pasdenom.info	89.1 KB	23.5 KB	112.6 KB	79%	2.1 KB
14	news.swapon.de	39.4 KB	2.3 MB	2.3 MB	1%	112.7 KB
15	news.samoylyk.net	30.3 KB	1.5 MB	1.5 MB	1%	83.3 KB
16	localhost	17.5 KB	0.0 KB	17.5 KB	100%	1.3 KB
17	news.bbs.nz	12.1 KB	0.0 KB	12.1 KB	100%	1.3 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		47.1 MB	105.1 MB	152.3 MB	30%	7.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 2 04:15:00 - 04:59:59	233	1.4%	0.09	1.3 MB	2.8%	0.50
Oct 2 05:00:00 - 05:59:59	359	2.2%	0.10	1.5 MB	3.1%	0.42
Oct 2 06:00:00 - 06:59:59	500	3.1%	0.14	1.5 MB	3.1%	0.41
Oct 2 07:00:00 - 07:59:59	652	4.0%	0.18	1.5 MB	3.3%	0.44
Oct 2 08:00:00 - 08:59:59	776	4.8%	0.22	1.9 MB	4.0%	0.53
Oct 2 09:00:00 - 09:59:59	832	5.1%	0.23	2.2 MB	4.6%	0.61
Oct 2 10:00:00 - 10:59:59	771	4.7%	0.21	2.0 MB	4.3%	0.57
Oct 2 11:00:00 - 11:59:59	795	4.9%	0.22	2.1 MB	4.4%	0.59
Oct 2 12:00:00 - 12:59:59	830	5.1%	0.23	1.9 MB	4.1%	0.55
Oct 2 13:00:00 - 13:59:59	836	5.1%	0.23	2.3 MB	4.8%	0.65
Oct 2 14:00:00 - 14:59:59	854	5.2%	0.24	2.5 MB	5.3%	0.72
Oct 2 15:00:00 - 15:59:59	1010	6.2%	0.28	4.0 MB	8.5%	1.14
Oct 2 16:00:00 - 16:59:59	969	5.9%	0.27	3.1 MB	6.6%	0.88
Oct 2 17:00:00 - 17:59:59	956	5.9%	0.27	2.5 MB	5.2%	0.71
Oct 2 18:00:00 - 18:59:59	798	4.9%	0.22	1.9 MB	4.1%	0.55
Oct 2 19:00:00 - 19:59:59	779	4.8%	0.22	2.3 MB	4.8%	0.64
Oct 2 20:00:00 - 20:59:59	910	5.6%	0.25	2.6 MB	5.5%	0.73
Oct 2 21:00:00 - 21:59:59	808	5.0%	0.22	2.1 MB	4.5%	0.60
Oct 2 22:00:00 - 22:59:59	540	3.3%	0.15	1.6 MB	3.4%	0.46
Oct 2 23:00:00 - 23:59:59	510	3.1%	0.14	1.6 MB	3.3%	0.45
Oct 3 00:00:00 - 00:59:59	379	2.3%	0.11	1.1 MB	2.3%	0.30
Oct 3 01:00:00 - 01:59:59	370	2.3%	0.10	1.1 MB	2.3%	0.31
Oct 3 02:00:00 - 02:59:59	361	2.2%	0.10	1.1 MB	2.3%	0.30
Oct 3 03:00:00 - 03:59:59	344	2.1%	0.10	1.2 MB	2.6%	0.35
Oct 3 04:00:00 - 04:15:00	126	0.8%	0.14	521.2 KB	1.1%	0.58
TOTAL: 24:00:00	16298	100.0%	0.19	47.2 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	1350	18	1332
2	news.freedyn.net	1134	9	1125
3	newsfeed.fu-berlin.de	458	11	447
4	newsfeed.neva.ru	449	101	348
5	csiph-out.news.weretis.net	343	124	219
6	csiph-out.feeder.erje.net	304	53	251
7	csiph-out.feed.uzoreto.com	219	6	213
8	news-out.mixmin.net	155	1	154
9	feeder.eternal-september.org	73	20	53
10	news-out.aioe.org	22	21	1
11	nntp.pasdenom.info	14	0	14
12	news.fcku.it	10	10	0
13	news.samoylyk.net	9	0	9
14	newsfeeds.xmission.com	8	2	6
15	news.swapon.de	6	0	6
TOTAL: 15		4554	376	4178

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	76
it-alt.sport.calcio.juventus	46
mozilla.dev.security.policy	27
mozilla.support.firefox	24
mozilla.support.thunderbird	22
alt.binaries.pictures.purebeauty	17
mozilla.support.seamonkey	13
alt.tv.star-trek	11
alt.computer.workshop	10
mozilla.dev.platform	9
fido7.ru.android	9
lada.auto	9
alt.politics	9
alt.politics.usa	7
oesterreich.tratsch	6
mozilla.general	5
alt.binaries.newstar	5
alt.survival	5
alt.philosophy	4
alt.biz.accounting	4
TOTAL: 64	376

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2289
EMP (phn path)	228
EMP (phl)	95
EMP (phn nph)	88
Too many newsgroups	72
User-issued cancel	35
HTML Multipart	33
Binary: misplaced rar	12
Binary Payload	11
User-issued spam cancel	11
Scoring filter (13)	8
Scoring filter (11)	6
Scoring filter (12)	5
Scoring filter (14)	4
Scoring filter (10)	4
Scoring filter (9)	1
Too many newsgroups (meow)	1
U2 violation - invalid distribution	1
Binary Image: misplaced jpg	1
TOTAL: 19	2905

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	34	0	34	34
TOTAL: 1	34	18	34	34

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	493
	csiph-out.news.xs4all.nl	142
	csiph-out.news.weretis.net	94
	csiph-out.feeder.erje.net	93
	csiph-out.feed.uzoreto.com	62
	news.samoylyk.net	9
	feeder.eternal-september.org	6
	newsfeeds.xmission.com	5
	news.swapon.de	4
	newsfeed.fu-berlin.de	2
	TOTAL: 11	911
Including strange strings
	newsfeed.fu-berlin.de	292
	csiph-out.news.xs4all.nl	28
	csiph-out.news.weretis.net	18
	news-out.mixmin.net	8
	csiph-out.feeder.erje.net	4
	csiph-out.feed.uzoreto.com	3
	news.freedyn.net	2
	newsfeed.neva.ru	2
	news.swapon.de	2
	news-out.aioe.org	1
	TOTAL: 10	360
TOTAL: 3		1316

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18776	1056	15207	0	0	5%	23:56:17
2	news.samoylyk.net	17071	502	15714	2	49	2%	23:51:46
3	news.freedyn.net	15331	478	14524	31	0	3%	23:50:05
4	fu-berlin.de	15293	469	14824	0	0	3%	23:50:05
5	news.bbs.nz	30614	183	15534	1	0	0%	23:50:05
6	usenet.pasdenom.info	16617	74	15663	497	14	0%	23:57:46
7	newsfeed.xs4all.nl	15120	54	14299	3	43	0%	23:56:27
8	neva.ru	15306	30	14433	76	0	0%	23:50:05
9	news.uzoreto.com	15832	22	15516	60	0	0%	23:50:04
10	erje	15357	21	14641	2	0	0%	23:52:12
11	aioe	14065	18	13636	0	0	0%	23:57:46
12	news.mixmin.net	15190	17	14806	0	0	0%	23:56:39
13	news.swapon.de	1251	15	1225	0	0	1%	23:45:52
14	weretis.net	12245	15	12038	0	0	0%	23:50:05
15	xmission	16764	14	13949	1	0	0%	23:50:05
16	eternal-september	13991	13	13577	0	0	0%	23:53:20
17	news.fcku.it	1987	1	1973	0	0	0%	22:07:30
18	news.redatomik.org	0	0	0	0	15729	0%	23:50:05
TOTAL: 18		250810	2982	221559	673	15835	1%	427:56:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	6.3 KB	23:56:17
2	news.freedyn.net	5.0 MB	47.6 KB	5.1 MB	0.1 KB/s	10.2 KB	23:50:05
3	news.samoylyk.net	5.0 MB	2.4 KB	5.0 MB	0.1 KB/s	10.2 KB	23:51:46
4	fu-berlin.de	5.0 MB	0.0 KB	5.0 MB	0.1 KB/s	10.8 KB	23:50:05
5	news.bbs.nz	473.2 KB	1.1 KB	474.3 KB	0.0 KB/s	2.6 KB	23:50:05
6	newsfeed.xs4all.nl	224.6 KB	16.9 KB	241.6 KB	0.0 KB/s	4.2 KB	23:56:27
7	usenet.pasdenom.info	185.9 KB	5.1 MB	5.3 MB	0.1 KB/s	9.5 KB	23:57:46
8	news.uzoreto.com	112.3 KB	335.8 KB	448.1 KB	0.0 KB/s	5.5 KB	23:50:04
9	neva.ru	66.2 KB	191.4 KB	257.6 KB	0.0 KB/s	2.4 KB	23:50:05
10	news.swapon.de	50.6 KB	0.0 KB	50.6 KB	0.0 KB/s	3.4 KB	23:45:52
11	aioe	32.2 KB	0.0 KB	32.2 KB	0.0 KB/s	1.8 KB	23:57:46
12	erje	29.6 KB	34.6 KB	64.2 KB	0.0 KB/s	2.8 KB	23:52:12
13	news.mixmin.net	23.0 KB	0.0 KB	23.0 KB	0.0 KB/s	1.4 KB	23:56:39
14	weretis.net	19.5 KB	0.0 KB	19.5 KB	0.0 KB/s	1.3 KB	23:50:05
15	xmission	17.6 KB	1.9 KB	19.4 KB	0.0 KB/s	1.3 KB	23:50:05
16	eternal-september	16.6 KB	0.0 KB	16.6 KB	0.0 KB/s	1.3 KB	23:53:20
17	news.fcku.it	1.5 KB	0.0 KB	1.5 KB	0.0 KB/s	1.5 KB	22:07:30
18	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:05
TOTAL: 18		22.8 MB	5.7 MB	28.5 MB	0.0 KB/s	8.0 KB	427:56:14

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	66-188-82-154.dhcp.mdsn.wi.charter.com	48	16	20.7 KB	15	14	14	10:38:53
2	a131033.upc-a.chello.nl	80	12	19.0 KB	6	0	0	23:00:18
3	208-71-239-109.cust.centrio.cz	3	8	16.1 KB	2	0	0	00:56:38
4	p2e5b5d21.dip0.t-ipconnect.de	1	1	24.4 KB	1	0	0	00:00:09
TOTAL: 4		132	37	80.1 KB	24	14	14	38:04:42

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.mdsn.wi.charter.com	48	16	20.7 KB	15	14	14	10:38:53
2	*.upc-a.chello.nl	80	12	19.0 KB	6	0	0	23:00:18
3	*.cust.centrio.cz	3	8	16.1 KB	2	0	0	00:56:38
4	*.dip0.t-ipconnect.de	1	1	24.4 KB	1	0	0	00:00:09
5	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	0	00:00:00
6	unresolved	21	0	0.0 KB	0	0	0	03:28:41
TOTAL: 6		154	37	80.1 KB	24	14	14	38:04:42

NNRP auth users [Top 20]:

User	Conn
ohlandl	14
ctomczyk	3
TOTAL: 2	17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
a131033.upc-a.chello.nl	5.884	1.021	0.000	23:00:18
66-188-82-154.dhcp.mdsn.wi.charter.com	4.108	0.853	0.048	10:38:53
172.221.41.102	1.525	2.606	0.000	03:28:26
208-71-239-109.cust.centrio.cz	0.330	0.062	0.007	00:56:38
159.203.201.143	0.074	0.008	0.000	00:00:09
p2e5b5d21.dip0.t-ipconnect.de	0.122	0.131	0.000	00:00:09
94.161.34.180	0.213	0.023	0.000	00:00:03
94.166.224.90	0.056	0.024	0.000	00:00:01
ec2-54-202-36-80.us-west-2.compute.amazonaws.com	0.070	0.008	0.000	00:00:00
TOTAL: 9	12.382	4.736	0.055	38:04:42

Curious NNRP server explorers [Top 20]:

System	Conn
172.221.41.102	16
94.161.34.180	3
159.203.201.143	1
94.166.224.90	1
ec2-54-202-36-80.us-west-2.compute.amazonaws.com	1
TOTAL: 5	22

NNRP client timeouts [Top 20]:

System	Conn	Peer
66-188-82-154.dhcp.mdsn.wi.charter.com	6	0
208-71-239-109.cust.centrio.cz	1	0
TOTAL: 2	7	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	35	94.6%
2	de	1	2.7%
3	pl	1	2.7%
TOTAL: 3		37	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	28
2	comp.lang.javascript	7
3	de.sci.electronics	1
4	pl.comp.www	1
TOTAL: 4		37

Daily Usenet report for csiph.com

Oct 2 04:15:00 -- Oct 3 04:15:00