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

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jun 27 11:41:58 rpi-studio innd: filter: No Cancel-Key[sha1:SPP1sSY/8tuzTApDEgfslATu7Ts=] matches Cancel-Lock[sha1:7nOtAsmOPZM7HcO0HrwR8WDmqqQ=] target=<667d32b7$0$3272$426a74cc@news.free.fr>

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2389	29.4%	445.1 KB	34.0%
innd	2196	27.1%	380.0 KB	29.0%
nnrpd	1796	22.1%	190.4 KB	14.5%
innfeed	1732	21.3%	295.0 KB	22.5%
controlchan	1	0.0%	0.2 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8115	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	13103	58.9%
Negative hits	6780	30.5%
Do not exist	2371	10.7%
Cache misses	3	0.0%
TOTAL: 4	22257	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.023	0.0%	8	1.000	2.875	6.000
article cancel	00:00:00.002	0.0%	8	0.000	0.250	1.000
article cleanup	00:00:00.060	0.0%	2376	0.000	0.025	0.250
article logging	00:00:00.155	0.0%	2376	0.000	0.065	0.500
article parse	00:00:00.277	0.0%	5799	0.000	0.048	0.185
article write	00:00:01.116	0.0%	2103	0.083	0.531	1.765
artlog/artcncl	00:00:00.001	0.0%	8	0.000	0.125	1.000
artlog/artcncl/perl	00:00:00.002	0.0%	8	0.000	0.250	1.000
data move	00:00:00.127	0.0%	23284	0.000	0.005	0.033
hisgrep/artcncl	00:00:00.000	0.0%	8	0.000	0.000	0.000
hisgrep/artcncl/perl	00:00:00.002	0.0%	8	0.000	0.250	1.000
hisgrep/perl	00:00:00.004	0.0%	25	0.000	0.160	1.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%	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.317	0.0%	22284	0.000	0.014	0.051
history sync	00:00:00.050	0.0%	351	0.000	0.142	1.000
history write	00:00:00.370	0.0%	2375	0.000	0.156	0.533
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:37.702	100.0%	26091	1372.991	3312.932	19371.742
nntp read	00:00:00.645	0.0%	25416	0.000	0.025	0.080
overview write	00:00:00.963	0.0%	2103	0.000	0.458	3.000
perl filter	00:00:10.616	0.0%	2375	2.571	4.470	12.250
python filter	00:00:00.013	0.0%	2375	0.000	0.005	0.080
site send	00:00:00.828	0.0%	4289	0.000	0.193	0.500
TOTAL: 24:01:00.687	24:00:53.273	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.012	0.0%	2091	0.000	0.006	0.111
article prepare	00:00:00.001	0.0%	748	0.000	0.001	0.250
article read	00:00:00.029	0.0%	180	0.000	0.161	3.000
backlog stats	00:00:00.149	0.0%	32424	0.000	0.005	0.031
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.417	0.0%	17217	0.000	0.024	0.069
data write	00:00:00.829	0.0%	15228	0.000	0.054	0.132
idle	23:50:24.475	100.0%	32424	995.532	2646.943	8024.733
status file	00:00:01.025	0.0%	202	1.000	5.074	40.000
TOTAL: 23:50:31.792	23:50:26.937	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:31.462	25.3%	1447	19.600	21.743	1875.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.095	0.1%	3209	0.000	0.030	0.500
TOTAL: 00:02:04.436	00:00:31.557	25.4%	-	-	-	-

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	3	2073	896	1177	0	43%	23:56:29
2	news.nntp4.net	2	2409	323	2024	62	13%	24:50:08
3	gegeweb-out.news.tnetconsulting.net	2	2157	307	1793	57	14%	26:21:56
4	news.samoylyk.net	2	4039	282	3727	30	6%	38:36:21
5	usenet.pasdenom.info	2	1492	114	1353	25	7%	24:39:16
6	news.glou.org	2	2194	107	1973	114	4%	26:26:04
7	i2pn.org	1	455	53	401	1	11%	05:19:55
8	nntp.terraraq.uk	2	1905	47	1854	4	2%	22:31:15
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		41	16724	2129	14302	293	12%	192:41:24

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.1 MB	0.0 KB	3.1 MB	100%	3.6 KB
2	news.samoylyk.net	1.9 MB	91.6 KB	2.0 MB	95%	6.4 KB
3	news.nntp4.net	1.2 MB	178.6 KB	1.4 MB	87%	3.8 KB
4	gegeweb-out.news.tnetconsulting.net	1003.8 KB	214.8 KB	1.2 MB	82%	3.3 KB
5	news.glou.org	395.2 KB	298.2 KB	693.4 KB	56%	3.1 KB
6	usenet.pasdenom.info	251.8 KB	26.2 KB	278.1 KB	90%	2.0 KB
7	nntp.terraraq.uk	221.9 KB	12.4 KB	234.3 KB	94%	4.6 KB
8	i2pn.org	212.2 KB	8.6 KB	220.8 KB	96%	4.1 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		8.3 MB	830.4 KB	9.1 MB	91%	3.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 27 04:15:01 - 04:59:59	42	2.0%	0.02	221.8 KB	2.9%	0.08
Jun 27 05:00:00 - 05:59:59	59	2.8%	0.02	249.5 KB	3.2%	0.07
Jun 27 06:00:00 - 06:59:59	48	2.3%	0.01	145.2 KB	1.9%	0.04
Jun 27 07:00:00 - 07:59:59	47	2.2%	0.01	195.7 KB	2.5%	0.05
Jun 27 08:00:00 - 08:59:59	53	2.5%	0.01	159.7 KB	2.1%	0.04
Jun 27 09:00:00 - 09:59:59	82	3.9%	0.02	373.2 KB	4.8%	0.10
Jun 27 10:00:00 - 10:59:59	69	3.3%	0.02	230.0 KB	3.0%	0.06
Jun 27 11:00:00 - 11:59:59	64	3.0%	0.02	162.5 KB	2.1%	0.05
Jun 27 12:00:00 - 12:59:59	93	4.4%	0.03	300.0 KB	3.9%	0.08
Jun 27 13:00:00 - 13:59:59	108	5.1%	0.03	310.7 KB	4.0%	0.09
Jun 27 14:00:00 - 14:59:59	108	5.1%	0.03	431.0 KB	5.6%	0.12
Jun 27 15:00:00 - 15:59:59	107	5.1%	0.03	363.1 KB	4.7%	0.10
Jun 27 16:00:00 - 16:59:59	115	5.5%	0.03	411.3 KB	5.3%	0.11
Jun 27 17:00:00 - 17:59:59	87	4.1%	0.02	296.0 KB	3.8%	0.08
Jun 27 18:00:00 - 18:59:59	90	4.3%	0.03	313.6 KB	4.0%	0.09
Jun 27 19:00:00 - 19:59:59	119	5.7%	0.03	455.9 KB	5.9%	0.13
Jun 27 20:00:00 - 20:59:59	122	5.8%	0.03	400.7 KB	5.2%	0.11
Jun 27 21:00:00 - 21:59:59	114	5.4%	0.03	353.8 KB	4.6%	0.10
Jun 27 22:00:00 - 22:59:59	93	4.4%	0.03	498.8 KB	6.4%	0.14
Jun 27 23:00:00 - 23:59:59	122	5.8%	0.03	418.4 KB	5.4%	0.12
Jun 28 00:00:00 - 00:59:59	110	5.2%	0.03	501.7 KB	6.5%	0.14
Jun 28 01:00:00 - 01:59:59	84	4.0%	0.02	443.6 KB	5.7%	0.12
Jun 28 02:00:00 - 02:59:59	78	3.7%	0.02	229.8 KB	3.0%	0.06
Jun 28 03:00:00 - 03:59:59	71	3.4%	0.02	229.2 KB	3.0%	0.06
Jun 28 04:00:00 - 04:15:01	16	0.8%	0.02	53.3 KB	0.7%	0.06
TOTAL: 24:00:00	2101	100.0%	0.02	7.6 MB	100.0%	0.09

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	news.glou.org	94	0	0	94
2	news.nntp4.net	56	0	0	56
3	gegeweb-out.news.tnetconsulting.net	51	0	0	51
4	usenet.pasdenom.info	34	0	0	34
5	news.samoylyk.net	24	0	0	24
6	i2pn.org	10	0	0	10
7	nntp.terraraq.uk	3	1	1	1
TOTAL: 7		272	1	1	270

