Dec 06 01:29:12.526 INFO testing dmerejkowsky/dmenv against try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468 for pr-56514 Dec 06 01:29:12.526 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 06 01:29:12.898 INFO [stdout] 8dddd6ee5c5408ed227975e8d7171849ee8f168413df4fcb69d3609f37b65ec0 Dec 06 01:29:12.912 INFO running `"docker" "start" "-a" "8dddd6ee5c5408ed227975e8d7171849ee8f168413df4fcb69d3609f37b65ec0"` Dec 06 01:29:13.773 INFO [stderr] usermod: no changes Dec 06 01:29:13.963 INFO [stderr] Compiling appdirs v0.2.0 Dec 06 01:29:13.963 INFO [stderr] Compiling syn v0.15.17 Dec 06 01:29:13.963 INFO [stderr] Compiling colored v1.6.1 Dec 06 01:29:24.273 INFO [stderr] Compiling synstructure v0.10.1 Dec 06 01:29:24.273 INFO [stderr] Compiling structopt-derive v0.2.12 Dec 06 01:29:24.273 INFO [stderr] Compiling serde_derive v1.0.80 Dec 06 01:29:29.164 INFO [stderr] Compiling failure_derive v0.1.3 Dec 06 01:29:29.972 INFO [stderr] Compiling structopt v0.2.12 Dec 06 01:29:31.670 INFO [stderr] Compiling failure v0.1.3 Dec 06 01:29:32.598 INFO [stderr] Compiling which v2.0.0 Dec 06 01:29:40.784 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 06 01:29:43.971 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 30.17s Dec 06 01:29:43.977 INFO [stderr] su: No module specific data is present Dec 06 01:29:44.236 INFO running `"docker" "inspect" "8dddd6ee5c5408ed227975e8d7171849ee8f168413df4fcb69d3609f37b65ec0"` Dec 06 01:29:44.338 INFO running `"docker" "rm" "-f" "8dddd6ee5c5408ed227975e8d7171849ee8f168413df4fcb69d3609f37b65ec0"` Dec 06 01:29:44.486 INFO [stdout] 8dddd6ee5c5408ed227975e8d7171849ee8f168413df4fcb69d3609f37b65ec0 Dec 06 01:29:44.488 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 06 01:29:44.630 INFO [stdout] 03db32e26969b1de0e7cfdeb4fd5a3f1445c85bd3c977600637e25ce5d1ef917 Dec 06 01:29:44.632 INFO running `"docker" "start" "-a" "03db32e26969b1de0e7cfdeb4fd5a3f1445c85bd3c977600637e25ce5d1ef917"` Dec 06 01:29:45.189 INFO [stderr] usermod: no changes Dec 06 01:29:45.282 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 06 01:29:47.170 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1.96s Dec 06 01:29:47.176 INFO [stderr] su: No module specific data is present Dec 06 01:29:47.461 INFO running `"docker" "inspect" "03db32e26969b1de0e7cfdeb4fd5a3f1445c85bd3c977600637e25ce5d1ef917"` Dec 06 01:29:47.530 INFO running `"docker" "rm" "-f" "03db32e26969b1de0e7cfdeb4fd5a3f1445c85bd3c977600637e25ce5d1ef917"` Dec 06 01:29:47.609 INFO [stdout] 03db32e26969b1de0e7cfdeb4fd5a3f1445c85bd3c977600637e25ce5d1ef917 Dec 06 01:29:47.611 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-4/try#6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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 06 01:29:47.765 INFO [stdout] 5199d840028296d7588227b9420513e1c0be169a25b2de5ece83c74558b05af9 Dec 06 01:29:47.767 INFO running `"docker" "start" "-a" "5199d840028296d7588227b9420513e1c0be169a25b2de5ece83c74558b05af9"` Dec 06 01:29:48.127 INFO [stderr] usermod: no changes Dec 06 01:29:48.212 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.07s Dec 06 01:29:48.227 INFO [stderr] Running /target/debug/deps/dmenv-0bb0fcbbf58751f5 Dec 06 01:29:48.228 INFO [stdout] Dec 06 01:29:48.228 INFO [stdout] running 0 tests Dec 06 01:29:48.228 INFO [stdout] Dec 06 01:29:48.228 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 01:29:48.228 INFO [stdout] Dec 06 01:29:48.230 INFO [stderr] Running /target/debug/deps/dmenv-e8cc61576c2f2522 Dec 06 01:29:48.230 INFO [stdout] Dec 06 01:29:48.231 INFO [stdout] running 0 tests Dec 06 01:29:48.231 INFO [stdout] Dec 06 01:29:48.231 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 01:29:48.231 INFO [stdout] Dec 06 01:29:48.232 INFO [stderr] Running /target/debug/deps/integration_tests-13458a68a57f007c Dec 06 01:29:48.233 INFO [stdout] Dec 06 01:29:48.233 INFO [stdout] running 10 tests Dec 06 01:29:48.239 INFO [stdout] test run_without_args ... ok Dec 06 01:29:48.378 INFO [stdout] test install_without_lock ... ok Dec 06 01:29:48.384 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok Dec 06 01:29:48.394 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok Dec 06 01:29:48.402 INFO [stdout] test run_without_virtualenv ... ok Dec 06 01:29:48.411 INFO [stdout] test init_generates_setup_py ... ok Dec 06 01:29:48.468 INFO [stdout] test show_venv_path ... ok Dec 06 01:29:53.375 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 06 01:29:53.375 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 06 01:29:53.402 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 06 01:29:53.403 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 06 01:29:53.428 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 06 01:29:53.428 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 06 01:29:53.448 INFO [stderr] Exception: Dec 06 01:29:53.448 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.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 06 01:29:53.448 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.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 06 01:29:53.448 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 06 01:29:53.448 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 06 01:29:53.448 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 06 01:29:53.448 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 06 01:29:53.448 INFO [stderr] Dec 06 01:29:53.448 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:29:53.448 INFO [stderr] Dec 06 01:29:53.448 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 06 01:29:53.448 INFO [stderr] body=body, headers=headers) Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.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 06 01:29:53.448 INFO [stderr] self._validate_conn(conn) Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.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 06 01:29:53.448 INFO [stderr] conn.connect() Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 06 01:29:53.448 INFO [stderr] conn = self._new_conn() Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.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 06 01:29:53.448 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 06 01:29:53.448 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 06 01:29:53.448 INFO [stderr] Dec 06 01:29:53.448 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:29:53.448 INFO [stderr] Dec 06 01:29:53.448 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 06 01:29:53.448 INFO [stderr] status = self.run(options, args) Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 06 01:29:53.448 INFO [stderr] requirement_set.prepare_files(finder) Dec 06 01:29:53.448 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 06 01:29:53.448 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 06 01:29:53.449 INFO [stderr] req_to_install, finder) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 06 01:29:53.449 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 06 01:29:53.449 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 06 01:29:53.449 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 06 01:29:53.449 INFO [stderr] page = self._get_page(location) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 06 01:29:53.449 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 06 01:29:53.449 INFO [stderr] "Cache-Control": "max-age=600", Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 06 01:29:53.449 INFO [stderr] return self.request('GET', url, **kwargs) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 06 01:29:53.449 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 06 01:29:53.449 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 06 01:29:53.449 INFO [stderr] r = adapter.send(request, **kwargs) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 06 01:29:53.449 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 06 01:29:53.449 INFO [stderr] timeout=timeout Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 06 01:29:53.449 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 06 01:29:53.449 INFO [stderr] File "/tmp/test-dmenv.MTnDLk6ItlbA/.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 06 01:29:53.449 INFO [stderr] total -= 1 Dec 06 01:29:53.449 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 06 01:29:53.454 INFO [stderr] Exception: Dec 06 01:29:53.454 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.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 06 01:29:53.454 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.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 06 01:29:53.454 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 06 01:29:53.454 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 06 01:29:53.454 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 06 01:29:53.454 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 06 01:29:53.454 INFO [stderr] Dec 06 01:29:53.454 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:29:53.454 INFO [stderr] Dec 06 01:29:53.454 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 06 01:29:53.454 INFO [stderr] body=body, headers=headers) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.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 06 01:29:53.454 INFO [stderr] self._validate_conn(conn) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.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 06 01:29:53.454 INFO [stderr] conn.connect() Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 06 01:29:53.454 INFO [stderr] conn = self._new_conn() Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.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 06 01:29:53.454 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 06 01:29:53.454 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 06 01:29:53.454 INFO [stderr] Dec 06 01:29:53.454 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:29:53.454 INFO [stderr] Dec 06 01:29:53.454 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 06 01:29:53.454 INFO [stderr] status = self.run(options, args) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 06 01:29:53.454 INFO [stderr] requirement_set.prepare_files(finder) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 06 01:29:53.454 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 06 01:29:53.454 INFO [stderr] req_to_install, finder) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 06 01:29:53.454 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 06 01:29:53.454 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 06 01:29:53.454 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 06 01:29:53.454 INFO [stderr] page = self._get_page(location) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 06 01:29:53.454 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 06 01:29:53.454 INFO [stderr] "Cache-Control": "max-age=600", Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 06 01:29:53.454 INFO [stderr] return self.request('GET', url, **kwargs) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 06 01:29:53.454 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 06 01:29:53.454 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 06 01:29:53.454 INFO [stderr] r = adapter.send(request, **kwargs) Dec 06 01:29:53.454 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 06 01:29:53.455 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 06 01:29:53.455 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 06 01:29:53.455 INFO [stderr] timeout=timeout Dec 06 01:29:53.455 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 06 01:29:53.455 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 06 01:29:53.455 INFO [stderr] File "/tmp/test-dmenv.6QGTikSEiAlu/.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 06 01:29:53.455 INFO [stderr] total -= 1 Dec 06 01:29:53.455 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 06 01:29:53.470 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.C95S3Yo10ArO/requirements.lock (line 2)) Dec 06 01:29:53.480 INFO [stderr] Exception: Dec 06 01:29:53.480 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.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 06 01:29:53.480 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.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 06 01:29:53.480 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 06 01:29:53.480 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 06 01:29:53.480 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 06 01:29:53.480 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 06 01:29:53.480 INFO [stderr] Dec 06 01:29:53.480 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:29:53.480 INFO [stderr] Dec 06 01:29:53.480 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 06 01:29:53.480 INFO [stderr] body=body, headers=headers) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.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 06 01:29:53.480 INFO [stderr] self._validate_conn(conn) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.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 06 01:29:53.480 INFO [stderr] conn.connect() Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 06 01:29:53.480 INFO [stderr] conn = self._new_conn() Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.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 06 01:29:53.480 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 06 01:29:53.480 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 06 01:29:53.480 INFO [stderr] Dec 06 01:29:53.480 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:29:53.480 INFO [stderr] Dec 06 01:29:53.480 INFO [stderr] Traceback (most recent call last): Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 06 01:29:53.480 INFO [stderr] status = self.run(options, args) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 06 01:29:53.480 INFO [stderr] requirement_set.prepare_files(finder) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 06 01:29:53.480 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file Dec 06 01:29:53.480 INFO [stderr] finder, self.upgrade, require_hashes) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link Dec 06 01:29:53.480 INFO [stderr] self.link = finder.find_requirement(self, upgrade) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 06 01:29:53.480 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 06 01:29:53.480 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 06 01:29:53.480 INFO [stderr] page = self._get_page(location) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 06 01:29:53.480 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 06 01:29:53.480 INFO [stderr] "Cache-Control": "max-age=600", Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 06 01:29:53.480 INFO [stderr] return self.request('GET', url, **kwargs) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 06 01:29:53.480 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 06 01:29:53.480 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 06 01:29:53.480 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 06 01:29:53.480 INFO [stderr] r = adapter.send(request, **kwargs) Dec 06 01:29:53.481 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 06 01:29:53.481 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 06 01:29:53.481 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 06 01:29:53.481 INFO [stderr] timeout=timeout Dec 06 01:29:53.481 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 06 01:29:53.481 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 06 01:29:53.481 INFO [stderr] File "/tmp/test-dmenv.C95S3Yo10ArO/.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 06 01:29:53.481 INFO [stderr] total -= 1 Dec 06 01:29:53.481 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 06 01:29:53.641 INFO [stdout] test lock_workflow ... FAILED Dec 06 01:29:53.641 INFO [stdout] test install_workflow_step_by_step ... FAILED Dec 06 01:29:53.648 INFO [stdout] test install_workflow_all_in_one ... FAILED Dec 06 01:29:53.648 INFO [stdout] Dec 06 01:29:53.648 INFO [stdout] failures: Dec 06 01:29:53.648 INFO [stdout] Dec 06 01:29:53.649 INFO [stdout] ---- lock_workflow stdout ---- Dec 06 01:29:53.649 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2 Dec 06 01:29:53.649 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2 Dec 06 01:29:53.649 INFO [stdout] -> running /tmp/test-dmenv.6QGTikSEiAlu/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 06 01:29:53.649 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 06 01:29:53.649 INFO [stdout] stack backtrace: Dec 06 01:29:53.649 INFO [stdout] 0: 0x55e3ffd13793 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 06 01:29:53.649 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 01:29:53.649 INFO [stdout] 1: 0x55e3ffd0ddf8 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 06 01:29:53.649 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 06 01:29:53.649 INFO [stdout] 2: 0x55e3ffd126b2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 06 01:29:53.649 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 06 01:29:53.649 INFO [stdout] at src/libstd/panicking.rs:211 Dec 06 01:29:53.649 INFO [stdout] 3: 0x55e3ffd1239c - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 06 01:29:53.649 INFO [stdout] at src/libstd/panicking.rs:221 Dec 06 01:29:53.649 INFO [stdout] 4: 0x55e3ffd12dc0 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 06 01:29:53.649 INFO [stdout] at src/libstd/panicking.rs:491 Dec 06 01:29:53.649 INFO [stdout] 5: 0x55e3ffd12941 - std::panicking::continue_panic_fmt::hf3c774aec6124caa Dec 06 01:29:53.649 INFO [stdout] at src/libstd/panicking.rs:398 Dec 06 01:29:53.649 INFO [stdout] 6: 0x55e3ffd12825 - rust_begin_unwind Dec 06 01:29:53.649 INFO [stdout] at src/libstd/panicking.rs:325 Dec 06 01:29:53.649 INFO [stdout] 7: 0x55e3ffd2934c - core::panicking::panic_fmt::haf17319257746a90 Dec 06 01:29:53.649 INFO [stdout] at src/libcore/panicking.rs:95 Dec 06 01:29:53.649 INFO [stdout] 8: 0x55e3ffa8feeb - core::result::unwrap_failed::h282b53c00dd91c00 Dec 06 01:29:53.649 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/macros.rs:26 Dec 06 01:29:53.649 INFO [stdout] 9: 0x55e3ffa8fd64 - >::expect::hfc2cf701be9a437f Dec 06 01:29:53.649 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/result.rs:835 Dec 06 01:29:53.649 INFO [stdout] 10: 0x55e3ffa9a0a6 - integration_tests::helpers::TestApp::assert_run_ok::h894961c8aa5e196b Dec 06 01:29:53.649 INFO [stdout] at tests/helpers/mod.rs:57 Dec 06 01:29:53.649 INFO [stdout] 11: 0x55e3ffa94255 - integration_tests::lock_workflow::hcd17f8cff6f30425 Dec 06 01:29:53.650 INFO [stdout] at tests/integration_tests.rs:53 Dec 06 01:29:53.650 INFO [stdout] 12: 0x55e3ffa94129 - integration_tests::lock_workflow::{{closure}}::hb5561362889582c0 Dec 06 01:29:53.650 INFO [stdout] at tests/integration_tests.rs:50 Dec 06 01:29:53.650 INFO [stdout] 13: 0x55e3ffa8c27d - core::ops::function::FnOnce::call_once::h045283b828001d1a Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 06 01:29:53.650 INFO [stdout] 14: 0x55e3ffaa4f7e - >::call_box::h9ed1658cdce7f33f Dec 06 01:29:53.650 INFO [stdout] at src/libtest/lib.rs:1471 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 01:29:53.650 INFO [stdout] 15: 0x55e3ffd26839 - __rust_maybe_catch_panic Dec 06 01:29:53.650 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:29:53.650 INFO [stdout] 16: 0x55e3ffa9ce53 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 01:29:53.650 INFO [stdout] at src/libtest/lib.rs:1426 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 06 01:29:53.650 INFO [stdout] 17: 0x55e3ffa9dde4 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 06 01:29:53.650 INFO [stdout] 18: 0x55e3ffd26839 - __rust_maybe_catch_panic Dec 06 01:29:53.650 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:29:53.650 INFO [stdout] 19: 0x55e3ffaa4e5c - >::call_box::h6465cc998309e287 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 01:29:53.650 INFO [stdout] 20: 0x55e3ffd1a7fd - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 06 01:29:53.650 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 06 01:29:53.650 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 06 01:29:53.650 INFO [stdout] 21: 0x7f1661e486b9 - start_thread Dec 06 01:29:53.650 INFO [stdout] 22: 0x7f166196841c - clone Dec 06 01:29:53.650 INFO [stdout] 23: 0x0 - Dec 06 01:29:53.650 INFO [stdout] Dec 06 01:29:53.650 INFO [stdout] ---- install_workflow_step_by_step stdout ---- Dec 06 01:29:53.650 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2 Dec 06 01:29:53.650 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2 Dec 06 01:29:53.650 INFO [stdout] -> running /tmp/test-dmenv.C95S3Yo10ArO/.venv/3.5.2/bin/pip install --requirement /tmp/test-dmenv.C95S3Yo10ArO/requirements.lock Dec 06 01:29:53.650 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 06 01:29:53.650 INFO [stdout] stack backtrace: Dec 06 01:29:53.650 INFO [stdout] 0: 0x55e3ffd13793 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 06 01:29:53.650 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 01:29:53.650 INFO [stdout] 1: 0x55e3ffd0ddf8 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 06 01:29:53.650 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 06 01:29:53.650 INFO [stdout] 2: 0x55e3ffd126b2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 06 01:29:53.650 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 06 01:29:53.650 INFO [stdout] at src/libstd/panicking.rs:211 Dec 06 01:29:53.650 INFO [stdout] 3: 0x55e3ffd1239c - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 06 01:29:53.650 INFO [stdout] at src/libstd/panicking.rs:221 Dec 06 01:29:53.650 INFO [stdout] 4: 0x55e3ffd12dc0 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 06 01:29:53.650 INFO [stdout] at src/libstd/panicking.rs:491 Dec 06 01:29:53.650 INFO [stdout] 5: 0x55e3ffd12941 - std::panicking::continue_panic_fmt::hf3c774aec6124caa Dec 06 01:29:53.650 INFO [stdout] at src/libstd/panicking.rs:398 Dec 06 01:29:53.650 INFO [stdout] 6: 0x55e3ffd12825 - rust_begin_unwind Dec 06 01:29:53.650 INFO [stdout] at src/libstd/panicking.rs:325 Dec 06 01:29:53.650 INFO [stdout] 7: 0x55e3ffd2934c - core::panicking::panic_fmt::haf17319257746a90 Dec 06 01:29:53.650 INFO [stdout] at src/libcore/panicking.rs:95 Dec 06 01:29:53.650 INFO [stdout] 8: 0x55e3ffa8feeb - core::result::unwrap_failed::h282b53c00dd91c00 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/macros.rs:26 Dec 06 01:29:53.650 INFO [stdout] 9: 0x55e3ffa8fd64 - >::expect::hfc2cf701be9a437f Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/result.rs:835 Dec 06 01:29:53.650 INFO [stdout] 10: 0x55e3ffa9a0a6 - integration_tests::helpers::TestApp::assert_run_ok::h894961c8aa5e196b Dec 06 01:29:53.650 INFO [stdout] at tests/helpers/mod.rs:57 Dec 06 01:29:53.650 INFO [stdout] 11: 0x55e3ffa94840 - integration_tests::install_workflow_step_by_step::haf063d0224321ba5 Dec 06 01:29:53.650 INFO [stdout] at tests/integration_tests.rs:77 Dec 06 01:29:53.650 INFO [stdout] 12: 0x55e3ffa946c9 - integration_tests::install_workflow_step_by_step::{{closure}}::h067021c0209ccafe Dec 06 01:29:53.650 INFO [stdout] at tests/integration_tests.rs:72 Dec 06 01:29:53.650 INFO [stdout] 13: 0x55e3ffa8c4bd - core::ops::function::FnOnce::call_once::hf6561cb57df4fa7a Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 06 01:29:53.650 INFO [stdout] 14: 0x55e3ffaa4f7e - >::call_box::h9ed1658cdce7f33f Dec 06 01:29:53.650 INFO [stdout] at src/libtest/lib.rs:1471 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 01:29:53.650 INFO [stdout] 15: 0x55e3ffd26839 - __rust_maybe_catch_panic Dec 06 01:29:53.650 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:29:53.650 INFO [stdout] 16: 0x55e3ffa9ce53 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 01:29:53.650 INFO [stdout] at src/libtest/lib.rs:1426 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 06 01:29:53.650 INFO [stdout] 17: 0x55e3ffa9dde4 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 06 01:29:53.650 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 06 01:29:53.650 INFO [stdout] 18: 0x55e3ffd26839 - __rust_maybe_catch_panic Dec 06 01:29:53.650 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:29:53.650 INFO [stdout] 19: 0x55e3ffaa4e5c - >::call_box::h6465cc998309e287 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 01:29:53.651 INFO [stdout] 20: 0x55e3ffd1a7fd - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 06 01:29:53.651 INFO [stdout] 21: 0x7f1661e486b9 - start_thread Dec 06 01:29:53.651 INFO [stdout] 22: 0x7f166196841c - clone Dec 06 01:29:53.651 INFO [stdout] 23: 0x0 - Dec 06 01:29:53.651 INFO [stdout] Dec 06 01:29:53.651 INFO [stdout] ---- install_workflow_all_in_one stdout ---- Dec 06 01:29:53.651 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2 Dec 06 01:29:53.651 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2 Dec 06 01:29:53.651 INFO [stdout] -> running /tmp/test-dmenv.MTnDLk6ItlbA/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 06 01:29:53.651 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 06 01:29:53.651 INFO [stdout] stack backtrace: Dec 06 01:29:53.651 INFO [stdout] 0: 0x55e3ffd13793 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h82c998a0e635a5c4 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 01:29:53.651 INFO [stdout] 1: 0x55e3ffd0ddf8 - std::sys_common::backtrace::_print::h5fbc0bc3cf12d9bf Dec 06 01:29:53.651 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 06 01:29:53.651 INFO [stdout] 2: 0x55e3ffd126b2 - std::panicking::default_hook::{{closure}}::h42b81c3dd1a50e32 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/panicking.rs:211 Dec 06 01:29:53.651 INFO [stdout] 3: 0x55e3ffd1239c - std::panicking::default_hook::h6c8d8e1c12a669e5 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/panicking.rs:221 Dec 06 01:29:53.651 INFO [stdout] 4: 0x55e3ffd12dc0 - std::panicking::rust_panic_with_hook::hce93a56e38c2b0aa Dec 06 01:29:53.651 INFO [stdout] at src/libstd/panicking.rs:491 Dec 06 01:29:53.651 INFO [stdout] 5: 0x55e3ffd12941 - std::panicking::continue_panic_fmt::hf3c774aec6124caa Dec 06 01:29:53.651 INFO [stdout] at src/libstd/panicking.rs:398 Dec 06 01:29:53.651 INFO [stdout] 6: 0x55e3ffd12825 - rust_begin_unwind Dec 06 01:29:53.651 INFO [stdout] at src/libstd/panicking.rs:325 Dec 06 01:29:53.651 INFO [stdout] 7: 0x55e3ffd2934c - core::panicking::panic_fmt::haf17319257746a90 Dec 06 01:29:53.651 INFO [stdout] at src/libcore/panicking.rs:95 Dec 06 01:29:53.651 INFO [stdout] 8: 0x55e3ffa8feeb - core::result::unwrap_failed::h282b53c00dd91c00 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/macros.rs:26 Dec 06 01:29:53.651 INFO [stdout] 9: 0x55e3ffa8fd64 - >::expect::hfc2cf701be9a437f Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/result.rs:835 Dec 06 01:29:53.651 INFO [stdout] 10: 0x55e3ffa9a0a6 - integration_tests::helpers::TestApp::assert_run_ok::h894961c8aa5e196b Dec 06 01:29:53.651 INFO [stdout] at tests/helpers/mod.rs:57 Dec 06 01:29:53.651 INFO [stdout] 11: 0x55e3ffa94668 - integration_tests::install_workflow_all_in_one::h4f37870aeafbcac4 Dec 06 01:29:53.651 INFO [stdout] at tests/integration_tests.rs:68 Dec 06 01:29:53.651 INFO [stdout] 12: 0x55e3ffa94519 - integration_tests::install_workflow_all_in_one::{{closure}}::h72788c57bde23ecc Dec 06 01:29:53.651 INFO [stdout] at tests/integration_tests.rs:63 Dec 06 01:29:53.651 INFO [stdout] 13: 0x55e3ffa8c3fd - core::ops::function::FnOnce::call_once::h7096086733d67d0f Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 06 01:29:53.651 INFO [stdout] 14: 0x55e3ffaa4f7e - >::call_box::h9ed1658cdce7f33f Dec 06 01:29:53.651 INFO [stdout] at src/libtest/lib.rs:1471 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libcore/ops/function.rs:238 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 01:29:53.651 INFO [stdout] 15: 0x55e3ffd26839 - __rust_maybe_catch_panic Dec 06 01:29:53.651 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:29:53.651 INFO [stdout] 16: 0x55e3ffa9ce53 - std::sys_common::backtrace::__rust_begin_short_backtrace::h018d03d6681412f5 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 01:29:53.651 INFO [stdout] at src/libtest/lib.rs:1426 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/sys_common/backtrace.rs:136 Dec 06 01:29:53.651 INFO [stdout] 17: 0x55e3ffa9dde4 - std::panicking::try::do_call::hf8ab3f21dcfaa7a6 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:477 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:319 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:310 Dec 06 01:29:53.651 INFO [stdout] 18: 0x55e3ffd26839 - __rust_maybe_catch_panic Dec 06 01:29:53.651 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:29:53.651 INFO [stdout] 19: 0x55e3ffaa4e5c - >::call_box::h6465cc998309e287 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panicking.rs:289 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/panic.rs:398 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/libstd/thread/mod.rs:476 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:673 Dec 06 01:29:53.651 INFO [stdout] 20: 0x55e3ffd1a7fd - std::sys::unix::thread::Thread::new::thread_start::h0fe9c527147fd5f5 Dec 06 01:29:53.651 INFO [stdout] at /rustc/6eb0fd3ac58f9b16bddb9924a17c3b6c156aa468/src/liballoc/boxed.rs:683 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 06 01:29:53.651 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 06 01:29:53.651 INFO [stdout] 21: 0x7f1661e486b9 - start_thread Dec 06 01:29:53.651 INFO [stdout] 22: 0x7f166196841c - clone Dec 06 01:29:53.651 INFO [stdout] 23: 0x0 - Dec 06 01:29:53.651 INFO [stdout] Dec 06 01:29:53.651 INFO [stdout] Dec 06 01:29:53.651 INFO [stdout] failures: Dec 06 01:29:53.651 INFO [stdout] install_workflow_all_in_one Dec 06 01:29:53.651 INFO [stdout] install_workflow_step_by_step Dec 06 01:29:53.651 INFO [stdout] lock_workflow Dec 06 01:29:53.651 INFO [stdout] Dec 06 01:29:53.651 INFO [stdout] test result: FAILED. 7 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 01:29:53.651 INFO [stdout] Dec 06 01:29:53.652 INFO [stderr] error: test failed, to rerun pass '--test integration_tests' Dec 06 01:29:53.657 INFO [stderr] su: No module specific data is present Dec 06 01:29:54.051 INFO running `"docker" "inspect" "5199d840028296d7588227b9420513e1c0be169a25b2de5ece83c74558b05af9"` Dec 06 01:29:54.309 INFO running `"docker" "rm" "-f" "5199d840028296d7588227b9420513e1c0be169a25b2de5ece83c74558b05af9"` Dec 06 01:29:54.549 INFO [stdout] 5199d840028296d7588227b9420513e1c0be169a25b2de5ece83c74558b05af9