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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 3 10:42:46 csiph nnrpd[15557]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 3 10:42:46 csiph nnrpd[15557]: 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]
Feb 3 14:40:52 csiph nnrpd[24772]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Feb 3 14:40:52 csiph nnrpd[24772]: 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	32507	50.1%	6.5 MB	60.5%
innd	26649	41.1%	3.4 MB	31.5%
innfeed	3749	5.8%	660.1 KB	6.0%
nnrpd	1899	2.9%	212.2 KB	1.9%
nocem	40	0.1%	4.6 KB	0.0%
controlchan	2	0.0%	0.2 KB	0.0%
TOTAL: 6	64846	100.0%	10.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	298471	49.8%
Negative hits	269339	44.9%
Do not exist	31703	5.3%
Cache misses	338	0.1%
TOTAL: 4	599851	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.803	0.0%	39	0.000	46.231	126.000
article cleanup	00:00:00.206	0.0%	31660	0.000	0.007	0.032
article logging	00:00:05.298	0.0%	32102	0.000	0.165	5.756
article parse	00:00:03.971	0.0%	90929	0.000	0.044	0.543
article write	00:08:14.472	0.6%	15861	0.500	31.175	237.271
artlog/artcncl	00:00:00.230	0.0%	39	0.000	5.897	28.750
artlog/artparse	00:00:00.120	0.0%	386	0.000	0.311	5.556
data move	00:00:00.263	0.0%	587799	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.117	0.0%	38	0.000	3.079	75.000
hishave/artcncl	00:00:00.050	0.0%	39	0.000	1.282	48.000
hishave/artparse	00:00:00.001	0.0%	383	0.000	0.003	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:04:13.761	0.3%	605936	0.003	0.419	1.913
history sync	00:00:06.291	0.0%	367	0.000	17.142	190.000
history write	00:05:51.164	0.4%	31622	0.539	11.105	36.449
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:02.989	0.0%	383	0.000	7.804	201.000
idle	23:26:06.434	97.6%	545943	85.921	154.533	457.461
nntp read	00:00:02.458	0.0%	590670	0.001	0.004	0.007
overview write	00:14:34.696	1.0%	15861	0.075	55.148	3036.721
perl filter	00:00:35.716	0.0%	31619	0.743	1.130	2.859
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.267	0.0%	31762	0.000	0.008	0.025
TOTAL: 24:00:56.772	24:00:00.307	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	16020	0.000	0.002	0.022
article prepare	00:00:00.001	0.0%	8544	0.000	0.000	0.010
article read	00:00:01.249	0.0%	968	0.000	1.290	65.000
backlog stats	00:00:00.220	0.0%	332384	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.236	0.0%	267650	0.000	0.005	0.011
data write	00:00:01.567	0.0%	255262	0.001	0.006	0.018
idle	23:44:49.672	100.0%	332384	144.206	257.202	849.373
status file	00:00:02.271	0.0%	334	0.000	6.799	213.000
TOTAL: 23:45:08.234	23:44:56.244	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.989	0.0%	96	10.302	10.302	10.302
idle	126:13:09.276	95.3%	600181	0.000	757.087	450144.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:01:02.029	0.0%	2441038	0.000	0.025	0.286
readart	05:35:27.630	4.2%	586649	0.000	34.309	58.000
TOTAL: 132:24:32.481	131:49:39.924	99.6%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	3
logmode	2
lowmark	1
mode	66
pause	3
paused	3
reload	4
reserve	2
TOTAL: 10	91

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	0	7185	3460	2494	1231	48%	69:10:22
2	feeder.eternal-september.org	12	5816	2660	3015	141	45%	27:10:54
3	newsfeeds.xmission.com	3	15642	2402	13221	19	15%	25:28:18
4	news-out.aioe.org	3	15171	1353	13765	53	8%	24:08:54
5	csiph-out.feeder.erje.net	1440	77238	1248	75466	524	1%	118:42:01
6	newsfeed.fu-berlin.de	27	28258	985	14083	13190	3%	27:56:43
7	news-out.mixmin.net	2	16743	955	15597	191	5%	23:59:36
8	csiph-out.news.weretis.net	3	34702	771	33620	311	2%	50:18:24
9	newsfeed.neva.ru	143	13008	623	12184	201	4%	71:27:27
10	news.fcku.it	12	2283	409	1864	10	17%	22:30:24
11	csiph-out.feed.uzoreto.com	864	24962	334	24237	391	1%	71:17:21
12	news-out.neodome.net	1	16010	237	15760	13	1%	21:05:42
13	nntp.pasdenom.info	3	10870	159	10709	2	1%	23:58:45
14	csiph-out.news.tnetconsulting.net	1	16251	96	16126	29	0%	23:59:56
15	news.swapon.de	6	1535	82	1453	0	5%	23:53:21
16	news.freedyn.net	1	18228	55	18109	64	0%	23:59:58
17	news.samoylyk.net	2	15499	45	15450	4	0%	23:54:38
18	news.bbs.nz	1	16045	11	16032	2	0%	23:59:53
19	xanadu-bbs.net	34	442	1	441	0	0%	21:32:04
20	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 20		2584	335888	15886	303626	16376	4%	718:34:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	9.4 MB	8.9 KB	10.9 MB	20.3 MB	46%	4.4 KB
2	csiph-out.feeder.erje.net	8.4 MB	56.5 KB	2.9 MB	11.3 MB	73%	6.5 KB
3	feeder.eternal-september.org	7.1 MB	1.7 KB	8.3 MB	15.4 MB	46%	5.6 KB
4	newsfeeds.xmission.com	6.8 MB	0.0 KB	987.0 KB	7.8 MB	87%	3.3 KB
5	news-out.aioe.org	3.1 MB	0.0 KB	135.1 KB	3.3 MB	95%	2.4 KB
6	news-out.mixmin.net	2.5 MB	7.0 KB	1.2 MB	3.8 MB	67%	3.4 KB
7	csiph-out.news.weretis.net	2.2 MB	5.7 KB	3.0 MB	5.2 MB	42%	4.9 KB
8	newsfeed.fu-berlin.de	2.2 MB	9.0 KB	30.7 MB	32.9 MB	6%	2.4 KB
9	newsfeed.neva.ru	1.7 MB	0.0 KB	649.9 KB	2.3 MB	72%	2.8 KB
10	csiph-out.feed.uzoreto.com	1.2 MB	0.0 KB	9.2 MB	10.4 MB	11%	14.7 KB
11	news-out.neodome.net	736.0 KB	5.7 KB	129.7 KB	871.4 KB	84%	3.5 KB
12	news.fcku.it	654.6 KB	0.0 KB	15.1 KB	669.7 KB	97%	1.6 KB
13	nntp.pasdenom.info	371.0 KB	0.0 KB	5.5 KB	376.4 KB	98%	2.3 KB
14	csiph-out.news.tnetconsulting.net	318.8 KB	0.0 KB	1.2 MB	1.5 MB	20%	12.2 KB
15	news.freedyn.net	272.8 KB	5.1 KB	2.9 MB	3.1 MB	8%	27.1 KB
16	news.samoylyk.net	181.4 KB	4.6 KB	97.2 KB	283.2 KB	64%	5.8 KB
17	news.swapon.de	169.1 KB	0.0 KB	0.0 KB	169.1 KB	100%	2.1 KB
18	news.bbs.nz	33.4 KB	0.0 KB	204.1 KB	237.6 KB	14%	18.3 KB
19	xanadu-bbs.net	1.4 KB	0.0 KB	0.0 KB	1.4 KB	100%	1.4 KB
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		47.3 MB	104.2 KB	72.4 MB	119.8 MB	39%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 3 04:15:00 - 04:59:59	285	1.8%	0.11	1.1 MB	2.4%	0.43
Feb 3 05:00:00 - 05:59:59	374	2.4%	0.10	2.0 MB	4.2%	0.57
Feb 3 06:00:00 - 06:59:59	361	2.3%	0.10	1.1 MB	2.4%	0.32
Feb 3 07:00:00 - 07:59:59	426	2.7%	0.12	1.2 MB	2.4%	0.33
Feb 3 08:00:00 - 08:59:59	583	3.7%	0.16	1.6 MB	3.4%	0.46
Feb 3 09:00:00 - 09:59:59	723	4.6%	0.20	1.9 MB	4.0%	0.54
Feb 3 10:00:00 - 10:59:59	814	5.1%	0.23	2.2 MB	4.7%	0.63
Feb 3 11:00:00 - 11:59:59	784	4.9%	0.22	1.9 MB	4.0%	0.54
Feb 3 12:00:00 - 12:59:59	787	5.0%	0.22	2.1 MB	4.3%	0.58
Feb 3 13:00:00 - 13:59:59	935	5.9%	0.26	2.3 MB	4.9%	0.66
Feb 3 14:00:00 - 14:59:59	966	6.1%	0.27	2.5 MB	5.3%	0.72
Feb 3 15:00:00 - 15:59:59	1030	6.5%	0.29	4.4 MB	9.3%	1.25
Feb 3 16:00:00 - 16:59:59	911	5.7%	0.25	3.1 MB	6.6%	0.89
Feb 3 17:00:00 - 17:59:59	928	5.9%	0.26	2.6 MB	5.4%	0.73
Feb 3 18:00:00 - 18:59:59	863	5.4%	0.24	2.6 MB	5.4%	0.73
Feb 3 19:00:00 - 19:59:59	794	5.0%	0.22	2.2 MB	4.7%	0.63
Feb 3 20:00:00 - 20:59:59	805	5.1%	0.22	2.2 MB	4.6%	0.62
Feb 3 21:00:00 - 21:59:59	756	4.8%	0.21	2.1 MB	4.5%	0.60
Feb 3 22:00:00 - 22:59:59	720	4.5%	0.20	2.2 MB	4.7%	0.63
Feb 3 23:00:00 - 23:59:59	557	3.5%	0.15	1.6 MB	3.5%	0.47
Feb 4 00:00:00 - 00:59:59	443	2.8%	0.12	1.4 MB	2.9%	0.39
Feb 4 01:00:00 - 01:59:59	319	2.0%	0.09	975.5 KB	2.0%	0.27
Feb 4 02:00:00 - 02:59:59	331	2.1%	0.09	997.0 KB	2.1%	0.28
Feb 4 03:00:00 - 03:59:59	309	1.9%	0.09	1004.8 KB	2.1%	0.28
Feb 4 04:00:00 - 04:15:00	49	0.3%	0.05	120.0 KB	0.2%	0.13
TOTAL: 24:00:00	15853	100.0%	0.18	47.3 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.fu-berlin.de	13592	8	3	0	13581
2	csiph-out.news.xs4all.nl	1294	81	6	0	1207
3	csiph-out.feed.uzoreto.com	398	14	0	0	384
4	csiph-out.news.weretis.net	297	59	1	0	237
5	csiph-out.feeder.erje.net	241	61	22	7	151
6	newsfeed.neva.ru	208	55	0	0	153
7	news-out.mixmin.net	192	4	2	0	186
8	feeder.eternal-september.org	142	37	1	0	104
9	news.freedyn.net	64	1	3	0	60
10	news-out.aioe.org	59	43	0	0	16
11	news.swapon.de	38	0	0	0	38
12	csiph-out.news.tnetconsulting.net	35	13	0	0	22
13	newsfeeds.xmission.com	24	5	0	0	19
14	news-out.neodome.net	15	2	1	0	12
15	news.fcku.it	10	10	0	0	0
16	news.samoylyk.net	4	0	2	0	2
17	nntp.pasdenom.info	2	0	0	0	2
TOTAL: 17		16615	393	41	7	16174

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	60
it-alt.sport.calcio.juventus	45
lada.talk	41
ab.test	29
scinet.stats	27
mozilla.support.thunderbird	17
alt.bbs.synchronet	14
alt.binaries.pictures.purebeauty	13
alt.job	13
at.jobs	12
alt.politics	10
lada.auto	9
mozilla.support.seamonkey	8
mozilla.general	7
alt.binaries.ebook	6
alt.survival	6
scinet.general.chatter	6
mozilla.support.firefox	6
scinet.sysop	5
alt.math.undergrad	4
TOTAL: 58	393

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	13162
EMP (md5)	1553
EMP (phn path)	246
EMP (phl)	162
Too many newsgroups	111
EMP (phn nph)	54
0	51
HTML Multipart	7
TOTAL: 8	15346

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.news.xs4all.nl	90
	csiph-out.feed.uzoreto.com	77
	feeder.eternal-september.org	71
	csiph-out.feeder.erje.net	49
	news.freedyn.net	29
	csiph-out.news.weretis.net	25
	csiph-out.news.tnetconsulting.net	22
	newsfeeds.xmission.com	13
	news-out.neodome.net	4
	news-out.mixmin.net	2
	TOTAL: 12	384
