Dec 07 18:31:36.494 INFO testing dmenv-0.6.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514 Dec 07 18:31:36.494 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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.696 INFO [stdout] f169e0a39338254db688b72853c759bfdfeafcf13a1dd07904ca67ef9237177d Dec 07 18:31:36.698 INFO running `"docker" "start" "-a" "f169e0a39338254db688b72853c759bfdfeafcf13a1dd07904ca67ef9237177d"` Dec 07 18:31:37.160 INFO [stderr] usermod: no changes Dec 07 18:31:37.269 INFO [stderr] Compiling appdirs v0.2.0 Dec 07 18:31:37.929 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 07 18:31:47.865 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 10.69s Dec 07 18:31:47.873 INFO [stderr] su: No module specific data is present Dec 07 18:31:48.370 INFO running `"docker" "inspect" "f169e0a39338254db688b72853c759bfdfeafcf13a1dd07904ca67ef9237177d"` Dec 07 18:31:48.493 INFO running `"docker" "rm" "-f" "f169e0a39338254db688b72853c759bfdfeafcf13a1dd07904ca67ef9237177d"` Dec 07 18:31:48.728 INFO [stdout] f169e0a39338254db688b72853c759bfdfeafcf13a1dd07904ca67ef9237177d Dec 07 18:31:48.730 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:49.117 INFO [stdout] cdfdc8608a1a4bd7f201accfbbec42b22c9b0b98dcdc272a7d7d0b56a1221ece Dec 07 18:31:49.119 INFO running `"docker" "start" "-a" "cdfdc8608a1a4bd7f201accfbbec42b22c9b0b98dcdc272a7d7d0b56a1221ece"` Dec 07 18:31:50.487 INFO [stderr] usermod: no changes Dec 07 18:31:50.680 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 07 18:31:56.288 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 5.78s Dec 07 18:31:56.295 INFO [stderr] su: No module specific data is present Dec 07 18:31:56.522 INFO running `"docker" "inspect" "cdfdc8608a1a4bd7f201accfbbec42b22c9b0b98dcdc272a7d7d0b56a1221ece"` Dec 07 18:31:56.603 INFO running `"docker" "rm" "-f" "cdfdc8608a1a4bd7f201accfbbec42b22c9b0b98dcdc272a7d7d0b56a1221ece"` Dec 07 18:31:56.777 INFO [stdout] cdfdc8608a1a4bd7f201accfbbec42b22c9b0b98dcdc272a7d7d0b56a1221ece Dec 07 18:31:56.782 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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:57.066 INFO [stdout] 3ebae3d396052de1bb35d1e096acc4a705997ab5b440bf996bd0b9a3ad473a1c Dec 07 18:31:57.070 INFO running `"docker" "start" "-a" "3ebae3d396052de1bb35d1e096acc4a705997ab5b440bf996bd0b9a3ad473a1c"` Dec 07 18:31:57.868 INFO [stderr] usermod: no changes Dec 07 18:31:58.033 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.13s Dec 07 18:31:58.056 INFO [stderr] Running /target/debug/deps/dmenv-a87e61f31701758e Dec 07 18:31:58.056 INFO [stdout] Dec 07 18:31:58.056 INFO [stdout] running 0 tests Dec 07 18:31:58.056 INFO [stdout] Dec 07 18:31:58.056 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 07 18:31:58.056 INFO [stdout] Dec 07 18:31:58.056 INFO [stdout] Dec 07 18:31:58.056 INFO [stderr] Running /target/debug/deps/dmenv-65c93b694fff8090 Dec 07 18:31:58.056 INFO [stdout] running 0 tests Dec 07 18:31:58.056 INFO [stdout] Dec 07 18:31:58.056 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 07 18:31:58.056 INFO [stdout] Dec 07 18:31:58.060 INFO [stderr] Running /target/debug/deps/integration_tests-d5bf36d354b3196a Dec 07 18:31:58.064 INFO [stdout] Dec 07 18:31:58.064 INFO [stdout] running 10 tests Dec 07 18:31:58.108 INFO [stdout] test run_without_args ... ok Dec 07 18:31:58.325 INFO [stdout] test run_without_virtualenv ... ok Dec 07 18:31:58.347 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok Dec 07 18:31:58.396 INFO [stdout] test install_without_lock ... ok Dec 07 18:31:58.425 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok Dec 07 18:31:58.472 INFO [stdout] test show_venv_path ... ok Dec 07 18:31:58.558 INFO [stdout] test init_generates_setup_py ... ok Dec 07 18:32:02.507 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:02.508 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:02.508 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:02.508 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:02.508 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:02.508 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:02.516 INFO [stderr] Exception: Dec 07 18:32:02.516 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.wajQYlezt67r/requirements.lock (line 2)) Dec 07 18:32:02.516 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.516 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.516 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 07 18:32:02.516 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.516 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 07 18:32:02.516 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 07 18:32:02.516 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 07 18:32:02.516 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 07 18:32:02.516 INFO [stderr] Dec 07 18:32:02.516 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:02.516 INFO [stderr] Dec 07 18:32:02.516 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.516 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.516 INFO [stderr] body=body, headers=headers) Dec 07 18:32:02.516 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] self._validate_conn(conn) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] conn.connect() Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] conn = self._new_conn() Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 07 18:32:02.517 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 07 18:32:02.517 INFO [stderr] Dec 07 18:32:02.517 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:02.517 INFO [stderr] Dec 07 18:32:02.517 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 07 18:32:02.517 INFO [stderr] status = self.run(options, args) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 07 18:32:02.517 INFO [stderr] requirement_set.prepare_files(finder) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 07 18:32:02.517 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 07 18:32:02.517 INFO [stderr] req_to_install, finder) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 07 18:32:02.517 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 07 18:32:02.517 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 07 18:32:02.517 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 07 18:32:02.517 INFO [stderr] page = self._get_page(location) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 07 18:32:02.517 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 07 18:32:02.517 INFO [stderr] "Cache-Control": "max-age=600", Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] return self.request('GET', url, **kwargs) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 07 18:32:02.517 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] r = adapter.send(request, **kwargs) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] timeout=timeout Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 07 18:32:02.517 INFO [stderr] File "/tmp/test-dmenv.zNtJ4ElZJ3uV/.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:02.517 INFO [stderr] total -= 1 Dec 07 18:32:02.517 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 07 18:32:02.523 INFO [stderr] Exception: Dec 07 18:32:02.523 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 07 18:32:02.523 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 07 18:32:02.523 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 07 18:32:02.523 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 07 18:32:02.523 INFO [stderr] Dec 07 18:32:02.523 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:02.523 INFO [stderr] Dec 07 18:32:02.523 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] body=body, headers=headers) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] self._validate_conn(conn) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] conn.connect() Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] conn = self._new_conn() Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 07 18:32:02.523 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 07 18:32:02.523 INFO [stderr] Dec 07 18:32:02.523 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:02.523 INFO [stderr] Dec 07 18:32:02.523 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 07 18:32:02.523 INFO [stderr] status = self.run(options, args) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 07 18:32:02.523 INFO [stderr] requirement_set.prepare_files(finder) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 07 18:32:02.523 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 07 18:32:02.523 INFO [stderr] req_to_install, finder) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 07 18:32:02.523 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 07 18:32:02.523 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 07 18:32:02.523 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 07 18:32:02.523 INFO [stderr] page = self._get_page(location) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 07 18:32:02.523 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 07 18:32:02.523 INFO [stderr] "Cache-Control": "max-age=600", Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] return self.request('GET', url, **kwargs) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 07 18:32:02.523 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] r = adapter.send(request, **kwargs) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.523 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 07 18:32:02.523 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.524 INFO [stderr] timeout=timeout Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.524 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.kD40ZWIPapgT/.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:02.524 INFO [stderr] total -= 1 Dec 07 18:32:02.524 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 07 18:32:02.524 INFO [stderr] Exception: Dec 07 18:32:02.524 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.524 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.524 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 07 18:32:02.524 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 07 18:32:02.524 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 07 18:32:02.524 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 07 18:32:02.524 INFO [stderr] Dec 07 18:32:02.524 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:02.524 INFO [stderr] Dec 07 18:32:02.524 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.524 INFO [stderr] body=body, headers=headers) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.524 INFO [stderr] self._validate_conn(conn) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.524 INFO [stderr] conn.connect() Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.524 INFO [stderr] conn = self._new_conn() Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.524 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 07 18:32:02.524 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 07 18:32:02.524 INFO [stderr] Dec 07 18:32:02.524 INFO [stderr] During handling of the above exception, another exception occurred: Dec 07 18:32:02.524 INFO [stderr] Dec 07 18:32:02.524 INFO [stderr] Traceback (most recent call last): Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 07 18:32:02.524 INFO [stderr] status = self.run(options, args) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 07 18:32:02.524 INFO [stderr] requirement_set.prepare_files(finder) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 07 18:32:02.524 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file Dec 07 18:32:02.524 INFO [stderr] finder, self.upgrade, require_hashes) Dec 07 18:32:02.524 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link Dec 07 18:32:02.524 INFO [stderr] self.link = finder.find_requirement(self, upgrade) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 07 18:32:02.525 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 07 18:32:02.525 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 07 18:32:02.525 INFO [stderr] page = self._get_page(location) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 07 18:32:02.525 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 07 18:32:02.525 INFO [stderr] "Cache-Control": "max-age=600", Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.525 INFO [stderr] return self.request('GET', url, **kwargs) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 07 18:32:02.525 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.525 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.525 INFO [stderr] r = adapter.send(request, **kwargs) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.525 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.525 INFO [stderr] timeout=timeout Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.525 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 07 18:32:02.525 INFO [stderr] File "/tmp/test-dmenv.wajQYlezt67r/.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:02.525 INFO [stderr] total -= 1 Dec 07 18:32:02.525 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 07 18:32:02.701 INFO [stdout] test lock_workflow ... FAILED Dec 07 18:32:02.701 INFO [stdout] test install_workflow_step_by_step ... FAILED Dec 07 18:32:02.708 INFO [stdout] test install_workflow_all_in_one ... FAILED Dec 07 18:32:02.708 INFO [stdout] Dec 07 18:32:02.708 INFO [stdout] failures: Dec 07 18:32:02.708 INFO [stdout] Dec 07 18:32:02.708 INFO [stdout] ---- lock_workflow stdout ---- Dec 07 18:32:02.708 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2 Dec 07 18:32:02.708 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2 Dec 07 18:32:02.708 INFO [stdout] -> running /tmp/test-dmenv.zNtJ4ElZJ3uV/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 07 18:32:02.708 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 07 18:32:02.708 INFO [stdout] stack backtrace: Dec 07 18:32:02.708 INFO [stdout] 0: 0x55fbaa38c7f3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 07 18:32:02.708 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 07 18:32:02.708 INFO [stdout] 1: 0x55fbaa386e58 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 07 18:32:02.708 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 07 18:32:02.708 INFO [stdout] 2: 0x55fbaa38b712 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 07 18:32:02.708 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 07 18:32:02.708 INFO [stdout] at src/libstd/panicking.rs:211 Dec 07 18:32:02.708 INFO [stdout] 3: 0x55fbaa38b3fc - std::panicking::default_hook::h4eae6f26f73244c8 Dec 07 18:32:02.708 INFO [stdout] at src/libstd/panicking.rs:221 Dec 07 18:32:02.708 INFO [stdout] 4: 0x55fbaa38be20 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 07 18:32:02.708 INFO [stdout] at src/libstd/panicking.rs:491 Dec 07 18:32:02.708 INFO [stdout] 5: 0x55fbaa38b9a1 - std::panicking::continue_panic_fmt::h1599d634b3be4304 Dec 07 18:32:02.708 INFO [stdout] at src/libstd/panicking.rs:398 Dec 07 18:32:02.708 INFO [stdout] 6: 0x55fbaa38b885 - rust_begin_unwind Dec 07 18:32:02.708 INFO [stdout] at src/libstd/panicking.rs:325 Dec 07 18:32:02.708 INFO [stdout] 7: 0x55fbaa3a23ac - core::panicking::panic_fmt::hdc202e6077c95723 Dec 07 18:32:02.708 INFO [stdout] at src/libcore/panicking.rs:95 Dec 07 18:32:02.709 INFO [stdout] 8: 0x55fbaa0f287b - core::result::unwrap_failed::hea674d8a1059bdbc Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/macros.rs:26 Dec 07 18:32:02.709 INFO [stdout] 9: 0x55fbaa0f22a4 - >::expect::hdd3b99f17eaef859 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/result.rs:835 Dec 07 18:32:02.709 INFO [stdout] 10: 0x55fbaa0fc786 - integration_tests::helpers::TestApp::assert_run_ok::h9d90ff4ccd06aa5c Dec 07 18:32:02.709 INFO [stdout] at tests/helpers/mod.rs:57 Dec 07 18:32:02.709 INFO [stdout] 11: 0x55fbaa0f6935 - integration_tests::lock_workflow::h443bc2a7d3718782 Dec 07 18:32:02.709 INFO [stdout] at tests/integration_tests.rs:53 Dec 07 18:32:02.709 INFO [stdout] 12: 0x55fbaa0f6809 - integration_tests::lock_workflow::{{closure}}::h1c8c0e96c4ef9d6b Dec 07 18:32:02.709 INFO [stdout] at tests/integration_tests.rs:50 Dec 07 18:32:02.709 INFO [stdout] 13: 0x55fbaa0eeb5d - core::ops::function::FnOnce::call_once::ha13a1976632ef7d3 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 07 18:32:02.709 INFO [stdout] 14: 0x55fbaa10762e - >::call_box::h994167e0fc25f327 Dec 07 18:32:02.709 INFO [stdout] at src/libtest/lib.rs:1471 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 07 18:32:02.709 INFO [stdout] 15: 0x55fbaa39f899 - __rust_maybe_catch_panic Dec 07 18:32:02.709 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:02.709 INFO [stdout] 16: 0x55fbaa0ff533 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5d34cfb78512c8c3 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 07 18:32:02.709 INFO [stdout] at src/libtest/lib.rs:1426 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 07 18:32:02.709 INFO [stdout] 17: 0x55fbaa100454 - std::panicking::try::do_call::h7a86b6211ebb5e72 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 07 18:32:02.709 INFO [stdout] 18: 0x55fbaa39f899 - __rust_maybe_catch_panic Dec 07 18:32:02.709 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:02.709 INFO [stdout] 19: 0x55fbaa10750c - >::call_box::h877dbe22cb3997f4 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 07 18:32:02.709 INFO [stdout] 20: 0x55fbaa39385d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 07 18:32:02.709 INFO [stdout] 21: 0x7ff663ce06b9 - start_thread Dec 07 18:32:02.709 INFO [stdout] 22: 0x7ff66380041c - clone Dec 07 18:32:02.709 INFO [stdout] 23: 0x0 - Dec 07 18:32:02.709 INFO [stdout] Dec 07 18:32:02.709 INFO [stdout] ---- install_workflow_step_by_step stdout ---- Dec 07 18:32:02.709 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2 Dec 07 18:32:02.709 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2 Dec 07 18:32:02.709 INFO [stdout] -> running /tmp/test-dmenv.wajQYlezt67r/.venv/3.5.2/bin/pip install --requirement /tmp/test-dmenv.wajQYlezt67r/requirements.lock Dec 07 18:32:02.709 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 07 18:32:02.709 INFO [stdout] stack backtrace: Dec 07 18:32:02.709 INFO [stdout] 0: 0x55fbaa38c7f3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 07 18:32:02.709 INFO [stdout] 1: 0x55fbaa386e58 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 07 18:32:02.709 INFO [stdout] 2: 0x55fbaa38b712 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/panicking.rs:211 Dec 07 18:32:02.709 INFO [stdout] 3: 0x55fbaa38b3fc - std::panicking::default_hook::h4eae6f26f73244c8 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/panicking.rs:221 Dec 07 18:32:02.709 INFO [stdout] 4: 0x55fbaa38be20 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/panicking.rs:491 Dec 07 18:32:02.709 INFO [stdout] 5: 0x55fbaa38b9a1 - std::panicking::continue_panic_fmt::h1599d634b3be4304 Dec 07 18:32:02.709 INFO [stdout] at src/libstd/panicking.rs:398 Dec 07 18:32:02.709 INFO [stdout] 6: 0x55fbaa38b885 - rust_begin_unwind Dec 07 18:32:02.709 INFO [stdout] at src/libstd/panicking.rs:325 Dec 07 18:32:02.709 INFO [stdout] 7: 0x55fbaa3a23ac - core::panicking::panic_fmt::hdc202e6077c95723 Dec 07 18:32:02.709 INFO [stdout] at src/libcore/panicking.rs:95 Dec 07 18:32:02.709 INFO [stdout] 8: 0x55fbaa0f287b - core::result::unwrap_failed::hea674d8a1059bdbc Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/macros.rs:26 Dec 07 18:32:02.709 INFO [stdout] 9: 0x55fbaa0f22a4 - >::expect::hdd3b99f17eaef859 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/result.rs:835 Dec 07 18:32:02.709 INFO [stdout] 10: 0x55fbaa0fc786 - integration_tests::helpers::TestApp::assert_run_ok::h9d90ff4ccd06aa5c Dec 07 18:32:02.709 INFO [stdout] at tests/helpers/mod.rs:57 Dec 07 18:32:02.709 INFO [stdout] 11: 0x55fbaa0f6f20 - integration_tests::install_workflow_step_by_step::h4978457821d66de7 Dec 07 18:32:02.709 INFO [stdout] at tests/integration_tests.rs:77 Dec 07 18:32:02.709 INFO [stdout] 12: 0x55fbaa0f6da9 - integration_tests::install_workflow_step_by_step::{{closure}}::hde6ea409eaeaa696 Dec 07 18:32:02.709 INFO [stdout] at tests/integration_tests.rs:72 Dec 07 18:32:02.709 INFO [stdout] 13: 0x55fbaa0eebdd - core::ops::function::FnOnce::call_once::hb67112483dfa0650 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 07 18:32:02.709 INFO [stdout] 14: 0x55fbaa10762e - >::call_box::h994167e0fc25f327 Dec 07 18:32:02.709 INFO [stdout] at src/libtest/lib.rs:1471 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 07 18:32:02.709 INFO [stdout] 15: 0x55fbaa39f899 - __rust_maybe_catch_panic Dec 07 18:32:02.709 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:02.709 INFO [stdout] 16: 0x55fbaa0ff533 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5d34cfb78512c8c3 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 07 18:32:02.709 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 07 18:32:02.709 INFO [stdout] at src/libtest/lib.rs:1426 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 07 18:32:02.710 INFO [stdout] 17: 0x55fbaa100454 - std::panicking::try::do_call::h7a86b6211ebb5e72 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 07 18:32:02.710 INFO [stdout] 18: 0x55fbaa39f899 - __rust_maybe_catch_panic Dec 07 18:32:02.710 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:02.710 INFO [stdout] 19: 0x55fbaa10750c - >::call_box::h877dbe22cb3997f4 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 07 18:32:02.710 INFO [stdout] 20: 0x55fbaa39385d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 07 18:32:02.710 INFO [stdout] 21: 0x7ff663ce06b9 - start_thread Dec 07 18:32:02.710 INFO [stdout] 22: 0x7ff66380041c - clone Dec 07 18:32:02.710 INFO [stdout] 23: 0x0 - Dec 07 18:32:02.710 INFO [stdout] Dec 07 18:32:02.710 INFO [stdout] ---- install_workflow_all_in_one stdout ---- Dec 07 18:32:02.710 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2 Dec 07 18:32:02.710 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2 Dec 07 18:32:02.710 INFO [stdout] -> running /tmp/test-dmenv.kD40ZWIPapgT/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 07 18:32:02.710 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 07 18:32:02.710 INFO [stdout] stack backtrace: Dec 07 18:32:02.710 INFO [stdout] 0: 0x55fbaa38c7f3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 07 18:32:02.710 INFO [stdout] 1: 0x55fbaa386e58 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 07 18:32:02.710 INFO [stdout] 2: 0x55fbaa38b712 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/panicking.rs:211 Dec 07 18:32:02.710 INFO [stdout] 3: 0x55fbaa38b3fc - std::panicking::default_hook::h4eae6f26f73244c8 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/panicking.rs:221 Dec 07 18:32:02.710 INFO [stdout] 4: 0x55fbaa38be20 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/panicking.rs:491 Dec 07 18:32:02.710 INFO [stdout] 5: 0x55fbaa38b9a1 - std::panicking::continue_panic_fmt::h1599d634b3be4304 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/panicking.rs:398 Dec 07 18:32:02.710 INFO [stdout] 6: 0x55fbaa38b885 - rust_begin_unwind Dec 07 18:32:02.710 INFO [stdout] at src/libstd/panicking.rs:325 Dec 07 18:32:02.710 INFO [stdout] 7: 0x55fbaa3a23ac - core::panicking::panic_fmt::hdc202e6077c95723 Dec 07 18:32:02.710 INFO [stdout] at src/libcore/panicking.rs:95 Dec 07 18:32:02.710 INFO [stdout] 8: 0x55fbaa0f287b - core::result::unwrap_failed::hea674d8a1059bdbc Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/macros.rs:26 Dec 07 18:32:02.710 INFO [stdout] 9: 0x55fbaa0f22a4 - >::expect::hdd3b99f17eaef859 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/result.rs:835 Dec 07 18:32:02.710 INFO [stdout] 10: 0x55fbaa0fc786 - integration_tests::helpers::TestApp::assert_run_ok::h9d90ff4ccd06aa5c Dec 07 18:32:02.710 INFO [stdout] at tests/helpers/mod.rs:57 Dec 07 18:32:02.710 INFO [stdout] 11: 0x55fbaa0f6d48 - integration_tests::install_workflow_all_in_one::h25646e425dc7d458 Dec 07 18:32:02.710 INFO [stdout] at tests/integration_tests.rs:68 Dec 07 18:32:02.710 INFO [stdout] 12: 0x55fbaa0f6bf9 - integration_tests::install_workflow_all_in_one::{{closure}}::h0ec5493aa3d4def7 Dec 07 18:32:02.710 INFO [stdout] at tests/integration_tests.rs:63 Dec 07 18:32:02.710 INFO [stdout] 13: 0x55fbaa0eeb1d - core::ops::function::FnOnce::call_once::h9d386563b1f32b7c Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 07 18:32:02.710 INFO [stdout] 14: 0x55fbaa10762e - >::call_box::h994167e0fc25f327 Dec 07 18:32:02.710 INFO [stdout] at src/libtest/lib.rs:1471 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 07 18:32:02.710 INFO [stdout] 15: 0x55fbaa39f899 - __rust_maybe_catch_panic Dec 07 18:32:02.710 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:02.710 INFO [stdout] 16: 0x55fbaa0ff533 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5d34cfb78512c8c3 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 07 18:32:02.710 INFO [stdout] at src/libtest/lib.rs:1426 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 07 18:32:02.710 INFO [stdout] 17: 0x55fbaa100454 - std::panicking::try::do_call::h7a86b6211ebb5e72 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 07 18:32:02.710 INFO [stdout] 18: 0x55fbaa39f899 - __rust_maybe_catch_panic Dec 07 18:32:02.710 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 07 18:32:02.710 INFO [stdout] 19: 0x55fbaa10750c - >::call_box::h877dbe22cb3997f4 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 07 18:32:02.710 INFO [stdout] 20: 0x55fbaa39385d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 07 18:32:02.710 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 07 18:32:02.710 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 07 18:32:02.710 INFO [stdout] 21: 0x7ff663ce06b9 - start_thread Dec 07 18:32:02.710 INFO [stdout] 22: 0x7ff66380041c - clone Dec 07 18:32:02.710 INFO [stdout] 23: 0x0 - Dec 07 18:32:02.710 INFO [stdout] Dec 07 18:32:02.710 INFO [stdout] Dec 07 18:32:02.711 INFO [stdout] failures: Dec 07 18:32:02.711 INFO [stdout] install_workflow_all_in_one Dec 07 18:32:02.711 INFO [stdout] install_workflow_step_by_step Dec 07 18:32:02.711 INFO [stdout] lock_workflow Dec 07 18:32:02.711 INFO [stdout] Dec 07 18:32:02.711 INFO [stdout] test result: FAILED. 7 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out Dec 07 18:32:02.711 INFO [stdout] Dec 07 18:32:02.711 INFO [stderr] error: test failed, to rerun pass '--test integration_tests' Dec 07 18:32:02.713 INFO [stderr] su: No module specific data is present Dec 07 18:32:03.050 INFO running `"docker" "inspect" "3ebae3d396052de1bb35d1e096acc4a705997ab5b440bf996bd0b9a3ad473a1c"` Dec 07 18:32:03.178 INFO running `"docker" "rm" "-f" "3ebae3d396052de1bb35d1e096acc4a705997ab5b440bf996bd0b9a3ad473a1c"` Dec 07 18:32:03.317 INFO [stdout] 3ebae3d396052de1bb35d1e096acc4a705997ab5b440bf996bd0b9a3ad473a1c