Dec 13 01:29:50.016 INFO testing sux2mfgj/nslfmt against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 13 01:29:50.016 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:50.746 INFO [stdout] 363693b9135f66c9cfe5336f723a3f1dbb18669d5014c076145d408d9eadecd6
Dec 13 01:29:50.748 INFO running `"docker" "start" "-a" "363693b9135f66c9cfe5336f723a3f1dbb18669d5014c076145d408d9eadecd6"`
Dec 13 01:29:53.085 INFO [stderr] usermod: no changes
Dec 13 01:29:53.208 INFO [stderr]    Compiling nslfmt v0.0.1 (/source)
Dec 13 01:29:54.931 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:29:54.931 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:29:54.932 INFO [stderr]     |
Dec 13 01:29:54.932 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:29:54.933 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:29:54.933 INFO [stderr]     |
Dec 13 01:29:54.933 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:29:54.933 INFO [stderr] 
Dec 13 01:29:55.099 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:29:55.099 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:29:55.100 INFO [stderr]   |
Dec 13 01:29:55.100 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:29:55.100 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:29:55.101 INFO [stderr]   |
Dec 13 01:29:55.101 INFO [stderr]   = note: #[warn(dead_code)] on by default
Dec 13 01:29:55.101 INFO [stderr] 
Dec 13 01:29:57.659 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:29:57.659 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:29:57.659 INFO [stderr]     |
Dec 13 01:29:57.659 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:29:57.659 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:29:57.659 INFO [stderr]     |
Dec 13 01:29:57.659 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:29:57.659 INFO [stderr] 
Dec 13 01:29:57.836 INFO [stderr] warning: method is never used: `peek`
Dec 13 01:29:57.836 INFO [stderr]   --> src/lexer.rs:38:5
Dec 13 01:29:57.836 INFO [stderr]    |
Dec 13 01:29:57.837 INFO [stderr] 38 |     pub fn peek(&mut self, is_pass_nl: bool) -> Option<&Token>
Dec 13 01:29:57.837 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:29:57.837 INFO [stderr]    |
Dec 13 01:29:57.837 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 01:29:57.838 INFO [stderr] 
Dec 13 01:29:57.838 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:29:57.838 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:29:57.839 INFO [stderr]   |
Dec 13 01:29:57.839 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:29:57.839 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:29:57.840 INFO [stderr] 
Dec 13 01:30:00.315 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.20s
Dec 13 01:30:00.320 INFO [stderr] su: No module specific data is present
Dec 13 01:30:01.595 INFO running `"docker" "inspect" "363693b9135f66c9cfe5336f723a3f1dbb18669d5014c076145d408d9eadecd6"`
Dec 13 01:30:01.973 INFO running `"docker" "rm" "-f" "363693b9135f66c9cfe5336f723a3f1dbb18669d5014c076145d408d9eadecd6"`
Dec 13 01:30:02.307 INFO [stdout] 363693b9135f66c9cfe5336f723a3f1dbb18669d5014c076145d408d9eadecd6
Dec 13 01:30:02.309 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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.779 INFO [stdout] 3a4ad2a72de5ab6828c40c1ca3c26f626457dc4b5de84db8f18b9f6f1bad5088
Dec 13 01:30:02.783 INFO running `"docker" "start" "-a" "3a4ad2a72de5ab6828c40c1ca3c26f626457dc4b5de84db8f18b9f6f1bad5088"`
Dec 13 01:30:05.128 INFO [stderr] usermod: no changes
Dec 13 01:30:05.203 INFO [stderr]    Compiling nslfmt v0.0.1 (/source)
Dec 13 01:30:06.779 INFO [stderr] warning: static variable `call_count` should have an upper case name such as `CALL_COUNT`
Dec 13 01:30:06.780 INFO [stderr]   --> tests/generator_test.rs:11:1
Dec 13 01:30:06.780 INFO [stderr]    |
Dec 13 01:30:06.780 INFO [stderr] 11 | static call_count: AtomicUsize = AtomicUsize::new(0);
Dec 13 01:30:06.781 INFO [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:06.787 INFO [stderr]    |
Dec 13 01:30:06.787 INFO [stderr]    = note: #[warn(non_upper_case_globals)] on by default
Dec 13 01:30:06.787 INFO [stderr] 
Dec 13 01:30:08.207 INFO [stderr] warning: unused variable: `p`
Dec 13 01:30:08.207 INFO [stderr]     --> tests/parser_test.rs:1186:13
Dec 13 01:30:08.207 INFO [stderr]      |
Dec 13 01:30:08.207 INFO [stderr] 1186 |         let p = Parser::new(&mut l);
Dec 13 01:30:08.207 INFO [stderr]      |             ^ help: consider using `_p` instead
Dec 13 01:30:08.207 INFO [stderr]      |
Dec 13 01:30:08.207 INFO [stderr]      = note: #[warn(unused_variables)] on by default
Dec 13 01:30:08.207 INFO [stderr] 
Dec 13 01:30:08.207 INFO [stderr] warning: unused variable: `module`
Dec 13 01:30:08.207 INFO [stderr]     --> tests/parser_test.rs:1206:13
Dec 13 01:30:08.207 INFO [stderr]      |
Dec 13 01:30:08.207 INFO [stderr] 1206 |         let module = create_node!(ASTClass::Module(
Dec 13 01:30:08.207 INFO [stderr]      |             ^^^^^^ help: consider using `_module` instead
Dec 13 01:30:08.207 INFO [stderr] 
Dec 13 01:30:08.207 INFO [stderr] warning: unused variable: `p`
Dec 13 01:30:08.207 INFO [stderr]     --> tests/parser_test.rs:1751:17
Dec 13 01:30:08.207 INFO [stderr]      |
Dec 13 01:30:08.207 INFO [stderr] 1751 |         let mut p = Parser::new(&mut l);
Dec 13 01:30:08.207 INFO [stderr]      |                 ^ help: consider using `_p` instead
Dec 13 01:30:08.207 INFO [stderr] 
Dec 13 01:30:08.480 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:30:08.480 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:30:08.480 INFO [stderr]     |
Dec 13 01:30:08.480 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:30:08.480 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:30:08.480 INFO [stderr]     |
Dec 13 01:30:08.480 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:30:08.480 INFO [stderr] 
Dec 13 01:30:08.611 INFO [stderr] warning: value assigned to `astarisc_flag` is never read
Dec 13 01:30:08.611 INFO [stderr]    --> src/lexer.rs:423:21
Dec 13 01:30:08.611 INFO [stderr]     |
Dec 13 01:30:08.611 INFO [stderr] 423 |                     astarisc_flag = false;
Dec 13 01:30:08.611 INFO [stderr]     |                     ^^^^^^^^^^^^^
Dec 13 01:30:08.611 INFO [stderr]     |
Dec 13 01:30:08.611 INFO [stderr]     = note: #[warn(unused_assignments)] on by default
Dec 13 01:30:08.611 INFO [stderr] 
Dec 13 01:30:09.121 INFO [stderr] warning: variable does not need to be mutable
Dec 13 01:30:09.122 INFO [stderr]     --> tests/parser_test.rs:1751:13
Dec 13 01:30:09.122 INFO [stderr]      |
Dec 13 01:30:09.122 INFO [stderr] 1751 |         let mut p = Parser::new(&mut l);
Dec 13 01:30:09.123 INFO [stderr]      |             ----^
Dec 13 01:30:09.123 INFO [stderr]      |             |
Dec 13 01:30:09.123 INFO [stderr]      |             help: remove this `mut`
Dec 13 01:30:09.123 INFO [stderr]      |
Dec 13 01:30:09.124 INFO [stderr]      = note: #[warn(unused_mut)] on by default
Dec 13 01:30:09.124 INFO [stderr] 
Dec 13 01:30:09.310 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:30:09.310 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:30:09.310 INFO [stderr]   |
Dec 13 01:30:09.310 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:30:09.310 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:09.339 INFO [stderr]   |
Dec 13 01:30:09.339 INFO [stderr]   = note: #[warn(dead_code)] on by default
Dec 13 01:30:09.339 INFO [stderr] 
Dec 13 01:30:09.539 INFO [stderr] warning: method is never used: `peek`
Dec 13 01:30:09.539 INFO [stderr]   --> src/lexer.rs:38:5
Dec 13 01:30:09.540 INFO [stderr]    |
Dec 13 01:30:09.540 INFO [stderr] 38 |     pub fn peek(&mut self, is_pass_nl: bool) -> Option<&Token>
Dec 13 01:30:09.540 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:09.542 INFO [stderr]    |
Dec 13 01:30:09.542 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 13 01:30:09.542 INFO [stderr] 
Dec 13 01:30:09.543 INFO [stderr] warning: field is never used: `number_of_nest`
Dec 13 01:30:09.543 INFO [stderr]  --> src/parser.rs:7:5
Dec 13 01:30:09.544 INFO [stderr]   |
Dec 13 01:30:09.544 INFO [stderr] 7 |     number_of_nest: usize,
Dec 13 01:30:09.544 INFO [stderr]   |     ^^^^^^^^^^^^^^^^^^^^^
Dec 13 01:30:09.545 INFO [stderr] 
Dec 13 01:30:15.067 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.91s
Dec 13 01:30:15.071 INFO [stderr] su: No module specific data is present
Dec 13 01:30:16.056 INFO running `"docker" "inspect" "3a4ad2a72de5ab6828c40c1ca3c26f626457dc4b5de84db8f18b9f6f1bad5088"`
Dec 13 01:30:16.339 INFO running `"docker" "rm" "-f" "3a4ad2a72de5ab6828c40c1ca3c26f626457dc4b5de84db8f18b9f6f1bad5088"`
Dec 13 01:30:16.675 INFO [stdout] 3a4ad2a72de5ab6828c40c1ca3c26f626457dc4b5de84db8f18b9f6f1bad5088
Dec 13 01:30:16.683 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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:17.407 INFO [stdout] 76b1778046069ec5cca8e39693bd61fe5e2d2e8212466674cea59de581b34ebf
Dec 13 01:30:17.410 INFO running `"docker" "start" "-a" "76b1778046069ec5cca8e39693bd61fe5e2d2e8212466674cea59de581b34ebf"`
Dec 13 01:30:18.959 INFO [stderr] usermod: no changes
Dec 13 01:30:19.015 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.02s
Dec 13 01:30:19.023 INFO [stderr]      Running /target/debug/deps/nslfmt-0adcefe69e8a037d
Dec 13 01:30:19.030 INFO [stdout] 
Dec 13 01:30:19.030 INFO [stdout] running 0 tests
Dec 13 01:30:19.030 INFO [stdout] 
Dec 13 01:30:19.030 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:19.030 INFO [stdout] 
Dec 13 01:30:19.035 INFO [stderr]      Running /target/debug/deps/nslfmt-d5992478fca372a4
Dec 13 01:30:19.036 INFO [stdout] 
Dec 13 01:30:19.036 INFO [stdout] running 0 tests
Dec 13 01:30:19.036 INFO [stdout] 
Dec 13 01:30:19.036 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:19.036 INFO [stdout] 
Dec 13 01:30:19.039 INFO [stderr]      Running /target/debug/deps/generator_test-e0271dff97b5859c
Dec 13 01:30:19.047 INFO [stdout] 
Dec 13 01:30:19.047 INFO [stdout] running 20 tests
Dec 13 01:30:19.047 INFO [stdout] test define_path ... ok
Dec 13 01:30:19.047 INFO [stdout] test comment_01 ... ok
Dec 13 01:30:19.047 INFO [stdout] test comment_00 ... ok
Dec 13 01:30:19.047 INFO [stdout] test aware_indent_02 ... ok
Dec 13 01:30:19.047 INFO [stdout] test aware_indent_01 ... ok
Dec 13 01:30:19.047 INFO [stdout] test assign_wire_00 ... ok
Dec 13 01:30:19.047 INFO [stdout] test any_00 ... ok
Dec 13 01:30:19.047 INFO [stdout] test func_00 ... ok
Dec 13 01:30:19.047 INFO [stdout] test func_call_00 ... ok
Dec 13 01:30:19.047 INFO [stdout] test func_self_00 ... ok
Dec 13 01:30:19.047 INFO [stdout] test new_by_file ... ok
Dec 13 01:30:19.047 INFO [stdout] test new_by_stdout ... ok
Dec 13 01:30:19.047 INFO [stdout] test output_declare_to_file ... ok
Dec 13 01:30:19.047 INFO [stdout] test parences ... ok
Dec 13 01:30:19.047 INFO [stdout] test reg_00 ... ok
Dec 13 01:30:19.051 INFO [stdout] test return_00 ... ok
Dec 13 01:30:19.051 INFO [stderr]      Running /target/debug/deps/lexer_test-7d349067e7987951
Dec 13 01:30:19.051 INFO [stdout] test two_arguments ... ok
Dec 13 01:30:19.051 INFO [stdout] test wire_00 ... ok
Dec 13 01:30:19.051 INFO [stdout] test aware_indent_03 ... ok
Dec 13 01:30:19.051 INFO [stdout] test wire_01 ... ok
Dec 13 01:30:19.051 INFO [stdout] 
Dec 13 01:30:19.051 INFO [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:19.051 INFO [stdout] 
Dec 13 01:30:19.051 INFO [stdout] 
Dec 13 01:30:19.051 INFO [stdout] running 52 tests
Dec 13 01:30:19.067 INFO [stdout] test create_instance_with_file ... ok
Dec 13 01:30:19.067 INFO [stdout] test declare_04 ... ok
Dec 13 01:30:19.067 INFO [stdout] test declare_func_in ... ok
Dec 13 01:30:19.067 INFO [stdout] test create_instance_with_string ... ok
Dec 13 01:30:19.067 INFO [stdout] test comment_00 ... ok
Dec 13 01:30:19.067 INFO [stdout] test braces_and_newline ... ok
Dec 13 01:30:19.067 INFO [stdout] test define_path ... ok
Dec 13 01:30:19.067 INFO [stdout] test any_else_00 ... ok
Dec 13 01:30:19.067 INFO [stdout] test declare_with_input ... ok
Dec 13 01:30:19.067 INFO [stdout] test any_block_00 ... ok
Dec 13 01:30:19.068 INFO [stdout] test func_block_00 ... ok
Dec 13 01:30:19.068 INFO [stdout] test func_block_return ... ok
Dec 13 01:30:19.068 INFO [stdout] test func_self_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test declare_func_out ... ok
Dec 13 01:30:19.071 INFO [stdout] test gt_lt_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test get_token_eop ... ok
Dec 13 01:30:19.071 INFO [stdout] test macro_define ... ok
Dec 13 01:30:19.071 INFO [stdout] test get_token_file ... ok
Dec 13 01:30:19.071 INFO [stdout] test macro_else ... ok
Dec 13 01:30:19.071 INFO [stdout] test get_token_str ... ok
Dec 13 01:30:19.071 INFO [stdout] test get_token_new_line ... ok
Dec 13 01:30:19.071 INFO [stdout] test macro_endif ... ok
Dec 13 01:30:19.071 INFO [stdout] test macro_ifdef ... ok
Dec 13 01:30:19.071 INFO [stdout] test macro_ifndef ... ok
Dec 13 01:30:19.071 INFO [stdout] test macro_include ... ok
Dec 13 01:30:19.071 INFO [stdout] test macro_undef ... ok
Dec 13 01:30:19.071 INFO [stdout] test mem_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test module_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test module_wire_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test module_wire_01 ... ok
Dec 13 01:30:19.071 INFO [stdout] test mutiline_comment_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test mutiline_comment_01 ... ok
Dec 13 01:30:19.071 INFO [stdout] test mutiline_comment_02 ... ok
Dec 13 01:30:19.071 INFO [stdout] test mutiline_comment_03 ... ok
Dec 13 01:30:19.071 INFO [stdout] test newline_in_declare_block ... ok
Dec 13 01:30:19.071 INFO [stdout] test next_nl ... ok
Dec 13 01:30:19.071 INFO [stdout] test number ... ok
Dec 13 01:30:19.071 INFO [stdout] test number_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test number_01 ... ok
Dec 13 01:30:19.071 INFO [stdout] test number_02 ... ok
Dec 13 01:30:19.071 INFO [stdout] test number_03 ... ok
Dec 13 01:30:19.071 INFO [stdout] test or_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test pass_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test pass_newlines ... ok
Dec 13 01:30:19.071 INFO [stdout] test peek_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test plus_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test proc_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test reg_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test reg_assignment_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test state_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test state_name_00 ... ok
Dec 13 01:30:19.071 INFO [stdout] test wire_assign_00 ... ok
Dec 13 01:30:19.071 INFO [stderr]      Running /target/debug/deps/parser_test-97cb5b3cfe09a96c
Dec 13 01:30:19.071 INFO [stdout] 
Dec 13 01:30:19.072 INFO [stdout] test result: ok. 52 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:19.072 INFO [stdout] 
Dec 13 01:30:19.075 INFO [stdout] 
Dec 13 01:30:19.075 INFO [stdout] running 77 tests
Dec 13 01:30:19.075 INFO [stdout] test declare::declare_only ... ok
Dec 13 01:30:19.075 INFO [stdout] test declare::declare_03 ... ok
Dec 13 01:30:19.075 INFO [stdout] test declare::func_out_return ... ok
Dec 13 01:30:19.075 INFO [stdout] test declare::expression_in_width_block_00 ... ok
Dec 13 01:30:19.075 INFO [stdout] test declare::macro_in_declare_00 ... ok
Dec 13 01:30:19.075 INFO [stdout] test declare::macro_in_declare_01 ... ok
Dec 13 01:30:19.075 INFO [stdout] test declare::multi_bit_input ... ok
Dec 13 01:30:19.075 INFO [stdout] test declare::newline_in_declare_block ... ok
Dec 13 01:30:19.079 INFO [stdout] test macros::define_macro2 ... ok
Dec 13 01:30:19.079 INFO [stdout] test macros::define_macro3 ... ok
Dec 13 01:30:19.087 INFO [stdout] test macros::define_macro_eof ... ok
Dec 13 01:30:19.087 INFO [stdout] test declare::output_inout ... ok
Dec 13 01:30:19.087 INFO [stdout] test declare::one_bit_input ... ok
Dec 13 01:30:19.087 INFO [stdout] test comment::multi_line_comment ... ok
Dec 13 01:30:19.087 INFO [stdout] test macros::define_macro_nl ... ok
Dec 13 01:30:19.087 INFO [stdout] test macros::endif_macro ... ok
Dec 13 01:30:19.103 INFO [stdout] test declare::expression_in_width_block_02 ... ok
Dec 13 01:30:19.103 INFO [stdout] test macros::ifndef_macro ... ok
Dec 13 01:30:19.103 INFO [stdout] test macros::if_else_end ... ok
Dec 13 01:30:19.103 INFO [stdout] test macros::ifdef_macro ... ok
Dec 13 01:30:19.103 INFO [stdout] test declare::expression_in_width_block_01 ... ok
Dec 13 01:30:19.103 INFO [stdout] test declare::func_in ... ok
Dec 13 01:30:19.103 INFO [stdout] test declare::func_in_return ... ok
Dec 13 01:30:19.111 INFO [stdout] test macros::undef_macro ... ok
Dec 13 01:30:19.111 INFO [stdout] test macros::include_macro ... ok
Dec 13 01:30:19.111 INFO [stdout] test module::any_else_00 ... ok
Dec 13 01:30:19.111 INFO [stdout] test module::any_00 ... ok
Dec 13 01:30:19.111 INFO [stdout] test module::func_call_00 ... ok
Dec 13 01:30:19.111 INFO [stdout] test module::any_02 ... ok
Dec 13 01:30:19.111 INFO [stdout] test module::any_01 ... ok
Dec 13 01:30:19.111 INFO [stdout] test module::func_in_00 ... ok
Dec 13 01:30:19.111 INFO [stdout] test module::func_in_module_00 ... ok
Dec 13 01:30:19.113 INFO [stdout] test module::func_self_00 ... ok
Dec 13 01:30:19.113 INFO [stdout] test module::func_self_02 ... ok
Dec 13 01:30:19.113 INFO [stdout] test module::func_in_module_01 ... ok
Dec 13 01:30:19.113 INFO [stdout] test module::func_self_01 ... ok
Dec 13 01:30:19.113 INFO [stdout] test module::function_call_01 ... ok
Dec 13 01:30:19.113 INFO [stdout] test module::if_00 ... ok
Dec 13 01:30:19.113 INFO [stdout] test module::function_call_00 ... ok
Dec 13 01:30:19.119 INFO [stdout] test module::if_01 ... ok
Dec 13 01:30:19.119 INFO [stdout] test module::func_self_03 ... ok
Dec 13 01:30:19.119 INFO [stdout] test module::mem_00 ... ok
Dec 13 01:30:19.119 INFO [stdout] test module::mem_01 ... ok
Dec 13 01:30:19.119 INFO [stdout] test module::if_02 ... ok
Dec 13 01:30:19.119 INFO [stdout] test module::mem_02 ... ok
Dec 13 01:30:19.119 INFO [stdout] test module::mem_03 ... ok
Dec 13 01:30:19.155 INFO [stdout] test module::mem_04 ... ok
Dec 13 01:30:19.155 INFO [stdout] test module::reg_00 ... ok
Dec 13 01:30:19.155 INFO [stdout] test module::reg_01 ... ok
Dec 13 01:30:19.155 INFO [stdout] test module::reg_02 ... ok
Dec 13 01:30:19.155 INFO [stdout] test module::proc_00 ... ok
Dec 13 01:30:19.155 INFO [stdout] test module::proc_01 ... ok
Dec 13 01:30:19.155 INFO [stdout] test module::module_00 ... ok
Dec 13 01:30:19.163 INFO [stdout] test module::reg_03 ... ok
Dec 13 01:30:19.163 INFO [stdout] test module::reg_06 ... ok
Dec 13 01:30:19.163 INFO [stdout] test module::reg_assign_00 ... ok
Dec 13 01:30:19.163 INFO [stdout] test module::reg_func_01 ... ok
Dec 13 01:30:19.163 INFO [stdout] test module::reg_func_00 ... ok
Dec 13 01:30:19.163 INFO [stdout] test module::reg_04 ... ok
Dec 13 01:30:19.171 INFO [stdout] test module::return_00 ... ok
Dec 13 01:30:19.171 INFO [stdout] test module::state_name_01 ... ok
Dec 13 01:30:19.171 INFO [stdout] test module::state_procedure_00 ... ok
Dec 13 01:30:19.171 INFO [stdout] test module::state_name_00 ... ok
Dec 13 01:30:19.171 INFO [stdout] test module::state_procedure_01 ... ok
Dec 13 01:30:19.171 INFO [stdout] test module::state_procedure_02 ... ok
Dec 13 01:30:19.187 INFO [stdout] test module::wire_01 ... ok
Dec 13 01:30:19.187 INFO [stdout] test module::wire_03 ... ok
Dec 13 01:30:19.187 INFO [stdout] test module::wire_05 ... ok
Dec 13 01:30:19.187 INFO [stdout] test module::wire_02 ... ok
Dec 13 01:30:19.187 INFO [stdout] test module::wire_assign_01 ... ok
Dec 13 01:30:19.187 INFO [stdout] test module::wire_assign_02 ... ok
Dec 13 01:30:19.187 INFO [stdout] test module::wire_assign_03 ... ok
Dec 13 01:30:19.187 INFO [stdout] test simple_tests::end_of_program ... ok
Dec 13 01:30:19.194 INFO [stdout] test module::reg_05 ... ok
Dec 13 01:30:19.199 INFO [stdout] test module::wire_assign_00 ... ok
Dec 13 01:30:19.199 INFO [stdout] test module::wire_04 ... ok
Dec 13 01:30:19.255 INFO [stdout] test module::if_03 ... FAILED
Dec 13 01:30:19.255 INFO [stderr] error: test failed, to rerun pass '--test parser_test'
Dec 13 01:30:19.255 INFO [stdout] 
Dec 13 01:30:19.255 INFO [stdout] failures:
Dec 13 01:30:19.255 INFO [stdout] 
Dec 13 01:30:19.255 INFO [stdout] ---- module::if_03 stdout ----
Dec 13 01:30:19.255 INFO [stdout] thread 'module::if_03' panicked at 'explicit panic', tests/parser_test.rs:1752:9
Dec 13 01:30:19.255 INFO [stdout] stack backtrace:
Dec 13 01:30:19.255 INFO [stdout]    0:     0x561b3e5dc3a3 - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::heb4d4d98bcf46d90
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 13 01:30:19.255 INFO [stdout]    1:     0x561b3e5d6b38 - std::sys_common::backtrace::_print::h919420b22c2473e3
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:71
Dec 13 01:30:19.255 INFO [stdout]    2:     0x561b3e5db312 - std::panicking::default_hook::{{closure}}::h22d4ac021acb88d5
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/sys_common/backtrace.rs:59
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/panicking.rs:210
Dec 13 01:30:19.255 INFO [stdout]    3:     0x561b3e5db007 - std::panicking::default_hook::h1134a4d34aab4428
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/panicking.rs:218
Dec 13 01:30:19.255 INFO [stdout]    4:     0x561b3e5dba20 - std::panicking::rust_panic_with_hook::h9c89557c251f2d42
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/panicking.rs:487
Dec 13 01:30:19.255 INFO [stdout]    5:     0x561b3e5ad9e7 - std::panicking::begin_panic::h7b7064b843d14982
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:421
Dec 13 01:30:19.255 INFO [stdout]    6:     0x561b3e53f818 - parser_test::module::if_03::h3507375aee89c281
Dec 13 01:30:19.255 INFO [stdout]                                at tests/parser_test.rs:1752
Dec 13 01:30:19.255 INFO [stdout]    7:     0x561b3e53f729 - parser_test::module::if_03::{{closure}}::h7307e35406772ed2
Dec 13 01:30:19.255 INFO [stdout]                                at tests/parser_test.rs:1748
Dec 13 01:30:19.255 INFO [stdout]    8:     0x561b3e54b46d - core::ops::function::FnOnce::call_once::h69a277af87f6be59
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238
Dec 13 01:30:19.255 INFO [stdout]    9:     0x561b3e56200e - <F as alloc::boxed::FnBox<A>>::call_box::h3cf534772c089f15
Dec 13 01:30:19.255 INFO [stdout]                                at src/libtest/lib.rs:1483
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libcore/ops/function.rs:238
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673
Dec 13 01:30:19.255 INFO [stdout]   10:     0x561b3e5ea019 - __rust_maybe_catch_panic
Dec 13 01:30:19.255 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 13 01:30:19.255 INFO [stdout]   11:     0x561b3e57f9b7 - test::run_test::run_test_inner::{{closure}}::h5f14673685eef7c8
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398
Dec 13 01:30:19.255 INFO [stdout]                                at src/libtest/lib.rs:1438
Dec 13 01:30:19.255 INFO [stdout]   12:     0x561b3e55a8c4 - std::sys_common::backtrace::__rust_begin_short_backtrace::h96de92a7d062bb5f
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/sys_common/backtrace.rs:136
Dec 13 01:30:19.255 INFO [stdout]   13:     0x561b3e55af34 - std::panicking::try::do_call::h6a71e68ea4ad0d8a
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:479
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:319
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:306
Dec 13 01:30:19.255 INFO [stdout]   14:     0x561b3e5ea019 - __rust_maybe_catch_panic
Dec 13 01:30:19.255 INFO [stdout]                                at src/libpanic_unwind/lib.rs:102
Dec 13 01:30:19.255 INFO [stdout]   15:     0x561b3e561eec - <F as alloc::boxed::FnBox<A>>::call_box::h260fd1ad8e2854a6
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panicking.rs:285
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/panic.rs:398
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/libstd/thread/mod.rs:478
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:673
Dec 13 01:30:19.255 INFO [stdout]   16:     0x561b3e5ddfdd - std::sys::unix::thread::Thread::new::thread_start::h08aa646226e66c99
Dec 13 01:30:19.255 INFO [stdout]                                at /rustc/bd47d6825bf4090517549d33cfef10d3300b4a75/src/liballoc/boxed.rs:683
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/sys_common/thread.rs:24
Dec 13 01:30:19.255 INFO [stdout]                                at src/libstd/sys/unix/thread.rs:91
Dec 13 01:30:19.255 INFO [stdout]   17:     0x7fb7e234e6b9 - start_thread
Dec 13 01:30:19.255 INFO [stdout]   18:     0x7fb7e1e6e41c - clone
Dec 13 01:30:19.255 INFO [stdout]   19:                0x0 - <unknown>
Dec 13 01:30:19.255 INFO [stdout] 
Dec 13 01:30:19.255 INFO [stdout] 
Dec 13 01:30:19.255 INFO [stdout] failures:
Dec 13 01:30:19.255 INFO [stdout]     module::if_03
Dec 13 01:30:19.255 INFO [stdout] 
Dec 13 01:30:19.255 INFO [stdout] test result: FAILED. 76 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out
Dec 13 01:30:19.255 INFO [stdout] 
Dec 13 01:30:19.271 INFO [stderr] su: No module specific data is present
Dec 13 01:30:20.384 INFO running `"docker" "inspect" "76b1778046069ec5cca8e39693bd61fe5e2d2e8212466674cea59de581b34ebf"`
Dec 13 01:30:20.739 INFO running `"docker" "rm" "-f" "76b1778046069ec5cca8e39693bd61fe5e2d2e8212466674cea59de581b34ebf"`
Dec 13 01:30:20.999 INFO [stdout] 76b1778046069ec5cca8e39693bd61fe5e2d2e8212466674cea59de581b34ebf