Nov 18 13:42:53.331 INFO testing fw-1.11.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 13:42:53.331 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 13:42:53.511 INFO [stdout] 80ed031d60d4839f31bb98caa09c110210675b6d62a2d31f31eebd67c4ad2117
Nov 18 13:42:53.512 INFO running `"docker" "start" "-a" "80ed031d60d4839f31bb98caa09c110210675b6d62a2d31f31eebd67c4ad2117"`
Nov 18 13:42:54.169 INFO [stderr] usermod: no changes
Nov 18 13:42:54.715 INFO [stderr]    Compiling slog-term v2.4.0
Nov 18 13:42:54.718 INFO [stderr]    Compiling crossbeam-channel v0.2.6
Nov 18 13:42:54.751 INFO [stderr]    Compiling github-gql-rs v0.0.1
Nov 18 13:42:57.983 INFO [stderr]    Compiling crossbeam v0.4.1
Nov 18 13:43:03.259 INFO [stderr]    Compiling fw v1.11.0 (/source)
Nov 18 13:43:26.671 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 32.48s
Nov 18 13:43:26.691 INFO [stderr] su: No module specific data is present
Nov 18 13:43:27.397 INFO running `"docker" "inspect" "80ed031d60d4839f31bb98caa09c110210675b6d62a2d31f31eebd67c4ad2117"`
Nov 18 13:43:27.760 INFO running `"docker" "rm" "-f" "80ed031d60d4839f31bb98caa09c110210675b6d62a2d31f31eebd67c4ad2117"`
Nov 18 13:43:28.058 INFO [stdout] 80ed031d60d4839f31bb98caa09c110210675b6d62a2d31f31eebd67c4ad2117
Nov 18 13:43:28.071 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 13:43:28.443 INFO [stdout] e16f21069bc7fa77958a8dc68ac8b4718331aab752580b994ee9512a9532d313
Nov 18 13:43:28.451 INFO running `"docker" "start" "-a" "e16f21069bc7fa77958a8dc68ac8b4718331aab752580b994ee9512a9532d313"`
Nov 18 13:43:29.303 INFO [stderr] usermod: no changes
Nov 18 13:43:29.779 INFO [stderr]    Compiling fw v1.11.0 (/source)
Nov 18 13:43:45.383 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.04s
Nov 18 13:43:45.402 INFO [stderr] su: No module specific data is present
Nov 18 13:43:45.823 INFO running `"docker" "inspect" "e16f21069bc7fa77958a8dc68ac8b4718331aab752580b994ee9512a9532d313"`
Nov 18 13:43:45.897 INFO running `"docker" "rm" "-f" "e16f21069bc7fa77958a8dc68ac8b4718331aab752580b994ee9512a9532d313"`
Nov 18 13:43:46.019 INFO [stdout] e16f21069bc7fa77958a8dc68ac8b4718331aab752580b994ee9512a9532d313
Nov 18 13:43:46.021 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-5/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 18 13:43:46.173 INFO [stdout] abaac8c2ab350fb52906bcd93a9978c562d383722c3271b15bbb5e2d6353f93b
Nov 18 13:43:46.175 INFO running `"docker" "start" "-a" "abaac8c2ab350fb52906bcd93a9978c562d383722c3271b15bbb5e2d6353f93b"`
Nov 18 13:43:46.546 INFO [stderr] usermod: no changes
Nov 18 13:43:46.824 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.26s
Nov 18 13:43:46.847 INFO [stderr]      Running /target/debug/deps/fw-4c8bef69a759c57b
Nov 18 13:43:47.396 INFO [stdout] 
Nov 18 13:43:47.396 INFO [stdout] running 19 tests
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 TRCE before sort, tags: []
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 TRCE after sort, tags: []
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 TRCE Resolving, tags: Some({"tag1", "tag2"})
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.396 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.396 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.396 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone1"), after_workon: Some("workon1"), priority: None, workspace: None }, tag_name: tag1
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.396 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.396 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.396 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone2"), after_workon: Some("workon2"), priority: None, workspace: None }, tag_name: tag2
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 TRCE before sort, tags: [("clone1", 50), ("clone2", 50)]
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.393 TRCE after sort, tags: [("clone1", 50), ("clone2", 50)]
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE Resolving, tags: Some({"tag1"})
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.396 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.396 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.396 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone1"), after_workon: Some("workon1"), priority: None, workspace: None }, tag_name: tag1
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE Resolving, tags: Some({"tag3", "tag4"})
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE before sort, tags: [("clone1", 50)]
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE after sort, tags: [("clone1", 50)]
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE before sort, tags: [("clone3", 100), ("clone4", 0)]
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE after sort, tags: [("clone4", 0), ("clone3", 100)]
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_after_clone_from_tags_missing_all_tags_graceful ... ok
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_after_clone_from_tags ... ok
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_after_clone_override_from_project ... ok
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_after_clone_from_tags_prioritized ... ok
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_do_not_expand_path_without_tilde ... ok
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_repo_name_from_ssh_pragma ... ok
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist", "tag1"})
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_repo_name_from_ssh_pragma_with_multiple_git_endings ... ok
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.396 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.396 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.396 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone1"), after_workon: Some("workon1"), priority: None, workspace: None }, tag_name: tag1
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE before sort, tags: [("clone1", 50)]
Nov 18 13:43:47.396 INFO [stdout] Nov 18 13:43:47.394 TRCE after sort, tags: [("clone1", 50)]
Nov 18 13:43:47.396 INFO [stdout] test config::tests::test_after_clone_from_tags_missing_one_tag_graceful ... ok
Nov 18 13:43:47.404 INFO [stdout] Nov 18 13:43:47.403 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Nov 18 13:43:47.404 INFO [stdout] Nov 18 13:43:47.403 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist", "tag1"})
Nov 18 13:43:47.404 INFO [stdout] Nov 18 13:43:47.403 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.404 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.404 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.404 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone1"), after_workon: Some("workon1"), priority: None, workspace: None }, tag_name: tag1
Nov 18 13:43:47.404 INFO [stdout] Nov 18 13:43:47.403 TRCE before sort, tags: [("workon1", 50)]
Nov 18 13:43:47.404 INFO [stdout] Nov 18 13:43:47.403 TRCE after sort, tags: [("workon1", 50)]
Nov 18 13:43:47.408 INFO [stdout] test config::tests::test_repo_name_from_url ... ok
Nov 18 13:43:47.409 INFO [stdout] test config::tests::test_workon_from_tags_missing_one_tag_graceful ... ok
Nov 18 13:43:47.409 INFO [stdout] test config::tests::test_do_expand_path ... ok
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE Resolving, tags: Some({"tag1"})
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE Resolving, tags: Some({"tag3", "tag4"})
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.409 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.409 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.409 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone1"), after_workon: Some("workon1"), priority: None, workspace: None }, tag_name: tag1
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE before sort, tags: [("workon3", 100), ("workon4", 0)]
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE before sort, tags: [("workon1", 50)]
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE after sort, tags: [("workon4", 0), ("workon3", 100)]
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE before sort, tags: []
Nov 18 13:43:47.409 INFO [stdout] test config::tests::test_workon_from_tags_prioritized ... ok
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE after sort, tags: []
Nov 18 13:43:47.409 INFO [stdout] test config::tests::test_workon_from_tags_missing_all_tags_graceful ... ok
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.408 TRCE Resolving, tags: Some({"tag1", "tag2"})
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.409 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.409 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.409 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.409 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone1"), after_workon: Some("workon1"), priority: None, workspace: None }, tag_name: tag1
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.409 DEBG No tag priority set, will use default (50).
Nov 18 13:43:47.409 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 18 13:43:47.409 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 18 13:43:47.409 INFO [stdout] conscious choice and set the value., tag_def: Tag { after_clone: Some("clone2"), after_workon: Some("workon2"), priority: None, workspace: None }, tag_name: tag2
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.409 TRCE before sort, tags: [("workon1", 50), ("workon2", 50)]
Nov 18 13:43:47.409 INFO [stdout] Nov 18 13:43:47.409 TRCE after sort, tags: [("workon1", 50), ("workon2", 50)]
Nov 18 13:43:47.409 INFO [stdout] test config::tests::test_workon_from_tags ... ok
Nov 18 13:43:47.409 INFO [stdout] test export::tests::test_workon_override_from_project ... ok
Nov 18 13:43:47.412 INFO [stdout] Nov 18 13:43:47.410 DEBG Writing projectile entry, entry: /home/mriehl/test
Nov 18 13:43:47.412 INFO [stdout] Nov 18 13:43:47.411 DEBG Writing projectile entry, entry: /home/mriehl/go/src/github.com/test2
Nov 18 13:43:47.412 INFO [stdout] Nov 18 13:43:47.411 TRCE after sort, tags: [("workon1", 50)]
Nov 18 13:43:47.417 INFO [stdout] test projectile::tests::test_persists_projectile_config ... ok
Nov 18 13:43:47.417 INFO [stdout] test config::tests::test_workon_override_from_project ... ok
Nov 18 13:43:47.417 INFO [stdout] test projectile::tests::test_replace_path_with_tilde ... ok
Nov 18 13:43:47.433 INFO [stdout] test sync::tests::test_username_from_git_url ... ok
Nov 18 13:43:47.433 INFO [stdout] 
Nov 18 13:43:47.433 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 13:43:47.433 INFO [stdout] 
Nov 18 13:43:47.452 INFO [stderr] su: No module specific data is present
Nov 18 13:43:47.745 INFO running `"docker" "inspect" "abaac8c2ab350fb52906bcd93a9978c562d383722c3271b15bbb5e2d6353f93b"`
Nov 18 13:43:47.821 INFO running `"docker" "rm" "-f" "abaac8c2ab350fb52906bcd93a9978c562d383722c3271b15bbb5e2d6353f93b"`
Nov 18 13:43:47.920 INFO [stdout] abaac8c2ab350fb52906bcd93a9978c562d383722c3271b15bbb5e2d6353f93b