Daily Usenet report for csiph.com: Sep 23 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Sep 23 09:42:05 csiph nnrpd[83085]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Sep 23 09:42:05 csiph nnrpd[83085]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	18608	42.1%	4.9 MB	58.6%
innd	14836	33.6%	2.0 MB	24.2%
innfeed	9846	22.3%	1.3 MB	16.0%
nnrpd	894	2.0%	97.7 KB	1.2%
nocem	11	0.0%	1.3 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	44196	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	318793	52.8%
Negative hits	239570	39.7%
Cache misses	27874	4.6%
Do not exist	17903	3.0%
TOTAL: 4	604140	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.407	0.0%	36	0.000	11.306	36.000
article cleanup	00:00:00.110	0.0%	17754	0.000	0.006	0.034
article logging	00:00:00.307	0.0%	18363	0.000	0.017	0.268
article parse	00:00:01.024	0.0%	52713	0.000	0.019	0.279
article write	00:00:09.274	0.0%	15573	0.156	0.596	14.200
artlog/artcncl	00:00:00.001	0.0%	36	0.000	0.028	0.143
artlog/artparse	00:00:00.143	0.0%	222	0.000	0.644	10.667
data move	00:00:00.151	0.0%	537276	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.034	0.0%	34	0.000	1.000	4.500
hishave/artcncl	00:00:00.008	0.0%	36	0.000	0.222	0.500
hishave/artparse	00:00:00.000	0.0%	216	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:06.631	0.0%	606071	0.002	0.011	0.267
history sync	00:00:01.457	0.0%	366	0.000	3.981	45.000
history write	00:00:28.025	0.0%	17747	0.068	1.579	6.632
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
hiswrite/artparse	00:00:00.395	0.0%	216	0.000	1.829	17.000
idle	23:58:07.323	99.8%	543671	55.351	158.712	466.601
nntp read	00:00:02.117	0.0%	545687	0.001	0.004	0.007
overview write	00:00:53.776	0.1%	15573	0.118	3.453	504.844
perl filter	00:00:25.309	0.0%	17740	1.122	1.427	2.074
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.267	0.0%	31157	0.000	0.009	0.049
TOTAL: 24:00:54.763	24:00:16.760	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.060	0.0%	41361	0.000	0.001	0.024
article prepare	00:00:00.003	0.0%	15524	0.000	0.000	0.009
article read	00:00:00.327	0.0%	2422	0.000	0.135	11.000
backlog stats	00:00:00.186	0.0%	349772	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.204	0.0%	273098	0.000	0.004	0.009
data write	00:00:01.569	0.0%	260330	0.001	0.006	0.014
idle	23:55:00.443	100.0%	349772	44.318	246.162	739.047
status file	00:00:01.734	0.0%	2930	0.000	0.592	2.000
TOTAL: 23:55:12.860	23:55:05.526	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	40:09:56.732	99.8%	604	61.500	239398.563	600167.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.014	0.0%	1520	0.000	0.009	0.167
readart	00:00:00.390	0.0%	48	0.000	8.125	42.000
TOTAL: 40:14:49.860	40:09:57.136	99.8%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	140
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	160

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	495	21158	3754	16114	1290	17%	68:18:07
2	newsfeeds.xmission.com	2	15322	2512	12753	57	16%	25:22:08
3	news-out.mixmin.net	3	15977	2020	13853	104	12%	24:08:42
4	feeder.eternal-september.org	1	16306	1966	14289	51	12%	23:01:19
5	news-out.aioe.org	3	14655	1145	13436	74	7%	24:09:02
6	newsfeed.fu-berlin.de	11	16185	1113	15036	36	6%	28:03:29
7	csiph-out.feeder.erje.net	1440	75031	1013	73859	159	1%	118:46:10
8	newsfeed.neva.ru	143	10495	656	9644	195	6%	71:49:08
9	csiph-out.news.weretis.net	2	31133	578	30433	122	1%	47:59:48
10	news.fcku.it	11	2004	437	1553	14	21%	22:00:38
11	csiph-out.feed.uzoreto.com	864	35871	186	35549	136	0%	71:11:44
12	news-out.neodome.net	2	16152	130	15969	53	0%	23:59:53
13	nntp.pasdenom.info	2	10330	112	10199	19	1%	23:59:36
14	news.swapon.de	6	1595	44	1551	0	2%	23:08:58
15	news.freedyn.net	2	17560	22	17502	36	0%	23:59:49
16	csiph-out.news.tnetconsulting.net	1	6379	9	6370	0	0%	13:34:51
17	localhost	31	5	5	0	0	100%	00:00:04
18	news.samoylyk.net	3	35353	1	35347	5	0%	18:15:27
19	news.bbs.nz	1	13468	0	13468	0	0%	23:59:52
20	xanadu-bbs.net	1	0	0	0	0	0%	00:10:00
TOTAL: 20		3024	354979	15703	336925	2351	4%	675:58:45

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	9.6 MB	0.0 KB	9.9 MB	19.4 MB	49%	3.9 KB
2	newsfeeds.xmission.com	7.4 MB	0.0 KB	3.1 MB	10.6 MB	70%	4.2 KB
3	feeder.eternal-september.org	5.4 MB	2.3 KB	1.2 MB	6.7 MB	81%	3.4 KB
4	news-out.mixmin.net	4.5 MB	2.0 KB	197.3 KB	4.7 MB	95%	2.3 KB
5	csiph-out.feeder.erje.net	4.3 MB	5.9 KB	2.7 MB	7.0 MB	61%	6.1 KB
6	news-out.aioe.org	4.2 MB	2.0 KB	244.2 KB	4.4 MB	94%	3.7 KB
7	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	266.7 KB	3.0 MB	91%	2.7 KB
8	newsfeed.neva.ru	1.7 MB	4.0 KB	571.6 KB	2.3 MB	75%	2.7 KB
9	csiph-out.news.weretis.net	1.5 MB	11.4 KB	989.4 KB	2.4 MB	59%	3.6 KB
10	news.fcku.it	727.1 KB	0.0 KB	16.8 KB	743.9 KB	97%	1.6 KB
11	csiph-out.feed.uzoreto.com	602.1 KB	0.0 KB	2.8 MB	3.4 MB	17%	10.9 KB
12	news.freedyn.net	505.4 KB	2.0 KB	1.6 MB	2.1 MB	23%	37.0 KB
13	news-out.neodome.net	335.7 KB	0.0 KB	84.2 KB	419.9 KB	79%	2.3 KB
14	nntp.pasdenom.info	256.8 KB	0.0 KB	24.3 KB	281.2 KB	91%	2.1 KB
15	news.swapon.de	109.9 KB	0.0 KB	0.0 KB	109.9 KB	100%	2.5 KB
16	csiph-out.news.tnetconsulting.net	35.2 KB	0.0 KB	0.0 KB	35.2 KB	100%	3.9 KB
17	localhost	5.4 KB	0.0 KB	0.0 KB	5.4 KB	100%	1.1 KB
18	news.samoylyk.net	1.8 KB	2.0 KB	380.4 KB	384.2 KB	0%	64.0 KB
19	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		43.8 MB	31.6 KB	24.1 MB	67.9 MB	64%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 23 04:15:00 - 04:59:59	199	1.3%	0.07	569.0 KB	1.3%	0.21
Sep 23 05:00:00 - 05:59:59	410	2.6%	0.11	1.1 MB	2.5%	0.31
Sep 23 06:00:00 - 06:59:59	449	2.9%	0.12	1.5 MB	3.3%	0.42
Sep 23 07:00:00 - 07:59:59	629	4.0%	0.17	1.5 MB	3.4%	0.42
Sep 23 08:00:00 - 08:59:59	688	4.4%	0.19	2.0 MB	4.6%	0.57
Sep 23 09:00:00 - 09:59:59	637	4.1%	0.18	1.6 MB	3.6%	0.45
Sep 23 10:00:00 - 10:59:59	760	4.9%	0.21	2.0 MB	4.6%	0.57
Sep 23 11:00:00 - 11:59:59	775	5.0%	0.22	2.4 MB	5.4%	0.68
Sep 23 12:00:00 - 12:59:59	884	5.7%	0.25	2.4 MB	5.5%	0.69
Sep 23 13:00:00 - 13:59:59	916	5.9%	0.25	2.4 MB	5.4%	0.67
Sep 23 14:00:00 - 14:59:59	1008	6.5%	0.28	2.7 MB	6.1%	0.76
Sep 23 15:00:00 - 15:59:59	841	5.4%	0.23	2.1 MB	4.7%	0.58
Sep 23 16:00:00 - 16:59:59	884	5.7%	0.25	2.6 MB	5.8%	0.73
Sep 23 17:00:00 - 17:59:59	896	5.8%	0.25	2.3 MB	5.2%	0.65
Sep 23 18:00:00 - 18:59:59	782	5.0%	0.22	2.0 MB	4.5%	0.56
Sep 23 19:00:00 - 19:59:59	789	5.1%	0.22	2.1 MB	4.8%	0.60
Sep 23 20:00:00 - 20:59:59	738	4.7%	0.20	2.0 MB	4.6%	0.58
Sep 23 21:00:00 - 21:59:59	658	4.2%	0.18	2.0 MB	4.5%	0.56
Sep 23 22:00:00 - 22:59:59	650	4.2%	0.18	2.3 MB	5.3%	0.66
Sep 23 23:00:00 - 23:59:59	482	3.1%	0.13	1.5 MB	3.4%	0.42
Sep 24 00:00:00 - 00:59:59	362	2.3%	0.10	1.3 MB	2.9%	0.37
Sep 24 01:00:00 - 01:59:59	348	2.2%	0.10	1.1 MB	2.5%	0.32
Sep 24 02:00:00 - 02:59:59	361	2.3%	0.10	1.0 MB	2.3%	0.29
Sep 24 03:00:00 - 03:59:59	302	1.9%	0.08	1.1 MB	2.6%	0.32
Sep 24 04:00:00 - 04:15:00	121	0.8%	0.13	522.2 KB	1.2%	0.58
TOTAL: 24:00:00	15569	100.0%	0.18	44.0 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1349	106	0	1243
2	newsfeed.fu-berlin.de	466	8	0	458
3	newsfeed.neva.ru	196	41	2	153
4	csiph-out.news.weretis.net	172	33	1	138
5	csiph-out.feeder.erje.net	162	36	3	123
6	news-out.mixmin.net	157	2	1	154
7	csiph-out.feed.uzoreto.com	136	8	0	128
8	news-out.aioe.org	90	63	1	26
9	newsfeeds.xmission.com	78	0	0	78
10	feeder.eternal-september.org	55	33	1	21
11	news-out.neodome.net	53	1	1	51
12	news.freedyn.net	38	1	1	36
13	nntp.pasdenom.info	19	0	0	19
14	news.fcku.it	15	14	0	1
15	news.swapon.de	12	0	0	12
16	news.samoylyk.net	5	0	1	4
17	csiph-out.news.tnetconsulting.net	1	0	1	0
TOTAL: 17		3004	346	13	2645

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it-alt.sport.calcio.juventus	46
alt.bbs.synchronet	42
mozilla.support.thunderbird	42
scinet.stats	27
oesterreich.tratsch	12
lada.auto	11
alt.philosophy	11
lada.talk	11
alt.job	10
alt.windows7.general	9
mozilla.general	9
at.jobs	9
att.jobs	9
lada.kitchen	8
alt.survival	8
mozilla.support.seamonkey	7
alt.binaries.pictures.purebeauty	6
abg.test	4
alt.tv.comedy-central.daily-show	4
mozilla.test	4
TOTAL: 58	346

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1262
EMP (phl)	215
EMP (phn path)	150
Too many newsgroups	54
0	39
EMP (phn nph)	35
Scoring filter	19
HTML Multipart	17
Binary Payload	12
Binary: misplaced binary	8
Too many newsgroups (meow)	3
HTML post	2
bofh violation - invalid distribution	1
TOTAL: 13	1817

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	162
	csiph-out.feed.uzoreto.com	26
	csiph-out.news.xs4all.nl	25
	TOTAL: 3	213
