Dec 15 12:04:00.728 INFO testing google-content2_sandbox-1.0.8+20181009 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 12:04:00.728 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 12:04:01.260 INFO [stdout] 90441c826f8041047f233d4e179eec1a8b8edebd49c4794c31e81df3d78c1c7c
Dec 15 12:04:01.263 INFO running `"docker" "start" "-a" "90441c826f8041047f233d4e179eec1a8b8edebd49c4794c31e81df3d78c1c7c"`
Dec 15 12:04:02.835 INFO [stderr] usermod: no changes
Dec 15 12:04:03.157 INFO [stderr]    Compiling google-content2_sandbox v1.0.8+20181009 (/source)
Dec 15 12:04:20.783 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.92s
Dec 15 12:04:20.807 INFO [stderr] su: No module specific data is present
Dec 15 12:04:21.716 INFO running `"docker" "inspect" "90441c826f8041047f233d4e179eec1a8b8edebd49c4794c31e81df3d78c1c7c"`
Dec 15 12:04:22.096 INFO running `"docker" "rm" "-f" "90441c826f8041047f233d4e179eec1a8b8edebd49c4794c31e81df3d78c1c7c"`
Dec 15 12:04:22.340 INFO [stdout] 90441c826f8041047f233d4e179eec1a8b8edebd49c4794c31e81df3d78c1c7c
Dec 15 12:04:22.347 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 12:04:22.952 INFO [stdout] 4f10a46901510f3cf17ff28c4341aa5f517b89cf66c4ec8b4895fa0934767b0d
Dec 15 12:04:22.954 INFO running `"docker" "start" "-a" "4f10a46901510f3cf17ff28c4341aa5f517b89cf66c4ec8b4895fa0934767b0d"`
Dec 15 12:04:24.112 INFO [stderr] usermod: no changes
Dec 15 12:04:24.319 INFO [stderr]    Compiling google-content2_sandbox v1.0.8+20181009 (/source)
Dec 15 12:04:39.033 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.89s
Dec 15 12:04:39.046 INFO [stderr] su: No module specific data is present
Dec 15 12:04:39.356 INFO running `"docker" "inspect" "4f10a46901510f3cf17ff28c4341aa5f517b89cf66c4ec8b4895fa0934767b0d"`
Dec 15 12:04:39.506 INFO running `"docker" "rm" "-f" "4f10a46901510f3cf17ff28c4341aa5f517b89cf66c4ec8b4895fa0934767b0d"`
Dec 15 12:04:39.627 INFO [stdout] 4f10a46901510f3cf17ff28c4341aa5f517b89cf66c4ec8b4895fa0934767b0d
Dec 15 12:04:39.629 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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 12:04:39.898 INFO [stdout] e6189cf49631f18cbbf36cf3f5279eecfc0f15765c358313bf457d8be9ce4faf
Dec 15 12:04:39.900 INFO running `"docker" "start" "-a" "e6189cf49631f18cbbf36cf3f5279eecfc0f15765c358313bf457d8be9ce4faf"`
Dec 15 12:04:40.413 INFO [stderr] usermod: no changes
Dec 15 12:04:40.560 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Dec 15 12:04:40.577 INFO [stderr]      Running /target/debug/deps/google_content2_sandbox-37b5120b8a1fee54
Dec 15 12:04:40.581 INFO [stdout] 
Dec 15 12:04:40.581 INFO [stdout] running 0 tests
Dec 15 12:04:40.581 INFO [stdout] 
Dec 15 12:04:40.581 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 12:04:40.581 INFO [stdout] 
Dec 15 12:04:40.581 INFO [stderr]    Doc-tests google-content2_sandbox
Dec 15 12:04:49.732 INFO [stdout] 
Dec 15 12:04:49.732 INFO [stdout] running 37 tests
Dec 15 12:04:49.733 INFO [stdout] test src/lib.rs -  (line 55) ... ignored
Dec 15 12:05:42.519 INFO [stdout] test src/lib.rs - OrderCreatetestreturnCall (line 11047) ... ok
Dec 15 12:05:43.331 INFO [stdout] test src/lib.rs - OrderAcknowledgeCall (line 7448) ... ok
Dec 15 12:05:45.107 INFO [stdout] test src/lib.rs - OrderCreatetestorderCall (line 8275) ... ok
Dec 15 12:05:45.609 INFO [stdout] test src/lib.rs - OrderCancelCall (line 12160) ... ok
Dec 15 12:05:45.835 INFO [stdout] test src/lib.rs - OrderAdvancetestorderCall (line 7734) ... ok
Dec 15 12:05:46.494 INFO [stdout] test src/lib.rs -  (line 106) ... ok
Dec 15 12:05:47.699 INFO [stdout] test src/lib.rs - OrderCanceltestorderbycustomerCall (line 8835) ... ok
Dec 15 12:05:49.743 INFO [stdout] test src/lib.rs - OrderCancellineitemCall (line 10761) ... test src/lib.rs - OrderCancellineitemCall (line 10761) has been running for over 60 seconds
Dec 15 12:05:51.682 INFO [stdout] test src/lib.rs - OrderCancellineitemCall (line 10761) ... ok
Dec 15 12:06:24.883 INFO [stdout] test src/lib.rs - OrderGettestordertemplateCall (line 6895) ... ok
Dec 15 12:06:26.683 INFO [stdout] test src/lib.rs - OrderListCall (line 6277) ... ok
Dec 15 12:06:28.095 INFO [stdout] test src/lib.rs - OrderCustombatchCall (line 10234) ... ok
Dec 15 12:06:31.767 INFO [stdout] test src/lib.rs - OrderMethods (line 3501) ... ok
Dec 15 12:06:33.940 INFO [stdout] test src/lib.rs - OrderGetbymerchantorderidCall (line 11619) ... ok
Dec 15 12:06:35.347 INFO [stdout] test src/lib.rs - OrderInstorerefundlineitemCall (line 10475) ... ok
Dec 15 12:06:41.019 INFO [stdout] test src/lib.rs - OrderGetCall (line 9407) ... ok
Dec 15 12:06:43.075 INFO [stdout] test src/lib.rs - OrderRefundCall (line 7162) ... ok
Dec 15 12:07:14.053 INFO [stdout] test src/lib.rs - OrderRejectreturnlineitemCall (line 7989) ... ok
Dec 15 12:07:20.851 INFO [stdout] test src/lib.rs - OrderReturnlineitemCall (line 9662) ... ok
Dec 15 12:07:26.823 INFO [stdout] test src/lib.rs - OrderShiplineitemCall (line 8549) ... ok
Dec 15 12:07:27.035 INFO [stdout] test src/lib.rs - OrderSetlineitemmetadataCall (line 11874) ... ok
Dec 15 12:07:27.295 INFO [stdout] test src/lib.rs - OrderReturnrefundlineitemCall (line 9121) ... ok
Dec 15 12:07:35.348 INFO [stdout] test src/lib.rs - OrderUpdatelineitemshippingdetailCall (line 9948) ... test src/lib.rs - OrderUpdatelineitemshippingdetailCall (line 9948) has been running for over 60 seconds
Dec 15 12:07:36.431 INFO [stdout] test src/lib.rs - OrderUpdatelineitemshippingdetailCall (line 9948) ... ok
Dec 15 12:07:40.184 INFO [stdout] test src/lib.rs - OrderUpdatemerchantorderidCall (line 6609) ... ok
Dec 15 12:07:43.076 INFO [stdout] test src/lib.rs - OrderUpdateshipmentCall (line 11333) ... test src/lib.rs - OrderUpdateshipmentCall (line 11333) has been running for over 60 seconds
Dec 15 12:07:43.630 INFO [stdout] test src/lib.rs - OrderUpdateshipmentCall (line 11333) ... ok
Dec 15 12:08:14.060 INFO [stdout] test src/lib.rs - OrderinvoiceCreatechargeinvoiceCall (line 5991) ... test src/lib.rs - OrderinvoiceCreatechargeinvoiceCall (line 5991) has been running for over 60 seconds
Dec 15 12:08:18.057 INFO [stdout] test src/lib.rs - OrderinvoiceCreatechargeinvoiceCall (line 5991) ... ok
Dec 15 12:08:20.858 INFO [stdout] test src/lib.rs - OrderinvoiceCreaterefundinvoiceCall (line 5705) ... test src/lib.rs - OrderinvoiceCreaterefundinvoiceCall (line 5705) has been running for over 60 seconds
Dec 15 12:08:26.831 INFO [stdout] test src/lib.rs - OrderinvoiceMethods (line 3417) ... test src/lib.rs - OrderinvoiceMethods (line 3417) has been running for over 60 seconds
Dec 15 12:08:27.043 INFO [stdout] test src/lib.rs - OrderpaymentMethods (line 3208) ... test src/lib.rs - OrderpaymentMethods (line 3208) has been running for over 60 seconds
Dec 15 12:08:27.304 INFO [stdout] test src/lib.rs - OrderpaymentNotifyauthapprovedCall (line 4289) ... test src/lib.rs - OrderpaymentNotifyauthapprovedCall (line 4289) has been running for over 60 seconds
Dec 15 12:08:27.771 INFO [stdout] test src/lib.rs - OrderinvoiceCreaterefundinvoiceCall (line 5705) ... ok
Dec 15 12:08:33.227 INFO [stdout] test src/lib.rs - OrderpaymentMethods (line 3208) ... ok
Dec 15 12:08:33.367 INFO [stdout] test src/lib.rs - OrderinvoiceMethods (line 3417) ... ok
Dec 15 12:08:33.847 INFO [stdout] test src/lib.rs - OrderpaymentNotifyauthapprovedCall (line 4289) ... ok
Dec 15 12:08:36.435 INFO [stdout] test src/lib.rs - OrderpaymentNotifyauthdeclinedCall (line 4003) ... test src/lib.rs - OrderpaymentNotifyauthdeclinedCall (line 4003) has been running for over 60 seconds
Dec 15 12:08:40.187 INFO [stdout] test src/lib.rs - OrderpaymentNotifychargeCall (line 4575) ... test src/lib.rs - OrderpaymentNotifychargeCall (line 4575) has been running for over 60 seconds
Dec 15 12:08:41.103 INFO [stdout] test src/lib.rs - OrderpaymentNotifychargeCall (line 4575) ... ok
Dec 15 12:08:41.419 INFO [stdout] test src/lib.rs - OrderpaymentNotifyauthdeclinedCall (line 4003) ... ok
Dec 15 12:08:43.631 INFO [stdout] test src/lib.rs - OrderpaymentNotifyrefundCall (line 4861) ... test src/lib.rs - OrderpaymentNotifyrefundCall (line 4861) has been running for over 60 seconds
Dec 15 12:08:44.403 INFO [stdout] test src/lib.rs - OrderpaymentNotifyrefundCall (line 4861) ... ok
Dec 15 12:09:01.475 INFO [stdout] test src/lib.rs - OrderreturnGetCall (line 5147) ... ok
Dec 15 12:09:05.923 INFO [stdout] test src/lib.rs - OrderreturnListCall (line 5402) ... ok
Dec 15 12:09:07.049 INFO [stdout] test src/lib.rs - OrderreturnMethods (line 3334) ... ok
Dec 15 12:09:07.206 INFO [stdout] test src/lib.rs - ShoppingContent (line 292) ... ok
Dec 15 12:09:07.206 INFO [stdout] 
Dec 15 12:09:07.206 INFO [stdout] test result: ok. 36 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 15 12:09:07.206 INFO [stdout] 
Dec 15 12:09:07.261 INFO [stderr] su: No module specific data is present
Dec 15 12:09:08.767 INFO running `"docker" "inspect" "e6189cf49631f18cbbf36cf3f5279eecfc0f15765c358313bf457d8be9ce4faf"`
Dec 15 12:09:09.037 INFO running `"docker" "rm" "-f" "e6189cf49631f18cbbf36cf3f5279eecfc0f15765c358313bf457d8be9ce4faf"`
Dec 15 12:09:09.439 INFO [stdout] e6189cf49631f18cbbf36cf3f5279eecfc0f15765c358313bf457d8be9ce4faf