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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2540	30.4%	473.1 KB	35.1%
innd	2238	26.8%	377.6 KB	28.0%
nnrpd	1804	21.6%	212.5 KB	15.8%
innfeed	1762	21.1%	285.1 KB	21.1%
controlchan	1	0.0%	0.0 KB	0.0%
nocem	1	0.0%	0.1 KB	0.0%
TOTAL: 6	8346	100.0%	1.3 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	13788	58.8%
Negative hits	7127	30.4%
Do not exist	2518	10.7%
Cache misses	1	0.0%
TOTAL: 4	23434	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
artcncl/perl	00:00:00.008	0.0%	6	1.000	1.333	3.000
article cancel	00:00:00.002	0.0%	6	0.000	0.333	1.000
article cleanup	00:00:00.058	0.0%	2523	0.000	0.023	0.200
article logging	00:00:00.175	0.0%	2523	0.000	0.069	0.429
article parse	00:00:00.300	0.0%	6887	0.000	0.044	0.222
article write	00:00:01.046	0.0%	2223	0.000	0.471	1.300
artlog/artcncl	00:00:00.000	0.0%	6	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.123	0.0%	25320	0.000	0.005	0.031
hisgrep/artcncl	00:00:00.001	0.0%	6	0.000	0.167	1.000
hisgrep/artcncl/perl	00:00:00.000	0.0%	6	0.000	0.000	0.000
hisgrep/perl	00:00:00.002	0.0%	25	0.000	0.080	1.000
hishave/artcncl	00:00:00.000	0.0%	6	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.382	0.0%	23448	0.000	0.016	0.067
history sync	00:00:00.041	0.0%	366	0.000	0.112	1.000
history write	00:00:00.431	0.0%	2523	0.000	0.171	0.625
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	24:00:38.575	100.0%	28134	1122.198	3072.388	27289.773
nntp read	00:00:00.727	0.0%	27403	0.000	0.027	0.079
overview write	00:00:00.755	0.0%	2223	0.000	0.340	2.444
perl filter	00:00:10.498	0.0%	2523	2.654	4.161	8.000
python filter	00:00:00.014	0.0%	2523	0.000	0.006	0.091
site send	00:00:00.993	0.0%	4573	0.000	0.217	0.536
TOTAL: 24:01:01.681	24:00:54.131	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.021	0.0%	2242	0.000	0.009	0.091
article prepare	00:00:00.003	0.0%	712	0.000	0.004	0.250
article read	00:00:00.036	0.0%	170	0.000	0.212	8.000
backlog stats	00:00:00.183	0.0%	33929	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.428	0.0%	18150	0.000	0.024	0.058
data write	00:00:00.874	0.0%	16128	0.000	0.054	0.113
idle	23:59:50.409	100.0%	33929	842.916	2546.211	6683.689
status file	00:00:00.423	0.0%	216	0.750	1.958	7.000
TOTAL: 23:59:57.481	23:59:52.377	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:31.060	19.0%	1450	20.600	21.421	112.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.098	0.1%	3220	0.000	0.030	0.364
TOTAL: 00:02:43.801	00:00:31.158	19.0%	-	-	-	-

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	2136	1091	1045	0	51%	23:57:36
2	news.nntp4.net	2	2127	318	1722	87	14%	21:48:25
3	gegeweb-out.news.tnetconsulting.net	1	1616	227	1340	49	14%	19:23:31
4	usenet.pasdenom.info	5	2203	155	2026	22	7%	34:06:51
5	news.samoylyk.net	2	2263	77	2186	0	3%	23:23:15
6	news.glou.org	2	2013	69	1812	132	3%	23:59:58
7	nntp.terraraq.uk	2	2069	61	2007	1	2%	23:57:49
8	i2pn.org	2	218	20	197	1	9%	03:35:00
9	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 9		44	14645	2018	12335	292	13%	174:12:25

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed-out.gegeweb.org	3.9 MB	0.0 KB	0.0 KB	3.9 MB	100%	3.7 KB
2	news.nntp4.net	1.3 MB	0.0 KB	1.0 MB	2.3 MB	55%	5.8 KB
3	news.samoylyk.net	971.5 KB	0.0 KB	0.0 KB	971.5 KB	100%	12.6 KB
4	gegeweb-out.news.tnetconsulting.net	707.5 KB	0.0 KB	173.5 KB	881.0 KB	80%	3.2 KB
5	usenet.pasdenom.info	365.3 KB	0.0 KB	35.2 KB	400.5 KB	91%	2.3 KB
6	news.glou.org	217.1 KB	0.0 KB	416.0 KB	633.1 KB	34%	3.1 KB
7	nntp.terraraq.uk	179.3 KB	1.8 KB	1.8 KB	183.0 KB	97%	3.0 KB
8	i2pn.org	68.2 KB	0.0 KB	4.1 KB	72.3 KB	94%	3.4 KB
9	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 9		7.7 MB	1.8 KB	1.6 MB	9.3 MB	82%	4.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 25 04:15:02 - 04:59:59	41	1.8%	0.02	126.6 KB	1.4%	0.05
Jun 25 05:00:00 - 05:59:59	64	2.9%	0.02	244.9 KB	2.8%	0.07
Jun 25 06:00:00 - 06:59:59	38	1.7%	0.01	230.1 KB	2.6%	0.06
Jun 25 07:00:00 - 07:59:59	35	1.6%	0.01	117.7 KB	1.3%	0.03
Jun 25 08:00:00 - 08:59:59	72	3.2%	0.02	300.5 KB	3.4%	0.08
Jun 25 09:00:00 - 09:59:59	70	3.1%	0.02	226.2 KB	2.6%	0.06
Jun 25 10:00:00 - 10:59:59	65	2.9%	0.02	171.7 KB	2.0%	0.05
Jun 25 11:00:00 - 11:59:59	105	4.7%	0.03	388.9 KB	4.4%	0.11
Jun 25 12:00:00 - 12:59:59	100	4.5%	0.03	331.4 KB	3.8%	0.09
Jun 25 13:00:00 - 13:59:59	98	4.4%	0.03	266.6 KB	3.0%	0.07
Jun 25 14:00:00 - 14:59:59	93	4.2%	0.03	301.1 KB	3.4%	0.08
Jun 25 15:00:00 - 15:59:59	116	5.2%	0.03	372.9 KB	4.3%	0.10
Jun 25 16:00:00 - 16:59:59	86	3.9%	0.02	560.6 KB	6.4%	0.16
Jun 25 17:00:00 - 17:59:59	138	6.2%	0.04	807.4 KB	9.2%	0.22
Jun 25 18:00:00 - 18:59:59	130	5.8%	0.04	563.4 KB	6.4%	0.16
Jun 25 19:00:00 - 19:59:59	190	8.5%	0.05	685.9 KB	7.8%	0.19
Jun 25 20:00:00 - 20:59:59	136	6.1%	0.04	431.5 KB	4.9%	0.12
Jun 25 21:00:00 - 21:59:59	124	5.6%	0.03	579.4 KB	6.6%	0.16
Jun 25 22:00:00 - 22:59:59	99	4.4%	0.03	322.5 KB	3.7%	0.09
Jun 25 23:00:00 - 23:59:59	110	4.9%	0.03	497.3 KB	5.7%	0.14
Jun 26 00:00:00 - 00:59:59	95	4.3%	0.03	277.0 KB	3.2%	0.08
Jun 26 01:00:00 - 01:59:59	65	2.9%	0.02	359.1 KB	4.1%	0.10
Jun 26 02:00:00 - 02:59:59	83	3.7%	0.02	309.5 KB	3.5%	0.09
Jun 26 03:00:00 - 03:59:59	62	2.8%	0.02	230.8 KB	2.6%	0.06
Jun 26 04:00:00 - 04:15:01	11	0.5%	0.01	55.6 KB	0.6%	0.06
TOTAL: 23:59:59	2226	100.0%	0.03	8.6 MB	100.0%	0.10

