Oct 16 10:45:25.813 INFO testing fw-1.11.0 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1
Oct 16 10:45:25.816 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"`
Oct 16 10:45:26.040 INFO blam! d485ee984d1c3b406b7acc20e94f3df6a67c00d54e8287ee0349ca3b939ed716
Oct 16 10:45:26.045 INFO running `"docker" "start" "-a" "d485ee984d1c3b406b7acc20e94f3df6a67c00d54e8287ee0349ca3b939ed716"`
Oct 16 10:45:26.776 INFO kablam! usermod: no changes
Oct 16 10:45:27.271 INFO kablam!    Compiling isatty v0.1.9
Oct 16 10:45:27.299 INFO kablam!    Compiling crossbeam-epoch v0.6.0
Oct 16 10:45:27.299 INFO kablam!    Compiling crossbeam-deque v0.5.2
Oct 16 10:45:27.299 INFO kablam!    Compiling slog-async v2.3.0
Oct 16 10:45:27.299 INFO kablam!    Compiling libgit2-sys v0.7.10
Oct 16 10:45:27.299 INFO kablam!    Compiling rayon v1.0.2
Oct 16 10:45:27.299 INFO kablam!    Compiling tokio-reactor v0.1.6
Oct 16 10:45:27.299 INFO kablam!    Compiling console v0.6.2
Oct 16 10:45:28.471 INFO kablam!    Compiling slog-term v2.4.0
Oct 16 10:45:33.863 INFO kablam!    Compiling crossbeam-channel v0.2.6
Oct 16 10:45:33.887 INFO kablam!    Compiling git2 v0.7.5
Oct 16 10:45:37.839 INFO kablam!    Compiling tokio-udp v0.1.2
Oct 16 10:45:38.639 INFO kablam!    Compiling tokio-uds v0.2.2
Oct 16 10:45:38.671 INFO kablam!    Compiling tokio-tcp v0.1.2
Oct 16 10:45:38.851 INFO kablam!    Compiling indicatif v0.9.0
Oct 16 10:45:42.998 INFO kablam!    Compiling crossbeam v0.4.1
Oct 16 10:45:49.163 INFO kablam!    Compiling tokio v0.1.11
Oct 16 10:45:55.080 INFO kablam!    Compiling tokio-core v0.1.17
Oct 16 10:45:59.759 INFO kablam!    Compiling tokio-proto v0.1.1
Oct 16 10:46:05.367 INFO kablam!    Compiling hyper v0.11.27
Oct 16 10:46:05.367 INFO kablam!    Compiling tokio-rustls v0.4.0
Oct 16 10:46:18.268 INFO kablam!    Compiling hyper-rustls v0.11.0
Oct 16 10:46:22.956 INFO kablam!    Compiling github-gql-rs v0.0.1
Oct 16 10:46:27.827 INFO kablam!    Compiling fw v1.11.0 (/source)
Oct 16 10:46:46.995 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 1m 20s
Oct 16 10:46:47.017 INFO kablam! su: No module specific data is present
Oct 16 10:46:47.424 INFO running `"docker" "rm" "-f" "d485ee984d1c3b406b7acc20e94f3df6a67c00d54e8287ee0349ca3b939ed716"`
Oct 16 10:46:47.554 INFO blam! d485ee984d1c3b406b7acc20e94f3df6a67c00d54e8287ee0349ca3b939ed716
Oct 16 10:46:47.556 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"`
Oct 16 10:46:47.741 INFO blam! 10931964768eb16de3669a7e9b4a3f5ace18275fcb2737126b61b17d4c1c42c4
Oct 16 10:46:47.743 INFO running `"docker" "start" "-a" "10931964768eb16de3669a7e9b4a3f5ace18275fcb2737126b61b17d4c1c42c4"`
Oct 16 10:46:48.282 INFO kablam! usermod: no changes
Oct 16 10:46:48.505 INFO kablam!    Compiling fw v1.11.0 (/source)
Oct 16 10:47:03.218 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 14.91s
Oct 16 10:47:03.241 INFO kablam! su: No module specific data is present
Oct 16 10:47:03.492 INFO running `"docker" "rm" "-f" "10931964768eb16de3669a7e9b4a3f5ace18275fcb2737126b61b17d4c1c42c4"`
Oct 16 10:47:03.598 INFO blam! 10931964768eb16de3669a7e9b4a3f5ace18275fcb2737126b61b17d4c1c42c4
Oct 16 10:47:03.600 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-1/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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"`
Oct 16 10:47:03.728 INFO blam! 4182d8d9d900d15243ddf279c868fce3ff50101a969da596a04015072a478c6b
Oct 16 10:47:03.730 INFO running `"docker" "start" "-a" "4182d8d9d900d15243ddf279c868fce3ff50101a969da596a04015072a478c6b"`
Oct 16 10:47:04.232 INFO kablam! usermod: no changes
Oct 16 10:47:04.485 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Oct 16 10:47:04.509 INFO kablam!      Running /target/debug/deps/fw-a7bf3952982f9a50
Oct 16 10:47:04.540 INFO blam! 
Oct 16 10:47:04.540 INFO blam! running 19 tests
Oct 16 10:47:04.546 INFO blam! Oct 16 10:47:04.540 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Oct 16 10:47:04.546 INFO blam! Oct 16 10:47:04.545 TRCE Resolving, tags: Some({"tag1", "tag2"})
Oct 16 10:47:04.546 INFO blam! Oct 16 10:47:04.546 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Oct 16 10:47:04.547 INFO blam! Oct 16 10:47:04.546 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Oct 16 10:47:04.547 INFO blam! Oct 16 10:47:04.546 TRCE Resolving, tags: Some({"tag3", "tag4"})
Oct 16 10:47:04.547 INFO blam! Oct 16 10:47:04.545 TRCE Resolving, tags: Some({"tag1"})
Oct 16 10:47:04.547 INFO blam! Oct 16 10:47:04.547 TRCE before sort, tags: []
Oct 16 10:47:04.548 INFO blam! Oct 16 10:47:04.547 TRCE after sort, tags: []
Oct 16 10:47:04.551 INFO blam! Oct 16 10:47:04.550 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.551 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.551 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.551 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
Oct 16 10:47:04.551 INFO blam! Oct 16 10:47:04.551 TRCE before sort, tags: [("clone1", 50)]
Oct 16 10:47:04.551 INFO blam! Oct 16 10:47:04.551 TRCE after sort, tags: [("clone1", 50)]
Oct 16 10:47:04.551 INFO blam! Oct 16 10:47:04.551 TRCE before sort, tags: [("clone3", 100), ("clone4", 0)]
Oct 16 10:47:04.551 INFO blam! Oct 16 10:47:04.551 TRCE after sort, tags: [("clone4", 0), ("clone3", 100)]
Oct 16 10:47:04.551 INFO blam! test config::tests::test_after_clone_override_from_project ... ok
Oct 16 10:47:04.555 INFO blam! Oct 16 10:47:04.547 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist", "tag1"})
Oct 16 10:47:04.555 INFO blam! Oct 16 10:47:04.555 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.555 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.555 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.555 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
Oct 16 10:47:04.555 INFO blam! Oct 16 10:47:04.555 TRCE before sort, tags: [("clone1", 50)]
Oct 16 10:47:04.555 INFO blam! Oct 16 10:47:04.555 TRCE after sort, tags: [("clone1", 50)]
Oct 16 10:47:04.559 INFO blam! test config::tests::test_after_clone_from_tags_prioritized ... ok
Oct 16 10:47:04.559 INFO blam! Oct 16 10:47:04.555 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.559 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.559 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.559 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
Oct 16 10:47:04.559 INFO blam! test config::tests::test_repo_name_from_ssh_pragma ... ok
Oct 16 10:47:04.559 INFO blam! Oct 16 10:47:04.559 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.559 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.559 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.559 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
Oct 16 10:47:04.559 INFO blam! Oct 16 10:47:04.559 TRCE before sort, tags: [("clone1", 50), ("clone2", 50)]
Oct 16 10:47:04.559 INFO blam! test config::tests::test_after_clone_from_tags_missing_all_tags_graceful ... ok
Oct 16 10:47:04.560 INFO blam! test config::tests::test_after_clone_from_tags_missing_one_tag_graceful ... ok
Oct 16 10:47:04.560 INFO blam! test config::tests::test_do_not_expand_path_without_tilde ... ok
Oct 16 10:47:04.560 INFO blam! test config::tests::test_do_expand_path ... ok
Oct 16 10:47:04.560 INFO blam! Oct 16 10:47:04.560 TRCE Resolving, tags: Some({"tag1", "tag2"})
Oct 16 10:47:04.560 INFO blam! test config::tests::test_repo_name_from_ssh_pragma_with_multiple_git_endings ... ok
Oct 16 10:47:04.560 INFO blam! test config::tests::test_repo_name_from_url ... ok
Oct 16 10:47:04.560 INFO blam! Oct 16 10:47:04.560 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.560 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.560 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.560 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
Oct 16 10:47:04.560 INFO blam! Oct 16 10:47:04.560 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.560 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.560 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.560 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
Oct 16 10:47:04.560 INFO blam! Oct 16 10:47:04.560 TRCE Resolving, tags: Some({"tag-does-not-exist"})
Oct 16 10:47:04.560 INFO blam! Oct 16 10:47:04.560 TRCE Resolving, tags: Some({"tag1"})
Oct 16 10:47:04.560 INFO blam! Oct 16 10:47:04.560 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.560 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.561 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.561 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
Oct 16 10:47:04.561 INFO blam! Oct 16 10:47:04.560 TRCE before sort, tags: [("workon1", 50)]
Oct 16 10:47:04.561 INFO blam! Oct 16 10:47:04.560 TRCE after sort, tags: [("workon1", 50)]
Oct 16 10:47:04.561 INFO blam! Oct 16 10:47:04.559 TRCE after sort, tags: [("clone1", 50), ("clone2", 50)]
Oct 16 10:47:04.561 INFO blam! test export::tests::test_workon_override_from_project ... ok
Oct 16 10:47:04.561 INFO blam! test config::tests::test_workon_override_from_project ... ok
Oct 16 10:47:04.561 INFO blam! Oct 16 10:47:04.560 TRCE before sort, tags: [("workon1", 50), ("workon2", 50)]
Oct 16 10:47:04.561 INFO blam! Oct 16 10:47:04.561 TRCE after sort, tags: [("workon1", 50), ("workon2", 50)]
Oct 16 10:47:04.561 INFO blam! test config::tests::test_workon_from_tags ... ok
Oct 16 10:47:04.562 INFO blam! Oct 16 10:47:04.560 TRCE Resolving, tags: Some({"tag-does-not-exist", "tag1"})
Oct 16 10:47:04.571 INFO blam! Oct 16 10:47:04.560 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist"})
Oct 16 10:47:04.571 INFO blam! Oct 16 10:47:04.571 TRCE before sort, tags: []
Oct 16 10:47:04.571 INFO blam! Oct 16 10:47:04.571 TRCE after sort, tags: []
Oct 16 10:47:04.571 INFO blam! test config::tests::test_after_clone_from_tags ... ok
Oct 16 10:47:04.571 INFO blam! test config::tests::test_workon_from_tags_missing_all_tags_graceful ... ok
Oct 16 10:47:04.573 INFO blam! Oct 16 10:47:04.562 WARN Ignoring tag since it was not found in the config, missing_tag: tag-does-not-exist, tags: Some({"tag-does-not-exist", "tag1"})
Oct 16 10:47:04.573 INFO blam! Oct 16 10:47:04.573 TRCE Resolving, tags: Some({"tag3", "tag4"})
Oct 16 10:47:04.574 INFO blam! Oct 16 10:47:04.573 TRCE before sort, tags: [("workon3", 100), ("workon4", 0)]
Oct 16 10:47:04.574 INFO blam! Oct 16 10:47:04.574 TRCE after sort, tags: [("workon4", 0), ("workon3", 100)]
Oct 16 10:47:04.574 INFO blam! test config::tests::test_workon_from_tags_prioritized ... ok
Oct 16 10:47:04.574 INFO blam! Oct 16 10:47:04.573 DEBG No tag priority set, will use default (50).
Oct 16 10:47:04.574 INFO blam! Tags with low priority are applied first and if they all have the same priority
Oct 16 10:47:04.574 INFO blam! they will be applied in alphabetical name order so it is recommended you make a
Oct 16 10:47:04.574 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
Oct 16 10:47:04.574 INFO blam! Oct 16 10:47:04.574 TRCE before sort, tags: [("workon1", 50)]
Oct 16 10:47:04.574 INFO blam! Oct 16 10:47:04.574 TRCE after sort, tags: [("workon1", 50)]
Oct 16 10:47:04.574 INFO blam! test config::tests::test_workon_from_tags_missing_one_tag_graceful ... ok
Oct 16 10:47:04.575 INFO blam! test projectile::tests::test_replace_path_with_tilde ... ok
Oct 16 10:47:04.578 INFO blam! Oct 16 10:47:04.577 DEBG Writing projectile entry, entry: /home/mriehl/test
Oct 16 10:47:04.579 INFO blam! Oct 16 10:47:04.579 DEBG Writing projectile entry, entry: /home/mriehl/go/src/github.com/test2
Oct 16 10:47:04.579 INFO blam! test projectile::tests::test_persists_projectile_config ... ok
Oct 16 10:47:04.595 INFO blam! test sync::tests::test_username_from_git_url ... ok
Oct 16 10:47:04.595 INFO blam! 
Oct 16 10:47:04.595 INFO blam! test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 16 10:47:04.595 INFO blam! 
Oct 16 10:47:04.599 INFO kablam! su: No module specific data is present
Oct 16 10:47:04.857 INFO running `"docker" "rm" "-f" "4182d8d9d900d15243ddf279c868fce3ff50101a969da596a04015072a478c6b"`
Oct 16 10:47:04.960 INFO blam! 4182d8d9d900d15243ddf279c868fce3ff50101a969da596a04015072a478c6b