Huge articles
	newsfeeds.xmission.com	48
	csiph-out.feeder.erje.net	45
	csiph-out.news.xs4all.nl	34
	csiph-out.news.weretis.net	28
	news.freedyn.net	26
	csiph-out.feed.uzoreto.com	16
	feeder.eternal-september.org	10
	news.samoylyk.net	4
	newsfeed.fu-berlin.de	2
	TOTAL: 9	213
Including strange strings
	newsfeed.fu-berlin.de	435
	news-out.mixmin.net	55
	csiph-out.news.xs4all.nl	52
	newsfeeds.xmission.com	20
	news-out.aioe.org	18
	news.swapon.de	12
	csiph-out.news.weretis.net	7
	csiph-out.feeder.erje.net	7
	newsfeed.neva.ru	2
	TOTAL: 9	608
No colon-space in header
	csiph-out.news.xs4all.nl	5
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	6
TOTAL: 4		1040

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	news.bbs.nz	28772	1324	13790	31	0	0	4%	23:59:43
2	news.samoylyk.net	38384	891	36966	33	8	2607	2%	23:59:55
3	tnet	18118	442	14708	0	0	0	2%	23:59:51
4	fu-berlin.de	14323	398	13925	0	0	0	2%	23:59:55
5	neva.ru	14130	189	13787	57	0	0	1%	23:59:55
6	erje	15607	115	13929	217	0	0	0%	23:59:47
7	news.freedyn.net	15270	44	14919	0	0	0	0%	23:59:55
8	newsfeed.xs4all.nl	12135	37	11669	1	0	0	0%	23:59:47
9	usenet.pasdenom.info	15446	20	15015	2	0	0	0%	23:59:55
10	xmission	14299	15	12628	2	0	0	0%	23:58:56
11	aioe	11518	12	11092	0	0	0	0%	23:59:55
12	neodome.net	15716	12	15383	0	0	0	0%	23:59:55
13	news.mixmin.net	13230	10	12929	0	0	0	0%	23:59:55
14	eternal-september	13426	10	13014	11	0	0	0%	23:59:55
15	weretis.net	14834	6	14493	0	0	0	0%	23:59:44
16	news.swapon.de	1679	5	1653	0	0	0	0%	23:39:01
17	news.uzoreto.com	14404	4	14088	6	0	0	0%	23:59:54
18	news.fcku.it	1426	0	1426	0	0	0	0%	21:22:06
19	xanadu-bbs	905	0	0	0	0	16474	0%	23:59:26
TOTAL: 19		273622	3534	245414	360	8	19081	1%	452:57:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.samoylyk.net	7.2 MB	81.8 KB	7.2 MB	0.1 KB/s	8.0 KB	23:59:55
2	news.bbs.nz	3.6 MB	96.0 KB	3.7 MB	0.0 KB/s	2.8 KB	23:59:43
3	fu-berlin.de	3.0 MB	0.0 KB	3.0 MB	0.0 KB/s	7.7 KB	23:59:55
4	tnet	1.2 MB	0.0 KB	1.2 MB	0.0 KB/s	2.7 KB	23:59:51
5	neva.ru	498.8 KB	133.7 KB	632.5 KB	0.0 KB/s	2.6 KB	23:59:55
6	news.freedyn.net	324.4 KB	0.0 KB	324.4 KB	0.0 KB/s	7.4 KB	23:59:55
7	erje	240.8 KB	686.0 KB	926.8 KB	0.0 KB/s	2.8 KB	23:59:47
8	newsfeed.xs4all.nl	77.5 KB	3.9 KB	81.5 KB	0.0 KB/s	2.1 KB	23:59:47
9	eternal-september	48.8 KB	53.9 KB	102.7 KB	0.0 KB/s	4.9 KB	23:59:55
10	usenet.pasdenom.info	44.7 KB	2.6 KB	47.3 KB	0.0 KB/s	2.2 KB	23:59:55
11	aioe	22.4 KB	0.0 KB	22.4 KB	0.0 KB/s	1.9 KB	23:59:55
12	xmission	22.2 KB	9.4 KB	31.6 KB	0.0 KB/s	1.9 KB	23:58:56
13	neodome.net	14.7 KB	0.0 KB	14.7 KB	0.0 KB/s	1.2 KB	23:59:55
14	news.mixmin.net	12.4 KB	0.0 KB	12.4 KB	0.0 KB/s	1.2 KB	23:59:55
15	weretis.net	7.9 KB	0.0 KB	7.9 KB	0.0 KB/s	1.3 KB	23:59:44
16	news.swapon.de	5.5 KB	0.0 KB	5.5 KB	0.0 KB/s	1.1 KB	23:39:01
17	news.uzoreto.com	4.5 KB	7.2 KB	11.6 KB	0.0 KB/s	1.2 KB	23:59:54
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:22:06
19	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:26
TOTAL: 19		16.2 MB	1.0 MB	17.3 MB	0.0 KB/s	4.5 KB	452:57:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	096-042-018-108.res.spectrum.com	20	23	42.8 KB	10	4	4	05:51:50
2	a131033.upc-a.chello.nl	20	21	40.6 KB	8	0	0	04:40:52
3	ip72-222-200-117.ph.ph.cox.net	9	4	4.8 KB	4	1	1	04:20:28
TOTAL: 3		49	48	88.1 KB	22	5	5	40:14:48

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	20	23	42.8 KB	10	4	4	05:51:50
2	*.upc-a.chello.nl	20	21	40.6 KB	8	0	0	04:40:52
3	*.ph.ph.cox.net	9	4	4.8 KB	4	1	1	04:20:28
4	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	0	0	00:00:11
5	*.hsd1.wa.comcast.net	2	0	0.0 KB	0	0	0	01:00:01
6	*.range5-81.btcentralplus.com	1	0	0.0 KB	0	0	0	00:00:53
7	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:02
8	unresolved	73	0	0.0 KB	0	0	0	24:20:28
TOTAL: 8		127	48	88.1 KB	22	5	5	40:14:48

