Daily Usenet report for news.niel.me: Jun 26 04:15:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 26 22:24:05 rpi-studio innd: filter: No Cancel-Key[sha1:Yg6pBhjN2/JSCzzF9fFwbAUReVI= sha256:LVXcwwJGYTnJpKOArsD705NjwK9YAqqT2ykJy7enQcM=] matches Cancel-Lock[sha1:ulb9cqgzJH4MJDXRoY5VWboqWBk= sha256:p4Sdvbm1lvKFtlU48q8R0RSodfWVECOz0PiWm0b0u5g=] target=<103k3rh$jp7$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
innd	2221	27.5%	390.9 KB	31.3%
inn	2072	25.7%	357.9 KB	28.7%
nnrpd	1953	24.2%	225.6 KB	18.1%
innfeed	1819	22.5%	273.2 KB	21.9%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8067	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	8940	55.2%
Negative hits	5198	32.1%
Do not exist	2054	12.7%
Cache misses	2	0.0%
TOTAL: 4	16194	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	4	0.500	2.000	6.000
article cancel	00:00:00.004	0.0%	5	0.000	0.800	4.000
article cleanup	00:00:00.048	0.0%	2060	0.000	0.023	0.143
article logging	00:00:00.155	0.0%	2060	0.000	0.075	0.273
article parse	00:00:00.332	0.0%	5121	0.000	0.065	0.333
article write	00:00:00.737	0.0%	1503	0.000	0.490	1.600
artlog/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:00:00.112	0.0%	18289	0.000	0.006	0.026
hisgrep/artcncl	00:00:00.000	0.0%	5	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	4	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%	5	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	4	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.273	0.0%	16230	0.000	0.017	0.081
history sync	00:00:00.052	0.0%	402	0.000	0.129	0.667
history write	00:00:00.434	0.0%	2060	0.000	0.211	0.720
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:50.545	100.0%	19880	1893.845	4348.619	20025.233
nntp read	00:00:00.540	0.0%	19153	0.000	0.028	0.071
overview write	00:00:00.497	0.0%	1503	0.000	0.331	2.750
perl filter	00:00:11.845	0.0%	2060	1.909	5.750	19.200
python filter	00:00:00.013	0.0%	2060	0.000	0.006	0.111
site send	00:00:00.561	0.0%	3062	0.000	0.183	0.600
TOTAL: 24:01:14.795	24:01:06.156	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.020	0.0%	1522	0.000	0.013	0.500
article prepare	00:00:00.000	0.0%	116	0.000	0.000	0.000
article read	00:00:00.008	0.0%	19	0.000	0.421	2.000
backlog stats	00:00:00.133	0.0%	22975	0.000	0.006	0.029
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.324	0.0%	11436	0.000	0.028	0.118
data write	00:00:00.568	0.0%	9986	0.000	0.057	0.160
idle	23:49:47.586	100.0%	22975	1607.171	3733.954	10183.000
status file	00:00:07.914	0.0%	252	0.000	31.405	85.000
TOTAL: 23:50:00.295	23:49:56.553	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:59.299	30.2%	1462	9.400	40.560	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.128	0.1%	3283	0.000	0.039	1.000
TOTAL: 00:03:16.612	00:00:59.427	30.2%	-	-	-	-

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	2	1940	478	977	485	24%	22:35:09
2	feed-out.gegeweb.org	3	1472	457	1015	0	31%	23:59:20
3	news.samoylyk.net	2	1052	128	924	0	12%	19:42:22
4	gegeweb-out.news.tnetconsulting.net	1	432	36	394	2	8%	10:36:35
5	news.glou.org	2	569	24	530	15	4%	13:12:16
6	nntp.terraraq.uk	2	248	21	224	3	8%	07:06:51
7	i2pn.org	1	291	14	277	0	4%	05:36:28
8	usenet.pasdenom.info	12	813	0	813	0	0%	26:55:13
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		50	6817	1158	5154	505	16%	129:44:14

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	1.9 MB	4.3 MB	6.2 MB	30%	6.6 KB
2	feed-out.gegeweb.org	1.3 MB	0.0 KB	1.3 MB	100%	2.8 KB
3	news.samoylyk.net	923.8 KB	0.0 KB	923.8 KB	100%	7.2 KB
4	gegeweb-out.news.tnetconsulting.net	119.0 KB	6.5 KB	125.5 KB	94%	3.3 KB
5	news.glou.org	65.7 KB	40.7 KB	106.4 KB	61%	2.7 KB
6	nntp.terraraq.uk	43.2 KB	6.5 KB	49.7 KB	86%	2.1 KB
7	i2pn.org	31.8 KB	0.0 KB	31.8 KB	100%	2.3 KB
8	usenet.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0%	0
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		4.3 MB	4.4 MB	8.7 MB	49%	5.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 26 04:15:02 - 04:59:59	30	2.0%	0.01	90.5 KB	1.7%	0.03
Jun 26 05:00:00 - 05:59:59	73	4.9%	0.02	226.6 KB	4.2%	0.06
Jun 26 06:00:00 - 06:59:59	47	3.1%	0.01	119.1 KB	2.2%	0.03
Jun 26 07:00:00 - 07:59:59	27	1.8%	0.01	82.3 KB	1.5%	0.02
Jun 26 08:00:00 - 08:59:59	38	2.5%	0.01	109.4 KB	2.0%	0.03
Jun 26 09:00:00 - 09:59:59	31	2.1%	0.01	104.2 KB	1.9%	0.03
Jun 26 10:00:00 - 10:59:59	50	3.3%	0.01	179.6 KB	3.3%	0.05
Jun 26 11:00:00 - 11:59:59	63	4.2%	0.02	205.9 KB	3.8%	0.06
Jun 26 12:00:00 - 12:59:59	70	4.7%	0.02	279.1 KB	5.1%	0.08
Jun 26 13:00:00 - 13:59:59	51	3.4%	0.01	284.5 KB	5.2%	0.08
Jun 26 14:00:00 - 14:59:59	45	3.0%	0.01	123.4 KB	2.3%	0.03
Jun 26 15:00:00 - 15:59:59	61	4.1%	0.02	265.4 KB	4.9%	0.07
Jun 26 16:00:00 - 16:59:59	79	5.3%	0.02	349.1 KB	6.4%	0.10
Jun 26 17:00:00 - 17:59:59	67	4.5%	0.02	364.7 KB	6.7%	0.10
Jun 26 18:00:00 - 18:59:59	118	7.9%	0.03	539.7 KB	9.9%	0.15
Jun 26 19:00:00 - 19:59:59	83	5.5%	0.02	274.6 KB	5.0%	0.08
Jun 26 20:00:00 - 20:59:59	94	6.3%	0.03	409.3 KB	7.5%	0.11
Jun 26 21:00:00 - 21:59:59	112	7.5%	0.03	347.7 KB	6.4%	0.10
Jun 26 22:00:00 - 22:59:59	84	5.6%	0.02	219.4 KB	4.0%	0.06
Jun 26 23:00:00 - 23:59:59	69	4.6%	0.02	208.8 KB	3.8%	0.06
Jun 27 00:00:00 - 00:59:59	71	4.7%	0.02	204.5 KB	3.8%	0.06
Jun 27 01:00:00 - 01:59:59	49	3.3%	0.01	137.4 KB	2.5%	0.04
Jun 27 02:00:00 - 02:59:59	49	3.3%	0.01	158.2 KB	2.9%	0.04
Jun 27 03:00:00 - 03:59:59	28	1.9%	0.01	75.5 KB	1.4%	0.02
Jun 27 04:00:00 - 04:15:01	12	0.8%	0.01	80.7 KB	1.5%	0.09
TOTAL: 23:59:59	1501	100.0%	0.02	5.3 MB	100.0%	0.06

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	520	303	217
2	news.glou.org	32	0	32
3	nntp.terraraq.uk	5	0	5
4	gegeweb-out.news.tnetconsulting.net	4	0	4
5	i2pn.org	1	0	1
TOTAL: 5		562	303	259

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	252
linux.kernel.git	49
rec.arts	1
news.test	1
TOTAL: 4	303

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	93
Binary: misplaced binary	58
EMP (md5)	57
Bad path (news.giganews.com.POSTED)	48
Too many newsgroups (meow)	1
Cancel of non-existing ID <mc4f0tFnhg6U1@mid.individual.net>	1
Angle-bracket bot	1
TOTAL: 7	259

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1134	7	1048	0	0	0%	23:44:38
2	i2pn.org	2040	4	1429	5	10	0%	23:59:29
3	news.tnetconsulting.net	2143	3	1494	2	11	0%	23:58:18
4	news.nntp4.net	1067	2	996	0	0	0%	23:51:54
5	news.aioe.org	0	0	0	0	1294	0%	23:59:59
6	news.pasdenom.info	1240	0	1179	3	0	0%	23:58:59
7	news.samoylyk.net	1523	0	1302	1	4	0%	23:59:57
8	nntp.terraraq.uk	923	0	858	2	0	0%	23:41:42
TOTAL: 8		10070	16	8306	13	1319	0%	191:14:56

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	13.4 KB	0.0 KB	13.4 KB	1.9 KB	23:44:38
2	news.tnetconsulting.net	8.6 KB	2.2 KB	10.8 KB	2.2 KB	23:58:18
3	i2pn.org	5.9 KB	30.9 KB	36.8 KB	4.1 KB	23:59:29
4	news.nntp4.net	2.2 KB	0.0 KB	2.2 KB	1.1 KB	23:51:54
5	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
6	news.pasdenom.info	0.0 KB	3.1 KB	3.1 KB	1.0 KB	23:58:59
7	news.samoylyk.net	0.0 KB	1.1 KB	1.1 KB	1.1 KB	23:59:57
8	nntp.terraraq.uk	0.0 KB	2.2 KB	2.2 KB	1.1 KB	23:41:42
TOTAL: 8		30.1 KB	39.6 KB	69.7 KB	2.4 KB	191:14:56

