Dec 07 09:13:57.682 INFO testing kicad_parse_gen-7.0.2 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 09:13:57.682 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 09:13:58.108 INFO [stdout] 48f59225927281c1496a71f9b1b8b679aedb4e0519425d2b5cee3fb5c7153ad7
Dec 07 09:13:58.113 INFO running `"docker" "start" "-a" "48f59225927281c1496a71f9b1b8b679aedb4e0519425d2b5cee3fb5c7153ad7"`
Dec 07 09:13:59.560 INFO [stderr] usermod: no changes
Dec 07 09:13:59.692 INFO [stderr]    Compiling symbolic_expressions v5.0.3
Dec 07 09:13:59.697 INFO [stderr]    Compiling env_logger v0.4.3
Dec 07 09:14:03.459 INFO [stderr]    Compiling kicad_parse_gen v7.0.2 (/source)
Dec 07 09:14:23.251 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.67s
Dec 07 09:14:23.268 INFO [stderr] su: No module specific data is present
Dec 07 09:14:23.822 INFO running `"docker" "inspect" "48f59225927281c1496a71f9b1b8b679aedb4e0519425d2b5cee3fb5c7153ad7"`
Dec 07 09:14:24.080 INFO running `"docker" "rm" "-f" "48f59225927281c1496a71f9b1b8b679aedb4e0519425d2b5cee3fb5c7153ad7"`
Dec 07 09:14:24.432 INFO [stdout] 48f59225927281c1496a71f9b1b8b679aedb4e0519425d2b5cee3fb5c7153ad7
Dec 07 09:14:24.436 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 09:14:24.876 INFO [stdout] 1ffe01e25c47ead22cbee10509bfee206407cbe92e4abd4a2e51b157f5e63e36
Dec 07 09:14:24.884 INFO running `"docker" "start" "-a" "1ffe01e25c47ead22cbee10509bfee206407cbe92e4abd4a2e51b157f5e63e36"`
Dec 07 09:14:25.968 INFO [stderr] usermod: no changes
Dec 07 09:14:26.122 INFO [stderr]    Compiling kicad_parse_gen v7.0.2 (/source)
Dec 07 09:14:45.194 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 19.19s
Dec 07 09:14:45.216 INFO [stderr] su: No module specific data is present
Dec 07 09:14:45.834 INFO running `"docker" "inspect" "1ffe01e25c47ead22cbee10509bfee206407cbe92e4abd4a2e51b157f5e63e36"`
Dec 07 09:14:46.130 INFO running `"docker" "rm" "-f" "1ffe01e25c47ead22cbee10509bfee206407cbe92e4abd4a2e51b157f5e63e36"`
Dec 07 09:14:46.404 INFO [stdout] 1ffe01e25c47ead22cbee10509bfee206407cbe92e4abd4a2e51b157f5e63e36
Dec 07 09:14:46.406 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/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 +906deae0790bd18681b937fe9a141a3c26cf1855-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 07 09:14:46.785 INFO [stdout] fd612a727ba36d150abcf2ac52e93966e7eb87ef1f5be9fbb806360bb1274786
Dec 07 09:14:46.790 INFO running `"docker" "start" "-a" "fd612a727ba36d150abcf2ac52e93966e7eb87ef1f5be9fbb806360bb1274786"`
Dec 07 09:14:48.100 INFO [stderr] usermod: no changes
Dec 07 09:14:48.220 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 07 09:14:48.244 INFO [stderr]      Running /target/debug/deps/kicad_parse_gen-6fa68e74fa1f957d
Dec 07 09:14:48.244 INFO [stdout] 
Dec 07 09:14:48.244 INFO [stdout] running 7 tests
Dec 07 09:14:48.244 INFO [stdout] test layout::data::test_unnamed_rename ... ok
Dec 07 09:14:48.244 INFO [stdout] test layout::data::test_is_unnamed_net ... ok
Dec 07 09:14:48.244 INFO [stdout] test footprint::data::test::pad_bound_180 ... ok
Dec 07 09:14:48.244 INFO [stdout] test footprint::data::test::pad_bound ... ok
Dec 07 09:14:48.256 INFO [stdout] test checkfix::tests::test_allowed_1_7_2 ... ok
Dec 07 09:14:48.256 INFO [stdout] test checkfix::tests::test_allowed_1_7_1 ... ok
Dec 07 09:14:48.268 INFO [stdout] test footprint::data::test::bound_fail_cy ... ok
Dec 07 09:14:48.268 INFO [stderr]      Running /target/debug/deps/adjust_layout-77d532b0d7f0b1cf
Dec 07 09:14:48.268 INFO [stdout] 
Dec 07 09:14:48.268 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.268 INFO [stdout] 
Dec 07 09:14:48.272 INFO [stdout] 
Dec 07 09:14:48.272 INFO [stderr]      Running /target/debug/deps/dump_footprint-355eb5e9f0b80b66
Dec 07 09:14:48.272 INFO [stdout] running 0 tests
Dec 07 09:14:48.272 INFO [stdout] 
Dec 07 09:14:48.272 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.272 INFO [stdout] 
Dec 07 09:14:48.284 INFO [stdout] 
Dec 07 09:14:48.284 INFO [stdout] running 0 tests
Dec 07 09:14:48.284 INFO [stdout] 
Dec 07 09:14:48.284 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.284 INFO [stdout] 
Dec 07 09:14:48.288 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stderr]      Running /target/debug/deps/dump_layout-7877d4ce05451e8b
Dec 07 09:14:48.292 INFO [stdout] running 0 tests
Dec 07 09:14:48.292 INFO [stderr]      Running /target/debug/deps/identify-cdf18bf7621a8ead
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stderr]      Running /target/debug/deps/klc_kicad_mod-a7697231da160329
Dec 07 09:14:48.292 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stdout] running 0 tests
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stdout] running 0 tests
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.292 INFO [stdout] 
Dec 07 09:14:48.292 INFO [stderr]      Running /target/debug/deps/klc_symbol_lib-5b1c040bdda3d283
Dec 07 09:14:48.296 INFO [stdout] 
Dec 07 09:14:48.296 INFO [stderr]      Running /target/debug/deps/footprint1-5aafc381c5f332c2
Dec 07 09:14:48.296 INFO [stdout] running 0 tests
Dec 07 09:14:48.296 INFO [stdout] 
Dec 07 09:14:48.296 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.296 INFO [stdout] 
Dec 07 09:14:48.296 INFO [stdout] 
Dec 07 09:14:48.296 INFO [stdout] running 1 test
Dec 07 09:14:48.303 INFO [stdout] test parse_and_compare ... ok
Dec 07 09:14:48.303 INFO [stderr]      Running /target/debug/deps/footprint2-9fd0346efcb0ab86
Dec 07 09:14:48.303 INFO [stdout] 
Dec 07 09:14:48.303 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.303 INFO [stdout] 
Dec 07 09:14:48.308 INFO [stdout] 
Dec 07 09:14:48.308 INFO [stdout] running 1 test
Dec 07 09:14:48.336 INFO [stdout] test parse_and_compare ... ok
Dec 07 09:14:48.336 INFO [stderr]      Running /target/debug/deps/fp_lib_table1-03b783c55e39e1b7
Dec 07 09:14:48.336 INFO [stdout] 
Dec 07 09:14:48.336 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.336 INFO [stdout] 
Dec 07 09:14:48.347 INFO [stdout] 
Dec 07 09:14:48.347 INFO [stdout] running 1 test
Dec 07 09:14:48.349 INFO [stdout] test parse_and_compare ... ok
Dec 07 09:14:48.349 INFO [stdout] 
Dec 07 09:14:48.349 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.349 INFO [stdout] 
Dec 07 09:14:48.352 INFO [stderr]      Running /target/debug/deps/layout1-e19449a61b578191
Dec 07 09:14:48.356 INFO [stdout] 
Dec 07 09:14:48.356 INFO [stdout] running 1 test
Dec 07 09:14:48.756 INFO [stdout] test parse_and_compare ... ok
Dec 07 09:14:48.756 INFO [stdout] 
Dec 07 09:14:48.756 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.756 INFO [stdout] 
Dec 07 09:14:48.760 INFO [stderr]      Running /target/debug/deps/layout2-7adc1ae9afefc9b7
Dec 07 09:14:48.764 INFO [stdout] 
Dec 07 09:14:48.764 INFO [stdout] running 1 test
Dec 07 09:14:48.780 INFO [stdout] test parse_and_compare ... ok
Dec 07 09:14:48.780 INFO [stderr] INFO:layout2: bound: Bound { x1: 99.1875, y1: 99.65, x2: 100.8125, y2: 100.35, is_bounded: true }
Dec 07 09:14:48.780 INFO [stdout] 
Dec 07 09:14:48.780 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.780 INFO [stdout] 
Dec 07 09:14:48.780 INFO [stderr]      Running /target/debug/deps/schematic1-6dbce2b0f3fd8917
Dec 07 09:14:48.784 INFO [stdout] 
Dec 07 09:14:48.784 INFO [stdout] running 1 test
Dec 07 09:14:48.829 INFO [stdout] test parse_and_compare ... ok
Dec 07 09:14:48.829 INFO [stderr]      Running /target/debug/deps/symbol_lib1-cfda415dfc178c72
Dec 07 09:14:48.829 INFO [stdout] 
Dec 07 09:14:48.829 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.829 INFO [stdout] 
Dec 07 09:14:48.829 INFO [stdout] 
Dec 07 09:14:48.829 INFO [stdout] running 1 test
Dec 07 09:14:48.832 INFO [stdout] test parse_and_compare ... ok
Dec 07 09:14:48.832 INFO [stdout] 
Dec 07 09:14:48.832 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:48.832 INFO [stdout] 
Dec 07 09:14:48.834 INFO [stderr]    Doc-tests kicad_parse_gen
Dec 07 09:14:50.188 INFO [stdout] 
Dec 07 09:14:50.188 INFO [stdout] running 0 tests
Dec 07 09:14:50.188 INFO [stdout] 
Dec 07 09:14:50.188 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 09:14:50.188 INFO [stdout] 
Dec 07 09:14:50.188 INFO [stderr] su: No module specific data is present
Dec 07 09:14:50.682 INFO running `"docker" "inspect" "fd612a727ba36d150abcf2ac52e93966e7eb87ef1f5be9fbb806360bb1274786"`
Dec 07 09:14:51.060 INFO running `"docker" "rm" "-f" "fd612a727ba36d150abcf2ac52e93966e7eb87ef1f5be9fbb806360bb1274786"`
Dec 07 09:14:51.412 INFO [stdout] fd612a727ba36d150abcf2ac52e93966e7eb87ef1f5be9fbb806360bb1274786