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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 15 17:00:07 rpi-studio pgpverify[7685]: pgpverify: verification failed
Jun 15 17:00:18 rpi-studio pgpverify[7709]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2134	26.4%	395.3 KB	30.9%
innd	2130	26.3%	388.2 KB	30.3%
nnrpd	1963	24.3%	212.7 KB	16.6%
innfeed	1841	22.8%	282.2 KB	22.0%
controlchan	13	0.2%	2.2 KB	0.2%
pgpverify	2	0.0%	0.5 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 7	8084	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	11380	58.5%
Negative hits	5934	30.5%
Do not exist	2122	10.9%
Cache misses	3	0.0%
TOTAL: 4	19439	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	1.500
article cancel	00:00:00.001	0.0%	8	0.000	0.125	0.500
article cleanup	00:00:00.046	0.0%	2122	0.000	0.022	0.667
article logging	00:00:00.123	0.0%	2122	0.000	0.058	0.500
article parse	00:00:00.452	0.0%	6215	0.000	0.073	0.400
article write	00:00:01.016	0.0%	1872	0.000	0.543	1.600
artlog/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
data move	00:00:00.146	0.0%	22610	0.000	0.006	0.039
hisgrep/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/perl	00:00:00.000	0.0%	10	0.000	0.000	0.000
hishave/artcncl	00:00:00.000	0.0%	8	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.368	0.0%	19443	0.000	0.019	0.096
history sync	00:00:00.053	0.0%	402	0.000	0.132	1.000
history write	00:00:00.358	0.0%	2122	0.000	0.169	0.667
hiswrite/artcncl	00:00:00.000	0.0%	2	0.000	0.000	0.000
idle	24:00:46.927	100.0%	24112	437.027	3585.224	20720.931
nntp read	00:00:00.665	0.0%	23419	0.000	0.028	0.143
overview write	00:00:00.612	0.0%	1872	0.000	0.327	5.667
perl filter	00:00:11.990	0.0%	2122	2.235	5.650	24.750
python filter	00:00:00.008	0.0%	2122	0.000	0.004	0.167
site send	00:00:00.721	0.0%	3803	0.000	0.190	0.667
TOTAL: 24:01:12.639	24:01:03.492	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.017	0.0%	1872	0.000	0.009	0.250
article prepare	00:00:00.001	0.0%	292	0.000	0.003	0.250
article read	00:00:00.022	0.0%	69	0.000	0.319	8.000
backlog stats	00:00:00.159	0.0%	27693	0.000	0.006	0.037
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.443	0.0%	14647	0.000	0.030	0.118
data write	00:00:00.710	0.0%	12811	0.000	0.055	0.158
idle	23:58:58.773	100.0%	27693	1222.150	3117.711	7901.724
status file	00:00:07.616	0.0%	249	1.000	30.586	92.000
TOTAL: 23:59:13.943	23:59:07.741	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:04:18.079	65.4%	1495	3.000	172.628	96237.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.159	0.0%	3505	0.000	0.045	1.000
readart	00:00:00.585	0.1%	11850	0.049	0.049	0.049
TOTAL: 00:06:34.815	00:04:18.823	65.6%	-	-	-	-

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	2	2	0	2
TOTAL	0	0	2	2	0	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feed-out.gegeweb.org	2	1854	672	1182	0	36%	23:59:46
2	news.nntp4.net	2	2058	560	1294	204	27%	23:59:57
3	news.samoylyk.net	2	2015	126	1887	2	6%	25:46:26
4	news.glou.org	4	1622	98	1495	29	6%	23:51:22
5	nntp.terraraq.uk	2	1496	91	1397	8	6%	22:05:05
6	gegeweb-out.news.tnetconsulting.net	2	416	28	388	0	6%	10:07:02
7	i2pn.org	2	518	23	495	0	4%	10:07:08
8	usenet.pasdenom.info	4	391	0	391	0	0%	12:02:00
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		45	10370	1598	8529	243	15%	151:58:46

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.nntp4.net	4.0 MB	1022.8 KB	5.0 MB	79%	6.7 KB
2	news.samoylyk.net	3.5 MB	2.6 KB	3.5 MB	99%	27.6 KB
3	feed-out.gegeweb.org	2.4 MB	0.0 KB	2.4 MB	100%	3.7 KB
4	news.glou.org	301.1 KB	110.9 KB	412.0 KB	73%	3.2 KB
5	nntp.terraraq.uk	159.8 KB	50.6 KB	210.4 KB	75%	2.1 KB
6	gegeweb-out.news.tnetconsulting.net	87.5 KB	0.0 KB	87.5 KB	100%	3.1 KB
7	i2pn.org	49.7 KB	0.0 KB	49.7 KB	100%	2.2 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		10.5 MB	1.2 MB	11.6 MB	90%	6.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 15 04:15:02 - 04:59:59	19	1.0%	0.01	60.7 KB	0.5%	0.02
Jun 15 05:00:00 - 05:59:59	51	2.7%	0.01	184.8 KB	1.6%	0.05
Jun 15 06:00:00 - 06:59:59	35	1.9%	0.01	139.6 KB	1.2%	0.04
Jun 15 07:00:00 - 07:59:59	23	1.2%	0.01	162.0 KB	1.4%	0.04
Jun 15 08:00:00 - 08:59:59	34	1.8%	0.01	130.8 KB	1.2%	0.04
Jun 15 09:00:00 - 09:59:59	52	2.8%	0.01	244.0 KB	2.1%	0.07
Jun 15 10:00:00 - 10:59:59	66	3.5%	0.02	299.5 KB	2.6%	0.08
Jun 15 11:00:00 - 11:59:59	81	4.3%	0.02	446.1 KB	3.9%	0.12
Jun 15 12:00:00 - 12:59:59	68	3.6%	0.02	220.8 KB	1.9%	0.06
Jun 15 13:00:00 - 13:59:59	89	4.8%	0.02	255.0 KB	2.2%	0.07
Jun 15 14:00:00 - 14:59:59	69	3.7%	0.02	387.4 KB	3.4%	0.11
Jun 15 15:00:00 - 15:59:59	102	5.5%	0.03	443.8 KB	3.9%	0.12
Jun 15 16:00:00 - 16:59:59	76	4.1%	0.02	967.8 KB	8.5%	0.27
Jun 15 17:00:00 - 17:59:59	98	5.2%	0.03	658.8 KB	5.8%	0.18
Jun 15 18:00:00 - 18:59:59	94	5.0%	0.03	588.9 KB	5.2%	0.16
Jun 15 19:00:00 - 19:59:59	119	6.4%	0.03	472.1 KB	4.2%	0.13
Jun 15 20:00:00 - 20:59:59	127	6.8%	0.04	326.7 KB	2.9%	0.09
Jun 15 21:00:00 - 21:59:59	151	8.1%	0.04	429.9 KB	3.8%	0.12
Jun 15 22:00:00 - 22:59:59	154	8.2%	0.04	3.8 MB	33.9%	1.07
Jun 15 23:00:00 - 23:59:59	108	5.8%	0.03	316.5 KB	2.8%	0.09
Jun 16 00:00:00 - 00:59:59	67	3.6%	0.02	262.8 KB	2.3%	0.07
Jun 16 01:00:00 - 01:59:59	81	4.3%	0.02	241.0 KB	2.1%	0.07
Jun 16 02:00:00 - 02:59:59	51	2.7%	0.01	131.1 KB	1.2%	0.04
Jun 16 03:00:00 - 03:59:59	46	2.5%	0.01	119.6 KB	1.1%	0.03
Jun 16 04:00:00 - 04:15:01	9	0.5%	0.01	17.1 KB	0.2%	0.02
TOTAL: 23:59:59	1870	100.0%	0.02	11.1 MB	100.0%	0.13

