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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Feb 7 14:00:23 csiph nnrpd[92205]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 7 14:00:23 csiph nnrpd[92205]: 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	37313	49.5%	7.1 MB	59.3%
innd	32132	42.6%	4.0 MB	33.5%
innfeed	3649	4.8%	617.6 KB	5.1%
nnrpd	2223	2.9%	249.3 KB	2.0%
nocem	57	0.1%	6.8 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	75375	100.0%	11.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	306297	50.5%
Negative hits	263221	43.4%
Do not exist	36667	6.0%
Cache misses	327	0.1%
TOTAL: 4	606512	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.252	0.0%	33	0.000	7.636	18.000
article cleanup	00:00:00.149	0.0%	32337	0.000	0.005	0.021
article logging	00:00:00.654	0.0%	32838	0.000	0.020	0.576
article parse	00:00:18.681	0.0%	1157163	0.002	0.016	0.174
article write	00:00:14.875	0.0%	15456	0.067	0.962	39.950
artlog/artcncl	00:00:00.000	0.0%	33	0.000	0.000	0.000
artlog/artparse	00:00:00.180	0.0%	4377	0.000	0.041	4.870
data move	00:00:01.874	0.0%	1668617	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.013	0.0%	31	0.000	0.419	1.333
hishave/artcncl	00:00:00.004	0.0%	33	0.000	0.121	1.000
hishave/artparse	00:00:00.011	0.0%	4374	0.000	0.003	0.010
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:22.362	0.0%	603899	0.002	0.037	0.477
history sync	00:00:01.762	0.0%	366	0.000	4.814	58.000
history write	00:00:41.766	0.0%	32339	0.059	1.292	11.798
hiswrite/artcncl	00:00:00.002	0.0%	2	1.000	1.000	1.000
hiswrite/artparse	00:00:05.451	0.0%	4374	0.000	1.246	29.200
idle	23:57:04.966	99.7%	1605481	2.824	53.707	414.040
nntp read	00:00:06.595	0.0%	1652258	0.002	0.004	0.007
overview write	00:00:44.150	0.1%	15456	0.082	2.856	115.492
perl filter	00:00:31.992	0.0%	32332	0.696	0.989	1.478
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.257	0.0%	30969	0.000	0.008	0.030
TOTAL: 24:00:57.616	24:00:15.996	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.031	0.0%	15683	0.000	0.002	0.033
article prepare	00:00:00.001	0.0%	11788	0.000	0.000	0.003
article read	00:00:00.212	0.0%	1260	0.000	0.168	21.500
backlog stats	00:00:00.204	0.0%	344836	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.247	0.0%	270595	0.000	0.005	0.009
data write	00:00:01.648	0.0%	254678	0.001	0.006	0.015
idle	23:53:45.487	100.0%	344836	151.750	249.468	757.600
status file	00:00:00.271	0.0%	327	0.000	0.829	11.500
TOTAL: 23:53:56.002	23:53:49.101	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	46:10:13.813	99.4%	1185	66.000	140264.821	267572.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.007	0.0%	2690	0.000	0.374	7.683
readart	00:00:00.156	0.0%	25	0.250	6.240	59.000
TOTAL: 46:25:40.425	46:10:14.976	99.4%	-	-	-	-

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	4	14354	2705	11639	10	18%	23:33:53
2	feeder.eternal-september.org	24	10723	2018	5001	3704	18%	36:06:54
3	csiph-out.news.xs4all.nl	0	5163	1865	2680	618	36%	62:00:43
4	news.freedyn.net	3	18533	1707	15651	1175	9%	24:06:57
5	news-out.aioe.org	3	15079	1641	13380	58	10%	24:09:56
6	news-out.mixmin.net	3	17034	1199	15571	264	7%	23:59:38
7	csiph-out.news.weretis.net	7	36160	979	34785	396	2%	54:28:34
8	newsfeed.fu-berlin.de	18	29486	813	15503	13170	2%	26:27:20
9	csiph-out.feeder.erje.net	1439	77123	777	75981	365	1%	118:46:43
10	news-out.neodome.net	5	19868	687	18243	938	3%	28:19:55
11	newsfeed.neva.ru	145	15880	468	15273	139	2%	71:45:00
12	csiph-out.feed.uzoreto.com	864	25714	437	24914	363	1%	71:21:02
13	nntp.pasdenom.info	5	9625	106	9518	1	1%	23:57:19
14	news.swapon.de	14	1343	68	1270	5	5%	21:20:44
15	news.fcku.it	16	1113	66	1047	0	5%	21:37:30
16	csiph-out.news.tnetconsulting.net	2	17355	48	17242	65	0%	25:24:01
17	news.bbs.nz	2	23334	12	23321	1	0%	33:43:47
18	news.samoylyk.net	5	14870	5	14864	1	0%	25:37:01
19	localhost	26	0	0	0	0	0%	00:00:02
20	xanadu-bbs.net	36	347	0	347	0	0%	18:28:10
TOTAL: 20		2621	353104	15601	316230	21273	4%	735:15:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	7.9 MB	0.0 KB	112.8 KB	8.0 MB	98%	3.0 KB
2	csiph-out.feeder.erje.net	6.9 MB	0.0 KB	5.3 MB	12.2 MB	56%	11.0 KB
3	feeder.eternal-september.org	5.8 MB	0.0 KB	2.5 GB	2.5 GB	0%	464.0 KB
4	csiph-out.news.xs4all.nl	5.3 MB	0.0 KB	6.8 MB	12.1 MB	43%	5.0 KB
5	news-out.aioe.org	4.8 MB	0.0 KB	110.8 KB	4.9 MB	97%	3.0 KB
6	news.freedyn.net	4.5 MB	0.0 KB	52.9 MB	57.4 MB	7%	20.4 KB
7	news-out.mixmin.net	2.9 MB	0.0 KB	754.4 KB	3.6 MB	79%	2.5 KB
8	csiph-out.news.weretis.net	2.8 MB	0.0 KB	2.0 MB	4.8 MB	58%	3.5 KB
9	news-out.neodome.net	2.5 MB	0.0 KB	3.8 MB	6.3 MB	39%	4.0 KB
10	newsfeed.fu-berlin.de	1.9 MB	0.0 KB	29.2 MB	31.0 MB	6%	2.3 KB
11	csiph-out.feed.uzoreto.com	1.6 MB	0.0 KB	8.7 MB	10.3 MB	15%	13.2 KB
12	newsfeed.neva.ru	1.3 MB	1.3 KB	643.9 KB	2.0 MB	68%	3.3 KB
13	nntp.pasdenom.info	263.6 KB	0.0 KB	2.9 KB	266.5 KB	98%	2.5 KB
14	news.swapon.de	153.4 KB	0.0 KB	8.8 KB	162.2 KB	94%	2.2 KB
15	csiph-out.news.tnetconsulting.net	138.3 KB	0.0 KB	237.5 KB	375.8 KB	36%	3.3 KB
16	news.fcku.it	119.9 KB	0.0 KB	0.0 KB	119.9 KB	100%	1.8 KB
17	news.bbs.nz	27.5 KB	0.0 KB	1.8 KB	29.3 KB	93%	2.3 KB
18	news.samoylyk.net	20.2 KB	0.0 KB	279.8 KB	300.0 KB	6%	50.0 KB
19	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		49.0 MB	1.3 KB	2.6 GB	2.7 GB	1%	76.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 7 04:15:00 - 04:59:59	231	1.5%	0.09	771.9 KB	1.6%	0.29
Feb 7 05:00:00 - 05:59:59	350	2.3%	0.10	1.1 MB	2.4%	0.33
Feb 7 06:00:00 - 06:59:59	281	1.8%	0.08	895.7 KB	1.8%	0.25
Feb 7 07:00:00 - 07:59:59	444	2.9%	0.12	1.6 MB	3.4%	0.46
Feb 7 08:00:00 - 08:59:59	556	3.6%	0.15	2.5 MB	5.2%	0.72
Feb 7 09:00:00 - 09:59:59	715	4.6%	0.20	2.6 MB	5.4%	0.74
Feb 7 10:00:00 - 10:59:59	747	4.8%	0.21	2.3 MB	4.6%	0.64
Feb 7 11:00:00 - 11:59:59	910	5.9%	0.25	2.8 MB	5.8%	0.80
Feb 7 12:00:00 - 12:59:59	805	5.2%	0.22	2.4 MB	4.9%	0.67
Feb 7 13:00:00 - 13:59:59	798	5.2%	0.22	2.6 MB	5.4%	0.75
Feb 7 14:00:00 - 14:59:59	839	5.4%	0.23	2.4 MB	4.9%	0.68
Feb 7 15:00:00 - 15:59:59	813	5.3%	0.23	2.1 MB	4.4%	0.61
Feb 7 16:00:00 - 16:59:59	856	5.5%	0.24	2.8 MB	5.8%	0.80
Feb 7 17:00:00 - 17:59:59	897	5.8%	0.25	2.4 MB	4.9%	0.68
Feb 7 18:00:00 - 18:59:59	937	6.1%	0.26	2.6 MB	5.4%	0.74
Feb 7 19:00:00 - 19:59:59	836	5.4%	0.23	2.4 MB	4.9%	0.68
Feb 7 20:00:00 - 20:59:59	703	4.6%	0.20	2.1 MB	4.3%	0.60
Feb 7 21:00:00 - 21:59:59	607	3.9%	0.17	1.8 MB	3.7%	0.52
Feb 7 22:00:00 - 22:59:59	572	3.7%	0.16	1.6 MB	3.4%	0.47
Feb 7 23:00:00 - 23:59:59	443	2.9%	0.12	1.5 MB	3.1%	0.43
Feb 8 00:00:00 - 00:59:59	511	3.3%	0.14	1.7 MB	3.4%	0.47
Feb 8 01:00:00 - 01:59:59	629	4.1%	0.17	2.2 MB	4.5%	0.62
Feb 8 02:00:00 - 02:59:59	519	3.4%	0.14	1.7 MB	3.5%	0.48
Feb 8 03:00:00 - 03:59:59	374	2.4%	0.10	1.2 MB	2.5%	0.34
Feb 8 04:00:00 - 04:15:00	74	0.5%	0.08	312.8 KB	0.6%	0.35
TOTAL: 24:00:00	15447	100.0%	0.18	48.5 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.fu-berlin.de	13531	5	0	0	13526
2	feeder.eternal-september.org	3717	47	0	0	3670
3	news.freedyn.net	1178	8	0	0	1170
4	news-out.neodome.net	1046	1	0	0	1045
5	csiph-out.news.xs4all.nl	748	25	0	0	723
6	csiph-out.news.weretis.net	433	61	0	0	372
7	csiph-out.feed.uzoreto.com	373	34	0	0	339
8	news-out.mixmin.net	282	18	0	0	264
9	csiph-out.feeder.erje.net	238	60	0	7	171
10	newsfeed.neva.ru	144	28	1	0	115
11	news-out.aioe.org	63	38	0	0	25
12	news.swapon.de	34	0	0	0	34
13	newsfeeds.xmission.com	23	8	0	0	15
14	csiph-out.news.tnetconsulting.net	20	19	0	0	1
15	news.bbs.nz	1	1	0	0	0
16	nntp.pasdenom.info	1	0	0	0	1
17	news.samoylyk.net	1	0	0	0	1
TOTAL: 17		21833	353	1	7	21472

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	122
alt.binaries.pictures.purebeauty	28
scinet.stats	26
alt.tv.comedy-central.daily-show	21
lada.talk	15
alt.politics	15
scinet.general.chatter	12
alt.bbs.synchronet	9
mozilla.support.firefox	8
mozilla.support.thunderbird	6
scinet.bbs.mods	5
oesterreich.tratsch	5
alt.philosophy	5
alt.binaries.dutch.ebook	5
mozilla.support.seamonkey	5
aioe.helpdesk	4
lada.auto	4
alt.survival	3
alt.binaries.documentaries	3
alt.politics.usa	3
TOTAL: 56	353

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

