Daily Usenet report for csiph.com: Jul 1 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jul 1 23:45:29 csiph nnrpd[12573]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Jul 1 23:45:29 csiph nnrpd[12573]: 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	20777	48.8%	5.4 MB	64.1%
innd	17296	40.7%	2.3 MB	27.3%
innfeed	3585	8.4%	645.4 KB	7.5%
nnrpd	835	2.0%	90.3 KB	1.1%
nocem	53	0.1%	6.1 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	42547	100.0%	8.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	319122	57.1%
Negative hits	220053	39.4%
Do not exist	19602	3.5%
Cache misses	342	0.1%
TOTAL: 4	559119	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.482	0.0%	42	0.000	11.476	41.000
article cleanup	00:00:00.124	0.0%	19392	0.000	0.006	0.036
article logging	00:00:00.416	0.0%	19880	0.000	0.021	0.742
article parse	00:00:00.760	0.0%	56620	0.000	0.013	0.125
article write	00:00:22.133	0.0%	16206	0.181	1.366	40.849
artlog/artcncl	00:00:00.002	0.0%	42	0.000	0.048	1.000
artlog/artparse	00:00:00.022	0.0%	271	0.000	0.081	1.545
data move	00:00:00.167	0.0%	519924	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.087	0.0%	39	0.000	2.231	7.000
hishave/artcncl	00:00:00.075	0.0%	42	0.000	1.786	7.556
hishave/artparse	00:00:00.000	0.0%	260	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:11.525	0.0%	561010	0.002	0.021	0.198
history sync	00:00:00.971	0.0%	359	0.000	2.705	18.500
history write	00:00:16.817	0.0%	19403	0.216	0.867	5.082
hiswrite/artcncl	00:00:00.001	0.0%	3	0.333	0.333	0.333
hiswrite/artparse	00:00:00.256	0.0%	260	0.000	0.985	6.000
idle	23:57:51.068	99.8%	524637	97.698	164.440	470.256
nntp read	00:00:02.013	0.0%	527592	0.001	0.004	0.008
overview write	00:01:07.261	0.1%	16206	0.159	4.150	105.897
perl filter	00:00:26.468	0.0%	19392	1.034	1.365	2.048
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.250	0.0%	32465	0.000	0.008	0.037
TOTAL: 24:00:53.925	24:00:20.898	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.033	0.0%	16443	0.000	0.002	0.024
article prepare	00:00:00.004	0.0%	11504	0.000	0.000	0.062
article read	00:00:00.191	0.0%	1208	0.000	0.158	15.333
backlog stats	00:00:00.179	0.0%	369912	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.261	0.0%	297734	0.000	0.004	0.007
data write	00:00:01.670	0.0%	283010	0.002	0.006	0.012
idle	23:54:20.885	100.0%	369912	139.290	232.652	614.233
status file	00:00:00.214	0.0%	293	0.000	0.730	12.000
TOTAL: 23:54:30.610	23:54:24.437	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	30:16:09.942	99.8%	588	0.000	185323.031	600195.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.010	0.0%	1364	0.000	0.007	0.167
readart	00:00:00.453	0.0%	39	0.000	11.615	42.000
TOTAL: 30:20:09.509	30:16:10.405	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	15853	4132	11689	32	26%	24:45:13
2	news-out.aioe.org	3	15863	3654	11991	218	23%	24:09:48
3	feeder.eternal-september.org	5	18128	2127	15772	229	11%	24:25:39
4	csiph-out.news.weretis.net	4	34360	1259	32893	208	3%	50:08:45
5	csiph-out.feeder.erje.net	1440	81968	956	80546	466	1%	118:43:22
6	news-out.mixmin.net	2	17105	853	15939	313	4%	24:08:39
7	newsfeed.fu-berlin.de	11	14068	760	13176	132	5%	21:28:53
8	csiph-out.news.xs4all.nl	861	18120	704	16719	697	3%	71:27:46
9	newsfeed.neva.ru	142	17581	668	16393	520	3%	71:55:17
10	csiph-out.feed.uzoreto.com	864	26251	403	25211	637	1%	71:16:11
11	news.fcku.it	13	1938	284	1648	6	14%	22:48:56
12	news-out.neodome.net	1	17242	217	17015	10	1%	23:59:54
13	nntp.pasdenom.info	2	11256	107	11106	43	0%	23:58:52
14	csiph-out.news.tnetconsulting.net	2	15797	85	15698	14	0%	23:59:55
15	news.swapon.de	7	1367	36	1331	0	2%	21:57:57
16	news.samoylyk.net	2	15777	12	15764	1	0%	23:59:44
17	news.bbs.nz	1	15497	6	15491	0	0%	23:59:52
18	localhost	31	5	5	0	0	100%	00:00:04
19	xanadu-bbs.net	30	480	0	479	1	0%	21:12:48
TOTAL: 19		3423	338656	16268	318861	3527	4%	688:27:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.7 MB	0.0 KB	1.5 MB	13.3 MB	88%	3.3 KB
2	news-out.aioe.org	8.9 MB	0.0 KB	976.4 KB	9.9 MB	90%	2.6 KB
3	feeder.eternal-september.org	5.8 MB	0.0 KB	992.5 KB	6.8 MB	85%	3.0 KB
4	csiph-out.feeder.erje.net	4.9 MB	0.0 KB	5.1 MB	10.0 MB	49%	7.2 KB
5	csiph-out.news.weretis.net	4.0 MB	10.7 KB	2.0 MB	6.0 MB	66%	4.2 KB
6	news-out.mixmin.net	3.2 MB	0.0 KB	545.3 KB	3.8 MB	85%	3.3 KB
7	csiph-out.news.xs4all.nl	2.0 MB	0.0 KB	5.9 MB	7.9 MB	24%	5.8 KB
8	newsfeed.fu-berlin.de	1.7 MB	0.0 KB	411.6 KB	2.1 MB	81%	2.4 KB
9	newsfeed.neva.ru	1.6 MB	0.0 KB	1.4 MB	3.0 MB	54%	2.6 KB
10	csiph-out.feed.uzoreto.com	1.6 MB	0.0 KB	14.0 MB	15.6 MB	10%	15.4 KB
11	news-out.neodome.net	512.1 KB	0.0 KB	18.6 KB	530.7 KB	96%	2.3 KB
12	news.fcku.it	453.5 KB	0.0 KB	9.6 KB	463.1 KB	97%	1.6 KB
13	csiph-out.news.tnetconsulting.net	328.1 KB	0.0 KB	27.8 KB	355.8 KB	92%	3.6 KB
14	nntp.pasdenom.info	254.9 KB	0.0 KB	63.4 KB	318.3 KB	80%	2.1 KB
15	news.swapon.de	93.8 KB	0.0 KB	0.0 KB	93.8 KB	100%	2.6 KB
16	news.samoylyk.net	36.1 KB	0.0 KB	100.0 KB	136.1 KB	26%	10.5 KB
17	news.bbs.nz	16.7 KB	0.0 KB	0.0 KB	16.7 KB	100%	2.8 KB
18	localhost	11.6 KB	0.0 KB	0.0 KB	11.6 KB	100%	2.3 KB
19	xanadu-bbs.net	0.0 KB	0.0 KB	2.0 KB	2.0 KB	0%	2.0 KB
TOTAL: 19		47.2 MB	10.7 KB	33.0 MB	80.2 MB	58%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 1 04:15:00 - 04:59:59	293	1.8%	0.11	925.2 KB	1.9%	0.34
Jul 1 05:00:00 - 05:59:59	454	2.8%	0.13	1.3 MB	2.7%	0.38
Jul 1 06:00:00 - 06:59:59	558	3.4%	0.15	2.1 MB	4.2%	0.59
Jul 1 07:00:00 - 07:59:59	574	3.5%	0.16	1.4 MB	3.0%	0.41
Jul 1 08:00:00 - 08:59:59	699	4.2%	0.19	2.1 MB	4.2%	0.58
Jul 1 09:00:00 - 09:59:59	754	4.6%	0.21	1.8 MB	3.8%	0.52
Jul 1 10:00:00 - 10:59:59	833	5.1%	0.23	2.2 MB	4.5%	0.63
Jul 1 11:00:00 - 11:59:59	708	4.3%	0.20	1.8 MB	3.6%	0.50
Jul 1 12:00:00 - 12:59:59	826	5.0%	0.23	2.1 MB	4.4%	0.61
Jul 1 13:00:00 - 13:59:59	818	5.0%	0.23	2.1 MB	4.4%	0.61
Jul 1 14:00:00 - 14:59:59	958	5.8%	0.27	2.7 MB	5.6%	0.78
Jul 1 15:00:00 - 15:59:59	983	6.0%	0.27	2.8 MB	5.7%	0.79
Jul 1 16:00:00 - 16:59:59	934	5.7%	0.26	2.4 MB	4.9%	0.68
Jul 1 17:00:00 - 17:59:59	860	5.2%	0.24	2.4 MB	4.9%	0.67
Jul 1 18:00:00 - 18:59:59	900	5.5%	0.25	2.4 MB	5.0%	0.69
Jul 1 19:00:00 - 19:59:59	788	4.8%	0.22	2.3 MB	4.8%	0.67
Jul 1 20:00:00 - 20:59:59	935	5.7%	0.26	3.7 MB	7.6%	1.05
Jul 1 21:00:00 - 21:59:59	754	4.6%	0.21	2.2 MB	4.6%	0.63
Jul 1 22:00:00 - 22:59:59	551	3.3%	0.15	1.6 MB	3.2%	0.45
Jul 1 23:00:00 - 23:59:59	467	2.8%	0.13	1.6 MB	3.2%	0.44
Jul 2 00:00:00 - 00:59:59	416	2.5%	0.12	1.2 MB	2.4%	0.33
Jul 2 01:00:00 - 01:59:59	388	2.4%	0.11	1.3 MB	2.8%	0.38
Jul 2 02:00:00 - 02:59:59	355	2.2%	0.10	1.4 MB	2.9%	0.40
Jul 2 03:00:00 - 03:59:59	302	1.8%	0.08	1023.9 KB	2.1%	0.28
Jul 2 04:00:00 - 04:15:00	380	2.3%	0.42	1.9 MB	3.8%	2.12
TOTAL: 24:00:00	16488	100.0%	0.19	48.8 MB	100.0%	0.58

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	701	36	0	665
2	csiph-out.feed.uzoreto.com	638	26	1	611
3	newsfeed.neva.ru	520	25	0	495
4	newsfeed.fu-berlin.de	516	35	0	481
5	csiph-out.feeder.erje.net	391	84	8	299
6	news-out.mixmin.net	316	4	2	310
7	news-out.aioe.org	288	141	0	147
8	feeder.eternal-september.org	232	27	0	205
9	csiph-out.news.weretis.net	213	83	2	128
10	nntp.pasdenom.info	43	0	0	43
11	csiph-out.news.tnetconsulting.net	32	24	0	8
12	newsfeeds.xmission.com	31	13	0	18
13	news-out.neodome.net	21	0	0	21
14	news.swapon.de	11	0	0	11
15	news.fcku.it	6	6	0	0
16	news.samoylyk.net	1	0	0	1
17	xanadu-bbs.net	1	0	0	1
TOTAL: 17		3961	504	13	3444

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	121
trollnet.cinema	41
alt.bbs.synchronet	28
scinet.stats	24
it-alt.sport.calcio.juventus	23
alt.politics.usa	22
alt.politics	20
mozilla.general	16
alt.survival	15
lada.talk	14
alt.windows7.general	10
spline.eisfair	10
alt.binaries.pictures.purebeauty	10
mozilla.support.firefox	9
mozilla.support.thunderbird	9
oesterreich.tratsch	8
alt.paranormal.spells	7
alt.philosophy	6
cakewalk.audio	5
lada.auto	4
TOTAL: 83	504

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1982
EMP (phn path)	209
EMP (phl)	177
0	120
HTML Multipart	75
Too many newsgroups	55
EMP (phn nph)	36
Too many newsgroups (meow)	13
Scoring filter	6
Binary: misplaced binary	4
Binary Payload	4
TOTAL: 11	2681

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	107
	csiph-out.feed.uzoreto.com	22
	csiph-out.news.xs4all.nl	16
	TOTAL: 3	145
