Daily Usenet report for csiph.com: Mar 31 04:15:00

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Mar 31 06:18:33 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[27898]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 31 06:18:33 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[27898]: 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]
Mar 31 18:32:27 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[67784]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 31 18:32:27 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[67784]: 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]
Mar 31 20:39:13 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[74851]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 31 20:39:13 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[74851]: 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	30357	46.6%	4.6 MB	40.5%
inn	28878	44.4%	5.7 MB	50.8%
innfeed	3671	5.6%	710.1 KB	6.1%
nnrpd	2152	3.3%	294.7 KB	2.6%
nocem	18	0.0%	2.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	65077	100.0%	11.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	332054	48.7%
Negative hits	320382	47.0%
Do not exist	28176	4.1%
Cache misses	588	0.1%
TOTAL: 4	681200	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.176	0.0%	26	0.000	6.769	53.000
article cleanup	00:00:00.178	0.0%	27975	0.000	0.006	0.025
article logging	00:00:01.813	0.0%	28460	0.000	0.064	1.238
article parse	00:00:02.129	0.0%	82117	0.000	0.026	0.365
article write	00:01:33.857	0.1%	15023	0.179	6.248	79.162
artlog/artcncl	00:00:00.003	0.0%	26	0.000	0.115	1.000
artlog/artparse	00:00:00.009	0.0%	337	0.000	0.027	1.000
data move	00:00:00.193	0.0%	616742	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.041	0.0%	26	0.000	1.577	13.000
hishave/artcncl	00:00:00.002	0.0%	26	0.000	0.077	0.667
hishave/artparse	00:00:00.001	0.0%	336	0.000	0.003	0.048
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:12.063	0.1%	684694	0.004	0.105	0.478
history sync	00:00:01.783	0.0%	357	0.000	4.994	57.500
history write	00:02:07.337	0.1%	27963	0.225	4.554	17.676
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.479	0.0%	336	0.000	4.402	84.000
idle	23:36:21.499	98.7%	629588	86.663	134.980	468.548
nntp read	00:00:02.843	0.0%	640092	0.002	0.004	0.009
overview write	00:11:11.210	0.8%	15023	0.196	44.679	11898.810
perl filter	00:00:43.714	0.1%	27961	1.185	1.563	2.271
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.275	0.0%	30064	0.000	0.009	0.037
TOTAL: 23:54:22.483	23:53:20.605	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.029	0.0%	15185	0.000	0.002	0.018
article prepare	00:00:00.000	0.0%	3844	0.000	0.000	0.000
article read	00:00:02.744	0.0%	822	0.000	3.338	83.750
backlog stats	00:00:00.163	0.0%	286469	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.029	0.0%	224389	0.001	0.005	0.014
data write	00:00:01.346	0.0%	211975	0.000	0.006	0.016
idle	23:54:40.145	100.0%	286469	178.943	300.487	955.536
status file	00:00:00.683	0.0%	347	0.000	1.968	43.000
TOTAL: 23:54:59.508	23:54:46.139	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	34:52:28.379	99.9%	1085	0.000	115712.792	600103.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.025	0.0%	2486	0.000	0.010	0.500
TOTAL: 34:54:11.361	34:52:28.404	99.9%	-	-	-	-

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	1351	27535	4173	15544	7818	15%	133:28:12
2	feeder.eternal-september.org	69	4264	2243	1487	534	52%	38:31:25
3	csiph-out.feeder.erje.net	1728	93672	1834	90317	1521	1%	142:52:54
4	news-out.aioe.org	2	14522	1334	13148	40	9%	23:59:15
5	news-out.mixmin.net	2	15838	1175	14451	212	7%	23:59:52
6	csiph-out.news.weretis.net	4	29540	1008	28406	126	3%	47:59:27
7	newsfeed.fu-berlin.de	21	14672	735	13816	121	5%	26:54:03
8	newsfeed.neva.ru	155	26420	733	24711	976	2%	71:43:47
9	csiph-out.feed.uzoreto.com	864	36932	461	33905	2566	1%	71:21:47
10	news-out.neodome.net	2	16265	289	15918	58	1%	23:59:53
11	nntp.pasdenom.info	4	9726	252	9469	5	2%	23:53:24
12	news.freedyn.net	3	28552	246	27926	380	0%	24:09:02
13	news.fcku.it	14	1595	223	1365	7	13%	21:51:17
14	newsfeeds.xmission.com	3	13320	113	13206	1	0%	24:02:52
15	csiph-out.news.tnetconsulting.net	4	22771	88	22682	1	0%	35:17:45
16	news.swapon.de	8	1261	41	1216	4	3%	23:09:49
17	news.bbs.nz	2	13713	5	13708	0	0%	23:59:50
18	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 18		4262	370598	14953	341275	14370	4%	781:14:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	11.4 MB	0.0 KB	28.6 MB	40.0 MB	28%	3.4 KB
2	csiph-out.feeder.erje.net	8.0 MB	46.5 KB	4.6 MB	12.7 MB	63%	3.9 KB
3	feeder.eternal-september.org	6.0 MB	0.0 KB	8.3 MB	14.2 MB	41%	5.2 KB
4	news-out.aioe.org	3.4 MB	4.2 KB	75.0 KB	3.4 MB	97%	2.6 KB
5	csiph-out.news.weretis.net	2.7 MB	4.3 KB	972.3 KB	3.6 MB	73%	3.3 KB
6	news-out.mixmin.net	2.6 MB	0.0 KB	660.6 KB	3.2 MB	79%	2.4 KB
7	newsfeed.neva.ru	2.0 MB	6.1 KB	2.4 MB	4.4 MB	45%	2.6 KB
8	newsfeed.fu-berlin.de	1.8 MB	0.0 KB	381.4 KB	2.2 MB	82%	2.6 KB
9	csiph-out.feed.uzoreto.com	1.4 MB	0.0 KB	11.7 MB	13.1 MB	10%	4.4 KB
10	news.freedyn.net	857.3 KB	0.0 KB	4.6 MB	5.4 MB	15%	8.8 KB
11	news-out.neodome.net	673.8 KB	0.0 KB	491.3 KB	1.1 MB	57%	3.4 KB
12	nntp.pasdenom.info	579.3 KB	0.0 KB	11.3 KB	590.6 KB	98%	2.3 KB
13	news.fcku.it	364.4 KB	0.0 KB	8.2 KB	372.6 KB	97%	1.6 KB
14	csiph-out.news.tnetconsulting.net	301.3 KB	0.0 KB	5.0 KB	306.2 KB	98%	3.4 KB
15	newsfeeds.xmission.com	199.1 KB	0.0 KB	2.3 KB	201.4 KB	98%	1.8 KB
16	news.swapon.de	94.0 KB	0.0 KB	118.3 KB	212.3 KB	44%	4.7 KB
17	news.bbs.nz	15.9 KB	0.0 KB	0.0 KB	15.9 KB	100%	3.2 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		42.1 MB	61.2 KB	62.8 MB	105.0 MB	40%	3.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 31 04:15:00 - 04:59:59	277	1.8%	0.10	1.5 MB	3.5%	0.57
Mar 31 05:00:00 - 05:59:59	406	2.7%	0.11	1.4 MB	3.3%	0.40
Mar 31 06:00:00 - 06:59:59	509	3.4%	0.14	1.4 MB	3.3%	0.40
Mar 31 07:00:00 - 07:59:59	544	3.6%	0.15	1.7 MB	4.0%	0.48
Mar 31 08:00:00 - 08:59:59	638	4.2%	0.18	1.7 MB	3.9%	0.47
Mar 31 09:00:00 - 09:59:59	700	4.6%	0.19	1.7 MB	4.1%	0.50
Mar 31 10:00:00 - 10:59:59	652	4.3%	0.18	1.7 MB	4.0%	0.48
Mar 31 11:00:00 - 11:59:59	796	5.3%	0.22	2.1 MB	5.1%	0.61
Mar 31 12:00:00 - 12:59:59	776	5.2%	0.22	2.1 MB	5.1%	0.61
Mar 31 13:00:00 - 13:59:59	815	5.4%	0.23	1.9 MB	4.6%	0.55
Mar 31 14:00:00 - 14:59:59	859	5.7%	0.24	2.2 MB	5.1%	0.62
Mar 31 15:00:00 - 15:59:59	869	5.8%	0.24	2.3 MB	5.4%	0.65
Mar 31 16:00:00 - 16:59:59	810	5.4%	0.23	2.1 MB	5.0%	0.61
Mar 31 17:00:00 - 17:59:59	788	5.2%	0.22	1.9 MB	4.4%	0.53
Mar 31 18:00:00 - 18:59:59	884	5.9%	0.25	2.3 MB	5.5%	0.66
Mar 31 19:00:00 - 19:59:59	770	5.1%	0.21	2.2 MB	5.2%	0.63
Mar 31 20:00:00 - 20:59:59	713	4.7%	0.20	1.9 MB	4.4%	0.53
Mar 31 21:00:00 - 21:59:59	746	5.0%	0.21	1.8 MB	4.3%	0.52
Mar 31 22:00:00 - 22:59:59	579	3.8%	0.16	2.0 MB	4.7%	0.57
Mar 31 23:00:00 - 23:59:59	441	2.9%	0.12	1.2 MB	2.9%	0.34
Apr 1 00:00:00 - 00:59:59	480	3.2%	0.13	1.4 MB	3.4%	0.40
Apr 1 01:00:00 - 01:59:59	324	2.2%	0.09	961.8 KB	2.2%	0.27
Apr 1 02:00:00 - 02:59:59	351	2.3%	0.10	1.3 MB	3.0%	0.36
Apr 1 03:00:00 - 03:59:59	229	1.5%	0.06	633.3 KB	1.5%	0.18
Apr 1 04:00:00 - 04:15:00	108	0.7%	0.12	926.6 KB	2.1%	1.03
TOTAL: 24:00:00	15064	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	7871	180	0	1	7690
2	csiph-out.feed.uzoreto.com	2562	28	0	0	2534
3	newsfeed.neva.ru	976	40	3	0	933
4	csiph-out.feeder.erje.net	559	88	4	0	467
5	feeder.eternal-september.org	507	83	0	0	424
6	newsfeed.fu-berlin.de	449	30	0	0	419
7	news.freedyn.net	363	5	0	0	358
8	news-out.mixmin.net	226	8	0	0	218
9	csiph-out.news.weretis.net	125	40	3	0	82
10	news-out.neodome.net	59	2	0	0	57
11	news-out.aioe.org	45	25	2	0	18
12	news.swapon.de	24	0	0	0	24
13	news.fcku.it	7	7	0	0	0
14	nntp.pasdenom.info	5	0	0	0	5
15	newsfeeds.xmission.com	4	0	1	0	3
TOTAL: 15		13782	536	13	1	13232

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	172
alt.windows7.general	35
alt.bbs.synchronet	34
alt.binaries.by-hash.shv1	25
alt.news.misc	23
lada.talk	21
mozilla.support.seamonkey	20
alt.job	18
it-alt.sport.calcio.juventus	18
mozilla.support.thunderbird	16
at.jobs	12
alt.philosophy	12
alt.computer.hardware	10
spline.eisfair	9
alt.binaries.pictures.purebeauty	8
oesterreich.tratsch	6
alt.att	5
lada.auto	4
mozilla.test	4
alt.survival	4
TOTAL: 69	536

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