Groups	Count
alt.binaries.sounds.mp3.jazz.vocals	7
TOTAL: 1	7

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	13106
EMP (md5)	1719
EMP (phn path)	1306
EMP (phn nph)	143
EMP (phl)	129
Too many newsgroups	89
0	63
Binary Payload	27
HTML Multipart	3
Too many newsgroups (meow)	2
Too many newsgroups (low_xpost)	1
TOTAL: 11	16588

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	csiph-out.feed.uzoreto.com	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	2
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	feeder.eternal-september.org	3581
	news.freedyn.net	478
	csiph-out.feed.uzoreto.com	105
	csiph-out.news.xs4all.nl	100
	csiph-out.feeder.erje.net	91
	csiph-out.news.weretis.net	12
	news-out.mixmin.net	6
	newsfeeds.xmission.com	2
	newsfeed.fu-berlin.de	2
	news-out.neodome.net	2
	TOTAL: 12	4381
Including strange strings
	newsfeed.fu-berlin.de	381
	news.swapon.de	29
	csiph-out.news.xs4all.nl	27
	csiph-out.news.weretis.net	27
	newsfeeds.xmission.com	13
	csiph-out.feed.uzoreto.com	8
	news-out.aioe.org	6
	newsfeed.neva.ru	5
	news-out.mixmin.net	4
	feeder.eternal-september.org	1
	TOTAL: 10	501