Huge articles
	csiph-out.feed.uzoreto.com	97
	csiph-out.feeder.erje.net	85
	csiph-out.news.xs4all.nl	40
	newsfeeds.xmission.com	16
	csiph-out.news.weretis.net	15
	feeder.eternal-september.org	5
	newsfeed.fu-berlin.de	3
	news-out.mixmin.net	1
	csiph-out.news.tnetconsulting.net	1
	news.samoylyk.net	1
	TOTAL: 10	264
Including strange strings
	newsfeed.fu-berlin.de	374
	news-out.aioe.org	75
	csiph-out.news.weretis.net	14
	news.swapon.de	11
	news-out.neodome.net	9
	newsfeeds.xmission.com	2
	csiph-out.news.xs4all.nl	1
	csiph-out.feeder.erje.net	1
	newsfeed.neva.ru	1
	TOTAL: 9	488
No colon-space in header
	csiph-out.news.xs4all.nl	7
	csiph-out.feed.uzoreto.com	2
	csiph-out.feeder.erje.net	1
	TOTAL: 3	10
TOTAL: 4		907

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18482	613	15864	1	11	3%	23:59:31
2	news.bbs.nz	31271	523	15477	31	54	1%	23:59:56
3	fu-berlin.de	15508	394	15112	0	2	2%	23:59:56
4	news.samoylyk.net	17026	384	16084	7	2	2%	23:59:56
5	tnet	17195	197	15878	1	18	1%	23:59:56
6	news.freedyn.net	16868	101	16366	0	21	0%	23:59:56
7	newsfeed.xs4all.nl	16249	69	15512	24	259	0%	23:59:55
8	neva.ru	15408	47	15038	171	2	0%	23:59:46
9	xmission	13665	39	11887	2	24	0%	23:59:45
10	usenet.pasdenom.info	16545	25	15675	11	291	0%	23:59:56
11	erje	15385	11	15166	121	1	0%	23:59:56
12	news.mixmin.net	15353	9	14822	0	2	0%	23:59:04
13	neodome.net	16846	9	16237	0	2	0%	23:59:51
14	news.uzoreto.com	16036	9	15687	14	1	0%	23:59:55
15	eternal-september	14347	8	13830	0	2	0%	23:59:49
16	aioe	12352	8	11699	0	1	0%	23:59:52
17	weretis.net	14733	7	14481	1	0	0%	23:59:56
18	news.swapon.de	1423	5	1400	0	0	0%	23:29:02
19	news.fcku.it	1551	1	1544	0	0	0%	21:32:37
TOTAL: 19		286243	2459	257759	384	693	0%	452:58:35

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	3.6 MB	3.1 KB	3.6 MB	6.0 KB	23:59:31
2	fu-berlin.de	3.2 MB	0.0 KB	3.2 MB	8.2 KB	23:59:56
3	news.samoylyk.net	3.1 MB	7.9 KB	3.1 MB	8.2 KB	23:59:56
4	news.bbs.nz	1.3 MB	159.8 KB	1.4 MB	2.6 KB	23:59:56
5	news.freedyn.net	777.6 KB	0.0 KB	777.6 KB	7.7 KB	23:59:56
6	tnet	435.2 KB	1.1 KB	436.3 KB	2.2 KB	23:59:56
7	newsfeed.xs4all.nl	278.5 KB	109.2 KB	387.7 KB	4.2 KB	23:59:55
8	neva.ru	130.2 KB	398.0 KB	528.3 KB	2.4 KB	23:59:46
9	xmission	113.1 KB	2.3 KB	115.3 KB	2.8 KB	23:59:45
10	usenet.pasdenom.info	98.8 KB	18.8 KB	117.6 KB	3.3 KB	23:59:56
11	news.fcku.it	40.9 KB	0.0 KB	40.9 KB	40.9 KB	21:32:37
12	erje	26.6 KB	456.4 KB	483.0 KB	3.7 KB	23:59:56
13	neodome.net	25.9 KB	0.0 KB	25.9 KB	2.9 KB	23:59:51
14	news.mixmin.net	23.4 KB	0.0 KB	23.4 KB	2.6 KB	23:59:04
15	news.uzoreto.com	19.5 KB	159.6 KB	179.0 KB	7.8 KB	23:59:55
16	eternal-september	16.7 KB	0.0 KB	16.7 KB	2.1 KB	23:59:49
17	aioe	15.6 KB	0.0 KB	15.6 KB	1.9 KB	23:59:52
18	weretis.net	14.2 KB	1.1 KB	15.4 KB	1.9 KB	23:59:56
19	news.swapon.de	11.7 KB	0.0 KB	11.7 KB	2.3 KB	23:29:02
TOTAL: 19		13.1 MB	1.3 MB	14.4 MB	5.2 KB	452:58:35

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	10	15	29.1 KB	5	0	0	03:17:28
2	071-013-248-209.res.spectrum.com	19	15	42.5 KB	10	5	5	05:16:59
3	8.44.58.56	3	5	10.0 KB	2	0	0	00:00:05
4	a131033.upc-a.chello.nl	12	4	8.8 KB	3	0	0	02:19:47
TOTAL: 4		44	39	90.5 KB	20	5	5	30:20:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	19	15	42.5 KB	10	5	5	05:16:59
2	*.ph.ph.cox.net	10	15	29.1 KB	5	0	0	03:17:28
3	unresolved	68	5	10.0 KB	2	0	0	19:25:54
4	*.upc-a.chello.nl	12	4	8.8 KB	3	0	0	02:19:47
5	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	0	00:00:00
TOTAL: 5		110	39	90.5 KB	20	5	5	30:20:08

