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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 1 12:32:07 csiph nnrpd[86582]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Oct 1 12:32:07 csiph nnrpd[86582]: 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	20570	50.3%	5.2 MB	64.9%
innd	15846	38.8%	2.1 MB	25.8%
innfeed	3456	8.5%	649.9 KB	7.9%
nnrpd	940	2.3%	101.8 KB	1.2%
nocem	46	0.1%	5.3 KB	0.1%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	40859	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	334656	56.4%
Negative hits	238723	40.2%
Do not exist	19835	3.3%
Cache misses	324	0.1%
TOTAL: 4	593538	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.088	0.0%	44	0.000	24.727	106.000
article cleanup	00:00:00.141	0.0%	19488	0.000	0.007	0.034
article logging	00:00:00.960	0.0%	20063	0.000	0.048	0.983
article parse	00:00:05.601	0.0%	61621	0.000	0.091	0.497
article write	00:07:10.461	0.5%	16081	1.441	26.768	96.545
artlog/artcncl	00:00:00.000	0.0%	44	0.000	0.000	0.000
artlog/artparse	00:00:00.031	0.0%	463	0.000	0.067	1.538
data move	00:00:00.224	0.0%	565497	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.088	0.0%	41	0.000	2.146	12.000
hishave/artcncl	00:00:00.324	0.0%	44	0.000	7.364	25.000
hishave/artparse	00:00:00.003	0.0%	454	0.000	0.007	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:19.704	0.2%	596552	0.002	0.335	0.718
history sync	00:00:02.907	0.0%	346	0.000	8.402	206.000
history write	00:03:25.918	0.2%	19497	2.792	10.562	23.458
hiswrite/artcncl	00:00:00.001	0.0%	3	0.333	0.333	0.333
hiswrite/artparse	00:00:04.943	0.0%	453	0.000	10.912	101.500
idle	23:35:59.282	98.3%	518689	99.406	163.796	572.219
nntp read	00:00:02.326	0.0%	572555	0.002	0.004	0.008
overview write	00:09:21.723	0.6%	16081	1.705	34.931	501.188
perl filter	00:00:30.038	0.0%	19487	1.159	1.541	2.490
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.311	0.0%	32207	0.000	0.010	0.029
TOTAL: 24:00:51.886	24:00:06.074	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.049	0.0%	16976	0.000	0.003	0.033
article prepare	00:00:00.002	0.0%	9016	0.000	0.000	0.008
article read	00:00:02.219	0.0%	977	0.000	2.271	36.333
backlog stats	00:00:00.199	0.0%	363969	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.503	0.0%	296192	0.000	0.005	0.011
data write	00:00:01.565	0.0%	274080	0.002	0.006	0.011
idle	23:55:08.786	100.0%	363969	137.105	236.583	755.251
status file	00:00:01.099	0.0%	239	0.000	4.598	88.000
TOTAL: 23:55:26.437	23:55:15.422	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	41:25:15.327	99.8%	615	63.000	242463.946	600117.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:26.634	0.0%	1404	0.000	18.970	605.023
readart	00:00:00.283	0.0%	47	0.000	6.021	42.000
TOTAL: 41:29:30.854	41:25:42.244	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	3	15359	3815	11495	49	24%	23:42:57
2	csiph-out.news.xs4all.nl	424	19346	2985	14425	1936	15%	68:50:37
3	feeder.eternal-september.org	6	18334	2213	16039	82	12%	24:34:43
4	news-out.mixmin.net	2	17149	2125	14860	164	12%	24:08:50
5	news-out.aioe.org	1	15751	1157	14510	84	7%	23:59:55
6	newsfeed.fu-berlin.de	10	15784	841	14731	212	5%	24:54:18
7	csiph-out.news.weretis.net	3	45023	808	43922	293	1%	62:26:11
8	newsfeed.neva.ru	148	16254	628	15251	375	3%	71:29:36
9	csiph-out.feeder.erje.net	1440	79969	612	79160	197	0%	118:41:17
10	news.fcku.it	12	1895	310	1572	13	16%	21:47:13
11	csiph-out.feed.uzoreto.com	864	25343	163	24876	304	0%	71:07:17
12	news-out.neodome.net	2	17717	131	17582	4	0%	23:59:52
13	nntp.pasdenom.info	1	10076	121	9924	31	1%	23:59:56
14	news.swapon.de	5	1358	55	1303	0	4%	24:47:19
15	news.freedyn.net	2	19604	39	19537	28	0%	23:59:44
16	csiph-out.news.tnetconsulting.net	1	15297	25	15257	15	0%	23:59:57
17	news.samoylyk.net	4	15716	12	15703	1	0%	25:04:22
18	localhost	33	7	7	0	0	100%	00:00:00
19	news.bbs.nz	2	14958	6	14952	0	0%	23:59:50
20	xanadu-bbs.net	21	473	1	468	4	0%	21:32:24
TOTAL: 20		2984	365413	16054	345567	3792	4%	727:06:18

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	12.6 MB	0.0 KB	2.7 MB	15.4 MB	82%	4.1 KB
2	csiph-out.news.xs4all.nl	7.7 MB	0.0 KB	18.5 MB	26.3 MB	29%	5.5 KB
3	feeder.eternal-september.org	6.2 MB	0.0 KB	1.2 MB	7.4 MB	83%	3.3 KB
4	news-out.aioe.org	4.5 MB	0.0 KB	264.8 KB	4.7 MB	94%	3.9 KB
5	csiph-out.feeder.erje.net	4.2 MB	0.0 KB	4.6 MB	8.8 MB	47%	11.2 KB
6	news-out.mixmin.net	4.2 MB	0.0 KB	371.6 KB	4.6 MB	92%	2.0 KB
7	csiph-out.news.weretis.net	2.5 MB	0.0 KB	1.1 MB	3.5 MB	70%	3.3 KB
8	newsfeed.fu-berlin.de	2.0 MB	0.0 KB	463.0 KB	2.5 MB	81%	2.4 KB
9	newsfeed.neva.ru	1.6 MB	0.0 KB	1.2 MB	2.7 MB	56%	2.8 KB
10	csiph-out.feed.uzoreto.com	985.6 KB	0.0 KB	9.3 MB	10.3 MB	9%	22.5 KB
11	news.fcku.it	512.0 KB	0.0 KB	15.8 KB	527.8 KB	97%	1.6 KB
12	news-out.neodome.net	417.2 KB	0.0 KB	7.7 KB	424.9 KB	98%	3.1 KB
13	nntp.pasdenom.info	292.6 KB	0.0 KB	39.5 KB	332.1 KB	88%	2.2 KB
14	news.freedyn.net	211.2 KB	0.0 KB	681.0 KB	892.2 KB	23%	13.3 KB
15	csiph-out.news.tnetconsulting.net	134.4 KB	1.8 KB	88.0 KB	224.2 KB	59%	5.6 KB
16	news.swapon.de	129.1 KB	0.0 KB	0.0 KB	129.1 KB	100%	2.3 KB
17	news.samoylyk.net	34.0 KB	0.0 KB	2.2 KB	36.2 KB	93%	2.8 KB
18	localhost	14.4 KB	0.0 KB	0.0 KB	14.4 KB	100%	2.1 KB
19	news.bbs.nz	8.1 KB	0.0 KB	0.0 KB	8.1 KB	100%	1.3 KB
20	xanadu-bbs.net	2.0 KB	0.0 KB	7.5 KB	9.4 KB	20%	1.9 KB
TOTAL: 20		48.2 MB	1.8 KB	40.5 MB	88.8 MB	54%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 04:15:00 - 04:59:59	294	1.8%	0.11	1.3 MB	2.6%	0.48
Oct 1 05:00:00 - 05:59:59	366	2.3%	0.10	1.3 MB	2.6%	0.36
Oct 1 06:00:00 - 06:59:59	432	2.7%	0.12	1.4 MB	2.9%	0.39
Oct 1 07:00:00 - 07:59:59	572	3.6%	0.16	1.5 MB	3.2%	0.44
Oct 1 08:00:00 - 08:59:59	676	4.2%	0.19	1.9 MB	3.9%	0.53
Oct 1 09:00:00 - 09:59:59	819	5.1%	0.23	2.0 MB	4.2%	0.58
Oct 1 10:00:00 - 10:59:59	828	5.1%	0.23	2.5 MB	5.2%	0.72
Oct 1 11:00:00 - 11:59:59	749	4.7%	0.21	2.2 MB	4.6%	0.63
Oct 1 12:00:00 - 12:59:59	820	5.1%	0.23	2.4 MB	5.0%	0.68
Oct 1 13:00:00 - 13:59:59	905	5.6%	0.25	2.5 MB	5.3%	0.72
Oct 1 14:00:00 - 14:59:59	923	5.7%	0.26	3.3 MB	6.8%	0.93
Oct 1 15:00:00 - 15:59:59	909	5.6%	0.25	3.0 MB	6.1%	0.84
Oct 1 16:00:00 - 16:59:59	953	5.9%	0.26	2.7 MB	5.5%	0.76
Oct 1 17:00:00 - 17:59:59	871	5.4%	0.24	2.4 MB	4.9%	0.67
Oct 1 18:00:00 - 18:59:59	889	5.5%	0.25	2.4 MB	5.0%	0.68
Oct 1 19:00:00 - 19:59:59	910	5.7%	0.25	2.3 MB	4.7%	0.64
Oct 1 20:00:00 - 20:59:59	866	5.4%	0.24	2.3 MB	4.8%	0.66
Oct 1 21:00:00 - 21:59:59	732	4.5%	0.20	2.2 MB	4.5%	0.62
Oct 1 22:00:00 - 22:59:59	718	4.5%	0.20	2.3 MB	4.8%	0.65
Oct 1 23:00:00 - 23:59:59	457	2.8%	0.13	1.6 MB	3.3%	0.45
Oct 2 00:00:00 - 00:59:59	298	1.9%	0.08	1.2 MB	2.4%	0.33
Oct 2 01:00:00 - 01:59:59	346	2.2%	0.10	1.4 MB	2.8%	0.39
Oct 2 02:00:00 - 02:59:59	346	2.2%	0.10	1.1 MB	2.4%	0.33
Oct 2 03:00:00 - 03:59:59	309	1.9%	0.09	1.1 MB	2.2%	0.30
Oct 2 04:00:00 - 04:15:00	101	0.6%	0.11	220.7 KB	0.4%	0.25
TOTAL: 24:00:00	16089	100.0%	0.19	48.3 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	1980	112	1868
2	newsfeed.fu-berlin.de	593	17	576
3	newsfeed.neva.ru	382	61	321
4	csiph-out.news.weretis.net	362	51	311
5	csiph-out.feed.uzoreto.com	307	8	299
6	news-out.mixmin.net	231	5	226
7	csiph-out.feeder.erje.net	196	54	142
8	news-out.aioe.org	111	74	37
9	feeder.eternal-september.org	85	38	47
10	newsfeeds.xmission.com	53	9	44
11	csiph-out.news.tnetconsulting.net	33	29	4
12	news.freedyn.net	32	0	32
13	nntp.pasdenom.info	31	0	31
14	news.swapon.de	18	0	18
15	news.fcku.it	13	13	0
16	news-out.neodome.net	5	0	5
17	xanadu-bbs.net	4	0	4
18	news.samoylyk.net	2	0	2
TOTAL: 18		4438	471	3967

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.bbs.synchronet	67
lada.talk	52
it-alt.sport.calcio.juventus	39
alt.computer.workshop	34
scinet.stats	25
alt.politics	22
alt.politics.usa	18
spline.eisfair	16
mozilla.support.thunderbird	16
alt.binaries.pictures.purebeauty	13
alt.survival	13
alt.windows7.general	12
free.it.religioni.scientology	11
mozilla.support.firefox	9
alt.philosophy	8
mozilla.support.seamonkey	8
alt.tv.comedy-central.daily-show	7
mozilla.support.bugzilla	7
mozilla.general	7
alt.job	6
TOTAL: 72	471

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1990
EMP (phn path)	471
EMP (phl)	194
Too many newsgroups	104
0	90
EMP (phn nph)	42
Scoring filter	18
HTML Multipart	14
Binary: misplaced binary	7
Too many newsgroups (meow)	3
TOTAL: 10	2933

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	60
	csiph-out.news.xs4all.nl	3
	csiph-out.feed.uzoreto.com	3
	TOTAL: 3	66
