Daily Usenet report for csiph.com: Oct 28 04:15:00

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 28 11:57:45 csiph nnrpd[68257]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Oct 28 11:57:45 csiph nnrpd[68257]: 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	19701	49.4%	5.0 MB	64.1%
innd	15636	39.2%	2.1 MB	26.5%
innfeed	3532	8.9%	634.8 KB	8.0%
nnrpd	1006	2.5%	109.8 KB	1.4%
nocem	23	0.1%	2.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	39899	100.0%	7.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	319067	57.2%
Negative hits	219061	39.3%
Do not exist	19059	3.4%
Cache misses	332	0.1%
TOTAL: 4	557519	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.264	0.0%	35	0.000	7.543	17.000
article cleanup	00:00:00.107	0.0%	18723	0.000	0.006	0.035
article logging	00:00:00.266	0.0%	19235	0.000	0.014	0.046
article parse	00:00:00.749	0.0%	59169	0.000	0.013	0.041
article write	00:00:09.532	0.0%	15750	0.186	0.605	27.086
artlog/artcncl	00:00:00.000	0.0%	35	0.000	0.000	0.000
artlog/artparse	00:00:00.006	0.0%	423	0.000	0.014	0.200
data move	00:00:00.254	0.0%	528584	0.000	0.000	0.008
hisgrep/artcncl	00:00:00.013	0.0%	35	0.000	0.371	1.000
hishave/artcncl	00:00:00.002	0.0%	35	0.000	0.057	0.167
hishave/artparse	00:00:00.001	0.0%	417	0.000	0.002	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.833	0.0%	558897	0.001	0.010	0.141
history sync	00:00:00.839	0.0%	376	0.000	2.231	7.000
history write	00:00:10.853	0.0%	18701	0.047	0.580	2.804
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.226	0.0%	417	0.000	0.542	8.000
idle	23:57:03.665	99.7%	535563	92.075	160.996	517.042
nntp read	00:00:02.137	0.0%	536328	0.001	0.004	0.007
overview write	00:02:18.576	0.2%	15750	0.086	8.798	2530.929
perl filter	00:00:28.773	0.0%	18695	1.154	1.539	4.253
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.271	0.0%	31522	0.000	0.009	0.031
TOTAL: 24:01:02.337	24:00:22.367	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.040	0.0%	16124	0.000	0.002	0.038
article prepare	00:00:00.000	0.0%	14872	0.000	0.000	0.000
article read	00:00:00.285	0.0%	1597	0.000	0.178	11.000
backlog stats	00:00:00.207	0.0%	363478	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.301	0.0%	287501	0.001	0.005	0.009
data write	00:00:01.639	0.0%	274432	0.000	0.006	0.011
idle	23:54:30.491	100.0%	363478	140.060	236.797	810.926
status file	00:00:00.237	0.0%	281	0.000	0.843	16.000
TOTAL: 23:54:43.125	23:54:34.200	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	44:16:59.788	99.8%	605	1.000	263503.782	600154.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	1333	0.000	0.003	0.167
readart	00:00:00.209	0.0%	12	0.000	17.417	42.000
TOTAL: 44:21:59.008	44:17:00.001	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeeds.xmission.com	2	15120	3511	11553	56	23%	24:21:27
2	csiph-out.news.xs4all.nl	495	18368	2778	14012	1578	15%	68:19:58
3	feeder.eternal-september.org	4	18420	2391	15909	120	12%	26:24:29
4	news-out.mixmin.net	2	16603	2290	14114	199	13%	24:08:56
5	news-out.aioe.org	1	15380	985	14291	104	6%	23:59:56
6	csiph-out.feeder.erje.net	1441	77756	979	76606	171	1%	118:40:45
7	newsfeed.fu-berlin.de	9	19336	943	18126	267	4%	32:56:00
8	csiph-out.news.weretis.net	3	32446	678	31682	86	2%	47:59:29
9	newsfeed.neva.ru	101	10047	581	9022	444	5%	48:10:51
10	news.fcku.it	12	1826	311	1511	4	17%	21:36:10
11	news-out.neodome.net	1	17061	202	16834	25	1%	23:59:55
12	nntp.pasdenom.info	2	10606	163	10426	17	1%	23:58:48
13	csiph-out.feed.uzoreto.com	864	24752	102	24355	295	0%	71:11:59
14	news.swapon.de	8	1343	56	1283	4	4%	23:16:12
15	csiph-out.news.tnetconsulting.net	2	15753	35	15694	24	0%	23:59:47
16	news.bbs.nz	2	15391	16	15374	1	0%	23:59:51
17	news.freedyn.net	33	18460	8	18423	29	0%	23:54:12
18	news.samoylyk.net	3	15417	4	15411	2	0%	24:53:10
19	localhost	29	3	3	0	0	100%	00:00:00
20	xanadu-bbs.net	31	340	0	340	0	0%	20:18:43
TOTAL: 20		3045	344428	16036	324966	3426	4%	696:10:38

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeeds.xmission.com	11.2 MB	0.0 KB	4.1 MB	15.3 MB	73%	4.4 KB
2	csiph-out.news.xs4all.nl	7.2 MB	3.6 KB	16.4 MB	23.6 MB	30%	5.5 KB
3	feeder.eternal-september.org	6.6 MB	4.3 KB	1.2 MB	7.8 MB	84%	3.2 KB
4	csiph-out.feeder.erje.net	5.9 MB	8.3 KB	2.4 MB	8.3 MB	71%	7.4 KB
5	news-out.mixmin.net	4.8 MB	3.5 KB	458.2 KB	5.2 MB	91%	2.2 KB
6	news-out.aioe.org	3.4 MB	3.4 KB	268.1 KB	3.7 MB	92%	3.5 KB
7	newsfeed.fu-berlin.de	2.4 MB	0.0 KB	729.0 KB	3.1 MB	76%	2.6 KB
8	csiph-out.news.weretis.net	2.0 MB	4.2 KB	1.2 MB	3.2 MB	63%	4.3 KB
9	newsfeed.neva.ru	1.7 MB	7.0 KB	911.0 KB	2.6 MB	65%	2.6 KB
10	csiph-out.feed.uzoreto.com	672.2 KB	3.4 KB	10.8 MB	11.5 MB	5%	29.6 KB
11	news-out.neodome.net	551.3 KB	3.5 KB	136.1 KB	691.0 KB	79%	3.0 KB
12	news.fcku.it	532.9 KB	1.9 KB	6.0 KB	540.8 KB	98%	1.7 KB
13	nntp.pasdenom.info	345.6 KB	0.0 KB	21.8 KB	367.4 KB	94%	2.0 KB
14	news.swapon.de	167.8 KB	0.0 KB	9.6 KB	177.3 KB	94%	3.0 KB
15	csiph-out.news.tnetconsulting.net	136.9 KB	0.0 KB	297.8 KB	434.7 KB	31%	7.4 KB
16	news.freedyn.net	124.3 KB	2.3 KB	1.1 MB	1.2 MB	9%	34.0 KB
17	news.bbs.nz	35.0 KB	0.0 KB	1.8 KB	36.8 KB	95%	2.2 KB
18	news.samoylyk.net	8.9 KB	3.5 KB	3.5 KB	15.9 KB	56%	2.7 KB
19	localhost	7.3 KB	0.0 KB	0.0 KB	7.3 KB	100%	2.4 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		47.8 MB	49.1 KB	40.0 MB	87.8 MB	54%	4.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 28 04:15:00 - 04:59:59	197	1.3%	0.07	627.9 KB	1.3%	0.23
Oct 28 05:00:00 - 05:59:59	308	2.0%	0.09	1.6 MB	3.4%	0.46
Oct 28 06:00:00 - 06:59:59	370	2.3%	0.10	1.1 MB	2.3%	0.31
Oct 28 07:00:00 - 07:59:59	469	3.0%	0.13	1.5 MB	3.1%	0.41
Oct 28 08:00:00 - 08:59:59	703	4.5%	0.20	1.8 MB	3.8%	0.50
Oct 28 09:00:00 - 09:59:59	825	5.2%	0.23	2.2 MB	4.8%	0.64
Oct 28 10:00:00 - 10:59:59	836	5.3%	0.23	2.2 MB	4.7%	0.63
Oct 28 11:00:00 - 11:59:59	845	5.4%	0.23	2.5 MB	5.4%	0.71
Oct 28 12:00:00 - 12:59:59	805	5.1%	0.22	2.3 MB	4.9%	0.65
Oct 28 13:00:00 - 13:59:59	871	5.5%	0.24	2.1 MB	4.6%	0.61
Oct 28 14:00:00 - 14:59:59	811	5.2%	0.23	2.0 MB	4.4%	0.58
Oct 28 15:00:00 - 15:59:59	972	6.2%	0.27	3.5 MB	7.4%	0.99
Oct 28 16:00:00 - 16:59:59	992	6.3%	0.28	3.2 MB	6.8%	0.91
Oct 28 17:00:00 - 17:59:59	956	6.1%	0.27	2.9 MB	6.2%	0.82
Oct 28 18:00:00 - 18:59:59	891	5.7%	0.25	2.3 MB	5.0%	0.66
Oct 28 19:00:00 - 19:59:59	846	5.4%	0.23	2.4 MB	5.2%	0.69
Oct 28 20:00:00 - 20:59:59	683	4.3%	0.19	2.2 MB	4.8%	0.64
Oct 28 21:00:00 - 21:59:59	696	4.4%	0.19	2.2 MB	4.7%	0.63
Oct 28 22:00:00 - 22:59:59	608	3.9%	0.17	1.7 MB	3.6%	0.48
Oct 28 23:00:00 - 23:59:59	562	3.6%	0.16	1.7 MB	3.7%	0.49
Oct 29 00:00:00 - 00:59:59	433	2.7%	0.12	1.3 MB	2.7%	0.36
Oct 29 01:00:00 - 01:59:59	360	2.3%	0.10	1.1 MB	2.4%	0.32
Oct 29 02:00:00 - 02:59:59	290	1.8%	0.08	966.1 KB	2.0%	0.27
Oct 29 03:00:00 - 03:59:59	325	2.1%	0.09	1.1 MB	2.4%	0.33
Oct 29 04:00:00 - 04:15:00	93	0.6%	0.10	225.2 KB	0.5%	0.25
TOTAL: 24:00:00	15747	100.0%	0.18	46.9 MB	100.0%	0.56

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1618	100	2	1	1515
2	newsfeed.fu-berlin.de	596	18	0	0	578
3	newsfeed.neva.ru	449	100	4	0	345
4	csiph-out.feed.uzoreto.com	296	12	2	0	282
5	news-out.mixmin.net	250	7	2	0	241
6	csiph-out.feeder.erje.net	171	60	4	0	107
7	news-out.aioe.org	130	75	2	0	53
8	feeder.eternal-september.org	121	54	2	0	65
9	csiph-out.news.weretis.net	97	50	2	0	45
10	newsfeeds.xmission.com	58	8	0	0	50
11	csiph-out.news.tnetconsulting.net	29	24	2	0	3
12	news.freedyn.net	28	0	1	0	27
13	news.swapon.de	27	0	0	0	27
14	news-out.neodome.net	25	14	2	0	9
15	nntp.pasdenom.info	17	1	0	0	16
16	news.fcku.it	4	3	1	0	0
17	news.samoylyk.net	2	0	2	0	0
18	news.bbs.nz	1	1	0	0	0
TOTAL: 18		3919	527	28	1	3363

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	93
alt.computer.workshop	68
it-alt.sport.calcio.juventus	49
scinet.stats	25
alt.bbs.synchronet	18
alt.politics	17
trollnet.cinema	15
alt.job	14
mozilla.general	14
free.it.religioni.scientology	13
mozilla.support.seamonkey	13
lada.auto	13
alt.binaries.pictures.purebeauty	12
alt.philosophy	11
mozilla.support.thunderbird	11
at.jobs	8
att.jobs	8
oesterreich.tratsch	8
alt.politics.usa	7
mozilla.dev.security.policy	7
TOTAL: 70	527

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)	1803
EMP (phn path)	248
EMP (phl)	176
Too many newsgroups	91
Binary Payload	41
0	41
Scoring filter	11
Binary: misplaced binary	6
HTML Multipart	6
HTML post	1
TOTAL: 10	2424

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	172
	csiph-out.feed.uzoreto.com	128
	newsfeeds.xmission.com	47
	csiph-out.feeder.erje.net	32
	news.freedyn.net	17
	csiph-out.news.weretis.net	11
	feeder.eternal-september.org	6
	news-out.mixmin.net	3
	newsfeed.fu-berlin.de	3
	csiph-out.news.tnetconsulting.net	1
	TOTAL: 11	421
