Daily Usenet report for csiph.com: Feb 27 04:15:00

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Feb 27 15:04:06 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[66413]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 27 15:04:06 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[66413]: 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]
Feb 27 22:11:19 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[90558]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 27 22:11:19 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[90558]: 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]
Feb 27 22:55:19 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[92917]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 27 22:55:19 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[92917]: 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	32309	48.2%	6.0 MB	52.5%
innd	28991	43.2%	4.5 MB	38.9%
innfeed	3711	5.5%	722.4 KB	6.1%
nnrpd	2064	3.1%	292.6 KB	2.5%
nocem	13	0.0%	1.8 KB	0.0%
controlchan	1	0.0%	0.3 KB	0.0%
TOTAL: 6	67089	100.0%	11.5 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	251266	50.1%
Negative hits	215914	43.1%
Do not exist	31675	6.3%
Cache misses	2268	0.5%
TOTAL: 4	501123	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.051	0.0%	31	0.000	33.903	145.000
article cleanup	00:00:00.188	0.0%	29318	0.000	0.006	0.021
article logging	00:00:02.232	0.0%	29726	0.000	0.075	1.214
article parse	00:00:12.504	0.0%	764724	0.000	0.016	0.232
article write	00:01:24.216	0.1%	13169	0.222	6.395	50.634
artlog/artcncl	00:00:00.003	0.0%	31	0.000	0.097	1.000
artlog/artparse	00:00:00.268	0.0%	2683	0.000	0.100	3.250
data move	00:00:00.853	0.0%	1194989	0.000	0.001	0.006
hisgrep/artcncl	00:00:00.009	0.0%	30	0.000	0.300	1.000
hishave/artcncl	00:00:00.003	0.0%	31	0.000	0.097	1.000
hishave/artparse	00:00:00.011	0.0%	2673	0.000	0.004	0.071
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:02.516	0.1%	502080	0.005	0.125	0.918
history sync	00:00:01.737	0.0%	344	0.000	5.049	68.500
history write	00:01:18.511	0.1%	29299	0.263	2.680	10.636
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:07.271	0.0%	2673	0.000	2.720	16.122
idle	23:46:29.925	98.8%	1165348	26.147	73.446	420.144
nntp read	00:00:05.103	0.0%	1174461	0.002	0.004	0.008
overview write	00:11:43.142	0.8%	13169	0.130	53.394	35826.688
perl filter	00:00:29.796	0.0%	29288	0.729	1.017	4.173
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.241	0.0%	26351	0.000	0.009	0.043
TOTAL: 24:04:01.314	24:02:59.580	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.043	0.0%	13215	0.000	0.003	0.021
article prepare	00:00:00.001	0.0%	4588	0.000	0.000	0.016
article read	00:00:00.299	0.0%	862	0.000	0.347	46.000
backlog stats	00:00:00.184	0.0%	252914	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.898	0.0%	196694	0.000	0.005	0.012
data write	00:00:01.141	0.0%	184597	0.000	0.006	0.020
idle	23:54:31.814	100.0%	252914	162.721	340.320	1550.194
status file	00:00:01.032	0.0%	387	0.000	2.667	81.000
TOTAL: 23:54:44.694	23:54:35.412	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	37:26:50.193	99.3%	1116	0.000	120797.664	600208.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.016	0.0%	2599	0.000	0.006	0.167
readart	00:00:00.130	0.0%	7	11.500	18.571	41.000
TOTAL: 37:43:33.053	37:26:50.339	99.3%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	12969	2013	10953	3	15%	25:56:34
2	feeder.eternal-september.org	14	8058	1680	3609	2769	20%	28:19:32
3	news-out.mixmin.net	3	14552	1671	12690	191	11%	24:08:56
4	csiph-out.news.xs4all.nl	0	8663	1657	6000	1006	19%	74:20:36
5	news.freedyn.net	4	16014	1547	13801	666	9%	24:12:01
6	csiph-out.news.weretis.net	5	29980	1074	28758	148	3%	52:44:09
7	csiph-out.feeder.erje.net	1440	67365	842	66233	290	1%	119:07:33
8	newsfeed.fu-berlin.de	11	26435	792	12845	12798	2%	25:10:11
9	news-out.neodome.net	3	15110	540	13897	673	3%	24:59:55
10	newsfeed.neva.ru	146	19241	484	18567	190	2%	71:38:52
11	csiph-out.feed.uzoreto.com	864	22367	401	21646	320	1%	71:27:08
12	news-out.aioe.org	1	12655	308	12226	121	2%	23:59:58
13	nntp.pasdenom.info	2	9033	95	8891	47	1%	23:58:11
14	news.fcku.it	19	827	81	745	1	9%	20:57:58
15	news.swapon.de	12	1067	59	1007	1	5%	23:26:23
16	csiph-out.news.tnetconsulting.net	2	4216	10	4205	1	0%	10:36:33
17	news.bbs.nz	2	4669	4	4665	0	0%	11:25:39
18	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 18		2557	273221	13258	240738	19225	4%	656:30:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	6.5 MB	0.0 KB	202.6 KB	6.7 MB	97%	3.4 KB
2	feeder.eternal-september.org	4.9 MB	5.1 KB	1.6 GB	1.6 GB	0%	375.9 KB
3	csiph-out.news.xs4all.nl	4.6 MB	0.0 KB	7.7 MB	12.3 MB	37%	4.7 KB
4	csiph-out.feeder.erje.net	4.6 MB	45.5 KB	5.1 MB	9.7 MB	47%	8.8 KB
5	news.freedyn.net	3.9 MB	0.0 KB	6.0 MB	9.9 MB	39%	4.6 KB
6	news-out.mixmin.net	3.6 MB	0.0 KB	516.6 KB	4.1 MB	87%	2.3 KB
7	csiph-out.news.weretis.net	3.1 MB	6.3 KB	1.0 MB	4.2 MB	75%	3.5 KB
8	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	31.1 MB	33.0 MB	5%	2.5 KB
9	news-out.neodome.net	1.7 MB	5.1 KB	2.5 MB	4.2 MB	40%	3.6 KB
10	csiph-out.feed.uzoreto.com	1.6 MB	0.0 KB	7.3 MB	8.8 MB	17%	12.5 KB
11	newsfeed.neva.ru	1.5 MB	0.0 KB	539.5 KB	2.0 MB	73%	3.1 KB
12	news-out.aioe.org	1.3 MB	1.3 KB	393.2 KB	1.7 MB	77%	4.0 KB
13	nntp.pasdenom.info	218.8 KB	1.3 KB	117.3 KB	337.4 KB	64%	2.4 KB
14	news.swapon.de	169.4 KB	0.0 KB	80.3 KB	249.7 KB	67%	4.2 KB
15	news.fcku.it	118.1 KB	0.0 KB	1.2 KB	119.4 KB	98%	1.5 KB
16	csiph-out.news.tnetconsulting.net	44.0 KB	0.0 KB	1.4 KB	45.4 KB	96%	4.1 KB
17	news.bbs.nz	8.5 KB	0.0 KB	0.0 KB	8.5 KB	100%	2.1 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		39.7 MB	64.6 KB	1.7 GB	1.7 GB	2%	54.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 27 04:15:00 - 04:59:59	164	1.2%	0.06	457.8 KB	1.1%	0.17
Feb 27 05:00:00 - 05:59:59	289	2.2%	0.08	930.5 KB	2.3%	0.26
Feb 27 06:00:00 - 06:59:59	287	2.2%	0.08	942.0 KB	2.3%	0.26
Feb 27 07:00:00 - 07:59:59	413	3.1%	0.11	2.3 MB	5.8%	0.65
Feb 27 08:00:00 - 08:59:59	502	3.8%	0.14	1.6 MB	4.1%	0.46
Feb 27 09:00:00 - 09:59:59	560	4.3%	0.16	1.9 MB	4.8%	0.55
Feb 27 10:00:00 - 10:59:59	683	5.2%	0.19	1.8 MB	4.6%	0.51
Feb 27 11:00:00 - 11:59:59	631	4.8%	0.18	1.7 MB	4.3%	0.48
Feb 27 12:00:00 - 12:59:59	572	4.3%	0.16	1.5 MB	3.7%	0.42
Feb 27 13:00:00 - 13:59:59	810	6.2%	0.23	2.3 MB	5.9%	0.66
Feb 27 14:00:00 - 14:59:59	751	5.7%	0.21	2.2 MB	5.5%	0.62
Feb 27 15:00:00 - 15:59:59	781	5.9%	0.22	2.1 MB	5.2%	0.59
Feb 27 16:00:00 - 16:59:59	727	5.5%	0.20	2.1 MB	5.2%	0.59
Feb 27 17:00:00 - 17:59:59	795	6.0%	0.22	2.5 MB	6.2%	0.70
Feb 27 18:00:00 - 18:59:59	831	6.3%	0.23	2.4 MB	6.1%	0.69
Feb 27 19:00:00 - 19:59:59	617	4.7%	0.17	1.7 MB	4.2%	0.48
Feb 27 20:00:00 - 20:59:59	605	4.6%	0.17	1.7 MB	4.3%	0.49
Feb 27 21:00:00 - 21:59:59	578	4.4%	0.16	1.7 MB	4.3%	0.48
Feb 27 22:00:00 - 22:59:59	588	4.5%	0.16	1.6 MB	4.1%	0.47
Feb 27 23:00:00 - 23:59:59	539	4.1%	0.15	1.7 MB	4.4%	0.49
Feb 28 00:00:00 - 00:59:59	391	3.0%	0.11	1.2 MB	3.1%	0.35
Feb 28 01:00:00 - 01:59:59	290	2.2%	0.08	860.7 KB	2.1%	0.24
Feb 28 02:00:00 - 02:59:59	315	2.4%	0.09	950.9 KB	2.3%	0.26
Feb 28 03:00:00 - 03:59:59	365	2.8%	0.10	1.2 MB	3.1%	0.35
Feb 28 04:00:00 - 04:15:00	67	0.5%	0.07	324.3 KB	0.8%	0.36
TOTAL: 24:00:00	13151	100.0%	0.15	39.6 MB	100.0%	0.47

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.fu-berlin.de	13103	18	0	13085
2	feeder.eternal-september.org	2490	29	2	2459
3	csiph-out.news.xs4all.nl	925	164	0	761
4	news-out.neodome.net	679	1	2	676
5	news.freedyn.net	676	22	0	654
6	csiph-out.feed.uzoreto.com	332	22	0	310
7	news-out.mixmin.net	202	15	0	187
8	csiph-out.feeder.erje.net	199	37	21	141
9	newsfeed.neva.ru	196	97	0	99
10	csiph-out.news.weretis.net	165	70	2	93
11	news-out.aioe.org	101	81	1	19
12	news.swapon.de	29	0	0	29
13	newsfeeds.xmission.com	22	0	0	22
14	nntp.pasdenom.info	4	0	1	3
15	csiph-out.news.tnetconsulting.net	3	2	0	1
16	news.fcku.it	1	1	0	0
TOTAL: 16		19127	559	29	18539

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.politics	155
lada.talk	108
alt.computer.workshop	89
alt.bbs.synchronet	24
it-alt.sport.calcio.juventus	21
scinet.stats	18
spline.eisfair	18
alt.binaries.pictures.purebeauty	15
alt.job	14
at.jobs	8
it-alt.comp.software.mozilla	7
alt.politics.usa	6
alt.slack.goathead	5
mozilla.support.thunderbird	4
att.jobs	4
mozilla.general	4
spline.eisfair.dev	4
alt.net.dn42.users	3
alt.conspiracy.retards	3
alt.binaries.books	3
TOTAL: 59	559

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	12789
EMP (md5)	1593
EMP (phn path)	684
Too many newsgroups	173
EMP (phl)	122
EMP (phn nph)	49
0	40
Binary Payload	35
HTML Multipart	10
Too many newsgroups (meow)	4
Scoring filter	4
HTML post	2
TOTAL: 12	15505

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	2391
	csiph-out.feeder.erje.net	76
	news.freedyn.net	51
	csiph-out.news.xs4all.nl	36
	csiph-out.feed.uzoreto.com	34
	news-out.neodome.net	11
	csiph-out.news.weretis.net	9
	news-out.mixmin.net	3
	newsfeeds.xmission.com	3
	news.swapon.de	1
	TOTAL: 10	2615
