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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Mar 23 04:04:34 csiph nnrpd[28535]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Mar 23 04:04:34 csiph nnrpd[28535]: 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	24879	52.4%	6.1 MB	66.4%
innd	17890	37.7%	2.3 MB	25.2%
innfeed	3473	7.3%	656.0 KB	7.0%
nnrpd	1171	2.5%	123.8 KB	1.3%
nocem	25	0.1%	2.8 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	47439	100.0%	9.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	390726	57.4%
Negative hits	264860	38.9%
Do not exist	24179	3.6%
Cache misses	770	0.1%
TOTAL: 4	680535	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.100	0.0%	14	0.000	7.143	15.000
article cleanup	00:00:00.109	0.0%	23707	0.000	0.005	0.030
article logging	00:00:00.338	0.0%	24366	0.000	0.014	0.216
article parse	00:00:01.160	0.0%	78308	0.000	0.015	0.195
article write	00:00:08.200	0.0%	17878	0.156	0.459	9.851
artlog/artcncl	00:00:00.000	0.0%	14	0.000	0.000	0.000
artlog/artparse	00:00:00.003	0.0%	529	0.000	0.006	0.167
data move	00:00:00.214	0.0%	625078	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.004	0.0%	11	0.000	0.364	1.000
hishave/artcncl	00:00:00.005	0.0%	14	0.000	0.357	1.000
hishave/artparse	00:00:00.001	0.0%	528	0.000	0.002	0.062
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.224	0.0%	682061	0.001	0.008	0.163
history sync	00:00:01.029	0.0%	367	0.000	2.804	10.000
history write	00:00:13.483	0.0%	23668	0.074	0.570	5.435
hiswrite/artcncl	00:00:00.001	0.0%	3	0.000	0.333	1.000
hiswrite/artparse	00:00:00.422	0.0%	528	0.000	0.799	8.133
idle	23:58:32.922	99.8%	632244	56.734	136.518	509.005
nntp read	00:00:02.355	0.0%	634492	0.001	0.004	0.007
overview write	00:00:38.026	0.0%	17878	0.118	2.127	381.804
perl filter	00:00:32.013	0.0%	23667	0.770	1.353	1.947
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.292	0.0%	35785	0.000	0.008	0.038
TOTAL: 24:00:55.891	24:00:15.901	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.042	0.0%	17974	0.000	0.002	0.031
article prepare	00:00:00.002	0.0%	17980	0.000	0.000	0.009
article read	00:00:00.283	0.0%	1890	0.000	0.150	9.200
backlog stats	00:00:00.240	0.0%	420284	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.459	0.0%	332852	0.000	0.004	0.010
data write	00:00:01.808	0.0%	317143	0.000	0.006	0.012
idle	23:54:43.625	100.0%	420284	67.909	204.823	703.056
status file	00:00:04.160	0.0%	262	0.000	15.878	994.750
TOTAL: 23:55:00.252	23:54:51.619	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	56:36:51.598	99.9%	746	0.000	273205.895	600082.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.012	0.0%	1757	0.000	0.007	0.167
readart	00:00:00.437	0.0%	39	0.000	11.205	52.000
TOTAL: 56:41:56.940	56:36:52.047	99.9%	-	-	-	-

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	16685	4202	12422	61	25%	22:48:56
2	news-out.aioe.org	3	17651	3826	13678	147	21%	24:09:56
3	feeder.eternal-september.org	5	19772	2523	16832	417	12%	23:35:04
4	csiph-out.feeder.erje.net	1440	95799	1317	93673	809	1%	118:47:49
5	csiph-out.news.xs4all.nl	861	21365	999	19001	1365	4%	71:26:16
6	news-out.mixmin.net	3	19223	973	17732	518	5%	24:08:54
7	newsfeed.fu-berlin.de	14	18124	938	16845	341	5%	24:46:17
8	csiph-out.news.weretis.net	2	38935	912	37246	777	2%	45:32:58
9	newsfeed.neva.ru	143	22001	524	20778	699	2%	72:11:30
10	news.freedyn.net	1	22014	481	21155	378	2%	23:59:58
11	news.fcku.it	10	2259	272	1985	2	12%	22:57:24
12	csiph-out.feed.uzoreto.com	864	30117	251	29344	522	0%	71:16:44
13	news-out.neodome.net	2	21162	161	20776	225	0%	23:59:49
14	csiph-out.news.tnetconsulting.net	2	17698	61	17613	24	0%	23:59:54
15	nntp.pasdenom.info	2	12126	53	12049	24	0%	23:59:48
16	news.swapon.de	7	1586	45	1540	1	2%	23:15:20
17	news.bbs.nz	1	17488	28	17452	8	0%	23:59:52
18	news.samoylyk.net	2	17492	10	17482	0	0%	24:54:37
19	localhost	28	2	2	0	0	100%	00:00:05
20	xanadu-bbs.net	25	476	0	476	0	0%	21:08:27
TOTAL: 20		3417	411975	17578	388079	6318	4%	710:59:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.8 MB	0.0 KB	4.0 MB	15.8 MB	74%	3.8 KB
2	news-out.aioe.org	9.0 MB	0.0 KB	616.0 KB	9.6 MB	93%	2.5 KB
3	csiph-out.feeder.erje.net	7.8 MB	111.5 KB	12.4 MB	20.3 MB	38%	9.8 KB
4	feeder.eternal-september.org	6.1 MB	0.0 KB	2.3 MB	8.4 MB	72%	2.9 KB
5	csiph-out.news.xs4all.nl	3.1 MB	148.2 KB	15.5 MB	18.7 MB	16%	8.1 KB
6	news-out.mixmin.net	2.8 MB	0.0 KB	1.4 MB	4.2 MB	67%	2.9 KB
7	csiph-out.news.weretis.net	2.6 MB	24.7 KB	10.7 MB	13.3 MB	19%	8.0 KB
8	newsfeed.fu-berlin.de	2.1 MB	0.0 KB	1.4 MB	3.6 MB	59%	2.9 KB
9	news.freedyn.net	1.7 MB	0.0 KB	3.0 MB	4.6 MB	36%	5.5 KB
10	newsfeed.neva.ru	1.2 MB	19.0 KB	4.2 MB	5.4 MB	22%	4.5 KB
11	csiph-out.feed.uzoreto.com	1.1 MB	4.9 KB	10.6 MB	11.7 MB	9%	15.6 KB
12	news.fcku.it	488.2 KB	0.0 KB	3.5 KB	491.7 KB	99%	1.8 KB
13	news-out.neodome.net	443.9 KB	0.0 KB	2.6 MB	3.1 MB	14%	8.1 KB
14	csiph-out.news.tnetconsulting.net	194.9 KB	1.7 KB	272.6 KB	469.2 KB	41%	5.5 KB
15	news.swapon.de	122.4 KB	2.4 KB	2.4 KB	127.2 KB	96%	2.8 KB
16	nntp.pasdenom.info	109.6 KB	0.0 KB	32.8 KB	142.4 KB	76%	1.8 KB
17	news.bbs.nz	81.6 KB	0.0 KB	65.2 KB	146.9 KB	55%	4.1 KB
18	news.samoylyk.net	28.9 KB	0.0 KB	0.0 KB	28.9 KB	100%	2.9 KB
19	localhost	2.7 KB	0.0 KB	0.0 KB	2.7 KB	100%	1.4 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		50.7 MB	312.3 KB	69.0 MB	120.0 MB	42%	5.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 22 04:15:00 - 04:59:59	201	1.1%	0.07	579.3 KB	1.1%	0.21
Mar 22 05:00:00 - 05:59:59	292	1.6%	0.08	807.8 KB	1.5%	0.22
Mar 22 06:00:00 - 06:59:59	337	1.9%	0.09	1.7 MB	3.3%	0.48
Mar 22 07:00:00 - 07:59:59	416	2.3%	0.12	1.4 MB	2.7%	0.40
Mar 22 08:00:00 - 08:59:59	728	4.1%	0.20	2.6 MB	5.0%	0.74
Mar 22 09:00:00 - 09:59:59	836	4.7%	0.23	2.3 MB	4.5%	0.67
Mar 22 10:00:00 - 10:59:59	878	4.9%	0.24	2.2 MB	4.3%	0.64
Mar 22 11:00:00 - 11:59:59	913	5.1%	0.25	2.7 MB	5.3%	0.78
Mar 22 12:00:00 - 12:59:59	859	4.8%	0.24	2.2 MB	4.2%	0.62
Mar 22 13:00:00 - 13:59:59	934	5.2%	0.26	2.3 MB	4.4%	0.65
Mar 22 14:00:00 - 14:59:59	1063	5.9%	0.30	2.7 MB	5.3%	0.77
Mar 22 15:00:00 - 15:59:59	1075	6.0%	0.30	3.3 MB	6.4%	0.93
Mar 22 16:00:00 - 16:59:59	1062	5.9%	0.29	2.7 MB	5.3%	0.78
Mar 22 17:00:00 - 17:59:59	1145	6.4%	0.32	3.2 MB	6.1%	0.90
Mar 22 18:00:00 - 18:59:59	1126	6.3%	0.31	2.8 MB	5.5%	0.81
Mar 22 19:00:00 - 19:59:59	981	5.5%	0.27	2.8 MB	5.4%	0.79
Mar 22 20:00:00 - 20:59:59	986	5.5%	0.27	2.8 MB	5.4%	0.79
Mar 22 21:00:00 - 21:59:59	854	4.8%	0.24	2.5 MB	4.9%	0.72
Mar 22 22:00:00 - 22:59:59	663	3.7%	0.18	1.8 MB	3.5%	0.51
Mar 22 23:00:00 - 23:59:59	626	3.5%	0.17	2.0 MB	4.0%	0.58
Mar 23 00:00:00 - 00:59:59	546	3.1%	0.15	1.6 MB	3.1%	0.45
Mar 23 01:00:00 - 01:59:59	481	2.7%	0.13	1.4 MB	2.8%	0.41
Mar 23 02:00:00 - 02:59:59	410	2.3%	0.11	1.1 MB	2.1%	0.31
Mar 23 03:00:00 - 03:59:59	373	2.1%	0.10	1.8 MB	3.5%	0.51
Mar 23 04:00:00 - 04:15:00	85	0.5%	0.09	276.7 KB	0.5%	0.31
TOTAL: 24:00:00	17870	100.0%	0.21	51.7 MB	100.0%	0.61

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	csiph-out.news.xs4all.nl	1358	382	17	959
2	csiph-out.news.weretis.net	884	622	3	259
3	newsfeed.fu-berlin.de	807	178	0	629
4	csiph-out.feeder.erje.net	737	323	16	398
5	newsfeed.neva.ru	698	332	1	365
6	news-out.mixmin.net	527	6	0	521
7	csiph-out.feed.uzoreto.com	520	194	1	325
8	feeder.eternal-september.org	433	62	0	371
9	news.freedyn.net	381	194	0	187
10	news-out.aioe.org	240	111	0	129
11	news-out.neodome.net	226	207	0	19
12	newsfeeds.xmission.com	71	7	0	64
13	csiph-out.news.tnetconsulting.net	60	57	0	3
14	nntp.pasdenom.info	24	0	0	24
15	news.swapon.de	18	0	1	17
16	news.bbs.nz	9	8	1	0
17	news.fcku.it	2	0	0	2
TOTAL: 17		6995	2683	40	4272