No colon-space in header
	csiph-out.feeder.erje.net	2
	csiph-out.news.xs4all.nl	1
	TOTAL: 2	3
TOTAL: 5		4932

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18856	967	14477	0	0	5%	23:59:55
2	fu-berlin.de	14347	564	13783	0	0	3%	23:59:55
3	news.samoylyk.net	16046	557	14875	9	101	3%	23:59:55
4	news.bbs.nz	29166	181	14661	25	0	0%	23:59:55
5	tnet	15889	73	14794	1	1	0%	23:59:34
6	newsfeed.xs4all.nl	14253	57	13517	1	131	0%	23:59:55
7	neva.ru	14324	52	14069	78	0	0%	23:59:55
8	erje	15728	48	14376	109	0	0%	23:59:55
9	xmission	14042	34	11971	1	0	0%	23:59:55
10	usenet.pasdenom.info	15136	7	14723	34	28	0%	23:59:55
11	neodome.net	15048	7	14629	0	1	0%	23:59:55
12	news.uzoreto.com	14540	6	14281	96	0	0%	23:59:55
13	news.freedyn.net	13560	6	13199	40	1	0%	23:59:46
14	news.mixmin.net	13520	2	13227	1	1	0%	23:59:55
15	news.swapon.de	1331	1	1324	0	0	0%	22:36:13
16	aioe	12624	1	12173	0	1	0%	23:59:55
17	weretis.net	14055	1	13775	0	6	0%	23:59:55
18	eternal-september	0	0	0	0	12639	0%	23:59:55
19	news.fcku.it	932	0	930	0	0	0%	21:17:41
TOTAL: 19		253397	2564	224784	395	12910	1%	451:51:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	0.0 KB	7.2 MB	0.1 KB/s	7.6 KB	23:59:55
2	fu-berlin.de	6.3 MB	0.0 KB	6.3 MB	0.1 KB/s	11.3 KB	23:59:55
3	news.samoylyk.net	6.2 MB	103.6 KB	6.3 MB	0.1 KB/s	11.3 KB	23:59:55
4	news.bbs.nz	471.1 KB	92.8 KB	563.8 KB	0.0 KB/s	2.7 KB	23:59:55
5	tnet	150.9 KB	1.3 KB	152.1 KB	0.0 KB/s	2.1 KB	23:59:34
6	newsfeed.xs4all.nl	121.4 KB	38.5 KB	159.9 KB	0.0 KB/s	2.8 KB	23:59:55
7	neva.ru	100.4 KB	189.0 KB	289.4 KB	0.0 KB/s	2.2 KB	23:59:55
8	erje	86.5 KB	373.9 KB	460.5 KB	0.0 KB/s	2.9 KB	23:59:55
9	xmission	69.8 KB	1.5 KB	71.3 KB	0.0 KB/s	2.0 KB	23:59:55
10	usenet.pasdenom.info	16.7 KB	59.7 KB	76.4 KB	0.0 KB/s	1.9 KB	23:59:55
11	news.freedyn.net	16.1 KB	683.4 KB	699.5 KB	0.0 KB/s	15.2 KB	23:59:46
12	neodome.net	10.2 KB	0.0 KB	10.2 KB	0.0 KB/s	1.5 KB	23:59:55
13	news.uzoreto.com	10.2 KB	261.8 KB	272.0 KB	0.0 KB/s	2.7 KB	23:59:55
14	aioe	5.0 KB	0.0 KB	5.0 KB	0.0 KB/s	5.0 KB	23:59:55
15	news.mixmin.net	3.0 KB	1.2 KB	4.2 KB	0.0 KB/s	1.4 KB	23:59:55
16	weretis.net	2.0 KB	0.0 KB	2.0 KB	0.0 KB/s	2.0 KB	23:59:55
17	news.swapon.de	1.3 KB	0.0 KB	1.3 KB	0.0 KB/s	1.3 KB	22:36:13
18	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:17:41
TOTAL: 19		20.7 MB	1.8 MB	22.4 MB	0.0 KB/s	7.8 KB	451:51:59

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	172.83.100.240	8	25	49.3 KB	30	01:54:16
2	212.90.61.180	1	0	0.0 KB	14	00:00:00
TOTAL: 2		9	25	49.3 KB	44	46:25:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	301	25	49.3 KB	44	36:31:29
2	*.members.linode.com	1	0	0.0 KB	0	00:00:00
3	*.range31-49.btcentralplus.com	1	0	0.0 KB	0	00:00:14
4	*.census.shodan.io	1	0	0.0 KB	0	00:00:04
5	*.upc-a.chello.nl	60	0	0.0 KB	0	09:53:48
TOTAL: 5		364	25	49.3 KB	44	46:25:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	17.715	4.786	34:36:58
a131033.upc-a.chello.nl	3.738	0.924	09:53:48
172.83.100.240	0.689	0.153	01:54:16
host31-49-229-214.range31-49.btcentralplus.com	0.053	0.027	00:00:14
178.62.31.194	0.070	0.009	00:00:10
cloud.census.shodan.io	0.061	0.014	00:00:04
218.249.89.194	0.063	0.016	00:00:04
91.241.19.21	0.074	0.000	00:00:00
li2038-209.members.linode.com	0.070	0.016	00:00:00
91.241.19.60	0.071	0.008	00:00:00
TOTAL: 10	22.604	5.953	46:25:37

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	288
a131033.upc-a.chello.nl	60
host31-49-229-214.range31-49.btcentralplus.com	1
li2038-209.members.linode.com	1
178.62.31.194	1
91.241.19.60	1
91.241.19.21	1
218.249.89.194	1
cloud.census.shodan.io	1
TOTAL: 9	355

