Daily Usenet report for news.niel.me: Feb 19 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	2181	26.9%	263.5 KB	21.5%
inn	2044	25.2%	353.3 KB	28.8%
innd	1966	24.2%	340.8 KB	27.8%
innfeed	1918	23.6%	269.0 KB	21.9%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8111	100.0%	1.2 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	7801	51.2%
Negative hits	5398	35.4%
Do not exist	2028	13.3%
Cache misses	2	0.0%
TOTAL: 4	15229	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	8	1.000	1.000	1.000
article cancel	00:00:00.000	0.0%	8	0.000	0.000	0.000
article cleanup	00:00:00.046	0.0%	2028	0.000	0.023	0.333
article logging	00:00:00.141	0.0%	2028	0.000	0.070	0.400
article parse	00:00:00.350	0.0%	6050	0.000	0.058	0.250
article write	00:00:00.896	0.0%	1669	0.000	0.537	1.571
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
data move	00:00:00.099	0.0%	17953	0.000	0.006	0.030
hisgrep/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	12	0.000	0.083	0.500
hishave/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	8	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.251	0.0%	15223	0.000	0.016	0.062
history sync	00:00:00.050	0.0%	367	0.000	0.136	1.000
history write	00:00:00.361	0.0%	2028	0.000	0.178	0.583
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:45.066	100.0%	19552	1057.611	4421.290	33350.667
nntp read	00:00:00.533	0.0%	18763	0.000	0.028	0.091
overview write	00:00:00.520	0.0%	1669	0.000	0.312	3.833
perl filter	00:00:12.162	0.0%	2028	1.933	5.997	20.000
python filter	00:00:00.014	0.0%	2028	0.000	0.007	0.182
site send	00:00:00.731	0.0%	3419	0.000	0.214	0.552
TOTAL: 24:01:08.335	24:01:01.229	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.024	0.0%	1699	0.000	0.014	0.200
article prepare	00:00:00.000	0.0%	100	0.000	0.000	0.000
article read	00:00:00.010	0.0%	24	0.000	0.417	4.000
backlog stats	00:00:00.137	0.0%	21667	0.000	0.006	0.057
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.271	0.0%	10236	0.000	0.026	0.129
data write	00:00:00.543	0.0%	8612	0.000	0.063	0.200
idle	23:57:19.270	100.0%	21667	1720.261	3980.213	9711.097
status file	00:00:08.249	0.0%	325	0.000	25.382	81.000
TOTAL: 23:57:32.937	23:57:28.504	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:56.112	14.8%	1505	5.000	37.284	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.104	0.0%	3512	0.000	0.030	0.500
TOTAL: 00:06:19.451	00:00:56.216	14.8%	-	-	-	-

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	1974	568	1099	307	28%	23:30:18
2	feed-out.gegeweb.org	3	1654	491	1163	0	29%	23:58:25
3	news.samoylyk.net	6	1714	384	1321	9	22%	23:57:21
4	news.glou.org	4	430	56	370	4	13%	10:58:01
5	nntp.terraraq.uk	5	1199	27	1172	0	2%	21:14:08
6	usenet.pasdenom.info	12	775	14	761	0	1%	23:19:49
7	gegeweb-out.news.tnetconsulting.net	5	218	7	211	0	3%	06:48:16
8	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 8		62	7964	1547	6097	320	19%	133:46:18

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.samoylyk.net	3.5 MB	15.3 KB	3.5 MB	99%	9.0 KB
2	news.nntp4.net	2.6 MB	2.5 MB	5.0 MB	50%	5.9 KB
3	feed-out.gegeweb.org	1.6 MB	0.0 KB	1.6 MB	100%	3.3 KB
4	news.glou.org	182.1 KB	27.5 KB	209.7 KB	86%	3.5 KB
5	nntp.terraraq.uk	148.6 KB	0.0 KB	148.6 KB	100%	5.5 KB
6	gegeweb-out.news.tnetconsulting.net	69.1 KB	0.0 KB	69.1 KB	100%	9.9 KB
7	usenet.pasdenom.info	28.8 KB	0.0 KB	28.8 KB	100%	2.1 KB
8	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 8		8.0 MB	2.5 MB	10.5 MB	76%	5.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Feb 19 04:15:01 - 04:59:59	27	1.6%	0.01	132.9 KB	1.5%	0.05
Feb 19 05:00:00 - 05:59:59	41	2.5%	0.01	139.3 KB	1.6%	0.04
Feb 19 06:00:00 - 06:59:59	57	3.4%	0.02	187.0 KB	2.2%	0.05
Feb 19 07:00:00 - 07:59:59	40	2.4%	0.01	218.2 KB	2.5%	0.06
Feb 19 08:00:00 - 08:59:59	37	2.2%	0.01	104.8 KB	1.2%	0.03
Feb 19 09:00:00 - 09:59:59	47	2.8%	0.01	515.1 KB	6.0%	0.14
Feb 19 10:00:00 - 10:59:59	63	3.8%	0.02	217.9 KB	2.5%	0.06
Feb 19 11:00:00 - 11:59:59	79	4.7%	0.02	253.5 KB	3.0%	0.07
Feb 19 12:00:00 - 12:59:59	91	5.5%	0.03	372.7 KB	4.3%	0.10
Feb 19 13:00:00 - 13:59:59	67	4.0%	0.02	233.4 KB	2.7%	0.06
Feb 19 14:00:00 - 14:59:59	81	4.9%	0.02	254.8 KB	3.0%	0.07
Feb 19 15:00:00 - 15:59:59	63	3.8%	0.02	211.1 KB	2.5%	0.06
Feb 19 16:00:00 - 16:59:59	69	4.1%	0.02	684.0 KB	8.0%	0.19
Feb 19 17:00:00 - 17:59:59	106	6.4%	0.03	393.4 KB	4.6%	0.11
Feb 19 18:00:00 - 18:59:59	117	7.0%	0.03	424.1 KB	4.9%	0.12
Feb 19 19:00:00 - 19:59:59	78	4.7%	0.02	290.8 KB	3.4%	0.08
Feb 19 20:00:00 - 20:59:59	76	4.6%	0.02	554.4 KB	6.5%	0.15
Feb 19 21:00:00 - 21:59:59	122	7.3%	0.03	558.1 KB	6.5%	0.16
Feb 19 22:00:00 - 22:59:59	69	4.1%	0.02	253.5 KB	3.0%	0.07
Feb 19 23:00:00 - 23:59:59	105	6.3%	0.03	1.6 MB	19.3%	0.46
Feb 20 00:00:00 - 00:59:59	88	5.3%	0.02	359.7 KB	4.2%	0.10
Feb 20 01:00:00 - 01:59:59	45	2.7%	0.01	168.0 KB	2.0%	0.05
Feb 20 02:00:00 - 02:59:59	52	3.1%	0.01	227.3 KB	2.7%	0.06
Feb 20 03:00:00 - 03:59:59	41	2.5%	0.01	141.3 KB	1.6%	0.04
Feb 20 04:00:00 - 04:15:01	8	0.5%	0.01	29.2 KB	0.3%	0.03
TOTAL: 24:00:00	1669	100.0%	0.02	8.4 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	318	245	73
2	news.glou.org	32	0	32
3	news.samoylyk.net	9	0	9
TOTAL: 3		359	245	114

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	177
linux.kernel.git	66
alt.slack	1
news.test	1
TOTAL: 4	245

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	55
Bad path (news.giganews.com.POSTED)	46
EMP (phn path)	8
Cancel of non-existing ID <10n6iga$3bv3f$1@dont-email.me>	2
HTML Multipart	1
Cancel of non-existing ID <10n7j4r$3nm2o$1@dont-email.me>	1
Cancel of non-existing ID <10n6rsr$3f53f$1@dont-email.me>	1
TOTAL: 7	114

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	3077	9	1646	10	30	0%	23:55:16
2	news.samoylyk.net	1363	2	1206	3	0	0%	23:58:56
3	nntp.terraraq.uk	835	1	800	0	0	0%	23:25:26
4	news.gegeweb.org	1211	0	1176	0	0	0%	23:49:54
5	news.pasdenom.info	1141	0	1062	0	0	0%	23:46:10
6	i2pn.org	0	0	0	0	1650	0%	24:00:00
7	news.nntp4.net	1091	0	1059	0	0	0%	23:18:39
8	news.aioe.org	0	0	0	0	1389	0%	24:00:00
TOTAL: 8		8718	12	6949	13	3069	0%	190:14:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	30.5 KB	119.8 KB	150.3 KB	7.9 KB	23:55:16
2	news.samoylyk.net	5.9 KB	27.5 KB	33.4 KB	6.7 KB	23:58:56
3	nntp.terraraq.uk	4.2 KB	0.0 KB	4.2 KB	4.2 KB	23:25:26
4	news.gegeweb.org	0.0 KB	0.0 KB	0.0 KB	0	23:49:54
5	news.pasdenom.info	0.0 KB	0.0 KB	0.0 KB	0	23:46:10
6	i2pn.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:18:39
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
TOTAL: 8		40.5 KB	147.3 KB	187.8 KB	7.5 KB	190:14:21

