[INFO] fetching crate senl 0.4.0...
[INFO] testing senl-0.4.0 against beta-2026-04-21 for beta-1.96-2
[INFO] extracting crate senl 0.4.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate senl 0.4.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate senl 0.4.0
[INFO] tweaked toml for crates.io crate senl 0.4.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate senl 0.4.0 on toolchain beta-2026-04-21
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-04-21" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate senl 0.4.0 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-04-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[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-04-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 696a30c05e372a7d6f4697148a3aa041f31e904b948f79465dc2dee68a39851d
[INFO] running `Command { std: "docker" "start" "-a" "696a30c05e372a7d6f4697148a3aa041f31e904b948f79465dc2dee68a39851d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "696a30c05e372a7d6f4697148a3aa041f31e904b948f79465dc2dee68a39851d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "696a30c05e372a7d6f4697148a3aa041f31e904b948f79465dc2dee68a39851d", kill_on_drop: false }`
[INFO] [stdout] 696a30c05e372a7d6f4697148a3aa041f31e904b948f79465dc2dee68a39851d
[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-04-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 327dc7bf44275421e74f448630133dc786dfc2a8f6c8554c2d69196c623740f4
[INFO] running `Command { std: "docker" "start" "-a" "327dc7bf44275421e74f448630133dc786dfc2a8f6c8554c2d69196c623740f4", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling bitflags v2.10.0
[INFO] [stderr]    Compiling env_home v0.1.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling typeid v1.0.3
[INFO] [stderr]    Compiling zmij v1.0.19
[INFO] [stderr]    Compiling lua-src v547.0.0
[INFO] [stderr]    Compiling erased-serde v0.4.9
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling ordered-float v2.10.1
[INFO] [stderr]    Compiling clap_builder v4.5.57
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling serde-value v0.7.0
[INFO] [stderr]    Compiling which v7.0.3
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling luajit-src v210.5.12+a4f56a4
[INFO] [stderr]    Compiling mlua-sys v0.6.8
[INFO] [stderr]    Compiling clap v4.5.57
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling mlua v0.10.5
[INFO] [stderr]    Compiling mlua-pkg v0.1.0
[INFO] [stderr]    Compiling senl v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 27.53s
[INFO] running `Command { std: "docker" "inspect" "327dc7bf44275421e74f448630133dc786dfc2a8f6c8554c2d69196c623740f4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "327dc7bf44275421e74f448630133dc786dfc2a8f6c8554c2d69196c623740f4", kill_on_drop: false }`
[INFO] [stdout] 327dc7bf44275421e74f448630133dc786dfc2a8f6c8554c2d69196c623740f4
[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-04-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] bc4c564e8415d7a18c0cd417286769499ee64e2e907ce2e7959bc4654297b34f
[INFO] running `Command { std: "docker" "start" "-a" "bc4c564e8415d7a18c0cd417286769499ee64e2e907ce2e7959bc4654297b34f", kill_on_drop: false }`
[INFO] [stderr]    Compiling senl v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.37s
[INFO] running `Command { std: "docker" "inspect" "bc4c564e8415d7a18c0cd417286769499ee64e2e907ce2e7959bc4654297b34f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bc4c564e8415d7a18c0cd417286769499ee64e2e907ce2e7959bc4654297b34f", kill_on_drop: false }`
[INFO] [stdout] bc4c564e8415d7a18c0cd417286769499ee64e2e907ce2e7959bc4654297b34f
[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-04-21" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] a27bb74336718299a09784ad2724b876045cf4737475e2e940cc5e5461ea2ba5
[INFO] running `Command { std: "docker" "start" "-a" "a27bb74336718299a09784ad2724b876045cf4737475e2e940cc5e5461ea2ba5", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/senl-b97d9df7a7b8530c)
[INFO] [stdout] 
[INFO] [stdout] running 100 tests
[INFO] [stdout] test builder::tests::missing_commands_table_is_error ... ok
[INFO] [stdout] test builder::tests::subcommand_registered ... ok
[INFO] [stdout] test builder::tests::version_is_set ... ok
[INFO] [stdout] test builder::tests::option_with_default ... ok
[INFO] [stdout] test builder::tests::minimal_command_builds ... ok
[INFO] [stdout] test dispatch::tests::dispatch_passes_flag ... ok
[INFO] [stdout] test dispatch::tests::dispatch_no_subcommand_is_error ... ok
[INFO] [stdout] test dispatch::tests::dispatch_returns_output ... ok
[INFO] [stdout] test dispatch::tests::dispatch_passes_positional_arg ... ok
[INFO] [stdout] test builder::tests::parses_with_positional_arg ... ok
[INFO] [stdout] test helpers::env::tests::home_returns_string_or_nil ... ok
[INFO] [stdout] test helpers::json::tests::encode_empty_table_as_object ... ok
[INFO] [stdout] test helpers::env::tests::set_overrides_os_var ... ok
[INFO] [stdout] test dispatch::tests::dispatch_error_exit_code ... ok
[INFO] [stdout] test helpers::env::tests::get_missing_var_returns_nil ... ok
[INFO] [stdout] test helpers::fs::tests::copy_file ... ok
[INFO] [stdout] test helpers::env::tests::get_or_returns_default ... ok
[INFO] [stdout] test helpers::json::tests::encode_nested_structure ... ok
[INFO] [stdout] test helpers::json::tests::encode_pretty_has_newlines ... ok
[INFO] [stdout] test helpers::env::tests::set_and_get_roundtrip ... ok
[INFO] [stdout] test helpers::fs::tests::exists_and_is_dir ... ok
[INFO] [stdout] test helpers::json::tests::encode_roundtrip ... ok
[INFO] [stdout] test helpers::json::tests::parse_array ... ok
[INFO] [stdout] test helpers::json::tests::parse_boolean ... ok
[INFO] [stdout] test helpers::json::tests::parse_invalid_returns_error ... ok
[INFO] [stdout] test helpers::json::tests::parse_null_becomes_nil ... ok
[INFO] [stdout] test helpers::lua_table::tests::empty_table_is_map ... ok
[INFO] [stdout] test helpers::lua_table::tests::mixed_keys_is_map ... ok
[INFO] [stdout] test helpers::path::tests::ext_extraction ... ok
[INFO] [stdout] test helpers::path::tests::filename_extraction ... ok
[INFO] [stdout] test helpers::json::tests::parse_object ... ok
[INFO] [stdout] test helpers::path::tests::is_absolute_false ... ok
[INFO] [stdout] test helpers::lua_table::tests::single_element_array ... ok
[INFO] [stdout] test helpers::path::tests::join_two_parts ... ok
[INFO] [stdout] test helpers::path::tests::is_absolute_true ... ok
[INFO] [stdout] test helpers::path::tests::parent_of_file ... ok
[INFO] [stdout] test helpers::lua_table::tests::sparse_int_keys_is_map ... ok
[INFO] [stdout] test helpers::path::tests::parent_of_root_is_nil ... ok
[INFO] [stdout] test helpers::lua_table::tests::string_keyed_is_map ... ok
[INFO] [stdout] test helpers::lua_table::tests::contiguous_array ... ok
[INFO] [stdout] test helpers::env::tests::get_existing_var ... ok
[INFO] [stdout] test helpers::env::tests::get_or_uses_set_value ... ok
[INFO] [stdout] test helpers::path::tests::stem_without_extension ... ok
[INFO] [stdout] test helpers::fs::tests::glob_matches_pattern ... ok
[INFO] [stdout] test helpers::time::tests::millis_returns_positive_integer ... ok
[INFO] [stdout] test helpers::time::tests::measure_propagates_error ... ok
[INFO] [stdout] test helpers::time::tests::sleep_infinity_returns_error ... ok
[INFO] [stdout] test helpers::time::tests::now_and_millis_consistent ... ok
[INFO] [stdout] test helpers::time::tests::sleep_neg_infinity_returns_error ... ok
[INFO] [stdout] test helpers::fs::tests::mkdir_creates_nested_dirs ... ok
[INFO] [stdout] test helpers::fs::tests::read_and_write_file ... ok
[INFO] [stdout] test helpers::fs::tests::read_nonexistent_returns_error ... ok
[INFO] [stdout] test helpers::fs::tests::remove_file ... ok
[INFO] [stdout] test helpers::fs::tests::walk_returns_files ... ok
[INFO] [stdout] test helpers::time::tests::sleep_negative_returns_error ... ok
[INFO] [stdout] test runtime::tests::helpers_available_via_registry ... ok
[INFO] [stdout] test runtime::tests::user_preloads_registered ... ok
[INFO] [stdout] test runtime::tests::submodule_require_works ... ok
[INFO] [stdout] test runtime::tests::user_preloads_hierarchical ... ok
[INFO] [stdout] test helpers::time::tests::measure_no_return_value ... ok
[INFO] [stdout] test tests::module_name_backslash_separator ... ok
[INFO] [stdout] Usage: test <COMMAND>
[INFO] [stdout] 
[INFO] [stdout] Commands:
[INFO] [stdout]   ping  ping
[INFO] [stdout]   help  Print this message or the help of the given subcommand(s)
[INFO] [stdout] 
[INFO] [stdout] Options:
[INFO] [stdout]   -h, --help     Print help
[INFO] [stdout]   -V, --version  Print version
[INFO] [stdout] test tests::lua_builder_option_with_default ... ok
[INFO] [stdout] test runtime::tests::search_path_nonexistent_returns_error ... ok
[INFO] [stdout] test helpers::time::tests::sleep_zero_is_valid ... ok
[INFO] [stdout] test tests::help_returns_ok_zero ... ok
[INFO] [stdout] test tests::mixed_args_flags_options ... ok
[INFO] [stdout] test runtime::tests::search_paths_added ... ok
[INFO] [stdout] test tests::module_name_deeply_nested ... ok
[INFO] [stdout] test tests::module_name_init ... ok
[INFO] [stdout] test tests::module_name_nested_file ... ok
[INFO] [stdout] test helpers::time::tests::sleep_nan_returns_error ... ok
[INFO] [stdout] test helpers::time::tests::now_returns_positive_number ... ok
[INFO] [stdout] test helpers::time::tests::sleep_exceeding_max_returns_error ... ok
[INFO] [stdout] test tests::invalid_flag_returns_cli_error ... ok
[INFO] [stdout] test tests::module_name_flat_file ... ok
[INFO] [stdout] test tests::module_name_nested_init ... ok
[INFO] [stdout] test tests::runtime_lua_access ... ok
[INFO] [stdout] test tests::runtime_builder_with_preload_dir ... ok
[INFO] [stdout] test tests::runtime_builder_with_preload ... ok
[INFO] [stdout] test tests::option_missing_without_default_is_nil ... ok
[INFO] [stdout] test tests::option_with_short_flag ... ok
[INFO] [stdout] test tests::option_with_default_value ... ok
[INFO] [stdout] test tests::preload_module_available_via_require ... ok
[INFO] [stdout] test tests::runtime_lua_sen_available ... ok
[INFO] [stdout] test tests::lua_builder_option_explicit_value ... ok
[INFO] [stdout] test runtime::tests::embedded_require_works ... ok
[INFO] [stdout] test tests::option_required_missing_returns_error ... ok
[INFO] [stdout] test tests::runtime_helpers_available ... ok
[INFO] [stdout] test tests::option_with_explicit_value ... ok
[INFO] [stdout] ping
[INFO] [stdout] 
[INFO] [stdout] Usage: test ping
[INFO] [stdout] 
[INFO] [stdout] Options:
[INFO] [stdout]   -h, --help  Print help
[INFO] [stdout] test 0.1.0
[INFO] [stdout] test runtime::tests::app_dir_modules_resolvable ... ok
[INFO] [stdout] test tests::runtime_new_default ... ok
[INFO] [stdout] test tests::subcommand_help_returns_ok_zero ... ok
[INFO] [stdout] test tests::version_returns_ok_zero ... ok
[INFO] [stdout] test tests::with_setup_error_propagates ... ok
[INFO] [stdout] test tests::with_setup_injects_global ... ok
[INFO] [stdout] test tests::with_setup_multiple_calls_execute_in_order ... ok
[INFO] [stdout] test tests::with_setup_table_injection ... ok
[INFO] [stdout] test tests::preload_dir_registers_multiple_modules ... ok
[INFO] [stdout] test helpers::time::tests::measure_returns_elapsed_and_result ... ok
[INFO] [stdout] test helpers::time::tests::sleep_pauses_execution ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 100 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/senl-8e7fa97685ff84f2)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/debug/deps/integration-099f8428259d7042)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test option_with_short_flag ... ok
[INFO] [stdout] test option_mixed_with_flags_and_args ... ok
[INFO] [stdout] test runtime_builder_with_preload_dir ... ok
[INFO] [stdout] test runtime_lua_access ... ok
[INFO] [stdout] test option_with_default_value ... ok
[INFO] [stdout] test preload_invalid_lua_source_errors ... ok
[INFO] [stdout] test runtime_helpers_available ... ok
[INFO] [stdout] test runtime_lua_sen_available ... ok
[INFO] [stdout] test with_preload_dir_maps_paths ... ok
[INFO] [stdout] test runtime_new_default ... ok
[INFO] [stdout] test with_preload_single_module ... ok
[INFO] [stdout] test with_preload_dir_nested_init ... ok
[INFO] [stdout] test option_with_explicit_value ... ok
[INFO] [stdout] test runtime_builder_with_preload ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests senl
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test src/lib.rs - SenlApp::with_preload_dir (line 127) - compile ... ok
[INFO] [stdout] test src/lib.rs - SenlApp::run_with_args (line 196) - compile ... ok
[INFO] [stdout] test src/lib.rs - SenlApp::with_preload (line 104) - compile ... ok
[INFO] [stdout] test src/lib.rs - SenlApp::with_setup (line 162) - compile ... ok
[INFO] [stdout] test src/lib.rs - SenlApp (line 19) - compile ... ok
[INFO] [stdout] test src/lib.rs - Runtime (line 304) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.46s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "a27bb74336718299a09784ad2724b876045cf4737475e2e940cc5e5461ea2ba5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a27bb74336718299a09784ad2724b876045cf4737475e2e940cc5e5461ea2ba5", kill_on_drop: false }`
[INFO] [stdout] a27bb74336718299a09784ad2724b876045cf4737475e2e940cc5e5461ea2ba5
