Daily Usenet report for csiph.com: Aug 22 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 22 15:59:54 csiph nnrpd[78762]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Aug 22 15:59:54 csiph nnrpd[78762]: 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	17410	44.9%	4.5 MB	60.2%
innd	16861	43.5%	2.2 MB	30.3%
innfeed	3546	9.2%	617.5 KB	8.1%
nnrpd	853	2.2%	93.7 KB	1.2%
nocem	66	0.2%	8.0 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	38737	100.0%	7.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	298979	56.1%
Negative hits	216824	40.7%
Do not exist	16812	3.2%
Cache misses	225	0.0%
TOTAL: 4	532840	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.244	0.0%	34	0.000	7.176	18.778
article cleanup	00:00:00.107	0.0%	16533	0.000	0.006	0.048
article logging	00:00:00.279	0.0%	17031	0.000	0.016	0.361
article parse	00:00:02.341	0.0%	52565	0.000	0.045	0.598
article write	00:00:06.304	0.0%	13912	0.176	0.453	9.023
artlog/artcncl	00:00:00.000	0.0%	34	0.000	0.000	0.000
artlog/artparse	00:00:00.002	0.0%	365	0.000	0.005	0.133
data move	00:00:00.189	0.0%	501869	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.035	0.0%	34	0.000	1.029	11.000
hishave/artcncl	00:00:00.027	0.0%	34	0.000	0.794	2.778
hishave/artparse	00:00:00.000	0.0%	357	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:36.999	0.0%	535170	0.001	0.069	0.391
history sync	00:00:00.863	0.0%	382	0.000	2.259	19.000
history write	00:00:54.454	0.1%	16541	0.034	3.292	12.572
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.833	0.0%	356	0.000	5.149	30.750
idle	23:57:39.833	99.8%	504008	90.939	171.148	532.550
nntp read	00:00:01.862	0.0%	508258	0.001	0.004	0.009
overview write	00:00:40.863	0.0%	13912	0.104	2.937	217.070
perl filter	00:00:23.177	0.0%	16533	1.066	1.402	1.959
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.242	0.0%	27890	0.000	0.009	0.030
TOTAL: 24:01:03.883	24:00:29.654	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.039	0.0%	14222	0.000	0.003	0.029
article prepare	00:00:00.003	0.0%	14456	0.000	0.000	0.016
article read	00:00:00.269	0.0%	1797	0.000	0.150	44.000
backlog stats	00:00:00.194	0.0%	328119	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.122	0.0%	255920	0.001	0.004	0.009
data write	00:00:01.453	0.0%	243220	0.002	0.006	0.012
idle	23:54:47.040	100.0%	328119	143.344	262.365	795.273
status file	00:00:00.239	0.0%	300	0.000	0.797	14.000
TOTAL: 23:54:56.781	23:54:50.359	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	36:27:51.333	99.8%	570	18.333	230300.584	600153.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.009	0.0%	1363	0.000	0.007	0.167
readart	00:00:00.209	0.0%	43	0.000	4.860	41.000
TOTAL: 36:32:45.158	36:27:51.551	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	13120	2984	10080	56	22%	23:03:20
2	news-out.mixmin.net	3	14494	2874	10986	634	19%	24:11:57
3	feeder.eternal-september.org	1	15288	1997	13167	124	13%	23:18:07
4	news-out.aioe.org	1	13400	1041	12304	55	7%	23:59:57
5	newsfeed.fu-berlin.de	4	13955	926	12945	84	6%	25:27:52
6	csiph-out.feeder.erje.net	1440	70692	909	69365	418	1%	118:45:51
7	csiph-out.news.weretis.net	4	31182	889	30138	155	2%	56:38:37
8	newsfeed.neva.ru	145	13011	665	11997	349	5%	72:10:01
9	csiph-out.news.xs4all.nl	861	15659	404	14889	366	2%	71:27:01
10	csiph-out.feed.uzoreto.com	864	22150	314	21175	661	1%	71:16:51
11	news-out.neodome.net	1	14772	210	14517	45	1%	23:59:53
12	news.fcku.it	8	1335	204	1131	0	15%	21:12:54
13	news.freedyn.net	1	16635	102	16433	100	0%	24:00:00
14	nntp.pasdenom.info	4	8658	78	8573	7	0%	23:56:28
15	news.swapon.de	6	1355	37	1316	2	2%	24:15:39
16	csiph-out.news.tnetconsulting.net	2	13853	33	13794	26	0%	23:59:43
17	news.bbs.nz	1	22038	9	22029	0	0%	38:04:13
18	news.samoylyk.net	2	13502	8	13494	0	0%	24:00:20
19	localhost	30	4	4	0	0	100%	00:00:00
20	xanadu-bbs.net	35	341	0	341	0	0%	22:00:57
TOTAL: 20		3415	315444	13688	298674	3082	4%	735:49:41

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.7 MB	4.5 MB	14.2 MB	68%	4.8 KB
2	news-out.mixmin.net	5.8 MB	1.4 MB	7.2 MB	80%	2.1 KB
3	feeder.eternal-september.org	5.6 MB	2.0 MB	7.6 MB	73%	3.7 KB
4	csiph-out.feeder.erje.net	4.4 MB	4.8 MB	9.2 MB	47%	7.1 KB
5	news-out.aioe.org	3.9 MB	205.7 KB	4.1 MB	95%	3.8 KB
6	csiph-out.news.weretis.net	2.7 MB	3.4 MB	6.1 MB	44%	5.9 KB
7	newsfeed.fu-berlin.de	2.4 MB	664.2 KB	3.0 MB	78%	3.1 KB
8	newsfeed.neva.ru	2.4 MB	986.2 KB	3.3 MB	70%	3.4 KB
9	csiph-out.feed.uzoreto.com	1.5 MB	16.8 MB	18.3 MB	7%	19.2 KB
10	csiph-out.news.xs4all.nl	1.1 MB	2.1 MB	3.2 MB	35%	4.3 KB
11	news.freedyn.net	692.6 KB	2.7 MB	3.3 MB	20%	16.9 KB
12	news-out.neodome.net	572.4 KB	119.7 KB	692.1 KB	82%	2.7 KB
13	news.fcku.it	350.6 KB	0.0 KB	350.6 KB	100%	1.7 KB
14	nntp.pasdenom.info	182.3 KB	14.3 KB	196.5 KB	92%	2.3 KB
15	csiph-out.news.tnetconsulting.net	118.2 KB	247.9 KB	366.1 KB	32%	6.2 KB
16	news.swapon.de	96.1 KB	4.8 KB	100.8 KB	95%	2.6 KB
17	news.samoylyk.net	28.3 KB	0.0 KB	28.3 KB	100%	3.5 KB
18	news.bbs.nz	23.9 KB	0.0 KB	23.9 KB	100%	2.7 KB
19	localhost	5.1 KB	0.0 KB	5.1 KB	100%	1.3 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		41.5 MB	39.9 MB	81.3 MB	50%	5.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 22 04:15:00 - 04:59:59	179	1.3%	0.07	572.2 KB	1.3%	0.21
Aug 22 05:00:00 - 05:59:59	374	2.7%	0.10	1.2 MB	2.9%	0.35
Aug 22 06:00:00 - 06:59:59	474	3.4%	0.13	1.5 MB	3.5%	0.42
Aug 22 07:00:00 - 07:59:59	573	4.1%	0.16	1.9 MB	4.5%	0.53
Aug 22 08:00:00 - 08:59:59	628	4.5%	0.17	1.9 MB	4.5%	0.53
Aug 22 09:00:00 - 09:59:59	716	5.2%	0.20	2.1 MB	5.0%	0.59
Aug 22 10:00:00 - 10:59:59	731	5.3%	0.20	1.8 MB	4.3%	0.51
Aug 22 11:00:00 - 11:59:59	589	4.2%	0.16	1.6 MB	3.8%	0.46
Aug 22 12:00:00 - 12:59:59	631	4.5%	0.18	1.8 MB	4.3%	0.51
Aug 22 13:00:00 - 13:59:59	714	5.1%	0.20	2.0 MB	4.9%	0.58
Aug 22 14:00:00 - 14:59:59	830	6.0%	0.23	2.4 MB	5.7%	0.68
Aug 22 15:00:00 - 15:59:59	745	5.4%	0.21	2.3 MB	5.4%	0.64
Aug 22 16:00:00 - 16:59:59	721	5.2%	0.20	2.0 MB	4.8%	0.57
Aug 22 17:00:00 - 17:59:59	827	6.0%	0.23	2.2 MB	5.3%	0.63
Aug 22 18:00:00 - 18:59:59	755	5.4%	0.21	2.4 MB	5.6%	0.67
Aug 22 19:00:00 - 19:59:59	681	4.9%	0.19	1.9 MB	4.7%	0.55
Aug 22 20:00:00 - 20:59:59	736	5.3%	0.20	2.8 MB	6.7%	0.80
Aug 22 21:00:00 - 21:59:59	592	4.3%	0.16	2.0 MB	4.8%	0.57
Aug 22 22:00:00 - 22:59:59	563	4.1%	0.16	1.7 MB	4.0%	0.48
Aug 22 23:00:00 - 23:59:59	438	3.2%	0.12	1.5 MB	3.6%	0.43
Aug 23 00:00:00 - 00:59:59	308	2.2%	0.09	1.0 MB	2.4%	0.28
Aug 23 01:00:00 - 01:59:59	388	2.8%	0.11	1.2 MB	2.8%	0.34
Aug 23 02:00:00 - 02:59:59	321	2.3%	0.09	1.1 MB	2.6%	0.31
Aug 23 03:00:00 - 03:59:59	317	2.3%	0.09	957.3 KB	2.2%	0.27
Aug 23 04:00:00 - 04:15:00	65	0.5%	0.07	162.8 KB	0.4%	0.18
TOTAL: 24:00:00	13896	100.0%	0.16	41.9 MB	100.0%	0.50

