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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 2 03:53:24 csiph pgpverify[26797]: pgpverify: verification failed
Oct 2 03:53:24 csiph controlchan[59336]: Cannot send mail: (<1569988379.2933@news.sztaki.hu>)

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	19980	48.0%	5.1 MB	64.3%
innd	17758	42.6%	2.2 MB	27.7%
innfeed	3360	8.1%	598.1 KB	7.3%
nnrpd	493	1.2%	56.7 KB	0.7%
nocem	36	0.1%	3.8 KB	0.0%
controlchan	13	0.0%	2.6 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	41641	100.0%	8.0 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	291119	58.2%
Negative hits	189456	37.9%
Do not exist	19388	3.9%
Cache misses	243	0.0%
TOTAL: 4	500206	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.376	0.0%	109	0.000	3.450	43.667
article cleanup	00:00:00.114	0.0%	18663	0.000	0.006	0.032
article logging	00:00:00.331	0.0%	19058	0.000	0.017	0.150
article parse	00:00:01.727	0.0%	72645	0.000	0.024	0.237
article write	00:00:20.461	0.0%	15731	0.250	1.301	57.717
artlog/artcncl	00:00:00.004	0.0%	92	0.000	0.043	0.400
artlog/artparse	00:00:00.025	0.0%	837	0.000	0.030	1.000
data move	00:00:00.123	0.0%	484963	0.000	0.000	0.001
hisgrep/artcncl	00:00:00.018	0.0%	101	0.000	0.178	1.000
hishave/artcncl	00:00:00.003	0.0%	109	0.000	0.028	0.500
hishave/artparse	00:00:00.001	0.0%	837	0.000	0.001	0.032
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:09.034	0.0%	502257	0.001	0.018	0.101
history sync	00:00:00.937	0.0%	383	0.000	2.446	19.000
history write	00:00:19.412	0.0%	18663	0.321	1.040	5.796
hiswrite/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hiswrite/artparse	00:00:01.036	0.0%	837	0.000	1.238	14.100
idle	23:57:55.963	99.8%	492116	95.826	175.316	630.781
nntp read	00:00:02.059	0.0%	492661	0.002	0.004	0.033
overview write	00:01:05.934	0.1%	15731	0.037	4.191	383.151
perl filter	00:00:30.279	0.0%	18663	1.075	1.622	2.981
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.257	0.0%	31495	0.000	0.008	0.029
TOTAL: 24:01:03.098	24:00:28.094	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.160	0.0%	15837	0.000	0.010	1.486
article prepare	00:00:00.001	0.0%	12208	0.000	0.000	0.007
article read	00:00:00.460	0.0%	911	0.000	0.505	10.714
backlog stats	00:00:00.174	0.0%	348869	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.349	0.0%	269532	0.001	0.005	0.012
data write	00:00:01.622	0.0%	248481	0.002	0.007	0.012
idle	23:54:46.777	100.0%	348869	143.210	246.760	892.461
status file	00:00:00.372	0.0%	268	0.000	1.388	25.000
TOTAL: 23:54:59.419	23:54:50.915	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	17:46:18.885	100.0%	345	29.000	185446.043	900162.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.004	0.0%	833	0.000	0.005	0.333
readart	00:00:00.421	0.0%	35	0.000	12.029	47.000
TOTAL: 17:46:37.190	17:46:19.310	100.0%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
hun-mnt@news.sztaki.hu	0	0	1	1	0	1
TOTAL	0	0	1	1	0	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	csiph-out.news.weretis.net	5	30946	3197	27558	191	10%	46:23:06
2	csiph-out.feeder.erje.net	1440	75184	2857	72058	269	3%	118:42:19
3	feeder.eternal-september.org	4	17190	1986	15119	85	11%	24:46:07
4	news.freedyn.net	2	18840	1544	16096	1200	8%	24:09:04
5	csiph-out.news.xs4all.nl	861	17469	1461	14727	1281	8%	71:28:11
6	news-out.aioe.org	3	15397	1268	14108	21	8%	24:08:49
7	newsfeed.fu-berlin.de	16	17717	954	16671	92	5%	31:08:17
8	newsfeed.neva.ru	143	20648	866	19513	269	4%	72:18:32
9	news-out.mixmin.net	1	16172	697	15365	110	4%	23:59:51
10	newsfeeds.xmission.com	2	13147	345	12801	1	2%	19:42:24
11	news.fcku.it	8	1987	272	1707	8	13%	22:59:43
12	csiph-out.feed.uzoreto.com	864	24178	158	23838	182	0%	71:12:22
13	nntp.pasdenom.info	2	11066	30	11020	16	0%	23:58:48
14	news.swapon.de	11	1201	16	1185	0	1%	23:05:21
15	news.samoylyk.net	3	15430	16	15395	19	0%	25:48:31
16	localhost	39	8	8	0	0	100%	00:00:05
17	news.bbs.nz	2	15736	5	15731	0	0%	23:59:50
18	xanadu-bbs.net	29	426	1	424	1	0%	21:34:36
TOTAL: 18		3435	312742	15681	293316	3745	5%	669:25:56

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	csiph-out.feeder.erje.net	11.0 MB	5.5 MB	16.5 MB	66%	5.4 KB
2	csiph-out.news.weretis.net	9.3 MB	4.9 MB	14.2 MB	65%	4.3 KB
3	feeder.eternal-september.org	5.1 MB	551.4 KB	5.7 MB	90%	2.8 KB
4	news.freedyn.net	4.0 MB	54.9 MB	58.9 MB	6%	22.0 KB
5	csiph-out.news.xs4all.nl	3.9 MB	11.5 MB	15.4 MB	25%	5.7 KB
6	news-out.aioe.org	2.9 MB	23.2 KB	2.9 MB	99%	2.3 KB
7	newsfeed.fu-berlin.de	2.3 MB	210.2 KB	2.5 MB	91%	2.4 KB
8	news-out.mixmin.net	2.3 MB	301.1 KB	2.6 MB	88%	3.2 KB
9	newsfeed.neva.ru	1.9 MB	932.1 KB	2.8 MB	67%	2.5 KB
10	newsfeeds.xmission.com	1.3 MB	50.3 KB	1.4 MB	96%	4.0 KB
11	csiph-out.feed.uzoreto.com	513.2 KB	4.8 MB	5.3 MB	9%	16.1 KB
12	news.fcku.it	477.8 KB	69.3 KB	547.1 KB	87%	2.0 KB
13	nntp.pasdenom.info	73.6 KB	34.9 KB	108.5 KB	67%	2.4 KB
14	news.swapon.de	55.7 KB	0.0 KB	55.7 KB	100%	3.5 KB
15	news.samoylyk.net	46.8 KB	1.5 MB	1.6 MB	2%	45.5 KB
16	localhost	9.6 KB	0.0 KB	9.6 KB	100%	1.2 KB
17	news.bbs.nz	6.5 KB	0.0 KB	6.5 KB	100%	1.3 KB
18	xanadu-bbs.net	1.8 KB	51.3 KB	53.1 KB	3%	26.5 KB
TOTAL: 18		45.1 MB	85.3 MB	130.3 MB	34%	6.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 04:15:00 - 04:59:59	305	1.9%	0.11	1.7 MB	3.7%	0.63
Oct 1 05:00:00 - 05:59:59	404	2.6%	0.11	1.4 MB	3.1%	0.40
Oct 1 06:00:00 - 06:59:59	489	3.1%	0.14	1.8 MB	3.9%	0.50
Oct 1 07:00:00 - 07:59:59	672	4.3%	0.19	1.9 MB	4.3%	0.55
Oct 1 08:00:00 - 08:59:59	692	4.4%	0.19	1.8 MB	4.0%	0.52
Oct 1 09:00:00 - 09:59:59	734	4.7%	0.20	2.1 MB	4.6%	0.59
Oct 1 10:00:00 - 10:59:59	785	5.0%	0.22	2.0 MB	4.4%	0.57
Oct 1 11:00:00 - 11:59:59	786	5.0%	0.22	2.1 MB	4.5%	0.58
Oct 1 12:00:00 - 12:59:59	757	4.8%	0.21	1.8 MB	4.1%	0.52
Oct 1 13:00:00 - 13:59:59	834	5.3%	0.23	1.9 MB	4.3%	0.55
Oct 1 14:00:00 - 14:59:59	924	5.9%	0.26	2.8 MB	6.2%	0.80
Oct 1 15:00:00 - 15:59:59	882	5.6%	0.24	2.2 MB	4.9%	0.63
Oct 1 16:00:00 - 16:59:59	966	6.1%	0.27	2.9 MB	6.5%	0.84
Oct 1 17:00:00 - 17:59:59	805	5.1%	0.22	2.4 MB	5.2%	0.67
Oct 1 18:00:00 - 18:59:59	847	5.4%	0.24	2.6 MB	5.7%	0.73
Oct 1 19:00:00 - 19:59:59	785	5.0%	0.22	1.9 MB	4.2%	0.54
Oct 1 20:00:00 - 20:59:59	829	5.3%	0.23	2.3 MB	5.0%	0.64
Oct 1 21:00:00 - 21:59:59	722	4.6%	0.20	2.2 MB	4.8%	0.62
Oct 1 22:00:00 - 22:59:59	538	3.4%	0.15	1.6 MB	3.6%	0.47
Oct 1 23:00:00 - 23:59:59	507	3.2%	0.14	1.4 MB	3.2%	0.41
Oct 2 00:00:00 - 00:59:59	449	2.9%	0.12	1.3 MB	2.8%	0.36
Oct 2 01:00:00 - 01:59:59	310	2.0%	0.09	885.3 KB	1.9%	0.25
Oct 2 02:00:00 - 02:59:59	315	2.0%	0.09	1.1 MB	2.4%	0.30
Oct 2 03:00:00 - 03:59:59	295	1.9%	0.08	882.4 KB	1.9%	0.25
Oct 2 04:00:00 - 04:15:00	91	0.6%	0.10	396.3 KB	0.9%	0.44
TOTAL: 24:00:00	15723	100.0%	0.18	45.2 MB	100.0%	0.54

