Nov 16 16:03:12.124 INFO testing rbspy/rbspy against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 16 16:03:12.125 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:12.680 INFO [stdout] 57370d6b8d89d1e215f5dc5539ee0c8441f4d7f68452a1f6775ccda99b642f8d
Nov 16 16:03:12.682 INFO running `"docker" "start" "-a" "57370d6b8d89d1e215f5dc5539ee0c8441f4d7f68452a1f6775ccda99b642f8d"`
Nov 16 16:03:14.346 INFO [stderr] usermod: no changes
Nov 16 16:03:14.656 INFO [stderr]    Compiling rbspy-ruby-structs v0.1.0 (/source/ruby-structs)
Nov 16 16:03:14.659 INFO [stderr]    Compiling memchr v0.1.11
Nov 16 16:03:14.659 INFO [stderr]    Compiling term_size v0.3.1
Nov 16 16:03:14.659 INFO [stderr]    Compiling nix v0.9.0
Nov 16 16:03:14.659 INFO [stderr]    Compiling tempdir v0.3.5
Nov 16 16:03:14.659 INFO [stderr]    Compiling miniz-sys v0.1.10
Nov 16 16:03:14.659 INFO [stderr]    Compiling nix v0.10.0
Nov 16 16:03:14.659 INFO [stderr]    Compiling read-process-memory v0.1.2
Nov 16 16:03:15.964 INFO [stderr]    Compiling thread-id v2.0.0
Nov 16 16:03:16.047 INFO [stderr]    Compiling flate2 v1.0.1
Nov 16 16:03:16.840 INFO [stderr]    Compiling failure v0.1.1
Nov 16 16:03:16.975 INFO [stderr]    Compiling thread_local v0.2.7
Nov 16 16:03:18.287 INFO [stderr]    Compiling aho-corasick v0.5.3
Nov 16 16:03:20.809 INFO [stderr]    Compiling proc-maps v0.1.4
Nov 16 16:03:23.519 INFO [stderr]    Compiling regex v0.1.80
Nov 16 16:03:45.431 INFO [stderr]    Compiling env_logger v0.3.5
Nov 16 16:03:47.227 INFO [stderr]    Compiling ctrlc v3.1.0
Nov 16 16:04:23.280 INFO [stderr]    Compiling rbspy v0.3.2 (/source)
Nov 16 16:04:25.543 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Nov 16 16:04:25.543 INFO [stderr]   --> src/main.rs:78:1
Nov 16 16:04:25.544 INFO [stderr]    |
Nov 16 16:04:25.544 INFO [stderr] 78 | / arg_enum!{
Nov 16 16:04:25.544 INFO [stderr] 79 | |     // The values of this enum get translated directly to command line arguments. Make them
Nov 16 16:04:25.544 INFO [stderr] 80 | |     // lowercase so that we don't have camelcase command line arguments
Nov 16 16:04:25.544 INFO [stderr] 81 | |     #[derive(Clone, Eq, PartialEq, Ord, PartialOrd, Hash, Debug)]
Nov 16 16:04:25.544 INFO [stderr] ...  |
Nov 16 16:04:25.544 INFO [stderr] 89 | |     }
Nov 16 16:04:25.544 INFO [stderr] 90 | | }
Nov 16 16:04:25.544 INFO [stderr]    | |_^
Nov 16 16:04:25.544 INFO [stderr]    |
Nov 16 16:04:25.544 INFO [stderr]    = note: #[warn(deprecated)] on by default
Nov 16 16:04:25.544 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:25.544 INFO [stderr] 
Nov 16 16:04:48.471 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 34s
Nov 16 16:04:48.483 INFO [stderr] su: No module specific data is present
Nov 16 16:04:49.851 INFO running `"docker" "inspect" "57370d6b8d89d1e215f5dc5539ee0c8441f4d7f68452a1f6775ccda99b642f8d"`
Nov 16 16:04:50.167 INFO running `"docker" "rm" "-f" "57370d6b8d89d1e215f5dc5539ee0c8441f4d7f68452a1f6775ccda99b642f8d"`
Nov 16 16:04:50.451 INFO [stdout] 57370d6b8d89d1e215f5dc5539ee0c8441f4d7f68452a1f6775ccda99b642f8d
Nov 16 16:04:50.455 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:50.987 INFO [stdout] b498819cf3142355ee90a488080ee8563552dafb533d8ab94d40090dc9928fed
Nov 16 16:04:50.995 INFO running `"docker" "start" "-a" "b498819cf3142355ee90a488080ee8563552dafb533d8ab94d40090dc9928fed"`
Nov 16 16:04:52.126 INFO [stderr] usermod: no changes
Nov 16 16:04:52.358 INFO [stderr]    Compiling flate2 v0.2.20
Nov 16 16:04:53.813 INFO [stderr]    Compiling rbspy-testdata v0.1.2
Nov 16 16:04:55.415 INFO [stderr]    Compiling rbspy v0.3.2 (/source)
Nov 16 16:04:57.403 INFO [stderr] warning: use of deprecated item 'std::ascii::AsciiExt': use inherent methods instead
Nov 16 16:04:57.404 INFO [stderr]   --> src/main.rs:78:1
Nov 16 16:04:57.404 INFO [stderr]    |
Nov 16 16:04:57.404 INFO [stderr] 78 | / arg_enum!{
Nov 16 16:04:57.404 INFO [stderr] 79 | |     // The values of this enum get translated directly to command line arguments. Make them
Nov 16 16:04:57.404 INFO [stderr] 80 | |     // lowercase so that we don't have camelcase command line arguments
Nov 16 16:04:57.404 INFO [stderr] 81 | |     #[derive(Clone, Eq, PartialEq, Ord, PartialOrd, Hash, Debug)]
Nov 16 16:04:57.404 INFO [stderr] ...  |
Nov 16 16:04:57.404 INFO [stderr] 89 | |     }
Nov 16 16:04:57.404 INFO [stderr] 90 | | }
Nov 16 16:04:57.404 INFO [stderr]    | |_^
Nov 16 16:04:57.404 INFO [stderr]    |
Nov 16 16:04:57.404 INFO [stderr]    = note: #[warn(deprecated)] on by default
Nov 16 16:04:57.404 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:57.404 INFO [stderr] 
Nov 16 16:05:01.431 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:05:01.431 INFO [stderr]   --> src/ui/flamegraph.rs:71:9
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr] 71 |         stats.record(&vec![f(1)]);
Nov 16 16:05:01.431 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr]    = note: #[warn(unused_must_use)] on by default
Nov 16 16:05:01.431 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:05:01.431 INFO [stderr] 
Nov 16 16:05:01.431 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:05:01.431 INFO [stderr]   --> src/ui/flamegraph.rs:72:9
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr] 72 |         stats.record(&vec![f(2), f(1)]);
Nov 16 16:05:01.431 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:05:01.431 INFO [stderr] 
Nov 16 16:05:01.431 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:05:01.431 INFO [stderr]   --> src/ui/flamegraph.rs:73:9
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr] 73 |         stats.record(&vec![f(2), f(1)]);
Nov 16 16:05:01.431 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:05:01.431 INFO [stderr] 
Nov 16 16:05:01.431 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:05:01.431 INFO [stderr]   --> src/ui/flamegraph.rs:74:9
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr] 74 |         stats.record(&vec![f(2), f(3), f(1)]);
Nov 16 16:05:01.431 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:05:01.431 INFO [stderr] 
Nov 16 16:05:01.431 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:05:01.431 INFO [stderr]   --> src/ui/flamegraph.rs:75:9
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr] 75 |         stats.record(&vec![f(2), f(3), f(1)]);
Nov 16 16:05:01.431 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:05:01.431 INFO [stderr] 
Nov 16 16:05:01.431 INFO [stderr] warning: unused `std::result::Result` that must be used
Nov 16 16:05:01.431 INFO [stderr]   --> src/ui/flamegraph.rs:76:9
Nov 16 16:05:01.431 INFO [stderr]    |
Nov 16 16:05:01.431 INFO [stderr] 76 |         stats.record(&vec![f(2), f(3), f(1)]);
Nov 16 16:05:01.431 INFO [stderr]    |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Nov 16 16:05:01.432 INFO [stderr]    |
Nov 16 16:05:01.432 INFO [stderr]    = note: this `Result` may be an `Err` variant, which should be handled
Nov 16 16:05:01.432 INFO [stderr] 
Nov 16 16:05:16.143 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.98s
Nov 16 16:05:16.155 INFO [stderr] su: No module specific data is present
Nov 16 16:05:16.689 INFO running `"docker" "inspect" "b498819cf3142355ee90a488080ee8563552dafb533d8ab94d40090dc9928fed"`
Nov 16 16:05:16.960 INFO running `"docker" "rm" "-f" "b498819cf3142355ee90a488080ee8563552dafb533d8ab94d40090dc9928fed"`
Nov 16 16:05:17.157 INFO [stdout] b498819cf3142355ee90a488080ee8563552dafb533d8ab94d40090dc9928fed
Nov 16 16:05:17.169 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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:17.695 INFO [stdout] d67c36ea35de5d389ba937ac40552ee3980686b9e54539b52a2713cc0c8597a7
Nov 16 16:05:17.700 INFO running `"docker" "start" "-a" "d67c36ea35de5d389ba937ac40552ee3980686b9e54539b52a2713cc0c8597a7"`
Nov 16 16:05:18.175 INFO [stderr] usermod: no changes
Nov 16 16:05:18.295 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Nov 16 16:05:18.320 INFO [stderr]      Running /target/debug/deps/rbspy-b6268c8bd0c0e98c
Nov 16 16:05:18.320 INFO [stdout] 
Nov 16 16:05:18.320 INFO [stdout] running 22 tests
Nov 16 16:05:18.324 INFO [stdout] test core::initialize::test_get_disallowed_process ... ok
Nov 16 16:05:18.324 INFO [stdout] test core::initialize::test_get_nonexistent_process ... ok
Nov 16 16:05:18.391 INFO [stdout] test test_output_filename ... ok
Nov 16 16:05:18.647 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_1_9_3 ... ok
Nov 16 16:05:18.759 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_2_4_0 ... ok
Nov 16 16:05:18.764 INFO [stdout] test tests::test_report_arg_parsing ... ok
Nov 16 16:05:18.765 INFO [stdout] test ui::callgrind::tests::stats_aggregate ... ok
Nov 16 16:05:18.773 INFO [stdout] test ui::callgrind::tests::stats_write ... ok
Nov 16 16:05:18.776 INFO [stdout] test ui::descendents::test_get_descendents ... ok
Nov 16 16:05:18.783 INFO [stdout] test ui::descendents::test_get_descendents_depth_2 ... ok
Nov 16 16:05:18.787 INFO [stdout] test ui::descendents::test_status_file_ppid ... ok
Nov 16 16:05:18.819 INFO [stdout] test ui::flamegraph::test_write_flamegraph ... ok
Nov 16 16:05:18.820 INFO [stdout] test ui::flamegraph::tests::test_stats ... ok
Nov 16 16:05:18.821 INFO [stdout] test ui::summary::tests::stats_by_function ... ok
Nov 16 16:05:18.822 INFO [stdout] test ui::summary::tests::stats_by_line_number ... ok
Nov 16 16:05:18.846 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_2_5_0 ... ok
Nov 16 16:05:18.915 INFO [stdout] test core::ruby_version::tests::test_get_ruby_stack_trace_2_1_6 ... ok
Nov 16 16:05:18.952 INFO [stdout] test core::initialize::test_current_thread_address ... FAILED
Nov 16 16:05:18.952 INFO [stdout] test core::initialize::test_get_trace ... FAILED
Nov 16 16:05:18.953 INFO [stdout] test tests::test_arg_parsing ... FAILED
Nov 16 16:05:20.535 INFO [stderr] su: No module specific data is present
Nov 16 16:05:20.761 INFO running `"docker" "inspect" "d67c36ea35de5d389ba937ac40552ee3980686b9e54539b52a2713cc0c8597a7"`
Nov 16 16:05:20.836 INFO running `"docker" "rm" "-f" "d67c36ea35de5d389ba937ac40552ee3980686b9e54539b52a2713cc0c8597a7"`
Nov 16 16:05:20.955 INFO [stdout] d67c36ea35de5d389ba937ac40552ee3980686b9e54539b52a2713cc0c8597a7