Daily Usenet report for csiph.com: Nov 6 04:15:00

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Nov 6 11:06:34 csiph nnrpd[57941]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 6 11:06:34 csiph nnrpd[57941]: 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]
Nov 6 14:39:23 csiph nnrpd[67454]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Nov 6 14:39:23 csiph nnrpd[67454]: 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	19723	49.5%	5.1 MB	64.8%
innd	15333	38.5%	2.0 MB	25.7%
innfeed	3513	8.8%	636.3 KB	7.9%
nnrpd	1233	3.1%	131.0 KB	1.6%
nocem	11	0.0%	1.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39814	100.0%	7.9 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	324036	54.3%
Negative hits	252845	42.4%
Do not exist	19164	3.2%
Cache misses	313	0.1%
TOTAL: 4	596358	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.278	0.0%	18	1.000	15.444	32.500
article cleanup	00:00:00.114	0.0%	18711	0.000	0.006	0.044
article logging	00:00:00.340	0.0%	19152	0.000	0.018	0.764
article parse	00:00:02.434	0.0%	66173	0.000	0.037	0.647
article write	00:00:30.459	0.0%	16087	0.158	1.893	40.455
artlog/artcncl	00:00:00.016	0.0%	18	0.000	0.889	4.000
artlog/artparse	00:00:00.014	0.0%	557	0.000	0.025	0.421
data move	00:00:00.534	0.0%	567088	0.000	0.001	0.115
hisgrep/artcncl	00:00:00.003	0.0%	18	0.000	0.167	1.000
hishave/artcncl	00:00:00.002	0.0%	18	0.000	0.111	0.222
hishave/artparse	00:00:00.001	0.0%	535	0.000	0.002	0.200
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:35.372	0.0%	597443	0.004	0.059	0.418
history sync	00:00:01.116	0.0%	333	0.000	3.351	42.333
history write	00:00:41.135	0.0%	18693	0.420	2.201	10.818
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:01.710	0.0%	534	0.000	3.202	35.500
idle	23:55:47.845	99.7%	568910	88.579	151.426	591.976
nntp read	00:00:02.585	0.0%	575768	0.001	0.004	0.048
overview write	00:01:45.032	0.1%	16087	0.135	6.529	716.706
perl filter	00:00:29.515	0.0%	18672	1.219	1.581	3.787
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.297	0.0%	32185	0.000	0.009	0.032
TOTAL: 24:00:38.220	23:59:58.802	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	16123	0.000	0.002	0.025
article prepare	00:00:00.002	0.0%	10296	0.000	0.000	0.036
article read	00:00:00.434	0.0%	1091	0.000	0.398	21.500
backlog stats	00:00:00.252	0.0%	368902	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.378	0.0%	293356	0.001	0.005	0.013
data write	00:00:01.810	0.0%	279949	0.001	0.006	0.015
idle	23:54:08.614	100.0%	368902	132.416	233.256	693.075
status file	00:00:00.228	0.0%	276	0.000	0.826	9.000
TOTAL: 23:54:20.560	23:54:12.746	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	51:36:54.951	99.8%	836	127.000	222266.688	600111.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.008	0.0%	2019	0.000	0.004	0.167
readart	00:00:00.337	0.0%	50	0.000	6.740	42.000
TOTAL: 51:41:50.893	51:36:55.296	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	news-out.aioe.org	3	15266	3833	11313	120	25%	24:09:00
2	newsfeeds.xmission.com	3	15201	2668	12497	36	17%	23:31:55
3	feeder.eternal-september.org	2	17477	2460	14864	153	14%	23:58:16
4	csiph-out.news.xs4all.nl	421	18461	2015	15315	1131	10%	69:16:52
5	csiph-out.feeder.erje.net	1440	78342	1016	77181	145	1%	118:40:00
6	newsfeed.fu-berlin.de	8	14524	783	13546	195	5%	23:17:29
7	news-out.mixmin.net	1	16854	739	15941	174	4%	23:59:49
8	csiph-out.news.weretis.net	3	32426	684	31638	104	2%	47:59:49
9	newsfeed.neva.ru	149	11669	624	10774	271	5%	71:34:12
10	csiph-out.feed.uzoreto.com	871	24896	365	23847	684	1%	71:23:57
11	news.fcku.it	9	1910	362	1543	5	18%	22:42:13
12	news-out.neodome.net	2	16997	191	16748	58	1%	23:59:52
13	nntp.pasdenom.info	2	10748	136	10597	15	1%	23:58:51
14	news.swapon.de	9	1427	55	1372	0	3%	23:31:06
15	csiph-out.news.tnetconsulting.net	1	15490	27	15442	21	0%	23:59:57
16	localhost	40	14	14	0	0	100%	00:00:03
17	news.samoylyk.net	1	15630	6	15623	1	0%	23:59:54
18	news.bbs.nz	1	15049	6	15043	0	0%	23:59:52
19	news.freedyn.net	3	18299	4	18206	89	0%	23:57:57
20	xanadu-bbs.net	28	450	0	449	1	0%	20:26:42
TOTAL: 20		2997	341130	15988	321939	3203	4%	708:27:46

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	8.7 MB	25.9 KB	462.5 KB	9.2 MB	94%	2.4 KB
2	newsfeeds.xmission.com	7.8 MB	3.3 KB	2.0 MB	9.9 MB	79%	3.7 KB
3	feeder.eternal-september.org	6.4 MB	28.2 KB	977.8 KB	7.4 MB	86%	2.9 KB
4	csiph-out.feeder.erje.net	5.7 MB	0.0 KB	1.8 MB	7.5 MB	76%	6.6 KB
5	csiph-out.news.xs4all.nl	5.0 MB	0.0 KB	18.1 MB	23.2 MB	21%	7.5 KB
6	news-out.mixmin.net	1.9 MB	6.8 KB	307.7 KB	2.2 MB	86%	2.5 KB
7	csiph-out.news.weretis.net	1.8 MB	20.0 KB	1.4 MB	3.3 MB	55%	4.3 KB
8	newsfeed.fu-berlin.de	1.7 MB	0.0 KB	421.7 KB	2.2 MB	80%	2.3 KB
9	newsfeed.neva.ru	1.6 MB	0.0 KB	666.5 KB	2.2 MB	70%	2.5 KB
10	csiph-out.feed.uzoreto.com	1.3 MB	0.0 KB	30.9 MB	32.1 MB	3%	31.4 KB
11	news-out.neodome.net	614.6 KB	27.4 KB	118.9 KB	760.9 KB	80%	3.1 KB
12	news.fcku.it	602.4 KB	0.0 KB	7.0 KB	609.4 KB	98%	1.7 KB
13	nntp.pasdenom.info	298.3 KB	2.1 KB	20.0 KB	320.5 KB	93%	2.1 KB
14	csiph-out.news.tnetconsulting.net	119.5 KB	2.0 KB	87.3 KB	208.8 KB	57%	4.3 KB
15	news.swapon.de	119.3 KB	0.0 KB	0.0 KB	119.3 KB	100%	2.2 KB
16	news.freedyn.net	22.7 KB	26.2 KB	2.8 MB	2.8 MB	0%	30.9 KB
17	localhost	16.1 KB	0.0 KB	0.0 KB	16.1 KB	100%	1.2 KB
18	news.samoylyk.net	14.0 KB	0.6 KB	0.6 KB	15.3 KB	91%	2.2 KB
19	news.bbs.nz	10.9 KB	0.0 KB	0.0 KB	10.9 KB	100%	1.8 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	53.4 KB	53.4 KB	0%	53.4 KB
TOTAL: 20		43.8 MB	142.4 KB	60.0 MB	103.9 MB	42%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 6 04:15:00 - 04:59:59	201	1.2%	0.07	549.5 KB	1.2%	0.20
Nov 6 05:00:00 - 05:59:59	346	2.2%	0.10	1.9 MB	4.3%	0.54
Nov 6 06:00:00 - 06:59:59	415	2.6%	0.12	1.5 MB	3.4%	0.43
Nov 6 07:00:00 - 07:59:59	495	3.1%	0.14	1.5 MB	3.5%	0.44
Nov 6 08:00:00 - 08:59:59	683	4.2%	0.19	1.8 MB	4.1%	0.52
Nov 6 09:00:00 - 09:59:59	731	4.5%	0.20	1.7 MB	3.9%	0.49
Nov 6 10:00:00 - 10:59:59	774	4.8%	0.21	1.9 MB	4.2%	0.53
Nov 6 11:00:00 - 11:59:59	698	4.3%	0.19	1.8 MB	4.1%	0.51
Nov 6 12:00:00 - 12:59:59	833	5.2%	0.23	2.3 MB	5.2%	0.65
Nov 6 13:00:00 - 13:59:59	844	5.2%	0.23	2.0 MB	4.5%	0.56
Nov 6 14:00:00 - 14:59:59	1009	6.3%	0.28	2.7 MB	6.1%	0.76
Nov 6 15:00:00 - 15:59:59	968	6.0%	0.27	3.1 MB	7.0%	0.88
Nov 6 16:00:00 - 16:59:59	1013	6.3%	0.28	2.5 MB	5.8%	0.72
Nov 6 17:00:00 - 17:59:59	1017	6.3%	0.28	2.4 MB	5.5%	0.68
Nov 6 18:00:00 - 18:59:59	785	4.9%	0.22	2.1 MB	4.7%	0.59
Nov 6 19:00:00 - 19:59:59	802	5.0%	0.22	2.1 MB	4.7%	0.59
Nov 6 20:00:00 - 20:59:59	785	4.9%	0.22	2.0 MB	4.5%	0.57
Nov 6 21:00:00 - 21:59:59	637	4.0%	0.18	1.6 MB	3.6%	0.45
Nov 6 22:00:00 - 22:59:59	589	3.7%	0.16	1.6 MB	3.6%	0.45
Nov 6 23:00:00 - 23:59:59	699	4.3%	0.19	2.0 MB	4.5%	0.56
Nov 7 00:00:00 - 00:59:59	529	3.3%	0.15	1.4 MB	3.3%	0.41
Nov 7 01:00:00 - 01:59:59	396	2.5%	0.11	1.2 MB	2.8%	0.35
Nov 7 02:00:00 - 02:59:59	388	2.4%	0.11	1.1 MB	2.6%	0.32
Nov 7 03:00:00 - 03:59:59	354	2.2%	0.10	1.0 MB	2.3%	0.29
Nov 7 04:00:00 - 04:15:00	102	0.6%	0.11	245.2 KB	0.5%	0.27
TOTAL: 24:00:00	16093	100.0%	0.19	44.0 MB	100.0%	0.52

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1146	37	0	1109
2	csiph-out.feed.uzoreto.com	681	22	0	659
3	newsfeed.fu-berlin.de	525	20	0	505
4	newsfeed.neva.ru	242	40	0	202
5	news-out.aioe.org	215	100	5	110
6	news-out.mixmin.net	173	2	4	167
7	feeder.eternal-september.org	155	34	6	115
8	csiph-out.feeder.erje.net	144	57	0	87
9	csiph-out.news.weretis.net	94	36	5	53
10	news.freedyn.net	90	0	5	85
11	news-out.neodome.net	57	1	7	49
12	newsfeeds.xmission.com	41	8	1	32
13	nntp.pasdenom.info	15	0	1	14
14	csiph-out.news.tnetconsulting.net	13	12	1	0
15	news.swapon.de	11	0	0	11
16	news.fcku.it	5	5	0	0
17	news.bbs.nz	3	0	3	0
18	news.samoylyk.net	1	0	1	0
19	xanadu-bbs.net	1	0	0	1
TOTAL: 19		3612	374	39	3199

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	74
lada.talk	30
spline.eisfair	22
alt.politics	18
alt.politics.usa	13
mozilla.test	12
mozilla.dev.security.policy	12
scinet.stats	11
alt.job	10
it-alt.sport.calcio.juventus	10
it-alt.comp.software.mozilla	8
att.jobs	8
alt.philosophy	7
oesterreich.tratsch	7
aus.jobs	7
at.jobs	7
alt.survival	6
alt.windows7.general	6
mozilla.support.seamonkey	6
alt.tv.comedy-central.daily-show	5
TOTAL: 77	374

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1505
EMP (phn path)	243
EMP (phl)	156
Too many newsgroups	81
EMP (phn nph)	70
Binary Payload	56
0	51
HTML Multipart	20
Scoring filter	12
Binary: misplaced binary	7
TOTAL: 10	2201

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	95
	csiph-out.feed.uzoreto.com	11
	csiph-out.news.xs4all.nl	10
	TOTAL: 3	116
