Nov 02 04:50:23.980 INFO testing silk-0.3.3 against beta-2018-10-30 for beta-1.31-1
Nov 02 04:50:23.981 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 02 04:50:24.388 INFO blam! c15f346b64db4a2807d19c3641e0f18822ec6b28f30d3757182cc92fa171fcbb
Nov 02 04:50:24.390 INFO running `"docker" "start" "-a" "c15f346b64db4a2807d19c3641e0f18822ec6b28f30d3757182cc92fa171fcbb"`
Nov 02 04:50:25.126 INFO kablam! usermod: no changes
Nov 02 04:50:25.304 INFO kablam!    Compiling generic-array v0.9.0
Nov 02 04:50:26.258 INFO kablam!    Compiling digest v0.7.6
Nov 02 04:50:26.895 INFO kablam!    Compiling sha2 v0.7.1
Nov 02 04:50:28.239 INFO kablam!    Compiling silk v0.3.3 (/source)
Nov 02 04:50:45.131 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 19.97s
Nov 02 04:50:45.142 INFO kablam! su: No module specific data is present
Nov 02 04:50:45.656 INFO running `"docker" "rm" "-f" "c15f346b64db4a2807d19c3641e0f18822ec6b28f30d3757182cc92fa171fcbb"`
Nov 02 04:50:45.839 INFO blam! c15f346b64db4a2807d19c3641e0f18822ec6b28f30d3757182cc92fa171fcbb
Nov 02 04:50:45.842 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 02 04:50:46.435 INFO blam! 3b8df7b9b0933d761c5818741cf90e0bed449b5fa1caa850aad8474a48ce9912
Nov 02 04:50:46.437 INFO running `"docker" "start" "-a" "3b8df7b9b0933d761c5818741cf90e0bed449b5fa1caa850aad8474a48ce9912"`
Nov 02 04:50:47.425 INFO kablam! usermod: no changes
Nov 02 04:50:47.545 INFO kablam!    Compiling silk v0.3.3 (/source)
Nov 02 04:51:00.436 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 12.99s
Nov 02 04:51:00.447 INFO kablam! su: No module specific data is present
Nov 02 04:51:01.051 INFO running `"docker" "rm" "-f" "3b8df7b9b0933d761c5818741cf90e0bed449b5fa1caa850aad8474a48ce9912"`
Nov 02 04:51:01.279 INFO blam! 3b8df7b9b0933d761c5818741cf90e0bed449b5fa1caa850aad8474a48ce9912
Nov 02 04:51:01.285 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-0/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/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 02 04:51:01.671 INFO blam! 338831806dc95a97d18ee2b4cd1bc00fe1e6100a557c094762e9a01de51736eb
Nov 02 04:51:01.675 INFO running `"docker" "start" "-a" "338831806dc95a97d18ee2b4cd1bc00fe1e6100a557c094762e9a01de51736eb"`
Nov 02 04:51:02.716 INFO kablam! usermod: no changes
Nov 02 04:51:02.883 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Nov 02 04:51:02.895 INFO kablam!      Running /target/debug/deps/silk-f338b9bb09aa6823
Nov 02 04:51:02.899 INFO blam! 
Nov 02 04:51:02.899 INFO blam! running 22 tests
Nov 02 04:51:02.907 INFO blam! test accountant::tests::test_cancel_transfer ... ok
Nov 02 04:51:02.923 INFO blam! test entry::tests::test_next_tick ... ok
Nov 02 04:51:02.923 INFO blam! {"num_hashes":1,"id":[58,67,111,159,129,39,197,79,146,128,228,66,5,238,228,230,137,221,185,50,92,49,75,86,246,0,103,27,158,205,152,216],"event":{"Transaction":{"from":[1,199,109,173,240,25,172,106,149,106,159,247,95,237,118,183,208,238,66,184,95,94,3,153,48,118,9,230,186,248,205,39],"to":[190,235,192,125,176,25,158,178,153,244,35,186,113,63,36,126,254,44,206,64,27,251,130,212,81,71,240,247,204,91,142,241],"if_all":[{"Timestamp":"2018-11-02T04:51:02.902288006Z"}],"unless_any":[{"Signature":[1,199,109,173,240,25,172,106,149,106,159,247,95,237,118,183,208,238,66,184,95,94,3,153,48,118,9,230,186,248,205,39]}],"asset":1,"last_id":[168,150,1,206,119,244,15,50,202,107,187,21,143,107,117,20,91,145,188,15,115,174,14,141,196,98,57,163,154,169,25,3],"sig":[18,7,137,102,170,194,201,147,244,64,221,214,160,104,219,111,50,166,10,135,68,188,44,7,228,59,7,169,133,160,121,125,243,56,48,190,139,104,79,80,255,225,83,245,69,103,141,27,231,237,6,139,198,198,90,232,49,179,94,5,162,214,234,7]}}}
Nov 02 04:51:02.937 INFO blam! test accountant::tests::test_transfer_after_date ... ok
Nov 02 04:51:02.944 INFO blam! test accountant::tests::test_transfer_on_date ... ok
Nov 02 04:51:02.951 INFO blam! {"num_hashes":1,"id":[49,56,195,252,35,173,202,195,244,226,40,108,172,195,129,98,245,161,145,161,184,81,215,95,19,51,79,40,42,88,193,220],"event":{"Transaction":{"from":[171,28,36,98,138,184,85,154,117,211,11,246,124,166,38,195,53,70,238,160,62,20,182,180,2,253,174,50,12,79,188,57],"to":[241,172,210,142,122,238,100,137,100,245,148,210,144,34,39,132,21,87,222,215,61,82,7,207,99,68,79,69,120,153,15,246],"if_all":[{"Timestamp":"2018-11-02T04:51:02.941224559Z"}],"unless_any":[{"Signature":[171,28,36,98,138,184,85,154,117,211,11,246,124,166,38,195,53,70,238,160,62,20,182,180,2,253,174,50,12,79,188,57]}],"asset":1,"last_id":[145,129,19,179,5,18,191,125,57,112,125,229,94,23,138,137,211,144,243,4,70,34,235,183,66,192,222,82,224,27,189,94],"sig":[15,186,145,28,171,183,160,207,137,2,143,122,61,130,125,139,153,74,155,69,152,177,35,110,20,81,42,145,59,0,160,186,100,167,183,86,182,195,14,129,214,57,113,221,49,52,243,238,240,223,124,116,250,169,214,87,140,91,32,197,168,199,100,12]}}}
Nov 02 04:51:02.951 INFO blam! {"num_hashes":0,"id":[134,142,211,124,117,35,11,247,252,125,249,11,56,207,5,215,94,212,60,219,207,143,166,147,255,250,254,181,53,178,28,211],"event":"Tick"}
Nov 02 04:51:02.951 INFO blam! {"num_hashes":1,"id":[121,164,136,212,134,136,232,4,228,1,186,120,139,163,51,120,119,194,206,183,161,206,98,106,238,217,184,86,211,171,157,251],"event":{"Transaction":{"from":[191,164,100,186,139,4,84,93,189,200,160,173,37,214,82,87,221,165,164,61,188,241,197,199,97,221,158,104,172,178,228,114],"to":[239,17,111,40,105,239,86,191,79,227,70,45,120,7,245,164,125,242,77,185,194,246,146,150,228,85,192,103,98,173,167,247],"if_all":[],"unless_any":[],"asset":1000,"last_id":[134,142,211,124,117,35,11,247,252,125,249,11,56,207,5,215,94,212,60,219,207,143,166,147,255,250,254,181,53,178,28,211],"sig":[194,194,253,21,119,203,101,66,104,237,93,208,41,198,166,125,236,28,75,126,12,219,134,103,203,72,58,103,155,145,166,117,247,212,197,148,93,37,155,234,156,1,213,65,174,216,7,53,143,153,228,199,179,150,83,83,200,102,47,195,51,59,121,0]}}}
Nov 02 04:51:02.959 INFO blam! test accountant::tests::test_invalid_transfer ... ok
Nov 02 04:51:02.959 INFO blam! {"num_hashes":1,"id":[46,185,76,1,103,140,104,178,97,87,99,236,65,98,108,12,181,31,144,181,238,188,135,84,27,229,231,28,126,62,121,134],"event":{"Transaction":{"from":[180,101,184,21,155,167,65,235,199,8,47,191,36,83,136,178,234,141,61,210,150,204,24,204,53,16,20,247,243,36,26,201],"to":[96,237,106,229,210,113,210,131,141,136,155,179,155,253,213,7,65,38,188,176,1,134,194,152,20,119,17,41,152,247,234,84],"if_all":[],"unless_any":[],"asset":500,"last_id":[14,229,44,171,118,209,253,20,152,210,25,185,195,80,164,51,44,104,6,185,113,151,181,143,71,44,87,247,220,64,255,24],"sig":[247,247,111,230,50,33,134,200,17,98,251,183,94,233,72,117,99,241,62,211,83,169,206,118,135,129,199,192,60,58,239,165,26,137,209,126,48,95,188,244,70,148,29,240,121,184,55,133,205,39,231,217,5,49,111,29,239,170,250,105,207,230,239,3]}}}
Nov 02 04:51:02.959 INFO blam! test accountant::tests::test_transfer_to_newb ... ok
Nov 02 04:51:02.967 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"}
Nov 02 04:51:02.971 INFO blam! test entry::tests::test_event_verify ... ok
Nov 02 04:51:02.971 INFO blam! test historian::tests::test_historian_closed_sender ... ok
Nov 02 04:51:02.975 INFO blam! {"num_hashes":464,"id":[71,75,215,67,164,70,17,84,238,244,1,90,210,199,86,55,77,175,8,217,135,163,123,31,85,241,201,68,104,235,160,92],"event":"Tick"}
Nov 02 04:51:02.987 INFO blam! test historian::tests::test_duplicate_event_signature ... ok
Nov 02 04:51:02.990 INFO blam! test log::tests::test_reorder_attack ... ok
Nov 02 04:51:02.996 INFO blam! test transaction::tests::test_bad_event_signature ... ok
Nov 02 04:51:03.015 INFO blam! test accountant_stub::tests::test_accountant_stub ... ok
Nov 02 04:51:03.015 INFO blam! {"num_hashes":290,"id":[209,183,111,67,6,240,238,229,219,85,185,87,10,108,251,220,119,47,50,145,232,154,55,26,153,235,230,49,125,208,106,57],"event":"Tick"}
Nov 02 04:51:03.015 INFO blam! {"num_hashes":0,"id":[209,183,111,67,6,240,238,229,219,85,185,87,10,108,251,220,119,47,50,145,232,154,55,26,153,235,230,49,125,208,106,57],"event":"Tick"}
Nov 02 04:51:03.015 INFO blam! test mint::tests::test_verify_entries ... ok
Nov 02 04:51:03.015 INFO blam! test historian::tests::test_ticking_historian ... ok
Nov 02 04:51:03.015 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"}
Nov 02 04:51:03.015 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"}
Nov 02 04:51:03.015 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"}
Nov 02 04:51:03.015 INFO blam! test mint::tests::test_create_events ... ok
Nov 02 04:51:03.015 INFO blam! {"num_hashes":1,"id":[16,12,101,168,61,149,30,159,156,242,119,244,88,57,34,123,37,57,31,181,99,165,217,193,184,238,197,173,198,177,46,30],"event":{"Transaction":{"from":[30,226,80,119,201,78,74,3,216,73,227,68,163,181,2,0,100,39,178,70,190,125,216,206,146,46,180,170,88,69,229,189],"to":[81,249,42,237,121,43,53,53,243,45,23,158,203,104,182,194,242,32,149,76,156,76,180,174,33,19,84,239,206,82,194,114],"if_all":[],"unless_any":[],"asset":1000,"last_id":[173,60,98,233,147,197,128,51,122,151,173,133,128,167,2,15,54,91,200,216,43,39,66,105,192,170,186,68,154,216,230,98],"sig":[39,35,174,167,38,247,159,134,145,18,59,185,205,173,138,52,152,65,192,123,74,188,241,76,140,162,36,27,9,61,128,211,185,186,110,166,135,8,162,31,105,187,47,142,199,43,208,76,108,61,169,149,185,145,80,249,149,116,252,227,36,24,161,11]}}}
Nov 02 04:51:03.015 INFO blam! {"num_hashes":1,"id":[43,7,100,14,118,208,85,60,142,20,114,17,64,207,100,104,158,146,109,113,45,14,230,24,173,230,219,111,23,167,239,89],"event":{"Transaction":{"from":[30,226,80,119,201,78,74,3,216,73,227,68,163,181,2,0,100,39,178,70,190,125,216,206,146,46,180,170,88,69,229,189],"to":[81,249,42,237,121,43,53,53,243,45,23,158,203,104,182,194,242,32,149,76,156,76,180,174,33,19,84,239,206,82,194,114],"if_all":[],"unless_any":[],"asset":500,"last_id":[173,60,98,233,147,197,128,51,122,151,173,133,128,167,2,15,54,91,200,216,43,39,66,105,192,170,186,68,154,216,230,98],"sig":[37,136,49,235,119,72,148,169,90,199,196,139,91,185,85,28,105,144,24,53,156,14,108,111,7,141,178,184,210,162,248,95,158,193,176,200,132,29,167,132,138,53,245,77,69,50,143,160,102,144,96,79,55,3,59,119,199,249,156,31,37,34,195,4]}}}
Nov 02 04:51:03.028 INFO blam! test transaction::tests::test_serialize_claim ... ok
Nov 02 04:51:03.030 INFO blam! test log::tests::test_verify_slice ... ok
Nov 02 04:51:03.034 INFO blam! test historian::tests::test_historian ... ok
Nov 02 04:51:03.048 INFO blam! {"num_hashes":1,"id":[248,141,57,89,37,163,216,222,178,86,248,240,62,106,168,138,137,99,243,7,26,16,186,218,108,173,156,204,240,116,159,130],"event":{"Transaction":{"from":[34,135,140,53,185,201,42,143,61,144,167,48,167,6,251,90,103,38,78,30,150,179,104,184,211,8,232,0,190,234,195,210],"to":[245,185,71,55,97,25,163,200,253,150,8,61,251,178,192,192,158,31,50,243,204,237,193,15,33,162,200,206,32,136,13,115],"if_all":[{"Timestamp":"2018-11-02T04:51:02.933210578Z"}],"unless_any":[{"Signature":[34,135,140,53,185,201,42,143,61,144,167,48,167,6,251,90,103,38,78,30,150,179,104,184,211,8,232,0,190,234,195,210]}],"asset":1,"last_id":[129,42,196,49,150,227,142,215,240,23,38,31,211,58,218,230,193,183,161,54,60,8,170,68,172,101,151,59,98,106,152,161],"sig":[179,28,254,244,185,53,46,142,133,149,24,254,227,31,66,56,142,165,155,208,88,115,127,120,243,155,98,163,189,94,127,247,249,108,103,134,65,94,149,21,179,146,225,172,203,122,246,80,191,128,91,59,118,117,136,76,19,240,240,36,186,164,86,8]}}}
Nov 02 04:51:03.055 INFO blam! {"num_hashes":1,"id":[201,71,232,219,128,27,138,64,145,202,189,188,231,153,129,130,168,126,91,144,151,31,150,162,184,72,209,175,17,83,34,31],"event":{"Transaction":{"from":[224,12,194,215,171,34,226,201,57,196,75,218,112,126,58,28,117,206,6,187,49,20,197,206,52,31,42,136,141,46,134,55],"to":[123,173,152,48,123,19,203,105,147,12,19,233,196,52,101,239,152,228,124,183,37,24,85,76,241,135,201,106,232,131,197,243],"if_all":[],"unless_any":[],"asset":500,"last_id":[232,234,186,59,190,86,6,82,208,130,29,113,200,4,12,191,55,184,26,133,81,167,194,130,73,132,173,242,76,229,98,148],"sig":[64,137,128,70,134,244,52,158,209,160,106,10,241,134,16,94,17,28,184,161,20,89,94,225,114,15,211,195,161,138,72,238,223,215,138,142,124,214,234,233,36,200,36,177,91,84,108,1,23,173,214,63,84,212,176,233,136,252,123,154,17,146,242,4]}}}
Nov 02 04:51:03.062 INFO blam! test transaction::tests::test_transfer ... ok
Nov 02 04:51:03.062 INFO blam! test accountant::tests::test_accountant ... ok
Nov 02 04:51:03.062 INFO blam! test transaction::tests::test_hijack_attack ... ok
Nov 02 04:51:03.062 INFO blam! test transaction::tests::test_claim ... ok
Nov 02 04:51:03.062 INFO blam! 
Nov 02 04:51:03.062 INFO blam! test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:03.062 INFO blam! 
Nov 02 04:51:03.067 INFO kablam!      Running /target/debug/deps/silk_client_demo-d7eeb72b4a05e23e
Nov 02 04:51:03.073 INFO blam! 
Nov 02 04:51:03.073 INFO blam! running 0 tests
Nov 02 04:51:03.073 INFO blam! 
Nov 02 04:51:03.073 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:03.073 INFO blam! 
Nov 02 04:51:03.081 INFO kablam!      Running /target/debug/deps/silk_genesis-6cfab89afce02b52
Nov 02 04:51:03.095 INFO blam! 
Nov 02 04:51:03.095 INFO blam! running 0 tests
Nov 02 04:51:03.111 INFO blam! 
Nov 02 04:51:03.115 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:03.115 INFO blam! 
Nov 02 04:51:03.115 INFO kablam!      Running /target/debug/deps/silk_genesis_demo-3f5a8f4d336fe385
Nov 02 04:51:03.118 INFO blam! 
Nov 02 04:51:03.118 INFO blam! running 0 tests
Nov 02 04:51:03.118 INFO blam! 
Nov 02 04:51:03.118 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:03.118 INFO blam! 
Nov 02 04:51:03.118 INFO kablam!      Running /target/debug/deps/silk_historian_demo-4691fe245841f5f0
Nov 02 04:51:03.118 INFO blam! 
Nov 02 04:51:03.118 INFO blam! running 0 tests
Nov 02 04:51:03.118 INFO blam! 
Nov 02 04:51:03.118 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:03.118 INFO blam! 
Nov 02 04:51:03.119 INFO kablam!      Running /target/debug/deps/silk_mint-11d03129e8eba65b
Nov 02 04:51:03.124 INFO blam! 
Nov 02 04:51:03.124 INFO blam! running 0 tests
Nov 02 04:51:03.124 INFO blam! 
Nov 02 04:51:03.124 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:03.124 INFO blam! 
Nov 02 04:51:03.124 INFO kablam!      Running /target/debug/deps/silk_testnode-fe87c6aaa608f102
Nov 02 04:51:03.135 INFO blam! 
Nov 02 04:51:03.135 INFO blam! running 0 tests
Nov 02 04:51:03.135 INFO blam! 
Nov 02 04:51:03.135 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:03.135 INFO blam! 
Nov 02 04:51:03.135 INFO kablam!    Doc-tests silk
Nov 02 04:51:06.794 INFO blam! 
Nov 02 04:51:06.794 INFO blam! running 0 tests
Nov 02 04:51:06.794 INFO blam! 
Nov 02 04:51:06.794 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 02 04:51:06.794 INFO blam! 
Nov 02 04:51:06.801 INFO kablam! su: No module specific data is present
Nov 02 04:51:07.172 INFO running `"docker" "rm" "-f" "338831806dc95a97d18ee2b4cd1bc00fe1e6100a557c094762e9a01de51736eb"`
Nov 02 04:51:07.297 INFO blam! 338831806dc95a97d18ee2b4cd1bc00fe1e6100a557c094762e9a01de51736eb