NNRP auth users [Top 20]:

User	Conn
ohlandl	5
TOTAL: 1	5

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.188	0.926	0.000	19:25:29
071-013-248-209.res.spectrum.com	1.713	0.316	0.012	05:16:59
ip72-222-200-117.ph.ph.cox.net	0.913	0.237	0.000	03:17:28
a131033.upc-a.chello.nl	0.875	0.196	0.000	02:19:47
192.35.168.214	0.122	0.041	0.000	00:00:10
8.44.58.56	0.256	0.056	0.000	00:00:05
185.153.199.12	0.064	0.016	0.000	00:00:04
94.166.155.66	0.133	0.032	0.000	00:00:02
185.220.101.203	0.064	0.016	0.000	00:00:00
ec2-52-12-202-8.us-west-2.compute.amazonaws.com	0.069	0.009	0.000	00:00:00
TOTAL: 10	8.397	1.845	0.012	30:20:08

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	59
192.35.168.214	2
94.166.155.66	2
185.220.101.203	1
185.153.199.12	1
ec2-52-12-202-8.us-west-2.compute.amazonaws.com	1
TOTAL: 6	66

NNRP unrecognized commands (by host):

System	Conn
185.220.101.203	5
185.153.199.12	1
192.35.168.214	1
TOTAL: 3	7

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	1
GET / HTTP/1.1	1
^C	1
Accept-Encoding: deflate, gzip	1
Host: 69.160.37.58:119	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Accept: /	1
TOTAL: 7	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-248-209.res.spectrum.com	8	0
ip72-222-200-117.ph.ph.cox.net	4	0
185.153.199.12	1	1
TOTAL: 3	13	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	38	97.4%
2	news	1	2.6%
TOTAL: 2		39	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	32
2	comp.unix.aix	5
3	news.admin.peering	1
4	comp.arch	1
TOTAL: 4		39

Daily Usenet report for csiph.com

Jul 1 04:15:00 -- Jul 2 04:15:00