Sites sending bad articles:

Server		Total	Group	Other
1	news.nntp4.net	204	59	145
2	news.glou.org	29	0	29
3	nntp.terraraq.uk	8	0	8
4	i2pn.org	4	0	4
5	gegeweb-out.news.tnetconsulting.net	3	0	3
6	news.samoylyk.net	2	0	2
TOTAL: 6		250	59	191

Unwanted newsgroups [Top 20]:

Newsgroup	Count
linux.kernel.netdev	43
linux.kernel.git	15
news.test	1
TOTAL: 3	59

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	90
EMP (md5)	51
Bad path (news.giganews.com.POSTED)	44
Cancels abusifs	3
Cancel of <102nfnr$nmm$1@news.chmurka.net> without Cancel-Key	1
HTML Multipart	1
Cancel of <102neie$lj1$1@news.chmurka.net> without Cancel-Key	1
TOTAL: 7	191

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.tnetconsulting.net	2618	24	1826	4	0	0%	23:59:23
2	news.gegeweb.org	1262	20	1190	0	0	1%	23:59:34
3	i2pn.org	2452	17	1703	2	0	0%	23:58:48
4	news.nntp4.net	1368	4	1289	0	0	0%	23:52:38
5	news.samoylyk.net	2114	1	1742	0	3	0%	23:59:55
6	news.pasdenom.info	1648	1	1581	0	0	0%	23:56:31
7	nntp.terraraq.uk	1306	0	1181	0	0	0%	23:52:14
8	news.aioe.org	0	0	0	0	1520	0%	23:59:59
TOTAL: 8		12768	67	10512	6	1523	0%	191:39:02

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.tnetconsulting.net	171.9 KB	68.9 KB	240.7 KB	8.6 KB	23:59:23
2	i2pn.org	145.3 KB	87.8 KB	233.1 KB	12.3 KB	23:58:48
3	news.pasdenom.info	123.2 KB	0.0 KB	123.2 KB	123.2 KB	23:56:31
4	news.gegeweb.org	40.9 KB	0.0 KB	40.9 KB	2.0 KB	23:59:34
5	news.nntp4.net	18.6 KB	0.0 KB	18.6 KB	4.6 KB	23:52:38
6	news.samoylyk.net	2.9 KB	0.0 KB	2.9 KB	2.9 KB	23:59:55
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:52:14
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
TOTAL: 8		502.8 KB	156.7 KB	659.5 KB	9.0 KB	191:39:02

