Hi Ilya,
Thanks!
Op 14-1-2020 om 07:48 schreef Илья Шипицин:
Hello,
since
https://github.com/haproxy/haproxy/commit/08fa16e397ffb1c6511b98ade2a3bfff9435e521
freebsd CI is red: https://cirrus-ci.com/task/5960933184897024
I'd say "it is something with CI itself", when I run the same tests
locally on freebsd, it is green.
Sadly i do get the same problem on my test server (version info below
its version 11.1 is a bit outdated, but hasn't failed my before...).
PiBa ?
thanks,
Ilya Shipitcin
Below a part of the output that the test generates for me. The first
curl request seems to succeed, but the second one runs into a timeout..
When compiled with the commit before 08fa16e
<https://github.com/haproxy/haproxy/commit/08fa16e397ffb1c6511b98ade2a3bfff9435e521>
it does not show that behaviour.. Current latest(24c928c) commit is
still affected..
**** top shell_out| % Total % Received % Xferd Average Speed
Time Time Time Current
**** top shell_out| Dload Upload
Total Spent Left Speed
**** top shell_out|\r 0 0 0 0 0 0 0 0 --:--:--
--:--:-- --:--:-- 0\r100 418k 0 418k 0 0 1908k 0
--:--:-- --:--:-- --:--:-- 1908k
**** top shell_out| % Total % Received % Xferd Average Speed
Time Time Time Current
**** top shell_out| Dload Upload
Total Spent Left Speed
**** top shell_out|\r 0 0 0 0 0 0 0 0 --:--:--
--:--:-- --:--:-- 0\r100 141k 0 141k 0 0 284k 0
--:--:-- --:--:-- --:--:-- 284k\r100 343k 0 343k 0 0
156k 0 --:--:-- 0:00:02 --:--:-- 156k\r100 343k 0 343k
0 0 105k 0 --:--:-- 0:00:03 --:--:-- 105k\r100 343k 0
343k 0 0 81274 0 --:--:-- 0:00:04 --:--:-- 81274\r100
343k 0 343k 0 0 65228 0 --:--:-- 0:00:05 --:--:--
65240\r100 343k 0 343k 0 0 54481 0 --:--:-- 0:00:06
--:--:-- 34743\r100 343k 0 343k 0 0 46768 0 --:--:--
0:00:07 --:--:-- 0\r100 343k 0 343k 0 0 40968 0
--:--:-- 0:00:08 --:--:-- 0\r100 343k 0 343k 0 0
36452 0 --:--:-- 0:00:09 --:--:-- 0\r100 343k 0 343k
0 0 32830 0 --:--:-- 0:00:10 --:--:-- 0\r100 343k 0
343k 0 0 29865 0 --:--:-- 0:00:11 --:--:-- 0\r100
343k 0 343k 0 0 27395 0 --:--:-- 0:00:12 --:--:--
0\r100 343k 0 343k 0 0 25297 0 --:--:-- 0:00:13
--:--:-- 0\r100 343k 0 343k 0 0 23500 0 --:--:--
0:00:14 --:--:-- 0\r100 343k 0 343k 0 0 23431 0
--:--:-- 0:00:15 --:--:-- 0
**** top shell_out|curl: (28) Operation timed out after 15002
milliseconds with 351514 bytes received
**** top shell_out|Expecting checksum 4d9c62aa5370b8d5f84f17ec2e78f483
**** top shell_out|Received checksum: da2d120aedfd693eeba9cf1e578897a8
**** top shell_status = 0x0001
---- top shell_exit not as expected: got 0x0001 wanted 0x0000
* top RESETTING after
./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
Should i update to a newer FreeBSD version, or is it likely unrelated,
and in need of some developer attention.. Do you (Willy or anyone), need
more information from my side? Or is there a patch i can try to validate?
Regards,
PiBa-NL (Pieter)
Yes im running a somewhat outdated OS here:
FreeBSD freebsd11 11.1-RELEASE FreeBSD 11.1-RELEASE #0 r321309: Fri
Jul 21 02:08:28 UTC 2017
r...@releng2.nyi.freebsd.org:/usr/obj/usr/src/sys/GENERIC amd64
Version used:
haproxy -vv
HA-Proxy version 2.2-dev0-08fa16e 2020/01/08 - https://haproxy.org/
Status: development branch - not safe for use in production.
Known bugs: https://github.com/haproxy/haproxy/issues?q=is:issue+is:open
Build options :
TARGET = freebsd
CPU = generic
CC = cc
CFLAGS = -pipe -g -fstack-protector -fno-strict-aliasing
-fno-strict-aliasing -Wdeclaration-after-statement -fwrapv
-fno-strict-overflow -Wno-null-dereference -Wno-unused-label
-Wno-unused-parameter -Wno-sign-compare -Wno-ignored-qualifiers
-Wno-unused-command-line-argument -Wno-missing-field-initializers
-Wno-address-of-packed-member -DFREEBSD_PORTS -DFREEBSD_PORTS
OPTIONS = USE_PCRE=1 USE_PCRE_JIT=1 USE_REGPARM=1 USE_STATIC_PCRE=1
USE_GETADDRINFO=1 USE_OPENSSL=1 USE_LUA=1 USE_ACCEPT4=1 USE_ZLIB=1
Feature list : -EPOLL +KQUEUE -MY_EPOLL -MY_SPLICE -NETFILTER +PCRE
+PCRE_JIT -PCRE2 -PCRE2_JIT +POLL -PRIVATE_CACHE +THREAD
-PTHREAD_PSHARED +REGPARM +STATIC_PCRE -STATIC_PCRE2 +TPROXY
-LINUX_TPROXY -LINUX_SPLICE +LIBCRYPT -CRYPT_H -VSYSCALL +GETADDRINFO
+OPENSSL +LUA -FUTEX +ACCEPT4 -MY_ACCEPT4 +ZLIB -SLZ +CPU_AFFINITY -TFO
-NS -DL -RT -DEVICEATLAS -51DEGREES -WURFL -SYSTEMD -OBSOLETE_LINKER
-PRCTL -THREAD_DUMP -EVPORTS
Default settings :
bufsize = 16384, maxrewrite = 1024, maxpollevents = 200
Built with multi-threading support (MAX_THREADS=64, default=16).
Built with OpenSSL version : OpenSSL 1.0.2k-freebsd 26 Jan 2017
Running on OpenSSL version : OpenSSL 1.0.2k-freebsd 26 Jan 2017
OpenSSL library supports TLS extensions : yes
OpenSSL library supports SNI : yes
OpenSSL library supports : SSLv3 TLSv1.0 TLSv1.1 TLSv1.2
Built with Lua version : Lua 5.3.4
Built with transparent proxy support using: IP_BINDANY IPV6_BINDANY
Built with PCRE version : 8.40 2017-01-11
Running on PCRE version : 8.40 2017-01-11
PCRE library supports JIT : yes
Encrypted password support via crypt(3): yes
Built with zlib version : 1.2.11
Running on zlib version : 1.2.11
Compression algorithms supported : identity("identity"),
deflate("deflate"), raw-deflate("deflate"), gzip("gzip")
Available polling systems :
kqueue : pref=300, test result OK
poll : pref=200, test result OK
select : pref=150, test result OK
Total: 3 (3 usable), will use kqueue.
Available multiplexer protocols :
(protocols marked as <default> cannot be specified using 'proto' keyword)
h2 : mode=HTTP side=FE|BE mux=H2
fcgi : mode=HTTP side=BE mux=FCGI
<default> : mode=HTTP side=FE|BE mux=H1
<default> : mode=TCP side=FE|BE mux=PASS
Available services : none
Available filters :
[SPOE] spoe
[CACHE] cache
[FCGI] fcgi-app
[TRACE] trace
[COMP] compression
HA-Proxy version 2.2-dev0-08fa16e 2020/01/08 - https://haproxy.org/
Status: development branch - not safe for use in production.
Known bugs: https://github.com/haproxy/haproxy/issues?q=is:issue+is:open
## Without HTX
**** dT 0.000
* top TEST ./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
starting
**** top extmacro def pwd=/usr/ports-pb_haproxy-devel
**** top extmacro def no-htx=no
**** top extmacro def localhost=127.0.0.1
**** top extmacro def bad_backend=127.0.0.1 57172
**** top extmacro def bad_ip=192.0.2.255
**** top macro def
testdir=/usr/ports-pb_haproxy-devel/./work/haproxy-08fa16e/reg-tests/compression
**** top macro def tmpdir=/tmp/vtc.40889.75a03523
** top === varnishtest "Compression validation"
* top VTEST Compression validation
** top === feature ignore_unknown_macro
** top === haproxy h1 -conf {
**** dT 0.001
**** h1 macro def h1_closed_sock=::1 16677
**** h1 macro def h1_closed_addr=::1
**** h1 macro def h1_closed_port=16677
**** dT 0.005
**** h1 macro def h1_cli_sock=::1 33386
**** h1 macro def h1_cli_addr=::1
**** h1 macro def h1_cli_port=33386
**** h1 setenv(cli, 3)
**** h1 macro def h1_fe1_sock=::1 26488
**** h1 macro def h1_fe1_addr=::1
**** h1 macro def h1_fe1_port=26488
**** h1 setenv(fe1, 4)
**** h1 macro def h1_fe2_sock=::1 57122
**** h1 macro def h1_fe2_addr=::1
**** h1 macro def h1_fe2_port=57122
**** h1 setenv(fe2, 5)
** h1 haproxy_start
**** h1 opt_worker 0 opt_daemon 0 opt_check_mode 0 opt_mcli 0
**** h1 argv|exec "haproxy" -d -f "/tmp/vtc.40889.75a03523/h1/cfg"
**** h1 conf| global
**** h1 conf|\tstats socket "/tmp/vtc.40889.75a03523/h1/stats.sock" level
admin mode 600
**** h1 conf| stats socket "fd@${cli}" level admin
**** h1 conf|
**** h1 conf|global
**** h1 conf|#\tlog stdout format short daemon
**** h1
conf|\tlua-load\t\t/usr/ports-pb_haproxy-devel/./work/haproxy-08fa16e/reg-tests/compression/lua_validation.lua
**** h1 conf|
**** h1 conf|defaults
**** h1 conf|\tmode\t\t\thttp
**** h1 conf|\tlog\t\t\tglobal
**** h1 conf|\tno option http-use-htx
**** h1 conf|\toption\t\t\thttplog
**** h1 conf|
**** h1 conf|frontend main-https
**** h1 conf|\tbind\t\t\t"fd@${fe1}" ssl crt
/usr/ports-pb_haproxy-devel/./work/haproxy-08fa16e/reg-tests/compression/common.pem
**** h1 conf|\tcompression algo gzip
**** h1 conf|\tcompression type text/html text/plain application/json
application/javascript
**** h1 conf|\tcompression offload
**** h1 conf|\tuse_backend TestBack if TRUE
**** h1 conf|
**** h1 conf|backend TestBack
**** h1 conf|\tserver\tLocalSrv ::1:57122
**** h1 conf|
**** h1 conf|listen fileloader
**** h1 conf|\tmode http
**** h1 conf|\tbind "fd@${fe2}"
**** h1 conf|\thttp-request use-service lua.fileloader-http01
**** h1 XXX 7 @725
**** dT 0.006
*** h1 PID: 40893
**** h1 macro def h1_pid=40893
**** h1 macro def h1_name=/tmp/vtc.40889.75a03523/h1
**** dT 0.007
** top === shell {
**** top shell_cmd|exec 2>&1 ;
**** top shell_cmd| HOST=::1
**** top shell_cmd| if [ "::1" = "::1" ] ; then
**** top shell_cmd| HOST="\[::1\]"
**** top shell_cmd| fi
**** top shell_cmd|
**** top shell_cmd| md5=$(which md5 || which md5sum)
**** top shell_cmd|
**** top shell_cmd| if [ -z $md5 ] ; then
**** top shell_cmd| echo "MD5 checksum utility not found"
**** top shell_cmd| exit 1
**** top shell_cmd| fi
**** top shell_cmd|
**** top shell_cmd| expectchecksum="4d9c62aa5370b8d5f84f17ec2e78f483"
**** top shell_cmd|
**** top shell_cmd| for opt in "" "--limit-rate 300K" "--limit-rate 500K" ;
do
**** top shell_cmd| checksum=$(curl --max-time 15 --compressed -k
"https://$HOST:26488" $opt | $md5 | cut -d ' ' -f1)
**** top shell_cmd| if [ "$checksum" != "$expectchecksum" ] ; then
**** top shell_cmd| echo "Expecting checksum $expectchecksum"
**** top shell_cmd| echo "Received checksum: $checksum"
**** top shell_cmd| exit 1;
**** top shell_cmd| fi
**** top shell_cmd| done
**** top shell_cmd|
**** dT 0.158
*** h1 debug|[WARNING] 013/195609 (40893) : parsing
[/tmp/vtc.40889.75a03523/h1/cfg:12]: option 'http-use-htx' is deprecated and
ignored. The HTX mode is now the only supported mode.
**** dT 0.159
*** h1 debug|[WARNING] 013/195609 (40893) : config : missing timeouts for
frontend 'main-https'.
*** h1 debug| | While not properly invalid, you will certainly encounter
various problems
*** h1 debug| | with such a configuration. To fix this, please ensure that
all following
*** h1 debug| | timeouts are set to a non-zero value: 'client', 'connect',
'server'.
*** h1 debug|[WARNING] 013/195609 (40893) : config : log format ignored for
frontend 'main-https' since it has no log address.
*** h1 debug|[WARNING] 013/195609 (40893) : config : missing timeouts for
backend 'TestBack'.
*** h1 debug| | While not properly invalid, you will certainly encounter
various problems
*** h1 debug| | with such a configuration. To fix this, please ensure that
all following
*** h1 debug| | timeouts are set to a non-zero value: 'client', 'connect',
'server'.
*** h1 debug|[WARNING] 013/195609 (40893) : config : missing timeouts for
proxy 'fileloader'.
*** h1 debug| | While not properly invalid, you will certainly encounter
various problems
*** h1 debug| | with such a configuration. To fix this, please ensure that
all following
*** h1 debug| | timeouts are set to a non-zero value: 'client', 'connect',
'server'.
*** h1 debug|[WARNING] 013/195609 (40893) : config : log format ignored for
proxy 'fileloader' since it has no log address.
**** dT 0.162
*** h1 debug|[WARNING] 013/195609 (40893) : Setting
tune.ssl.default-dh-param to 1024 by default, if your workload permits it you
should set it to at least 2048. Please set a value >= 1024 to make this warning
disappear.
*** h1 debug|Note: setting global.maxconn to 117418.
*** h1 debug|Available polling systems :
*** h1 debug| kqueue : pref=300,
*** h1 debug| test result OK
*** h1 debug| poll : pref=200, test result OK
*** h1 debug|
*** h1 debug| select : pref=150,
*** h1 debug| test result FAILED
*** h1 debug|Total: 3 (2 usable), will use kqueue.
*** h1 debug|
*** h1 debug|Available filters :
*** h1 debug|\t[SPOE] spoe
*** h1 debug|\t[CACHE] cache
*** h1 debug|\t[FCGI] fcgi-app
*** h1 debug|\t[TRACE] trace
*** h1 debug|\t[COMP] compression
**** dT 0.169
*** h1 debug|Using kqueue() as the polling mechanism.
**** dT 0.211
*** h1 debug|00000000:main-https.accept(0004)=0037 from [::1:54150]
ALPN=<none>
*** h1 debug|00000000:main-https.clireq[0037:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: host: [::1]:26488
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: accept: */*
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: accept-encoding:
deflate, gzip
**** dT 0.212
*** h1 debug|00000001:fileloader.accept(0005)=0039 from [::1:60611]
ALPN=<none>
*** h1 debug|00000001:fileloader.clireq[0039:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000001:fileloader.clihdr[0039:ffffffff]: host: [::1]:26488
*** h1 debug|00000001:fileloader.clihdr[0039:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000001:fileloader.clihdr[0039:ffffffff]: accept: */*
*** h1 debug|00000001:fileloader.srvrep[0039:ffffffff]: HTTP/1.1 200 OK
*** h1 debug|00000001:fileloader.srvhdr[0039:ffffffff]: content-type:
application/javascript
*** h1 debug|00000001:fileloader.srvhdr[0039:ffffffff]: content-length:
1888190
**** dT 0.213
*** h1 debug|00000000:TestBack.srvrep[0037:0038]: HTTP/1.1 200 OK
*** h1 debug|00000000:TestBack.srvhdr[0037:0038]: content-type:
application/javascript
*** h1 debug|00000000:TestBack.srvhdr[0037:0038]: content-length: 1888190
**** dT 0.243
*** h1 debug|00000001:fileloader.srvcls[0039:ffffffff]
*** h1 debug|00000001:fileloader.clicls[0039:ffffffff]
*** h1 debug|00000001:fileloader.closed[0039:ffffffff]
*** h1 debug|00000002:fileloader.accept(0005)=0039 from [::1:60611]
ALPN=<none>
**** dT 0.246
*** h1 debug|00000000:TestBack.srvcls[0037:0038]
*** h1 debug|00000000:TestBack.clicls[0037:0038]
*** h1 debug|00000000:TestBack.closed[0037:0038]
*** h1 debug|00000003:main-https.accept(0004)=0037 from [::1:54150]
ALPN=<none>
*** h1 debug|00000003:main-https.clicls[0037:ffffffff]
*** h1 debug|00000003:main-https.closed[0037:ffffffff]
**** dT 0.270
*** h1 debug|00000004:main-https.accept(0004)=0037 from [::1:51575]
ALPN=<none>
**** dT 0.271
*** h1 debug|00000004:main-https.clireq[0037:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: host: [::1]:26488
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: accept: */*
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: accept-encoding:
deflate, gzip
**** dT 0.272
*** h1 debug|00000005:fileloader.accept(0005)=003b from [::1:26122]
ALPN=<none>
*** h1 debug|00000005:fileloader.clireq[003b:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000005:fileloader.clihdr[003b:ffffffff]: host: [::1]:26488
*** h1 debug|00000005:fileloader.clihdr[003b:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000005:fileloader.clihdr[003b:ffffffff]: accept: */*
*** h1 debug|00000005:fileloader.srvrep[003b:ffffffff]: HTTP/1.1 200 OK
*** h1 debug|00000005:fileloader.srvhdr[003b:ffffffff]: content-type:
application/javascript
*** h1 debug|00000005:fileloader.srvhdr[003b:ffffffff]: content-length:
1888190
*** h1 debug|00000004:TestBack.srvrep[0037:003a]: HTTP/1.1 200 OK
*** h1 debug|00000004:TestBack.srvhdr[0037:003a]: content-type:
application/javascript
*** h1 debug|00000004:TestBack.srvhdr[0037:003a]: content-length: 1888190
**** dT 5.272
*** h1 debug|00000002:fileloader.clicls[0039:ffffffff]
*** h1 debug|00000002:fileloader.closed[0039:ffffffff]
**** dT 15.297
**** top shell_out| % Total % Received % Xferd Average Speed Time
Time Time Current
**** top shell_out| Dload Upload Total
Spent Left Speed
**** top shell_out|\r 0 0 0 0 0 0 0 0 --:--:--
--:--:-- --:--:-- 0\r100 418k 0 418k 0 0 1849k 0 --:--:--
--:--:-- --:--:-- 1849k
**** top shell_out| % Total % Received % Xferd Average Speed Time
Time Time Current
**** top shell_out| Dload Upload Total
Spent Left Speed
**** top shell_out|\r 0 0 0 0 0 0 0 0 --:--:--
--:--:-- --:--:-- 0\r100 162k 0 162k 0 0 296k 0 --:--:--
--:--:-- --:--:-- 295k\r100 209k 0 209k 0 0 117k 0 --:--:--
0:00:01 --:--:-- 117k\r100 209k 0 209k 0 0 75263 0 --:--:--
0:00:02 --:--:-- 75237\r100 209k 0 209k 0 0 54796 0 --:--:--
0:00:03 --:--:-- 54796\r100 209k 0 209k 0 0 43081 0 --:--:--
0:00:04 --:--:-- 43072\r100 209k 0 209k 0 0 35771 0 --:--:--
0:00:05 --:--:-- 8821\r100 209k 0 209k 0 0 30374 0 --:--:--
0:00:07 --:--:-- 0\r100 209k 0 209k 0 0 26395 0 --:--:--
0:00:08 --:--:-- 0\r100 209k 0 209k 0 0 23335 0 --:--:--
0:00:09 --:--:-- 0\r100 209k 0 209k 0 0 20913 0 --:--:--
0:00:10 --:--:-- 0\r100 209k 0 209k 0 0 19019 0 --:--:--
0:00:11 --:--:-- 0\r100 209k 0 209k 0 0 17379 0 --:--:--
0:00:12 --:--:-- 0\r100 209k 0 209k 0 0 15999 0 --:--:--
0:00:13 --:--:-- 0\r100 209k 0 209k 0 0 14821 0 --:--:--
0:00:14 --:--:-- 0\r100 209k 0 209k 0 0 14246 0 --:--:--
0:00:15 --:--:-- 0
**** top shell_out|curl: (28) Operation timed out after 15035 milliseconds
with 214201 bytes received
**** top shell_out|Expecting checksum 4d9c62aa5370b8d5f84f17ec2e78f483
**** top shell_out|Received checksum: af7785dc7013cbb06f58b0e52c1ebe8a
**** top shell_status = 0x0001
---- top shell_exit not as expected: got 0x0001 wanted 0x0000
* top RESETTING after
./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
** h1 Reset and free h1 haproxy 40893
** h1 Wait
** h1 Stop HAproxy pid=40893
**** h1 Kill(2)=0: No error: 0
**** dT 15.298
**** h1 STDOUT EOF
**** dT 15.412
** h1 WAIT4 pid=40893 status=0x0002 (user 0.232979 sys 0.064270)
* top TEST ./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
FAILED
# top TEST ./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
FAILED (15.416) exit=2
## With HTX
**** dT 0.000
* top TEST ./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
starting
**** top extmacro def pwd=/usr/ports-pb_haproxy-devel
**** top extmacro def no-htx=
**** top extmacro def localhost=127.0.0.1
**** top extmacro def bad_backend=127.0.0.1 38916
**** top extmacro def bad_ip=192.0.2.255
**** top macro def
testdir=/usr/ports-pb_haproxy-devel/./work/haproxy-08fa16e/reg-tests/compression
**** top macro def tmpdir=/tmp/vtc.40905.4b3fe4df
** top === varnishtest "Compression validation"
* top VTEST Compression validation
** top === feature ignore_unknown_macro
** top === haproxy h1 -conf {
**** dT 0.001
**** h1 macro def h1_closed_sock=::1 39835
**** h1 macro def h1_closed_addr=::1
**** h1 macro def h1_closed_port=39835
**** dT 0.005
**** h1 macro def h1_cli_sock=::1 18608
**** h1 macro def h1_cli_addr=::1
**** h1 macro def h1_cli_port=18608
**** h1 setenv(cli, 3)
**** h1 macro def h1_fe1_sock=::1 36521
**** h1 macro def h1_fe1_addr=::1
**** h1 macro def h1_fe1_port=36521
**** h1 setenv(fe1, 4)
**** h1 macro def h1_fe2_sock=::1 58368
**** h1 macro def h1_fe2_addr=::1
**** h1 macro def h1_fe2_port=58368
**** h1 setenv(fe2, 5)
** h1 haproxy_start
**** h1 opt_worker 0 opt_daemon 0 opt_check_mode 0 opt_mcli 0
**** h1 argv|exec "haproxy" -d -f "/tmp/vtc.40905.4b3fe4df/h1/cfg"
**** h1 conf| global
**** h1 conf|\tstats socket "/tmp/vtc.40905.4b3fe4df/h1/stats.sock" level
admin mode 600
**** h1 conf| stats socket "fd@${cli}" level admin
**** h1 conf|
**** h1 conf|global
**** h1 conf|#\tlog stdout format short daemon
**** h1
conf|\tlua-load\t\t/usr/ports-pb_haproxy-devel/./work/haproxy-08fa16e/reg-tests/compression/lua_validation.lua
**** h1 conf|
**** h1 conf|defaults
**** h1 conf|\tmode\t\t\thttp
**** h1 conf|\tlog\t\t\tglobal
**** h1 conf|\t option http-use-htx
**** h1 conf|\toption\t\t\thttplog
**** h1 conf|
**** h1 conf|frontend main-https
**** h1 conf|\tbind\t\t\t"fd@${fe1}" ssl crt
/usr/ports-pb_haproxy-devel/./work/haproxy-08fa16e/reg-tests/compression/common.pem
**** h1 conf|\tcompression algo gzip
**** h1 conf|\tcompression type text/html text/plain application/json
application/javascript
**** h1 conf|\tcompression offload
**** h1 conf|\tuse_backend TestBack if TRUE
**** h1 conf|
**** h1 conf|backend TestBack
**** h1 conf|\tserver\tLocalSrv ::1:58368
**** h1 conf|
**** h1 conf|listen fileloader
**** h1 conf|\tmode http
**** h1 conf|\tbind "fd@${fe2}"
**** h1 conf|\thttp-request use-service lua.fileloader-http01
**** h1 XXX 7 @725
**** dT 0.006
*** h1 PID: 40909
**** h1 macro def h1_pid=40909
**** h1 macro def h1_name=/tmp/vtc.40905.4b3fe4df/h1
**** dT 0.007
** top === shell {
**** top shell_cmd|exec 2>&1 ;
**** top shell_cmd| HOST=::1
**** top shell_cmd| if [ "::1" = "::1" ] ; then
**** top shell_cmd| HOST="\[::1\]"
**** top shell_cmd| fi
**** top shell_cmd|
**** top shell_cmd| md5=$(which md5 || which md5sum)
**** top shell_cmd|
**** top shell_cmd| if [ -z $md5 ] ; then
**** top shell_cmd| echo "MD5 checksum utility not found"
**** top shell_cmd| exit 1
**** top shell_cmd| fi
**** top shell_cmd|
**** top shell_cmd| expectchecksum="4d9c62aa5370b8d5f84f17ec2e78f483"
**** top shell_cmd|
**** top shell_cmd| for opt in "" "--limit-rate 300K" "--limit-rate 500K" ;
do
**** top shell_cmd| checksum=$(curl --max-time 15 --compressed -k
"https://$HOST:36521" $opt | $md5 | cut -d ' ' -f1)
**** top shell_cmd| if [ "$checksum" != "$expectchecksum" ] ; then
**** top shell_cmd| echo "Expecting checksum $expectchecksum"
**** top shell_cmd| echo "Received checksum: $checksum"
**** top shell_cmd| exit 1;
**** top shell_cmd| fi
**** top shell_cmd| done
**** top shell_cmd|
**** dT 0.151
*** h1 debug|[WARNING] 013/195625 (40909) : config : missing timeouts for
frontend 'main-https'.
*** h1 debug| | While not properly invalid, you will certainly encounter
various problems
*** h1 debug| | with such a configuration. To fix this, please ensure that
all following
*** h1 debug| | timeouts are set to a non-zero value: 'client', 'connect',
'server'.
*** h1 debug|[WARNING] 013/195625 (40909) : config : log format ignored for
frontend 'main-https' since it has no log address.
*** h1 debug|[WARNING] 013/195625 (40909) : config : missing timeouts for
backend 'TestBack'.
*** h1 debug| | While not properly invalid, you will certainly encounter
various problems
*** h1 debug| | with such a configuration. To fix this, please ensure that
all following
*** h1 debug| | timeouts are set to a non-zero value: 'client', 'connect',
'server'.
*** h1 debug|[WARNING] 013/195625 (40909) : config : missing timeouts for
proxy 'fileloader'.
*** h1 debug| | While not properly invalid, you will certainly encounter
various problems
*** h1 debug| | with such a configuration. To fix this, please ensure that
all following
*** h1 debug| | timeouts are set to a non-zero value: 'client', 'connect',
'server'.
*** h1 debug|[WARNING] 013/195625 (40909) : config : log format ignored for
proxy 'fileloader' since it has no log address.
**** dT 0.154
*** h1 debug|[WARNING] 013/195625 (40909) : Setting
tune.ssl.default-dh-param to 1024 by default, if your workload permits it you
should set it to at least 2048. Please set a value >= 1024 to make this warning
disappear.
*** h1 debug|Note: setting global.maxconn to 117418.
*** h1 debug|Available polling systems :
*** h1 debug| kqueue : pref=300, test result OK
*** h1 debug| poll : pref=200, test result OK
*** h1 debug| select : pref=150, test result FAILED
*** h1 debug|Total: 3 (2 usable), will use kqueue.
*** h1 debug|
*** h1 debug|Available filters :
*** h1 debug|\t[SPOE] spoe
*** h1 debug|\t[CACHE] cache
*** h1 debug|\t[FCGI] fcgi-app
*** h1 debug|\t[TRACE] trace
*** h1 debug|\t[COMP] compression
**** dT 0.162
*** h1 debug|Using kqueue() as the polling mechanism.
**** dT 0.207
*** h1 debug|00000000:main-https.accept(0004)=0037 from [::1:46189]
ALPN=<none>
**** dT 0.208
*** h1 debug|00000000:main-https.clireq[0037:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: host: [::1]:36521
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: accept: */*
*** h1 debug|00000000:main-https.clihdr[0037:ffffffff]: accept-encoding:
deflate, gzip
**** dT 0.209
*** h1 debug|00000001:fileloader.accept(0005)=0039 from [::1:52548]
ALPN=<none>
*** h1 debug|00000001:fileloader.clireq[0039:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000001:fileloader.clihdr[0039:ffffffff]: host: [::1]:36521
*** h1 debug|00000001:fileloader.clihdr[0039:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000001:fileloader.clihdr[0039:ffffffff]: accept: */*
*** h1 debug|00000001:fileloader.srvrep[0039:ffffffff]: HTTP/1.1 200 OK
*** h1 debug|00000001:fileloader.srvhdr[0039:ffffffff]: content-length:
1888190
*** h1 debug|00000001:fileloader.srvhdr[0039:ffffffff]: content-type:
application/javascript
*** h1 debug|00000000:TestBack.srvrep[0037:0038]: HTTP/1.1 200 OK
*** h1 debug|00000000:TestBack.srvhdr[0037:0038]: content-length: 1888190
*** h1 debug|00000000:TestBack.srvhdr[0037:0038]: content-type:
application/javascript
**** dT 0.240
*** h1 debug|00000001:fileloader.srvcls[0039:ffffffff]
*** h1 debug|00000001:fileloader.clicls[0039:ffffffff]
*** h1 debug|00000001:fileloader.closed[0039:ffffffff]
*** h1 debug|00000002:fileloader.accept(0005)=0039 from [::1:52548]
ALPN=<none>
**** dT 0.243
*** h1 debug|00000000:TestBack.srvcls[0037:0038]
*** h1 debug|00000000:TestBack.clicls[0037:0038]
*** h1 debug|00000000:TestBack.closed[0037:0038]
*** h1 debug|00000003:main-https.accept(0004)=0037 from [::1:46189]
ALPN=<none>
**** dT 0.245
*** h1 debug|00000003:main-https.clicls[0037:ffffffff]
*** h1 debug|00000003:main-https.closed[0037:ffffffff]
**** dT 0.270
*** h1 debug|00000004:main-https.accept(0004)=0037 from [::1:58281]
ALPN=<none>
**** dT 0.271
*** h1 debug|00000004:main-https.clireq[0037:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: host: [::1]:36521
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: accept: */*
*** h1 debug|00000004:main-https.clihdr[0037:ffffffff]: accept-encoding:
deflate, gzip
*** h1 debug|00000005:fileloader.accept(0005)=003b from [::1:21236]
ALPN=<none>
*** h1 debug|00000005:fileloader.clireq[003b:ffffffff]: GET / HTTP/1.1
*** h1 debug|00000005:fileloader.clihdr[003b:ffffffff]: host: [::1]:36521
*** h1 debug|00000005:fileloader.clihdr[003b:ffffffff]: user-agent:
curl/7.60.0
*** h1 debug|00000005:fileloader.clihdr[003b:ffffffff]: accept: */*
*** h1 debug|00000005:fileloader.srvrep[003b:ffffffff]: HTTP/1.1 200 OK
*** h1 debug|00000005:fileloader.srvhdr[003b:ffffffff]: content-length:
1888190
*** h1 debug|00000005:fileloader.srvhdr[003b:ffffffff]: content-type:
application/javascript
**** dT 0.272
*** h1 debug|00000004:TestBack.srvrep[0037:003a]: HTTP/1.1 200 OK
*** h1 debug|00000004:TestBack.srvhdr[0037:003a]: content-length: 1888190
*** h1 debug|00000004:TestBack.srvhdr[0037:003a]: content-type:
application/javascript
**** dT 5.259
*** h1 debug|00000002:fileloader.clicls[0039:ffffffff]
*** h1 debug|00000002:fileloader.closed[0039:ffffffff]
**** dT 15.295
**** top shell_out| % Total % Received % Xferd Average Speed Time
Time Time Current
**** top shell_out| Dload Upload Total
Spent Left Speed
**** top shell_out|\r 0 0 0 0 0 0 0 0 --:--:--
--:--:-- --:--:-- 0\r100 418k 0 418k 0 0 1858k 0 --:--:--
--:--:-- --:--:-- 1866k
**** top shell_out| % Total % Received % Xferd Average Speed Time
Time Time Current
**** top shell_out| Dload Upload Total
Spent Left Speed
**** top shell_out|\r 0 0 0 0 0 0 0 0 --:--:--
--:--:-- --:--:-- 0\r100 31151 0 31151 0 0 316k 0 --:--:--
--:--:-- --:--:-- 316k\r100 231k 0 231k 0 0 126k 0 --:--:--
0:00:01 --:--:-- 126k\r100 231k 0 231k 0 0 82109 0 --:--:--
0:00:02 --:--:-- 82080\r100 231k 0 231k 0 0 60449 0 --:--:--
0:00:03 --:--:-- 60434\r100 231k 0 231k 0 0 47543 0 --:--:--
0:00:04 --:--:-- 47534\r100 231k 0 231k 0 0 39172 0 --:--:--
0:00:06 --:--:-- 34567\r100 231k 0 231k 0 0 33308 0 --:--:--
0:00:07 --:--:-- 0\r100 231k 0 231k 0 0 28974 0 --:--:--
0:00:08 --:--:-- 0\r100 231k 0 231k 0 0 25730 0 --:--:--
0:00:09 --:--:-- 0\r100 231k 0 231k 0 0 23137 0 --:--:--
0:00:10 --:--:-- 0\r100 231k 0 231k 0 0 21006 0 --:--:--
0:00:11 --:--:-- 0\r100 231k 0 231k 0 0 19195 0 --:--:--
0:00:12 --:--:-- 0\r100 231k 0 231k 0 0 17671 0 --:--:--
0:00:13 --:--:-- 0\r100 231k 0 231k 0 0 16371 0 --:--:--
0:00:14 --:--:-- 0\r100 231k 0 231k 0 0 15745 0 --:--:--
0:00:15 --:--:-- 0
**** top shell_out|curl: (28) Operation timed out after 15034 milliseconds
with 236721 bytes received
**** top shell_out|Expecting checksum 4d9c62aa5370b8d5f84f17ec2e78f483
**** top shell_out|Received checksum: ff274af570a16930f013a23203279cf2
**** top shell_status = 0x0001
---- top shell_exit not as expected: got 0x0001 wanted 0x0000
* top RESETTING after
./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
** h1 Reset and free h1 haproxy 40909
** h1 Wait
** h1 Stop HAproxy pid=40909
**** h1 Kill(2)=0: No error: 0
**** dT 15.296
**** h1 STDOUT EOF
**** dT 15.407
** h1 WAIT4 pid=40909 status=0x0002 (user 0.251095 sys 0.040499)
* top TEST ./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
FAILED
# top TEST ./work/haproxy-08fa16e/reg-tests/compression/lua_validation.vtc
FAILED (15.412) exit=2
HA-Proxy version 2.2-dev0-08fa16e 2020/01/08 - https://haproxy.org/
Status: development branch - not safe for use in production.
Known bugs: https://github.com/haproxy/haproxy/issues?q=is:issue+is:open