Willy,
Am 11.06.19 um 20:53 schrieb Willy Tarreau:
> Hi Tim,
>
> On Tue, Jun 11, 2019 at 08:14:41PM +0200, Tim Düsterhus wrote:
>> Willy,
>>
>> Am 11.06.19 um 20:02 schrieb Willy Tarreau:
>>> I noticed that no regtest failed on Travis since commit 7b3a79f6 which
>>
>> The abns_socket.vtc one is incredibly flaky though. I've noticed two
>> "failed" tests for Travis in in the list of commits today where a single
>> configuration failed to succeed because of this test. I've pushed the
>> button to re-run the failing configurations and they immediately
>> succeeded. We really must find a solution for that one.
>
> Ah that's interesting indeed. I remember seeing it fail very often in
> the past and was almost happy not to see it anymore. Now I understand,
> you're behind the curtains with your crank to restart it :-)
Only today! There's one failed job due to that test from 7 days before,
though: https://travis-ci.com/haproxy/haproxy/jobs/205334815#L529. I've
attached the relevant part of the log for posterity.
I guess if one pushes the "restart" button it will succeed without issue :/
> Yes we definitely need to address this. Either the test is bogus (which
> is always possible) or it reveals a real problem that either we need to
> address or to document if it's a technical limitation, all of which are
> unknown to me at this point.
>
Reading the log I believe this might actually be a real problem within
HAProxy. Note the log lines starting at 370 in the attached file:
line 370: The old worker starts stopping its proxies (due to having
received the SIGUSR1 from the re-executed master)
line 401: The client connects
line 406: The client starts waiting for a response
line 408: The old worker stopped all proxies
line 409: The new worker starts
line 410: The client receives a connection reset
line 411: The old worker exits
So clearly there is no *seamless* reload happening here which is the
very thing the test is testing. The question is: Is the issue with the
'abns' socket or is the issue with the 'fd@${testme}' socket? Can
fd-sockets be seamlessly passed?
In any case this is a bad test, because it is inherently racy.
Best regards
Tim Düsterhus
Test case: reg-tests/seamless-reload/abns_socket.vtc
* top 0.0 TEST reg-tests/seamless-reload/abns_socket.vtc starting
**** top 0.0 extmacro def pwd=/home/travis/build/haproxy/haproxy
**** top 0.0 extmacro def no-htx=
**** top 0.0 extmacro def localhost=127.0.0.1
**** top 0.0 extmacro def bad_backend=127.0.0.1 46119
**** top 0.0 extmacro def bad_ip=192.0.2.255
**** top 0.0 macro def testdir=/home/travis/build/haproxy/haproxy/reg-tests/seamless-reload
**** top 0.0 macro def tmpdir=/tmp/haregtests-2019-06-04_14-58-10.evaaZM/vtc.5888.1e49b2c2
** top 0.0 === varnishtest "Seamless reload issue with abns sockets"
* top 0.0 VTEST Seamless reload issue with abns sockets
** top 0.0 === feature ignore_unknown_macro
** top 0.0 === haproxy h1 -W -conf {
**** h1 0.0 macro def h1_cli_sock=127.0.0.1 35461
**** h1 0.0 macro def h1_cli_addr=127.0.0.1
**** h1 0.0 macro def h1_cli_port=35461
**** h1 0.0 setenv(cli, 4)
**** h1 0.0 macro def h1_testme_sock=127.0.0.1 43423
**** h1 0.0 macro def h1_testme_addr=127.0.0.1
**** h1 0.0 macro def h1_testme_port=43423
**** h1 0.0 setenv(testme, 5)
** h1 0.0 haproxy_start
**** h1 0.0 opt_worker 1 opt_daemon 0 opt_check_mode 0
**** h1 0.0 argv|exec "/home/travis/build/haproxy/haproxy/haproxy" -d -W -f "/tmp/haregtests-2019-06-04_14-58-10.evaaZM/vtc.5888.1e49b2c2/h1/cfg" -p "/tmp/haregtests-2019-06-04_14-58-10.evaaZM/vtc.5888.1e49b2c2/h1/pid"
**** h1 0.0 conf| global
**** h1 0.0 conf|\tstats socket "/tmp/haregtests-2019-06-04_14-58-10.evaaZM/vtc.5888.1e49b2c2/h1/stats.sock" level admin mode 600
**** h1 0.0 conf| stats socket "fd@${cli}" level admin
**** h1 0.0 conf|
**** h1 0.0 conf| global
**** h1 0.0 conf| stats socket "/tmp/haregtests-2019-06-04_14-58-10.evaaZM/vtc.5888.1e49b2c2/h1/stats" level admin expose-fd listeners
**** h1 0.0 conf|
**** h1 0.0 conf| defaults
**** h1 0.0 conf| mode http
**** h1 0.0 conf| option http-use-htx
**** h1 0.0 conf| log global
**** h1 0.0 conf| option httplog
**** h1 0.0 conf| timeout connect 15ms
**** h1 0.0 conf| timeout client 20ms
**** h1 0.0 conf| timeout server 20ms
**** h1 0.0 conf|
**** h1 0.0 conf| listen testme
**** h1 0.0 conf| bind "fd@${testme}"
**** h1 0.0 conf| server test_abns_server abns@wpproc1 send-proxy-v2
**** h1 0.0 conf|
**** h1 0.0 conf| frontend test_abns
**** h1 0.0 conf| bind abns@wpproc1 accept-proxy
**** h1 0.0 conf| http-request deny deny_status 200
**** h1 0.0 XXX 7 @637
*** h1 0.0 PID: 6050
**** h1 0.0 macro def h1_pid=6050
**** h1 0.0 macro def h1_name=/tmp/haregtests-2019-06-04_14-58-10.evaaZM/vtc.5888.1e49b2c2/h1
*** h1 0.0 wait-pid-file
*** h1 0.0 debug|[WARNING] 154/145825 (6050) : config : log format ignored for proxy 'testme' since it has no log address.
*** h1 0.0 debug|[WARNING] 154/145825 (6050) : config : log format ignored for frontend 'test_abns' since it has no log address.
*** h1 0.0 debug|Note: setting global.maxconn to 14984.
*** h1 0.0 debug|Available polling systems :
*** h1 0.0 debug| epoll : pref=300,
*** h1 0.0 debug| test result OK
*** h1 0.0 debug| poll : pref=200, test result OK
*** h1 0.0 debug| select : pref=150,
*** h1 0.0 debug| test result FAILED
*** h1 0.0 debug|Total: 3 (2 usable), will use epoll.
*** h1 0.0 debug|
*** h1 0.0 debug|Available filters :
*** h1 0.0 debug|\t[TRACE] trace
*** h1 0.0 debug|\t[CACHE] cache
*** h1 0.0 debug|\t[COMP] compression
*** h1 0.0 debug|\t[SPOE] spoe
*** h1 0.0 debug|Using epoll() as the polling mechanism.
** h1 0.0 haproxy PID 6050 successfully started
** top 0.0 === shell {
**** top 0.0 shell_cmd|exec 2>&1 ;
**** top 0.0 shell_cmd| kill -USR2 $(cat "/tmp/haregtests-2019-06-04_14-58-10.evaaZM/vtc.5888.1e49b2c2/h1/pid")
*** h1 0.0 debug|[NOTICE] 154/145825 (6050) : New worker #1 (6053) forked
**** top 0.0 shell_status = 0x0000
** top 0.0 === client c1 -connect ${h1_testme_sock} {
** c1 0.0 Starting client
** c1 0.0 Waiting for client
** c1 0.0 Started (50 iterations)
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46666 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|[WARNING] 154/145825 (6050) : Reexecuting Master process
*** h1 0.0 debug|[WARNING] 154/145825 (6050) : config : log format ignored for proxy 'testme' since it has no log address.
*** h1 0.0 debug|[WARNING] 154/145825 (6050) : config : log format ignored for frontend 'test_abns' since it has no log address.
*** h1 0.0 debug|00000000:testme.accept(0005)=0011 from [127.0.0.1:46666] ALPN=<none>
*** h1 0.0 debug|Note: setting global.maxconn to 14984.
*** h1 0.0 debug|00000000:testme.clireq[0011:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000000:testme.clihdr[0011:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|Available polling systems :
*** h1 0.0 debug| epoll :
*** h1 0.0 debug|pref=300,
*** h1 0.0 debug| test result OK
*** h1 0.0 debug|
*** h1 0.0 debug| poll :
*** h1 0.0 debug|pref=200,
*** h1 0.0 debug| test result OK
*** h1 0.0 debug|00000001:test_abns.accept(000b)=0013 from [127.0.0.1:46666] ALPN=<none>
*** h1 0.0 debug|
*** h1 0.0 debug|00000001:test_abns.clireq[0013:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug| select : 00000001:test_abns.clihdr[0013:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|pref=150,
*** h1 0.0 debug| test result FAILED
*** h1 0.0 debug|
*** h1 0.0 debug|Total: 3 (2 usable), will use epoll.
*** h1 0.0 debug|
*** h1 0.0 debug|00000001:test_abns.clicls[0013:ffffffff]
*** h1 0.0 debug|00000001:test_abns.closed[0013:ffffffff]
*** h1 0.0 debug|Available filters :
*** h1 0.0 debug|\t[TRACE] trace
*** h1 0.0 debug|00000000:testme.srvrep[0011:0012]: HTTP/1.0 200 OK
*** h1 0.0 debug|00000000:testme.srvhdr[0011:0012]: cache-control: no-cache
*** h1 0.0 debug|00000000:testme.srvhdr[0011:0012]: content-type: text/html
*** h1 0.0 debug|\t[CACHE] cache
*** h1 0.0 debug|00000000:testme.srvcls[0011:0012]
*** h1 0.0 debug|\t[COMP] compression
*** h1 0.0 debug|\t[SPOE] spoe
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46668 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|00000000:testme.clicls[0011:0012]
*** h1 0.0 debug|00000000:testme.closed[0011:0012]
*** h1 0.0 debug|00000002:testme.accept(0005)=0013 from [127.0.0.1:46668] ALPN=<none>
*** h1 0.0 debug|00000002:testme.clireq[0013:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000002:testme.clihdr[0013:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000003:test_abns.accept(000b)=0012 from [127.0.0.1:46668] ALPN=<none>
*** h1 0.0 debug|00000003:test_abns.clireq[0012:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000003:test_abns.clihdr[0012:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000003:test_abns.clicls[0012:ffffffff]
*** h1 0.0 debug|00000003:test_abns.closed[0012:ffffffff]
*** h1 0.0 debug|00000002:testme.srvrep[0013:0011]: HTTP/1.0 200 OK
*** h1 0.0 debug|00000002:testme.srvhdr[0013:0011]: cache-control: no-cache
*** h1 0.0 debug|00000002:testme.srvhdr[0013:0011]: content-type: text/html
*** h1 0.0 debug|00000002:testme.srvcls[0013:0011]
*** h1 0.0 debug|00000002:testme.clicls[0013:0011]
*** h1 0.0 debug|00000002:testme.closed[0013:0011]
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46670 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|00000004:testme.accept(0005)=0011 from [127.0.0.1:46670] ALPN=<none>
*** h1 0.0 debug|00000004:testme.clireq[0011:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000004:testme.clihdr[0011:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000005:test_abns.accept(000b)=0013 from [127.0.0.1:46670] ALPN=<none>
*** h1 0.0 debug|00000005:test_abns.clireq[0013:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000005:test_abns.clihdr[0013:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000005:test_abns.clicls[0013:ffffffff]
*** h1 0.0 debug|00000005:test_abns.closed[0013:ffffffff]
*** h1 0.0 debug|00000004:testme.srvrep[0011:0012]: HTTP/1.0 200 OK
*** h1 0.0 debug|00000004:testme.srvhdr[0011:0012]: cache-control: no-cache
*** h1 0.0 debug|00000004:testme.srvhdr[0011:0012]: content-type: text/html
*** h1 0.0 debug|00000004:testme.srvcls[0011:0012]
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46672 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|00000006:testme.accept(0005)=0013 from [127.0.0.1:46672] ALPN=<none>
*** h1 0.0 debug|00000004:testme.clicls[0011:0012]
*** h1 0.0 debug|00000004:testme.closed[0011:0012]
*** h1 0.0 debug|00000006:testme.clireq[0013:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000006:testme.clihdr[0013:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000007:test_abns.accept(000b)=0012 from [127.0.0.1:46672] ALPN=<none>
*** h1 0.0 debug|00000007:test_abns.clireq[0012:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000007:test_abns.clihdr[0012:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000007:test_abns.clicls[0012:ffffffff]
*** h1 0.0 debug|00000007:test_abns.closed[0012:ffffffff]
*** h1 0.0 debug|00000006:testme.srvrep[0013:0011]: HTTP/1.0 200 OK
*** h1 0.0 debug|00000006:testme.srvhdr[0013:0011]: cache-control: no-cache
*** h1 0.0 debug|00000006:testme.srvhdr[0013:0011]: content-type: text/html
*** h1 0.0 debug|00000006:testme.srvcls[0013:0011]
*** h1 0.0 debug|00000006:testme.clicls[0013:0011]
*** h1 0.0 debug|00000006:testme.closed[0013:0011]
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46674 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|Using epoll() as the polling mechanism.
*** h1 0.0 debug|00000008:testme.accept(0005)=0011 from [127.0.0.1:46674] ALPN=<none>
*** h1 0.0 debug|00000008:testme.clireq[0011:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000008:testme.clihdr[0011:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000009:test_abns.accept(000b)=0013 from [127.0.0.1:46674] ALPN=<none>
*** h1 0.0 debug|00000009:test_abns.clireq[0013:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000009:test_abns.clihdr[0013:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|00000009:test_abns.clicls[0013:ffffffff]
*** h1 0.0 debug|00000009:test_abns.closed[0013:ffffffff]
*** h1 0.0 debug|00000008:testme.srvrep[0011:0012]: HTTP/1.0 200 OK
*** h1 0.0 debug|00000008:testme.srvhdr[0011:0012]: cache-control: no-cache
*** h1 0.0 debug|00000008:testme.srvhdr[0011:0012]: content-type: text/html
*** h1 0.0 debug|00000008:testme.srvcls[0011:0012]
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46676 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|0000000a:testme.accept(0005)=0013 from [127.0.0.1:46676] ALPN=<none>
*** h1 0.0 debug|00000008:testme.clicls[0011:0012]
*** h1 0.0 debug|00000008:testme.closed[0011:0012]
*** h1 0.0 debug|0000000a:testme.clireq[0013:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|0000000a:testme.clihdr[0013:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|0000000b:test_abns.accept(000b)=0012 from [127.0.0.1:46676] ALPN=<none>
*** h1 0.0 debug|0000000b:test_abns.clireq[0012:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|0000000b:test_abns.clihdr[0012:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|0000000b:test_abns.clicls[0012:ffffffff]
*** h1 0.0 debug|0000000b:test_abns.closed[0012:ffffffff]
*** h1 0.0 debug|0000000a:testme.srvrep[0013:0011]: HTTP/1.0 200 OK
*** h1 0.0 debug|0000000a:testme.srvhdr[0013:0011]: cache-control: no-cache
*** h1 0.0 debug|0000000a:testme.srvhdr[0013:0011]: content-type: text/html
*** h1 0.0 debug|0000000a:testme.srvcls[0013:0011]
*** h1 0.0 debug|0000000a:testme.clicls[0013:0011]
*** h1 0.0 debug|0000000a:testme.closed[0013:0011]
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46678 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|0000000c:testme.accept(0005)=0012 from [127.0.0.1:46678] ALPN=<none>
*** h1 0.0 debug|0000000d:GLOBAL.accept(000a)=0011 from [unix:3] ALPN=<none>
*** h1 0.0 debug|0000000c:testme.clireq[0012:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|0000000c:testme.clihdr[0012:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|0000000d:GLOBAL.srvcls[adfd:ffffffff]
*** h1 0.0 debug|0000000d:GLOBAL.clicls[adfd:ffffffff]
*** h1 0.0 debug|0000000d:GLOBAL.closed[adfd:ffffffff]
*** h1 0.0 debug|0000000e:test_abns.accept(000b)=0014 from [127.0.0.1:46678] ALPN=<none>
*** h1 0.0 debug|0000000e:test_abns.clireq[0014:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|0000000e:test_abns.clihdr[0014:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|0000000e:test_abns.clicls[0014:ffffffff]
*** h1 0.0 debug|0000000e:test_abns.closed[0014:ffffffff]
*** h1 0.0 debug|0000000c:testme.srvrep[0012:0013]: HTTP/1.0 200 OK
*** h1 0.0 debug|0000000c:testme.srvhdr[0012:0013]: cache-control: no-cache
*** h1 0.0 debug|0000000c:testme.srvhdr[0012:0013]: content-type: text/html
*** h1 0.0 debug|0000000c:testme.srvcls[0012:0013]
*** h1 0.0 debug|0000000c:testme.clicls[0012:0013]
*** h1 0.0 debug|0000000c:testme.closed[0012:0013]
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46680 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|0000000f:testme.accept(0005)=0011 from [127.0.0.1:46680] ALPN=<none>
*** h1 0.0 debug|0000000f:testme.clireq[0011:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|0000000f:testme.clihdr[0011:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|[WARNING] 154/145825 (6053) : Stopping frontend GLOBAL in 0 ms.
*** h1 0.0 debug|00000010:test_abns.accept(000b)=0013 from [127.0.0.1:46680] ALPN=<none>
*** h1 0.0 debug|00000010:test_abns.clireq[0013:ffffffff]: GET / HTTP/1.1
*** h1 0.0 debug|00000010:test_abns.clihdr[0013:ffffffff]: host: 127.0.0.1
*** h1 0.0 debug|[WARNING] 154/145825 (6053) : Stopping proxy testme in 0 ms.
*** h1 0.0 debug|[WARNING] 154/145825 (6053) : Stopping frontend test_abns in 0 ms.
*** h1 0.0 debug|00000010:test_abns.clicls[0013:ffffffff]
*** h1 0.0 debug|00000010:test_abns.closed[0013:ffffffff]
*** h1 0.0 debug|[WARNING] 154/145825 (6053) : Proxy GLOBAL stopped (FE: 1 conns, BE: 1 conns).
*** h1 0.0 debug|0000000f:testme.srvrep[0011:0012]: HTTP/1.0 200 OK
*** h1 0.0 debug|0000000f:testme.srvhdr[0011:0012]: cache-control: no-cache
*** h1 0.0 debug|0000000f:testme.srvhdr[0011:0012]: content-type: text/html
*** h1 0.0 debug|0000000f:testme.srvcls[0011:0012]
*** h1 0.0 debug|0000000f:testme.clicls[0011:0012]
*** h1 0.0 debug|0000000f:testme.closed[0011:0012]
**** c1 0.0 rxhdr|HTTP/1.0 200 OK\r
**** c1 0.0 rxhdr|cache-control: no-cache\r
**** c1 0.0 rxhdr|content-type: text/html\r
**** c1 0.0 rxhdr|\r
**** c1 0.0 rxhdrlen = 69
**** c1 0.0 http[ 0] |HTTP/1.0
**** c1 0.0 http[ 1] |200
**** c1 0.0 http[ 2] |OK
**** c1 0.0 http[ 3] |cache-control: no-cache
**** c1 0.0 http[ 4] |content-type: text/html
**** c1 0.0 eof|<html><body><h1>200 OK</h1>
**** c1 0.0 eof|Service ready.
**** c1 0.0 eof|</body></html>
**** c1 0.0 bodylen = 58
*** c1 0.0 closing fd 6
*** c1 0.0 Connect to 127.0.0.1 43423
*** c1 0.0 connected fd 6 from 127.0.0.1 46682 to 127.0.0.1 43423
** c1 0.0 === txreq -url "/"
**** c1 0.0 txreq|GET / HTTP/1.1\r
**** c1 0.0 txreq|Host: 127.0.0.1\r
**** c1 0.0 txreq|\r
** c1 0.0 === rxresp
*** h1 0.0 debug|[WARNING] 154/145825 (6053) : Proxy testme stopped (FE: 8 conns, BE: 8 conns).
*** h1 0.0 debug|[WARNING] 154/145825 (6053) : Proxy test_abns stopped (FE: 8 conns, BE: 0 conns).
*** h1 0.0 debug|[NOTICE] 154/145825 (6050) : New worker #1 (6057) forked
---- c1 1.1 HTTP rx failed (fd:6 read: Connection reset by peer)
*** h1 1.1 debug|[WARNING] 154/145826 (6050) : Former worker #1 (6053) exited with code 0 (Exit)
* top 1.1 RESETTING after reg-tests/seamless-reload/abns_socket.vtc
** h1 1.1 Reset and free h1 haproxy 6050
** h1 1.1 Wait
** h1 1.1 Stop HAproxy pid=6050
**** h1 1.1 Kill(2)=0: Success
*** h1 1.1 debug|[WARNING] 154/145826 (6050) : Exiting Master process...
*** h1 1.1 debug|[ALERT] 154/145826 (6050) : Current worker #1 (6057) exited with code 130 (Interrupt)
*** h1 1.1 debug|[WARNING] 154/145826 (6050) : All workers exited. Exiting... (130)
**** h1 1.1 STDOUT poll 0x10
** h1 1.2 WAIT4 pid=6050 status=0x8200 (user 0.039059 sys 0.055398)
* top 1.2 TEST reg-tests/seamless-reload/abns_socket.vtc FAILED
