[INFO] cloning repository https://github.com/joefreeman/quiver
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/joefreeman/quiver" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoefreeman%2Fquiver", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoefreeman%2Fquiver'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 099599e9f9e9702876dbe4f877e6afa5432cde17
[INFO] testing joefreeman/quiver against beta-2026-01-20 for beta-1.94-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjoefreeman%2Fquiver" "/workspace/builds/worker-1-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/joefreeman/quiver
[INFO] finished tweaking git repo https://github.com/joefreeman/quiver
[INFO] tweaked toml for git repo https://github.com/joefreeman/quiver written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/joefreeman/quiver on toolchain beta-2026-01-20
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2026-01-20" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/joefreeman/quiver 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-01-20" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] d6eb77eefa363e1311b8a545304b1ff4ff07b9f99bf1b60d187eda9e1ac715a9
[INFO] running `Command { std: "docker" "start" "-a" "d6eb77eefa363e1311b8a545304b1ff4ff07b9f99bf1b60d187eda9e1ac715a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d6eb77eefa363e1311b8a545304b1ff4ff07b9f99bf1b60d187eda9e1ac715a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d6eb77eefa363e1311b8a545304b1ff4ff07b9f99bf1b60d187eda9e1ac715a9", kill_on_drop: false }`
[INFO] [stdout] d6eb77eefa363e1311b8a545304b1ff4ff07b9f99bf1b60d187eda9e1ac715a9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] dce70e6960e11fc05bb635f39718cb079c13f596598af0df342a375c3efe1c0e
[INFO] running `Command { std: "docker" "start" "-a" "dce70e6960e11fc05bb635f39718cb079c13f596598af0df342a375c3efe1c0e", kill_on_drop: false }`
[INFO] [stderr]    Compiling unicode-ident v1.0.18
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.104
[INFO] [stderr]    Compiling log v0.4.28
[INFO] [stderr]    Compiling memchr v2.7.5
[INFO] [stderr]    Compiling bumpalo v3.19.0
[INFO] [stderr]    Compiling libc v0.2.177
[INFO] [stderr]    Compiling serde_json v1.0.143
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling bitflags v2.9.4
[INFO] [stderr]    Compiling bytecount v0.6.9
[INFO] [stderr]    Compiling cfg_aliases v0.1.1
[INFO] [stderr]    Compiling io-uring v0.6.4
[INFO] [stderr]    Compiling wasm-bindgen v0.2.104
[INFO] [stderr]    Compiling anstyle-query v1.1.4
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling nix v0.28.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling endian-type v0.1.2
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling home v0.5.11
[INFO] [stderr]    Compiling colored v2.2.0
[INFO] [stderr]    Compiling rustix v1.1.2
[INFO] [stderr]    Compiling clap_builder v4.5.49
[INFO] [stderr]    Compiling ariadne v0.4.1
[INFO] [stderr]    Compiling nibble_vec v0.1.0
[INFO] [stderr]    Compiling radix_trie v0.2.1
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling include_dir_macros v0.7.4
[INFO] [stderr]    Compiling include_dir v0.7.4
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling fd-lock v4.0.4
[INFO] [stderr]    Compiling nom_locate v4.2.0
[INFO] [stderr]    Compiling rustyline v14.0.0
[INFO] [stderr]    Compiling wasm-bindgen-backend v0.2.104
[INFO] [stderr]    Compiling serde_derive_internals v0.28.0
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling clap_derive v4.5.49
[INFO] [stderr]    Compiling tsify-macros v0.4.5
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.104
[INFO] [stderr]    Compiling wasm-bindgen-macro v0.2.104
[INFO] [stderr]    Compiling js-sys v0.3.81
[INFO] [stderr]    Compiling console_error_panic_hook v0.1.7
[INFO] [stderr]    Compiling clap v4.5.49
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling web-sys v0.3.81
[INFO] [stderr]    Compiling quiver-core v0.3.0 (/opt/rustwide/workdir/quiver-core)
[INFO] [stderr]    Compiling serde-wasm-bindgen v0.6.5
[INFO] [stderr]    Compiling quiver-compiler v0.3.0 (/opt/rustwide/workdir/quiver-compiler)
[INFO] [stderr]    Compiling quiver-io v0.3.0 (/opt/rustwide/workdir/quiver-io)
[INFO] [stderr]    Compiling quiver-environment v0.3.0 (/opt/rustwide/workdir/quiver-environment)
[INFO] [stderr]    Compiling gloo-utils v0.1.7
[INFO] [stderr]    Compiling tsify v0.4.5
[INFO] [stderr]    Compiling quiver-cli v0.3.0 (/opt/rustwide/workdir/quiver-cli)
[INFO] [stderr]    Compiling quiver-web v0.3.0 (/opt/rustwide/workdir/quiver-web)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 47.88s
[INFO] running `Command { std: "docker" "inspect" "dce70e6960e11fc05bb635f39718cb079c13f596598af0df342a375c3efe1c0e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dce70e6960e11fc05bb635f39718cb079c13f596598af0df342a375c3efe1c0e", kill_on_drop: false }`
[INFO] [stdout] dce70e6960e11fc05bb635f39718cb079c13f596598af0df342a375c3efe1c0e
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4c68cc42daa39008ff2264a666f78e80489043b6000fb4fb53ff49a6dce190f1
[INFO] running `Command { std: "docker" "start" "-a" "4c68cc42daa39008ff2264a666f78e80489043b6000fb4fb53ff49a6dce190f1", kill_on_drop: false }`
[INFO] [stderr]    Compiling quiver-core v0.3.0 (/opt/rustwide/workdir/quiver-core)
[INFO] [stderr]    Compiling quiver-compiler v0.3.0 (/opt/rustwide/workdir/quiver-compiler)
[INFO] [stderr]    Compiling quiver-io v0.3.0 (/opt/rustwide/workdir/quiver-io)
[INFO] [stderr]    Compiling quiver-environment v0.3.0 (/opt/rustwide/workdir/quiver-environment)
[INFO] [stderr]    Compiling quiver-cli v0.3.0 (/opt/rustwide/workdir/quiver-cli)
[INFO] [stderr]    Compiling quiver-tests v0.3.0 (/opt/rustwide/workdir/quiver-tests)
[INFO] [stderr]    Compiling quiver-web v0.3.0 (/opt/rustwide/workdir/quiver-web)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 49.02s
[INFO] running `Command { std: "docker" "inspect" "4c68cc42daa39008ff2264a666f78e80489043b6000fb4fb53ff49a6dce190f1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4c68cc42daa39008ff2264a666f78e80489043b6000fb4fb53ff49a6dce190f1", kill_on_drop: false }`
[INFO] [stdout] 4c68cc42daa39008ff2264a666f78e80489043b6000fb4fb53ff49a6dce190f1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2026-01-20" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 8b9cc7f88a4dd1dbebed8b3ca60d7dff0e770f0955011cf137f6e1e4e977d818
[INFO] running `Command { std: "docker" "start" "-a" "8b9cc7f88a4dd1dbebed8b3ca60d7dff0e770f0955011cf137f6e1e4e977d818", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.27s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/quiver_cli-9a483a29a0f0e71e)
[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 unittests src/main.rs (/opt/rustwide/target/debug/deps/quiv-092030de4b7855e8)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/quiver_compiler-a6d37867eec0d8a6)
[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] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test parser::tests::test_context_detection_function_body ... ok
[INFO] [stdout] test parser::tests::test_context_detection_unterminated_string ... ok
[INFO] [stdout] test parser::tests::test_context_detection_unclosed_block ... ok
[INFO] [stdout] test parser::tests::test_context_detection_unclosed_tuple ... ok
[INFO] [stdout] test parser::tests::test_span_error_at_start ... ok
[INFO] [stdout] test parser::tests::test_span_malformed_hex ... ok
[INFO] [stdout] test parser::tests::test_span_error_at_end ... ok
[INFO] [stdout] test parser::tests::test_span_malformed_integer ... ok
[INFO] [stdout] test parser::tests::test_span_invalid_escape_sequence ... ok
[INFO] [stdout] test parser::tests::test_span_process_ref_malformed ... ok
[INFO] [stdout] test parser::tests::test_span_with_unicode ... ok
[INFO] [stdout] test parser::tests::test_valid_program_parses ... ok
[INFO] [stdout] test parser::tests::test_span_multiline_error ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/quiver_core-2e077b1a5edafd76)
[INFO] [stdout] test parser::tests::test_error_has_span ... 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] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test binary::tests::test_concat ... ok
[INFO] [stdout] test binary::tests::test_iterator ... ok
[INFO] [stdout] test binary::tests::test_nested_operations ... ok
[INFO] [stdout] test binary::tests::test_owned ... ok
[INFO] [stdout] test binary::tests::test_slice ... ok
[INFO] [stdout] test binary::tests::test_slice_bounds ... ok
[INFO] [stdout] test binary::tests::test_zeroed ... ok
[INFO] [stdout] test binary::tests::test_depth ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/quiver_environment-fe1673656e7313bd)
[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 unittests src/lib.rs (/opt/rustwide/target/debug/deps/quiver_io-1adfa64fc9c9affc)
[INFO] [stderr]      Running tests/access.rs (/opt/rustwide/target/debug/deps/access-297d2a2546ba4282)
[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] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test test_nested_member_access_as_operation ... ok
[INFO] [stdout] test test_nested_member_access_as_value ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/arithmetic.rs (/opt/rustwide/target/debug/deps/arithmetic-b2bff96b9c29cd6a)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_modulo_by_zero ... ok
[INFO] [stdout] test test_builtin_sqrt_negative_errors ... ok
[INFO] [stdout] test test_subtraction ... ok
[INFO] [stdout] test test_division ... ok
[INFO] [stdout] test test_division_by_zero ... ok
[INFO] [stdout] test test_modulo ... ok
[INFO] [stdout] test test_addition ... ok
[INFO] [stdout] test test_multiplication ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/assignment.rs (/opt/rustwide/target/debug/deps/assignment-8373816dbaaaf122)
[INFO] [stdout] 
[INFO] [stdout] running 34 tests
[INFO] [stdout] test test_complex_union_pattern_matching ... ok
[INFO] [stdout] test test_deeply_nested_tuple_pattern ... ok
[INFO] [stdout] test test_failed_assignment_length ... ok
[INFO] [stdout] test test_failed_assignment_type ... ok
[INFO] [stdout] test test_empty_tuple_pattern ... ok
[INFO] [stdout] test test_mixed_pattern_literal_and_binding ... ok
[INFO] [stdout] test test_partial_pattern_order_for_union ... ok
[INFO] [stdout] test test_named_partial_pattern_in_block ... ok
[INFO] [stdout] test test_named_partial_pattern_with_union ... ok
[INFO] [stdout] test test_repeated_identifier_bind ... ok
[INFO] [stdout] test test_comparison_with_literal ... ok
[INFO] [stdout] test test_failed_assignment_on_mismatched_literal ... ok
[INFO] [stdout] test test_multiple_placeholders ... ok
[INFO] [stdout] test test_simple_partial_pattern ... ok
[INFO] [stdout] test test_nested_field_assignment ... ok
[INFO] [stdout] test test_simple_assignment ... ok
[INFO] [stdout] test test_union_match_with_literals ... ok
[INFO] [stdout] test test_star_assignment ... ok
[INFO] [stdout] test test_named_partial_pattern ... ok
[INFO] [stdout] test test_repeated_identifier_multiple_times ... ok
[INFO] [stdout] test test_ignore_placeholder ... ok
[INFO] [stdout] test test_tuple_destructuring ... ok
[INFO] [stdout] test test_wildcard ... ok
[INFO] [stdout] test test_union_match_with_tuples ... ok
[INFO] [stdout] test test_failed_assignment_on_mismatched_literal_in_tuple ... ok
[INFO] [stdout] test test_repeated_identifier_nested ... ok
[INFO] [stdout] test test_partial_tuple_assignment ... ok
[INFO] [stdout] test test_match_union_in_nested_tuple ... ok
[INFO] [stdout] test test_recursive_destructuring ... ok
[INFO] [stdout] test test_failed_assignment_with_unrecognised_partial_field ... ok
[INFO] [stdout] test test_star_pattern_order_for_union ... ok
[INFO] [stdout] test test_union_type_partial_destructuring ... ok
[INFO] [stdout] test test_named_field_assignment ... ok
[INFO] [stdout] test test_string_literal_pattern ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.45s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/binary.rs (/opt/rustwide/target/debug/deps/binary-aa195218bacc86b8)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test test_shift ... ok
[INFO] [stdout] test test_or ... ok
[INFO] [stdout] test test_bit_manipulation_pattern ... ok
[INFO] [stdout] test test_chained_operations ... ok
[INFO] [stdout] test test_set_byte ... ok
[INFO] [stdout] test test_get_byte ... ok
[INFO] [stdout] test test_xor ... ok
[INFO] [stdout] test test_and ... ok
[INFO] [stdout] test test_not ... ok
[INFO] [stdout] test test_length ... ok
[INFO] [stdout] test test_concat ... ok
[INFO] [stdout] test test_set_bit ... ok
[INFO] [stdout] test test_slice ... ok
[INFO] [stdout] test test_new ... ok
[INFO] [stdout] test test_append ... ok
[INFO] [stdout] test test_get_bit ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.37s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/bitwise.rs (/opt/rustwide/target/debug/deps/bitwise-9a97fd6ef41d0c22)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test test_shift_operations_boundary ... ok
[INFO] [stdout] test test_binary_popcount_hamt_pattern ... ok
[INFO] [stdout] test test_bitwise_chaining ... ok
[INFO] [stdout] test test_hamt_simulation ... ok
[INFO] [stdout] test test_binary_shift_left ... ok
[INFO] [stdout] test test_binary_and ... ok
[INFO] [stdout] test test_error_conditions ... ok
[INFO] [stdout] test test_binary_or ... ok
[INFO] [stdout] test test_binary_shift_right ... ok
[INFO] [stdout] test test_binary_get_bit_pos ... ok
[INFO] [stdout] test test_binary_set_bit ... ok
[INFO] [stdout] test test_bit_unaligned_access ... ok
[INFO] [stdout] test test_binary_not ... ok
[INFO] [stdout] test test_binary_xor ... ok
[INFO] [stdout] test test_binary_popcount_critical ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.37s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/blocks.rs (/opt/rustwide/target/debug/deps/blocks-9b8bbb1b1faa77c7)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_block_with_parameter ... ok
[INFO] [stdout] test test_block_evaluation ... ok
[INFO] [stdout] test test_block_with_positional_parameter ... ok
[INFO] [stdout] test test_block_with_repeated_parameter ... ok
[INFO] [stdout] test test_block_with_closure ... ok
[INFO] [stdout] test test_block_scope ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/branches.rs (/opt/rustwide/target/debug/deps/branches-3f6728e3ccb16471)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test test_branch_first_succeeds ... ok
[INFO] [stdout] test test_branch_pattern_matching ... ok
[INFO] [stdout] test test_branch_first_fails_second_term ... ok
[INFO] [stdout] test test_branch_with_failing_consequence ... ok
[INFO] [stdout] test test_branch_first_fails_first_term ... ok
[INFO] [stdout] test test_branch_with_consequence ... ok
[INFO] [stdout] test test_consequence_ripple_fallback_branch ... ok
[INFO] [stdout] test test_use_continuation_in_consequence ... ok
[INFO] [stdout] test test_consequence_ripple_in_tuple ... ok
[INFO] [stdout] test test_string_match ... ok
[INFO] [stdout] test test_consequence_ripple_is_block_parameter_not_condition_result ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/builtins.rs (/opt/rustwide/target/debug/deps/builtins-38c837ac69c96a7b)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_builtin ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/chaining.rs (/opt/rustwide/target/debug/deps/chaining-b38fc7cb01a4ca31)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_member_access_chaining ... ok
[INFO] [stdout] test test_standalone_ripple_with_nested_chain ... ok
[INFO] [stdout] test test_nested_chain_value_dropped ... ok
[INFO] [stdout] test test_operation_chaining ... ok
[INFO] [stdout] test test_standalone_ripple ... ok
[INFO] [stdout] test test_nested_chain_outer_value_used ... ok
[INFO] [stdout] test test_nested_tupple_construction ... ok
[INFO] [stdout] test test_nested_ripple_contexts ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/comments.rs (/opt/rustwide/target/debug/deps/comments-ece20199947df8fc)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_comments ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/common.rs (/opt/rustwide/target/debug/deps/common-76e20cb8013ec691)
[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/comparison.rs (/opt/rustwide/target/debug/deps/comparison-f008a6ba45a5ee37)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test test_equal ... ok
[INFO] [stdout] test test_greater_than ... ok
[INFO] [stderr]      Running tests/equality.rs (/opt/rustwide/target/debug/deps/equality-27fe87d77596d8dc)
[INFO] [stdout] test test_less_than ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_unequal_integers ... ok
[INFO] [stdout] test test_equal_strings ... ok
[INFO] [stdout] test test_binary_inequality_content ... ok
[INFO] [stdout] test test_binary_equality_content ... ok
[INFO] [stderr]      Running tests/files.rs (/opt/rustwide/target/debug/deps/files-933328737a4d462d)
[INFO] [stdout] test test_equal_integers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test test_file_type_checking ... ok
[INFO] [stdout] test test_file_resource_type ... FAILED
[INFO] [stdout] test test_file_flush ... FAILED
[INFO] [stdout] test test_file_write_and_read ... FAILED
[INFO] [stdout] test test_file_append ... FAILED
[INFO] [stdout] test test_read_from_closed_file ... FAILED
[INFO] [stdout] test test_multiple_writes ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_file_resource_type stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_file_resource_type' (565) panicked at quiver-tests/tests/common.rs:389:17:
[INFO] [stdout] Expected result type '\File', got error: Environment(Timeout(5s)) for source: __file_open__["/tmp/foo" ~> .0, 577, 420]
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::libunwind::trace::hf0bd46b30bc61685
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::trace_unsynchronized::h1d1fbb72ad89d7b7
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x57d7ca5a02f2 - std::sys::backtrace::_print_fmt::he38b6f68ca847f47
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x57d7ca5a02f2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hb7e843cde1bc81a3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x57d7ca5b126a - core::fmt::rt::Argument::fmt::hc1dd77ac64f2688c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x57d7ca5b126a - core::fmt::write::h952c506e2dbe9cff
[INFO] [stdout]    6:     0x57d7ca56e3f6 - std::io::default_write_fmt::hd23bd8145c03a23a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x57d7ca56e3f6 - std::io::Write::write_fmt::hdd3314a2bd8a34bd
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x57d7ca57f4f9 - std::sys::backtrace::BacktraceLock::print::h1079b82381c2c252
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x57d7ca57f4f9 - std::panicking::default_hook::{{closure}}::h85cb76b8462c00f2
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x57d7ca57f391 - std::panicking::default_hook::h0134d220cc034c02
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x57d7ca3a7b8e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb2e68dca3970e3ca
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x57d7ca3a7b8e - test::test_main_with_exit_callback::{{closure}}::ha7ec2063a2565ec3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x57d7ca57f772 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::haebee478d0f56cf4
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x57d7ca57f772 - std::panicking::panic_with_hook::h8119b6db1fef734f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x57d7ca57f5b8 - std::panicking::panic_handler::{{closure}}::h24f5360b91482c0e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x57d7ca57a889 - std::sys::backtrace::__rust_end_short_backtrace::h4e60c5cbd1bd0108
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x57d7ca5626dd - __rustc[e4c6931bd8050421]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x57d7ca5b971c - core::panicking::panic_fmt::hb1614a3ff6840071
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x57d7ca37c664 - files::common::TestResult::expect_type::h9ea0a85d912e5515
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/common.rs:389:17
[INFO] [stdout]   20:     0x57d7ca368ad8 - files::test_file_resource_type::h3d8bf9a749e24c8d
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:99:10
[INFO] [stdout]   21:     0x57d7ca368b07 - files::test_file_resource_type::{{closure}}::h05c3565194398bc9
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:95:29
[INFO] [stdout]   22:     0x57d7ca370146 - core::ops::function::FnOnce::call_once::hfb5d2ea1aa2b07b9
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x57d7ca3a798b - core::ops::function::FnOnce::call_once::hefaf63600cd43d2a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x57d7ca3a798b - test::__rust_begin_short_backtrace::he30322a73a0e9ea6
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x57d7ca3bb35a - test::run_test_in_process::{{closure}}::hdea3ab8d0243ef36
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x57d7ca3bb35a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h66d192f42498ffce
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x57d7ca3bb35a - std::panicking::catch_unwind::do_call::h822e18e8d3a6bb65
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x57d7ca3bb35a - std::panicking::catch_unwind::h54b1de7ceab578bf
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x57d7ca3bb35a - std::panic::catch_unwind::h60c29b7a9d0246b1
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x57d7ca3bb35a - test::run_test_in_process::h6099b5f871379b13
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x57d7ca3bb35a - test::run_test::{{closure}}::h926ce0a38281a4c8
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x57d7ca3957b4 - test::run_test::{{closure}}::h508de21a9afc7140
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x57d7ca3957b4 - std::sys::backtrace::__rust_begin_short_backtrace::h6e2f5c39ca00735a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::h133e44f0e5802c14
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x57d7ca399072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4718c1285283955c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x57d7ca399072 - std::panicking::catch_unwind::do_call::h0bba3937b4892238
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x57d7ca399072 - std::panicking::catch_unwind::h5b31cab5fb8c5d7f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x57d7ca399072 - std::panic::catch_unwind::hb2fa05d52ed0124e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::hf401cf5ff501bd29
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x57d7ca399072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h160022e94ce3b0bb
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x57d7ca5759bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf234e0d1d1d272ba
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x57d7ca5759bf - std::sys::thread::unix::Thread::new::thread_start::h106a295241840d3b
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x70df47998aa4 - <unknown>
[INFO] [stdout]   44:     0x70df47a25a64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_file_flush stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_file_flush' (564) panicked at quiver-tests/tests/common.rs:225:17:
[INFO] [stdout] Expected value 'Ok', got error: Environment(Timeout(5s)) for source: 
[INFO] [stdout]             file = __file_open__["/tmp/quiver_test_flush_562.txt" ~> .0, 577, 420],
[INFO] [stdout]             __file_write__[file, 0, "Flushed data" ~> .0],
[INFO] [stdout]             __file_flush__[file],
[INFO] [stdout]             __file_close__[file],
[INFO] [stdout]             Ok
[INFO] [stdout]         
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::libunwind::trace::hf0bd46b30bc61685
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::trace_unsynchronized::h1d1fbb72ad89d7b7
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x57d7ca5a02f2 - std::sys::backtrace::_print_fmt::he38b6f68ca847f47
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x57d7ca5a02f2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hb7e843cde1bc81a3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x57d7ca5b126a - core::fmt::rt::Argument::fmt::hc1dd77ac64f2688c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x57d7ca5b126a - core::fmt::write::h952c506e2dbe9cff
[INFO] [stdout]    6:     0x57d7ca56e3f6 - std::io::default_write_fmt::hd23bd8145c03a23a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x57d7ca56e3f6 - std::io::Write::write_fmt::hdd3314a2bd8a34bd
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x57d7ca57f4f9 - std::sys::backtrace::BacktraceLock::print::h1079b82381c2c252
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x57d7ca57f4f9 - std::panicking::default_hook::{{closure}}::h85cb76b8462c00f2
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x57d7ca57f391 - std::panicking::default_hook::h0134d220cc034c02
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x57d7ca3a7b8e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb2e68dca3970e3ca
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x57d7ca3a7b8e - test::test_main_with_exit_callback::{{closure}}::ha7ec2063a2565ec3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x57d7ca57f772 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::haebee478d0f56cf4
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x57d7ca57f772 - std::panicking::panic_with_hook::h8119b6db1fef734f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x57d7ca57f5b8 - std::panicking::panic_handler::{{closure}}::h24f5360b91482c0e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x57d7ca57a889 - std::sys::backtrace::__rust_end_short_backtrace::h4e60c5cbd1bd0108
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x57d7ca5626dd - __rustc[e4c6931bd8050421]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x57d7ca5b971c - core::panicking::panic_fmt::hb1614a3ff6840071
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x57d7ca37d3d3 - files::common::TestResult::expect::h494053bdc65652a5
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/common.rs:225:17
[INFO] [stdout]   20:     0x57d7ca367e37 - files::test_file_flush::hf03cf5f8e228affa
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:121:10
[INFO] [stdout]   21:     0x57d7ca367ff7 - files::test_file_flush::{{closure}}::h80340e2b8692ccb8
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:103:21
[INFO] [stdout]   22:     0x57d7ca36fb06 - core::ops::function::FnOnce::call_once::hba848fab715bd155
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x57d7ca3a798b - core::ops::function::FnOnce::call_once::hefaf63600cd43d2a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x57d7ca3a798b - test::__rust_begin_short_backtrace::he30322a73a0e9ea6
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x57d7ca3bb35a - test::run_test_in_process::{{closure}}::hdea3ab8d0243ef36
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x57d7ca3bb35a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h66d192f42498ffce
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x57d7ca3bb35a - std::panicking::catch_unwind::do_call::h822e18e8d3a6bb65
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x57d7ca3bb35a - std::panicking::catch_unwind::h54b1de7ceab578bf
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x57d7ca3bb35a - std::panic::catch_unwind::h60c29b7a9d0246b1
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x57d7ca3bb35a - test::run_test_in_process::h6099b5f871379b13
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x57d7ca3bb35a - test::run_test::{{closure}}::h926ce0a38281a4c8
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x57d7ca3957b4 - test::run_test::{{closure}}::h508de21a9afc7140
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x57d7ca3957b4 - std::sys::backtrace::__rust_begin_short_backtrace::h6e2f5c39ca00735a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::h133e44f0e5802c14
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x57d7ca399072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4718c1285283955c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x57d7ca399072 - std::panicking::catch_unwind::do_call::h0bba3937b4892238
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x57d7ca399072 - std::panicking::catch_unwind::h5b31cab5fb8c5d7f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x57d7ca399072 - std::panic::catch_unwind::hb2fa05d52ed0124e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::hf401cf5ff501bd29
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x57d7ca399072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h160022e94ce3b0bb
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x57d7ca5759bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf234e0d1d1d272ba
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x57d7ca5759bf - std::sys::thread::unix::Thread::new::thread_start::h106a295241840d3b
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x70df47998aa4 - <unknown>
[INFO] [stdout]   44:     0x70df47a25a64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_file_write_and_read stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_file_write_and_read' (567) panicked at quiver-tests/tests/common.rs:225:17:
[INFO] [stdout] Expected value '"Hello, World!"', got error: Environment(Timeout(5s)) for source: 
[INFO] [stdout]             // O_WRONLY | O_CREAT | O_TRUNC = 1 | 64 | 512 = 577
[INFO] [stdout]             // O_RDONLY = 0
[INFO] [stdout]             // Mode 0o644 = 420
[INFO] [stdout] 
[INFO] [stdout]             write_file = __file_open__["/tmp/quiver_test_562.txt" ~> .0, 577, 420],
[INFO] [stdout]             __file_write__[write_file, 0, "Hello, World!" ~> .0],
[INFO] [stdout]             __file_close__[write_file],
[INFO] [stdout] 
[INFO] [stdout]             read_file = __file_open__["/tmp/quiver_test_562.txt" ~> .0, 0, 0],
[INFO] [stdout]             data = __file_read__[read_file, 0, 4096],
[INFO] [stdout]             __file_close__[read_file],
[INFO] [stdout] 
[INFO] [stdout]             Str[data]
[INFO] [stdout]         
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::libunwind::trace::hf0bd46b30bc61685
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::trace_unsynchronized::h1d1fbb72ad89d7b7
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x57d7ca5a02f2 - std::sys::backtrace::_print_fmt::he38b6f68ca847f47
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x57d7ca5a02f2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hb7e843cde1bc81a3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x57d7ca5b126a - core::fmt::rt::Argument::fmt::hc1dd77ac64f2688c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x57d7ca5b126a - core::fmt::write::h952c506e2dbe9cff
[INFO] [stdout]    6:     0x57d7ca56e3f6 - std::io::default_write_fmt::hd23bd8145c03a23a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x57d7ca56e3f6 - std::io::Write::write_fmt::hdd3314a2bd8a34bd
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x57d7ca57f4f9 - std::sys::backtrace::BacktraceLock::print::h1079b82381c2c252
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x57d7ca57f4f9 - std::panicking::default_hook::{{closure}}::h85cb76b8462c00f2
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x57d7ca57f391 - std::panicking::default_hook::h0134d220cc034c02
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x57d7ca3a7b8e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb2e68dca3970e3ca
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x57d7ca3a7b8e - test::test_main_with_exit_callback::{{closure}}::ha7ec2063a2565ec3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x57d7ca57f772 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::haebee478d0f56cf4
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x57d7ca57f772 - std::panicking::panic_with_hook::h8119b6db1fef734f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x57d7ca57f5b8 - std::panicking::panic_handler::{{closure}}::h24f5360b91482c0e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x57d7ca57a889 - std::sys::backtrace::__rust_end_short_backtrace::h4e60c5cbd1bd0108
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x57d7ca5626dd - __rustc[e4c6931bd8050421]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x57d7ca5b971c - core::panicking::panic_fmt::hb1614a3ff6840071
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x57d7ca37d3d3 - files::common::TestResult::expect::h494053bdc65652a5
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/common.rs:225:17
[INFO] [stdout]   20:     0x57d7ca368fea - files::test_file_write_and_read::h9b47a0de284a0f1b
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:31:10
[INFO] [stdout]   21:     0x57d7ca3690c7 - files::test_file_write_and_read::{{closure}}::hf76c3abf2cdd41b5
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:5:30
[INFO] [stdout]   22:     0x57d7ca36fbf6 - core::ops::function::FnOnce::call_once::hc3eb79ac5d9dd0c6
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x57d7ca3a798b - core::ops::function::FnOnce::call_once::hefaf63600cd43d2a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x57d7ca3a798b - test::__rust_begin_short_backtrace::he30322a73a0e9ea6
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x57d7ca3bb35a - test::run_test_in_process::{{closure}}::hdea3ab8d0243ef36
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x57d7ca3bb35a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h66d192f42498ffce
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x57d7ca3bb35a - std::panicking::catch_unwind::do_call::h822e18e8d3a6bb65
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x57d7ca3bb35a - std::panicking::catch_unwind::h54b1de7ceab578bf
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x57d7ca3bb35a - std::panic::catch_unwind::h60c29b7a9d0246b1
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x57d7ca3bb35a - test::run_test_in_process::h6099b5f871379b13
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x57d7ca3bb35a - test::run_test::{{closure}}::h926ce0a38281a4c8
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x57d7ca3957b4 - test::run_test::{{closure}}::h508de21a9afc7140
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x57d7ca3957b4 - std::sys::backtrace::__rust_begin_short_backtrace::h6e2f5c39ca00735a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::h133e44f0e5802c14
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x57d7ca399072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4718c1285283955c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x57d7ca399072 - std::panicking::catch_unwind::do_call::h0bba3937b4892238
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x57d7ca399072 - std::panicking::catch_unwind::h5b31cab5fb8c5d7f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x57d7ca399072 - std::panic::catch_unwind::hb2fa05d52ed0124e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::hf401cf5ff501bd29
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x57d7ca399072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h160022e94ce3b0bb
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x57d7ca5759bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf234e0d1d1d272ba
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x57d7ca5759bf - std::sys::thread::unix::Thread::new::thread_start::h106a295241840d3b
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x70df47998aa4 - <unknown>
[INFO] [stdout]   44:     0x70df47a25a64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_file_append stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_file_append' (563) panicked at quiver-tests/tests/common.rs:225:17:
[INFO] [stdout] Expected value '"First line\nSecond line\n"', got error: Environment(Timeout(5s)) for source: 
[INFO] [stdout]             // O_WRONLY | O_CREAT | O_TRUNC = 577
[INFO] [stdout]             write_file = __file_open__["/tmp/quiver_test_append_562.txt" ~> .0, 577, 420],
[INFO] [stdout]             __file_write__[write_file, 0, "First line\n" ~> .0],
[INFO] [stdout]             __file_close__[write_file],
[INFO] [stdout] 
[INFO] [stdout]             // Write at offset 11 (length of "First line\n")
[INFO] [stdout]             append_file = __file_open__["/tmp/quiver_test_append_562.txt" ~> .0, 1, 420],
[INFO] [stdout]             __file_write__[append_file, 11, "Second line\n" ~> .0],
[INFO] [stdout]             __file_close__[append_file],
[INFO] [stdout] 
[INFO] [stdout]             // Read everything
[INFO] [stdout]             read_file = __file_open__["/tmp/quiver_test_append_562.txt" ~> .0, 0, 0],
[INFO] [stdout]             data = __file_read__[read_file, 0, 4096],
[INFO] [stdout]             __file_close__[read_file],
[INFO] [stdout] 
[INFO] [stdout]             Str[data]
[INFO] [stdout]         
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::libunwind::trace::hf0bd46b30bc61685
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::trace_unsynchronized::h1d1fbb72ad89d7b7
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x57d7ca5a02f2 - std::sys::backtrace::_print_fmt::he38b6f68ca847f47
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x57d7ca5a02f2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hb7e843cde1bc81a3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x57d7ca5b126a - core::fmt::rt::Argument::fmt::hc1dd77ac64f2688c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x57d7ca5b126a - core::fmt::write::h952c506e2dbe9cff
[INFO] [stdout]    6:     0x57d7ca56e3f6 - std::io::default_write_fmt::hd23bd8145c03a23a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x57d7ca56e3f6 - std::io::Write::write_fmt::hdd3314a2bd8a34bd
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x57d7ca57f4f9 - std::sys::backtrace::BacktraceLock::print::h1079b82381c2c252
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x57d7ca57f4f9 - std::panicking::default_hook::{{closure}}::h85cb76b8462c00f2
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x57d7ca57f391 - std::panicking::default_hook::h0134d220cc034c02
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x57d7ca3a7b8e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb2e68dca3970e3ca
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x57d7ca3a7b8e - test::test_main_with_exit_callback::{{closure}}::ha7ec2063a2565ec3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x57d7ca57f772 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::haebee478d0f56cf4
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x57d7ca57f772 - std::panicking::panic_with_hook::h8119b6db1fef734f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x57d7ca57f5b8 - std::panicking::panic_handler::{{closure}}::h24f5360b91482c0e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x57d7ca57a889 - std::sys::backtrace::__rust_end_short_backtrace::h4e60c5cbd1bd0108
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x57d7ca5626dd - __rustc[e4c6931bd8050421]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x57d7ca5b971c - core::panicking::panic_fmt::hb1614a3ff6840071
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x57d7ca37d3d3 - files::common::TestResult::expect::h494053bdc65652a5
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/common.rs:225:17
[INFO] [stdout]   20:     0x57d7ca368469 - files::test_file_append::h911a97f469786afb
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:67:10
[INFO] [stdout]   21:     0x57d7ca368547 - files::test_file_append::{{closure}}::hc2cbc7c306983af3
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:38:22
[INFO] [stdout]   22:     0x57d7ca36ecd6 - core::ops::function::FnOnce::call_once::h1fdd03cb9a533ca3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x57d7ca3a798b - core::ops::function::FnOnce::call_once::hefaf63600cd43d2a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x57d7ca3a798b - test::__rust_begin_short_backtrace::he30322a73a0e9ea6
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x57d7ca3bb35a - test::run_test_in_process::{{closure}}::hdea3ab8d0243ef36
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x57d7ca3bb35a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h66d192f42498ffce
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x57d7ca3bb35a - std::panicking::catch_unwind::do_call::h822e18e8d3a6bb65
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x57d7ca3bb35a - std::panicking::catch_unwind::h54b1de7ceab578bf
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x57d7ca3bb35a - std::panic::catch_unwind::h60c29b7a9d0246b1
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x57d7ca3bb35a - test::run_test_in_process::h6099b5f871379b13
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x57d7ca3bb35a - test::run_test::{{closure}}::h926ce0a38281a4c8
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x57d7ca3957b4 - test::run_test::{{closure}}::h508de21a9afc7140
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x57d7ca3957b4 - std::sys::backtrace::__rust_begin_short_backtrace::h6e2f5c39ca00735a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::h133e44f0e5802c14
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x57d7ca399072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4718c1285283955c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x57d7ca399072 - std::panicking::catch_unwind::do_call::h0bba3937b4892238
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x57d7ca399072 - std::panicking::catch_unwind::h5b31cab5fb8c5d7f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x57d7ca399072 - std::panic::catch_unwind::hb2fa05d52ed0124e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::hf401cf5ff501bd29
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x57d7ca399072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h160022e94ce3b0bb
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x57d7ca5759bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf234e0d1d1d272ba
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x57d7ca5759bf - std::sys::thread::unix::Thread::new::thread_start::h106a295241840d3b
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x70df47998aa4 - <unknown>
[INFO] [stdout]   44:     0x70df47a25a64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_read_from_closed_file stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_read_from_closed_file' (569) panicked at quiver-tests/tests/common.rs:250:17:
[INFO] [stdout] Expected runtime error InvalidArgument("Effect operation failed: InvalidArgument(\"Resource 1 not found\")"), but got Environment(Timeout(5s)) for source: 
[INFO] [stdout]             file = __file_open__["/tmp/quiver_test_closed_562.txt" ~> .0, 0, 0],
[INFO] [stdout]             __file_close__[file],
[INFO] [stdout]             __file_read__[file, 0, 1024]
[INFO] [stdout]         
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::libunwind::trace::hf0bd46b30bc61685
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::trace_unsynchronized::h1d1fbb72ad89d7b7
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x57d7ca5a02f2 - std::sys::backtrace::_print_fmt::he38b6f68ca847f47
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x57d7ca5a02f2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hb7e843cde1bc81a3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x57d7ca5b126a - core::fmt::rt::Argument::fmt::hc1dd77ac64f2688c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x57d7ca5b126a - core::fmt::write::h952c506e2dbe9cff
[INFO] [stdout]    6:     0x57d7ca56e3f6 - std::io::default_write_fmt::hd23bd8145c03a23a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x57d7ca56e3f6 - std::io::Write::write_fmt::hdd3314a2bd8a34bd
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x57d7ca57f4f9 - std::sys::backtrace::BacktraceLock::print::h1079b82381c2c252
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x57d7ca57f4f9 - std::panicking::default_hook::{{closure}}::h85cb76b8462c00f2
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x57d7ca57f391 - std::panicking::default_hook::h0134d220cc034c02
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x57d7ca3a7b8e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb2e68dca3970e3ca
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x57d7ca3a7b8e - test::test_main_with_exit_callback::{{closure}}::ha7ec2063a2565ec3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x57d7ca57f772 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::haebee478d0f56cf4
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x57d7ca57f772 - std::panicking::panic_with_hook::h8119b6db1fef734f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x57d7ca57f5b8 - std::panicking::panic_handler::{{closure}}::h24f5360b91482c0e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x57d7ca57a889 - std::sys::backtrace::__rust_end_short_backtrace::h4e60c5cbd1bd0108
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x57d7ca5626dd - __rustc[e4c6931bd8050421]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x57d7ca5b971c - core::panicking::panic_fmt::hb1614a3ff6840071
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x57d7ca37cdeb - files::common::TestResult::expect_runtime_error::hf30918ecd95d4b8e
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/common.rs:250:17
[INFO] [stdout]   20:     0x57d7ca369599 - files::test_read_from_closed_file::h00e734a9a0903f97
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:182:10
[INFO] [stdout]   21:     0x57d7ca369667 - files::test_read_from_closed_file::{{closure}}::hd155e93002ac0450
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:163:32
[INFO] [stdout]   22:     0x57d7ca36ee26 - core::ops::function::FnOnce::call_once::h28d5bf1f7155da81
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x57d7ca3a798b - core::ops::function::FnOnce::call_once::hefaf63600cd43d2a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x57d7ca3a798b - test::__rust_begin_short_backtrace::he30322a73a0e9ea6
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x57d7ca3bb35a - test::run_test_in_process::{{closure}}::hdea3ab8d0243ef36
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x57d7ca3bb35a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h66d192f42498ffce
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x57d7ca3bb35a - std::panicking::catch_unwind::do_call::h822e18e8d3a6bb65
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x57d7ca3bb35a - std::panicking::catch_unwind::h54b1de7ceab578bf
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x57d7ca3bb35a - std::panic::catch_unwind::h60c29b7a9d0246b1
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x57d7ca3bb35a - test::run_test_in_process::h6099b5f871379b13
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x57d7ca3bb35a - test::run_test::{{closure}}::h926ce0a38281a4c8
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x57d7ca3957b4 - test::run_test::{{closure}}::h508de21a9afc7140
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x57d7ca3957b4 - std::sys::backtrace::__rust_begin_short_backtrace::h6e2f5c39ca00735a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::h133e44f0e5802c14
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x57d7ca399072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4718c1285283955c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x57d7ca399072 - std::panicking::catch_unwind::do_call::h0bba3937b4892238
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x57d7ca399072 - std::panicking::catch_unwind::h5b31cab5fb8c5d7f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x57d7ca399072 - std::panic::catch_unwind::hb2fa05d52ed0124e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::hf401cf5ff501bd29
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x57d7ca399072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h160022e94ce3b0bb
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x57d7ca5759bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf234e0d1d1d272ba
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x57d7ca5759bf - std::sys::thread::unix::Thread::new::thread_start::h106a295241840d3b
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x70df47998aa4 - <unknown>
[INFO] [stdout]   44:     0x70df47a25a64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test_multiple_writes stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'test_multiple_writes' (568) panicked at quiver-tests/tests/common.rs:225:17:
[INFO] [stdout] Expected value '"Line 1\nLine 2\nLine 3\n"', got error: Environment(Timeout(5s)) for source: 
[INFO] [stdout]             file = __file_open__["/tmp/quiver_test_multi_562.txt" ~> .0, 577, 420],
[INFO] [stdout]             __file_write__[file, 0, "Line 1\n" ~> .0],
[INFO] [stdout]             __file_write__[file, 7, "Line 2\n" ~> .0],
[INFO] [stdout]             __file_write__[file, 14, "Line 3\n" ~> .0],
[INFO] [stdout]             __file_close__[file],
[INFO] [stdout] 
[INFO] [stdout]             read_file = __file_open__["/tmp/quiver_test_multi_562.txt" ~> .0, 0, 0],
[INFO] [stdout]             data = __file_read__[read_file, 0, 4096],
[INFO] [stdout]             __file_close__[read_file],
[INFO] [stdout] 
[INFO] [stdout]             Str[data]
[INFO] [stdout]         
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::libunwind::trace::hf0bd46b30bc61685
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x57d7ca5a02f2 - std::backtrace_rs::backtrace::trace_unsynchronized::h1d1fbb72ad89d7b7
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x57d7ca5a02f2 - std::sys::backtrace::_print_fmt::he38b6f68ca847f47
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x57d7ca5a02f2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hb7e843cde1bc81a3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x57d7ca5b126a - core::fmt::rt::Argument::fmt::hc1dd77ac64f2688c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x57d7ca5b126a - core::fmt::write::h952c506e2dbe9cff
[INFO] [stdout]    6:     0x57d7ca56e3f6 - std::io::default_write_fmt::hd23bd8145c03a23a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x57d7ca56e3f6 - std::io::Write::write_fmt::hdd3314a2bd8a34bd
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x57d7ca57f4f9 - std::sys::backtrace::BacktraceLock::print::h1079b82381c2c252
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x57d7ca57f4f9 - std::panicking::default_hook::{{closure}}::h85cb76b8462c00f2
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x57d7ca57f391 - std::panicking::default_hook::h0134d220cc034c02
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x57d7ca3a7b8e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hb2e68dca3970e3ca
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x57d7ca3a7b8e - test::test_main_with_exit_callback::{{closure}}::ha7ec2063a2565ec3
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x57d7ca57f772 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::haebee478d0f56cf4
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x57d7ca57f772 - std::panicking::panic_with_hook::h8119b6db1fef734f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x57d7ca57f5b8 - std::panicking::panic_handler::{{closure}}::h24f5360b91482c0e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x57d7ca57a889 - std::sys::backtrace::__rust_end_short_backtrace::h4e60c5cbd1bd0108
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x57d7ca5626dd - __rustc[e4c6931bd8050421]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x57d7ca5b971c - core::panicking::panic_fmt::hb1614a3ff6840071
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x57d7ca37d3d3 - files::common::TestResult::expect::h494053bdc65652a5
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/common.rs:225:17
[INFO] [stdout]   20:     0x57d7ca36897e - files::test_multiple_writes::hb97099030a1794bd
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:156:10
[INFO] [stdout]   21:     0x57d7ca368a57 - files::test_multiple_writes::{{closure}}::he0f9c1e441722469
[INFO] [stdout]                                at /opt/rustwide/workdir/quiver-tests/tests/files.rs:132:26
[INFO] [stdout]   22:     0x57d7ca36f716 - core::ops::function::FnOnce::call_once::h965caf0c83a7518f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x57d7ca3a798b - core::ops::function::FnOnce::call_once::hefaf63600cd43d2a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x57d7ca3a798b - test::__rust_begin_short_backtrace::he30322a73a0e9ea6
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x57d7ca3bb35a - test::run_test_in_process::{{closure}}::hdea3ab8d0243ef36
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x57d7ca3bb35a - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h66d192f42498ffce
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   27:     0x57d7ca3bb35a - std::panicking::catch_unwind::do_call::h822e18e8d3a6bb65
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x57d7ca3bb35a - std::panicking::catch_unwind::h54b1de7ceab578bf
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x57d7ca3bb35a - std::panic::catch_unwind::h60c29b7a9d0246b1
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x57d7ca3bb35a - test::run_test_in_process::h6099b5f871379b13
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x57d7ca3bb35a - test::run_test::{{closure}}::h926ce0a38281a4c8
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x57d7ca3957b4 - test::run_test::{{closure}}::h508de21a9afc7140
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x57d7ca3957b4 - std::sys::backtrace::__rust_begin_short_backtrace::h6e2f5c39ca00735a
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::h133e44f0e5802c14
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x57d7ca399072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4718c1285283955c
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   36:     0x57d7ca399072 - std::panicking::catch_unwind::do_call::h0bba3937b4892238
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x57d7ca399072 - std::panicking::catch_unwind::h5b31cab5fb8c5d7f
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x57d7ca399072 - std::panic::catch_unwind::hb2fa05d52ed0124e
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x57d7ca399072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::hf401cf5ff501bd29
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x57d7ca399072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h160022e94ce3b0bb
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x57d7ca5759bf - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf234e0d1d1d272ba
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   42:     0x57d7ca5759bf - std::sys::thread::unix::Thread::new::thread_start::h106a295241840d3b
[INFO] [stdout]                                at /rustc/9b1f8ff42d110b0ca138116745be921df5dc97e7/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   43:     0x70df47998aa4 - <unknown>
[INFO] [stdout]   44:     0x70df47a25a64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_file_append
[INFO] [stdout]     test_file_flush
[INFO] [stdout]     test_file_resource_type
[INFO] [stdout]     test_file_write_and_read
[INFO] [stdout]     test_multiple_writes
[INFO] [stdout]     test_read_from_closed_file
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 6 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.14s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p quiver-tests --test files`
[INFO] running `Command { std: "docker" "inspect" "8b9cc7f88a4dd1dbebed8b3ca60d7dff0e770f0955011cf137f6e1e4e977d818", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8b9cc7f88a4dd1dbebed8b3ca60d7dff0e770f0955011cf137f6e1e4e977d818", kill_on_drop: false }`
[INFO] [stdout] 8b9cc7f88a4dd1dbebed8b3ca60d7dff0e770f0955011cf137f6e1e4e977d818
