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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 15 17:00:07 rpi-studio pgpverify[10936]: pgpverify: verification failed
Oct 15 17:00:15 rpi-studio pgpverify[10958]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2400	28.6%	451.0 KB	33.4%
innd	2172	25.9%	384.2 KB	28.4%
nnrpd	1904	22.7%	214.4 KB	15.9%
innfeed	1895	22.6%	298.5 KB	22.1%
controlchan	13	0.2%	2.2 KB	0.2%
pgpverify	2	0.0%	0.4 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 7	8387	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	12851	58.8%
Negative hits	6611	30.3%
Do not exist	2377	10.9%
Cache misses	5	0.0%
TOTAL: 4	21844	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	4	1.000	2.000	4.000
article cancel	00:00:00.001	0.0%	4	0.000	0.250	1.000
article cleanup	00:00:00.059	0.0%	2382	0.000	0.025	0.250
article logging	00:00:00.175	0.0%	2382	0.000	0.073	0.500
article parse	00:00:00.387	0.0%	6166	0.000	0.063	0.444
article write	00:00:01.134	0.0%	2178	0.000	0.521	1.111
artlog/artcncl	00:00:00.000	0.0%	4	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	4	0.000	0.000	0.000
artlog/artwrite	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:00.126	0.0%	23535	0.000	0.005	0.029
hisgrep/artcncl	00:00:00.000	0.0%	4	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.001	0.0%	9	0.000	0.111	1.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%	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.365	0.0%	21883	0.000	0.017	0.086
history sync	00:00:00.063	0.0%	394	0.000	0.160	1.000
history write	00:00:00.439	0.0%	2382	0.000	0.184	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:48.936	100.0%	25796	1074.708	3351.254	15395.949
nntp read	00:00:00.668	0.0%	25003	0.000	0.027	0.067
overview write	00:00:00.709	0.0%	2178	0.000	0.326	5.200
perl filter	00:00:09.795	0.0%	2382	2.040	4.112	9.500
python filter	00:00:00.010	0.0%	2382	0.000	0.004	0.143
site send	00:00:00.933	0.0%	4380	0.000	0.213	0.667
TOTAL: 24:01:11.243	24:01:03.809	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.027	0.0%	2295	0.000	0.012	1.000
article prepare	00:00:00.003	0.0%	604	0.000	0.005	0.250
article read	00:00:00.033	0.0%	150	0.000	0.220	7.000
backlog stats	00:00:00.182	0.0%	31673	0.000	0.006	0.027
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.430	0.0%	17041	0.000	0.025	0.095
data write	00:00:00.888	0.0%	15085	0.000	0.059	0.140
idle	23:49:54.766	100.0%	31673	1046.817	2708.767	8128.959
status file	00:00:00.587	0.0%	285	0.000	2.060	7.000
TOTAL: 23:50:02.061	23:49:56.916	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:01:13.814	90.8%	1461	5.500	2514.589	600353.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.121	0.0%	3260	0.000	0.037	1.000
TOTAL: 01:07:24.923	01:01:13.935	90.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

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	3	2136	990	1146	0	46%	23:58:36
2	news.samoylyk.net	4	3518	440	3077	1	12%	35:16:16
3	gegeweb-out.news.tnetconsulting.net	3	1863	274	1553	36	14%	23:53:07
4	i2pn.org	2	2080	204	1876	0	9%	24:10:00
5	news.nntp4.net	2	2958	178	2607	173	6%	29:09:09
6	usenet.pasdenom.info	6	2135	140	1973	22	6%	39:06:17
7	nntp.terraraq.uk	4	1865	64	1801	0	3%	24:44:25
8	news.glou.org	3	88	4	76	8	4%	02:08:48
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		52	16643	2294	14109	240	13%	202:26:38

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.9 MB	0.0 KB	3.9 MB	100%	4.0 KB
2	news.samoylyk.net	3.0 MB	2.2 KB	3.0 MB	99%	6.9 KB
3	gegeweb-out.news.tnetconsulting.net	1.2 MB	134.0 KB	1.3 MB	89%	4.3 KB
4	news.nntp4.net	890.6 KB	605.0 KB	1.5 MB	59%	4.3 KB
5	i2pn.org	583.1 KB	0.0 KB	583.1 KB	100%	2.9 KB
6	nntp.terraraq.uk	372.1 KB	0.0 KB	372.1 KB	100%	5.8 KB
7	usenet.pasdenom.info	317.5 KB	47.6 KB	365.0 KB	86%	2.3 KB
8	news.glou.org	12.3 KB	40.9 KB	53.2 KB	23%	4.4 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		10.2 MB	829.8 KB	11.0 MB	92%	4.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 15 04:15:01 - 04:59:59	28	1.3%	0.01	96.1 KB	0.9%	0.04
Oct 15 05:00:00 - 05:59:59	53	2.4%	0.01	162.6 KB	1.6%	0.05
Oct 15 06:00:00 - 06:59:59	27	1.2%	0.01	83.5 KB	0.8%	0.02
Oct 15 07:00:00 - 07:59:59	36	1.6%	0.01	162.4 KB	1.6%	0.05
Oct 15 08:00:00 - 08:59:59	63	2.9%	0.02	226.7 KB	2.2%	0.06
Oct 15 09:00:00 - 09:59:59	52	2.4%	0.01	338.3 KB	3.3%	0.09
Oct 15 10:00:00 - 10:59:59	56	2.6%	0.02	175.2 KB	1.7%	0.05
Oct 15 11:00:00 - 11:59:59	39	1.8%	0.01	127.2 KB	1.3%	0.04
Oct 15 12:00:00 - 12:59:59	57	2.6%	0.02	207.2 KB	2.0%	0.06
Oct 15 13:00:00 - 13:59:59	79	3.6%	0.02	272.8 KB	2.7%	0.08
Oct 15 14:00:00 - 14:59:59	114	5.2%	0.03	471.6 KB	4.6%	0.13
Oct 15 15:00:00 - 15:59:59	132	6.0%	0.04	506.6 KB	5.0%	0.14
Oct 15 16:00:00 - 16:59:59	141	6.4%	0.04	489.0 KB	4.8%	0.14
Oct 15 17:00:00 - 17:59:59	168	7.7%	0.05	1.5 MB	14.8%	0.42
Oct 15 18:00:00 - 18:59:59	134	6.1%	0.04	606.4 KB	6.0%	0.17
Oct 15 19:00:00 - 19:59:59	173	7.9%	0.05	620.9 KB	6.1%	0.17
Oct 15 20:00:00 - 20:59:59	86	3.9%	0.02	386.8 KB	3.8%	0.11
Oct 15 21:00:00 - 21:59:59	146	6.7%	0.04	464.1 KB	4.6%	0.13
Oct 15 22:00:00 - 22:59:59	121	5.5%	0.03	843.4 KB	8.3%	0.23
Oct 15 23:00:00 - 23:59:59	102	4.7%	0.03	406.8 KB	4.0%	0.11
Oct 16 00:00:00 - 00:59:59	62	2.8%	0.02	679.2 KB	6.7%	0.19
Oct 16 01:00:00 - 01:59:59	177	8.1%	0.05	761.6 KB	7.5%	0.21
Oct 16 02:00:00 - 02:59:59	62	2.8%	0.02	260.5 KB	2.6%	0.07
Oct 16 03:00:00 - 03:59:59	55	2.5%	0.02	187.7 KB	1.8%	0.05
Oct 16 04:00:00 - 04:15:01	25	1.1%	0.03	139.1 KB	1.4%	0.15
TOTAL: 24:00:00	2188	100.0%	0.03	9.9 MB	100.0%	0.12

