Dec 15 02:26:53.657 INFO testing oysterpack_testing-0.1.2 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550 Dec 15 02:26:53.657 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 15 02:26:53.896 INFO [stdout] b2a52324e6d92dc41dbac16863a0929e8b905809f14cb5d134fbb2b13d96721f Dec 15 02:26:53.898 INFO running `"docker" "start" "-a" "b2a52324e6d92dc41dbac16863a0929e8b905809f14cb5d134fbb2b13d96721f"` Dec 15 02:26:54.341 INFO [stderr] usermod: no changes Dec 15 02:26:54.419 INFO [stderr] Compiling oysterpack_testing v0.1.2 (/source) Dec 15 02:26:55.140 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.78s Dec 15 02:26:55.145 INFO [stderr] su: No module specific data is present Dec 15 02:26:55.390 INFO running `"docker" "inspect" "b2a52324e6d92dc41dbac16863a0929e8b905809f14cb5d134fbb2b13d96721f"` Dec 15 02:26:55.470 INFO running `"docker" "rm" "-f" "b2a52324e6d92dc41dbac16863a0929e8b905809f14cb5d134fbb2b13d96721f"` Dec 15 02:26:55.581 INFO [stdout] b2a52324e6d92dc41dbac16863a0929e8b905809f14cb5d134fbb2b13d96721f Dec 15 02:26:55.583 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 15 02:26:55.741 INFO [stdout] 9c7dde7a6b66da2439fb32c0dfdd96a754ea016816f3eaf10dd9b624920eb47a Dec 15 02:26:55.744 INFO running `"docker" "start" "-a" "9c7dde7a6b66da2439fb32c0dfdd96a754ea016816f3eaf10dd9b624920eb47a"` Dec 15 02:26:56.216 INFO [stderr] usermod: no changes Dec 15 02:26:56.337 INFO [stderr] Compiling oysterpack_testing v0.1.2 (/source) Dec 15 02:27:00.588 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 4.35s Dec 15 02:27:00.595 INFO [stderr] su: No module specific data is present Dec 15 02:27:01.000 INFO running `"docker" "inspect" "9c7dde7a6b66da2439fb32c0dfdd96a754ea016816f3eaf10dd9b624920eb47a"` Dec 15 02:27:01.167 INFO running `"docker" "rm" "-f" "9c7dde7a6b66da2439fb32c0dfdd96a754ea016816f3eaf10dd9b624920eb47a"` Dec 15 02:27:01.294 INFO [stdout] 9c7dde7a6b66da2439fb32c0dfdd96a754ea016816f3eaf10dd9b624920eb47a Dec 15 02:27:01.297 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 15 02:27:01.515 INFO [stdout] 226d8480cbee31006d57ad9dc29b8bc7fbcaf1184e8df2f4e2a1935f2f93302d Dec 15 02:27:01.520 INFO running `"docker" "start" "-a" "226d8480cbee31006d57ad9dc29b8bc7fbcaf1184e8df2f4e2a1935f2f93302d"` Dec 15 02:27:02.250 INFO [stderr] usermod: no changes Dec 15 02:27:02.354 INFO [stderr] Finished dev [unoptimized + debuginfo] target(s) in 0.07s Dec 15 02:27:02.371 INFO [stderr] Running /target/debug/deps/oysterpack_testing-24f3057ba40bdf03 Dec 15 02:27:02.381 INFO [stdout] Dec 15 02:27:02.381 INFO [stdout] running 4 tests Dec 15 02:27:02.381 INFO [stdout] [02:27:02.376][INFO][oysterpack_testing::tests][src/lib.rs:105] logging has been initialized for oysterpack_testing Dec 15 02:27:02.381 INFO [stdout] [02:27:02.376][INFO][oysterpack_testing::tests][src/lib.rs:105] it compiles :) Dec 15 02:27:02.381 INFO [stdout] [02:27:02.376][INFO][oysterpack_testing::tests][src/lib.rs:105] compiles: test run time: 30.38µs Dec 15 02:27:02.381 INFO [stdout] test tests::compiles ... ok Dec 15 02:27:02.387 INFO [stdout] [02:27:02.379][INFO][oysterpack_testing::macros::tests][src/macros.rs:187] bar passed !!! Dec 15 02:27:02.387 INFO [stderr] Running /target/debug/deps/version_numbers-69c70a2a1cb0527f Dec 15 02:27:02.387 INFO [stdout] [02:27:02.385][INFO][oysterpack_testing::tests][src/lib.rs:105] bar: test run time: 6.6079ms Dec 15 02:27:02.387 INFO [stdout] test macros::tests::bar ... ok Dec 15 02:27:02.387 INFO [stdout] [02:27:02.385][INFO][oysterpack_testing::macros::tests][src/macros.rs:187] bar passed !!! Dec 15 02:27:02.387 INFO [stdout] [02:27:02.385][INFO][oysterpack_testing::tests][src/lib.rs:105] test_op_test_fn: test run time: 269.296µs Dec 15 02:27:02.387 INFO [stdout] test macros::tests::test_op_test_fn ... ok Dec 15 02:27:02.387 INFO [stdout] [02:27:02.385][INFO][oysterpack_testing::macros::tests][src/macros.rs:174] tests_op_test passed !!! Dec 15 02:27:02.387 INFO [stdout] [02:27:02.385][INFO][foo][src/macros.rs:175] foo info Dec 15 02:27:02.387 INFO [stdout] [02:27:02.385][ERROR][bar][src/macros.rs:177] bar error Dec 15 02:27:02.387 INFO [stdout] [02:27:02.385][INFO][oysterpack_testing::tests][src/lib.rs:105] tests_op_test: test run time: 172.356µs Dec 15 02:27:02.387 INFO [stdout] test macros::tests::tests_op_test ... ok Dec 15 02:27:02.387 INFO [stdout] Dec 15 02:27:02.387 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 02:27:02.387 INFO [stdout] Dec 15 02:27:02.391 INFO [stdout] Dec 15 02:27:02.391 INFO [stdout] running 1 test Dec 15 02:27:02.403 INFO [stdout] test test_html_root_url ... ok Dec 15 02:27:02.403 INFO [stderr] Doc-tests oysterpack_testing Dec 15 02:27:02.403 INFO [stdout] Dec 15 02:27:02.403 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 02:27:02.403 INFO [stdout] Dec 15 02:27:04.185 INFO [stdout] Dec 15 02:27:04.186 INFO [stdout] running 5 tests Dec 15 02:27:06.517 INFO [stdout] test src/lib.rs - (line 26) ... ok Dec 15 02:27:07.567 INFO [stdout] test src/macros.rs - op_tests_mod (line 20) ... ok Dec 15 02:27:07.683 INFO [stdout] test src/macros.rs - op_tests_mod (line 39) ... ok Dec 15 02:27:07.810 INFO [stdout] test src/lib.rs - (line 54) ... ok Dec 15 02:27:07.854 INFO [stdout] test src/macros.rs - op_test (line 137) ... ok Dec 15 02:27:07.855 INFO [stdout] Dec 15 02:27:07.855 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out Dec 15 02:27:07.855 INFO [stdout] Dec 15 02:27:07.870 INFO [stderr] su: No module specific data is present Dec 15 02:27:08.367 INFO running `"docker" "inspect" "226d8480cbee31006d57ad9dc29b8bc7fbcaf1184e8df2f4e2a1935f2f93302d"` Dec 15 02:27:08.668 INFO running `"docker" "rm" "-f" "226d8480cbee31006d57ad9dc29b8bc7fbcaf1184e8df2f4e2a1935f2f93302d"` Dec 15 02:27:08.951 INFO [stdout] 226d8480cbee31006d57ad9dc29b8bc7fbcaf1184e8df2f4e2a1935f2f93302d