Unwanted newsgroups [Top 20]:

Newsgroup	Count
comp.lang.go	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	163
Bad path (news.giganews.com.POSTED)	52
Too many newsgroups	21
EMP (md5)	13
HTML Multipart	3
Cancel of <v5jjrq$1978p$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5jr7s$19iks$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5jh45$1936j$1@i2pn2.org> without Cancel-Key	1
Cancels abusifs	1
Cancel of <v5klac$1aks5$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5jhbo$193mr$1@i2pn2.org> without Cancel-Key	1
No Cancel-Key matches Cancel-Lock. target=<667d32b7$0$3272$426a74cc@news.free.fr>	1
Cancel of <v5k058$19ptr$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5kjmm$1aisb$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5kioa$1ahpb$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5kkbd$1ajm4$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5jq7g$19gpd$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5je7d$18v15$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5jiuk$19600$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5jjdj$196lg$1@i2pn2.org> without Cancel-Key	1
TOTAL: 23	270

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	i2pn.org	3055	98	1796	11	0	3%	23:59:57
2	news.gegeweb.org	1296	63	1170	0	0	4%	23:55:06
3	news.tnetconsulting.net	3449	10	2086	4	0	0%	23:59:55
4	news.aioe.org	0	0	0	0	1716	0%	24:00:00
5	news.pasdenom.info	1930	0	1723	1	0	0%	23:59:57
6	nntp.terraraq.uk	1487	0	1365	0	0	0%	23:59:42
7	news.nntp4.net	1892	0	1809	0	0	0%	23:59:42
8	news.samoylyk.net	2188	0	1849	0	0	0%	23:59:57
TOTAL: 8		15297	171	11798	16	1716	1%	191:54:16

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	i2pn.org	343.8 KB	45.2 KB	389.0 KB	3.6 KB	23:59:57
2	news.gegeweb.org	218.7 KB	0.0 KB	218.7 KB	3.5 KB	23:55:06
3	news.tnetconsulting.net	17.8 KB	11.1 KB	28.9 KB	2.1 KB	23:59:55
4	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	24:00:00
5	news.pasdenom.info	0.0 KB	3.9 KB	3.9 KB	3.9 KB	23:59:57
6	nntp.terraraq.uk	0.0 KB	0.0 KB	0.0 KB	0	23:59:42
7	news.nntp4.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:42
8	news.samoylyk.net	0.0 KB	0.0 KB	0.0 KB	0	23:59:57
TOTAL: 8		580.3 KB	60.2 KB	640.4 KB	3.4 KB	191:54:16

