Dec 13 21:13:53.073 INFO testing dmerejkowsky/dmenv against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550 Dec 13 21:13:53.073 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 21:13:53.447 INFO [stdout] c24f49b516533237202fa0e95790a2a6062b04af6e86600b7e07178e8f4d4894 Dec 13 21:13:53.454 INFO running `"docker" "start" "-a" "c24f49b516533237202fa0e95790a2a6062b04af6e86600b7e07178e8f4d4894"` Dec 13 21:13:54.383 INFO [stderr] usermod: no changes Dec 13 21:13:54.516 INFO [stderr] Compiling appdirs v0.2.0 Dec 13 21:13:54.516 INFO [stderr] Compiling syn v0.15.17 Dec 13 21:14:05.539 INFO [stderr] Compiling synstructure v0.10.1 Dec 13 21:14:05.539 INFO [stderr] Compiling structopt-derive v0.2.12 Dec 13 21:14:05.539 INFO [stderr] Compiling serde_derive v1.0.80 Dec 13 21:14:12.208 INFO [stderr] Compiling failure_derive v0.1.3 Dec 13 21:14:12.920 INFO [stderr] Compiling structopt v0.2.12 Dec 13 21:14:16.252 INFO [stderr] Compiling failure v0.1.3 Dec 13 21:14:17.584 INFO [stderr] Compiling which v2.0.0 Dec 13 21:14:28.344 INFO [stderr] Compiling dmenv v0.7.0 (/source) Dec 13 21:14:35.217 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 40.81s Dec 13 21:14:35.221 INFO [stderr] su: No module specific data is present Dec 13 21:14:35.701 INFO running `"docker" "inspect" "c24f49b516533237202fa0e95790a2a6062b04af6e86600b7e07178e8f4d4894"` Dec 13 21:14:35.795 INFO running `"docker" "rm" "-f" "c24f49b516533237202fa0e95790a2a6062b04af6e86600b7e07178e8f4d4894"` Dec 13 21:14:35.914 INFO [stdout] c24f49b516533237202fa0e95790a2a6062b04af6e86600b7e07178e8f4d4894 Dec 13 21:14:35.917 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 21:14:36.175 INFO [stdout] e5b96c7267137c2ca12745f27473f856ff447be7194839e8b44d8616c76317f8 Dec 13 21:14:36.180 INFO running `"docker" "start" "-a" "e5b96c7267137c2ca12745f27473f856ff447be7194839e8b44d8616c76317f8"` Dec 13 21:14:36.682 INFO [stderr] usermod: no changes Dec 13 21:14:36.787 INFO [stderr] Compiling dmenv v0.7.0 (/source) Dec 13 21:14:40.575 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 3.87s Dec 13 21:14:40.583 INFO [stderr] su: No module specific data is present Dec 13 21:14:41.087 INFO running `"docker" "inspect" "e5b96c7267137c2ca12745f27473f856ff447be7194839e8b44d8616c76317f8"` Dec 13 21:14:41.335 INFO running `"docker" "rm" "-f" "e5b96c7267137c2ca12745f27473f856ff447be7194839e8b44d8616c76317f8"` Dec 13 21:14:41.601 INFO [stdout] e5b96c7267137c2ca12745f27473f856ff447be7194839e8b44d8616c76317f8 Dec 13 21:14:41.604 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-3/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 21:14:41.987 INFO [stdout] fb52baf7b59bdb0908370e13ad7d5e6046db8ae1d2383ff9c14df69b97bb46ae Dec 13 21:14:41.993 INFO running `"docker" "start" "-a" "fb52baf7b59bdb0908370e13ad7d5e6046db8ae1d2383ff9c14df69b97bb46ae"` Dec 13 21:14:42.891 INFO [stderr] usermod: no changes Dec 13 21:14:43.047 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.12s Dec 13 21:14:43.051 INFO [stderr] Running /target/debug/deps/dmenv-fb3261464f433d1f Dec 13 21:14:43.051 INFO [stdout] Dec 13 21:14:43.051 INFO [stdout] running 4 tests Dec 13 21:14:43.074 INFO [stdout] test lock::tests::git_bump ... ok Dec 13 21:14:43.074 INFO [stdout] test lock::tests::simple_bump ... ok Dec 13 21:14:43.074 INFO [stdout] test lock::tests::dep_not_found ... ok Dec 13 21:14:43.074 INFO [stdout] test lock::tests::malformed_lock ... ok Dec 13 21:14:43.074 INFO [stdout] Dec 13 21:14:43.074 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 21:14:43.074 INFO [stdout] Dec 13 21:14:43.079 INFO [stderr] Running /target/debug/deps/dmenv-2881a8667e15dba7 Dec 13 21:14:43.085 INFO [stderr] Running /target/debug/deps/integration_tests-7beb00a2560f59e0 Dec 13 21:14:43.085 INFO [stdout] Dec 13 21:14:43.085 INFO [stdout] running 0 tests Dec 13 21:14:43.085 INFO [stdout] Dec 13 21:14:43.085 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 21:14:43.085 INFO [stdout] Dec 13 21:14:43.085 INFO [stdout] Dec 13 21:14:43.085 INFO [stdout] running 12 tests Dec 13 21:14:43.519 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok Dec 13 21:14:43.519 INFO [stdout] test install_without_lock ... ok Dec 13 21:14:43.522 INFO [stdout] test bump_in_lock_simple ... ok Dec 13 21:14:43.523 INFO [stdout] test run_without_args ... ok Dec 13 21:14:43.539 INFO [stdout] test init_generates_setup_py ... ok Dec 13 21:14:43.576 INFO [stdout] test bump_in_lock_git ... ok Dec 13 21:14:43.579 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok Dec 13 21:14:43.747 INFO [stdout] test run_without_virtualenv ... ok Dec 13 21:14:43.857 INFO [stdout] test show_venv_path ... ok Dec 13 21:14:49.033 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 13 21:14:49.033 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 13 21:14:49.033 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 13 21:14:49.033 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 13 21:14:49.039 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 13 21:14:49.039 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 13 21:14:49.088 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.rWwI9eQdftQ4/requirements.lock (line 2)) Dec 13 21:14:49.131 INFO [stderr] Exception: Dec 13 21:14:49.131 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.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 13 21:14:49.131 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.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 13 21:14:49.131 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 13 21:14:49.131 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 13 21:14:49.131 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 13 21:14:49.131 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 13 21:14:49.131 INFO [stderr] Dec 13 21:14:49.131 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:49.131 INFO [stderr] Dec 13 21:14:49.131 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 13 21:14:49.131 INFO [stderr] body=body, headers=headers) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.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 13 21:14:49.131 INFO [stderr] self._validate_conn(conn) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.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 13 21:14:49.131 INFO [stderr] conn.connect() Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 13 21:14:49.131 INFO [stderr] conn = self._new_conn() Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.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 13 21:14:49.131 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 13 21:14:49.131 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 13 21:14:49.131 INFO [stderr] Dec 13 21:14:49.131 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:49.131 INFO [stderr] Dec 13 21:14:49.131 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 13 21:14:49.131 INFO [stderr] status = self.run(options, args) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 13 21:14:49.131 INFO [stderr] requirement_set.prepare_files(finder) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 13 21:14:49.131 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 13 21:14:49.131 INFO [stderr] req_to_install, finder) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 13 21:14:49.131 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 13 21:14:49.131 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 13 21:14:49.131 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 13 21:14:49.131 INFO [stderr] page = self._get_page(location) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 13 21:14:49.131 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 13 21:14:49.131 INFO [stderr] "Cache-Control": "max-age=600", Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 13 21:14:49.131 INFO [stderr] return self.request('GET', url, **kwargs) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 13 21:14:49.131 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 13 21:14:49.131 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 13 21:14:49.131 INFO [stderr] r = adapter.send(request, **kwargs) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 13 21:14:49.131 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 13 21:14:49.131 INFO [stderr] timeout=timeout Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 13 21:14:49.131 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 13 21:14:49.131 INFO [stderr] File "/tmp/test-dmenv.rSgOGZ90JnsC/.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 13 21:14:49.131 INFO [stderr] total -= 1 Dec 13 21:14:49.131 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 13 21:14:49.135 INFO [stderr] Exception: Dec 13 21:14:49.135 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.135 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.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 13 21:14:49.135 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 13 21:14:49.135 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.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 13 21:14:49.135 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 13 21:14:49.135 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 13 21:14:49.135 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 13 21:14:49.135 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 13 21:14:49.135 INFO [stderr] Dec 13 21:14:49.135 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:49.135 INFO [stderr] Dec 13 21:14:49.135 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.135 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 13 21:14:49.135 INFO [stderr] body=body, headers=headers) Dec 13 21:14:49.135 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.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 13 21:14:49.135 INFO [stderr] self._validate_conn(conn) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.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 13 21:14:49.136 INFO [stderr] conn.connect() Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 13 21:14:49.136 INFO [stderr] conn = self._new_conn() Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.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 13 21:14:49.136 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 13 21:14:49.136 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 13 21:14:49.136 INFO [stderr] Dec 13 21:14:49.136 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:49.136 INFO [stderr] Dec 13 21:14:49.136 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 13 21:14:49.136 INFO [stderr] status = self.run(options, args) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 13 21:14:49.136 INFO [stderr] requirement_set.prepare_files(finder) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 13 21:14:49.136 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 13 21:14:49.136 INFO [stderr] req_to_install, finder) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 13 21:14:49.136 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 13 21:14:49.136 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 13 21:14:49.136 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 13 21:14:49.136 INFO [stderr] page = self._get_page(location) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 13 21:14:49.136 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 13 21:14:49.136 INFO [stderr] "Cache-Control": "max-age=600", Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 13 21:14:49.136 INFO [stderr] return self.request('GET', url, **kwargs) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 13 21:14:49.136 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 13 21:14:49.136 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 13 21:14:49.136 INFO [stderr] r = adapter.send(request, **kwargs) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 13 21:14:49.136 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 13 21:14:49.136 INFO [stderr] timeout=timeout Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 13 21:14:49.136 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 13 21:14:49.136 INFO [stderr] File "/tmp/test-dmenv.lUM1Fj5z1nMS/.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 13 21:14:49.136 INFO [stderr] total -= 1 Dec 13 21:14:49.136 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 13 21:14:49.147 INFO [stderr] Exception: Dec 13 21:14:49.147 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.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 13 21:14:49.147 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.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 13 21:14:49.147 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 13 21:14:49.147 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 13 21:14:49.147 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 13 21:14:49.147 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 13 21:14:49.147 INFO [stderr] Dec 13 21:14:49.147 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:49.147 INFO [stderr] Dec 13 21:14:49.147 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 13 21:14:49.147 INFO [stderr] body=body, headers=headers) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.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 13 21:14:49.147 INFO [stderr] self._validate_conn(conn) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.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 13 21:14:49.147 INFO [stderr] conn.connect() Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 13 21:14:49.147 INFO [stderr] conn = self._new_conn() Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.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 13 21:14:49.147 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 13 21:14:49.147 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 13 21:14:49.147 INFO [stderr] Dec 13 21:14:49.147 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:49.147 INFO [stderr] Dec 13 21:14:49.147 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 13 21:14:49.147 INFO [stderr] status = self.run(options, args) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 13 21:14:49.147 INFO [stderr] requirement_set.prepare_files(finder) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 13 21:14:49.147 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file Dec 13 21:14:49.147 INFO [stderr] finder, self.upgrade, require_hashes) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link Dec 13 21:14:49.147 INFO [stderr] self.link = finder.find_requirement(self, upgrade) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 13 21:14:49.147 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 13 21:14:49.147 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 13 21:14:49.147 INFO [stderr] page = self._get_page(location) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 13 21:14:49.147 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 13 21:14:49.147 INFO [stderr] "Cache-Control": "max-age=600", Dec 13 21:14:49.147 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 13 21:14:49.148 INFO [stderr] return self.request('GET', url, **kwargs) Dec 13 21:14:49.148 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 13 21:14:49.148 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 13 21:14:49.148 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 13 21:14:49.148 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 13 21:14:49.148 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 13 21:14:49.148 INFO [stderr] r = adapter.send(request, **kwargs) Dec 13 21:14:49.148 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 13 21:14:49.148 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 13 21:14:49.148 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 13 21:14:49.148 INFO [stderr] timeout=timeout Dec 13 21:14:49.148 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 13 21:14:49.148 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 13 21:14:49.148 INFO [stderr] File "/tmp/test-dmenv.rWwI9eQdftQ4/.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 13 21:14:49.148 INFO [stderr] total -= 1 Dec 13 21:14:49.148 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 13 21:14:49.447 INFO [stdout] test install_workflow_step_by_step ... FAILED Dec 13 21:14:49.447 INFO [stdout] test lock_workflow ... FAILED Dec 13 21:14:49.449 INFO [stdout] test install_workflow_all_in_one ... FAILED Dec 13 21:14:49.449 INFO [stdout] Dec 13 21:14:49.449 INFO [stdout] failures: Dec 13 21:14:49.449 INFO [stdout] Dec 13 21:14:49.449 INFO [stdout] ---- install_workflow_step_by_step stdout ---- Dec 13 21:14:49.449 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2 Dec 13 21:14:49.449 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2 Dec 13 21:14:49.449 INFO [stdout] -> Running /tmp/test-dmenv.rWwI9eQdftQ4/.venv/3.5.2/bin/pip install --requirement /tmp/test-dmenv.rWwI9eQdftQ4/requirements.lock Dec 13 21:14:49.449 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 13 21:14:49.449 INFO [stdout] stack backtrace: Dec 13 21:14:49.449 INFO [stdout] 0: 0x5653d543ec23 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd Dec 13 21:14:49.449 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 21:14:49.449 INFO [stdout] 1: 0x5653d5439368 - std::sys_common::backtrace::_print::he11ceebc70626ac9 Dec 13 21:14:49.449 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 21:14:49.449 INFO [stdout] 2: 0x5653d543db42 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413 Dec 13 21:14:49.449 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 21:14:49.449 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 21:14:49.450 INFO [stdout] 3: 0x5653d543d837 - std::panicking::default_hook::hf0c15a583008ed0d Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 21:14:49.450 INFO [stdout] 4: 0x5653d543e250 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185 Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 21:14:49.450 INFO [stdout] 5: 0x5653d543ddd1 - std::panicking::continue_panic_fmt::h42bc2d03ddf03b3e Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:394 Dec 13 21:14:49.450 INFO [stdout] 6: 0x5653d543dcb5 - rust_begin_unwind Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:321 Dec 13 21:14:49.450 INFO [stdout] 7: 0x5653d54547dc - core::panicking::panic_fmt::h44df04385fc0c5a6 Dec 13 21:14:49.450 INFO [stdout] at src/libcore/panicking.rs:95 Dec 13 21:14:49.450 INFO [stdout] 8: 0x5653d5211c20 - core::result::unwrap_failed::ha8efd6fe9c2b3ade Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/macros.rs:26 Dec 13 21:14:49.450 INFO [stdout] 9: 0x5653d51ac1a8 - >::expect::h4ce543ae230b980f Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/result.rs:835 Dec 13 21:14:49.450 INFO [stdout] 10: 0x5653d51ab816 - integration_tests::helpers::TestApp::assert_run_ok::hf1a44514c562c799 Dec 13 21:14:49.450 INFO [stdout] at tests/helpers/mod.rs:57 Dec 13 21:14:49.450 INFO [stdout] 11: 0x5653d51a38c0 - integration_tests::install_workflow_step_by_step::h2844ad16aac3e2d6 Dec 13 21:14:49.450 INFO [stdout] at tests/integration_tests.rs:107 Dec 13 21:14:49.450 INFO [stdout] 12: 0x5653d51a3749 - integration_tests::install_workflow_step_by_step::{{closure}}::hfafaa6386ce2780b Dec 13 21:14:49.450 INFO [stdout] at tests/integration_tests.rs:102 Dec 13 21:14:49.450 INFO [stdout] 13: 0x5653d519e12d - core::ops::function::FnOnce::call_once::hda58b2c696ca2941 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 21:14:49.450 INFO [stdout] 14: 0x5653d51b5d7e - >::call_box::hac337ba1af72dc2b Dec 13 21:14:49.450 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 21:14:49.450 INFO [stdout] 15: 0x5653d5451cc9 - __rust_maybe_catch_panic Dec 13 21:14:49.450 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:49.450 INFO [stdout] 16: 0x5653d51d3587 - test::run_test::run_test_inner::{{closure}}::hfbfe4a58c0ebb01b Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 21:14:49.450 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 21:14:49.450 INFO [stdout] 17: 0x5653d51ae494 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd1f0cf202092f8d7 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136 Dec 13 21:14:49.450 INFO [stdout] 18: 0x5653d51aea84 - std::panicking::try::do_call::h5e99f92fbf448cf6 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306 Dec 13 21:14:49.450 INFO [stdout] 19: 0x5653d5451cc9 - __rust_maybe_catch_panic Dec 13 21:14:49.450 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:49.450 INFO [stdout] 20: 0x5653d51b5c5c - >::call_box::h8ae063aff93ccdb6 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478 Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 21:14:49.450 INFO [stdout] 21: 0x5653d5445c8d - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683 Dec 13 21:14:49.450 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 21:14:49.450 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 21:14:49.450 INFO [stdout] 22: 0x7f5b761716b9 - start_thread Dec 13 21:14:49.450 INFO [stdout] 23: 0x7f5b75c9141c - clone Dec 13 21:14:49.450 INFO [stdout] 24: 0x0 - Dec 13 21:14:49.450 INFO [stdout] Dec 13 21:14:49.450 INFO [stdout] ---- lock_workflow stdout ---- Dec 13 21:14:49.450 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2 Dec 13 21:14:49.450 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2 Dec 13 21:14:49.450 INFO [stdout] -> Running /tmp/test-dmenv.rSgOGZ90JnsC/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 13 21:14:49.450 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 13 21:14:49.450 INFO [stdout] stack backtrace: Dec 13 21:14:49.450 INFO [stdout] 0: 0x5653d543ec23 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd Dec 13 21:14:49.450 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 21:14:49.450 INFO [stdout] 1: 0x5653d5439368 - std::sys_common::backtrace::_print::he11ceebc70626ac9 Dec 13 21:14:49.450 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 21:14:49.450 INFO [stdout] 2: 0x5653d543db42 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413 Dec 13 21:14:49.450 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 21:14:49.450 INFO [stdout] 3: 0x5653d543d837 - std::panicking::default_hook::hf0c15a583008ed0d Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 21:14:49.450 INFO [stdout] 4: 0x5653d543e250 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185 Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 21:14:49.450 INFO [stdout] 5: 0x5653d543ddd1 - std::panicking::continue_panic_fmt::h42bc2d03ddf03b3e Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:394 Dec 13 21:14:49.450 INFO [stdout] 6: 0x5653d543dcb5 - rust_begin_unwind Dec 13 21:14:49.450 INFO [stdout] at src/libstd/panicking.rs:321 Dec 13 21:14:49.450 INFO [stdout] 7: 0x5653d54547dc - core::panicking::panic_fmt::h44df04385fc0c5a6 Dec 13 21:14:49.450 INFO [stdout] at src/libcore/panicking.rs:95 Dec 13 21:14:49.450 INFO [stdout] 8: 0x5653d5211c20 - core::result::unwrap_failed::ha8efd6fe9c2b3ade Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/macros.rs:26 Dec 13 21:14:49.450 INFO [stdout] 9: 0x5653d51ac1a8 - >::expect::h4ce543ae230b980f Dec 13 21:14:49.450 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/result.rs:835 Dec 13 21:14:49.451 INFO [stdout] 10: 0x5653d51ab816 - integration_tests::helpers::TestApp::assert_run_ok::hf1a44514c562c799 Dec 13 21:14:49.451 INFO [stdout] at tests/helpers/mod.rs:57 Dec 13 21:14:49.451 INFO [stdout] 11: 0x5653d51a32d5 - integration_tests::lock_workflow::hc32d9c5889eb9a61 Dec 13 21:14:49.451 INFO [stdout] at tests/integration_tests.rs:83 Dec 13 21:14:49.451 INFO [stdout] 12: 0x5653d51a31a9 - integration_tests::lock_workflow::{{closure}}::hf3cf0e604f5fec54 Dec 13 21:14:49.451 INFO [stdout] at tests/integration_tests.rs:80 Dec 13 21:14:49.451 INFO [stdout] 13: 0x5653d519dead - core::ops::function::FnOnce::call_once::h0a8662aad880bc4b Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 21:14:49.451 INFO [stdout] 14: 0x5653d51b5d7e - >::call_box::hac337ba1af72dc2b Dec 13 21:14:49.451 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 21:14:49.451 INFO [stdout] 15: 0x5653d5451cc9 - __rust_maybe_catch_panic Dec 13 21:14:49.451 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:49.451 INFO [stdout] 16: 0x5653d51d3587 - test::run_test::run_test_inner::{{closure}}::hfbfe4a58c0ebb01b Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 21:14:49.451 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 21:14:49.451 INFO [stdout] 17: 0x5653d51ae494 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd1f0cf202092f8d7 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136 Dec 13 21:14:49.451 INFO [stdout] 18: 0x5653d51aea84 - std::panicking::try::do_call::h5e99f92fbf448cf6 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306 Dec 13 21:14:49.451 INFO [stdout] 19: 0x5653d5451cc9 - __rust_maybe_catch_panic Dec 13 21:14:49.451 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:49.451 INFO [stdout] 20: 0x5653d51b5c5c - >::call_box::h8ae063aff93ccdb6 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 21:14:49.451 INFO [stdout] 21: 0x5653d5445c8d - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683 Dec 13 21:14:49.451 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 21:14:49.451 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 21:14:49.451 INFO [stdout] 22: 0x7f5b761716b9 - start_thread Dec 13 21:14:49.451 INFO [stdout] 23: 0x7f5b75c9141c - clone Dec 13 21:14:49.451 INFO [stdout] 24: 0x0 - Dec 13 21:14:49.451 INFO [stdout] Dec 13 21:14:49.451 INFO [stdout] ---- install_workflow_all_in_one stdout ---- Dec 13 21:14:49.451 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2 Dec 13 21:14:49.451 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2 Dec 13 21:14:49.451 INFO [stdout] -> Running /tmp/test-dmenv.lUM1Fj5z1nMS/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 13 21:14:49.451 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 13 21:14:49.451 INFO [stdout] stack backtrace: Dec 13 21:14:49.451 INFO [stdout] 0: 0x5653d543ec23 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd Dec 13 21:14:49.451 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 21:14:49.451 INFO [stdout] 1: 0x5653d5439368 - std::sys_common::backtrace::_print::he11ceebc70626ac9 Dec 13 21:14:49.451 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 21:14:49.451 INFO [stdout] 2: 0x5653d543db42 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413 Dec 13 21:14:49.451 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 21:14:49.451 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 21:14:49.451 INFO [stdout] 3: 0x5653d543d837 - std::panicking::default_hook::hf0c15a583008ed0d Dec 13 21:14:49.451 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 21:14:49.451 INFO [stdout] 4: 0x5653d543e250 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185 Dec 13 21:14:49.451 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 21:14:49.451 INFO [stdout] 5: 0x5653d543ddd1 - std::panicking::continue_panic_fmt::h42bc2d03ddf03b3e Dec 13 21:14:49.451 INFO [stdout] at src/libstd/panicking.rs:394 Dec 13 21:14:49.451 INFO [stdout] 6: 0x5653d543dcb5 - rust_begin_unwind Dec 13 21:14:49.451 INFO [stdout] at src/libstd/panicking.rs:321 Dec 13 21:14:49.451 INFO [stdout] 7: 0x5653d54547dc - core::panicking::panic_fmt::h44df04385fc0c5a6 Dec 13 21:14:49.451 INFO [stdout] at src/libcore/panicking.rs:95 Dec 13 21:14:49.451 INFO [stdout] 8: 0x5653d5211c20 - core::result::unwrap_failed::ha8efd6fe9c2b3ade Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/macros.rs:26 Dec 13 21:14:49.451 INFO [stdout] 9: 0x5653d51ac1a8 - >::expect::h4ce543ae230b980f Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/result.rs:835 Dec 13 21:14:49.451 INFO [stdout] 10: 0x5653d51ab816 - integration_tests::helpers::TestApp::assert_run_ok::hf1a44514c562c799 Dec 13 21:14:49.451 INFO [stdout] at tests/helpers/mod.rs:57 Dec 13 21:14:49.451 INFO [stdout] 11: 0x5653d51a36e8 - integration_tests::install_workflow_all_in_one::h719aa992d8ee4df8 Dec 13 21:14:49.451 INFO [stdout] at tests/integration_tests.rs:98 Dec 13 21:14:49.451 INFO [stdout] 12: 0x5653d51a3599 - integration_tests::install_workflow_all_in_one::{{closure}}::h163e7f0ab1914e20 Dec 13 21:14:49.451 INFO [stdout] at tests/integration_tests.rs:93 Dec 13 21:14:49.451 INFO [stdout] 13: 0x5653d519dfad - core::ops::function::FnOnce::call_once::h9455e03d5d23dfbb Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 21:14:49.451 INFO [stdout] 14: 0x5653d51b5d7e - >::call_box::hac337ba1af72dc2b Dec 13 21:14:49.451 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238 Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 21:14:49.451 INFO [stdout] 15: 0x5653d5451cc9 - __rust_maybe_catch_panic Dec 13 21:14:49.451 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:49.451 INFO [stdout] 16: 0x5653d51d3587 - test::run_test::run_test_inner::{{closure}}::hfbfe4a58c0ebb01b Dec 13 21:14:49.451 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 21:14:49.452 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 21:14:49.452 INFO [stdout] 17: 0x5653d51ae494 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd1f0cf202092f8d7 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136 Dec 13 21:14:49.452 INFO [stdout] 18: 0x5653d51aea84 - std::panicking::try::do_call::h5e99f92fbf448cf6 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306 Dec 13 21:14:49.452 INFO [stdout] 19: 0x5653d5451cc9 - __rust_maybe_catch_panic Dec 13 21:14:49.452 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:49.452 INFO [stdout] 20: 0x5653d51b5c5c - >::call_box::h8ae063aff93ccdb6 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478 Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673 Dec 13 21:14:49.452 INFO [stdout] 21: 0x5653d5445c8d - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f Dec 13 21:14:49.452 INFO [stdout] at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683 Dec 13 21:14:49.452 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 21:14:49.452 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 21:14:49.452 INFO [stdout] 22: 0x7f5b761716b9 - start_thread Dec 13 21:14:49.452 INFO [stdout] 23: 0x7f5b75c9141c - clone Dec 13 21:14:49.452 INFO [stdout] 24: 0x0 - Dec 13 21:14:49.452 INFO [stdout] Dec 13 21:14:49.452 INFO [stdout] Dec 13 21:14:49.452 INFO [stdout] failures: Dec 13 21:14:49.452 INFO [stdout] install_workflow_all_in_one Dec 13 21:14:49.452 INFO [stdout] install_workflow_step_by_step Dec 13 21:14:49.452 INFO [stdout] lock_workflow Dec 13 21:14:49.452 INFO [stdout] Dec 13 21:14:49.452 INFO [stdout] test result: FAILED. 9 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 21:14:49.452 INFO [stdout] Dec 13 21:14:49.454 INFO [stderr] error: test failed, to rerun pass '--test integration_tests' Dec 13 21:14:49.455 INFO [stderr] su: No module specific data is present Dec 13 21:14:50.227 INFO running `"docker" "inspect" "fb52baf7b59bdb0908370e13ad7d5e6046db8ae1d2383ff9c14df69b97bb46ae"` Dec 13 21:14:50.464 INFO running `"docker" "rm" "-f" "fb52baf7b59bdb0908370e13ad7d5e6046db8ae1d2383ff9c14df69b97bb46ae"` Dec 13 21:14:50.707 INFO [stdout] fb52baf7b59bdb0908370e13ad7d5e6046db8ae1d2383ff9c14df69b97bb46ae