Oct 15 18:51:23.597 INFO testing silk-0.3.3 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1
Oct 15 18:51:23.598 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/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 15 18:51:23.996 INFO blam! 32a1008d2bbdf825812b94df5305d01d2c5af1fe491404234bd29cf7dd4d6dd1
Oct 15 18:51:24.003 INFO running `"docker" "start" "-a" "32a1008d2bbdf825812b94df5305d01d2c5af1fe491404234bd29cf7dd4d6dd1"`
Oct 15 18:51:25.114 INFO kablam! usermod: no changes
Oct 15 18:51:25.347 INFO kablam!    Compiling generic-array v0.9.0
Oct 15 18:51:25.359 INFO kablam!    Compiling chrono v0.4.6
Oct 15 18:51:25.375 INFO kablam!    Compiling rayon v1.0.2
Oct 15 18:51:26.888 INFO kablam!    Compiling digest v0.7.6
Oct 15 18:51:27.883 INFO kablam!    Compiling sha2 v0.7.1
Oct 15 18:51:32.859 INFO kablam!    Compiling silk v0.3.3 (/source)
Oct 15 18:51:50.631 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 25.48s
Oct 15 18:51:50.650 INFO kablam! su: No module specific data is present
Oct 15 18:51:51.171 INFO running `"docker" "rm" "-f" "32a1008d2bbdf825812b94df5305d01d2c5af1fe491404234bd29cf7dd4d6dd1"`
Oct 15 18:51:51.445 INFO blam! 32a1008d2bbdf825812b94df5305d01d2c5af1fe491404234bd29cf7dd4d6dd1
Oct 15 18:51:51.459 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/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 15 18:51:51.941 INFO blam! d0a54770dc5c5cf0448b0c5cb636a80fe020ab6294a6ae56316c8c8bef07ea2a
Oct 15 18:51:51.946 INFO running `"docker" "start" "-a" "d0a54770dc5c5cf0448b0c5cb636a80fe020ab6294a6ae56316c8c8bef07ea2a"`
Oct 15 18:51:53.272 INFO kablam! usermod: no changes
Oct 15 18:51:53.397 INFO kablam!    Compiling silk v0.3.3 (/source)
Oct 15 18:52:08.387 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 15.10s
Oct 15 18:52:08.402 INFO kablam! su: No module specific data is present
Oct 15 18:52:09.017 INFO running `"docker" "rm" "-f" "d0a54770dc5c5cf0448b0c5cb636a80fe020ab6294a6ae56316c8c8bef07ea2a"`
Oct 15 18:52:09.143 INFO blam! d0a54770dc5c5cf0448b0c5cb636a80fe020ab6294a6ae56316c8c8bef07ea2a
Oct 15 18:52:09.155 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-2/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-2/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 15 18:52:09.378 INFO blam! a4c3de15f4b7cfc2f5a931743f3f680f0c3919669a03a422b1a89a3227e92c42
Oct 15 18:52:09.380 INFO running `"docker" "start" "-a" "a4c3de15f4b7cfc2f5a931743f3f680f0c3919669a03a422b1a89a3227e92c42"`
Oct 15 18:52:09.711 INFO kablam! usermod: no changes
Oct 15 18:52:09.799 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.07s
Oct 15 18:52:09.807 INFO kablam!      Running /target/debug/deps/silk-345275d385b1c842
Oct 15 18:52:09.811 INFO blam! 
Oct 15 18:52:09.811 INFO blam! running 22 tests
Oct 15 18:52:09.835 INFO blam! {"num_hashes":1,"id":[177,122,90,88,106,254,110,94,217,52,162,91,245,9,223,142,193,205,204,59,52,21,189,144,105,75,158,205,246,148,84,10],"event":{"Transaction":{"from":[12,214,149,66,222,17,244,159,43,8,231,212,251,90,145,93,177,99,156,154,245,47,198,1,83,231,96,238,35,74,189,91],"to":[127,117,80,173,149,249,88,120,244,251,0,222,189,225,149,138,54,129,155,47,60,59,193,235,185,187,178,114,223,62,149,22],"if_all":[],"unless_any":[],"asset":1000,"last_id":[45,63,145,168,93,20,32,116,117,54,112,119,231,121,92,166,208,244,141,183,136,226,177,223,139,125,228,83,91,20,213,72],"sig":[57,71,98,197,35,205,71,29,47,59,208,193,147,30,69,204,41,188,186,191,64,39,12,74,164,255,95,145,197,128,119,216,5,245,195,182,2,146,189,86,187,168,161,61,86,165,2,32,143,53,80,111,5,10,21,144,144,153,207,177,174,120,38,5]}}}
Oct 15 18:52:09.857 INFO blam! test accountant::tests::test_cancel_transfer ... ok
Oct 15 18:52:09.875 INFO blam! test entry::tests::test_event_verify ... ok
Oct 15 18:52:09.875 INFO blam! test accountant::tests::test_invalid_transfer ... ok
Oct 15 18:52:09.875 INFO blam! {"num_hashes":1,"id":[130,45,14,198,53,196,126,217,11,105,145,114,133,112,177,152,232,118,205,92,80,100,88,84,16,111,32,170,152,169,129,215],"event":{"Transaction":{"from":[181,84,249,170,19,162,237,72,223,160,199,125,76,154,84,103,187,250,182,140,233,224,160,28,4,117,102,36,12,161,248,125],"to":[123,200,216,4,15,97,188,221,199,53,77,59,66,80,200,23,155,130,175,156,94,27,110,135,106,241,180,188,34,88,233,100],"if_all":[],"unless_any":[],"asset":1000,"last_id":[239,66,163,185,160,201,151,153,79,16,154,58,103,85,1,221,51,9,111,36,1,235,96,179,91,147,194,40,254,213,62,80],"sig":[133,79,253,101,58,252,42,227,172,39,95,55,168,97,111,159,249,91,117,49,200,96,228,210,24,212,133,230,70,23,234,208,252,69,226,47,201,60,209,105,90,21,95,170,115,215,81,92,139,161,122,188,178,196,71,175,225,205,210,58,77,19,97,13]}}}
Oct 15 18:52:09.875 INFO blam! {"num_hashes":0,"id":[130,45,14,198,53,196,126,217,11,105,145,114,133,112,177,152,232,118,205,92,80,100,88,84,16,111,32,170,152,169,129,215],"event":"Tick"}
Oct 15 18:52:09.875 INFO blam! {"num_hashes":1,"id":[50,207,217,180,162,14,9,51,121,223,199,75,178,229,169,189,32,65,34,103,9,124,5,66,151,223,14,150,243,119,104,200],"event":{"Transaction":{"from":[181,84,249,170,19,162,237,72,223,160,199,125,76,154,84,103,187,250,182,140,233,224,160,28,4,117,102,36,12,161,248,125],"to":[123,200,216,4,15,97,188,221,199,53,77,59,66,80,200,23,155,130,175,156,94,27,110,135,106,241,180,188,34,88,233,100],"if_all":[],"unless_any":[],"asset":500,"last_id":[239,66,163,185,160,201,151,153,79,16,154,58,103,85,1,221,51,9,111,36,1,235,96,179,91,147,194,40,254,213,62,80],"sig":[41,160,68,60,97,170,100,15,59,121,175,111,144,63,245,187,49,169,153,42,242,23,30,52,154,24,106,198,128,215,199,175,128,245,221,103,193,2,22,123,221,116,129,7,5,180,110,18,63,8,236,157,200,32,189,119,52,118,162,234,26,91,200,1]}}}
Oct 15 18:52:09.875 INFO blam! {"num_hashes":0,"id":[50,207,217,180,162,14,9,51,121,223,199,75,178,229,169,189,32,65,34,103,9,124,5,66,151,223,14,150,243,119,104,200],"event":"Tick"}
Oct 15 18:52:09.875 INFO blam! test accountant::tests::test_transfer_after_date ... ok
Oct 15 18:52:09.875 INFO blam! {"num_hashes":1,"id":[234,218,169,210,41,150,33,165,63,139,7,236,191,190,199,25,55,121,78,225,184,111,147,235,115,103,109,247,104,32,123,25],"event":{"Transaction":{"from":[187,25,207,1,140,195,70,94,173,78,176,81,31,45,152,46,14,74,214,14,5,166,136,180,115,201,219,50,109,158,227,62],"to":[195,5,135,243,8,78,145,217,68,3,32,224,173,45,240,157,62,69,129,113,227,22,163,235,7,156,9,169,150,182,17,236],"if_all":[],"unless_any":[],"asset":500,"last_id":[164,44,219,235,23,67,247,105,83,222,172,76,171,87,68,97,214,97,40,196,20,37,148,72,107,204,191,151,249,226,35,97],"sig":[183,18,234,66,207,114,186,12,96,38,88,167,19,15,45,46,23,251,147,212,242,193,246,180,214,205,96,240,103,198,202,196,16,30,20,251,63,89,172,91,106,252,135,180,168,3,18,230,198,64,253,185,175,42,135,145,52,189,220,83,48,220,219,11]}}}
Oct 15 18:52:09.875 INFO blam! test accountant::tests::test_transfer_on_date ... ok
Oct 15 18:52:09.875 INFO blam! {"num_hashes":1,"id":[128,104,116,19,83,66,242,186,18,76,14,167,18,38,39,245,184,23,163,253,110,80,84,92,56,140,13,138,171,233,207,238],"event":{"Transaction":{"from":[25,162,9,142,110,28,153,77,110,120,21,0,71,82,125,246,135,240,134,134,179,212,244,72,83,75,211,156,250,85,172,151],"to":[28,24,22,126,197,165,215,181,4,82,63,88,38,101,194,154,56,34,175,157,0,110,154,195,253,94,53,56,10,5,132,131],"if_all":[{"Timestamp":"2018-10-15T18:52:09.847242896Z"}],"unless_any":[{"Signature":[25,162,9,142,110,28,153,77,110,120,21,0,71,82,125,246,135,240,134,134,179,212,244,72,83,75,211,156,250,85,172,151]}],"asset":1,"last_id":[143,47,22,0,218,98,113,160,21,82,10,236,232,169,57,117,183,81,218,145,204,214,193,3,132,249,241,215,152,40,235,214],"sig":[204,217,136,230,91,36,100,254,231,39,186,17,126,88,1,6,101,111,63,133,184,153,124,187,239,128,129,73,255,58,139,4,12,148,99,16,99,249,9,208,55,64,81,168,13,178,241,61,224,35,212,244,104,130,77,94,122,113,138,122,236,216,124,9]}}}
Oct 15 18:52:09.883 INFO blam! test accountant::tests::test_transfer_to_newb ... ok
Oct 15 18:52:09.883 INFO blam! test historian::tests::test_duplicate_event_signature ... ok
Oct 15 18:52:09.883 INFO blam! test entry::tests::test_next_tick ... ok
Oct 15 18:52:09.883 INFO blam! {"num_hashes":1,"id":[155,204,188,27,11,240,226,221,220,52,255,146,121,171,182,142,118,73,119,199,139,11,134,236,134,210,164,242,191,229,160,177],"event":{"Transaction":{"from":[23,50,219,238,232,184,16,128,8,42,37,107,214,107,107,228,79,10,98,16,146,5,223,43,34,155,109,96,125,203,66,204],"to":[72,124,126,54,57,254,244,41,58,209,43,56,39,41,196,223,224,4,167,43,42,107,193,108,173,28,26,64,89,183,254,58],"if_all":[{"Timestamp":"2018-10-15T18:52:09.865916657Z"}],"unless_any":[{"Signature":[23,50,219,238,232,184,16,128,8,42,37,107,214,107,107,228,79,10,98,16,146,5,223,43,34,155,109,96,125,203,66,204]}],"asset":1,"last_id":[13,220,252,22,199,130,142,254,25,166,124,218,19,22,205,78,10,94,154,234,161,62,76,184,52,204,80,65,30,60,153,142],"sig":[159,160,139,123,70,19,26,216,234,252,86,117,56,179,74,122,201,197,37,73,223,188,201,2,110,82,137,131,123,4,64,106,87,136,50,164,145,20,67,218,160,240,177,120,254,7,85,45,20,168,19,169,114,33,196,217,219,132,25,72,163,163,35,8]}}}
Oct 15 18:52:09.891 INFO blam! {"num_hashes":0,"id":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"event":"Tick"}
Oct 15 18:52:09.891 INFO blam! test historian::tests::test_historian_closed_sender ... ok
Oct 15 18:52:09.891 INFO blam! test accountant::tests::test_accountant ... ok
Oct 15 18:52:09.891 INFO blam! {"num_hashes":1,"id":[84,187,8,63,199,238,23,140,47,42,128,39,233,205,116,53,77,93,156,184,74,55,182,42,205,199,115,52,240,128,255,172],"event":{"Transaction":{"from":[125,10,233,237,119,159,33,186,241,143,94,97,172,96,18,36,229,23,37,158,140,40,193,137,234,0,144,153,40,114,81,4],"to":[187,170,15,32,9,115,55,154,58,32,232,32,3,84,27,250,177,170,26,230,170,15,104,160,214,167,178,108,238,166,242,168],"if_all":[{"Timestamp":"2018-10-15T18:52:09.844582892Z"}],"unless_any":[{"Signature":[125,10,233,237,119,159,33,186,241,143,94,97,172,96,18,36,229,23,37,158,140,40,193,137,234,0,144,153,40,114,81,4]}],"asset":1,"last_id":[96,165,62,48,32,51,180,138,239,247,55,208,82,151,68,111,136,143,176,18,166,86,150,16,124,247,184,69,98,167,62,221],"sig":[46,152,238,207,247,215,233,112,61,102,134,105,97,99,100,34,53,156,32,19,196,110,191,23,11,95,62,237,18,87,177,168,16,91,159,151,158,59,137,131,46,111,110,36,199,206,235,137,224,89,13,18,36,223,247,119,111,95,32,191,15,222,140,13]}}}
Oct 15 18:52:09.891 INFO blam! test log::tests::test_verify_slice ... ok
Oct 15 18:52:09.898 INFO blam! {"num_hashes":0,"id":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"event":"Tick"}
Oct 15 18:52:09.898 INFO blam! {"num_hashes":0,"id":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"event":"Tick"}
Oct 15 18:52:09.898 INFO blam! {"num_hashes":0,"id":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"event":"Tick"}
Oct 15 18:52:09.898 INFO blam! test mint::tests::test_verify_entries ... ok
Oct 15 18:52:09.902 INFO blam! test mint::tests::test_create_events ... ok
Oct 15 18:52:09.906 INFO blam! test transaction::tests::test_hijack_attack ... ok
Oct 15 18:52:09.915 INFO blam! test transaction::tests::test_bad_event_signature ... ok
Oct 15 18:52:09.915 INFO blam! test transaction::tests::test_serialize_claim ... ok
Oct 15 18:52:09.915 INFO blam! test transaction::tests::test_claim ... ok
Oct 15 18:52:09.915 INFO blam! test transaction::tests::test_transfer ... ok
Oct 15 18:52:09.919 INFO blam! test log::tests::test_reorder_attack ... ok
Oct 15 18:52:09.919 INFO blam! test historian::tests::test_historian ... ok
Oct 15 18:52:09.963 INFO blam! {"num_hashes":590,"id":[170,119,176,115,240,138,102,116,78,242,61,230,226,153,4,123,186,6,214,155,156,6,169,173,74,162,201,190,156,202,89,146],"event":"Tick"}
Oct 15 18:52:09.963 INFO blam! {"num_hashes":0,"id":[170,119,176,115,240,138,102,116,78,242,61,230,226,153,4,123,186,6,214,155,156,6,169,173,74,162,201,190,156,202,89,146],"event":"Tick"}
Oct 15 18:52:09.963 INFO blam! {"num_hashes":0,"id":[170,119,176,115,240,138,102,116,78,242,61,230,226,153,4,123,186,6,214,155,156,6,169,173,74,162,201,190,156,202,89,146],"event":"Tick"}
Oct 15 18:52:09.983 INFO blam! test historian::tests::test_ticking_historian ... ok
Oct 15 18:53:09.809 INFO blam! test accountant_stub::tests::test_accountant_stub ... test accountant_stub::tests::test_accountant_stub has been running for over 60 seconds
Oct 15 18:58:09.786 INFO error running command: process killed after not generating output for  300s
Oct 15 18:58:09.786 INFO running `"docker" "rm" "-f" "a4c3de15f4b7cfc2f5a931743f3f680f0c3919669a03a422b1a89a3227e92c42"`
Oct 15 18:58:10.757 INFO blam! a4c3de15f4b7cfc2f5a931743f3f680f0c3919669a03a422b1a89a3227e92c42