Daily Usenet report for csiph.com: May 2 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

May 2 17:34:39 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[40993]: unable to get certificate from '/usr/local/news/etc/cert.pem'
May 2 17:34:39 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[40993]: 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	23816	45.1%	3.8 MB	38.4%
inn	18390	34.8%	4.5 MB	45.5%
nnrpd	6937	13.1%	936.8 KB	9.2%
innfeed	3713	7.0%	710.7 KB	7.0%
TOTAL: 4	52856	100.0%	10.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	274595	52.4%
Negative hits	226644	43.2%
Do not exist	17532	3.3%
Cache misses	5763	1.1%
TOTAL: 4	524534	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.348	0.0%	305	0.000	24.092	185.000
article cleanup	00:00:00.119	0.0%	17145	0.000	0.007	0.052
article logging	00:00:01.400	0.0%	17661	0.000	0.079	1.247
article parse	00:00:04.326	0.0%	58417	0.000	0.074	1.475
article write	00:01:51.529	0.1%	13869	0.139	8.042	67.234
artlog/artcncl	00:00:00.004	0.0%	305	0.000	0.013	0.014
artlog/artparse	00:00:00.011	0.0%	481	0.000	0.023	1.000
data move	00:00:00.199	0.0%	485026	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.181	0.0%	305	0.000	0.593	92.000
hishave/artcncl	00:00:00.065	0.0%	305	0.000	0.213	5.429
hishave/artparse	00:00:00.002	0.0%	478	0.000	0.004	0.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:10.026	0.1%	529896	0.002	0.132	0.533
history sync	00:00:01.813	0.0%	336	0.000	5.396	42.667
history write	00:01:25.138	0.1%	17115	0.198	4.974	17.167
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:03.839	0.0%	477	0.000	8.048	388.000
idle	23:46:37.775	98.7%	484601	83.831	176.636	620.454
nntp read	00:00:02.204	0.0%	497301	0.002	0.004	0.009
overview write	00:12:25.833	0.9%	13869	0.220	53.777	22703.400
perl filter	00:00:23.001	0.0%	17112	0.868	1.344	2.491
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.253	0.0%	27738	0.000	0.009	0.040
TOTAL: 24:05:11.748	24:04:15.066	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.042	0.0%	14124	0.000	0.003	0.068
article prepare	00:00:00.001	0.0%	4196	0.000	0.000	0.125
article read	00:00:02.032	0.0%	894	0.000	2.273	907.000
backlog stats	00:00:00.161	0.0%	269115	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.970	0.0%	208462	0.000	0.005	0.012
data write	00:00:01.318	0.0%	196990	0.002	0.007	0.014
idle	23:54:20.326	100.0%	269115	133.262	319.790	1237.037
status file	00:00:01.068	0.0%	392	0.000	2.724	40.667
TOTAL: 23:54:35.185	23:54:25.918	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	29:58:53.698	99.8%	9050	0.000	11926.375	207168.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:25.132	0.0%	102368	0.000	0.246	33.903
readart	00:00:12.394	0.0%	1317	0.000	9.411	23.045
TOTAL: 30:03:05.153	29:59:31.224	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	2454	16251	4797	9167	2287	29%	122:58:05
2	feeder.eternal-september.org	17	3559	1837	1583	139	51%	27:34:50
3	news-out.aioe.org	2	12781	1239	11493	49	9%	23:59:49
4	newsfeed.fu-berlin.de	9	14358	950	13039	369	6%	25:08:32
5	csiph-out.feeder.erje.net	1440	71105	929	69489	687	1%	119:06:50
6	news-out.mixmin.net	2	14993	921	13887	185	6%	23:58:44
7	csiph-out.news.weretis.net	4	27005	762	26195	48	2%	47:58:04
8	newsfeeds.xmission.com	2	13099	512	12576	11	3%	23:37:07
9	nntp.pasdenom.info	10	11242	438	10793	11	3%	34:05:12
10	csiph-out.news.tnetconsulting.net	2	22989	355	22623	11	1%	38:36:56
11	newsfeed.neva.ru	146	17424	347	17004	73	1%	71:39:48
12	news-out.neodome.net	2	15563	223	15302	38	1%	23:59:54
13	csiph-out.feed.uzoreto.com	864	31708	161	31393	154	0%	71:12:44
14	news.freedyn.net	3	15940	134	15771	35	0%	22:09:52
15	news.fcku.it	14	1136	108	1028	0	9%	21:54:12
16	news.swapon.de	10	1382	48	1334	0	3%	23:18:43
17	news.bbs.nz	2	13160	13	13146	1	0%	24:04:47
18	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 18		5009	303695	13774	285823	4098	4%	745:24:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	13.5 MB	0.0 KB	20.6 MB	34.1 MB	39%	4.9 KB
2	csiph-out.feeder.erje.net	7.5 MB	86.6 KB	5.7 MB	13.4 MB	56%	8.5 KB
3	feeder.eternal-september.org	5.1 MB	2.5 KB	8.0 MB	13.2 MB	38%	6.8 KB
4	news-out.aioe.org	3.2 MB	0.0 KB	70.6 KB	3.3 MB	97%	2.6 KB
5	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	5.6 MB	8.0 MB	29%	6.2 KB
6	csiph-out.news.weretis.net	2.3 MB	15.5 KB	1.5 MB	3.9 MB	60%	4.9 KB
7	news-out.mixmin.net	2.2 MB	3.8 KB	629.9 KB	2.8 MB	78%	2.6 KB
8	newsfeeds.xmission.com	1.7 MB	0.0 KB	890.3 KB	2.6 MB	66%	5.0 KB
9	csiph-out.news.tnetconsulting.net	1.2 MB	0.0 KB	83.4 KB	1.3 MB	93%	3.6 KB
10	newsfeed.neva.ru	872.0 KB	0.0 KB	163.7 KB	1.0 MB	84%	2.5 KB
11	nntp.pasdenom.info	834.9 KB	0.0 KB	27.9 KB	862.8 KB	96%	1.9 KB
12	csiph-out.feed.uzoreto.com	566.3 KB	0.0 KB	2.9 MB	3.4 MB	16%	11.1 KB
13	news-out.neodome.net	550.1 KB	0.0 KB	121.7 KB	671.8 KB	81%	2.6 KB
14	news.freedyn.net	539.0 KB	0.0 KB	526.8 KB	1.0 MB	50%	6.3 KB
15	news.fcku.it	147.4 KB	0.0 KB	0.0 KB	147.4 KB	100%	1.4 KB
16	news.swapon.de	107.6 KB	0.0 KB	0.0 KB	107.6 KB	100%	2.2 KB
17	news.bbs.nz	45.9 KB	0.0 KB	3.1 KB	49.0 KB	93%	3.5 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		42.7 MB	108.3 KB	46.9 MB	89.7 MB	47%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 2 04:15:00 - 04:59:59	182	1.3%	0.07	709.9 KB	1.6%	0.26
May 2 05:00:00 - 05:59:59	593	4.3%	0.16	2.4 MB	5.8%	0.70
May 2 06:00:00 - 06:59:59	501	3.6%	0.14	1.8 MB	4.2%	0.51
May 2 07:00:00 - 07:59:59	527	3.8%	0.15	1.7 MB	4.0%	0.49
May 2 08:00:00 - 08:59:59	635	4.6%	0.18	1.9 MB	4.5%	0.55
May 2 09:00:00 - 09:59:59	689	5.0%	0.19	1.7 MB	4.1%	0.49
May 2 10:00:00 - 10:59:59	698	5.0%	0.19	2.6 MB	6.0%	0.73
May 2 11:00:00 - 11:59:59	638	4.6%	0.18	1.7 MB	4.1%	0.49
May 2 12:00:00 - 12:59:59	702	5.1%	0.20	2.0 MB	4.6%	0.56
May 2 13:00:00 - 13:59:59	784	5.7%	0.22	1.9 MB	4.5%	0.55
May 2 14:00:00 - 14:59:59	898	6.5%	0.25	2.2 MB	5.3%	0.64
May 2 15:00:00 - 15:59:59	802	5.8%	0.22	3.3 MB	7.8%	0.94
May 2 16:00:00 - 16:59:59	773	5.6%	0.21	2.1 MB	5.0%	0.61
May 2 17:00:00 - 17:59:59	861	6.2%	0.24	3.6 MB	8.4%	1.01
May 2 18:00:00 - 18:59:59	721	5.2%	0.20	2.0 MB	4.7%	0.57
May 2 19:00:00 - 19:59:59	663	4.8%	0.18	1.7 MB	4.0%	0.49
May 2 20:00:00 - 20:59:59	606	4.4%	0.17	1.5 MB	3.6%	0.44
May 2 21:00:00 - 21:59:59	481	3.5%	0.13	1.5 MB	3.4%	0.42
May 2 22:00:00 - 22:59:59	534	3.9%	0.15	1.6 MB	3.6%	0.44
May 2 23:00:00 - 23:59:59	398	2.9%	0.11	1.1 MB	2.7%	0.32
May 3 00:00:00 - 00:59:59	306	2.2%	0.09	1.0 MB	2.4%	0.29
May 3 01:00:00 - 01:59:59	313	2.3%	0.09	823.9 KB	1.9%	0.23
May 3 02:00:00 - 02:59:59	235	1.7%	0.07	777.1 KB	1.8%	0.22
May 3 03:00:00 - 03:59:59	211	1.5%	0.06	600.8 KB	1.4%	0.17
May 3 04:00:00 - 04:15:00	76	0.5%	0.08	227.1 KB	0.5%	0.25
TOTAL: 24:00:00	13827	100.0%	0.16	42.6 MB	100.0%	0.50

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	2382	181	0	11	2190
2	newsfeed.fu-berlin.de	756	15	0	0	741
3	csiph-out.feeder.erje.net	344	69	29	1	245
4	news-out.mixmin.net	190	4	1	0	185
5	csiph-out.feed.uzoreto.com	158	16	0	0	142
6	feeder.eternal-september.org	126	32	2	0	92
7	newsfeed.neva.ru	75	43	0	0	32
8	news-out.aioe.org	50	34	0	0	16
9	csiph-out.news.weretis.net	47	7	1	0	39
10	news.freedyn.net	47	1	0	0	46
11	news-out.neodome.net	41	1	0	0	40
12	news.swapon.de	18	0	0	0	18
13	newsfeeds.xmission.com	13	0	0	0	13
14	csiph-out.news.tnetconsulting.net	7	7	0	0	0
15	nntp.pasdenom.info	7	0	0	0	7
TOTAL: 15		4261	410	33	12	3806

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	89
it-alt.sport.calcio.juventus	49
alt.news.misc	48
alt.binaries.by-hash.shv1	30
alt.binaries.pictures.purebeauty	18
lada.talk	17
alt.windows7.general	14
spline.eisfair	9
biz.marketplace	6
alt.binaries.e-books.flood	6
lada.movies	5
alt.slack.goathead	5
mozilla.support.seamonkey	4
free.it.religioni.scientology	4
alt.politics	4
alt.finance	4
alt.computer.hardware	3
alt.comp.lang.raku	3
0.test	3
alt.religion	3
TOTAL: 97	410

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

