Dec 15 12:04:02.418 INFO testing google-content2_sandbox-1.0.8+20181009 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 12:04:02.418 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 12:04:02.920 INFO [stdout] 3cd802f8347ef0b997686b9f35d1b2c198f6728a3fb47509eb8674a97d27146b
Dec 15 12:04:02.922 INFO running `"docker" "start" "-a" "3cd802f8347ef0b997686b9f35d1b2c198f6728a3fb47509eb8674a97d27146b"`
Dec 15 12:04:05.282 INFO [stderr] usermod: no changes
Dec 15 12:04:05.501 INFO [stderr]    Compiling google-content2_sandbox v1.0.8+20181009 (/source)
Dec 15 12:04:22.911 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.60s
Dec 15 12:04:22.926 INFO [stderr] su: No module specific data is present
Dec 15 12:04:23.552 INFO running `"docker" "inspect" "3cd802f8347ef0b997686b9f35d1b2c198f6728a3fb47509eb8674a97d27146b"`
Dec 15 12:04:23.791 INFO running `"docker" "rm" "-f" "3cd802f8347ef0b997686b9f35d1b2c198f6728a3fb47509eb8674a97d27146b"`
Dec 15 12:04:24.120 INFO [stdout] 3cd802f8347ef0b997686b9f35d1b2c198f6728a3fb47509eb8674a97d27146b
Dec 15 12:04:24.125 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 12:04:24.487 INFO [stdout] 45b011885d1725ccd8d8543257f319217da6cc4a1efecfbaced8c96cc115b509
Dec 15 12:04:24.495 INFO running `"docker" "start" "-a" "45b011885d1725ccd8d8543257f319217da6cc4a1efecfbaced8c96cc115b509"`
Dec 15 12:04:25.727 INFO [stderr] usermod: no changes
Dec 15 12:04:26.054 INFO [stderr]    Compiling google-content2_sandbox v1.0.8+20181009 (/source)
Dec 15 12:04:40.203 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.45s
Dec 15 12:04:40.213 INFO [stderr] su: No module specific data is present
Dec 15 12:04:40.527 INFO running `"docker" "inspect" "45b011885d1725ccd8d8543257f319217da6cc4a1efecfbaced8c96cc115b509"`
Dec 15 12:04:40.647 INFO running `"docker" "rm" "-f" "45b011885d1725ccd8d8543257f319217da6cc4a1efecfbaced8c96cc115b509"`
Dec 15 12:04:40.815 INFO [stdout] 45b011885d1725ccd8d8543257f319217da6cc4a1efecfbaced8c96cc115b509
Dec 15 12:04:40.821 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-5/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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 12:04:41.033 INFO [stdout] 1b98633e1a2cc2422021fe72a9104ded26f32a778eb0d4b53440ac201514a2d6
Dec 15 12:04:41.036 INFO running `"docker" "start" "-a" "1b98633e1a2cc2422021fe72a9104ded26f32a778eb0d4b53440ac201514a2d6"`
Dec 15 12:04:41.571 INFO [stderr] usermod: no changes
Dec 15 12:04:41.729 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 15 12:04:41.745 INFO [stderr]      Running /target/debug/deps/google_content2_sandbox-4d27723fa3251e77
Dec 15 12:04:41.747 INFO [stdout] 
Dec 15 12:04:41.747 INFO [stdout] running 0 tests
Dec 15 12:04:41.747 INFO [stdout] 
Dec 15 12:04:41.747 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 12:04:41.747 INFO [stdout] 
Dec 15 12:04:41.747 INFO [stderr]    Doc-tests google-content2_sandbox
Dec 15 12:04:50.990 INFO [stdout] 
Dec 15 12:04:50.990 INFO [stdout] running 37 tests
Dec 15 12:04:51.023 INFO [stdout] test src/lib.rs -  (line 55) ... ignored
Dec 15 12:05:42.761 INFO [stdout] test src/lib.rs - OrderCancellineitemCall (line 10761) ... ok
Dec 15 12:05:45.323 INFO [stdout] test src/lib.rs - OrderCancelCall (line 12160) ... ok
Dec 15 12:05:47.376 INFO [stdout] test src/lib.rs -  (line 106) ... ok
Dec 15 12:05:47.699 INFO [stdout] test src/lib.rs - OrderAcknowledgeCall (line 7448) ... ok
Dec 15 12:05:48.287 INFO [stdout] test src/lib.rs - OrderCreatetestreturnCall (line 11047) ... ok
Dec 15 12:05:48.451 INFO [stdout] test src/lib.rs - OrderCanceltestorderbycustomerCall (line 8835) ... ok
Dec 15 12:05:48.851 INFO [stdout] test src/lib.rs - OrderAdvancetestorderCall (line 7734) ... ok
Dec 15 12:05:49.767 INFO [stdout] test src/lib.rs - OrderCreatetestorderCall (line 8275) ... ok
Dec 15 12:06:25.023 INFO [stdout] test src/lib.rs - OrderCustombatchCall (line 10234) ... ok
Dec 15 12:06:38.369 INFO [stdout] test src/lib.rs - OrderListCall (line 6277) ... ok
Dec 15 12:06:39.191 INFO [stdout] test src/lib.rs - OrderGetCall (line 9407) ... ok
Dec 15 12:06:40.011 INFO [stdout] test src/lib.rs - OrderGetbymerchantorderidCall (line 11619) ... ok
Dec 15 12:06:40.912 INFO [stdout] test src/lib.rs - OrderRefundCall (line 7162) ... ok
Dec 15 12:06:42.579 INFO [stdout] test src/lib.rs - OrderInstorerefundlineitemCall (line 10475) ... ok
Dec 15 12:06:42.619 INFO [stdout] test src/lib.rs - OrderGettestordertemplateCall (line 6895) ... ok
Dec 15 12:06:47.779 INFO [stdout] test src/lib.rs - OrderMethods (line 3501) ... ok
Dec 15 12:07:21.335 INFO [stdout] test src/lib.rs - OrderRejectreturnlineitemCall (line 7989) ... ok
Dec 15 12:07:36.079 INFO [stdout] test src/lib.rs - OrderReturnlineitemCall (line 9662) ... ok
Dec 15 12:07:37.415 INFO [stdout] test src/lib.rs - OrderSetlineitemmetadataCall (line 11874) ... ok
Dec 15 12:07:38.719 INFO [stdout] test src/lib.rs - OrderUpdatemerchantorderidCall (line 6609) ... ok
Dec 15 12:07:39.187 INFO [stdout] test src/lib.rs - OrderReturnrefundlineitemCall (line 9121) ... test src/lib.rs - OrderReturnrefundlineitemCall (line 9121) has been running for over 60 seconds
Dec 15 12:07:40.574 INFO [stdout] test src/lib.rs - OrderShiplineitemCall (line 8549) ... ok
Dec 15 12:07:42.575 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:44.431 INFO [stdout] test src/lib.rs - OrderReturnrefundlineitemCall (line 9121) ... ok
Dec 15 12:07:47.131 INFO [stdout] test src/lib.rs - OrderUpdatelineitemshippingdetailCall (line 9948) ... ok
Dec 15 12:07:47.283 INFO [stdout] test src/lib.rs - OrderUpdateshipmentCall (line 11333) ... ok
Dec 15 12:08:21.347 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:26.214 INFO [stdout] test src/lib.rs - OrderinvoiceCreatechargeinvoiceCall (line 5991) ... ok
Dec 15 12:08:36.087 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:37.427 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:38.739 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:40.319 INFO [stdout] test src/lib.rs - OrderinvoiceMethods (line 3417) ... ok
Dec 15 12:08:40.583 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:41.859 INFO [stdout] test src/lib.rs - OrderpaymentMethods (line 3208) ... ok
Dec 15 12:08:44.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:46.423 INFO [stdout] test src/lib.rs - OrderinvoiceCreaterefundinvoiceCall (line 5705) ... ok
Dec 15 12:08:46.828 INFO [stdout] test src/lib.rs - OrderpaymentNotifyauthapprovedCall (line 4289) ... ok
Dec 15 12:08:47.139 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:47.287 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:51.175 INFO [stdout] test src/lib.rs - OrderpaymentNotifyauthdeclinedCall (line 4003) ... ok
Dec 15 12:08:54.007 INFO [stdout] test src/lib.rs - OrderpaymentNotifychargeCall (line 4575) ... ok
Dec 15 12:08:54.343 INFO [stdout] test src/lib.rs - OrderpaymentNotifyrefundCall (line 4861) ... ok
Dec 15 12:09:11.671 INFO [stdout] test src/lib.rs - OrderreturnGetCall (line 5147) ... ok
Dec 15 12:09:15.558 INFO [stdout] test src/lib.rs - OrderreturnMethods (line 3334) ... ok
Dec 15 12:09:16.716 INFO [stdout] test src/lib.rs - OrderreturnListCall (line 5402) ... ok
Dec 15 12:09:17.283 INFO [stdout] test src/lib.rs - ShoppingContent (line 292) ... ok
Dec 15 12:09:17.283 INFO [stdout] 
Dec 15 12:09:17.283 INFO [stdout] test result: ok. 36 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 15 12:09:17.283 INFO [stdout] 
Dec 15 12:09:17.370 INFO [stderr] su: No module specific data is present
Dec 15 12:09:18.513 INFO running `"docker" "inspect" "1b98633e1a2cc2422021fe72a9104ded26f32a778eb0d4b53440ac201514a2d6"`
Dec 15 12:09:18.805 INFO running `"docker" "rm" "-f" "1b98633e1a2cc2422021fe72a9104ded26f32a778eb0d4b53440ac201514a2d6"`
Dec 15 12:09:19.158 INFO [stdout] 1b98633e1a2cc2422021fe72a9104ded26f32a778eb0d4b53440ac201514a2d6