diff --git a/testresults.xml b/testresults.xml index 1c5a54a..80f05ef 100644 --- a/testresults.xml +++ b/testresults.xml @@ -1,4702 +1,382 @@ - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - + name="log/vcp6-tests/" + tests="32" + failures="3" + skipped="10" + > - - - - + name="02-install-vcp-redhat.sh" + classname="log/vcp6-tests/.02-install-vcp-redhat.sh" - - - - - - - - - - - - - - + name="03-install-vmods-redhat.sh" + classname="log/vcp6-tests/.03-install-vmods-redhat.sh" - - - - - - - - + name="04-install-vcs-redhat.sh" + classname="log/vcp6-tests/.04-install-vcs-redhat.sh" - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - + name="05-install-varnish-agent-redhat.sh" + classname="log/vcp6-tests/.05-install-varnish-agent-redhat.sh" - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - > - - - - - > - - > - - - > - +**** top 0.0 extmacro def pwd=/vagrant +**** top 0.0 extmacro def localhost=127.0.0.1 +**** top 0.0 extmacro def bad_backend=127.0.0.1 40529 +**** top 0.0 extmacro def bad_ip=192.0.2.255 +**** top 0.0 macro def testdir=//vagrant/vcp6-tests/vtc +**** top 0.0 macro def tmpdir=/tmp/vtc.15127.2fc17a50 +* top 0.0 TEST /vagrant/vcp6-tests/vtc/01-all-vmods.vtc starting +** top 0.0 === varnishtest "Verify that Varnish starts with all VMODs impor... +* top 0.0 TEST Verify that Varnish starts with all VMODs imported +** top 0.0 === server s1 { +** s1 0.0 Starting server +**** s1 0.0 macro def s1_addr=127.0.0.1 +**** s1 0.0 macro def s1_port=33331 +**** s1 0.0 macro def s1_sock=127.0.0.1 33331 +* s1 0.0 Listen on 127.0.0.1 33331 +** top 0.0 === varnish v1 -vcl+backend { +** s1 0.0 Started on 127.0.0.1 33331 +** v1 0.0 Launch +*** v1 0.0 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtc.15127.2fc17a50/v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p sigsegv_handler=on -p thread_pool_min=10 -p debug=+vtc_mode -a '127.0.0.1:0' -M '127.0.0.1 40091' -P /tmp/vtc.15127.2fc17a50/v1/varnishd.pid +*** v1 0.0 CMD: cd /vagrant && exec varnishd -d -n /tmp/vtc.15127.2fc17a50/v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p sigsegv_handler=on -p thread_pool_min=10 -p debug=+vtc_mode -a '127.0.0.1:0' -M '127.0.0.1 40091' -P /tmp/vtc.15127.2fc17a50/v1/varnishd.pid +*** v1 0.0 PID: 15133 +**** v1 0.0 macro def v1_pid=15133 +**** v1 0.0 macro def v1_name=/tmp/vtc.15127.2fc17a50/v1 +*** v1 0.0 debug|Debug: Version: varnish-plus-6.0.1r5 revision 87748e9eb3054a0395e8d0df6e06306b5782a089 +*** v1 0.0 debug|Debug: Platform: Linux,4.15.0-20-generic,x86_64,-junix,-sdefault,-sdefault,-hcritbit +*** v1 0.0 debug|200 325 +*** v1 0.0 debug|----------------------------- +*** v1 0.0 debug|Varnish Cache CLI 1.0 +*** v1 0.0 debug|----------------------------- +*** v1 0.0 debug|Linux,4.15.0-20-generic,x86_64,-junix,-sdefault,-sdefault,-hcritbit +*** v1 0.0 debug|varnish-plus-6.0.1r5 revision 87748e9eb3054a0395e8d0df6e06306b5782a089 +*** v1 0.0 debug| +*** v1 0.0 debug|Type 'help' for command list. +*** v1 0.0 debug|Type 'quit' to close CLI session. +*** v1 0.0 debug|Type 'start' to launch worker process. +*** v1 0.0 debug| +**** v1 0.1 CLIPOLL 1 0x1 0x0 +*** v1 0.1 CLI connection fd = 7 +*** v1 0.1 CLI RX 107 +**** v1 0.1 CLI RX|axiesfjrwtfjfsnumyjmltdlnnojqhbv +**** v1 0.1 CLI RX| +**** v1 0.1 CLI RX|Authentication required. +**** v1 0.1 CLI TX|auth 6a93a66bfbbce3eb8e643447d69d8921360e7bc735eb12e4851d9128ae0429c5 +*** v1 0.1 CLI RX 200 +**** v1 0.1 CLI RX|----------------------------- +**** v1 0.1 CLI RX|Varnish Cache CLI 1.0 +**** v1 0.1 CLI RX|----------------------------- +**** v1 0.1 CLI RX|Linux,4.15.0-20-generic,x86_64,-junix,-sdefault,-sdefault,-hcritbit +**** v1 0.1 CLI RX|varnish-plus-6.0.1r5 revision 87748e9eb3054a0395e8d0df6e06306b5782a089 +**** v1 0.1 CLI RX| +**** v1 0.1 CLI RX|Type 'help' for command list. +**** v1 0.1 CLI RX|Type 'quit' to close CLI session. +**** v1 0.1 CLI RX|Type 'start' to launch worker process. +**** v1 0.1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P +**** v1 0.1 CLI TX|vcl 4.1; +**** v1 0.1 CLI TX|backend s1 { .host = "127.0.0.1"; .port = "33331"; } +**** v1 0.1 CLI TX| +**** v1 0.1 CLI TX| +**** v1 0.1 CLI TX|\t# This list needs to be updated whenever new VMODs are added +**** v1 0.1 CLI TX|\t#import acl; +**** v1 0.1 CLI TX|\timport aclplus; +**** v1 0.1 CLI TX|\timport akamai; +**** v1 0.1 CLI TX|\timport deviceatlas; +**** v1 0.1 CLI TX|\timport bodyaccess; +**** v1 0.1 CLI TX|\timport cookieplus; +**** v1 0.1 CLI TX|\timport cookie; +**** v1 0.1 CLI TX|\timport curl; +**** v1 0.1 CLI TX|\timport digest; +**** v1 0.1 CLI TX|\timport directors; +**** v1 0.1 CLI TX|\timport edgestash; +**** v1 0.1 CLI TX|\timport geoip; +**** v1 0.1 CLI TX|\timport goto; +**** v1 0.1 CLI TX|\timport header; +**** v1 0.1 CLI TX|\timport http; +**** v1 0.1 CLI TX|\timport json; +**** v1 0.1 CLI TX|\timport kvstore; +**** v1 0.1 CLI TX|\timport leastconn; +**** v1 0.1 CLI TX|\timport memcached; +**** v1 0.1 CLI TX|\t#import named; +**** v1 0.1 CLI TX|\timport paywall; +**** v1 0.1 CLI TX|\timport rewrite; +**** v1 0.1 CLI TX|\timport rtstatus; +**** v1 0.1 CLI TX|\timport saintmode; +**** v1 0.1 CLI TX|\timport session; +**** v1 0.1 CLI TX|\t#import softpurge; +**** v1 0.1 CLI TX|\t#import sqlite3; +**** v1 0.1 CLI TX|\timport std; +**** v1 0.1 CLI TX|\timport tcp; +**** v1 0.1 CLI TX|\timport var; +**** v1 0.1 CLI TX|\timport vha; +**** v1 0.1 CLI TX|\timport vsthrottle; +**** v1 0.1 CLI TX|\timport xkey; +**** v1 0.1 CLI TX| +**** v1 0.1 CLI TX|%XJEIFLH|)Xspa8P +*** v1 0.1 CLI RX 106 +**** v1 0.1 CLI RX|Message from VCC-compiler: +**** v1 0.1 CLI RX|Incompatible VMOD akamai +**** v1 0.1 CLI RX|\tFile name: /usr/lib/varnish-plus/vmods/libvmod_akamai.so +**** v1 0.1 CLI RX|\tABI mismatch, expected , got +**** v1 0.1 CLI RX|('' Line 8 Pos 16) +**** v1 0.1 CLI RX| import akamai; +**** v1 0.1 CLI RX|---------------######- +**** v1 0.1 CLI RX| +**** v1 0.1 CLI RX|Running VCC-compiler failed, exited with 2 +**** v1 0.1 CLI RX|VCL compilation failed +---- v1 0.1 FAIL VCL does not compile +* top 0.1 RESETTING after /vagrant/vcp6-tests/vtc/01-all-vmods.vtc +** s1 0.1 Waiting for server (4/-1) +** v1 0.1 Wait +**** v1 0.1 CLI TX|panic.clear +*** v1 0.2 CLI RX 300 +**** v1 0.2 CLI RX|No panic to clear +*** v1 0.2 debug|Info: manager stopping child +*** v1 0.2 debug|Info: manager dies +**** v1 0.2 STDOUT poll 0x10 +*** v1 0.2 vsl|No VSL chunk found (child not started ?) +** v1 0.2 WAIT4 pid=15133 status=0x0000 (user 0.003926 sys 0.022808) +* top 0.2 TEST /vagrant/vcp6-tests/vtc/01-all-vmods.vtc FAILED +# top TEST /vagrant/vcp6-tests/vtc/01-all-vmods.vtc FAILED (0.214) exit=2 + > - - - > - +INFO: systemctl found, running systemd test (ubuntu bionic) +Generating new nodefile /etc/varnish/nodes.conf (2019-01-09 15:00:50.62660743 +0000 UTC m=+0.002414597) +# /lib/systemd/system/vha-agent.service +[Unit] +Description=Varnish High Availability Agent. +After=varnish.service +Requisite=varnish.service + +[Service] +ExecStart=/usr/bin/vha-agent -D -t off -P /run/vha-agent/vha-agent.pid -N /etc/varnish/nodes.conf -s /run/vha-agent/vha-status -T TOKEN +ExecReload=/bin/kill -HUP $MAINPID +PIDFile=/run/vha-agent/vha-agent.pid +User=varnish +Group=varnish +RuntimeDirectory=vha-agent +RuntimeDirectoryPreserve=yes +Type=forking + +[Install] +WantedBy=multi-user.target + +# /etc/systemd/system/vha-agent.service.d/exec.conf +[Service] +ExecStart= +ExecStart=/usr/bin/vha-agent -D -t off -P /run/vha-agent/vha-agent.pid -N /etc/varnish/nodes.conf -s /run/vha-agent/vha-status -T qa-test -p stat_intvl=1 +PONG 1547046050 1.0 +Unit varnish-discovery.service could not be found. +Failed to restart varnish-discovery.service: Unit varnish-discovery.service not found. + > - - +INFO: Running on ubuntu +Ign:1 https://repo.mongodb.org/apt/ubuntu bionic/mongodb-org/4.0 InRelease +Hit:2 https://repo.mongodb.org/apt/ubuntu bionic/mongodb-org/4.0 Release +Hit:3 http://security.ubuntu.com/ubuntu bionic-security InRelease +Hit:5 http://us.archive.ubuntu.com/ubuntu bionic InRelease +Hit:6 http://us.archive.ubuntu.com/ubuntu bionic-updates InRelease +Hit:7 http://us.archive.ubuntu.com/ubuntu bionic-backports InRelease +Hit:8 https://packagecloud.io/varnishplus/60-staging/ubuntu bionic InRelease +Hit:9 https://packagecloud.io/varnishplus/60-akamai-staging/ubuntu bionic InRelease +Hit:10 https://packagecloud.io/varnishplus/60-deviceatlas-staging/ubuntu bionic InRelease +Hit:11 https://packagecloud.io/varnishplus/60/ubuntu bionic InRelease +Hit:12 https://packagecloud.io/varnishplus/vagrant-qa-backup/ubuntu bionic InRelease +Reading package lists... +resource-agents - Cluster Resource Agents +libvarnishapi-dev - development files for Varnish +libvarnishapi1 - shared libraries for Varnish +nagios-plugins-contrib - Plugins for nagios compatible monitoring systems +prometheus-varnish-exporter - Prometheus exporter for Varnish +varnish - state of the art, high-performance web accelerator +varnish-doc - documentation for Varnish Cache +varnish-modules - Varnish module collection +varnish-agent - Varnish Agent is a small daemon meant to communicate with +varnish-broadcaster - The Varnish Broadcaster broadcasts requests to multiple Varnish caches +varnish-custom-statistics - Varnish Custom Statistics aggregating daemon. +varnish-custom-statistics-agent - Varnish Custom Statistics agent. +varnish-plus - A supercharged version of the popular web cache, Varnish Cache +varnish-plus-dev - development files for Varnish Cache +varnish-plus-ha - Simple replication of object insertions in a Varnish Cache Plus server. +varnish-plus-vmods-extra - Extra package of Varnish Modules (VMODs) for Varnish Cache Plus. +varnish-plus-akamai-connector - Varnish Akamai Connector +libvmod-deviceatlas - DeviceAtlas VMOD for Varnish +vac - Varnish Administration Console (VAC) is a management console for clusters of Varnish web caches. +varnish-plus-addon-ssl - Hitch TLS proxy +varnish-plus-discovery - Creates and updates nodes.conf files, used notably by vha-agent +Reading package lists... +Building dependency tree... +Reading state information... +The following NEW packages will be installed: + varnish-broadcaster +0 upgraded, 1 newly installed, 0 to remove and 222 not upgraded. +Need to get 2,717 kB of archives. +After this operation, 7,883 kB of additional disk space will be used. +Get:1 https://packagecloud.io/varnishplus/60-staging/ubuntu bionic/main amd64 varnish-broadcaster amd64 1.2.0-22~bionic [2,717 kB] +Fetched 2,717 kB in 3s (851 kB/s) +Selecting previously unselected package varnish-broadcaster. +(Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 106549 files and directories currently installed.) +Preparing to unpack .../varnish-broadcaster_1.2.0-22~bionic_amd64.deb ... +Unpacking varnish-broadcaster (1.2.0-22~bionic) ... +Processing triggers for ureadahead (0.100.0-20) ... +Setting up varnish-broadcaster (1.2.0-22~bionic) ... +Adding system user `vbcast' (UID 113) ... +Adding new group `vbcast' (GID 117) ... +Adding new user `vbcast' (UID 113) with group `vbcast' ... +Not creating home directory `/'. +Created symlink /etc/systemd/system/multi-user.target.wants/broadcaster.service → /lib/systemd/system/broadcaster.service. +A dependency job for broadcaster.service failed. See 'journalctl -xe' for details. +invoke-rc.d: initscript broadcaster, action "start" failed. +● broadcaster.service - Varnish Broadcaster + Loaded: loaded (/lib/systemd/system/broadcaster.service; enabled; vendor preset: enabled) + Active: inactive (dead) + +Jan 09 15:01:15 ubuntu-18 systemd[1]: Dependency failed for Varnish Broadcaster. +Jan 09 15:01:15 ubuntu-18 systemd[1]: broadcaster.service: Job broadcaster.service/start failed with result 'dependency'. +dpkg: error processing package varnish-broadcaster (--configure): + installed varnish-broadcaster package post-installation script subprocess returned error exit status 1 +Processing triggers for systemd (237-3ubuntu10) ... +Processing triggers for ureadahead (0.100.0-20) ... +Errors were encountered while processing: + varnish-broadcaster +E: Sub-process /usr/bin/dpkg returned an error code (1) + > - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - + name="30-install-broadcaster-redhat.sh" + classname="log/vcp6-tests/.30-install-broadcaster-redhat.sh" - - - - - - - - - - - - - - - - - -**** top 0.0 extmacro def pwd=/home/dmatetelki/p/varnish-cache-plus/bin/varnishtest -**** top 0.0 extmacro def topsrc=/home/dmatetelki/p/varnish-cache-plus -**** top 0.0 extmacro def localhost=127.0.0.1 -**** top 0.0 extmacro def bad_backend=127.0.0.1 43897 -**** top 0.0 extmacro def bad_ip=192.0.2.255 -**** top 0.0 extmacro def topbuild=/home/dmatetelki/p/varnish-cache-plus -**** top 0.0 macro def testdir=/home/dmatetelki/p/varnish-cache-plus/bin/varnishtest/./tests -**** top 0.0 macro def tmpdir=/tmp/vtc.14817.4cbfd8c0 -* top 0.0 TEST ./tests/c00094.vtc starting -** top 0.0 === varnishtest 'Test Backend Polling with a backend listening a... -* top 0.0 TEST Test Backend Polling with a backend listening at a UDS -** top 0.0 === barrier b1 cond 2 -** top 0.0 === server s1 -listen '${tmpdir}/s1.sock' { -** s1 0.0 Starting server -**** s1 0.0 macro def s1_addr=0.0.0.0 -**** s1 0.0 macro def s1_port=0 -**** s1 0.0 macro def s1_sock=/tmp/vtc.14817.4cbfd8c0/s1.sock -* s1 0.0 Listen on /tmp/vtc.14817.4cbfd8c0/s1.sock -** top 0.0 === varnish v1 -vcl { -** s1 0.0 Started on /tmp/vtc.14817.4cbfd8c0/s1.sock -** v1 0.0 Launch -*** v1 0.0 CMD: cd ${pwd} ANDAND exec varnishd -d -n /tmp/vtc.14817.4cbfd8c0/v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p sigsegv_handler=on -p thread_pool_min=10 -p debug=+vtc_mode -a '127.0.0.1:0' -M '127.0.0.1 34891' -P /tmp/vtc.14817.4cbfd8c0/v1/varnishd.pid -p vmod_path=:/home/dmatetelki/p/varnish-cache-plus/lib/varnish-modules/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_accept/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_aclplus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_blob/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_cookieplus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_crypto/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_curl/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_debug/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_digest/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_directors/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_edgestash/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_geoip/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_goto/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_http/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_kvstore/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_leastconn/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_memcached/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_paywall/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_purge/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_rewrite/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_rtstatus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_session/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_std/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_synthbackend/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_urlplus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_vha/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_vtc/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_xbody/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_blob/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_unix/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_proxy/.libs -*** v1 0.0 CMD: cd /home/dmatetelki/p/varnish-cache-plus/bin/varnishtest ANDAND exec varnishd -d -n /tmp/vtc.14817.4cbfd8c0/v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p sigsegv_handler=on -p thread_pool_min=10 -p debug=+vtc_mode -a '127.0.0.1:0' -M '127.0.0.1 34891' -P /tmp/vtc.14817.4cbfd8c0/v1/varnishd.pid -p vmod_path=:/home/dmatetelki/p/varnish-cache-plus/lib/varnish-modules/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_accept/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_aclplus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_blob/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_cookieplus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_crypto/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_curl/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_debug/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_digest/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_directors/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_edgestash/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_geoip/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_goto/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_http/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_kvstore/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_leastconn/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_memcached/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_paywall/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_purge/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_rewrite/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_rtstatus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_session/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_std/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_synthbackend/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_urlplus/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_vha/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_vtc/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_xbody/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_blob/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_unix/.libs:/home/dmatetelki/p/varnish-cache-plus/lib/libvmod_proxy/.libs -*** v1 0.0 PID: 14858 -**** v1 0.0 macro def v1_pid=14858 -**** v1 0.0 macro def v1_name=/tmp/vtc.14817.4cbfd8c0/v1 -*** v1 0.1 debug|Debug: Version: varnish-plus-6.0.1r4 revision a6d0c534e1c26bb7e59614049fbe1a8c1b1f1082 -*** v1 0.1 debug|Debug: Platform: Linux,4.15.0-30-generic,x86_64,-jnone,-sdefault,-sdefault,-hcritbit -*** v1 0.1 debug|200 325 -*** v1 0.1 debug|----------------------------- -*** v1 0.1 debug|Varnish Cache CLI 1.0 -*** v1 0.1 debug|----------------------------- -*** v1 0.1 debug|Linux,4.15.0-30-generic,x86_64,-jnone,-sdefault,-sdefault,-hcritbit -*** v1 0.1 debug|varnish-plus-6.0.1r4 revision a6d0c534e1c26bb7e59614049fbe1a8c1b1f1082 -*** v1 0.1 debug| -*** v1 0.1 debug|Type 'help' for command list. -*** v1 0.1 debug|Type 'quit' to close CLI session. -*** v1 0.1 debug|Type 'start' to launch worker process. -*** v1 0.1 debug| -**** v1 0.2 CLIPOLL 1 0x1 0x0 -*** v1 0.2 CLI connection fd = 7 -*** v1 0.2 CLI RX 107 -**** v1 0.2 CLI RX|bkbfrxjhqxkjmucrmkfrzzukshjleucp -**** v1 0.2 CLI RX| -**** v1 0.2 CLI RX|Authentication required. -**** v1 0.2 CLI TX|auth 8458c17ec0bb13b44f70e2d0f5dd5d4d49fff5dbff9b50211f59b792ccefd85f -*** v1 0.2 CLI RX 200 -**** v1 0.2 CLI RX|----------------------------- -**** v1 0.2 CLI RX|Varnish Cache CLI 1.0 -**** v1 0.2 CLI RX|----------------------------- -**** v1 0.2 CLI RX|Linux,4.15.0-30-generic,x86_64,-jnone,-sdefault,-sdefault,-hcritbit -**** v1 0.2 CLI RX|varnish-plus-6.0.1r4 revision a6d0c534e1c26bb7e59614049fbe1a8c1b1f1082 -**** v1 0.2 CLI RX| -**** v1 0.2 CLI RX|Type 'help' for command list. -**** v1 0.2 CLI RX|Type 'quit' to close CLI session. -**** v1 0.2 CLI RX|Type 'start' to launch worker process. -**** v1 0.2 CLI TX|vcl.inline vcl1 LESSLESS %XJEIFLH|)Xspa8P -**** v1 0.2 CLI TX|vcl 4.1; -**** v1 0.2 CLI TX| -**** v1 0.2 CLI TX| -**** v1 0.2 CLI TX|\tbackend foo { -**** v1 0.2 CLI TX|\t\t.path = '/tmp/vtc.14817.4cbfd8c0/s1.sock'; -**** v1 0.2 CLI TX|\t\t.probe = { -**** v1 0.2 CLI TX|\t\t\t.timeout = 1 s; -**** v1 0.2 CLI TX|\t\t\t.interval = 0.1 s; -**** v1 0.2 CLI TX|\t\t} -**** v1 0.2 CLI TX|\t} -**** v1 0.2 CLI TX| -**** v1 0.2 CLI TX| -**** v1 0.2 CLI TX|%XJEIFLH|)Xspa8P -*** v1 0.3 vsl|No VSL chunk found (child not started ?) -*** v1 0.4 vsl|No VSL chunk found (child not started ?) -*** v1 0.4 CLI RX 200 -**** v1 0.4 CLI RX|VCL compiled. -**** v1 0.4 CLI TX|vcl.use vcl1 -*** v1 0.4 CLI RX 200 -** v1 0.4 Start -**** v1 0.4 CLI TX|start -*** v1 0.5 debug|Debug: Child (15126) Started -*** v1 0.5 debug|Info: Child (15126) said Child starts -*** v1 0.5 CLI RX 200 -*** v1 0.5 wait-running -**** v1 0.5 CLI TX|status -*** s1 0.5 accepted fd 5 0.0.0.0 0 -** s1 0.5 === loop 8 { -**** s1 0.5 Loop #1 -** s1 0.5 === rxreq -**** s1 0.5 rxhdr|GET / HTTP/1.1\r -**** s1 0.5 rxhdr|Host: 0.0.0.0\r -**** s1 0.5 rxhdr|Connection: close\r -**** s1 0.5 rxhdr|\r -**** s1 0.5 rxhdrlen = 52 -**** s1 0.5 http[ 0] |GET -**** s1 0.5 http[ 1] |/ -**** s1 0.5 http[ 2] |HTTP/1.1 -**** s1 0.5 http[ 3] |Host: 0.0.0.0 -**** s1 0.5 http[ 4] |Connection: close -**** s1 0.5 bodylen = 0 -** s1 0.5 === expect req.url == '/' -**** s1 0.5 EXPECT req.url (/) == '/' match -** s1 0.5 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 0.5 txresp|HTTP/1.1 200 OK\r -**** s1 0.5 txresp|Bar: foo\r -**** s1 0.5 txresp|Content-Length: 6\r -**** s1 0.5 txresp|\r -**** s1 0.5 txresp|foobar -** s1 0.5 === accept -**** s1 0.5 Accepting -*** v1 0.5 CLI RX 200 -**** v1 0.5 CLI RX|Child in state running -**** v1 0.5 CLI TX|debug.listen_address -*** v1 0.6 CLI RX 200 -**** v1 0.6 CLI RX|127.0.0.1 46857 -**** v1 0.6 CLI TX|debug.xid 999 -**** v1 0.6 vsl| 0 CLI - Rd vcl.load 'vcl1' vcl_vcl1.1546519298.832932949/vgc.so 1auto -**** v1 0.6 vsl| 0 Backend_health - (null) Went sick -------H 2 3 8 0.000000 0.000000 -**** v1 0.6 vsl| 0 Backend_health - vcl1.foo Still sick -------H 2 3 8 0.000000 0.000000 -**** v1 0.6 vsl| 0 CLI - Wr 200 55 Loaded 'vcl_vcl1.1546519298.832932949/vgc.so' as 'vcl1' -**** v1 0.6 vsl| 0 CLI - Rd vcl.use 'vcl1' -**** v1 0.6 vsl| 0 CLI - Wr 200 0 -**** v1 0.6 vsl| 0 CLI - Rd start -**** v1 0.6 vsl| 0 CLI - Wr 200 0 -**** v1 0.6 vsl| 0 Backend_health - vcl1.foo Back healthy --U-X-RH 3 3 8 0.000382 0.000127 HTTP/1.1 200 OK -**** v1 0.6 vsl| 0 CLI - Rd debug.listen_address -**** v1 0.6 vsl| 0 CLI - Wr 200 16 127.0.0.1 46857 - -*** s1 0.6 Accepted socket fd is 5 -**** s1 0.6 Loop #2 -** s1 0.6 === rxreq -**** s1 0.6 rxhdr|GET / HTTP/1.1\r -**** s1 0.6 rxhdr|Host: 0.0.0.0\r -**** s1 0.6 rxhdr|Connection: close\r -**** s1 0.6 rxhdr|\r -**** s1 0.6 rxhdrlen = 52 -**** s1 0.6 http[ 0] |GET -**** s1 0.6 http[ 1] |/ -**** s1 0.6 http[ 2] |HTTP/1.1 -**** s1 0.6 http[ 3] |Host: 0.0.0.0 -**** s1 0.6 http[ 4] |Connection: close -**** s1 0.6 bodylen = 0 -** s1 0.6 === expect req.url == '/' -**** s1 0.6 EXPECT req.url (/) == '/' match -** s1 0.6 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 0.6 txresp|HTTP/1.1 200 OK\r -**** s1 0.6 txresp|Bar: foo\r -**** s1 0.6 txresp|Content-Length: 6\r -**** s1 0.6 txresp|\r -**** s1 0.6 txresp|foobar -** s1 0.6 === accept -**** s1 0.6 Accepting -*** v1 0.6 CLI RX 200 -**** v1 0.6 CLI RX|XID is 999 -**** v1 0.6 CLI TX|debug.listen_address -*** v1 0.7 CLI RX 200 -**** v1 0.7 CLI RX|127.0.0.1 46857 -** v1 0.7 Listen on 127.0.0.1 46857 -**** v1 0.7 macro def v1_addr=127.0.0.1 -**** v1 0.7 macro def v1_port=46857 -**** v1 0.7 macro def v1_sock=127.0.0.1 46857 -** top 0.7 === barrier b1 sync -**** top 0.7 Barrier(b1) wait 1 of 2 -**** v1 0.7 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 4 3 8 0.000548 0.000233 HTTP/1.1 200 OK -**** v1 0.7 vsl| 0 CLI - Rd debug.xid 999 -**** v1 0.7 vsl| 0 CLI - Wr 200 10 XID is 999 -**** v1 0.7 vsl| 0 CLI - Rd debug.listen_address -**** v1 0.7 vsl| 0 CLI - Wr 200 16 127.0.0.1 46857 - -*** s1 0.7 Accepted socket fd is 5 -**** s1 0.7 Loop #3 -** s1 0.7 === rxreq -**** s1 0.7 rxhdr|GET / HTTP/1.1\r -**** s1 0.7 rxhdr|Host: 0.0.0.0\r -**** s1 0.7 rxhdr|Connection: close\r -**** s1 0.7 rxhdr|\r -**** s1 0.7 rxhdrlen = 52 -**** s1 0.7 http[ 0] |GET -**** s1 0.7 http[ 1] |/ -**** s1 0.7 http[ 2] |HTTP/1.1 -**** s1 0.7 http[ 3] |Host: 0.0.0.0 -**** s1 0.7 http[ 4] |Connection: close -**** s1 0.7 bodylen = 0 -** s1 0.7 === expect req.url == '/' -**** s1 0.7 EXPECT req.url (/) == '/' match -** s1 0.7 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 0.7 txresp|HTTP/1.1 200 OK\r -**** s1 0.7 txresp|Bar: foo\r -**** s1 0.7 txresp|Content-Length: 6\r -**** s1 0.7 txresp|\r -**** s1 0.7 txresp|foobar -** s1 0.7 === accept -**** s1 0.7 Accepting -**** v1 0.8 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 5 3 8 0.000286 0.000246 HTTP/1.1 200 OK -*** s1 0.8 Accepted socket fd is 5 -**** s1 0.8 Loop #4 -** s1 0.8 === rxreq -**** s1 0.8 rxhdr|GET / HTTP/1.1\r -**** s1 0.8 rxhdr|Host: 0.0.0.0\r -**** s1 0.8 rxhdr|Connection: close\r -**** s1 0.8 rxhdr|\r -**** s1 0.8 rxhdrlen = 52 -**** s1 0.8 http[ 0] |GET -**** s1 0.8 http[ 1] |/ -**** s1 0.8 http[ 2] |HTTP/1.1 -**** s1 0.8 http[ 3] |Host: 0.0.0.0 -**** s1 0.8 http[ 4] |Connection: close -**** s1 0.8 bodylen = 0 -** s1 0.8 === expect req.url == '/' -**** s1 0.8 EXPECT req.url (/) == '/' match -** s1 0.8 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 0.8 txresp|HTTP/1.1 200 OK\r -**** s1 0.8 txresp|Bar: foo\r -**** s1 0.8 txresp|Content-Length: 6\r -**** s1 0.8 txresp|\r -**** s1 0.8 txresp|foobar -** s1 0.8 === accept -**** s1 0.8 Accepting -**** v1 0.9 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 6 3 8 0.000425 0.000291 HTTP/1.1 200 OK -*** s1 0.9 Accepted socket fd is 5 -**** s1 0.9 Loop #5 -** s1 0.9 === rxreq -**** s1 0.9 rxhdr|GET / HTTP/1.1\r -**** s1 0.9 rxhdr|Host: 0.0.0.0\r -**** s1 0.9 rxhdr|Connection: close\r -**** s1 0.9 rxhdr|\r -**** s1 0.9 rxhdrlen = 52 -**** s1 0.9 http[ 0] |GET -**** s1 0.9 http[ 1] |/ -**** s1 0.9 http[ 2] |HTTP/1.1 -**** s1 0.9 http[ 3] |Host: 0.0.0.0 -**** s1 0.9 http[ 4] |Connection: close -**** s1 0.9 bodylen = 0 -** s1 0.9 === expect req.url == '/' -**** s1 0.9 EXPECT req.url (/) == '/' match -** s1 0.9 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 0.9 txresp|HTTP/1.1 200 OK\r -**** s1 0.9 txresp|Bar: foo\r -**** s1 0.9 txresp|Content-Length: 6\r -**** s1 0.9 txresp|\r -**** s1 0.9 txresp|foobar -** s1 0.9 === accept -**** s1 0.9 Accepting -**** v1 1.0 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 7 3 8 0.000262 0.000283 HTTP/1.1 200 OK -*** s1 1.0 Accepted socket fd is 5 -**** s1 1.0 Loop #6 -** s1 1.0 === rxreq -**** s1 1.0 rxhdr|GET / HTTP/1.1\r -**** s1 1.0 rxhdr|Host: 0.0.0.0\r -**** s1 1.0 rxhdr|Connection: close\r -**** s1 1.0 rxhdr|\r -**** s1 1.0 rxhdrlen = 52 -**** s1 1.0 http[ 0] |GET -**** s1 1.0 http[ 1] |/ -**** s1 1.0 http[ 2] |HTTP/1.1 -**** s1 1.0 http[ 3] |Host: 0.0.0.0 -**** s1 1.0 http[ 4] |Connection: close -**** s1 1.0 bodylen = 0 -** s1 1.0 === expect req.url == '/' -**** s1 1.0 EXPECT req.url (/) == '/' match -** s1 1.0 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 1.0 txresp|HTTP/1.1 200 OK\r -**** s1 1.0 txresp|Bar: foo\r -**** s1 1.0 txresp|Content-Length: 6\r -**** s1 1.0 txresp|\r -**** s1 1.0 txresp|foobar -** s1 1.0 === accept -**** s1 1.0 Accepting -**** v1 1.1 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 8 3 8 0.000186 0.000259 HTTP/1.1 200 OK -*** s1 1.1 Accepted socket fd is 5 -**** s1 1.1 Loop #7 -** s1 1.1 === rxreq -**** s1 1.1 rxhdr|GET / HTTP/1.1\r -**** s1 1.1 rxhdr|Host: 0.0.0.0\r -**** s1 1.1 rxhdr|Connection: close\r -**** s1 1.1 rxhdr|\r -**** s1 1.1 rxhdrlen = 52 -**** s1 1.1 http[ 0] |GET -**** s1 1.1 http[ 1] |/ -**** s1 1.1 http[ 2] |HTTP/1.1 -**** s1 1.1 http[ 3] |Host: 0.0.0.0 -**** s1 1.1 http[ 4] |Connection: close -**** s1 1.1 bodylen = 0 -** s1 1.1 === expect req.url == '/' -**** s1 1.1 EXPECT req.url (/) == '/' match -** s1 1.1 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 1.1 txresp|HTTP/1.1 200 OK\r -**** s1 1.1 txresp|Bar: foo\r -**** s1 1.1 txresp|Content-Length: 6\r -**** s1 1.1 txresp|\r -**** s1 1.1 txresp|foobar -** s1 1.1 === accept -**** s1 1.1 Accepting -**** v1 1.2 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 8 3 8 0.000229 0.000251 HTTP/1.1 200 OK -*** s1 1.2 Accepted socket fd is 5 -**** s1 1.2 Loop #8 -** s1 1.2 === rxreq -**** s1 1.2 rxhdr|GET / HTTP/1.1\r -**** s1 1.2 rxhdr|Host: 0.0.0.0\r -**** s1 1.2 rxhdr|Connection: close\r -**** s1 1.2 rxhdr|\r -**** s1 1.2 rxhdrlen = 52 -**** s1 1.2 http[ 0] |GET -**** s1 1.2 http[ 1] |/ -**** s1 1.2 http[ 2] |HTTP/1.1 -**** s1 1.2 http[ 3] |Host: 0.0.0.0 -**** s1 1.2 http[ 4] |Connection: close -**** s1 1.2 bodylen = 0 -** s1 1.2 === expect req.url == '/' -**** s1 1.2 EXPECT req.url (/) == '/' match -** s1 1.2 === txresp -hdr 'Bar: foo' -body 'foobar' -**** s1 1.2 txresp|HTTP/1.1 200 OK\r -**** s1 1.2 txresp|Bar: foo\r -**** s1 1.2 txresp|Content-Length: 6\r -**** s1 1.2 txresp|\r -**** s1 1.2 txresp|foobar -** s1 1.2 === accept -**** s1 1.2 Accepting -**** v1 1.3 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 8 3 8 0.000140 0.000224 HTTP/1.1 200 OK -*** s1 1.3 Accepted socket fd is 5 -** s1 1.3 === loop 3 { -**** s1 1.3 Loop #1 -** s1 1.3 === rxreq -**** s1 1.3 rxhdr|GET / HTTP/1.1\r -**** s1 1.3 rxhdr|Host: 0.0.0.0\r -**** s1 1.3 rxhdr|Connection: close\r -**** s1 1.3 rxhdr|\r -**** s1 1.3 rxhdrlen = 52 -**** s1 1.3 http[ 0] |GET -**** s1 1.3 http[ 1] |/ -**** s1 1.3 http[ 2] |HTTP/1.1 -**** s1 1.3 http[ 3] |Host: 0.0.0.0 -**** s1 1.3 http[ 4] |Connection: close -**** s1 1.3 bodylen = 0 -** s1 1.3 === expect req.url == '/' -**** s1 1.3 EXPECT req.url (/) == '/' match -** s1 1.3 === txresp -status 404 -hdr 'Bar: foo' -body 'foobar' -**** s1 1.3 txresp|HTTP/1.1 404 OK\r -**** s1 1.3 txresp|Bar: foo\r -**** s1 1.3 txresp|Content-Length: 6\r -**** s1 1.3 txresp|\r -**** s1 1.3 txresp|foobar -** s1 1.3 === accept -**** s1 1.3 Accepting -**** v1 1.4 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-R- 7 3 8 0.000182 0.000224 HTTP/1.1 404 OK -*** s1 1.4 Accepted socket fd is 5 -**** s1 1.4 Loop #2 -** s1 1.4 === rxreq -**** s1 1.4 rxhdr|GET / HTTP/1.1\r -**** s1 1.4 rxhdr|Host: 0.0.0.0\r -**** s1 1.4 rxhdr|Connection: close\r -**** s1 1.4 rxhdr|\r -**** s1 1.4 rxhdrlen = 52 -**** s1 1.4 http[ 0] |GET -**** s1 1.4 http[ 1] |/ -**** s1 1.4 http[ 2] |HTTP/1.1 -**** s1 1.4 http[ 3] |Host: 0.0.0.0 -**** s1 1.4 http[ 4] |Connection: close -**** s1 1.4 bodylen = 0 -** s1 1.4 === expect req.url == '/' -**** s1 1.4 EXPECT req.url (/) == '/' match -** s1 1.4 === txresp -status 404 -hdr 'Bar: foo' -body 'foobar' -**** s1 1.4 txresp|HTTP/1.1 404 OK\r -**** s1 1.4 txresp|Bar: foo\r -**** s1 1.4 txresp|Content-Length: 6\r -**** s1 1.4 txresp|\r -**** s1 1.4 txresp|foobar -** s1 1.4 === accept -**** s1 1.4 Accepting -**** v1 1.5 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-R- 6 3 8 0.000221 0.000224 HTTP/1.1 404 OK -*** s1 1.5 Accepted socket fd is 5 -**** s1 1.5 Loop #3 -** s1 1.5 === rxreq -**** s1 1.5 rxhdr|GET / HTTP/1.1\r -**** s1 1.5 rxhdr|Host: 0.0.0.0\r -**** s1 1.5 rxhdr|Connection: close\r -**** s1 1.5 rxhdr|\r -**** s1 1.5 rxhdrlen = 52 -**** s1 1.5 http[ 0] |GET -**** s1 1.5 http[ 1] |/ -**** s1 1.5 http[ 2] |HTTP/1.1 -**** s1 1.5 http[ 3] |Host: 0.0.0.0 -**** s1 1.5 http[ 4] |Connection: close -**** s1 1.5 bodylen = 0 -** s1 1.5 === expect req.url == '/' -**** s1 1.5 EXPECT req.url (/) == '/' match -** s1 1.5 === txresp -status 404 -hdr 'Bar: foo' -body 'foobar' -**** s1 1.5 txresp|HTTP/1.1 404 OK\r -**** s1 1.5 txresp|Bar: foo\r -**** s1 1.5 txresp|Content-Length: 6\r -**** s1 1.5 txresp|\r -**** s1 1.5 txresp|foobar -** s1 1.5 === accept -**** s1 1.5 Accepting -**** v1 1.6 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-R- 5 3 8 0.000174 0.000224 HTTP/1.1 404 OK -*** s1 1.6 Accepted socket fd is 5 -** s1 1.6 === loop 2 { -**** s1 1.6 Loop #1 -** s1 1.6 === rxreq -**** s1 1.6 rxhdr|GET / HTTP/1.1\r -**** s1 1.6 rxhdr|Host: 0.0.0.0\r -**** s1 1.6 rxhdr|Connection: close\r -**** s1 1.6 rxhdr|\r -**** s1 1.6 rxhdrlen = 52 -**** s1 1.6 http[ 0] |GET -**** s1 1.6 http[ 1] |/ -**** s1 1.6 http[ 2] |HTTP/1.1 -**** s1 1.6 http[ 3] |Host: 0.0.0.0 -**** s1 1.6 http[ 4] |Connection: close -**** s1 1.6 bodylen = 0 -** s1 1.6 === expect req.url == '/' -**** s1 1.6 EXPECT req.url (/) == '/' match -** s1 1.6 === txresp -proto 'FROBOZ' -status 200 -hdr 'Bar: foo' -body 'fo... -**** s1 1.6 txresp|FROBOZ 200 OK\r -**** s1 1.6 txresp|Bar: foo\r -**** s1 1.6 txresp|Content-Length: 6\r -**** s1 1.6 txresp|\r -**** s1 1.6 txresp|foobar -** s1 1.6 === accept -**** s1 1.6 Accepting -**** v1 1.7 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-R- 4 3 8 0.000224 0.000224 FROBOZ 200 OK -*** s1 1.7 Accepted socket fd is 5 -**** s1 1.7 Loop #2 -** s1 1.7 === rxreq -**** s1 1.7 rxhdr|GET / HTTP/1.1\r -**** s1 1.7 rxhdr|Host: 0.0.0.0\r -**** s1 1.7 rxhdr|Connection: close\r -**** s1 1.7 rxhdr|\r -**** s1 1.7 rxhdrlen = 52 -**** s1 1.7 http[ 0] |GET -**** s1 1.7 http[ 1] |/ -**** s1 1.7 http[ 2] |HTTP/1.1 -**** s1 1.7 http[ 3] |Host: 0.0.0.0 -**** s1 1.7 http[ 4] |Connection: close -**** s1 1.7 bodylen = 0 -** s1 1.7 === expect req.url == '/' -**** s1 1.7 EXPECT req.url (/) == '/' match -** s1 1.7 === txresp -proto 'FROBOZ' -status 200 -hdr 'Bar: foo' -body 'fo... -**** s1 1.7 txresp|FROBOZ 200 OK\r -**** s1 1.7 txresp|Bar: foo\r -**** s1 1.7 txresp|Content-Length: 6\r -**** s1 1.7 txresp|\r -**** s1 1.7 txresp|foobar -** s1 1.7 === accept -**** s1 1.7 Accepting -**** v1 1.8 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-R- 3 3 8 0.000213 0.000224 FROBOZ 200 OK -*** s1 1.8 Accepted socket fd is 5 -** s1 1.8 === loop 2 { -**** s1 1.8 Loop #1 -** s1 1.8 === rxreq -**** s1 1.8 rxhdr|GET / HTTP/1.1\r -**** s1 1.8 rxhdr|Host: 0.0.0.0\r -**** s1 1.8 rxhdr|Connection: close\r -**** s1 1.8 rxhdr|\r -**** s1 1.8 rxhdrlen = 52 -**** s1 1.8 http[ 0] |GET -**** s1 1.8 http[ 1] |/ -**** s1 1.8 http[ 2] |HTTP/1.1 -**** s1 1.8 http[ 3] |Host: 0.0.0.0 -**** s1 1.8 http[ 4] |Connection: close -**** s1 1.8 bodylen = 0 -** s1 1.8 === expect req.url == '/' -**** s1 1.8 EXPECT req.url (/) == '/' match -** s1 1.8 === send 'HTTP/1.1 200 \r\n' -**** s1 1.8 send|HTTP/1.1 200 \r -** s1 1.8 === accept -**** s1 1.8 Accepting -**** v1 1.9 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 3 3 8 0.000142 0.000203 HTTP/1.1 200 -*** s1 1.9 Accepted socket fd is 5 -**** s1 1.9 Loop #2 -** s1 1.9 === rxreq -**** s1 1.9 rxhdr|GET / HTTP/1.1\r -**** s1 1.9 rxhdr|Host: 0.0.0.0\r -**** s1 1.9 rxhdr|Connection: close\r -**** s1 1.9 rxhdr|\r -**** s1 1.9 rxhdrlen = 52 -**** s1 1.9 http[ 0] |GET -**** s1 1.9 http[ 1] |/ -**** s1 1.9 http[ 2] |HTTP/1.1 -**** s1 1.9 http[ 3] |Host: 0.0.0.0 -**** s1 1.9 http[ 4] |Connection: close -**** s1 1.9 bodylen = 0 -** s1 1.9 === expect req.url == '/' -**** s1 1.9 EXPECT req.url (/) == '/' match -** s1 1.9 === send 'HTTP/1.1 200 \r\n' -**** s1 1.9 send|HTTP/1.1 200 \r -** s1 1.9 === accept -**** s1 1.9 Accepting -**** v1 2.0 vsl| 0 Backend_health - vcl1.foo Still healthy --U-X-RH 3 3 8 0.000185 0.000199 HTTP/1.1 200 -*** s1 2.0 Accepted socket fd is 5 -** s1 2.0 === barrier b1 sync -**** s1 2.0 Barrier(b1) wake 2 -*** s1 2.0 shutting fd 5 -** s1 2.0 Ending -** top 2.0 === varnish v1 -cliexpect '^CLI RX| -+U+ Good UNIX' 'backend.li... -**** v1 2.0 CLI TX|backend.list -p denes -*** v1 2.0 CLI RX 200 -**** v1 2.0 CLI RX|Backend name Admin Probe Last updated -** v1 2.0 CLI 200 LESSbackend.list -p denesMORE ----- v1 2.0 Expect failed (-1) -* top 2.0 RESETTING after ./tests/c00094.vtc -** s1 2.0 Waiting for server (4/-1) -** v1 2.0 Wait -**** v1 2.0 CLI TX|panic.clear -*** v1 2.1 CLI RX 300 -**** v1 2.1 CLI RX|No panic to clear -*** v1 2.1 debug|Info: manager stopping child -*** v1 2.1 debug|Debug: Stopping Child -**** v1 2.1 vsl| 0 Backend_health - vcl1.foo Went sick --U-Xr-- 2 3 8 0.000000 0.000199 Read error 104 (Connection reset by peer) -**** v1 2.1 vsl| 0 CLI - Rd backend.list -p denes -**** v1 2.1 vsl| 0 CLI - Wr 200 75 Backend name Admin Probe Last updated -**** v1 2.1 vsl| 0 CLI - EOF on CLI connection, worker stops -*** v1 3.1 debug|Info: Child (15126) ended -*** v1 3.1 debug|Info: Child (15126) said Child dies -*** v1 3.1 debug|Debug: Child cleanup complete -*** v1 3.1 debug|Info: manager dies -**** v1 3.1 STDOUT poll 0x10 -** v1 3.1 WAIT4 pid=14858 status=0x0000 (user 0.195153 sys 0.050115) -* top 3.1 TEST ./tests/c00094.vtc FAILED -# top TEST ./tests/c00094.vtc FAILED (3.079) exit=2 -FAIL tests/c00094.vtc (exit status: 2) - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - -