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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Apr 29 09:00:24 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[75464]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 29 09:00:24 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[75464]: 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 29 13:28:54 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[90689]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Apr 29 13:28:54 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[90689]: 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	22702	42.1%	3.7 MB	35.5%
inn	18878	35.0%	4.8 MB	46.7%
nnrpd	8707	16.1%	1.1 MB	11.1%
innfeed	3638	6.7%	710.2 KB	6.7%
TOTAL: 4	53925	100.0%	10.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	292850	53.5%
Negative hits	235926	43.1%
Do not exist	18177	3.3%
Cache misses	338	0.1%
TOTAL: 4	547291	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.315	0.0%	26	0.000	12.115	43.000
article cleanup	00:00:00.112	0.0%	18032	0.000	0.006	0.041
article logging	00:00:01.544	0.0%	18551	0.000	0.083	1.434
article parse	00:00:01.380	0.0%	54920	0.000	0.025	0.365
article write	00:02:00.488	0.1%	15045	0.145	8.009	103.571
artlog/artcncl	00:00:00.015	0.0%	26	0.000	0.577	2.800
artlog/artparse	00:00:00.001	0.0%	260	0.000	0.004	0.333
data move	00:00:00.176	0.0%	502745	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.049	0.0%	25	0.000	1.960	6.200
hishave/artcncl	00:00:00.080	0.0%	26	0.000	3.077	8.800
hishave/artparse	00:00:00.000	0.0%	258	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:37.823	0.0%	550976	0.003	0.069	0.402
history sync	00:00:01.592	0.0%	340	0.000	4.682	30.667
history write	00:00:57.615	0.1%	18004	0.222	3.200	17.427
hiswrite/artcncl	00:00:00.001	0.0%	1	1.000	1.000	1.000
hiswrite/artparse	00:00:00.950	0.0%	252	0.000	3.770	46.000
idle	23:34:39.007	98.5%	504887	98.030	168.115	442.703
nntp read	00:00:02.344	0.0%	516813	0.002	0.005	0.007
overview write	00:16:05.244	1.1%	15045	0.157	64.157	9361.146
perl filter	00:00:25.067	0.0%	18002	1.103	1.392	2.270
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.270	0.0%	30090	0.000	0.009	0.029
TOTAL: 23:56:01.982	23:54:54.073	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.046	0.0%	15125	0.000	0.003	0.032
article prepare	00:00:00.001	0.0%	4460	0.000	0.000	0.050
article read	00:00:00.790	0.0%	981	0.000	0.805	24.688
backlog stats	00:00:00.190	0.0%	295166	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.065	0.0%	228690	0.000	0.005	0.013
data write	00:00:01.304	0.0%	216468	0.001	0.006	0.015
idle	23:55:03.123	100.0%	295166	152.842	291.711	850.572
status file	00:00:00.572	0.0%	370	0.000	1.546	43.000
TOTAL: 23:55:18.907	23:55:07.091	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	50:41:26.945	99.9%	9884	0.000	18462.864	600103.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.117	0.0%	20747	0.000	0.006	0.167
readart	00:00:00.000	0.0%	13	0.000	0.000	0.000
TOTAL: 50:45:23.841	50:41:27.062	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	2307	17532	4592	10747	2193	26%	125:51:59
2	feeder.eternal-september.org	14	4821	2130	2594	97	44%	27:06:13
3	newsfeeds.xmission.com	3	11868	1463	10399	6	12%	19:09:05
4	news-out.aioe.org	3	13882	1295	12560	27	9%	24:09:00
5	newsfeed.fu-berlin.de	21	16317	1105	15131	81	6%	28:48:00
6	csiph-out.feeder.erje.net	1440	74358	931	72821	606	1%	118:46:45
7	news-out.mixmin.net	2	16013	777	15023	213	4%	23:59:31
8	csiph-out.news.weretis.net	4	29755	620	29068	67	2%	47:59:30
9	newsfeed.neva.ru	143	18149	541	17477	131	2%	71:19:48
10	nntp.pasdenom.info	5	26573	484	26079	10	1%	62:42:25
11	csiph-out.news.tnetconsulting.net	2	22282	422	21839	21	1%	33:32:55
12	news-out.neodome.net	2	16597	418	16156	23	2%	23:59:55
13	news.fcku.it	14	1809	349	1457	3	19%	21:54:13
14	csiph-out.feed.uzoreto.com	863	24909	166	24631	112	0%	71:12:08
15	news.freedyn.net	2	17345	67	17242	36	0%	23:59:56
16	news.swapon.de	6	1459	46	1413	0	3%	24:02:28
17	news.bbs.nz	1	14670	5	14664	1	0%	23:59:51
18	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 18		4858	328339	15411	309301	3627	4%	772:33:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	11.4 MB	0.0 KB	19.4 MB	30.8 MB	36%	4.7 KB
2	csiph-out.feeder.erje.net	6.1 MB	0.0 KB	5.3 MB	11.4 MB	53%	7.6 KB
3	feeder.eternal-september.org	5.6 MB	10.7 KB	1.5 MB	7.1 MB	78%	3.3 KB
4	newsfeeds.xmission.com	4.6 MB	8.1 KB	196.8 KB	4.8 MB	95%	3.3 KB
5	news-out.aioe.org	3.3 MB	28.6 KB	68.1 KB	3.4 MB	97%	2.7 KB
6	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	452.5 KB	3.1 MB	85%	2.7 KB
7	news-out.mixmin.net	1.9 MB	37.4 KB	424.0 KB	2.4 MB	80%	2.4 KB
8	csiph-out.news.weretis.net	1.6 MB	61.8 KB	2.0 MB	3.7 MB	44%	5.5 KB
9	csiph-out.news.tnetconsulting.net	1.4 MB	16.6 KB	243.8 KB	1.7 MB	84%	3.8 KB
10	newsfeed.neva.ru	1.3 MB	22.5 KB	413.6 KB	1.7 MB	75%	2.7 KB
11	news-out.neodome.net	1.3 MB	36.9 KB	71.9 KB	1.4 MB	92%	3.2 KB
12	nntp.pasdenom.info	1.0 MB	0.0 KB	11.4 KB	1.0 MB	98%	2.1 KB
13	news.fcku.it	541.0 KB	0.0 KB	3.0 KB	544.0 KB	99%	1.5 KB
14	csiph-out.feed.uzoreto.com	492.8 KB	0.0 KB	3.4 MB	3.8 MB	12%	14.1 KB
15	news.freedyn.net	226.6 KB	5.1 KB	598.0 KB	829.7 KB	27%	8.1 KB
16	news.swapon.de	100.5 KB	0.0 KB	0.0 KB	100.5 KB	100%	2.2 KB
17	news.bbs.nz	5.1 KB	2.5 KB	2.5 KB	10.2 KB	50%	1.7 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		43.5 MB	230.2 KB	33.9 MB	77.7 MB	56%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 29 04:15:00 - 04:59:59	240	1.6%	0.09	1.2 MB	2.9%	0.46
Apr 29 05:00:00 - 05:59:59	402	2.7%	0.11	1.3 MB	3.1%	0.38
Apr 29 06:00:00 - 06:59:59	492	3.3%	0.14	1.7 MB	4.1%	0.50
Apr 29 07:00:00 - 07:59:59	610	4.0%	0.17	1.5 MB	3.6%	0.44
Apr 29 08:00:00 - 08:59:59	744	4.9%	0.21	2.1 MB	5.0%	0.61
Apr 29 09:00:00 - 09:59:59	831	5.5%	0.23	2.2 MB	5.2%	0.63
Apr 29 10:00:00 - 10:59:59	738	4.9%	0.20	2.0 MB	4.7%	0.57
Apr 29 11:00:00 - 11:59:59	692	4.6%	0.19	1.7 MB	4.0%	0.48
Apr 29 12:00:00 - 12:59:59	794	5.3%	0.22	1.9 MB	4.4%	0.53
Apr 29 13:00:00 - 13:59:59	901	6.0%	0.25	2.5 MB	6.0%	0.72
Apr 29 14:00:00 - 14:59:59	832	5.5%	0.23	2.1 MB	4.9%	0.60
Apr 29 15:00:00 - 15:59:59	1031	6.8%	0.29	2.7 MB	6.3%	0.76
Apr 29 16:00:00 - 16:59:59	843	5.6%	0.23	2.2 MB	5.3%	0.63
Apr 29 17:00:00 - 17:59:59	860	5.7%	0.24	2.1 MB	5.0%	0.61
Apr 29 18:00:00 - 18:59:59	685	4.5%	0.19	1.8 MB	4.4%	0.53
Apr 29 19:00:00 - 19:59:59	785	5.2%	0.22	2.0 MB	4.8%	0.58
Apr 29 20:00:00 - 20:59:59	625	4.1%	0.17	1.8 MB	4.3%	0.52
Apr 29 21:00:00 - 21:59:59	612	4.1%	0.17	1.7 MB	4.0%	0.48
Apr 29 22:00:00 - 22:59:59	523	3.5%	0.15	1.6 MB	3.8%	0.45
Apr 29 23:00:00 - 23:59:59	447	3.0%	0.12	1.2 MB	2.9%	0.34
Apr 30 00:00:00 - 00:59:59	293	1.9%	0.08	943.3 KB	2.2%	0.26
Apr 30 01:00:00 - 01:59:59	359	2.4%	0.10	1.1 MB	2.6%	0.31
Apr 30 02:00:00 - 02:59:59	335	2.2%	0.09	1.1 MB	2.5%	0.31
Apr 30 03:00:00 - 03:59:59	271	1.8%	0.08	836.7 KB	1.9%	0.23
Apr 30 04:00:00 - 04:15:00	128	0.8%	0.14	923.3 KB	2.1%	1.03
TOTAL: 24:00:00	15073	100.0%	0.17	42.4 MB	100.0%	0.50

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	2310	163	0	1	2146
2	newsfeed.fu-berlin.de	410	8	0	0	402
3	csiph-out.feeder.erje.net	255	108	0	0	147
4	news-out.mixmin.net	221	0	5	0	216
5	newsfeed.neva.ru	144	54	1	0	89
6	csiph-out.feed.uzoreto.com	114	9	0	0	105
7	feeder.eternal-september.org	100	63	5	0	32
8	csiph-out.news.weretis.net	74	10	6	0	58
9	news.freedyn.net	39	1	3	0	35
10	news-out.neodome.net	24	0	4	0	20
11	news-out.aioe.org	23	17	1	0	5
12	news.swapon.de	18	0	0	0	18
13	csiph-out.news.tnetconsulting.net	16	11	1	0	4
14	newsfeeds.xmission.com	15	1	3	0	11
15	nntp.pasdenom.info	7	0	0	0	7
16	news.fcku.it	3	3	0	0	0
17	news.bbs.nz	2	0	1	0	1
TOTAL: 17		3775	448	30	1	3296

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	83
alt.binaries.by-hash.shv1	55
alt.news.misc	51
lada.talk	42
it-alt.sport.calcio.juventus	23
alt.binaries.pictures.purebeauty	19
alt.job	15
alt.computer.hardware	13
ba.forsale	12
alt.politics.usa	10
alt.windows7.general	9
alt.slack.goathead	9
alt.bbs.synchronet	9
alt.religion	7
alt.finance	5
alt.survival	4
alt.science	4
alt.binaries.audiobooks	4
demos.local.lists.cgp-russian	3
harvard.forsale	3
TOTAL: 69	448

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1824
Too many newsgroups	221
EMP (phl)	183
EMP (phn path)	125
EMP (phn nph)	71
0	56
Binary: misplaced binary	16
Scoring filter	9
HTML Multipart	7
Binary Payload	3
Too many newsgroups (meow)	2
TOTAL: 11	2517

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.news.xs4all.nl	116
	csiph-out.feeder.erje.net	75
	csiph-out.feed.uzoreto.com	27
	csiph-out.news.weretis.net	16
	feeder.eternal-september.org	13
	news.freedyn.net	5
	csiph-out.news.tnetconsulting.net	3
	newsfeeds.xmission.com	3
	news-out.mixmin.net	1
	TOTAL: 9	259