Sites sending bad articles:

Server		Total	Other
1	news.nntp4.net	104	104
2	news.glou.org	49	49
3	gegeweb-out.news.tnetconsulting.net	39	39
4	usenet.pasdenom.info	11	11
5	news.samoylyk.net	1	1
TOTAL: 5		204	204

INND Perl filter [Top 20]:

Reason	Count
Too many newsgroups	97
EMP (phn path)	54
Bad path (news.giganews.com.POSTED)	39
EMP (md5)	5
Too many newsgroups (meow)	3
Cancel of <4588e35d3cd1289120c01864abf14eb89a0ece0e@i2pn2.org> without Cancel-Key	1
Cancels abusifs	1
Cancel of <9130cead2781049cd839f70976c203cccce62ebd@i2pn2.org> without Cancel-Key	1
Cancel of <3ed66e9299ae5868ddca7083d4173a0c399b25c7@i2pn2.org> without Cancel-Key	1
Cancel of <2512026e0d1c188879b70e30caf4d9138796d3a3@i2pn2.org> without Cancel-Key	1
Cancel of <98f8ee79baedf332178b9c88ab7830e10a9704ef@i2pn2.org> without Cancel-Key	1
TOTAL: 11	204

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3190	94	1915	7	56	2%	23:58:57
2	news.gegeweb.org	1317	41	1178	0	0	3%	23:32:07
3	news.tnetconsulting.net	3619	3	2181	1	43	0%	23:58:57
4	news.pasdenom.info	1845	2	1679	2	0	0%	23:54:40
5	news.samoylyk.net	2065	0	1826	2	28	0%	23:56:01
6	news.aioe.org	0	0	0	0	1806	0%	24:00:00
7	news.nntp4.net	2067	0	1968	1	0	0%	23:58:57
8	nntp.terraraq.uk	1352	0	1339	0	0	0%	23:43:49
TOTAL: 8		15455	140	12086	13	1933	0%	191:03:28

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	391.9 KB	30.9 KB	422.8 KB	4.2 KB	23:58:57
2	news.gegeweb.org	120.5 KB	0.0 KB	120.5 KB	2.9 KB	23:32:07
3	news.tnetconsulting.net	9.0 KB	2.0 KB	11.0 KB	2.8 KB	23:58:57
4	news.pasdenom.info	6.4 KB	6.7 KB	13.1 KB	3.3 KB	23:54:40
5	news.samoylyk.net	0.0 KB	15.9 KB	15.9 KB	7.9 KB	23:56:01
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	news.nntp4.net	0.0 KB	2.2 KB	2.2 KB	2.2 KB	23:58:57
8	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:43:49
TOTAL: 8		527.7 KB	57.8 KB	585.5 KB	3.8 KB	191:03:28

