Dec 07 18:31:36.286 INFO testing dmenv-0.6.0 against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514 Dec 07 18:31:36.286 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 18:31:36.586 INFO [stdout] 1f1fa0e222ff4bfebb8917699ee06832949793ef8d4019a9ae46a5aa07e0e573 Dec 07 18:31:36.590 INFO running `"docker" "start" "-a" "1f1fa0e222ff4bfebb8917699ee06832949793ef8d4019a9ae46a5aa07e0e573"` Dec 07 18:31:37.039 INFO [stderr] usermod: no changes Dec 07 18:31:37.143 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 07 18:31:46.076 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 9.01s Dec 07 18:31:46.084 INFO [stderr] su: No module specific data is present Dec 07 18:31:47.147 INFO running `"docker" "inspect" "1f1fa0e222ff4bfebb8917699ee06832949793ef8d4019a9ae46a5aa07e0e573"` Dec 07 18:31:47.512 INFO running `"docker" "rm" "-f" "1f1fa0e222ff4bfebb8917699ee06832949793ef8d4019a9ae46a5aa07e0e573"` Dec 07 18:31:47.911 INFO [stdout] 1f1fa0e222ff4bfebb8917699ee06832949793ef8d4019a9ae46a5aa07e0e573 Dec 07 18:31:47.913 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 18:31:48.210 INFO [stdout] d0e4fb239d62ac75aa7dfcd32b182cc4a7f8ea90fa1ecb0838e9bf3a71ebd67f Dec 07 18:31:48.213 INFO running `"docker" "start" "-a" "d0e4fb239d62ac75aa7dfcd32b182cc4a7f8ea90fa1ecb0838e9bf3a71ebd67f"` Dec 07 18:31:48.845 INFO [stderr] usermod: no changes Dec 07 18:31:49.024 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 07 18:31:54.780 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 5.91s Dec 07 18:31:54.793 INFO [stderr] su: No module specific data is present Dec 07 18:31:55.342 INFO running `"docker" "inspect" "d0e4fb239d62ac75aa7dfcd32b182cc4a7f8ea90fa1ecb0838e9bf3a71ebd67f"` Dec 07 18:31:55.543 INFO running `"docker" "rm" "-f" "d0e4fb239d62ac75aa7dfcd32b182cc4a7f8ea90fa1ecb0838e9bf3a71ebd67f"` Dec 07 18:31:55.692 INFO [stdout] d0e4fb239d62ac75aa7dfcd32b182cc4a7f8ea90fa1ecb0838e9bf3a71ebd67f Dec 07 18:31:55.694 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/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 +6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468-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 07 18:31:55.996 INFO [stdout] 2a1681ad321368c0d2e980c18ab4217ae33929e5b94424ec6cc7778e394fc8ae Dec 07 18:31:56.004 INFO running `"docker" "start" "-a" "2a1681ad321368c0d2e980c18ab4217ae33929e5b94424ec6cc7778e394fc8ae"` Dec 07 18:31:56.456 INFO [stderr] usermod: no changes Dec 07 18:31:56.553 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.07s Dec 07 18:31:56.567 INFO [stderr] Running /target/debug/deps/dmenv-eef234ce37be4525 Dec 07 18:31:56.569 INFO [stdout] Dec 07 18:31:56.569 INFO [stdout] running 0 tests Dec 07 18:31:56.569 INFO [stdout] Dec 07 18:31:56.569 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 07 18:31:56.569 INFO [stdout] Dec 07 18:31:56.570 INFO [stderr] Running /target/debug/deps/dmenv-56e83f477ee52fc7 Dec 07 18:31:56.573 INFO [stdout] Dec 07 18:31:56.573 INFO [stdout] running 0 tests Dec 07 18:31:56.573 INFO [stdout] Dec 07 18:31:56.573 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 07 18:31:56.573 INFO [stdout] Dec 07 18:31:56.573 INFO [stderr] Running /target/debug/deps/integration_tests-40bceb6ad769fdef Dec 07 18:31:56.577 INFO [stdout] Dec 07 18:31:56.577 INFO [stdout] running 10 tests Dec 07 18:31:56.582 INFO [stdout] test run_without_args ... ok Dec 07 18:31:56.766 INFO [stdout] test run_without_virtualenv ... ok Dec 07 18:31:56.777 INFO [stdout] test install_without_lock ... ok Dec 07 18:31:56.790 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok Dec 07 18:31:56.794 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok Dec 07 18:31:56.820 INFO [stdout] test init_generates_setup_py ... ok Dec 07 18:31:56.853 INFO [stdout] test show_venv_path ... ok Dec 07 18:32:01.086 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 07 18:32:01.087 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 07 18:32:01.087 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 07 18:32:01.087 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 07 18:32:01.133 INFO [stderr] Exception: Dec 07 18:32:01.133 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.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 07 18:32:01.133 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.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 07 18:32:01.133 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 07 18:32:01.133 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 07 18:32:01.133 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 07 18:32:01.133 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 07 18:32:01.133 INFO [stderr] Dec 07 18:32:01.133 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:01.133 INFO [stderr] Dec 07 18:32:01.133 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 07 18:32:01.133 INFO [stderr] body=body, headers=headers) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.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 07 18:32:01.133 INFO [stderr] self._validate_conn(conn) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.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 07 18:32:01.133 INFO [stderr] conn.connect() Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 07 18:32:01.133 INFO [stderr] conn = self._new_conn() Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.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 07 18:32:01.133 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 07 18:32:01.133 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 07 18:32:01.133 INFO [stderr] Dec 07 18:32:01.133 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:01.133 INFO [stderr] Dec 07 18:32:01.133 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 07 18:32:01.133 INFO [stderr] status = self.run(options, args) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 07 18:32:01.133 INFO [stderr] requirement_set.prepare_files(finder) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 07 18:32:01.133 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 07 18:32:01.133 INFO [stderr] req_to_install, finder) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 07 18:32:01.133 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 07 18:32:01.133 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 07 18:32:01.133 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 07 18:32:01.133 INFO [stderr] page = self._get_page(location) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 07 18:32:01.133 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 07 18:32:01.133 INFO [stderr] "Cache-Control": "max-age=600", Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 07 18:32:01.133 INFO [stderr] return self.request('GET', url, **kwargs) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 07 18:32:01.133 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 07 18:32:01.133 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 07 18:32:01.133 INFO [stderr] r = adapter.send(request, **kwargs) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 07 18:32:01.133 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 07 18:32:01.133 INFO [stderr] timeout=timeout Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 07 18:32:01.133 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 07 18:32:01.133 INFO [stderr] File "/tmp/test-dmenv.ZlqEEzOGyuXE/.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 07 18:32:01.133 INFO [stderr] total -= 1 Dec 07 18:32:01.133 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 07 18:32:01.182 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.jBAdVcvwXNDn/requirements.lock (line 2)) Dec 07 18:32:01.182 INFO [stderr] Exception: Dec 07 18:32:01.182 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.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 07 18:32:01.182 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.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 07 18:32:01.182 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 07 18:32:01.182 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 07 18:32:01.182 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 07 18:32:01.182 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 07 18:32:01.182 INFO [stderr] Dec 07 18:32:01.182 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:01.182 INFO [stderr] Dec 07 18:32:01.182 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 07 18:32:01.182 INFO [stderr] body=body, headers=headers) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.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 07 18:32:01.182 INFO [stderr] self._validate_conn(conn) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.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 07 18:32:01.182 INFO [stderr] conn.connect() Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 07 18:32:01.182 INFO [stderr] conn = self._new_conn() Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.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 07 18:32:01.182 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 07 18:32:01.182 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 07 18:32:01.182 INFO [stderr] Dec 07 18:32:01.182 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:01.182 INFO [stderr] Dec 07 18:32:01.182 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 07 18:32:01.182 INFO [stderr] status = self.run(options, args) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 07 18:32:01.182 INFO [stderr] requirement_set.prepare_files(finder) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 07 18:32:01.182 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file Dec 07 18:32:01.182 INFO [stderr] finder, self.upgrade, require_hashes) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link Dec 07 18:32:01.182 INFO [stderr] self.link = finder.find_requirement(self, upgrade) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 07 18:32:01.182 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 07 18:32:01.182 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 07 18:32:01.182 INFO [stderr] page = self._get_page(location) Dec 07 18:32:01.182 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 07 18:32:01.183 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 07 18:32:01.183 INFO [stderr] "Cache-Control": "max-age=600", Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 07 18:32:01.183 INFO [stderr] return self.request('GET', url, **kwargs) Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 07 18:32:01.183 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 07 18:32:01.183 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 07 18:32:01.183 INFO [stderr] r = adapter.send(request, **kwargs) Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 07 18:32:01.183 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 07 18:32:01.183 INFO [stderr] timeout=timeout Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 07 18:32:01.183 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 07 18:32:01.183 INFO [stderr] File "/tmp/test-dmenv.jBAdVcvwXNDn/.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 07 18:32:01.183 INFO [stderr] total -= 1 Dec 07 18:32:01.183 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 07 18:32:01.348 INFO [stdout] test install_workflow_step_by_step ... FAILED Dec 07 18:32:01.352 INFO [stdout] test install_workflow_all_in_one ... FAILED Dec 07 18:32:01.401 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 07 18:32:01.401 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 07 18:32:01.435 INFO [stderr] Exception: Dec 07 18:32:01.435 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.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 07 18:32:01.435 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.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 07 18:32:01.435 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 07 18:32:01.435 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 07 18:32:01.435 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 07 18:32:01.435 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 07 18:32:01.435 INFO [stderr] Dec 07 18:32:01.435 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:01.435 INFO [stderr] Dec 07 18:32:01.435 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 07 18:32:01.435 INFO [stderr] body=body, headers=headers) Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.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 07 18:32:01.435 INFO [stderr] self._validate_conn(conn) Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.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 07 18:32:01.435 INFO [stderr] conn.connect() Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 07 18:32:01.435 INFO [stderr] conn = self._new_conn() Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.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 07 18:32:01.435 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 07 18:32:01.435 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 07 18:32:01.435 INFO [stderr] Dec 07 18:32:01.435 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:01.435 INFO [stderr] Dec 07 18:32:01.435 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 07 18:32:01.435 INFO [stderr] status = self.run(options, args) Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 07 18:32:01.435 INFO [stderr] requirement_set.prepare_files(finder) Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 07 18:32:01.435 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 07 18:32:01.435 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 07 18:32:01.436 INFO [stderr] req_to_install, finder) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 07 18:32:01.436 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 07 18:32:01.436 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 07 18:32:01.436 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 07 18:32:01.436 INFO [stderr] page = self._get_page(location) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 07 18:32:01.436 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 07 18:32:01.436 INFO [stderr] "Cache-Control": "max-age=600", Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 07 18:32:01.436 INFO [stderr] return self.request('GET', url, **kwargs) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 07 18:32:01.436 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 07 18:32:01.436 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 07 18:32:01.436 INFO [stderr] r = adapter.send(request, **kwargs) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 07 18:32:01.436 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 07 18:32:01.436 INFO [stderr] timeout=timeout Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 07 18:32:01.436 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 07 18:32:01.436 INFO [stderr] File "/tmp/test-dmenv.0xXz6gL1HTSS/.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 07 18:32:01.436 INFO [stderr] total -= 1 Dec 07 18:32:01.436 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 07 18:32:01.503 INFO [stdout] test lock_workflow ... FAILED Dec 07 18:32:01.503 INFO [stdout] Dec 07 18:32:01.503 INFO [stdout] failures: Dec 07 18:32:01.507 INFO [stdout] Dec 07 18:32:01.507 INFO [stdout] ---- install_workflow_step_by_step stdout ---- Dec 07 18:32:01.507 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2 Dec 07 18:32:01.507 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2 Dec 07 18:32:01.507 INFO [stdout] -> running /tmp/test-dmenv.jBAdVcvwXNDn/.venv/3.5.2/bin/pip install --requirement /tmp/test-dmenv.jBAdVcvwXNDn/requirements.lock Dec 07 18:32:01.507 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 07 18:32:01.507 INFO [stdout] stack backtrace: Dec 07 18:32:01.507 INFO [stdout] 0: 0x55c68fd44da3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 07 18:32:01.507 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 07 18:32:01.507 INFO [stdout] 1: 0x55c68fd3f408 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 07 18:32:01.507 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 07 18:32:01.507 INFO [stdout] 2: 0x55c68fd43cc2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 07 18:32:01.507 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 07 18:32:01.507 INFO [stdout] at src/libstd/panicking.rs:211 Dec 07 18:32:01.507 INFO [stdout] 3: 0x55c68fd439ac - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 07 18:32:01.507 INFO [stdout] at src/libstd/panicking.rs:221 Dec 07 18:32:01.507 INFO [stdout] 4: 0x55c68fd443d0 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 07 18:32:01.507 INFO [stdout] at src/libstd/panicking.rs:491 Dec 07 18:32:01.507 INFO [stdout] 5: 0x55c68fd43f51 - std::panicking::continue_panic_fmt::hf3c774aec6124caa Dec 07 18:32:01.507 INFO [stdout] at src/libstd/panicking.rs:398 Dec 07 18:32:01.507 INFO [stdout] 6: 0x55c68fd43e35 - rust_begin_unwind Dec 07 18:32:01.507 INFO [stdout] at src/libstd/panicking.rs:325 Dec 07 18:32:01.507 INFO [stdout] 7: 0x55c68fd5a95c - core::panicking::panic_fmt::haf17319257746a90 Dec 07 18:32:01.508 INFO [stdout] at src/libcore/panicking.rs:95 Dec 07 18:32:01.508 INFO [stdout] 8: 0x55c68fabfddb - core::result::unwrap_failed::hf5148867332d8fa1 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/macros.rs:26 Dec 07 18:32:01.508 INFO [stdout] 9: 0x55c68fabf5a4 - >::expect::h1d8bb8eaf91bcf89 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/result.rs:835 Dec 07 18:32:01.508 INFO [stdout] 10: 0x55c68fac9ce6 - integration_tests::helpers::TestApp::assert_run_ok::h3443b56d49eba0b5 Dec 07 18:32:01.508 INFO [stdout] at tests/helpers/mod.rs:57 Dec 07 18:32:01.508 INFO [stdout] 11: 0x55c68fac4480 - integration_tests::install_workflow_step_by_step::h64be0530495ebbe5 Dec 07 18:32:01.508 INFO [stdout] at tests/integration_tests.rs:77 Dec 07 18:32:01.508 INFO [stdout] 12: 0x55c68fac4309 - integration_tests::install_workflow_step_by_step::{{closure}}::h6124826cb99831c9 Dec 07 18:32:01.508 INFO [stdout] at tests/integration_tests.rs:72 Dec 07 18:32:01.508 INFO [stdout] 13: 0x55c68fabc03d - core::ops::function::FnOnce::call_once::h9de6f4bb1df3321b Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 07 18:32:01.508 INFO [stdout] 14: 0x55c68fad4bbe - >::call_box::h9ed1658cdce7f33f Dec 07 18:32:01.508 INFO [stdout] at src/libtest/lib.rs:1471 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 07 18:32:01.508 INFO [stdout] 15: 0x55c68fd57e49 - __rust_maybe_catch_panic Dec 07 18:32:01.508 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:01.508 INFO [stdout] 16: 0x55c68facca93 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 07 18:32:01.508 INFO [stdout] at src/libtest/lib.rs:1426 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 07 18:32:01.508 INFO [stdout] 17: 0x55c68facda24 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 07 18:32:01.508 INFO [stdout] 18: 0x55c68fd57e49 - __rust_maybe_catch_panic Dec 07 18:32:01.508 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:01.508 INFO [stdout] 19: 0x55c68fad4a9c - >::call_box::h6465cc998309e287 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 07 18:32:01.508 INFO [stdout] 20: 0x55c68fd4be0d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 07 18:32:01.508 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 07 18:32:01.508 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 07 18:32:01.508 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 07 18:32:01.508 INFO [stdout] 21: 0x7fe75d5656b9 - start_thread Dec 07 18:32:01.508 INFO [stdout] 22: 0x7fe75d08541c - clone Dec 07 18:32:01.508 INFO [stdout] 23: 0x0 - Dec 07 18:32:01.508 INFO [stdout] Dec 07 18:32:01.508 INFO [stdout] ---- install_workflow_all_in_one stdout ---- Dec 07 18:32:01.508 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2 Dec 07 18:32:01.508 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2 Dec 07 18:32:01.508 INFO [stdout] -> running /tmp/test-dmenv.ZlqEEzOGyuXE/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 07 18:32:01.508 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 07 18:32:01.508 INFO [stdout] stack backtrace: Dec 07 18:32:01.508 INFO [stdout] 0: 0x55c68fd44da3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 07 18:32:01.508 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 07 18:32:01.508 INFO [stdout] 1: 0x55c68fd3f408 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 07 18:32:01.509 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 07 18:32:01.509 INFO [stdout] 2: 0x55c68fd43cc2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:211 Dec 07 18:32:01.509 INFO [stdout] 3: 0x55c68fd439ac - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:221 Dec 07 18:32:01.509 INFO [stdout] 4: 0x55c68fd443d0 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:491 Dec 07 18:32:01.509 INFO [stdout] 5: 0x55c68fd43f51 - std::panicking::continue_panic_fmt::hf3c774aec6124caa Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:398 Dec 07 18:32:01.509 INFO [stdout] 6: 0x55c68fd43e35 - rust_begin_unwind Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:325 Dec 07 18:32:01.509 INFO [stdout] 7: 0x55c68fd5a95c - core::panicking::panic_fmt::haf17319257746a90 Dec 07 18:32:01.509 INFO [stdout] at src/libcore/panicking.rs:95 Dec 07 18:32:01.509 INFO [stdout] 8: 0x55c68fabfddb - core::result::unwrap_failed::hf5148867332d8fa1 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/macros.rs:26 Dec 07 18:32:01.509 INFO [stdout] 9: 0x55c68fabf5a4 - >::expect::h1d8bb8eaf91bcf89 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/result.rs:835 Dec 07 18:32:01.509 INFO [stdout] 10: 0x55c68fac9ce6 - integration_tests::helpers::TestApp::assert_run_ok::h3443b56d49eba0b5 Dec 07 18:32:01.509 INFO [stdout] at tests/helpers/mod.rs:57 Dec 07 18:32:01.509 INFO [stdout] 11: 0x55c68fac42a8 - integration_tests::install_workflow_all_in_one::h15cb4a240e822f4a Dec 07 18:32:01.509 INFO [stdout] at tests/integration_tests.rs:68 Dec 07 18:32:01.509 INFO [stdout] 12: 0x55c68fac4159 - integration_tests::install_workflow_all_in_one::{{closure}}::h684ec17cdc25690f Dec 07 18:32:01.509 INFO [stdout] at tests/integration_tests.rs:63 Dec 07 18:32:01.509 INFO [stdout] 13: 0x55c68fabc07d - core::ops::function::FnOnce::call_once::hc15d6bc5b03f6c81 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 07 18:32:01.509 INFO [stdout] 14: 0x55c68fad4bbe - >::call_box::h9ed1658cdce7f33f Dec 07 18:32:01.509 INFO [stdout] at src/libtest/lib.rs:1471 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 07 18:32:01.509 INFO [stdout] 15: 0x55c68fd57e49 - __rust_maybe_catch_panic Dec 07 18:32:01.509 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:01.509 INFO [stdout] 16: 0x55c68facca93 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 07 18:32:01.509 INFO [stdout] at src/libtest/lib.rs:1426 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 07 18:32:01.509 INFO [stdout] 17: 0x55c68facda24 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 07 18:32:01.509 INFO [stdout] 18: 0x55c68fd57e49 - __rust_maybe_catch_panic Dec 07 18:32:01.509 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:01.509 INFO [stdout] 19: 0x55c68fad4a9c - >::call_box::h6465cc998309e287 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 07 18:32:01.509 INFO [stdout] 20: 0x55c68fd4be0d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 07 18:32:01.509 INFO [stdout] 21: 0x7fe75d5656b9 - start_thread Dec 07 18:32:01.509 INFO [stdout] 22: 0x7fe75d08541c - clone Dec 07 18:32:01.509 INFO [stdout] 23: 0x0 - Dec 07 18:32:01.509 INFO [stdout] Dec 07 18:32:01.509 INFO [stdout] ---- lock_workflow stdout ---- Dec 07 18:32:01.509 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2 Dec 07 18:32:01.509 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2 Dec 07 18:32:01.509 INFO [stdout] -> running /tmp/test-dmenv.0xXz6gL1HTSS/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 07 18:32:01.509 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 07 18:32:01.509 INFO [stdout] stack backtrace: Dec 07 18:32:01.509 INFO [stdout] 0: 0x55c68fd44da3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 07 18:32:01.509 INFO [stdout] 1: 0x55c68fd3f408 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 07 18:32:01.509 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 07 18:32:01.509 INFO [stdout] 2: 0x55c68fd43cc2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:211 Dec 07 18:32:01.509 INFO [stdout] 3: 0x55c68fd439ac - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:221 Dec 07 18:32:01.509 INFO [stdout] 4: 0x55c68fd443d0 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:491 Dec 07 18:32:01.509 INFO [stdout] 5: 0x55c68fd43f51 - std::panicking::continue_panic_fmt::hf3c774aec6124caa Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:398 Dec 07 18:32:01.509 INFO [stdout] 6: 0x55c68fd43e35 - rust_begin_unwind Dec 07 18:32:01.509 INFO [stdout] at src/libstd/panicking.rs:325 Dec 07 18:32:01.509 INFO [stdout] 7: 0x55c68fd5a95c - core::panicking::panic_fmt::haf17319257746a90 Dec 07 18:32:01.509 INFO [stdout] at src/libcore/panicking.rs:95 Dec 07 18:32:01.509 INFO [stdout] 8: 0x55c68fabfddb - core::result::unwrap_failed::hf5148867332d8fa1 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/macros.rs:26 Dec 07 18:32:01.509 INFO [stdout] 9: 0x55c68fabf5a4 - >::expect::h1d8bb8eaf91bcf89 Dec 07 18:32:01.509 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/result.rs:835 Dec 07 18:32:01.509 INFO [stdout] 10: 0x55c68fac9ce6 - integration_tests::helpers::TestApp::assert_run_ok::h3443b56d49eba0b5 Dec 07 18:32:01.509 INFO [stdout] at tests/helpers/mod.rs:57 Dec 07 18:32:01.509 INFO [stdout] 11: 0x55c68fac3e95 - integration_tests::lock_workflow::h6e7816467f60ac6f Dec 07 18:32:01.510 INFO [stdout] at tests/integration_tests.rs:53 Dec 07 18:32:01.510 INFO [stdout] 12: 0x55c68fac3d69 - integration_tests::lock_workflow::{{closure}}::h49b822f6652ced35 Dec 07 18:32:01.510 INFO [stdout] at tests/integration_tests.rs:50 Dec 07 18:32:01.510 INFO [stdout] 13: 0x55c68fabbfbd - core::ops::function::FnOnce::call_once::h7628a6e1cb39a3ab Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 07 18:32:01.510 INFO [stdout] 14: 0x55c68fad4bbe - >::call_box::h9ed1658cdce7f33f Dec 07 18:32:01.510 INFO [stdout] at src/libtest/lib.rs:1471 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 07 18:32:01.510 INFO [stdout] 15: 0x55c68fd57e49 - __rust_maybe_catch_panic Dec 07 18:32:01.510 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:01.510 INFO [stdout] 16: 0x55c68facca93 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 07 18:32:01.510 INFO [stdout] at src/libtest/lib.rs:1426 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 07 18:32:01.510 INFO [stdout] 17: 0x55c68facda24 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 07 18:32:01.510 INFO [stdout] 18: 0x55c68fd57e49 - __rust_maybe_catch_panic Dec 07 18:32:01.510 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:01.510 INFO [stdout] 19: 0x55c68fad4a9c - >::call_box::h6465cc998309e287 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 07 18:32:01.510 INFO [stdout] 20: 0x55c68fd4be0d - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 07 18:32:01.510 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 07 18:32:01.510 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 07 18:32:01.510 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 07 18:32:01.510 INFO [stdout] 21: 0x7fe75d5656b9 - start_thread Dec 07 18:32:01.510 INFO [stdout] 22: 0x7fe75d08541c - clone Dec 07 18:32:01.510 INFO [stdout] 23: 0x0 - Dec 07 18:32:01.510 INFO [stdout] Dec 07 18:32:01.510 INFO [stdout] Dec 07 18:32:01.510 INFO [stdout] failures: Dec 07 18:32:01.510 INFO [stdout] install_workflow_all_in_one Dec 07 18:32:01.510 INFO [stdout] install_workflow_step_by_step Dec 07 18:32:01.510 INFO [stdout] lock_workflow Dec 07 18:32:01.510 INFO [stdout] Dec 07 18:32:01.510 INFO [stdout] test result: FAILED. 7 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out Dec 07 18:32:01.510 INFO [stdout] Dec 07 18:32:01.512 INFO [stderr] error: test failed, to rerun pass '--test integration_tests' Dec 07 18:32:01.513 INFO [stderr] su: No module specific data is present Dec 07 18:32:02.006 INFO running `"docker" "inspect" "2a1681ad321368c0d2e980c18ab4217ae33929e5b94424ec6cc7778e394fc8ae"` Dec 07 18:32:02.144 INFO running `"docker" "rm" "-f" "2a1681ad321368c0d2e980c18ab4217ae33929e5b94424ec6cc7778e394fc8ae"` Dec 07 18:32:02.281 INFO [stdout] 2a1681ad321368c0d2e980c18ab4217ae33929e5b94424ec6cc7778e394fc8ae