Groups	Count
fido7.z7.test	1
fido7.mikdim.test	1
fido7.esib.test	1
fido7.dn.test	1
fido7.f157.test	1
fido7.f400.test	1
fido7.kharkov.blin.test	1
fido7.pskov.test	1
fido7.donbass.test	1
fido7.cris.test	1
fido7.krs.test	1
fido7.kharkov.star.test	1
TOTAL: 12	12

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1778
Binary: misplaced binary	298
Too many newsgroups	260
EMP (phn nph)	181
Binary Payload	92
EMP (phn path)	87
EMP (phl)	72
0	38
HTML Multipart	3
Too many newsgroups (meow)	2
bofh violation - invalid distribution	1
MID-Bot	1
HTML post	1
TOTAL: 13	2814

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	csiph-out.news.xs4all.nl	238
	feeder.eternal-september.org	79
	csiph-out.feeder.erje.net	76
	csiph-out.feed.uzoreto.com	38
	csiph-out.news.weretis.net	18
	newsfeeds.xmission.com	11
	news-out.mixmin.net	7
	news.freedyn.net	6
	newsfeed.fu-berlin.de	2
	TOTAL: 9	475
Including strange strings
	newsfeed.fu-berlin.de	370
	csiph-out.news.xs4all.nl	99
	news.swapon.de	18
	csiph-out.feeder.erje.net	8
	csiph-out.feed.uzoreto.com	5
	news-out.mixmin.net	4
	news-out.aioe.org	3
	news-out.neodome.net	2
	newsfeed.neva.ru	2
	newsfeeds.xmission.com	2
	TOTAL: 11	514
