Nov 17 07:32:46.220 INFO testing brocode/fw against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 07:32:46.221 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 17 07:32:46.781 INFO [stdout] c1e309ea9dc67d64d611db40115dc5426e8be27f0e2f8f3cf8e7a6a63ca94ec1
Nov 17 07:32:46.782 INFO running `"docker" "start" "-a" "c1e309ea9dc67d64d611db40115dc5426e8be27f0e2f8f3cf8e7a6a63ca94ec1"`
Nov 17 07:32:48.554 INFO [stderr] usermod: no changes
Nov 17 07:32:49.013 INFO [stderr]    Compiling slog v2.4.1
Nov 17 07:32:49.014 INFO [stderr]    Compiling crossbeam-utils v0.6.0
Nov 17 07:32:49.043 INFO [stderr]    Compiling isatty v0.1.9
Nov 17 07:32:49.044 INFO [stderr]    Compiling term v0.5.1
Nov 17 07:32:49.044 INFO [stderr]    Compiling clicolors-control v0.2.0
Nov 17 07:32:49.044 INFO [stderr]    Compiling libz-sys v1.0.25
Nov 17 07:32:49.045 INFO [stderr]    Compiling curl-sys v0.4.15
Nov 17 07:32:49.045 INFO [stderr]    Compiling base64 v0.6.0
Nov 17 07:32:51.564 INFO [stderr]    Compiling want v0.0.4
Nov 17 07:32:54.959 INFO [stderr]    Compiling crossbeam-epoch v0.6.1
Nov 17 07:32:54.962 INFO [stderr]    Compiling num-complex v0.1.43
Nov 17 07:32:55.004 INFO [stderr]    Compiling num-bigint v0.1.44
Nov 17 07:32:55.017 INFO [stderr]    Compiling num-iter v0.1.37
Nov 17 07:32:56.978 INFO [stderr]    Compiling chrono v0.4.6
Nov 17 07:32:57.115 INFO [stderr]    Compiling rayon v0.8.2
Nov 17 07:33:04.235 INFO [stderr]    Compiling console v0.6.2
Nov 17 07:33:04.659 INFO [stderr]    Compiling libssh2-sys v0.2.11
Nov 17 07:33:05.675 INFO [stderr]    Compiling crossbeam-deque v0.6.2
Nov 17 07:33:06.889 INFO [stderr]    Compiling crossbeam-channel v0.3.1
Nov 17 07:33:12.391 INFO [stderr]    Compiling slog-async v2.3.0
Nov 17 07:33:18.504 INFO [stderr]    Compiling tokio-threadpool v0.1.8
Nov 17 07:33:18.804 INFO [stderr]    Compiling num-rational v0.1.42
Nov 17 07:33:20.916 INFO [stderr]    Compiling ring v0.12.1
Nov 17 07:33:23.461 INFO [stderr]    Compiling slog-term v2.4.0
Nov 17 07:33:23.467 INFO [stderr]    Compiling indicatif v0.9.0
Nov 17 07:33:23.732 INFO [stderr]    Compiling crossbeam v0.5.0
Nov 17 07:33:31.113 INFO [stderr]    Compiling num v0.1.42
Nov 17 07:33:31.601 INFO [stderr]    Compiling libgit2-sys v0.7.10
Nov 17 07:33:34.131 INFO [stderr]    Compiling spectral v0.6.0
Nov 17 07:33:44.411 INFO [stderr]    Compiling tokio-fs v0.1.4
Nov 17 07:33:51.248 INFO [stderr]    Compiling tokio v0.1.11
Nov 17 07:34:00.735 INFO [stderr]    Compiling tokio-core v0.1.17
Nov 17 07:34:00.820 INFO [stderr]    Compiling sct v0.2.0
Nov 17 07:34:00.823 INFO [stderr]    Compiling webpki v0.17.0
Nov 17 07:34:02.171 INFO [stderr]    Compiling ct-logs v0.2.0
Nov 17 07:34:04.879 INFO [stderr]    Compiling rustls v0.11.0
Nov 17 07:34:04.880 INFO [stderr]    Compiling webpki-roots v0.13.0
Nov 17 07:34:12.906 INFO [stderr]    Compiling tokio-proto v0.1.1
Nov 17 07:34:24.119 INFO [stderr]    Compiling hyper v0.11.27
Nov 17 07:34:28.001 INFO [stderr]    Compiling tokio-rustls v0.4.0
Nov 17 07:34:45.003 INFO [stderr]    Compiling git2 v0.7.5
Nov 17 07:34:45.651 INFO [stderr]    Compiling hyper-rustls v0.11.0
Nov 17 07:34:49.206 INFO [stderr]    Compiling github-gql-rs v0.0.1
Nov 17 07:34:54.443 INFO [stderr]    Compiling fw v1.14.1-alpha.0 (/source)
Nov 17 07:35:17.175 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2m 28s
Nov 17 07:35:17.208 INFO [stderr] su: No module specific data is present
Nov 17 07:35:17.770 INFO running `"docker" "inspect" "c1e309ea9dc67d64d611db40115dc5426e8be27f0e2f8f3cf8e7a6a63ca94ec1"`
Nov 17 07:35:18.063 INFO running `"docker" "rm" "-f" "c1e309ea9dc67d64d611db40115dc5426e8be27f0e2f8f3cf8e7a6a63ca94ec1"`
Nov 17 07:35:18.397 INFO [stdout] c1e309ea9dc67d64d611db40115dc5426e8be27f0e2f8f3cf8e7a6a63ca94ec1
Nov 17 07:35:18.406 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 17 07:35:18.823 INFO [stdout] cb036c8dcb198d046fb038da9f1006a53851d77d6f1f12c823f3257893cc5441
Nov 17 07:35:18.831 INFO running `"docker" "start" "-a" "cb036c8dcb198d046fb038da9f1006a53851d77d6f1f12c823f3257893cc5441"`
Nov 17 07:35:19.862 INFO [stderr] usermod: no changes
Nov 17 07:35:20.267 INFO [stderr]    Compiling fw v1.14.1-alpha.0 (/source)
Nov 17 07:35:35.239 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.35s
Nov 17 07:35:35.269 INFO [stderr] su: No module specific data is present
Nov 17 07:35:36.775 INFO running `"docker" "inspect" "cb036c8dcb198d046fb038da9f1006a53851d77d6f1f12c823f3257893cc5441"`
Nov 17 07:35:37.122 INFO running `"docker" "rm" "-f" "cb036c8dcb198d046fb038da9f1006a53851d77d6f1f12c823f3257893cc5441"`
Nov 17 07:35:37.519 INFO [stdout] cb036c8dcb198d046fb038da9f1006a53851d77d6f1f12c823f3257893cc5441
Nov 17 07:35:37.523 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 17 07:35:38.196 INFO [stdout] 788269a7ea3697cad1637524c29f092b303257b5b609a447d230427ce91c3deb
Nov 17 07:35:38.199 INFO running `"docker" "start" "-a" "788269a7ea3697cad1637524c29f092b303257b5b609a447d230427ce91c3deb"`
Nov 17 07:35:39.863 INFO [stderr] usermod: no changes
Nov 17 07:35:40.261 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.37s
Nov 17 07:35:40.274 INFO [stderr]      Running /target/debug/deps/fw-2bac944665b21002
Nov 17 07:35:40.327 INFO [stdout] 
Nov 17 07:35:40.327 INFO [stdout] running 19 tests
Nov 17 07:35:40.359 INFO [stdout] Nov 17 07:35:40.359 TRCE Resolving, tags: Some({"tag1"})
Nov 17 07:35:40.359 INFO [stdout] Nov 17 07:35:40.359 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.359 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.359 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.359 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 17 07:35:40.360 INFO [stdout] Nov 17 07:35:40.359 TRCE before sort, tags: [("clone1", 50)]
Nov 17 07:35:40.360 INFO [stdout] Nov 17 07:35:40.359 TRCE after sort, tags: [("clone1", 50)]
Nov 17 07:35:40.360 INFO [stdout] test config::tests::test_after_clone_override_from_project ... ok
Nov 17 07:35:40.360 INFO [stdout] test config::tests::test_do_expand_path ... ok
Nov 17 07:35:40.360 INFO [stdout] Nov 17 07:35:40.360 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Nov 17 07:35:40.360 INFO [stdout] Nov 17 07:35:40.360 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 17 07:35:40.360 INFO [stdout] Nov 17 07:35:40.360 TRCE Resolving, tags: Some({"tag3", "tag4"})
Nov 17 07:35:40.361 INFO [stdout] Nov 17 07:35:40.360 TRCE before sort, tags: [("clone3", 100), ("clone4", 0)]
Nov 17 07:35:40.361 INFO [stdout] Nov 17 07:35:40.361 TRCE after sort, tags: [("clone4", 0), ("clone3", 100)]
Nov 17 07:35:40.361 INFO [stdout] Nov 17 07:35:40.360 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.361 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.361 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.361 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 17 07:35:40.361 INFO [stdout] Nov 17 07:35:40.361 TRCE before sort, tags: [("clone1", 50)]
Nov 17 07:35:40.361 INFO [stdout] test config::tests::test_after_clone_from_tags_prioritized ... ok
Nov 17 07:35:40.361 INFO [stdout] Nov 17 07:35:40.361 TRCE after sort, tags: [("clone1", 50)]
Nov 17 07:35:40.362 INFO [stdout] Nov 17 07:35:40.361 TRCE Resolving, tags: Some({"tag1", "tag2"})
Nov 17 07:35:40.363 INFO [stdout] test config::tests::test_after_clone_from_tags_missing_one_tag_graceful ... ok
Nov 17 07:35:40.363 INFO [stdout] Nov 17 07:35:40.361 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.363 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.363 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.364 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 17 07:35:40.364 INFO [stdout] Nov 17 07:35:40.361 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.364 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.364 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.365 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 17 07:35:40.365 INFO [stdout] Nov 17 07:35:40.362 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Nov 17 07:35:40.365 INFO [stdout] Nov 17 07:35:40.362 TRCE before sort, tags: [("workon1", 50), ("workon2", 50)]
Nov 17 07:35:40.366 INFO [stdout] Nov 17 07:35:40.362 TRCE after sort, tags: [("workon1", 50), ("workon2", 50)]
Nov 17 07:35:40.366 INFO [stdout] Nov 17 07:35:40.362 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Nov 17 07:35:40.366 INFO [stdout] Nov 17 07:35:40.362 TRCE before sort, tags: []
Nov 17 07:35:40.366 INFO [stdout] test config::tests::test_workon_from_tags ... ok
Nov 17 07:35:40.367 INFO [stdout] Nov 17 07:35:40.362 TRCE after sort, tags: []
Nov 17 07:35:40.367 INFO [stdout] test config::tests::test_after_clone_from_tags_missing_all_tags_graceful ... ok
Nov 17 07:35:40.367 INFO [stdout] test config::tests::test_repo_name_from_ssh_pragma_with_multiple_git_endings ... ok
Nov 17 07:35:40.367 INFO [stdout] test config::tests::test_repo_name_from_url ... ok
Nov 17 07:35:40.368 INFO [stdout] test config::tests::test_do_not_expand_path_without_tilde ... ok
Nov 17 07:35:40.368 INFO [stdout] Nov 17 07:35:40.359 TRCE Resolving, tags: Some({"tag1", "tag2"})
Nov 17 07:35:40.368 INFO [stdout] Nov 17 07:35:40.362 TRCE Resolving, tags: Some({"tag1"})
Nov 17 07:35:40.368 INFO [stdout] Nov 17 07:35:40.363 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.369 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.369 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.369 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 17 07:35:40.369 INFO [stdout] Nov 17 07:35:40.363 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.370 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.370 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.370 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 17 07:35:40.370 INFO [stdout] Nov 17 07:35:40.363 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.371 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.371 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.371 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 17 07:35:40.371 INFO [stdout] test export::tests::test_workon_override_from_project ... ok
Nov 17 07:35:40.372 INFO [stdout] Nov 17 07:35:40.363 TRCE before sort, tags: [("clone1", 50), ("clone2", 50)]
Nov 17 07:35:40.372 INFO [stdout] Nov 17 07:35:40.363 TRCE before sort, tags: [("workon1", 50)]
Nov 17 07:35:40.372 INFO [stdout] Nov 17 07:35:40.363 TRCE after sort, tags: [("clone1", 50), ("clone2", 50)]
Nov 17 07:35:40.373 INFO [stdout] Nov 17 07:35:40.363 TRCE after sort, tags: [("workon1", 50)]
Nov 17 07:35:40.373 INFO [stdout] test config::tests::test_after_clone_from_tags ... ok
Nov 17 07:35:40.373 INFO [stdout] test config::tests::test_workon_override_from_project ... ok
Nov 17 07:35:40.374 INFO [stdout] Nov 17 07:35:40.363 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Nov 17 07:35:40.374 INFO [stdout] Nov 17 07:35:40.363 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Nov 17 07:35:40.374 INFO [stdout] Nov 17 07:35:40.363 TRCE Resolving, tags: Some({"tag3", "tag4"})
Nov 17 07:35:40.374 INFO [stdout] Nov 17 07:35:40.364 TRCE before sort, tags: [("workon3", 100), ("workon4", 0)]
Nov 17 07:35:40.379 INFO [stdout] Nov 17 07:35:40.365 TRCE after sort, tags: [("workon4", 0), ("workon3", 100)]
Nov 17 07:35:40.379 INFO [stdout] Nov 17 07:35:40.363 TRCE before sort, tags: []
Nov 17 07:35:40.379 INFO [stdout] Nov 17 07:35:40.365 TRCE after sort, tags: []
Nov 17 07:35:40.380 INFO [stdout] Nov 17 07:35:40.363 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Nov 17 07:35:40.380 INFO [stdout] Nov 17 07:35:40.365 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 17 07:35:40.380 INFO [stdout] Nov 17 07:35:40.365 DEBG No tag priority set, will use default (50).
Nov 17 07:35:40.381 INFO [stdout] Tags with low priority are applied first and if they all have the same priority
Nov 17 07:35:40.381 INFO [stdout] they will be applied in alphabetical name order so it is recommended you make a
Nov 17 07:35:40.381 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 17 07:35:40.381 INFO [stdout] Nov 17 07:35:40.365 TRCE before sort, tags: [("workon1", 50)]
Nov 17 07:35:40.382 INFO [stdout] Nov 17 07:35:40.365 TRCE after sort, tags: [("workon1", 50)]
Nov 17 07:35:40.382 INFO [stdout] test projectile::tests::test_replace_path_with_tilde ... ok
Nov 17 07:35:40.382 INFO [stdout] test config::tests::test_workon_from_tags_prioritized ... ok
Nov 17 07:35:40.383 INFO [stdout] test config::tests::test_workon_from_tags_missing_all_tags_graceful ... ok
Nov 17 07:35:40.383 INFO [stdout] test config::tests::test_workon_from_tags_missing_one_tag_graceful ... ok
Nov 17 07:35:40.383 INFO [stdout] test config::tests::test_repo_name_from_ssh_pragma ... ok
Nov 17 07:35:40.387 INFO [stdout] Nov 17 07:35:40.364 DEBG Writing projectile entry, entry: /home/mriehl/test
Nov 17 07:35:40.387 INFO [stdout] Nov 17 07:35:40.383 DEBG Writing projectile entry, entry: /home/mriehl/go/src/github.com/test2
Nov 17 07:35:40.387 INFO [stdout] test projectile::tests::test_persists_projectile_config ... ok
Nov 17 07:35:40.423 INFO [stdout] test sync::tests::test_username_from_git_url ... ok
Nov 17 07:35:40.423 INFO [stdout] 
Nov 17 07:35:40.423 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 07:35:40.423 INFO [stdout] 
Nov 17 07:35:40.442 INFO [stderr] su: No module specific data is present
Nov 17 07:35:41.697 INFO running `"docker" "inspect" "788269a7ea3697cad1637524c29f092b303257b5b609a447d230427ce91c3deb"`
Nov 17 07:35:41.943 INFO running `"docker" "rm" "-f" "788269a7ea3697cad1637524c29f092b303257b5b609a447d230427ce91c3deb"`
Nov 17 07:35:42.218 INFO [stdout] 788269a7ea3697cad1637524c29f092b303257b5b609a447d230427ce91c3deb