Oct 15 17:25:53.125 INFO testing subprocess-0.1.16 against master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513 for rustdoc-test-static-cling-1
Oct 15 17:25:53.125 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:53.507 INFO blam! 7be200312d46d708fa90b6fe508be9e1e408283cd6f4e9cc589f80d189df2ea8
Oct 15 17:25:53.509 INFO running `"docker" "start" "-a" "7be200312d46d708fa90b6fe508be9e1e408283cd6f4e9cc589f80d189df2ea8"`
Oct 15 17:25:54.807 INFO kablam! usermod: no changes
Oct 15 17:25:54.863 INFO kablam!    Compiling subprocess v0.1.16 (/source)
Oct 15 17:25:58.547 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 3.72s
Oct 15 17:25:58.551 INFO kablam! su: No module specific data is present
Oct 15 17:25:59.092 INFO running `"docker" "rm" "-f" "7be200312d46d708fa90b6fe508be9e1e408283cd6f4e9cc589f80d189df2ea8"`
Oct 15 17:25:59.430 INFO blam! 7be200312d46d708fa90b6fe508be9e1e408283cd6f4e9cc589f80d189df2ea8
Oct 15 17:25:59.439 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:59.923 INFO blam! ee7399cf4c577b728114eab4cc0ccc64b8decf71cc25f0aa8b53df9efad27a08
Oct 15 17:25:59.931 INFO running `"docker" "start" "-a" "ee7399cf4c577b728114eab4cc0ccc64b8decf71cc25f0aa8b53df9efad27a08"`
Oct 15 17:26:00.929 INFO kablam! usermod: no changes
Oct 15 17:26:01.001 INFO kablam!    Compiling subprocess v0.1.16 (/source)
Oct 15 17:26:06.695 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 5.73s
Oct 15 17:26:06.695 INFO kablam! su: No module specific data is present
Oct 15 17:26:07.331 INFO running `"docker" "rm" "-f" "ee7399cf4c577b728114eab4cc0ccc64b8decf71cc25f0aa8b53df9efad27a08"`
Oct 15 17:26:07.467 INFO blam! ee7399cf4c577b728114eab4cc0ccc64b8decf71cc25f0aa8b53df9efad27a08
Oct 15 17:26:07.469 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-0/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-0/rustdoc-test-static-cling-1/master#0e07c4281c343e9e15a0a8fca79538ad1a8eb513:/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 +0e07c4281c343e9e15a0a8fca79538ad1a8eb513-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:07.749 INFO blam! f53bcf2df7eea6608438af6dcb3a163a4603abc8cafc8be6563060493f835ad9
Oct 15 17:26:07.755 INFO running `"docker" "start" "-a" "f53bcf2df7eea6608438af6dcb3a163a4603abc8cafc8be6563060493f835ad9"`
Oct 15 17:26:08.223 INFO kablam! usermod: no changes
Oct 15 17:26:08.269 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Oct 15 17:26:08.269 INFO kablam!      Running /target/debug/deps/subprocess-02ca18cfca682ef1
Oct 15 17:26:08.271 INFO blam! 
Oct 15 17:26:08.271 INFO blam! running 62 tests
Oct 15 17:26:08.275 INFO blam! test posix::tests::test_split_path ... ok
Oct 15 17:26:08.280 INFO blam! test tests::builder::exec_to_string ... ok
Oct 15 17:26:08.281 INFO blam! test tests::builder::null_file ... ok
Oct 15 17:26:08.285 INFO blam! test tests::builder::env_inherit_set ... ok
Oct 15 17:26:08.285 INFO blam! test tests::builder::env_inherit ... ok
Oct 15 17:26:08.288 INFO blam! test tests::builder::exec_join ... ok
Oct 15 17:26:08.293 INFO blam! test tests::builder::env_extend ... ok
Oct 15 17:26:08.293 INFO blam! test tests::builder::env_set ... ok
Oct 15 17:26:08.294 INFO blam! test tests::builder::exec_shell ... ok
Oct 15 17:26:08.299 INFO blam! test tests::builder::pipeline_invalid_1 ... ok
Oct 15 17:26:08.304 INFO blam! test tests::builder::pipeline_invalid_2 ... ok
Oct 15 17:26:08.306 INFO blam! test tests::builder::pipeline_compose_pipelines ... ok
Oct 15 17:26:08.306 INFO blam! test tests::builder::pipeline_capture ... ok
Oct 15 17:26:08.306 INFO blam! test tests::builder::pipeline_stream_out ... ok
Oct 15 17:26:08.308 INFO blam! test tests::builder::pipeline_capture_error ... ok
Oct 15 17:26:08.308 INFO blam! test tests::builder::pipeline_stream_in ... ok
Oct 15 17:26:08.309 INFO blam! test tests::builder::pipeline_to_string ... ok
Oct 15 17:26:08.310 INFO blam! test tests::builder::pipeline_open ... ok
Oct 15 17:26:08.313 INFO blam! test tests::builder::pipeline_join ... ok
Oct 15 17:26:08.314 INFO blam! test tests::builder::stream_capture_out ... ok
Oct 15 17:26:08.319 INFO blam! test tests::builder::stream_capture_err ... ok
Oct 15 17:26:08.322 INFO blam! test tests::builder::stream_capture_out_with_input_data2 ... ok
Oct 15 17:26:08.324 INFO blam! test tests::builder::stream_stderr ... ok
Oct 15 17:26:08.324 INFO blam! test tests::builder::stream_capture_out_with_input_data1 ... ok
Oct 15 17:26:08.327 INFO blam! test tests::common::bad_cmd ... ok
Oct 15 17:26:08.327 INFO blam! test tests::builder::stream_stdin ... ok
Oct 15 17:26:08.333 INFO blam! test tests::builder::stream_stdout ... ok
Oct 15 17:26:08.333 INFO blam! test tests::common::communicate_input ... ok
Oct 15 17:26:08.333 INFO blam! test tests::common::communicate_input_output ... ok
Oct 15 17:26:08.348 INFO blam! test tests::builder::reject_input_data_join ... ok
Oct 15 17:26:08.348 INFO blam! test tests::builder::reject_input_data_popen ... ok
Oct 15 17:26:08.348 INFO blam! test tests::builder::reject_input_data_stream_stderr ... ok
Oct 15 17:26:08.348 INFO blam! test tests::builder::reject_input_data_stream_stdin ... ok
Oct 15 17:26:08.348 INFO blam! test tests::builder::reject_input_data_stream_stdout ... ok
Oct 15 17:26:08.348 INFO blam! test tests::common::cwd ... ok
Oct 15 17:26:08.348 INFO blam! test tests::common::env_add ... ok
Oct 15 17:26:08.349 INFO blam! test tests::common::communicate_output ... ok
Oct 15 17:26:08.352 INFO blam! test tests::common::err_exit ... ok
Oct 15 17:26:08.352 INFO blam! test tests::common::failed_cwd ... ok
Oct 15 17:26:08.352 INFO blam! test tests::common::merge_err_to_out_file ... ok
Oct 15 17:26:08.354 INFO blam! test tests::common::communicate_input_output_long ... ok
Oct 15 17:26:08.354 INFO blam! test tests::common::input_from_file ... ok
Oct 15 17:26:08.356 INFO blam! test tests::common::good_cmd ... ok
Oct 15 17:26:08.356 INFO blam! test tests::common::reject_empty_argv ... ok
Oct 15 17:26:08.357 INFO blam! test tests::common::env_dup ... ok
Oct 15 17:26:08.358 INFO blam! test tests::common::input_output_from_file ... ok
Oct 15 17:26:08.358 INFO blam! test tests::common::setup_executable ... ok
Oct 15 17:26:08.359 INFO blam! test tests::common::merge_err_to_out_pipe ... ok
Oct 15 17:26:08.362 INFO blam! test tests::common::null_byte_in_cmd ... ok
Oct 15 17:26:08.362 INFO blam! test tests::common::output_to_file ... ok
Oct 15 17:26:08.366 INFO blam! test tests::common::simple_pipe ... ok
Oct 15 17:26:08.366 INFO blam! test tests::common::read_from_stdout ... ok
Oct 15 17:26:08.368 INFO blam! test tests::posix::env_set_all_2 ... ok
Oct 15 17:26:08.369 INFO blam! test tests::posix::err_terminate ... ok
Oct 15 17:26:08.370 INFO blam! test tests::posix::env_set_all_1 ... ok
Oct 15 17:26:08.370 INFO blam! test tests::posix::send_signal ... ok
Oct 15 17:26:08.374 INFO blam! test tests::posix::waitpid_echild ... ok
Oct 15 17:26:08.374 INFO blam! test tests::common::merge_out_to_err_pipe ... ok
Oct 15 17:26:08.374 INFO blam! test tests::common::write_to_subprocess ... ok
Oct 15 17:26:08.374 INFO blam! test tests::common::terminate ... ok
Oct 15 17:26:08.464 INFO blam! test tests::common::terminate_twice ... ok
Oct 15 17:26:08.907 INFO blam! test tests::common::wait_timeout ... ok
Oct 15 17:26:08.907 INFO kablam!      Running /target/debug/deps/just_echo-d9eacc8b7a11c9b2
Oct 15 17:26:08.907 INFO blam! 
Oct 15 17:26:08.907 INFO blam! test result: ok. 62 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:08.907 INFO blam! 
Oct 15 17:26:08.907 INFO blam! 
Oct 15 17:26:08.907 INFO blam! running 0 tests
Oct 15 17:26:08.907 INFO blam! 
Oct 15 17:26:08.907 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:08.907 INFO blam! 
Oct 15 17:26:08.915 INFO blam! 
Oct 15 17:26:08.915 INFO kablam!      Running /target/debug/deps/escape_args-58b8939c7c8423bd
Oct 15 17:26:08.915 INFO blam! running 1 test
Oct 15 17:26:08.991 INFO blam! test escape_args ... ok
Oct 15 17:26:08.991 INFO blam! 
Oct 15 17:26:08.991 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:08.991 INFO blam! 
Oct 15 17:26:08.994 INFO kablam!      Running /target/debug/deps/just_echo-6cc442b337e5ebff
Oct 15 17:26:08.999 INFO kablam!      Running /target/debug/deps/weird_args-73ca48aaefb318e8
Oct 15 17:26:08.999 INFO blam! 
Oct 15 17:26:08.999 INFO blam! running 0 tests
Oct 15 17:26:08.999 INFO blam! 
Oct 15 17:26:08.999 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:08.999 INFO blam! 
Oct 15 17:26:09.006 INFO blam! 
Oct 15 17:26:09.007 INFO blam! running 1 test
Oct 15 17:26:09.039 INFO blam! test weird_args ... ok
Oct 15 17:26:09.039 INFO kablam!    Doc-tests subprocess
Oct 15 17:26:09.039 INFO blam! 
Oct 15 17:26:09.039 INFO blam! test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 15 17:26:09.039 INFO blam! 
Oct 15 17:26:09.869 INFO blam! 
Oct 15 17:26:09.869 INFO blam! running 12 tests
Oct 15 17:26:11.683 INFO blam! test src/builder.rs - builder::exec::Exec (line 54) ... ok
Oct 15 17:26:11.779 INFO blam! test src/builder.rs - builder::pipeline::Pipeline (line 692) ... ok
Oct 15 17:26:12.263 INFO blam! test src/builder.rs - builder::exec::Exec (line 76) ... ok
Oct 15 17:26:12.272 INFO blam! test src/builder.rs - builder::exec::Exec (line 103) ... ok
Oct 15 17:26:12.467 INFO blam! test src/builder.rs - builder::exec::Exec (line 43) ... ok
Oct 15 17:26:12.606 INFO blam! test src/builder.rs - builder::pipeline::Pipeline (line 703) ... ok
Oct 15 17:26:12.643 INFO blam! test src/builder.rs - builder::exec::Exec (line 65) ... ok
Oct 15 17:26:12.875 INFO blam! test src/builder.rs - builder::exec::Exec (line 89) ... ok
Oct 15 17:26:14.175 INFO blam! test src/popen.rs - popen::Popen::create (line 335) ... ok
Oct 15 17:26:14.568 INFO blam! test src/lib.rs -  (line 53) ... ok
Oct 15 17:26:14.619 INFO blam! test src/lib.rs -  (line 30) ... ok
Oct 15 17:26:14.944 INFO kablam! su: No module specific data is present
Oct 15 17:26:15.439 INFO running `"docker" "rm" "-f" "f53bcf2df7eea6608438af6dcb3a163a4603abc8cafc8be6563060493f835ad9"`
Oct 15 17:26:15.631 INFO blam! f53bcf2df7eea6608438af6dcb3a163a4603abc8cafc8be6563060493f835ad9