Dec 06 01:29:13.732 INFO testing dmerejkowsky/dmenv against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514 Dec 06 01:29:13.732 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 06 01:29:14.102 INFO [stdout] 0d025717e26e3572c9c24025f47fc64f54215e052ca68c57a10a2fd07a8b23bc Dec 06 01:29:14.104 INFO running `"docker" "start" "-a" "0d025717e26e3572c9c24025f47fc64f54215e052ca68c57a10a2fd07a8b23bc"` Dec 06 01:29:14.879 INFO [stderr] usermod: no changes Dec 06 01:29:15.028 INFO [stderr] Compiling syn v0.15.17 Dec 06 01:29:25.317 INFO [stderr] Compiling synstructure v0.10.1 Dec 06 01:29:25.317 INFO [stderr] Compiling structopt-derive v0.2.12 Dec 06 01:29:25.317 INFO [stderr] Compiling serde_derive v1.0.80 Dec 06 01:29:29.977 INFO [stderr] Compiling failure_derive v0.1.3 Dec 06 01:29:31.263 INFO [stderr] Compiling structopt v0.2.12 Dec 06 01:29:33.482 INFO [stderr] Compiling failure v0.1.3 Dec 06 01:29:34.756 INFO [stderr] Compiling which v2.0.0 Dec 06 01:29:42.104 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 06 01:29:47.568 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 32.67s Dec 06 01:29:47.574 INFO [stderr] su: No module specific data is present Dec 06 01:29:47.809 INFO running `"docker" "inspect" "0d025717e26e3572c9c24025f47fc64f54215e052ca68c57a10a2fd07a8b23bc"` Dec 06 01:29:47.909 INFO running `"docker" "rm" "-f" "0d025717e26e3572c9c24025f47fc64f54215e052ca68c57a10a2fd07a8b23bc"` Dec 06 01:29:47.992 INFO [stdout] 0d025717e26e3572c9c24025f47fc64f54215e052ca68c57a10a2fd07a8b23bc Dec 06 01:29:47.994 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 06 01:29:48.147 INFO [stdout] ffc5399d4d4b8b9cb74cca198003cea604defa13441c7c0618ff138d13077d8e Dec 06 01:29:48.150 INFO running `"docker" "start" "-a" "ffc5399d4d4b8b9cb74cca198003cea604defa13441c7c0618ff138d13077d8e"` Dec 06 01:29:48.596 INFO [stderr] usermod: no changes Dec 06 01:29:48.698 INFO [stderr] Compiling dmenv v0.6.0 (/source) Dec 06 01:29:53.844 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 5.23s Dec 06 01:29:53.849 INFO [stderr] su: No module specific data is present Dec 06 01:29:54.255 INFO running `"docker" "inspect" "ffc5399d4d4b8b9cb74cca198003cea604defa13441c7c0618ff138d13077d8e"` Dec 06 01:29:54.454 INFO running `"docker" "rm" "-f" "ffc5399d4d4b8b9cb74cca198003cea604defa13441c7c0618ff138d13077d8e"` Dec 06 01:29:54.640 INFO [stdout] ffc5399d4d4b8b9cb74cca198003cea604defa13441c7c0618ff138d13077d8e Dec 06 01:29:54.647 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 06 01:29:54.941 INFO [stdout] d79973655089ebe435c1e4298c3613c12db76fed171d74e3eb02ea31bb227b8c Dec 06 01:29:54.946 INFO running `"docker" "start" "-a" "d79973655089ebe435c1e4298c3613c12db76fed171d74e3eb02ea31bb227b8c"` Dec 06 01:29:55.676 INFO [stderr] usermod: no changes Dec 06 01:29:55.800 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.10s Dec 06 01:29:55.817 INFO [stderr] Running /target/debug/deps/dmenv-1a91500b1dd448f8 Dec 06 01:29:55.818 INFO [stdout] Dec 06 01:29:55.818 INFO [stdout] running 0 tests Dec 06 01:29:55.820 INFO [stdout] Dec 06 01:29:55.820 INFO [stderr] Running /target/debug/deps/dmenv-2b54a5619f4875e9 Dec 06 01:29:55.820 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 01:29:55.820 INFO [stdout] Dec 06 01:29:55.824 INFO [stdout] Dec 06 01:29:55.824 INFO [stderr] Running /target/debug/deps/integration_tests-a34abc82bd20ab7b Dec 06 01:29:55.824 INFO [stdout] running 0 tests Dec 06 01:29:55.824 INFO [stdout] Dec 06 01:29:55.824 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 01:29:55.824 INFO [stdout] Dec 06 01:29:55.827 INFO [stdout] Dec 06 01:29:55.827 INFO [stdout] running 10 tests Dec 06 01:29:55.827 INFO [stdout] test run_without_args ... ok Dec 06 01:29:55.996 INFO [stdout] test install_without_lock ... ok Dec 06 01:29:56.053 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok Dec 06 01:29:56.138 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok Dec 06 01:29:56.138 INFO [stdout] test init_generates_setup_py ... ok Dec 06 01:29:56.221 INFO [stdout] test run_without_virtualenv ... ok Dec 06 01:29:56.276 INFO [stdout] test show_venv_path ... ok Dec 06 01:30:01.156 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:30:01.156 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:30:01.204 INFO [stderr] Exception: Dec 06 01:30:01.204 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.204 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.204 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 06 01:30:01.204 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 06 01:30:01.204 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 06 01:30:01.204 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 06 01:30:01.204 INFO [stderr] Dec 06 01:30:01.204 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:30:01.204 INFO [stderr] Dec 06 01:30:01.204 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.204 INFO [stderr] body=body, headers=headers) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.204 INFO [stderr] self._validate_conn(conn) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.204 INFO [stderr] conn.connect() Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.204 INFO [stderr] conn = self._new_conn() Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.204 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 06 01:30:01.204 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 06 01:30:01.204 INFO [stderr] Dec 06 01:30:01.204 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:30:01.204 INFO [stderr] Dec 06 01:30:01.204 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 06 01:30:01.204 INFO [stderr] status = self.run(options, args) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 06 01:30:01.204 INFO [stderr] requirement_set.prepare_files(finder) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 06 01:30:01.204 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 06 01:30:01.204 INFO [stderr] req_to_install, finder) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 06 01:30:01.204 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 06 01:30:01.204 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 06 01:30:01.204 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 06 01:30:01.204 INFO [stderr] page = self._get_page(location) Dec 06 01:30:01.204 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 06 01:30:01.205 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 06 01:30:01.205 INFO [stderr] "Cache-Control": "max-age=600", Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.205 INFO [stderr] return self.request('GET', url, **kwargs) Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 06 01:30:01.205 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.205 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.205 INFO [stderr] r = adapter.send(request, **kwargs) Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.205 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.205 INFO [stderr] timeout=timeout Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.205 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 06 01:30:01.205 INFO [stderr] File "/tmp/test-dmenv.TrX9kVG6gRyt/.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:30:01.205 INFO [stderr] total -= 1 Dec 06 01:30:01.205 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 06 01:30:01.268 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:30:01.268 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:30:01.320 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.zdB8AMVO1R7c/requirements.lock (line 2)) Dec 06 01:30:01.330 INFO [stderr] Exception: Dec 06 01:30:01.330 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.330 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.330 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 06 01:30:01.330 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.330 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 06 01:30:01.330 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 06 01:30:01.330 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 06 01:30:01.330 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 06 01:30:01.330 INFO [stderr] Dec 06 01:30:01.330 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:30:01.331 INFO [stderr] Dec 06 01:30:01.331 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] body=body, headers=headers) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] self._validate_conn(conn) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] conn.connect() Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] conn = self._new_conn() Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 06 01:30:01.331 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 06 01:30:01.331 INFO [stderr] Dec 06 01:30:01.331 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:30:01.331 INFO [stderr] Dec 06 01:30:01.331 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 06 01:30:01.331 INFO [stderr] status = self.run(options, args) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 06 01:30:01.331 INFO [stderr] requirement_set.prepare_files(finder) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 06 01:30:01.331 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file Dec 06 01:30:01.331 INFO [stderr] finder, self.upgrade, require_hashes) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link Dec 06 01:30:01.331 INFO [stderr] self.link = finder.find_requirement(self, upgrade) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 06 01:30:01.331 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 06 01:30:01.331 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 06 01:30:01.331 INFO [stderr] page = self._get_page(location) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 06 01:30:01.331 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 06 01:30:01.331 INFO [stderr] "Cache-Control": "max-age=600", Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] return self.request('GET', url, **kwargs) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 06 01:30:01.331 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] r = adapter.send(request, **kwargs) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] timeout=timeout Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 06 01:30:01.331 INFO [stderr] File "/tmp/test-dmenv.zdB8AMVO1R7c/.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:30:01.331 INFO [stderr] total -= 1 Dec 06 01:30:01.331 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 06 01:30:01.452 INFO [stdout] test lock_workflow ... FAILED Dec 06 01:30:01.463 INFO [stdout] test install_workflow_step_by_step ... FAILED Dec 06 01:30:01.505 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:30:01.505 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:30:01.549 INFO [stderr] Exception: Dec 06 01:30:01.549 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 06 01:30:01.549 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 06 01:30:01.549 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 06 01:30:01.549 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 06 01:30:01.549 INFO [stderr] Dec 06 01:30:01.549 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:30:01.549 INFO [stderr] Dec 06 01:30:01.549 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] body=body, headers=headers) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] self._validate_conn(conn) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] conn.connect() Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] conn = self._new_conn() Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 06 01:30:01.549 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 06 01:30:01.549 INFO [stderr] Dec 06 01:30:01.549 INFO [stderr] During handling of the above exception, another exception occurred: Dec 06 01:30:01.549 INFO [stderr] Dec 06 01:30:01.549 INFO [stderr] Traceback (most recent call last): Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 06 01:30:01.549 INFO [stderr] status = self.run(options, args) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 06 01:30:01.549 INFO [stderr] requirement_set.prepare_files(finder) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 06 01:30:01.549 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 06 01:30:01.549 INFO [stderr] req_to_install, finder) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 06 01:30:01.549 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 06 01:30:01.549 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 06 01:30:01.549 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 06 01:30:01.549 INFO [stderr] page = self._get_page(location) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 06 01:30:01.549 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 06 01:30:01.549 INFO [stderr] "Cache-Control": "max-age=600", Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] return self.request('GET', url, **kwargs) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 06 01:30:01.549 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] r = adapter.send(request, **kwargs) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] timeout=timeout Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 06 01:30:01.549 INFO [stderr] File "/tmp/test-dmenv.YMjR99GDwCph/.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:30:01.549 INFO [stderr] total -= 1 Dec 06 01:30:01.549 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 06 01:30:01.645 INFO [stdout] test install_workflow_all_in_one ... FAILED Dec 06 01:30:01.645 INFO [stdout] Dec 06 01:30:01.645 INFO [stdout] failures: Dec 06 01:30:01.646 INFO [stdout] Dec 06 01:30:01.646 INFO [stdout] ---- lock_workflow stdout ---- Dec 06 01:30:01.646 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2 Dec 06 01:30:01.646 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2 Dec 06 01:30:01.646 INFO [stdout] -> running /tmp/test-dmenv.TrX9kVG6gRyt/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 06 01:30:01.646 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 06 01:30:01.646 INFO [stdout] stack backtrace: Dec 06 01:30:01.646 INFO [stdout] 0: 0x56176ef00ec3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 06 01:30:01.646 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 01:30:01.646 INFO [stdout] 1: 0x56176eefb528 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 06 01:30:01.646 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 06 01:30:01.646 INFO [stdout] 2: 0x56176eeffde2 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 06 01:30:01.646 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 06 01:30:01.646 INFO [stdout] at src/libstd/panicking.rs:211 Dec 06 01:30:01.646 INFO [stdout] 3: 0x56176eeffacc - std::panicking::default_hook::h4eae6f26f73244c8 Dec 06 01:30:01.646 INFO [stdout] at src/libstd/panicking.rs:221 Dec 06 01:30:01.646 INFO [stdout] 4: 0x56176ef004f0 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 06 01:30:01.646 INFO [stdout] at src/libstd/panicking.rs:491 Dec 06 01:30:01.646 INFO [stdout] 5: 0x56176ef00071 - std::panicking::continue_panic_fmt::h1599d634b3be4304 Dec 06 01:30:01.646 INFO [stdout] at src/libstd/panicking.rs:398 Dec 06 01:30:01.646 INFO [stdout] 6: 0x56176eefff55 - rust_begin_unwind Dec 06 01:30:01.646 INFO [stdout] at src/libstd/panicking.rs:325 Dec 06 01:30:01.646 INFO [stdout] 7: 0x56176ef16a7c - core::panicking::panic_fmt::hdc202e6077c95723 Dec 06 01:30:01.646 INFO [stdout] at src/libcore/panicking.rs:95 Dec 06 01:30:01.646 INFO [stdout] 8: 0x56176ec7b55b - core::result::unwrap_failed::hd66368021fbea0b4 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/macros.rs:26 Dec 06 01:30:01.646 INFO [stdout] 9: 0x56176ec7aec4 - >::expect::h4d9587e5a8c49544 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/result.rs:835 Dec 06 01:30:01.646 INFO [stdout] 10: 0x56176ec85466 - integration_tests::helpers::TestApp::assert_run_ok::hd7ea45eb3dae98b4 Dec 06 01:30:01.646 INFO [stdout] at tests/helpers/mod.rs:57 Dec 06 01:30:01.646 INFO [stdout] 11: 0x56176ec7f615 - integration_tests::lock_workflow::h6be0e42f62586c59 Dec 06 01:30:01.646 INFO [stdout] at tests/integration_tests.rs:53 Dec 06 01:30:01.646 INFO [stdout] 12: 0x56176ec7f4e9 - integration_tests::lock_workflow::{{closure}}::h2229ba23ac2d422a Dec 06 01:30:01.646 INFO [stdout] at tests/integration_tests.rs:50 Dec 06 01:30:01.646 INFO [stdout] 13: 0x56176ec777fd - core::ops::function::FnOnce::call_once::hb49ebc87c98b3839 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 01:30:01.646 INFO [stdout] 14: 0x56176ec9030e - >::call_box::h994167e0fc25f327 Dec 06 01:30:01.646 INFO [stdout] at src/libtest/lib.rs:1471 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 01:30:01.646 INFO [stdout] 15: 0x56176ef13f69 - __rust_maybe_catch_panic Dec 06 01:30:01.646 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:30:01.646 INFO [stdout] 16: 0x56176ec88213 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5d34cfb78512c8c3 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 01:30:01.646 INFO [stdout] at src/libtest/lib.rs:1426 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 06 01:30:01.646 INFO [stdout] 17: 0x56176ec89134 - std::panicking::try::do_call::h7a86b6211ebb5e72 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 06 01:30:01.646 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 06 01:30:01.647 INFO [stdout] 18: 0x56176ef13f69 - __rust_maybe_catch_panic Dec 06 01:30:01.647 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:30:01.647 INFO [stdout] 19: 0x56176ec901ec - >::call_box::h877dbe22cb3997f4 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 01:30:01.647 INFO [stdout] 20: 0x56176ef07f2d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 06 01:30:01.647 INFO [stdout] 21: 0x7f650da3e6b9 - start_thread Dec 06 01:30:01.647 INFO [stdout] 22: 0x7f650d55e41c - clone Dec 06 01:30:01.647 INFO [stdout] 23: 0x0 - Dec 06 01:30:01.647 INFO [stdout] Dec 06 01:30:01.647 INFO [stdout] ---- install_workflow_step_by_step stdout ---- Dec 06 01:30:01.647 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2 Dec 06 01:30:01.647 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2 Dec 06 01:30:01.647 INFO [stdout] -> running /tmp/test-dmenv.zdB8AMVO1R7c/.venv/3.5.2/bin/pip install --requirement /tmp/test-dmenv.zdB8AMVO1R7c/requirements.lock Dec 06 01:30:01.647 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 06 01:30:01.647 INFO [stdout] stack backtrace: Dec 06 01:30:01.647 INFO [stdout] 0: 0x56176ef00ec3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 01:30:01.647 INFO [stdout] 1: 0x56176eefb528 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 06 01:30:01.647 INFO [stdout] 2: 0x56176eeffde2 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/panicking.rs:211 Dec 06 01:30:01.647 INFO [stdout] 3: 0x56176eeffacc - std::panicking::default_hook::h4eae6f26f73244c8 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/panicking.rs:221 Dec 06 01:30:01.647 INFO [stdout] 4: 0x56176ef004f0 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/panicking.rs:491 Dec 06 01:30:01.647 INFO [stdout] 5: 0x56176ef00071 - std::panicking::continue_panic_fmt::h1599d634b3be4304 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/panicking.rs:398 Dec 06 01:30:01.647 INFO [stdout] 6: 0x56176eefff55 - rust_begin_unwind Dec 06 01:30:01.647 INFO [stdout] at src/libstd/panicking.rs:325 Dec 06 01:30:01.647 INFO [stdout] 7: 0x56176ef16a7c - core::panicking::panic_fmt::hdc202e6077c95723 Dec 06 01:30:01.647 INFO [stdout] at src/libcore/panicking.rs:95 Dec 06 01:30:01.647 INFO [stdout] 8: 0x56176ec7b55b - core::result::unwrap_failed::hd66368021fbea0b4 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/macros.rs:26 Dec 06 01:30:01.647 INFO [stdout] 9: 0x56176ec7aec4 - >::expect::h4d9587e5a8c49544 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/result.rs:835 Dec 06 01:30:01.647 INFO [stdout] 10: 0x56176ec85466 - integration_tests::helpers::TestApp::assert_run_ok::hd7ea45eb3dae98b4 Dec 06 01:30:01.647 INFO [stdout] at tests/helpers/mod.rs:57 Dec 06 01:30:01.647 INFO [stdout] 11: 0x56176ec7fc00 - integration_tests::install_workflow_step_by_step::h4c147d706c4c2f6b Dec 06 01:30:01.647 INFO [stdout] at tests/integration_tests.rs:77 Dec 06 01:30:01.647 INFO [stdout] 12: 0x56176ec7fa89 - integration_tests::install_workflow_step_by_step::{{closure}}::h9a854ebe7d514eb8 Dec 06 01:30:01.647 INFO [stdout] at tests/integration_tests.rs:72 Dec 06 01:30:01.647 INFO [stdout] 13: 0x56176ec7773d - core::ops::function::FnOnce::call_once::h6e7334505a0ff9d5 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 01:30:01.647 INFO [stdout] 14: 0x56176ec9030e - >::call_box::h994167e0fc25f327 Dec 06 01:30:01.647 INFO [stdout] at src/libtest/lib.rs:1471 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 01:30:01.647 INFO [stdout] 15: 0x56176ef13f69 - __rust_maybe_catch_panic Dec 06 01:30:01.647 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:30:01.647 INFO [stdout] 16: 0x56176ec88213 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5d34cfb78512c8c3 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 01:30:01.647 INFO [stdout] at src/libtest/lib.rs:1426 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 06 01:30:01.647 INFO [stdout] 17: 0x56176ec89134 - std::panicking::try::do_call::h7a86b6211ebb5e72 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 06 01:30:01.647 INFO [stdout] 18: 0x56176ef13f69 - __rust_maybe_catch_panic Dec 06 01:30:01.647 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:30:01.647 INFO [stdout] 19: 0x56176ec901ec - >::call_box::h877dbe22cb3997f4 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 01:30:01.647 INFO [stdout] 20: 0x56176ef07f2d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 06 01:30:01.647 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 06 01:30:01.647 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 06 01:30:01.647 INFO [stdout] 21: 0x7f650da3e6b9 - start_thread Dec 06 01:30:01.647 INFO [stdout] 22: 0x7f650d55e41c - clone Dec 06 01:30:01.647 INFO [stdout] 23: 0x0 - Dec 06 01:30:01.647 INFO [stdout] Dec 06 01:30:01.647 INFO [stdout] ---- install_workflow_all_in_one stdout ---- Dec 06 01:30:01.648 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2 Dec 06 01:30:01.648 INFO [stdout] -> running /usr/bin/python3 -m venv /tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2 Dec 06 01:30:01.648 INFO [stdout] -> running /tmp/test-dmenv.YMjR99GDwCph/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 06 01:30:01.648 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 06 01:30:01.648 INFO [stdout] stack backtrace: Dec 06 01:30:01.648 INFO [stdout] 0: 0x56176ef00ec3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3c2ada0a2db8c5e7 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 06 01:30:01.648 INFO [stdout] 1: 0x56176eefb528 - std::sys_common::backtrace::_print::h95865c77318e98b7 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 06 01:30:01.648 INFO [stdout] 2: 0x56176eeffde2 - std::panicking::default_hook::{{closure}}::hb3d1f299fcea42e5 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/panicking.rs:211 Dec 06 01:30:01.648 INFO [stdout] 3: 0x56176eeffacc - std::panicking::default_hook::h4eae6f26f73244c8 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/panicking.rs:221 Dec 06 01:30:01.648 INFO [stdout] 4: 0x56176ef004f0 - std::panicking::rust_panic_with_hook::h368d37fd53b59464 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/panicking.rs:491 Dec 06 01:30:01.648 INFO [stdout] 5: 0x56176ef00071 - std::panicking::continue_panic_fmt::h1599d634b3be4304 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/panicking.rs:398 Dec 06 01:30:01.648 INFO [stdout] 6: 0x56176eefff55 - rust_begin_unwind Dec 06 01:30:01.648 INFO [stdout] at src/libstd/panicking.rs:325 Dec 06 01:30:01.648 INFO [stdout] 7: 0x56176ef16a7c - core::panicking::panic_fmt::hdc202e6077c95723 Dec 06 01:30:01.648 INFO [stdout] at src/libcore/panicking.rs:95 Dec 06 01:30:01.648 INFO [stdout] 8: 0x56176ec7b55b - core::result::unwrap_failed::hd66368021fbea0b4 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/macros.rs:26 Dec 06 01:30:01.648 INFO [stdout] 9: 0x56176ec7aec4 - >::expect::h4d9587e5a8c49544 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/result.rs:835 Dec 06 01:30:01.648 INFO [stdout] 10: 0x56176ec85466 - integration_tests::helpers::TestApp::assert_run_ok::hd7ea45eb3dae98b4 Dec 06 01:30:01.648 INFO [stdout] at tests/helpers/mod.rs:57 Dec 06 01:30:01.648 INFO [stdout] 11: 0x56176ec7fa28 - integration_tests::install_workflow_all_in_one::he843dae6b046c7d6 Dec 06 01:30:01.648 INFO [stdout] at tests/integration_tests.rs:68 Dec 06 01:30:01.648 INFO [stdout] 12: 0x56176ec7f8d9 - integration_tests::install_workflow_all_in_one::{{closure}}::he4980b240d2db027 Dec 06 01:30:01.648 INFO [stdout] at tests/integration_tests.rs:63 Dec 06 01:30:01.648 INFO [stdout] 13: 0x56176ec777bd - core::ops::function::FnOnce::call_once::h8b394971d5030a0f Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 01:30:01.648 INFO [stdout] 14: 0x56176ec9030e - >::call_box::h994167e0fc25f327 Dec 06 01:30:01.648 INFO [stdout] at src/libtest/lib.rs:1471 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libcore/ops/function.rs:238 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 01:30:01.648 INFO [stdout] 15: 0x56176ef13f69 - __rust_maybe_catch_panic Dec 06 01:30:01.648 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:30:01.648 INFO [stdout] 16: 0x56176ec88213 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5d34cfb78512c8c3 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 01:30:01.648 INFO [stdout] at src/libtest/lib.rs:1426 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/sys_common/backtrace.rs:136 Dec 06 01:30:01.648 INFO [stdout] 17: 0x56176ec89134 - std::panicking::try::do_call::h7a86b6211ebb5e72 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:477 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:319 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:310 Dec 06 01:30:01.648 INFO [stdout] 18: 0x56176ef13f69 - __rust_maybe_catch_panic Dec 06 01:30:01.648 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 06 01:30:01.648 INFO [stdout] 19: 0x56176ec901ec - >::call_box::h877dbe22cb3997f4 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panicking.rs:289 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/panic.rs:398 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/libstd/thread/mod.rs:476 Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:673 Dec 06 01:30:01.648 INFO [stdout] 20: 0x56176ef07f2d - std::sys::unix::thread::Thread::new::thread_start::hea5f7f9c570dc4cd Dec 06 01:30:01.648 INFO [stdout] at /rustc/906deae0790bd18681b937fe9a141a3c26cf1855/src/liballoc/boxed.rs:683 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 06 01:30:01.648 INFO [stdout] at src/libstd/sys/unix/thread.rs:90 Dec 06 01:30:01.648 INFO [stdout] 21: 0x7f650da3e6b9 - start_thread Dec 06 01:30:01.648 INFO [stdout] 22: 0x7f650d55e41c - clone Dec 06 01:30:01.648 INFO [stdout] 23: 0x0 - Dec 06 01:30:01.648 INFO [stdout] Dec 06 01:30:01.648 INFO [stdout] Dec 06 01:30:01.648 INFO [stdout] failures: Dec 06 01:30:01.648 INFO [stdout] install_workflow_all_in_one Dec 06 01:30:01.648 INFO [stdout] install_workflow_step_by_step Dec 06 01:30:01.648 INFO [stdout] lock_workflow Dec 06 01:30:01.648 INFO [stdout] Dec 06 01:30:01.648 INFO [stdout] test result: FAILED. 7 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out Dec 06 01:30:01.648 INFO [stdout] Dec 06 01:30:01.649 INFO [stderr] error: test failed, to rerun pass '--test integration_tests' Dec 06 01:30:01.651 INFO [stderr] su: No module specific data is present Dec 06 01:30:02.688 INFO running `"docker" "inspect" "d79973655089ebe435c1e4298c3613c12db76fed171d74e3eb02ea31bb227b8c"` Dec 06 01:30:03.004 INFO running `"docker" "rm" "-f" "d79973655089ebe435c1e4298c3613c12db76fed171d74e3eb02ea31bb227b8c"` Dec 06 01:30:03.436 INFO [stdout] d79973655089ebe435c1e4298c3613c12db76fed171d74e3eb02ea31bb227b8c