Including strange strings
	newsfeed.fu-berlin.de	339
	csiph-out.news.xs4all.nl	115
	news.swapon.de	18
	newsfeed.neva.ru	12
	news-out.mixmin.net	9
	newsfeeds.xmission.com	8
	csiph-out.news.weretis.net	7
	csiph-out.feeder.erje.net	7
	news.freedyn.net	2
	csiph-out.feed.uzoreto.com	1
	TOTAL: 10	518
No colon-space in header
	csiph-out.news.xs4all.nl	2
	TOTAL: 1	2
TOTAL: 3		779

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	13737	369	13367	0	0	2%	23:53:19
2	xmission	17080	153	12885	10	0	0%	23:57:36
3	news.bbs.nz	28142	143	14376	21	0	0%	23:49:37
4	erje	15122	59	13533	197	0	0%	23:52:32
5	tnet	15731	47	14507	4	0	0%	23:52:45
6	newsfeed.xs4all.nl	10649	28	10112	0	77	0%	23:54:22
7	news.freedyn.net	15143	9	14910	21	0	0%	23:55:27
8	news.mixmin.net	13604	8	13403	2	0	0%	23:50:40
9	usenet.pasdenom.info	14401	8	14156	3	7	0%	23:59:50
10	neodome.net	14916	7	14657	0	0	0%	23:52:59
11	weretis.net	14287	6	13630	2	0	0%	23:49:47
12	news.uzoreto.com	13960	1	13848	7	0	0%	23:50:05
13	news.swapon.de	1473	1	1454	0	0	0%	23:50:43
14	aioe	13007	1	12630	0	0	0%	23:50:05
15	neva.ru	13656	1	13594	1	0	0%	23:59:39
16	eternal-september	0	0	0	0	12364	0%	23:50:05
17	xanadu-bbs	0	0	0	0	14974	0%	23:50:09
18	news.fcku.it	1347	0	1336	0	0	0%	20:45:17
19	news.samoylyk.net	0	0	0	0	14978	0%	23:50:06
TOTAL: 19		216255	841	192398	268	42400	0%	450:35:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	fu-berlin.de	3.9 MB	0.0 KB	3.9 MB	10.9 KB	23:53:19
2	xmission	441.4 KB	19.4 KB	460.7 KB	2.8 KB	23:57:36
3	news.bbs.nz	368.2 KB	84.9 KB	453.2 KB	2.8 KB	23:49:37
4	erje	205.8 KB	914.5 KB	1.1 MB	4.4 KB	23:52:32
5	newsfeed.xs4all.nl	125.4 KB	0.0 KB	125.4 KB	4.5 KB	23:54:22
6	tnet	108.6 KB	5.3 KB	114.0 KB	2.2 KB	23:52:45
7	neodome.net	67.9 KB	0.0 KB	67.9 KB	9.7 KB	23:52:59
8	usenet.pasdenom.info	52.4 KB	4.1 KB	56.5 KB	5.1 KB	23:59:50
9	news.mixmin.net	36.5 KB	3.6 KB	40.1 KB	4.0 KB	23:50:40
10	news.freedyn.net	34.2 KB	369.2 KB	403.4 KB	13.4 KB	23:55:27
11	news.uzoreto.com	15.1 KB	53.0 KB	68.0 KB	8.5 KB	23:50:05
12	news.swapon.de	14.0 KB	0.0 KB	14.0 KB	14.0 KB	23:50:43
13	weretis.net	9.2 KB	2.4 KB	11.5 KB	1.4 KB	23:49:47
14	aioe	5.2 KB	0.0 KB	5.2 KB	5.2 KB	23:50:05
15	neva.ru	4.7 KB	35.0 KB	39.7 KB	19.8 KB	23:59:39
16	eternal-september	0.0 KB	0.0 KB	0.0 KB	0	23:50:05
17	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0	23:50:09
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	20:45:17
19	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:50:06
TOTAL: 19		5.4 MB	1.5 MB	6.8 MB	6.3 KB	450:35:03

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	217-121-77-183.cable.dynamic.v4.ziggo.nl	119	13	51.1 KB	10	0	0	00:24:05
TOTAL: 1		119	13	51.1 KB	13	0	0	50:44:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cable.dynamic.v4.ziggo.nl	119	13	51.1 KB	10	00:24:05
2	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	00:30:01
3	*.upc-a.chello.nl	39	0	0.0 KB	0	06:18:55
4	*.internet-census.org	6	0	0.0 KB	0	00:00:01
5	unresolved	313	0	0.0 KB	3	43:31:52
TOTAL: 5		478	13	51.1 KB	13	50:44:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.242	4.184	37:41:12
a131033.upc-a.chello.nl	2.265	0.601	06:18:55
172.83.100.205	0.602	0.133	03:41:10
208.72.53.140	0.273	0.033	01:30:12
87.140.192.0	0.225	0.075	00:38:19
c-73-140-175-55.hsd1.wa.comcast.net	0.060	0.017	00:30:01
217-121-77-183.cable.dynamic.v4.ziggo.nl	11.301	2.112	00:24:05
81.145.97.98	0.124	0.030	00:00:55
207.154.240.112	0.537	0.110	00:00:02
zl-ams-nl-gp6-wk113.internet-census.org	0.366	0.094	00:00:01
TOTAL: 10	32.995	7.389	50:44:57

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	287
a131033.upc-a.chello.nl	39
207.154.240.112	9
172.83.100.205	8
zl-ams-nl-gp6-wk113.internet-census.org	6
87.140.192.0	4
208.72.53.140	3
81.145.97.98	2
c-73-140-175-55.hsd1.wa.comcast.net	1
TOTAL: 9	359

