Nov 16 16:03:10.932 INFO testing rbspy/rbspy against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 16:03:10.933 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 16:03:11.335 INFO [stdout] cb93259729b6ee81a685cf5f11e1b11bc44560200d21c1b1c7cb2624797a4846
Nov 16 16:03:11.341 INFO running `"docker" "start" "-a" "cb93259729b6ee81a685cf5f11e1b11bc44560200d21c1b1c7cb2624797a4846"`
Nov 16 16:03:12.546 INFO [stderr] usermod: no changes
Nov 16 16:03:12.835 INFO [stderr]    Compiling rbspy-ruby-structs v0.1.0 (/source/ruby-structs)
Nov 16 16:03:12.835 INFO [stderr]    Compiling miniz-sys v0.1.10
Nov 16 16:03:12.842 INFO [stderr]    Compiling term_size v0.3.1
Nov 16 16:03:12.849 INFO [stderr]    Compiling nix v0.9.0
Nov 16 16:03:12.850 INFO [stderr]    Compiling elf v0.0.10
Nov 16 16:03:12.853 INFO [stderr]    Compiling tempdir v0.3.5
Nov 16 16:03:12.854 INFO [stderr]    Compiling read-process-memory v0.1.2
Nov 16 16:03:12.854 INFO [stderr]    Compiling backtrace v0.3.5
Nov 16 16:03:14.335 INFO [stderr]    Compiling env_logger v0.3.5
Nov 16 16:03:18.071 INFO [stderr]    Compiling failure v0.1.1
Nov 16 16:03:20.135 INFO [stderr]    Compiling flate2 v1.0.1
Nov 16 16:03:20.549 INFO [stderr]    Compiling proc-maps v0.1.4
Nov 16 16:03:31.808 INFO [stderr]    Compiling ctrlc v3.1.0
Nov 16 16:04:05.180 INFO [stderr]    Compiling rbspy v0.3.2 (/source)
Nov 16 16:04:07.199 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Nov 16 16:04:07.199 INFO [stderr]   --> src/main.rs:78:1
Nov 16 16:04:07.199 INFO [stderr]    |
Nov 16 16:04:07.199 INFO [stderr] 78 | / arg_enum!{
Nov 16 16:04:07.199 INFO [stderr] 79 | |     // The values of this enum get translated directly to command line arguments. Make them
Nov 16 16:04:07.199 INFO [stderr] 80 | |     // lowercase so that we don't have camelcase command line arguments
Nov 16 16:04:07.199 INFO [stderr] 81 | |     #[derive(Clone, Eq, PartialEq, Ord, PartialOrd, Hash, Debug)]
Nov 16 16:04:07.199 INFO [stderr] ...  |
Nov 16 16:04:07.199 INFO [stderr] 89 | |     }
Nov 16 16:04:07.199 INFO [stderr] 90 | | }
Nov 16 16:04:07.199 INFO [stderr]    | |_^
Nov 16 16:04:07.199 INFO [stderr]    |
Nov 16 16:04:07.199 INFO [stderr]    = note: #[warn(deprecated)] on by default
Nov 16 16:04:07.199 INFO [stderr]    = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Nov 16 16:04:07.199 INFO [stderr] 
Nov 16 16:04:32.306 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 19s
Nov 16 16:04:32.317 INFO [stderr] su: No module specific data is present
Nov 16 16:04:34.224 INFO running `"docker" "inspect" "cb93259729b6ee81a685cf5f11e1b11bc44560200d21c1b1c7cb2624797a4846"`
Nov 16 16:04:34.621 INFO running `"docker" "rm" "-f" "cb93259729b6ee81a685cf5f11e1b11bc44560200d21c1b1c7cb2624797a4846"`
Nov 16 16:04:34.999 INFO [stdout] cb93259729b6ee81a685cf5f11e1b11bc44560200d21c1b1c7cb2624797a4846
Nov 16 16:04:35.009 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 16:04:35.526 INFO [stdout] cf90bfc9f736e7f877c2bf763afa31240bd664193ca83e617d716b3001c8f379
Nov 16 16:04:35.529 INFO running `"docker" "start" "-a" "cf90bfc9f736e7f877c2bf763afa31240bd664193ca83e617d716b3001c8f379"`
Nov 16 16:04:38.170 INFO [stderr] usermod: no changes
Nov 16 16:04:38.402 INFO [stderr]    Compiling flate2 v0.2.20
Nov 16 16:04:39.995 INFO [stderr]    Compiling rbspy-testdata v0.1.2
Nov 16 16:04:41.611 INFO [stderr]    Compiling rbspy v0.3.2 (/source)
Nov 16 16:04:43.603 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Nov 16 16:04:43.603 INFO [stderr]   --> src/main.rs:78:1
Nov 16 16:04:43.603 INFO [stderr]    |
Nov 16 16:04:43.603 INFO [stderr] 78 | / arg_enum!{
Nov 16 16:04:43.603 INFO [stderr] 79 | |     // The values of this enum get translated directly to command line arguments. Make them
Nov 16 16:04:43.603 INFO [stderr] 80 | |     // lowercase so that we don't have camelcase command line arguments
Nov 16 16:04:43.603 INFO [stderr] 81 | |     #[derive(Clone, Eq, PartialEq, Ord, PartialOrd, Hash, Debug)]
Nov 16 16:04:43.603 INFO [stderr] ...  |
Nov 16 16:04:43.603 INFO [stderr] 89 | |     }
Nov 16 16:04:43.603 INFO [stderr] 90 | | }
Nov 16 16:04:43.603 INFO [stderr]    | |_^
Nov 16 16:04:43.603 INFO [stderr]    |
Nov 16 16:04:43.603 INFO [stderr]    = note: #[warn(deprecated)] on by default
Nov 16 16:04:43.603 INFO [stderr]    = note: this error originates in a macro outside of the current crate (in Nightly builds, run with -Z external-macro-backtrace for more info)
Nov 16 16:04:43.603 INFO [stderr] 
Nov 16 16:04:48.075 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:04:48.075 INFO [stderr]   --> src/ui/flamegraph.rs:71:9
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr] 71 |         stats.record(&vec![f(1)]);
Nov 16 16:04:48.075 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Nov 16 16:04:48.075 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:04:48.075 INFO [stderr] 
Nov 16 16:04:48.075 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:04:48.075 INFO [stderr]   --> src/ui/flamegraph.rs:72:9
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr] 72 |         stats.record(&vec![f(2), f(1)]);
Nov 16 16:04:48.075 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:04:48.075 INFO [stderr] 
Nov 16 16:04:48.075 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:04:48.075 INFO [stderr]   --> src/ui/flamegraph.rs:73:9
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr] 73 |         stats.record(&vec![f(2), f(1)]);
Nov 16 16:04:48.075 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:04:48.075 INFO [stderr] 
Nov 16 16:04:48.075 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:04:48.075 INFO [stderr]   --> src/ui/flamegraph.rs:74:9
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr] 74 |         stats.record(&vec![f(2), f(3), f(1)]);
Nov 16 16:04:48.075 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:04:48.075 INFO [stderr] 
Nov 16 16:04:48.075 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:04:48.075 INFO [stderr]   --> src/ui/flamegraph.rs:75:9
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr] 75 |         stats.record(&vec![f(2), f(3), f(1)]);
Nov 16 16:04:48.075 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:04:48.075 INFO [stderr] 
Nov 16 16:04:48.075 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:04:48.075 INFO [stderr]   --> src/ui/flamegraph.rs:76:9
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr] 76 |         stats.record(&vec![f(2), f(3), f(1)]);
Nov 16 16:04:48.075 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:04:48.075 INFO [stderr]    |
Nov 16 16:04:48.075 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:04:48.075 INFO [stderr] 
Nov 16 16:05:01.142 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.95s
Nov 16 16:05:01.165 INFO [stderr] su: No module specific data is present
Nov 16 16:05:02.813 INFO running `"docker" "inspect" "cf90bfc9f736e7f877c2bf763afa31240bd664193ca83e617d716b3001c8f379"`
Nov 16 16:05:03.173 INFO running `"docker" "rm" "-f" "cf90bfc9f736e7f877c2bf763afa31240bd664193ca83e617d716b3001c8f379"`
Nov 16 16:05:03.499 INFO [stdout] cf90bfc9f736e7f877c2bf763afa31240bd664193ca83e617d716b3001c8f379
Nov 16 16:05:03.509 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +2e026d6e5d7621634147ad4c8074f1d964dbb1be-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Nov 16 16:05:03.985 INFO [stdout] f0c90967ccca8705d5a1f6b63a2865156f1ea92c620d7d34e99d9be4334d39c3
Nov 16 16:05:03.987 INFO running `"docker" "start" "-a" "f0c90967ccca8705d5a1f6b63a2865156f1ea92c620d7d34e99d9be4334d39c3"`
Nov 16 16:05:06.354 INFO [stderr] usermod: no changes
Nov 16 16:05:06.593 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.22s
Nov 16 16:05:06.611 INFO [stderr]      Running /target/debug/deps/rbspy-8ce4995c8efbd649
Nov 16 16:05:06.615 INFO [stdout] 
Nov 16 16:05:06.615 INFO [stdout] running 22 tests
Nov 16 16:05:06.663 INFO [stdout] test core::initialize::test_get_disallowed_process ... ok
Nov 16 16:05:06.663 INFO [stdout] test core::initialize::test_get_nonexistent_process ... ok
Nov 16 16:05:06.742 INFO [stdout] test test_output_filename ... ok
Nov 16 16:05:07.511 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_1_9_3 ... ok
Nov 16 16:05:08.159 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_2_5_0 ... ok
Nov 16 16:05:08.160 INFO [stdout] test tests::test_report_arg_parsing ... ok
Nov 16 16:05:08.161 INFO [stdout] test ui::callgrind::tests::stats_aggregate ... ok
Nov 16 16:05:08.162 INFO [stdout] test ui::callgrind::tests::stats_write ... ok
Nov 16 16:05:08.168 INFO [stdout] test ui::descendents::test_get_descendents ... ok
Nov 16 16:05:08.168 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_2_4_0 ... ok
Nov 16 16:05:08.169 INFO [stdout] test ui::descendents::test_get_descendents_depth_2 ... ok
Nov 16 16:05:08.175 INFO [stdout] test ui::descendents::test_status_file_ppid ... ok
Nov 16 16:05:08.211 INFO [stdout] test ui::flamegraph::tests::test_stats ... ok
Nov 16 16:05:08.212 INFO [stdout] test ui::summary::tests::stats_by_function ... ok
Nov 16 16:05:08.223 INFO [stdout] test ui::summary::tests::stats_by_line_number ... ok
Nov 16 16:05:08.382 INFO [stdout] test ui::flamegraph::test_write_flamegraph ... ok
Nov 16 16:05:08.557 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_2_1_6 ... ok
Nov 16 16:05:08.631 INFO [stdout] test core::initialize::test_get_trace ... FAILED
Nov 16 16:05:08.638 INFO [stdout] test core::initialize::test_current_thread_address ... FAILED
Nov 16 16:05:08.643 INFO [stdout] test tests::test_arg_parsing ... FAILED
Nov 16 16:05:09.187 INFO [stderr] su: No module specific data is present
Nov 16 16:05:10.251 INFO running `"docker" "inspect" "f0c90967ccca8705d5a1f6b63a2865156f1ea92c620d7d34e99d9be4334d39c3"`
Nov 16 16:05:10.541 INFO running `"docker" "rm" "-f" "f0c90967ccca8705d5a1f6b63a2865156f1ea92c620d7d34e99d9be4334d39c3"`
Nov 16 16:05:10.923 INFO [stdout] f0c90967ccca8705d5a1f6b63a2865156f1ea92c620d7d34e99d9be4334d39c3