Unwanted newsgroups [Top 20]:

Newsgroup	Count
gmane.linux.kernel	130
alt.computer.workshop	121
spline.eisfair	95
gmane.linux.suse.general	83
gmane.linux.redhat.fedora.devel	80
gmane.linux.gentoo.devel	80
gmane.linux.ports.ppc.embedded	74
gmane.comp.video.videolan.vlc.devel	73
gmane.comp.gis.openstreetmap	71
gmane.comp.gis.openstreetmap.tagging	68
gmane.linux.gentoo.user	68
gmane.linux.debian.devel.mentors	61
gmane.linux.debian.user	61
gmane.linux.debian.user.italian	61
gmane.ietf.announce	60
gmane.linux.debian.devel.general	59
gmane.linux.debian.devel.boot	56
gmane.linux.redhat.fedora.general	55
gmane.comp.voip.openser.user	51
gmane.comp.voip.ser	51
TOTAL: 208	2683

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2193
EMP (phn path)	464
EMP (phl)	281
0	65
HTML Image Tags	32
HTML Multipart	19
Too many newsgroups	17
EMP (phn nph)	12
Scoring filter	10
Binary: misplaced binary	2
HTML post	2
Too many newsgroups (meow)	2
Too many newsgroups (low_xpost)	1
TOTAL: 13	3100

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	65
	csiph-out.news.xs4all.nl	4
	csiph-out.feed.uzoreto.com	4
	TOTAL: 3	73