NNRP readership statistics:

System		Conn	Elapsed
1	147.185.132.182	1	00:00:00
2	198.235.24.160	6	00:03:29
3	147.185.132.98	1	00:00:00
4	2a06:4882:b000::cc	1	00:00:02
5	147.185.132.111	1	00:00:02
6	93.123.109.218	2	00:00:15
7	91.238.181.91	1	00:00:01
8	2a06:4883:3000::3e	1	00:00:00
9	natural.monitoring.internet-measurement.com	1	00:00:01
10	206.168.34.210	1	00:00:22
11	2a06:4883:5000::4a	2	00:00:11
12	252.149.216.162.bc.googleusercontent.com	1	00:00:00
13	194.165.16.164	1	00:00:00
14	mail.gegeweb.eu	288	00:01:44
15	203.55.131.4	1	00:00:01
16	2a06:4883:3000::3c	1	00:00:00
17	185.242.226.41	1	00:00:00
18	184.150.216.162.bc.googleusercontent.com	2	00:00:04
19	205.210.31.200	1	00:00:04
20	51.159.234.118	1	00:00:00
21	2a06:4882:3000::2b	1	00:00:00
22	pool-100-38-239-226.nycmny.fios.verizon.net	1	00:00:12
23	49.150.216.162.bc.googleusercontent.com	2	00:00:03
24	172-236-228-245.ip.linodeusercontent.com	2	00:00:01
TOTAL: 24		321	00:06:42

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.nycmny.fios.verizon.net	1	00:00:12
2	?	6	00:00:14
3	*.149.216.162.bc.googleusercontent.com	1	00:00:00
4	*.ip.linodeusercontent.com	2	00:00:01
5	*.gegeweb.eu	288	00:01:44
6	*.150.216.162.bc.googleusercontent.com	4	00:00:07
7	*.monitoring.internet-measurement.com	1	00:00:01
8	unresolved	18	00:04:19
TOTAL: 8		321	00:06:42

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
198.235.24.160	0.107	0.105	0.000	00:03:29
mail.gegeweb.eu	4.745	4.369	1.842	00:01:44
206.168.34.210	0.044	0.022	0.000	00:00:22
93.123.109.218	0.045	0.033	0.000	00:00:15
pool-100-38-239-226.nycmny.fios.verizon.net	0.301	0.711	0.000	00:00:12
2a06:4883:5000::4a	0.129	0.065	0.000	00:00:11
184.150.216.162.bc.googleusercontent.com	0.164	0.067	0.000	00:00:04
205.210.31.200	0.028	0.024	0.000	00:00:04
49.150.216.162.bc.googleusercontent.com	0.214	0.082	0.000	00:00:03
2a06:4882:b000::cc	0.034	0.017	0.000	00:00:02
147.185.132.111	0.034	0.017	0.000	00:00:02
natural.monitoring.internet-measurement.com	0.026	0.022	0.000	00:00:01
203.55.131.4	0.015	0.008	0.000	00:00:01
91.238.181.91	0.030	0.021	0.000	00:00:01
172-236-228-245.ip.linodeusercontent.com	0.024	0.024	0.000	00:00:01
51.159.234.118	0.037	0.016	0.000	00:00:00
147.185.132.98	0.021	0.030	0.000	00:00:00
194.165.16.164	0.019	0.008	0.000	00:00:00
252.149.216.162.bc.googleusercontent.com	0.038	0.034	0.000	00:00:00
147.185.132.182	0.073	0.016	0.000	00:00:00
TOTAL: 23	6.213	5.754	1.842	00:06:42

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
172-236-228-245.ip.linodeusercontent.com	7
147.185.132.98	4
147.185.132.182	4
184.150.216.162.bc.googleusercontent.com	4
252.149.216.162.bc.googleusercontent.com	4
49.150.216.162.bc.googleusercontent.com	4
185.242.226.41	2
198.235.24.160	2
206.168.34.210	1
93.123.109.218	1
91.238.181.91	1
TOTAL: 11	34

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	6
Accept-Encoding: gzip	4
Host: 45.13.105.36:563	4
User-Agent: Expanse, a Palo Alto Network...	3
Accept: /	3
#026#003	2
User-Agent: curl/7.68.0	2
#001	2
#026#003#001	2
Host: 45.13.105.36:119	2
EHLO www.censys.io	1
#003	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
SSH-2.0-OpenSSH	1
TOTAL: 14	34

NNRP client timeouts [Top 20]:

System	Conn	Peer
198.235.24.160	4	6
49.150.216.162.bc.googleusercontent.com	1	2
184.150.216.162.bc.googleusercontent.com	1	2
91.238.181.91	1	1
TOTAL: 4	7	13

Daily Usenet report for news.niel.me

Jun 15 04:15:02 -- Jun 16 04:15:01