Daily Usenet report for csiph.com: Jan 12 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 12 10:42:59 csiph nnrpd[43877]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 12 10:42:59 csiph nnrpd[43877]: 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]
Jan 12 15:06:23 csiph nnrpd[58268]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jan 12 15:06:23 csiph nnrpd[58268]: 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	21170	51.1%	5.3 MB	65.9%
innd	14510	35.0%	1.9 MB	23.4%
innfeed	3591	8.7%	655.8 KB	8.0%
nnrpd	2121	5.1%	223.7 KB	2.7%
nocem	13	0.0%	1.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41406	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	323566	54.2%
Negative hits	252844	42.3%
Do not exist	20560	3.4%
Cache misses	398	0.1%
TOTAL: 4	597368	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.684	0.0%	35	0.000	19.543	44.333
article cleanup	00:00:00.118	0.0%	19931	0.000	0.006	0.034
article logging	00:00:00.518	0.0%	20464	0.000	0.025	1.024
article parse	00:00:01.900	0.0%	73930	0.000	0.026	0.165
article write	00:00:45.337	0.1%	16456	0.169	2.755	89.605
artlog/artcncl	00:00:00.003	0.0%	35	0.000	0.086	1.000
artlog/artparse	00:00:00.008	0.0%	689	0.000	0.012	0.500
data move	00:00:00.251	0.0%	576051	0.000	0.000	0.003
hisgrep/artcncl	00:00:00.040	0.0%	35	0.000	1.143	9.333
hishave/artcncl	00:00:00.015	0.0%	35	0.000	0.429	6.500
hishave/artparse	00:00:00.000	0.0%	678	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:27.410	0.0%	598611	0.000	0.046	0.622
history sync	00:00:01.390	0.0%	376	0.000	3.697	50.500
history write	00:00:37.083	0.0%	19931	0.685	1.861	12.855
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.156	0.0%	678	0.000	1.705	8.200
idle	23:55:36.198	99.6%	572440	80.033	150.472	452.302
nntp read	00:00:02.511	0.0%	578295	0.001	0.004	0.095
overview write	00:02:20.792	0.2%	16456	0.195	8.556	920.062
perl filter	00:00:29.061	0.0%	19920	1.110	1.459	2.133
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.281	0.0%	32925	0.000	0.009	0.038
TOTAL: 24:01:02.449	24:00:24.756	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	16526	0.000	0.002	0.025
article prepare	00:00:00.001	0.0%	11556	0.000	0.000	0.006
article read	00:00:00.357	0.0%	1318	0.000	0.271	10.750
backlog stats	00:00:00.247	0.0%	361371	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.424	0.0%	283965	0.001	0.005	0.010
data write	00:00:01.702	0.0%	270449	0.001	0.006	0.017
idle	23:55:07.951	100.0%	361371	137.385	238.281	691.592
status file	00:00:00.312	0.0%	311	0.000	1.003	58.000
TOTAL: 23:55:18.732	23:55:12.033	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	56:36:56.395	99.5%	1141	3.667	178629.619	600163.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.935	0.0%	48082	0.000	0.061	0.167
readart	00:00:00.000	0.0%	1	0.000	0.000	0.000
TOTAL: 56:53:26.479	56:36:59.330	99.5%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	131
pause	3
paused	2
reload	2
reserve	2
TOTAL: 10	152

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	0	9405	4311	3230	1864	45%	72:52:33
2	newsfeeds.xmission.com	3	15516	3008	12484	24	19%	23:46:01
3	feeder.eternal-september.org	45	6774	2559	4102	113	37%	39:56:45
4	news-out.mixmin.net	3	17907	1753	15818	336	9%	24:08:45
5	newsfeed.fu-berlin.de	19	16488	910	15024	554	5%	25:21:31
6	csiph-out.feeder.erje.net	1439	82862	835	81407	620	1%	118:46:12
7	newsfeed.neva.ru	146	18283	713	17361	209	3%	71:34:23
8	csiph-out.news.weretis.net	4	21259	709	20368	182	3%	32:45:03
9	news.fcku.it	13	1870	348	1515	7	18%	22:31:20
10	news-out.aioe.org	1	15889	306	15520	63	1%	23:59:57
11	news-out.neodome.net	1	18663	300	18330	33	1%	23:59:55
12	csiph-out.feed.uzoreto.com	864	26256	295	25678	283	1%	71:16:55
13	nntp.pasdenom.info	2	11265	188	11069	8	1%	23:59:35
14	news.swapon.de	3	1690	51	1639	0	3%	24:43:45
15	csiph-out.news.tnetconsulting.net	1	17372	45	17277	50	0%	23:59:54
16	news.freedyn.net	1	19312	13	19178	121	0%	23:59:56
17	news.samoylyk.net	2	16002	8	15990	4	0%	23:59:04
18	news.bbs.nz	1	16903	8	16895	0	0%	23:59:53
19	localhost	27	1	1	0	0	100%	00:00:03
20	xanadu-bbs.net	21	685	0	683	2	0%	23:55:18
TOTAL: 20		2596	334402	16361	313568	4473	4%	719:36:48

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	11.3 MB	0.0 KB	16.7 MB	28.0 MB	40%	4.6 KB
2	newsfeeds.xmission.com	8.9 MB	27.3 KB	838.0 KB	9.7 MB	91%	3.3 KB
3	feeder.eternal-september.org	6.6 MB	0.0 KB	737.6 KB	7.3 MB	90%	2.8 KB
4	csiph-out.feeder.erje.net	6.4 MB	5.4 KB	11.0 MB	17.5 MB	36%	12.3 KB
5	news-out.mixmin.net	4.3 MB	29.2 KB	2.1 MB	6.4 MB	67%	3.1 KB
6	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	11.3 MB	13.3 MB	15%	9.3 KB
7	newsfeed.neva.ru	1.9 MB	0.0 KB	680.3 KB	2.6 MB	74%	2.9 KB
8	csiph-out.news.weretis.net	1.8 MB	2.4 KB	1.7 MB	3.6 MB	51%	4.1 KB
9	news-out.aioe.org	1.3 MB	0.0 KB	115.2 KB	1.4 MB	91%	3.8 KB
10	csiph-out.feed.uzoreto.com	1.1 MB	0.0 KB	8.9 MB	10.1 MB	11%	17.9 KB
11	news-out.neodome.net	862.1 KB	27.3 KB	1.6 MB	2.5 MB	33%	7.7 KB
12	news.fcku.it	547.7 KB	0.0 KB	11.3 KB	558.9 KB	97%	1.6 KB
13	nntp.pasdenom.info	406.3 KB	0.0 KB	11.4 KB	417.7 KB	97%	2.1 KB
14	csiph-out.news.tnetconsulting.net	148.5 KB	0.0 KB	2.7 MB	2.8 MB	5%	30.5 KB
15	news.swapon.de	123.5 KB	0.0 KB	0.0 KB	123.5 KB	100%	2.4 KB
16	news.freedyn.net	58.7 KB	27.3 KB	6.2 MB	6.3 MB	0%	48.3 KB
17	news.samoylyk.net	25.6 KB	27.2 KB	215.9 KB	268.7 KB	9%	22.4 KB
18	news.bbs.nz	16.1 KB	0.0 KB	0.0 KB	16.1 KB	100%	2.0 KB
19	localhost	1.8 KB	0.0 KB	0.0 KB	1.8 KB	100%	1.8 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	6.6 KB	6.6 KB	0%	3.3 KB
TOTAL: 20		47.9 MB	146.0 KB	64.9 MB	112.9 MB	42%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 12 04:15:00 - 04:59:59	238	1.4%	0.09	708.4 KB	1.4%	0.26
Jan 12 05:00:00 - 05:59:59	385	2.3%	0.11	2.3 MB	4.7%	0.65
Jan 12 06:00:00 - 06:59:59	361	2.2%	0.10	1.2 MB	2.5%	0.35
Jan 12 07:00:00 - 07:59:59	526	3.2%	0.15	1.3 MB	2.7%	0.37
Jan 12 08:00:00 - 08:59:59	665	4.0%	0.18	1.7 MB	3.4%	0.47
Jan 12 09:00:00 - 09:59:59	759	4.6%	0.21	2.4 MB	4.9%	0.67
Jan 12 10:00:00 - 10:59:59	764	4.6%	0.21	1.9 MB	3.9%	0.54
Jan 12 11:00:00 - 11:59:59	780	4.7%	0.22	2.0 MB	4.0%	0.56
Jan 12 12:00:00 - 12:59:59	808	4.9%	0.22	2.0 MB	4.2%	0.58
Jan 12 13:00:00 - 13:59:59	765	4.7%	0.21	1.9 MB	3.9%	0.53
Jan 12 14:00:00 - 14:59:59	854	5.2%	0.24	2.3 MB	4.7%	0.65
Jan 12 15:00:00 - 15:59:59	1035	6.3%	0.29	3.5 MB	7.2%	0.99
Jan 12 16:00:00 - 16:59:59	1009	6.1%	0.28	2.7 MB	5.6%	0.77
Jan 12 17:00:00 - 17:59:59	1019	6.2%	0.28	3.1 MB	6.5%	0.89
Jan 12 18:00:00 - 18:59:59	1057	6.4%	0.29	2.8 MB	5.8%	0.80
Jan 12 19:00:00 - 19:59:59	967	5.9%	0.27	3.0 MB	6.3%	0.86
Jan 12 20:00:00 - 20:59:59	859	5.2%	0.24	2.5 MB	5.3%	0.72
Jan 12 21:00:00 - 21:59:59	805	4.9%	0.22	2.3 MB	4.8%	0.66
Jan 12 22:00:00 - 22:59:59	699	4.2%	0.19	2.3 MB	4.8%	0.67
Jan 12 23:00:00 - 23:59:59	534	3.2%	0.15	1.5 MB	3.1%	0.43
Jan 13 00:00:00 - 00:59:59	470	2.9%	0.13	1.6 MB	3.3%	0.45
Jan 13 01:00:00 - 01:59:59	286	1.7%	0.08	946.7 KB	1.9%	0.26
Jan 13 02:00:00 - 02:59:59	294	1.8%	0.08	944.8 KB	1.9%	0.26
Jan 13 03:00:00 - 03:59:59	425	2.6%	0.12	1.2 MB	2.5%	0.34
Jan 13 04:00:00 - 04:15:00	85	0.5%	0.09	374.7 KB	0.8%	0.42
TOTAL: 24:00:00	16449	100.0%	0.19	48.4 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1818	121	0	1	1696
2	newsfeed.fu-berlin.de	983	44	0	0	939
3	csiph-out.feeder.erje.net	394	78	3	0	313
4	news-out.mixmin.net	328	11	2	0	315
5	csiph-out.feed.uzoreto.com	286	18	0	0	268
6	csiph-out.news.weretis.net	239	108	1	0	130
7	newsfeed.neva.ru	221	85	0	0	136
8	feeder.eternal-september.org	114	82	0	0	32
9	news.freedyn.net	100	0	1	0	99
10	news-out.aioe.org	60	48	0	0	12
11	newsfeeds.xmission.com	36	12	1	0	23
12	news-out.neodome.net	35	2	1	0	32
13	news.swapon.de	27	0	0	0	27
14	csiph-out.news.tnetconsulting.net	24	5	1	0	18
15	nntp.pasdenom.info	8	5	0	0	3
16	news.fcku.it	7	6	0	0	1
17	news.samoylyk.net	4	0	1	0	3
18	xanadu-bbs.net	2	0	0	0	2
TOTAL: 18		4686	625	11	1	4049

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	142
lada.talk	57
alt.binaries.pictures.purebeauty	45
scinet.sysop	36
alt.politics	33
spline.eisfair	31
scinet.stats	27
fr.misc.automobile.electrique	24
mozilla.support.seamonkey	18
alt.survival	16
lada.auto	15
mozilla.general	13
scinet.general.chatter	13
alt.bbs.synchronet	13
oesterreich.tratsch	11
it-alt.sport.calcio.juventus	9
alt.job	9
at.jobs	8
scinet.bbs.software	8
alt.politics.usa	7
TOTAL: 71	625

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)	1682
EMP (phn path)	374
Binary: misplaced binary	222
EMP (phn nph)	184
Binary Payload	126
Too many newsgroups	99
EMP (phl)	82
0	32
HTML Multipart	12
Scoring filter	11
Too many newsgroups (meow)	3
HTML post	2
TOTAL: 12	2829

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	55
	csiph-out.feed.uzoreto.com	3
	csiph-out.news.xs4all.nl	2
	TOTAL: 3	60