NNRP readership statistics:

System		Conn	Elapsed
1	confident.monitoring.internet-measurement.com	1	00:00:01
2	distinct.monitoring.internet-measurement.com	1	00:00:01
3	fierce.monitoring.internet-measurement.com	1	00:00:00
4	thorough.monitoring.internet-measurement.com	1	00:00:00
5	generous.monitoring.internet-measurement.com	1	00:00:00
6	rewarding.monitoring.internet-measurement.com	1	00:00:03
7	201.34.168.206.censys-scanner.com	1	00:00:33
8	skilful.monitoring.internet-measurement.com	1	00:00:00
9	165.154.129.201	12	00:00:12
10	147.185.132.133	4	00:01:22
11	239.210.203.35.bc.googleusercontent.com	2	00:00:01
12	nurturing.monitoring.internet-measurement.com	1	00:00:06
13	40.34.168.206.censys-scanner.com	1	00:00:01
14	adroit.monitoring.internet-measurement.com	1	00:00:00
15	eager.monitoring.internet-measurement.com	1	00:00:00
16	buoyant.monitoring.internet-measurement.com	1	00:00:01
17	lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	1	00:00:10
18	impeccable.monitoring.internet-measurement.com	2	00:00:01
19	48.34.168.206.censys-scanner.com	1	00:00:30
20	203.55.131.3	1	00:00:00
21	113.153.132.66.censys-scanner.com	1	00:00:30
22	o322.scanner.modat.io	1	00:00:00
23	91.238.181.93	1	00:00:00
24	171.150.216.162.bc.googleusercontent.com	1	00:00:01
25	147.185.133.97	1	00:00:09
26	sensational.monitoring.internet-measurement.com	1	00:00:01
27	captivating.monitoring.internet-measurement.com	1	00:00:00
28	2603:6010:6800:36:1a03:73ff:fe28:be0f	6	00:00:05
29	mail.gegeweb.eu	288	00:00:32
30	azpdsg59c86t.stretchoid.com	2	00:00:10
31	147.185.132.99	1	00:00:03
32	130.153.132.66.censys-scanner.com	3	00:00:03
33	66.61.98.95	4	00:00:04
34	57.34.168.206.censys-scanner.com	1	00:00:31
35	engaging.monitoring.internet-measurement.com	1	00:00:00
36	147.185.133.149	4	00:01:54
37	136.153.132.66.censys-scanner.com	1	00:00:33
38	247.211.203.35.bc.googleusercontent.com	1	00:00:00
39	visionary.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 39		356	00:07:53

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	17	00:00:19
2	unresolved	28	00:03:47
3	*.gegeweb.eu	288	00:00:32
4	*.210.203.35.bc.googleusercontent.com	2	00:00:01
5	*.153.132.66.censys-scanner.com	5	00:01:07
6	*.w92-154.abo.wanadoo.fr	1	00:00:10
7	*.150.216.162.bc.googleusercontent.com	1	00:00:01
8	*.211.203.35.bc.googleusercontent.com	1	00:00:00
9	*.scanner.modat.io	1	00:00:00
10	?	6	00:00:05
11	*.34.168.206.censys-scanner.com	4	00:01:36
12	*.stretchoid.com	2	00:00:10
TOTAL: 12		356	00:07:53

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
147.185.133.149	0.422	0.123	0.000	00:01:54
147.185.132.133	0.432	0.155	0.000	00:01:22
201.34.168.206.censys-scanner.com	0.038	0.026	0.000	00:00:33
136.153.132.66.censys-scanner.com	0.046	0.017	0.000	00:00:33
mail.gegeweb.eu	5.084	4.243	1.647	00:00:32
57.34.168.206.censys-scanner.com	0.024	0.039	0.000	00:00:31
48.34.168.206.censys-scanner.com	0.030	0.026	0.000	00:00:30
113.153.132.66.censys-scanner.com	0.044	0.013	0.000	00:00:30
165.154.129.201	0.442	0.151	0.000	00:00:12
azpdsg59c86t.stretchoid.com	0.043	0.029	0.000	00:00:10
lstlambert-656-1-48-236.w92-154.abo.wanadoo.fr	0.034	0.011	0.000	00:00:10
147.185.133.97	0.092	0.017	0.000	00:00:09
nurturing.monitoring.internet-measurement.com	0.175	0.040	0.000	00:00:06
2603:6010:6800:36:1a03:73ff:fe28:be0f	0.140	0.090	0.000	00:00:05
66.61.98.95	0.088	0.097	0.000	00:00:04
130.153.132.66.censys-scanner.com	0.081	0.042	0.000	00:00:03
147.185.132.99	0.011	0.011	0.000	00:00:03
rewarding.monitoring.internet-measurement.com	0.023	0.005	0.000	00:00:03
confident.monitoring.internet-measurement.com	0.023	0.027	0.000	00:00:01
40.34.168.206.censys-scanner.com	0.036	0.016	0.000	00:00:01
TOTAL: 37	8.330	5.515	1.647	00:07:53

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.185.133.149	8
147.185.132.133	8
247.211.203.35.bc.googleusercontent.com	4
147.185.133.97	4
239.210.203.35.bc.googleusercontent.com	4
171.150.216.162.bc.googleusercontent.com	4
azpdsg59c86t.stretchoid.com	1
40.34.168.206.censys-scanner.com	1
91.238.181.93	1
130.153.132.66.censys-scanner.com	1
TOTAL: 10	36

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	8
Host: 45.13.105.36:563	7
User-Agent: curl/7.68.0	5
Accept: /	5
User-Agent: Hello from Palo Alto Network...	3
Accept-Encoding: gzip	3
EHLO www.censys.io	2
#003	1
Host: 45.13.105.36:119	1
MGLNDD_45.13.105.36_119	1
TOTAL: 10	36

NNRP client timeouts [Top 20]:

System	Conn	Peer
147.185.132.133	2	4
147.185.133.149	2	3
239.210.203.35.bc.googleusercontent.com	1	2
91.238.181.93	1	1
130.153.132.66.censys-scanner.com	1	2
TOTAL: 5	7	13

Daily Usenet report for news.niel.me

Feb 19 04:15:01 -- Feb 20 04:15:01