Huge articles
	csiph-out.feeder.erje.net	182
	csiph-out.news.xs4all.nl	101
	csiph-out.feed.uzoreto.com	78
	csiph-out.news.weretis.net	56
	newsfeeds.xmission.com	55
	news.freedyn.net	10
	news-out.mixmin.net	9
	news-out.neodome.net	9
	feeder.eternal-september.org	8
	newsfeed.fu-berlin.de	4
	TOTAL: 11	515
Including strange strings
	newsfeed.fu-berlin.de	495
	news-out.aioe.org	93
	news.swapon.de	17
	csiph-out.news.weretis.net	16
	news-out.mixmin.net	14
	newsfeeds.xmission.com	9
	csiph-out.feeder.erje.net	3
	newsfeed.neva.ru	3
	csiph-out.news.xs4all.nl	2
	news.freedyn.net	2
	TOTAL: 12	656
No colon-space in header
	csiph-out.feed.uzoreto.com	1
	TOTAL: 1	1
TOTAL: 4		1245

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	21457	1381	16479	8	0	6%	23:59:54
2	news.bbs.nz	33658	546	16741	8	1	1%	23:59:44
3	news.samoylyk.net	18546	542	17306	12	0	2%	23:58:48
4	news.freedyn.net	17742	534	16715	27	0	3%	23:59:54
5	fu-berlin.de	16700	525	16175	0	0	3%	23:59:53
6	tnet	18702	242	17090	4	0	1%	23:59:54
7	newsfeed.xs4all.nl	17381	63	16641	14	128	0%	23:59:54
8	aioe	12841	58	12299	1	0	0%	23:59:54
9	xmission	15327	47	12891	4	0	0%	23:59:52
10	usenet.pasdenom.info	27649	34	17222	22	0	0%	23:59:54
11	neva.ru	16918	28	16573	92	0	0%	23:59:54
12	news.mixmin.net	16598	16	15996	0	0	0%	23:59:54
13	erje	16548	14	16237	200	0	0%	23:59:54
14	neodome.net	18303	14	17654	0	0	0%	23:59:54
15	news.uzoreto.com	17462	10	17032	41	0	0%	23:59:54
16	eternal-september	15239	2	14605	0	0	0%	23:59:54
17	weretis.net	16563	2	16317	1	0	0%	23:59:54
18	news.swapon.de	1631	2	1591	1	0	0%	23:06:58
19	news.fcku.it	1851	0	1847	0	0	0%	21:57:01
TOTAL: 19		321116	4060	277411	435	129	1%	453:00:58

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	7.7 MB	77.5 KB	7.8 MB	0.1 KB/s	5.8 KB	23:59:54
2	news.freedyn.net	5.8 MB	46.1 KB	5.8 MB	0.1 KB/s	10.6 KB	23:59:54
3	fu-berlin.de	5.7 MB	0.0 KB	5.7 MB	0.1 KB/s	11.1 KB	23:59:53
4	news.samoylyk.net	5.6 MB	68.1 KB	5.7 MB	0.1 KB/s	10.5 KB	23:58:48
5	news.bbs.nz	1.4 MB	26.9 KB	1.4 MB	0.0 KB/s	2.6 KB	23:59:44
6	tnet	573.3 KB	6.4 KB	579.7 KB	0.0 KB/s	2.4 KB	23:59:54
7	newsfeed.xs4all.nl	222.0 KB	42.3 KB	264.4 KB	0.0 KB/s	3.4 KB	23:59:54
8	xmission	169.2 KB	9.9 KB	179.1 KB	0.0 KB/s	3.5 KB	23:59:52
9	aioe	115.2 KB	0.7 KB	115.9 KB	0.0 KB/s	2.0 KB	23:59:54
10	usenet.pasdenom.info	109.8 KB	73.7 KB	183.5 KB	0.0 KB/s	3.3 KB	23:59:54
11	neva.ru	50.4 KB	202.2 KB	252.6 KB	0.0 KB/s	2.1 KB	23:59:54
12	erje	29.3 KB	637.7 KB	667.1 KB	0.0 KB/s	3.1 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:54
14	neodome.net	20.5 KB	0.0 KB	20.5 KB	0.0 KB/s	1.5 KB	23:59:54
15	news.uzoreto.com	18.0 KB	137.1 KB	155.1 KB	0.0 KB/s	3.0 KB	23:59:54
16	eternal-september	2.7 KB	0.0 KB	2.7 KB	0.0 KB/s	1.4 KB	23:59:54
17	weretis.net	2.7 KB	1.3 KB	4.0 KB	0.0 KB/s	1.3 KB	23:59:54
18	news.swapon.de	2.7 KB	2.7 KB	5.4 KB	0.0 KB/s	1.8 KB	23:06:58
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:57:01
TOTAL: 19		27.5 MB	1.3 MB	28.8 MB	0.0 KB/s	6.6 KB	453:00:58

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	a131033.upc-a.chello.nl	76	18	36.5 KB	7	0	0	22:44:30
2	5-168-143-94.cust.centrio.cz	7	16	35.7 KB	4	0	0	02:37:49
3	96-42-18-55.dhcp.mdsn.wi.charter.com	17	5	11.1 KB	5	2	2	05:18:52
TOTAL: 3		100	39	83.3 KB	16	2	2	56:41:55

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.upc-a.chello.nl	76	18	36.5 KB	7	0	0	22:44:30
2	*.cust.centrio.cz	7	16	35.7 KB	4	0	0	02:37:49
3	*.dhcp.mdsn.wi.charter.com	17	5	11.1 KB	5	2	2	05:18:52
4	unresolved	80	0	0.0 KB	0	0	0	24:00:16
5	*.20.97.83.ro.ovo.sc	1	0	0.0 KB	0	0	0	00:00:11
6	*.ph.ph.cox.net	4	0	0.0 KB	0	0	0	02:00:15
TOTAL: 6		185	39	83.3 KB	16	2	2	56:41:55

