Daily Usenet report for news.niel.me: Nov 8 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	27650	69.3%	5.2 MB	76.3%
innd	8488	21.3%	1.1 MB	16.2%
innfeed	1944	4.9%	326.7 KB	4.7%
nnrpd	1827	4.6%	198.8 KB	2.8%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	39911	100.0%	6.8 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	49085	40.5%
Negative hits	44108	36.4%
Do not exist	27555	22.8%
Cache misses	326	0.3%
TOTAL: 4	121074	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.013	0.0%	12	0.000	1.083	2.000
article cancel	00:00:00.003	0.0%	12	0.000	0.250	1.000
article cleanup	00:00:00.595	0.0%	27650	0.000	0.022	0.079
article logging	00:00:02.205	0.0%	27650	0.032	0.080	0.135
article parse	00:00:07.972	0.0%	142272	0.031	0.056	0.081
article write	00:00:16.151	0.0%	21217	0.418	0.761	2.308
artlog/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	12	0.000	0.083	0.500
data move	00:00:01.476	0.0%	270972	0.000	0.005	0.014
hisgrep/artcncl	00:00:00.001	0.0%	12	0.000	0.083	1.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	12	0.000	0.083	1.000
hisgrep/perl	00:00:00.001	0.0%	13	0.000	0.077	1.000
hishave/artcncl	00:00:00.000	0.0%	12	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.001	0.0%	12	0.000	0.083	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.247	0.0%	121481	0.005	0.018	0.038
history sync	00:00:00.055	0.0%	397	0.000	0.139	1.000
history write	00:00:06.099	0.0%	27650	0.092	0.221	0.381
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	23:57:37.216	99.8%	235652	207.556	366.036	873.642
nntp read	00:00:07.064	0.0%	235128	0.014	0.030	0.048
overview write	00:00:08.140	0.0%	21217	0.081	0.384	9.058
perl filter	00:01:46.935	0.1%	27650	3.025	3.867	5.532
python filter	00:00:00.138	0.0%	27650	0.000	0.005	0.020
site send	00:00:06.503	0.0%	43595	0.041	0.149	0.339
TOTAL: 24:01:03.738	24:00:22.817	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.195	0.0%	21146	0.000	0.009	0.057
article prepare	00:00:00.026	0.0%	7428	0.000	0.004	0.083
article read	00:00:00.185	0.0%	1124	0.000	0.165	0.714
backlog stats	00:00:01.487	0.0%	321030	0.002	0.005	0.010
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:05.051	0.0%	222258	0.012	0.023	0.036
data write	00:00:08.887	0.0%	173331	0.031	0.051	0.074
idle	23:54:04.251	100.0%	321030	146.957	268.026	625.743
status file	00:00:00.449	0.0%	210	0.000	2.138	8.000
TOTAL: 23:54:36.040	23:54:20.531	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:01:12.789	46.2%	1461	26.500	49.821	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.095	0.1%	3234	0.000	0.029	0.333
TOTAL: 00:02:37.445	00:01:12.884	46.3%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	2
logmode	2
lowmark	1
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	171

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	4	26712	9304	12234	5174	34%	24:09:32
2	gegeweb-out.news.tnetconsulting.net	2	21255	5229	15948	78	24%	24:24:08
3	news.glou.org	3	11830	4948	5837	1045	41%	23:23:26
4	feed-out.gegeweb.org	3	2783	969	1813	1	34%	23:58:59
5	nntp.terraraq.uk	1	6740	403	6235	102	5%	30:10:09
6	i2pn.org	1	3706	217	3489	0	5%	21:39:06
7	news.samoylyk.net	2	6435	31	6404	0	0%	47:31:24
8	usenet.pasdenom.info	2	433	20	411	2	4%	05:24:11
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		43	79894	21121	52371	6402	26%	200:40:55

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	104.7 MB	48.2 MB	153.0 MB	68%	10.8 KB
2	gegeweb-out.news.tnetconsulting.net	42.5 MB	333.5 KB	42.8 MB	99%	8.3 KB
3	news.glou.org	31.6 MB	5.6 MB	37.2 MB	84%	6.4 KB
4	feed-out.gegeweb.org	9.2 MB	1.2 KB	9.2 MB	99%	9.7 KB
5	i2pn.org	2.7 MB	0.0 KB	2.7 MB	100%	12.5 KB
6	nntp.terraraq.uk	2.1 MB	625.1 KB	2.7 MB	77%	5.5 KB
7	news.samoylyk.net	1.0 MB	0.0 KB	1.0 MB	100%	33.6 KB
8	usenet.pasdenom.info	34.2 KB	2.0 KB	36.2 KB	94%	1.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		193.8 MB	54.8 MB	248.6 MB	77%	9.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 8 04:15:01 - 04:59:59	702	3.3%	0.26	8.5 MB	4.4%	3.24
Nov 8 05:00:00 - 05:59:59	1058	5.0%	0.29	10.9 MB	5.6%	3.10
Nov 8 06:00:00 - 06:59:59	1020	4.8%	0.28	10.4 MB	5.4%	2.95
Nov 8 07:00:00 - 07:59:59	1378	6.5%	0.38	13.7 MB	7.1%	3.91
Nov 8 08:00:00 - 08:59:59	1484	7.0%	0.41	13.8 MB	7.1%	3.91
Nov 8 09:00:00 - 09:59:59	1333	6.3%	0.37	13.5 MB	7.0%	3.83
Nov 8 10:00:00 - 10:59:59	1288	6.1%	0.36	11.8 MB	6.1%	3.34
Nov 8 11:00:00 - 11:59:59	1087	5.1%	0.30	9.1 MB	4.7%	2.59
Nov 8 12:00:00 - 12:59:59	1099	5.2%	0.31	9.3 MB	4.8%	2.65
Nov 8 13:00:00 - 13:59:59	936	4.4%	0.26	7.9 MB	4.1%	2.26
Nov 8 14:00:00 - 14:59:59	987	4.7%	0.27	8.8 MB	4.6%	2.51
Nov 8 15:00:00 - 15:59:59	1083	5.1%	0.30	11.1 MB	5.7%	3.16
Nov 8 16:00:00 - 16:59:59	856	4.0%	0.24	7.8 MB	4.0%	2.21
Nov 8 17:00:00 - 17:59:59	654	3.1%	0.18	6.2 MB	3.2%	1.77
Nov 8 18:00:00 - 18:59:59	704	3.3%	0.20	6.2 MB	3.2%	1.78
Nov 8 19:00:00 - 19:59:59	564	2.7%	0.16	4.9 MB	2.6%	1.40
Nov 8 20:00:00 - 20:59:59	737	3.5%	0.20	7.0 MB	3.6%	1.99
Nov 8 21:00:00 - 21:59:59	576	2.7%	0.16	4.4 MB	2.3%	1.24
Nov 8 22:00:00 - 22:59:59	658	3.1%	0.18	4.5 MB	2.3%	1.29
Nov 8 23:00:00 - 23:59:59	636	3.0%	0.18	4.7 MB	2.4%	1.33
Nov 9 00:00:00 - 00:59:59	511	2.4%	0.14	4.1 MB	2.1%	1.16
Nov 9 01:00:00 - 01:59:59	443	2.1%	0.12	3.1 MB	1.6%	0.89
Nov 9 02:00:00 - 02:59:59	573	2.7%	0.16	4.8 MB	2.5%	1.36
Nov 9 03:00:00 - 03:59:59	646	3.0%	0.18	5.3 MB	2.7%	1.50
Nov 9 04:00:00 - 04:15:01	187	0.9%	0.21	1.6 MB	0.8%	1.76
TOTAL: 24:00:00	21200	100.0%	0.25	193.4 MB	100.0%	2.29

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	5177	1	5176
2	news.glou.org	1069	0	1069
3	nntp.terraraq.uk	95	0	95
4	gegeweb-out.news.tnetconsulting.net	78	1	77
5	usenet.pasdenom.info	6	0	6
6	feed-out.gegeweb.org	1	0	1
TOTAL: 6		6426	2	6424

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.education.entraide.physique-chimie	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	6291
Bad path (news.giganews.com.POSTED)	99
EMP (phl)	20
Too many newsgroups	4
Cancel for rejected article	4
EMP (phn path)	3
Cancel of non-existing ID <kr2lknFmbvsU1@mid.individual.net>	1
Cancels abusifs	1
hierarchy violation - crosspost outside sdnet	1
TOTAL: 9	6424

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.nntp4.net	14129	309	11554	0	0	2%	23:59:55
2	news.tnetconsulting.net	31991	208	20952	35	0	0%	23:59:28
3	nntp.terraraq.uk	23468	208	19546	347	0	0%	23:59:51
4	i2pn.org	30859	153	20286	364	12	0%	23:59:51
5	news.samoylyk.net	29585	10	20975	198	0	0%	23:59:23
6	news.gegeweb.org	20338	3	20229	15	0	0%	23:59:56
7	news.pasdenom.info	22186	1	20770	11	0	0%	23:59:50
8	news.aioe.org	0	0	0	0	4642	0%	23:59:54
TOTAL: 8		172556	892	134312	970	4654	0%	191:58:08

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	news.nntp4.net	2.6 MB	0.0 KB	2.6 MB	0.0 KB/s	8.5 KB	23:59:55
2	news.tnetconsulting.net	1.9 MB	184.7 KB	2.0 MB	0.0 KB/s	8.6 KB	23:59:28
3	nntp.terraraq.uk	1.2 MB	3.4 MB	4.6 MB	0.1 KB/s	8.4 KB	23:59:51
4	i2pn.org	837.9 KB	2.9 MB	3.7 MB	0.0 KB/s	7.4 KB	23:59:51
5	news.samoylyk.net	31.2 KB	1.7 MB	1.8 MB	0.0 KB/s	8.6 KB	23:59:23
6	news.gegeweb.org	19.0 KB	143.4 KB	162.4 KB	0.0 KB/s	9.0 KB	23:59:56
7	news.pasdenom.info	14.3 KB	113.8 KB	128.1 KB	0.0 KB/s	10.7 KB	23:59:50
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:54
TOTAL: 8		6.5 MB	8.5 MB	15.0 MB	0.0 KB/s	8.2 KB	191:58:08

