Daily Usenet report for csiph.com: Nov 11 04:15:00

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Nov 11 10:10:47 csiph nnrpd[71442]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 11 10:10:47 csiph nnrpd[71442]: 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]
Nov 11 16:25:09 csiph nnrpd[86630]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 11 16:25:09 csiph nnrpd[86630]: 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]
Nov 11 21:18:40 csiph nnrpd[98194]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 11 21:18:40 csiph nnrpd[98194]: 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	19994	50.2%	5.1 MB	65.1%
innd	15359	38.6%	2.0 MB	25.7%
innfeed	3511	8.8%	632.0 KB	7.8%
nnrpd	948	2.4%	108.1 KB	1.3%
nocem	16	0.0%	1.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39829	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	329130	55.8%
Negative hits	240951	40.9%
Do not exist	19391	3.3%
Cache misses	328	0.1%
TOTAL: 4	589800	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.361	0.0%	41	0.000	8.805	25.143
article cleanup	00:00:00.127	0.0%	18720	0.000	0.007	0.038
article logging	00:00:00.334	0.0%	19205	0.000	0.017	0.262
article parse	00:00:02.329	0.0%	73038	0.000	0.032	0.393
article write	00:00:23.010	0.0%	16170	0.168	1.423	47.708
artlog/artcncl	00:00:00.000	0.0%	41	0.000	0.000	0.000
artlog/artparse	00:00:00.009	0.0%	699	0.000	0.013	1.000
data move	00:00:01.128	0.0%	572046	0.000	0.002	0.057
hisgrep/artcncl	00:00:00.007	0.0%	40	0.000	0.175	1.000
hishave/artcncl	00:00:00.003	0.0%	41	0.000	0.073	1.000
hishave/artparse	00:00:00.000	0.0%	697	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:38.686	0.0%	590295	0.003	0.066	0.483
history sync	00:00:01.672	0.0%	375	0.000	4.459	94.500
history write	00:00:41.384	0.0%	18720	0.528	2.211	21.565
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.597	0.0%	697	0.000	2.291	14.417
idle	23:46:44.175	99.7%	572069	82.916	149.640	467.369
nntp read	00:00:03.468	0.0%	578007	0.001	0.006	0.110
overview write	00:01:02.226	0.1%	16170	0.155	3.848	78.271
perl filter	00:00:34.797	0.0%	18718	1.196	1.859	8.908
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.298	0.0%	32354	0.000	0.009	0.041
TOTAL: 23:51:02.739	23:50:15.611	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	16364	0.000	0.002	0.014
article prepare	00:00:00.002	0.0%	12368	0.000	0.000	0.016
article read	00:00:00.921	0.0%	1480	0.000	0.622	16.929
backlog stats	00:00:00.220	0.0%	377765	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.423	0.0%	299805	0.000	0.005	0.012
data write	00:00:01.755	0.0%	281444	0.001	0.006	0.016
idle	23:54:58.034	100.0%	377765	130.433	227.914	691.161
status file	00:00:00.208	0.0%	286	0.000	0.727	4.600
TOTAL: 23:55:10.670	23:55:02.595	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	40:44:04.007	99.9%	723	579.642	202827.119	600165.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.016	0.0%	1904	0.000	0.008	0.500
readart	00:00:00.352	0.0%	103	0.000	3.417	42.000
TOTAL: 40:47:27.385	40:44:04.375	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	15684	3301	12349	34	21%	25:08:52
2	feeder.eternal-september.org	6	18039	2707	15219	113	15%	24:34:40
3	csiph-out.news.xs4all.nl	454	18796	2417	14975	1404	12%	68:56:10
4	news-out.mixmin.net	2	16837	2414	14251	172	14%	24:08:56
5	news-out.aioe.org	4	15488	1299	14100	89	8%	24:09:35
6	csiph-out.feeder.erje.net	1440	78388	846	77448	94	1%	119:01:17
7	newsfeed.fu-berlin.de	15	13811	748	12856	207	5%	21:03:12
8	newsfeed.neva.ru	143	14170	671	13290	209	4%	71:26:42
9	csiph-out.news.weretis.net	2	34035	649	33179	207	1%	47:59:47
10	news.fcku.it	15	2045	347	1697	1	16%	22:09:48
11	csiph-out.feed.uzoreto.com	864	25210	335	24291	584	1%	71:11:24
12	news-out.neodome.net	2	17417	203	17145	69	1%	23:59:50
13	nntp.pasdenom.info	2	10827	120	10699	8	1%	23:58:10
14	csiph-out.news.tnetconsulting.net	2	16842	61	16767	14	0%	23:59:56
15	news.swapon.de	6	1479	57	1422	0	3%	24:04:18
16	news.freedyn.net	5	18714	19	18668	27	0%	23:50:12
17	news.samoylyk.net	2	15483	9	15474	0	0%	24:54:01
18	localhost	33	7	7	0	0	100%	00:00:01
19	news.bbs.nz	1	16505	4	16501	0	0%	23:59:52
20	xanadu-bbs.net	18	579	0	579	0	0%	21:59:56
TOTAL: 20		3019	350356	16214	330910	3232	4%	710:36:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	10.6 MB	0.0 KB	1.1 MB	11.7 MB	90%	3.6 KB
2	feeder.eternal-september.org	7.1 MB	2.7 KB	4.0 MB	11.2 MB	63%	4.1 KB
3	csiph-out.news.xs4all.nl	6.9 MB	2.8 KB	32.7 MB	39.6 MB	17%	10.6 KB
4	csiph-out.feeder.erje.net	6.3 MB	0.0 KB	1.6 MB	7.9 MB	80%	8.6 KB
5	news-out.mixmin.net	4.8 MB	0.0 KB	348.6 KB	5.1 MB	93%	2.0 KB
6	news-out.aioe.org	4.4 MB	0.0 KB	197.2 KB	4.6 MB	95%	3.4 KB
7	csiph-out.news.weretis.net	1.8 MB	0.0 KB	1.1 MB	2.8 MB	62%	3.4 KB
8	newsfeed.fu-berlin.de	1.7 MB	0.0 KB	479.4 KB	2.2 MB	78%	2.4 KB
9	csiph-out.feed.uzoreto.com	1.7 MB	0.0 KB	29.0 MB	30.7 MB	5%	34.2 KB
10	newsfeed.neva.ru	1.5 MB	0.0 KB	484.8 KB	2.0 MB	75%	2.3 KB
11	news-out.neodome.net	655.1 KB	0.0 KB	497.4 KB	1.1 MB	56%	4.2 KB
12	news.fcku.it	552.7 KB	0.0 KB	2.0 KB	554.7 KB	99%	1.6 KB
13	nntp.pasdenom.info	270.3 KB	0.0 KB	10.4 KB	280.7 KB	96%	2.2 KB
14	csiph-out.news.tnetconsulting.net	191.8 KB	0.0 KB	97.8 KB	289.6 KB	66%	3.9 KB
15	news.freedyn.net	189.5 KB	0.0 KB	1.4 MB	1.6 MB	11%	36.3 KB
16	news.swapon.de	132.1 KB	0.0 KB	0.0 KB	132.1 KB	100%	2.3 KB
17	news.samoylyk.net	91.8 KB	0.0 KB	0.0 KB	91.8 KB	100%	10.2 KB
18	localhost	8.6 KB	0.0 KB	0.0 KB	8.6 KB	100%	1.2 KB
19	news.bbs.nz	6.4 KB	0.0 KB	0.0 KB	6.4 KB	100%	1.6 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		49.0 MB	5.5 KB	73.0 MB	122.0 MB	40%	6.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 11 04:15:00 - 04:59:59	225	1.4%	0.08	742.7 KB	1.5%	0.28
Nov 11 05:00:00 - 05:59:59	354	2.2%	0.10	1.9 MB	3.8%	0.53
Nov 11 06:00:00 - 06:59:59	359	2.2%	0.10	1.1 MB	2.2%	0.30
Nov 11 07:00:00 - 07:59:59	462	2.8%	0.13	1.6 MB	3.2%	0.45
Nov 11 08:00:00 - 08:59:59	739	4.6%	0.21	1.7 MB	3.5%	0.49
Nov 11 09:00:00 - 09:59:59	828	5.1%	0.23	2.4 MB	5.0%	0.69
Nov 11 10:00:00 - 10:59:59	848	5.2%	0.24	2.3 MB	4.7%	0.66
Nov 11 11:00:00 - 11:59:59	815	5.0%	0.23	2.1 MB	4.3%	0.59
Nov 11 12:00:00 - 12:59:59	765	4.7%	0.21	1.9 MB	3.9%	0.55
Nov 11 13:00:00 - 13:59:59	859	5.3%	0.24	2.1 MB	4.3%	0.60
Nov 11 14:00:00 - 14:59:59	864	5.3%	0.24	2.3 MB	4.7%	0.65
Nov 11 15:00:00 - 15:59:59	973	6.0%	0.27	3.0 MB	6.2%	0.86
Nov 11 16:00:00 - 16:59:59	1007	6.2%	0.28	3.1 MB	6.3%	0.88
Nov 11 17:00:00 - 17:59:59	1011	6.2%	0.28	4.0 MB	8.2%	1.14
Nov 11 18:00:00 - 18:59:59	859	5.3%	0.24	2.3 MB	4.7%	0.65
Nov 11 19:00:00 - 19:59:59	916	5.6%	0.25	3.1 MB	6.3%	0.88
Nov 11 20:00:00 - 20:59:59	813	5.0%	0.23	2.6 MB	5.4%	0.75
Nov 11 21:00:00 - 21:59:59	751	4.6%	0.21	2.3 MB	4.8%	0.66
Nov 11 22:00:00 - 22:59:59	707	4.4%	0.20	2.0 MB	4.0%	0.56
Nov 11 23:00:00 - 23:59:59	520	3.2%	0.14	1.7 MB	3.5%	0.48
Nov 12 00:00:00 - 00:59:59	462	2.8%	0.13	1.4 MB	2.9%	0.40
Nov 12 01:00:00 - 01:59:59	324	2.0%	0.09	1.0 MB	2.1%	0.30
Nov 12 02:00:00 - 02:59:59	357	2.2%	0.10	1.1 MB	2.2%	0.31
Nov 12 03:00:00 - 03:59:59	319	2.0%	0.09	912.9 KB	1.8%	0.25
Nov 12 04:00:00 - 04:15:00	77	0.5%	0.09	241.7 KB	0.5%	0.27
TOTAL: 24:00:00	16214	100.0%	0.19	49.0 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1413	83	1	1329
2	csiph-out.feed.uzoreto.com	588	11	0	577
3	newsfeed.fu-berlin.de	586	19	0	567
4	news-out.mixmin.net	226	9	0	217
5	newsfeed.neva.ru	210	151	0	59
6	csiph-out.news.weretis.net	184	46	0	138
7	news-out.aioe.org	114	63	0	51
8	feeder.eternal-september.org	109	30	1	78
9	csiph-out.feeder.erje.net	95	47	0	48
10	news-out.neodome.net	78	5	0	73
11	csiph-out.news.tnetconsulting.net	41	34	0	7
12	newsfeeds.xmission.com	36	21	0	15
13	news.freedyn.net	30	2	0	28
14	news.swapon.de	19	0	0	19
15	nntp.pasdenom.info	8	0	0	8
16	news.fcku.it	2	1	0	1
TOTAL: 16		3739	522	2	3215

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	127
alt.bbs.synchronet	41
scinet.stats	30
alt.testing	30
alt.politics	28
spline.eisfair	22
lada.auto	21
nntp4.test3	20
alt.computer.workshop	19
it-alt.sport.calcio.juventus	14
nntp4.test	13
mozilla.support.thunderbird	10
alt.politics.usa	10
alt.job	9
alt.binaries.pictures.purebeauty	9
mozilla.general	9
free.it.religioni.scientology	9
mozilla.test	8
alt.windows7.general	7
at.jobs	7
TOTAL: 71	522

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1335
EMP (phn path)	332
EMP (phl)	103
EMP (phn nph)	84
0	83
Too many newsgroups	71
Scoring filter	5
HTML Multipart	5
Too many newsgroups (meow)	4
Binary: misplaced binary	3
Binary Payload	3
HTML post	2
TOTAL: 12	2030

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	csiph-out.feed.uzoreto.com	2
	csiph-out.news.xs4all.nl	2
	TOTAL: 3	49