Sites sending bad articles:

Server		Total	Group	Other
1	csiph-out.news.xs4all.nl	1330	19	1311
2	news.freedyn.net	1200	15	1185
3	newsfeed.fu-berlin.de	404	13	391
4	csiph-out.feeder.erje.net	271	70	201
5	newsfeed.neva.ru	270	54	216
6	csiph-out.news.weretis.net	225	107	118
7	csiph-out.feed.uzoreto.com	184	5	179
8	news-out.mixmin.net	123	0	123
9	feeder.eternal-september.org	85	9	76
10	news-out.aioe.org	21	21	0
11	news.samoylyk.net	19	0	19
12	nntp.pasdenom.info	16	0	16
13	news.fcku.it	8	7	1
14	newsfeeds.xmission.com	5	0	5
15	news.swapon.de	4	0	4
16	xanadu-bbs.net	1	0	1
TOTAL: 16		4166	320	3846

Unwanted newsgroups [Top 20]:

Newsgroup	Count
lada.talk	40
it-alt.sport.calcio.juventus	37
mozilla.support.thunderbird	25
mozilla.support.seamonkey	21
alt.binaries.pictures.purebeauty	17
alt.survival	11
alt.music.pink-floyd.publius	7
mozilla.dev.platform	7
alt.bbs.synchronet	7
oesterreich.tratsch	7
mozilla.support.firefox	6
mozilla.dev.telemetry-alerts	6
mozilla.test	6
mozilla.general	5
fido7.ru.android	5
mozilla.dev.security.policy	5
alt.fan.snuffles	5
alt.math.undergrad	4
alt.politics.usa	4
alt.politics	3
TOTAL: 78	320

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2208
Too many newsgroups	108
EMP (phn nph)	96
EMP (phn path)	76
EMP (phl)	34
HTML Multipart	27
User-issued spam cancel	15
Binary: misplaced rar	12
User-issued cancel	11
Binary Payload	7
Scoring filter (10)	6
Scoring filter (8)	3
HTML Image Tags	3
Scoring filter (9)	3
Scoring filter (11)	1
Binary: misplaced par2	1
TOTAL: 16	2611

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	27	0	29	29
TOTAL: 1	27	5	29	29

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	csiph-out.feeder.erje.net	45
	TOTAL: 1	45
