Daily Usenet report for csiph.com: Sep 16 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Sep 16 13:38:16 csiph nnrpd[70279]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Sep 16 13:38:16 csiph nnrpd[70279]: 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]
Sep 16 13:44:35 csiph nnrpd[70542]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Sep 16 13:44:35 csiph nnrpd[70542]: 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	19758	48.1%	5.1 MB	63.4%
innd	16576	40.3%	2.2 MB	27.4%
innfeed	3645	8.9%	630.0 KB	7.6%
nnrpd	1118	2.7%	124.3 KB	1.5%
nocem	18	0.0%	2.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	41116	100.0%	8.1 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	325947	53.8%
Negative hits	260009	43.0%
Do not exist	19052	3.1%
Cache misses	327	0.1%
TOTAL: 4	605335	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.078	0.0%	26	0.000	3.000	22.000
article cleanup	00:00:00.105	0.0%	18698	0.000	0.006	0.030
article logging	00:00:00.407	0.0%	19241	0.000	0.021	0.417
article parse	00:00:01.018	0.0%	57334	0.000	0.018	0.296
article write	00:00:41.173	0.0%	16308	0.146	2.525	68.811
artlog/artcncl	00:00:00.001	0.0%	26	0.000	0.038	0.125
artlog/artparse	00:00:00.003	0.0%	374	0.000	0.008	0.250
data move	00:00:00.164	0.0%	563538	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.006	0.0%	25	0.000	0.240	2.000
hishave/artcncl	00:00:00.001	0.0%	26	0.000	0.038	0.500
hishave/artparse	00:00:00.000	0.0%	366	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:21.222	0.0%	605267	0.002	0.035	0.363
history sync	00:00:01.028	0.0%	347	0.000	2.963	56.333
history write	00:00:22.249	0.0%	18692	0.449	1.190	10.797
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:00.513	0.0%	366	0.000	1.402	10.333
idle	23:46:09.180	99.7%	566451	86.536	151.062	465.014
nntp read	00:00:02.265	0.0%	572046	0.001	0.004	0.008
overview write	00:02:08.943	0.2%	16308	0.122	7.907	978.491
perl filter	00:00:26.108	0.0%	18684	1.144	1.397	1.932
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.244	0.0%	32633	0.000	0.007	0.044
TOTAL: 23:50:53.947	23:50:14.708	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.038	0.0%	16928	0.000	0.002	0.045
article prepare	00:00:00.000	0.0%	9792	0.000	0.000	0.000
article read	00:00:00.333	0.0%	1254	0.000	0.266	8.400
backlog stats	00:00:00.222	0.0%	374694	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.396	0.0%	296394	0.000	0.005	0.010
data write	00:00:01.677	0.0%	283258	0.001	0.006	0.014
idle	23:53:51.991	100.0%	374694	132.700	229.606	703.057
status file	00:00:00.634	0.0%	308	0.000	2.058	18.000
TOTAL: 23:54:03.908	23:53:56.291	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	57:04:45.708	99.9%	739	539.000	278059.145	600123.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.937	0.0%	92581	0.000	0.021	1.262
readart	00:00:00.521	0.0%	36	0.000	14.472	42.000
TOTAL: 57:07:51.419	57:04:48.166	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.xs4all.nl	864	18522	3341	13918	1263	18%	82:19:35
2	news-out.mixmin.net	3	17069	2814	14060	195	16%	24:08:38
3	feeder.eternal-september.org	3	18209	2560	15586	63	14%	24:46:00
4	newsfeeds.xmission.com	5	16564	2273	14241	50	13%	28:55:49
5	news-out.aioe.org	2	15697	1388	14193	116	8%	24:09:02
6	newsfeed.fu-berlin.de	9	16976	976	15953	47	5%	26:53:20
7	csiph-out.news.weretis.net	5	47654	904	46364	386	1%	65:14:24
8	newsfeed.neva.ru	151	9804	742	8719	343	7%	71:08:06
9	csiph-out.feeder.erje.net	1440	78759	616	78035	108	0%	118:46:38
10	csiph-out.feed.uzoreto.com	864	23988	447	23351	190	1%	71:16:42
11	news.fcku.it	14	1938	431	1500	7	22%	22:11:02
12	nntp.pasdenom.info	3	10759	66	10656	37	0%	23:57:44
13	news.swapon.de	7	1249	60	1189	0	4%	22:49:00
14	news-out.neodome.net	1	17561	41	17517	3	0%	23:59:55
15	news.freedyn.net	1	19007	27	18938	42	0%	23:59:59
16	csiph-out.news.tnetconsulting.net	2	16172	10	16150	12	0%	23:59:53
17	news.samoylyk.net	2	15942	2	15939	1	0%	23:59:50
18	news.bbs.nz	1	15182	1	15181	0	0%	23:59:52
19	localhost	26	0	0	0	0	0%	00:00:02
20	xanadu-bbs.net	31	500	0	500	0	0%	21:21:34
TOTAL: 20		3434	361552	16699	341990	2863	4%	747:57:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.news.xs4all.nl	8.9 MB	3.1 KB	11.7 MB	20.5 MB	43%	4.6 KB
2	feeder.eternal-september.org	7.6 MB	0.0 KB	237.6 KB	7.9 MB	97%	3.1 KB
3	news-out.mixmin.net	7.2 MB	3.0 KB	470.1 KB	7.6 MB	93%	2.6 KB
4	newsfeeds.xmission.com	6.9 MB	0.0 KB	2.7 MB	9.6 MB	71%	4.2 KB
5	news-out.aioe.org	5.1 MB	3.0 KB	386.7 KB	5.5 MB	93%	3.7 KB
6	csiph-out.feeder.erje.net	3.6 MB	6.0 KB	3.7 MB	7.2 MB	49%	10.3 KB
7	csiph-out.news.weretis.net	2.6 MB	3.1 KB	1.6 MB	4.3 MB	62%	3.4 KB
8	newsfeed.fu-berlin.de	2.2 MB	0.0 KB	113.8 KB	2.3 MB	95%	2.3 KB
9	newsfeed.neva.ru	2.2 MB	9.1 KB	1004.7 KB	3.1 MB	68%	3.0 KB
10	csiph-out.feed.uzoreto.com	1.4 MB	6.1 KB	6.3 MB	7.7 MB	18%	12.4 KB
11	news.fcku.it	662.3 KB	0.0 KB	105.1 KB	767.4 KB	86%	1.8 KB
12	news.freedyn.net	396.5 KB	3.0 KB	1.4 MB	1.8 MB	21%	26.9 KB
13	news.swapon.de	161.7 KB	0.0 KB	0.0 KB	161.7 KB	100%	2.7 KB
14	nntp.pasdenom.info	150.2 KB	0.0 KB	45.9 KB	196.1 KB	76%	1.9 KB
15	news-out.neodome.net	132.1 KB	0.0 KB	45.7 KB	177.8 KB	74%	4.0 KB
16	csiph-out.news.tnetconsulting.net	23.3 KB	3.1 KB	32.1 KB	58.5 KB	39%	2.7 KB
17	news.samoylyk.net	11.9 KB	3.0 KB	3.0 KB	18.0 KB	66%	6.0 KB
18	news.bbs.nz	1.7 KB	0.0 KB	0.0 KB	1.7 KB	100%	1.7 KB
19	localhost	0.0 KB	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 KB	0%	0
TOTAL: 20		49.1 MB	42.5 KB	29.7 MB	78.9 MB	62%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 16 04:15:00 - 04:59:59	289	1.8%	0.11	1.1 MB	2.4%	0.43
Sep 16 05:00:00 - 05:59:59	443	2.7%	0.12	1.2 MB	2.4%	0.33
Sep 16 06:00:00 - 06:59:59	522	3.2%	0.14	1.3 MB	2.8%	0.38
Sep 16 07:00:00 - 07:59:59	581	3.5%	0.16	1.5 MB	3.2%	0.43
Sep 16 08:00:00 - 08:59:59	740	4.5%	0.21	2.2 MB	4.5%	0.62
Sep 16 09:00:00 - 09:59:59	734	4.5%	0.20	2.2 MB	4.6%	0.63
Sep 16 10:00:00 - 10:59:59	770	4.7%	0.21	2.5 MB	5.2%	0.70
Sep 16 11:00:00 - 11:59:59	620	3.8%	0.17	1.7 MB	3.5%	0.48
Sep 16 12:00:00 - 12:59:59	720	4.4%	0.20	1.9 MB	3.9%	0.53
Sep 16 13:00:00 - 13:59:59	913	5.6%	0.25	2.3 MB	4.8%	0.65
Sep 16 14:00:00 - 14:59:59	1027	6.3%	0.29	2.9 MB	6.1%	0.83
Sep 16 15:00:00 - 15:59:59	1016	6.2%	0.28	3.1 MB	6.4%	0.87
Sep 16 16:00:00 - 16:59:59	938	5.7%	0.26	2.8 MB	5.8%	0.78
Sep 16 17:00:00 - 17:59:59	836	5.1%	0.23	2.5 MB	5.2%	0.70
Sep 16 18:00:00 - 18:59:59	883	5.4%	0.25	2.4 MB	4.9%	0.67
Sep 16 19:00:00 - 19:59:59	824	5.0%	0.23	2.1 MB	4.4%	0.61
Sep 16 20:00:00 - 20:59:59	894	5.5%	0.25	2.7 MB	5.7%	0.78
Sep 16 21:00:00 - 21:59:59	923	5.6%	0.26	3.1 MB	6.4%	0.88
Sep 16 22:00:00 - 22:59:59	635	3.9%	0.18	2.1 MB	4.4%	0.60
Sep 16 23:00:00 - 23:59:59	525	3.2%	0.15	1.7 MB	3.6%	0.49
Sep 17 00:00:00 - 00:59:59	415	2.5%	0.12	1.3 MB	2.8%	0.38
Sep 17 01:00:00 - 01:59:59	356	2.2%	0.10	1.0 MB	2.2%	0.30
Sep 17 02:00:00 - 02:59:59	356	2.2%	0.10	1.0 MB	2.2%	0.29
Sep 17 03:00:00 - 03:59:59	315	1.9%	0.09	996.8 KB	2.0%	0.28
Sep 17 04:00:00 - 04:15:00	93	0.6%	0.10	313.6 KB	0.6%	0.35
TOTAL: 24:00:00	16368	100.0%	0.19	47.9 MB	100.0%	0.57

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1304	95	1	0	1208
2	newsfeed.fu-berlin.de	439	10	0	0	429
3	newsfeed.neva.ru	340	190	3	1	146
4	csiph-out.news.weretis.net	326	82	1	0	243
5	news-out.mixmin.net	251	3	1	0	247
6	csiph-out.feed.uzoreto.com	192	17	2	0	173
7	news-out.aioe.org	137	65	1	0	71
8	csiph-out.feeder.erje.net	113	22	2	0	89
9	feeder.eternal-september.org	88	16	0	0	72
10	newsfeeds.xmission.com	52	3	0	0	49
11	news.freedyn.net	44	2	1	0	41
12	nntp.pasdenom.info	37	1	0	0	36
13	news.swapon.de	19	0	0	0	19
14	news.fcku.it	7	6	0	0	1
15	csiph-out.news.tnetconsulting.net	3	0	1	0	2
16	news-out.neodome.net	2	2	0	0	0
17	news.samoylyk.net	1	0	1	0	0
TOTAL: 17		3355	514	14	1	2826

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	177
mozilla.support.thunderbird	54
alt.tv.comedy-central.daily-show	26
scinet.stats	22
alt.math.undergrad	22
alt.bbs.synchronet	21
it-alt.sport.calcio.juventus	18
alt.politics.usa	17
alt.philosophy	12
mozilla.test	11
att.jobs	10
alt.computer.workshop	9
mozilla.support.seamonkey	8
mozilla.support.firefox	7
mozilla.dev.platform	7
alt.job	7
alt.politics	7
alt.survival	7
aus.jobs	4
alt.religion	4
TOTAL: 59	514

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)	1355
EMP (phn path)	190
EMP (phl)	135
Too many newsgroups	78
0	47
EMP (phn nph)	44
Binary: misplaced binary	15
HTML Multipart	15
Scoring filter	6
Too many newsgroups (meow)	1
TOTAL: 10	1886

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.news.xs4all.nl	145
	csiph-out.feed.uzoreto.com	82
	csiph-out.feeder.erje.net	73
	newsfeeds.xmission.com	46
	news.freedyn.net	28
	csiph-out.news.weretis.net	10
	feeder.eternal-september.org	1
	news-out.mixmin.net	1
	news.fcku.it	1
	newsfeed.fu-berlin.de	1
	TOTAL: 10	388
