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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 17 09:57:15 csiph nnrpd[83817]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 17 09:57:15 csiph nnrpd[83817]: 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	19022	48.1%	4.8 MB	62.9%
innd	16253	41.1%	2.2 MB	28.5%
innfeed	3558	9.0%	589.4 KB	7.6%
nnrpd	691	1.7%	77.9 KB	1.0%
nocem	30	0.1%	3.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	39555	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	299585	51.0%
Negative hits	234926	40.0%
Cache misses	34397	5.9%
Do not exist	18433	3.1%
TOTAL: 4	587341	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.215	0.0%	27	0.000	7.963	39.200
article cleanup	00:00:00.117	0.0%	17918	0.000	0.007	0.043
article logging	00:00:00.273	0.0%	18436	0.000	0.015	0.086
article parse	00:00:00.958	0.0%	70623	0.000	0.014	0.047
article write	00:00:06.652	0.0%	14904	0.125	0.446	11.955
artlog/artcncl	00:00:00.001	0.0%	27	0.000	0.037	1.000
artlog/artparse	00:00:00.009	0.0%	566	0.000	0.016	0.500
data move	00:00:00.201	0.0%	557381	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.005	0.0%	27	0.000	0.185	0.400
hishave/artcncl	00:00:00.001	0.0%	27	0.000	0.037	0.200
hishave/artparse	00:00:00.001	0.0%	547	0.000	0.002	0.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.670	0.0%	588349	0.001	0.010	0.118
history sync	00:00:01.325	0.0%	366	0.000	3.620	20.000
history write	00:00:13.532	0.0%	17937	0.023	0.754	4.345
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.310	0.0%	547	0.000	0.567	9.000
idle	23:59:04.820	99.9%	564353	50.300	152.998	649.517
nntp read	00:00:02.187	0.0%	564430	0.002	0.004	0.009
overview write	00:00:18.536	0.0%	14904	0.065	1.244	28.031
perl filter	00:00:25.472	0.0%	17918	1.020	1.422	2.097
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.204	0.0%	29838	0.000	0.007	0.024
TOTAL: 24:00:56.527	24:00:20.489	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.036	0.0%	15009	0.000	0.002	0.049
article prepare	00:00:00.000	0.0%	11612	0.000	0.000	0.000
article read	00:00:00.252	0.0%	1427	0.000	0.177	44.000
backlog stats	00:00:00.182	0.0%	331561	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.199	0.0%	259039	0.000	0.005	0.009
data write	00:00:01.527	0.0%	247393	0.001	0.006	0.016
idle	23:54:09.802	100.0%	331561	137.135	259.529	1217.446
status file	00:00:00.223	0.0%	300	0.000	0.743	3.500
TOTAL: 23:54:21.731	23:54:13.221	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	37:11:01.189	99.8%	369	1452.400	362767.450	600176.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.007	0.0%	841	0.000	0.008	0.167
readart	00:00:00.125	0.0%	5	13.667	25.000	42.000
TOTAL: 37:15:46.653	37:11:01.321	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	3	14190	3209	10870	111	22%	24:09:02
2	newsfeeds.xmission.com	2	13508	2354	11102	52	17%	22:03:08
3	feeder.eternal-september.org	13	5682	2130	3511	41	37%	27:21:49
4	csiph-out.news.xs4all.nl	566	32865	2065	29389	1411	6%	67:19:19
5	news-out.mixmin.net	1	16073	861	14969	243	5%	23:59:50
6	csiph-out.feeder.erje.net	1439	73915	786	72809	320	1%	119:43:37
7	newsfeed.fu-berlin.de	19	12485	775	11487	223	6%	22:21:05
8	csiph-out.news.weretis.net	4	43638	765	42511	362	1%	64:45:56
9	newsfeed.neva.ru	149	14296	587	13475	234	4%	71:51:23
10	news.fcku.it	13	1666	311	1352	3	18%	21:38:12
11	csiph-out.feed.uzoreto.com	864	44090	278	43241	571	0%	71:12:12
12	news-out.neodome.net	2	16383	245	16117	21	1%	23:59:47
13	nntp.pasdenom.info	4	10160	141	10013	6	1%	23:57:06
14	news.freedyn.net	2	17551	70	17378	103	0%	23:59:57
15	csiph-out.news.tnetconsulting.net	2	14766	51	14679	36	0%	23:59:46
16	news.swapon.de	4	1425	45	1380	0	3%	22:35:25
17	news.samoylyk.net	2	14543	3	14540	0	0%	24:57:27
18	news.bbs.nz	1	14459	1	14458	0	0%	23:59:52
19	localhost	26	0	0	0	0	0%	00:00:02
20	xanadu-bbs.net	30	437	0	437	0	0%	21:26:48
TOTAL: 20		3146	362132	14677	343718	3737	4%	725:21:43

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	7.9 MB	461.9 KB	8.3 MB	94%	2.6 KB
2	newsfeeds.xmission.com	7.3 MB	2.5 MB	9.8 MB	74%	4.2 KB
3	csiph-out.feeder.erje.net	6.8 MB	2.3 MB	9.1 MB	74%	8.5 KB
4	feeder.eternal-september.org	5.7 MB	837.0 KB	6.5 MB	87%	3.1 KB
5	csiph-out.news.xs4all.nl	5.2 MB	7.7 MB	12.9 MB	40%	3.8 KB
6	news-out.mixmin.net	2.3 MB	413.1 KB	2.7 MB	84%	2.5 KB
7	csiph-out.news.weretis.net	2.1 MB	4.7 MB	6.8 MB	30%	6.2 KB
8	newsfeed.fu-berlin.de	1.7 MB	662.6 KB	2.4 MB	72%	2.4 KB
9	newsfeed.neva.ru	1.3 MB	1.4 MB	2.7 MB	49%	3.4 KB
10	csiph-out.feed.uzoreto.com	1.2 MB	50.6 MB	51.8 MB	2%	62.4 KB
11	news-out.neodome.net	897.8 KB	88.7 KB	986.5 KB	91%	3.7 KB
12	news.fcku.it	561.2 KB	5.1 KB	566.3 KB	99%	1.8 KB
13	nntp.pasdenom.info	328.9 KB	13.1 KB	342.0 KB	96%	2.3 KB
14	news.freedyn.net	244.6 KB	2.9 MB	3.1 MB	7%	18.4 KB
15	csiph-out.news.tnetconsulting.net	175.2 KB	113.0 KB	288.2 KB	60%	3.3 KB
16	news.swapon.de	101.1 KB	0.0 KB	101.1 KB	100%	2.2 KB
17	news.samoylyk.net	39.7 KB	0.0 KB	39.7 KB	100%	13.2 KB
18	news.bbs.nz	1.1 KB	0.0 KB	1.1 KB	100%	1.1 KB
19	localhost	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
TOTAL: 20		43.7 MB	74.6 MB	118.4 MB	36%	6.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 17 04:15:00 - 04:59:59	163	1.1%	0.06	402.8 KB	0.9%	0.15
Dec 17 05:00:00 - 05:59:59	295	2.0%	0.08	2.0 MB	4.5%	0.57
Dec 17 06:00:00 - 06:59:59	335	2.2%	0.09	1.3 MB	2.8%	0.36
Dec 17 07:00:00 - 07:59:59	456	3.1%	0.13	1.2 MB	2.7%	0.34
Dec 17 08:00:00 - 08:59:59	582	3.9%	0.16	1.5 MB	3.4%	0.43
Dec 17 09:00:00 - 09:59:59	725	4.9%	0.20	1.9 MB	4.2%	0.53
Dec 17 10:00:00 - 10:59:59	719	4.8%	0.20	1.9 MB	4.3%	0.54
Dec 17 11:00:00 - 11:59:59	734	4.9%	0.20	1.9 MB	4.2%	0.53
Dec 17 12:00:00 - 12:59:59	679	4.6%	0.19	1.9 MB	4.2%	0.53
Dec 17 13:00:00 - 13:59:59	768	5.2%	0.21	1.9 MB	4.4%	0.55
Dec 17 14:00:00 - 14:59:59	816	5.5%	0.23	2.4 MB	5.5%	0.69
Dec 17 15:00:00 - 15:59:59	934	6.3%	0.26	3.5 MB	7.9%	1.00
Dec 17 16:00:00 - 16:59:59	984	6.6%	0.27	2.9 MB	6.5%	0.82
Dec 17 17:00:00 - 17:59:59	792	5.3%	0.22	1.9 MB	4.4%	0.55
Dec 17 18:00:00 - 18:59:59	946	6.3%	0.26	2.4 MB	5.4%	0.68
Dec 17 19:00:00 - 19:59:59	949	6.4%	0.26	3.1 MB	6.9%	0.87
Dec 17 20:00:00 - 20:59:59	762	5.1%	0.21	2.4 MB	5.4%	0.68
Dec 17 21:00:00 - 21:59:59	666	4.5%	0.18	1.8 MB	4.1%	0.52
Dec 17 22:00:00 - 22:59:59	594	4.0%	0.17	1.8 MB	4.1%	0.52
Dec 17 23:00:00 - 23:59:59	542	3.6%	0.15	1.8 MB	4.1%	0.52
Dec 18 00:00:00 - 00:59:59	369	2.5%	0.10	1.2 MB	2.6%	0.33
Dec 18 01:00:00 - 01:59:59	366	2.5%	0.10	1.1 MB	2.5%	0.31
Dec 18 02:00:00 - 02:59:59	335	2.2%	0.09	1.1 MB	2.5%	0.31
Dec 18 03:00:00 - 03:59:59	306	2.1%	0.09	918.5 KB	2.0%	0.26
Dec 18 04:00:00 - 04:15:00	89	0.6%	0.10	226.1 KB	0.5%	0.25
TOTAL: 24:00:00	14906	100.0%	0.17	44.5 MB	100.0%	0.53

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	1434	45	1389
2	csiph-out.feed.uzoreto.com	575	31	544
3	newsfeed.fu-berlin.de	570	27	543
4	csiph-out.news.weretis.net	356	77	279
5	news-out.mixmin.net	253	1	252
6	newsfeed.neva.ru	234	61	173
7	news-out.aioe.org	213	102	111
8	csiph-out.feeder.erje.net	144	40	104
9	news.freedyn.net	106	0	106
10	newsfeeds.xmission.com	57	6	51
11	feeder.eternal-september.org	45	19	26
12	csiph-out.news.tnetconsulting.net	41	39	2
13	news.swapon.de	31	0	31
14	news-out.neodome.net	21	3	18
15	nntp.pasdenom.info	6	0	6
16	news.fcku.it	3	2	1
TOTAL: 16		4089	453	3636

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	136
lada.talk	40
alt.tv.comedy-central.daily-show	31
it-alt.sport.calcio.juventus	26
alt.bbs.synchronet	18
spline.eisfair	16
scinet.stats	15
alt.survival	15
alt.binaries.pictures.purebeauty	14
alt.politics	11
aus.jobs	9
at.jobs	9
att.jobs	8
mozilla.support.firefox	8
spline.eisfair.dev	8
mozilla.support.thunderbird	6
alt.politics.usa	5
alt.math.undergrad	5
alt.job	4
lada.photo	4
TOTAL: 70	453

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1787
EMP (phn path)	285
EMP (phn nph)	202
EMP (phl)	146
0	61
Too many newsgroups	53
HTML Multipart	10
Binary: misplaced binary	4
Too many newsgroups (low_xpost)	3
Scoring filter	1
TOTAL: 10	2552

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.feed.uzoreto.com	306
	csiph-out.news.xs4all.nl	77
	newsfeeds.xmission.com	47
	csiph-out.feeder.erje.net	41
	news.freedyn.net	35
	csiph-out.news.weretis.net	30
	feeder.eternal-september.org	4
	newsfeed.fu-berlin.de	3
	csiph-out.news.tnetconsulting.net	2
	news-out.mixmin.net	1
	TOTAL: 11	547