NNRP readership statistics:

System		Conn	Elapsed
1	205.210.31.157	3	00:00:07
2	147.185.133.74	1	00:00:08
3	2a06:4883:b000::b7	1	00:00:00
4	timeless.monitoring.internet-measurement.com	1	00:00:06
5	170.210.203.35.bc.googleusercontent.com	2	00:00:02
6	205.210.31.233	1	00:00:01
7	unparalleled.monitoring.internet-measurement.com	1	00:00:03
8	luminous.monitoring.internet-measurement.com	1	00:00:00
9	47.74.41.172	1	00:00:03
10	2a06:4883:b000::c6	1	00:00:00
11	194.165.16.165	1	00:00:00
12	147.185.132.37	1	00:00:03
13	mail.gegeweb.eu	288	00:01:50
14	206.168.34.206	1	00:00:18
15	2a06:4883:b000::ba	1	00:00:00
16	flourishing.monitoring.internet-measurement.com	1	00:00:00
17	2a06:4883:d000::e7	1	00:00:03
18	204.150.216.162.bc.googleusercontent.com	1	00:00:00
19	117.149.216.162.bc.googleusercontent.com	2	00:00:03
20	azpdcgbf0s1x.stretchoid.com	1	00:00:00
21	exhilarating.monitoring.internet-measurement.com	1	00:00:00
22	194.165.16.167	1	00:00:00
23	198.235.24.157	3	00:00:09
24	2a06:4883:d000::ed	1	00:00:03
25	194.165.16.166	1	00:00:00
26	110.7.233.35.bc.googleusercontent.com	2	00:00:11
TOTAL: 26		320	00:03:22

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:01:50
2	*.monitoring.internet-measurement.com	5	00:00:10
3	unresolved	14	00:00:55
4	*.149.216.162.bc.googleusercontent.com	2	00:00:03
5	*.150.216.162.bc.googleusercontent.com	1	00:00:00
6	*.7.233.35.bc.googleusercontent.com	2	00:00:11
7	?	5	00:00:06
8	*.210.203.35.bc.googleusercontent.com	2	00:00:02
9	*.stretchoid.com	1	00:00:00
TOTAL: 9		320	00:03:22

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.814	4.443	1.851	00:01:50
206.168.34.206	0.018	0.007	0.000	00:00:18
110.7.233.35.bc.googleusercontent.com	0.038	0.038	0.000	00:00:11
198.235.24.157	0.037	0.031	0.000	00:00:09
147.185.133.74	0.051	0.026	0.000	00:00:08
205.210.31.157	0.073	0.052	0.000	00:00:07
timeless.monitoring.internet-measurement.com	0.068	0.014	0.000	00:00:06
147.185.132.37	0.023	0.027	0.000	00:00:03
unparalleled.monitoring.internet-measurement.com	0.028	0.004	0.000	00:00:03
47.74.41.172	0.015	0.008	0.000	00:00:03
2a06:4883:d000::e7	0.013	0.039	0.000	00:00:03
117.149.216.162.bc.googleusercontent.com	0.210	0.022	0.000	00:00:03
2a06:4883:d000::ed	0.077	0.008	0.000	00:00:03
170.210.203.35.bc.googleusercontent.com	0.168	0.058	0.000	00:00:02
205.210.31.233	0.033	0.018	0.000	00:00:01
194.165.16.165	0.019	0.009	0.000	00:00:00
azpdcgbf0s1x.stretchoid.com	0.015	0.030	0.000	00:00:00
204.150.216.162.bc.googleusercontent.com	0.021	0.021	0.000	00:00:00
luminous.monitoring.internet-measurement.com	0.016	0.012	0.000	00:00:00
194.165.16.167	0.029	0.025	0.000	00:00:00
TOTAL: 25	5.892	4.969	1.851	00:03:22

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.185.133.74	4
170.210.203.35.bc.googleusercontent.com	4
204.150.216.162.bc.googleusercontent.com	4
117.149.216.162.bc.googleusercontent.com	4
47.74.41.172	3
110.7.233.35.bc.googleusercontent.com	3
205.210.31.157	1
198.235.24.157	1
194.165.16.166	1
azpdcgbf0s1x.stretchoid.com	1
TOTAL: 10	26

NNRP unrecognized commands (by command):

Command	Count
Host: 45.13.105.36:563	4
GET / HTTP/1.1	4
Accept: /	2
#026#003	2
#026#003#001	2
Accept-Encoding: gzip	2
User-Agent: Expanse, a Palo Alto Network...	2
User-Agent: curl/7.68.0	2
À#024	2
À#À'	1
MGLNDD_45.13.105.36_119	1
#003	1
#023#001#023#002#023#003#001	1
TOTAL: 13	26

NNRP client timeouts [Top 20]:

System	Conn	Peer
198.235.24.157	2	3
205.210.31.157	2	3
117.149.216.162.bc.googleusercontent.com	1	2
170.210.203.35.bc.googleusercontent.com	1	2
194.165.16.166	1	1
47.74.41.172	1	1
TOTAL: 6	8	14

Daily Usenet report for news.niel.me

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