NNRP readership statistics:

System		Conn	Elapsed
1	205.210.31.150	1	00:00:10
2	mail.gegeweb.eu	288	00:02:32
3	206.168.34.211	1	00:00:30
4	206.168.34.206	1	00:00:30
5	194.165.16.73	1	00:00:00
6	206.168.34.209	1	00:00:30
7	principled.monitoring.internet-measurement.com	1	00:00:00
8	server-0-3.survey.inspici.com	1	00:00:04
9	58.149.216.162.bc.googleusercontent.com	1	00:00:00
10	206.168.34.218	1	00:00:30
11	congratulated.monitoring.internet-measurement.com	1	00:00:02
12	famous.monitoring.internet-measurement.com	1	00:00:06
13	ns31410048.ip-145-239-154.eu	2	00:00:00
14	69.10.48.174	2	00:00:00
15	253.210.203.35.bc.googleusercontent.com	1	00:00:00
16	147.185.133.51	1	00:00:12
17	205.210.31.149	1	00:00:10
18	91.238.181.32	1	00:00:01
19	2a02-8440-641a-ddef-c5b1-ace1-18a4-1dce.rev.sfr.net	2	01:00:03
20	energetic.monitoring.internet-measurement.com	1	00:00:00
21	205.210.31.103	1	00:00:10
22	206.168.34.220	1	00:00:30
23	91.238.181.23	1	00:00:00
24	206.168.34.213	1	00:00:30
25	206.168.34.36	1	00:00:31
26	71.6.146.186	1	00:00:08
27	147.45.112.151	1	00:00:01
28	chivalrous.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 28		318	01:07:24

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	288	00:02:32
2	*.149.216.162.bc.googleusercontent.com	1	00:00:00
3	unresolved	18	00:04:32
4	*.rev.sfr.net	2	01:00:03
5	*.monitoring.internet-measurement.com	5	00:00:08
6	*.survey.inspici.com	1	00:00:04
7	*.210.203.35.bc.googleusercontent.com	1	00:00:00
8	*.ip-145-239-154.eu	2	00:00:00
TOTAL: 8		318	01:07:24

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
2a02-8440-641a-ddef-c5b1-ace1-18a4-1dce.rev.sfr.net	0.047	0.056	0.000	01:00:03
mail.gegeweb.eu	5.268	4.475	1.730	00:02:32
206.168.34.36	0.047	0.018	0.000	00:00:31
206.168.34.206	0.045	0.015	0.000	00:00:30
206.168.34.218	0.042	0.023	0.000	00:00:30
206.168.34.220	0.016	0.016	0.000	00:00:30
206.168.34.209	0.016	0.016	0.000	00:00:30
206.168.34.213	0.028	0.000	0.000	00:00:30
206.168.34.211	0.039	0.009	0.000	00:00:30
147.185.133.51	0.052	0.026	0.000	00:00:12
205.210.31.103	0.013	0.010	0.000	00:00:10
205.210.31.149	0.034	0.015	0.000	00:00:10
205.210.31.150	0.024	0.020	0.000	00:00:10
71.6.146.186	0.036	0.009	0.000	00:00:08
famous.monitoring.internet-measurement.com	0.056	0.018	0.000	00:00:06
server-0-3.survey.inspici.com	0.015	0.008	0.000	00:00:04
congratulated.monitoring.internet-measurement.com	0.028	0.036	0.000	00:00:02
91.238.181.32	0.040	0.016	0.000	00:00:01
147.45.112.151	0.009	0.013	0.000	00:00:01
69.10.48.174	0.017	0.030	0.000	00:00:00
TOTAL: 27	6.121	4.984	1.730	01:07:24

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
58.149.216.162.bc.googleusercontent.com	4
147.185.133.51	4
253.210.203.35.bc.googleusercontent.com	4
ns31410048.ip-145-239-154.eu	1
194.165.16.73	1
147.45.112.151	1
TOTAL: 6	15

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	3
Accept-Encoding: gzip	3
User-Agent: Expanse, a Palo Alto Network...	3
Host: 45.13.105.36:563	3
#003	2
#026#003	1
TOTAL: 6	15

NNRP client timeouts [Top 20]:

System	Conn	Peer
2a02-8440-641a-ddef-c5b1-ace1-18a4-1dce.rev.sfr.net	2	0
147.45.112.151	1	1
194.165.16.73	1	1
TOTAL: 3	4	2

Daily Usenet report for news.niel.me

Oct 15 04:15:01 -- Oct 16 04:15:01