Daily Usenet report for csiph.com: Apr 10 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Apr 10 08:05:27 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[24138]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 10 08:05:27 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[24138]: 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]
Apr 10 18:32:24 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[59490]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 10 18:32:24 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[59490]: 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
innd	22869	47.5%	3.7 MB	39.1%
inn	17889	37.2%	4.5 MB	48.0%
innfeed	5214	10.8%	946.6 KB	9.8%
nnrpd	2122	4.4%	295.8 KB	3.1%
nocem	4	0.0%	0.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	48099	100.0%	9.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	268367	52.5%
Negative hits	222491	43.5%
Do not exist	17311	3.4%
Cache misses	3453	0.7%
TOTAL: 4	511622	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.434	0.0%	23	0.000	18.870	36.667
article cleanup	00:00:00.095	0.0%	17015	0.000	0.006	0.064
article logging	00:00:00.594	0.0%	17471	0.000	0.034	0.720
article parse	00:00:01.860	0.0%	54730	0.000	0.034	2.129
article write	00:00:52.656	0.1%	14013	0.174	3.758	35.475
artlog/artcncl	00:00:00.000	0.0%	23	0.000	0.000	0.000
artlog/artparse	00:00:00.026	0.0%	331	0.000	0.079	1.800
data move	00:00:00.146	0.0%	470516	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.050	0.0%	22	0.000	2.273	16.000
hishave/artcncl	00:00:00.017	0.0%	23	0.000	0.739	4.000
hishave/artparse	00:00:00.001	0.0%	330	0.000	0.003	0.083
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:40.991	0.0%	512050	0.002	0.080	0.417
history sync	00:00:01.424	0.0%	377	0.000	3.777	62.000
history write	00:00:50.845	0.1%	16987	0.206	2.993	17.609
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:01.376	0.0%	330	0.000	4.170	108.400
idle	23:42:07.008	98.9%	478505	98.132	178.320	548.682
nntp read	00:00:02.169	0.0%	483277	0.002	0.004	0.011
overview write	00:12:06.766	0.8%	14013	0.138	51.864	10253.000
perl filter	00:00:23.051	0.0%	16986	0.995	1.357	2.390
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.241	0.0%	28030	0.000	0.009	0.032
TOTAL: 23:57:59.167	23:57:09.750	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	14358	0.000	0.003	0.455
article prepare	00:00:00.000	0.0%	4616	0.000	0.000	0.000
article read	00:00:01.528	0.0%	988	0.000	1.547	505.500
backlog stats	00:00:00.162	0.0%	272487	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.939	0.0%	212584	0.000	0.004	0.035
data write	00:00:01.203	0.0%	199413	0.002	0.006	0.013
idle	23:54:44.631	100.0%	272487	178.178	315.922	2127.855
status file	00:00:00.714	0.0%	1003	0.000	0.712	10.000
TOTAL: 23:55:00.363	23:54:49.215	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	44:47:22.664	99.9%	1117	0.000	144353.325	600034.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.020	0.0%	2582	0.000	0.008	0.167
readart	00:00:00.081	0.0%	4	0.000	20.250	40.000
TOTAL: 44:49:02.384	44:47:22.765	99.9%	-	-	-	-

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	csiph-out.news.xs4all.nl	2302	16295	2455	12510	1330	15%	124:10:36
2	feeder.eternal-september.org	12	4800	2153	2521	126	44%	26:34:32
3	csiph-out.feeder.erje.net	1440	70174	1547	68004	623	2%	119:01:57
4	newsfeeds.xmission.com	3	12625	1470	11153	2	11%	23:30:30
5	news-out.aioe.org	2	12677	1346	11297	34	10%	23:59:53
6	news-out.mixmin.net	1	15125	1050	13698	377	6%	23:59:28
7	csiph-out.news.weretis.net	4	28052	909	27059	84	3%	47:59:15
8	csiph-out.feed.uzoreto.com	864	23914	898	22343	673	3%	71:20:39
9	newsfeed.fu-berlin.de	9	11316	768	10376	172	6%	19:40:28
10	newsfeed.neva.ru	139	15972	400	15470	102	2%	71:51:18
11	news-out.neodome.net	1	15301	252	15006	43	1%	23:59:54
12	nntp.pasdenom.info	7	11289	219	11066	4	1%	33:00:29
13	news.freedyn.net	1	16289	199	16012	78	1%	23:59:56
14	csiph-out.news.tnetconsulting.net	2	14250	163	14074	13	1%	23:59:56
15	news.fcku.it	9	1300	125	1174	1	9%	21:47:12
16	news.swapon.de	11	1178	41	1136	1	3%	23:33:22
17	news.bbs.nz	1	14000	1	13999	0	0%	23:59:52
18	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 18		4834	284557	13996	266898	3663	4%	726:29:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	10.3 MB	32.7 KB	9.3 MB	19.7 MB	52%	9.3 KB
2	csiph-out.news.xs4all.nl	6.5 MB	0.0 KB	9.6 MB	16.2 MB	40%	4.4 KB
3	feeder.eternal-september.org	5.9 MB	10.9 KB	5.5 MB	11.4 MB	51%	5.1 KB
4	newsfeeds.xmission.com	4.3 MB	3.7 KB	50.3 KB	4.3 MB	98%	3.0 KB
5	news-out.aioe.org	3.4 MB	1.3 KB	101.0 KB	3.5 MB	97%	2.6 KB
6	csiph-out.feed.uzoreto.com	2.6 MB	0.0 KB	9.5 MB	12.1 MB	21%	7.9 KB
7	news-out.mixmin.net	2.5 MB	0.0 KB	921.4 KB	3.4 MB	73%	2.5 KB
8	csiph-out.news.weretis.net	2.4 MB	0.0 KB	1.8 MB	4.3 MB	56%	4.4 KB
9	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	2.1 MB	3.9 MB	47%	4.2 KB
10	newsfeed.neva.ru	1.1 MB	11.9 KB	323.1 KB	1.4 MB	77%	3.0 KB
11	news.freedyn.net	680.3 KB	0.0 KB	301.9 KB	982.2 KB	69%	3.5 KB
12	news-out.neodome.net	603.4 KB	0.0 KB	174.9 KB	778.3 KB	77%	2.6 KB
13	csiph-out.news.tnetconsulting.net	587.6 KB	0.0 KB	131.2 KB	718.8 KB	81%	4.1 KB
14	nntp.pasdenom.info	465.9 KB	1.8 KB	6.6 KB	474.4 KB	98%	2.1 KB
15	news.fcku.it	186.4 KB	0.0 KB	1.0 KB	187.4 KB	99%	1.5 KB
16	news.swapon.de	78.5 KB	0.0 KB	80.3 KB	158.8 KB	49%	3.8 KB
17	news.bbs.nz	0.8 KB	0.0 KB	0.0 KB	0.8 KB	100%	0.8 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		43.5 MB	62.4 KB	39.9 MB	83.4 MB	52%	4.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 10 04:15:00 - 04:59:59	183	1.3%	0.07	567.2 KB	1.3%	0.21
Apr 10 05:00:00 - 05:59:59	342	2.4%	0.10	1.1 MB	2.6%	0.32
Apr 10 06:00:00 - 06:59:59	375	2.7%	0.10	1.3 MB	3.0%	0.37
Apr 10 07:00:00 - 07:59:59	551	3.9%	0.15	2.4 MB	5.6%	0.69
Apr 10 08:00:00 - 08:59:59	685	4.9%	0.19	2.4 MB	5.6%	0.69
Apr 10 09:00:00 - 09:59:59	794	5.7%	0.22	2.1 MB	4.8%	0.59
Apr 10 10:00:00 - 10:59:59	819	5.8%	0.23	2.8 MB	6.3%	0.78
Apr 10 11:00:00 - 11:59:59	697	5.0%	0.19	1.7 MB	4.0%	0.50
Apr 10 12:00:00 - 12:59:59	709	5.0%	0.20	2.0 MB	4.6%	0.57
Apr 10 13:00:00 - 13:59:59	747	5.3%	0.21	2.4 MB	5.5%	0.68
Apr 10 14:00:00 - 14:59:59	778	5.5%	0.22	2.4 MB	5.5%	0.68
Apr 10 15:00:00 - 15:59:59	761	5.4%	0.21	2.5 MB	5.7%	0.70
Apr 10 16:00:00 - 16:59:59	729	5.2%	0.20	2.6 MB	6.0%	0.74
Apr 10 17:00:00 - 17:59:59	890	6.3%	0.25	2.6 MB	6.0%	0.74
Apr 10 18:00:00 - 18:59:59	771	5.5%	0.21	2.3 MB	5.3%	0.66
Apr 10 19:00:00 - 19:59:59	742	5.3%	0.21	2.0 MB	4.7%	0.58
Apr 10 20:00:00 - 20:59:59	630	4.5%	0.17	1.6 MB	3.7%	0.45
Apr 10 21:00:00 - 21:59:59	520	3.7%	0.14	1.5 MB	3.5%	0.44
Apr 10 22:00:00 - 22:59:59	518	3.7%	0.14	1.6 MB	3.8%	0.46
Apr 10 23:00:00 - 23:59:59	477	3.4%	0.13	1.4 MB	3.3%	0.40
Apr 11 00:00:00 - 00:59:59	312	2.2%	0.09	1.0 MB	2.3%	0.29
Apr 11 01:00:00 - 01:59:59	362	2.6%	0.10	1.1 MB	2.5%	0.30
Apr 11 02:00:00 - 02:59:59	288	2.1%	0.08	916.3 KB	2.1%	0.25
Apr 11 03:00:00 - 03:59:59	275	2.0%	0.08	812.6 KB	1.8%	0.23
Apr 11 04:00:00 - 04:15:00	90	0.6%	0.10	207.9 KB	0.5%	0.23
TOTAL: 24:00:00	14045	100.0%	0.16	43.4 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1389	81	0	1308
2	csiph-out.feed.uzoreto.com	715	49	0	666
3	newsfeed.fu-berlin.de	433	9	0	424
4	csiph-out.feeder.erje.net	387	97	19	271
5	news-out.mixmin.net	384	4	0	380
6	feeder.eternal-september.org	119	12	4	103
7	newsfeed.neva.ru	108	19	4	85
8	csiph-out.news.weretis.net	97	20	0	77
9	news.freedyn.net	75	3	0	72
10	news-out.neodome.net	42	10	0	32
11	news-out.aioe.org	37	19	1	17
12	news.swapon.de	17	0	0	17
13	csiph-out.news.tnetconsulting.net	8	8	0	0
14	nntp.pasdenom.info	6	0	1	5
15	news.fcku.it	1	1	0	0
16	newsfeeds.xmission.com	1	0	0	1
TOTAL: 16		3819	332	29	3458

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	56
alt.binaries.by-hash.shv1	41
alt.news.misc	39
alt.bbs.synchronet	28
free.it.religioni.scientology	19
alt.binaries.pictures.purebeauty	18
alt.job	15
alt.tv.comedy-central.daily-show	12
1.test	8
mozilla.support.seamonkey	7
lada.auto	7
purdue.forsale	6
alt.philosophy	6
alt.politics.usa	6
at.jobs	4
mozilla.test	3
alt.religion	3
ibm.software.network.directory-integrator	3
oesterreich.tratsch	3
it-alt.sport.calcio.juventus	3
TOTAL: 55	332

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2014
Too many newsgroups	201
EMP (phn path)	161
EMP (phl)	158
EMP (phn nph)	54
Binary Payload	28
Binary: misplaced binary	21
0	19
Scoring filter	8
HTML Multipart	6
Too many newsgroups (meow)	1
TOTAL: 11	2671

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	csiph-out.news.xs4all.nl	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 3	47
Huge articles
	csiph-out.feeder.erje.net	141
	csiph-out.feed.uzoreto.com	72
	csiph-out.news.xs4all.nl	54
	feeder.eternal-september.org	36
	csiph-out.news.weretis.net	20
	news-out.mixmin.net	2
	news.freedyn.net	2
	newsfeeds.xmission.com	1
	news.swapon.de	1
	news-out.neodome.net	1
	TOTAL: 10	330
