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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Nov 1 04:12:49 csiph nnrpd[82572]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 1 04:12:49 csiph nnrpd[82572]: 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	18244	40.6%	4.7 MB	56.7%
innd	15198	33.8%	2.1 MB	25.1%
innfeed	10709	23.8%	1.4 MB	17.2%
nnrpd	755	1.7%	85.0 KB	1.0%
nocem	5	0.0%	0.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	44912	100.0%	8.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	302211	56.6%
Negative hits	212004	39.7%
Do not exist	17606	3.3%
Cache misses	1770	0.3%
TOTAL: 4	533591	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.360	0.0%	30	0.000	12.000	31.000
article cleanup	00:00:00.112	0.0%	17481	0.000	0.006	0.036
article logging	00:00:00.377	0.0%	18014	0.000	0.021	0.771
article parse	00:00:00.773	0.0%	51156	0.000	0.015	0.083
article write	00:00:19.458	0.0%	14987	0.167	1.298	65.561
artlog/artcncl	00:00:00.002	0.0%	30	0.000	0.067	0.500
artlog/artparse	00:00:00.004	0.0%	203	0.000	0.020	0.500
data move	00:00:00.160	0.0%	505523	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.041	0.0%	29	0.000	1.414	18.000
hishave/artcncl	00:00:00.000	0.0%	30	0.000	0.000	0.000
hishave/artparse	00:00:00.000	0.0%	199	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:09.798	0.0%	536189	0.001	0.018	0.089
history sync	00:00:00.738	0.0%	342	0.000	2.158	14.000
history write	00:00:23.080	0.0%	17478	0.232	1.321	5.344
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.263	0.0%	199	0.000	1.322	8.500
idle	23:57:56.276	99.8%	510223	91.706	169.095	438.119
nntp read	00:00:02.027	0.0%	512649	0.001	0.004	0.008
overview write	00:00:53.632	0.1%	14987	0.098	3.579	142.610
perl filter	00:00:26.414	0.0%	17473	1.152	1.512	2.509
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.257	0.0%	29979	0.000	0.009	0.027
TOTAL: 24:00:47.203	24:00:13.772	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.034	0.0%	25057	0.000	0.001	0.022
article prepare	00:00:00.003	0.0%	13256	0.000	0.000	0.042
article read	00:00:00.255	0.0%	1365	0.000	0.187	9.400
backlog stats	00:00:00.222	0.0%	348782	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.294	0.0%	273693	0.000	0.005	0.010
data write	00:00:01.623	0.0%	260151	0.002	0.006	0.013
idle	23:54:16.581	100.0%	348782	105.030	246.735	773.960
status file	00:00:02.247	0.0%	3306	0.000	0.680	127.500
TOTAL: 23:54:30.316	23:54:22.259	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	33:56:13.741	99.8%	459	43.000	266173.728	600147.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.063	0.0%	1084	0.000	0.058	0.983
readart	00:00:00.167	0.0%	14	0.000	11.929	42.000
TOTAL: 34:00:59.655	33:56:13.971	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	13925	3392	10481	52	24%	23:30:00
2	news-out.aioe.org	2	13946	3324	10539	83	23%	24:09:01
3	feeder.eternal-september.org	1	16906	2453	14349	104	14%	25:19:05
4	csiph-out.news.xs4all.nl	492	16915	1553	14251	1111	9%	68:24:25
5	newsfeed.fu-berlin.de	10	16460	1033	15368	59	6%	28:32:42
6	csiph-out.news.weretis.net	4	31691	790	30696	205	2%	49:52:15
7	news-out.mixmin.net	1	15493	678	14609	206	4%	23:59:51
8	csiph-out.feeder.erje.net	1440	73606	620	72802	184	0%	118:43:27
9	newsfeed.neva.ru	150	14538	432	13835	271	2%	71:21:27
10	csiph-out.feed.uzoreto.com	864	23883	284	23250	349	1%	71:12:21
11	news-out.neodome.net	2	15697	261	15419	17	1%	23:59:41
12	nntp.pasdenom.info	4	10400	122	10253	25	1%	20:33:02
13	news.fcku.it	14	1214	108	1106	0	8%	21:54:07
14	news.swapon.de	13	1514	56	1455	3	3%	23:16:55
15	csiph-out.news.tnetconsulting.net	1	15511	46	15447	18	0%	23:59:58
16	news.freedyn.net	2	17007	12	16972	23	0%	23:59:55
17	news.samoylyk.net	2	14232	3	14228	1	0%	24:46:10
18	news.bbs.nz	2	14698	1	14697	0	0%	23:59:49
19	localhost	27	1	1	0	0	100%	00:00:02
20	xanadu-bbs.net	29	723	0	722	1	0%	22:19:04
TOTAL: 20		3062	328360	15169	310479	2712	4%	713:53:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	9.8 MB	6.4 KB	3.4 MB	13.3 MB	74%	3.9 KB
2	news-out.aioe.org	7.9 MB	0.0 KB	460.3 KB	8.3 MB	94%	2.5 KB
3	feeder.eternal-september.org	7.6 MB	3.3 KB	882.7 KB	8.4 MB	89%	3.4 KB
4	csiph-out.feeder.erje.net	4.6 MB	0.0 KB	3.3 MB	7.9 MB	58%	10.0 KB
5	csiph-out.news.xs4all.nl	4.1 MB	0.0 KB	4.4 MB	8.5 MB	47%	3.3 KB
6	newsfeed.fu-berlin.de	2.5 MB	0.0 KB	411.4 KB	2.9 MB	86%	2.7 KB
7	csiph-out.news.weretis.net	2.0 MB	1.5 KB	1.4 MB	3.5 MB	58%	3.6 KB
8	news-out.mixmin.net	1.4 MB	0.0 KB	367.2 KB	1.7 MB	79%	2.0 KB
9	newsfeed.neva.ru	1.3 MB	1.4 KB	609.1 KB	1.9 MB	68%	2.8 KB
10	csiph-out.feed.uzoreto.com	1.0 MB	0.0 KB	9.9 MB	11.0 MB	9%	17.8 KB
11	news-out.neodome.net	792.2 KB	1.4 KB	77.8 KB	871.4 KB	90%	3.1 KB
12	nntp.pasdenom.info	269.1 KB	0.0 KB	30.8 KB	299.8 KB	89%	2.0 KB
13	csiph-out.news.tnetconsulting.net	209.9 KB	2.1 KB	145.6 KB	357.6 KB	58%	5.6 KB
14	news.fcku.it	167.5 KB	0.0 KB	0.0 KB	167.5 KB	100%	1.6 KB
15	news.swapon.de	157.6 KB	0.0 KB	167.5 KB	325.1 KB	48%	5.5 KB
16	news.freedyn.net	107.8 KB	1.5 KB	621.8 KB	731.1 KB	14%	20.9 KB
17	news.samoylyk.net	7.1 KB	1.5 KB	1.5 KB	10.1 KB	69%	2.5 KB
18	news.bbs.nz	1.3 KB	0.0 KB	0.0 KB	1.3 KB	100%	1.3 KB
19	localhost	0.9 KB	0.0 KB	0.0 KB	0.9 KB	100%	0.9 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	4.8 KB	4.8 KB	0%	4.8 KB
TOTAL: 20		43.9 MB	19.2 KB	26.2 MB	70.1 MB	62%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 31 04:15:00 - 04:59:59	184	1.2%	0.07	523.6 KB	1.2%	0.19
Oct 31 05:00:00 - 05:59:59	402	2.7%	0.11	980.3 KB	2.2%	0.27
Oct 31 06:00:00 - 06:59:59	516	3.4%	0.14	1.5 MB	3.4%	0.42
Oct 31 07:00:00 - 07:59:59	565	3.8%	0.16	1.6 MB	3.6%	0.45
Oct 31 08:00:00 - 08:59:59	557	3.7%	0.15	2.4 MB	5.6%	0.68
Oct 31 09:00:00 - 09:59:59	587	3.9%	0.16	1.7 MB	4.0%	0.49
Oct 31 10:00:00 - 10:59:59	699	4.7%	0.19	1.8 MB	4.2%	0.52
Oct 31 11:00:00 - 11:59:59	719	4.8%	0.20	2.3 MB	5.2%	0.64
Oct 31 12:00:00 - 12:59:59	657	4.4%	0.18	1.6 MB	3.8%	0.46
Oct 31 13:00:00 - 13:59:59	761	5.1%	0.21	2.1 MB	4.9%	0.61
Oct 31 14:00:00 - 14:59:59	949	6.3%	0.26	2.5 MB	5.9%	0.72
Oct 31 15:00:00 - 15:59:59	834	5.6%	0.23	2.2 MB	5.0%	0.62
Oct 31 16:00:00 - 16:59:59	963	6.4%	0.27	3.0 MB	6.9%	0.85
Oct 31 17:00:00 - 17:59:59	839	5.6%	0.23	2.2 MB	5.1%	0.63
Oct 31 18:00:00 - 18:59:59	884	5.9%	0.25	2.2 MB	5.2%	0.64
Oct 31 19:00:00 - 19:59:59	807	5.4%	0.22	2.2 MB	5.0%	0.62
Oct 31 20:00:00 - 20:59:59	645	4.3%	0.18	1.7 MB	4.0%	0.49
Oct 31 21:00:00 - 21:59:59	720	4.8%	0.20	2.1 MB	4.7%	0.58
Oct 31 22:00:00 - 22:59:59	638	4.3%	0.18	1.9 MB	4.3%	0.53
Oct 31 23:00:00 - 23:59:59	514	3.4%	0.14	1.5 MB	3.4%	0.42
Nov 1 00:00:00 - 00:59:59	508	3.4%	0.14	1.6 MB	3.6%	0.45
Nov 1 01:00:00 - 01:59:59	341	2.3%	0.09	1.2 MB	2.8%	0.34
Nov 1 02:00:00 - 02:59:59	289	1.9%	0.08	1.2 MB	2.8%	0.35
Nov 1 03:00:00 - 03:59:59	327	2.2%	0.09	1.2 MB	2.7%	0.33
Nov 1 04:00:00 - 04:15:00	80	0.5%	0.09	259.2 KB	0.6%	0.29
TOTAL: 24:00:00	14985	100.0%	0.17	43.3 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1142	47	0	0	1095
2	newsfeed.fu-berlin.de	431	23	0	0	408
3	csiph-out.feed.uzoreto.com	349	14	0	1	334
4	newsfeed.neva.ru	271	47	1	0	223
5	csiph-out.news.weretis.net	235	71	1	0	163
6	news-out.mixmin.net	209	1	0	0	208
7	news-out.aioe.org	176	80	0	0	96
8	csiph-out.feeder.erje.net	174	82	0	0	92
9	feeder.eternal-september.org	89	29	1	0	59
10	newsfeeds.xmission.com	46	9	1	0	36
11	news.swapon.de	27	0	0	0	27
12	nntp.pasdenom.info	25	1	0	0	24
13	news.freedyn.net	23	0	1	0	22
14	csiph-out.news.tnetconsulting.net	20	20	0	0	0
15	news-out.neodome.net	10	2	1	0	7
16	news.samoylyk.net	1	0	1	0	0
17	xanadu-bbs.net	1	0	0	0	1
TOTAL: 17		3229	426	7	1	2795

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	57
lada.talk	34
alt.binaries.pictures.purebeauty	30
scinet.stats	29
spline.eisfair	21
alt.tv.comedy-central.daily-show	20
alt.politics	14
mozilla.support.firefox	14
alt.survival	13
it-alt.sport.calcio.juventus	11
mozilla.general	10
att.jobs	10
mozilla.support.thunderbird	9
alt.job	8
alt.bbs.synchronet	7
at.jobs	7
oesterreich.tratsch	7
lada.auto	7
alt.eunuchs	7
alt.binaries.pictures.sierra	6
TOTAL: 79	426

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