Including strange strings
	newsfeed.fu-berlin.de	346
	news.swapon.de	38
	csiph-out.news.xs4all.nl	20
	newsfeed.neva.ru	8
	newsfeeds.xmission.com	6
	news-out.aioe.org	6
	csiph-out.feeder.erje.net	6
	csiph-out.news.weretis.net	5
	csiph-out.feed.uzoreto.com	5
	news-out.mixmin.net	1
	TOTAL: 11	442
No colon-space in header
	csiph-out.feed.uzoreto.com	1
	csiph-out.feeder.erje.net	1
	csiph-out.news.xs4all.nl	1
	TOTAL: 3	3
TOTAL: 4		874

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18199	710	15135	7	1	3%	23:59:49
2	fu-berlin.de	14498	417	14081	0	0	2%	23:59:48
3	news.samoylyk.net	16160	416	15388	5	95	2%	23:59:48
4	news.bbs.nz	28042	146	15235	25	1	0%	23:59:48
5	xmission	14512	101	12903	0	0	0%	23:59:49
6	tnet	16190	67	15349	2	0	0%	23:59:49
7	newsfeed.xs4all.nl	12851	44	12155	5	118	0%	23:59:48
8	neva.ru	14539	34	14343	32	1	0%	23:59:48
9	erje	14642	21	13994	49	6	0%	23:59:48
10	usenet.pasdenom.info	15539	19	15217	20	10	0%	23:59:48
11	news.uzoreto.com	14938	6	14788	6	0	0%	23:59:48
12	neodome.net	15727	4	15463	0	1	0%	23:59:42
13	news.mixmin.net	14212	3	14044	0	0	0%	23:59:48
14	news.freedyn.net	14169	3	13997	23	0	0%	23:59:48
15	aioe	13237	1	12951	0	0	0%	23:59:43
16	weretis.net	14603	1	14360	0	0	0%	23:59:48
17	eternal-september	0	0	0	0	12619	0%	23:59:50
18	news.swapon.de	1520	0	1517	0	0	0%	23:31:42
19	news.fcku.it	1748	0	1747	0	0	0%	21:32:15
TOTAL: 19		255326	1993	232667	174	12852	0%	453:00:27

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.7 MB	13.1 KB	6.7 MB	0.1 KB/s	9.6 KB	23:59:49
2	fu-berlin.de	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	14.6 KB	23:59:48
3	news.samoylyk.net	5.9 MB	38.1 KB	6.0 MB	0.1 KB/s	14.5 KB	23:59:48
4	news.bbs.nz	342.6 KB	98.5 KB	441.1 KB	0.0 KB/s	2.6 KB	23:59:48
5	xmission	202.7 KB	0.0 KB	202.7 KB	0.0 KB/s	2.0 KB	23:59:49
6	tnet	143.2 KB	6.2 KB	149.3 KB	0.0 KB/s	2.2 KB	23:59:49
7	newsfeed.xs4all.nl	101.5 KB	21.2 KB	122.7 KB	0.0 KB/s	2.5 KB	23:59:48
8	neva.ru	78.6 KB	88.8 KB	167.5 KB	0.0 KB/s	2.5 KB	23:59:48
9	usenet.pasdenom.info	62.3 KB	23.2 KB	85.5 KB	0.0 KB/s	2.2 KB	23:59:48
10	erje	30.3 KB	94.6 KB	124.9 KB	0.0 KB/s	1.8 KB	23:59:48
11	news.uzoreto.com	14.4 KB	11.7 KB	26.1 KB	0.0 KB/s	2.2 KB	23:59:48
12	neodome.net	11.9 KB	0.0 KB	11.9 KB	0.0 KB/s	3.0 KB	23:59:42
13	news.freedyn.net	7.4 KB	372.4 KB	379.8 KB	0.0 KB/s	14.6 KB	23:59:48
14	news.mixmin.net	4.7 KB	0.0 KB	4.7 KB	0.0 KB/s	1.6 KB	23:59:48
15	aioe	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.9 KB	23:59:43
16	weretis.net	1.6 KB	0.0 KB	1.6 KB	0.0 KB/s	1.6 KB	23:59:48
17	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:50
18	news.swapon.de	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:31:42
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:32:15
TOTAL: 19		19.6 MB	767.7 KB	20.3 MB	0.0 KB/s	9.6 KB	453:00:27

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	a131033.upc-a.chello.nl	31	3	8.0 KB	2	07:29:31
2	91.187.33.87	240	1	2.0 KB	1	33:38:44
TOTAL: 2		271	585801	1.4 GB	4	132:24:30

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	249	585798	1.4 GB	2	121:46:01
2	*.upc-a.chello.nl	31	3	8.0 KB	2	07:29:31
3	*.net.upcbroadband.cz	38	0	0.0 KB	0	03:07:18
4	*.us-west-1.compute.amazonaws.com	1	0	0.0 KB	0	00:00:00
5	*.range31-49.btcentralplus.com	2	0	0.0 KB	0	00:01:38
TOTAL: 5		321	585801	1.4 GB	4	132:24:30

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.90.61.180	71.554	147.902	87:37:13
91.187.33.87	15.040	3.374	33:38:44
a131033.upc-a.chello.nl	1.992	0.439	07:29:31
ip-78-102-198-192.net.upcbroadband.cz	2.332	0.701	03:07:18
87.140.194.0	0.114	0.010	00:30:01
host31-49-229-214.range31-49.btcentralplus.com	0.118	0.055	00:01:38
188.166.76.78	0.454	0.061	00:00:01
89.248.168.176	0.051	0.029	00:00:01
ec2-54-183-100-188.us-west-1.compute.amazonaws.com	0.047	0.031	00:00:00
TOTAL: 9	91.702	152.602	132:24:30

