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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 18 23:10:16 rpi-studio innd: filter: No Cancel-Key[sha1:S1sUWuFGYiX5v3MVVFvDwkhIR2Y= sha256:nORMGTMvYNq/h/HbhdtttBBxxxH03TSYkIMp1Pj11IM=] matches Cancel-Lock[sha1:RQfki6Nze4//DDimpLSZPr+LcC0= sha256:P/tikZ4txx3qJ76Quu23vMaqaxhDDlGiS9n50QgQnX4=] target=<v4sdst$rv7$1@rasp.pasdenom.info>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2704	30.7%	505.0 KB	35.7%
innd	2233	25.4%	375.6 KB	26.5%
nnrpd	2113	24.0%	234.3 KB	16.5%
innfeed	1751	19.9%	301.2 KB	21.3%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.2 KB	0.0%
TOTAL: 6	8803	100.0%	1.4 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	15003	58.7%
Negative hits	7865	30.8%
Do not exist	2684	10.5%
Cache misses	1	0.0%
TOTAL: 4	25553	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.007	0.0%	7	0.000	1.000	2.000
article cancel	00:00:00.000	0.0%	7	0.000	0.000	0.000
article cleanup	00:00:00.065	0.0%	2691	0.000	0.024	0.188
article logging	00:00:00.183	0.0%	2691	0.000	0.068	0.267
article parse	00:00:00.353	0.0%	6936	0.000	0.051	0.500
article write	00:00:01.252	0.0%	2414	0.000	0.519	1.231
artlog/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
artlog/artcncl/perl	00:00:00.000	0.0%	7	0.000	0.000	0.000
data move	00:00:00.156	0.0%	26861	0.000	0.006	0.025
hisgrep/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.001	0.0%	7	0.000	0.143	1.000
hisgrep/perl	00:00:00.002	0.0%	24	0.000	0.083	0.500
hishave/artcncl	00:00:00.000	0.0%	7	0.000	0.000	0.000
hishave/artcncl/perl	00:00:00.000	0.0%	7	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.401	0.0%	25574	0.000	0.016	0.054
history sync	00:00:00.051	0.0%	342	0.000	0.149	1.000
history write	00:00:00.436	0.0%	2688	0.000	0.162	0.667
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:36.314	100.0%	29824	1112.072	2898.213	26120.087
nntp read	00:00:00.761	0.0%	29003	0.000	0.026	0.111
overview write	00:00:00.755	0.0%	2414	0.000	0.313	3.000
perl filter	00:00:11.875	0.0%	2688	2.444	4.418	15.000
python filter	00:00:00.013	0.0%	2688	0.000	0.005	0.182
site send	00:00:01.070	0.0%	4939	0.000	0.217	0.714
TOTAL: 24:01:03.262	24:00:53.695	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.014	0.0%	2462	0.000	0.006	0.111
article prepare	00:00:00.003	0.0%	908	0.000	0.003	0.250
article read	00:00:00.046	0.0%	222	0.000	0.207	1.500
backlog stats	00:00:00.194	0.0%	35552	0.000	0.005	0.017
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.507	0.0%	19329	0.000	0.026	0.078
data write	00:00:00.969	0.0%	17250	0.000	0.056	0.143
idle	23:58:39.791	100.0%	35552	1054.445	2427.987	10381.155
status file	00:00:00.974	0.0%	211	0.000	4.616	19.000
TOTAL: 23:58:50.490	23:58:42.498	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:58.594	92.5%	1539	19.800	1207.663	900023.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.132	0.0%	3531	0.000	0.037	1.000
TOTAL: 00:33:29.475	00:30:58.726	92.5%	-	-	-	-

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	feed-out.gegeweb.org	5	2311	1006	1304	1	43%	26:48:04
2	news.nntp4.net	2	3659	541	3060	58	14%	33:45:18
3	gegeweb-out.news.tnetconsulting.net	1	1818	266	1518	34	14%	21:44:02
4	i2pn.org	1	2101	214	1887	0	10%	20:37:28
5	news.glou.org	1	3654	163	3290	201	4%	43:43:19
6	news.samoylyk.net	2	2475	122	2323	30	4%	23:05:17
7	usenet.pasdenom.info	3	1000	117	870	13	11%	16:58:09
8	nntp.terraraq.uk	3	112	3	109	0	2%	03:20:35
9	localhost	25	0	0	0	0	0%	00:00:01
TOTAL: 9		43	17130	2432	14361	337	14%	190:02:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.6 MB	2.5 KB	2.5 KB	3.6 MB	99%	3.7 KB
2	news.nntp4.net	2.9 MB	0.0 KB	274.5 KB	3.1 MB	91%	5.4 KB
3	gegeweb-out.news.tnetconsulting.net	1006.5 KB	4.9 KB	125.0 KB	1.1 MB	88%	3.8 KB
4	news.samoylyk.net	801.7 KB	0.0 KB	92.4 KB	894.2 KB	89%	5.9 KB
5	i2pn.org	657.1 KB	0.0 KB	0.0 KB	657.1 KB	100%	3.1 KB
6	news.glou.org	521.8 KB	12.4 KB	623.0 KB	1.1 MB	45%	3.2 KB
7	usenet.pasdenom.info	298.4 KB	0.0 KB	16.0 KB	314.4 KB	94%	2.4 KB
8	nntp.terraraq.uk	13.8 KB	0.0 KB	0.0 KB	13.8 KB	100%	4.6 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		9.7 MB	19.8 KB	1.1 MB	10.8 MB	89%	4.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 18 04:15:01 - 04:59:59	49	2.0%	0.02	280.8 KB	3.0%	0.10
Jun 18 05:00:00 - 05:59:59	45	1.9%	0.01	169.2 KB	1.8%	0.05
Jun 18 06:00:00 - 06:59:59	30	1.2%	0.01	97.3 KB	1.0%	0.03
Jun 18 07:00:00 - 07:59:59	52	2.2%	0.01	204.9 KB	2.2%	0.06
Jun 18 08:00:00 - 08:59:59	51	2.1%	0.01	147.1 KB	1.6%	0.04
Jun 18 09:00:00 - 09:59:59	129	5.3%	0.04	783.8 KB	8.3%	0.22
Jun 18 10:00:00 - 10:59:59	112	4.6%	0.03	384.6 KB	4.1%	0.11
Jun 18 11:00:00 - 11:59:59	103	4.3%	0.03	471.7 KB	5.0%	0.13
Jun 18 12:00:00 - 12:59:59	102	4.2%	0.03	426.2 KB	4.5%	0.12
Jun 18 13:00:00 - 13:59:59	101	4.2%	0.03	310.7 KB	3.3%	0.09
Jun 18 14:00:00 - 14:59:59	105	4.4%	0.03	362.6 KB	3.9%	0.10
Jun 18 15:00:00 - 15:59:59	161	6.7%	0.04	499.0 KB	5.3%	0.14
Jun 18 16:00:00 - 16:59:59	93	3.9%	0.03	351.3 KB	3.7%	0.10
Jun 18 17:00:00 - 17:59:59	116	4.8%	0.03	366.9 KB	3.9%	0.10
Jun 18 18:00:00 - 18:59:59	170	7.0%	0.05	528.4 KB	5.6%	0.15
Jun 18 19:00:00 - 19:59:59	159	6.6%	0.04	500.4 KB	5.3%	0.14
Jun 18 20:00:00 - 20:59:59	131	5.4%	0.04	474.8 KB	5.1%	0.13
Jun 18 21:00:00 - 21:59:59	113	4.7%	0.03	488.7 KB	5.2%	0.14
Jun 18 22:00:00 - 22:59:59	145	6.0%	0.04	1000.2 KB	10.7%	0.28
Jun 18 23:00:00 - 23:59:59	153	6.3%	0.04	478.3 KB	5.1%	0.13
Jun 19 00:00:00 - 00:59:59	91	3.8%	0.03	420.9 KB	4.5%	0.12
Jun 19 01:00:00 - 01:59:59	61	2.5%	0.02	186.4 KB	2.0%	0.05
Jun 19 02:00:00 - 02:59:59	69	2.9%	0.02	235.6 KB	2.5%	0.07
Jun 19 03:00:00 - 03:59:59	64	2.7%	0.02	202.0 KB	2.2%	0.06
Jun 19 04:00:00 - 04:15:01	8	0.3%	0.01	14.7 KB	0.2%	0.02
TOTAL: 24:00:00	2413	100.0%	0.03	9.2 MB	100.0%	0.11

Sites sending bad articles:

Server		Total	Duplic	Other
1	news.glou.org	126	0	126
2	gegeweb-out.news.tnetconsulting.net	62	2	60
3	news.nntp4.net	39	0	39
4	news.samoylyk.net	30	0	30
5	usenet.pasdenom.info	18	0	18
6	nntp.terraraq.uk	1	0	1
7	feed-out.gegeweb.org	1	1	0
TOTAL: 7		277	3	274

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	166
Bad path (news.giganews.com.POSTED)	75
Too many newsgroups	14
Cancel of <v4t2rv$d5m6$1@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <6671bec9$0$2581$426a74cc@news.free.fr>	1
Cancel of <v4t294$d50c$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4sphr$cqnc$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4rq2r$biik$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4r4ji$anj1$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4sqov$cs7l$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4sj48$cipl$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4sdk0$cbs2$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4t1u1$d4kb$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4s7hp$c3ji$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4smfm$cn3g$1@i2pn2.org> without Cancel-Key	1
HTML Multipart	1
Cancel of <v4sm53$cmke$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4spa7$cqfu$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4smj0$cn5i$1@i2pn2.org> without Cancel-Key	1
Cancel of <v4s7nk$c43t$1@i2pn2.org> without Cancel-Key	1
TOTAL: 22	274

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3361	116	2037	8	26	3%	23:59:56
2	news.gegeweb.org	1418	73	1310	0	0	5%	23:55:36
3	news.tnetconsulting.net	3976	24	2385	3	20	0%	23:59:10
4	news.samoylyk.net	2776	2	2273	0	3	0%	23:59:56
5	news.pasdenom.info	2201	1	1937	0	0	0%	23:59:27
6	news.aioe.org	0	0	0	0	1974	0%	24:00:00
7	nntp.terraraq.uk	1594	0	1427	0	0	0%	23:51:58
8	news.nntp4.net	2072	0	2004	0	0	0%	23:59:56
TOTAL: 8		17398	216	13373	11	2023	1%	191:45:59

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	431.7 KB	68.3 KB	500.0 KB	4.0 KB	23:59:56
2	news.gegeweb.org	203.3 KB	0.0 KB	203.3 KB	2.8 KB	23:55:36
3	news.tnetconsulting.net	64.0 KB	20.7 KB	84.7 KB	3.1 KB	23:59:10
4	news.samoylyk.net	4.2 KB	0.0 KB	4.2 KB	2.1 KB	23:59:56
5	news.pasdenom.info	2.5 KB	0.0 KB	2.5 KB	2.5 KB	23:59:27
6	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
7	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:51:58
8	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:56
TOTAL: 8		705.8 KB	89.0 KB	794.8 KB	3.5 KB	191:45:59

NNRP readership statistics:

System		Conn	Elapsed
1	pleasant.monitoring.internet-measurement.com	1	00:00:00
2	172-105-246-139.ip.linodeusercontent.com	98	00:00:28
3	calm.monitoring.internet-measurement.com	1	00:00:00
4	mail.gegeweb.eu	287	00:02:18
5	147.185.132.244	1	00:00:00
6	sagacious.monitoring.internet-measurement.com	1	00:00:05
7	beautiful.monitoring.internet-measurement.com	1	00:00:00
8	transformative.monitoring.internet-measurement.com	1	00:00:00
9	intelligent.monitoring.internet-measurement.com	1	00:00:06
10	successful.monitoring.internet-measurement.com	1	00:00:00
11	sh-ams-nl-gp1-wk111c.internet-census.org	1	00:00:10
12	staunch.monitoring.internet-measurement.com	1	00:00:00
13	azpdcsc33.stretchoid.com	1	00:00:09
14	60.190.226.189	1	00:30:00
15	hopeful.monitoring.internet-measurement.com	1	00:00:05
16	232.150.216.162.bc.googleusercontent.com	1	00:00:00
17	natural.monitoring.internet-measurement.com	1	00:00:00
TOTAL: 17		400	00:33:28

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.ip.linodeusercontent.com	98	00:00:28
2	*.stretchoid.com	1	00:00:09
3	*.monitoring.internet-measurement.com	10	00:00:18
4	*.internet-census.org	1	00:00:10
5	unresolved	2	00:30:01
6	*.gegeweb.eu	287	00:02:18
7	*.150.216.162.bc.googleusercontent.com	1	00:00:00
TOTAL: 7		400	00:33:28

NNRP auth users [Top 20]:

User	Conn
mailman	287
TOTAL: 1	287

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
60.190.226.189	0.008	0.016	0.000	00:30:00
mail.gegeweb.eu	4.266	4.218	1.515	00:02:18
172-105-246-139.ip.linodeusercontent.com	2.429	1.446	0.000	00:00:28
sh-ams-nl-gp1-wk111c.internet-census.org	0.008	0.017	0.000	00:00:10
azpdcsc33.stretchoid.com	0.029	0.024	0.000	00:00:09
intelligent.monitoring.internet-measurement.com	0.035	0.023	0.000	00:00:06
sagacious.monitoring.internet-measurement.com	0.037	0.025	0.000	00:00:05
hopeful.monitoring.internet-measurement.com	0.058	0.027	0.000	00:00:05
232.150.216.162.bc.googleusercontent.com	0.061	0.013	0.000	00:00:00
147.185.132.244	0.027	0.013	0.000	00:00:00
transformative.monitoring.internet-measurement.com	0.047	0.016	0.000	00:00:00
natural.monitoring.internet-measurement.com	0.037	0.030	0.000	00:00:00
staunch.monitoring.internet-measurement.com	0.038	0.021	0.000	00:00:00
pleasant.monitoring.internet-measurement.com	0.036	0.016	0.000	00:00:00
calm.monitoring.internet-measurement.com	0.024	0.005	0.000	00:00:00
successful.monitoring.internet-measurement.com	0.025	0.004	0.000	00:00:00
TOTAL: 16	7.165	5.914	1.515	00:33:28

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
intelligent.monitoring.internet-measurement.com	6
232.150.216.162.bc.googleusercontent.com	3
147.185.132.244	3
60.190.226.189	1
TOTAL: 4	13

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	3
User-Agent: Expanse, a Palo Alto Network...	2
Host: 45.13.105.36:563	2
Host: [2a0e:e701:1154:0:4e10:5c89:4af0:d...	1
GET / HTTP/1.0	1
Connection: close	1
Accept: /	1
User-Agent: Mozilla/5.0 (compatible; Int...	1
Accept-Encoding: gzip	1
TOTAL: 9	13

NNRP client timeouts [Top 20]:

System	Conn	Peer
60.190.226.189	1	0
TOTAL: 1	1	0

Daily Usenet report for news.niel.me

Jun 18 04:15:01 -- Jun 19 04:15:01