NNRP readership statistics:

System		Conn	Elapsed
1	suave.monitoring.internet-measurement.com	1	00:00:06
2	spirited.monitoring.internet-measurement.com	1	00:00:00
3	sensitive.monitoring.internet-measurement.com	1	00:00:00
4	azpdcgb53.stretchoid.com	1	00:00:00
5	45.227.254.8	1	00:00:05
6	worthy.monitoring.internet-measurement.com	1	00:00:00
7	mail.gegeweb.eu	288	00:01:45
8	miraculous.monitoring.internet-measurement.com	1	00:00:00
9	90.211.203.35.bc.googleusercontent.com	1	00:00:00
10	147.210.203.35.bc.googleusercontent.com	1	00:00:00
11	splendid.monitoring.internet-measurement.com	1	00:00:02
12	divine.monitoring.internet-measurement.com	1	00:00:00
13	azpdegc32.stretchoid.com	1	00:00:01
TOTAL: 13		300	00:02:04

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.211.203.35.bc.googleusercontent.com	1	00:00:00
2	*.210.203.35.bc.googleusercontent.com	1	00:00:00
3	*.monitoring.internet-measurement.com	7	00:00:09
4	unresolved	1	00:00:05
5	*.stretchoid.com	2	00:00:01
6	*.gegeweb.eu	288	00:01:45
TOTAL: 6		300	00:02:04

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
mail.gegeweb.eu	4.288	4.229	1.463	00:01:45
suave.monitoring.internet-measurement.com	0.072	0.017	0.000	00:00:06
45.227.254.8	0.030	0.013	0.000	00:00:05
splendid.monitoring.internet-measurement.com	0.029	0.017	0.000	00:00:02
azpdegc32.stretchoid.com	0.016	0.008	0.000	00:00:01
azpdcgb53.stretchoid.com	0.029	0.025	0.000	00:00:00
90.211.203.35.bc.googleusercontent.com	0.066	0.023	0.000	00:00:00
147.210.203.35.bc.googleusercontent.com	0.029	0.011	0.000	00:00:00
sensitive.monitoring.internet-measurement.com	0.044	0.020	0.000	00:00:00
worthy.monitoring.internet-measurement.com	0.044	0.020	0.000	00:00:00
miraculous.monitoring.internet-measurement.com	0.040	0.024	0.000	00:00:00
TOTAL: 11	4.687	4.407	1.463	00:02:04

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
147.210.203.35.bc.googleusercontent.com	4
90.211.203.35.bc.googleusercontent.com	4
azpdcgb53.stretchoid.com	1
azpdegc32.stretchoid.com	1
45.227.254.8	1
TOTAL: 5	11

NNRP unrecognized commands (by command):

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
45.227.254.8	1	1
TOTAL: 1	1	1

Daily Usenet report for news.niel.me

Jun 27 04:15:01 -- Jun 28 04:15:01