Dec 13 21:13:55.276 INFO testing dmerejkowsky/dmenv against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 13 21:13:55.276 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 21:13:55.797 INFO [stdout] 75d83e6972d7b6de92052b6042fa6b62deeda9e50415dd95a223218f108a2db9 Dec 13 21:13:55.801 INFO running `"docker" "start" "-a" "75d83e6972d7b6de92052b6042fa6b62deeda9e50415dd95a223218f108a2db9"` Dec 13 21:13:56.662 INFO [stderr] usermod: no changes Dec 13 21:13:56.866 INFO [stderr] Compiling proc-macro2 v0.4.20 Dec 13 21:13:56.866 INFO [stderr] Compiling version_check v0.1.5 Dec 13 21:13:56.867 INFO [stderr] Compiling appdirs v0.2.0 Dec 13 21:13:58.314 INFO [stderr] Compiling lazy_static v1.1.0 Dec 13 21:13:59.251 INFO [stderr] Compiling colored v1.6.1 Dec 13 21:14:01.016 INFO [stderr] Compiling quote v0.6.9 Dec 13 21:14:02.980 INFO [stderr] Compiling syn v0.15.17 Dec 13 21:14:14.329 INFO [stderr] Compiling synstructure v0.10.1 Dec 13 21:14:14.329 INFO [stderr] Compiling structopt-derive v0.2.12 Dec 13 21:14:14.329 INFO [stderr] Compiling serde_derive v1.0.80 Dec 13 21:14:20.051 INFO [stderr] Compiling failure_derive v0.1.3 Dec 13 21:14:21.183 INFO [stderr] Compiling structopt v0.2.12 Dec 13 21:14:24.348 INFO [stderr] Compiling failure v0.1.3 Dec 13 21:14:26.147 INFO [stderr] Compiling which v2.0.0 Dec 13 21:14:33.571 INFO [stderr] Compiling dmenv v0.7.0 (/source) Dec 13 21:14:39.764 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 43.08s Dec 13 21:14:39.771 INFO [stderr] su: No module specific data is present Dec 13 21:14:40.131 INFO running `"docker" "inspect" "75d83e6972d7b6de92052b6042fa6b62deeda9e50415dd95a223218f108a2db9"` Dec 13 21:14:40.224 INFO running `"docker" "rm" "-f" "75d83e6972d7b6de92052b6042fa6b62deeda9e50415dd95a223218f108a2db9"` Dec 13 21:14:40.366 INFO [stdout] 75d83e6972d7b6de92052b6042fa6b62deeda9e50415dd95a223218f108a2db9 Dec 13 21:14:40.369 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 21:14:40.788 INFO [stdout] d712d8ebac3d600bd6d1372451dd5808b23622cdda811884923cfee4c93a672c Dec 13 21:14:40.789 INFO running `"docker" "start" "-a" "d712d8ebac3d600bd6d1372451dd5808b23622cdda811884923cfee4c93a672c"` Dec 13 21:14:41.818 INFO [stderr] usermod: no changes Dec 13 21:14:41.959 INFO [stderr] Compiling dmenv v0.7.0 (/source) Dec 13 21:14:47.954 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 6.10s Dec 13 21:14:47.954 INFO [stderr] su: No module specific data is present Dec 13 21:14:48.294 INFO running `"docker" "inspect" "d712d8ebac3d600bd6d1372451dd5808b23622cdda811884923cfee4c93a672c"` Dec 13 21:14:48.476 INFO running `"docker" "rm" "-f" "d712d8ebac3d600bd6d1372451dd5808b23622cdda811884923cfee4c93a672c"` Dec 13 21:14:48.584 INFO [stdout] d712d8ebac3d600bd6d1372451dd5808b23622cdda811884923cfee4c93a672c Dec 13 21:14:48.589 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +bd47d6825bf4090517549d33cfef10d3300b4a75-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"` Dec 13 21:14:48.807 INFO [stdout] e254cc9b7bdb4da98b85ddbdf019e1057b0c5674f983e428fc3b9e418600b352 Dec 13 21:14:48.813 INFO running `"docker" "start" "-a" "e254cc9b7bdb4da98b85ddbdf019e1057b0c5674f983e428fc3b9e418600b352"` Dec 13 21:14:49.939 INFO [stderr] usermod: no changes Dec 13 21:14:50.039 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.07s Dec 13 21:14:50.059 INFO [stderr] Running /target/debug/deps/dmenv-a9a5e8dabb08dcf6 Dec 13 21:14:50.063 INFO [stdout] Dec 13 21:14:50.063 INFO [stdout] running 4 tests Dec 13 21:14:50.063 INFO [stdout] test lock::tests::git_bump ... ok Dec 13 21:14:50.063 INFO [stdout] test lock::tests::dep_not_found ... ok Dec 13 21:14:50.063 INFO [stdout] test lock::tests::malformed_lock ... ok Dec 13 21:14:50.071 INFO [stdout] test lock::tests::simple_bump ... ok Dec 13 21:14:50.071 INFO [stdout] Dec 13 21:14:50.071 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 21:14:50.071 INFO [stdout] Dec 13 21:14:50.075 INFO [stderr] Running /target/debug/deps/dmenv-876ce0c66f2b23af Dec 13 21:14:50.078 INFO [stderr] Running /target/debug/deps/integration_tests-2dd5574beff9b3a6 Dec 13 21:14:50.078 INFO [stdout] Dec 13 21:14:50.078 INFO [stdout] running 0 tests Dec 13 21:14:50.078 INFO [stdout] Dec 13 21:14:50.078 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 21:14:50.078 INFO [stdout] Dec 13 21:14:50.080 INFO [stdout] Dec 13 21:14:50.080 INFO [stdout] running 12 tests Dec 13 21:14:50.227 INFO [stdout] test bump_in_lock_simple ... ok Dec 13 21:14:50.239 INFO [stdout] test bump_in_lock_git ... ok Dec 13 21:14:50.255 INFO [stdout] test run_without_args ... ok Dec 13 21:14:50.303 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok Dec 13 21:14:50.307 INFO [stdout] test install_without_lock ... ok Dec 13 21:14:50.326 INFO [stdout] test init_generates_setup_py ... ok Dec 13 21:14:50.471 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok Dec 13 21:14:50.527 INFO [stdout] test run_without_virtualenv ... ok Dec 13 21:14:50.527 INFO [stdout] test show_venv_path ... ok Dec 13 21:14:54.845 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 13 21:14:54.845 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 13 21:14:54.860 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 13 21:14:54.860 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 13 21:14:54.860 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 13 21:14:54.868 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 13 21:14:54.879 INFO [stderr] Exception: Dec 13 21:14:54.879 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.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 13 21:14:54.879 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.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 13 21:14:54.879 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 13 21:14:54.879 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 13 21:14:54.879 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 13 21:14:54.879 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 13 21:14:54.879 INFO [stderr] Dec 13 21:14:54.879 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:54.879 INFO [stderr] Dec 13 21:14:54.879 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 13 21:14:54.879 INFO [stderr] body=body, headers=headers) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.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 13 21:14:54.879 INFO [stderr] self._validate_conn(conn) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.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 13 21:14:54.879 INFO [stderr] conn.connect() Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 13 21:14:54.879 INFO [stderr] conn = self._new_conn() Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.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 13 21:14:54.879 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 13 21:14:54.879 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 13 21:14:54.879 INFO [stderr] Dec 13 21:14:54.879 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:54.879 INFO [stderr] Dec 13 21:14:54.879 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 13 21:14:54.879 INFO [stderr] status = self.run(options, args) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 13 21:14:54.879 INFO [stderr] requirement_set.prepare_files(finder) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 13 21:14:54.879 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 13 21:14:54.879 INFO [stderr] req_to_install, finder) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 13 21:14:54.879 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 13 21:14:54.879 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 13 21:14:54.879 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 13 21:14:54.879 INFO [stderr] page = self._get_page(location) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 13 21:14:54.879 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 13 21:14:54.879 INFO [stderr] "Cache-Control": "max-age=600", Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 13 21:14:54.879 INFO [stderr] return self.request('GET', url, **kwargs) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 13 21:14:54.879 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 13 21:14:54.879 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 13 21:14:54.879 INFO [stderr] r = adapter.send(request, **kwargs) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 13 21:14:54.879 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 13 21:14:54.879 INFO [stderr] timeout=timeout Dec 13 21:14:54.879 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 13 21:14:54.879 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 13 21:14:54.880 INFO [stderr] File "/tmp/test-dmenv.B53n8kR2F3Hk/.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 13 21:14:54.880 INFO [stderr] total -= 1 Dec 13 21:14:54.880 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 13 21:14:54.911 INFO [stderr] Exception: Dec 13 21:14:54.911 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.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 13 21:14:54.911 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.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 13 21:14:54.911 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 13 21:14:54.911 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 13 21:14:54.911 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 13 21:14:54.911 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 13 21:14:54.911 INFO [stderr] Dec 13 21:14:54.911 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:54.911 INFO [stderr] Dec 13 21:14:54.911 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 13 21:14:54.911 INFO [stderr] body=body, headers=headers) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.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 13 21:14:54.911 INFO [stderr] self._validate_conn(conn) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.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 13 21:14:54.911 INFO [stderr] conn.connect() Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 13 21:14:54.911 INFO [stderr] conn = self._new_conn() Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.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 13 21:14:54.911 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 13 21:14:54.911 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 13 21:14:54.911 INFO [stderr] Dec 13 21:14:54.911 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:54.911 INFO [stderr] Dec 13 21:14:54.911 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 13 21:14:54.911 INFO [stderr] status = self.run(options, args) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 13 21:14:54.911 INFO [stderr] requirement_set.prepare_files(finder) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 13 21:14:54.911 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file Dec 13 21:14:54.911 INFO [stderr] req_to_install, finder) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed Dec 13 21:14:54.911 INFO [stderr] finder.find_requirement(req_to_install, self.upgrade) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 13 21:14:54.911 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 13 21:14:54.911 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 13 21:14:54.911 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 13 21:14:54.911 INFO [stderr] page = self._get_page(location) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 13 21:14:54.912 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 13 21:14:54.912 INFO [stderr] "Cache-Control": "max-age=600", Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 13 21:14:54.912 INFO [stderr] return self.request('GET', url, **kwargs) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 13 21:14:54.912 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 13 21:14:54.912 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 13 21:14:54.912 INFO [stderr] r = adapter.send(request, **kwargs) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 13 21:14:54.912 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 13 21:14:54.912 INFO [stderr] timeout=timeout Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 13 21:14:54.912 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 13 21:14:54.912 INFO [stderr] File "/tmp/test-dmenv.CMzjj5xdC3Aa/.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 13 21:14:54.912 INFO [stderr] total -= 1 Dec 13 21:14:54.912 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 13 21:14:54.915 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.5mvNjjGOfFys/requirements.lock (line 2)) Dec 13 21:14:54.926 INFO [stderr] Exception: Dec 13 21:14:54.926 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.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 13 21:14:54.926 INFO [stderr] (self.host, self.port), self.timeout, **extra_kw) Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.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 13 21:14:54.926 INFO [stderr] for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM): Dec 13 21:14:54.926 INFO [stderr] File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo Dec 13 21:14:54.926 INFO [stderr] for res in _socket.getaddrinfo(host, port, family, type, proto, flags): Dec 13 21:14:54.926 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution Dec 13 21:14:54.926 INFO [stderr] Dec 13 21:14:54.926 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:54.926 INFO [stderr] Dec 13 21:14:54.926 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen Dec 13 21:14:54.926 INFO [stderr] body=body, headers=headers) Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.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 13 21:14:54.926 INFO [stderr] self._validate_conn(conn) Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.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 13 21:14:54.926 INFO [stderr] conn.connect() Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect Dec 13 21:14:54.926 INFO [stderr] conn = self._new_conn() Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.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 13 21:14:54.926 INFO [stderr] self, "Failed to establish a new connection: %s" % e) Dec 13 21:14:54.926 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: : Failed to establish a new connection: [Errno -3] Temporary failure in name resolution Dec 13 21:14:54.926 INFO [stderr] Dec 13 21:14:54.926 INFO [stderr] During handling of the above exception, another exception occurred: Dec 13 21:14:54.926 INFO [stderr] Dec 13 21:14:54.926 INFO [stderr] Traceback (most recent call last): Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main Dec 13 21:14:54.926 INFO [stderr] status = self.run(options, args) Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run Dec 13 21:14:54.926 INFO [stderr] requirement_set.prepare_files(finder) Dec 13 21:14:54.926 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files Dec 13 21:14:54.926 INFO [stderr] ignore_dependencies=self.ignore_dependencies)) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file Dec 13 21:14:54.927 INFO [stderr] finder, self.upgrade, require_hashes) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link Dec 13 21:14:54.927 INFO [stderr] self.link = finder.find_requirement(self, upgrade) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement Dec 13 21:14:54.927 INFO [stderr] all_candidates = self.find_all_candidates(req.name) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates Dec 13 21:14:54.927 INFO [stderr] for page in self._get_pages(url_locations, project_name): Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages Dec 13 21:14:54.927 INFO [stderr] page = self._get_page(location) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page Dec 13 21:14:54.927 INFO [stderr] return HTMLPage.get_page(link, session=self.session) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page Dec 13 21:14:54.927 INFO [stderr] "Cache-Control": "max-age=600", Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get Dec 13 21:14:54.927 INFO [stderr] return self.request('GET', url, **kwargs) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request Dec 13 21:14:54.927 INFO [stderr] return super(PipSession, self).request(method, url, *args, **kwargs) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request Dec 13 21:14:54.927 INFO [stderr] resp = self.send(prep, **send_kwargs) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send Dec 13 21:14:54.927 INFO [stderr] r = adapter.send(request, **kwargs) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send Dec 13 21:14:54.927 INFO [stderr] resp = super(CacheControlAdapter, self).send(request, **kw) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send Dec 13 21:14:54.927 INFO [stderr] timeout=timeout Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen Dec 13 21:14:54.927 INFO [stderr] _stacktrace=sys.exc_info()[2]) Dec 13 21:14:54.927 INFO [stderr] File "/tmp/test-dmenv.5mvNjjGOfFys/.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 13 21:14:54.927 INFO [stderr] total -= 1 Dec 13 21:14:54.927 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int' Dec 13 21:14:55.075 INFO [stdout] test install_workflow_all_in_one ... FAILED Dec 13 21:14:55.080 INFO [stdout] test install_workflow_step_by_step ... FAILED Dec 13 21:14:55.088 INFO [stdout] test lock_workflow ... FAILED Dec 13 21:14:55.088 INFO [stdout] Dec 13 21:14:55.088 INFO [stdout] failures: Dec 13 21:14:55.088 INFO [stdout] Dec 13 21:14:55.088 INFO [stdout] ---- install_workflow_all_in_one stdout ---- Dec 13 21:14:55.088 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2 Dec 13 21:14:55.088 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2 Dec 13 21:14:55.088 INFO [stdout] -> Running /tmp/test-dmenv.CMzjj5xdC3Aa/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 13 21:14:55.088 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 13 21:14:55.088 INFO [stdout] stack backtrace: Dec 13 21:14:55.089 INFO [stdout] 0: 0x557a399db223 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 21:14:55.089 INFO [stdout] 1: 0x557a399d5968 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 21:14:55.089 INFO [stdout] 2: 0x557a399da142 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 21:14:55.089 INFO [stdout] 3: 0x557a399d9e37 - std::panicking::default_hook::h1134a4d34aab4428 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 21:14:55.089 INFO [stdout] 4: 0x557a399da850 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 21:14:55.089 INFO [stdout] 5: 0x557a399da3d1 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:394 Dec 13 21:14:55.089 INFO [stdout] 6: 0x557a399da2b5 - rust_begin_unwind Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:321 Dec 13 21:14:55.089 INFO [stdout] 7: 0x557a399f0ddc - core::panicking::panic_fmt::h68c043a5e81351f3 Dec 13 21:14:55.089 INFO [stdout] at src/libcore/panicking.rs:95 Dec 13 21:14:55.089 INFO [stdout] 8: 0x557a397aeab0 - core::result::unwrap_failed::ha48681ae7ec6fcf2 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/macros.rs:26 Dec 13 21:14:55.089 INFO [stdout] 9: 0x557a39749298 - >::expect::hcaf6d60a08542057 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/result.rs:835 Dec 13 21:14:55.089 INFO [stdout] 10: 0x557a397486a6 - integration_tests::helpers::TestApp::assert_run_ok::h35830a910c85a72c Dec 13 21:14:55.089 INFO [stdout] at tests/helpers/mod.rs:57 Dec 13 21:14:55.089 INFO [stdout] 11: 0x557a39740578 - integration_tests::install_workflow_all_in_one::h5c2838f43b51a935 Dec 13 21:14:55.089 INFO [stdout] at tests/integration_tests.rs:98 Dec 13 21:14:55.089 INFO [stdout] 12: 0x557a39740429 - integration_tests::install_workflow_all_in_one::{{closure}}::h8744880b35d7bfdb Dec 13 21:14:55.089 INFO [stdout] at tests/integration_tests.rs:93 Dec 13 21:14:55.089 INFO [stdout] 13: 0x557a3973aefd - core::ops::function::FnOnce::call_once::h96c6fd2bcad1d232 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 21:14:55.089 INFO [stdout] 14: 0x557a39752a6e - >::call_box::h3cf534772c089f15 Dec 13 21:14:55.089 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 21:14:55.089 INFO [stdout] 15: 0x557a399ee2c9 - __rust_maybe_catch_panic Dec 13 21:14:55.089 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:55.089 INFO [stdout] 16: 0x557a39770417 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 21:14:55.089 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 21:14:55.089 INFO [stdout] 17: 0x557a3974b324 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 13 21:14:55.089 INFO [stdout] 18: 0x557a3974b994 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 13 21:14:55.089 INFO [stdout] 19: 0x557a399ee2c9 - __rust_maybe_catch_panic Dec 13 21:14:55.089 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:55.089 INFO [stdout] 20: 0x557a3975294c - >::call_box::h260fd1ad8e2854a6 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 21:14:55.089 INFO [stdout] 21: 0x557a399e228d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 21:14:55.089 INFO [stdout] 22: 0x7f2847b186b9 - start_thread Dec 13 21:14:55.089 INFO [stdout] 23: 0x7f284763841c - clone Dec 13 21:14:55.089 INFO [stdout] 24: 0x0 - Dec 13 21:14:55.089 INFO [stdout] Dec 13 21:14:55.089 INFO [stdout] ---- install_workflow_step_by_step stdout ---- Dec 13 21:14:55.089 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2 Dec 13 21:14:55.089 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2 Dec 13 21:14:55.089 INFO [stdout] -> Running /tmp/test-dmenv.5mvNjjGOfFys/.venv/3.5.2/bin/pip install --requirement /tmp/test-dmenv.5mvNjjGOfFys/requirements.lock Dec 13 21:14:55.089 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 13 21:14:55.089 INFO [stdout] stack backtrace: Dec 13 21:14:55.089 INFO [stdout] 0: 0x557a399db223 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 21:14:55.089 INFO [stdout] 1: 0x557a399d5968 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 21:14:55.089 INFO [stdout] 2: 0x557a399da142 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 21:14:55.089 INFO [stdout] 3: 0x557a399d9e37 - std::panicking::default_hook::h1134a4d34aab4428 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 21:14:55.089 INFO [stdout] 4: 0x557a399da850 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 21:14:55.089 INFO [stdout] 5: 0x557a399da3d1 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:394 Dec 13 21:14:55.089 INFO [stdout] 6: 0x557a399da2b5 - rust_begin_unwind Dec 13 21:14:55.089 INFO [stdout] at src/libstd/panicking.rs:321 Dec 13 21:14:55.089 INFO [stdout] 7: 0x557a399f0ddc - core::panicking::panic_fmt::h68c043a5e81351f3 Dec 13 21:14:55.089 INFO [stdout] at src/libcore/panicking.rs:95 Dec 13 21:14:55.089 INFO [stdout] 8: 0x557a397aeab0 - core::result::unwrap_failed::ha48681ae7ec6fcf2 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/macros.rs:26 Dec 13 21:14:55.089 INFO [stdout] 9: 0x557a39749298 - >::expect::hcaf6d60a08542057 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/result.rs:835 Dec 13 21:14:55.089 INFO [stdout] 10: 0x557a397486a6 - integration_tests::helpers::TestApp::assert_run_ok::h35830a910c85a72c Dec 13 21:14:55.089 INFO [stdout] at tests/helpers/mod.rs:57 Dec 13 21:14:55.089 INFO [stdout] 11: 0x557a39740750 - integration_tests::install_workflow_step_by_step::h919860f0cf036317 Dec 13 21:14:55.089 INFO [stdout] at tests/integration_tests.rs:107 Dec 13 21:14:55.089 INFO [stdout] 12: 0x557a397405d9 - integration_tests::install_workflow_step_by_step::{{closure}}::h8c41ec833ac26447 Dec 13 21:14:55.089 INFO [stdout] at tests/integration_tests.rs:102 Dec 13 21:14:55.089 INFO [stdout] 13: 0x557a3973af3d - core::ops::function::FnOnce::call_once::h97a655ca8db8af48 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 21:14:55.089 INFO [stdout] 14: 0x557a39752a6e - >::call_box::h3cf534772c089f15 Dec 13 21:14:55.089 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 21:14:55.089 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 21:14:55.090 INFO [stdout] 15: 0x557a399ee2c9 - __rust_maybe_catch_panic Dec 13 21:14:55.090 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:55.090 INFO [stdout] 16: 0x557a39770417 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 21:14:55.090 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 21:14:55.090 INFO [stdout] 17: 0x557a3974b324 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 13 21:14:55.090 INFO [stdout] 18: 0x557a3974b994 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 13 21:14:55.090 INFO [stdout] 19: 0x557a399ee2c9 - __rust_maybe_catch_panic Dec 13 21:14:55.090 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:55.090 INFO [stdout] 20: 0x557a3975294c - >::call_box::h260fd1ad8e2854a6 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 21:14:55.090 INFO [stdout] 21: 0x557a399e228d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 21:14:55.090 INFO [stdout] 22: 0x7f2847b186b9 - start_thread Dec 13 21:14:55.090 INFO [stdout] 23: 0x7f284763841c - clone Dec 13 21:14:55.090 INFO [stdout] 24: 0x0 - Dec 13 21:14:55.090 INFO [stdout] Dec 13 21:14:55.090 INFO [stdout] ---- lock_workflow stdout ---- Dec 13 21:14:55.090 INFO [stdout] :: Creating virtualenv in: /tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2 Dec 13 21:14:55.090 INFO [stdout] -> Running /usr/bin/python3 -m venv /tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2 Dec 13 21:14:55.090 INFO [stdout] -> Running /tmp/test-dmenv.B53n8kR2F3Hk/.venv/3.5.2/bin/python -m pip install pip --upgrade Dec 13 21:14:55.090 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', src/libcore/result.rs:1009:5 Dec 13 21:14:55.090 INFO [stdout] stack backtrace: Dec 13 21:14:55.090 INFO [stdout] 0: 0x557a399db223 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49 Dec 13 21:14:55.090 INFO [stdout] 1: 0x557a399d5968 - std::sys_common::backtrace::_print::h919420b22c2473e3 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/sys_common/backtrace.rs:71 Dec 13 21:14:55.090 INFO [stdout] 2: 0x557a399da142 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/sys_common/backtrace.rs:59 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/panicking.rs:210 Dec 13 21:14:55.090 INFO [stdout] 3: 0x557a399d9e37 - std::panicking::default_hook::h1134a4d34aab4428 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/panicking.rs:218 Dec 13 21:14:55.090 INFO [stdout] 4: 0x557a399da850 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/panicking.rs:487 Dec 13 21:14:55.090 INFO [stdout] 5: 0x557a399da3d1 - std::panicking::continue_panic_fmt::h6b63497ce98499d4 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/panicking.rs:394 Dec 13 21:14:55.090 INFO [stdout] 6: 0x557a399da2b5 - rust_begin_unwind Dec 13 21:14:55.090 INFO [stdout] at src/libstd/panicking.rs:321 Dec 13 21:14:55.090 INFO [stdout] 7: 0x557a399f0ddc - core::panicking::panic_fmt::h68c043a5e81351f3 Dec 13 21:14:55.090 INFO [stdout] at src/libcore/panicking.rs:95 Dec 13 21:14:55.090 INFO [stdout] 8: 0x557a397aeab0 - core::result::unwrap_failed::ha48681ae7ec6fcf2 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/macros.rs:26 Dec 13 21:14:55.090 INFO [stdout] 9: 0x557a39749298 - >::expect::hcaf6d60a08542057 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/result.rs:835 Dec 13 21:14:55.090 INFO [stdout] 10: 0x557a397486a6 - integration_tests::helpers::TestApp::assert_run_ok::h35830a910c85a72c Dec 13 21:14:55.090 INFO [stdout] at tests/helpers/mod.rs:57 Dec 13 21:14:55.090 INFO [stdout] 11: 0x557a39740165 - integration_tests::lock_workflow::hf2586e95e868df86 Dec 13 21:14:55.090 INFO [stdout] at tests/integration_tests.rs:83 Dec 13 21:14:55.090 INFO [stdout] 12: 0x557a39740039 - integration_tests::lock_workflow::{{closure}}::h9de51d25cfe303dd Dec 13 21:14:55.090 INFO [stdout] at tests/integration_tests.rs:80 Dec 13 21:14:55.090 INFO [stdout] 13: 0x557a3973affd - core::ops::function::FnOnce::call_once::he5f028d0b7c36a97 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 21:14:55.090 INFO [stdout] 14: 0x557a39752a6e - >::call_box::h3cf534772c089f15 Dec 13 21:14:55.090 INFO [stdout] at src/libtest/lib.rs:1483 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 21:14:55.090 INFO [stdout] 15: 0x557a399ee2c9 - __rust_maybe_catch_panic Dec 13 21:14:55.090 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:55.090 INFO [stdout] 16: 0x557a39770417 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 21:14:55.090 INFO [stdout] at src/libtest/lib.rs:1438 Dec 13 21:14:55.090 INFO [stdout] 17: 0x557a3974b324 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136 Dec 13 21:14:55.090 INFO [stdout] 18: 0x557a3974b994 - std::panicking::try::do_call::h6a71e68ea4ad0d8a Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306 Dec 13 21:14:55.090 INFO [stdout] 19: 0x557a399ee2c9 - __rust_maybe_catch_panic Dec 13 21:14:55.090 INFO [stdout] at src/libpanic_unwind/lib.rs:102 Dec 13 21:14:55.090 INFO [stdout] 20: 0x557a3975294c - >::call_box::h260fd1ad8e2854a6 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673 Dec 13 21:14:55.090 INFO [stdout] 21: 0x557a399e228d - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99 Dec 13 21:14:55.090 INFO [stdout] at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/sys_common/thread.rs:24 Dec 13 21:14:55.090 INFO [stdout] at src/libstd/sys/unix/thread.rs:91 Dec 13 21:14:55.090 INFO [stdout] 22: 0x7f2847b186b9 - start_thread Dec 13 21:14:55.090 INFO [stdout] 23: 0x7f284763841c - clone Dec 13 21:14:55.090 INFO [stdout] 24: 0x0 - Dec 13 21:14:55.090 INFO [stdout] Dec 13 21:14:55.090 INFO [stdout] Dec 13 21:14:55.091 INFO [stdout] failures: Dec 13 21:14:55.091 INFO [stdout] install_workflow_all_in_one Dec 13 21:14:55.091 INFO [stdout] install_workflow_step_by_step Dec 13 21:14:55.091 INFO [stdout] lock_workflow Dec 13 21:14:55.091 INFO [stdout] Dec 13 21:14:55.091 INFO [stdout] test result: FAILED. 9 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out Dec 13 21:14:55.091 INFO [stdout] Dec 13 21:14:55.093 INFO [stderr] error: test failed, to rerun pass '--test integration_tests' Dec 13 21:14:55.098 INFO [stderr] su: No module specific data is present Dec 13 21:14:55.648 INFO running `"docker" "inspect" "e254cc9b7bdb4da98b85ddbdf019e1057b0c5674f983e428fc3b9e418600b352"` Dec 13 21:14:55.837 INFO running `"docker" "rm" "-f" "e254cc9b7bdb4da98b85ddbdf019e1057b0c5674f983e428fc3b9e418600b352"` Dec 13 21:14:56.072 INFO [stdout] e254cc9b7bdb4da98b85ddbdf019e1057b0c5674f983e428fc3b9e418600b352