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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 26 01:44:00 csiph nnrpd[96161]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Oct 26 01:44:00 csiph nnrpd[96161]: 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	17588	46.8%	4.5 MB	61.6%
innd	15690	41.7%	2.1 MB	28.6%
innfeed	3546	9.4%	639.1 KB	8.6%
nnrpd	742	2.0%	83.6 KB	1.1%
nocem	24	0.1%	2.6 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	37591	100.0%	7.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	285491	54.4%
Negative hits	221534	42.2%
Do not exist	16990	3.2%
Cache misses	642	0.1%
TOTAL: 4	524657	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.403	0.0%	20	0.000	20.150	49.250
article cleanup	00:00:00.103	0.0%	16686	0.000	0.006	0.029
article logging	00:00:00.307	0.0%	17229	0.000	0.018	0.492
article parse	00:00:00.816	0.0%	51227	0.000	0.016	0.358
article write	00:00:10.273	0.0%	13859	0.139	0.741	23.000
artlog/artcncl	00:00:00.000	0.0%	20	0.000	0.000	0.000
artlog/artparse	00:00:00.004	0.0%	342	0.000	0.012	0.500
data move	00:00:00.162	0.0%	498549	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.007	0.0%	20	0.000	0.350	2.000
hishave/artcncl	00:00:00.002	0.0%	20	0.000	0.100	0.250
hishave/artparse	00:00:00.001	0.0%	341	0.000	0.003	0.053
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:05.913	0.0%	525603	0.001	0.011	0.177
history sync	00:00:00.785	0.0%	385	0.000	2.039	14.000
history write	00:00:10.843	0.0%	16685	0.066	0.650	5.521
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.329	0.0%	341	0.000	0.965	78.000
idle	23:57:46.015	99.8%	505055	97.419	170.805	479.444
nntp read	00:00:01.953	0.0%	505858	0.001	0.004	0.008
overview write	00:01:51.947	0.1%	13859	0.088	8.078	1649.513
perl filter	00:00:22.993	0.0%	16684	1.035	1.378	2.048
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.219	0.0%	27742	0.000	0.008	0.038
TOTAL: 24:01:06.802	24:00:33.075	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.030	0.0%	15770	0.000	0.002	0.019
article prepare	00:00:00.002	0.0%	11288	0.000	0.000	0.018
article read	00:00:00.208	0.0%	1202	0.000	0.173	21.500
backlog stats	00:00:00.196	0.0%	321234	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.151	0.0%	252389	0.000	0.005	0.010
data write	00:00:01.429	0.0%	239794	0.002	0.006	0.014
idle	23:53:18.831	100.0%	321234	139.547	267.714	800.282
status file	00:00:00.277	0.0%	299	0.000	0.926	3.200
TOTAL: 23:53:28.973	23:53:22.124	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	36:24:10.738	99.8%	5438	43.000	24099.069	600423.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.074	0.0%	152805	0.000	0.020	33.781
readart	00:01:04.048	0.0%	5009	0.000	12.787	42.000
TOTAL: 36:29:06.820	36:25:17.860	99.8%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news-out.aioe.org	2	13550	3655	9726	169	26%	24:09:59
2	newsfeeds.xmission.com	2	13869	2464	11364	41	17%	26:42:01
3	feeder.eternal-september.org	7	16231	1972	14162	97	12%	25:23:45
4	csiph-out.news.xs4all.nl	511	16756	1631	13792	1333	9%	68:03:15
5	csiph-out.feeder.erje.net	1440	69237	1073	67998	166	1%	118:47:06
6	newsfeed.fu-berlin.de	5	14537	691	13710	136	4%	25:43:30
7	csiph-out.news.weretis.net	3	28794	676	27956	162	2%	48:59:47
8	news-out.mixmin.net	1	15305	641	14242	422	4%	23:59:53
9	newsfeed.neva.ru	144	10847	493	9990	364	4%	72:09:35
10	news-out.neodome.net	1	15428	207	15202	19	1%	23:59:53
11	news.fcku.it	13	1161	175	985	1	15%	22:20:56
12	nntp.pasdenom.info	2	8915	159	8741	15	1%	23:59:25
13	csiph-out.feed.uzoreto.com	864	22937	147	22516	274	0%	71:16:36
14	news.swapon.de	10	1363	77	1286	0	5%	23:35:28
15	csiph-out.news.tnetconsulting.net	1	13378	40	13332	6	0%	23:59:57
16	news.freedyn.net	4	16885	21	16832	32	0%	23:59:42
17	news.bbs.nz	1	4651	5	4646	0	0%	11:20:37
18	news.samoylyk.net	2	13523	2	13518	3	0%	23:59:52
19	localhost	27	1	1	0	0	100%	00:00:04
20	xanadu-bbs.net	27	414	0	414	0	0%	21:14:36
TOTAL: 20		3067	297782	14130	280412	3240	4%	703:45:57

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news-out.aioe.org	8.4 MB	0.0 KB	465.2 KB	8.9 MB	94%	2.4 KB
2	newsfeeds.xmission.com	7.7 MB	0.0 KB	3.3 MB	11.0 MB	70%	4.5 KB
3	feeder.eternal-september.org	5.5 MB	1.9 KB	709.6 KB	6.1 MB	88%	3.0 KB
4	csiph-out.feeder.erje.net	5.3 MB	0.0 KB	2.6 MB	7.9 MB	67%	6.5 KB
5	csiph-out.news.xs4all.nl	4.2 MB	1.4 KB	9.7 MB	13.9 MB	30%	4.8 KB
6	csiph-out.news.weretis.net	1.8 MB	0.0 KB	1.4 MB	3.3 MB	56%	4.0 KB
7	news-out.mixmin.net	1.6 MB	0.0 KB	846.7 KB	2.4 MB	66%	2.4 KB
8	newsfeed.fu-berlin.de	1.5 MB	0.0 KB	309.7 KB	1.8 MB	83%	2.3 KB
9	newsfeed.neva.ru	1.3 MB	0.0 KB	936.8 KB	2.2 MB	57%	2.6 KB
10	csiph-out.feed.uzoreto.com	600.3 KB	0.0 KB	10.5 MB	11.1 MB	5%	26.9 KB
11	news-out.neodome.net	564.8 KB	0.0 KB	553.1 KB	1.1 MB	50%	4.9 KB
12	nntp.pasdenom.info	315.2 KB	0.0 KB	19.6 KB	334.8 KB	94%	1.9 KB
13	news.fcku.it	281.9 KB	0.0 KB	0.8 KB	282.7 KB	99%	1.6 KB
14	news.freedyn.net	248.5 KB	0.0 KB	768.3 KB	1016.8 KB	24%	19.2 KB
15	news.swapon.de	166.6 KB	0.0 KB	0.0 KB	166.6 KB	100%	2.2 KB
16	csiph-out.news.tnetconsulting.net	124.5 KB	2.4 KB	11.3 KB	138.2 KB	90%	3.0 KB
17	news.bbs.nz	12.9 KB	0.0 KB	0.0 KB	12.9 KB	100%	2.6 KB
18	news.samoylyk.net	4.8 KB	0.0 KB	171.7 KB	176.5 KB	2%	35.3 KB
19	localhost	1.2 KB	0.0 KB	0.0 KB	1.2 KB	100%	1.2 KB
20	xanadu-bbs.net	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 20		39.7 MB	5.7 KB	32.1 MB	71.8 MB	55%	4.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 25 04:15:00 - 04:59:59	172	1.2%	0.06	673.7 KB	1.7%	0.25
Oct 25 05:00:00 - 05:59:59	279	2.0%	0.08	801.5 KB	2.0%	0.22
Oct 25 06:00:00 - 06:59:59	345	2.5%	0.10	845.6 KB	2.1%	0.23
Oct 25 07:00:00 - 07:59:59	555	4.0%	0.15	1.7 MB	4.4%	0.48
Oct 25 08:00:00 - 08:59:59	552	4.0%	0.15	1.4 MB	3.6%	0.39
Oct 25 09:00:00 - 09:59:59	723	5.2%	0.20	2.0 MB	5.1%	0.56
Oct 25 10:00:00 - 10:59:59	728	5.3%	0.20	1.9 MB	4.8%	0.53
Oct 25 11:00:00 - 11:59:59	638	4.6%	0.18	1.7 MB	4.4%	0.48
Oct 25 12:00:00 - 12:59:59	649	4.7%	0.18	1.8 MB	4.6%	0.51
Oct 25 13:00:00 - 13:59:59	679	4.9%	0.19	2.1 MB	5.6%	0.61
Oct 25 14:00:00 - 14:59:59	650	4.7%	0.18	1.8 MB	4.7%	0.52
Oct 25 15:00:00 - 15:59:59	795	5.7%	0.22	2.3 MB	5.9%	0.65
Oct 25 16:00:00 - 16:59:59	860	6.2%	0.24	2.6 MB	6.7%	0.73
Oct 25 17:00:00 - 17:59:59	869	6.3%	0.24	2.3 MB	5.8%	0.64
Oct 25 18:00:00 - 18:59:59	874	6.3%	0.24	2.3 MB	5.9%	0.65
Oct 25 19:00:00 - 19:59:59	682	4.9%	0.19	1.7 MB	4.4%	0.48
Oct 25 20:00:00 - 20:59:59	670	4.8%	0.19	1.8 MB	4.6%	0.51
Oct 25 21:00:00 - 21:59:59	642	4.6%	0.18	1.7 MB	4.3%	0.47
Oct 25 22:00:00 - 22:59:59	565	4.1%	0.16	1.6 MB	4.1%	0.45
Oct 25 23:00:00 - 23:59:59	527	3.8%	0.15	1.5 MB	4.0%	0.43
Oct 26 00:00:00 - 00:59:59	371	2.7%	0.10	1.2 MB	3.0%	0.33
Oct 26 01:00:00 - 01:59:59	318	2.3%	0.09	897.5 KB	2.3%	0.25
Oct 26 02:00:00 - 02:59:59	334	2.4%	0.09	1.0 MB	2.6%	0.29
Oct 26 03:00:00 - 03:59:59	309	2.2%	0.09	1.1 MB	2.7%	0.30
Oct 26 04:00:00 - 04:15:00	70	0.5%	0.08	264.6 KB	0.7%	0.29
TOTAL: 24:00:00	13856	100.0%	0.16	38.7 MB	100.0%	0.46

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	csiph-out.news.xs4all.nl	1354	44	1	0	1309
2	newsfeed.fu-berlin.de	473	10	0	0	463
3	news-out.mixmin.net	428	0	0	0	428
4	newsfeed.neva.ru	365	58	0	0	307
5	news-out.aioe.org	308	83	0	0	225
6	csiph-out.feed.uzoreto.com	275	19	0	1	255
7	csiph-out.feeder.erje.net	169	42	0	0	127
8	feeder.eternal-september.org	96	31	1	0	64
9	csiph-out.news.weretis.net	93	48	0	0	45
10	newsfeeds.xmission.com	40	1	0	0	39
11	news.freedyn.net	31	0	0	0	31
12	news.swapon.de	23	0	0	0	23
13	news-out.neodome.net	21	4	0	0	17
14	csiph-out.news.tnetconsulting.net	17	14	0	0	3
15	nntp.pasdenom.info	15	0	0	0	15
16	news.samoylyk.net	3	0	0	0	3
17	news.fcku.it	1	0	0	0	1
TOTAL: 17		3712	354	2	1	3355

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.computer.workshop	57
lada.talk	36
alt.tv.comedy-central.daily-show	33
scinet.stats	30
it-alt.sport.calcio.juventus	19
alt.survival	14
free.it.religioni.scientology	12
lada.movies	11
alt.bbs.synchronet	10
alt.binaries.pictures.purebeauty	9
alt.politics	9
fido7.ru.android	8
alt.philosophy	7
mozilla.general	6
mozilla.support.thunderbird	6
oesterreich.tratsch	6
mozilla.test	6
alt.testing	5
mozilla.support.firefox	5
spline.fli4l	5
TOTAL: 63	354

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)	1753
EMP (phn path)	268
EMP (phl)	168
EMP (phn nph)	125
Too many newsgroups	80
0	46
HTML Multipart	11
Scoring filter	8
Binary Payload	5
Binary: misplaced binary	4
HTML post	1
HTML Image Tags	1
TOTAL: 12	2470

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	85
	csiph-out.feed.uzoreto.com	9
	csiph-out.news.xs4all.nl	8
	TOTAL: 3	102