Including strange strings
	newsfeed.fu-berlin.de	404
	news-out.mixmin.net	54
	news-out.aioe.org	24
	csiph-out.news.xs4all.nl	20
	news.swapon.de	19
	csiph-out.news.weretis.net	16
	feeder.eternal-september.org	2
	csiph-out.feed.uzoreto.com	2
	newsfeed.neva.ru	1
	csiph-out.news.tnetconsulting.net	1
	TOTAL: 11	544
No colon-space in header
	csiph-out.news.xs4all.nl	8
	TOTAL: 1	8
TOTAL: 4		985

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.bbs.nz	30939	648	15379	20	1	2%	24:09:45
2	xanadu-bbs	19804	627	15724	23	43	3%	24:01:57
3	fu-berlin.de	15275	352	14923	0	0	2%	24:09:28
4	news.samoylyk.net	16648	349	16008	5	4	2%	24:00:12
5	tnet	18788	97	15923	0	0	0%	24:04:43
6	xmission	15334	77	13640	2	470	0%	24:02:13
7	news.freedyn.net	15979	30	15779	0	0	0%	24:05:47
8	newsfeed.xs4all.nl	13273	13	12945	0	57	0%	24:09:41
9	usenet.pasdenom.info	16331	10	15969	3	25	0%	24:09:07
10	erje	17625	10	15491	111	0	0%	24:08:45
11	aioe	12027	8	11714	1	0	0%	24:02:35
12	neodome.net	17023	3	16186	0	0	0%	24:09:31
13	neva.ru	14877	3	14718	55	0	0%	24:06:33
14	weretis.net	15357	2	15074	2	0	0%	24:08:14
15	eternal-september	13486	2	13323	0	0	0%	24:09:45
16	news.mixmin.net	13047	1	12914	0	0	0%	24:00:11
17	news.uzoreto.com	15079	1	14931	14	0	0%	24:09:44
18	news.swapon.de	1345	1	1339	0	0	0%	23:41:29
19	news.fcku.it	1456	0	1456	0	0	0%	21:36:26
TOTAL: 19		283693	2234	253436	236	600	0%	455:06:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	3.8 MB	85.7 KB	3.9 MB	6.1 KB	24:01:57
2	fu-berlin.de	3.1 MB	0.0 KB	3.1 MB	9.0 KB	24:09:28
3	news.samoylyk.net	3.1 MB	6.6 KB	3.1 MB	8.9 KB	24:00:12
4	news.bbs.nz	1.8 MB	85.0 KB	1.8 MB	2.8 KB	24:09:45
5	news.freedyn.net	390.5 KB	0.0 KB	390.5 KB	13.0 KB	24:05:47
6	tnet	217.8 KB	0.0 KB	217.8 KB	2.2 KB	24:04:43
7	xmission	190.9 KB	7.5 KB	198.4 KB	2.5 KB	24:02:13
8	usenet.pasdenom.info	38.5 KB	4.6 KB	43.0 KB	3.3 KB	24:09:07
9	aioe	28.7 KB	0.8 KB	29.5 KB	3.3 KB	24:02:35
10	newsfeed.xs4all.nl	27.6 KB	0.0 KB	27.6 KB	2.1 KB	24:09:41
11	erje	19.0 KB	245.7 KB	264.8 KB	2.2 KB	24:08:45
12	eternal-september	11.1 KB	0.0 KB	11.1 KB	5.6 KB	24:09:45
13	neva.ru	6.2 KB	124.3 KB	130.4 KB	2.2 KB	24:06:33
14	neodome.net	6.1 KB	0.0 KB	6.1 KB	2.0 KB	24:09:31
15	news.swapon.de	3.3 KB	0.0 KB	3.3 KB	3.3 KB	23:41:29
16	weretis.net	3.2 KB	2.5 KB	5.6 KB	1.4 KB	24:08:14
17	news.mixmin.net	1.9 KB	0.0 KB	1.9 KB	1.9 KB	24:00:11
18	news.uzoreto.com	1.7 KB	46.4 KB	48.0 KB	3.2 KB	24:09:44
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0	21:36:26
TOTAL: 19		12.6 MB	609.0 KB	13.2 MB	5.5 KB	455:06:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	024-178-047-152.res.spectrum.com	16	14	34.5 KB	6	04:35:39
2	a131033.upc-a.chello.nl	73	14	31.0 KB	6	22:01:25
3	ip72-222-200-117.ph.ph.cox.net	20	8	13.9 KB	4	06:12:43
TOTAL: 3		109	36	79.3 KB	16	57:07:50

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.res.spectrum.com	16	14	34.5 KB	6	04:35:39
2	*.upc-a.chello.nl	73	14	31.0 KB	6	22:01:25
3	*.ph.ph.cox.net	20	8	13.9 KB	4	06:12:43
4	unresolved	40	0	0.0 KB	0	12:50:19
5	*.net.upcbroadband.cz	32	0	0.0 KB	0	10:22:01
6	*.threatsinkhole.com	1	0	0.0 KB	0	00:00:10
7	*.cinci.res.rr.com	2	0	0.0 KB	0	01:05:29
TOTAL: 7		184	36	79.3 KB	16	57:07:50

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
a131033.upc-a.chello.nl	5.104	1.094	22:01:25
91.187.33.87	2.476	0.652	12:50:09
ip-89-176-128-12.net.upcbroadband.cz	2.310	0.434	10:22:01
ip72-222-200-117.ph.ph.cox.net	1.624	0.339	06:12:43
024-178-047-152.res.spectrum.com	1.297	0.248	04:35:39
cpe-174-101-149-78.cinci.res.rr.com	0.249	0.193	01:05:29
winnti-scanner-victims-will-be-notified.threatsinkhole.com	0.069	0.008	00:00:10
192.241.235.168	0.058	0.022	00:00:09
TOTAL: 8	13.187	2.990	57:07:50

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	39
ip-89-176-128-12.net.upcbroadband.cz	32
cpe-174-101-149-78.cinci.res.rr.com	2
winnti-scanner-victims-will-be-notified.threatsinkhole.com	1
192.241.235.168	1
TOTAL: 5	75

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip72-222-200-117.ph.ph.cox.net	10	0
024-178-047-152.res.spectrum.com	7	2
cpe-174-101-149-78.cinci.res.rr.com	2	0
TOTAL: 3	19	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	31
2	comp.unix.aix	5
TOTAL: 2		36

Daily Usenet report for csiph.com

Sep 16 04:15:00 -- Sep 17 04:15:00