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

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 29 12:37:30 csiph nnrpd[98731]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 29 12:37:30 csiph nnrpd[98731]: 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]
Dec 29 16:55:45 csiph nnrpd[12365]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Dec 29 16:55:45 csiph nnrpd[12365]: 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	19132	46.8%	4.8 MB	62.3%
innd	15837	38.8%	2.1 MB	26.9%
innfeed	3591	8.8%	607.0 KB	7.7%
nnrpd	2269	5.6%	242.3 KB	3.1%
nocem	36	0.1%	4.1 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	40866	100.0%	7.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	298494	45.2%
Negative hits	246952	37.4%
Cache misses	96186	14.6%
Do not exist	18579	2.8%
TOTAL: 4	660211	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.967	0.0%	45	0.000	21.489	69.714
article cleanup	00:00:00.117	0.0%	18314	0.000	0.006	0.040
article logging	00:00:00.439	0.0%	18816	0.000	0.023	1.567
article parse	00:00:01.118	0.0%	56363	0.000	0.020	0.291
article write	00:00:32.665	0.0%	15233	0.170	2.144	67.021
artlog/artcncl	00:00:00.000	0.0%	45	0.000	0.000	0.000
artlog/artparse	00:00:00.001	0.0%	281	0.000	0.004	0.333
data move	00:00:00.154	0.0%	547781	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.027	0.0%	44	0.000	0.614	1.778
hishave/artcncl	00:00:00.008	0.0%	45	0.000	0.178	1.000
hishave/artparse	00:00:00.001	0.0%	272	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:16.092	0.0%	661059	0.001	0.024	0.331
history sync	00:00:02.034	0.0%	370	0.000	5.497	24.000
history write	00:00:34.628	0.0%	18319	0.051	1.890	7.484
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.621	0.0%	272	0.000	2.283	31.000
idle	23:55:36.101	99.6%	553342	27.101	155.665	570.652
nntp read	00:00:02.237	0.0%	556870	0.002	0.004	0.008
overview write	00:02:42.632	0.2%	15233	0.083	10.676	1847.327
perl filter	00:00:27.148	0.0%	18310	1.083	1.483	2.471
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.235	0.0%	30501	0.000	0.008	0.032
TOTAL: 24:00:57.496	24:00:17.225	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.078	0.0%	94491	0.000	0.001	0.024
article prepare	00:00:00.001	0.0%	27972	0.000	0.000	0.000
article read	00:00:00.878	0.0%	5001	0.000	0.176	28.000
backlog stats	00:00:00.197	0.0%	365751	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.275	0.0%	281090	0.000	0.005	0.010
data write	00:00:01.705	0.0%	273372	0.001	0.006	0.015
idle	23:54:19.188	100.0%	365751	14.315	235.294	862.057
status file	00:00:00.382	0.0%	332	0.000	1.151	83.000
TOTAL: 23:54:30.309	23:54:23.704	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	84:33:59.517	99.1%	106240	88.500	2865.583	407725.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:04.399	0.0%	468673	0.000	0.009	0.167
readart	00:28:32.120	0.6%	103862	0.000	16.485	42.000
TOTAL: 85:19:24.002	85:02:36.036	99.7%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	546	21873	3624	16875	1374	16%	67:23:10
2	feeder.eternal-september.org	14	5065	2295	2661	109	45%	26:33:37
3	newsfeeds.xmission.com	2	14418	1749	12614	55	12%	24:02:35
4	news-out.aioe.org	2	14948	1532	13366	50	10%	24:09:02
5	csiph-out.feeder.erje.net	1440	73927	1246	72333	348	1%	118:42:08
6	newsfeed.neva.ru	146	11566	1028	10089	449	8%	71:22:38
7	newsfeed.fu-berlin.de	10	15653	918	14510	225	5%	26:48:15
8	csiph-out.news.weretis.net	4	19363	860	18379	124	4%	33:31:50
9	news-out.mixmin.net	1	16162	853	15083	226	5%	23:59:53
10	csiph-out.feed.uzoreto.com	863	50869	360	50145	364	0%	71:21:56
11	news-out.neodome.net	1	16588	313	16254	21	1%	23:59:54
12	news.fcku.it	8	1630	194	1434	2	11%	21:33:26
13	nntp.pasdenom.info	1	10234	95	10130	9	0%	23:59:55
14	news.swapon.de	7	1465	56	1409	0	3%	22:38:16
15	csiph-out.news.tnetconsulting.net	2	14476	39	14435	2	0%	23:59:57
16	news.freedyn.net	1	17785	14	17736	35	0%	23:59:57
17	news.bbs.nz	2	13907	12	13895	0	0%	23:59:48
18	localhost	28	2	2	0	0	100%	00:00:02
19	news.samoylyk.net	2	81406	1	81405	0	0%	15:12:42
20	xanadu-bbs.net	32	369	0	369	0	0%	19:49:14
TOTAL: 20		3112	401706	15191	383122	3393	3%	687:08:15

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	10.0 MB	0.0 KB	8.3 MB	18.3 MB	54%	3.7 KB
2	csiph-out.feeder.erje.net	8.5 MB	0.0 KB	2.8 MB	11.2 MB	75%	7.2 KB
3	feeder.eternal-september.org	6.7 MB	0.0 KB	1.7 MB	8.4 MB	79%	3.6 KB
4	newsfeeds.xmission.com	5.6 MB	0.0 KB	4.3 MB	9.9 MB	56%	5.6 KB
5	news-out.aioe.org	3.8 MB	0.0 KB	119.7 KB	4.0 MB	97%	2.6 KB
6	news-out.mixmin.net	2.6 MB	0.0 KB	471.0 KB	3.1 MB	84%	2.9 KB
7	csiph-out.news.weretis.net	2.4 MB	19.6 KB	1.4 MB	3.8 MB	62%	3.9 KB
8	newsfeed.neva.ru	2.3 MB	18.1 KB	963.5 KB	3.3 MB	70%	2.3 KB
9	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	490.6 KB	2.6 MB	81%	2.3 KB
10	csiph-out.feed.uzoreto.com	1.4 MB	0.0 KB	6.0 MB	7.4 MB	18%	10.5 KB
11	news-out.neodome.net	956.5 KB	18.1 KB	274.1 KB	1.2 MB	76%	3.7 KB
12	news.fcku.it	344.8 KB	0.0 KB	2.9 KB	347.7 KB	99%	1.8 KB
13	nntp.pasdenom.info	221.1 KB	0.0 KB	16.1 KB	237.2 KB	93%	2.3 KB
14	news.swapon.de	129.5 KB	0.0 KB	0.0 KB	129.5 KB	100%	2.3 KB
15	csiph-out.news.tnetconsulting.net	114.6 KB	0.0 KB	3.2 KB	117.8 KB	97%	2.9 KB
16	news.freedyn.net	44.7 KB	18.2 KB	1.9 MB	1.9 MB	2%	40.7 KB
17	news.bbs.nz	28.7 KB	0.0 KB	0.0 KB	28.7 KB	100%	2.4 KB
18	localhost	4.3 KB	0.0 KB	0.0 KB	4.3 KB	100%	2.1 KB
19	news.samoylyk.net	1.6 KB	0.0 KB	0.0 KB	1.6 KB	100%	1.6 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		47.2 MB	74.1 KB	28.6 MB	75.9 MB	62%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 29 04:15:00 - 04:59:59	204	1.3%	0.08	943.5 KB	2.0%	0.35
Dec 29 05:00:00 - 05:59:59	289	1.9%	0.08	895.1 KB	1.9%	0.25
Dec 29 06:00:00 - 06:59:59	273	1.8%	0.08	896.4 KB	1.9%	0.25
Dec 29 07:00:00 - 07:59:59	458	3.0%	0.13	1.5 MB	3.3%	0.44
Dec 29 08:00:00 - 08:59:59	552	3.6%	0.15	2.2 MB	4.6%	0.62
Dec 29 09:00:00 - 09:59:59	774	5.1%	0.21	3.3 MB	6.9%	0.93
Dec 29 10:00:00 - 10:59:59	863	5.7%	0.24	3.3 MB	7.0%	0.94
Dec 29 11:00:00 - 11:59:59	702	4.6%	0.20	1.8 MB	3.9%	0.53
Dec 29 12:00:00 - 12:59:59	805	5.3%	0.22	2.0 MB	4.2%	0.57
Dec 29 13:00:00 - 13:59:59	806	5.3%	0.22	2.1 MB	4.5%	0.60
Dec 29 14:00:00 - 14:59:59	960	6.3%	0.27	2.5 MB	5.2%	0.70
Dec 29 15:00:00 - 15:59:59	1014	6.7%	0.28	3.2 MB	6.7%	0.90
Dec 29 16:00:00 - 16:59:59	936	6.1%	0.26	3.0 MB	6.4%	0.86
Dec 29 17:00:00 - 17:59:59	869	5.7%	0.24	2.5 MB	5.3%	0.71
Dec 29 18:00:00 - 18:59:59	864	5.7%	0.24	2.2 MB	4.7%	0.63
Dec 29 19:00:00 - 19:59:59	761	5.0%	0.21	2.3 MB	5.0%	0.67
Dec 29 20:00:00 - 20:59:59	720	4.7%	0.20	2.1 MB	4.4%	0.59
Dec 29 21:00:00 - 21:59:59	666	4.4%	0.18	2.0 MB	4.3%	0.57
Dec 29 22:00:00 - 22:59:59	646	4.2%	0.18	2.0 MB	4.2%	0.56
Dec 29 23:00:00 - 23:59:59	607	4.0%	0.17	1.8 MB	3.8%	0.51
Dec 30 00:00:00 - 00:59:59	374	2.5%	0.10	1.2 MB	2.5%	0.34
Dec 30 01:00:00 - 01:59:59	370	2.4%	0.10	1.2 MB	2.6%	0.35
Dec 30 02:00:00 - 02:59:59	303	2.0%	0.08	976.3 KB	2.0%	0.27
Dec 30 03:00:00 - 03:59:59	319	2.1%	0.09	1.1 MB	2.3%	0.31
Dec 30 04:00:00 - 04:15:00	91	0.6%	0.10	219.4 KB	0.5%	0.24
TOTAL: 24:00:00	15226	100.0%	0.18	47.1 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	1397	79	1318
2	newsfeed.fu-berlin.de	598	32	566
3	newsfeed.neva.ru	461	81	380
4	csiph-out.feed.uzoreto.com	369	28	341
5	csiph-out.feeder.erje.net	246	95	151
6	news-out.mixmin.net	231	13	218
7	csiph-out.news.weretis.net	222	95	127
8	feeder.eternal-september.org	121	58	63
9	newsfeeds.xmission.com	71	2	69
10	news-out.aioe.org	60	34	26
11	news.freedyn.net	34	0	34
12	news.swapon.de	22	0	22
13	news-out.neodome.net	13	2	11
14	nntp.pasdenom.info	9	0	9
15	csiph-out.news.tnetconsulting.net	5	0	5
16	news.fcku.it	2	2	0
TOTAL: 16		3861	521	3340

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	142
lada.talk	70
alt.survival	38
scinet.stats	29
alt.binaries.pictures.purebeauty	26
mozilla.support.seamonkey	25
mozilla.support.bugzilla	17
oesterreich.tratsch	13
alt.politics	9
mozilla.support.firefox	9
alt.8178287618	9
cakewalk.audio	9
alt.philosophy	9
spline.eisfair	8
trollnet.cinema	6
att.jobs	6
lada.auto	6
scinet.general.chatter	5
alt.bbs.synchronet	5
alt.math.undergrad	5
TOTAL: 67	521

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1800
EMP (phn path)	312
EMP (phn nph)	184
EMP (phl)	147
0	49
Too many newsgroups	39
HTML Multipart	13
Scoring filter	5
Binary: misplaced binary	3
Binary Payload	2
Too many newsgroups (meow)	2
HTML post	1
TOTAL: 12	2557

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	70
	csiph-out.feed.uzoreto.com	6
	csiph-out.news.xs4all.nl	5
	TOTAL: 3	81
