Dec 14 08:35:13.645 INFO testing LegNeato/asciinema-rs against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 08:35:13.645 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 14 08:35:14.076 INFO [stdout] 558448848c7fa02456c1dfa12a428c2b0eac7dc9a0e5bb0d7d4e65d57022f45e
Dec 14 08:35:14.081 INFO running `"docker" "start" "-a" "558448848c7fa02456c1dfa12a428c2b0eac7dc9a0e5bb0d7d4e65d57022f45e"`
Dec 14 08:35:15.545 INFO [stderr] usermod: no changes
Dec 14 08:35:16.264 INFO [stderr]    Compiling errno v0.1.8
Dec 14 08:35:16.274 INFO [stderr]    Compiling nix v0.10.0
Dec 14 08:35:16.275 INFO [stderr]    Compiling uuid v0.6.5
Dec 14 08:35:16.275 INFO [stderr]    Compiling tokio-threadpool v0.1.8
Dec 14 08:35:16.279 INFO [stderr]    Compiling miow v0.1.5
Dec 14 08:35:16.279 INFO [stderr]    Compiling serde-hjson v0.8.2
Dec 14 08:35:16.279 INFO [stderr]    Compiling os_type v2.2.0
Dec 14 08:35:16.279 INFO [stderr]    Compiling asciicast v0.2.2
Dec 14 08:35:18.623 INFO [stderr]    Compiling mio v0.5.1
Dec 14 08:35:20.523 INFO [stderr]    Compiling pty v0.2.2
Dec 14 08:35:28.283 INFO [stderr]    Compiling config v0.9.1
Dec 14 08:35:28.359 INFO [stderr]    Compiling tokio-fs v0.1.4
Dec 14 08:35:32.823 INFO [stderr]    Compiling tokio v0.1.11
Dec 14 08:35:35.219 INFO [stderr]    Compiling pty-shell v0.3.0 (/source/pty-shell)
Dec 14 08:35:38.979 INFO [stderr]    Compiling hyper v0.12.13
Dec 14 08:35:48.134 INFO [stderr]    Compiling hyper-tls v0.3.1
Dec 14 08:35:51.012 INFO [stderr]    Compiling reqwest v0.9.4
Dec 14 08:35:59.839 INFO [stderr]    Compiling asciinema v0.5.2-pre (/source)
Dec 14 08:36:21.390 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1m 05s
Dec 14 08:36:21.422 INFO [stderr] su: No module specific data is present
Dec 14 08:36:22.537 INFO running `"docker" "inspect" "558448848c7fa02456c1dfa12a428c2b0eac7dc9a0e5bb0d7d4e65d57022f45e"`
Dec 14 08:36:22.877 INFO running `"docker" "rm" "-f" "558448848c7fa02456c1dfa12a428c2b0eac7dc9a0e5bb0d7d4e65d57022f45e"`
Dec 14 08:36:23.155 INFO [stdout] 558448848c7fa02456c1dfa12a428c2b0eac7dc9a0e5bb0d7d4e65d57022f45e
Dec 14 08:36:23.163 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 14 08:36:23.752 INFO [stdout] d95af005e97d521bad4fd63312bc9698a6162e6cd94b752191188e7cc209ec4d
Dec 14 08:36:23.754 INFO running `"docker" "start" "-a" "d95af005e97d521bad4fd63312bc9698a6162e6cd94b752191188e7cc209ec4d"`
Dec 14 08:36:25.710 INFO [stderr] usermod: no changes
Dec 14 08:36:26.060 INFO [stderr]    Compiling asciinema v0.5.2-pre (/source)
Dec 14 08:36:40.628 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.89s
Dec 14 08:36:40.655 INFO [stderr] su: No module specific data is present
Dec 14 08:36:42.184 INFO running `"docker" "inspect" "d95af005e97d521bad4fd63312bc9698a6162e6cd94b752191188e7cc209ec4d"`
Dec 14 08:36:42.449 INFO running `"docker" "rm" "-f" "d95af005e97d521bad4fd63312bc9698a6162e6cd94b752191188e7cc209ec4d"`
Dec 14 08:36:42.699 INFO [stdout] d95af005e97d521bad4fd63312bc9698a6162e6cd94b752191188e7cc209ec4d
Dec 14 08:36:42.704 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-0/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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"`
Dec 14 08:36:43.221 INFO [stdout] 3e784ec2285cd50b4ed146fdecf68e90e24dd70c98976529cb29b05d46e04c3a
Dec 14 08:36:43.223 INFO running `"docker" "start" "-a" "3e784ec2285cd50b4ed146fdecf68e90e24dd70c98976529cb29b05d46e04c3a"`
Dec 14 08:36:44.435 INFO [stderr] usermod: no changes
Dec 14 08:36:44.784 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.32s
Dec 14 08:36:44.823 INFO [stderr]      Running /target/debug/deps/asciinema-89eadd903d08371b
Dec 14 08:36:44.834 INFO [stdout] 
Dec 14 08:36:44.834 INFO [stdout] running 33 tests
Dec 14 08:36:44.835 INFO [stdout] test clock::tests::test_elapsed_fractional_seconds ... ok
Dec 14 08:36:44.835 INFO [stdout] test api::tests::upload_url ... ok
Dec 14 08:36:44.835 INFO [stdout] test api::tests::normalized_upload_url ... ok
Dec 14 08:36:44.835 INFO [stdout] test api::tests::normalized_base_url ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_capturing_env_for_header ... ok
Dec 14 08:36:44.839 INFO [stdout] test api::tests::normalized_authentication_url ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_env_copies_parent ... ok
Dec 14 08:36:44.839 INFO [stdout] test clock::tests::test_elapsed_whole_seconds ... ok
Dec 14 08:36:44.839 INFO [stdout] test api::tests::base_url ... ok
Dec 14 08:36:44.839 INFO [stdout] test api::tests::authentication_url ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_env_sets_asciinema_rec ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_existent_output_path ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_nonexistent_output_path_with_append ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_nonexistent_output_path ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_existent_output_path_with_append ... ok
Dec 14 08:36:44.839 INFO [stdout] test commands::record::tests::test_unset_output_path ... ok
Dec 14 08:36:44.843 INFO [stdout] test commands::record::tests::test_existent_output_path_with_overwrite ... ok
Dec 14 08:36:44.851 INFO [stdout] test output_formats::asciicast::tests::test_writes_input_event ... ok
Dec 14 08:36:44.851 INFO [stdout] test output_formats::asciicast::tests::test_writes_header ... ok
Dec 14 08:36:44.851 INFO [stdout] test output_formats::asciicast::tests::test_writes_output_event ... ok
Dec 14 08:36:44.851 INFO [stdout] test output_formats::raw::tests::test_writes_input_event ... ok
Dec 14 08:36:44.851 INFO [stdout] test output_formats::raw::tests::test_does_not_write_header ... ok
Dec 14 08:36:44.851 INFO [stdout] test output_formats::raw::tests::test_writes_output_event ... ok
Dec 14 08:36:44.851 INFO [stdout] test output_formats::tests::thread_stops_on_error ... ok
Dec 14 08:36:44.851 INFO [stdout] test session::tests::sends_message_for_header ... ok
Dec 14 08:36:44.851 INFO [stdout] test settings::config::tests::no_home ... ok
Dec 14 08:36:44.851 INFO [stdout] test settings::config::tests::home ... ok
Dec 14 08:36:44.851 INFO [stdout] test settings::config::tests::env_overwrites_api_url ... ok
Dec 14 08:36:44.851 INFO [stdout] test settings::config::tests::asciinema_config_home ... ok
Dec 14 08:36:44.851 INFO [stdout] test settings::config::tests::xdg_config_home ... ok
Dec 14 08:36:44.851 INFO [stdout] test session::tests::sends_message_for_output ... ok
Dec 14 08:36:44.851 INFO [stdout] test session::tests::sends_message_for_input ... ok
Dec 14 08:36:44.859 INFO [stdout] test output_formats::tests::thread_stops_on_finish_message ... ok
Dec 14 08:36:44.859 INFO [stdout] 
Dec 14 08:36:44.859 INFO [stdout] test result: ok. 33 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 08:36:44.859 INFO [stdout] 
Dec 14 08:36:44.867 INFO [stderr] su: No module specific data is present
Dec 14 08:36:45.561 INFO running `"docker" "inspect" "3e784ec2285cd50b4ed146fdecf68e90e24dd70c98976529cb29b05d46e04c3a"`
Dec 14 08:36:45.763 INFO running `"docker" "rm" "-f" "3e784ec2285cd50b4ed146fdecf68e90e24dd70c98976529cb29b05d46e04c3a"`
Dec 14 08:36:46.015 INFO [stdout] 3e784ec2285cd50b4ed146fdecf68e90e24dd70c98976529cb29b05d46e04c3a