Sites sending bad articles:

Server		Total	Group	Other
1	news-out.mixmin.net	709	15	694
2	csiph-out.feed.uzoreto.com	662	23	639
3	newsfeed.fu-berlin.de	452	38	414
4	csiph-out.news.xs4all.nl	366	16	350
5	newsfeed.neva.ru	355	39	316
6	csiph-out.feeder.erje.net	307	86	221
7	csiph-out.news.weretis.net	193	53	140
8	feeder.eternal-september.org	121	32	89
9	news.freedyn.net	99	0	99
10	news-out.aioe.org	68	53	15
11	newsfeeds.xmission.com	68	1	67
12	news-out.neodome.net	44	2	42
13	csiph-out.news.tnetconsulting.net	15	14	1
14	news.swapon.de	15	0	15
15	nntp.pasdenom.info	7	0	7
TOTAL: 15		3481	372	3109

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	77
alt.binaries.pictures.purebeauty	27
scinet.stats	24
lada.talk	20
it-alt.sport.calcio.juventus	16
mozilla.support.seamonkey	15
spline.eisfair	14
alt.tv.comedy-central.daily-show	12
lada.movies	11
mozilla.support.firefox	11
alt.philosophy	10
trollnet.cinema	9
mozilla.general	8
alt.bbs.synchronet	8
alt.politics	7
at.jobs	7
mozilla.test	5
alt.binaries.ebook	5
alt.politics.usa	5
alt.windows7.general	4
TOTAL: 74	372

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1698
EMP (phn path)	177
EMP (phl)	116
0	89
EMP (phn nph)	59
Too many newsgroups	50
HTML Multipart	25
Scoring filter	13
Excessive Supersedes (b09da6e5.news.xs4all.nl)	11
Binary: misplaced binary	6
Binary Payload	2
HTML post	1
TOTAL: 12	2247

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	75
	csiph-out.feed.uzoreto.com	7
	csiph-out.news.xs4all.nl	6
	TOTAL: 3	88
