Oct 15 17:25:47.393 INFO testing subprocess-0.1.16 against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 15 17:25:47.393 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 15 17:25:47.639 INFO blam! fe335dde0d46838b7f0006e90b07373bdd7e42eeee3d7f6010326fd35c983459
Oct 15 17:25:47.643 INFO running `"docker" "start" "-a" "fe335dde0d46838b7f0006e90b07373bdd7e42eeee3d7f6010326fd35c983459"`
Oct 15 17:25:48.349 INFO kablam! usermod: no changes
Oct 15 17:25:48.479 INFO kablam!    Compiling subprocess v0.1.16 (/source)
Oct 15 17:25:52.046 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 3.67s
Oct 15 17:25:52.050 INFO kablam! su: No module specific data is present
Oct 15 17:25:52.424 INFO running `"docker" "rm" "-f" "fe335dde0d46838b7f0006e90b07373bdd7e42eeee3d7f6010326fd35c983459"`
Oct 15 17:25:52.535 INFO blam! fe335dde0d46838b7f0006e90b07373bdd7e42eeee3d7f6010326fd35c983459
Oct 15 17:25:52.537 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 15 17:25:52.805 INFO blam! 0956dfc1847a6fc36311dc56b03f2a886d628b3ffb8402559de0487f53e9ecc3
Oct 15 17:25:52.807 INFO running `"docker" "start" "-a" "0956dfc1847a6fc36311dc56b03f2a886d628b3ffb8402559de0487f53e9ecc3"`
Oct 15 17:25:53.816 INFO kablam! usermod: no changes
Oct 15 17:25:53.911 INFO kablam!    Compiling subprocess v0.1.16 (/source)
Oct 15 17:25:59.975 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 6.14s
Oct 15 17:25:59.983 INFO kablam! su: No module specific data is present
Oct 15 17:26:00.666 INFO running `"docker" "rm" "-f" "0956dfc1847a6fc36311dc56b03f2a886d628b3ffb8402559de0487f53e9ecc3"`
Oct 15 17:26:00.918 INFO blam! 0956dfc1847a6fc36311dc56b03f2a886d628b3ffb8402559de0487f53e9ecc3
Oct 15 17:26:00.927 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-4/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-4/rustdoc-test-static-cling-1/try#40d4795669493f1965de7c44029c69552134fe1f:/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 +40d4795669493f1965de7c44029c69552134fe1f-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"`
Oct 15 17:26:01.347 INFO blam! ae9c1613747a384d5e52e100a27a128e6b01cd5f1dfe1544cf356f267db2d0a3
Oct 15 17:26:01.351 INFO running `"docker" "start" "-a" "ae9c1613747a384d5e52e100a27a128e6b01cd5f1dfe1544cf356f267db2d0a3"`
Oct 15 17:26:02.495 INFO kablam! usermod: no changes
Oct 15 17:26:02.591 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.06s
Oct 15 17:26:02.615 INFO blam! 
Oct 15 17:26:02.615 INFO blam! running 62 tests
Oct 15 17:26:02.615 INFO kablam!      Running /target/debug/deps/subprocess-0116e7d8cfe52a26
Oct 15 17:26:02.615 INFO blam! test posix::tests::test_split_path ... ok
Oct 15 17:26:02.623 INFO blam! test tests::builder::exec_to_string ... ok
Oct 15 17:26:02.624 INFO blam! test tests::builder::env_extend ... ok
Oct 15 17:26:02.624 INFO blam! test tests::builder::env_inherit ... ok
Oct 15 17:26:02.628 INFO blam! test tests::builder::env_inherit_set ... ok
Oct 15 17:26:02.639 INFO blam! test tests::builder::exec_join ... ok
Oct 15 17:26:02.639 INFO blam! test tests::builder::exec_shell ... ok
Oct 15 17:26:02.639 INFO blam! test tests::builder::env_set ... ok
Oct 15 17:26:02.663 INFO blam! test tests::builder::pipeline_invalid_1 ... ok
Oct 15 17:26:02.663 INFO blam! test tests::builder::pipeline_invalid_2 ... ok
Oct 15 17:26:02.669 INFO blam! test tests::builder::null_file ... ok
Oct 15 17:26:02.675 INFO blam! test tests::builder::pipeline_capture_error ... ok
Oct 15 17:26:02.675 INFO blam! test tests::builder::pipeline_capture ... ok
Oct 15 17:26:02.678 INFO blam! test tests::builder::pipeline_to_string ... ok
Oct 15 17:26:02.695 INFO blam! test tests::builder::pipeline_open ... ok
Oct 15 17:26:02.703 INFO blam! test tests::builder::pipeline_stream_in ... ok
Oct 15 17:26:02.705 INFO blam! test tests::builder::pipeline_stream_out ... ok
Oct 15 17:26:02.713 INFO blam! test tests::builder::stream_capture_err ... ok
Oct 15 17:26:02.714 INFO blam! test tests::builder::pipeline_compose_pipelines ... ok
Oct 15 17:26:02.717 INFO blam! test tests::builder::reject_input_data_stream_stderr ... ok
Oct 15 17:26:02.717 INFO blam! test tests::builder::pipeline_join ... ok
Oct 15 17:26:02.717 INFO blam! test tests::builder::reject_input_data_join ... ok
Oct 15 17:26:02.718 INFO blam! test tests::builder::reject_input_data_stream_stdout ... ok
Oct 15 17:26:02.718 INFO blam! test tests::builder::reject_input_data_popen ... ok
Oct 15 17:26:02.718 INFO blam! test tests::builder::reject_input_data_stream_stdin ... ok
Oct 15 17:26:02.721 INFO blam! test tests::builder::stream_capture_out ... ok
Oct 15 17:26:02.731 INFO blam! test tests::builder::stream_stderr ... ok
Oct 15 17:26:02.743 INFO blam! test tests::common::bad_cmd ... ok
Oct 15 17:26:02.743 INFO blam! test tests::builder::stream_capture_out_with_input_data2 ... ok
Oct 15 17:26:02.747 INFO blam! test tests::common::communicate_input ... ok
Oct 15 17:26:02.747 INFO blam! test tests::builder::stream_capture_out_with_input_data1 ... ok
Oct 15 17:26:02.747 INFO blam! test tests::builder::stream_stdout ... ok
Oct 15 17:26:02.747 INFO blam! test tests::builder::stream_stdin ... ok
Oct 15 17:26:02.750 INFO blam! test tests::common::err_exit ... ok
Oct 15 17:26:02.751 INFO blam! test tests::common::communicate_input_output ... ok
Oct 15 17:26:02.767 INFO blam! test tests::common::env_add ... ok
Oct 15 17:26:02.775 INFO blam! test tests::common::env_dup ... ok
Oct 15 17:26:02.788 INFO blam! test tests::common::input_from_file ... ok
Oct 15 17:26:02.788 INFO blam! test tests::common::failed_cwd ... ok
Oct 15 17:26:02.790 INFO blam! test tests::common::cwd ... ok
Oct 15 17:26:02.807 INFO blam! test tests::common::communicate_output ... ok
Oct 15 17:26:02.827 INFO blam! test tests::common::communicate_input_output_long ... ok
Oct 15 17:26:02.827 INFO blam! test tests::common::merge_err_to_out_pipe ... ok
Oct 15 17:26:02.827 INFO blam! test tests::common::input_output_from_file ... ok
Oct 15 17:26:02.828 INFO blam! test tests::common::good_cmd ... ok
Oct 15 17:26:02.828 INFO blam! test tests::common::null_byte_in_cmd ... ok
Oct 15 17:26:02.838 INFO blam! test tests::common::merge_err_to_out_file ... ok
Oct 15 17:26:02.840 INFO blam! test tests::common::reject_empty_argv ... ok
Oct 15 17:26:02.845 INFO blam! test tests::common::output_to_file ... ok
Oct 15 17:26:02.863 INFO blam! test tests::common::read_from_stdout ... ok
Oct 15 17:26:02.863 INFO blam! test tests::common::setup_executable ... ok
Oct 15 17:26:02.863 INFO blam! test tests::common::merge_out_to_err_pipe ... ok
Oct 15 17:26:02.887 INFO blam! test tests::common::simple_pipe ... ok
Oct 15 17:26:02.887 INFO blam! test tests::common::terminate ... ok
Oct 15 17:26:02.887 INFO blam! test tests::common::write_to_subprocess ... ok
Oct 15 17:26:02.887 INFO blam! test tests::posix::env_set_all_1 ... ok
Oct 15 17:26:02.891 INFO blam! test tests::posix::env_set_all_2 ... ok
Oct 15 17:26:02.899 INFO blam! test tests::posix::err_terminate ... ok
Oct 15 17:26:02.899 INFO blam! test tests::posix::waitpid_echild ... ok
Oct 15 17:26:02.899 INFO blam! test tests::posix::send_signal ... ok
Oct 15 17:26:02.967 INFO blam! test tests::common::terminate_twice ... ok
Oct 15 17:26:03.388 INFO blam! test tests::common::wait_timeout ... ok
Oct 15 17:26:03.388 INFO blam! 
Oct 15 17:26:03.388 INFO blam! test result: ok. 62 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:03.388 INFO blam! 
Oct 15 17:26:03.389 INFO kablam!      Running /target/debug/deps/just_echo-e9bd94eab11c8040
Oct 15 17:26:03.390 INFO blam! 
Oct 15 17:26:03.390 INFO blam! running 0 tests
Oct 15 17:26:03.395 INFO blam! 
Oct 15 17:26:03.395 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:03.395 INFO blam! 
Oct 15 17:26:03.395 INFO kablam!      Running /target/debug/deps/escape_args-1be4896864506135
Oct 15 17:26:03.396 INFO blam! 
Oct 15 17:26:03.396 INFO blam! running 1 test
Oct 15 17:26:03.506 INFO blam! test escape_args ... ok
Oct 15 17:26:03.506 INFO blam! 
Oct 15 17:26:03.506 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:03.506 INFO blam! 
Oct 15 17:26:03.519 INFO kablam!      Running /target/debug/deps/just_echo-9e01cfcd2fc17866
Oct 15 17:26:03.531 INFO blam! 
Oct 15 17:26:03.531 INFO blam! running 0 tests
Oct 15 17:26:03.531 INFO blam! 
Oct 15 17:26:03.531 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:03.531 INFO blam! 
Oct 15 17:26:03.532 INFO kablam!      Running /target/debug/deps/weird_args-9ddcc2b2e88efdba
Oct 15 17:26:03.535 INFO blam! 
Oct 15 17:26:03.535 INFO blam! running 1 test
Oct 15 17:26:03.596 INFO blam! test weird_args ... ok
Oct 15 17:26:03.596 INFO blam! 
Oct 15 17:26:03.596 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:03.597 INFO blam! 
Oct 15 17:26:03.597 INFO kablam!    Doc-tests subprocess
Oct 15 17:26:04.426 INFO blam! 
Oct 15 17:26:04.426 INFO blam! running 12 tests
Oct 15 17:26:06.055 INFO blam! test src/builder.rs - builder::pipeline::Pipeline (line 692) ... ok
Oct 15 17:26:06.535 INFO blam! test src/builder.rs - builder::exec::Exec (line 76) ... ok
Oct 15 17:26:06.719 INFO blam! test src/builder.rs - builder::exec::Exec (line 54) ... ok
Oct 15 17:26:07.039 INFO blam! test src/builder.rs - builder::exec::Exec (line 43) ... ok
Oct 15 17:26:07.084 INFO blam! test src/builder.rs - builder::exec::Exec (line 89) ... ok
Oct 15 17:26:07.323 INFO blam! test src/builder.rs - builder::pipeline::Pipeline (line 703) ... ok
Oct 15 17:26:07.361 INFO blam! test src/builder.rs - builder::exec::Exec (line 65) ... ok
Oct 15 17:26:07.439 INFO blam! test src/builder.rs - builder::exec::Exec (line 103) ... ok
Oct 15 17:26:07.879 INFO blam! test src/popen.rs - popen::Popen::create (line 335) ... ok
Oct 15 17:26:07.942 INFO blam! test src/lib.rs -  (line 30) ... ok
Oct 15 17:26:07.955 INFO blam! test src/lib.rs -  (line 53) ... ok
Oct 15 17:26:08.346 INFO blam! test src/popen.rs - popen::PopenConfig (line 135) ... ok
Oct 15 17:26:08.347 INFO blam! 
Oct 15 17:26:08.347 INFO blam! test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:08.347 INFO blam! 
Oct 15 17:26:08.351 INFO kablam! su: No module specific data is present
Oct 15 17:26:08.850 INFO running `"docker" "rm" "-f" "ae9c1613747a384d5e52e100a27a128e6b01cd5f1dfe1544cf356f267db2d0a3"`
Oct 15 17:26:09.151 INFO blam! ae9c1613747a384d5e52e100a27a128e6b01cd5f1dfe1544cf356f267db2d0a3