Dec 07 20:30:51.747 INFO testing clargs-1.0.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 20:30:51.747 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 20:30:52.000 INFO [stdout] 44cebfc4edc2532c23384784de396131c26c0e178980a525bc1622fd19d95b9d
Dec 07 20:30:52.002 INFO running `"docker" "start" "-a" "44cebfc4edc2532c23384784de396131c26c0e178980a525bc1622fd19d95b9d"`
Dec 07 20:30:52.548 INFO [stderr] usermod: no changes
Dec 07 20:30:52.595 INFO [stderr]    Compiling clargs v1.0.0 (/source)
Dec 07 20:30:54.112 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 1.53s
Dec 07 20:30:54.114 INFO [stderr] su: No module specific data is present
Dec 07 20:30:54.472 INFO running `"docker" "inspect" "44cebfc4edc2532c23384784de396131c26c0e178980a525bc1622fd19d95b9d"`
Dec 07 20:30:54.624 INFO running `"docker" "rm" "-f" "44cebfc4edc2532c23384784de396131c26c0e178980a525bc1622fd19d95b9d"`
Dec 07 20:30:54.994 INFO [stdout] 44cebfc4edc2532c23384784de396131c26c0e178980a525bc1622fd19d95b9d
Dec 07 20:30:54.997 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 20:30:56.164 INFO [stdout] 18585921aa0afa226b8d87012f45f41dd929b9b069355ace184ec4973d03a157
Dec 07 20:30:56.166 INFO running `"docker" "start" "-a" "18585921aa0afa226b8d87012f45f41dd929b9b069355ace184ec4973d03a157"`
Dec 07 20:30:56.976 INFO [stderr] usermod: no changes
Dec 07 20:30:57.009 INFO [stderr]    Compiling clargs v1.0.0 (/source)
Dec 07 20:31:05.753 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 8.77s
Dec 07 20:31:05.764 INFO [stderr] su: No module specific data is present
Dec 07 20:31:06.256 INFO running `"docker" "inspect" "18585921aa0afa226b8d87012f45f41dd929b9b069355ace184ec4973d03a157"`
Dec 07 20:31:06.559 INFO running `"docker" "rm" "-f" "18585921aa0afa226b8d87012f45f41dd929b9b069355ace184ec4973d03a157"`
Dec 07 20:31:06.701 INFO [stdout] 18585921aa0afa226b8d87012f45f41dd929b9b069355ace184ec4973d03a157
Dec 07 20:31:06.706 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 20:31:07.085 INFO [stdout] 1538df70d5c29c84b9f55ec3e2b2ffad2888803613f6d2fecc148dd93d44a7a4
Dec 07 20:31:07.086 INFO running `"docker" "start" "-a" "1538df70d5c29c84b9f55ec3e2b2ffad2888803613f6d2fecc148dd93d44a7a4"`
Dec 07 20:31:07.830 INFO [stderr] usermod: no changes
Dec 07 20:31:07.863 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.01s
Dec 07 20:31:07.868 INFO [stderr]      Running /target/debug/deps/clargs-148be49a535f9cd9
Dec 07 20:31:07.869 INFO [stdout] 
Dec 07 20:31:07.869 INFO [stderr]      Running /target/debug/deps/a_tests-4688c05d412daaf6
Dec 07 20:31:07.869 INFO [stdout] running 0 tests
Dec 07 20:31:07.869 INFO [stdout] 
Dec 07 20:31:07.869 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:31:07.869 INFO [stdout] 
Dec 07 20:31:07.869 INFO [stdout] 
Dec 07 20:31:07.869 INFO [stdout] running 8 tests
Dec 07 20:31:07.883 INFO [stdout] test a_test5 ... ok
Dec 07 20:31:07.884 INFO [stdout] test a_test6 ... ok
Dec 07 20:31:07.884 INFO [stdout] test a_test2 ... ok
Dec 07 20:31:07.884 INFO [stdout] test a_test1 ... ok
Dec 07 20:31:07.884 INFO [stdout] test a_test4 ... ok
Dec 07 20:31:07.884 INFO [stdout] test a_test3 ... ok
Dec 07 20:31:07.884 INFO [stdout] test a_test8 ... ok
Dec 07 20:31:07.885 INFO [stdout] test a_test7 ... ok
Dec 07 20:31:07.885 INFO [stderr]      Running /target/debug/deps/dh_tests-1af922298bfcf32c
Dec 07 20:31:07.885 INFO [stdout] 
Dec 07 20:31:07.885 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:31:07.885 INFO [stdout] 
Dec 07 20:31:07.886 INFO [stdout] 
Dec 07 20:31:07.886 INFO [stdout] running 6 tests
Dec 07 20:31:07.887 INFO [stdout] test dh_test1 ... ok
Dec 07 20:31:07.887 INFO [stdout] test dh_test2 ... ok
Dec 07 20:31:07.887 INFO [stdout] test dh_test3 ... ok
Dec 07 20:31:07.887 INFO [stdout] test dh_test5 ... ok
Dec 07 20:31:07.887 INFO [stdout] test dh_test4 ... ok
Dec 07 20:31:07.887 INFO [stdout] test dh_test6 ... ok
Dec 07 20:31:07.887 INFO [stdout] 
Dec 07 20:31:07.887 INFO [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:31:07.887 INFO [stdout] 
Dec 07 20:31:07.887 INFO [stderr]      Running /target/debug/deps/dha_tests-2d6bcc1a0d7c2a84
Dec 07 20:31:07.889 INFO [stdout] 
Dec 07 20:31:07.889 INFO [stdout] running 8 tests
Dec 07 20:31:07.896 INFO [stdout] test dha_test3 ... ok
Dec 07 20:31:07.896 INFO [stdout] test dha_test2 ... ok
Dec 07 20:31:07.896 INFO [stdout] test dha_test4 ... ok
Dec 07 20:31:07.896 INFO [stdout] test dha_test5 ... ok
Dec 07 20:31:07.896 INFO [stdout] test dha_test8 ... ok
Dec 07 20:31:07.896 INFO [stdout] test dha_test1 ... ok
Dec 07 20:31:07.896 INFO [stdout] test dha_test6 ... ok
Dec 07 20:31:07.903 INFO [stdout] test dha_test7 ... ok
Dec 07 20:31:07.904 INFO [stderr]      Running /target/debug/deps/sh_tests-8e3e83ea181bb9f6
Dec 07 20:31:07.904 INFO [stdout] 
Dec 07 20:31:07.904 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:31:07.904 INFO [stdout] 
Dec 07 20:31:07.904 INFO [stdout] 
Dec 07 20:31:07.904 INFO [stdout] running 13 tests
Dec 07 20:31:07.912 INFO [stdout] test sh_test11 ... ok
Dec 07 20:31:07.912 INFO [stdout] test sh_test13 ... ok
Dec 07 20:31:07.912 INFO [stdout] test sh_test4 ... ok
Dec 07 20:31:07.912 INFO [stdout] test sh_test1 ... ok
Dec 07 20:31:07.912 INFO [stdout] test sh_test2 ... ok
Dec 07 20:31:07.924 INFO [stdout] test sh_test7 ... ok
Dec 07 20:31:07.924 INFO [stdout] test sh_test6 ... ok
Dec 07 20:31:07.924 INFO [stdout] test sh_test8 ... ok
Dec 07 20:31:07.924 INFO [stdout] test sh_test12 ... ok
Dec 07 20:31:07.924 INFO [stdout] test sh_test5 ... ok
Dec 07 20:31:07.924 INFO [stdout] test sh_test3 ... ok
Dec 07 20:31:07.924 INFO [stdout] test sh_test10 ... ok
Dec 07 20:31:07.928 INFO [stdout] test sh_test9 ... ok
Dec 07 20:31:07.928 INFO [stdout] 
Dec 07 20:31:07.928 INFO [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:31:07.928 INFO [stdout] 
Dec 07 20:31:07.936 INFO [stderr]      Running /target/debug/deps/subcommand_tests-0844bdc0c61f1da0
Dec 07 20:31:07.937 INFO [stdout] 
Dec 07 20:31:07.937 INFO [stdout] running 7 tests
Dec 07 20:31:07.966 INFO [stdout] test subcommand_test2 ... ok
Dec 07 20:31:07.966 INFO [stdout] test subcommand_test7 ... ok
Dec 07 20:31:07.966 INFO [stdout] test subcommand_test6 ... ok
Dec 07 20:31:07.966 INFO [stdout] test subcommand_test4 ... ok
Dec 07 20:31:07.966 INFO [stdout] test subcommand_test1 ... ok
Dec 07 20:31:07.966 INFO [stdout] test subcommand_test3 ... ok
Dec 07 20:31:07.966 INFO [stdout] test subcommand_test5 ... ok
Dec 07 20:31:07.966 INFO [stdout] 
Dec 07 20:31:07.966 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:31:07.966 INFO [stdout] 
Dec 07 20:31:07.968 INFO [stderr]    Doc-tests clargs
Dec 07 20:31:08.824 INFO [stdout] 
Dec 07 20:31:08.824 INFO [stdout] running 0 tests
Dec 07 20:31:08.824 INFO [stdout] 
Dec 07 20:31:08.824 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 20:31:08.824 INFO [stdout] 
Dec 07 20:31:08.831 INFO [stderr] su: No module specific data is present
Dec 07 20:31:09.492 INFO running `"docker" "inspect" "1538df70d5c29c84b9f55ec3e2b2ffad2888803613f6d2fecc148dd93d44a7a4"`
Dec 07 20:31:09.626 INFO running `"docker" "rm" "-f" "1538df70d5c29c84b9f55ec3e2b2ffad2888803613f6d2fecc148dd93d44a7a4"`
Dec 07 20:31:09.711 INFO [stdout] 1538df70d5c29c84b9f55ec3e2b2ffad2888803613f6d2fecc148dd93d44a7a4