Oct 31 18:07:32.538 INFO testing troiganto/scenarios against beta-2018-10-30 for beta-1.31-1
Oct 31 18:07:32.540 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Oct 31 18:07:32.887 INFO blam! 580e416abba930e0a0b7f60bacdbd801bd60b0171770e5d1748969ec58c0cde3
Oct 31 18:07:32.895 INFO running `"docker" "start" "-a" "580e416abba930e0a0b7f60bacdbd801bd60b0171770e5d1748969ec58c0cde3"`
Oct 31 18:07:33.943 INFO kablam! usermod: no changes
Oct 31 18:07:34.163 INFO kablam!    Compiling cc v1.0.3
Oct 31 18:07:34.165 INFO kablam!    Compiling iovec v0.1.2
Oct 31 18:07:34.165 INFO kablam!    Compiling term_size v0.3.1
Oct 31 18:07:34.775 INFO kablam!    Compiling textwrap v0.9.0
Oct 31 18:07:35.042 INFO kablam!    Compiling bytes v0.4.6
Oct 31 18:07:35.043 INFO kablam!    Compiling mio v0.6.13
Oct 31 18:07:36.291 INFO kablam!    Compiling clap v2.29.0
Oct 31 18:07:39.820 INFO kablam!    Compiling tokio-io v0.1.5
Oct 31 18:07:40.091 INFO kablam!    Compiling backtrace-sys v0.1.16
Oct 31 18:07:42.643 INFO kablam!    Compiling mio-uds v0.6.4
Oct 31 18:07:43.211 INFO kablam!    Compiling tokio-core v0.1.12
Oct 31 18:07:46.667 INFO kablam!    Compiling tokio-signal v0.1.4
Oct 31 18:07:47.907 INFO kablam!    Compiling tokio-process v0.1.5
Oct 31 18:08:03.760 INFO kablam!    Compiling backtrace v0.3.5
Oct 31 18:08:05.491 INFO kablam!    Compiling failure v0.1.1
Oct 31 18:08:08.099 INFO kablam!    Compiling scenarios v0.2.0 (/source)
Oct 31 18:08:11.905 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 37.93s
Oct 31 18:08:11.911 INFO kablam! su: No module specific data is present
Oct 31 18:08:12.823 INFO running `"docker" "rm" "-f" "580e416abba930e0a0b7f60bacdbd801bd60b0171770e5d1748969ec58c0cde3"`
Oct 31 18:08:13.155 INFO blam! 580e416abba930e0a0b7f60bacdbd801bd60b0171770e5d1748969ec58c0cde3
Oct 31 18:08:13.179 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Oct 31 18:08:13.691 INFO blam! 4463f847a595f7bfbd7b734699f8d3efb44545d9298051d98817045be48d1b0b
Oct 31 18:08:13.699 INFO running `"docker" "start" "-a" "4463f847a595f7bfbd7b734699f8d3efb44545d9298051d98817045be48d1b0b"`
Oct 31 18:08:15.366 INFO kablam! usermod: no changes
Oct 31 18:08:15.490 INFO kablam!    Compiling scenarios v0.2.0 (/source)
Oct 31 18:08:24.367 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 8.97s
Oct 31 18:08:24.379 INFO kablam! su: No module specific data is present
Oct 31 18:08:25.534 INFO running `"docker" "rm" "-f" "4463f847a595f7bfbd7b734699f8d3efb44545d9298051d98817045be48d1b0b"`
Oct 31 18:08:25.789 INFO blam! 4463f847a595f7bfbd7b734699f8d3efb44545d9298051d98817045be48d1b0b
Oct 31 18:08:25.803 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-1/worker-5/beta-2018-10-30:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-1/beta-2018-10-30:/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 +beta-2018-10-30 test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Oct 31 18:08:26.405 INFO blam! 8586930a242c1be18d7776cb666013c9eab7e6f80b8627effe99dc764752d9c5
Oct 31 18:08:26.419 INFO running `"docker" "start" "-a" "8586930a242c1be18d7776cb666013c9eab7e6f80b8627effe99dc764752d9c5"`
Oct 31 18:08:27.745 INFO kablam! usermod: no changes
Oct 31 18:08:27.935 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.15s
Oct 31 18:08:27.936 INFO blam! 
Oct 31 18:08:27.936 INFO kablam!      Running /target/debug/deps/scenarios-c4c9d6189019ef37
Oct 31 18:08:27.936 INFO blam! running 66 tests
Oct 31 18:08:27.937 INFO blam! test app::tests::choose ... ok
Oct 31 18:08:27.937 INFO blam! test app::tests::exec_prefix_terminator ... ok
Oct 31 18:08:27.937 INFO blam! test app::tests::flags_that_require_exec ... ok
Oct 31 18:08:27.938 INFO blam! test app::tests::delimiter ... ok
Oct 31 18:08:27.938 INFO blam! test app::tests::delimiter_arg_required ... ok
Oct 31 18:08:27.938 INFO blam! test app::tests::delimiter_no_default ... ok
Oct 31 18:08:27.939 INFO blam! test app::tests::default_action ... ok
Oct 31 18:08:27.939 INFO blam! test app::tests::exclude ... ok
Oct 31 18:08:27.939 INFO blam! test app::tests::exec ... ok
Oct 31 18:08:27.940 INFO blam! test app::tests::exec_prefix_takes_all ... ok
Oct 31 18:08:27.940 INFO blam! test app::tests::jobs_empty_value_allowed ... ok
Oct 31 18:08:27.940 INFO blam! test app::tests::jobs_no_arg_required ... ok
Oct 31 18:08:27.943 INFO blam! test app::tests::print_no_args_suffix ... ok
Oct 31 18:08:27.943 INFO blam! test app::tests::print_with_args_prefix_bad ... ok
Oct 31 18:08:27.943 INFO blam! test app::tests::print_print0_exec_conflicts ... ok
Oct 31 18:08:27.943 INFO blam! test app::tests::jobs ... ok
Oct 31 18:08:27.943 INFO blam! test app::tests::jobs_default ... ok
Oct 31 18:08:27.943 INFO blam! test app::tests::print_with_args_prefix_equals ... ok
Oct 31 18:08:27.943 INFO blam! test app::tests::print_with_args_prefix_sep ... ok
Oct 31 18:08:27.943 INFO blam! test cartesian::tests::lengths::test_length ... ok
Oct 31 18:08:27.943 INFO blam! test cartesian::tests::lengths::test_nullary_product ... ok
Oct 31 18:08:27.947 INFO blam! test app::tests::print_with_args_suffix ... ok
Oct 31 18:08:27.947 INFO blam! test app::tests::print_with_equals_and_delim_arg ... ok
Oct 31 18:08:27.947 INFO blam! test cartesian::tests::lengths::test_empty_vector ... ok
Oct 31 18:08:27.947 INFO blam! test cartesian::tests::lengths::test_unequal_length ... ok
Oct 31 18:08:27.947 INFO blam! test cartesian::tests::types::test_string ... ok
Oct 31 18:08:27.947 INFO blam! test cartesian::tests::types::test_slices ... ok
Oct 31 18:08:27.947 INFO blam! test consumers::printer::tests::test_broken_pattern ... ok
Oct 31 18:08:27.947 INFO blam! test consumers::printer::tests::test_default ... ok
Oct 31 18:08:27.947 INFO blam! test consumers::printer::tests::test_null ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::filter::tests::test_choose ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::filter::tests::test_default ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::filter::tests::test_exclusion ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::filter::tests::test_ignore ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::inputline::tests::test_comment ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::inputline::tests::test_definition ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::inputline::tests::test_header ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::inputline::tests::test_size_of_inputline ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::location::tests::test_display ... ok
Oct 31 18:08:27.947 INFO blam! test scenarios::location::tests::test_display_without_lineno ... ok
Oct 31 18:08:27.955 INFO blam! test scenarios::scenario::tests::test_is_c_identifier ... ok
Oct 31 18:08:27.955 INFO blam! test app::tests::jobs_no_exec_required ... ok
Oct 31 18:08:27.967 INFO blam! test consumers::commandline::tests::test_echo ... ok
Oct 31 18:08:27.967 INFO blam! test app::tests::print_no_args_prefix ... ok
Oct 31 18:08:27.967 INFO blam! test app::tests::input ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario::tests::test_merge_error_three ... ok
Oct 31 18:08:27.967 INFO blam! test cartesian::tests::types::test_i32 ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario::tests::test_lax_merge ... ok
Oct 31 18:08:27.967 INFO blam! test consumers::commandline::tests::test_insert_name ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario::tests::test_scenario_new ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_invalid_header ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_invalid_scenario_name ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_invalid_variable_def ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_invalid_variable_name ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_iter_from_file ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_non_unique_names ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_non_unique_names_allowed ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_unexpected_vardef ... ok
Oct 31 18:08:27.967 INFO blam! test scenarios::scenario_file::tests::test_variable_already_defined ... ok
Oct 31 18:08:27.975 INFO blam! test consumers::printer::tests::test_complicated_pattern ... ok
Oct 31 18:08:27.975 INFO blam! test scenarios::scenario::tests::test_merge_one ... ok
Oct 31 18:08:27.975 INFO blam! test scenarios::scenario::tests::test_multi_merge ... ok
Oct 31 18:08:27.975 INFO blam! test scenarios::scenario::tests::test_merge_error_two ... ok
Oct 31 18:08:27.975 INFO blam! test scenarios::scenario::tests::test_merge_two ... ok
Oct 31 18:08:27.975 INFO blam! test scenarios::scenario::tests::test_scenario_add_variable ... ok
Oct 31 18:08:28.117 INFO blam! test scenarios::scenario::tests::test_merge_none_panics ... ok
Oct 31 18:08:28.117 INFO blam! 
Oct 31 18:08:28.117 INFO blam! test result: ok. 66 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 31 18:08:28.117 INFO blam! 
Oct 31 18:08:28.118 INFO kablam!      Running /target/debug/deps/runner-02e6a2b99976c730
Oct 31 18:08:28.124 INFO blam! 
Oct 31 18:08:28.124 INFO blam! running 0 tests
Oct 31 18:08:28.124 INFO blam! 
Oct 31 18:08:28.124 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 31 18:08:28.124 INFO blam! 
Oct 31 18:08:28.131 INFO kablam!      Running /target/debug/deps/tests-86268afc5650febd
Oct 31 18:08:28.136 INFO blam! 
Oct 31 18:08:28.136 INFO blam! running 38 tests
Oct 31 18:08:28.239 INFO blam! test environment::test_insert_name ... ok
Oct 31 18:08:28.263 INFO blam! test errors::test_broken_file ... ok
Oct 31 18:08:28.264 INFO blam! test environment::test_empty_env ... ok
Oct 31 18:08:28.280 INFO blam! test environment::test_no_export_name ... ok
Oct 31 18:08:28.280 INFO blam! test environment::test_ignore_env ... ok
Oct 31 18:08:28.280 INFO blam! test errors::test_broken_command ... ok
Oct 31 18:08:28.281 INFO blam! test errors::test_broken_command_parallel ... ok
Oct 31 18:08:28.282 INFO blam! test environment::test_non_empty_env ... ok
Oct 31 18:08:28.295 INFO blam! test errors::test_missing_file ... ok
Oct 31 18:08:28.296 INFO blam! test errors::test_conflict_print0_exec ... ok
Oct 31 18:08:28.300 INFO blam! test environment::test_no_insert_name ... ok
Oct 31 18:08:28.307 INFO blam! test errors::test_conflict_print_print0 ... ok
Oct 31 18:08:28.323 INFO blam! test invalid_args::test_choose ... ok
Oct 31 18:08:28.339 INFO blam! test errors::test_no_args ... ok
Oct 31 18:08:28.340 INFO blam! test errors::test_keep_going_parallel ... ok
Oct 31 18:08:28.340 INFO blam! test errors::test_stop_at_first_error_parallel ... ok
Oct 31 18:08:28.340 INFO blam! test errors::test_conflict_print_exec ... ok
Oct 31 18:08:28.348 INFO blam! test errors::test_stop_at_first_error ... ok
Oct 31 18:08:28.360 INFO blam! test invalid_args::test_delimiter ... ok
Oct 31 18:08:28.360 INFO blam! test errors::test_finish_what_is_started ... ok
Oct 31 18:08:28.367 INFO blam! test invalid_args::test_print0 ... ok
Oct 31 18:08:28.388 INFO blam! test invalid_args::test_command_line ... ok
Oct 31 18:08:28.389 INFO blam! test invalid_args::test_jobs_no_unicode ... ok
Oct 31 18:08:28.390 INFO blam! test invalid_args::test_exclude ... ok
Oct 31 18:08:28.391 INFO blam! test invalid_args::test_input_file ... ok
Oct 31 18:08:28.391 INFO blam! test invalid_args::test_jobs_not_a_number ... ok
Oct 31 18:08:28.403 INFO blam! test invalid_args::test_print ... ok
Oct 31 18:08:28.404 INFO blam! test printing::test_choose ... ok
Oct 31 18:08:28.428 INFO blam! test printing::test_print0 ... ok
Oct 31 18:08:28.428 INFO blam! test printing::test_exclude ... ok
Oct 31 18:08:28.428 INFO blam! test printing::test_print ... ok
Oct 31 18:08:28.429 INFO blam! test printing::test_delimiter ... ok
Oct 31 18:08:28.443 INFO blam! test printing::test_template ... ok
Oct 31 18:08:28.444 INFO blam! test printing::test_lax_mode ... ok
Oct 31 18:08:28.444 INFO blam! test errors::test_keep_going ... ok
Oct 31 18:08:28.455 INFO blam! test printing::test_strict_mode ... ok
Oct 31 18:08:28.456 INFO blam! test printing::test_simple ... ok
Oct 31 18:08:28.467 INFO blam! test printing::test_strict_mode_is_default ... ok
Oct 31 18:08:28.468 INFO kablam! su: No module specific data is present
Oct 31 18:08:28.468 INFO blam! 
Oct 31 18:08:28.468 INFO blam! test result: ok. 38 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 31 18:08:28.469 INFO blam! 
Oct 31 18:08:29.355 INFO running `"docker" "rm" "-f" "8586930a242c1be18d7776cb666013c9eab7e6f80b8627effe99dc764752d9c5"`
Oct 31 18:08:29.675 INFO blam! 8586930a242c1be18d7776cb666013c9eab7e6f80b8627effe99dc764752d9c5