NNRP auth users [Top 20]:

User	Conn
ctomczyk	7
ohlandl	2
TOTAL: 2	9

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.788	1.109	0.000	23:59:55
a131033.upc-a.chello.nl	5.306	1.201	0.000	22:44:30
96-42-18-55.dhcp.mdsn.wi.charter.com	1.161	0.333	0.005	05:18:52
5-168-143-94.cust.centrio.cz	0.661	0.113	0.017	02:37:49
ip72-222-200-117.ph.ph.cox.net	0.463	0.041	0.000	02:00:15
164.52.24.176	0.370	0.123	0.000	00:00:18
46.20.97.83.ro.ovo.sc	0.056	0.024	0.000	00:00:11
94.164.95.80	0.069	0.008	0.000	00:00:01
94.163.164.209	0.048	0.032	0.000	00:00:01
TOTAL: 9	12.922	2.984	0.022	56:41:55

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
164.52.24.176	6
ip72-222-200-117.ph.ph.cox.net	4
94.164.95.80	1
94.163.164.209	1
46.20.97.83.ro.ovo.sc	1
TOTAL: 6	85

NNRP unrecognized commands (by host):

System	Conn
164.52.24.176	8
TOTAL: 1	8

NNRP unrecognized commands (by command):

Command	Count
^V^C^A^A"^A	2
^V^C^A	2
À^SÀ\x09	2
À/À+À'À#À^SÀ\x09	2
TOTAL: 4	8

NNRP client timeouts [Top 20]:

System	Conn	Peer
96-42-18-55.dhcp.mdsn.wi.charter.com	6	0
5-168-143-94.cust.centrio.cz	5	0
ip72-222-200-117.ph.ph.cox.net	4	0
164.52.24.176	2	3
TOTAL: 4	17	3

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	39	97.5%
2	pl	1	2.5%
TOTAL: 2		40	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	24
2	comp.lang.javascript	15
3	pl.comp.www.nowe-strony	1
TOTAL: 3		40

Daily Usenet report for csiph.com

Mar 22 04:15:00 -- Mar 23 04:15:00