NNRP unrecognized commands (by host):

System	Conn
91.241.19.21	1
91.241.19.60	1
TOTAL: 2	2

NNRP unrecognized commands (by command):

Command	Count
^C	2
TOTAL: 1	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
172.83.100.240	3	0
91.241.19.21	1	1
91.241.19.60	1	1
TOTAL: 3	5	3

Newsgroup request counts (by category):

Category		Count	Pct
1	it	46775	76.1%
2	microsoft	14335	23.3%
3	alt	152	0.2%
4	comp	84	0.1%
5	de	57	0.1%
6	fj	48	0.1%
7	pdaxs	2	0.0%
8	uk	2	0.0%
TOTAL: 8		61455	100.0%

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

Newsgroup		Count
1	it.cultura.filosofia	46775
2	microsoft.public.windowsxp.general	14247
3	alt.tv.seinfeld	80
4	microsoft.public.word.tables	73
5	alt.video.laserdisc	67
6	de.alt.netdigest	57
7	comp.sys.ibm.pc.hardware.systems	53
8	fj.rec.disney	48
9	comp.sys.ibm.ps2.hardware	31
10	microsoft.public.windowsmedia.player	12
11	alt.irs.general	4
12	microsoft.public.netspeechsdk	3
13	uk.sport.athletics	2
14	pdaxs.jobs.construction	2
15	alt.sports.college.big-12	1
TOTAL: 15		61455

Daily Usenet report for csiph.com

Feb 7 04:15:00 -- Feb 8 04:15:00