Dec 13 01:29:48.575 INFO testing sux2mfgj/nslfmt against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 13 01:29:48.579 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 13 01:29:49.116 INFO [stdout] 7a7f5479877ef0f8360d6656c24fc595403e1f6eeddc81eaeff48a6f5697db2d
Dec 13 01:29:49.118 INFO running `"docker" "start" "-a" "7a7f5479877ef0f8360d6656c24fc595403e1f6eeddc81eaeff48a6f5697db2d"`
Dec 13 01:29:50.487 INFO [stderr] usermod: no changes
Dec 13 01:29:50.553 INFO [stderr]    Compiling nslfmt v0.0.1 (/source)
Dec 13 01:29:50.555 INFO [stderr]    Compiling getopts v0.2.18
Dec 13 01:29:54.229 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:29:54.229 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:29:54.229 INFO [stderr]     |
Dec 13 01:29:54.229 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:29:54.230 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:29:54.230 INFO [stderr]     |
Dec 13 01:29:54.230 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:29:54.230 INFO [stderr] 
Dec 13 01:29:54.403 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:29:54.403 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:29:54.404 INFO [stderr]   |
Dec 13 01:29:54.404 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:29:54.404 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:29:54.405 INFO [stderr]   |
Dec 13 01:29:54.405 INFO [stderr]   = note: #[warn(dead_code)] on by default
Dec 13 01:29:54.405 INFO [stderr] 
Dec 13 01:29:56.939 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:29:56.939 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:29:56.939 INFO [stderr]     |
Dec 13 01:29:56.939 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:29:56.939 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:29:56.939 INFO [stderr]     |
Dec 13 01:29:56.939 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:29:56.939 INFO [stderr] 
Dec 13 01:29:57.115 INFO [stderr] warning: method is never used: `peek`
Dec 13 01:29:57.115 INFO [stderr]   --> src/lexer.rs:38:5
Dec 13 01:29:57.115 INFO [stderr]    |
Dec 13 01:29:57.115 INFO [stderr] 38 |     pub fn peek(&mut self, is_pass_nl: bool) -> Option<&Token>
Dec 13 01:29:57.115 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:29:57.115 INFO [stderr]    |
Dec 13 01:29:57.115 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 01:29:57.115 INFO [stderr] 
Dec 13 01:29:57.115 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:29:57.115 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:29:57.115 INFO [stderr]   |
Dec 13 01:29:57.115 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:29:57.115 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:29:57.115 INFO [stderr] 
Dec 13 01:29:59.607 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.09s
Dec 13 01:29:59.610 INFO [stderr] su: No module specific data is present
Dec 13 01:30:01.386 INFO running `"docker" "inspect" "7a7f5479877ef0f8360d6656c24fc595403e1f6eeddc81eaeff48a6f5697db2d"`
Dec 13 01:30:01.777 INFO running `"docker" "rm" "-f" "7a7f5479877ef0f8360d6656c24fc595403e1f6eeddc81eaeff48a6f5697db2d"`
Dec 13 01:30:02.091 INFO [stdout] 7a7f5479877ef0f8360d6656c24fc595403e1f6eeddc81eaeff48a6f5697db2d
Dec 13 01:30:02.095 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 13 01:30:02.641 INFO [stdout] a96a679ea16c28e3dd8a1a96066ed65bf09c36830733104235a8be97282bf176
Dec 13 01:30:02.643 INFO running `"docker" "start" "-a" "a96a679ea16c28e3dd8a1a96066ed65bf09c36830733104235a8be97282bf176"`
Dec 13 01:30:03.853 INFO [stderr] usermod: no changes
Dec 13 01:30:03.911 INFO [stderr]    Compiling nslfmt v0.0.1 (/source)
Dec 13 01:30:05.099 INFO [stderr] warning: static variable `call_count` should have an upper case name such as `CALL_COUNT`
Dec 13 01:30:05.099 INFO [stderr]   --> tests/generator_test.rs:11:1
Dec 13 01:30:05.099 INFO [stderr]    |
Dec 13 01:30:05.099 INFO [stderr] 11 | static call_count: AtomicUsize = AtomicUsize::new(0);
Dec 13 01:30:05.099 INFO [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:05.099 INFO [stderr]    |
Dec 13 01:30:05.099 INFO [stderr]    = note: #[warn(non_upper_case_globals)] on by default
Dec 13 01:30:05.099 INFO [stderr] 
Dec 13 01:30:06.237 INFO [stderr] warning: unused variable: `p`
Dec 13 01:30:06.237 INFO [stderr]     --> tests/parser_test.rs:1186:13
Dec 13 01:30:06.237 INFO [stderr]      |
Dec 13 01:30:06.237 INFO [stderr] 1186 |         let p = Parser::new(&mut l);
Dec 13 01:30:06.237 INFO [stderr]      |             ^ help: consider using `_p` instead
Dec 13 01:30:06.263 INFO [stderr]      |
Dec 13 01:30:06.263 INFO [stderr]      = note: #[warn(unused_variables)] on by default
Dec 13 01:30:06.263 INFO [stderr] 
Dec 13 01:30:06.264 INFO [stderr] warning: unused variable: `module`
Dec 13 01:30:06.264 INFO [stderr]     --> tests/parser_test.rs:1206:13
Dec 13 01:30:06.264 INFO [stderr]      |
Dec 13 01:30:06.264 INFO [stderr] 1206 |         let module = create_node!(ASTClass::Module(
Dec 13 01:30:06.264 INFO [stderr]      |             ^^^^^^ help: consider using `_module` instead
Dec 13 01:30:06.264 INFO [stderr] 
Dec 13 01:30:06.265 INFO [stderr] warning: unused variable: `p`
Dec 13 01:30:06.266 INFO [stderr]     --> tests/parser_test.rs:1751:17
Dec 13 01:30:06.266 INFO [stderr]      |
Dec 13 01:30:06.266 INFO [stderr] 1751 |         let mut p = Parser::new(&mut l);
Dec 13 01:30:06.266 INFO [stderr]      |                 ^ help: consider using `_p` instead
Dec 13 01:30:06.266 INFO [stderr] 
Dec 13 01:30:06.963 INFO [stderr] warning: variable does not need to be mutable
Dec 13 01:30:06.963 INFO [stderr]     --> tests/parser_test.rs:1751:13
Dec 13 01:30:06.963 INFO [stderr]      |
Dec 13 01:30:06.963 INFO [stderr] 1751 |         let mut p = Parser::new(&mut l);
Dec 13 01:30:06.963 INFO [stderr]      |             ----^
Dec 13 01:30:06.963 INFO [stderr]      |             |
Dec 13 01:30:06.963 INFO [stderr]      |             help: remove this `mut`
Dec 13 01:30:06.963 INFO [stderr]      |
Dec 13 01:30:06.963 INFO [stderr]      = note: #[warn(unused_mut)] on by default
Dec 13 01:30:06.963 INFO [stderr] 
Dec 13 01:30:07.119 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:30:07.119 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:30:07.120 INFO [stderr]     |
Dec 13 01:30:07.120 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:30:07.120 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:30:07.159 INFO [stderr]     |
Dec 13 01:30:07.159 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:30:07.160 INFO [stderr] 
Dec 13 01:30:07.167 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:30:07.167 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:30:07.167 INFO [stderr]     |
Dec 13 01:30:07.168 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:30:07.168 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:30:07.168 INFO [stderr]     |
Dec 13 01:30:07.168 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:30:07.168 INFO [stderr] 
Dec 13 01:30:08.007 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:30:08.008 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:30:08.008 INFO [stderr]   |
Dec 13 01:30:08.008 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:30:08.009 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:08.018 INFO [stderr] warning: method is never used: `peek`
Dec 13 01:30:08.018 INFO [stderr]   --> src/lexer.rs:38:5
Dec 13 01:30:08.019 INFO [stderr]    |
Dec 13 01:30:08.019 INFO [stderr] 38 |     pub fn peek(&mut self, is_pass_nl: bool) -> Option<&Token>
Dec 13 01:30:08.019 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:08.020 INFO [stderr]    |
Dec 13 01:30:08.020 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 01:30:08.021 INFO [stderr] 
Dec 13 01:30:08.021 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:30:08.021 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:30:08.022 INFO [stderr]   |
Dec 13 01:30:08.022 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:30:08.022 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:08.023 INFO [stderr] 
Dec 13 01:30:08.044 INFO [stderr]   |
Dec 13 01:30:08.044 INFO [stderr]   = note: #[warn(dead_code)] on by default
Dec 13 01:30:08.044 INFO [stderr] 
Dec 13 01:30:13.508 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.63s
Dec 13 01:30:13.516 INFO [stderr] su: No module specific data is present
Dec 13 01:30:14.811 INFO running `"docker" "inspect" "a96a679ea16c28e3dd8a1a96066ed65bf09c36830733104235a8be97282bf176"`
Dec 13 01:30:15.229 INFO running `"docker" "rm" "-f" "a96a679ea16c28e3dd8a1a96066ed65bf09c36830733104235a8be97282bf176"`
Dec 13 01:30:15.533 INFO [stdout] a96a679ea16c28e3dd8a1a96066ed65bf09c36830733104235a8be97282bf176
Dec 13 01:30:15.536 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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"`
Dec 13 01:30:15.949 INFO [stdout] 6af0a29db45e3efe4df2433eb14634b5bb7f59ed099c4991f0890bd295a04820
Dec 13 01:30:15.951 INFO running `"docker" "start" "-a" "6af0a29db45e3efe4df2433eb14634b5bb7f59ed099c4991f0890bd295a04820"`
Dec 13 01:30:17.626 INFO [stderr] usermod: no changes
Dec 13 01:30:17.687 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Dec 13 01:30:17.692 INFO [stderr]      Running /target/debug/deps/nslfmt-489481184272fb6e
Dec 13 01:30:17.692 INFO [stdout] 
Dec 13 01:30:17.692 INFO [stdout] running 0 tests
Dec 13 01:30:17.692 INFO [stdout] 
Dec 13 01:30:17.692 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:17.692 INFO [stdout] 
Dec 13 01:30:17.700 INFO [stdout] 
Dec 13 01:30:17.700 INFO [stderr]      Running /target/debug/deps/nslfmt-5145b2fcc6385bee
Dec 13 01:30:17.700 INFO [stdout] running 0 tests
Dec 13 01:30:17.700 INFO [stderr]      Running /target/debug/deps/generator_test-03f2526b11070e5c
Dec 13 01:30:17.700 INFO [stdout] 
Dec 13 01:30:17.700 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:17.700 INFO [stdout] 
Dec 13 01:30:17.700 INFO [stdout] 
Dec 13 01:30:17.700 INFO [stdout] running 20 tests
Dec 13 01:30:17.700 INFO [stdout] test comment_01 ... ok
Dec 13 01:30:17.700 INFO [stdout] test define_path ... ok
Dec 13 01:30:17.700 INFO [stdout] test comment_00 ... ok
Dec 13 01:30:17.700 INFO [stdout] test aware_indent_03 ... ok
Dec 13 01:30:17.700 INFO [stdout] test aware_indent_02 ... ok
Dec 13 01:30:17.706 INFO [stdout] test aware_indent_01 ... ok
Dec 13 01:30:17.707 INFO [stderr]      Running /target/debug/deps/lexer_test-6c00e61e0ebe3fc8
Dec 13 01:30:17.707 INFO [stdout] test func_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test func_call_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test func_self_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test assign_wire_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test new_by_file ... ok
Dec 13 01:30:17.707 INFO [stdout] test new_by_stdout ... ok
Dec 13 01:30:17.707 INFO [stdout] test any_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test output_declare_to_file ... ok
Dec 13 01:30:17.707 INFO [stdout] test parences ... ok
Dec 13 01:30:17.707 INFO [stdout] test reg_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test return_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test two_arguments ... ok
Dec 13 01:30:17.707 INFO [stdout] test wire_00 ... ok
Dec 13 01:30:17.707 INFO [stdout] test wire_01 ... ok
Dec 13 01:30:17.707 INFO [stdout] 
Dec 13 01:30:17.707 INFO [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:17.707 INFO [stdout] 
Dec 13 01:30:17.707 INFO [stdout] 
Dec 13 01:30:17.707 INFO [stdout] running 52 tests
Dec 13 01:30:17.719 INFO [stdout] test create_instance_with_string ... ok
Dec 13 01:30:17.731 INFO [stdout] test create_instance_with_file ... ok
Dec 13 01:30:17.731 INFO [stdout] test braces_and_newline ... ok
Dec 13 01:30:17.731 INFO [stdout] test comment_00 ... ok
Dec 13 01:30:17.731 INFO [stdout] test any_else_00 ... ok
Dec 13 01:30:17.731 INFO [stdout] test any_block_00 ... ok
Dec 13 01:30:17.731 INFO [stdout] test declare_04 ... ok
Dec 13 01:30:17.731 INFO [stdout] test declare_func_in ... ok
Dec 13 01:30:17.731 INFO [stdout] test declare_func_out ... ok
Dec 13 01:30:17.739 INFO [stdout] test get_token_new_line ... ok
Dec 13 01:30:17.739 INFO [stdout] test func_self_00 ... ok
Dec 13 01:30:17.739 INFO [stdout] test get_token_eop ... ok
Dec 13 01:30:17.739 INFO [stdout] test get_token_file ... ok
Dec 13 01:30:17.747 INFO [stdout] test get_token_str ... ok
Dec 13 01:30:17.747 INFO [stderr]      Running /target/debug/deps/parser_test-dcd5780da76d2821
Dec 13 01:30:17.747 INFO [stdout] test gt_lt_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test func_block_return ... ok
Dec 13 01:30:17.747 INFO [stdout] test macro_else ... ok
Dec 13 01:30:17.747 INFO [stdout] test func_block_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test macro_define ... ok
Dec 13 01:30:17.747 INFO [stdout] test define_path ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare_with_input ... ok
Dec 13 01:30:17.747 INFO [stdout] test macro_endif ... ok
Dec 13 01:30:17.747 INFO [stdout] test macro_ifdef ... ok
Dec 13 01:30:17.747 INFO [stdout] test macro_undef ... ok
Dec 13 01:30:17.747 INFO [stdout] test mem_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test mutiline_comment_01 ... ok
Dec 13 01:30:17.747 INFO [stdout] test mutiline_comment_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test mutiline_comment_02 ... ok
Dec 13 01:30:17.747 INFO [stdout] test newline_in_declare_block ... ok
Dec 13 01:30:17.747 INFO [stdout] test next_nl ... ok
Dec 13 01:30:17.747 INFO [stdout] test module_wire_01 ... ok
Dec 13 01:30:17.747 INFO [stdout] test number_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test number ... ok
Dec 13 01:30:17.747 INFO [stdout] test mutiline_comment_03 ... ok
Dec 13 01:30:17.747 INFO [stdout] test number_01 ... ok
Dec 13 01:30:17.747 INFO [stdout] test number_03 ... ok
Dec 13 01:30:17.747 INFO [stdout] test number_02 ... ok
Dec 13 01:30:17.747 INFO [stdout] test or_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test pass_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test pass_newlines ... ok
Dec 13 01:30:17.747 INFO [stdout] test peek_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test plus_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test proc_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test reg_assignment_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test reg_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test state_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test state_name_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test wire_assign_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test macro_ifndef ... ok
Dec 13 01:30:17.747 INFO [stdout] test macro_include ... ok
Dec 13 01:30:17.747 INFO [stdout] test module_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test module_wire_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] 
Dec 13 01:30:17.747 INFO [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:17.747 INFO [stdout] 
Dec 13 01:30:17.747 INFO [stdout] 
Dec 13 01:30:17.747 INFO [stdout] running 77 tests
Dec 13 01:30:17.747 INFO [stdout] test declare::func_in ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::expression_in_width_block_02 ... ok
Dec 13 01:30:17.747 INFO [stdout] test comment::multi_line_comment ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::expression_in_width_block_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::func_out_return ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::macro_in_declare_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::macro_in_declare_01 ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::multi_bit_input ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::output_inout ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::func_in_return ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::expression_in_width_block_01 ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::define_macro_eof ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::declare_03 ... ok
Dec 13 01:30:17.747 INFO [stdout] test declare::declare_only ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::define_macro_nl ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::if_else_end ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::ifdef_macro ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::endif_macro ... ok
Dec 13 01:30:17.747 INFO [stdout] test module::any_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::undef_macro ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::ifndef_macro ... ok
Dec 13 01:30:17.747 INFO [stdout] test macros::include_macro ... ok
Dec 13 01:30:17.747 INFO [stdout] test module::any_01 ... ok
Dec 13 01:30:17.747 INFO [stdout] test module::any_02 ... ok
Dec 13 01:30:17.747 INFO [stdout] test module::any_else_00 ... ok
Dec 13 01:30:17.747 INFO [stdout] test module::func_call_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::func_self_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::func_in_module_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::func_in_module_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test macros::define_macro2 ... ok
Dec 13 01:30:17.755 INFO [stdout] test declare::newline_in_declare_block ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::func_in_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test declare::one_bit_input ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::function_call_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::func_self_02 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::function_call_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::if_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::if_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::func_self_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::func_self_03 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::mem_02 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::mem_03 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::mem_04 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::proc_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::module_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::proc_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_02 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_03 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_04 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_05 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_06 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_assign_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_func_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::reg_func_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::return_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::state_name_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::state_name_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test macros::define_macro3 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::state_procedure_00 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::state_procedure_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::state_procedure_02 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::wire_02 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::wire_03 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::wire_04 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::wire_05 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::if_02 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::wire_assign_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::mem_01 ... ok
Dec 13 01:30:17.755 INFO [stdout] test module::wire_01 ... ok
Dec 13 01:30:17.767 INFO [stdout] test module::wire_assign_00 ... ok
Dec 13 01:30:17.767 INFO [stdout] test module::wire_assign_02 ... ok
Dec 13 01:30:17.767 INFO [stdout] test simple_tests::end_of_program ... ok
Dec 13 01:30:17.767 INFO [stdout] test module::mem_00 ... ok
Dec 13 01:30:17.779 INFO [stdout] test module::wire_assign_03 ... ok
Dec 13 01:30:17.939 INFO [stdout] test module::if_03 ... FAILED
Dec 13 01:30:17.939 INFO [stdout] 
Dec 13 01:30:17.939 INFO [stdout] failures:
Dec 13 01:30:17.939 INFO [stdout] 
Dec 13 01:30:17.939 INFO [stdout] ---- module::if_03 stdout ----
Dec 13 01:30:17.939 INFO [stdout] thread 'module::if_03' panicked at 'explicit panic', tests/parser_test.rs:1752:9
Dec 13 01:30:17.939 INFO [stdout] stack backtrace:
Dec 13 01:30:17.939 INFO [stdout]    0:     0x561973fff3a3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h2c40bdfb30f25bdd
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 13 01:30:17.939 INFO [stdout]    1:     0x561973ff9b38 - std::sys_common::backtrace::_print::he11ceebc70626ac9
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:71
Dec 13 01:30:17.939 INFO [stdout]    2:     0x561973ffe312 - std::panicking::default_hook::{{closure}}::h4fde3ffb19c01413
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:59
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/panicking.rs:210
Dec 13 01:30:17.939 INFO [stdout]    3:     0x561973ffe007 - std::panicking::default_hook::hf0c15a583008ed0d
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/panicking.rs:218
Dec 13 01:30:17.939 INFO [stdout]    4:     0x561973ffea20 - std::panicking::rust_panic_with_hook::hc1663f6bc7813185
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/panicking.rs:487
Dec 13 01:30:17.939 INFO [stdout]    5:     0x561973fd09e7 - std::panicking::begin_panic::h06d0acf3ff7ac9fb
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:421
Dec 13 01:30:17.939 INFO [stdout]    6:     0x561973f62818 - parser_test::module::if_03::h3f56dce978be4f7b
Dec 13 01:30:17.939 INFO [stdout]                                at tests/parser_test.rs:1752
Dec 13 01:30:17.939 INFO [stdout]    7:     0x561973f62729 - parser_test::module::if_03::{{closure}}::ha57faa3dfe034178
Dec 13 01:30:17.939 INFO [stdout]                                at tests/parser_test.rs:1748
Dec 13 01:30:17.939 INFO [stdout]    8:     0x561973f6ea2d - core::ops::function::FnOnce::call_once::hace6febb83ef6153
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238
Dec 13 01:30:17.939 INFO [stdout]    9:     0x561973f851ae - <F as alloc::boxed::FnBox<A>>::call_box::hac337ba1af72dc2b
Dec 13 01:30:17.939 INFO [stdout]                                at src/libtest/lib.rs:1483
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libcore/ops/function.rs:238
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673
Dec 13 01:30:17.939 INFO [stdout]   10:     0x56197400d019 - __rust_maybe_catch_panic
Dec 13 01:30:17.939 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 13 01:30:17.939 INFO [stdout]   11:     0x561973fa29b7 - test::run_test::run_test_inner::{{closure}}::hfbfe4a58c0ebb01b
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398
Dec 13 01:30:17.939 INFO [stdout]                                at src/libtest/lib.rs:1438
Dec 13 01:30:17.939 INFO [stdout]   12:     0x561973f7d8c4 - std::sys_common::backtrace::__rust_begin_short_backtrace::hd1f0cf202092f8d7
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/sys_common/backtrace.rs:136
Dec 13 01:30:17.939 INFO [stdout]   13:     0x561973f7deb4 - std::panicking::try::do_call::h5e99f92fbf448cf6
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:479
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:319
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:306
Dec 13 01:30:17.939 INFO [stdout]   14:     0x56197400d019 - __rust_maybe_catch_panic
Dec 13 01:30:17.939 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 13 01:30:17.939 INFO [stdout]   15:     0x561973f8508c - <F as alloc::boxed::FnBox<A>>::call_box::h8ae063aff93ccdb6
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panicking.rs:285
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/panic.rs:398
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/libstd/thread/mod.rs:478
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:673
Dec 13 01:30:17.939 INFO [stdout]   16:     0x561974000fdd - std::sys::unix::thread::Thread::new::thread_start::h42c32b292fdd228f
Dec 13 01:30:17.939 INFO [stdout]                                at /rustc/aa49d8ef14939ddec0e34b346b60174a5673d48f/src/liballoc/boxed.rs:683
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/sys_common/thread.rs:24
Dec 13 01:30:17.939 INFO [stdout]                                at src/libstd/sys/unix/thread.rs:91
Dec 13 01:30:17.939 INFO [stdout]   17:     0x7f91cad346b9 - start_thread
Dec 13 01:30:17.939 INFO [stdout]   18:     0x7f91ca85441c - clone
Dec 13 01:30:17.939 INFO [stdout]   19:                0x0 - <unknown>
Dec 13 01:30:17.939 INFO [stdout] 
Dec 13 01:30:17.939 INFO [stdout] 
Dec 13 01:30:17.939 INFO [stdout] failures:
Dec 13 01:30:17.939 INFO [stdout]     module::if_03
Dec 13 01:30:17.939 INFO [stdout] 
Dec 13 01:30:17.939 INFO [stdout] test result: FAILED. 76 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:17.939 INFO [stdout] 
Dec 13 01:30:17.946 INFO [stderr] error: test failed, to rerun pass '--test parser_test'
Dec 13 01:30:17.946 INFO [stderr] su: No module specific data is present
Dec 13 01:30:18.884 INFO running `"docker" "inspect" "6af0a29db45e3efe4df2433eb14634b5bb7f59ed099c4991f0890bd295a04820"`
Dec 13 01:30:19.173 INFO running `"docker" "rm" "-f" "6af0a29db45e3efe4df2433eb14634b5bb7f59ed099c4991f0890bd295a04820"`
Dec 13 01:30:19.426 INFO [stdout] 6af0a29db45e3efe4df2433eb14634b5bb7f59ed099c4991f0890bd295a04820