Daily Usenet report for news.niel.me: Dec 9 04:15:01

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Dec 9 05:47:27 rpi-studio innd: filter: No Cancel-Key[sha1:lFONfJkY52+PMKfFpHJVrixxAbs= sha256:6y6PnsqJV2PjtyZY/Eeq4lpz2+F3fUzW2mmwJ/UFVqI=] matches Cancel-Lock[sha1:4F67K3VL7zcqkDW4ltvm/2cEMIc= sha256:MPmn+OuqaDqLXKAHpUB9SxsByUKQOwhgU522p6Rfhkw=] target=<10h89gj$h061$1@gwaiyur.mb-net.net>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2424	28.3%	416.6 KB	31.9%
innd	2192	25.6%	367.0 KB	28.1%
nnrpd	2061	24.1%	244.6 KB	18.8%
innfeed	1882	22.0%	275.7 KB	21.1%
controlchan	1	0.0%	0.1 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8561	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8586	54.0%
Negative hits	4900	30.8%
Do not exist	2404	15.1%
Cache misses	2	0.0%
TOTAL: 4	15892	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.006	0.0%	6	0.000	1.000	2.000
article cancel	00:00:00.002	0.0%	7	0.000	0.286	1.000
article cleanup	00:00:00.045	0.0%	2407	0.000	0.019	0.333
article logging	00:00:00.176	0.0%	2407	0.000	0.073	0.500
article parse	00:00:00.574	0.0%	7975	0.000	0.072	0.500
article write	00:00:00.947	0.0%	1867	0.000	0.507	1.222
artlog/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	6	0.000	0.167	0.500
data move	00:00:00.098	0.0%	21212	0.000	0.005	0.032
hisgrep/artcncl	00:00:00.001	0.0%	7	0.000	0.143	0.500
hisgrep/artcncl/perl	00:00:00.001	0.0%	6	0.000	0.167	1.000
hisgrep/perl	00:00:00.001	0.0%	8	0.000	0.125	0.500
hishave/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	6	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.275	0.0%	15914	0.000	0.017	0.063
history sync	00:00:00.059	0.0%	371	0.000	0.159	1.000
history write	00:00:00.467	0.0%	2407	0.000	0.194	0.750
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:37.294	100.0%	21665	527.732	3989.720	30021.750
nntp read	00:00:00.637	0.0%	20992	0.000	0.030	0.110
overview write	00:00:00.562	0.0%	1867	0.000	0.301	4.500
perl filter	00:00:13.637	0.0%	2407	1.167	5.666	28.000
python filter	00:00:00.005	0.0%	2407	0.000	0.002	0.154
site send	00:00:00.819	0.0%	3831	0.000	0.214	0.583
TOTAL: 24:01:03.963	24:00:55.607	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.015	0.0%	1944	0.000	0.008	0.286
article prepare	00:00:00.001	0.0%	236	0.000	0.004	0.250
article read	00:00:00.019	0.0%	59	0.000	0.322	8.000
backlog stats	00:00:00.158	0.0%	24218	0.000	0.007	0.037
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.334	0.0%	11900	0.000	0.028	0.100
data write	00:00:00.583	0.0%	10087	0.000	0.058	0.179
idle	23:58:36.828	100.0%	24218	1600.872	3564.160	8896.515
status file	00:00:08.427	0.0%	319	0.000	26.417	81.000
TOTAL: 23:59:01.502	23:58:46.365	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:47.434	3.9%	1506	10.600	31.497	10005.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.123	0.0%	3504	0.000	0.035	1.000
TOTAL: 00:20:16.778	00:00:47.557	3.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.nntp4.net	1	2344	935	913	496	39%	23:59:56
2	feed-out.gegeweb.org	2	1865	387	1478	0	20%	23:59:52
3	news.samoylyk.net	1	1765	230	1534	1	13%	24:19:51
4	news.glou.org	3	1535	164	1332	39	10%	26:23:22
5	usenet.pasdenom.info	9	814	26	788	0	3%	23:29:40
6	gegeweb-out.news.tnetconsulting.net	3	395	13	382	0	3%	09:23:17
7	nntp.terraraq.uk	1	122	5	117	0	4%	03:24:34
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		45	8840	1760	6544	536	19%	135:00:32

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	4.8 MB	8.7 MB	13.5 MB	35%	9.7 KB
2	feed-out.gegeweb.org	1.5 MB	0.0 KB	1.5 MB	100%	4.0 KB
3	news.samoylyk.net	1.4 MB	3.8 KB	1.4 MB	99%	6.2 KB
4	news.glou.org	625.0 KB	110.9 KB	735.9 KB	84%	3.6 KB
5	usenet.pasdenom.info	55.8 KB	0.0 KB	55.8 KB	100%	2.1 KB
6	gegeweb-out.news.tnetconsulting.net	29.7 KB	0.0 KB	29.7 KB	100%	2.3 KB
7	nntp.terraraq.uk	8.7 KB	0.0 KB	8.7 KB	100%	1.7 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		8.4 MB	8.8 MB	17.2 MB	48%	7.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 9 04:15:01 - 04:59:59	25	1.3%	0.01	107.5 KB	1.2%	0.04
Dec 9 05:00:00 - 05:59:59	35	1.9%	0.01	125.3 KB	1.4%	0.03
Dec 9 06:00:00 - 06:59:59	63	3.4%	0.02	231.1 KB	2.5%	0.06
Dec 9 07:00:00 - 07:59:59	34	1.8%	0.01	169.2 KB	1.8%	0.05
Dec 9 08:00:00 - 08:59:59	72	3.8%	0.02	206.6 KB	2.2%	0.06
Dec 9 09:00:00 - 09:59:59	61	3.3%	0.02	208.0 KB	2.3%	0.06
Dec 9 10:00:00 - 10:59:59	61	3.3%	0.02	171.5 KB	1.9%	0.05
Dec 9 11:00:00 - 11:59:59	71	3.8%	0.02	213.2 KB	2.3%	0.06
Dec 9 12:00:00 - 12:59:59	89	4.8%	0.02	422.1 KB	4.6%	0.12
Dec 9 13:00:00 - 13:59:59	106	5.7%	0.03	774.8 KB	8.4%	0.22
Dec 9 14:00:00 - 14:59:59	52	2.8%	0.01	187.0 KB	2.0%	0.05
Dec 9 15:00:00 - 15:59:59	73	3.9%	0.02	265.7 KB	2.9%	0.07
Dec 9 16:00:00 - 16:59:59	106	5.7%	0.03	603.7 KB	6.5%	0.17
Dec 9 17:00:00 - 17:59:59	74	4.0%	0.02	502.5 KB	5.4%	0.14
Dec 9 18:00:00 - 18:59:59	94	5.0%	0.03	518.1 KB	5.6%	0.14
Dec 9 19:00:00 - 19:59:59	84	4.5%	0.02	253.2 KB	2.7%	0.07
Dec 9 20:00:00 - 20:59:59	73	3.9%	0.02	284.0 KB	3.1%	0.08
Dec 9 21:00:00 - 21:59:59	81	4.3%	0.02	269.1 KB	2.9%	0.07
Dec 9 22:00:00 - 22:59:59	108	5.8%	0.03	358.9 KB	3.9%	0.10
Dec 9 23:00:00 - 23:59:59	102	5.5%	0.03	955.0 KB	10.3%	0.27
Dec 10 00:00:00 - 00:59:59	122	6.5%	0.03	588.5 KB	6.4%	0.16
Dec 10 01:00:00 - 01:59:59	146	7.8%	0.04	714.1 KB	7.7%	0.20
Dec 10 02:00:00 - 02:59:59	73	3.9%	0.02	371.5 KB	4.0%	0.10
Dec 10 03:00:00 - 03:59:59	45	2.4%	0.01	560.9 KB	6.1%	0.16
Dec 10 04:00:00 - 04:15:02	21	1.1%	0.02	165.4 KB	1.8%	0.18
TOTAL: 24:00:01	1871	100.0%	0.02	9.0 MB	100.0%	0.11

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	496	145	351
2	news.glou.org	39	0	39
3	nntp.terraraq.uk	4	0	4
4	news.samoylyk.net	1	0	1
TOTAL: 4		540	145	395

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	88
linux.kernel.git	45
news.test	12
TOTAL: 3	145