Groups	Count
it.scienza.astronomia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	10035
EMP (md5)	1712
EMP (phn path)	301
EMP (phl)	176
EMP (phn nph)	112
0	34
Too many newsgroups (meow)	17
Binary: misplaced binary	9
HTML Multipart	3
Scoring filter	3
HTML post	1
TOTAL: 11	12403

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	54
	TOTAL: 1	54
Huge articles
	csiph-out.news.xs4all.nl	102
	csiph-out.feed.uzoreto.com	62
	csiph-out.feeder.erje.net	59
	feeder.eternal-september.org	58
	news.freedyn.net	33
	csiph-out.news.weretis.net	12
	news-out.neodome.net	7
	news-out.mixmin.net	4
	news.swapon.de	2
	newsfeed.fu-berlin.de	2
	TOTAL: 10	341
Including strange strings
	newsfeed.fu-berlin.de	328
	csiph-out.news.xs4all.nl	74
	news.swapon.de	20
	csiph-out.feeder.erje.net	15
	csiph-out.news.weretis.net	15
	news-out.mixmin.net	10
	news-out.aioe.org	8
	csiph-out.feed.uzoreto.com	8
	feeder.eternal-september.org	3
	newsfeed.neva.ru	2
	TOTAL: 12	486
No colon-space in header
	csiph-out.feeder.erje.net	1
	TOTAL: 1	1
