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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 21 18:07:38 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14748]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 21 18:07:38 842ffb22-07e1-11e5-a459-00266cf00584 nnrpd[14748]: 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	29327	47.2%	5.7 MB	52.2%
innd	26684	42.9%	4.1 MB	38.0%
innfeed	4013	6.5%	784.4 KB	7.0%
nnrpd	2132	3.4%	306.5 KB	2.8%
nocem	12	0.0%	1.5 KB	0.0%
controlchan	5	0.0%	0.4 KB	0.0%
TOTAL: 6	62173	100.0%	10.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	237614	50.0%
Negative hits	206080	43.3%
Do not exist	28355	6.0%
Cache misses	3396	0.7%
TOTAL: 4	475445	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.372	0.0%	29	0.000	12.828	51.000
article cleanup	00:00:00.131	0.0%	27663	0.000	0.005	0.027
article logging	00:00:01.529	0.0%	28024	0.000	0.055	1.424
article parse	00:00:07.262	0.0%	146193	0.000	0.050	1.026
article write	00:01:04.464	0.1%	13022	0.145	4.950	48.831
artlog/artcncl	00:00:00.001	0.0%	29	0.000	0.034	0.100
artlog/artparse	00:00:00.041	0.0%	977	0.000	0.042	2.100
data move	00:00:00.230	0.0%	537209	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.020	0.0%	28	0.000	0.714	15.000
hishave/artcncl	00:00:00.000	0.0%	29	0.000	0.000	0.000
hishave/artparse	00:00:00.001	0.0%	975	0.000	0.001	0.007
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:01:40.341	0.1%	480725	0.004	0.209	1.146
history sync	00:00:01.712	0.0%	370	0.000	4.627	80.000
history write	00:02:23.358	0.2%	27636	0.720	5.187	15.054
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:05.529	0.0%	975	0.000	5.671	656.000
idle	23:26:24.079	99.0%	528683	15.551	159.612	462.552
nntp read	00:00:02.360	0.0%	538665	0.002	0.004	0.014
overview write	00:08:00.439	0.6%	13022	0.149	36.894	8146.848
perl filter	00:00:26.954	0.0%	27633	0.705	0.975	1.509
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.235	0.0%	26051	0.000	0.009	0.045
TOTAL: 23:41:00.956	23:40:19.058	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	12997	0.000	0.003	0.089
article prepare	00:00:00.002	0.0%	5224	0.000	0.000	0.050
article read	00:00:00.844	0.0%	1028	0.000	0.821	55.000
backlog stats	00:00:00.173	0.0%	252019	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.854	0.0%	194919	0.000	0.004	0.014
data write	00:00:01.146	0.0%	182942	0.001	0.006	0.015
idle	23:24:54.112	100.0%	252019	201.979	334.475	976.614
status file	00:00:00.449	0.0%	392	0.000	1.145	15.000
TOTAL: 23:25:07.604	23:24:57.615	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	52:08:58.522	99.5%	1087	0.000	172712.532	600105.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.013	0.0%	2462	0.000	0.005	0.167
readart	00:00:00.298	0.0%	6	29.000	49.667	100.000
TOTAL: 52:25:31.853	52:08:58.833	99.5%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	129
pause	2
paused	2
reload	4
reserve	2
shutdown	9
TOTAL: 11	160

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	53	4924	2040	2500	384	41%	33:55:44
2	newsfeeds.xmission.com	6	12609	1682	10912	15	13%	24:15:30
3	csiph-out.news.xs4all.nl	12	26328	1556	24076	696	5%	68:07:54
4	news-out.mixmin.net	5	14062	1553	12310	199	11%	23:56:29
5	news.freedyn.net	5	15412	1218	13575	619	7%	23:58:02
6	csiph-out.feeder.erje.net	1455	65673	1104	64193	376	1%	118:11:43
7	csiph-out.news.weretis.net	17	26903	909	25815	179	3%	49:23:32
8	newsfeed.fu-berlin.de	18	25512	741	12103	12668	2%	25:41:28
9	news-out.aioe.org	4	12141	680	11434	27	5%	23:44:02
10	csiph-out.feed.uzoreto.com	872	25391	464	24348	579	1%	70:39:14
11	newsfeed.neva.ru	145	18257	387	17730	140	2%	70:26:13
12	news-out.neodome.net	4	14248	363	13863	22	2%	23:47:30
13	news.fcku.it	19	1248	153	1095	0	12%	20:40:02
14	nntp.pasdenom.info	5	8195	129	8063	3	1%	23:42:23
15	csiph-out.news.tnetconsulting.net	4	20548	56	20482	10	0%	35:11:10
16	news.swapon.de	13	1033	36	997	0	3%	21:59:22
17	news.bbs.nz	4	19984	6	19978	0	0%	34:28:05
18	localhost	26	0	0	0	0	0%	00:00:00
TOTAL: 18		2667	312468	13077	283474	15917	4%	692:08:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	6.1 MB	9.6 KB	3.0 MB	9.1 MB	67%	6.3 KB
2	feeder.eternal-september.org	5.8 MB	0.0 KB	150.9 MB	156.8 MB	3%	66.2 KB
3	newsfeeds.xmission.com	4.9 MB	0.0 KB	1006.1 KB	5.9 MB	83%	3.5 KB
4	csiph-out.news.xs4all.nl	4.4 MB	0.0 KB	2.8 MB	7.3 MB	60%	3.3 KB
5	news-out.mixmin.net	3.4 MB	0.0 KB	584.1 KB	3.9 MB	85%	2.3 KB
6	news.freedyn.net	2.9 MB	10.1 KB	4.1 MB	6.9 MB	41%	3.9 KB
7	csiph-out.news.weretis.net	2.5 MB	0.0 KB	2.2 MB	4.7 MB	52%	4.4 KB
8	news-out.aioe.org	2.0 MB	0.0 KB	80.8 KB	2.1 MB	96%	3.1 KB
9	csiph-out.feed.uzoreto.com	1.8 MB	37.0 KB	28.8 MB	30.7 MB	5%	30.1 KB
10	newsfeed.fu-berlin.de	1.7 MB	0.0 KB	32.0 MB	33.7 MB	5%	2.6 KB
11	news-out.neodome.net	1.2 MB	10.1 KB	140.3 KB	1.3 MB	89%	3.6 KB
12	newsfeed.neva.ru	1.1 MB	31.2 KB	416.3 KB	1.5 MB	70%	2.9 KB
13	nntp.pasdenom.info	292.2 KB	0.0 KB	8.0 KB	300.2 KB	97%	2.3 KB
14	news.fcku.it	223.2 KB	0.0 KB	0.0 KB	223.2 KB	100%	1.5 KB
15	csiph-out.news.tnetconsulting.net	194.8 KB	0.0 KB	188.3 KB	383.1 KB	50%	5.8 KB
16	news.swapon.de	101.4 KB	0.0 KB	0.0 KB	101.4 KB	100%	2.8 KB
17	news.bbs.nz	11.2 KB	0.0 KB	0.0 KB	11.2 KB	100%	1.9 KB
18	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 18		38.6 MB	97.9 KB	226.2 MB	264.9 MB	14%	9.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 21 04:15:00 - 04:59:59	214	1.6%	0.08	661.2 KB	1.6%	0.24
Feb 21 05:00:00 - 05:59:59	255	1.9%	0.07	724.3 KB	1.8%	0.20
Feb 21 06:00:00 - 06:59:59	253	1.9%	0.07	799.0 KB	2.0%	0.22
Feb 21 07:00:00 - 07:59:59	423	3.2%	0.12	1.4 MB	3.6%	0.40
Feb 21 08:00:00 - 08:59:59	537	4.0%	0.15	2.1 MB	5.4%	0.60
Feb 21 09:00:00 - 09:59:59	595	4.5%	0.17	2.5 MB	6.4%	0.71
Feb 21 10:00:00 - 10:59:59	632	4.8%	0.18	1.7 MB	4.4%	0.49
Feb 21 11:00:00 - 11:59:59	575	4.3%	0.16	1.6 MB	4.1%	0.45
Feb 21 12:00:00 - 12:59:59	507	3.8%	0.14	1.5 MB	3.9%	0.43
Feb 21 13:00:00 - 13:59:59	525	4.0%	0.15	1.3 MB	3.3%	0.36
Feb 21 14:00:00 - 14:59:59	751	5.7%	0.21	2.1 MB	5.3%	0.59
Feb 21 15:00:00 - 15:59:59	703	5.3%	0.20	2.0 MB	5.0%	0.56
Feb 21 16:00:00 - 16:59:59	847	6.4%	0.24	2.1 MB	5.5%	0.61
Feb 21 17:00:00 - 17:59:59	829	6.2%	0.23	2.3 MB	5.8%	0.64
Feb 21 18:00:00 - 18:59:59	762	5.7%	0.21	2.1 MB	5.3%	0.59
Feb 21 19:00:00 - 19:59:59	679	5.1%	0.19	2.0 MB	5.2%	0.58
Feb 21 20:00:00 - 20:59:59	654	4.9%	0.18	1.7 MB	4.3%	0.48
Feb 21 21:00:00 - 21:59:59	699	5.3%	0.19	1.9 MB	4.9%	0.54
Feb 21 22:00:00 - 22:59:59	628	4.7%	0.17	1.8 MB	4.6%	0.51
Feb 21 23:00:00 - 23:59:59	575	4.3%	0.16	1.8 MB	4.6%	0.51
Feb 22 00:00:00 - 00:59:59	488	3.7%	0.14	1.5 MB	3.8%	0.42
Feb 22 01:00:00 - 01:59:59	425	3.2%	0.12	1.4 MB	3.6%	0.40
Feb 22 02:00:00 - 02:59:59	286	2.2%	0.08	954.3 KB	2.4%	0.27
Feb 22 03:00:00 - 03:59:59	361	2.7%	0.10	1.1 MB	2.9%	0.32
Feb 22 04:00:00 - 04:15:00	69	0.5%	0.08	239.3 KB	0.6%	0.27
TOTAL: 24:00:00	13272	100.0%	0.15	39.2 MB	100.0%	0.46

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.fu-berlin.de	12883	39	0	12844
2	csiph-out.news.xs4all.nl	800	62	0	738
3	news.freedyn.net	607	7	1	599
4	csiph-out.feed.uzoreto.com	584	11	13	560
5	feeder.eternal-september.org	389	18	0	371
6	news-out.mixmin.net	206	5	0	201
7	csiph-out.feeder.erje.net	176	51	3	122
8	newsfeed.neva.ru	144	10	11	123
9	csiph-out.news.weretis.net	127	37	0	90
10	news-out.aioe.org	28	21	0	7
11	newsfeeds.xmission.com	27	2	0	25
12	news-out.neodome.net	27	2	1	24
13	news.swapon.de	20	0	0	20
14	csiph-out.news.tnetconsulting.net	5	4	0	1
15	nntp.pasdenom.info	3	0	0	3
TOTAL: 15		16026	269	29	15728

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	51
spline.eisfair	40
scinet.stats	25
mozilla.general	18
alt.binaries.pictures.purebeauty	17
alt.bbs.synchronet	11
lada.talk	8
it-alt.sport.calcio.juventus	7
alt.survival	7
free.it.religioni.scientology	6
alt.binaries.ebook	6
mozilla.test	6
alt.philosophy	4
mozilla.support.seamonkey	4
mozilla.support.firefox	4
scinet.bbs.mystic	3
trollnet.cinema	3
scinet.bbs.software	3
scinet.general.chatter	3
oesterreich.tratsch	2
TOTAL: 53	269

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	12500
EMP (md5)	1460
Too many newsgroups	185
EMP (phn nph)	83
EMP (phl)	72
EMP (phn path)	47
0	23
Binary Payload	13
HTML Multipart	8
Too many test groups in crosspost	1
TOTAL: 10	14392

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	csiph-out.feed.uzoreto.com	337
	feeder.eternal-september.org	314
	csiph-out.news.xs4all.nl	164
	csiph-out.feeder.erje.net	54
	news.freedyn.net	36
	newsfeeds.xmission.com	19
	newsfeed.fu-berlin.de	19
	csiph-out.news.weretis.net	17
	news-out.mixmin.net	5
	news-out.neodome.net	1
	TOTAL: 11	967