Huge articles
	csiph-out.feed.uzoreto.com	117
	csiph-out.feeder.erje.net	82
	newsfeeds.xmission.com	53
	news.freedyn.net	36
	csiph-out.news.weretis.net	28
	feeder.eternal-september.org	14
	csiph-out.news.xs4all.nl	11
	newsfeed.fu-berlin.de	10
	news-out.mixmin.net	5
	csiph-out.news.tnetconsulting.net	1
	TOTAL: 10	357
Including strange strings
	newsfeed.fu-berlin.de	370
	news-out.mixmin.net	77
	csiph-out.news.weretis.net	16
	news.swapon.de	13
	newsfeeds.xmission.com	10
	news-out.aioe.org	9
	csiph-out.feeder.erje.net	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 8	497
No colon-space in header
	csiph-out.news.xs4all.nl	7
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	8
TOTAL: 4		950

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	16695	1254	12630	12	46	7%	23:59:55
2	news.bbs.nz	24768	576	12215	10	679	2%	23:59:55
3	fu-berlin.de	12863	399	12464	0	0	3%	23:59:55
4	news.samoylyk.net	14210	386	13496	4	103	2%	23:59:55
5	tnet	14765	208	13278	1	0	1%	23:59:55
6	erje	15724	92	12571	236	0	0%	23:59:40
7	news.freedyn.net	14003	45	13703	1	0	0%	23:59:54
8	newsfeed.xs4all.nl	13771	40	13368	12	163	0%	23:59:55
9	neva.ru	12807	35	12576	134	0	0%	23:59:54
10	xmission	12490	33	10300	0	0	0%	23:57:59
11	usenet.pasdenom.info	13839	19	13387	2	35	0%	23:59:55
12	news.mixmin.net	10514	12	10264	0	0	0%	23:59:48
13	eternal-september	11792	12	11270	0	0	0%	23:58:41
14	neodome.net	13989	9	13634	0	0	0%	23:59:41
15	weretis.net	12468	7	12369	0	0	0%	23:59:26
16	news.swapon.de	1322	6	1315	0	0	0%	23:42:43
17	aioe	10117	5	9765	0	0	0%	23:59:55
18	news.uzoreto.com	13574	4	13321	49	0	0%	23:59:55
19	news.fcku.it	1009	0	1008	0	0	0%	20:51:29
TOTAL: 19		240720	3142	212934	461	1026	1%	452:28:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.3 MB	109.1 KB	5.4 MB	0.1 KB/s	4.4 KB	23:59:55
2	fu-berlin.de	3.0 MB	0.0 KB	3.0 MB	0.0 KB/s	7.8 KB	23:59:55
3	news.samoylyk.net	3.0 MB	25.7 KB	3.0 MB	0.0 KB/s	7.9 KB	23:59:55
4	news.bbs.nz	1.7 MB	49.2 KB	1.7 MB	0.0 KB/s	3.0 KB	23:59:55
5	tnet	593.0 KB	2.9 KB	595.9 KB	0.0 KB/s	2.9 KB	23:59:55
6	news.freedyn.net	372.7 KB	3.0 KB	375.6 KB	0.0 KB/s	8.2 KB	23:59:54
7	erje	207.1 KB	782.0 KB	989.2 KB	0.0 KB/s	3.0 KB	23:59:40
8	newsfeed.xs4all.nl	89.6 KB	76.5 KB	166.1 KB	0.0 KB/s	3.2 KB	23:59:55
9	usenet.pasdenom.info	75.2 KB	5.1 KB	80.3 KB	0.0 KB/s	3.8 KB	23:59:55
10	xmission	72.2 KB	0.0 KB	72.2 KB	0.0 KB/s	2.2 KB	23:57:59
11	neva.ru	70.3 KB	306.4 KB	376.7 KB	0.0 KB/s	2.2 KB	23:59:54
12	news.mixmin.net	65.4 KB	0.0 KB	65.4 KB	0.0 KB/s	5.4 KB	23:59:48
13	eternal-september	41.5 KB	0.0 KB	41.5 KB	0.0 KB/s	3.5 KB	23:58:41
14	neodome.net	26.8 KB	0.0 KB	26.8 KB	0.0 KB/s	3.0 KB	23:59:41
15	weretis.net	10.8 KB	0.0 KB	10.8 KB	0.0 KB/s	1.5 KB	23:59:26
16	aioe	8.6 KB	0.0 KB	8.6 KB	0.0 KB/s	1.7 KB	23:59:55
17	news.swapon.de	8.1 KB	0.0 KB	8.1 KB	0.0 KB/s	1.4 KB	23:42:43
18	news.uzoreto.com	7.1 KB	172.4 KB	179.5 KB	0.0 KB/s	3.4 KB	23:59:55
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:51:29
TOTAL: 19		14.6 MB	1.5 MB	16.1 MB	0.0 KB/s	4.6 KB	452:28:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	28	16	28.3 KB	5	0	0	05:40:31
2	109-178-178-100.pat.ren.cosmote.net	1	16	29.3 KB	1	0	0	00:00:10
3	047-035-184-231.res.spectrum.com	14	11	16.9 KB	6	4	3	04:19:31
TOTAL: 3		43	43	74.5 KB	12	4	3	36:32:44

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	28	16	28.3 KB	5	0	0	05:40:31
2	*.pat.ren.cosmote.net	1	16	29.3 KB	1	0	0	00:00:10
3	*.res.spectrum.com	14	11	16.9 KB	6	4	3	04:19:31
4	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:02:44
5	*.internet-census.org	6	0	0.0 KB	0	0	0	00:00:01
6	unresolved	73	0	0.0 KB	0	0	0	23:59:43
7	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:00
TOTAL: 7		127	43	74.5 KB	12	4	3	36:32:44

