Nov 17 19:33:57.847 INFO testing ucg-0.2.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 19:33:57.849 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 19:33:58.200 INFO [stdout] fc538fb9febf4b91b96a84d804bbe72d223ad5705436ed74bff16713e32d6af0
Nov 17 19:33:58.203 INFO running `"docker" "start" "-a" "fc538fb9febf4b91b96a84d804bbe72d223ad5705436ed74bff16713e32d6af0"`
Nov 17 19:33:58.867 INFO [stderr] usermod: no changes
Nov 17 19:33:58.953 INFO [stderr]    Compiling textwrap v0.8.0
Nov 17 19:33:58.953 INFO [stderr]    Compiling nom v3.2.1
Nov 17 19:34:00.111 INFO [stderr]    Compiling clap v2.26.2
Nov 17 19:34:03.341 INFO [stderr]    Compiling nom_locate v0.1.1
Nov 17 19:34:19.533 INFO [stderr]    Compiling ucg v0.2.0 (/source)
Nov 17 19:34:36.309 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 37.42s
Nov 17 19:34:36.316 INFO [stderr] su: No module specific data is present
Nov 17 19:34:37.028 INFO running `"docker" "inspect" "fc538fb9febf4b91b96a84d804bbe72d223ad5705436ed74bff16713e32d6af0"`
Nov 17 19:34:37.285 INFO running `"docker" "rm" "-f" "fc538fb9febf4b91b96a84d804bbe72d223ad5705436ed74bff16713e32d6af0"`
Nov 17 19:34:37.595 INFO [stdout] fc538fb9febf4b91b96a84d804bbe72d223ad5705436ed74bff16713e32d6af0
Nov 17 19:34:37.605 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 19:34:38.017 INFO [stdout] a2d2743914bb896c6381cc6a94180224e96da41b25d3b0889987b0b217d10ba8
Nov 17 19:34:38.019 INFO running `"docker" "start" "-a" "a2d2743914bb896c6381cc6a94180224e96da41b25d3b0889987b0b217d10ba8"`
Nov 17 19:34:39.555 INFO [stderr] usermod: no changes
Nov 17 19:34:39.794 INFO [stderr]    Compiling error-chain v0.5.0
Nov 17 19:34:41.023 INFO [stderr]    Compiling cpuprofiler v0.0.3
Nov 17 19:34:42.259 INFO [stderr]    Compiling ucg v0.2.0 (/source)
Nov 17 19:34:58.636 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.05s
Nov 17 19:34:58.651 INFO [stderr] su: No module specific data is present
Nov 17 19:34:59.793 INFO running `"docker" "inspect" "a2d2743914bb896c6381cc6a94180224e96da41b25d3b0889987b0b217d10ba8"`
Nov 17 19:35:00.207 INFO running `"docker" "rm" "-f" "a2d2743914bb896c6381cc6a94180224e96da41b25d3b0889987b0b217d10ba8"`
Nov 17 19:35:00.439 INFO [stdout] a2d2743914bb896c6381cc6a94180224e96da41b25d3b0889987b0b217d10ba8
Nov 17 19:35:00.449 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-4/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 19:35:00.979 INFO [stdout] 9f7ac29489f1263becaaee911829b028f965bee690db04dc6b44b11c6068854e
Nov 17 19:35:00.982 INFO running `"docker" "start" "-a" "9f7ac29489f1263becaaee911829b028f965bee690db04dc6b44b11c6068854e"`
Nov 17 19:35:03.908 INFO [stderr] usermod: no changes
Nov 17 19:35:04.155 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.09s
Nov 17 19:35:04.159 INFO [stderr]      Running /target/debug/deps/ucglib-184b8335e03cf8df
Nov 17 19:35:04.192 INFO [stdout] 
Nov 17 19:35:04.192 INFO [stdout] running 88 tests
Nov 17 19:35:04.211 INFO [stdout] test ast::test::test_macro_validation_selector_happy_path ... ok
Nov 17 19:35:04.211 INFO [stdout] test ast::test::test_macro_validation_fail ... ok
Nov 17 19:35:04.212 INFO [stdout] test ast::test::test_macro_validation_selector_fail ... ok
Nov 17 19:35:04.212 INFO [stdout] test ast::test::test_macro_validation_happy_path ... ok
Nov 17 19:35:04.223 INFO [stdout] test build::compile_test::test_format ... ok
Nov 17 19:35:04.232 INFO [stdout] test build::compile_test::test_concatenation ... ok
Nov 17 19:35:04.233 INFO [stdout] test build::compile_test::test_empty_value ... ok
Nov 17 19:35:04.239 INFO [stdout] test build::compile_test::test_binary_operator_precedence ... ok
Nov 17 19:35:04.259 INFO [stdout] test build::test::test_eval_add_expr ... ok
Nov 17 19:35:04.275 INFO [stdout] test build::test::test_build_file_string ... ok
Nov 17 19:35:04.287 INFO [stdout] test build::test::test_eval_div_expr ... ok
Nov 17 19:35:04.311 INFO [stdout] test build::compile_test::test_select_expressions ... ok
Nov 17 19:35:04.313 INFO [stdout] test build::compile_test::test_macros ... ok
Nov 17 19:35:04.319 INFO [stdout] test build::test::test_eval_mul_expr ... ok
Nov 17 19:35:04.327 INFO [stdout] test build::test::test_eval_nested_tuple ... ok
Nov 17 19:35:04.335 INFO [stdout] test build::test::test_eval_selector_expr ... ok
Nov 17 19:35:04.337 INFO [stdout] test build::compile_test::test_tuples ... ok
Nov 17 19:35:04.367 INFO [stdout] test build::test::test_eval_simple_expr ... ok
Nov 17 19:35:04.379 INFO [stdout] test build::test::test_eval_simple_lookup_error ... ok
Nov 17 19:35:04.391 INFO [stdout] test build::test::test_eval_selector_list_expr ... ok
Nov 17 19:35:04.403 INFO [stdout] test build::compile_test::test_selectors ... ok
Nov 17 19:35:04.404 INFO [stdout] test build::test::test_eval_simple_lookup_expr ... ok
Nov 17 19:35:04.407 INFO [stdout] test build::test::test_eval_subtract_expr ... ok
Nov 17 19:35:04.408 INFO [stdout] test build::test::test_expr_copy ... ok
Nov 17 19:35:04.419 INFO [stdout] test build::compile_test::test_list_operations ... ok
Nov 17 19:35:04.479 INFO [stdout] test build::compile_test::test_comparisons ... ok
Nov 17 19:35:04.480 INFO [stdout] test build::test::test_let_statement ... ok
Nov 17 19:35:04.487 INFO [stdout] test build::test::test_macro_call ... ok
Nov 17 19:35:04.519 INFO [stdout] test build::test::test_eval_add_expr_fail ... ok
Nov 17 19:35:04.519 INFO [stdout] test build::test::test_eval_div_expr_fail ... ok
Nov 17 19:35:04.520 INFO [stdout] test build::test::test_eval_mul_expr_fail ... ok
Nov 17 19:35:04.520 INFO [stdout] test build::test::test_eval_subtract_expr_fail ... ok
Nov 17 19:35:04.520 INFO [stdout] test build::test::test_expr_copy_no_such_tuple ... ok
Nov 17 19:35:04.521 INFO [stdout] test build::test::test_select_expr_not_a_string ... ok
Nov 17 19:35:04.521 INFO [stdout] test build::test::test_select_expr ... ok
Nov 17 19:35:04.531 INFO [stdout] test build::test::test_expr_copy_field_type_error ... ok
Nov 17 19:35:04.531 INFO [stdout] test build::test::test_expr_copy_not_a_tuple ... ok
Nov 17 19:35:04.535 INFO [stdout] test convert::exec::exec_test::convert_just_command_test ... ok
Nov 17 19:35:04.535 INFO [stdout] test format::test::test_format_happy_path ... ok
Nov 17 19:35:04.535 INFO [stdout] test format::test::test_format_happy_wrong_too_few_args ... ok
Nov 17 19:35:04.536 INFO [stdout] test format::test::test_format_happy_wrong_too_many_args ... ok
Nov 17 19:35:04.551 INFO [stdout] test build::test::test_macro_hermetic ... ok
Nov 17 19:35:04.551 INFO [stdout] test convert::exec::exec_test::convert_command_with_env_test ... ok
Nov 17 19:35:04.552 INFO [stdout] test parse::test::test_boolean_parsing ... ok
Nov 17 19:35:04.568 INFO [stdout] test parse::test::test_call_parse ... ok
Nov 17 19:35:04.571 INFO [stdout] test parse::test::test_assert_statement_parse ... ok
Nov 17 19:35:04.579 INFO [stdout] test parse::test::test_copy_parse ... ok
Nov 17 19:35:04.592 INFO [stdout] test parse::test::test_field_list_parse ... ok
Nov 17 19:35:04.592 INFO [stdout] test parse::test::test_field_value_parse ... ok
Nov 17 19:35:04.608 INFO [stdout] test parse::test::test_expression_statement_parse ... ok
Nov 17 19:35:04.611 INFO [stdout] test parse::test::test_import_statement_parse ... ok
Nov 17 19:35:04.612 INFO [stdout] test parse::test::test_format_parse ... ok
Nov 17 19:35:04.612 INFO [stdout] test parse::test::test_grouped_expression_parse ... ok
Nov 17 19:35:04.623 INFO [stdout] test parse::test::test_null_parsing ... ok
Nov 17 19:35:04.623 INFO [stdout] test parse::test::test_out_statement_parse ... ok
Nov 17 19:35:04.623 INFO [stdout] test parse::test::test_number_parsing ... ok
Nov 17 19:35:04.624 INFO [stdout] test parse::test::test_macro_expression_parsing ... ok
Nov 17 19:35:04.628 INFO [stdout] test parse::test::test_parse ... ok
Nov 17 19:35:04.639 INFO [stdout] test parse::test::test_symbol_parsing ... ok
Nov 17 19:35:04.640 INFO [stdout] test parse::test::test_selector_parsing ... ok
Nov 17 19:35:04.641 INFO [stdout] test parse::test::test_list_value_parse ... ok
Nov 17 19:35:04.643 INFO [stdout] test parse::test::test_select_parse ... ok
Nov 17 19:35:04.643 INFO [stdout] test tokenizer::test::test_assert_token ... ok
Nov 17 19:35:04.644 INFO [stdout] test tokenizer::test::test_boolean ... ok
Nov 17 19:35:04.644 INFO [stdout] test parse::test::test_let_statement_parse ... ok
Nov 17 19:35:04.651 INFO [stdout] test parse::test::test_statement_parse ... ok
Nov 17 19:35:04.651 INFO [stdout] test tokenizer::test::test_escape_quoted ... ok
Nov 17 19:35:04.652 INFO [stdout] test tokenizer::test::test_gteqtok ... ok
Nov 17 19:35:04.659 INFO [stdout] test tokenizer::test::test_empty_token ... ok
Nov 17 19:35:04.659 INFO [stdout] test tokenizer::test::test_gttok ... ok
Nov 17 19:35:04.660 INFO [stdout] test tokenizer::test::test_lteqtok ... ok
Nov 17 19:35:04.660 INFO [stdout] test tokenizer::test::test_eqeqtok ... ok
Nov 17 19:35:04.661 INFO [stdout] test parse::test::test_expression_parse ... ok
Nov 17 19:35:04.675 INFO [stdout] test tokenizer::test::test_match_word_empty_input ... ok
Nov 17 19:35:04.675 INFO [stdout] test tokenizer::test::test_match_punct ... ok
Nov 17 19:35:04.687 INFO [stdout] test tokenizer::test::test_match_type ... ok
Nov 17 19:35:04.687 INFO [stdout] test tokenizer::test::test_match_word ... ok
Nov 17 19:35:04.688 INFO [stdout] test tokenizer::test::test_lttok ... ok
Nov 17 19:35:04.688 INFO [stdout] test tokenizer::test::test_notequaltok ... ok
Nov 17 19:35:04.695 INFO [stdout] test tokenizer::test::test_string_with_escaping ... ok
Nov 17 19:35:04.695 INFO [stdout] test tokenizer::test::test_parse_has_end ... ok
Nov 17 19:35:04.696 INFO [stdout] test tokenizer::test::test_tokenize_bareword_with_dash ... ok
Nov 17 19:35:04.696 INFO [stdout] test tokenizer::test::test_pipe_quoted ... ok
Nov 17 19:35:04.719 INFO [stdout] test tokenizer::test::test_tokenize_one_of_each ... ok
Nov 17 19:35:04.731 INFO [stdout] test tokenizer::test::test_parse_comment ... ok
Nov 17 19:35:04.731 INFO [stdout] test tokenizer::test::test_out_token ... ok
Nov 17 19:35:04.763 INFO [stdout] test parse::test::test_tuple_parse ... ok
Nov 17 19:35:04.811 INFO [stdout] test convert::exec::exec_test::convert_command_with_arg_test ... ok
Nov 17 19:35:04.811 INFO [stdout] 
Nov 17 19:35:04.811 INFO [stdout] test result: ok. 88 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:35:04.811 INFO [stdout] 
Nov 17 19:35:04.815 INFO [stderr]      Running /target/debug/deps/ucg-a7bb8c80726efdef
Nov 17 19:35:04.839 INFO [stdout] 
Nov 17 19:35:04.839 INFO [stdout] running 0 tests
Nov 17 19:35:04.840 INFO [stdout] 
Nov 17 19:35:04.840 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:35:04.840 INFO [stdout] 
Nov 17 19:35:04.841 INFO [stderr]    Doc-tests ucglib
Nov 17 19:35:07.091 INFO [stdout] 
Nov 17 19:35:07.091 INFO [stdout] running 0 tests
Nov 17 19:35:07.091 INFO [stdout] 
Nov 17 19:35:07.091 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 19:35:07.091 INFO [stdout] 
Nov 17 19:35:07.101 INFO [stderr] su: No module specific data is present
Nov 17 19:35:08.031 INFO running `"docker" "inspect" "9f7ac29489f1263becaaee911829b028f965bee690db04dc6b44b11c6068854e"`
Nov 17 19:35:08.289 INFO running `"docker" "rm" "-f" "9f7ac29489f1263becaaee911829b028f965bee690db04dc6b44b11c6068854e"`
Nov 17 19:35:08.670 INFO [stdout] 9f7ac29489f1263becaaee911829b028f965bee690db04dc6b44b11c6068854e