NNRP auth users [Top 20]:

User	Conn
ohlandl	4
kev009	1
TOTAL: 2	5

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.990	1.482	0.000	24:20:26
096-042-018-108.res.spectrum.com	1.709	0.339	0.030	05:51:50
a131033.upc-a.chello.nl	1.644	0.349	0.000	04:40:52
ip72-222-200-117.ph.ph.cox.net	0.861	0.225	0.003	04:20:28
c-73-140-175-55.hsd1.wa.comcast.net	0.132	0.016	0.000	01:00:01
host5-81-182-102.range5-81.btcentralplus.com	0.073	0.009	0.000	00:00:53
34.20.97.83.ro.ovo.sc	0.070	0.009	0.000	00:00:11
scan-141.security.ipip.net	0.068	0.017	0.000	00:00:02
151.44.29.3	0.081	0.000	0.000	00:00:02
TOTAL: 9	9.628	2.446	0.033	40:14:48

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
c-73-140-175-55.hsd1.wa.comcast.net	2
host5-81-182-102.range5-81.btcentralplus.com	1
scan-141.security.ipip.net	1
34.20.97.83.ro.ovo.sc	1
151.44.29.3	1
TOTAL: 6	78

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
Connection: close	1
Host: 69.160.37.58:119	1
GET / HTTP/1.1	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
096-042-018-108.res.spectrum.com	11	1
ip72-222-200-117.ph.ph.cox.net	8	0
91.187.33.87	2	0
c-73-140-175-55.hsd1.wa.comcast.net	2	0
TOTAL: 4	23	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Sep 23 04:15:00 -- Sep 24 04:15:00