Oct 12 17:27:20.165 INFO testing zaphar/ucg against try#40d4795669493f1965de7c44029c69552134fe1f for rustdoc-test-static-cling-1
Oct 12 17:27:20.167 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/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 12 17:27:20.548 INFO blam! 99fb8eb1956dceb5d87e3f6e463d6e5379f0eeb334fce2b53c2336827c9d9cc5
Oct 12 17:27:20.551 INFO running `"docker" "start" "-a" "99fb8eb1956dceb5d87e3f6e463d6e5379f0eeb334fce2b53c2336827c9d9cc5"`
Oct 12 17:27:21.995 INFO kablam! usermod: no changes
Oct 12 17:27:22.183 INFO kablam!    Compiling serde v1.0.75
Oct 12 17:27:22.183 INFO kablam!    Compiling ryu v0.2.5
Oct 12 17:27:22.183 INFO kablam!    Compiling libc v0.2.43
Oct 12 17:27:22.183 INFO kablam!    Compiling linked-hash-map v0.5.1
Oct 12 17:27:22.183 INFO kablam!    Compiling unicode-width v0.1.5
Oct 12 17:27:22.183 INFO kablam!    Compiling bitflags v0.9.1
Oct 12 17:27:22.183 INFO kablam!    Compiling dtoa v0.4.3
Oct 12 17:27:22.207 INFO kablam!    Compiling itoa v0.4.2
Oct 12 17:27:23.095 INFO kablam!    Compiling vec_map v0.8.1
Oct 12 17:27:24.018 INFO kablam!    Compiling simple-error v0.1.11
Oct 12 17:27:24.019 INFO kablam!    Compiling yaml-rust v0.4.0
Oct 12 17:27:25.851 INFO kablam!    Compiling memchr v1.0.2
Oct 12 17:27:25.851 INFO kablam!    Compiling term_size v0.3.1
Oct 12 17:27:25.851 INFO kablam!    Compiling atty v0.2.11
Oct 12 17:27:26.947 INFO kablam!    Compiling textwrap v0.8.0
Oct 12 17:27:27.023 INFO kablam!    Compiling nom v3.2.1
Oct 12 17:27:30.270 INFO kablam!    Compiling clap v2.26.2
Oct 12 17:27:34.521 INFO kablam!    Compiling nom_locate v0.1.1
Oct 12 17:27:53.967 INFO kablam!    Compiling serde_json v1.0.26
Oct 12 17:27:54.879 INFO kablam!    Compiling serde_yaml v0.8.1
Oct 12 17:28:03.533 INFO kablam!    Compiling ucg v0.2.0 (/source)
Oct 12 17:28:15.975 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 53.94s
Oct 12 17:28:15.987 INFO kablam! su: No module specific data is present
Oct 12 17:28:17.031 INFO running `"docker" "rm" "-f" "99fb8eb1956dceb5d87e3f6e463d6e5379f0eeb334fce2b53c2336827c9d9cc5"`
Oct 12 17:28:17.347 INFO blam! 99fb8eb1956dceb5d87e3f6e463d6e5379f0eeb334fce2b53c2336827c9d9cc5
Oct 12 17:28:17.351 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/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 12 17:28:17.840 INFO blam! 8e4a58d49d64c88cb64844b6f7ac970d4a5fd89a4ff74bcfe72cdd77dbf2993e
Oct 12 17:28:17.841 INFO running `"docker" "start" "-a" "8e4a58d49d64c88cb64844b6f7ac970d4a5fd89a4ff74bcfe72cdd77dbf2993e"`
Oct 12 17:28:19.059 INFO kablam! usermod: no changes
Oct 12 17:28:19.185 INFO kablam!    Compiling cc v1.0.22
Oct 12 17:28:19.191 INFO kablam!    Compiling backtrace v0.2.3
Oct 12 17:28:19.191 INFO kablam!    Compiling cfg-if v0.1.5
Oct 12 17:28:19.191 INFO kablam!    Compiling rustc-demangle v0.1.9
Oct 12 17:28:19.199 INFO kablam!    Compiling lazy_static v0.2.11
Oct 12 17:28:19.199 INFO kablam!    Compiling bencher v0.1.5
Oct 12 17:28:19.199 INFO kablam!    Compiling dbghelp-sys v0.2.0
Oct 12 17:28:24.796 INFO kablam!    Compiling backtrace-sys v0.1.24
Oct 12 17:28:28.479 INFO kablam!    Compiling error-chain v0.5.0
Oct 12 17:28:28.983 INFO kablam!    Compiling cpuprofiler v0.0.3
Oct 12 17:28:29.609 INFO kablam!    Compiling ucg v0.2.0 (/source)
Oct 12 17:28:47.492 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 28.41s
Oct 12 17:28:47.500 INFO kablam! su: No module specific data is present
Oct 12 17:28:49.356 INFO running `"docker" "rm" "-f" "8e4a58d49d64c88cb64844b6f7ac970d4a5fd89a4ff74bcfe72cdd77dbf2993e"`
Oct 12 17:28:49.671 INFO blam! 8e4a58d49d64c88cb64844b6f7ac970d4a5fd89a4ff74bcfe72cdd77dbf2993e
Oct 12 17:28:49.675 INFO running `"docker" "create" "-v" "/mnt/big/crater/./work/local/target-dirs/rustdoc-test-static-cling-1/worker-3/try#40d4795669493f1965de7c44029c69552134fe1f:/target:rw,Z" "-v" "/mnt/big/crater/./work/local/test-source/worker-3/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 12 17:28:50.325 INFO blam! bd453e0390488884c16e41a82be20f2aecc6d3c54367d40167eb0db7db87a74e
Oct 12 17:28:50.328 INFO running `"docker" "start" "-a" "bd453e0390488884c16e41a82be20f2aecc6d3c54367d40167eb0db7db87a74e"`
Oct 12 17:28:52.419 INFO kablam! usermod: no changes
Oct 12 17:28:52.563 INFO kablam!     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Oct 12 17:28:52.587 INFO kablam!      Running /target/debug/deps/ucglib-c00f0f881ca6a7e4
Oct 12 17:28:52.588 INFO blam! 
Oct 12 17:28:52.588 INFO blam! running 88 tests
Oct 12 17:28:52.611 INFO blam! test build::compile_test::test_binary_operator_precedence ... ok
Oct 12 17:28:52.611 INFO blam! test ast::test::test_macro_validation_selector_fail ... ok
Oct 12 17:28:52.611 INFO blam! test build::compile_test::test_concatenation ... ok
Oct 12 17:28:52.611 INFO blam! test ast::test::test_macro_validation_happy_path ... ok
Oct 12 17:28:52.619 INFO blam! test build::compile_test::test_empty_value ... ok
Oct 12 17:28:52.619 INFO blam! test ast::test::test_macro_validation_selector_happy_path ... ok
Oct 12 17:28:52.619 INFO blam! test ast::test::test_macro_validation_fail ... ok
Oct 12 17:28:52.667 INFO blam! test build::test::test_build_file_string ... ok
Oct 12 17:28:52.667 INFO blam! test build::compile_test::test_format ... ok
Oct 12 17:28:52.710 INFO blam! test build::test::test_eval_add_expr ... ok
Oct 12 17:28:52.710 INFO blam! test build::test::test_eval_div_expr ... ok
Oct 12 17:28:52.727 INFO blam! test build::compile_test::test_macros ... ok
Oct 12 17:28:52.739 INFO blam! test build::test::test_eval_mul_expr ... ok
Oct 12 17:28:52.739 INFO blam! test build::compile_test::test_select_expressions ... ok
Oct 12 17:28:52.747 INFO blam! test build::test::test_eval_nested_tuple ... ok
Oct 12 17:28:52.750 INFO blam! test build::test::test_eval_selector_expr ... ok
Oct 12 17:28:52.750 INFO blam! test build::test::test_eval_selector_list_expr ... ok
Oct 12 17:28:52.755 INFO blam! test build::test::test_eval_simple_expr ... ok
Oct 12 17:28:52.755 INFO blam! test build::test::test_eval_simple_lookup_error ... ok
Oct 12 17:28:52.755 INFO blam! test build::test::test_eval_simple_lookup_expr ... ok
Oct 12 17:28:52.755 INFO blam! test build::test::test_eval_subtract_expr ... ok
Oct 12 17:28:52.771 INFO blam! test build::test::test_eval_div_expr_fail ... ok
Oct 12 17:28:52.771 INFO blam! test build::test::test_eval_mul_expr_fail ... ok
Oct 12 17:28:52.771 INFO blam! test build::test::test_eval_add_expr_fail ... ok
Oct 12 17:28:52.771 INFO blam! test build::test::test_eval_subtract_expr_fail ... ok
Oct 12 17:28:52.787 INFO blam! test build::test::test_expr_copy_field_type_error ... ok
Oct 12 17:28:52.787 INFO blam! test build::test::test_expr_copy_not_a_tuple ... ok
Oct 12 17:28:52.787 INFO blam! test build::compile_test::test_selectors ... ok
Oct 12 17:28:52.787 INFO blam! test build::test::test_expr_copy ... ok
Oct 12 17:28:52.787 INFO blam! test build::test::test_expr_copy_no_such_tuple ... ok
Oct 12 17:28:52.791 INFO blam! test build::test::test_macro_call ... ok
Oct 12 17:28:52.791 INFO blam! test build::test::test_macro_hermetic ... ok
Oct 12 17:28:52.815 INFO blam! test build::test::test_let_statement ... ok
Oct 12 17:28:52.831 INFO blam! test convert::exec::exec_test::convert_just_command_test ... ok
Oct 12 17:28:52.839 INFO blam! test build::test::test_select_expr_not_a_string ... ok
Oct 12 17:28:52.839 INFO blam! test format::test::test_format_happy_path ... ok
Oct 12 17:28:52.863 INFO blam! test build::compile_test::test_tuples ... ok
Oct 12 17:28:52.863 INFO blam! test format::test::test_format_happy_wrong_too_few_args ... ok
Oct 12 17:28:52.863 INFO blam! test build::test::test_select_expr ... ok
Oct 12 17:28:52.863 INFO blam! test convert::exec::exec_test::convert_command_with_env_test ... ok
Oct 12 17:28:52.863 INFO blam! test build::compile_test::test_list_operations ... ok
Oct 12 17:28:52.863 INFO blam! test format::test::test_format_happy_wrong_too_many_args ... ok
Oct 12 17:28:52.864 INFO blam! test parse::test::test_boolean_parsing ... ok
Oct 12 17:28:52.864 INFO blam! test parse::test::test_assert_statement_parse ... ok
Oct 12 17:28:52.879 INFO blam! test parse::test::test_field_list_parse ... ok
Oct 12 17:28:52.879 INFO blam! test parse::test::test_expression_statement_parse ... ok
Oct 12 17:28:52.879 INFO blam! test parse::test::test_field_value_parse ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_import_statement_parse ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_copy_parse ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_call_parse ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_grouped_expression_parse ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_format_parse ... ok
Oct 12 17:28:52.911 INFO blam! test build::compile_test::test_comparisons ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_expression_parse ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_number_parsing ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_out_statement_parse ... ok
Oct 12 17:28:52.911 INFO blam! test parse::test::test_list_value_parse ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_selector_parsing ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_let_statement_parse ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_parse ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_statement_parse ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_macro_expression_parsing ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_select_parse ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_null_parsing ... ok
Oct 12 17:28:52.935 INFO blam! test parse::test::test_symbol_parsing ... ok
Oct 12 17:28:52.937 INFO blam! test tokenizer::test::test_assert_token ... ok
Oct 12 17:28:52.937 INFO blam! test tokenizer::test::test_eqeqtok ... ok
Oct 12 17:28:52.937 INFO blam! test tokenizer::test::test_escape_quoted ... ok
Oct 12 17:28:52.937 INFO blam! test tokenizer::test::test_empty_token ... ok
Oct 12 17:28:52.937 INFO blam! test tokenizer::test::test_gteqtok ... ok
Oct 12 17:28:52.943 INFO blam! test tokenizer::test::test_gttok ... ok
Oct 12 17:28:52.943 INFO blam! test tokenizer::test::test_match_punct ... ok
Oct 12 17:28:52.943 INFO blam! test tokenizer::test::test_lteqtok ... ok
Oct 12 17:28:52.943 INFO blam! test tokenizer::test::test_match_type ... ok
Oct 12 17:28:52.943 INFO blam! test tokenizer::test::test_lttok ... ok
Oct 12 17:28:52.946 INFO blam! test tokenizer::test::test_match_word ... ok
Oct 12 17:28:52.946 INFO blam! test tokenizer::test::test_parse_comment ... ok
Oct 12 17:28:52.946 INFO blam! test tokenizer::test::test_match_word_empty_input ... ok
Oct 12 17:28:52.946 INFO blam! test tokenizer::test::test_string_with_escaping ... ok
Oct 12 17:28:52.946 INFO blam! test tokenizer::test::test_pipe_quoted ... ok
Oct 12 17:28:52.946 INFO blam! test tokenizer::test::test_parse_has_end ... ok
Oct 12 17:28:52.947 INFO blam! test tokenizer::test::test_tokenize_bareword_with_dash ... ok
Oct 12 17:28:52.947 INFO blam! test tokenizer::test::test_tokenize_one_of_each ... ok
Oct 12 17:28:52.951 INFO blam! test tokenizer::test::test_boolean ... ok
Oct 12 17:28:52.951 INFO blam! test tokenizer::test::test_out_token ... ok
Oct 12 17:28:52.951 INFO blam! test tokenizer::test::test_notequaltok ... ok
Oct 12 17:28:52.984 INFO blam! test convert::exec::exec_test::convert_command_with_arg_test ... ok
Oct 12 17:28:52.984 INFO blam! test parse::test::test_tuple_parse ... ok
Oct 12 17:28:52.984 INFO blam! 
Oct 12 17:28:52.984 INFO blam! test result: ok. 88 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 17:28:52.984 INFO blam! 
Oct 12 17:28:52.989 INFO kablam!      Running /target/debug/deps/ucg-c18096f36c968dad
Oct 12 17:28:52.989 INFO blam! 
Oct 12 17:28:52.989 INFO blam! running 0 tests
Oct 12 17:28:52.989 INFO blam! 
Oct 12 17:28:52.989 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 17:28:52.989 INFO blam! 
Oct 12 17:28:52.999 INFO kablam!    Doc-tests ucglib
Oct 12 17:28:53.735 INFO blam! 
Oct 12 17:28:53.735 INFO blam! running 0 tests
Oct 12 17:28:53.735 INFO blam! 
Oct 12 17:28:53.735 INFO blam! test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Oct 12 17:28:53.735 INFO blam! 
Oct 12 17:28:53.747 INFO kablam! su: No module specific data is present
Oct 12 17:28:55.099 INFO running `"docker" "rm" "-f" "bd453e0390488884c16e41a82be20f2aecc6d3c54367d40167eb0db7db87a74e"`
Oct 12 17:28:55.479 INFO blam! bd453e0390488884c16e41a82be20f2aecc6d3c54367d40167eb0db7db87a74e