Dec 01 19:15:08.933 INFO testing dmerejkowsky/dmenv against 1.30.1 for beta-1.31-5
Dec 01 19:15:08.933 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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 +1.30.1 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 19:15:09.412 INFO [stdout] d1874647b46f19c62280ff1e47c64307f4417e7f79d1c55170ecae0a69d9a0a5
Dec 01 19:15:09.414 INFO running `"docker" "start" "-a" "d1874647b46f19c62280ff1e47c64307f4417e7f79d1c55170ecae0a69d9a0a5"`
Dec 01 19:15:10.690 INFO [stderr] usermod: no changes
Dec 01 19:15:10.887 INFO [stderr]    Compiling appdirs v0.2.0
Dec 01 19:15:10.899 INFO [stderr]    Compiling structopt-derive v0.2.12
Dec 01 19:15:10.899 INFO [stderr]    Compiling failure v0.1.3
Dec 01 19:15:12.912 INFO [stderr]    Compiling which v2.0.0
Dec 01 19:15:18.664 INFO [stderr]    Compiling structopt v0.2.12
Dec 01 19:15:19.626 INFO [stderr]    Compiling dmenv v0.6.0 (/source)
Dec 01 19:15:27.103 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.38s
Dec 01 19:15:27.117 INFO [stderr] su: No module specific data is present
Dec 01 19:15:28.089 INFO running `"docker" "inspect" "d1874647b46f19c62280ff1e47c64307f4417e7f79d1c55170ecae0a69d9a0a5"`
Dec 01 19:15:28.463 INFO running `"docker" "rm" "-f" "d1874647b46f19c62280ff1e47c64307f4417e7f79d1c55170ecae0a69d9a0a5"`
Dec 01 19:15:28.799 INFO [stdout] d1874647b46f19c62280ff1e47c64307f4417e7f79d1c55170ecae0a69d9a0a5
Dec 01 19:15:28.801 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 19:15:29.248 INFO [stdout] eeda6b13adefcc8f242bc119238ab003d253e86829848c3ec46d1b75b305be1e
Dec 01 19:15:29.251 INFO running `"docker" "start" "-a" "eeda6b13adefcc8f242bc119238ab003d253e86829848c3ec46d1b75b305be1e"`
Dec 01 19:15:30.994 INFO [stderr] usermod: no changes
Dec 01 19:15:31.155 INFO [stderr]    Compiling dmenv v0.6.0 (/source)
Dec 01 19:15:38.571 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.54s
Dec 01 19:15:38.579 INFO [stderr] su: No module specific data is present
Dec 01 19:15:39.538 INFO running `"docker" "inspect" "eeda6b13adefcc8f242bc119238ab003d253e86829848c3ec46d1b75b305be1e"`
Dec 01 19:15:39.809 INFO running `"docker" "rm" "-f" "eeda6b13adefcc8f242bc119238ab003d253e86829848c3ec46d1b75b305be1e"`
Dec 01 19:15:40.083 INFO [stdout] eeda6b13adefcc8f242bc119238ab003d253e86829848c3ec46d1b75b305be1e
Dec 01 19:15:40.085 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-6/1.30.1:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-5/1.30.1:/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 +1.30.1 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 01 19:15:40.603 INFO [stdout] 2585c4e73ed23f2c5f7278153f8500e5267b9bc6ad4359952be8c507768e8109
Dec 01 19:15:40.615 INFO running `"docker" "start" "-a" "2585c4e73ed23f2c5f7278153f8500e5267b9bc6ad4359952be8c507768e8109"`
Dec 01 19:15:41.959 INFO [stderr] usermod: no changes
Dec 01 19:15:42.063 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 01 19:15:42.079 INFO [stderr]      Running /target/debug/deps/dmenv-dddc5a841c93a3df
Dec 01 19:15:42.079 INFO [stderr]      Running /target/debug/deps/dmenv-6e546a2fde5562f7
Dec 01 19:15:42.079 INFO [stdout] 
Dec 01 19:15:42.079 INFO [stdout] running 0 tests
Dec 01 19:15:42.079 INFO [stdout] 
Dec 01 19:15:42.079 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 19:15:42.079 INFO [stdout] 
Dec 01 19:15:42.081 INFO [stdout] 
Dec 01 19:15:42.081 INFO [stdout] running 0 tests
Dec 01 19:15:42.081 INFO [stdout] 
Dec 01 19:15:42.081 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 19:15:42.081 INFO [stdout] 
Dec 01 19:15:42.085 INFO [stderr]      Running /target/debug/deps/integration_tests-30f0f1c207d84e5c
Dec 01 19:15:42.085 INFO [stdout] 
Dec 01 19:15:42.085 INFO [stdout] running 10 tests
Dec 01 19:15:42.099 INFO [stdout] test run_without_args ... ok
Dec 01 19:15:42.382 INFO [stdout] test init_generates_setup_py ... ok
Dec 01 19:15:42.399 INFO [stdout] test init_does_not_overwrite_existing_setup_py ... ok
Dec 01 19:15:42.401 INFO [stdout] test run_without_virtualenv ... ok
Dec 01 19:15:42.417 INFO [stdout] test install_without_lock ... ok
Dec 01 19:15:42.458 INFO [stdout] test lock_complains_if_setup_py_does_not_exist ... ok
Dec 01 19:15:42.472 INFO [stdout] test show_venv_path ... ok
Dec 01 19:15:47.140 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 01 19:15:47.140 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 01 19:15:47.247 INFO [stderr] Exception:
Dec 01 19:15:47.247 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.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 01 19:15:47.247 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.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 01 19:15:47.247 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 01 19:15:47.247 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 01 19:15:47.247 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 01 19:15:47.247 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 01 19:15:47.247 INFO [stderr] 
Dec 01 19:15:47.247 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 01 19:15:47.247 INFO [stderr] 
Dec 01 19:15:47.247 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 01 19:15:47.247 INFO [stderr]     body=body, headers=headers)
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.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 01 19:15:47.247 INFO [stderr]     self._validate_conn(conn)
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.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 01 19:15:47.247 INFO [stderr]     conn.connect()
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 01 19:15:47.247 INFO [stderr]     conn = self._new_conn()
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.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 01 19:15:47.247 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 01 19:15:47.247 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7fc2dec1e6d8>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 01 19:15:47.247 INFO [stderr] 
Dec 01 19:15:47.247 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 01 19:15:47.247 INFO [stderr] 
Dec 01 19:15:47.247 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 01 19:15:47.247 INFO [stderr]     status = self.run(options, args)
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 01 19:15:47.247 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 01 19:15:47.247 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 01 19:15:47.248 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file
Dec 01 19:15:47.248 INFO [stderr]     req_to_install, finder)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed
Dec 01 19:15:47.248 INFO [stderr]     finder.find_requirement(req_to_install, self.upgrade)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 01 19:15:47.248 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 01 19:15:47.248 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 01 19:15:47.248 INFO [stderr]     page = self._get_page(location)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 01 19:15:47.248 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 01 19:15:47.248 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 01 19:15:47.248 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 01 19:15:47.248 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 01 19:15:47.248 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 01 19:15:47.248 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 01 19:15:47.248 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 01 19:15:47.248 INFO [stderr]     timeout=timeout
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 01 19:15:47.248 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 01 19:15:47.248 INFO [stderr]   File "/tmp/test-dmenv.PVS4pYaBjoOd/.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 01 19:15:47.248 INFO [stderr]     total -= 1
Dec 01 19:15:47.248 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 01 19:15:47.507 INFO [stdout] test lock_workflow ... FAILED
Dec 01 19:15:47.739 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 01 19:15:47.739 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 01 19:15:47.775 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 01 19:15:47.775 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 01 19:15:47.821 INFO [stdout] Collecting atomicwrites==1.2.1 (from -r /tmp/test-dmenv.YIPJOvAmNTx8/requirements.lock (line 2))
Dec 01 19:15:47.821 INFO [stderr] Exception:
Dec 01 19:15:47.821 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.821 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.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 01 19:15:47.821 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 01 19:15:47.821 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.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 01 19:15:47.821 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 01 19:15:47.821 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 01 19:15:47.821 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 01 19:15:47.821 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 01 19:15:47.821 INFO [stderr] 
Dec 01 19:15:47.821 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 01 19:15:47.821 INFO [stderr] 
Dec 01 19:15:47.821 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.821 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 01 19:15:47.821 INFO [stderr]     body=body, headers=headers)
Dec 01 19:15:47.821 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.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 01 19:15:47.821 INFO [stderr]     self._validate_conn(conn)
Dec 01 19:15:47.821 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.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 01 19:15:47.821 INFO [stderr]     conn.connect()
Dec 01 19:15:47.821 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 01 19:15:47.821 INFO [stderr]     conn = self._new_conn()
Dec 01 19:15:47.821 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.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 01 19:15:47.822 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 01 19:15:47.822 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7f6c5e189978>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 01 19:15:47.822 INFO [stderr] 
Dec 01 19:15:47.822 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 01 19:15:47.822 INFO [stderr] 
Dec 01 19:15:47.822 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 01 19:15:47.822 INFO [stderr]     status = self.run(options, args)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 01 19:15:47.822 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 01 19:15:47.822 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 448, in _prepare_file
Dec 01 19:15:47.822 INFO [stderr]     req_to_install, finder)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 397, in _check_skip_installed
Dec 01 19:15:47.822 INFO [stderr]     finder.find_requirement(req_to_install, self.upgrade)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 01 19:15:47.822 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 01 19:15:47.822 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 01 19:15:47.822 INFO [stderr]     page = self._get_page(location)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 01 19:15:47.822 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 01 19:15:47.822 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 01 19:15:47.822 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 01 19:15:47.822 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 01 19:15:47.822 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 01 19:15:47.822 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 01 19:15:47.822 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 01 19:15:47.822 INFO [stderr]     timeout=timeout
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 01 19:15:47.822 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 01 19:15:47.822 INFO [stderr]   File "/tmp/test-dmenv.YbKuThkwfFyn/.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 01 19:15:47.822 INFO [stderr]     total -= 1
Dec 01 19:15:47.822 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 01 19:15:47.839 INFO [stderr] Exception:
Dec 01 19:15:47.839 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.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 01 19:15:47.839 INFO [stderr]     (self.host, self.port), self.timeout, **extra_kw)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.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 01 19:15:47.839 INFO [stderr]     for res in socket.getaddrinfo(host, port, 0, socket.SOCK_STREAM):
Dec 01 19:15:47.839 INFO [stderr]   File "/usr/lib/python3.5/socket.py", line 732, in getaddrinfo
Dec 01 19:15:47.839 INFO [stderr]     for res in _socket.getaddrinfo(host, port, family, type, proto, flags):
Dec 01 19:15:47.839 INFO [stderr] socket.gaierror: [Errno -3] Temporary failure in name resolution
Dec 01 19:15:47.839 INFO [stderr] 
Dec 01 19:15:47.839 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 01 19:15:47.839 INFO [stderr] 
Dec 01 19:15:47.839 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 560, in urlopen
Dec 01 19:15:47.839 INFO [stderr]     body=body, headers=headers)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.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 01 19:15:47.839 INFO [stderr]     self._validate_conn(conn)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.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 01 19:15:47.839 INFO [stderr]     conn.connect()
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connection.py", line 217, in connect
Dec 01 19:15:47.839 INFO [stderr]     conn = self._new_conn()
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.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 01 19:15:47.839 INFO [stderr]     self, "Failed to establish a new connection: %s" % e)
Dec 01 19:15:47.839 INFO [stderr] requests.packages.urllib3.exceptions.NewConnectionError: <requests.packages.urllib3.connection.VerifiedHTTPSConnection object at 0x7f1a83fa1b70>: Failed to establish a new connection: [Errno -3] Temporary failure in name resolution
Dec 01 19:15:47.839 INFO [stderr] 
Dec 01 19:15:47.839 INFO [stderr] During handling of the above exception, another exception occurred:
Dec 01 19:15:47.839 INFO [stderr] 
Dec 01 19:15:47.839 INFO [stderr] Traceback (most recent call last):
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/basecommand.py", line 209, in main
Dec 01 19:15:47.839 INFO [stderr]     status = self.run(options, args)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/commands/install.py", line 317, in run
Dec 01 19:15:47.839 INFO [stderr]     requirement_set.prepare_files(finder)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 360, in prepare_files
Dec 01 19:15:47.839 INFO [stderr]     ignore_dependencies=self.ignore_dependencies))
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_set.py", line 512, in _prepare_file
Dec 01 19:15:47.839 INFO [stderr]     finder, self.upgrade, require_hashes)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/req/req_install.py", line 273, in populate_link
Dec 01 19:15:47.839 INFO [stderr]     self.link = finder.find_requirement(self, upgrade)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 442, in find_requirement
Dec 01 19:15:47.839 INFO [stderr]     all_candidates = self.find_all_candidates(req.name)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 400, in find_all_candidates
Dec 01 19:15:47.839 INFO [stderr]     for page in self._get_pages(url_locations, project_name):
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 545, in _get_pages
Dec 01 19:15:47.839 INFO [stderr]     page = self._get_page(location)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 648, in _get_page
Dec 01 19:15:47.839 INFO [stderr]     return HTMLPage.get_page(link, session=self.session)
Dec 01 19:15:47.839 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/index.py", line 757, in get_page
Dec 01 19:15:47.840 INFO [stderr]     "Cache-Control": "max-age=600",
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 480, in get
Dec 01 19:15:47.840 INFO [stderr]     return self.request('GET', url, **kwargs)
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/lib/python3.5/site-packages/pip/download.py", line 378, in request
Dec 01 19:15:47.840 INFO [stderr]     return super(PipSession, self).request(method, url, *args, **kwargs)
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 468, in request
Dec 01 19:15:47.840 INFO [stderr]     resp = self.send(prep, **send_kwargs)
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/sessions.py", line 576, in send
Dec 01 19:15:47.840 INFO [stderr]     r = adapter.send(request, **kwargs)
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/CacheControl-0.11.5-py2.py3-none-any.whl/cachecontrol/adapter.py", line 46, in send
Dec 01 19:15:47.840 INFO [stderr]     resp = super(CacheControlAdapter, self).send(request, **kw)
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/requests-2.9.1-py2.py3-none-any.whl/requests/adapters.py", line 376, in send
Dec 01 19:15:47.840 INFO [stderr]     timeout=timeout
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/share/python-wheels/urllib3-1.13.1-py2.py3-none-any.whl/urllib3/connectionpool.py", line 610, in urlopen
Dec 01 19:15:47.840 INFO [stderr]     _stacktrace=sys.exc_info()[2])
Dec 01 19:15:47.840 INFO [stderr]   File "/tmp/test-dmenv.YIPJOvAmNTx8/.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 01 19:15:47.840 INFO [stderr]     total -= 1
Dec 01 19:15:47.840 INFO [stderr] TypeError: unsupported operand type(s) for -=: 'Retry' and 'int'
Dec 01 19:15:47.953 INFO [stdout] test install_workflow_all_in_one ... FAILED
Dec 01 19:15:47.974 INFO [stdout] test install_workflow_step_by_step ... FAILED
Dec 01 19:15:47.974 INFO [stderr] error: test failed, to rerun pass '--test integration_tests'
Dec 01 19:15:47.974 INFO [stdout] 
Dec 01 19:15:47.974 INFO [stdout] failures:
Dec 01 19:15:47.974 INFO [stdout] 
Dec 01 19:15:47.974 INFO [stdout] ---- lock_workflow stdout ----
Dec 01 19:15:47.974 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2
Dec 01 19:15:47.974 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2
Dec 01 19:15:47.974 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.PVS4pYaBjoOd/.venv/3.5.2/bin/python[0m -m pip install pip --upgrade
Dec 01 19:15:47.974 INFO [stdout] thread 'lock_workflow' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 01 19:15:47.974 INFO [stdout] stack backtrace:
Dec 01 19:15:47.974 INFO [stdout]    0:     0x5619f6b409ae - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 01 19:15:47.974 INFO [stdout]    1:     0x5619f6b2eae6 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 01 19:15:47.974 INFO [stdout]    2:     0x5619f6b2cf0d - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/panicking.rs:211
Dec 01 19:15:47.974 INFO [stdout]    3:     0x5619f6b2cc1b - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/panicking.rs:221
Dec 01 19:15:47.974 INFO [stdout]    4:     0x5619f6b2d5bc - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/panicking.rs:477
Dec 01 19:15:47.974 INFO [stdout]    5:     0x5619f6b2d179 - std::panicking::continue_panic_fmt::h2d2d2bfb065a70b2
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/panicking.rs:391
Dec 01 19:15:47.974 INFO [stdout]    6:     0x5619f6b2d075 - rust_begin_unwind
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/panicking.rs:326
Dec 01 19:15:47.974 INFO [stdout]    7:     0x5619f6b864eb - core::panicking::panic_fmt::h2155aa66b67fe83c
Dec 01 19:15:47.974 INFO [stdout]                                at libcore/panicking.rs:77
Dec 01 19:15:47.974 INFO [stdout]    8:     0x5619f68bf2a3 - core::result::unwrap_failed::h9ff31d763d4bed33
Dec 01 19:15:47.974 INFO [stdout]                                at libcore/macros.rs:26
Dec 01 19:15:47.974 INFO [stdout]    9:     0x5619f68beb9d - <core::result::Result<T, E>>::expect::h1c4f17a78b06512b
Dec 01 19:15:47.974 INFO [stdout]                                at libcore/result.rs:835
Dec 01 19:15:47.974 INFO [stdout]   10:     0x5619f68cb926 - integration_tests::helpers::TestApp::assert_run_ok::h72cd5ee3c260b6bf
Dec 01 19:15:47.974 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 01 19:15:47.974 INFO [stdout]   11:     0x5619f68c754a - integration_tests::lock_workflow::h280a14571811a2ea
Dec 01 19:15:47.974 INFO [stdout]                                at tests/integration_tests.rs:53
Dec 01 19:15:47.974 INFO [stdout]   12:     0x5619f68c7439 - integration_tests::lock_workflow::{{closure}}::hb6e2239ef4d1ac85
Dec 01 19:15:47.974 INFO [stdout]                                at tests/integration_tests.rs:50
Dec 01 19:15:47.974 INFO [stdout]   13:     0x5619f68bf86d - core::ops::function::FnOnce::call_once::hd453e406955fda5d
Dec 01 19:15:47.974 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 19:15:47.974 INFO [stdout]   14:     0x5619f68cf79e - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 01 19:15:47.974 INFO [stdout]                                at libtest/lib.rs:1461
Dec 01 19:15:47.974 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 19:15:47.974 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 19:15:47.974 INFO [stdout]   15:     0x5619f6b53c39 - __rust_maybe_catch_panic
Dec 01 19:15:47.974 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 19:15:47.974 INFO [stdout]   16:     0x5619f68db0fd - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 01 19:15:47.974 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 19:15:47.975 INFO [stdout]                                at libtest/lib.rs:1416
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 01 19:15:47.975 INFO [stdout]   17:     0x5619f68f3734 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panic.rs:313
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:310
Dec 01 19:15:47.975 INFO [stdout]   18:     0x5619f6b53c39 - __rust_maybe_catch_panic
Dec 01 19:15:47.975 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 19:15:47.975 INFO [stdout]   19:     0x5619f68e4006 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 01 19:15:47.975 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 19:15:47.975 INFO [stdout]   20:     0x5619f6b42bda - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 01 19:15:47.975 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 01 19:15:47.975 INFO [stdout]   21:     0x5619f6b2dd25 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 01 19:15:47.975 INFO [stdout]   22:     0x7f0a61f196b9 - start_thread
Dec 01 19:15:47.975 INFO [stdout]   23:     0x7f0a61a3941c - clone
Dec 01 19:15:47.975 INFO [stdout]   24:                0x0 - <unknown>
Dec 01 19:15:47.975 INFO [stdout] 
Dec 01 19:15:47.975 INFO [stdout] ---- install_workflow_all_in_one stdout ----
Dec 01 19:15:47.975 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2
Dec 01 19:15:47.975 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2
Dec 01 19:15:47.975 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.YbKuThkwfFyn/.venv/3.5.2/bin/python[0m -m pip install pip --upgrade
Dec 01 19:15:47.975 INFO [stdout] thread 'install_workflow_all_in_one' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 01 19:15:47.975 INFO [stdout] stack backtrace:
Dec 01 19:15:47.975 INFO [stdout]    0:     0x5619f6b409ae - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 01 19:15:47.975 INFO [stdout]    1:     0x5619f6b2eae6 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 01 19:15:47.975 INFO [stdout]    2:     0x5619f6b2cf0d - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:211
Dec 01 19:15:47.975 INFO [stdout]    3:     0x5619f6b2cc1b - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:221
Dec 01 19:15:47.975 INFO [stdout]    4:     0x5619f6b2d5bc - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:477
Dec 01 19:15:47.975 INFO [stdout]    5:     0x5619f6b2d179 - std::panicking::continue_panic_fmt::h2d2d2bfb065a70b2
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:391
Dec 01 19:15:47.975 INFO [stdout]    6:     0x5619f6b2d075 - rust_begin_unwind
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:326
Dec 01 19:15:47.975 INFO [stdout]    7:     0x5619f6b864eb - core::panicking::panic_fmt::h2155aa66b67fe83c
Dec 01 19:15:47.975 INFO [stdout]                                at libcore/panicking.rs:77
Dec 01 19:15:47.975 INFO [stdout]    8:     0x5619f68bf2a3 - core::result::unwrap_failed::h9ff31d763d4bed33
Dec 01 19:15:47.975 INFO [stdout]                                at libcore/macros.rs:26
Dec 01 19:15:47.975 INFO [stdout]    9:     0x5619f68beb9d - <core::result::Result<T, E>>::expect::h1c4f17a78b06512b
Dec 01 19:15:47.975 INFO [stdout]                                at libcore/result.rs:835
Dec 01 19:15:47.975 INFO [stdout]   10:     0x5619f68cb926 - integration_tests::helpers::TestApp::assert_run_ok::h72cd5ee3c260b6bf
Dec 01 19:15:47.975 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 01 19:15:47.975 INFO [stdout]   11:     0x5619f68c7915 - integration_tests::install_workflow_all_in_one::hd763293331df0250
Dec 01 19:15:47.975 INFO [stdout]                                at tests/integration_tests.rs:68
Dec 01 19:15:47.975 INFO [stdout]   12:     0x5619f68c77d9 - integration_tests::install_workflow_all_in_one::{{closure}}::h196582559e93cc1a
Dec 01 19:15:47.975 INFO [stdout]                                at tests/integration_tests.rs:63
Dec 01 19:15:47.975 INFO [stdout]   13:     0x5619f68bf6ad - core::ops::function::FnOnce::call_once::h0c50b14307ecd6e7
Dec 01 19:15:47.975 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 19:15:47.975 INFO [stdout]   14:     0x5619f68cf79e - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 01 19:15:47.975 INFO [stdout]                                at libtest/lib.rs:1461
Dec 01 19:15:47.975 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 19:15:47.975 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 19:15:47.975 INFO [stdout]   15:     0x5619f6b53c39 - __rust_maybe_catch_panic
Dec 01 19:15:47.975 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 19:15:47.975 INFO [stdout]   16:     0x5619f68db0fd - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 19:15:47.975 INFO [stdout]                                at libtest/lib.rs:1416
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 01 19:15:47.975 INFO [stdout]   17:     0x5619f68f3734 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panic.rs:313
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:310
Dec 01 19:15:47.975 INFO [stdout]   18:     0x5619f6b53c39 - __rust_maybe_catch_panic
Dec 01 19:15:47.975 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 19:15:47.975 INFO [stdout]   19:     0x5619f68e4006 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 01 19:15:47.975 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 19:15:47.975 INFO [stdout]   20:     0x5619f6b42bda - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 01 19:15:47.975 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 01 19:15:47.975 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 01 19:15:47.975 INFO [stdout]   21:     0x5619f6b2dd25 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 01 19:15:47.976 INFO [stdout]   22:     0x7f0a61f196b9 - start_thread
Dec 01 19:15:47.976 INFO [stdout]   23:     0x7f0a61a3941c - clone
Dec 01 19:15:47.976 INFO [stdout]   24:                0x0 - <unknown>
Dec 01 19:15:47.976 INFO [stdout] 
Dec 01 19:15:47.976 INFO [stdout] ---- install_workflow_step_by_step stdout ----
Dec 01 19:15:47.976 INFO [stdout] [34m::[0m Creating virtualenv in: /tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2
Dec 01 19:15:47.976 INFO [stdout] [34m->[0m running [1m/usr/bin/python3[0m -m venv /tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2
Dec 01 19:15:47.976 INFO [stdout] [34m->[0m running [1m/tmp/test-dmenv.YIPJOvAmNTx8/.venv/3.5.2/bin/pip[0m install --requirement /tmp/test-dmenv.YIPJOvAmNTx8/requirements.lock
Dec 01 19:15:47.976 INFO [stdout] thread 'install_workflow_step_by_step' panicked at ': Error { description: "command failed" }', libcore/result.rs:1009:5
Dec 01 19:15:47.976 INFO [stdout] stack backtrace:
Dec 01 19:15:47.976 INFO [stdout]    0:     0x5619f6b409ae - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h15749a98198ba25f
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 01 19:15:47.976 INFO [stdout]    1:     0x5619f6b2eae6 - std::sys_common::backtrace::print::h0ba65c811294dbf0
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Dec 01 19:15:47.976 INFO [stdout]    2:     0x5619f6b2cf0d - std::panicking::default_hook::{{closure}}::h42bb31757f0fee64
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:211
Dec 01 19:15:47.976 INFO [stdout]    3:     0x5619f6b2cc1b - std::panicking::default_hook::hebc8848d7aa7dbd9
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:221
Dec 01 19:15:47.976 INFO [stdout]    4:     0x5619f6b2d5bc - std::panicking::rust_panic_with_hook::h86e619a638415243
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:477
Dec 01 19:15:47.976 INFO [stdout]    5:     0x5619f6b2d179 - std::panicking::continue_panic_fmt::h2d2d2bfb065a70b2
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:391
Dec 01 19:15:47.976 INFO [stdout]    6:     0x5619f6b2d075 - rust_begin_unwind
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:326
Dec 01 19:15:47.976 INFO [stdout]    7:     0x5619f6b864eb - core::panicking::panic_fmt::h2155aa66b67fe83c
Dec 01 19:15:47.976 INFO [stdout]                                at libcore/panicking.rs:77
Dec 01 19:15:47.976 INFO [stdout]    8:     0x5619f68bf2a3 - core::result::unwrap_failed::h9ff31d763d4bed33
Dec 01 19:15:47.976 INFO [stdout]                                at libcore/macros.rs:26
Dec 01 19:15:47.976 INFO [stdout]    9:     0x5619f68beb9d - <core::result::Result<T, E>>::expect::h1c4f17a78b06512b
Dec 01 19:15:47.976 INFO [stdout]                                at libcore/result.rs:835
Dec 01 19:15:47.976 INFO [stdout]   10:     0x5619f68cb926 - integration_tests::helpers::TestApp::assert_run_ok::h72cd5ee3c260b6bf
Dec 01 19:15:47.976 INFO [stdout]                                at tests/helpers/mod.rs:57
Dec 01 19:15:47.976 INFO [stdout]   11:     0x5619f68c7acb - integration_tests::install_workflow_step_by_step::h2a0fbce4b48a3947
Dec 01 19:15:47.976 INFO [stdout]                                at tests/integration_tests.rs:77
Dec 01 19:15:47.976 INFO [stdout]   12:     0x5619f68c7969 - integration_tests::install_workflow_step_by_step::{{closure}}::h45d0b4e66a65aa71
Dec 01 19:15:47.976 INFO [stdout]                                at tests/integration_tests.rs:72
Dec 01 19:15:47.976 INFO [stdout]   13:     0x5619f68bf7ed - core::ops::function::FnOnce::call_once::hc88eee625a419b19
Dec 01 19:15:47.976 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 19:15:47.976 INFO [stdout]   14:     0x5619f68cf79e - <F as alloc::boxed::FnBox<A>>::call_box::hbea525814fa423cc
Dec 01 19:15:47.976 INFO [stdout]                                at libtest/lib.rs:1461
Dec 01 19:15:47.976 INFO [stdout]                                at libcore/ops/function.rs:238
Dec 01 19:15:47.976 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 19:15:47.976 INFO [stdout]   15:     0x5619f6b53c39 - __rust_maybe_catch_panic
Dec 01 19:15:47.976 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 19:15:47.976 INFO [stdout]   16:     0x5619f68db0fd - std::sys_common::backtrace::__rust_begin_short_backtrace::h0ff507d7422944d3
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 19:15:47.976 INFO [stdout]                                at libtest/lib.rs:1416
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/sys_common/backtrace.rs:136
Dec 01 19:15:47.976 INFO [stdout]   17:     0x5619f68f3734 - std::panicking::try::do_call::hba0bdcc3670471cc
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/thread/mod.rs:409
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panic.rs:313
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:310
Dec 01 19:15:47.976 INFO [stdout]   18:     0x5619f6b53c39 - __rust_maybe_catch_panic
Dec 01 19:15:47.976 INFO [stdout]                                at libpanic_unwind/lib.rs:103
Dec 01 19:15:47.976 INFO [stdout]   19:     0x5619f68e4006 - <F as alloc::boxed::FnBox<A>>::call_box::hdac52587b00591af
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panicking.rs:289
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/panic.rs:392
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/thread/mod.rs:408
Dec 01 19:15:47.976 INFO [stdout]                                at liballoc/boxed.rs:646
Dec 01 19:15:47.976 INFO [stdout]   20:     0x5619f6b42bda - std::sys_common::thread::start_thread::h75d887c7d2cc4479
Dec 01 19:15:47.976 INFO [stdout]                                at liballoc/boxed.rs:656
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Dec 01 19:15:47.976 INFO [stdout]   21:     0x5619f6b2dd25 - std::sys::unix::thread::Thread::new::thread_start::h8414caee632bf9ed
Dec 01 19:15:47.976 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Dec 01 19:15:47.976 INFO [stdout]   22:     0x7f0a61f196b9 - start_thread
Dec 01 19:15:47.976 INFO [stdout]   23:     0x7f0a61a3941c - clone
Dec 01 19:15:47.976 INFO [stdout]   24:                0x0 - <unknown>
Dec 01 19:15:47.976 INFO [stdout] 
Dec 01 19:15:47.976 INFO [stdout] 
Dec 01 19:15:47.976 INFO [stdout] failures:
Dec 01 19:15:47.976 INFO [stdout]     install_workflow_all_in_one
Dec 01 19:15:47.976 INFO [stdout]     install_workflow_step_by_step
Dec 01 19:15:47.976 INFO [stdout]     lock_workflow
Dec 01 19:15:47.976 INFO [stdout] 
Dec 01 19:15:47.976 INFO [stdout] test result: FAILED. 7 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out
Dec 01 19:15:47.976 INFO [stdout] 
Dec 01 19:15:47.977 INFO [stderr] su: No module specific data is present
Dec 01 19:15:48.441 INFO running `"docker" "inspect" "2585c4e73ed23f2c5f7278153f8500e5267b9bc6ad4359952be8c507768e8109"`
Dec 01 19:15:48.729 INFO running `"docker" "rm" "-f" "2585c4e73ed23f2c5f7278153f8500e5267b9bc6ad4359952be8c507768e8109"`
Dec 01 19:15:48.930 INFO [stdout] 2585c4e73ed23f2c5f7278153f8500e5267b9bc6ad4359952be8c507768e8109
