[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 beta-2026-03-05 for beta-1.95-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkotabrog%2FRust_DeZero" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-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-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/kotabrog/Rust_DeZero on toolchain beta-2026-03-05
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-03-05" "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" "+beta-2026-03-05" "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-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-03-05" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 3614c9b822877e38ee73134fc5b805d2805647af3007ecff63d332a5392ad17c
[INFO] running `Command { std: "docker" "start" "-a" "3614c9b822877e38ee73134fc5b805d2805647af3007ecff63d332a5392ad17c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3614c9b822877e38ee73134fc5b805d2805647af3007ecff63d332a5392ad17c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3614c9b822877e38ee73134fc5b805d2805647af3007ecff63d332a5392ad17c", kill_on_drop: false }`
[INFO] [stdout] 3614c9b822877e38ee73134fc5b805d2805647af3007ecff63d332a5392ad17c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-03-05" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9205c1ce7dffd55f62f97d7e1de7fabab770efcb6f9ad72a6252651b65dbe92f
[INFO] running `Command { std: "docker" "start" "-a" "9205c1ce7dffd55f62f97d7e1de7fabab770efcb6f9ad72a6252651b65dbe92f", 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 3.85s
[INFO] running `Command { std: "docker" "inspect" "9205c1ce7dffd55f62f97d7e1de7fabab770efcb6f9ad72a6252651b65dbe92f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9205c1ce7dffd55f62f97d7e1de7fabab770efcb6f9ad72a6252651b65dbe92f", kill_on_drop: false }`
[INFO] [stdout] 9205c1ce7dffd55f62f97d7e1de7fabab770efcb6f9ad72a6252651b65dbe92f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-03-05" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7615e90d9a32f96f73a3aca402f5da646ef5fc900839d67053e7df0bce7182e5
[INFO] running `Command { std: "docker" "start" "-a" "7615e90d9a32f96f73a3aca402f5da646ef5fc900839d67053e7df0bce7182e5", 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 ucd-trie v0.1.5
[INFO] [stderr]    Compiling simd-adler32 v0.3.5
[INFO] [stderr]    Compiling libloading v0.8.0
[INFO] [stderr]    Compiling thiserror v1.0.40
[INFO] [stderr]    Compiling num-traits v0.2.15
[INFO] [stderr]    Compiling num-rational v0.4.1
[INFO] [stderr]    Compiling yeslogic-fontconfig-sys v3.2.0
[INFO] [stderr]    Compiling weezl v0.1.7
[INFO] [stderr]    Compiling font-kit v0.11.0
[INFO] [stderr]    Compiling freetype-sys v0.13.1
[INFO] [stderr]    Compiling plotters-backend v0.3.5
[INFO] [stderr]    Compiling log v0.4.19
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling const-cstr v0.3.0
[INFO] [stderr]    Compiling jpeg-decoder v0.3.0
[INFO] [stderr]    Compiling bytemuck v1.13.1
[INFO] [stderr]    Compiling once_cell v1.18.0
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling dlib v0.5.2
[INFO] [stderr]    Compiling miniz_oxide v0.7.1
[INFO] [stderr]    Compiling fdeflate v0.3.0
[INFO] [stderr]    Compiling walkdir v2.3.3
[INFO] [stderr]    Compiling time v0.1.45
[INFO] [stderr]    Compiling float-ord v0.2.0
[INFO] [stderr]    Compiling iana-time-zone v0.1.57
[INFO] [stderr]    Compiling gif v0.12.0
[INFO] [stderr]    Compiling ttf-parser v0.17.1
[INFO] [stderr]    Compiling freetype v0.7.0
[INFO] [stderr]    Compiling flate2 v1.0.26
[INFO] [stderr]    Compiling plotters-svg v0.3.5
[INFO] [stderr]    Compiling quote v1.0.28
[INFO] [stderr]    Compiling syn v2.0.22
[INFO] [stderr]    Compiling png v0.17.9
[INFO] [stderr]    Compiling num-integer v0.1.45
[INFO] [stderr]    Compiling chrono v0.4.26
[INFO] [stderr]    Compiling image v0.24.6
[INFO] [stderr]    Compiling thiserror-impl v1.0.40
[INFO] [stderr]    Compiling plotters-bitmap v0.3.3
[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 kdezero v0.1.0 (/opt/rustwide/workdir/kdezero)
[INFO] [stderr]    Compiling ktensor v0.1.0 (/opt/rustwide/workdir/ktensor)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 21.80s
[INFO] running `Command { std: "docker" "inspect" "7615e90d9a32f96f73a3aca402f5da646ef5fc900839d67053e7df0bce7182e5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7615e90d9a32f96f73a3aca402f5da646ef5fc900839d67053e7df0bce7182e5", kill_on_drop: false }`
[INFO] [stdout] 7615e90d9a32f96f73a3aca402f5da646ef5fc900839d67053e7df0bce7182e5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-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" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-03-05" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d32dd131298dbaf4d0e0391904d9b744f1283627197ca29b54a9ee3a79283444
[INFO] running `Command { std: "docker" "start" "-a" "d32dd131298dbaf4d0e0391904d9b744f1283627197ca29b54a9ee3a79283444", 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.12s
[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::backward_normal ... ok
[INFO] [stdout] test function::function::linear::tests::forward_add_b ... ok
[INFO] [stdout] test function::function::sigmoid::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::add::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::add::tests::forward_normal ... ok
[INFO] [stdout] test function::function::linear::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::operator::cos::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::div::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::div::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::exp::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::exp::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::div::tests::backward_x_2 ... ok
[INFO] [stdout] test function::operator::broadcast_to::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::matmul::tests::backward_backward_x_2 ... ok
[INFO] [stdout] test function::operator::exp::tests::forward_normal ... 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::forward_normal ... ok
[INFO] [stdout] test function::operator::div::tests::backward_backward_x_2 ... ok
[INFO] [stdout] test function::operator::mul::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::mean_squared_error::tests::backward_backward_x_y ... ok
[INFO] [stdout] test function::operator::cos::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::neg::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::mul::tests::backward_backward_x_2 ... ok
[INFO] [stdout] test function::operator::matmul::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::pow::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::pow::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::reshape::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::reshape::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sin::tests::backward_backward_normal ... ok
[INFO] [stdout] test function::operator::sin::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::cos::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::mul::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sin::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::pow::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::matmul::tests::forward_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::square::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sub::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::sub::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sum::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::sum::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::tanh::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::tanh::tests::backward_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::sum_to::tests::backward_normal ... ok
[INFO] [stdout] test function::operator::sum_to::tests::forward_normal ... ok
[INFO] [stdout] test function::operator::sum::tests::backward_reshape ... ok
[INFO] [stdout] test function::operator::neg::tests::forward_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::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::matmul::tests::backward_backward_x_y - 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::transpose::tests::backward_backward_normal - should panic ... ok
[INFO] [stdout] test function::operator::sum_to::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.07s
[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 step1 ... ok
[INFO] [stdout] test step13 ... ok
[INFO] [stdout] test step16 ... ok
[INFO] [stdout] test step18_2 ... ok
[INFO] [stdout] test step18_1 ... ok
[INFO] [stdout] test step19 ... ok
[INFO] [stdout] test step20 ... ok
[INFO] [stdout] test step2 ... ok
[INFO] [stdout] test step3 ... ok
[INFO] [stdout] test step14 ... ok
[INFO] [stdout] test step27 ... ok
[INFO] [stdout] test step4 ... ok
[INFO] [stdout] test step36 ... ok
[INFO] [stdout] test step33 ... ok
[INFO] [stdout] test step7 ... ok
[INFO] [stdout] test step26 ... FAILED
[INFO] [stdout] test step35 ... FAILED
[INFO] [stdout] test step11 ... ok
[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' (101) 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:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::trace_unsynchronized::<std[b80a194dd3c418bb]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5ab43258be1a - std[b80a194dd3c418bb]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5ab43258be1a - <<std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[10b6fa85044e1869]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5ab4325a118a - <core[10b6fa85044e1869]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5ab4325a118a - core[10b6fa85044e1869]::fmt::write
[INFO] [stdout]    6:     0x5ab432590802 - std[b80a194dd3c418bb]::io::default_write_fmt::<alloc[dd269455e567d8e9]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5ab432590802 - <alloc[dd269455e567d8e9]::vec::Vec<u8> as std[b80a194dd3c418bb]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5ab43256a14f - <std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5ab43256a14f - std[b80a194dd3c418bb]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5ab432584389 - std[b80a194dd3c418bb]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5ab4324b604c - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5ab4324b604c - test[826cbc6ef54ab466]::test_main_with_exit_callback::<test[826cbc6ef54ab466]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5ab432584542 - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5ab432584542 - std[b80a194dd3c418bb]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5ab43256a208 - std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5ab432561a99 - std[b80a194dd3c418bb]::sys::backtrace::__rust_end_short_backtrace::<std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5ab43256af1d - __rustc[9698a3e60dd14283]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5ab4325a1a5c - core[10b6fa85044e1869]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5ab4325a17a2 - core[10b6fa85044e1869]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5ab432507a1e - core::result::Result<T,E>::expect::h722b24bf0e95352b
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5ab43250e911 - kdezero::variable::variable_table::VariableTable::plot_dot_graph::hbdf61bcec761b67d
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/src/variable/variable_table.rs:362:52
[INFO] [stdout]   22:     0x5ab43236a655 - step_test::step26::h3875974a52c5f790
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:431:20
[INFO] [stdout]   23:     0x5ab43236a747 - step_test::step26::{{closure}}::hbc85917f6a853e6e
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:401:12
[INFO] [stdout]   24:     0x5ab4323a0e86 - core::ops::function::FnOnce::call_once::h43f776f9d37aaeca
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5ab4324aa12b - <fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5ab4324aa12b - test[826cbc6ef54ab466]::__rust_begin_short_backtrace::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:663:18
[INFO] [stdout]   27:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:74
[INFO] [stdout]   28:     0x5ab4324b6b1b - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   29:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   30:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:27
[INFO] [stdout]   33:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:607:43
[INFO] [stdout]   34:     0x5ab4324b2234 - test[826cbc6ef54ab466]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:637:41
[INFO] [stdout]   35:     0x5ab4324b2234 - std[b80a194dd3c418bb]::sys::backtrace::__rust_begin_short_backtrace::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   37:     0x5ab4324b9722 - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   38:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::<(), core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   42:     0x5ab4324b9722 - <std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1} as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5ab43258b72f - <alloc[dd269455e567d8e9]::boxed::Box<dyn core[10b6fa85044e1869]::ops::function::FnOnce<(), Output = ()> + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   44:     0x5ab43258b72f - <std[b80a194dd3c418bb]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   45:     0x73217563baa4 - <unknown>
[INFO] [stdout]   46:     0x7321756c8a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- step35 stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'step35' (105) 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:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::trace_unsynchronized::<std[b80a194dd3c418bb]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5ab43258be1a - std[b80a194dd3c418bb]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5ab43258be1a - <<std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[10b6fa85044e1869]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5ab4325a118a - <core[10b6fa85044e1869]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5ab4325a118a - core[10b6fa85044e1869]::fmt::write
[INFO] [stdout]    6:     0x5ab432590802 - std[b80a194dd3c418bb]::io::default_write_fmt::<alloc[dd269455e567d8e9]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5ab432590802 - <alloc[dd269455e567d8e9]::vec::Vec<u8> as std[b80a194dd3c418bb]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5ab43256a14f - <std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5ab43256a14f - std[b80a194dd3c418bb]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5ab432584389 - std[b80a194dd3c418bb]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5ab4324b604c - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5ab4324b604c - test[826cbc6ef54ab466]::test_main_with_exit_callback::<test[826cbc6ef54ab466]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5ab432584542 - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5ab432584542 - std[b80a194dd3c418bb]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5ab43256a208 - std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5ab432561a99 - std[b80a194dd3c418bb]::sys::backtrace::__rust_end_short_backtrace::<std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5ab43256af1d - __rustc[9698a3e60dd14283]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5ab4325a1a5c - core[10b6fa85044e1869]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5ab4325a17a2 - core[10b6fa85044e1869]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5ab432507a1e - core::result::Result<T,E>::expect::h722b24bf0e95352b
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5ab43250e911 - kdezero::variable::variable_table::VariableTable::plot_dot_graph::hbdf61bcec761b67d
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/src/variable/variable_table.rs:362:52
[INFO] [stdout]   22:     0x5ab43236d83d - step_test::step35::h2d65192a02aa575f
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:552:20
[INFO] [stdout]   23:     0x5ab43236da87 - step_test::step35::{{closure}}::h23c44538f8205e35
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:517:12
[INFO] [stdout]   24:     0x5ab4323a1176 - core::ops::function::FnOnce::call_once::hfa2dd28faa009113
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5ab4324aa12b - <fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x5ab4324aa12b - test[826cbc6ef54ab466]::__rust_begin_short_backtrace::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:663:18
[INFO] [stdout]   27:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:74
[INFO] [stdout]   28:     0x5ab4324b6b1b - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   29:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   30:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   31:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   32:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:27
[INFO] [stdout]   33:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:607:43
[INFO] [stdout]   34:     0x5ab4324b2234 - test[826cbc6ef54ab466]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:637:41
[INFO] [stdout]   35:     0x5ab4324b2234 - std[b80a194dd3c418bb]::sys::backtrace::__rust_begin_short_backtrace::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   36:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   37:     0x5ab4324b9722 - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   38:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::<(), core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   42:     0x5ab4324b9722 - <std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1} as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x5ab43258b72f - <alloc[dd269455e567d8e9]::boxed::Box<dyn core[10b6fa85044e1869]::ops::function::FnOnce<(), Output = ()> + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   44:     0x5ab43258b72f - <std[b80a194dd3c418bb]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   45:     0x73217563baa4 - <unknown>
[INFO] [stdout]   46:     0x7321756c8a64 - clone
[INFO] [stdout]   47:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- step42 stdout ----
[INFO] [stdout] iter 0 w: Tensor { data: [Scaler { data: 0.6830506193717326 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 1.3024982482798249 }], shape: [] }, loss: Tensor { data: [Scaler { data: 42.87238390301085 }], shape: [] }
[INFO] [stdout] iter 10 w: Tensor { data: [Scaler { data: 2.523656236420732 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 4.973822856102928 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.2571194580618543 }], shape: [] }
[INFO] [stdout] iter 20 w: Tensor { data: [Scaler { data: 2.5460621334674793 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.213349197662121 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.12388542828314066 }], shape: [] }
[INFO] [stdout] iter 30 w: Tensor { data: [Scaler { data: 2.4804605391548233 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.2620023609160125 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.11684313342771885 }], shape: [] }
[INFO] [stdout] iter 40 w: Tensor { data: [Scaler { data: 2.4189710558291178 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.295603786343523 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.11181798130787081 }], shape: [] }
[INFO] [stdout] iter 50 w: Tensor { data: [Scaler { data: 2.3653938431393375 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.324281506060614 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.108038925210788 }], shape: [] }
[INFO] [stdout] iter 60 w: Tensor { data: [Scaler { data: 2.318913631100641 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.34912822294917 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.10519637379512478 }], shape: [] }
[INFO] [stdout] iter 70 w: Tensor { data: [Scaler { data: 2.278601238245303 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.370676093956836 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.10305824593885352 }], shape: [] }
[INFO] [stdout] iter 80 w: Tensor { data: [Scaler { data: 2.243638798092361 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.38936420205054 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.10144997573519884 }], shape: [] }
[INFO] [stdout] iter 90 w: Tensor { data: [Scaler { data: 2.2133163376365945 }], shape: [1, 1] }, b: Tensor { data: [Scaler { data: 5.405572145156038 }], shape: [] }, loss: Tensor { data: [Scaler { data: 0.10024025713001418 }], shape: [] }
[INFO] [stdout] 
[INFO] [stdout] thread 'step42' (108) 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:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::trace_unsynchronized::<std[b80a194dd3c418bb]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5ab43258be1a - std[b80a194dd3c418bb]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5ab43258be1a - <<std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[10b6fa85044e1869]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5ab4325a118a - <core[10b6fa85044e1869]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5ab4325a118a - core[10b6fa85044e1869]::fmt::write
[INFO] [stdout]    6:     0x5ab432590802 - std[b80a194dd3c418bb]::io::default_write_fmt::<alloc[dd269455e567d8e9]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5ab432590802 - <alloc[dd269455e567d8e9]::vec::Vec<u8> as std[b80a194dd3c418bb]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5ab43256a14f - <std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5ab43256a14f - std[b80a194dd3c418bb]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5ab432584389 - std[b80a194dd3c418bb]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5ab4324b604c - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5ab4324b604c - test[826cbc6ef54ab466]::test_main_with_exit_callback::<test[826cbc6ef54ab466]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5ab432584542 - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5ab432584542 - std[b80a194dd3c418bb]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5ab43256a208 - std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5ab432561a99 - std[b80a194dd3c418bb]::sys::backtrace::__rust_end_short_backtrace::<std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5ab43256af1d - __rustc[9698a3e60dd14283]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5ab4325a1a5c - core[10b6fa85044e1869]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5ab4325a17a2 - core[10b6fa85044e1869]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5ab4323806cb - core::result::Result<T,E>::expect::hc46b9baeaa640f92
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5ab43236f860 - step_test::step42::h173a167cb5676ceb
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:710:65
[INFO] [stdout]   22:     0x5ab432370557 - step_test::step42::{{closure}}::h534ffed3e8838751
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:590:12
[INFO] [stdout]   23:     0x5ab4323a1136 - core::ops::function::FnOnce::call_once::hee4af9e4ae9ca150
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5ab4324aa12b - <fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5ab4324aa12b - test[826cbc6ef54ab466]::__rust_begin_short_backtrace::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x5ab4324b6b1b - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   28:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x5ab4324b2234 - test[826cbc6ef54ab466]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x5ab4324b2234 - std[b80a194dd3c418bb]::sys::backtrace::__rust_begin_short_backtrace::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   36:     0x5ab4324b9722 - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   37:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::<(), core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   41:     0x5ab4324b9722 - <std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1} as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5ab43258b72f - <alloc[dd269455e567d8e9]::boxed::Box<dyn core[10b6fa85044e1869]::ops::function::FnOnce<(), Output = ()> + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   43:     0x5ab43258b72f - <std[b80a194dd3c418bb]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x73217563baa4 - <unknown>
[INFO] [stdout]   45:     0x7321756c8a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- step43 stdout ----
[INFO] [stdout] iter 0
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.009958594946754051 }, Scaler { data: 0.22985740247470424 }, Scaler { data: 0.44970252160669055 }, Scaler { data: 0.10877662319418538 }, Scaler { data: 0.37441135945756765 }, Scaler { data: 0.7499944895032319 }, Scaler { data: 0.20837004057592934 }, Scaler { data: 0.19338291213758524 }, Scaler { data: 0.5645620517105895 }, Scaler { data: 0.792245766117074 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.20175382715306248 }, Scaler { data: -0.08923911885585398 }, Scaler { data: -0.06333383783957858 }, Scaler { data: -0.12107527935254075 }, Scaler { data: -0.07786230917372705 }, Scaler { data: -0.04192214592229967 }, Scaler { data: -0.21220477097827298 }, Scaler { data: -0.1817334589973815 }, Scaler { data: -0.05571134743912679 }, Scaler { data: -0.0716882511538177 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.46791172333795833 }, Scaler { data: -0.06338129331546904 }, Scaler { data: -0.20202186061674476 }, Scaler { data: 0.0924695946604579 }, Scaler { data: -0.12860658101149536 }, Scaler { data: -0.3279009486621377 }, Scaler { data: 0.4975965616089851 }, Scaler { data: 0.35972116686662337 }, Scaler { data: -0.24737627292340747 }, Scaler { data: -0.18832680593505302 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.8787483401665761 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 5.555991680123952 }], shape: [] }
[INFO] [stdout] iter 10
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.08749959343581097 }, Scaler { data: 0.20148622462764215 }, Scaler { data: 0.4347672469595783 }, Scaler { data: 0.06568095150773587 }, Scaler { data: 0.35252160129752036 }, Scaler { data: 0.7466433659792803 }, Scaler { data: 0.1276679356070986 }, Scaler { data: 0.12552813057975892 }, Scaler { data: 0.5539987846378902 }, Scaler { data: 0.777143673571177 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.15987142379069932 }, Scaler { data: -0.1103732746349904 }, Scaler { data: -0.10140333925403526 }, Scaler { data: -0.12357463033223376 }, Scaler { data: -0.1070127969029084 }, Scaler { data: -0.09662938814107257 }, Scaler { data: -0.16836807915977425 }, Scaler { data: -0.15362452926012568 }, Scaler { data: -0.0995966528728925 }, Scaler { data: -0.10871581559126073 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.6776154435904229 }, Scaler { data: 0.13882537472011597 }, Scaler { data: -0.01735274822692797 }, Scaler { data: 0.30197104097166105 }, Scaler { data: 0.06121060181186977 }, Scaler { data: -0.1679379476432831 }, Scaler { data: 0.6846025164242813 }, Scaler { data: 0.5524571778903389 }, Scaler { data: -0.07246150260978722 }, Scaler { data: -0.03646010052254619 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.41228372011772 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5858546767728084 }], shape: [] }
[INFO] [stdout] iter 20
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.18168354473341197 }, Scaler { data: 0.18191033845539567 }, Scaler { data: 0.4385339674948776 }, Scaler { data: 0.022594951806840572 }, Scaler { data: 0.3449553410014646 }, Scaler { data: 0.7715311925404056 }, Scaler { data: 0.03262369972042809 }, Scaler { data: 0.04853393954916236 }, Scaler { data: 0.5659208129202445 }, Scaler { data: 0.785624293699395 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.14560938504931917 }, Scaler { data: -0.10775752081961536 }, Scaler { data: -0.10201506144624074 }, Scaler { data: -0.11785803875707106 }, Scaler { data: -0.10589078819642816 }, Scaler { data: -0.10301379624039493 }, Scaler { data: -0.15604908194210493 }, Scaler { data: -0.14361558958651374 }, Scaler { data: -0.10187149701623004 }, Scaler { data: -0.11092234174560985 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.7294203550292593 }, Scaler { data: 0.14748585197423 }, Scaler { data: -0.04167325078745637 }, Scaler { data: 0.3304685202771809 }, Scaler { data: 0.0485817723779821 }, Scaler { data: -0.23417016337110771 }, Scaler { data: 0.7070714148148581 }, Scaler { data: 0.5743495993652558 }, Scaler { data: -0.11311236007728559 }, Scaler { data: -0.10593397250837722 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.3380460128701423 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5612975117214193 }], shape: [] }
[INFO] [stdout] iter 30
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.2773610354746451 }, Scaler { data: 0.16213331687466112 }, Scaler { data: 0.44533177504197047 }, Scaler { data: -0.02219723034045369 }, Scaler { data: 0.33942288003956217 }, Scaler { data: 0.8031888350765399 }, Scaler { data: -0.06087830709476701 }, Scaler { data: -0.027793937072872928 }, Scaler { data: 0.5824701437092931 }, Scaler { data: 0.8020183001635806 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.13039845041872622 }, Scaler { data: -0.10528647235333846 }, Scaler { data: -0.1030707159622265 }, Scaler { data: -0.11217160595586204 }, Scaler { data: -0.10512030295012267 }, Scaler { data: -0.11109766104585139 }, Scaler { data: -0.14407390692608663 }, Scaler { data: -0.1339875529030641 }, Scaler { data: -0.10493734781279662 }, Scaler { data: -0.11510499034259689 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.7891410325006867 }, Scaler { data: 0.15632170874360743 }, Scaler { data: -0.06753892982782803 }, Scaler { data: 0.36140367516954747 }, Scaler { data: 0.03540755379514501 }, Scaler { data: -0.3028608414683873 }, Scaler { data: 0.7389681944574981 }, Scaler { data: 0.6033648312710376 }, Scaler { data: -0.15568083383949252 }, Scaler { data: -0.17567523128498333 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.2716296158938847 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5356464323010514 }], shape: [] }
[INFO] [stdout] iter 40
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.37375059709693637 }, Scaler { data: 0.14236770794018094 }, Scaler { data: 0.45491143111218474 }, Scaler { data: -0.06824194524068805 }, Scaler { data: 0.3358209303001876 }, Scaler { data: 0.8406609130481155 }, Scaler { data: -0.15270110230736986 }, Scaler { data: -0.10328913907480819 }, Scaler { data: 0.6031303837035136 }, Scaler { data: 0.8252667286858392 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.11381327992372538 }, Scaler { data: -0.1029466706391471 }, Scaler { data: -0.10450995396087716 }, Scaler { data: -0.10645963056420149 }, Scaler { data: -0.10464294558487626 }, Scaler { data: -0.12077508854750324 }, Scaler { data: -0.13196681662053888 }, Scaler { data: -0.12443569272851131 }, Scaler { data: -0.10870963152216927 }, Scaler { data: -0.12100632048957595 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.8557185987869054 }, Scaler { data: 0.16567607774085952 }, Scaler { data: -0.09437523656297947 }, Scaler { data: 0.39477588297581545 }, Scaler { data: 0.02211536699837147 }, Scaler { data: -0.3732081520986739 }, Scaler { data: 0.7791149540585152 }, Scaler { data: 0.6387331929059632 }, Scaler { data: -0.19952337477059676 }, Scaler { data: -0.2452384465397315 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.2120669670415372 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.5088343184045258 }], shape: [] }
[INFO] [stdout] iter 50
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.4696187368925667 }, Scaler { data: 0.12285074383182634 }, Scaler { data: 0.46686581310259956 }, Scaler { data: -0.11489486521946975 }, Scaler { data: 0.33396950594827757 }, Scaler { data: 0.8826820340379449 }, Scaler { data: -0.24225624466173076 }, Scaler { data: -0.177417769706134 }, Scaler { data: 0.6271614335972496 }, Scaler { data: 0.8540661324679146 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.09562164126557017 }, Scaler { data: -0.10073581652111124 }, Scaler { data: -0.10626603546761276 }, Scaler { data: -0.10070353362832699 }, Scaler { data: -0.10440714225979152 }, Scaler { data: -0.13188656471981416 }, Scaler { data: -0.11942575054210873 }, Scaler { data: -0.11477057555155315 }, Scaler { data: -0.11308816108406873 }, Scaler { data: -0.12837481629210762 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 0.9274994613612185 }, Scaler { data: 0.17561193126265157 }, Scaler { data: -0.12166752194521609 }, Scaler { data: 0.43015790952104105 }, Scaler { data: 0.008992038459187415 }, Scaler { data: -0.44422957143994185 }, Scaler { data: 0.825875517363716 }, Scaler { data: 0.6792525072622508 }, Scaler { data: -0.24395673721666966 }, Scaler { data: -0.3139993743023197 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.15882579678097214 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.48124247736492015 }], shape: [] }
[INFO] [stdout] iter 60
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.5633642361491098 }, Scaler { data: 0.10385647037677032 }, Scaler { data: 0.48064120487750933 }, Scaler { data: -0.16131984156136897 }, Scaler { data: 0.3336207729721329 }, Scaler { data: 0.9277118176688302 }, Scaler { data: -0.32856131217007833 }, Scaler { data: -0.24931160064195856 }, Scaler { data: 0.653615032253977 }, Scaler { data: 0.8868890145747047 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.07581351020404974 }, Scaler { data: -0.09865652871835945 }, Scaler { data: -0.10826452068790897 }, Scaler { data: -0.09491299632246508 }, Scaler { data: -0.10436383622610274 }, Scaler { data: -0.14419573352693135 }, Scaler { data: -0.10630552903629809 }, Scaler { data: -0.10489836025757394 }, Scaler { data: -0.1179508640626656 }, Scaler { data: -0.13693243347651526 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 1.002394979319618 }, Scaler { data: 0.1860436385661285 }, Scaler { data: -0.14883474167823388 }, Scaler { data: 0.4668289983239086 }, Scaler { data: -0.003688929093423602 }, Scaler { data: -0.5146607644597565 }, Scaler { data: 0.8772827517479355 }, Scaler { data: 0.7234109151129079 }, Scaler { data: -0.2881392207951856 }, Scaler { data: -0.3810571490767376 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.11151262907149766 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.45366090738937354 }], shape: [] }
[INFO] [stdout] iter 70
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.6532004700967978 }, Scaler { data: 0.0856875366418131 }, Scaler { data: 0.4955746291351348 }, Scaler { data: -0.20654460364086383 }, Scaler { data: 0.33447532527934276 }, Scaler { data: 0.9740399852956776 }, Scaler { data: -0.4103880113135006 }, Scaler { data: -0.3178793750862978 }, Scaler { data: 0.6813956216335892 }, Scaler { data: 0.9220659956269547 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.05464948226062699 }, Scaler { data: -0.09671730772338165 }, Scaler { data: -0.110422413973553 }, Scaler { data: -0.0891343256729527 }, Scaler { data: -0.10446638896402571 }, Scaler { data: -0.15737073015169648 }, Scaler { data: -0.09264276901953365 }, Scaler { data: -0.09483650948815027 }, Scaler { data: -0.12314735319311843 }, Scaler { data: -0.1463535369743048 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 1.0780234896340528 }, Scaler { data: 0.19675441637394378 }, Scaler { data: -0.1752636331161936 }, Scaler { data: 0.5038317867232724 }, Scaler { data: -0.015681346199924184 }, Scaler { data: -0.5830623032099185 }, Scaler { data: 0.9311333937113853 }, Scaler { data: 0.7694611803690367 }, Scaler { data: -0.3311317221750499 }, Scaler { data: -0.44533306292784974 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.06983226564657877 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.4271537475294595 }], shape: [] }
[INFO] [stdout] iter 80
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.7373848704528647 }, Scaler { data: 0.06865164200972178 }, Scaler { data: 0.5109556893708672 }, Scaler { data: -0.24955963609254067 }, Scaler { data: 0.33620775875090664 }, Scaler { data: 1.0199434856334737 }, Scaler { data: -0.4864613384978222 }, Scaler { data: -0.38196844088500287 }, Scaler { data: 0.7093601890568302 }, Scaler { data: 0.9579186359864047 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.03265154515287664 }, Scaler { data: -0.09493128078494202 }, Scaler { data: -0.11265057889298262 }, Scaler { data: -0.08345136099232832 }, Scaler { data: -0.10467110111895345 }, Scaler { data: -0.17099260860550838 }, Scaler { data: -0.07865443060997529 }, Scaler { data: -0.08471444452735613 }, Scaler { data: -0.128501407829261 }, Scaler { data: -0.1562653441320727 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 1.1519394908899634 }, Scaler { data: 0.20743700823224734 }, Scaler { data: -0.200357728327932 }, Scaler { data: 0.5400829904331982 }, Scaler { data: -0.02677949550931681 }, Scaler { data: -0.6479728122065871 }, Scaler { data: 0.9851675856013931 }, Scaler { data: 0.8155701222277799 }, Scaler { data: -0.37198779253082587 }, Scaler { data: -0.5057071403853909 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.033533894264433796 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.40283707318216033 }], shape: [] }
[INFO] [stdout] iter 90
[INFO] [stdout] w1: Tensor { data: [Scaler { data: -0.8144460647930479 }, Scaler { data: 0.053027336161581645 }, Scaler { data: 0.5261016160099067 }, Scaler { data: -0.2894404706878548 }, Scaler { data: 0.3384969715640374 }, Scaler { data: 1.063861542149619 }, Scaler { data: -0.5556690668652401 }, Scaler { data: -0.4405448697270619 }, Scaler { data: 0.7364371501291683 }, Scaler { data: 0.9929132959428333 }], shape: [1, 10] }
[INFO] [stdout] b1: Tensor { data: [Scaler { data: -0.010524755734123432 }, Scaler { data: -0.09331303461191712 }, Scaler { data: -0.11485980021314234 }, Scaler { data: -0.07797598959281002 }, Scaler { data: -0.10493843568767762 }, Scaler { data: -0.18459392228371488 }, Scaler { data: -0.06469700423616839 }, Scaler { data: -0.07475020149782369 }, Scaler { data: -0.13382392710204843 }, Scaler { data: -0.16627197049440584 }], shape: [10] }
[INFO] [stdout] w2: Tensor { data: [Scaler { data: 1.2219005964443344 }, Scaler { data: 0.2177512076550448 }, Scaler { data: -0.22359260334759523 }, Scaler { data: 0.5745177484550135 }, Scaler { data: -0.0368283142916273 }, Scaler { data: -0.7080805465816687 }, Scaler { data: 1.0372918261128723 }, Scaler { data: 0.8600105131859475 }, Scaler { data: -0.4098567988476906 }, Scaler { data: -0.5611696095917659 }], shape: [10, 1] }
[INFO] [stdout] b2: Tensor { data: [Scaler { data: -0.002354235479793108 }], shape: [1] }
[INFO] [stdout] loss: Tensor { data: [Scaler { data: 0.38163146513910035 }], shape: [] }
[INFO] [stdout] 
[INFO] [stdout] thread 'step43' (109) 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:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5ab43258be1a - std[b80a194dd3c418bb]::backtrace_rs::backtrace::trace_unsynchronized::<std[b80a194dd3c418bb]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5ab43258be1a - std[b80a194dd3c418bb]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5ab43258be1a - <<std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[10b6fa85044e1869]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5ab4325a118a - <core[10b6fa85044e1869]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5ab4325a118a - core[10b6fa85044e1869]::fmt::write
[INFO] [stdout]    6:     0x5ab432590802 - std[b80a194dd3c418bb]::io::default_write_fmt::<alloc[dd269455e567d8e9]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5ab432590802 - <alloc[dd269455e567d8e9]::vec::Vec<u8> as std[b80a194dd3c418bb]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5ab43256a14f - <std[b80a194dd3c418bb]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5ab43256a14f - std[b80a194dd3c418bb]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5ab432584389 - std[b80a194dd3c418bb]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5ab4324b604c - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5ab4324b604c - test[826cbc6ef54ab466]::test_main_with_exit_callback::<test[826cbc6ef54ab466]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5ab432584542 - <alloc[dd269455e567d8e9]::boxed::Box<dyn for<'a, 'b> core[10b6fa85044e1869]::ops::function::Fn<(&'a std[b80a194dd3c418bb]::panic::PanicHookInfo<'b>,), Output = ()> + core[10b6fa85044e1869]::marker::Sync + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::Fn<(&std[b80a194dd3c418bb]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5ab432584542 - std[b80a194dd3c418bb]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5ab43256a208 - std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5ab432561a99 - std[b80a194dd3c418bb]::sys::backtrace::__rust_end_short_backtrace::<std[b80a194dd3c418bb]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5ab43256af1d - __rustc[9698a3e60dd14283]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5ab4325a1a5c - core[10b6fa85044e1869]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5ab4325a17a2 - core[10b6fa85044e1869]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5ab4323806cb - core::result::Result<T,E>::expect::hc46b9baeaa640f92
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5ab43237350d - step_test::step43::h5877a383cc839027
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:846:59
[INFO] [stdout]   22:     0x5ab4323744f7 - step_test::step43::{{closure}}::h0fa8796b75280c09
[INFO] [stdout]                                at /opt/rustwide/workdir/kdezero/tests/step_test.rs:714:12
[INFO] [stdout]   23:     0x5ab4323a0e06 - core::ops::function::FnOnce::call_once::h39b0d62d75fdf144
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5ab4324aa12b - <fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5ab4324aa12b - test[826cbc6ef54ab466]::__rust_begin_short_backtrace::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, fn() -> core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x5ab4324b6b1b - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   28:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panicking::catch_unwind::<core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>, core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5ab4324b6b1b - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<test[826cbc6ef54ab466]::run_test_in_process::{closure#0}>, core[10b6fa85044e1869]::result::Result<(), alloc[dd269455e567d8e9]::string::String>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test_in_process
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x5ab4324b6b1b - test[826cbc6ef54ab466]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x5ab4324b2234 - test[826cbc6ef54ab466]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x5ab4324b2234 - std[b80a194dd3c418bb]::sys::backtrace::__rust_begin_short_backtrace::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   36:     0x5ab4324b9722 - <core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   37:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::do_call::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panicking::catch_unwind::<(), core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5ab4324b9722 - std[b80a194dd3c418bb]::panic::catch_unwind::<core[10b6fa85044e1869]::panic::unwind_safe::AssertUnwindSafe<std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5ab4324b9722 - std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked::<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   41:     0x5ab4324b9722 - <std[b80a194dd3c418bb]::thread::lifecycle::spawn_unchecked<test[826cbc6ef54ab466]::run_test::{closure#1}, ()>::{closure#1} as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5ab43258b72f - <alloc[dd269455e567d8e9]::boxed::Box<dyn core[10b6fa85044e1869]::ops::function::FnOnce<(), Output = ()> + core[10b6fa85044e1869]::marker::Send> as core[10b6fa85044e1869]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   43:     0x5ab43258b72f - <std[b80a194dd3c418bb]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ad726b5063362ec9897ef3d67452fc5606ee70fa/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x73217563baa4 - <unknown>
[INFO] [stdout]   45:     0x7321756c8a64 - 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.39s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p kdezero --test step_test`
[INFO] running `Command { std: "docker" "inspect" "d32dd131298dbaf4d0e0391904d9b744f1283627197ca29b54a9ee3a79283444", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d32dd131298dbaf4d0e0391904d9b744f1283627197ca29b54a9ee3a79283444", kill_on_drop: false }`
[INFO] [stdout] d32dd131298dbaf4d0e0391904d9b744f1283627197ca29b54a9ee3a79283444