Including strange strings
	newsfeed.fu-berlin.de	360
	news-out.mixmin.net	57
	csiph-out.news.xs4all.nl	37
	news-out.aioe.org	28
	news.swapon.de	23
	newsfeeds.xmission.com	3
	feeder.eternal-september.org	2
	csiph-out.news.weretis.net	1
	csiph-out.feed.uzoreto.com	1
	newsfeed.neva.ru	1
	TOTAL: 10	513
No colon-space in header
	csiph-out.news.xs4all.nl	5
	csiph-out.feed.uzoreto.com	1
	TOTAL: 2	6
TOTAL: 4		1021

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	19912	1144	14588	15	45	5%	23:59:53
2	news.bbs.nz	29684	510	14706	19	0	1%	23:59:53
3	fu-berlin.de	14743	463	14280	0	0	3%	23:59:53
4	news.samoylyk.net	16409	447	15287	9	109	2%	23:59:53
5	tnet	16960	169	15077	0	0	0%	23:59:53
6	newsfeed.xs4all.nl	13349	69	12826	2	3	0%	23:59:53
7	news.freedyn.net	15347	62	14644	336	222	0%	23:59:53
8	xmission	13566	45	11664	1	0	0%	23:59:35
9	neva.ru	14375	37	14094	126	0	0%	23:59:38
10	news.uzoreto.com	14796	19	14482	141	0	0%	23:59:53
11	usenet.pasdenom.info	15651	15	15061	3	12	0%	23:59:53
12	erje	16288	14	14598	37	13	0%	23:59:53
13	news.mixmin.net	13042	9	12728	0	0	0%	23:59:51
14	neodome.net	15901	7	15511	0	0	0%	23:59:21
15	aioe	11682	6	11146	3	0	0%	23:59:46
16	eternal-september	13151	3	12802	0	0	0%	23:59:53
17	weretis.net	14992	3	14535	1	0	0%	23:59:39
18	news.swapon.de	1283	3	1273	0	0	0%	23:25:43
19	news.fcku.it	1444	0	1442	0	0	0%	21:20:34
TOTAL: 19		272575	3025	240744	693	404	1%	452:42:50

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	6.7 MB	105.2 KB	6.8 MB	0.1 KB/s	6.0 KB	23:59:53
2	fu-berlin.de	4.5 MB	0.0 KB	4.5 MB	0.1 KB/s	10.0 KB	23:59:53
3	news.samoylyk.net	4.4 MB	62.2 KB	4.5 MB	0.1 KB/s	10.0 KB	23:59:53
4	news.bbs.nz	1.5 MB	81.2 KB	1.6 MB	0.0 KB/s	3.0 KB	23:59:53
5	news.freedyn.net	454.3 KB	3.2 MB	3.6 MB	0.0 KB/s	9.3 KB	23:59:53
6	tnet	425.4 KB	0.0 KB	425.4 KB	0.0 KB/s	2.5 KB	23:59:53
7	newsfeed.xs4all.nl	234.8 KB	39.1 KB	273.9 KB	0.0 KB/s	3.9 KB	23:59:53
8	xmission	178.2 KB	6.8 KB	185.0 KB	0.0 KB/s	4.0 KB	23:59:35
9	neva.ru	105.3 KB	311.3 KB	416.6 KB	0.0 KB/s	2.6 KB	23:59:38
10	usenet.pasdenom.info	98.9 KB	5.3 KB	104.1 KB	0.0 KB/s	5.8 KB	23:59:53
11	news.mixmin.net	81.4 KB	0.0 KB	81.4 KB	0.0 KB/s	9.0 KB	23:59:51
12	news.uzoreto.com	65.2 KB	446.7 KB	511.9 KB	0.0 KB/s	3.2 KB	23:59:53
13	erje	27.4 KB	336.8 KB	364.2 KB	0.0 KB/s	7.1 KB	23:59:53
14	neodome.net	15.3 KB	0.0 KB	15.3 KB	0.0 KB/s	2.2 KB	23:59:21
15	aioe	13.2 KB	3.3 KB	16.5 KB	0.0 KB/s	1.8 KB	23:59:46
16	eternal-september	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	2.4 KB	23:59:53
17	weretis.net	7.3 KB	1.1 KB	8.4 KB	0.0 KB/s	2.1 KB	23:59:39
18	news.swapon.de	7.3 KB	0.0 KB	7.3 KB	0.0 KB/s	2.4 KB	23:25:43
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:20:34
TOTAL: 19		18.8 MB	4.5 MB	23.3 MB	0.0 KB/s	6.4 KB	452:42:50

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	ip72-222-200-117.ph.ph.cox.net	5	6	11.2 KB	7	0	0	02:33:06
2	071-013-251-224.res.spectrum.com	19	5	10.3 KB	5	3	2	05:41:40
3	a131033.upc-a.chello.nl	60	1	1.4 KB	1	0	0	12:06:49
TOTAL: 3		84	12	22.9 KB	13	3	2	44:21:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.ph.ph.cox.net	5	6	11.2 KB	7	0	0	02:33:06
2	*.res.spectrum.com	19	5	10.3 KB	5	3	2	05:41:40
3	*.upc-a.chello.nl	60	1	1.4 KB	1	0	0	12:06:49
4	unresolved	72	0	0.0 KB	0	0	0	24:00:14
5	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
6	*.internet-census.org	6	0	0.0 KB	0	0	0	00:00:01
7	*.home.otenet.gr	3	0	0.0 KB	0	0	0	00:00:04
TOTAL: 7		166	12	22.9 KB	13	3	2	44:21:57

