Dec 15 16:46:08.924 INFO testing dmenv-0.7.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 15 16:46:08.924 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 16:46:09.170 INFO [stdout] b94ad9e9b7ac2f3ba12be8e4e2682c45bed8123906960d2ac5d642acbc351236 Dec 15 16:46:09.172 INFO running `"docker" "start" "-a" "b94ad9e9b7ac2f3ba12be8e4e2682c45bed8123906960d2ac5d642acbc351236"` Dec 15 16:46:09.743 INFO [stderr] usermod: no changes Dec 15 16:46:09.866 INFO [stderr] Compiling dmenv v0.7.0 (/source) Dec 15 16:46:19.831 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 10.07s Dec 15 16:46:19.846 INFO [stderr] su: No module specific data is present Dec 15 16:46:20.851 INFO running `"docker" "inspect" "b94ad9e9b7ac2f3ba12be8e4e2682c45bed8123906960d2ac5d642acbc351236"` Dec 15 16:46:21.205 INFO running `"docker" "rm" "-f" "b94ad9e9b7ac2f3ba12be8e4e2682c45bed8123906960d2ac5d642acbc351236"` Dec 15 16:46:21.552 INFO [stdout] b94ad9e9b7ac2f3ba12be8e4e2682c45bed8123906960d2ac5d642acbc351236 Dec 15 16:46:21.556 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 16:46:22.103 INFO [stdout] 5d94a2d359916590e5092bf2c37226459ba3fc12cfed97a50cba5a653e534071 Dec 15 16:46:22.105 INFO running `"docker" "start" "-a" "5d94a2d359916590e5092bf2c37226459ba3fc12cfed97a50cba5a653e534071"` Dec 15 16:46:23.315 INFO [stderr] usermod: no changes Dec 15 16:46:23.503 INFO [stderr] Compiling dmenv v0.7.0 (/source) Dec 15 16:46:30.027 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 6.68s Dec 15 16:46:30.036 INFO [stderr] su: No module specific data is present Dec 15 16:46:30.362 INFO running `"docker" "inspect" "5d94a2d359916590e5092bf2c37226459ba3fc12cfed97a50cba5a653e534071"` Dec 15 16:46:30.455 INFO running `"docker" "rm" "-f" "5d94a2d359916590e5092bf2c37226459ba3fc12cfed97a50cba5a653e534071"` Dec 15 16:46:30.550 INFO [stdout] 5d94a2d359916590e5092bf2c37226459ba3fc12cfed97a50cba5a653e534071 Dec 15 16:46:30.555 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 16:46:30.723 INFO [stdout] 01edbf9427c8b28dc85d30b3a6865b955c146b767ed8bca02cd9d48ec3e2fdb7 Dec 15 16:46:30.725 INFO running `"docker" "start" "-a" "01edbf9427c8b28dc85d30b3a6865b955c146b767ed8bca02cd9d48ec3e2fdb7"` Dec 15 16:46:31.735 INFO [stderr] usermod: no changes Dec 15 16:46:31.923 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.16s Dec 15 16:46:31.931 INFO [stderr] Running /target/debug/deps/dmenv-284fbd7798310a74 Dec 15 16:46:31.935 INFO [stdout] Dec 15 16:46:31.935 INFO [stdout] running 4 tests Dec 15 16:46:31.939 INFO [stdout] test lock::tests::dep_not_found ... ok Dec 15 16:46:31.939 INFO [stdout] test lock::tests::malformed_lock ... ok Dec 15 16:46:31.947 INFO [stderr] Running /target/debug/deps/dmenv-997241e494bdca87 Dec 15 16:46:31.947 INFO [stdout] test lock::tests::git_bump ... ok Dec 15 16:46:31.947 INFO [stdout] test lock::tests::simple_bump ... ok Dec 15 16:46:31.947 INFO [stdout] Dec 15 16:46:31.947 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 16:46:31.947 INFO [stdout] Dec 15 16:46:31.959 INFO [stderr] Running /target/debug/deps/integration_tests-043ed3fb8546dd70 Dec 15 16:46:31.959 INFO [stdout] Dec 15 16:46:31.959 INFO [stdout] running 0 tests Dec 15 16:46:31.959 INFO [stdout] Dec 15 16:46:31.959 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 16:46:31.959 INFO [stdout] Dec 15 16:46:31.979 INFO [stdout] Dec 15 16:46:31.979 INFO [stdout] running 12 tests Dec 15 16:46:32.239 INFO [stdout] test init_generates_setup_py ... ok Dec 15 16:46:32.287 INFO [stdout] test install_without_lock ... ok Dec 15 16:46:32.291 INFO [stdout] test run_without_args ... ok Dec 15 16:46:32.299 INFO [stdout] test bump_in_lock_simple ... ok Dec 15 16:46:32.311 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok Dec 15 16:46:32.371 INFO [stdout] test bump_in_lock_git ... ok Dec 15 16:46:32.514 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok Dec 15 16:46:32.519 INFO [stdout] test run_without_virtualenv ... ok Dec 15 16:46:32.519 INFO [stdout] test show_venv_path ... ok Dec 15 16:46:36.381 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 15 16:46:36.381 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 15 16:46:36.417 INFO [stderr] Exception: Dec 15 16:46:36.417 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.417 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.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 15 16:46:36.417 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 15 16:46:36.417 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.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 15 16:46:36.417 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 15 16:46:36.417 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 15 16:46:36.417 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 15 16:46:36.417 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 15 16:46:36.417 INFO [stderr] Dec 15 16:46:36.417 INFO [stderr] During handling of the above exception, another exception occurred: Dec 15 16:46:36.417 INFO [stderr] Dec 15 16:46:36.417 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.417 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 15 16:46:36.417 INFO [stderr] body=body, headers=headers) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.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 15 16:46:36.418 INFO [stderr] self._validate_conn(conn) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.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 15 16:46:36.418 INFO [stderr] conn.connect() Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 15 16:46:36.418 INFO [stderr] conn = self._new_conn() Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.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 15 16:46:36.418 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 15 16:46:36.418 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 15 16:46:36.418 INFO [stderr] Dec 15 16:46:36.418 INFO [stderr] During handling of the above exception, another exception occurred: Dec 15 16:46:36.418 INFO [stderr] Dec 15 16:46:36.418 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 15 16:46:36.418 INFO [stderr] status = self.run(options, args) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 15 16:46:36.418 INFO [stderr] requirement_set.prepare_files(finder) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 15 16:46:36.418 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 15 16:46:36.418 INFO [stderr] req_to_install, finder) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 15 16:46:36.418 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 15 16:46:36.418 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 15 16:46:36.418 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 15 16:46:36.418 INFO [stderr] page = self._get_page(location) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 15 16:46:36.418 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 15 16:46:36.418 INFO [stderr] "Cache-Control": "max-age=600", Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 15 16:46:36.418 INFO [stderr] return self.request('GET', url, **kwargs) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 15 16:46:36.418 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 15 16:46:36.418 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 15 16:46:36.418 INFO [stderr] r = adapter.send(request, **kwargs) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 15 16:46:36.418 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 15 16:46:36.418 INFO [stderr] timeout=timeout Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 15 16:46:36.418 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 15 16:46:36.418 INFO [stderr] File "/tmp/test-dmenv.f52MKm1jXlDi/.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 15 16:46:36.418 INFO [stderr] total -= 1 Dec 15 16:46:36.418 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 15 16:46:36.563 INFO [stdout] test lock_workflow ... FAILED Dec 15 16:46:36.639 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 15 16:46:36.639 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 15 16:46:36.675 INFO [stderr] Exception: Dec 15 16:46:36.675 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.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 15 16:46:36.675 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.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 15 16:46:36.675 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 15 16:46:36.675 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 15 16:46:36.675 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 15 16:46:36.675 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 15 16:46:36.675 INFO [stderr] Dec 15 16:46:36.675 INFO [stderr] During handling of the above exception, another exception occurred: Dec 15 16:46:36.675 INFO [stderr] Dec 15 16:46:36.675 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 15 16:46:36.675 INFO [stderr] body=body, headers=headers) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.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 15 16:46:36.675 INFO [stderr] self._validate_conn(conn) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.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 15 16:46:36.675 INFO [stderr] conn.connect() Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 15 16:46:36.675 INFO [stderr] conn = self._new_conn() Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.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 15 16:46:36.675 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 15 16:46:36.675 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 15 16:46:36.675 INFO [stderr] Dec 15 16:46:36.675 INFO [stderr] During handling of the above exception, another exception occurred: Dec 15 16:46:36.675 INFO [stderr] Dec 15 16:46:36.675 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 15 16:46:36.675 INFO [stderr] status = self.run(options, args) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 15 16:46:36.675 INFO [stderr] requirement_set.prepare_files(finder) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 15 16:46:36.675 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 15 16:46:36.675 INFO [stderr] req_to_install, finder) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 15 16:46:36.675 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 15 16:46:36.675 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 15 16:46:36.675 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 15 16:46:36.675 INFO [stderr] page = self._get_page(location) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 15 16:46:36.675 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 15 16:46:36.675 INFO [stderr] "Cache-Control": "max-age=600", Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 15 16:46:36.675 INFO [stderr] return self.request('GET', url, **kwargs) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 15 16:46:36.675 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 15 16:46:36.675 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 15 16:46:36.675 INFO [stderr] r = adapter.send(request, **kwargs) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 15 16:46:36.675 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 15 16:46:36.675 INFO [stderr] timeout=timeout Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 15 16:46:36.675 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 15 16:46:36.675 INFO [stderr] File "/tmp/test-dmenv.slgHP6rm1vNS/.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 15 16:46:36.675 INFO [stderr] total -= 1 Dec 15 16:46:36.675 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 15 16:46:36.784 INFO [stdout] test install_workflow_all_in_one ... FAILED Dec 15 16:46:36.834 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 15 16:46:36.834 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 15 16:46:36.868 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.QbjfnuasVbDa/requirements.lock (line 2)) Dec 15 16:46:36.909 INFO [stderr] Exception: Dec 15 16:46:36.909 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.909 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.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 15 16:46:36.910 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.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 15 16:46:36.910 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 15 16:46:36.910 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 15 16:46:36.910 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 15 16:46:36.910 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 15 16:46:36.910 INFO [stderr] Dec 15 16:46:36.910 INFO [stderr] During handling of the above exception, another exception occurred: Dec 15 16:46:36.910 INFO [stderr] Dec 15 16:46:36.910 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 15 16:46:36.910 INFO [stderr] body=body, headers=headers) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.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 15 16:46:36.910 INFO [stderr] self._validate_conn(conn) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.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 15 16:46:36.910 INFO [stderr] conn.connect() Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 15 16:46:36.910 INFO [stderr] conn = self._new_conn() Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.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 15 16:46:36.910 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 15 16:46:36.910 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 15 16:46:36.910 INFO [stderr] Dec 15 16:46:36.910 INFO [stderr] During handling of the above exception, another exception occurred: Dec 15 16:46:36.910 INFO [stderr] Dec 15 16:46:36.910 INFO [stderr] Traceback (most recent call last): Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 15 16:46:36.910 INFO [stderr] status = self.run(options, args) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 15 16:46:36.910 INFO [stderr] requirement_set.prepare_files(finder) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 15 16:46:36.910 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file Dec 15 16:46:36.910 INFO [stderr] finder, self.upgrade, require_hashes) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link Dec 15 16:46:36.910 INFO [stderr] self.link = finder.find_requirement(self, upgrade) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 15 16:46:36.910 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 15 16:46:36.910 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 15 16:46:36.910 INFO [stderr] page = self._get_page(location) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 15 16:46:36.910 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 15 16:46:36.910 INFO [stderr] "Cache-Control": "max-age=600", Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 15 16:46:36.910 INFO [stderr] return self.request('GET', url, **kwargs) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 15 16:46:36.910 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 15 16:46:36.910 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 15 16:46:36.910 INFO [stderr] r = adapter.send(request, **kwargs) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 15 16:46:36.910 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 15 16:46:36.910 INFO [stderr] timeout=timeout Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 15 16:46:36.910 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 15 16:46:36.910 INFO [stderr] File "/tmp/test-dmenv.QbjfnuasVbDa/.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 15 16:46:36.910 INFO [stderr] total -= 1 Dec 15 16:46:36.910 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 15 16:46:36.983 INFO [stdout] test install_workflow_step_by_step ... FAILED Dec 15 16:46:36.983 INFO [stdout] Dec 15 16:46:36.983 INFO [stdout] failures: Dec 15 16:46:36.983 INFO [stdout] Dec 15 16:46:36.983 INFO [stdout] ---- lock_workflow stdout ---- Dec 15 16:46:36.983 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2 Dec 15 16:46:36.983 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2 Dec 15 16:46:36.983 INFO [stdout] -> Running /tmp/test-dmenv.f52MKm1jXlDi/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 15 16:46:36.983 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 15 16:46:36.983 INFO [stdout] stack backtrace: Dec 15 16:46:36.983 INFO [stdout] 0: 0x56206dc9d4b3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 15 16:46:36.983 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 15 16:46:36.983 INFO [stdout] 1: 0x56206dc97bf8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 15 16:46:36.983 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 15 16:46:36.983 INFO [stdout] 2: 0x56206dc9c3d2 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 15 16:46:36.983 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 15 16:46:36.983 INFO [stdout] at src/libstd/panicking.rs:210 Dec 15 16:46:36.983 INFO [stdout] 3: 0x56206dc9c0c7 - std::panicking::default_hook::h1134a4d34aab4428 Dec 15 16:46:36.983 INFO [stdout] at src/libstd/panicking.rs:218 Dec 15 16:46:36.983 INFO [stdout] 4: 0x56206dc9cae0 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 15 16:46:36.983 INFO [stdout] at src/libstd/panicking.rs:487 Dec 15 16:46:36.983 INFO [stdout] 5: 0x56206dc9c661 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 15 16:46:36.983 INFO [stdout] at src/libstd/panicking.rs:394 Dec 15 16:46:36.983 INFO [stdout] 6: 0x56206dc9c545 - rust_begin_unwind Dec 15 16:46:36.983 INFO [stdout] at src/libstd/panicking.rs:321 Dec 15 16:46:36.983 INFO [stdout] 7: 0x56206dcb306c - core::panicking::panic_fmt::h68c043a5e81351f3 Dec 15 16:46:36.983 INFO [stdout] at src/libcore/panicking.rs:95 Dec 15 16:46:36.983 INFO [stdout] 8: 0x56206da79330 - core::result::unwrap_failed::hdbdbe338b2542507 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/macros.rs:26 Dec 15 16:46:36.984 INFO [stdout] 9: 0x56206da13338 - >::expect::h0cd7c2b3072a33fd Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/result.rs:835 Dec 15 16:46:36.984 INFO [stdout] 10: 0x56206da12b46 - integration_tests::helpers::TestApp::assert_run_ok::h73401f7ade377694 Dec 15 16:46:36.984 INFO [stdout] at tests/helpers/mod.rs:57 Dec 15 16:46:36.984 INFO [stdout] 11: 0x56206da0a605 - integration_tests::lock_workflow::hf180ff08adfad9cf Dec 15 16:46:36.984 INFO [stdout] at tests/integration_tests.rs:83 Dec 15 16:46:36.984 INFO [stdout] 12: 0x56206da0a4d9 - integration_tests::lock_workflow::{{closure}}::h217892ab89649180 Dec 15 16:46:36.984 INFO [stdout] at tests/integration_tests.rs:80 Dec 15 16:46:36.984 INFO [stdout] 13: 0x56206da0539d - core::ops::function::FnOnce::call_once::h7fd9751feddb47eb Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 16:46:36.984 INFO [stdout] 14: 0x56206da1cf0e - >::call_box::h3cf534772c089f15 Dec 15 16:46:36.984 INFO [stdout] at src/libtest/lib.rs:1483 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 16:46:36.984 INFO [stdout] 15: 0x56206dcb0559 - __rust_maybe_catch_panic Dec 15 16:46:36.984 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 16:46:36.984 INFO [stdout] 16: 0x56206da3a8b7 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 16:46:36.984 INFO [stdout] at src/libtest/lib.rs:1438 Dec 15 16:46:36.984 INFO [stdout] 17: 0x56206da157c4 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 15 16:46:36.984 INFO [stdout] 18: 0x56206da15e34 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 15 16:46:36.984 INFO [stdout] 19: 0x56206dcb0559 - __rust_maybe_catch_panic Dec 15 16:46:36.984 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 16:46:36.984 INFO [stdout] 20: 0x56206da1cdec - >::call_box::h260fd1ad8e2854a6 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 16:46:36.984 INFO [stdout] 21: 0x56206dca451d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 15 16:46:36.984 INFO [stdout] 22: 0x7f900ad8b6b9 - start_thread Dec 15 16:46:36.984 INFO [stdout] 23: 0x7f900a8ab41c - clone Dec 15 16:46:36.984 INFO [stdout] 24: 0x0 - Dec 15 16:46:36.984 INFO [stdout] Dec 15 16:46:36.984 INFO [stdout] ---- install_workflow_all_in_one stdout ---- Dec 15 16:46:36.984 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2 Dec 15 16:46:36.984 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2 Dec 15 16:46:36.984 INFO [stdout] -> Running /tmp/test-dmenv.slgHP6rm1vNS/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 15 16:46:36.984 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 15 16:46:36.984 INFO [stdout] stack backtrace: Dec 15 16:46:36.984 INFO [stdout] 0: 0x56206dc9d4b3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 15 16:46:36.984 INFO [stdout] 1: 0x56206dc97bf8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 15 16:46:36.984 INFO [stdout] 2: 0x56206dc9c3d2 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:210 Dec 15 16:46:36.984 INFO [stdout] 3: 0x56206dc9c0c7 - std::panicking::default_hook::h1134a4d34aab4428 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:218 Dec 15 16:46:36.984 INFO [stdout] 4: 0x56206dc9cae0 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:487 Dec 15 16:46:36.984 INFO [stdout] 5: 0x56206dc9c661 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:394 Dec 15 16:46:36.984 INFO [stdout] 6: 0x56206dc9c545 - rust_begin_unwind Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:321 Dec 15 16:46:36.984 INFO [stdout] 7: 0x56206dcb306c - core::panicking::panic_fmt::h68c043a5e81351f3 Dec 15 16:46:36.984 INFO [stdout] at src/libcore/panicking.rs:95 Dec 15 16:46:36.984 INFO [stdout] 8: 0x56206da79330 - core::result::unwrap_failed::hdbdbe338b2542507 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/macros.rs:26 Dec 15 16:46:36.984 INFO [stdout] 9: 0x56206da13338 - >::expect::h0cd7c2b3072a33fd Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/result.rs:835 Dec 15 16:46:36.984 INFO [stdout] 10: 0x56206da12b46 - integration_tests::helpers::TestApp::assert_run_ok::h73401f7ade377694 Dec 15 16:46:36.984 INFO [stdout] at tests/helpers/mod.rs:57 Dec 15 16:46:36.984 INFO [stdout] 11: 0x56206da0aa18 - integration_tests::install_workflow_all_in_one::h9964ae0ed48457c3 Dec 15 16:46:36.984 INFO [stdout] at tests/integration_tests.rs:98 Dec 15 16:46:36.984 INFO [stdout] 12: 0x56206da0a8c9 - integration_tests::install_workflow_all_in_one::{{closure}}::hd264215dc9044fe8 Dec 15 16:46:36.984 INFO [stdout] at tests/integration_tests.rs:93 Dec 15 16:46:36.984 INFO [stdout] 13: 0x56206da0535d - core::ops::function::FnOnce::call_once::h7b484261f293b35b Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 16:46:36.984 INFO [stdout] 14: 0x56206da1cf0e - >::call_box::h3cf534772c089f15 Dec 15 16:46:36.984 INFO [stdout] at src/libtest/lib.rs:1483 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 16:46:36.984 INFO [stdout] 15: 0x56206dcb0559 - __rust_maybe_catch_panic Dec 15 16:46:36.984 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 16:46:36.984 INFO [stdout] 16: 0x56206da3a8b7 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 16:46:36.984 INFO [stdout] at src/libtest/lib.rs:1438 Dec 15 16:46:36.984 INFO [stdout] 17: 0x56206da157c4 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 15 16:46:36.984 INFO [stdout] 18: 0x56206da15e34 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 15 16:46:36.984 INFO [stdout] 19: 0x56206dcb0559 - __rust_maybe_catch_panic Dec 15 16:46:36.984 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 16:46:36.984 INFO [stdout] 20: 0x56206da1cdec - >::call_box::h260fd1ad8e2854a6 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 16:46:36.984 INFO [stdout] 21: 0x56206dca451d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 15 16:46:36.984 INFO [stdout] 22: 0x7f900ad8b6b9 - start_thread Dec 15 16:46:36.984 INFO [stdout] 23: 0x7f900a8ab41c - clone Dec 15 16:46:36.984 INFO [stdout] 24: 0x0 - Dec 15 16:46:36.984 INFO [stdout] Dec 15 16:46:36.984 INFO [stdout] ---- install_workflow_step_by_step stdout ---- Dec 15 16:46:36.984 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2 Dec 15 16:46:36.984 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2 Dec 15 16:46:36.984 INFO [stdout] -> Running /tmp/test-dmenv.QbjfnuasVbDa/.venv/3.5.2/bin/pip install --requirement /tmp/test-dmenv.QbjfnuasVbDa/requirements.lock Dec 15 16:46:36.984 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 15 16:46:36.984 INFO [stdout] stack backtrace: Dec 15 16:46:36.984 INFO [stdout] 0: 0x56206dc9d4b3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 15 16:46:36.984 INFO [stdout] 1: 0x56206dc97bf8 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 15 16:46:36.984 INFO [stdout] 2: 0x56206dc9c3d2 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:210 Dec 15 16:46:36.984 INFO [stdout] 3: 0x56206dc9c0c7 - std::panicking::default_hook::h1134a4d34aab4428 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:218 Dec 15 16:46:36.984 INFO [stdout] 4: 0x56206dc9cae0 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:487 Dec 15 16:46:36.984 INFO [stdout] 5: 0x56206dc9c661 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:394 Dec 15 16:46:36.984 INFO [stdout] 6: 0x56206dc9c545 - rust_begin_unwind Dec 15 16:46:36.984 INFO [stdout] at src/libstd/panicking.rs:321 Dec 15 16:46:36.984 INFO [stdout] 7: 0x56206dcb306c - core::panicking::panic_fmt::h68c043a5e81351f3 Dec 15 16:46:36.984 INFO [stdout] at src/libcore/panicking.rs:95 Dec 15 16:46:36.984 INFO [stdout] 8: 0x56206da79330 - core::result::unwrap_failed::hdbdbe338b2542507 Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/macros.rs:26 Dec 15 16:46:36.984 INFO [stdout] 9: 0x56206da13338 - >::expect::h0cd7c2b3072a33fd Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/result.rs:835 Dec 15 16:46:36.984 INFO [stdout] 10: 0x56206da12b46 - integration_tests::helpers::TestApp::assert_run_ok::h73401f7ade377694 Dec 15 16:46:36.984 INFO [stdout] at tests/helpers/mod.rs:57 Dec 15 16:46:36.984 INFO [stdout] 11: 0x56206da0abf0 - integration_tests::install_workflow_step_by_step::h6530fb00db8e2834 Dec 15 16:46:36.984 INFO [stdout] at tests/integration_tests.rs:107 Dec 15 16:46:36.984 INFO [stdout] 12: 0x56206da0aa79 - integration_tests::install_workflow_step_by_step::{{closure}}::he0a6b2904e27731f Dec 15 16:46:36.984 INFO [stdout] at tests/integration_tests.rs:102 Dec 15 16:46:36.984 INFO [stdout] 13: 0x56206da0521d - core::ops::function::FnOnce::call_once::h0a107b5007555f6a Dec 15 16:46:36.984 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 16:46:36.984 INFO [stdout] 14: 0x56206da1cf0e - >::call_box::h3cf534772c089f15 Dec 15 16:46:36.985 INFO [stdout] at src/libtest/lib.rs:1483 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 16:46:36.985 INFO [stdout] 15: 0x56206dcb0559 - __rust_maybe_catch_panic Dec 15 16:46:36.985 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 16:46:36.985 INFO [stdout] 16: 0x56206da3a8b7 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 16:46:36.985 INFO [stdout] at src/libtest/lib.rs:1438 Dec 15 16:46:36.985 INFO [stdout] 17: 0x56206da157c4 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 15 16:46:36.985 INFO [stdout] 18: 0x56206da15e34 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 15 16:46:36.985 INFO [stdout] 19: 0x56206dcb0559 - __rust_maybe_catch_panic Dec 15 16:46:36.985 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 15 16:46:36.985 INFO [stdout] 20: 0x56206da1cdec - >::call_box::h260fd1ad8e2854a6 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 15 16:46:36.985 INFO [stdout] 21: 0x56206dca451d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 15 16:46:36.985 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 15 16:46:36.985 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 15 16:46:36.985 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 15 16:46:36.985 INFO [stdout] 22: 0x7f900ad8b6b9 - start_thread Dec 15 16:46:36.985 INFO [stdout] 23: 0x7f900a8ab41c - clone Dec 15 16:46:36.985 INFO [stdout] 24: 0x0 - Dec 15 16:46:36.985 INFO [stdout] Dec 15 16:46:36.985 INFO [stdout] Dec 15 16:46:36.985 INFO [stdout] failures: Dec 15 16:46:36.985 INFO [stdout] install_workflow_all_in_one Dec 15 16:46:36.985 INFO [stdout] install_workflow_step_by_step Dec 15 16:46:36.985 INFO [stdout] lock_workflow Dec 15 16:46:36.985 INFO [stdout] Dec 15 16:46:36.985 INFO [stdout] test result: FAILED. 9 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 16:46:36.985 INFO [stdout] Dec 15 16:46:36.985 INFO [stderr] error: test failed, to rerun pass '--test integration_tests' Dec 15 16:46:36.985 INFO [stderr] su: No module specific data is present Dec 15 16:46:37.295 INFO running `"docker" "inspect" "01edbf9427c8b28dc85d30b3a6865b955c146b767ed8bca02cd9d48ec3e2fdb7"` Dec 15 16:46:37.404 INFO running `"docker" "rm" "-f" "01edbf9427c8b28dc85d30b3a6865b955c146b767ed8bca02cd9d48ec3e2fdb7"` Dec 15 16:46:37.475 INFO [stdout] 01edbf9427c8b28dc85d30b3a6865b955c146b767ed8bca02cd9d48ec3e2fdb7