INND Perl filter [Top 20]:

Reason	Count
Binary: misplaced binary	266
EMP (phn path)	71
Bad path (news.giganews.com.POSTED)	48
Cancels abusifs	4
Too many newsgroups	3
HTML Multipart	1
Too many newsgroups (meow)	1
Cancel of non-existing ID <mpqaeiFa2lrU1@mid.individual.net>	1
TOTAL: 8	395

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	3425	18	1816	33	71	0%	23:59:58
2	news.gegeweb.org	1598	4	1458	0	0	0%	23:54:06
3	news.samoylyk.net	1752	1	1532	0	0	0%	23:58:52
4	nntp.terraraq.uk	1111	1	1053	0	1	0%	23:31:55
5	news.pasdenom.info	1298	1	1222	1	0	0%	23:53:10
6	news.nntp4.net	1058	0	927	0	1	0%	23:25:22
7	i2pn.org	0	0	0	0	1863	0%	24:00:01
8	news.aioe.org	0	0	0	0	1576	0%	24:00:01
TOTAL: 8		10242	25	8008	34	3512	0%	190:43:25

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	108.3 KB	242.8 KB	351.1 KB	6.9 KB	23:59:58
2	news.gegeweb.org	22.4 KB	0.0 KB	22.4 KB	5.6 KB	23:54:06
3	news.pasdenom.info	16.5 KB	4.9 KB	21.3 KB	10.7 KB	23:53:10
4	news.samoylyk.net	2.9 KB	0.0 KB	2.9 KB	2.9 KB	23:58:52
5	nntp.terraraq.uk	2.9 KB	0.0 KB	2.9 KB	2.9 KB	23:31:55
6	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:25:22
7	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:01
TOTAL: 8		153.0 KB	247.7 KB	400.7 KB	6.8 KB	190:43:25