Including strange strings
	newsfeed.fu-berlin.de	311
	csiph-out.news.xs4all.nl	50
	csiph-out.feed.uzoreto.com	36
	news.swapon.de	16
	csiph-out.news.weretis.net	15
	news-out.mixmin.net	8
	newsfeed.neva.ru	6
	csiph-out.feeder.erje.net	6
	news-out.aioe.org	5
	news.freedyn.net	2
	TOTAL: 11	456
No colon-space in header
	csiph-out.news.xs4all.nl	1
	TOTAL: 1	1
TOTAL: 4		834

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	12973	497	12476	0	0	3%	24:01:22
2	news.bbs.nz	25877	143	13221	24	0	0%	23:51:25
3	erje	13313	62	12022	199	0	0%	24:06:31
4	xmission	14774	57	11770	5	0	0%	23:55:18
5	newsfeed.xs4all.nl	11748	33	11225	0	110	0%	23:59:51
6	tnet	14161	19	13414	2	0	0%	23:59:20
7	news.uzoreto.com	12427	10	12272	34	0	0%	23:55:18
8	neodome.net	13900	4	13681	0	0	0%	23:52:26
9	weretis.net	12662	4	12319	6	0	0%	23:55:18
10	usenet.pasdenom.info	13339	3	13171	1	16	0%	23:59:51
11	neva.ru	12944	2	12786	3	0	0%	23:55:18
12	aioe	11616	2	11431	0	0	0%	24:02:12
13	news.freedyn.net	13814	1	13649	42	0	0%	23:55:01
14	news.mixmin.net	12063	1	11963	0	0	0%	23:56:05
15	news.swapon.de	1272	0	1269	0	0	0%	23:52:01
16	eternal-september	0	0	0	0	11141	0%	23:59:56
17	xanadu-bbs	214	0	0	0	14259	0%	23:58:53
18	news.samoylyk.net	0	0	0	0	14018	0%	23:58:31
19	news.fcku.it	1106	0	1106	0	0	0%	20:57:29
TOTAL: 19		198203	838	177775	316	39544	0%	452:12:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	fu-berlin.de	6.5 MB	0.0 KB	6.5 MB	0.1 KB/s	13.3 KB	24:01:22
2	news.bbs.nz	386.2 KB	82.1 KB	468.3 KB	0.0 KB/s	2.8 KB	23:51:25
3	erje	221.0 KB	639.9 KB	860.9 KB	0.0 KB/s	3.3 KB	24:06:31
4	xmission	156.4 KB	10.6 KB	167.0 KB	0.0 KB/s	2.7 KB	23:55:18
5	newsfeed.xs4all.nl	133.8 KB	0.0 KB	133.8 KB	0.0 KB/s	4.1 KB	23:59:51
6	tnet	41.7 KB	5.5 KB	47.2 KB	0.0 KB/s	2.2 KB	23:59:20
7	news.uzoreto.com	41.3 KB	158.6 KB	199.9 KB	0.0 KB/s	4.5 KB	23:55:18
8	neodome.net	20.6 KB	0.0 KB	20.6 KB	0.0 KB/s	5.1 KB	23:52:26
9	usenet.pasdenom.info	20.6 KB	1.3 KB	21.9 KB	0.0 KB/s	5.5 KB	23:59:51
10	aioe	12.4 KB	0.0 KB	12.4 KB	0.0 KB/s	6.2 KB	24:02:12
11	weretis.net	6.7 KB	34.7 KB	41.4 KB	0.0 KB/s	4.1 KB	23:55:18
12	news.freedyn.net	4.8 KB	770.1 KB	774.9 KB	0.0 KB/s	18.0 KB	23:55:01
13	news.mixmin.net	4.8 KB	0.0 KB	4.8 KB	0.0 KB/s	4.8 KB	23:56:05
14	neva.ru	2.1 KB	7.4 KB	9.5 KB	0.0 KB/s	1.9 KB	23:55:18
15	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:52:01
16	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
17	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:53
18	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:31
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:57:29
TOTAL: 19		7.5 MB	1.7 MB	9.2 MB	0.0 KB/s	8.1 KB	452:12:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	a131033.upc-a.chello.nl	52	2	3.8 KB	2	09:24:51
2	172.83.100.199	7	2	3.8 KB	2	03:15:47
TOTAL: 2		59	4	7.7 KB	4	44:48:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	296	2	3.8 KB	2	35:23:08
2	*.upc-a.chello.nl	52	2	3.8 KB	2	09:24:51
3	*.security.ipip.net	1	0	0.0 KB	0	00:00:01
4	*.range86-139.btcentralplus.com	1	0	0.0 KB	0	00:00:46
5	*.ch1.censys-scanner.com	2	0	0.0 KB	0	00:00:01
TOTAL: 5		352	4	7.7 KB	4	44:48:49

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.335	3.494	32:07:10
a131033.upc-a.chello.nl	3.259	0.593	09:24:51
172.83.100.199	0.602	0.132	03:15:47
host86-139-107-9.range86-139.btcentralplus.com	0.055	0.024	00:00:46
192.241.207.185	0.055	0.016	00:00:09
scan-141.security.ipip.net	0.064	0.015	00:00:01
scanner-04.ch1.censys-scanner.com	0.130	0.016	00:00:01
TOTAL: 7	21.500	4.290	44:48:49

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	288
scanner-04.ch1.censys-scanner.com	2
scan-141.security.ipip.net	1
host86-139-107-9.range86-139.btcentralplus.com	1
192.241.207.185	1
TOTAL: 5	293

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
scanner-04.ch1.censys-scanner.com	1
TOTAL: 2	5

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	1
GET / HTTP/1.1	1
Host: 69.160.37.58:119	1
Connection: close	1
EHLO www.censys.io	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.199	6	0
TOTAL: 1	6	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Apr 10 04:15:00 -- Apr 11 04:15:00