Huge articles
	csiph-out.feed.uzoreto.com	127
	csiph-out.news.xs4all.nl	97
	csiph-out.feeder.erje.net	42
	newsfeeds.xmission.com	35
	news.freedyn.net	10
	news-out.neodome.net	9
	csiph-out.news.weretis.net	7
	feeder.eternal-september.org	4
	csiph-out.news.tnetconsulting.net	3
	news-out.mixmin.net	3
	TOTAL: 12	341
Including strange strings
	newsfeed.fu-berlin.de	339
	news-out.aioe.org	133
	news.swapon.de	23
	csiph-out.news.xs4all.nl	21
	news-out.mixmin.net	8
	csiph-out.news.weretis.net	6
	newsfeeds.xmission.com	4
	csiph-out.feeder.erje.net	3
	newsfeed.neva.ru	2
	feeder.eternal-september.org	2
	TOTAL: 12	543
No colon-space in header
	csiph-out.feed.uzoreto.com	1
	TOTAL: 1	1
TOTAL: 4		987

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	16696	910	12939	7	0	5%	23:59:55
2	fu-berlin.de	12990	475	12515	0	0	3%	23:59:52
3	news.samoylyk.net	14175	463	13380	10	0	3%	23:59:52
4	news.bbs.nz	26063	399	12938	22	0	1%	23:59:55
5	news.freedyn.net	13525	168	13149	0	0	1%	23:59:55
6	tnet	14641	135	13231	2	0	0%	23:59:38
7	neva.ru	12711	41	12537	68	0	0%	23:59:52
8	newsfeed.xs4all.nl	12481	33	12141	1	1928	0%	23:59:54
9	erje	14347	21	12680	11	0	0%	23:59:54
10	xmission	12123	19	10921	1	0	0%	23:59:51
11	usenet.pasdenom.info	13652	9	13194	3	0	0%	23:59:55
12	news.uzoreto.com	13450	6	13257	3	0	0%	23:59:43
13	news.mixmin.net	12665	6	12451	0	0	0%	23:59:55
14	neodome.net	13864	5	13612	0	0	0%	23:59:46
15	weretis.net	12880	3	12608	3	0	0%	23:59:55
16	eternal-september	11576	1	11350	0	9	0%	23:59:55
17	aioe	9218	1	8940	0	0	0%	23:59:44
18	news.swapon.de	1283	1	1274	0	0	0%	22:54:57
19	news.fcku.it	939	0	939	0	0	0%	21:36:25
TOTAL: 19		239279	2696	214056	131	1937	1%	452:28:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	5.4 MB	79.5 KB	5.5 MB	0.1 KB/s	6.1 KB	23:59:55
2	fu-berlin.de	4.1 MB	0.0 KB	4.1 MB	0.0 KB/s	8.9 KB	23:59:52
3	news.samoylyk.net	4.1 MB	49.4 KB	4.1 MB	0.0 KB/s	8.9 KB	23:59:52
4	news.freedyn.net	1.3 MB	0.0 KB	1.3 MB	0.0 KB/s	8.1 KB	23:59:55
5	news.bbs.nz	1.0 MB	53.8 KB	1.1 MB	0.0 KB/s	2.6 KB	23:59:55
6	tnet	331.8 KB	2.4 KB	334.1 KB	0.0 KB/s	2.4 KB	23:59:38
7	newsfeed.xs4all.nl	124.7 KB	1.1 KB	125.8 KB	0.0 KB/s	3.7 KB	23:59:54
8	neva.ru	94.4 KB	155.1 KB	249.5 KB	0.0 KB/s	2.3 KB	23:59:52
9	erje	57.5 KB	166.3 KB	223.8 KB	0.0 KB/s	7.0 KB	23:59:54
10	xmission	40.6 KB	4.4 KB	45.0 KB	0.0 KB/s	2.3 KB	23:59:51
11	usenet.pasdenom.info	18.2 KB	30.9 KB	49.1 KB	0.0 KB/s	4.1 KB	23:59:55
12	news.uzoreto.com	11.6 KB	5.6 KB	17.2 KB	0.0 KB/s	1.9 KB	23:59:43
13	news.mixmin.net	8.8 KB	0.0 KB	8.8 KB	0.0 KB/s	1.5 KB	23:59:55
14	neodome.net	6.2 KB	0.0 KB	6.2 KB	0.0 KB/s	1.2 KB	23:59:46
15	weretis.net	4.2 KB	3.5 KB	7.7 KB	0.0 KB/s	1.3 KB	23:59:55
16	eternal-september	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	23:59:55
17	aioe	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	23:59:44
18	news.swapon.de	1.2 KB	0.0 KB	1.2 KB	0.0 KB/s	1.2 KB	22:54:57
19	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	21:36:25
TOTAL: 19		16.6 MB	552.0 KB	17.2 MB	0.0 KB/s	6.2 KB	452:28:53

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	aorleans-654-1-144-27.w90-20.abo.wanadoo.fr	6	5003	9.2 MB	2	0	0	01:15:46
2	a131033.upc-a.chello.nl	24	3	3.8 KB	3	0	0	05:14:00
3	071-013-251-224.res.spectrum.com	13	3	3.8 KB	2	1	1	05:23:15
TOTAL: 3		43	5009	9.2 MB	7	1	1	36:29:05

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.w90-20.abo.wanadoo.fr	6	5003	9.2 MB	2	0	0	01:15:46
2	*.res.spectrum.com	13	3	3.8 KB	2	1	1	05:23:15
3	*.upc-a.chello.nl	24	3	3.8 KB	3	0	0	05:14:00
4	*.ch1.censys-scanner.com	2	0	0.0 KB	0	0	0	00:00:00
5	*.dip0.t-ipconnect.de	1	0	0.0 KB	0	0	0	00:00:06
6	unresolved	52	0	0.0 KB	0	0	0	17:14:40
7	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
8	*.home.otenet.gr	1	0	0.0 KB	0	0	0	00:00:02
9	*.ph.ph.cox.net	3	0	0.0 KB	0	0	0	01:30:09
10	*.net.upcbroadband.cz	18	0	0.0 KB	0	0	0	05:51:02
TOTAL: 10		121	5009	9.2 MB	7	1	1	36:29:05