NNRP auth users [Top 20]:

User	Conn
ohlandl	2
TOTAL: 1	2

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	4.686	1.624	0.000	24:00:14
a131033.upc-a.chello.nl	3.714	1.217	0.000	12:06:49
071-013-251-224.res.spectrum.com	1.481	0.404	0.006	05:41:40
ip72-222-200-117.ph.ph.cox.net	0.517	0.064	0.000	02:33:06
ppp-2-84-161-174.home.otenet.gr	0.123	0.025	0.000	00:00:03
ppp-2-87-178-169.home.otenet.gr	0.066	0.008	0.000	00:00:01
sh-phx-us-gp1-wk106.internet-census.org	0.375	0.082	0.000	00:00:01
scan-141.security.ipip.net	0.063	0.087	0.000	00:00:00
TOTAL: 8	11.025	3.511	0.006	44:21:57

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	72
sh-phx-us-gp1-wk106.internet-census.org	6
ppp-2-84-161-174.home.otenet.gr	2
scan-141.security.ipip.net	1
ppp-2-87-178-169.home.otenet.gr	1
TOTAL: 5	82

NNRP unrecognized commands (by host):

System	Conn
sh-phx-us-gp1-wk106.internet-census.org	10
scan-141.security.ipip.net	4
TOTAL: 2	14

NNRP unrecognized commands (by command):

Command	Count
Host: 69.160.37.58:119	2
EHLO sh-phx-us-gp1-wk106.internet-census...	2
GET / HTTP/1.1	2
SSH-2.0-Go	1
Accept-Encoding: gzip	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
Accept: /	1
Connection: close	1
^A	1
User-Agent: HTTP Banner Detection (https...	1
^V^C^A	1
TOTAL: 11	14

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-251-224.res.spectrum.com	7	0
ip72-222-200-117.ph.ph.cox.net	5	0
TOTAL: 2	12	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report for csiph.com

Oct 28 04:15:00 -- Oct 29 04:15:00