Including strange strings
	newsfeed.fu-berlin.de	300
	news.swapon.de	20
	csiph-out.news.xs4all.nl	12
	news-out.mixmin.net	8
	csiph-out.news.weretis.net	7
	newsfeeds.xmission.com	6
	csiph-out.feeder.erje.net	6
	csiph-out.feed.uzoreto.com	5
	newsfeed.neva.ru	2
	news-out.aioe.org	2
	TOTAL: 13	371
No colon-space in header
	csiph-out.news.xs4all.nl	2
	TOTAL: 1	2
TOTAL: 4		1385

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	fu-berlin.de	12284	488	11796	0	0	3%	23:43:15
2	news.bbs.nz	24604	160	12489	18	2	0%	23:43:15
3	erje	12529	119	11222	129	0	0%	23:43:15
4	newsfeed.xs4all.nl	12273	96	11349	1	81	0%	23:44:28
5	tnet	13405	83	12584	2	0	0%	23:43:16
6	xmission	12669	36	10826	4	0	0%	23:43:16
7	neva.ru	12199	36	12013	49	0	0%	23:43:15
8	usenet.pasdenom.info	12894	9	12532	21	9	0%	23:50:02
9	news.freedyn.net	11930	5	11695	48	0	0%	23:43:15
10	news.swapon.de	1069	2	1059	0	0	0%	22:26:46
11	news.mixmin.net	10951	2	10746	1	0	0%	23:43:15
12	neodome.net	12890	2	12617	0	0	0%	23:43:15
13	weretis.net	11806	1	11599	1	0	0%	23:43:15
14	news.uzoreto.com	12303	1	12068	18	0	0%	23:43:14
15	eternal-september	0	0	0	0	10511	0%	23:43:16
16	aioe	10880	0	10569	0	0	0%	23:43:15
17	news.samoylyk.net	0	0	0	0	13182	0%	23:40:43
18	xanadu-bbs	0	0	0	0	13182	0%	23:40:49
19	news.fcku.it	1071	0	1071	0	0	0%	20:21:14
TOTAL: 19		185757	1040	166235	292	36967	0%	446:06:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	fu-berlin.de	4.4 MB	0.0 KB	4.4 MB	0.1 KB/s	9.1 KB	23:43:15
2	news.bbs.nz	442.8 KB	48.1 KB	490.8 KB	0.0 KB/s	2.8 KB	23:43:15
3	erje	357.8 KB	387.4 KB	745.2 KB	0.0 KB/s	3.0 KB	23:43:15
4	newsfeed.xs4all.nl	300.5 KB	1.5 KB	302.0 KB	0.0 KB/s	3.1 KB	23:44:28
5	tnet	150.9 KB	3.1 KB	154.1 KB	0.0 KB/s	1.8 KB	23:43:16
6	xmission	76.7 KB	6.4 KB	83.1 KB	0.0 KB/s	2.1 KB	23:43:16
7	neva.ru	75.2 KB	98.8 KB	173.9 KB	0.0 KB/s	2.0 KB	23:43:15
8	news.freedyn.net	53.7 KB	555.4 KB	609.0 KB	0.0 KB/s	11.5 KB	23:43:15
9	usenet.pasdenom.info	32.3 KB	37.7 KB	70.1 KB	0.0 KB/s	2.3 KB	23:50:02
10	news.uzoreto.com	4.0 KB	36.2 KB	40.2 KB	0.0 KB/s	2.1 KB	23:43:14
11	news.swapon.de	3.0 KB	0.0 KB	3.0 KB	0.0 KB/s	1.5 KB	22:26:46
12	neodome.net	2.8 KB	0.0 KB	2.8 KB	0.0 KB/s	1.4 KB	23:43:15
13	news.mixmin.net	2.6 KB	1.3 KB	4.0 KB	0.0 KB/s	1.3 KB	23:43:15
14	weretis.net	1.5 KB	1.1 KB	2.7 KB	0.0 KB/s	1.3 KB	23:43:15
15	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:43:16
16	aioe	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:43:15
17	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:40:43
18	xanadu-bbs	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:40:49
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:21:14
TOTAL: 19		5.8 MB	1.1 MB	7.0 MB	0.0 KB/s	5.4 KB	446:06:19

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	91.187.33.87	289	3	6.3 KB	3	36:28:36
2	a131033.upc-a.chello.nl	44	2	3.0 KB	2	12:41:26
3	192.163.24.10.ip.us.as30241.net	10	1	1.7 KB	1	02:07:38
TOTAL: 3		343	6	11.0 KB	7	52:25:13

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	292	3	6.3 KB	4	37:29:01
2	*.upc-a.chello.nl	44	2	3.0 KB	2	12:41:26
3	*.163.24.10.ip.us.as30241.net	10	1	1.7 KB	1	02:07:38
4	*.security.ipip.net	1	0	0.0 KB	0	00:00:01
5	*.ch1.censys-scanner.com	1	0	0.0 KB	0	00:00:01
6	*.range31-49.btcentralplus.com	1	0	0.0 KB	0	00:07:03
TOTAL: 6		349	6	11.0 KB	7	52:25:13

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.413	4.050	36:28:36
a131033.upc-a.chello.nl	2.752	0.743	12:41:26
192.163.24.10.ip.us.as30241.net	0.432	0.149	02:07:38
172.83.100.250	0.192	0.054	01:00:14
host31-49-229-214.range31-49.btcentralplus.com	0.065	0.016	00:07:03
217.138.221.91	0.046	0.023	00:00:10
scanner-21.ch1.censys-scanner.com	0.058	0.014	00:00:01
scan-141.security.ipip.net	0.069	0.008	00:00:01
TOTAL: 8	21.027	5.057	52:25:13

Curious NNRP server explorers [Top 20]:

System	Conn
172.83.100.250	2
217.138.221.91	1
scanner-21.ch1.censys-scanner.com	1
host31-49-229-214.range31-49.btcentralplus.com	1
scan-141.security.ipip.net	1
TOTAL: 5	6

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
TOTAL: 1	4

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
User-Agent: HTTP Banner Detection (https...	1
Host: 69.160.37.58:119	1
Connection: close	1
TOTAL: 4	4

NNRP client timeouts [Top 20]:

System	Conn	Peer
192.163.24.10.ip.us.as30241.net	2	0
172.83.100.250	2	0
91.187.33.87	1	0
scanner-21.ch1.censys-scanner.com	1	1
TOTAL: 4	6	1

Newsgroup request counts (by category):

Category		Count	Pct
1	uk	56920	100.0%
2	comp	6	0.0%
TOTAL: 2		56926	100.0%

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

Newsgroup		Count
1	uk.people.silversurfers	56920
2	comp.sys.ibm.ps2.hardware	6
TOTAL: 2		56926

Daily Usenet report for csiph.com

Feb 21 04:15:00 -- Feb 22 04:15:00