Sites sending bad articles:

Server		Total	Other
1	news.glou.org	132	132
2	news.nntp4.net	90	90
3	gegeweb-out.news.tnetconsulting.net	65	65
4	usenet.pasdenom.info	14	14
5	i2pn.org	1	1
TOTAL: 5		302	302

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	153
Bad path (news.giganews.com.POSTED)	71
Too many newsgroups	55
Cancel of <v5ck01$10bd4$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5evpj$13bqd$1@i2pn2.org> without Cancel-Key	1
Cancel of <v51cgg$ier2$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5cp47$10ikc$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5fgrf$1422t$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5cefa$100up$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5evc5$13b8c$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5cnhi$10g9q$1@i2pn2.org> without Cancel-Key	1
Cancel of non-existing ID <667ab778$0$11426$426a34cc@news.free.fr>	1
Cancel of <v5eudt$139se$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5e2ad$122ol$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5co0o$10h30$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5co65$10h5p$1@i2pn2.org> without Cancel-Key	1
Cancel of <v5cfog$104n2$1@i2pn2.org> without Cancel-Key	1
Cancel for rejected article	1
Cancel of <v5cq2j$10jrl$1@i2pn2.org> without Cancel-Key	1
Cancels abusifs	1
TOTAL: 26	302

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	news.gegeweb.org	1158	94	1041	0	0	8%	23:52:53
2	i2pn.org	3335	47	1956	15	8	1%	23:57:45
3	news.tnetconsulting.net	3687	11	2207	4	6	0%	23:59:56
4	news.pasdenom.info	2026	1	1808	3	0	0%	23:58:47
5	news.nntp4.net	1928	1	1858	0	0	0%	23:59:57
6	news.samoylyk.net	2506	1	2140	0	5	0%	23:58:48
7	nntp.terraraq.uk	1551	1	1429	0	0	0%	23:58:58
8	news.aioe.org	0	0	0	0	1809	0%	23:59:59
TOTAL: 8		16191	156	12439	22	1828	0%	191:47:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	news.gegeweb.org	281.5 KB	0.0 KB	281.5 KB	3.0 KB	23:52:53
2	i2pn.org	105.3 KB	87.6 KB	192.9 KB	3.1 KB	23:57:45
3	news.tnetconsulting.net	40.9 KB	9.8 KB	50.7 KB	3.4 KB	23:59:56
4	news.pasdenom.info	5.6 KB	8.3 KB	13.9 KB	3.5 KB	23:58:47
5	news.samoylyk.net	4.1 KB	0.0 KB	4.1 KB	4.1 KB	23:58:48
6	nntp.terraraq.uk	1.1 KB	0.0 KB	1.1 KB	1.1 KB	23:58:58
7	news.nntp4.net	1.1 KB	0.0 KB	1.1 KB	1.1 KB	23:59:57
8	news.aioe.org	0.0 KB	0.0 KB	0.0 KB	0	23:59:59
TOTAL: 8		439.6 KB	105.7 KB	545.2 KB	3.1 KB	191:47:03