Groups	Count
uk.legal.moderated	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1720
EMP (phl)	111
EMP (phn nph)	77
Too many newsgroups	70
0	45
Binary Payload	13
HTML Multipart	9
Binary: misplaced binary	6
HTML post	5
HTML Image Tags	1
Too many newsgroups (meow)	1
TOTAL: 11	2058

INND misc events:

Events	Count
RCreader	1
TOTAL: 1	1

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	140
	csiph-out.feed.uzoreto.com	20
	csiph-out.news.xs4all.nl	20
	TOTAL: 3	180
Huge articles
	csiph-out.feeder.erje.net	61
	csiph-out.feed.uzoreto.com	53
	newsfeeds.xmission.com	36
	csiph-out.news.xs4all.nl	23
	csiph-out.news.weretis.net	9
	feeder.eternal-september.org	5
	news.freedyn.net	5
	newsfeed.fu-berlin.de	4
	news.swapon.de	2
	news-out.mixmin.net	1
	TOTAL: 10	199
Including strange strings
	newsfeed.fu-berlin.de	378
	news-out.aioe.org	88
	csiph-out.news.xs4all.nl	31
	news.swapon.de	24
	csiph-out.news.weretis.net	5
	news-out.mixmin.net	3
	csiph-out.feed.uzoreto.com	2
	feeder.eternal-september.org	1
	newsfeed.neva.ru	1
	TOTAL: 9	533
