Nov 16 12:43:01.482 INFO testing spacecowboy/scarecrow against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 12:43:01.483 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 12:43:01.903 INFO [stdout] 3746603a0be81b9df364f5b6ce4e9fb0b5cc47987a4e80d679c7470586c08d47
Nov 16 12:43:01.908 INFO running `"docker" "start" "-a" "3746603a0be81b9df364f5b6ce4e9fb0b5cc47987a4e80d679c7470586c08d47"`
Nov 16 12:43:03.111 INFO [stderr] usermod: no changes
Nov 16 12:43:03.225 INFO [stderr]    Compiling scarecrow v0.1.0 (/source)
Nov 16 12:43:04.441 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.32s
Nov 16 12:43:04.444 INFO [stderr] su: No module specific data is present
Nov 16 12:43:05.319 INFO running `"docker" "inspect" "3746603a0be81b9df364f5b6ce4e9fb0b5cc47987a4e80d679c7470586c08d47"`
Nov 16 12:43:05.623 INFO running `"docker" "rm" "-f" "3746603a0be81b9df364f5b6ce4e9fb0b5cc47987a4e80d679c7470586c08d47"`
Nov 16 12:43:05.891 INFO [stdout] 3746603a0be81b9df364f5b6ce4e9fb0b5cc47987a4e80d679c7470586c08d47
Nov 16 12:43:05.895 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 12:43:06.303 INFO [stdout] 12f4aa13afae2adef0a3009ab12c6a4686fe3f07553817193eb6591c62f77990
Nov 16 12:43:06.312 INFO running `"docker" "start" "-a" "12f4aa13afae2adef0a3009ab12c6a4686fe3f07553817193eb6591c62f77990"`
Nov 16 12:43:08.147 INFO [stderr] usermod: no changes
Nov 16 12:43:08.200 INFO [stderr]    Compiling scarecrow v0.1.0 (/source)
Nov 16 12:43:10.551 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.37s
Nov 16 12:43:10.555 INFO [stderr] su: No module specific data is present
Nov 16 12:43:11.677 INFO running `"docker" "inspect" "12f4aa13afae2adef0a3009ab12c6a4686fe3f07553817193eb6591c62f77990"`
Nov 16 12:43:12.009 INFO running `"docker" "rm" "-f" "12f4aa13afae2adef0a3009ab12c6a4686fe3f07553817193eb6591c62f77990"`
Nov 16 12:43:12.359 INFO [stdout] 12f4aa13afae2adef0a3009ab12c6a4686fe3f07553817193eb6591c62f77990
Nov 16 12:43:12.363 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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"`
Nov 16 12:43:12.863 INFO [stdout] cffcbdb3825b5aa0a7cb0e3470bda17360082bd2c52c41326a9adadeef28694b
Nov 16 12:43:12.871 INFO running `"docker" "start" "-a" "cffcbdb3825b5aa0a7cb0e3470bda17360082bd2c52c41326a9adadeef28694b"`
Nov 16 12:43:14.163 INFO [stderr] usermod: no changes
Nov 16 12:43:14.231 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 16 12:43:14.231 INFO [stdout] 
Nov 16 12:43:14.231 INFO [stderr]      Running /target/debug/deps/scarecrow-57a46ef7a40be366
Nov 16 12:43:14.231 INFO [stdout] running 15 tests
Nov 16 12:43:14.232 INFO [stdout] test layers::tests::dense_delta_from_inputs ... ok
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::hyperbolic_output ... ok
Nov 16 12:43:14.246 INFO [stderr]      Running /target/debug/deps/sgd-da51285c5c7b97f1
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::rectified_delta_from_inputs ... ok
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::hyperbolic_derivo ... ok
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::dense_output ... ok
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::sigmoid_output ... ok
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::dense_derivw ... ok
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::rectified_output ... ok
Nov 16 12:43:14.246 INFO [stdout] test layers::tests::sigmoid_delta_from_outputs ... ok
Nov 16 12:43:14.246 INFO [stdout] test utils::tests::dot_test ... ok
Nov 16 12:43:14.246 INFO [stdout] test tests::it_works ... ok
Nov 16 12:43:14.246 INFO [stdout] test utils::tests::sum_test ... ok
Nov 16 12:43:14.246 INFO [stdout] test utils::tests::add_test ... ok
Nov 16 12:43:14.246 INFO [stdout] test utils::tests::product_test ... ok
Nov 16 12:43:14.246 INFO [stdout] test utils::tests::normal_vector_test ... ok
Nov 16 12:43:14.246 INFO [stdout] 
Nov 16 12:43:14.247 INFO [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 12:43:14.247 INFO [stdout] 
Nov 16 12:43:14.247 INFO [stdout] 
Nov 16 12:43:14.247 INFO [stdout] running 1 test
Nov 16 12:43:14.599 INFO [stdout] test train_xor ... FAILED
Nov 16 12:43:14.599 INFO [stdout] 
Nov 16 12:43:14.599 INFO [stdout] failures:
Nov 16 12:43:14.599 INFO [stdout] 
Nov 16 12:43:14.599 INFO [stdout] ---- train_xor stdout ----
Nov 16 12:43:14.599 INFO [stdout] X: [0.0, 0.0], Y: [0.041757505], T: [0.0]
Nov 16 12:43:14.599 INFO [stdout] X: [0.0, 1.0], Y: [0.017007617], T: [1.0]
Nov 16 12:43:14.599 INFO [stdout] X: [1.0, 0.0], Y: [0.428813], T: [1.0]
Nov 16 12:43:14.599 INFO [stdout] X: [1.0, 1.0], Y: [0.24353805], T: [0.0]
Nov 16 12:43:14.599 INFO [stdout] X: [0.0, 0.0], Y: [0.06600884], T: [0.0]
Nov 16 12:43:14.599 INFO [stdout] X: [0.0, 1.0], Y: [0.009864386], T: [1.0]
Nov 16 12:43:14.599 INFO [stdout] thread 'train_xor' panicked at 'assertion failed: trainer.loss.loss1(o[0], t[0]) < 0.01', tests/sgd.rs:50:9
Nov 16 12:43:14.599 INFO [stdout] stack backtrace:
Nov 16 12:43:14.599 INFO [stdout]    0:     0x5598b7d1bf73 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h1a944b80a560daa8
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Nov 16 12:43:14.599 INFO [stdout]    1:     0x5598b7d16698 - std::sys_common::backtrace::_print::h18f735b4c3a49bdb
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/sys_common/backtrace.rs:71
Nov 16 12:43:14.599 INFO [stdout]    2:     0x5598b7d1aee4 - std::panicking::default_hook::{{closure}}::h97ab3548a215c097
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/sys_common/backtrace.rs:59
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/panicking.rs:211
Nov 16 12:43:14.599 INFO [stdout]    3:     0x5598b7d1abcc - std::panicking::default_hook::h98c2a01ef734e4b0
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/panicking.rs:221
Nov 16 12:43:14.599 INFO [stdout]    4:     0x5598b7d1b5f0 - std::panicking::rust_panic_with_hook::hb4b3f28eb79ea752
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/panicking.rs:476
Nov 16 12:43:14.599 INFO [stdout]    5:     0x5598b7d02337 - std::panicking::begin_panic::he5947997f8cb8066
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:410
Nov 16 12:43:14.599 INFO [stdout]    6:     0x5598b7cab01e - sgd::train_xor::h140f952f95ca1f8d
Nov 16 12:43:14.599 INFO [stdout]                                at tests/sgd.rs:50
Nov 16 12:43:14.599 INFO [stdout]    7:     0x5598b7ca9cd9 - sgd::train_xor::{{closure}}::hb525226a3c60a186
Nov 16 12:43:14.599 INFO [stdout]                                at tests/sgd.rs:10
Nov 16 12:43:14.599 INFO [stdout]    8:     0x5598b7cac71d - core::ops::function::FnOnce::call_once::h62894a0337faafd0
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 16 12:43:14.599 INFO [stdout]    9:     0x5598b7cb67ce - <F as alloc::boxed::FnBox<A>>::call_box::h9dcb7fc9b8bb4240
Nov 16 12:43:14.599 INFO [stdout]                                at libtest/lib.rs:1471
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libcore/ops/function.rs:238
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 16 12:43:14.599 INFO [stdout]   10:     0x5598b7d29d09 - __rust_maybe_catch_panic
Nov 16 12:43:14.599 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 16 12:43:14.599 INFO [stdout]   11:     0x5598b7cae833 - std::sys_common::backtrace::__rust_begin_short_backtrace::h89ea8afe71e36f86
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 16 12:43:14.599 INFO [stdout]                                at libtest/lib.rs:1426
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/sys_common/backtrace.rs:136
Nov 16 12:43:14.599 INFO [stdout]   12:     0x5598b7caf6e4 - std::panicking::try::do_call::h4132042e0cbea3ae
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:477
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:319
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:310
Nov 16 12:43:14.599 INFO [stdout]   13:     0x5598b7d29d09 - __rust_maybe_catch_panic
Nov 16 12:43:14.599 INFO [stdout]                                at libpanic_unwind/lib.rs:102
Nov 16 12:43:14.599 INFO [stdout]   14:     0x5598b7cb68fc - <F as alloc::boxed::FnBox<A>>::call_box::hab7f616f05880bf6
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panicking.rs:289
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/panic.rs:398
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/libstd/thread/mod.rs:476
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:673
Nov 16 12:43:14.599 INFO [stdout]   15:     0x5598b7d1dbed - std::sys::unix::thread::Thread::new::thread_start::h5f8d67158a1ca038
Nov 16 12:43:14.599 INFO [stdout]                                at /rustc/6b9b97bd9b704f85f0184f7a213cc4d62bd9654c/src/liballoc/boxed.rs:683
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/sys_common/thread.rs:24
Nov 16 12:43:14.599 INFO [stdout]                                at libstd/sys/unix/thread.rs:90
Nov 16 12:43:14.599 INFO [stdout]   16:     0x7fd713d106b9 - start_thread
Nov 16 12:43:14.599 INFO [stdout]   17:     0x7fd71383041c - clone
Nov 16 12:43:14.599 INFO [stdout]   18:                0x0 - <unknown>
Nov 16 12:43:14.599 INFO [stdout] 
Nov 16 12:43:14.599 INFO [stdout] 
Nov 16 12:43:14.599 INFO [stdout] failures:
Nov 16 12:43:14.599 INFO [stdout]     train_xor
Nov 16 12:43:14.599 INFO [stdout] 
Nov 16 12:43:14.599 INFO [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 12:43:14.599 INFO [stdout] 
Nov 16 12:43:14.608 INFO [stderr] error: test failed, to rerun pass '--test sgd'
Nov 16 12:43:14.608 INFO [stderr] su: No module specific data is present
Nov 16 12:43:15.547 INFO running `"docker" "inspect" "cffcbdb3825b5aa0a7cb0e3470bda17360082bd2c52c41326a9adadeef28694b"`
Nov 16 12:43:15.881 INFO running `"docker" "rm" "-f" "cffcbdb3825b5aa0a7cb0e3470bda17360082bd2c52c41326a9adadeef28694b"`
Nov 16 12:43:16.131 INFO [stdout] cffcbdb3825b5aa0a7cb0e3470bda17360082bd2c52c41326a9adadeef28694b