Daily Usenet report for news.niel.me: Apr 26 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Apr 26 17:27:50 rpi-studio innd: filter: No Cancel-Key[sha1:FoPtUu9RQ9hqkrYQdyU221sM8Fc= sha256:IOCzzAP/vKo5iALJOd8K0jhnzc93YtVdBJnQDfN0b/I=] matches Cancel-Lock[sha1:p0jTcv1AfQPDET2rn+sKchV+sEk= sha256:3ZYPKliqLNnFlNH7vxRX0Oj5vPv+OuinzOlxMcthTqE=] target=<vuitu3$qup$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	2216	27.8%	252.6 KB	20.3%
innd	2118	26.5%	370.6 KB	29.8%
inn	1859	23.3%	338.2 KB	27.2%
innfeed	1787	22.4%	280.7 KB	22.6%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	7982	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	9120	57.9%
Negative hits	4786	30.4%
Do not exist	1850	11.7%
Cache misses	2	0.0%
TOTAL: 4	15758	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.004	0.0%	3	1.000	1.333	2.000
article cancel	00:00:00.002	0.0%	4	0.000	0.500	1.000
article cleanup	00:00:00.032	0.0%	1852	0.000	0.017	0.500
article logging	00:00:00.129	0.0%	1853	0.000	0.070	0.500
article parse	00:00:00.245	0.0%	3940	0.000	0.062	0.333
article write	00:00:00.776	0.0%	1633	0.000	0.475	1.000
artlog/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:00:00.087	0.0%	16725	0.000	0.005	0.030
hisgrep/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	3	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	3	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:00.256	0.0%	15763	0.000	0.016	0.125
history sync	00:00:00.062	0.0%	366	0.000	0.169	1.000
history write	00:00:00.267	0.0%	1852	0.000	0.144	1.000
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:43.236	100.0%	18638	1887.069	4638.010	30018.300
nntp read	00:00:00.526	0.0%	17731	0.000	0.030	0.093
overview write	00:00:00.580	0.0%	1633	0.000	0.355	4.125
perl filter	00:00:10.069	0.0%	1852	1.786	5.437	33.000
python filter	00:00:00.010	0.0%	1852	0.000	0.005	0.100
site send	00:00:00.551	0.0%	3345	0.000	0.165	0.556
TOTAL: 24:01:04.437	24:00:56.832	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.013	0.0%	1637	0.000	0.008	0.182
article prepare	00:00:00.001	0.0%	456	0.000	0.002	0.083
article read	00:00:00.024	0.0%	108	0.000	0.222	3.000
backlog stats	00:00:00.126	0.0%	25185	0.000	0.005	0.031
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.353	0.0%	12652	0.000	0.028	0.100
data write	00:00:00.597	0.0%	11086	0.000	0.054	0.190
idle	23:59:29.229	100.0%	25185	1407.745	3429.392	9690.210
status file	00:00:06.747	0.0%	249	1.000	27.096	87.000
TOTAL: 23:59:40.531	23:59:37.090	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:30.576	6.8%	1534	0.000	19.932	3867.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.129	0.0%	3490	0.000	0.037	1.000
TOTAL: 00:07:28.121	00:00:30.705	6.9%	-	-	-	-

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	1	1796	536	1068	192	29%	23:59:57
2	feed-out.gegeweb.org	2	1598	413	1185	0	25%	23:56:16
3	news.samoylyk.net	2	2041	260	1781	0	12%	29:17:45
4	news.glou.org	4	2499	224	2233	42	8%	46:20:16
5	i2pn.org	2	1845	146	1696	3	7%	23:59:14
6	nntp.terraraq.uk	2	1319	125	1188	6	9%	26:06:27
7	gegeweb-out.news.tnetconsulting.net	2	1324	86	1236	2	6%	23:57:19
8	localhost	25	0	0	0	0	0%	00:00:00
9	usenet.pasdenom.info	12	738	0	738	0	0%	23:06:37
TOTAL: 9		52	13160	1790	11125	245	13%	220:43:51

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	2.3 MB	959.0 KB	3.2 MB	70%	4.5 KB
2	news.samoylyk.net	1.7 MB	0.0 KB	1.7 MB	100%	6.6 KB
3	feed-out.gegeweb.org	1.3 MB	0.0 KB	1.3 MB	100%	3.1 KB
4	news.glou.org	712.2 KB	117.6 KB	829.8 KB	85%	3.1 KB
5	i2pn.org	349.1 KB	5.3 KB	354.4 KB	98%	2.4 KB
6	gegeweb-out.news.tnetconsulting.net	263.7 KB	19.1 KB	282.9 KB	93%	3.2 KB
7	nntp.terraraq.uk	246.2 KB	12.5 KB	258.7 KB	95%	2.0 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
9	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		6.7 MB	1.1 MB	7.8 MB	86%	3.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 26 04:15:01 - 04:59:59	38	2.3%	0.01	123.0 KB	2.0%	0.05
Apr 26 05:00:00 - 05:59:59	36	2.2%	0.01	109.8 KB	1.8%	0.03
Apr 26 06:00:00 - 06:59:59	27	1.7%	0.01	78.3 KB	1.3%	0.02
Apr 26 07:00:00 - 07:59:59	26	1.6%	0.01	419.6 KB	6.9%	0.12
Apr 26 08:00:00 - 08:59:59	40	2.4%	0.01	183.6 KB	3.0%	0.05
Apr 26 09:00:00 - 09:59:59	71	4.3%	0.02	255.2 KB	4.2%	0.07
Apr 26 10:00:00 - 10:59:59	84	5.1%	0.02	335.8 KB	5.5%	0.09
Apr 26 11:00:00 - 11:59:59	78	4.8%	0.02	354.1 KB	5.8%	0.10
Apr 26 12:00:00 - 12:59:59	62	3.8%	0.02	237.0 KB	3.9%	0.07
Apr 26 13:00:00 - 13:59:59	73	4.5%	0.02	257.0 KB	4.2%	0.07
Apr 26 14:00:00 - 14:59:59	69	4.2%	0.02	203.7 KB	3.3%	0.06
Apr 26 15:00:00 - 15:59:59	84	5.1%	0.02	251.4 KB	4.1%	0.07
Apr 26 16:00:00 - 16:59:59	62	3.8%	0.02	158.1 KB	2.6%	0.04
Apr 26 17:00:00 - 17:59:59	94	5.8%	0.03	279.5 KB	4.6%	0.08
Apr 26 18:00:00 - 18:59:59	76	4.7%	0.02	245.3 KB	4.0%	0.07
Apr 26 19:00:00 - 19:59:59	76	4.7%	0.02	178.2 KB	2.9%	0.05
Apr 26 20:00:00 - 20:59:59	79	4.8%	0.02	284.3 KB	4.7%	0.08
Apr 26 21:00:00 - 21:59:59	93	5.7%	0.03	316.0 KB	5.2%	0.09
Apr 26 22:00:00 - 22:59:59	80	4.9%	0.02	290.4 KB	4.8%	0.08
Apr 26 23:00:00 - 23:59:59	106	6.5%	0.03	355.9 KB	5.8%	0.10
Apr 27 00:00:00 - 00:59:59	87	5.3%	0.02	307.7 KB	5.0%	0.09
Apr 27 01:00:00 - 01:59:59	89	5.5%	0.02	540.5 KB	8.9%	0.15
Apr 27 02:00:00 - 02:59:59	53	3.2%	0.01	180.4 KB	3.0%	0.05
Apr 27 03:00:00 - 03:59:59	43	2.6%	0.01	130.8 KB	2.1%	0.04
Apr 27 04:00:00 - 04:15:02	7	0.4%	0.01	18.9 KB	0.3%	0.02
TOTAL: 24:00:01	1633	100.0%	0.02	6.0 MB	100.0%	0.07

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	192	69	123
2	news.glou.org	13	0	13
3	nntp.terraraq.uk	10	0	10
4	i2pn.org	2	0	2
5	feed-out.gegeweb.org	1	0	1
6	gegeweb-out.news.tnetconsulting.net	1	0	1
TOTAL: 6		219	69	150

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	56
linux.kernel.git	7
news.test	6
TOTAL: 3	69

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	99
Bad path (news.giganews.com.POSTED)	46
Too many newsgroups (meow)	2
Cancel of non-existing ID <vube9c$3p93p$1@dont-email.me>	1
Cancel of non-existing ID <vuec2f$2g5qh$1@dont-email.me>	1
TOTAL: 5	149

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	feed-out.gegeweb.org	1
	TOTAL: 1	1