Huge articles
	csiph-out.feed.uzoreto.com	292
	csiph-out.news.xs4all.nl	147
	csiph-out.feeder.erje.net	32
	newsfeeds.xmission.com	24
	news.freedyn.net	19
	csiph-out.news.weretis.net	10
	feeder.eternal-september.org	10
	newsfeed.fu-berlin.de	1
	news-out.mixmin.net	1
	xanadu-bbs.net	1
	TOTAL: 10	537
Including strange strings
	newsfeed.fu-berlin.de	317
	news-out.aioe.org	93
	csiph-out.news.xs4all.nl	16
	news.swapon.de	11
	newsfeeds.xmission.com	2
	feeder.eternal-september.org	1
	csiph-out.feeder.erje.net	1
	TOTAL: 7	441
No colon-space in header
	csiph-out.news.xs4all.nl	21
	TOTAL: 1	21
TOTAL: 4		1115

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18989	721	15369	3	0	3%	23:59:54
2	fu-berlin.de	15103	458	14645	0	0	3%	23:59:54
3	news.samoylyk.net	16517	447	15637	3	0	2%	23:59:42
4	news.bbs.nz	30414	364	15226	27	0	1%	23:59:52
5	tnet	17080	66	15560	0	0	0%	23:59:27
6	newsfeed.xs4all.nl	14416	57	13943	2	16	0%	23:59:53
7	neva.ru	14668	45	14437	84	0	0%	23:59:54
8	usenet.pasdenom.info	16045	26	15459	1	12	0%	23:59:54
9	xmission	14134	25	12823	2	0	0%	23:59:54
10	news.freedyn.net	15743	25	15379	44	28	0%	23:59:54
11	aioe	11505	19	11004	1	0	0%	23:59:54
12	erje	16603	16	14830	24	0	0%	23:59:54
13	news.mixmin.net	14858	16	14571	0	0	0%	23:59:35
14	weretis.net	15153	15	14785	0	0	0%	23:59:54
15	news.swapon.de	1435	14	1417	0	0	0%	23:38:55
16	neodome.net	16199	14	15875	0	0	0%	23:59:53
17	eternal-september	13358	13	12980	0	0	0%	23:59:54
18	news.uzoreto.com	15456	12	15182	30	12	0%	23:59:53
19	news.fcku.it	1484	0	1482	0	0	0%	22:19:15
TOTAL: 19		279160	2353	250604	221	68	0%	453:55:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.8 MB	7.9 KB	4.8 MB	0.1 KB/s	6.8 KB	23:59:54
2	fu-berlin.de	4.2 MB	0.0 KB	4.2 MB	0.0 KB/s	9.3 KB	23:59:54
3	news.samoylyk.net	4.1 MB	3.2 KB	4.1 MB	0.0 KB/s	9.3 KB	23:59:42
4	news.bbs.nz	904.0 KB	84.9 KB	988.9 KB	0.0 KB/s	2.5 KB	23:59:52
5	newsfeed.xs4all.nl	164.2 KB	71.9 KB	236.2 KB	0.0 KB/s	4.0 KB	23:59:53
6	tnet	133.4 KB	0.0 KB	133.4 KB	0.0 KB/s	2.0 KB	23:59:27
7	news.freedyn.net	111.2 KB	702.5 KB	813.7 KB	0.0 KB/s	11.8 KB	23:59:54
8	neva.ru	108.5 KB	241.4 KB	349.9 KB	0.0 KB/s	2.7 KB	23:59:54
9	xmission	83.4 KB	2.2 KB	85.7 KB	0.0 KB/s	3.2 KB	23:59:54
10	usenet.pasdenom.info	45.5 KB	5.3 KB	50.8 KB	0.0 KB/s	1.9 KB	23:59:54
11	aioe	33.6 KB	4.6 KB	38.3 KB	0.0 KB/s	1.9 KB	23:59:54
12	erje	28.1 KB	130.2 KB	158.3 KB	0.0 KB/s	4.0 KB	23:59:54
13	news.mixmin.net	22.8 KB	0.0 KB	22.8 KB	0.0 KB/s	1.4 KB	23:59:35
14	neodome.net	18.0 KB	0.0 KB	18.0 KB	0.0 KB/s	1.3 KB	23:59:53
15	weretis.net	17.9 KB	0.0 KB	17.9 KB	0.0 KB/s	1.2 KB	23:59:54
16	news.swapon.de	16.2 KB	0.0 KB	16.2 KB	0.0 KB/s	1.2 KB	23:38:55
17	news.uzoreto.com	15.6 KB	165.5 KB	181.1 KB	0.0 KB/s	4.3 KB	23:59:53
18	eternal-september	15.2 KB	0.0 KB	15.2 KB	0.0 KB/s	1.2 KB	23:59:54
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:19:15
TOTAL: 19		14.7 MB	1.4 MB	16.1 MB	0.0 KB/s	6.4 KB	453:55:25

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	131.93.176.49	38	18	22.7 KB	14	14	11	10:31:20
2	ip72-222-200-117.ph.ph.cox.net	12	18	22.7 KB	21	0	0	04:07:42
3	a131033.upc-a.chello.nl	46	14	17.7 KB	5	0	0	12:02:45
TOTAL: 3		96	50	63.2 KB	40	14	11	51:41:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	unresolved	112	18	22.7 KB	14	14	11	34:31:18
2	*.ph.ph.cox.net	12	18	22.7 KB	21	0	0	04:07:42
3	*.upc-a.chello.nl	46	14	17.7 KB	5	0	0	12:02:45
4	*.range81-135.btcentralplus.com	2	0	0.0 KB	0	0	0	01:00:02
TOTAL: 4		172	50	63.2 KB	40	14	11	51:41:49