Huge articles
	news.freedyn.net	497
	csiph-out.news.xs4all.nl	106
	csiph-out.feeder.erje.net	96
	csiph-out.news.weretis.net	65
	csiph-out.feed.uzoreto.com	41
	news.samoylyk.net	19
	feeder.eternal-september.org	6
	newsfeeds.xmission.com	2
	news-out.mixmin.net	2
	news.fcku.it	1
	TOTAL: 11	836
Including strange strings
	newsfeed.fu-berlin.de	328
	csiph-out.news.xs4all.nl	46
	csiph-out.news.weretis.net	12
	news.swapon.de	4
	csiph-out.feeder.erje.net	3
	csiph-out.feed.uzoreto.com	3
	news-out.mixmin.net	2
	news.freedyn.net	1
	TOTAL: 8	399
TOTAL: 3		1280

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	xanadu-bbs	18605	595	15125	1	0	3%	23:59:53
2	news.freedyn.net	14513	509	13675	20	0	3%	23:59:53
3	news.samoylyk.net	16627	422	15285	0	83	2%	23:59:53
4	fu-berlin.de	14747	405	14342	0	0	2%	23:59:44
5	news.bbs.nz	29993	231	15063	11	0	0%	23:59:53
6	usenet.pasdenom.info	16298	129	15143	421	15	0%	23:59:53
7	aioe	13941	54	13332	0	0	0%	23:59:27
8	newsfeed.xs4all.nl	14736	32	13787	4	44	0%	23:59:53
9	neva.ru	14824	22	14094	88	0	0%	23:59:48
10	news.mixmin.net	14963	16	14520	0	0	0%	23:59:50
11	weretis.net	13024	10	11831	1	0	0%	23:59:53
12	eternal-september	13732	10	13202	0	0	0%	23:59:53
13	xmission	19300	9	14846	0	0	0%	23:59:53
14	news.swapon.de	1281	8	1251	0	0	0%	23:20:35
15	news.uzoreto.com	15580	7	15236	37	1	0%	23:59:32
16	erje	12966	2	12775	53	8	0%	23:59:53
17	news.redatomik.org	0	0	0	0	15308	0%	23:59:54
18	news.fcku.it	1646	0	1628	0	0	0%	22:16:37
TOTAL: 18		246776	2461	215135	636	15459	0%	429:34:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	xanadu-bbs	4.6 MB	2.3 KB	4.6 MB	0.1 KB/s	7.9 KB	23:59:53
2	news.freedyn.net	4.5 MB	36.3 KB	4.5 MB	0.1 KB/s	8.7 KB	23:59:53
3	news.samoylyk.net	4.1 MB	0.0 KB	4.1 MB	0.0 KB/s	10.0 KB	23:59:53
4	fu-berlin.de	4.1 MB	0.0 KB	4.1 MB	0.0 KB/s	10.3 KB	23:59:44
5	news.bbs.nz	603.3 KB	35.0 KB	638.2 KB	0.0 KB/s	2.6 KB	23:59:53
6	usenet.pasdenom.info	389.9 KB	4.1 MB	4.5 MB	0.1 KB/s	8.4 KB	23:59:53
7	aioe	131.5 KB	0.0 KB	131.5 KB	0.0 KB/s	2.4 KB	23:59:27
8	newsfeed.xs4all.nl	78.4 KB	11.2 KB	89.6 KB	0.0 KB/s	2.5 KB	23:59:53
9	neva.ru	69.2 KB	194.2 KB	263.4 KB	0.0 KB/s	2.4 KB	23:59:48
10	news.mixmin.net	22.4 KB	0.0 KB	22.4 KB	0.0 KB/s	1.4 KB	23:59:50
11	news.uzoreto.com	18.2 KB	112.9 KB	131.1 KB	0.0 KB/s	3.0 KB	23:59:32
12	eternal-september	15.5 KB	0.0 KB	15.5 KB	0.0 KB/s	1.6 KB	23:59:53
13	weretis.net	14.1 KB	1.1 KB	15.2 KB	0.0 KB/s	1.4 KB	23:59:53
14	xmission	12.5 KB	0.0 KB	12.5 KB	0.0 KB/s	1.4 KB	23:59:53
15	news.swapon.de	9.6 KB	0.0 KB	9.6 KB	0.0 KB/s	1.2 KB	23:20:35
16	erje	2.8 KB	221.4 KB	224.2 KB	0.0 KB/s	4.1 KB	23:59:53
17	news.redatomik.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
18	news.fcku.it	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	22:16:37
TOTAL: 18		18.6 MB	4.7 MB	23.3 MB	0.0 KB/s	7.7 KB	429:34:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	66-188-82-154.dhcp.mdsn.wi.charter.com	37	18	27.4 KB	12	8	7	09:36:23
2	a131033.upc-a.chello.nl	29	15	18.8 KB	7	0	0	06:37:21
3	208-71-239-109.cust.centrio.cz	1	2	4.7 KB	1	0	0	00:30:12
TOTAL: 3		67	35	50.8 KB	20	8	7	17:46:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.dhcp.mdsn.wi.charter.com	37	18	27.4 KB	12	8	7	09:36:23
2	*.upc-a.chello.nl	29	15	18.8 KB	7	0	0	06:37:21
3	*.cust.centrio.cz	1	2	4.7 KB	1	0	0	00:30:12
4	*.security.ipip.net	1	0	0.0 KB	0	0	0	00:00:00
5	*.hsd1.wa.comcast.net	1	0	0.0 KB	0	0	0	00:30:01
6	*.sfj.corp.censys.io	2	0	0.0 KB	0	0	0	00:00:01
7	unresolved	5	0	0.0 KB	0	0	0	00:32:35
TOTAL: 7		76	35	50.8 KB	20	8	7	17:46:36