No colon-space in header
	csiph-out.news.xs4all.nl	1
	csiph-out.feeder.erje.net	1
	TOTAL: 2	2
TOTAL: 3		991

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	12608	456	12152	0	0	3%	23:50:09
2	news.bbs.nz	25580	139	13048	15	0	0%	23:50:09
3	xmission	17180	76	12455	9	0	0%	23:50:09
4	tnet	14449	35	13235	1	0	0%	23:58:51
5	newsfeed.xs4all.nl	9038	29	8591	1	70	0%	23:54:24
6	erje	13328	27	12668	19	0	0%	23:56:20
7	usenet.pasdenom.info	13101	27	12800	13	39	0%	23:59:53
8	news.freedyn.net	14126	18	13653	101	45	0%	23:55:47
9	weretis.net	12591	12	12165	5	0	0%	23:52:42
10	news.uzoreto.com	12987	8	12820	38	0	0%	23:59:00
11	news.mixmin.net	12110	8	11992	1	0	0%	23:59:05
12	neodome.net	13692	7	13513	0	0	0%	23:50:08
13	neva.ru	12772	2	12712	3	0	0%	23:52:32
14	aioe	11652	0	11358	0	0	0%	23:54:13
15	news.fcku.it	945	0	944	0	0	0%	21:26:03
16	xanadu-bbs	0	0	0	0	13827	0%	23:58:54
17	eternal-september	0	0	0	0	11293	0%	23:50:09
18	news.samoylyk.net	0	0	0	0	13781	0%	23:50:11
19	news.swapon.de	1317	0	1310	0	0	0%	22:56:34
TOTAL: 19		197476	844	175416	206	39055	0%	450:45:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	12.4 KB	23:50:09
2	news.bbs.nz	346.3 KB	70.0 KB	416.4 KB	0.0 KB/s	2.7 KB	23:50:09
3	newsfeed.xs4all.nl	293.2 KB	1.2 KB	294.3 KB	0.0 KB/s	9.8 KB	23:54:24
4	xmission	219.1 KB	16.7 KB	235.8 KB	0.0 KB/s	2.8 KB	23:50:09
5	usenet.pasdenom.info	193.0 KB	93.3 KB	286.3 KB	0.0 KB/s	7.2 KB	23:59:53
6	news.freedyn.net	191.6 KB	1.4 MB	1.6 MB	0.0 KB/s	13.9 KB	23:55:47
7	news.mixmin.net	160.8 KB	0.8 KB	161.6 KB	0.0 KB/s	18.0 KB	23:59:05
8	tnet	139.7 KB	1.4 KB	141.0 KB	0.0 KB/s	3.9 KB	23:58:51
9	news.uzoreto.com	58.3 KB	357.7 KB	416.0 KB	0.0 KB/s	9.0 KB	23:59:00
10	erje	53.1 KB	93.7 KB	146.8 KB	0.0 KB/s	3.2 KB	23:56:20
11	neodome.net	40.0 KB	0.0 KB	40.0 KB	0.0 KB/s	5.7 KB	23:50:08
12	weretis.net	32.9 KB	16.0 KB	48.9 KB	0.0 KB/s	2.9 KB	23:52:42
13	neva.ru	2.7 KB	74.5 KB	77.2 KB	0.0 KB/s	15.4 KB	23:52:32
14	aioe	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:54:13
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:26:03
16	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:58:54
17	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:09
18	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:11
19	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:56:34
TOTAL: 19		7.2 MB	2.1 MB	9.4 MB	0.0 KB/s	9.1 KB	450:45:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	217-121-77-183.cable.dynamic.v4.ziggo.nl	90	1317	2.0 MB	19	0	0	00:29:25
TOTAL: 1		90	1317	2.0 MB	19	0	0	30:02:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.cable.dynamic.v4.ziggo.nl	90	1317	2.0 MB	19	00:29:25
2	*.ch1.censys-scanner.com	1	0	0.0 KB	0	00:00:00
3	*.security.ipip.net	1	0	0.0 KB	0	00:00:01
4	*.upc-a.chello.nl	23	0	0.0 KB	0	03:40:02
5	unresolved	288	0	0.0 KB	0	23:52:45
6	*.163.24.10.ip.us.as30241.net	4	0	0.0 KB	0	02:00:28
TOTAL: 6		407	1317	2.0 MB	19	30:02:44

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.260	4.349	23:52:45
a131033.upc-a.chello.nl	1.396	0.277	03:40:02
192.163.24.10.ip.us.as30241.net	0.415	0.095	02:00:28
217-121-77-183.cable.dynamic.v4.ziggo.nl	9.149	4.747	00:29:25
scan-141.security.ipip.net	0.057	0.016	00:00:01
scanner-08.ch1.censys-scanner.com	0.070	0.009	00:00:00
TOTAL: 6	28.347	9.493	30:02:44

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	288
a131033.upc-a.chello.nl	23
192.163.24.10.ip.us.as30241.net	4
scan-141.security.ipip.net	1
scanner-08.ch1.censys-scanner.com	1
TOTAL: 5	317

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
192.163.24.10.ip.us.as30241.net	4	0
TOTAL: 1	4	0

Newsgroup request counts (by category):

Category		Count	Pct
1	free	1282	97.3%
2	rec	33	2.5%
3	uk	1	0.1%
4	xs4all	1	0.1%
TOTAL: 4		1317	100.0%

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

Newsgroup		Count
1	free.willey	772
2	free.usenet	431
3	free.pt	79
4	rec.arts.tv	33
5	xs4all.general	1
6	uk.radio.amateur	1
TOTAL: 6		1317

Daily Usenet report for csiph.com

May 2 04:15:00 -- May 3 04:15:00