Huge articles
	csiph-out.news.xs4all.nl	178
	csiph-out.feeder.erje.net	159
	newsfeed.fu-berlin.de	116
	csiph-out.feed.uzoreto.com	95
	news.freedyn.net	57
	news-out.neodome.net	27
	csiph-out.news.tnetconsulting.net	15
	newsfeeds.xmission.com	11
	csiph-out.news.weretis.net	10
	news-out.mixmin.net	4
	TOTAL: 12	679
Including strange strings
	newsfeed.fu-berlin.de	425
	news.swapon.de	27
	csiph-out.news.xs4all.nl	26
	newsfeed.neva.ru	12
	newsfeeds.xmission.com	12
	news-out.mixmin.net	10
	csiph-out.news.weretis.net	10
	csiph-out.feeder.erje.net	3
	csiph-out.feed.uzoreto.com	3
	feeder.eternal-september.org	2
	TOTAL: 11	531
No colon-space in header
	csiph-out.news.xs4all.nl	6
	csiph-out.feeder.erje.net	4
	TOTAL: 2	10
TOTAL: 4		1280

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19370	808	15640	1	0	4%	23:59:47
2	fu-berlin.de	15213	530	14683	0	0	3%	23:50:07
3	news.samoylyk.net	16932	519	15869	8	0	3%	23:50:07
4	news.bbs.nz	30891	376	15505	19	0	1%	23:57:50
5	neva.ru	14967	144	14563	75	0	0%	23:50:07
6	xmission	15440	59	12745	9	0	0%	23:50:07
7	tnet	17114	53	15821	0	0	0%	23:50:07
8	newsfeed.xs4all.nl	12620	42	11940	3	96	0%	23:54:27
9	news.uzoreto.com	15621	21	15249	46	0	0%	23:50:07
10	usenet.pasdenom.info	16221	17	15675	36	0	0%	23:59:43
11	erje	15960	15	15289	22	0	0%	23:50:07
12	news.mixmin.net	14357	13	13904	1	0	0%	23:53:24
13	neodome.net	16518	11	15959	0	0	0%	23:50:07
14	news.freedyn.net	15005	10	14574	29	0	0%	23:54:27
15	weretis.net	15322	10	15003	1	0	0%	23:50:07
16	aioe	13897	8	13339	0	0	0%	23:56:30
17	news.swapon.de	1747	1	1713	0	0	0%	23:50:46
18	eternal-september	0	0	0	0	13185	0%	23:50:07
19	news.fcku.it	1461	0	1453	0	0	0%	22:07:54
TOTAL: 19		268656	2637	238924	250	13281	0%	451:55:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.1 MB	19.6 KB	6.2 MB	0.1 KB/s	7.8 KB	23:59:47
2	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	10.7 KB	23:50:07
3	news.samoylyk.net	5.4 MB	58.1 KB	5.5 MB	0.1 KB/s	10.7 KB	23:50:07
4	news.bbs.nz	912.1 KB	70.7 KB	982.9 KB	0.0 KB/s	2.5 KB	23:57:50
5	neva.ru	283.6 KB	143.9 KB	427.6 KB	0.0 KB/s	2.0 KB	23:50:07
6	xmission	132.4 KB	15.5 KB	147.9 KB	0.0 KB/s	2.2 KB	23:50:07
7	tnet	111.0 KB	0.0 KB	111.0 KB	0.0 KB/s	2.1 KB	23:50:07
8	newsfeed.xs4all.nl	102.1 KB	7.9 KB	110.0 KB	0.0 KB/s	2.4 KB	23:54:27
9	usenet.pasdenom.info	33.9 KB	59.1 KB	92.9 KB	0.0 KB/s	1.8 KB	23:59:43
10	news.uzoreto.com	32.5 KB	117.5 KB	150.0 KB	0.0 KB/s	2.2 KB	23:50:07
11	erje	26.7 KB	102.0 KB	128.7 KB	0.0 KB/s	3.5 KB	23:50:07
12	neodome.net	26.6 KB	0.0 KB	26.6 KB	0.0 KB/s	2.4 KB	23:50:07
13	news.mixmin.net	25.8 KB	3.4 KB	29.2 KB	0.0 KB/s	2.1 KB	23:53:24
14	news.freedyn.net	23.2 KB	403.6 KB	426.8 KB	0.0 KB/s	10.9 KB	23:54:27
15	aioe	22.9 KB	0.0 KB	22.9 KB	0.0 KB/s	2.9 KB	23:56:30
16	weretis.net	12.3 KB	3.4 KB	15.7 KB	0.0 KB/s	1.4 KB	23:50:07
17	news.swapon.de	1.8 KB	0.0 KB	1.8 KB	0.0 KB/s	1.8 KB	23:50:46
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:07
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:07:54
TOTAL: 19		18.8 MB	1004.5 KB	19.8 MB	0.0 KB/s	7.0 KB	451:55:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	172.83.100.213	6	1	1.8 KB	1	1	1	02:26:01
TOTAL: 1		6	1	1.8 KB	1	1	1	56:53:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	300	1	1.8 KB	1	1	1	51:03:29
2	*.upc-a.chello.nl	32	0	0.0 KB	0	0	0	05:18:05
3	*.range86-138.btcentralplus.com	2	0	0.0 KB	0	0	0	00:01:39
4	*.w90-20.abo.wanadoo.fr	1	0	0.0 KB	0	0	0	00:00:05
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
6	*.subs.proxad.net	1	0	0.0 KB	0	0	0	00:30:01
7	*.internet-census.org	5	0	0.0 KB	0	0	0	00:00:00
TOTAL: 7		342	1	1.8 KB	1	1	1	56:53:24