NNRP readership statistics:

System		Conn	Elapsed
1	172-232-209-27.ip.linodeusercontent.com	1	00:00:00
2	206.168.34.210	1	00:00:25
3	99.211.203.35.bc.googleusercontent.com	1	00:00:00
4	keen.monitoring.internet-measurement.com	1	00:00:00
5	sensitive.monitoring.internet-measurement.com	1	00:00:00
6	mail.gegeweb.eu	289	00:01:50
7	adorable.monitoring.internet-measurement.com	1	00:00:00
8	lucid.monitoring.internet-measurement.com	1	00:00:00
9	172.104.238.162	1	00:00:03
10	206.168.34.43	1	00:00:05
11	147.185.133.223	1	00:00:10
12	valiant.monitoring.internet-measurement.com	1	00:00:06
TOTAL: 12		300	00:02:43

NNRP readership statistics (by domain):

System		Conn	Elapsed
1	*.gegeweb.eu	289	00:01:50
2	*.211.203.35.bc.googleusercontent.com	1	00:00:00
3	*.monitoring.internet-measurement.com	5	00:00:07
4	unresolved	4	00:00:44
5	*.ip.linodeusercontent.com	1	00:00:00
TOTAL: 5		300	00:02:43

NNRP auth users [Top 20]:

User	Conn
mailman	289
TOTAL: 1	289

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
mail.gegeweb.eu	4.396	4.283	1.480	00:01:50
206.168.34.210	0.029	0.012	0.000	00:00:25
147.185.133.223	0.064	0.016	0.000	00:00:10
valiant.monitoring.internet-measurement.com	0.060	0.012	0.000	00:00:06
206.168.34.43	0.037	0.029	0.000	00:00:05
172.104.238.162	0.020	0.008	0.000	00:00:03
99.211.203.35.bc.googleusercontent.com	0.029	0.012	0.000	00:00:00
172-232-209-27.ip.linodeusercontent.com	0.008	0.016	0.000	00:00:00
lucid.monitoring.internet-measurement.com	0.033	0.029	0.000	00:00:00
sensitive.monitoring.internet-measurement.com	0.024	0.032	0.000	00:00:00
keen.monitoring.internet-measurement.com	0.012	0.016	0.000	00:00:00
?	0.008	0.013	0.000	00:00:00
TOTAL: 12	4.720	4.478	1.480	00:02:43

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

System	Conn
99.211.203.35.bc.googleusercontent.com	4
147.185.133.223	4
206.168.34.210	1
206.168.34.43	1
TOTAL: 4	10

NNRP unrecognized commands (by command):

Command	Count
User-Agent: Expanse, a Palo Alto Network...	2
EHLO www.censys.io	2
Host: 45.13.105.36:563	2
GET / HTTP/1.1	2
Accept-Encoding: gzip	2
TOTAL: 5	10

Daily Usenet report for news.niel.me

Jun 25 04:15:02 -- Jun 26 04:15:01