Huge articles
	csiph-out.news.xs4all.nl	65
	csiph-out.feed.uzoreto.com	61
	newsfeeds.xmission.com	55
	csiph-out.feeder.erje.net	47
	news.freedyn.net	17
	csiph-out.news.weretis.net	12
	feeder.eternal-september.org	10
	news-out.neodome.net	3
	csiph-out.news.tnetconsulting.net	2
	newsfeed.fu-berlin.de	1
	TOTAL: 10	273
Including strange strings
	newsfeed.fu-berlin.de	387
	news.swapon.de	22
	csiph-out.news.xs4all.nl	22
	csiph-out.news.weretis.net	17
	newsfeeds.xmission.com	14
	newsfeed.neva.ru	14
	news-out.aioe.org	10
	feeder.eternal-september.org	6
	csiph-out.feed.uzoreto.com	4
	news-out.mixmin.net	3
	TOTAL: 11	502
No colon-space in header
	csiph-out.news.xs4all.nl	6
	csiph-out.feeder.erje.net	3
	TOTAL: 2	9
TOTAL: 4		865

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.samoylyk.net	89987	3271	86317	136	4688	3%	23:50:00
2	xanadu-bbs	18077	1217	13998	11	0	6%	23:59:47
3	news.bbs.nz	28086	910	13655	37	0	3%	23:53:47
4	fu-berlin.de	14073	558	13515	0	0	3%	23:50:04
5	tnet	17103	145	14454	2	0	0%	23:50:04
6	xmission	14720	119	12679	11	0	0%	23:50:04
7	newsfeed.xs4all.nl	11738	54	11258	1	65	0%	23:54:30
8	neva.ru	13163	34	12871	187	0	0%	23:50:04
9	usenet.pasdenom.info	14913	17	14471	64	0	0%	23:59:24
10	neodome.net	15158	11	14818	0	0	0%	23:50:05
11	news.mixmin.net	13854	11	13522	1	0	0%	23:51:53
12	erje	13895	9	13708	84	0	0%	23:50:04
13	aioe	12617	8	12253	0	0	0%	23:53:42
14	news.uzoreto.com	14533	7	14230	23	0	0%	23:52:20
15	news.freedyn.net	13812	6	13475	51	0	0%	23:50:04
16	weretis.net	13912	3	13565	0	0	0%	23:50:04
17	news.swapon.de	1450	3	1443	1	0	0%	23:44:41
18	news.fcku.it	1386	0	1384	0	0	0%	21:29:47
19	eternal-september	0	0	0	0	12278	0%	23:59:56
TOTAL: 19		322477	6383	291616	609	17031	1%	451:10:20

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.samoylyk.net	39.0 MB	783.6 KB	39.7 MB	0.5 KB/s	11.9 KB	23:50:00
2	xanadu-bbs	8.3 MB	70.0 KB	8.4 MB	0.1 KB/s	7.0 KB	23:59:47
3	fu-berlin.de	6.6 MB	0.0 KB	6.6 MB	0.1 KB/s	12.1 KB	23:50:04
4	news.bbs.nz	2.5 MB	144.1 KB	2.6 MB	0.0 KB/s	2.8 KB	23:53:47
5	tnet	329.0 KB	2.7 KB	331.7 KB	0.0 KB/s	2.3 KB	23:50:04
6	xmission	279.8 KB	27.3 KB	307.1 KB	0.0 KB/s	2.4 KB	23:50:04
7	newsfeed.xs4all.nl	110.1 KB	1.8 KB	111.9 KB	0.0 KB/s	2.0 KB	23:54:30
8	neva.ru	71.8 KB	401.2 KB	473.0 KB	0.0 KB/s	2.1 KB	23:50:04
9	usenet.pasdenom.info	27.1 KB	105.3 KB	132.4 KB	0.0 KB/s	1.6 KB	23:59:24
10	weretis.net	24.4 KB	0.0 KB	24.4 KB	0.0 KB/s	8.1 KB	23:50:04
11	neodome.net	17.6 KB	0.0 KB	17.6 KB	0.0 KB/s	1.6 KB	23:50:05
12	news.mixmin.net	17.5 KB	1.8 KB	19.3 KB	0.0 KB/s	1.6 KB	23:51:53
13	erje	16.3 KB	179.3 KB	195.5 KB	0.0 KB/s	2.1 KB	23:50:04
14	news.uzoreto.com	15.0 KB	65.2 KB	80.2 KB	0.0 KB/s	2.7 KB	23:52:20
15	aioe	13.9 KB	0.0 KB	13.9 KB	0.0 KB/s	1.7 KB	23:53:42
16	news.freedyn.net	11.9 KB	591.7 KB	603.6 KB	0.0 KB/s	10.6 KB	23:50:04
17	news.swapon.de	5.9 KB	1.8 KB	7.7 KB	0.0 KB/s	1.9 KB	23:44:41
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:29:47
19	eternal-september	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:56
TOTAL: 19		57.3 MB	2.3 MB	59.6 MB	0.0 KB/s	8.7 KB	451:10:20

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	38	7	13.5 KB	4	0	0	08:56:39
2	91.187.33.87	295	6	15.7 KB	6	2	2	46:50:01
TOTAL: 2		333	103815	506.7 MB	12	2	2	85:19:21

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	297	103808	506.7 MB	8	2	2	76:21:32
2	*.upc-a.chello.nl	38	7	13.5 KB	4	0	0	08:56:39
3	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	0	0	00:00:13
4	*.range31-50.btcentralplus.com	2	0	0.0 KB	0	0	0	00:00:56
TOTAL: 4		338	103815	506.7 MB	12	2	2	85:19:21