NNRP readership statistics:

System		Conn	Elapsed
1	n32.scanner.modat.io	1	00:00:01
2	2a06:4883:5000::6c	1	00:00:03
3	66.132.153.140	1	00:00:30
4	45.227.254.156	1	00:00:00
5	2a06:4882:5000::4a	1	00:00:03
6	190-2-155-231.hosted-by-worldstream.net	4	00:14:45
7	114.149.216.162.bc.googleusercontent.com	2	00:00:11
8	202.155.148.22	2	00:00:00
9	10.149.216.162.bc.googleusercontent.com	4	00:00:58
10	66.61.98.95	6	00:00:06
11	2603:6010:6800:36:1a03:73ff:fe28:be0f	8	00:00:04
12	2a06:4883:3000::39	1	00:00:00
13	106.75.18.206	1	00:00:00
14	66.132.153.125	1	00:00:32
15	imaginative.monitoring.internet-measurement.com	1	00:00:02
16	66.132.153.121	1	00:00:30
17	147.185.132.31	1	00:00:00
18	2a06:4882:3000::2c	1	00:00:00
19	205.210.31.200	1	00:00:03
20	mail.gegeweb.eu	288	00:01:33
21	85.211.203.35.bc.googleusercontent.com	1	00:00:00
22	205.210.31.49	1	00:00:02
23	116.211.203.35.bc.googleusercontent.com	1	00:00:00
24	azpdcske3p93.stretchoid.com	2	00:00:10
25	206.168.34.113	1	00:00:31
26	206.168.34.203	1	00:00:30
27	2a06:4882:3000::32	1	00:00:00
28	206.168.34.210	1	00:00:05
TOTAL: 28		336	00:20:51

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.scanner.modat.io	1	00:00:01
2	*.stretchoid.com	2	00:00:10
3	*.hosted-by-worldstream.net	4	00:14:45
4	*.gegeweb.eu	288	00:01:33
5	*.211.203.35.bc.googleusercontent.com	2	00:00:00
6	unresolved	19	00:02:56
7	*.monitoring.internet-measurement.com	1	00:00:02
8	?	13	00:00:10
9	*.149.216.162.bc.googleusercontent.com	6	00:01:10
TOTAL: 9		336	00:20:51

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
190-2-155-231.hosted-by-worldstream.net	0.142	0.062	0.000	00:14:45
mail.gegeweb.eu	4.573	3.873	1.671	00:01:33
10.149.216.162.bc.googleusercontent.com	0.601	0.166	0.000	00:00:58
66.132.153.125	0.037	0.025	0.000	00:00:32
206.168.34.113	0.020	0.009	0.000	00:00:31
66.132.153.140	0.030	0.034	0.000	00:00:30
206.168.34.203	0.041	0.023	0.000	00:00:30
66.132.153.121	0.029	0.029	0.000	00:00:30
114.149.216.162.bc.googleusercontent.com	0.298	0.096	0.000	00:00:11
azpdcske3p93.stretchoid.com	0.052	0.050	0.000	00:00:10
66.61.98.95	0.122	0.082	0.000	00:00:06
206.168.34.210	0.023	0.016	0.000	00:00:05
2603:6010:6800:36:1a03:73ff:fe28:be0f	0.218	0.130	0.000	00:00:04
2a06:4883:5000::6c	0.082	0.016	0.000	00:00:03
2a06:4882:5000::4a	0.038	0.021	0.000	00:00:03
205.210.31.200	0.021	0.026	0.000	00:00:03
imaginative.monitoring.internet-measurement.com	0.013	0.010	0.000	00:00:02
205.210.31.49	0.013	0.009	0.000	00:00:02
n32.scanner.modat.io	0.027	0.023	0.000	00:00:01
202.155.148.22	0.050	0.023	0.000	00:00:00
TOTAL: 27	6.678	4.822	1.671	00:20:51

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
10.149.216.162.bc.googleusercontent.com	8
202.155.148.22	6
116.211.203.35.bc.googleusercontent.com	4
114.149.216.162.bc.googleusercontent.com	4
85.211.203.35.bc.googleusercontent.com	4
azpdcske3p93.stretchoid.com	1
206.168.34.210	1
TOTAL: 7	28

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:563	6
GET / HTTP/1.1	6
Accept-Encoding: gzip	3
User-Agent: curl/7.68.0	3
Accept: /	3
User-Agent: Hello from Palo Alto Network...	2
Accept-Charset: utf-8	1
EHLO www.censys.io	1
User-Agent: Mozilla/5.0 (Macintosh; U; P...	1
MGLNDD_45.13.105.36_119	1
Connection: close	1
TOTAL: 11	28

NNRP client timeouts [Top 20]:

System	Conn	Peer
10.149.216.162.bc.googleusercontent.com	2	4
114.149.216.162.bc.googleusercontent.com	1	2
TOTAL: 2	3	9

Daily Usenet report for news.niel.me

Dec 9 04:15:01 -- Dec 10 04:15:02