Huge articles
	csiph-out.news.xs4all.nl	202
	csiph-out.feed.uzoreto.com	107
	csiph-out.feeder.erje.net	80
	newsfeeds.xmission.com	37
	news.freedyn.net	11
	csiph-out.news.weretis.net	6
	feeder.eternal-september.org	4
	csiph-out.news.tnetconsulting.net	2
	news-out.mixmin.net	1
	TOTAL: 9	450
Including strange strings
	newsfeed.fu-berlin.de	394
	news-out.mixmin.net	65
	csiph-out.news.xs4all.nl	47
	news-out.aioe.org	21
	news.swapon.de	18
	csiph-out.news.weretis.net	14
	newsfeed.neva.ru	4
	csiph-out.feed.uzoreto.com	3
	newsfeeds.xmission.com	3
	csiph-out.feeder.erje.net	2
	TOTAL: 13	574
No colon-space in header
	csiph-out.news.xs4all.nl	8
	csiph-out.feeder.erje.net	1
	TOTAL: 2	9
TOTAL: 4		1099

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18512	661	15396	29	108	3%	23:59:51
2	fu-berlin.de	15024	384	14640	0	0	2%	23:59:51
3	news.samoylyk.net	16663	377	15682	9	530	2%	23:58:39
4	news.bbs.nz	29132	290	15386	19	1	0%	23:59:50
5	tnet	16742	82	15623	1	0	0%	23:59:51
6	neva.ru	14872	77	14521	39	0	0%	23:59:51
7	news.freedyn.net	15648	43	15351	0	0	0%	23:59:51
8	newsfeed.xs4all.nl	13418	36	12991	1	0	0%	23:59:51
9	usenet.pasdenom.info	16094	17	15551	2	0	0%	23:59:51
10	aioe	11858	17	11302	0	0	0%	23:59:51
11	news.mixmin.net	13713	15	13415	0	0	0%	23:59:51
12	neodome.net	16297	13	15921	0	0	0%	23:59:50
13	xmission	13668	12	11683	0	0	0%	23:59:42
14	erje	15491	8	15283	100	0	0%	23:59:13
15	news.swapon.de	1392	7	1372	0	0	0%	23:33:04
16	weretis.net	15061	7	14904	0	0	0%	23:59:51
17	eternal-september	13631	6	13350	0	294	0%	23:59:51
18	news.uzoreto.com	15180	2	14916	5	0	0%	23:59:51
19	news.fcku.it	1464	0	1464	0	0	0%	20:54:23
TOTAL: 19		273860	2054	248751	205	933	0%	452:22:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.4 MB	79.9 KB	4.5 MB	0.1 KB/s	6.7 KB	23:59:51
2	news.samoylyk.net	3.7 MB	10.5 KB	3.7 MB	0.0 KB/s	9.7 KB	23:58:39
3	fu-berlin.de	3.6 MB	0.0 KB	3.6 MB	0.0 KB/s	9.6 KB	23:59:51
4	news.bbs.nz	701.0 KB	139.3 KB	840.3 KB	0.0 KB/s	2.7 KB	23:59:50
5	news.freedyn.net	593.9 KB	0.0 KB	593.9 KB	0.0 KB/s	13.8 KB	23:59:51
6	tnet	203.1 KB	1.0 KB	204.1 KB	0.0 KB/s	2.5 KB	23:59:51
7	neva.ru	184.4 KB	79.1 KB	263.5 KB	0.0 KB/s	2.3 KB	23:59:51
8	newsfeed.xs4all.nl	65.8 KB	9.5 KB	75.3 KB	0.0 KB/s	2.0 KB	23:59:51
9	aioe	46.3 KB	0.0 KB	46.3 KB	0.0 KB/s	2.7 KB	23:59:51
10	usenet.pasdenom.info	32.4 KB	5.2 KB	37.6 KB	0.0 KB/s	2.0 KB	23:59:51
11	news.mixmin.net	26.7 KB	0.0 KB	26.7 KB	0.0 KB/s	1.8 KB	23:59:51
12	neodome.net	24.8 KB	0.0 KB	24.8 KB	0.0 KB/s	1.9 KB	23:59:50
13	xmission	23.5 KB	0.0 KB	23.5 KB	0.0 KB/s	2.0 KB	23:59:42
14	erje	18.7 KB	283.9 KB	302.6 KB	0.0 KB/s	2.8 KB	23:59:13
15	news.swapon.de	14.4 KB	0.0 KB	14.4 KB	0.0 KB/s	2.1 KB	23:33:04
16	weretis.net	14.4 KB	0.0 KB	14.4 KB	0.0 KB/s	2.1 KB	23:59:51
17	eternal-september	12.6 KB	0.0 KB	12.6 KB	0.0 KB/s	2.1 KB	23:59:51
18	news.uzoreto.com	2.9 KB	11.5 KB	14.4 KB	0.0 KB/s	2.1 KB	23:59:51
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:54:23
TOTAL: 19		13.6 MB	620.0 KB	14.2 MB	0.0 KB/s	6.4 KB	452:22:53

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ppp-2-84-172-192.home.otenet.gr	2	19	41.2 KB	1	0	0	00:00:15
2	096-042-018-108.res.spectrum.com	25	17	32.5 KB	13	7	7	07:02:24
3	a131033.upc-a.chello.nl	32	8	21.3 KB	2	0	0	09:05:43
4	p2e5b5f8f.dip0.t-ipconnect.de	1	3	4.8 KB	2	0	0	00:00:38
TOTAL: 4		60	47	99.8 KB	18	7	7	41:29:29

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.home.otenet.gr	2	19	41.2 KB	1	0	0	00:00:15
2	*.res.spectrum.com	25	17	32.5 KB	13	7	7	07:02:24
3	*.upc-a.chello.nl	32	8	21.3 KB	2	0	0	09:05:43
4	*.dip0.t-ipconnect.de	1	3	4.8 KB	2	0	0	00:00:38
5	*.ph.ph.cox.net	5	0	0.0 KB	0	0	0	02:15:43
6	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:01
7	*.net.upcbroadband.cz	18	0	0.0 KB	0	0	0	05:57:05
8	unresolved	55	0	0.0 KB	0	0	0	17:07:37
TOTAL: 8		139	47	99.8 KB	18	7	7	41:29:29

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
TOTAL: 1	7

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	3.602	0.792	0.000	17:07:26
a131033.upc-a.chello.nl	2.201	0.412	0.000	09:05:43
096-042-018-108.res.spectrum.com	2.007	0.386	0.018	07:02:24
ip-78-102-198-192.net.upcbroadband.cz	1.110	0.309	0.000	05:57:05
ip72-222-200-117.ph.ph.cox.net	0.403	0.085	0.000	02:15:43
p2e5b5f8f.dip0.t-ipconnect.de	0.108	0.085	0.000	00:00:38
ppp-2-84-172-192.home.otenet.gr	0.182	0.016	0.000	00:00:15
192.241.219.35	0.057	0.021	0.000	00:00:09
192.35.168.193	0.114	0.043	0.000	00:00:01
scan-141.security.ipip.net	0.065	0.014	0.000	00:00:01
TOTAL: 10	9.849	2.163	0.018	41:29:29

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	52
ip-78-102-198-192.net.upcbroadband.cz	18
ip72-222-200-117.ph.ph.cox.net	5
192.35.168.193	2
192.241.219.35	1
scan-141.security.ipip.net	1
TOTAL: 6	79

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
192.35.168.193	1
TOTAL: 2	5

NNRP unrecognized commands (by command):

Command	Count
Host: 69.160.37.58:119	1
User-Agent: HTTP Banner Detection (https...	1
EHLO www.censys.io	1
GET / HTTP/1.1	1
Connection: close	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
096-042-018-108.res.spectrum.com	11	0
ip72-222-200-117.ph.ph.cox.net	3	0
TOTAL: 2	14	1

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	44	93.6%
2	alt	2	4.3%
3	de	1	2.1%
TOTAL: 3		47	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	44
2	alt.free.newsservers	2
3	de.sci.electronics	1
TOTAL: 3		47

Daily Usenet report for csiph.com

Oct 1 04:15:00 -- Oct 2 04:15:00