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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 22 20:35:12 csiph nnrpd[98331]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 22 20:35:12 csiph nnrpd[98331]: 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	19258	49.0%	4.8 MB	63.6%
innd	15716	40.0%	2.1 MB	27.4%
innfeed	3653	9.3%	629.9 KB	8.1%
nnrpd	643	1.6%	70.4 KB	0.9%
nocem	14	0.0%	1.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39285	100.0%	7.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	304781	54.4%
Negative hits	237023	42.3%
Do not exist	18531	3.3%
Cache misses	298	0.1%
TOTAL: 4	560633	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.292	0.0%	35	0.000	8.343	21.000
article cleanup	00:00:00.110	0.0%	18274	0.000	0.006	0.040
article logging	00:00:00.398	0.0%	18883	0.000	0.021	0.596
article parse	00:00:00.660	0.0%	55374	0.000	0.012	0.048
article write	00:00:19.859	0.0%	15169	0.148	1.309	36.455
artlog/artcncl	00:00:00.001	0.0%	35	0.000	0.029	0.500
artlog/artparse	00:00:00.006	0.0%	331	0.000	0.018	0.500
data move	00:00:00.172	0.0%	531599	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.026	0.0%	35	0.000	0.743	10.500
hishave/artcncl	00:00:00.005	0.0%	35	0.000	0.143	1.000
hishave/artparse	00:00:00.000	0.0%	314	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:04.311	0.0%	562834	0.001	0.008	0.063
history sync	00:00:00.932	0.0%	363	0.000	2.567	28.333
history write	00:00:10.450	0.0%	18289	0.042	0.571	7.870
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.135	0.0%	314	0.000	0.430	2.000
idle	23:58:02.649	99.8%	536806	92.225	160.733	513.156
nntp read	00:00:02.040	0.0%	538691	0.000	0.004	0.007
overview write	00:01:01.401	0.1%	15169	0.253	4.048	89.018
perl filter	00:00:26.064	0.0%	18272	1.097	1.426	2.348
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.242	0.0%	30352	0.000	0.008	0.031
TOTAL: 24:00:49.874	24:00:09.753	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	15258	0.000	0.002	0.035
article prepare	00:00:00.003	0.0%	13288	0.000	0.000	0.011
article read	00:00:00.296	0.0%	1502	0.000	0.197	44.000
backlog stats	00:00:00.206	0.0%	331980	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.235	0.0%	260624	0.000	0.005	0.010
data write	00:00:01.479	0.0%	248143	0.001	0.006	0.014
idle	23:54:54.777	100.0%	331980	151.135	259.337	801.089
status file	00:00:00.321	0.0%	328	0.000	0.979	10.500
TOTAL: 23:55:05.091	23:54:58.345	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	29:35:26.968	99.8%	342	9621.000	311482.363	450120.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.011	0.0%	785	0.000	0.014	0.167
readart	00:00:00.042	0.0%	1	42.000	42.000	42.000
TOTAL: 29:39:27.184	29:35:27.021	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	14104	2686	11375	43	19%	23:01:55
2	news-out.mixmin.net	3	16592	2382	13857	353	14%	24:08:55
3	feeder.eternal-september.org	13	6213	2298	3742	173	36%	27:40:49
4	csiph-out.news.xs4all.nl	546	17592	2289	14112	1191	13%	67:52:46
5	news-out.aioe.org	1	14680	1207	13314	159	8%	23:59:56
6	newsfeed.fu-berlin.de	16	16886	959	15623	304	5%	29:35:31
7	csiph-out.feeder.erje.net	1440	76767	954	75463	350	1%	118:41:41
8	csiph-out.news.weretis.net	3	45049	878	43869	302	1%	64:17:29
9	newsfeed.neva.ru	149	14374	605	13526	243	4%	71:36:09
10	news-out.neodome.net	1	17209	305	16889	15	1%	23:59:53
11	csiph-out.feed.uzoreto.com	864	23612	213	23134	265	0%	71:10:58
12	news.fcku.it	15	1824	176	1637	11	9%	21:25:37
13	nntp.pasdenom.info	1	10469	168	10291	10	1%	23:59:55
14	news.freedyn.net	1	17812	65	17609	138	0%	23:59:57
15	news.swapon.de	7	1481	50	1431	0	3%	23:45:11
16	csiph-out.news.tnetconsulting.net	2	3287	13	3236	38	0%	06:53:25
17	news.samoylyk.net	2	14617	3	14614	0	0%	23:59:44
18	xanadu-bbs.net	33	341	0	341	0	0%	20:40:30
19	news.bbs.nz	2	15324	0	15324	0	0%	23:59:53
20	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 20		3127	328233	15251	309387	3595	4%	714:50:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	8.2 MB	0.0 KB	2.9 MB	11.1 MB	73%	8.7 KB
2	newsfeeds.xmission.com	8.0 MB	0.0 KB	2.2 MB	10.3 MB	78%	3.8 KB
3	feeder.eternal-september.org	6.1 MB	0.0 KB	959.7 KB	7.0 MB	86%	2.9 KB
4	csiph-out.news.xs4all.nl	5.7 MB	0.0 KB	6.2 MB	11.9 MB	47%	3.5 KB
5	news-out.mixmin.net	5.0 MB	0.0 KB	676.2 KB	5.7 MB	88%	2.1 KB
6	news-out.aioe.org	4.1 MB	0.0 KB	432.7 KB	4.5 MB	90%	3.4 KB
7	newsfeed.fu-berlin.de	2.3 MB	0.0 KB	614.1 KB	2.9 MB	79%	2.3 KB
8	csiph-out.news.weretis.net	2.1 MB	0.0 KB	2.6 MB	4.7 MB	45%	4.1 KB
9	newsfeed.neva.ru	1.5 MB	1.3 KB	547.1 KB	2.0 MB	73%	2.4 KB
10	news-out.neodome.net	1005.1 KB	1.3 KB	29.8 KB	1.0 MB	97%	3.2 KB
11	csiph-out.feed.uzoreto.com	768.3 KB	0.0 KB	5.4 MB	6.1 MB	12%	13.1 KB
12	news.freedyn.net	371.6 KB	0.0 KB	3.5 MB	3.9 MB	9%	19.6 KB
13	nntp.pasdenom.info	349.7 KB	0.0 KB	15.5 KB	365.2 KB	95%	2.1 KB
14	news.fcku.it	267.8 KB	0.0 KB	13.7 KB	281.5 KB	95%	1.5 KB
15	news.swapon.de	99.9 KB	0.0 KB	0.0 KB	99.9 KB	100%	2.0 KB
16	csiph-out.news.tnetconsulting.net	55.4 KB	0.0 KB	116.9 KB	172.3 KB	32%	3.4 KB
17	news.samoylyk.net	6.1 KB	0.0 KB	0.0 KB	6.1 KB	100%	2.0 KB
18	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
19	news.bbs.nz	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
20	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		45.8 MB	2.6 KB	26.2 MB	72.0 MB	63%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 22 04:15:00 - 04:59:59	192	1.3%	0.07	635.1 KB	1.4%	0.24
Dec 22 05:00:00 - 05:59:59	279	1.8%	0.08	800.2 KB	1.7%	0.22
Dec 22 06:00:00 - 06:59:59	383	2.5%	0.11	1.3 MB	2.9%	0.38
Dec 22 07:00:00 - 07:59:59	442	2.9%	0.12	1.2 MB	2.6%	0.33
Dec 22 08:00:00 - 08:59:59	711	4.7%	0.20	2.8 MB	6.1%	0.79
Dec 22 09:00:00 - 09:59:59	758	5.0%	0.21	2.6 MB	5.7%	0.73
Dec 22 10:00:00 - 10:59:59	963	6.3%	0.27	2.9 MB	6.3%	0.81
Dec 22 11:00:00 - 11:59:59	794	5.2%	0.22	2.6 MB	5.6%	0.73
Dec 22 12:00:00 - 12:59:59	776	5.1%	0.22	2.2 MB	4.8%	0.63
Dec 22 13:00:00 - 13:59:59	750	4.9%	0.21	2.9 MB	6.4%	0.84
Dec 22 14:00:00 - 14:59:59	746	4.9%	0.21	1.9 MB	4.3%	0.55
Dec 22 15:00:00 - 15:59:59	826	5.4%	0.23	2.3 MB	5.0%	0.64
Dec 22 16:00:00 - 16:59:59	911	6.0%	0.25	2.4 MB	5.3%	0.69
Dec 22 17:00:00 - 17:59:59	905	6.0%	0.25	2.8 MB	6.0%	0.78
Dec 22 18:00:00 - 18:59:59	841	5.5%	0.23	2.2 MB	4.9%	0.64
Dec 22 19:00:00 - 19:59:59	926	6.1%	0.26	2.6 MB	5.8%	0.75
Dec 22 20:00:00 - 20:59:59	769	5.1%	0.21	2.1 MB	4.5%	0.58
Dec 22 21:00:00 - 21:59:59	642	4.2%	0.18	1.6 MB	3.6%	0.46
Dec 22 22:00:00 - 22:59:59	663	4.4%	0.18	1.8 MB	3.8%	0.50
Dec 22 23:00:00 - 23:59:59	553	3.6%	0.15	1.9 MB	4.1%	0.53
Dec 23 00:00:00 - 00:59:59	377	2.5%	0.10	1.1 MB	2.5%	0.32
Dec 23 01:00:00 - 01:59:59	315	2.1%	0.09	1.1 MB	2.3%	0.30
Dec 23 02:00:00 - 02:59:59	287	1.9%	0.08	934.9 KB	2.0%	0.26
Dec 23 03:00:00 - 03:59:59	290	1.9%	0.08	888.9 KB	1.9%	0.25
Dec 23 04:00:00 - 04:15:00	74	0.5%	0.08	271.6 KB	0.6%	0.30
TOTAL: 24:00:00	15173	100.0%	0.18	45.6 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1212	103	0	1109
2	newsfeed.fu-berlin.de	731	21	0	710
3	news-out.mixmin.net	427	53	0	374
4	csiph-out.feed.uzoreto.com	271	25	0	246
5	csiph-out.news.weretis.net	270	88	0	182
6	newsfeed.neva.ru	245	88	1	156
7	news-out.aioe.org	196	155	0	41
8	feeder.eternal-september.org	169	137	0	32
9	csiph-out.feeder.erje.net	159	55	0	104
10	news.freedyn.net	136	0	0	136
11	csiph-out.news.tnetconsulting.net	114	22	0	92
12	newsfeeds.xmission.com	56	10	0	46
13	news.swapon.de	29	0	0	29
14	news-out.neodome.net	14	1	1	12
15	news.fcku.it	11	11	0	0
16	nntp.pasdenom.info	10	0	0	10
TOTAL: 16		4050	769	2	3279

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	383
it-alt.sport.calcio.juventus	62
lada.talk	54
spline.eisfair	24
scinet.stats	21
alt.hack.nl	19
alt.politics	16
lada.auto	16
alt.binaries.pictures.purebeauty	15
it-alt.comp.software.mozilla	10
mozilla.general	10
mozilla.support.bugzilla	9
alt.math.undergrad	8
mozilla.support.firefox	6
oesterreich.tratsch	6
mozilla.support.thunderbird	6
lada.movies	6
alt.binaries.ebook	5
alt.survival	5
ba.forsale	4
TOTAL: 70	769

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1468
EMP (phn path)	474
EMP (phn nph)	153
EMP (phl)	121
Too many newsgroups	41
0	39
Binary Payload	32
HTML Multipart	12
HTML post	3
Too many newsgroups (meow)	1
Binary: misplaced binary	1
TOTAL: 11	2345

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	82
	csiph-out.news.xs4all.nl	66
	csiph-out.feeder.erje.net	54
	news.freedyn.net	45
	newsfeeds.xmission.com	33
	csiph-out.news.weretis.net	23
	feeder.eternal-september.org	4
	TOTAL: 7	307