No colon-space in header
	csiph-out.news.xs4all.nl	3
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	4
TOTAL: 4		916

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18098	862	14110	13	0	4%	23:59:21
2	news.bbs.nz	28188	527	13947	19	0	1%	23:59:54
3	fu-berlin.de	13833	484	13349	0	1	3%	23:59:42
4	news.samoylyk.net	15525	480	14494	8	137	3%	23:59:54
5	tnet	16049	79	14418	0	0	0%	23:59:54
6	neva.ru	13964	56	13684	141	0	0%	23:59:54
7	newsfeed.xs4all.nl	13990	49	13321	1	6	0%	23:59:54
8	xmission	11900	27	10946	0	0	0%	23:59:54
9	news.freedyn.net	14606	10	13772	487	0	0%	23:59:54
10	usenet.pasdenom.info	17789	9	16409	1	7911	0%	23:58:43
11	neodome.net	15079	6	14687	0	0	0%	23:59:50
12	aioe	10971	5	10488	0	0	0%	23:59:34
13	news.mixmin.net	13840	4	13500	0	0	0%	23:59:17
14	eternal-september	12448	3	12041	0	0	0%	23:59:54
15	weretis.net	13932	3	13671	0	6	0%	23:59:54
16	news.uzoreto.com	14623	2	14328	20	0	0%	23:59:54
17	news.swapon.de	1502	1	1486	0	0	0%	23:33:36
18	erje	14416	1	14257	24	0	0%	23:59:50
19	news.fcku.it	1041	0	1041	0	0	0%	21:55:37
TOTAL: 19		261794	2608	233949	714	8061	0%	453:24:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.2 MB	52.0 KB	5.2 MB	0.1 KB/s	6.1 KB	23:59:21
2	fu-berlin.de	4.1 MB	0.0 KB	4.1 MB	0.0 KB/s	8.7 KB	23:59:42
3	news.samoylyk.net	4.1 MB	56.2 KB	4.1 MB	0.0 KB/s	8.7 KB	23:59:54
4	news.bbs.nz	1.3 MB	59.7 KB	1.3 MB	0.0 KB/s	2.5 KB	23:59:54
5	tnet	194.7 KB	0.0 KB	194.7 KB	0.0 KB/s	2.5 KB	23:59:54
6	newsfeed.xs4all.nl	181.6 KB	4.8 KB	186.4 KB	0.0 KB/s	3.7 KB	23:59:54
7	neva.ru	121.5 KB	331.6 KB	453.1 KB	0.0 KB/s	2.3 KB	23:59:54
8	xmission	82.7 KB	0.0 KB	82.7 KB	0.0 KB/s	3.1 KB	23:59:54
9	news.freedyn.net	64.3 KB	4.1 MB	4.2 MB	0.0 KB/s	8.6 KB	23:59:54
10	usenet.pasdenom.info	52.9 KB	0.6 KB	53.5 KB	0.0 KB/s	5.4 KB	23:58:43
11	news.mixmin.net	42.4 KB	0.0 KB	42.4 KB	0.0 KB/s	10.6 KB	23:59:17
12	neodome.net	11.3 KB	0.0 KB	11.3 KB	0.0 KB/s	1.9 KB	23:59:50
13	aioe	7.4 KB	0.0 KB	7.4 KB	0.0 KB/s	1.5 KB	23:59:34
14	eternal-september	5.1 KB	0.0 KB	5.1 KB	0.0 KB/s	1.7 KB	23:59:54
15	weretis.net	3.8 KB	0.0 KB	3.8 KB	0.0 KB/s	1.3 KB	23:59:54
16	news.uzoreto.com	2.1 KB	96.9 KB	98.9 KB	0.0 KB/s	4.5 KB	23:59:54
17	news.swapon.de	0.9 KB	0.0 KB	0.9 KB	0.0 KB/s	0.9 KB	23:33:36
18	erje	0.9 KB	131.4 KB	132.3 KB	0.0 KB/s	5.3 KB	23:59:50
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:55:37
TOTAL: 19		15.4 MB	4.9 MB	20.3 MB	0.0 KB/s	6.2 KB	453:24:30

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	071-013-251-224.res.spectrum.com	13	5	8.1 KB	3	1	1	04:37:04
2	a131033.upc-a.chello.nl	14	5	8.1 KB	4	0	0	03:23:23
3	ppp-2-87-178-169.home.otenet.gr	1	4	5.4 KB	1	0	0	00:00:03
TOTAL: 3		28	14	21.6 KB	8	1	1	34:00:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	13	5	8.1 KB	3	1	1	04:37:04
2	*.upc-a.chello.nl	14	5	8.1 KB	4	0	0	03:23:23
3	*.home.otenet.gr	1	4	5.4 KB	1	0	0	00:00:03
4	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	0	0	00:00:16
5	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:13
6	*.ch1.censys-scanner.com	2	0	0.0 KB	0	0	0	00:00:00
7	unresolved	75	0	0.0 KB	0	0	0	23:59:56
TOTAL: 7		110	14	21.6 KB	8	1	1	34:00:58