TOTAL: 1		1

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1329	42	1192	0	0	3%	23:51:09
2	news.samoylyk.net	1722	24	1391	17	0	1%	23:59:57
3	i2pn.org	2181	10	1466	3	2	0%	23:55:00
4	news.tnetconsulting.net	2359	6	1614	10	2	0%	23:59:01
5	nntp.terraraq.uk	948	1	852	0	0	0%	23:49:38
6	news.nntp4.net	1214	1	1093	0	0	0%	23:51:15
7	news.pasdenom.info	1378	0	1231	0	0	0%	23:57:26
8	news.aioe.org	0	0	0	0	1434	0%	24:00:00
TOTAL: 8		11131	84	8839	30	1438	0%	191:23:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.samoylyk.net	83.4 KB	113.5 KB	196.9 KB	4.8 KB	23:59:57
2	news.gegeweb.org	80.8 KB	0.0 KB	80.8 KB	1.9 KB	23:51:09
3	i2pn.org	54.4 KB	10.1 KB	64.5 KB	5.0 KB	23:55:00
4	news.tnetconsulting.net	20.3 KB	30.3 KB	50.6 KB	3.2 KB	23:59:01
5	news.nntp4.net	9.6 KB	0.0 KB	9.6 KB	9.6 KB	23:51:15
6	nntp.terraraq.uk	3.0 KB	0.0 KB	3.0 KB	3.0 KB	23:49:38
7	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:57:26
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		251.6 KB	153.9 KB	405.5 KB	3.6 KB	191:23:26