Including strange strings
	newsfeed.fu-berlin.de	434
	news-out.mixmin.net	67
	news-out.aioe.org	30
	news.swapon.de	29
	csiph-out.news.xs4all.nl	25
	newsfeeds.xmission.com	11
	csiph-out.news.weretis.net	7
	csiph-out.feed.uzoreto.com	4
	newsfeed.neva.ru	2
	csiph-out.feeder.erje.net	1
	TOTAL: 10	610
No colon-space in header
	csiph-out.news.xs4all.nl	16
	csiph-out.feeder.erje.net	1
	TOTAL: 2	17
TOTAL: 4		979

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18602	1078	14073	22	3	5%	23:59:55
2	fu-berlin.de	14075	655	13420	0	1	4%	23:59:52
3	news.samoylyk.net	15599	650	14514	6	0	4%	23:59:55
4	news.bbs.nz	28285	362	14061	29	1	1%	23:59:55
5	newsfeed.xs4all.nl	13699	86	12619	1	104	0%	23:59:55
6	neva.ru	13580	60	13341	70	0	0%	23:59:55
7	xmission	13565	58	11631	15	0	0%	23:59:33
8	tnet	15745	48	14405	1	0	0%	23:59:55
9	usenet.pasdenom.info	14727	13	14260	8	0	0%	23:59:33
10	news.freedyn.net	14630	7	14339	33	0	0%	23:59:42
11	news.uzoreto.com	14495	7	14211	39	0	0%	23:59:36
12	neodome.net	15271	5	14824	0	0	0%	23:59:55
13	erje	14223	2	13953	68	0	0%	23:59:49
14	news.swapon.de	1447	1	1439	0	0	0%	23:32:46
15	aioe	10962	1	10608	0	0	0%	23:59:51
16	news.mixmin.net	12085	1	11802	0	0	0%	23:59:55
17	weretis.net	14002	0	13566	0	0	0%	23:59:55
18	news.fcku.it	1557	0	1550	0	0	0%	20:43:40
19	eternal-september	0	0	0	0	12057	0%	23:59:55
TOTAL: 19		246549	3034	218616	292	12166	1%	452:13:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	8.4 MB	52.3 KB	8.5 MB	0.1 KB/s	7.9 KB	23:59:55
2	fu-berlin.de	7.4 MB	0.0 KB	7.4 MB	0.1 KB/s	11.6 KB	23:59:52
3	news.samoylyk.net	7.4 MB	28.5 KB	7.4 MB	0.1 KB/s	11.6 KB	23:59:55
4	news.bbs.nz	920.5 KB	105.8 KB	1.0 MB	0.0 KB/s	2.6 KB	23:59:55
5	newsfeed.xs4all.nl	290.1 KB	39.1 KB	329.2 KB	0.0 KB/s	3.8 KB	23:59:55
6	neva.ru	152.3 KB	163.4 KB	315.7 KB	0.0 KB/s	2.4 KB	23:59:55
7	xmission	134.0 KB	22.8 KB	156.8 KB	0.0 KB/s	2.1 KB	23:59:33
8	tnet	101.6 KB	11.0 KB	112.6 KB	0.0 KB/s	2.3 KB	23:59:55
9	usenet.pasdenom.info	52.0 KB	21.5 KB	73.5 KB	0.0 KB/s	3.5 KB	23:59:33
10	news.freedyn.net	26.5 KB	438.3 KB	464.8 KB	0.0 KB/s	11.6 KB	23:59:42
11	news.uzoreto.com	24.8 KB	100.6 KB	125.4 KB	0.0 KB/s	2.7 KB	23:59:36
12	neodome.net	17.7 KB	0.0 KB	17.7 KB	0.0 KB/s	3.5 KB	23:59:55
13	news.mixmin.net	3.4 KB	0.0 KB	3.4 KB	0.0 KB/s	3.4 KB	23:59:55
14	aioe	2.6 KB	0.0 KB	2.6 KB	0.0 KB/s	2.6 KB	23:59:51
15	erje	1.9 KB	199.0 KB	200.8 KB	0.0 KB/s	2.9 KB	23:59:49
16	news.swapon.de	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	23:32:46
17	weretis.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	20:43:40
19	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:55
TOTAL: 19		24.9 MB	1.2 MB	26.0 MB	0.0 KB/s	8.0 KB	452:13:32

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	91.187.33.87	63	1	1.6 KB	1	0	0	20:15:50
TOTAL: 1		63	1	1.6 KB	4	0	0	29:39:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	65	1	1.6 KB	4	20:16:11
2	*.upc-a.chello.nl	38	0	0.0 KB	0	06:12:45
3	*.range31-50.btcentralplus.com	1	0	0.0 KB	0	00:03:14
4	*.net.upcbroadband.cz	10	0	0.0 KB	0	03:07:16
TOTAL: 4		114	1	1.6 KB	4	29:39:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
91.187.33.87	4.012	0.938	20:15:50
a131033.upc-a.chello.nl	2.355	0.517	06:12:45
ip-78-102-198-192.net.upcbroadband.cz	0.664	0.104	03:07:16
host31-50-220-99.range31-50.btcentralplus.com	0.063	0.018	00:03:14
142.93.90.191	0.066	0.008	00:00:10
192.241.224.33	0.061	0.015	00:00:09
TOTAL: 6	7.221	1.600	29:39:26

Curious NNRP server explorers [Top 20]:

System	Conn
a131033.upc-a.chello.nl	38
ip-78-102-198-192.net.upcbroadband.cz	10
142.93.90.191	1
host31-50-220-99.range31-50.btcentralplus.com	1
192.241.224.33	1
TOTAL: 5	51

NNRP client timeouts [Top 20]:

System	Conn	Peer
91.187.33.87	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	alt	33178	100.0%
2	comp	1	0.0%
TOTAL: 2		33179	100.0%

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

Newsgroup		Count
1	alt.astronomy	33171
2	alt.astronomy.solar	5
3	alt.atari.2600vcs	2
4	comp.sys.ibm.ps2.hardware	1
TOTAL: 4		33179

Daily Usenet report for csiph.com

Dec 22 04:15:00 -- Dec 23 04:15:00