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

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Aug 15 17:00:04 rpi-studio pgpverify[464]: pgpverify: verification failed
Aug 15 17:00:05 rpi-studio pgpverify[480]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4448	41.5%	831.3 KB	47.1%
innd	2668	24.9%	433.3 KB	24.5%
nnrpd	1853	17.3%	209.5 KB	11.9%
innfeed	1739	16.2%	288.7 KB	16.4%
controlchan	13	0.1%	2.2 KB	0.1%
pgpverify	2	0.0%	0.3 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 7	10724	100.0%	1.7 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	22219	59.8%
Negative hits	10517	28.3%
Do not exist	4391	11.8%
Cache misses	5	0.0%
TOTAL: 4	37132	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.027	0.0%	24	0.000	1.125	5.000
article cancel	00:00:00.005	0.0%	24	0.000	0.208	1.000
article cleanup	00:00:00.100	0.0%	4404	0.000	0.023	0.150
article logging	00:00:00.328	0.0%	4406	0.000	0.074	0.286
article parse	00:00:00.787	0.0%	16186	0.000	0.049	0.143
article write	00:00:01.846	0.0%	3662	0.125	0.504	1.033
artlog/artcncl	00:00:00.000	0.0%	24	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.001	0.0%	24	0.000	0.042	1.000
data move	00:00:00.239	0.0%	47246	0.000	0.005	0.026
hisgrep/artcncl	00:00:00.002	0.0%	24	0.000	0.083	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	24	0.000	0.000	0.000
hisgrep/perl	00:00:00.001	0.0%	24	0.000	0.042	0.500
hishave/artcncl	00:00:00.000	0.0%	24	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	24	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.553	0.0%	37187	0.000	0.015	0.043
history sync	00:00:00.053	0.0%	371	0.000	0.143	1.500
history write	00:00:00.763	0.0%	4402	0.000	0.173	0.562
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:24.879	100.0%	48595	588.717	1778.473	5457.864
nntp read	00:00:01.254	0.0%	47925	0.000	0.026	0.050
overview write	00:00:01.033	0.0%	3662	0.067	0.282	1.300
perl filter	00:00:16.824	0.0%	4402	2.217	3.822	10.655
python filter	00:00:00.022	0.0%	4402	0.000	0.005	0.074
site send	00:00:01.370	0.0%	7422	0.000	0.185	0.487
TOTAL: 24:01:00.797	24:00:50.087	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.035	0.0%	3692	0.000	0.009	0.077
article prepare	00:00:00.010	0.0%	1932	0.000	0.005	0.250
article read	00:00:00.067	0.0%	339	0.000	0.198	7.000
backlog stats	00:00:00.240	0.0%	54956	0.000	0.004	0.022
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.717	0.0%	33004	0.000	0.022	0.059
data write	00:00:01.490	0.0%	27403	0.013	0.054	0.104
idle	23:50:33.020	100.0%	54956	626.268	1561.850	3683.959
status file	00:00:00.409	0.0%	199	0.000	2.055	8.000
TOTAL: 23:50:39.973	23:50:35.988	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:20:39.743	91.9%	1460	9.000	849.139	300056.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.091	0.0%	3240	0.000	0.028	0.364
TOTAL: 00:22:29.592	00:20:39.834	91.9%	-	-	-	-

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	2173	931	1242	0	42%	23:59:22
2	i2pn.org	1	4153	892	2940	321	21%	26:45:01
3	news.glou.org	2	3514	791	2567	156	22%	23:59:22
4	news.nntp4.net	2	4107	517	3357	233	12%	23:23:10
5	gegeweb-out.news.tnetconsulting.net	2	3274	254	2991	29	7%	21:06:54
6	usenet.pasdenom.info	2	1904	142	1756	6	7%	23:58:36
7	news.samoylyk.net	1	3556	101	3455	0	2%	23:59:54
8	nntp.terraraq.uk	2	3161	77	3062	22	2%	23:58:58
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		40	25842	3705	21370	767	14%	191:11:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.glou.org	5.6 MB	0.0 KB	602.6 KB	6.2 MB	90%	6.7 KB
2	feed-out.gegeweb.org	4.5 MB	0.0 KB	0.0 KB	4.5 MB	100%	4.9 KB
3	i2pn.org	3.4 MB	0.0 KB	1.0 MB	4.4 MB	76%	3.7 KB
4	news.nntp4.net	3.4 MB	55.4 KB	1.8 MB	5.3 MB	63%	7.2 KB
5	gegeweb-out.news.tnetconsulting.net	1.9 MB	0.0 KB	250.7 KB	2.2 MB	88%	7.9 KB
6	news.samoylyk.net	572.8 KB	0.0 KB	0.0 KB	572.8 KB	100%	5.7 KB
7	usenet.pasdenom.info	456.1 KB	0.0 KB	10.8 KB	467.0 KB	97%	3.2 KB
8	nntp.terraraq.uk	338.9 KB	0.0 KB	155.3 KB	494.2 KB	68%	5.0 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		20.1 MB	55.4 KB	3.8 MB	24.0 MB	83%	5.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 15 04:15:01 - 04:59:59	82	2.2%	0.03	584.2 KB	2.7%	0.22
Aug 15 05:00:00 - 05:59:59	81	2.2%	0.02	320.0 KB	1.5%	0.09
Aug 15 06:00:00 - 06:59:59	102	2.8%	0.03	732.6 KB	3.3%	0.20
Aug 15 07:00:00 - 07:59:59	92	2.5%	0.03	496.7 KB	2.3%	0.14
Aug 15 08:00:00 - 08:59:59	192	5.3%	0.05	1.1 MB	5.0%	0.30
Aug 15 09:00:00 - 09:59:59	120	3.3%	0.03	703.8 KB	3.2%	0.20
Aug 15 10:00:00 - 10:59:59	118	3.2%	0.03	1.5 MB	6.8%	0.42
Aug 15 11:00:00 - 11:59:59	108	3.0%	0.03	607.3 KB	2.8%	0.17
Aug 15 12:00:00 - 12:59:59	101	2.8%	0.03	1.0 MB	4.8%	0.29
Aug 15 13:00:00 - 13:59:59	126	3.4%	0.04	722.5 KB	3.3%	0.20
Aug 15 14:00:00 - 14:59:59	163	4.5%	0.05	1.5 MB	6.9%	0.42
Aug 15 15:00:00 - 15:59:59	145	4.0%	0.04	495.2 KB	2.3%	0.14
Aug 15 16:00:00 - 16:59:59	215	5.9%	0.06	1.1 MB	4.9%	0.30
Aug 15 17:00:00 - 17:59:59	205	5.6%	0.06	1.1 MB	5.3%	0.32
Aug 15 18:00:00 - 18:59:59	175	4.8%	0.05	656.4 KB	3.0%	0.18
Aug 15 19:00:00 - 19:59:59	192	5.3%	0.05	788.7 KB	3.6%	0.22
Aug 15 20:00:00 - 20:59:59	185	5.1%	0.05	990.7 KB	4.5%	0.28
Aug 15 21:00:00 - 21:59:59	195	5.3%	0.05	869.5 KB	4.0%	0.24
Aug 15 22:00:00 - 22:59:59	217	5.9%	0.06	839.8 KB	3.8%	0.23
Aug 15 23:00:00 - 23:59:59	210	5.7%	0.06	1.2 MB	5.4%	0.33
Aug 16 00:00:00 - 00:59:59	163	4.5%	0.05	1.1 MB	5.2%	0.32
Aug 16 01:00:00 - 01:59:59	165	4.5%	0.05	746.0 KB	3.4%	0.21
Aug 16 02:00:00 - 02:59:59	152	4.2%	0.04	1.8 MB	8.2%	0.50
Aug 16 03:00:00 - 03:59:59	123	3.4%	0.03	690.1 KB	3.1%	0.19
Aug 16 04:00:00 - 04:15:01	30	0.8%	0.03	172.3 KB	0.8%	0.19
TOTAL: 24:00:00	3657	100.0%	0.04	21.5 MB	100.0%	0.25

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	i2pn.org	297	1	0	296
2	news.nntp4.net	230	0	1	229
3	news.glou.org	156	0	0	156
4	gegeweb-out.news.tnetconsulting.net	35	2	0	33
5	nntp.terraraq.uk	17	0	0	17
6	news.samoylyk.net	4	0	1	3
7	usenet.pasdenom.info	2	0	0	2
8	feed-out.gegeweb.org	2	0	0	2
TOTAL: 8		743	3	2	738

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fr.petites-annonces.informatique.materiel	2
fr.education.entraide.physique-chimie	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	215
Bad path (news.giganews.com.POSTED)	156
EMP (phn nph)	138
EMP (phl)	58
Too many newsgroups	55
EMP (phn path)	54
EMP (phr nph)	50
Too many newsgroups (meow)	8
Cancels abusifs	1
HTML Multipart	1
TOTAL: 10	736

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nntp4.net	1
	nntp.terraraq.uk	1
	TOTAL: 2	2