NNRP unrecognized commands (by host):

System	Conn
207.154.240.112	48
zl-ams-nl-gp6-wk113.internet-census.org	11
TOTAL: 2	59

NNRP unrecognized commands (by command):

Command	Count
Accept: /	9
Host: 69.160.37.58:119	9
Connection: keep-alive	8
Accept-Encoding: gzip, deflate	8
User-Agent: Mozilla/5.0 (Windows NT 10.0...	8
GET /system_api.php HTTP/1.1	2
^V^C^A	2
^A	2
GET /stream/live.php HTTP/1.1	1
GET /streaming/clients_live.php HTTP/1.1	1
GET /stalker_portal/c/version.js HTTP/1....	1
M-^@.^A	1
GET / HTTP/1.1	1
Accept-Encoding: gzip	1
GET /c/version.js HTTP/1.1	1
GET /client_area/ HTTP/1.1	1
User-Agent: Roku/DVP-9.10 (289.10E04111A...	1
GET /stalker_portal/c/ HTTP/1.1	1
SSH-2.0-Go	1
TOTAL: 19	59

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.205	7	0
208.72.53.140	3	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 3	11	0

Newsgroup request counts (by category):

Category		Count	Pct
1	uk	78703	100.0%
2	rec	13	0.0%
3	cz	4	0.0%
TOTAL: 3		78720	100.0%

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

Newsgroup		Count
1	uk.radio.amateur	78696
2	rec.arts.tv	13
3	uk.games.video.misc	7
4	cz.comp.lang.php	4
TOTAL: 4		78720

Daily Usenet report for csiph.com

Apr 29 04:15:00 -- Apr 30 04:15:00