[INFO] cloning repository https://github.com/kotabrog/Rust_DeZero
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/kotabrog/Rust_DeZero" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkotabrog%2FRust_DeZero", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkotabrog%2FRust_DeZero'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] af149e28950346c831d1417927ad3efaa8b6e398
[INFO] testing kotabrog/Rust_DeZero against 1.98.0-beta.1 for beta-1.98-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkotabrog%2FRust_DeZero" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/kotabrog/Rust_DeZero
[INFO] finished tweaking git repo https://github.com/kotabrog/Rust_DeZero
[INFO] tweaked toml for git repo https://github.com/kotabrog/Rust_DeZero written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/kotabrog/Rust_DeZero on toolchain 1.98.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/kotabrog/Rust_DeZero already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr]   |
[INFO] [stderr]   = note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr]   = note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr]   = note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3d5ced03c013a94a2f102a4510f48a6e9184255caf5fd8244f58017bde7f5210" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e
[INFO] running `Command { std: "docker" "start" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr]   |
[INFO] [stderr]   = note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr]   = note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr]   = note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]    Compiling libc v0.2.146
[INFO] [stderr]    Compiling getrandom v0.2.10
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling ktensor v0.1.0 (/opt/rustwide/workdir/ktensor)
[INFO] [stderr]    Compiling kdezero v0.1.0 (/opt/rustwide/workdir/kdezero)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 4.03s
[INFO] running `Command { std: "docker" "inspect" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr]   |
[INFO] [stderr]   = note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr]   = note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr]   = note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]    Compiling proc-macro2 v1.0.62
[INFO] [stderr]    Compiling unicode-ident v1.0.9
[INFO] [stderr]    Compiling quote v1.0.28
[INFO] [stderr]    Compiling thiserror v1.0.40
[INFO] [stderr]    Compiling pkg-config v0.3.27
[INFO] [stderr]    Compiling ucd-trie v0.1.5
[INFO] [stderr]    Compiling cc v1.0.79
[INFO] [stderr]    Compiling simd-adler32 v0.3.5
[INFO] [stderr]    Compiling libloading v0.8.0
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling num-integer v0.1.45
[INFO] [stderr]    Compiling num-rational v0.4.1
[INFO] [stderr]    Compiling jpeg-decoder v0.3.0
[INFO] [stderr]    Compiling font-kit v0.11.0
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling plotters-backend v0.3.5
[INFO] [stderr]    Compiling weezl v0.1.7
[INFO] [stderr]    Compiling bytemuck v1.13.1
[INFO] [stderr]    Compiling once_cell v1.18.0
[INFO] [stderr]    Compiling miniz_oxide v0.7.1
[INFO] [stderr]    Compiling fdeflate v0.3.0
[INFO] [stderr]    Compiling const-cstr v0.3.0
[INFO] [stderr]    Compiling log v0.4.19
[INFO] [stderr]    Compiling walkdir v2.3.3
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling dlib v0.5.2
[INFO] [stderr]    Compiling time v0.1.45
[INFO] [stderr]    Compiling iana-time-zone v0.1.57
[INFO] [stderr]    Compiling float-ord v0.2.0
[INFO] [stderr]    Compiling plotters-svg v0.3.5
[INFO] [stderr]    Compiling ttf-parser v0.17.1
[INFO] [stderr]    Compiling gif v0.12.0
[INFO] [stderr]    Compiling yeslogic-fontconfig-sys v3.2.0
[INFO] [stderr]    Compiling cmake v0.1.50
[INFO] [stderr]    Compiling flate2 v1.0.26
[INFO] [stderr]    Compiling syn v2.0.22
[INFO] [stderr]    Compiling png v0.17.9
[INFO] [stderr]    Compiling freetype-sys v0.13.1
[INFO] [stderr]    Compiling chrono v0.4.26
[INFO] [stderr]    Compiling freetype v0.7.0
[INFO] [stderr]    Compiling image v0.24.6
[INFO] [stderr]    Compiling plotters-bitmap v0.3.3
[INFO] [stderr]    Compiling thiserror-impl v1.0.40
[INFO] [stderr]    Compiling pest v2.7.0
[INFO] [stderr]    Compiling semver-parser v0.10.2
[INFO] [stderr]    Compiling semver v0.11.0
[INFO] [stderr]    Compiling rustc_version v0.3.3
[INFO] [stderr]    Compiling pathfinder_simd v0.5.1
[INFO] [stderr]    Compiling pathfinder_geometry v0.5.1
[INFO] [stderr]    Compiling plotters v0.3.5
[INFO] [stderr]    Compiling ktensor v0.1.0 (/opt/rustwide/workdir/ktensor)
[INFO] [stderr]    Compiling kdezero v0.1.0 (/opt/rustwide/workdir/kdezero)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 28.99s
[INFO] running `Command { std: "docker" "inspect" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: virtual workspace defaulting to `resolver = "1"` despite one or more workspace members being on edition 2021 which implies `resolver = "2"`
[INFO] [stderr]   |
[INFO] [stderr]   = note: to keep the current resolver, specify `workspace.resolver = "1"` in the workspace root's manifest
[INFO] [stderr]   = note: to use the edition 2021 resolver, specify `workspace.resolver = "2"` in the workspace root's manifest
[INFO] [stderr]   = note: for more details see https://doc.rust-lang.org/cargo/reference/resolver.html#resolver-versions
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/kdezero-24829bacc4abcdfb)
[INFO] [stdout] 
[INFO] [stdout] running 66 tests
[INFO] [stdout] test function::function::linear::tests::forward_add_b ... ok
[INFO] [stdout] test function::function::linear::tests::backward_normal ... ok
[INFO] [stdout] test function::function::sigmoid::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::broadcast_to::tests::backward_normal ... ok
[INFO] [stdout] test function::function::sigmoid::tests::backward_normal ... ok
[INFO] [stdout] test function::function::linear::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::add::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::broadcast_to::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::cos::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::cos::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::cos::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::div::tests::backward_backward_x_2 ... ok
[INFO] [stdout] test function::operator::div::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::div::tests::backward_x_2 ... ok
[INFO] [stdout] test function::operator::exp::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::exp::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::exp::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::matmul::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::matmul::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::matmul::tests::backward_backward_x_2 ... ok
[INFO] [stdout] test function::operator::mean_squared_error::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::mean_squared_error::tests::backward_backward_x_2 ... ok
[INFO] [stdout] test function::operator::mean_squared_error::tests::backward_backward_x_y ... ok
[INFO] [stdout] test function::operator::div::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::add::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::mean_squared_error::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::mul::tests::backward_backward_x_2 ... ok
[INFO] [stdout] test function::operator::mul::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::mul::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::neg::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::neg::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::pow::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sin::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::square::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::square::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::reshape::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::reshape::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::pow::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::pow::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::sin::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sin::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::sub::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sub::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::sum::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sum::tests::backward_reshape ... ok
[INFO] [stdout] test function::operator::sum::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::sum_to::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::tanh::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sum_to::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::tanh::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::transpose::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::transpose::tests::forward_normal ... ok
[INFO] [stdout] test variable::tests::new_normal ... ok
[INFO] [stdout] test variable::variable_table::tests::generate_variable_from_f64_tensor_normal ... ok
[INFO] [stdout] test function::operator::square::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::broadcast_to::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::div::tests::backward_backward_x_y - should panic ... ok
[INFO] [stdout] test function::operator::add::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::matmul::tests::backward_backward_x_y - should panic ... ok
[INFO] [stdout] test function::operator::mul::tests::backward_backward_x_y - should panic ... ok
[INFO] [stdout] test function::operator::neg::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::reshape::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::sub::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::sum::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::sum_to::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::transpose::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/step_test.rs (/opt/rustwide/target/debug/deps/step_test-ed40c405a4cb7b8a)
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test step11 ... ok
[INFO] [stdout] test step1 ... ok
[INFO] [stdout] test step13 ... ok
[INFO] [stdout] test step14 ... ok
[INFO] [stdout] test step16 ... ok
[INFO] [stdout] test step18_1 ... ok
[INFO] [stdout] test step18_2 ... ok
[INFO] [stdout] test step19 ... ok
[INFO] [stdout] test step2 ... ok
[INFO] [stdout] test step20 ... ok
[INFO] [stdout] test step27 ... ok
[INFO] [stdout] test step3 ... ok
[INFO] [stdout] test step36 ... ok
[INFO] [stdout] test step4 ... ok
[INFO] [stdout] test step7 ... ok
[INFO] [stdout] test step33 ... ok
[INFO] [stdout] test step26 ... FAILED
[INFO] [stdout] test step35 ... FAILED
[INFO] [stdout] test step42 ... FAILED
[INFO] [stdout] test step43 ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- step26 stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'step26' (1345) panicked at kdezero/src/variable/variable_table.rs:362:52:
[INFO] [stdout] Failed to create file: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62906c784141 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62906c784141 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62906c79982a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62906c79982a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x62906c78878c - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x62906c78878c - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x62906c761bc6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62906c761bc6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62906c77c369 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stderr] error: test failed, to rerun pass `-p kdezero --test step_test`
[INFO] [stdout]   11:     0x62906c6ac6a0 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x62906c6ac6a0 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x62906c77c522 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x62906c77c522 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x62906c761c72 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x62906c759489 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62906c76286d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x62906c79a01c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62906c799d62 - core[6883ba1bc0fe4ed1]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1870:5
[INFO] [stdout]   20:     0x62906c6e691e - <core[6883ba1bc0fe4ed1]::result::Result<std[73adb7dc35730857]::fs::File, core[6883ba1bc0fe4ed1]::io::error::Error>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1183:23
[INFO] [stdout]   21:     0x62906c707ff1 - <kdezero[3342c97a6354a407]::variable::variable_table::VariableTable>::plot_dot_graph
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/src/variable/variable_table.rs:362:52
[INFO] [stdout]   22:     0x62906c552a15 - step_test[a6d403c04bbae5c9]::step26
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:431:20
[INFO] [stdout]   23:     0x62906c548cc7 - step_test[a6d403c04bbae5c9]::step26::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:401:12
[INFO] [stdout]   24:     0x62906c58d556 - <step_test[a6d403c04bbae5c9]::step26::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x62906c69f9db - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x62906c69f9db - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x62906c6ad025 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   30:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x62906c6ad025 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x62906c6a7ad4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x62906c6a7ad4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x62906c6b0172 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   39:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x62906c6b0172 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x62906c6b0172 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x62906c7839cf - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   44:     0x62906c7839cf - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   45:     0x761f39b6aaa4 - <unknown>
[INFO] [stdout]   46:     0x761f39bf7a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- step35 stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'step35' (1349) panicked at kdezero/src/variable/variable_table.rs:362:52:
[INFO] [stdout] Failed to create file: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62906c784141 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62906c784141 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62906c79982a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62906c79982a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x62906c78878c - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x62906c78878c - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x62906c761bc6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62906c761bc6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62906c77c369 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x62906c6ac6a0 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x62906c6ac6a0 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x62906c77c522 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x62906c77c522 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x62906c761c72 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x62906c759489 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62906c76286d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x62906c79a01c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62906c799d62 - core[6883ba1bc0fe4ed1]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1870:5
[INFO] [stdout]   20:     0x62906c6e691e - <core[6883ba1bc0fe4ed1]::result::Result<std[73adb7dc35730857]::fs::File, core[6883ba1bc0fe4ed1]::io::error::Error>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1183:23
[INFO] [stdout]   21:     0x62906c707ff1 - <kdezero[3342c97a6354a407]::variable::variable_table::VariableTable>::plot_dot_graph
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/src/variable/variable_table.rs:362:52
[INFO] [stdout]   22:     0x62906c554c7d - step_test[a6d403c04bbae5c9]::step35
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:552:20
[INFO] [stdout]   23:     0x62906c548d57 - step_test[a6d403c04bbae5c9]::step35::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:517:12
[INFO] [stdout]   24:     0x62906c58d616 - <step_test[a6d403c04bbae5c9]::step35::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x62906c69f9db - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x62906c69f9db - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   27:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   28:     0x62906c6ad025 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   29:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   30:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x62906c6ad025 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   33:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   34:     0x62906c6a7ad4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   35:     0x62906c6a7ad4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   37:     0x62906c6b0172 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   38:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   39:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x62906c6b0172 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   42:     0x62906c6b0172 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x62906c7839cf - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   44:     0x62906c7839cf - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   45:     0x761f39b6aaa4 - <unknown>
[INFO] [stdout]   46:     0x761f39bf7a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- step42 stdout ----
[INFO] [stdout] iter 0 w: Tensor { data: [Scaler { data: 0.7331419863972053 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 1.314550621333924 }], shape: [] }, loss: Tensor { data: [Scaler { data: 43.58210130007408 }], shape: [] }
[INFO] [stdout] iter 10 w: Tensor { data: [Scaler { data: 2.64541896100283 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 4.89518945640207 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.24493052015369926 }], shape: [] }
[INFO] [stdout] iter 20 w: Tensor { data: [Scaler { data: 2.6622591762446537 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.112842205827255 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.1334220719643717 }], shape: [] }
[INFO] [stdout] iter 30 w: Tensor { data: [Scaler { data: 2.594821374240764 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.162167501202493 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.12616706989235982 }], shape: [] }
[INFO] [stdout] iter 40 w: Tensor { data: [Scaler { data: 2.530461988963025 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.199220411515721 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.1205531109160321 }], shape: [] }
[INFO] [stdout] iter 50 w: Tensor { data: [Scaler { data: 2.4726793515220704 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.232029430918276 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.11605894267759036 }], shape: [] }
[INFO] [stdout] iter 60 w: Tensor { data: [Scaler { data: 2.4209675427664354 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.261369223973327 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.11246083743577223 }], shape: [] }
[INFO] [stdout] iter 70 w: Tensor { data: [Scaler { data: 2.3746968056539526 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.287620828266294 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.10958013414005915 }], shape: [] }
[INFO] [stdout] iter 80 w: Tensor { data: [Scaler { data: 2.3332950279643856 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.311109986133485 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.1072737948509974 }], shape: [] }
[INFO] [stdout] iter 90 w: Tensor { data: [Scaler { data: 2.2962498798180473 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.332127422471338 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.10542730100201274 }], shape: [] }
[INFO] [stdout] 
[INFO] [stdout] thread 'step42' (1352) panicked at kdezero/tests/step_test.rs:710:65:
[INFO] [stdout] Failed to plot: BackendError(DrawingError(ImageError(IoError(Os { code: 2, kind: NotFound, message: "No such file or directory" }))))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62906c784141 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62906c784141 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62906c79982a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62906c79982a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x62906c78878c - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x62906c78878c - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x62906c761bc6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62906c761bc6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62906c77c369 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x62906c6ac6a0 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x62906c6ac6a0 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x62906c77c522 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x62906c77c522 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x62906c761c72 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x62906c759489 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62906c76286d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x62906c79a01c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62906c799d62 - core[6883ba1bc0fe4ed1]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1870:5
[INFO] [stdout]   20:     0x62906c58689b - <core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::error::Error>>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1183:23
[INFO] [stdout]   21:     0x62906c556c40 - step_test[a6d403c04bbae5c9]::step42
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:710:65
[INFO] [stdout]   22:     0x62906c548db7 - step_test[a6d403c04bbae5c9]::step42::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:590:12
[INFO] [stdout]   23:     0x62906c58d696 - <step_test[a6d403c04bbae5c9]::step42::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x62906c69f9db - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x62906c69f9db - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x62906c6ad025 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x62906c6ad025 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x62906c6a7ad4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x62906c6a7ad4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x62906c6b0172 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x62906c6b0172 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x62906c6b0172 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x62906c7839cf - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   43:     0x62906c7839cf - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x761f39b6aaa4 - <unknown>
[INFO] [stdout]   45:     0x761f39bf7a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- step43 stdout ----
[INFO] [stdout] iter 0
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.5162598693885034 }, Scaler { data: 0.9004356287296605 }, Scaler { data: 0.49738717106038133 }, Scaler { data: 0.15970800173631022 }, Scaler { data: 0.8273095303951021 }, Scaler { data: 0.0439418020142536 }, Scaler { data: 0.7866827080148482 }, Scaler { data: 0.15847751866937035 }, Scaler { data: 0.11951975296003826 }, Scaler { data: 0.7763482660639216 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.27914417035179473 }, Scaler { data: -0.13530386357667798 }, Scaler { data: -0.009058234732640117 }, Scaler { data: -0.20973825631100734 }, Scaler { data: -0.1718354531106444 }, Scaler { data: -0.23631095587292128 }, Scaler { data: -0.1807121328729169 }, Scaler { data: -0.21367252724025107 }, Scaler { data: -0.11752375812906615 }, Scaler { data: -0.09346321757485253 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.2801893644708575 }, Scaler { data: -0.25655642371229403 }, Scaler { data: -0.6515084185943607 }, Scaler { data: 0.07205001100004382 }, Scaler { data: -0.1174445590479617 }, Scaler { data: 0.17667250019978686 }, Scaler { data: -0.08314579995959859 }, Scaler { data: 0.08532777544392811 }, Scaler { data: -0.22723191213898097 }, Scaler { data: -0.39908708168493057 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -1.1819007054356963 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 9.608278831142114 }], shape: [] }
[INFO] [stdout] iter 10
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.4344652753792855 }, Scaler { data: 0.845008854183636 }, Scaler { data: 0.46257422112769836 }, Scaler { data: 0.09057334008319394 }, Scaler { data: 0.765070471889143 }, Scaler { data: -0.028832445441326963 }, Scaler { data: 0.7227013301915745 }, Scaler { data: 0.0887098196789038 }, Scaler { data: 0.06519943207635152 }, Scaler { data: 0.7278300589124955 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.27044713061703424 }, Scaler { data: -0.22268018411813972 }, Scaler { data: -0.16496245584868616 }, Scaler { data: -0.23453492414815127 }, Scaler { data: -0.23407390738115577 }, Scaler { data: -0.24083505348493783 }, Scaler { data: -0.23682407161940128 }, Scaler { data: -0.23611186104250148 }, Scaler { data: -0.19503398941528163 }, Scaler { data: -0.20641062743957322 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.5452384172677066 }, Scaler { data: 0.025481528587664212 }, Scaler { data: -0.3119700079225681 }, Scaler { data: 0.37732391189579373 }, Scaler { data: 0.1588236980662671 }, Scaler { data: 0.48148669292258106 }, Scaler { data: 0.19303422364281161 }, Scaler { data: 0.3896117701187162 }, Scaler { data: 0.10526367671536886 }, Scaler { data: -0.09863318437474018 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.4762135457890937 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.6194306560608057 }], shape: [] }
[INFO] [stdout] iter 20
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.3581035633517609 }, Scaler { data: 0.8459588849505386 }, Scaler { data: 0.5073051191658429 }, Scaler { data: 0.03538564951334737 }, Scaler { data: 0.7473817168784336 }, Scaler { data: -0.09866154893492146 }, Scaler { data: 0.699826103118953 }, Scaler { data: 0.03179717918527117 }, Scaler { data: 0.048019308952102935 }, Scaler { data: 0.7445730738263014 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.258108562020743 }, Scaler { data: -0.22293476200404502 }, Scaler { data: -0.17378537236250738 }, Scaler { data: -0.22519643247286059 }, Scaler { data: -0.2297668526326013 }, Scaler { data: -0.22782919864290727 }, Scaler { data: -0.23159516668041485 }, Scaler { data: -0.22646959609158268 }, Scaler { data: -0.19209607899421807 }, Scaler { data: -0.21053749730401472 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.5322221317665589 }, Scaler { data: -0.04768869553006418 }, Scaler { data: -0.33418992768718037 }, Scaler { data: 0.41235834381416864 }, Scaler { data: 0.09710190911848113 }, Scaler { data: 0.5339651683809952 }, Scaler { data: 0.13729143534238183 }, Scaler { data: 0.42498245604300006 }, Scaler { data: 0.14233406790159459 }, Scaler { data: -0.15664108187362052 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.3761364816137664 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5923757344847752 }], shape: [] }
[INFO] [stdout] iter 30
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.2858450734135825 }, Scaler { data: 0.856136945558508 }, Scaler { data: 0.5535489410102142 }, Scaler { data: -0.022381997741486272 }, Scaler { data: 0.7382218187106171 }, Scaler { data: -0.17243143270601266 }, Scaler { data: 0.6849203007602903 }, Scaler { data: -0.027663473208868198 }, Scaler { data: 0.026578564060492013 }, Scaler { data: 0.7682701024836799 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.24713208371327747 }, Scaler { data: -0.22569036530447625 }, Scaler { data: -0.18282040327001606 }, Scaler { data: -0.21549322493824324 }, Scaler { data: -0.22764268761999662 }, Scaler { data: -0.213708634729087 }, Scaler { data: -0.2283706194418183 }, Scaler { data: -0.21644973658878375 }, Scaler { data: -0.1885541070279051 }, Scaler { data: -0.21627909343696225 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.5282788869263747 }, Scaler { data: -0.12061405825605148 }, Scaler { data: -0.36376131944020257 }, Scaler { data: 0.45197480900990483 }, Scaler { data: 0.037793875237392804 }, Scaler { data: 0.5923624269754898 }, Scaler { data: 0.08449045321504975 }, Scaler { data: 0.4651574390038274 }, Scaler { data: 0.17872641394546412 }, Scaler { data: -0.21701961710127335 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.2841456696453683 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5652400469987066 }], shape: [] }
[INFO] [stdout] iter 40
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.21688738663892465 }, Scaler { data: 0.874647198916468 }, Scaler { data: 0.6016148506031088 }, Scaler { data: -0.08255614229191532 }, Scaler { data: 0.736709969954241 }, Scaler { data: -0.24973930781310702 }, Scaler { data: 0.6771346260511797 }, Scaler { data: -0.08950665646469602 }, Scaler { data: 0.0014449773538318007 }, Scaler { data: 0.7983651844073333 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.23700552466375785 }, Scaler { data: -0.23065083340301948 }, Scaler { data: -0.1922846540639977 }, Scaler { data: -0.20521866482917683 }, Scaler { data: -0.2273007655211755 }, Scaler { data: -0.19811797065191666 }, Scaler { data: -0.2267488188866277 }, Scaler { data: -0.20583117372029308 }, Scaler { data: -0.18447490118506052 }, Scaler { data: -0.22356311795820635 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.5327423850911104 }, Scaler { data: -0.1932996804333077 }, Scaler { data: -0.39943862324609786 }, Scaler { data: 0.49634841832113175 }, Scaler { data: -0.01931761977880853 }, Scaler { data: 0.6567232443036956 }, Scaler { data: 0.03438593865942599 }, Scaler { data: 0.510282503360571 }, Scaler { data: 0.21531773146828126 }, Scaler { data: -0.27937437835988915 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.1990016653140825 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5372285732990976 }], shape: [] }
[INFO] [stdout] iter 50
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.15073781977881082 }, Scaler { data: 0.9004192538462082 }, Scaler { data: 0.6514377485461595 }, Scaler { data: -0.14471389120571324 }, Scaler { data: 0.7418959105458343 }, Scaler { data: -0.3298480748368218 }, Scaler { data: 0.6755855104373016 }, Scaler { data: -0.15330725567622025 }, Scaler { data: -0.026788332346233176 }, Scaler { data: 0.8340665226344038 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.22738339929943116 }, Scaler { data: -0.2375772770436253 }, Scaler { data: -0.20230480736369813 }, Scaler { data: -0.19421851410768548 }, Scaler { data: -0.22844043815986836 }, Scaler { data: -0.1807819129069701 }, Scaler { data: -0.22643288727501565 }, Scaler { data: -0.19444828911545226 }, Scaler { data: -0.17990808677881898 }, Scaler { data: -0.23231028209521384 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.5447409593656499 }, Scaler { data: -0.2655830093354447 }, Scaler { data: -0.440066881635158 }, Scaler { data: 0.5451922070919072 }, Scaler { data: -0.07435193768410533 }, Scaler { data: 0.7265289087327355 }, Scaler { data: -0.013203729400094863 }, Scaler { data: 0.5600387260709053 }, Scaler { data: 0.2525274858964891 }, Scaler { data: -0.34321069128580445 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.12005269627198767 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5080025587103567 }], shape: [] }
[INFO] [stdout] iter 60
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.08723304941670623 }, Scaler { data: 0.9321661954322056 }, Scaler { data: 0.7025805574919999 }, Scaler { data: -0.20813366383510112 }, Scaler { data: 0.7527221528306155 }, Scaler { data: -0.41164709164129326 }, Scaler { data: 0.6793220458455919 }, Scaler { data: -0.21833226920995563 }, Scaler { data: -0.057438492205870335 }, Scaler { data: 0.8743138712636231 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.21802573837693814 }, Scaler { data: -0.24623434481603515 }, Scaler { data: -0.21292990148380392 }, Scaler { data: -0.18239204914079837 }, Scaler { data: -0.23080969109912627 }, Scaler { data: -0.16153561771472602 }, Scaler { data: -0.22718126018324034 }, Scaler { data: -0.1821925338337749 }, Scaler { data: -0.17489925863609357 }, Scaler { data: -0.24240540168717986 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.5632522931602064 }, Scaler { data: -0.3369805070095678 }, Scaler { data: -0.48437222502193283 }, Scaler { data: 0.5978393659599175 }, Scaler { data: -0.12719638458546442 }, Scaler { data: 0.8007752321880588 }, Scaler { data: -0.058268800004753644 }, Scaler { data: 0.6137231139237024 }, Scaler { data: 0.29044461488702694 }, Scaler { data: -0.4077627264957298 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.04691200933188287 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.47770211930746226 }], shape: [] }
[INFO] [stdout] iter 70
[INFO] [stdout] w1: Tensor { data: [Scaler { data: 0.026518253687033946 }, Scaler { data: 0.9683898681037839 }, Scaler { data: 0.7542781376949004 }, Scaler { data: -0.27180509966852545 }, Scaler { data: 0.7680135767375643 }, Scaler { data: -0.49369401436616545 }, Scaler { data: 0.6873133331661584 }, Scaler { data: -0.28355321184018994 }, Scaler { data: -0.0897029671831935 }, Scaler { data: 0.9177951027286058 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.2087911432664654 }, Scaler { data: -0.25634448299509 }, Scaler { data: -0.22411486482827395 }, Scaler { data: -0.16972806117136324 }, Scaler { data: -0.2341736324425837 }, Scaler { data: -0.14040003131879533 }, Scaler { data: -0.22878078714852307 }, Scaler { data: -0.16905027425195845 }, Scaler { data: -0.1695087039704819 }, Scaler { data: -0.25366186310288247 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.5870509039449286 }, Scaler { data: -0.40673110291738895 }, Scaler { data: -0.5309561355923885 }, Scaler { data: 0.6532304817375205 }, Scaler { data: -0.17754866745199402 }, Scaler { data: 0.8779771607950501 }, Scaler { data: -0.10065004875373156 }, Scaler { data: 0.6702359372044837 }, Scaler { data: 0.328828507187767 }, Scaler { data: -0.4720228003215153 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: 0.020570470390133783 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.4469379800796181 }], shape: [] }
[INFO] [stdout] iter 80
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.031005526193669832 }, Scaler { data: 1.0074424081398774 }, Scaler { data: 0.8055230360525939 }, Scaler { data: -0.3344946636597885 }, Scaler { data: 0.7865069161739862 }, Scaler { data: -0.5743245076793796 }, Scaler { data: 0.6984686956350331 }, Scaler { data: -0.3477151494049332 }, Scaler { data: -0.12266732900667247 }, Scaler { data: 0.9630171993104621 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.1996349064091819 }, Scaler { data: -0.26755648041708263 }, Scaler { data: -0.23570875859503962 }, Scaler { data: -0.15633711016505666 }, Scaler { data: -0.23829259360433522 }, Scaler { data: -0.11764238665150632 }, Scaler { data: -0.23102814259093332 }, Scaler { data: -0.15513664572771294 }, Scaler { data: -0.1638259714059692 }, Scaler { data: -0.26579684981796753 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.6147137111998283 }, Scaler { data: -0.47387512778514385 }, Scaler { data: -0.5783371396150404 }, Scaler { data: 0.7099662771798086 }, Scaler { data: -0.2249752396628106 }, Scaler { data: 0.956255282260671 }, Scaler { data: -0.1400894566723936 }, Scaler { data: 0.7281350813837989 }, Scaler { data: 0.36714434665086165 }, Scaler { data: -0.534811701360237 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: 0.08236028250795875 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.4167006128608656 }], shape: [] }
[INFO] [stdout] iter 90
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.08477199245068362 }, Scaler { data: 1.047637368853534 }, Scaler { data: 0.8551906837279275 }, Scaler { data: -0.3948661617222867 }, Scaler { data: 0.8069168024809495 }, Scaler { data: -0.6518183484003564 }, Scaler { data: 0.7116877530264001 }, Scaler { data: -0.40946103942754974 }, Scaler { data: -0.15535411499614027 }, Scaler { data: 1.0084255601053935 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.19060325176291573 }, Scaler { data: -0.27943843862594886 }, Scaler { data: -0.24745882047696544 }, Scaler { data: -0.1424650317039351 }, Scaler { data: -0.24291285977647595 }, Scaler { data: -0.09379210016696475 }, Scaler { data: -0.23372090506951024 }, Scaler { data: -0.14070953798496483 }, Scaler { data: -0.15797635663302947 }, Scaler { data: -0.2784289586678462 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.6446900434506929 }, Scaler { data: -0.5373670022735232 }, Scaler { data: -0.6250342501073298 }, Scaler { data: 0.7664332353637759 }, Scaler { data: -0.2689846151469636 }, Scaler { data: 1.0335070801572446 }, Scaler { data: -0.1762884482426021 }, Scaler { data: 0.7857644858537838 }, Scaler { data: 0.40464385431356836 }, Scaler { data: -0.5948870910024052 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: 0.13831945706518156 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.3881726045270063 }], shape: [] }
[INFO] [stdout] 
[INFO] [stdout] thread 'step43' (1353) panicked at kdezero/tests/step_test.rs:846:59:
[INFO] [stdout] Failed to plot: BackendError(DrawingError(ImageError(IoError(Os { code: 2, kind: NotFound, message: "No such file or directory" }))))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62906c784141 - std[73adb7dc35730857]::backtrace_rs::backtrace::trace_unsynchronized::<std[73adb7dc35730857]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62906c784141 - std[73adb7dc35730857]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62906c784141 - <<std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6883ba1bc0fe4ed1]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62906c79982a - <core[6883ba1bc0fe4ed1]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62906c79982a - core[6883ba1bc0fe4ed1]::fmt::write
[INFO] [stdout]    6:     0x62906c78878c - std[73adb7dc35730857]::io::default_write_fmt::<alloc[55a36b64bcbf2c0d]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:626:11
[INFO] [stdout]    7:     0x62906c78878c - <alloc[55a36b64bcbf2c0d]::vec::Vec<u8> as std[73adb7dc35730857]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/io/mod.rs:1730:13
[INFO] [stdout]    8:     0x62906c761bc6 - <std[73adb7dc35730857]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62906c761bc6 - std[73adb7dc35730857]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62906c77c369 - std[73adb7dc35730857]::panicking::default_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x62906c6ac6a0 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   12:     0x62906c6ac6a0 - test[980ffaebd391d06d]::test_main_inner::<test[980ffaebd391d06d]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x62906c77c522 - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn for<'a, 'b> core[6883ba1bc0fe4ed1]::ops::function::Fn<(&'a std[73adb7dc35730857]::panic::PanicHookInfo<'b>,), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send + core[6883ba1bc0fe4ed1]::marker::Sync> as core[6883ba1bc0fe4ed1]::ops::function::Fn<(&std[73adb7dc35730857]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2333:9
[INFO] [stdout]   14:     0x62906c77c522 - std[73adb7dc35730857]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x62906c761c72 - std[73adb7dc35730857]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:688:13
[INFO] [stdout]   16:     0x62906c759489 - std[73adb7dc35730857]::sys::backtrace::__rust_end_short_backtrace::<std[73adb7dc35730857]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62906c76286d - __rustc[a7b7b02e776dd976]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x62906c79a01c - core[6883ba1bc0fe4ed1]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62906c799d62 - core[6883ba1bc0fe4ed1]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1870:5
[INFO] [stdout]   20:     0x62906c58689b - <core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::error::Error>>>::expect
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/result.rs:1183:23
[INFO] [stdout]   21:     0x62906c558b6d - step_test[a6d403c04bbae5c9]::step43
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:846:59
[INFO] [stdout]   22:     0x62906c548de7 - step_test[a6d403c04bbae5c9]::step43::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:714:12
[INFO] [stdout]   23:     0x62906c58d6d6 - <step_test[a6d403c04bbae5c9]::step43::{closure#0} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x62906c69f9db - <fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x62906c69f9db - test[980ffaebd391d06d]::__rust_begin_short_backtrace::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, fn() -> core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x62906c6ad025 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x62906c6ad025 - std[73adb7dc35730857]::panicking::catch_unwind::<core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>, core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x62906c6ad025 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<test[980ffaebd391d06d]::run_test_in_process::{closure#0}>, core[6883ba1bc0fe4ed1]::result::Result<(), alloc[55a36b64bcbf2c0d]::string::String>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test_in_process
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x62906c6ad025 - test[980ffaebd391d06d]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x62906c6a7ad4 - test[980ffaebd391d06d]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x62906c6a7ad4 - std[73adb7dc35730857]::sys::backtrace::__rust_begin_short_backtrace::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x62906c6b0172 - <core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::do_call::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x62906c6b0172 - std[73adb7dc35730857]::panicking::catch_unwind::<(), core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x62906c6b0172 - std[73adb7dc35730857]::panic::catch_unwind::<core[6883ba1bc0fe4ed1]::panic::unwind_safe::AssertUnwindSafe<std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x62906c6b0172 - std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked::<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x62906c6b0172 - <std[73adb7dc35730857]::thread::lifecycle::spawn_unchecked<test[980ffaebd391d06d]::run_test::{closure#1}, ()>::{closure#1} as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x62906c7839cf - <alloc[55a36b64bcbf2c0d]::boxed::Box<dyn core[6883ba1bc0fe4ed1]::ops::function::FnOnce<(), Output = ()> + core[6883ba1bc0fe4ed1]::marker::Send> as core[6883ba1bc0fe4ed1]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/alloc/src/boxed.rs:2319:9
[INFO] [stdout]   43:     0x62906c7839cf - <std[73adb7dc35730857]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/6b3fa26749ab40e159b2dd5cf577acaaf5902772/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x761f39b6aaa4 - <unknown>
[INFO] [stdout]   45:     0x761f39bf7a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     step26
[INFO] [stdout]     step35
[INFO] [stdout]     step42
[INFO] [stdout]     step43
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 16 passed; 4 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.40s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e", kill_on_drop: false }`
[INFO] [stdout] dc78d6cea1464e452cad95302bff464da79b0f1788f59a1d446dff1398fc9b8e