Huge articles
	csiph-out.news.xs4all.nl	302
	csiph-out.feed.uzoreto.com	288
	feeder.eternal-september.org	30
	csiph-out.feeder.erje.net	27
	news.freedyn.net	16
	newsfeeds.xmission.com	14
	news-out.neodome.net	7
	csiph-out.news.tnetconsulting.net	7
	csiph-out.news.weretis.net	6
	newsfeed.fu-berlin.de	1
	TOTAL: 11	699
Including strange strings
	newsfeed.fu-berlin.de	363
	news-out.mixmin.net	54
	news-out.aioe.org	23
	news.swapon.de	19
	csiph-out.news.weretis.net	11
	csiph-out.news.xs4all.nl	9
	csiph-out.feed.uzoreto.com	3
	csiph-out.feeder.erje.net	1
	newsfeed.neva.ru	1
	newsfeeds.xmission.com	1
	TOTAL: 11	486
No colon-space in header
	csiph-out.news.xs4all.nl	2
	TOTAL: 1	2
TOTAL: 4		1236

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19100	749	15455	10	92	3%	23:59:54
2	news.bbs.nz	30375	495	15138	18	0	1%	23:50:09
3	fu-berlin.de	15154	478	14675	0	0	3%	23:50:09
4	news.samoylyk.net	16572	474	15673	7	0	2%	23:50:09
5	tnet	17104	62	15591	1	0	0%	23:50:09
6	erje	17324	57	14693	332	0	0%	23:50:09
7	neva.ru	14719	38	14469	75	0	0%	23:50:09
8	newsfeed.xs4all.nl	14048	29	13597	1	10	0%	23:54:27
9	aioe	12177	16	11727	0	0	0%	23:56:16
10	eternal-september	13131	15	12820	0	0	0%	23:59:54
11	usenet.pasdenom.info	16177	15	15565	2	20	0%	23:59:54
12	news.freedyn.net	15694	14	15278	139	49	0%	23:58:18
13	xmission	14112	13	12393	0	0	0%	23:51:36
14	news.swapon.de	1471	10	1453	0	0	0%	23:21:25
15	news.mixmin.net	13394	9	13119	0	0	0%	23:58:44
16	weretis.net	15353	8	14894	0	0	0%	23:52:59
17	neodome.net	16294	7	15937	0	0	0%	23:50:09
18	news.uzoreto.com	15592	0	15255	16	0	0%	23:50:09
19	news.fcku.it	1586	0	1586	0	0	0%	21:50:00
TOTAL: 19		279377	2489	249318	601	171	0%	451:24:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.9 MB	36.9 KB	6.0 MB	0.1 KB/s	8.1 KB	23:59:54
2	fu-berlin.de	5.2 MB	0.0 KB	5.2 MB	0.1 KB/s	11.1 KB	23:50:09
3	news.samoylyk.net	5.1 MB	61.6 KB	5.2 MB	0.1 KB/s	11.0 KB	23:50:09
4	news.bbs.nz	1.3 MB	114.7 KB	1.4 MB	0.0 KB/s	2.7 KB	23:50:09
5	tnet	147.7 KB	2.7 KB	150.4 KB	0.0 KB/s	2.4 KB	23:50:09
6	erje	122.6 KB	1.0 MB	1.1 MB	0.0 KB/s	3.0 KB	23:50:09
7	neva.ru	77.8 KB	162.6 KB	240.4 KB	0.0 KB/s	2.1 KB	23:50:09
8	newsfeed.xs4all.nl	71.5 KB	1.9 KB	73.4 KB	0.0 KB/s	2.4 KB	23:54:27
9	news.freedyn.net	60.5 KB	1.1 MB	1.2 MB	0.0 KB/s	8.0 KB	23:58:18
10	eternal-september	39.0 KB	0.0 KB	39.0 KB	0.0 KB/s	2.6 KB	23:59:54
11	xmission	38.2 KB	0.0 KB	38.2 KB	0.0 KB/s	2.9 KB	23:51:36
12	aioe	30.4 KB	0.0 KB	30.4 KB	0.0 KB/s	1.9 KB	23:56:16
13	usenet.pasdenom.info	23.9 KB	15.1 KB	39.0 KB	0.0 KB/s	2.3 KB	23:59:54
14	news.swapon.de	14.5 KB	0.0 KB	14.5 KB	0.0 KB/s	1.5 KB	23:21:25
15	news.mixmin.net	11.5 KB	0.0 KB	11.5 KB	0.0 KB/s	1.3 KB	23:58:44
16	weretis.net	10.0 KB	0.0 KB	10.0 KB	0.0 KB/s	1.3 KB	23:52:59
17	neodome.net	8.6 KB	0.0 KB	8.6 KB	0.0 KB/s	1.2 KB	23:50:09
18	news.uzoreto.com	0.0 KB	78.2 KB	78.2 KB	0.0 KB/s	4.9 KB	23:50:09
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:50:00
TOTAL: 19		18.2 MB	2.6 MB	20.8 MB	0.0 KB/s	6.9 KB	451:24:39

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-162-19.home.otenet.gr	1	50	94.7 KB	1	0	0	00:00:32
2	a131033.upc-a.chello.nl	17	24	39.4 KB	6	0	0	03:19:51
3	ip72-222-200-117.ph.ph.cox.net	14	17	24.2 KB	20	0	0	05:41:13
4	071-013-248-095.res.spectrum.com	29	12	16.4 KB	13	7	7	08:08:55
TOTAL: 4		61	103	174.7 KB	40	7	7	40:47:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	1	50	94.7 KB	1	0	0	00:00:32
2	*.upc-a.chello.nl	17	24	39.4 KB	6	0	0	03:19:51
3	*.ph.ph.cox.net	14	17	24.2 KB	20	0	0	05:41:13
4	*.res.spectrum.com	29	12	16.4 KB	13	7	7	08:08:55
5	*.census.shodan.io	2	0	0.0 KB	0	0	0	00:00:02
6	*.range86-138.btcentralplus.com	3	0	0.0 KB	0	0	0	00:33:20
7	*.net.upcbroadband.cz	25	0	0.0 KB	0	0	0	08:06:26
8	unresolved	45	0	0.0 KB	0	0	0	14:56:51
9	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	0	0	00:00:11
TOTAL: 9		137	103	174.7 KB	40	7	7	40:47:26

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
TOTAL: 1	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	2.913	1.724	0.000	14:56:51
071-013-248-095.res.spectrum.com	2.056	0.927	0.257	08:08:55
ip-78-102-198-192.net.upcbroadband.cz	1.717	1.167	0.000	08:06:26
ip72-222-200-117.ph.ph.cox.net	1.307	0.452	0.000	05:41:13
a131033.upc-a.chello.nl	1.477	0.759	0.000	03:19:51
host86-138-214-15.range86-138.btcentralplus.com	0.190	0.320	0.000	00:33:20
ppp-2-84-162-19.home.otenet.gr	0.105	0.023	0.000	00:00:32
34.20.97.83.ro.ovo.sc	0.049	0.028	0.000	00:00:11
house.census.shodan.io	0.068	0.030	0.000	00:00:01
mason.census.shodan.io	0.067	0.008	0.000	00:00:01
TOTAL: 10	9.949	5.438	0.257	40:47:26

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	45
ip-78-102-198-192.net.upcbroadband.cz	25
host86-138-214-15.range86-138.btcentralplus.com	3
mason.census.shodan.io	1
house.census.shodan.io	1
34.20.97.83.ro.ovo.sc	1
TOTAL: 6	76

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	9	0
071-013-248-095.res.spectrum.com	8	0
host86-138-214-15.range86-138.btcentralplus.com	1	0
TOTAL: 3	18	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 11 04:15:00 -- Nov 12 04:15:00