NNRP readership statistics:

System		Conn	Elapsed
1	206.168.34.95	1	00:00:37
2	syn-066-061-096-239.res.spectrum.com	4	00:00:03
3	206.168.34.86	1	00:00:31
4	mail.gegeweb.eu	288	00:01:57
5	185.165.191.26	1	00:00:04
6	172-105-246-139.ip.linodeusercontent.com	78	00:00:19
7	194.165.16.165	1	00:00:00
8	206.168.34.34	1	00:00:31
9	146.210.203.35.bc.googleusercontent.com	1	00:00:01
10	205.210.31.59	1	00:00:01
11	balanced.monitoring.internet-measurement.com	1	00:00:03
12	intriguing.monitoring.internet-measurement.com	1	00:00:00
13	azpdsgu9x2qp.stretchoid.com	1	00:00:01
14	45-79-163-53.ip.linodeusercontent.com	1	00:00:00
15	206.168.34.35	1	00:00:41
16	194.165.16.162	1	00:00:00
17	joyous.monitoring.internet-measurement.com	1	00:00:00
18	205.210.31.175	1	00:00:02
19	congratulated.monitoring.internet-measurement.com	1	00:00:00
20	71.6.234.7	10	00:00:01
21	206.168.34.206	1	00:00:44
22	206.168.34.208	1	00:00:31
23	149.150.216.162.bc.googleusercontent.com	1	00:00:00
24	88.214.25.123	2	00:00:01
25	jersey.scan.bufferover.run	1	00:00:01
26	206.168.34.213	1	00:00:33
27	thriving.monitoring.internet-measurement.com	1	00:00:03
28	meritable.monitoring.internet-measurement.com	1	00:00:00
29	206.168.34.67	1	00:00:31
TOTAL: 29		406	00:07:27

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:57
2	*.210.203.35.bc.googleusercontent.com	1	00:00:01
3	*.scan.bufferover.run	1	00:00:01
4	unresolved	25	00:04:54
5	*.monitoring.internet-measurement.com	6	00:00:07
6	*.150.216.162.bc.googleusercontent.com	1	00:00:00
7	*.stretchoid.com	1	00:00:01
8	*.res.spectrum.com	4	00:00:03
9	*.ip.linodeusercontent.com	79	00:00:20
TOTAL: 9		406	00:07:27

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.575	4.376	1.832	00:01:57
206.168.34.206	0.044	0.022	0.000	00:00:44
206.168.34.35	0.031	0.027	0.000	00:00:41
206.168.34.95	0.036	0.020	0.000	00:00:37
206.168.34.213	0.034	0.021	0.000	00:00:33
206.168.34.34	0.041	0.008	0.000	00:00:31
206.168.34.86	0.032	0.032	0.000	00:00:31
206.168.34.67	0.044	0.020	0.000	00:00:31
206.168.34.208	0.012	0.015	0.000	00:00:31
172-105-246-139.ip.linodeusercontent.com	1.915	1.065	0.000	00:00:19
185.165.191.26	0.012	0.012	0.000	00:00:04
balanced.monitoring.internet-measurement.com	0.016	0.016	0.000	00:00:03
syn-066-061-096-239.res.spectrum.com	0.083	0.079	0.000	00:00:03
thriving.monitoring.internet-measurement.com	0.027	0.015	0.000	00:00:03
205.210.31.175	0.028	0.024	0.000	00:00:02
205.210.31.59	0.027	0.023	0.000	00:00:01
azpdsgu9x2qp.stretchoid.com	0.020	0.005	0.000	00:00:01
71.6.234.7	0.237	0.128	0.000	00:00:01
jersey.scan.bufferover.run	0.025	0.021	0.000	00:00:01
146.210.203.35.bc.googleusercontent.com	0.068	0.012	0.000	00:00:01
TOTAL: 27	7.610	6.086	1.832	00:07:27

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
jersey.scan.bufferover.run	5
146.210.203.35.bc.googleusercontent.com	4
149.150.216.162.bc.googleusercontent.com	4
194.165.16.165	1
194.165.16.162	1
88.214.25.123	1
azpdsgu9x2qp.stretchoid.com	1
TOTAL: 7	17

NNRP unrecognized commands (by command):

Command	Count
#003	3
Accept-Encoding: gzip	3
GET / HTTP/1.1	3
Host: 45.13.105.36:563	3
User-Agent: Expanse, a Palo Alto Network...	2
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
MGLNDD_45.13.105.36_119	1
Accept: /	1
TOTAL: 8	17

NNRP client timeouts [Top 20]:

System	Conn	Peer
88.214.25.123	1	1
194.165.16.162	1	1
194.165.16.165	1	1
TOTAL: 3	3	5

Daily Usenet report for news.niel.me

Apr 26 04:15:01 -- Apr 27 04:15:02