NNRP readership statistics:

System		Conn	Elapsed
1	45.227.254.8	1	00:00:00
2	hat.census.shodan.io	1	00:00:04
3	139.162.190.203	1	00:00:11
4	80.66.88.211	1	00:00:01
5	88.165.5.23	1	00:00:00
6	mail.gegeweb.eu	288	00:01:52
7	2607:ff10:c8:594::6	3	00:00:04
8	107.170.244.10	1	00:00:01
9	128.14.239.217	3	00:00:11
10	buoyant.monitoring.internet-measurement.com	1	00:00:02
11	adorable.monitoring.internet-measurement.com	1	00:00:00
12	empathetic.monitoring.internet-measurement.com	2	00:00:00
13	likeable.monitoring.internet-measurement.com	1	00:00:00
14	reliable.monitoring.internet-measurement.com	1	00:00:05
TOTAL: 14		306	00:02:36

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:52
2	*.census.shodan.io	1	00:00:04
3	*.monitoring.internet-measurement.com	6	00:00:08
4	unresolved	8	00:00:26
5	?	3	00:00:04
TOTAL: 5		306	00:02:36

NNRP auth users [Top 20]:

User	Conn
mailman	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	4.551	4.450	1.646	00:01:52
128.14.239.217	0.087	0.072	0.000	00:00:11
139.162.190.203	0.030	0.013	0.000	00:00:11
reliable.monitoring.internet-measurement.com	0.037	0.025	0.000	00:00:05
2607:ff10:c8:594::6	0.089	0.068	0.000	00:00:04
hat.census.shodan.io	0.027	0.027	0.000	00:00:04
buoyant.monitoring.internet-measurement.com	0.030	0.021	0.000	00:00:02
80.66.88.211	0.037	0.015	0.000	00:00:01
107.170.244.10	0.036	0.018	0.000	00:00:01
88.165.5.23	0.036	0.016	0.000	00:00:00
adorable.monitoring.internet-measurement.com	0.044	0.020	0.000	00:00:00
likeable.monitoring.internet-measurement.com	0.024	0.004	0.000	00:00:00
45.227.254.8	0.016	0.012	0.000	00:00:00
empathetic.monitoring.internet-measurement.com	0.023	0.005	0.000	00:00:00
TOTAL: 14	5.067	4.766	1.646	00:02:36

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	6
TOTAL: 1	6

NNRP unrecognized commands (by host):

System	Conn
2607:ff10:c8:594::6	7
128.14.239.217	2
107.170.244.10	1
80.66.88.211	1
TOTAL: 4	11

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
#026#003#001#001#027#001	1
MGLNDD_45.13.105.36_119	1
#026#003#001	1
User-Agent: Mozilla/5.0 (Windows NT 10.0...	1
À#024	1
#023#003#023#001#023#002#001	1
#003	1
Accept-Encoding: gzip	1
Accept: /	1
TOTAL: 11	11

NNRP client timeouts [Top 20]:

System	Conn	Peer
80.66.88.211	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Nov 8 04:15:01 -- Nov 9 04:15:01