Daily Usenet report for csiph.com: Oct 17 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 17 22:17:27 csiph nnrpd[42380]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Oct 17 22:17:27 csiph nnrpd[42380]: 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	19769	48.3%	4.8 MB	62.3%
innd	16773	41.0%	2.2 MB	28.4%
innfeed	3478	8.5%	633.3 KB	8.0%
nnrpd	903	2.2%	98.4 KB	1.2%
nocem	21	0.1%	2.5 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40945	100.0%	7.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	308400	55.4%
Negative hits	227640	40.9%
Do not exist	19088	3.4%
Cache misses	1156	0.2%
TOTAL: 4	556284	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.257	0.0%	34	0.000	7.559	25.000
article cleanup	00:00:00.117	0.0%	19037	0.000	0.006	0.042
article logging	00:00:00.287	0.0%	19574	0.000	0.015	0.193
article parse	00:00:00.677	0.0%	54258	0.000	0.012	0.200
article write	00:00:05.730	0.0%	14724	0.173	0.389	6.963
artlog/artcncl	00:00:00.000	0.0%	34	0.000	0.000	0.000
artlog/artparse	00:00:00.002	0.0%	232	0.000	0.009	0.143
data move	00:00:00.166	0.0%	529858	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.040	0.0%	34	0.000	1.176	3.400
hishave/artcncl	00:00:00.026	0.0%	34	0.000	0.765	11.000
hishave/artparse	00:00:00.001	0.0%	230	0.000	0.004	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.753	0.0%	561603	0.000	0.008	0.189
history sync	00:00:00.834	0.0%	377	0.000	2.212	11.000
history write	00:00:10.831	0.0%	19039	0.050	0.569	7.048
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.177	0.0%	230	0.000	0.770	7.900
idle	23:56:41.343	99.7%	536810	84.474	160.581	472.515
nntp read	00:00:01.991	0.0%	537374	0.001	0.004	0.007
overview write	00:02:56.327	0.2%	14724	0.117	11.975	3160.229
perl filter	00:00:25.891	0.0%	19037	0.487	1.360	2.217
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.255	0.0%	29468	0.000	0.009	0.025
TOTAL: 24:01:02.635	24:00:29.705	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	14800	0.000	0.003	0.023
article prepare	00:00:00.003	0.0%	13840	0.000	0.000	0.023
article read	00:00:00.244	0.0%	1448	0.000	0.169	8.000
backlog stats	00:00:00.192	0.0%	339171	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.113	0.0%	267150	0.001	0.004	0.013
data write	00:00:01.475	0.0%	253873	0.001	0.006	0.011
idle	23:53:33.196	100.0%	339171	142.539	253.598	671.281
status file	00:00:00.209	0.0%	256	0.000	0.816	6.400
TOTAL: 23:53:44.515	23:53:36.470	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	35:02:23.913	99.8%	979	44.667	128849.758	600245.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.017	0.0%	3369	0.000	0.005	0.143
readart	00:00:05.605	0.0%	447	0.000	12.539	42.000
TOTAL: 35:06:04.408	35:02:29.535	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	3	13965	3265	10635	65	23%	24:43:14
2	news-out.mixmin.net	2	16149	2443	13331	375	15%	24:08:52
3	feeder.eternal-september.org	4	17067	2154	14787	126	12%	24:59:34
4	csiph-out.news.xs4all.nl	496	19392	1986	14877	2529	10%	68:47:57
5	news-out.aioe.org	2	14044	1314	12619	111	9%	24:09:02
6	csiph-out.feeder.erje.net	1440	74932	924	73873	135	1%	118:47:51
7	newsfeed.fu-berlin.de	5	19335	892	18268	175	4%	31:17:27
8	csiph-out.news.weretis.net	3	19263	553	18567	143	2%	33:53:05
9	newsfeed.neva.ru	144	14120	441	13348	331	3%	71:26:47
10	news.fcku.it	9	1457	230	1227	0	15%	22:57:42
11	csiph-out.feed.uzoreto.com	864	24708	152	24306	250	0%	71:12:56
12	nntp.pasdenom.info	2	9294	125	9058	111	1%	23:58:37
13	news-out.neodome.net	2	16509	111	16390	8	0%	23:59:45
14	news.swapon.de	4	1349	63	1285	1	4%	23:33:29
15	csiph-out.news.tnetconsulting.net	2	15126	52	14973	101	0%	23:59:56
16	news.freedyn.net	1	19137	33	18955	149	0%	23:59:54
17	news.bbs.nz	2	14108	9	14099	0	0%	23:59:37
18	localhost	33	7	7	0	0	100%	00:00:05
19	news.samoylyk.net	2	14215	3	14212	0	0%	23:59:47
20	xanadu-bbs.net	36	300	0	300	0	0%	19:39:26
TOTAL: 20		3056	324477	14757	305110	4610	4%	703:35:03

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	10.0 MB	0.0 KB	3.2 MB	13.2 MB	75%	4.0 KB
2	csiph-out.feeder.erje.net	6.3 MB	0.0 KB	2.3 MB	8.6 MB	73%	8.3 KB
3	feeder.eternal-september.org	6.0 MB	0.0 KB	1.0 MB	7.0 MB	85%	3.2 KB
4	csiph-out.news.xs4all.nl	5.1 MB	0.0 KB	10.3 MB	15.4 MB	32%	3.5 KB
5	news-out.mixmin.net	5.0 MB	0.0 KB	710.1 KB	5.7 MB	87%	2.1 KB
6	news-out.aioe.org	4.5 MB	0.0 KB	319.6 KB	4.8 MB	93%	3.5 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	371.1 KB	2.7 MB	86%	2.6 KB
8	csiph-out.news.weretis.net	1.5 MB	0.0 KB	1.3 MB	2.8 MB	54%	4.1 KB
9	newsfeed.neva.ru	1.3 MB	0.0 KB	651.7 KB	1.9 MB	67%	2.6 KB
10	csiph-out.feed.uzoreto.com	582.1 KB	0.0 KB	6.0 MB	6.6 MB	8%	16.8 KB
11	news.freedyn.net	387.4 KB	0.0 KB	1.3 MB	1.6 MB	23%	9.2 KB
12	news-out.neodome.net	379.0 KB	0.0 KB	22.3 KB	401.3 KB	94%	3.4 KB
13	news.fcku.it	375.5 KB	0.0 KB	0.0 KB	375.5 KB	100%	1.6 KB
14	nntp.pasdenom.info	283.0 KB	0.0 KB	142.7 KB	425.7 KB	66%	1.8 KB
15	csiph-out.news.tnetconsulting.net	203.0 KB	2.4 KB	213.7 KB	419.1 KB	48%	2.7 KB
16	news.swapon.de	165.8 KB	0.0 KB	80.3 KB	246.1 KB	67%	3.8 KB
17	news.bbs.nz	13.9 KB	0.0 KB	0.0 KB	13.9 KB	100%	1.5 KB
18	localhost	11.3 KB	0.0 KB	0.0 KB	11.3 KB	100%	1.6 KB
19	news.samoylyk.net	9.2 KB	0.0 KB	0.0 KB	9.2 KB	100%	3.1 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		44.4 MB	2.4 KB	27.9 MB	72.3 MB	61%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 17 04:15:00 - 04:59:59	238	1.6%	0.09	729.5 KB	1.6%	0.27
Oct 17 05:00:00 - 05:59:59	372	2.5%	0.10	1.4 MB	3.2%	0.40
Oct 17 06:00:00 - 06:59:59	409	2.8%	0.11	1.5 MB	3.4%	0.42
Oct 17 07:00:00 - 07:59:59	538	3.7%	0.15	1.8 MB	4.1%	0.51
Oct 17 08:00:00 - 08:59:59	579	3.9%	0.16	1.6 MB	3.7%	0.46
Oct 17 09:00:00 - 09:59:59	698	4.8%	0.19	1.8 MB	4.0%	0.50
Oct 17 10:00:00 - 10:59:59	812	5.5%	0.23	2.4 MB	5.5%	0.69
Oct 17 11:00:00 - 11:59:59	668	4.6%	0.19	2.0 MB	4.6%	0.57
Oct 17 12:00:00 - 12:59:59	803	5.5%	0.22	2.2 MB	5.0%	0.63
Oct 17 13:00:00 - 13:59:59	828	5.6%	0.23	2.1 MB	4.7%	0.58
Oct 17 14:00:00 - 14:59:59	809	5.5%	0.22	2.0 MB	4.6%	0.57
Oct 17 15:00:00 - 15:59:59	914	6.2%	0.25	3.4 MB	7.7%	0.96
Oct 17 16:00:00 - 16:59:59	914	6.2%	0.25	2.9 MB	6.5%	0.82
Oct 17 17:00:00 - 17:59:59	805	5.5%	0.22	2.2 MB	5.0%	0.63
Oct 17 18:00:00 - 18:59:59	854	5.8%	0.24	2.7 MB	6.2%	0.77
Oct 17 19:00:00 - 19:59:59	668	4.6%	0.19	1.9 MB	4.2%	0.53
Oct 17 20:00:00 - 20:59:59	614	4.2%	0.17	1.7 MB	3.9%	0.49
Oct 17 21:00:00 - 21:59:59	616	4.2%	0.17	1.9 MB	4.3%	0.54
Oct 17 22:00:00 - 22:59:59	639	4.4%	0.18	2.2 MB	5.0%	0.62
Oct 17 23:00:00 - 23:59:59	473	3.2%	0.13	1.3 MB	3.0%	0.38
Oct 18 00:00:00 - 00:59:59	355	2.4%	0.10	1.1 MB	2.4%	0.30
Oct 18 01:00:00 - 01:59:59	322	2.2%	0.09	941.2 KB	2.1%	0.26
Oct 18 02:00:00 - 02:59:59	357	2.4%	0.10	1.2 MB	2.8%	0.35
Oct 18 03:00:00 - 03:59:59	310	2.1%	0.09	839.0 KB	1.9%	0.23
Oct 18 04:00:00 - 04:15:00	85	0.6%	0.09	265.9 KB	0.6%	0.30
TOTAL: 24:00:00	14680	100.0%	0.17	44.0 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	2534	80	2454
2	newsfeed.fu-berlin.de	515	18	497
3	news-out.mixmin.net	449	24	425
4	newsfeed.neva.ru	334	94	240
5	csiph-out.feed.uzoreto.com	249	12	237
6	csiph-out.news.weretis.net	159	35	124
7	news-out.aioe.org	155	105	50
8	news.freedyn.net	148	0	148
9	csiph-out.feeder.erje.net	136	79	57
10	feeder.eternal-september.org	114	53	61
11	nntp.pasdenom.info	111	0	111
12	newsfeeds.xmission.com	69	3	66
13	csiph-out.news.tnetconsulting.net	51	15	36
14	news.swapon.de	30	0	30
15	news-out.neodome.net	6	3	3
TOTAL: 15		5060	521	4539

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	122
lada.talk	83
scinet.stats	30
alt.bbs.synchronet	29
alt.binaries.pictures.purebeauty	21
alt.philosophy	19
alt.politics.usa	19
alt.windows7.general	14
mozilla.support.thunderbird	14
it-alt.sport.calcio.juventus	14
spline.eisfair.dev	12
free.it.religioni.scientology	10
lada.auto	10
alt.binaries.ebook	8
mozilla.general	8
at.jobs	7
alt.survival	7
att.jobs	7
it-alt.comp.software.mozilla	6
oesterreich.tratsch	5
TOTAL: 63	521

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1980
Binary: misplaced binary	998
EMP (phn path)	335
EMP (phl)	171
0	143
Too many newsgroups	78
EMP (phn nph)	38
HTML Multipart	23
Too many newsgroups (meow)	1
Too many newsgroups (low_xpost)	1
TOTAL: 10	3768

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	newsfeeds.xmission.com	56
	csiph-out.feed.uzoreto.com	52
	csiph-out.feeder.erje.net	41
	csiph-out.news.xs4all.nl	36
	news.freedyn.net	17
	feeder.eternal-september.org	14
	csiph-out.news.weretis.net	12
	news-out.mixmin.net	2
	news.swapon.de	1
	TOTAL: 9	231
