Nov 18 07:58:32.811 INFO testing kicad_parse_gen-7.0.2 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 07:58:32.813 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 18 07:58:33.235 INFO [stdout] d07a238caeba4db936355298fe51a567c675cc10125778f49c0f7c11ebe87e3d
Nov 18 07:58:33.237 INFO running `"docker" "start" "-a" "d07a238caeba4db936355298fe51a567c675cc10125778f49c0f7c11ebe87e3d"`
Nov 18 07:58:34.320 INFO [stderr] usermod: no changes
Nov 18 07:58:34.427 INFO [stderr]    Compiling symbolic_expressions v5.0.3
Nov 18 07:58:36.599 INFO [stderr]    Compiling kicad_parse_gen v7.0.2 (/source)
Nov 18 07:58:56.921 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.58s
Nov 18 07:58:56.926 INFO [stderr] su: No module specific data is present
Nov 18 07:58:57.797 INFO running `"docker" "inspect" "d07a238caeba4db936355298fe51a567c675cc10125778f49c0f7c11ebe87e3d"`
Nov 18 07:58:58.131 INFO running `"docker" "rm" "-f" "d07a238caeba4db936355298fe51a567c675cc10125778f49c0f7c11ebe87e3d"`
Nov 18 07:58:58.523 INFO [stdout] d07a238caeba4db936355298fe51a567c675cc10125778f49c0f7c11ebe87e3d
Nov 18 07:58:58.528 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 18 07:58:59.105 INFO [stdout] 132ecf1cd617dc5c9efd93287fd1d9ef1b6778bed5454530bac4e7fea5198dda
Nov 18 07:58:59.106 INFO running `"docker" "start" "-a" "132ecf1cd617dc5c9efd93287fd1d9ef1b6778bed5454530bac4e7fea5198dda"`
Nov 18 07:59:00.312 INFO [stderr] usermod: no changes
Nov 18 07:59:00.454 INFO [stderr]    Compiling kicad_parse_gen v7.0.2 (/source)
Nov 18 07:59:19.347 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.97s
Nov 18 07:59:19.355 INFO [stderr] su: No module specific data is present
Nov 18 07:59:20.249 INFO running `"docker" "inspect" "132ecf1cd617dc5c9efd93287fd1d9ef1b6778bed5454530bac4e7fea5198dda"`
Nov 18 07:59:20.429 INFO running `"docker" "rm" "-f" "132ecf1cd617dc5c9efd93287fd1d9ef1b6778bed5454530bac4e7fea5198dda"`
Nov 18 07:59:20.704 INFO [stdout] 132ecf1cd617dc5c9efd93287fd1d9ef1b6778bed5454530bac4e7fea5198dda
Nov 18 07:59:20.714 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-7/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/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 18 07:59:21.111 INFO [stdout] f5edd32ec319b27722afc2ca9b67b6d2f3e026e802669f1ffaf01c62ecaa942c
Nov 18 07:59:21.116 INFO running `"docker" "start" "-a" "f5edd32ec319b27722afc2ca9b67b6d2f3e026e802669f1ffaf01c62ecaa942c"`
Nov 18 07:59:22.483 INFO [stderr] usermod: no changes
Nov 18 07:59:22.540 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.03s
Nov 18 07:59:22.559 INFO [stderr]      Running /target/debug/deps/kicad_parse_gen-e96d8ccb77b070e8
Nov 18 07:59:22.562 INFO [stdout] 
Nov 18 07:59:22.562 INFO [stdout] running 7 tests
Nov 18 07:59:22.564 INFO [stdout] test footprint::data::test::pad_bound_180 ... ok
Nov 18 07:59:22.564 INFO [stdout] test footprint::data::test::pad_bound ... ok
Nov 18 07:59:22.567 INFO [stdout] test layout::data::test_is_unnamed_net ... ok
Nov 18 07:59:22.567 INFO [stdout] test checkfix::tests::test_allowed_1_7_1 ... ok
Nov 18 07:59:22.567 INFO [stdout] test checkfix::tests::test_allowed_1_7_2 ... ok
Nov 18 07:59:22.576 INFO [stdout] test layout::data::test_unnamed_rename ... ok
Nov 18 07:59:22.579 INFO [stdout] test footprint::data::test::bound_fail_cy ... ok
Nov 18 07:59:22.579 INFO [stderr]      Running /target/debug/deps/adjust_layout-7de01931fe1d2c76
Nov 18 07:59:22.579 INFO [stdout] 
Nov 18 07:59:22.579 INFO [stderr]      Running /target/debug/deps/dump_footprint-1850b80209c9f428
Nov 18 07:59:22.579 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.579 INFO [stdout] 
Nov 18 07:59:22.579 INFO [stdout] 
Nov 18 07:59:22.579 INFO [stdout] running 0 tests
Nov 18 07:59:22.579 INFO [stdout] 
Nov 18 07:59:22.579 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.579 INFO [stdout] 
Nov 18 07:59:22.592 INFO [stdout] 
Nov 18 07:59:22.592 INFO [stderr]      Running /target/debug/deps/dump_layout-fad074d79116ca93
Nov 18 07:59:22.592 INFO [stdout] running 0 tests
Nov 18 07:59:22.592 INFO [stderr]      Running /target/debug/deps/identify-b2c3dd00af8567d9
Nov 18 07:59:22.592 INFO [stdout] 
Nov 18 07:59:22.592 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.592 INFO [stdout] 
Nov 18 07:59:22.592 INFO [stdout] 
Nov 18 07:59:22.592 INFO [stdout] running 0 tests
Nov 18 07:59:22.592 INFO [stdout] 
Nov 18 07:59:22.592 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.592 INFO [stdout] 
Nov 18 07:59:22.597 INFO [stdout] 
Nov 18 07:59:22.597 INFO [stdout] running 0 tests
Nov 18 07:59:22.597 INFO [stdout] 
Nov 18 07:59:22.598 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.598 INFO [stdout] 
Nov 18 07:59:22.598 INFO [stderr]      Running /target/debug/deps/klc_kicad_mod-48a93b7e44f5c4c5
Nov 18 07:59:22.608 INFO [stderr]      Running /target/debug/deps/klc_symbol_lib-93f46e7d5001fc61
Nov 18 07:59:22.608 INFO [stdout] 
Nov 18 07:59:22.608 INFO [stderr]      Running /target/debug/deps/footprint1-90ed4ccde9322d5a
Nov 18 07:59:22.608 INFO [stdout] running 0 tests
Nov 18 07:59:22.608 INFO [stdout] 
Nov 18 07:59:22.608 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.608 INFO [stdout] 
Nov 18 07:59:22.608 INFO [stdout] 
Nov 18 07:59:22.608 INFO [stdout] running 0 tests
Nov 18 07:59:22.608 INFO [stdout] 
Nov 18 07:59:22.608 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.608 INFO [stdout] 
Nov 18 07:59:22.610 INFO [stdout] 
Nov 18 07:59:22.610 INFO [stdout] running 1 test
Nov 18 07:59:22.615 INFO [stdout] test parse_and_compare ... ok
Nov 18 07:59:22.615 INFO [stdout] 
Nov 18 07:59:22.615 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.615 INFO [stdout] 
Nov 18 07:59:22.616 INFO [stderr]      Running /target/debug/deps/footprint2-ffab006f31043cd3
Nov 18 07:59:22.620 INFO [stdout] 
Nov 18 07:59:22.620 INFO [stdout] running 1 test
Nov 18 07:59:22.635 INFO [stdout] test parse_and_compare ... ok
Nov 18 07:59:22.635 INFO [stdout] 
Nov 18 07:59:22.635 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.635 INFO [stdout] 
Nov 18 07:59:22.643 INFO [stderr]      Running /target/debug/deps/fp_lib_table1-17572d8a6cd4fc9c
Nov 18 07:59:22.649 INFO [stdout] 
Nov 18 07:59:22.649 INFO [stdout] running 1 test
Nov 18 07:59:22.649 INFO [stdout] test parse_and_compare ... ok
Nov 18 07:59:22.649 INFO [stdout] 
Nov 18 07:59:22.649 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:22.649 INFO [stdout] 
Nov 18 07:59:22.649 INFO [stderr]      Running /target/debug/deps/layout1-e6d961fc65a8efa4
Nov 18 07:59:22.660 INFO [stdout] 
Nov 18 07:59:22.660 INFO [stdout] running 1 test
Nov 18 07:59:23.030 INFO [stderr]      Running /target/debug/deps/layout2-f293179781618785
Nov 18 07:59:23.030 INFO [stdout] test parse_and_compare ... ok
Nov 18 07:59:23.030 INFO [stdout] 
Nov 18 07:59:23.030 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:23.031 INFO [stdout] 
Nov 18 07:59:23.031 INFO [stdout] 
Nov 18 07:59:23.031 INFO [stdout] running 1 test
Nov 18 07:59:23.051 INFO [stdout] test parse_and_compare ... ok
Nov 18 07:59:23.051 INFO [stderr] INFO:layout2: bound: Bound { x1: 99.1875, y1: 99.65, x2: 100.8125, y2: 100.35, is_bounded: true }
Nov 18 07:59:23.052 INFO [stdout] 
Nov 18 07:59:23.052 INFO [stderr]      Running /target/debug/deps/schematic1-4e487e7feed0e234
Nov 18 07:59:23.052 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:23.052 INFO [stdout] 
Nov 18 07:59:23.052 INFO [stdout] 
Nov 18 07:59:23.052 INFO [stdout] running 1 test
Nov 18 07:59:23.088 INFO [stdout] test parse_and_compare ... ok
Nov 18 07:59:23.088 INFO [stderr]      Running /target/debug/deps/symbol_lib1-e969de94c427824b
Nov 18 07:59:23.088 INFO [stdout] 
Nov 18 07:59:23.088 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:23.088 INFO [stdout] 
Nov 18 07:59:23.091 INFO [stdout] 
Nov 18 07:59:23.091 INFO [stdout] running 1 test
Nov 18 07:59:23.093 INFO [stdout] test parse_and_compare ... ok
Nov 18 07:59:23.093 INFO [stdout] 
Nov 18 07:59:23.093 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:23.093 INFO [stdout] 
Nov 18 07:59:23.094 INFO [stderr]    Doc-tests kicad_parse_gen
Nov 18 07:59:24.083 INFO [stdout] 
Nov 18 07:59:24.083 INFO [stdout] running 0 tests
Nov 18 07:59:24.083 INFO [stdout] 
Nov 18 07:59:24.083 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 07:59:24.083 INFO [stdout] 
Nov 18 07:59:24.094 INFO [stderr] su: No module specific data is present
Nov 18 07:59:24.409 INFO running `"docker" "inspect" "f5edd32ec319b27722afc2ca9b67b6d2f3e026e802669f1ffaf01c62ecaa942c"`
Nov 18 07:59:24.529 INFO running `"docker" "rm" "-f" "f5edd32ec319b27722afc2ca9b67b6d2f3e026e802669f1ffaf01c62ecaa942c"`
Nov 18 07:59:24.695 INFO [stdout] f5edd32ec319b27722afc2ca9b67b6d2f3e026e802669f1ffaf01c62ecaa942c