TOTAL: 1		2

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nntp.terraraq.uk	2823	246	2270	25	0	8%	23:59:15
2	news.nntp4.net	3440	57	2960	0	0	1%	23:59:54
3	i2pn.org	4556	45	2746	5	22	0%	23:59:34
4	news.samoylyk.net	5159	45	3530	10	10	0%	23:59:56
5	news.tnetconsulting.net	5426	33	3608	10	19	0%	23:59:56
6	news.gegeweb.org	2898	10	2722	1	0	0%	23:59:51
7	news.aioe.org	0	0	0	0	2631	0%	23:59:55
8	news.pasdenom.info	3123	0	2998	1	0	0%	23:59:56
TOTAL: 8		27425	436	20834	52	2682	1%	191:58:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	nntp.terraraq.uk	1.3 MB	437.8 KB	1.7 MB	6.4 KB	23:59:15
2	news.nntp4.net	328.1 KB	0.0 KB	328.1 KB	5.8 KB	23:59:54
3	news.tnetconsulting.net	210.0 KB	105.1 KB	315.1 KB	7.3 KB	23:59:56
4	i2pn.org	169.8 KB	15.1 KB	184.9 KB	3.7 KB	23:59:34
5	news.samoylyk.net	159.1 KB	33.4 KB	192.6 KB	3.5 KB	23:59:56
6	news.gegeweb.org	73.9 KB	2.5 KB	76.4 KB	6.9 KB	23:59:51
7	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:55
8	news.pasdenom.info	0.0 KB	2.0 KB	2.0 KB	2.0 KB	23:59:56
TOTAL: 8		2.2 MB	595.9 KB	2.8 MB	5.8 KB	191:58:17