Including strange strings
	newsfeed.fu-berlin.de	360
	news-out.mixmin.net	77
	news-out.aioe.org	32
	news.swapon.de	29
	csiph-out.news.xs4all.nl	27
	csiph-out.news.weretis.net	5
	newsfeed.neva.ru	3
	feeder.eternal-september.org	2
	csiph-out.feeder.erje.net	1
	csiph-out.feed.uzoreto.com	1
	TOTAL: 11	538
No colon-space in header
	csiph-out.feeder.erje.net	2
	TOTAL: 1	2
TOTAL: 4		816

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18798	1122	13538	20	131	5%	23:59:54
2	fu-berlin.de	13685	564	13121	0	0	4%	23:59:52
3	news.samoylyk.net	15009	557	14115	4	0	3%	23:59:51
4	news.bbs.nz	27475	431	13647	18	0	1%	23:59:54
5	tnet	15653	164	13937	0	0	1%	23:59:42
6	neva.ru	13569	120	13310	37	0	0%	23:59:54
7	newsfeed.xs4all.nl	13077	50	12576	0	0	0%	23:59:54
8	news.freedyn.net	14321	49	14041	0	0	0%	23:59:52
9	xmission	12442	37	10728	0	0	0%	23:59:41
10	news.uzoreto.com	14052	25	13804	111	0	0%	23:59:54
11	usenet.pasdenom.info	14451	19	13939	7	17	0%	23:59:54
12	aioe	10272	17	9913	0	0	0%	23:59:45
13	neodome.net	14857	12	14537	0	0	0%	23:59:53
14	news.mixmin.net	11715	10	11472	0	0	0%	23:59:54
15	eternal-september	12106	9	11824	0	0	0%	23:59:29
16	weretis.net	13662	8	13306	0	0	0%	23:59:54
17	erje	13779	7	13535	56	0	0%	23:59:34
18	news.swapon.de	1332	7	1322	0	0	0%	23:44:21
19	news.fcku.it	1172	0	1172	0	0	0%	22:40:45
TOTAL: 19		251427	3208	223837	253	148	1%	454:21:57

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.2 MB	140.7 KB	7.3 MB	0.1 KB/s	6.6 KB	23:59:54
2	fu-berlin.de	5.5 MB	0.0 KB	5.5 MB	0.1 KB/s	10.0 KB	23:59:52
3	news.samoylyk.net	5.5 MB	4.2 KB	5.5 MB	0.1 KB/s	10.0 KB	23:59:51
4	news.bbs.nz	1.2 MB	63.8 KB	1.2 MB	0.0 KB/s	2.8 KB	23:59:54
5	news.freedyn.net	615.6 KB	0.0 KB	615.6 KB	0.0 KB/s	12.6 KB	23:59:52
6	tnet	446.1 KB	0.0 KB	446.1 KB	0.0 KB/s	2.7 KB	23:59:42
7	neva.ru	315.7 KB	87.7 KB	403.4 KB	0.0 KB/s	2.6 KB	23:59:54
8	newsfeed.xs4all.nl	123.0 KB	0.0 KB	123.0 KB	0.0 KB/s	2.5 KB	23:59:54
9	xmission	108.7 KB	0.0 KB	108.7 KB	0.0 KB/s	2.9 KB	23:59:41
10	news.uzoreto.com	42.5 KB	269.6 KB	312.1 KB	0.0 KB/s	2.3 KB	23:59:54
11	aioe	37.4 KB	0.0 KB	37.4 KB	0.0 KB/s	2.2 KB	23:59:45
12	usenet.pasdenom.info	36.5 KB	22.9 KB	59.4 KB	0.0 KB/s	2.3 KB	23:59:54
13	neodome.net	27.3 KB	0.0 KB	27.3 KB	0.0 KB/s	2.3 KB	23:59:53
14	news.mixmin.net	25.1 KB	0.0 KB	25.1 KB	0.0 KB/s	2.5 KB	23:59:54
15	eternal-september	23.8 KB	0.0 KB	23.8 KB	0.0 KB/s	2.6 KB	23:59:29
16	weretis.net	17.6 KB	0.0 KB	17.6 KB	0.0 KB/s	2.2 KB	23:59:54
17	erje	12.0 KB	139.5 KB	151.5 KB	0.0 KB/s	2.4 KB	23:59:34
18	news.swapon.de	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	1.6 KB	23:44:21
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:40:45
TOTAL: 19		21.2 MB	728.2 KB	21.9 MB	0.0 KB/s	6.5 KB	454:21:57

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	aorleans-654-1-57-204.w90-20.abo.wanadoo.fr	1	411	939.6 KB	1	0	0	00:05:57
2	172-220-043-074.res.spectrum.com	16	14	26.1 KB	12	7	6	03:01:58
3	ip72-222-200-117.ph.ph.cox.net	17	12	25.1 KB	14	0	0	03:16:24
4	a131033.upc-a.chello.nl	27	6	11.1 KB	4	0	0	05:18:49
5	8.44.58.66	1	4	6.3 KB	1	0	0	00:00:03
TOTAL: 5		62	447	1008.2 KB	32	7	6	35:06:03

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.w90-20.abo.wanadoo.fr	1	411	939.6 KB	1	0	0	00:05:57
2	*.res.spectrum.com	16	14	26.1 KB	12	7	6	03:01:58
3	*.ph.ph.cox.net	17	12	25.1 KB	14	0	0	03:16:24
4	*.upc-a.chello.nl	27	6	11.1 KB	4	0	0	05:18:49
5	unresolved	51	4	6.3 KB	1	0	0	16:04:07
6	*.ch1.censys-scanner.com	1	0	0.0 KB	0	0	0	00:00:00
7	*.net.upcbroadband.cz	21	0	0.0 KB	0	0	0	07:18:45
TOTAL: 7		134	447	1008.2 KB	32	7	6	35:06:03

NNRP auth users [Top 20]:

User	Conn
ohlandl	6
TOTAL: 1	6

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	3.424	0.756	0.000	16:03:53
ip-78-102-198-192.net.upcbroadband.cz	1.298	0.315	0.000	07:18:45
a131033.upc-a.chello.nl	1.977	0.422	0.000	05:18:49
ip72-222-200-117.ph.ph.cox.net	1.161	0.327	0.000	03:16:24
172-220-043-074.res.spectrum.com	1.488	0.307	0.015	03:01:58
aorleans-654-1-57-204.w90-20.abo.wanadoo.fr	0.138	0.086	0.000	00:05:57
192.241.221.158	0.065	0.008	0.000	00:00:09
8.44.58.66	0.112	0.008	0.000	00:00:03
scanner-04.ch1.censys-scanner.com	0.066	0.016	0.000	00:00:00
TOTAL: 9	9.729	2.245	0.015	35:06:03

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	49
ip-78-102-198-192.net.upcbroadband.cz	21
192.241.221.158	1
scanner-04.ch1.censys-scanner.com	1
TOTAL: 4	72

NNRP unrecognized commands (by host):

System	Conn
scanner-04.ch1.censys-scanner.com	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
EHLO www.censys.io	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
172-220-043-074.res.spectrum.com	5	1
ip72-222-200-117.ph.ph.cox.net	4	0
ip-78-102-198-192.net.upcbroadband.cz	2	0
TOTAL: 3	11	1

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	411	91.5%
2	comp	38	8.5%
TOTAL: 2		449	100.0%

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

Newsgroup		Count
1	fr.comp.lang.php	411
2	comp.sys.ibm.ps2.hardware	38
TOTAL: 2		449

Daily Usenet report for csiph.com

Oct 17 04:15:00 -- Oct 18 04:15:00