Dec 06 02:56:30.566 INFO testing cthwaite/annals against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 02:56:30.566 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 06 02:56:31.005 INFO [stdout] 617ae79154563590bfe962af1adad00e9473f9cfb83393f0b2189effdfaeec9e
Dec 06 02:56:31.011 INFO running `"docker" "start" "-a" "617ae79154563590bfe962af1adad00e9473f9cfb83393f0b2189effdfaeec9e"`
Dec 06 02:56:32.365 INFO [stderr] usermod: no changes
Dec 06 02:56:32.548 INFO [stderr]    Compiling failure_derive v0.1.3
Dec 06 02:56:32.548 INFO [stderr]    Compiling rand_core v0.3.0
Dec 06 02:56:32.550 INFO [stderr]    Compiling serde_yaml v0.7.5
Dec 06 02:56:32.550 INFO [stderr]    Compiling syn v0.15.18
Dec 06 02:56:32.556 INFO [stderr]    Compiling titlecase v0.10.0
Dec 06 02:56:35.240 INFO [stderr]    Compiling rand_core v0.2.2
Dec 06 02:56:35.932 INFO [stderr]    Compiling rand v0.5.5
Dec 06 02:56:47.811 INFO [stderr]    Compiling synstructure v0.10.1
Dec 06 02:56:47.811 INFO [stderr]    Compiling serde_derive v1.0.80
Dec 06 02:56:53.352 INFO [stderr]    Compiling failure v0.1.3
Dec 06 02:57:02.533 INFO [stderr]    Compiling annals v0.1.0 (/source)
Dec 06 02:57:06.719 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 34.32s
Dec 06 02:57:06.735 INFO [stderr] su: No module specific data is present
Dec 06 02:57:07.238 INFO running `"docker" "inspect" "617ae79154563590bfe962af1adad00e9473f9cfb83393f0b2189effdfaeec9e"`
Dec 06 02:57:07.506 INFO running `"docker" "rm" "-f" "617ae79154563590bfe962af1adad00e9473f9cfb83393f0b2189effdfaeec9e"`
Dec 06 02:57:07.788 INFO [stdout] 617ae79154563590bfe962af1adad00e9473f9cfb83393f0b2189effdfaeec9e
Dec 06 02:57:07.796 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 06 02:57:08.182 INFO [stdout] b9e326f3db40b29f15eeb92c3bbea9d339ed3f2ff633395749162d7acc39db6b
Dec 06 02:57:08.187 INFO running `"docker" "start" "-a" "b9e326f3db40b29f15eeb92c3bbea9d339ed3f2ff633395749162d7acc39db6b"`
Dec 06 02:57:09.239 INFO [stderr] usermod: no changes
Dec 06 02:57:09.390 INFO [stderr]    Compiling annals v0.1.0 (/source)
Dec 06 02:57:21.053 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.77s
Dec 06 02:57:21.061 INFO [stderr] su: No module specific data is present
Dec 06 02:57:21.342 INFO running `"docker" "inspect" "b9e326f3db40b29f15eeb92c3bbea9d339ed3f2ff633395749162d7acc39db6b"`
Dec 06 02:57:21.439 INFO running `"docker" "rm" "-f" "b9e326f3db40b29f15eeb92c3bbea9d339ed3f2ff633395749162d7acc39db6b"`
Dec 06 02:57:21.529 INFO [stdout] b9e326f3db40b29f15eeb92c3bbea9d339ed3f2ff633395749162d7acc39db6b
Dec 06 02:57:21.530 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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"`
Dec 06 02:57:21.699 INFO [stdout] bc1754f55fad2721d67db6f5e66c8801f4a91542415e4705d974c44a6d1a6dc7
Dec 06 02:57:21.703 INFO running `"docker" "start" "-a" "bc1754f55fad2721d67db6f5e66c8801f4a91542415e4705d974c44a6d1a6dc7"`
Dec 06 02:57:22.108 INFO [stderr] usermod: no changes
Dec 06 02:57:22.191 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Dec 06 02:57:22.204 INFO [stderr]      Running /target/debug/deps/annals-b21254d2249de303
Dec 06 02:57:22.209 INFO [stdout] 
Dec 06 02:57:22.209 INFO [stdout] running 15 tests
Dec 06 02:57:22.210 INFO [stdout] test parse::test::test_escaped_subst ... ok
Dec 06 02:57:22.215 INFO [stdout] test parse::test::test_err_zero_length ... ok
Dec 06 02:57:22.228 INFO [stdout] test parse::test::test_nested_expression ... ok
Dec 06 02:57:22.229 INFO [stdout] test parse::test::test_expression ... ok
Dec 06 02:57:22.229 INFO [stdout] test parse::test::test_bad_binding ... ok
Dec 06 02:57:22.232 INFO [stdout] test parse::test::test_unbalanced ... ok
Dec 06 02:57:22.232 INFO [stdout] test parse::test::test_sticky_nonterminal ... ok
Dec 06 02:57:22.239 INFO [stdout] test parse::test::test_err_empty_token ... ok
Dec 06 02:57:22.239 INFO [stdout] test parse::test::test_nonterminal ... ok
Dec 06 02:57:22.240 INFO [stdout] test parse::test::test_range ... ok
Dec 06 02:57:22.244 INFO [stdout] test rule::test::test_template_valid ... ok
Dec 06 02:57:22.248 INFO [stdout] test parse::test::test_expr ... ok
Dec 06 02:57:22.250 INFO [stdout] test rule::test::test_template_invalid ... ok
Dec 06 02:57:22.263 INFO [stdout] test parse::test::test_binding ... ok
Dec 06 02:57:22.284 INFO [stdout] test test::test_handle_token ... ok
Dec 06 02:57:22.285 INFO [stdout] 
Dec 06 02:57:22.285 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 02:57:22.285 INFO [stdout] 
Dec 06 02:57:22.285 INFO [stderr]    Doc-tests annals
Dec 06 02:57:24.264 INFO [stdout] 
Dec 06 02:57:24.264 INFO [stdout] running 4 tests
Dec 06 02:57:31.131 INFO [stdout] test src/cognate.rs - cognate::Cognate::from_yaml (line 61) ... ok
Dec 06 02:57:33.936 INFO [stdout] test src/cognate.rs - cognate::Cognate::add_group (line 99) ... ok
Dec 06 02:57:33.949 INFO [stdout] test src/cognate.rs - cognate::Cognate::group_from_rules (line 78) ... ok
Dec 06 02:57:34.312 INFO [stdout] test src/cognate.rs - cognate::Cognate::new (line 23) ... ok
Dec 06 02:57:34.313 INFO [stdout] 
Dec 06 02:57:34.313 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 02:57:34.313 INFO [stdout] 
Dec 06 02:57:34.344 INFO [stderr] su: No module specific data is present
Dec 06 02:57:35.110 INFO running `"docker" "inspect" "bc1754f55fad2721d67db6f5e66c8801f4a91542415e4705d974c44a6d1a6dc7"`
Dec 06 02:57:35.480 INFO running `"docker" "rm" "-f" "bc1754f55fad2721d67db6f5e66c8801f4a91542415e4705d974c44a6d1a6dc7"`
Dec 06 02:57:35.662 INFO [stdout] bc1754f55fad2721d67db6f5e66c8801f4a91542415e4705d974c44a6d1a6dc7