TOTAL: 4		882

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	14120	448	13672	0	0	3%	23:59:48
2	news.bbs.nz	28228	158	14367	7	0	0%	23:59:48
3	xmission	18400	109	14190	11	0	0%	23:59:48
4	tnet	15541	66	14472	1	0	0%	23:59:35
5	weretis.net	13678	59	13266	4	0	0%	23:59:48
6	usenet.pasdenom.info	14582	19	14251	2	20	0%	23:59:48
7	newsfeed.xs4all.nl	10994	11	10539	1	129	0%	23:59:48
8	news.freedyn.net	14779	7	14638	40	0	0%	23:59:48
9	news.uzoreto.com	13046	5	12968	13	0	0%	23:59:48
10	news.mixmin.net	13215	3	13106	0	0	0%	23:59:48
11	neva.ru	13357	2	13254	18	0	0%	23:59:48
12	aioe	12632	2	12532	0	0	0%	23:59:48
13	erje	13034	1	12977	3	15	0%	23:59:48
14	neodome.net	14761	1	14644	0	0	0%	23:59:43
15	news.fcku.it	1230	0	1230	0	0	0%	21:27:54
16	news.samoylyk.net	0	0	0	0	15064	0%	23:58:35
17	xanadu-bbs	0	0	0	0	15064	0%	23:58:43
18	eternal-september	0	0	0	0	12171	0%	23:59:49
19	news.swapon.de	1236	0	1231	0	0	0%	23:22:05
TOTAL: 19		212833	891	191337	100	42463	0%	452:44:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	fu-berlin.de	3.6 MB	0.0 KB	3.6 MB	8.3 KB	23:59:48
2	news.bbs.nz	327.4 KB	22.2 KB	349.6 KB	2.1 KB	23:59:48
3	xmission	280.1 KB	18.1 KB	298.2 KB	2.5 KB	23:59:48
4	usenet.pasdenom.info	140.0 KB	6.7 KB	146.7 KB	7.0 KB	23:59:48
5	weretis.net	129.9 KB	24.5 KB	154.3 KB	2.4 KB	23:59:48
6	tnet	99.3 KB	1.7 KB	101.0 KB	1.5 KB	23:59:35
7	news.uzoreto.com	46.8 KB	34.1 KB	80.9 KB	4.5 KB	23:59:48
8	newsfeed.xs4all.nl	20.9 KB	2.1 KB	23.0 KB	1.9 KB	23:59:48
9	news.freedyn.net	15.6 KB	808.3 KB	823.9 KB	17.5 KB	23:59:48
10	news.mixmin.net	7.8 KB	0.0 KB	7.8 KB	2.6 KB	23:59:48
11	aioe	5.3 KB	0.0 KB	5.3 KB	2.6 KB	23:59:48
12	neva.ru	3.8 KB	49.5 KB	53.3 KB	2.7 KB	23:59:48
13	erje	1.8 KB	45.4 KB	47.2 KB	11.8 KB	23:59:48
14	neodome.net	1.2 KB	0.0 KB	1.2 KB	1.2 KB	23:59:43
15	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	21:27:54
16	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:58:35
17	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0	23:58:43
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0	23:59:49
19	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0	23:22:05
TOTAL: 19		4.7 MB	1012.5 KB	5.7 MB	5.9 KB	452:44:00