NNRP readership statistics:

System		Conn	Elapsed
1	blissful.monitoring.internet-measurement.com	1	00:00:01
2	beaming.monitoring.internet-measurement.com	1	00:00:00
3	composed.monitoring.internet-measurement.com	1	00:00:06
4	45.227.254.8	1	00:00:00
5	ns31410046.ip-145-239-154.eu	2	00:00:01
6	192.241.207.60	1	00:00:00
7	88.165.5.23	1	00:00:01
8	104.152.52.229	1	00:00:10
9	agreeable.monitoring.internet-measurement.com	1	00:00:00
10	reassuring.monitoring.internet-measurement.com	1	00:00:00
11	plentiful.monitoring.internet-measurement.com	1	00:00:06
12	mail.gegeweb.eu	288	00:01:35
13	skilful.monitoring.internet-measurement.com	1	00:00:06
14	115-13-212-87.ftth.glasoperator.nl	2	00:20:01
15	208.100.26.230	2	00:00:02
16	192.241.218.21	1	00:00:09
17	steadfast.monitoring.internet-measurement.com	1	00:00:00
18	194.165.16.73	1	00:00:00
19	104.152.52.216	1	00:00:00
20	great.monitoring.internet-measurement.com	1	00:00:00
21	fetching.monitoring.internet-measurement.com	1	00:00:01
TOTAL: 21		311	00:22:29

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.monitoring.internet-measurement.com	10	00:00:24
2	*.ip-145-239-154.eu	2	00:00:01
3	*.gegeweb.eu	288	00:01:35
4	*.ftth.glasoperator.nl	2	00:20:01
5	unresolved	9	00:00:26
TOTAL: 5		311	00:22:29

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
115-13-212-87.ftth.glasoperator.nl	0.035	0.035	0.000	00:20:01
mail.gegeweb.eu	4.469	3.614	1.475	00:01:35
104.152.52.229	0.019	0.005	0.000	00:00:10
192.241.218.21	0.028	0.024	0.000	00:00:09
skilful.monitoring.internet-measurement.com	0.047	0.013	0.000	00:00:06
composed.monitoring.internet-measurement.com	0.046	0.012	0.000	00:00:06
plentiful.monitoring.internet-measurement.com	0.055	0.013	0.000	00:00:06
208.100.26.230	0.036	0.036	0.000	00:00:02
blissful.monitoring.internet-measurement.com	0.020	0.024	0.000	00:00:01
fetching.monitoring.internet-measurement.com	0.019	0.010	0.000	00:00:01
88.165.5.23	0.014	0.009	0.000	00:00:01
ns31410046.ip-145-239-154.eu	0.040	0.022	0.000	00:00:01
192.241.207.60	0.029	0.022	0.000	00:00:00
194.165.16.73	0.016	0.023	0.000	00:00:00
45.227.254.8	0.024	0.004	0.000	00:00:00
agreeable.monitoring.internet-measurement.com	0.020	0.008	0.000	00:00:00
beaming.monitoring.internet-measurement.com	0.039	0.023	0.000	00:00:00
104.152.52.216	0.009	0.013	0.000	00:00:00
great.monitoring.internet-measurement.com	0.047	0.009	0.000	00:00:00
reassuring.monitoring.internet-measurement.com	0.024	0.005	0.000	00:00:00
TOTAL: 21	5.070	3.950	1.475	00:22:29

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
composed.monitoring.internet-measurement.com	6
ns31410046.ip-145-239-154.eu	1
194.165.16.73	1
192.241.207.60	1
TOTAL: 4	9

NNRP unrecognized commands (by command):

Command	Count
MGLNDD_45.13.105.36_119	1
Connection: close	1
GET / HTTP/1.1	1
#026#003	1
Accept: /	1
User-Agent: Mozilla/5.0 (compatible; Int...	1
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
#003	1
Accept-Encoding: gzip	1
TOTAL: 9	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
194.165.16.73	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Aug 15 04:15:01 -- Aug 16 04:15:01