NNRP auth users [Top 20]:

User	Conn
slavotinek	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	18.787	5.682	0.006	46:50:01
212.90.61.180	10.700	21.250	0.000	29:31:15
a131033.upc-a.chello.nl	2.654	0.742	0.000	08:56:39
host31-50-220-99.range31-50.btcentralplus.com	0.100	0.075	0.000	00:00:56
23.90.145.34	0.060	0.015	0.000	00:00:14
34.20.97.83.ro.ovo.sc	0.061	0.026	0.000	00:00:13
45.146.165.32	0.074	0.000	0.000	00:00:00
TOTAL: 7	32.436	27.790	0.006	85:19:21

Curious NNRP server explorers [Top 20]:

System	Conn
host31-50-220-99.range31-50.btcentralplus.com	2
23.90.145.34	1
34.20.97.83.ro.ovo.sc	1
45.146.165.32	1
TOTAL: 4	5

NNRP unrecognized commands (by host):

System	Conn
45.146.165.32	1
TOTAL: 1	1

NNRP unrecognized commands (by command):

Command	Count
^C	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.146.165.32	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	rec	63266	100.0%
2	comp	13	0.0%
3	soc	1	0.0%
TOTAL: 3		63280	100.0%

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

Newsgroup		Count
1	rec.bicycles.tech	63266
2	comp.sys.ibm.ps2.hardware	13
3	soc.culture.sri-lanka	1
TOTAL: 3		63280

Daily Usenet report for csiph.com

Dec 29 04:15:00 -- Dec 30 04:15:00