NNRP readership statistics (by domain):

System		Conn	Groups	Elapsed
1	*.upc-a.chello.nl	35	0	05:39:25
2	*.ch1.censys-scanner.com	1	0	00:00:01
3	*.security.ipip.net	1	0	00:00:01
4	*.range86-139.btcentralplus.com	2	0	00:03:49
5	unresolved	305	34	29:10:36
TOTAL: 5		344	34	34:53:56

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.125	4.510	23:54:07
a131033.upc-a.chello.nl	2.087	0.484	05:39:25
172.83.100.217	1.015	0.206	05:16:18
host86-139-107-9.range86-139.btcentralplus.com	0.109	0.043	00:03:49
192.241.218.211	0.047	0.028	00:00:09
scan-141.security.ipip.net	0.055	0.016	00:00:01
scanner-07.ch1.censys-scanner.com	0.070	0.008	00:00:01
185.137.234.48	0.065	0.008	00:00:00
TOTAL: 8	20.573	5.303	34:53:56

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	288
a131033.upc-a.chello.nl	35
172.83.100.217	15
host86-139-107-9.range86-139.btcentralplus.com	2
scanner-07.ch1.censys-scanner.com	1
192.241.218.211	1
scan-141.security.ipip.net	1
185.137.234.48	1
TOTAL: 8	344

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
185.137.234.48	1
scanner-07.ch1.censys-scanner.com	1
TOTAL: 3	6

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.217	6	0
185.137.234.48	1	1
TOTAL: 2	7	1