NNRP auth users [Top 20]:

User	Conn
kev009	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	18.253	4.287	0.000	47:37:04
a131033.upc-a.chello.nl	2.050	0.345	0.000	05:18:05
172.83.100.213	0.553	0.157	0.004	02:26:01
208.72.53.154	0.132	0.023	0.000	01:00:04
91-165-244-119.subs.proxad.net	0.066	0.008	0.000	00:30:01
host86-138-214-35.range86-138.btcentralplus.com	0.113	0.051	0.000	00:01:39
45.227.255.224	0.066	0.009	0.000	00:00:10
192.241.207.142	0.051	0.025	0.000	00:00:09
aorleans-654-1-12-115.w90-20.abo.wanadoo.fr	0.120	0.060	0.000	00:00:05
sh-phx-us-gp1-wk108.internet-census.org	0.301	0.080	0.000	00:00:00
scan-141.security.ipip.net	0.062	0.015	0.000	00:00:00
TOTAL: 11	21.767	5.060	0.004	56:53:24

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	290
a131033.upc-a.chello.nl	32
sh-phx-us-gp1-wk108.internet-census.org	5
host86-138-214-35.range86-138.btcentralplus.com	2
208.72.53.154	2
91-165-244-119.subs.proxad.net	1
192.241.207.142	1
45.227.255.224	1
scan-141.security.ipip.net	1
aorleans-654-1-12-115.w90-20.abo.wanadoo.fr	1
TOTAL: 10	336

NNRP unrecognized commands (by host):

System	Conn
sh-phx-us-gp1-wk108.internet-census.org	10
scan-141.security.ipip.net	4
TOTAL: 2	14

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
^V^C^A	2
Host: 69.160.37.58:119	2
^A	2
SSH-2.0-Go	1
Connection: close	1
User-Agent: HTTP Banner Detection (https...	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Accept-Encoding: gzip	1
Accept: /	1
TOTAL: 10	14

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.213	4	0
208.72.53.154	2	0
91-165-244-119.subs.proxad.net	1	0
TOTAL: 3	7	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Jan 12 04:15:00 -- Jan 13 04:15:00