Including strange strings
	newsfeed.fu-berlin.de	327
	news-out.aioe.org	95
	news.swapon.de	31
	csiph-out.news.xs4all.nl	28
	csiph-out.news.weretis.net	10
	news-out.mixmin.net	9
	feeder.eternal-september.org	5
	csiph-out.feed.uzoreto.com	5
	newsfeeds.xmission.com	4
	csiph-out.feeder.erje.net	3
	TOTAL: 11	518
No colon-space in header
	csiph-out.news.xs4all.nl	18
	csiph-out.feeder.erje.net	1
	TOTAL: 2	19
TOTAL: 4		1129

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	17888	788	14115	1	0	4%	23:59:45
2	fu-berlin.de	13826	522	13304	0	0	3%	23:59:53
3	news.samoylyk.net	15402	517	14380	6	55	3%	23:59:53
4	news.bbs.nz	28070	444	13877	25	0	1%	23:59:53
5	newsfeed.xs4all.nl	14100	165	12579	2	19	1%	23:59:53
6	xmission	14353	54	11739	1	0	0%	23:59:53
7	tnet	15969	46	14300	1	0	0%	23:59:01
8	neva.ru	13441	33	13191	79	0	0%	23:59:53
9	usenet.pasdenom.info	14654	19	14175	6	48	0%	23:59:53
10	erje	14735	17	13795	64	0	0%	23:59:43
11	neodome.net	15039	7	14595	0	0	0%	23:59:53
12	news.freedyn.net	14358	6	14034	76	0	0%	23:59:53
13	news.mixmin.net	13513	4	13226	0	0	0%	23:59:53
14	aioe	10608	3	10203	0	6	0%	23:58:28
15	weretis.net	13977	1	13485	0	7	0%	23:59:53
16	news.swapon.de	1428	1	1404	0	0	0%	23:54:31
17	news.uzoreto.com	14216	1	13988	24	0	0%	23:59:53
18	news.fcku.it	1298	0	1288	0	0	0%	21:15:50
19	eternal-september	0	0	0	0	12103	0%	23:59:53
TOTAL: 19		246875	2628	217678	285	12238	1%	453:05:47

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.7 MB	18.0 KB	6.7 MB	0.1 KB/s	8.8 KB	23:59:45
2	fu-berlin.de	6.0 MB	0.0 KB	6.0 MB	0.1 KB/s	11.7 KB	23:59:53
3	news.samoylyk.net	5.9 MB	31.0 KB	6.0 MB	0.1 KB/s	11.7 KB	23:59:53
4	news.bbs.nz	1.2 MB	85.3 KB	1.3 MB	0.0 KB/s	2.7 KB	23:59:53
5	newsfeed.xs4all.nl	712.0 KB	38.7 KB	750.7 KB	0.0 KB/s	4.5 KB	23:59:53
6	xmission	152.4 KB	2.0 KB	154.4 KB	0.0 KB/s	2.8 KB	23:59:53
7	news.mixmin.net	105.4 KB	0.0 KB	105.4 KB	0.0 KB/s	26.3 KB	23:59:53
8	tnet	96.4 KB	3.1 KB	99.6 KB	0.0 KB/s	2.1 KB	23:59:01
9	news.freedyn.net	93.1 KB	1.5 MB	1.6 MB	0.0 KB/s	19.5 KB	23:59:53
10	neodome.net	81.9 KB	0.0 KB	81.9 KB	0.0 KB/s	11.7 KB	23:59:53
11	neva.ru	72.1 KB	197.5 KB	269.6 KB	0.0 KB/s	2.4 KB	23:59:53
12	usenet.pasdenom.info	53.0 KB	44.6 KB	97.6 KB	0.0 KB/s	3.9 KB	23:59:53
13	erje	32.2 KB	355.5 KB	387.6 KB	0.0 KB/s	4.8 KB	23:59:43
14	aioe	7.6 KB	0.0 KB	7.6 KB	0.0 KB/s	2.5 KB	23:58:28
15	news.uzoreto.com	2.4 KB	257.3 KB	259.7 KB	0.0 KB/s	10.4 KB	23:59:53
16	weretis.net	1.9 KB	0.0 KB	1.9 KB	0.0 KB/s	1.9 KB	23:59:53
17	news.swapon.de	1.7 KB	0.0 KB	1.7 KB	0.0 KB/s	1.7 KB	23:54:31
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:15:50
19	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:53
TOTAL: 19		21.2 MB	2.5 MB	23.6 MB	0.0 KB/s	8.3 KB	453:05:47

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	a131033.upc-a.chello.nl	41	4	7.1 KB	2	11:56:37
2	91.187.33.87	77	1	1.5 KB	1	24:48:59
TOTAL: 2		118	5	8.6 KB	3	37:15:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.upc-a.chello.nl	41	4	7.1 KB	2	11:56:37
2	unresolved	77	1	1.5 KB	1	24:48:59
3	*.range31-50.btcentralplus.com	1	0	0.0 KB	0	00:00:07
4	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	00:30:01
TOTAL: 4		120	5	8.6 KB	3	37:15:45

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	4.834	1.323	24:48:59
a131033.upc-a.chello.nl	2.680	0.651	11:56:37
c-73-140-175-55.hsd1.wa.comcast.net	0.048	0.032	00:30:01
host31-50-220-99.range31-50.btcentralplus.com	0.057	0.021	00:00:07
TOTAL: 4	7.619	2.027	37:15:45

Curious NNRP server explorers [Top 20]:

System	Conn
c-73-140-175-55.hsd1.wa.comcast.net	1
host31-50-220-99.range31-50.btcentralplus.com	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.187.33.87	5	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 2	6	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Dec 17 04:15:00 -- Dec 18 04:15:00