NNRP auth users [Top 20]:

User	Conn
ohlandl	11
TOTAL: 1	11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.998	1.771	0.000	23:59:42
a131033.upc-a.chello.nl	3.312	1.651	0.000	12:02:45
131.93.176.49	2.959	0.794	0.030	10:31:20
ip72-222-200-117.ph.ph.cox.net	1.089	0.270	0.000	04:07:42
host81-135-1-153.range81-135.btcentralplus.com	0.127	0.032	0.000	01:00:02
125.64.94.138	0.072	0.008	0.000	00:00:14
183.136.225.45	0.070	0.008	0.000	00:00:00
TOTAL: 7	12.627	4.534	0.030	51:41:49

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
host81-135-1-153.range81-135.btcentralplus.com	2
125.64.94.138	1
183.136.225.45	1
TOTAL: 4	76

NNRP unrecognized commands (by host):

System	Conn
183.136.225.45	5
TOTAL: 1	5

NNRP unrecognized commands (by command):

Command	Count
Connection: keep-alive	1
Accept: /	1
GET / HTTP/1.1	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
Host: 69.160.37.58:119	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
131.93.176.49	9	0
ip72-222-200-117.ph.ph.cox.net	7	0
host81-135-1-153.range81-135.btcentralplus.com	2	0
TOTAL: 3	18	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Nov 6 04:15:00 -- Nov 7 04:15:00