Nov 01 13:59:31.082 INFO testing brocode/fw against beta-2018-10-30 for beta-1.31-1
Nov 01 13:59:31.084 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 01 13:59:31.868 INFO blam! 20338118e9ffae4584997decf7185ed0433da452bb896730b839f040b3f99dfd
Nov 01 13:59:31.871 INFO running `"docker" "start" "-a" "20338118e9ffae4584997decf7185ed0433da452bb896730b839f040b3f99dfd"`
Nov 01 13:59:33.987 INFO kablam! usermod: no changes
Nov 01 13:59:34.507 INFO kablam!    Compiling libz-sys v1.0.25
Nov 01 13:59:34.507 INFO kablam!    Compiling curl-sys v0.4.13
Nov 01 13:59:34.507 INFO kablam!    Compiling libgit2-sys v0.7.10
Nov 01 13:59:34.507 INFO kablam!    Compiling base64 v0.6.0
Nov 01 13:59:34.519 INFO kablam!    Compiling want v0.0.4
Nov 01 13:59:34.519 INFO kablam!    Compiling num-complex v0.1.43
Nov 01 13:59:34.519 INFO kablam!    Compiling num-bigint v0.1.44
Nov 01 13:59:34.519 INFO kablam!    Compiling num-iter v0.1.37
Nov 01 13:59:38.022 INFO kablam!    Compiling crossbeam-deque v0.5.2
Nov 01 13:59:40.508 INFO kablam!    Compiling regex v0.2.11
Nov 01 13:59:40.520 INFO kablam!    Compiling slog-term v2.4.0
Nov 01 13:59:44.180 INFO kablam!    Compiling error-chain v0.11.0
Nov 01 13:59:44.413 INFO kablam!    Compiling rayon v0.8.2
Nov 01 13:59:44.441 INFO kablam!    Compiling console v0.6.2
Nov 01 13:59:44.520 INFO kablam!    Compiling libssh2-sys v0.2.11
Nov 01 13:59:48.187 INFO kablam!    Compiling crossbeam v0.4.1
Nov 01 13:59:59.720 INFO kablam!    Compiling num-rational v0.1.42
Nov 01 13:59:59.752 INFO kablam!    Compiling tokio-core v0.1.17
Nov 01 14:00:10.674 INFO kablam!    Compiling ring v0.12.1
Nov 01 14:00:11.737 INFO kablam!    Compiling num v0.1.42
Nov 01 14:00:15.267 INFO kablam!    Compiling spectral v0.6.0
Nov 01 14:00:16.447 INFO kablam!    Compiling indicatif v0.9.0
Nov 01 14:00:21.908 INFO kablam!    Compiling tokio-proto v0.1.1
Nov 01 14:00:38.907 INFO kablam!    Compiling sct v0.2.0
Nov 01 14:00:38.907 INFO kablam!    Compiling webpki v0.17.0
Nov 01 14:00:39.213 INFO kablam!    Compiling hyper v0.11.27
Nov 01 14:00:41.601 INFO kablam!    Compiling ct-logs v0.2.0
Nov 01 14:00:42.733 INFO kablam!    Compiling rustls v0.11.0
Nov 01 14:00:42.735 INFO kablam!    Compiling webpki-roots v0.13.0
Nov 01 14:00:55.899 INFO kablam!    Compiling tokio-rustls v0.4.0
Nov 01 14:00:59.771 INFO kablam!    Compiling hyper-rustls v0.11.0
Nov 01 14:01:03.844 INFO kablam!    Compiling github-gql-rs v0.0.1
Nov 01 14:01:11.707 INFO kablam!    Compiling git2 v0.7.5
Nov 01 14:01:18.439 INFO kablam!    Compiling fw v1.13.1-alpha.0 (/source)
Nov 01 14:01:39.323 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 2m 05s
Nov 01 14:01:39.338 INFO kablam! su: No module specific data is present
Nov 01 14:01:40.175 INFO running `"docker" "rm" "-f" "20338118e9ffae4584997decf7185ed0433da452bb896730b839f040b3f99dfd"`
Nov 01 14:01:40.431 INFO blam! 20338118e9ffae4584997decf7185ed0433da452bb896730b839f040b3f99dfd
Nov 01 14:01:40.436 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 01 14:01:40.843 INFO blam! 96f03f8b9ea7066466f8dfb8c4ae1e7b85fe32928b5de0a4f8f2412f932c8bbd
Nov 01 14:01:40.845 INFO running `"docker" "start" "-a" "96f03f8b9ea7066466f8dfb8c4ae1e7b85fe32928b5de0a4f8f2412f932c8bbd"`
Nov 01 14:01:42.122 INFO kablam! usermod: no changes
Nov 01 14:01:42.443 INFO kablam!    Compiling fw v1.13.1-alpha.0 (/source)
Nov 01 14:01:57.467 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 15.30s
Nov 01 14:01:57.495 INFO kablam! su: No module specific data is present
Nov 01 14:01:58.485 INFO running `"docker" "rm" "-f" "96f03f8b9ea7066466f8dfb8c4ae1e7b85fe32928b5de0a4f8f2412f932c8bbd"`
Nov 01 14:01:58.745 INFO blam! 96f03f8b9ea7066466f8dfb8c4ae1e7b85fe32928b5de0a4f8f2412f932c8bbd
Nov 01 14:01:58.749 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-6/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 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"`
Nov 01 14:01:59.138 INFO blam! bd5082e4e0596d4d60d01fcbcfaa12b96e73498ff6eb1bf0ccedcd0f1dafc6ce
Nov 01 14:01:59.147 INFO running `"docker" "start" "-a" "bd5082e4e0596d4d60d01fcbcfaa12b96e73498ff6eb1bf0ccedcd0f1dafc6ce"`
Nov 01 14:02:00.372 INFO kablam! usermod: no changes
Nov 01 14:02:00.803 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.40s
Nov 01 14:02:00.813 INFO kablam!      Running /target/debug/deps/fw-6c5e39d3a6d73117
Nov 01 14:02:00.879 INFO blam! 
Nov 01 14:02:00.879 INFO blam! running 19 tests
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE Resolving, tags: Some({"tag1", "tag2"})
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.919 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.919 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.919 INFO blam! 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 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 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 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.919 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.919 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.919 INFO blam! 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 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.919 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.919 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.919 INFO blam! 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 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE before sort, tags: [("clone1", 50)]
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE before sort, tags: [("clone1", 50), ("clone2", 50)]
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE after sort, tags: [("clone1", 50), ("clone2", 50)]
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE after sort, tags: [("clone1", 50)]
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.915 TRCE Resolving, tags: Some({"tag3", "tag4"})
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.916 TRCE before sort, tags: [("clone3", 100), ("clone4", 0)]
Nov 01 14:02:00.919 INFO blam! Nov 01 14:02:00.916 TRCE after sort, tags: [("clone4", 0), ("clone3", 100)]
Nov 01 14:02:00.939 INFO blam! Nov 01 14:02:00.915 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Nov 01 14:02:00.939 INFO blam! Nov 01 14:02:00.935 TRCE before sort, tags: []
Nov 01 14:02:00.939 INFO blam! Nov 01 14:02:00.935 TRCE after sort, tags: []
Nov 01 14:02:00.939 INFO blam! test config::tests::test_after_clone_from_tags_prioritized ... ok
Nov 01 14:02:00.939 INFO blam! test config::tests::test_after_clone_from_tags_missing_one_tag_graceful ... ok
Nov 01 14:02:00.939 INFO blam! test config::tests::test_after_clone_from_tags ... ok
Nov 01 14:02:00.939 INFO blam! test config::tests::test_do_expand_path ... ok
Nov 01 14:02:00.939 INFO blam! test config::tests::test_after_clone_from_tags_missing_all_tags_graceful ... ok
Nov 01 14:02:00.939 INFO blam! Nov 01 14:02:00.939 TRCE Resolving, tags: Some({"tag1"})
Nov 01 14:02:00.939 INFO blam! Nov 01 14:02:00.939 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.939 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.939 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.939 INFO blam! 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 01 14:02:00.939 INFO blam! Nov 01 14:02:00.939 TRCE before sort, tags: [("clone1", 50)]
Nov 01 14:02:00.939 INFO blam! Nov 01 14:02:00.939 TRCE after sort, tags: [("clone1", 50)]
Nov 01 14:02:00.939 INFO blam! test config::tests::test_after_clone_override_from_project ... ok
Nov 01 14:02:00.940 INFO blam! test config::tests::test_repo_name_from_ssh_pragma_with_multiple_git_endings ... ok
Nov 01 14:02:00.940 INFO blam! test config::tests::test_repo_name_from_url ... ok
Nov 01 14:02:00.940 INFO blam! Nov 01 14:02:00.940 TRCE Resolving, tags: Some({"tag3", "tag4"})
Nov 01 14:02:00.940 INFO blam! Nov 01 14:02:00.940 TRCE before sort, tags: [("workon3", 100), ("workon4", 0)]
Nov 01 14:02:00.940 INFO blam! Nov 01 14:02:00.940 TRCE after sort, tags: [("workon4", 0), ("workon3", 100)]
Nov 01 14:02:00.940 INFO blam! test config::tests::test_workon_from_tags_prioritized ... ok
Nov 01 14:02:00.940 INFO blam! Nov 01 14:02:00.940 TRCE Resolving, tags: Some({"tag1", "tag2"})
Nov 01 14:02:00.941 INFO blam! Nov 01 14:02:00.940 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.941 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.941 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.941 INFO blam! 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 01 14:02:00.941 INFO blam! Nov 01 14:02:00.940 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.941 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.941 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.941 INFO blam! 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 01 14:02:00.941 INFO blam! Nov 01 14:02:00.941 TRCE before sort, tags: [("workon1", 50), ("workon2", 50)]
Nov 01 14:02:00.941 INFO blam! Nov 01 14:02:00.941 TRCE after sort, tags: [("workon1", 50), ("workon2", 50)]
Nov 01 14:02:00.941 INFO blam! test config::tests::test_workon_from_tags ... ok
Nov 01 14:02:00.941 INFO blam! test config::tests::test_repo_name_from_ssh_pragma ... ok
Nov 01 14:02:00.941 INFO blam! test config::tests::test_do_not_expand_path_without_tilde ... ok
Nov 01 14:02:00.941 INFO blam! Nov 01 14:02:00.941 TRCE Resolving, tags: Some({"tag1"})
Nov 01 14:02:00.941 INFO blam! Nov 01 14:02:00.941 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.941 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.941 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.941 INFO blam! 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 01 14:02:00.941 INFO blam! Nov 01 14:02:00.941 TRCE before sort, tags: [("workon1", 50)]
Nov 01 14:02:00.941 INFO blam! Nov 01 14:02:00.941 TRCE after sort, tags: [("workon1", 50)]
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 TRCE before sort, tags: []
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 TRCE after sort, tags: []
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 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 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 DEBG No tag priority set, will use default (50).
Nov 01 14:02:00.947 INFO blam! Tags with low priority are applied first and if they all have the same priority
Nov 01 14:02:00.947 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Nov 01 14:02:00.947 INFO blam! 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 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 TRCE before sort, tags: [("workon1", 50)]
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 TRCE after sort, tags: [("workon1", 50)]
Nov 01 14:02:00.947 INFO blam! test config::tests::test_workon_override_from_project ... ok
Nov 01 14:02:00.947 INFO blam! test export::tests::test_workon_override_from_project ... ok
Nov 01 14:02:00.947 INFO blam! test config::tests::test_workon_from_tags_missing_all_tags_graceful ... ok
Nov 01 14:02:00.947 INFO blam! test config::tests::test_workon_from_tags_missing_one_tag_graceful ... ok
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.943 DEBG Writing projectile entry, entry: /home/mriehl/test
Nov 01 14:02:00.947 INFO blam! Nov 01 14:02:00.945 DEBG Writing projectile entry, entry: /home/mriehl/go/src/github.com/test2
Nov 01 14:02:00.959 INFO blam! test projectile::tests::test_replace_path_with_tilde ... ok
Nov 01 14:02:00.959 INFO blam! test projectile::tests::test_persists_projectile_config ... ok
Nov 01 14:02:00.973 INFO blam! test sync::tests::test_username_from_git_url ... ok
Nov 01 14:02:00.973 INFO blam! 
Nov 01 14:02:00.973 INFO blam! test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 01 14:02:00.973 INFO blam! 
Nov 01 14:02:01.003 INFO kablam! su: No module specific data is present
Nov 01 14:02:01.502 INFO running `"docker" "rm" "-f" "bd5082e4e0596d4d60d01fcbcfaa12b96e73498ff6eb1bf0ccedcd0f1dafc6ce"`
Nov 01 14:02:01.763 INFO blam! bd5082e4e0596d4d60d01fcbcfaa12b96e73498ff6eb1bf0ccedcd0f1dafc6ce