NNRP auth users [Top 20]:

User	Conn
ohlandl	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
91.187.33.87	3.308	0.845	0.000	17:14:40
ip-78-102-198-192.net.upcbroadband.cz	1.153	0.274	0.000	05:51:02
071-013-251-224.res.spectrum.com	0.910	0.189	0.068	05:23:15
a131033.upc-a.chello.nl	1.750	0.406	0.000	05:14:00
ip72-222-200-117.ph.ph.cox.net	0.271	0.168	0.000	01:30:09
aorleans-654-1-144-27.w90-20.abo.wanadoo.fr	1.275	5.017	0.000	01:15:46
p5dee92d7.dip0.t-ipconnect.de	0.103	0.069	0.000	00:00:06
ppp-2-84-161-174.home.otenet.gr	0.060	0.017	0.000	00:00:02
scan-141.security.ipip.net	0.063	0.016	0.000	00:00:00
scanner-04.ch1.censys-scanner.com	0.129	0.033	0.000	00:00:00
TOTAL: 10	9.022	7.034	0.068	36:29:05

Curious NNRP server explorers [Top 20]:

System	Conn
91.187.33.87	52
ip-78-102-198-192.net.upcbroadband.cz	18
ip72-222-200-117.ph.ph.cox.net	3
scanner-04.ch1.censys-scanner.com	2
ppp-2-84-161-174.home.otenet.gr	1
p5dee92d7.dip0.t-ipconnect.de	1
scan-141.security.ipip.net	1
TOTAL: 7	78

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
scanner-04.ch1.censys-scanner.com	1
TOTAL: 2	5

NNRP unrecognized commands (by command):

Command	Count
Connection: close	1
EHLO www.censys.io	1
Host: 69.160.37.58:119	1
GET / HTTP/1.1	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 5	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
071-013-251-224.res.spectrum.com	9	0
ip72-222-200-117.ph.ph.cox.net	3	0
ip-78-102-198-192.net.upcbroadband.cz	1	0
TOTAL: 3	13	1

Newsgroup request counts (by category):

Category		Count	Pct
1	fr	5003	99.9%
2	comp	6	0.1%
TOTAL: 2		5009	100.0%

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

Newsgroup		Count
1	fr.rec.bricolage	5003
2	comp.sys.ibm.ps2.hardware	6
TOTAL: 2		5009

Daily Usenet report for csiph.com

Oct 25 04:15:00 -- Oct 26 04:15:00