Nov 16 16:11:21.802 INFO testing randomPoison/frame-timing against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 16 16:11:21.802 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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:11:22.340 INFO [stdout] f7dfd94b925579841db1bacb24bda4717fa1577b32bfc135b1708e501eec86bb
Nov 16 16:11:22.347 INFO running `"docker" "start" "-a" "f7dfd94b925579841db1bacb24bda4717fa1577b32bfc135b1708e501eec86bb"`
Nov 16 16:11:23.211 INFO [stderr] usermod: no changes
Nov 16 16:11:23.287 INFO [stderr]    Compiling unicode-segmentation v0.1.3
Nov 16 16:11:23.288 INFO [stderr]    Compiling bencher v0.1.5
Nov 16 16:11:23.867 INFO [stderr]    Compiling clap v2.19.3
Nov 16 16:11:33.812 INFO [stderr]    Compiling frame-timing v0.1.0 (/source)
Nov 16 16:11:35.456 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.22s
Nov 16 16:11:35.460 INFO [stderr] su: No module specific data is present
Nov 16 16:11:35.788 INFO running `"docker" "inspect" "f7dfd94b925579841db1bacb24bda4717fa1577b32bfc135b1708e501eec86bb"`
Nov 16 16:11:35.980 INFO running `"docker" "rm" "-f" "f7dfd94b925579841db1bacb24bda4717fa1577b32bfc135b1708e501eec86bb"`
Nov 16 16:11:36.131 INFO [stdout] f7dfd94b925579841db1bacb24bda4717fa1577b32bfc135b1708e501eec86bb
Nov 16 16:11:36.135 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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:11:36.339 INFO [stdout] 547d0942f2d79b0fe6f897e1950427c46d222bd18e759db47c9173b2f3cc7e9c
Nov 16 16:11:36.344 INFO running `"docker" "start" "-a" "547d0942f2d79b0fe6f897e1950427c46d222bd18e759db47c9173b2f3cc7e9c"`
Nov 16 16:11:36.799 INFO [stderr] usermod: no changes
Nov 16 16:11:36.849 INFO [stderr]    Compiling frame-timing v0.1.0 (/source)
Nov 16 16:11:37.591 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.77s
Nov 16 16:11:37.595 INFO [stderr] su: No module specific data is present
Nov 16 16:11:37.992 INFO running `"docker" "inspect" "547d0942f2d79b0fe6f897e1950427c46d222bd18e759db47c9173b2f3cc7e9c"`
Nov 16 16:11:38.109 INFO running `"docker" "rm" "-f" "547d0942f2d79b0fe6f897e1950427c46d222bd18e759db47c9173b2f3cc7e9c"`
Nov 16 16:11:38.367 INFO [stdout] 547d0942f2d79b0fe6f897e1950427c46d222bd18e759db47c9173b2f3cc7e9c
Nov 16 16:11:38.379 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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:11:38.891 INFO [stdout] 8474a8f230d5e5006253f93143cc5161e8c0044087be9a8e3bef0a686213d173
Nov 16 16:11:38.893 INFO running `"docker" "start" "-a" "8474a8f230d5e5006253f93143cc5161e8c0044087be9a8e3bef0a686213d173"`
Nov 16 16:11:39.542 INFO [stderr] usermod: no changes
Nov 16 16:11:39.586 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 16 16:11:39.587 INFO [stderr]      Running /target/debug/deps/frame_timing-b8bf4bdd0381c5a0
Nov 16 16:11:39.589 INFO [stdout] 
Nov 16 16:11:39.590 INFO [stdout] running 0 tests
Nov 16 16:11:39.590 INFO [stdout] 
Nov 16 16:11:39.590 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 16 16:11:39.590 INFO [stdout] 
Nov 16 16:11:39.592 INFO [stderr] su: No module specific data is present
Nov 16 16:11:39.839 INFO running `"docker" "inspect" "8474a8f230d5e5006253f93143cc5161e8c0044087be9a8e3bef0a686213d173"`
Nov 16 16:11:39.902 INFO running `"docker" "rm" "-f" "8474a8f230d5e5006253f93143cc5161e8c0044087be9a8e3bef0a686213d173"`
Nov 16 16:11:40.010 INFO [stdout] 8474a8f230d5e5006253f93143cc5161e8c0044087be9a8e3bef0a686213d173