Dec 03 13:52:06.443 INFO testing dmenv-0.6.0 against 1.30.1 for beta-1.31-5
Dec 03 13:52:06.443 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/1.30.1:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 13:52:06.596 INFO [stdout] 6c9dee2a52a9f67a3b6912459bdf9dd8c47ff5157fabfe8e73776021e20ad7a7
Dec 03 13:52:06.598 INFO running `"docker" "start" "-a" "6c9dee2a52a9f67a3b6912459bdf9dd8c47ff5157fabfe8e73776021e20ad7a7"`
Dec 03 13:52:07.007 INFO [stderr] usermod: no changes
Dec 03 13:52:07.094 INFO [stderr]    Compiling appdirs v0.2.0
Dec 03 13:52:07.860 INFO [stderr]    Compiling dmenv v0.6.0 (/source)
Dec 03 13:52:15.483 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.46s
Dec 03 13:52:15.490 INFO [stderr] su: No module specific data is present
Dec 03 13:52:16.730 INFO running `"docker" "inspect" "6c9dee2a52a9f67a3b6912459bdf9dd8c47ff5157fabfe8e73776021e20ad7a7"`
Dec 03 13:52:17.085 INFO running `"docker" "rm" "-f" "6c9dee2a52a9f67a3b6912459bdf9dd8c47ff5157fabfe8e73776021e20ad7a7"`
Dec 03 13:52:17.467 INFO [stdout] 6c9dee2a52a9f67a3b6912459bdf9dd8c47ff5157fabfe8e73776021e20ad7a7
Dec 03 13:52:17.475 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/1.30.1:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 13:52:17.955 INFO [stdout] 9851987bd55d2593f0e45c582b16e659a9277b21456f300bbfe5f84c4f0944c6
Dec 03 13:52:17.957 INFO running `"docker" "start" "-a" "9851987bd55d2593f0e45c582b16e659a9277b21456f300bbfe5f84c4f0944c6"`
Dec 03 13:52:18.854 INFO [stderr] usermod: no changes
Dec 03 13:52:18.939 INFO [stderr]    Compiling dmenv v0.6.0 (/source)
Dec 03 13:52:25.084 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.19s
Dec 03 13:52:25.084 INFO [stderr] su: No module specific data is present
Dec 03 13:52:25.425 INFO running `"docker" "inspect" "9851987bd55d2593f0e45c582b16e659a9277b21456f300bbfe5f84c4f0944c6"`
Dec 03 13:52:25.733 INFO running `"docker" "rm" "-f" "9851987bd55d2593f0e45c582b16e659a9277b21456f300bbfe5f84c4f0944c6"`
Dec 03 13:52:26.008 INFO [stdout] 9851987bd55d2593f0e45c582b16e659a9277b21456f300bbfe5f84c4f0944c6
Dec 03 13:52:26.017 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/1.30.1:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 13:52:26.439 INFO [stdout] 8bacc93c6970fc36091c649c71643d38eff565fd31ef6c2f5b1c936519408fab
Dec 03 13:52:26.447 INFO running `"docker" "start" "-a" "8bacc93c6970fc36091c649c71643d38eff565fd31ef6c2f5b1c936519408fab"`
Dec 03 13:52:27.101 INFO [stderr] usermod: no changes
Dec 03 13:52:27.182 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Dec 03 13:52:27.207 INFO [stderr]      Running /target/debug/deps/dmenv-ba15f1b60b97a475
Dec 03 13:52:27.215 INFO [stderr]      Running /target/debug/deps/dmenv-e539067dc0cedf6f
Dec 03 13:52:27.215 INFO [stdout] 
Dec 03 13:52:27.215 INFO [stdout] running 0 tests
Dec 03 13:52:27.215 INFO [stdout] 
Dec 03 13:52:27.215 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 13:52:27.215 INFO [stdout] 
Dec 03 13:52:27.219 INFO [stdout] 
Dec 03 13:52:27.219 INFO [stderr]      Running /target/debug/deps/integration_tests-4f0bf8b3f93e776e
Dec 03 13:52:27.219 INFO [stdout] running 0 tests
Dec 03 13:52:27.220 INFO [stdout] 
Dec 03 13:52:27.220 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 13:52:27.220 INFO [stdout] 
Dec 03 13:52:27.239 INFO [stdout] 
Dec 03 13:52:27.239 INFO [stdout] running 10 tests
Dec 03 13:52:27.247 INFO [stdout] test run_without_args ... ok
Dec 03 13:52:27.310 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok
Dec 03 13:52:27.422 INFO [stdout] test init_generates_setup_py ... ok
Dec 03 13:52:27.551 INFO [stdout] test install_without_lock ... ok
Dec 03 13:52:27.551 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok
Dec 03 13:52:27.573 INFO [stdout] test show_venv_path ... ok
Dec 03 13:52:27.657 INFO [stdout] test run_without_virtualenv ... ok
Dec 03 13:52:30.699 INFO [stderr] The directory '/home/crater/.cache/pip/http' or its parent directory is not owned by the current user and the cache has been disabled. Please check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:30.699 INFO [stderr] The directory '/home/crater/.cache/pip' or its parent directory is not owned by the current user and caching wheels has been disabled. check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:30.731 INFO [stderr] The directory '/home/crater/.cache/pip/http' or its parent directory is not owned by the current user and the cache has been disabled. Please check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:30.731 INFO [stderr] The directory '/home/crater/.cache/pip' or its parent directory is not owned by the current user and caching wheels has been disabled. check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:30.753 INFO [stderr] Exception:
Dec 03 13:52:30.753 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 137, in _new_conn
Dec 03 13:52:30.753 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/connection.py", line 67, in create_connection
Dec 03 13:52:30.753 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 03 13:52:30.753 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 03 13:52:30.753 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 03 13:52:30.753 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:30.753 INFO [stderr] 
Dec 03 13:52:30.753 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:30.753 INFO [stderr] 
Dec 03 13:52:30.753 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 03 13:52:30.753 INFO [stderr]     body=body, headers=headers)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 346, in _make_request
Dec 03 13:52:30.753 INFO [stderr]     self._validate_conn(conn)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 787, in _validate_conn
Dec 03 13:52:30.753 INFO [stderr]     conn.connect()
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 03 13:52:30.753 INFO [stderr]     conn = self._new_conn()
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 146, in _new_conn
Dec 03 13:52:30.753 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 03 13:52:30.753 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7f44fc406940>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:30.753 INFO [stderr] 
Dec 03 13:52:30.753 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:30.753 INFO [stderr] 
Dec 03 13:52:30.753 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 03 13:52:30.753 INFO [stderr]     status = self.run(options, args)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 03 13:52:30.753 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 03 13:52:30.753 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file
Dec 03 13:52:30.753 INFO [stderr]     req_to_install, finder)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed
Dec 03 13:52:30.753 INFO [stderr]     finder.find_requirement(req_to_install, self.upgrade)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 03 13:52:30.753 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 03 13:52:30.753 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 03 13:52:30.753 INFO [stderr]     page = self._get_page(location)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 03 13:52:30.753 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 03 13:52:30.753 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 03 13:52:30.753 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 03 13:52:30.753 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 03 13:52:30.753 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 03 13:52:30.753 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 03 13:52:30.753 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 03 13:52:30.753 INFO [stderr]     timeout=timeout
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 03 13:52:30.753 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 03 13:52:30.753 INFO [stderr]   File "/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/retry.py", line 228, in increment
Dec 03 13:52:30.753 INFO [stderr]     total -= 1
Dec 03 13:52:30.753 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 03 13:52:30.775 INFO [stderr] The directory '/home/crater/.cache/pip/http' or its parent directory is not owned by the current user and the cache has been disabled. Please check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:30.775 INFO [stderr] The directory '/home/crater/.cache/pip' or its parent directory is not owned by the current user and caching wheels has been disabled. check the permissions and owner of that directory. If executing pip with sudo, you may want sudo's -H flag.
Dec 03 13:52:30.784 INFO [stderr] Exception:
Dec 03 13:52:30.784 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 137, in _new_conn
Dec 03 13:52:30.784 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/connection.py", line 67, in create_connection
Dec 03 13:52:30.784 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 03 13:52:30.784 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 03 13:52:30.784 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 03 13:52:30.784 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:30.784 INFO [stderr] 
Dec 03 13:52:30.784 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:30.784 INFO [stderr] 
Dec 03 13:52:30.784 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 03 13:52:30.784 INFO [stderr]     body=body, headers=headers)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 346, in _make_request
Dec 03 13:52:30.784 INFO [stderr]     self._validate_conn(conn)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 787, in _validate_conn
Dec 03 13:52:30.784 INFO [stderr]     conn.connect()
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 03 13:52:30.784 INFO [stderr]     conn = self._new_conn()
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 146, in _new_conn
Dec 03 13:52:30.784 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 03 13:52:30.784 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7f7274c3b978>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:30.784 INFO [stderr] 
Dec 03 13:52:30.784 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:30.784 INFO [stderr] 
Dec 03 13:52:30.784 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 03 13:52:30.784 INFO [stderr]     status = self.run(options, args)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 03 13:52:30.784 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 03 13:52:30.784 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file
Dec 03 13:52:30.784 INFO [stderr]     req_to_install, finder)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed
Dec 03 13:52:30.784 INFO [stderr]     finder.find_requirement(req_to_install, self.upgrade)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 03 13:52:30.784 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 03 13:52:30.784 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 03 13:52:30.784 INFO [stderr]     page = self._get_page(location)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 03 13:52:30.784 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 03 13:52:30.784 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 03 13:52:30.785 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 03 13:52:30.785 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 03 13:52:30.785 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 03 13:52:30.785 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 03 13:52:30.785 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 03 13:52:30.785 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 03 13:52:30.785 INFO [stderr]     timeout=timeout
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 03 13:52:30.785 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 03 13:52:30.785 INFO [stderr]   File "/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/retry.py", line 228, in increment
Dec 03 13:52:30.785 INFO [stderr]     total -= 1
Dec 03 13:52:30.785 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 03 13:52:30.812 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.014WH5o3cjqe/requirements.lock (line 2))
Dec 03 13:52:30.821 INFO [stderr] Exception:
Dec 03 13:52:30.821 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 137, in _new_conn
Dec 03 13:52:30.821 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/connection.py", line 67, in create_connection
Dec 03 13:52:30.821 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 03 13:52:30.821 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 03 13:52:30.821 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 03 13:52:30.821 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:30.821 INFO [stderr] 
Dec 03 13:52:30.821 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:30.821 INFO [stderr] 
Dec 03 13:52:30.821 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 03 13:52:30.821 INFO [stderr]     body=body, headers=headers)
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 346, in _make_request
Dec 03 13:52:30.821 INFO [stderr]     self._validate_conn(conn)
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 787, in _validate_conn
Dec 03 13:52:30.821 INFO [stderr]     conn.connect()
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 03 13:52:30.821 INFO [stderr]     conn = self._new_conn()
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 146, in _new_conn
Dec 03 13:52:30.821 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 03 13:52:30.821 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7fbace1dbba8>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 03 13:52:30.821 INFO [stderr] 
Dec 03 13:52:30.821 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 03 13:52:30.821 INFO [stderr] 
Dec 03 13:52:30.821 INFO [stderr] Traceback (most recent call last):
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 03 13:52:30.821 INFO [stderr]     status = self.run(options, args)
Dec 03 13:52:30.821 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 03 13:52:30.821 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 03 13:52:30.822 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file
Dec 03 13:52:30.822 INFO [stderr]     finder, self.upgrade, require_hashes)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link
Dec 03 13:52:30.822 INFO [stderr]     self.link = finder.find_requirement(self, upgrade)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 03 13:52:30.822 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 03 13:52:30.822 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 03 13:52:30.822 INFO [stderr]     page = self._get_page(location)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 03 13:52:30.822 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 03 13:52:30.822 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 03 13:52:30.822 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 03 13:52:30.822 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 03 13:52:30.822 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 03 13:52:30.822 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 03 13:52:30.822 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 03 13:52:30.822 INFO [stderr]     timeout=timeout
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 03 13:52:30.822 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 03 13:52:30.822 INFO [stderr]   File "/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/util/retry.py", line 228, in increment
Dec 03 13:52:30.822 INFO [stderr]     total -= 1
Dec 03 13:52:30.822 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 03 13:52:30.898 INFO [stdout] test install_workflow_all_in_one ... FAILED
Dec 03 13:52:30.907 INFO [stdout] test lock_workflow ... FAILED
Dec 03 13:52:30.933 INFO [stdout] test install_workflow_step_by_step ... FAILED
Dec 03 13:52:30.933 INFO [stdout] 
Dec 03 13:52:30.933 INFO [stdout] failures:
Dec 03 13:52:30.933 INFO [stdout] 
Dec 03 13:52:30.933 INFO [stdout] ---- install_workflow_all_in_one stdout ----
Dec 03 13:52:30.933 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2
Dec 03 13:52:30.933 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2
Dec 03 13:52:30.933 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.KS7rSUOi16dR/.venv/3.5.2/bin/python[0m -m pip install pip --upgrade
Dec 03 13:52:30.933 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 03 13:52:30.933 INFO [stdout] stack backtrace:
Dec 03 13:52:30.933 INFO [stdout]    0:     0x56043299974e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 13:52:30.933 INFO [stdout]    1:     0x560432987886 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 13:52:30.933 INFO [stdout]    2:     0x560432985cad - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 13:52:30.933 INFO [stdout]    3:     0x5604329859bb - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 13:52:30.933 INFO [stdout]    4:     0x56043298635c - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:477
Dec 03 13:52:30.933 INFO [stdout]    5:     0x560432985f19 - std::panicking::continue_panic_fmt::h2d2d2bfb065a70b2
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:391
Dec 03 13:52:30.933 INFO [stdout]    6:     0x560432985e15 - rust_begin_unwind
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:326
Dec 03 13:52:30.933 INFO [stdout]    7:     0x5604329df27b - core::panicking::panic_fmt::h2155aa66b67fe83c
Dec 03 13:52:30.933 INFO [stdout]                                at libcore/panicking.rs:77
Dec 03 13:52:30.933 INFO [stdout]    8:     0x560432701963 - core::result::unwrap_failed::hc41f6a20f9af7e58
Dec 03 13:52:30.933 INFO [stdout]                                at libcore/macros.rs:26
Dec 03 13:52:30.933 INFO [stdout]    9:     0x56043270152d - <core::result::Result<T, E>>::expect::hbef5507e60ca5435
Dec 03 13:52:30.933 INFO [stdout]                                at libcore/result.rs:835
Dec 03 13:52:30.933 INFO [stdout]   10:     0x56043270dfe6 - integration_tests::helpers::TestApp::assert_run_ok::ha92a163ded2d38ca
Dec 03 13:52:30.933 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 03 13:52:30.933 INFO [stdout]   11:     0x560432709fd5 - integration_tests::install_workflow_all_in_one::ha047426ce210c4a6
Dec 03 13:52:30.933 INFO [stdout]                                at tests/integration_tests.rs:68
Dec 03 13:52:30.933 INFO [stdout]   12:     0x560432709e99 - integration_tests::install_workflow_all_in_one::{{closure}}::ha4f85edd5ba20fee
Dec 03 13:52:30.933 INFO [stdout]                                at tests/integration_tests.rs:63
Dec 03 13:52:30.933 INFO [stdout]   13:     0x560432701eed - core::ops::function::FnOnce::call_once::hebd4afe1a572d9af
Dec 03 13:52:30.933 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:30.933 INFO [stdout]   14:     0x560432711e5e - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 03 13:52:30.933 INFO [stdout]                                at libtest/lib.rs:1461
Dec 03 13:52:30.933 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:30.933 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 03 13:52:30.933 INFO [stdout]   15:     0x5604329ac9c9 - __rust_maybe_catch_panic
Dec 03 13:52:30.933 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 03 13:52:30.933 INFO [stdout]   16:     0x56043271d7bd - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:30.933 INFO [stdout]                                at libtest/lib.rs:1416
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 13:52:30.933 INFO [stdout]   17:     0x560432735df4 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 13:52:30.933 INFO [stdout]   18:     0x5604329ac9c9 - __rust_maybe_catch_panic
Dec 03 13:52:30.933 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 03 13:52:30.933 INFO [stdout]   19:     0x5604327266c6 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 13:52:30.933 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 03 13:52:30.933 INFO [stdout]   20:     0x56043299b97a - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 03 13:52:30.933 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 13:52:30.933 INFO [stdout]   21:     0x560432986ac5 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 03 13:52:30.933 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 13:52:30.933 INFO [stdout]   22:     0x7f0e0515a6b9 - start_thread
Dec 03 13:52:30.933 INFO [stdout]   23:     0x7f0e04c7a41c - clone
Dec 03 13:52:30.933 INFO [stdout]   24:                0x0 - <unknown>
Dec 03 13:52:30.933 INFO [stdout] 
Dec 03 13:52:30.933 INFO [stdout] ---- lock_workflow stdout ----
Dec 03 13:52:30.933 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2
Dec 03 13:52:30.933 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2
Dec 03 13:52:30.934 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.XFDzVfe5ng8F/.venv/3.5.2/bin/python[0m -m pip install pip --upgrade
Dec 03 13:52:30.934 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 03 13:52:30.934 INFO [stdout] stack backtrace:
Dec 03 13:52:30.934 INFO [stdout]    0:     0x56043299974e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 13:52:30.934 INFO [stdout]    1:     0x560432987886 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 13:52:30.934 INFO [stdout]    2:     0x560432985cad - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 13:52:30.934 INFO [stdout]    3:     0x5604329859bb - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 13:52:30.934 INFO [stdout]    4:     0x56043298635c - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:477
Dec 03 13:52:30.934 INFO [stdout]    5:     0x560432985f19 - std::panicking::continue_panic_fmt::h2d2d2bfb065a70b2
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:391
Dec 03 13:52:30.934 INFO [stdout]    6:     0x560432985e15 - rust_begin_unwind
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:326
Dec 03 13:52:30.934 INFO [stdout]    7:     0x5604329df27b - core::panicking::panic_fmt::h2155aa66b67fe83c
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/panicking.rs:77
Dec 03 13:52:30.934 INFO [stdout]    8:     0x560432701963 - core::result::unwrap_failed::hc41f6a20f9af7e58
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/macros.rs:26
Dec 03 13:52:30.934 INFO [stdout]    9:     0x56043270152d - <core::result::Result<T, E>>::expect::hbef5507e60ca5435
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/result.rs:835
Dec 03 13:52:30.934 INFO [stdout]   10:     0x56043270dfe6 - integration_tests::helpers::TestApp::assert_run_ok::ha92a163ded2d38ca
Dec 03 13:52:30.934 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 03 13:52:30.934 INFO [stdout]   11:     0x560432709c0a - integration_tests::lock_workflow::h5436e5f5ee78a7a2
Dec 03 13:52:30.934 INFO [stdout]                                at tests/integration_tests.rs:53
Dec 03 13:52:30.934 INFO [stdout]   12:     0x560432709af9 - integration_tests::lock_workflow::{{closure}}::ha93101455337e08a
Dec 03 13:52:30.934 INFO [stdout]                                at tests/integration_tests.rs:50
Dec 03 13:52:30.934 INFO [stdout]   13:     0x560432701e2d - core::ops::function::FnOnce::call_once::h9f4fd52aa3b3e1f8
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:30.934 INFO [stdout]   14:     0x560432711e5e - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 03 13:52:30.934 INFO [stdout]                                at libtest/lib.rs:1461
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:30.934 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 03 13:52:30.934 INFO [stdout]   15:     0x5604329ac9c9 - __rust_maybe_catch_panic
Dec 03 13:52:30.934 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 03 13:52:30.934 INFO [stdout]   16:     0x56043271d7bd - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:30.934 INFO [stdout]                                at libtest/lib.rs:1416
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 13:52:30.934 INFO [stdout]   17:     0x560432735df4 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 13:52:30.934 INFO [stdout]   18:     0x5604329ac9c9 - __rust_maybe_catch_panic
Dec 03 13:52:30.934 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 03 13:52:30.934 INFO [stdout]   19:     0x5604327266c6 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 13:52:30.934 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 03 13:52:30.934 INFO [stdout]   20:     0x56043299b97a - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 03 13:52:30.934 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 13:52:30.934 INFO [stdout]   21:     0x560432986ac5 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 13:52:30.934 INFO [stdout]   22:     0x7f0e0515a6b9 - start_thread
Dec 03 13:52:30.934 INFO [stdout]   23:     0x7f0e04c7a41c - clone
Dec 03 13:52:30.934 INFO [stdout]   24:                0x0 - <unknown>
Dec 03 13:52:30.934 INFO [stdout] 
Dec 03 13:52:30.934 INFO [stdout] ---- install_workflow_step_by_step stdout ----
Dec 03 13:52:30.934 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2
Dec 03 13:52:30.934 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2
Dec 03 13:52:30.934 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.014WH5o3cjqe/.venv/3.5.2/bin/pip[0m install --requirement /tmp/test-dmenv.014WH5o3cjqe/requirements.lock
Dec 03 13:52:30.934 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 03 13:52:30.934 INFO [stdout] stack backtrace:
Dec 03 13:52:30.934 INFO [stdout]    0:     0x56043299974e - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 03 13:52:30.934 INFO [stdout]    1:     0x560432987886 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 03 13:52:30.934 INFO [stdout]    2:     0x560432985cad - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:211
Dec 03 13:52:30.934 INFO [stdout]    3:     0x5604329859bb - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:221
Dec 03 13:52:30.934 INFO [stdout]    4:     0x56043298635c - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:477
Dec 03 13:52:30.934 INFO [stdout]    5:     0x560432985f19 - std::panicking::continue_panic_fmt::h2d2d2bfb065a70b2
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:391
Dec 03 13:52:30.934 INFO [stdout]    6:     0x560432985e15 - rust_begin_unwind
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:326
Dec 03 13:52:30.934 INFO [stdout]    7:     0x5604329df27b - core::panicking::panic_fmt::h2155aa66b67fe83c
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/panicking.rs:77
Dec 03 13:52:30.934 INFO [stdout]    8:     0x560432701963 - core::result::unwrap_failed::hc41f6a20f9af7e58
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/macros.rs:26
Dec 03 13:52:30.934 INFO [stdout]    9:     0x56043270152d - <core::result::Result<T, E>>::expect::hbef5507e60ca5435
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/result.rs:835
Dec 03 13:52:30.934 INFO [stdout]   10:     0x56043270dfe6 - integration_tests::helpers::TestApp::assert_run_ok::ha92a163ded2d38ca
Dec 03 13:52:30.934 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 03 13:52:30.934 INFO [stdout]   11:     0x56043270a18b - integration_tests::install_workflow_step_by_step::h1b92b5eb32c73c08
Dec 03 13:52:30.934 INFO [stdout]                                at tests/integration_tests.rs:77
Dec 03 13:52:30.934 INFO [stdout]   12:     0x56043270a029 - integration_tests::install_workflow_step_by_step::{{closure}}::h19dfb19f81e7a529
Dec 03 13:52:30.934 INFO [stdout]                                at tests/integration_tests.rs:72
Dec 03 13:52:30.934 INFO [stdout]   13:     0x560432701d6d - core::ops::function::FnOnce::call_once::h83308dfcbf118856
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:30.934 INFO [stdout]   14:     0x560432711e5e - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 03 13:52:30.934 INFO [stdout]                                at libtest/lib.rs:1461
Dec 03 13:52:30.934 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 03 13:52:30.934 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 03 13:52:30.934 INFO [stdout]   15:     0x5604329ac9c9 - __rust_maybe_catch_panic
Dec 03 13:52:30.934 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 03 13:52:30.934 INFO [stdout]   16:     0x56043271d7bd - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:30.934 INFO [stdout]                                at libtest/lib.rs:1416
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 03 13:52:30.934 INFO [stdout]   17:     0x560432735df4 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panic.rs:313
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:310
Dec 03 13:52:30.934 INFO [stdout]   18:     0x5604329ac9c9 - __rust_maybe_catch_panic
Dec 03 13:52:30.934 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 03 13:52:30.934 INFO [stdout]   19:     0x5604327266c6 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panicking.rs:289
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/panic.rs:392
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 03 13:52:30.934 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 03 13:52:30.934 INFO [stdout]   20:     0x56043299b97a - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 03 13:52:30.934 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 03 13:52:30.934 INFO [stdout]   21:     0x560432986ac5 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 03 13:52:30.934 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 03 13:52:30.934 INFO [stdout]   22:     0x7f0e0515a6b9 - start_thread
Dec 03 13:52:30.934 INFO [stdout]   23:     0x7f0e04c7a41c - clone
Dec 03 13:52:30.934 INFO [stdout]   24:                0x0 - <unknown>
Dec 03 13:52:30.934 INFO [stdout] 
Dec 03 13:52:30.934 INFO [stdout] 
Dec 03 13:52:30.934 INFO [stdout] failures:
Dec 03 13:52:30.934 INFO [stdout]     install_workflow_all_in_one
Dec 03 13:52:30.934 INFO [stdout]     install_workflow_step_by_step
Dec 03 13:52:30.934 INFO [stdout]     lock_workflow
Dec 03 13:52:30.934 INFO [stdout] 
Dec 03 13:52:30.934 INFO [stdout] test result: FAILED. 7 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 13:52:30.934 INFO [stdout] 
Dec 03 13:52:30.936 INFO [stderr] error: test failed, to rerun pass '--test integration_tests'
Dec 03 13:52:30.937 INFO [stderr] su: No module specific data is present
Dec 03 13:52:31.244 INFO running `"docker" "inspect" "8bacc93c6970fc36091c649c71643d38eff565fd31ef6c2f5b1c936519408fab"`
Dec 03 13:52:31.340 INFO running `"docker" "rm" "-f" "8bacc93c6970fc36091c649c71643d38eff565fd31ef6c2f5b1c936519408fab"`
Dec 03 13:52:31.479 INFO [stdout] 8bacc93c6970fc36091c649c71643d38eff565fd31ef6c2f5b1c936519408fab