NNRP auth users [Top 20]:

User	Conn
ohlandl	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.719	0.990	0.000	23:59:45
071-013-251-224.res.spectrum.com	0.951	0.216	0.003	04:37:04
a131033.upc-a.chello.nl	1.099	0.215	0.000	03:23:23
ip72-222-200-117.ph.ph.cox.net	0.428	0.065	0.000	02:00:13
34.20.97.83.ro.ovo.sc	0.070	0.008	0.000	00:00:16
45.227.255.233	0.060	0.015	0.000	00:00:10
ppp-2-87-178-169.home.otenet.gr	0.110	0.008	0.000	00:00:03
94.232.44.38	0.112	0.041	0.000	00:00:00
scanner-05.ch1.censys-scanner.com	0.140	0.023	0.000	00:00:00
TOTAL: 9	7.689	1.581	0.003	34:00:58

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
ip72-222-200-117.ph.ph.cox.net	4
scanner-05.ch1.censys-scanner.com	2
94.232.44.38	2
34.20.97.83.ro.ovo.sc	1
45.227.255.233	1
TOTAL: 6	82

NNRP unrecognized commands (by host):

System	Conn
94.232.44.38	2
scanner-05.ch1.censys-scanner.com	1
TOTAL: 2	3

NNRP unrecognized commands (by command):

Command	Count
^C	2
EHLO www.censys.io	1
TOTAL: 2	3

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-251-224.res.spectrum.com	6	0
ip72-222-200-117.ph.ph.cox.net	4	0
94.232.44.38	2	2
TOTAL: 3	12	3

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Oct 31 04:15:00 -- Nov 1 04:15:00