Curious NNRP server explorers [Top 20]:

System	Conn
ip-78-102-198-192.net.upcbroadband.cz	38
188.166.76.78	7
host31-49-229-214.range31-49.btcentralplus.com	2
87.140.194.0	1
89.248.168.176	1
ec2-54-183-100-188.us-west-1.compute.amazonaws.com	1
TOTAL: 6	50

NNRP unrecognized commands (by host):

System	Conn
188.166.76.78	36
TOTAL: 1	36

NNRP unrecognized commands (by command):

Command	Count
Accept: /	6
Connection: keep-alive	6
Host: 69.160.37.58:119	6
Accept-Encoding: gzip, deflate	6
User-Agent: Mozilla/5.0 (Windows NT 10.0...	6
GET /c/version.js HTTP/1.1	1
GET /stalker_portal/c/version.js HTTP/1....	1
GET /stalker_portal/c/ HTTP/1.1	1
GET /system_api.php HTTP/1.1	1
GET /client_area/ HTTP/1.1	1
GET /streaming/clients_live.php HTTP/1.1	1
TOTAL: 11	36

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.90.61.180	1	1
87.140.194.0	1	0
TOTAL: 2	2	1

Newsgroup request counts (by category):

Category		Count	Pct
1	soc	100437	100.0%
2	comp	4	0.0%
TOTAL: 2		100441	100.0%

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

Newsgroup		Count
1	soc.culture.polish	100437
2	comp.sys.ibm.ps2.hardware	4
TOTAL: 2		100441

Daily Usenet report for csiph.com

Feb 3 04:15:00 -- Feb 4 04:15:00