NNRP auth users [Top 20]:

User	Conn
ohlandl	7
ctomczyk	1
TOTAL: 2	8

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
66-188-82-154.dhcp.mdsn.wi.charter.com	3.119	0.508	0.015	09:36:23
a131033.upc-a.chello.nl	2.497	0.312	0.000	06:37:21
208-71-239-109.cust.centrio.cz	0.107	0.025	0.003	00:30:12
115.238.34.18	0.072	0.008	0.000	00:30:01
c-73-140-175-55.hsd1.wa.comcast.net	0.073	0.008	0.000	00:30:01
172.221.41.102	0.070	0.008	0.000	00:02:29
94.163.42.86	0.191	0.046	0.000	00:00:03
worker-18.sfj.corp.censys.io	0.142	0.016	0.000	00:00:01
scan-141.security.ipip.net	0.070	0.008	0.000	00:00:00
TOTAL: 9	6.341	0.939	0.018	17:46:36

Curious NNRP server explorers [Top 20]:

System	Conn
94.163.42.86	3
worker-18.sfj.corp.censys.io	2
115.238.34.18	1
scan-141.security.ipip.net	1
172.221.41.102	1
c-73-140-175-55.hsd1.wa.comcast.net	1
TOTAL: 6	9

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	4
worker-18.sfj.corp.censys.io	2
115.238.34.18	1
TOTAL: 3	7

NNRP unrecognized commands (by command):

Command	Count
Host: 69.160.37.58:119	2
GET / HTTP/1.1	2
Connection: close	1
GET / HTTP/1.0	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 5	7

NNRP client timeouts [Top 20]:

System	Conn	Peer
66-188-82-154.dhcp.mdsn.wi.charter.com	10	0
worker-18.sfj.corp.censys.io	2	2
115.238.34.18	1	0
208-71-239-109.cust.centrio.cz	1	0
c-73-140-175-55.hsd1.wa.comcast.net	1	0
TOTAL: 5	15	2

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	comp.sys.ibm.ps2.hardware	33
2	comp.lang.javascript	2
TOTAL: 2		35

Daily Usenet report for csiph.com

Oct 1 04:15:00 -- Oct 2 04:15:00