Newsgroup request counts (by category):

Category		Count	Pct
1	es	6854	65.5%
2	alt	2154	20.6%
3	it	643	6.1%
4	chile	228	2.2%
5	hr	221	2.1%
6	perl	148	1.4%
7	rec	101	1.0%
8	de	69	0.7%
9	comp	16	0.2%
10	soc	13	0.1%
11	hk	10	0.1%
12	bit	3	0.0%
13	fr	3	0.0%
14	free	2	0.0%
15	humanityquest	1	0.0%
16	umn	1	0.0%
TOTAL: 16		10467	100.0%

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

Newsgroup		Count
1	es.charla.motor	6847
2	alt.religion.christian.roman-catholic	1708
3	it.tlc.gestori.fastweb	586
4	chile.rec.cocina	228
5	hr.sci.elektronika	221
6	alt.music.heather-nova	172
7	perl.dbi.users	148
8	alt.religion.scientology.xenu	125
9	rec.games.playing-cards	101
10	alt.religion.raelian	80
11	it.arti.musica	56
12	de.etc.haushalt	46
13	de.comp.sys.mac.soc	22
14	alt.publish.books	17
15	comp.os.geos.misc	16
16	alt.skincare.acne	16
17	soc.singles	13
18	alt.fan.countries.guyana	11
19	alt.music.mp3.winamp	11
20	hk.rec.cars	10
21	es.news.grupos	7
22	alt.tv.oz	6
23	alt.internet	4
24	bit.listserv.db2-l	3
25	alt.troll-busters	2
26	fr.lettres.langue.espagnole	2
27	free.spam	2
28	it.economia.borsa	1
29	de.comp.hardware.cpu+mainboard.misc	1
30	alt.sex.toupee	1
31	alt.paranet.psi	1
32	umn.general.movies	1
33	fr.comp.os.ms-windows.programmation	1
34	humanityquest.privacy	1
TOTAL: 34		10467

Daily Usenet report for csiph.com

Mar 31 04:15:00 -- Apr 1 04:15:00