NNRP auth users [Top 20]:

User	Conn
ohlandl	3
TOTAL: 1	3

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.811	1.143	0.000	23:59:42
a131033.upc-a.chello.nl	2.111	0.421	0.000	05:40:31
047-035-184-231.res.spectrum.com	1.107	0.206	0.008	04:19:31
ip72-222-200-117.ph.ph.cox.net	0.427	0.066	0.000	02:02:44
c-73-140-175-55.hsd1.wa.comcast.net	0.063	0.016	0.000	00:30:00
109-178-178-100.pat.ren.cosmote.net	0.102	0.016	0.000	00:00:10
sh-chi-us-gp1-wk111.internet-census.org	0.381	0.083	0.000	00:00:01
151.36.166.254	0.053	0.023	0.000	00:00:01
TOTAL: 8	9.055	1.974	0.008	36:32:44

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
sh-chi-us-gp1-wk111.internet-census.org	6
ip72-222-200-117.ph.ph.cox.net	4
151.36.166.254	1
c-73-140-175-55.hsd1.wa.comcast.net	1
TOTAL: 5	84

NNRP unrecognized commands (by host):

System	Conn
sh-chi-us-gp1-wk111.internet-census.org	11
TOTAL: 1	11

NNRP unrecognized commands (by command):

Command	Count
EHLO sh-chi-us-gp1-wk111.internet-census...	2
^A	1
Accept: /	1
^V^C^A	1
ë{^\\x09Õ	1
Host: 69.160.37.58:119	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Accept-Encoding: gzip	1
GET / HTTP/1.1	1
SSH-2.0-Go	1
TOTAL: 10	11

NNRP client timeouts [Top 20]:

System	Conn	Peer
047-035-184-231.res.spectrum.com	5	0
ip72-222-200-117.ph.ph.cox.net	4	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 3	10	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Aug 22 04:15:00 -- Aug 23 04:15:00