Including strange strings
	newsfeed.fu-berlin.de	286
	csiph-out.news.xs4all.nl	35
	news.swapon.de	28
	newsfeeds.xmission.com	19
	csiph-out.news.weretis.net	15
	csiph-out.feed.uzoreto.com	12
	newsfeed.neva.ru	5
	csiph-out.feeder.erje.net	4
	nntp.pasdenom.info	2
	feeder.eternal-september.org	2
	TOTAL: 11	409
No colon-space in header
	csiph-out.news.xs4all.nl	7
	csiph-out.feeder.erje.net	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	10
TOTAL: 4		3079

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	12126	426	11700	0	0	3%	23:59:29
2	news.bbs.nz	24775	151	12525	16	0	0%	24:04:43
3	xmission	12347	102	10618	6	0	0%	23:59:17
4	newsfeed.xs4all.nl	12134	84	11334	1	78	0%	23:59:51
5	tnet	13471	83	12606	3	0	0%	24:04:36
6	usenet.pasdenom.info	12923	57	12491	18	13	0%	23:57:43
7	neva.ru	12077	14	11932	62	0	0%	24:03:38
8	news.freedyn.net	11484	11	11303	10	0	0%	23:58:59
9	erje	12132	4	11977	63	0	0%	23:54:36
10	neodome.net	12868	3	12576	0	0	0%	24:05:46
11	aioe	11114	3	10831	0	0	0%	24:03:15
12	news.mixmin.net	10962	3	10750	0	0	0%	23:58:59
13	news.uzoreto.com	12298	2	12107	23	0	0%	24:00:45
14	news.swapon.de	1166	2	1150	0	0	0%	23:07:47
15	xanadu-bbs	0	0	0	0	13111	0%	23:50:09
16	weretis.net	11683	0	11497	2	0	0%	24:02:05
17	eternal-september	0	0	0	0	10688	0%	23:50:07
18	news.samoylyk.net	0	0	0	0	13146	0%	23:57:48
19	news.fcku.it	701	0	700	0	0	0%	19:49:14
TOTAL: 19		184261	945	166097	204	37036	0%	450:48:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	fu-berlin.de	3.4 MB	0.0 KB	3.4 MB	8.3 KB	23:59:29
2	news.bbs.nz	428.4 KB	58.2 KB	486.6 KB	2.9 KB	24:04:43
3	newsfeed.xs4all.nl	288.1 KB	10.4 KB	298.6 KB	3.5 KB	23:59:51
4	xmission	277.7 KB	11.1 KB	288.8 KB	2.7 KB	23:59:17
5	usenet.pasdenom.info	260.6 KB	36.2 KB	296.8 KB	4.0 KB	23:57:43
6	tnet	238.3 KB	3.6 KB	241.8 KB	2.8 KB	24:04:36
7	news.freedyn.net	56.1 KB	144.2 KB	200.3 KB	9.5 KB	23:58:59
8	news.mixmin.net	39.0 KB	0.0 KB	39.0 KB	13.0 KB	23:58:59
9	neva.ru	36.8 KB	208.1 KB	244.8 KB	3.2 KB	24:03:38
10	erje	20.1 KB	240.6 KB	260.8 KB	3.9 KB	23:54:36
11	aioe	13.9 KB	0.0 KB	13.9 KB	4.6 KB	24:03:15
12	neodome.net	5.2 KB	0.0 KB	5.2 KB	1.7 KB	24:05:46
13	news.uzoreto.com	4.9 KB	97.7 KB	102.6 KB	4.1 KB	24:00:45
14	news.swapon.de	3.5 KB	0.0 KB	3.5 KB	1.7 KB	23:07:47
15	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0	23:50:09
16	weretis.net	0.0 KB	5.7 KB	5.7 KB	2.9 KB	24:02:05
17	eternal-september	0.0 KB	0.0 KB	0.0 KB	0	23:50:07
18	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:57:48
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	19:49:14
TOTAL: 19		5.1 MB	815.9 KB	5.9 MB	5.2 KB	450:48:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	91.187.33.87	288	4	10.7 KB	4	27:10:05
2	a131033.upc-a.chello.nl	31	3	8.4 KB	2	05:48:41
TOTAL: 2		319	7	19.1 KB	6	37:43:04

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	297	4	10.7 KB	4	31:13:02
2	*.upc-a.chello.nl	31	3	8.4 KB	2	05:48:41
3	*.range31-49.btcentralplus.com	3	0	0.0 KB	0	00:41:20
TOTAL: 3		331	7	19.1 KB	6	37:43:04

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.450	4.495	27:10:05
a131033.upc-a.chello.nl	2.003	0.433	05:48:41
172.83.100.250	0.665	0.132	04:02:45
host31-49-229-214.range31-49.btcentralplus.com	0.205	0.039	00:41:20
178.62.217.201	0.043	0.035	00:00:10
TOTAL: 5	20.366	5.134	37:43:04

Curious NNRP server explorers [Top 20]:

System	Conn
172.83.100.250	8
host31-49-229-214.range31-49.btcentralplus.com	3
178.62.217.201	1
TOTAL: 3	12

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.250	8	0
91.187.33.87	2	0
host31-49-229-214.range31-49.btcentralplus.com	1	0
TOTAL: 3	11	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Feb 27 04:15:00 -- Feb 28 04:15:00