Dec 07 14:41:39.430 INFO testing google-androidpublisher3-1.0.8+20181009 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 14:41:39.431 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 14:41:39.777 INFO [stdout] 9f0ab71882fed98b0868faaedcea96abb6b7e68dcd405bb42774db38ca8c3443
Dec 07 14:41:39.779 INFO running `"docker" "start" "-a" "9f0ab71882fed98b0868faaedcea96abb6b7e68dcd405bb42774db38ca8c3443"`
Dec 07 14:41:42.195 INFO [stderr] usermod: no changes
Dec 07 14:41:42.394 INFO [stderr]    Compiling google-androidpublisher3 v1.0.8+20181009 (/source)
Dec 07 14:41:54.813 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.59s
Dec 07 14:41:54.865 INFO [stderr] su: No module specific data is present
Dec 07 14:41:55.942 INFO running `"docker" "inspect" "9f0ab71882fed98b0868faaedcea96abb6b7e68dcd405bb42774db38ca8c3443"`
Dec 07 14:41:56.244 INFO running `"docker" "rm" "-f" "9f0ab71882fed98b0868faaedcea96abb6b7e68dcd405bb42774db38ca8c3443"`
Dec 07 14:41:56.568 INFO [stdout] 9f0ab71882fed98b0868faaedcea96abb6b7e68dcd405bb42774db38ca8c3443
Dec 07 14:41:56.570 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 14:41:56.962 INFO [stdout] 0239eddc4261b0ccb55eb788afbb4354effa66c33f25fd6fe3c0875932d58753
Dec 07 14:41:56.966 INFO running `"docker" "start" "-a" "0239eddc4261b0ccb55eb788afbb4354effa66c33f25fd6fe3c0875932d58753"`
Dec 07 14:41:59.068 INFO [stderr] usermod: no changes
Dec 07 14:41:59.303 INFO [stderr]    Compiling google-androidpublisher3 v1.0.8+20181009 (/source)
Dec 07 14:42:10.201 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.10s
Dec 07 14:42:10.217 INFO [stderr] su: No module specific data is present
Dec 07 14:42:11.499 INFO running `"docker" "inspect" "0239eddc4261b0ccb55eb788afbb4354effa66c33f25fd6fe3c0875932d58753"`
Dec 07 14:42:11.859 INFO running `"docker" "rm" "-f" "0239eddc4261b0ccb55eb788afbb4354effa66c33f25fd6fe3c0875932d58753"`
Dec 07 14:42:12.220 INFO [stdout] 0239eddc4261b0ccb55eb788afbb4354effa66c33f25fd6fe3c0875932d58753
Dec 07 14:42:12.228 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-2/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/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 14:42:12.660 INFO [stdout] 19eab3ecb4215d8904a30a1bbeba275a4d828e295c76ea579b52d0f363865515
Dec 07 14:42:12.662 INFO running `"docker" "start" "-a" "19eab3ecb4215d8904a30a1bbeba275a4d828e295c76ea579b52d0f363865515"`
Dec 07 14:42:14.939 INFO [stderr] usermod: no changes
Dec 07 14:42:15.154 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.20s
Dec 07 14:42:15.165 INFO [stderr]      Running /target/debug/deps/google_androidpublisher3-e3b1c3cdb1dbd445
Dec 07 14:42:15.168 INFO [stdout] 
Dec 07 14:42:15.168 INFO [stdout] running 0 tests
Dec 07 14:42:15.168 INFO [stdout] 
Dec 07 14:42:15.168 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 14:42:15.168 INFO [stdout] 
Dec 07 14:42:15.168 INFO [stderr]    Doc-tests google-androidpublisher3
Dec 07 14:42:23.271 INFO [stdout] 
Dec 07 14:42:23.271 INFO [stdout] running 60 tests
Dec 07 14:42:23.333 INFO [stdout] test src/lib.rs -  (line 65) ... ignored
Dec 07 14:43:07.900 INFO [stdout] test src/lib.rs - AndroidPublisher (line 284) ... ok
Dec 07 14:43:12.436 INFO [stdout] test src/lib.rs - EditApkUploadCall (line 9872) ... ok
Dec 07 14:43:14.197 INFO [stdout] test src/lib.rs - EditBundleUploadCall (line 13495) ... ok
Dec 07 14:43:14.517 INFO [stdout] test src/lib.rs -  (line 98) ... ok
Dec 07 14:43:17.472 INFO [stdout] test src/lib.rs - EditApkAddexternallyhostedCall (line 7318) ... ok
Dec 07 14:43:18.052 INFO [stdout] test src/lib.rs - EditBundleListCall (line 10754) ... ok
Dec 07 14:43:18.852 INFO [stdout] test src/lib.rs - EditCommitCall (line 12465) ... ok
Dec 07 14:43:21.660 INFO [stdout] test src/lib.rs - EditApkListCall (line 10234) ... ok
Dec 07 14:44:07.912 INFO [stdout] test src/lib.rs - EditDeleteCall (line 11009) ... test src/lib.rs - EditDeleteCall (line 11009) has been running for over 60 seconds
Dec 07 14:44:10.928 INFO [stdout] test src/lib.rs - EditDeleteCall (line 11009) ... ok
Dec 07 14:44:12.444 INFO [stdout] test src/lib.rs - EditDeobfuscationfileUploadCall (line 5736) ... test src/lib.rs - EditDeobfuscationfileUploadCall (line 5736) has been running for over 60 seconds
Dec 07 14:44:13.124 INFO [stdout] test src/lib.rs - EditDeobfuscationfileUploadCall (line 5736) ... ok
Dec 07 14:44:14.204 INFO [stdout] test src/lib.rs - EditDetailGetCall (line 6819) ... test src/lib.rs - EditDetailGetCall (line 6819) has been running for over 60 seconds
Dec 07 14:44:14.528 INFO [stdout] test src/lib.rs - EditDetailPatchCall (line 14725) ... test src/lib.rs - EditDetailPatchCall (line 14725) has been running for over 60 seconds
Dec 07 14:44:14.529 INFO [stdout] test src/lib.rs - EditDetailPatchCall (line 14725) ... ok
Dec 07 14:44:16.708 INFO [stdout] test src/lib.rs - EditDetailGetCall (line 6819) ... ok
Dec 07 14:44:17.487 INFO [stdout] test src/lib.rs - EditDetailUpdateCall (line 7604) ... test src/lib.rs - EditDetailUpdateCall (line 7604) has been running for over 60 seconds
Dec 07 14:44:18.053 INFO [stdout] test src/lib.rs - EditExpansionfileGetCall (line 14169) ... test src/lib.rs - EditExpansionfileGetCall (line 14169) has been running for over 60 seconds
Dec 07 14:44:18.864 INFO [stdout] test src/lib.rs - EditExpansionfilePatchCall (line 8157) ... test src/lib.rs - EditExpansionfilePatchCall (line 8157) has been running for over 60 seconds
Dec 07 14:44:20.628 INFO [stdout] test src/lib.rs - EditExpansionfileGetCall (line 14169) ... ok
Dec 07 14:44:21.450 INFO [stdout] test src/lib.rs - EditExpansionfilePatchCall (line 8157) ... ok
Dec 07 14:44:21.672 INFO [stdout] test src/lib.rs - EditExpansionfileUpdateCall (line 6510) ... test src/lib.rs - EditExpansionfileUpdateCall (line 6510) has been running for over 60 seconds
Dec 07 14:44:22.472 INFO [stdout] test src/lib.rs - EditDetailUpdateCall (line 7604) ... ok
Dec 07 14:44:23.256 INFO [stdout] test src/lib.rs - EditExpansionfileUpdateCall (line 6510) ... ok
Dec 07 14:45:10.952 INFO [stdout] test src/lib.rs - EditExpansionfileUploadCall (line 11253) ... test src/lib.rs - EditExpansionfileUploadCall (line 11253) has been running for over 60 seconds
Dec 07 14:45:13.128 INFO [stdout] test src/lib.rs - EditGetCall (line 9340) ... test src/lib.rs - EditGetCall (line 9340) has been running for over 60 seconds
Dec 07 14:45:13.588 INFO [stdout] test src/lib.rs - EditGetCall (line 9340) ... ok
Dec 07 14:45:14.308 INFO [stdout] test src/lib.rs - EditExpansionfileUploadCall (line 11253) ... ok
Dec 07 14:45:14.552 INFO [stdout] test src/lib.rs - EditImageDeleteCall (line 9595) ... test src/lib.rs - EditImageDeleteCall (line 9595) has been running for over 60 seconds
Dec 07 14:45:16.272 INFO [stdout] test src/lib.rs - EditImageDeleteCall (line 9595) ... ok
Dec 07 14:45:16.724 INFO [stdout] test src/lib.rs - EditImageDeleteallCall (line 14447) ... test src/lib.rs - EditImageDeleteallCall (line 14447) has been running for over 60 seconds
Dec 07 14:45:19.736 INFO [stdout] test src/lib.rs - EditImageDeleteallCall (line 14447) ... ok
Dec 07 14:45:20.632 INFO [stdout] test src/lib.rs - EditImageListCall (line 8466) ... test src/lib.rs - EditImageListCall (line 8466) has been running for over 60 seconds
Dec 07 14:45:21.460 INFO [stdout] test src/lib.rs - EditImageUploadCall (line 6123) ... test src/lib.rs - EditImageUploadCall (line 6123) has been running for over 60 seconds
Dec 07 14:45:22.472 INFO [stdout] test src/lib.rs - EditInsertCall (line 11640) ... test src/lib.rs - EditInsertCall (line 11640) has been running for over 60 seconds
Dec 07 14:45:23.260 INFO [stdout] test src/lib.rs - EditListingDeleteCall (line 15309) ... test src/lib.rs - EditListingDeleteCall (line 15309) has been running for over 60 seconds
Dec 07 14:45:23.864 INFO [stdout] test src/lib.rs - EditImageListCall (line 8466) ... ok
Dec 07 14:45:24.076 INFO [stdout] test src/lib.rs - EditImageUploadCall (line 6123) ... ok
Dec 07 14:45:26.391 INFO [stdout] test src/lib.rs - EditListingDeleteCall (line 15309) ... ok
Dec 07 14:45:27.460 INFO [stdout] test src/lib.rs - EditInsertCall (line 11640) ... ok
Dec 07 14:46:13.596 INFO [stdout] test src/lib.rs - EditListingDeleteallCall (line 7074) ... test src/lib.rs - EditListingDeleteallCall (line 7074) has been running for over 60 seconds
Dec 07 14:46:14.312 INFO [stdout] test src/lib.rs - EditListingGetCall (line 10489) ... test src/lib.rs - EditListingGetCall (line 10489) has been running for over 60 seconds
Dec 07 14:46:16.276 INFO [stdout] test src/lib.rs - EditListingListCall (line 11914) ... test src/lib.rs - EditListingListCall (line 11914) has been running for over 60 seconds
Dec 07 14:46:19.301 INFO [stdout] test src/lib.rs - EditListingDeleteallCall (line 7074) ... ok
Dec 07 14:46:19.756 INFO [stdout] test src/lib.rs - EditListingPatchCall (line 9042) ... test src/lib.rs - EditListingPatchCall (line 9042) has been running for over 60 seconds
Dec 07 14:46:23.636 INFO [stdout] test src/lib.rs - EditListingGetCall (line 10489) ... ok
Dec 07 14:46:23.772 INFO [stdout] test src/lib.rs - EditListingListCall (line 11914) ... ok
Dec 07 14:46:23.864 INFO [stdout] test src/lib.rs - EditListingUpdateCall (line 13871) ... test src/lib.rs - EditListingUpdateCall (line 13871) has been running for over 60 seconds
Dec 07 14:46:24.080 INFO [stdout] test src/lib.rs - EditMethods (line 1995) ... test src/lib.rs - EditMethods (line 1995) has been running for over 60 seconds
Dec 07 14:46:25.855 INFO [stdout] test src/lib.rs - EditListingUpdateCall (line 13871) ... ok
Dec 07 14:46:26.400 INFO [stdout] test src/lib.rs - EditTesterGetCall (line 12973) ... test src/lib.rs - EditTesterGetCall (line 12973) has been running for over 60 seconds
Dec 07 14:46:27.480 INFO [stdout] test src/lib.rs - EditTesterPatchCall (line 12167) ... test src/lib.rs - EditTesterPatchCall (line 12167) has been running for over 60 seconds
Dec 07 14:46:27.929 INFO [stdout] test src/lib.rs - EditListingPatchCall (line 9042) ... ok
Dec 07 14:46:29.773 INFO [stdout] test src/lib.rs - EditTesterGetCall (line 12973) ... ok
Dec 07 14:46:31.120 INFO [stdout] test src/lib.rs - EditTesterPatchCall (line 12167) ... ok
Dec 07 14:46:31.989 INFO [stdout] test src/lib.rs - EditMethods (line 1995) ... ok
Dec 07 14:47:19.312 INFO [stdout] test src/lib.rs - EditTesterUpdateCall (line 15563) ... test src/lib.rs - EditTesterUpdateCall (line 15563) has been running for over 60 seconds
Dec 07 14:47:22.392 INFO [stdout] test src/lib.rs - EditTesterUpdateCall (line 15563) ... ok
Dec 07 14:47:23.644 INFO [stdout] test src/lib.rs - EditTrackGetCall (line 7890) ... test src/lib.rs - EditTrackGetCall (line 7890) has been running for over 60 seconds
Dec 07 14:47:23.776 INFO [stdout] test src/lib.rs - EditTrackListCall (line 12720) ... test src/lib.rs - EditTrackListCall (line 12720) has been running for over 60 seconds
Dec 07 14:47:24.284 INFO [stdout] test src/lib.rs - EditTrackGetCall (line 7890) ... ok
Dec 07 14:47:25.868 INFO [stdout] test src/lib.rs - EditTrackPatchCall (line 15011) ... test src/lib.rs - EditTrackPatchCall (line 15011) has been running for over 60 seconds
Dec 07 14:47:26.223 INFO [stdout] test src/lib.rs - EditTrackListCall (line 12720) ... ok
Dec 07 14:47:27.940 INFO [stdout] test src/lib.rs - EditTrackUpdateCall (line 8744) ... test src/lib.rs - EditTrackUpdateCall (line 8744) has been running for over 60 seconds
Dec 07 14:47:28.163 INFO [stdout] test src/lib.rs - InappproductGetCall (line 17221) ... ok
Dec 07 14:47:28.824 INFO [stdout] test src/lib.rs - EditTrackPatchCall (line 15011) ... ok
Dec 07 14:47:29.240 INFO [stdout] test src/lib.rs - InappproductDeleteCall (line 16977) ... ok
Dec 07 14:47:29.333 INFO [stdout] test src/lib.rs - EditTrackUpdateCall (line 8744) ... ok
Dec 07 14:47:29.776 INFO [stdout] test src/lib.rs - EditValidateCall (line 13240) ... test src/lib.rs - EditValidateCall (line 13240) has been running for over 60 seconds
Dec 07 14:47:31.296 INFO [stdout] test src/lib.rs - EditValidateCall (line 13240) ... ok
Dec 07 14:48:16.900 INFO [stdout] test src/lib.rs - InappproductInsertCall (line 16691) ... ok
Dec 07 14:48:18.076 INFO [stdout] test src/lib.rs - InappproductPatchCall (line 17475) ... ok
Dec 07 14:48:19.408 INFO [stdout] test src/lib.rs - OrderMethods (line 2765) ... ok
Dec 07 14:48:24.280 INFO [stdout] test src/lib.rs - InappproductListCall (line 16415) ... test src/lib.rs - InappproductListCall (line 16415) has been running for over 60 seconds
Dec 07 14:48:26.224 INFO [stdout] test src/lib.rs - InappproductMethods (line 2827) ... test src/lib.rs - InappproductMethods (line 2827) has been running for over 60 seconds
Dec 07 14:48:26.344 INFO [stdout] test src/lib.rs - InappproductMethods (line 2827) ... ok
Dec 07 14:48:27.962 INFO [stdout] test src/lib.rs - InappproductListCall (line 16415) ... ok
Dec 07 14:48:28.824 INFO [stdout] test src/lib.rs - InappproductUpdateCall (line 16117) ... test src/lib.rs - InappproductUpdateCall (line 16117) has been running for over 60 seconds
Dec 07 14:48:29.333 INFO [stdout] test src/lib.rs - OrderRefundCall (line 15861) ... test src/lib.rs - OrderRefundCall (line 15861) has been running for over 60 seconds
Dec 07 14:48:29.400 INFO [stdout] test src/lib.rs - PurchaseMethods (line 1803) ... ok
Dec 07 14:48:32.324 INFO [stdout] test src/lib.rs - OrderRefundCall (line 15861) ... ok
Dec 07 14:48:35.512 INFO [stdout] test src/lib.rs - InappproductUpdateCall (line 16117) ... ok
Dec 07 14:49:08.642 INFO [stdout] test src/lib.rs - PurchaseProductGetCall (line 4092) ... ok
Dec 07 14:49:14.376 INFO [stdout] test src/lib.rs - PurchaseSubscriptionGetCall (line 4359) ... ok
Dec 07 14:49:18.080 INFO [stdout] test src/lib.rs - PurchaseSubscriptionCancelCall (line 4926) ... test src/lib.rs - PurchaseSubscriptionCancelCall (line 4926) has been running for over 60 seconds
Dec 07 14:49:19.409 INFO [stdout] test src/lib.rs - PurchaseSubscriptionDeferCall (line 5438) ... test src/lib.rs - PurchaseSubscriptionDeferCall (line 5438) has been running for over 60 seconds
Dec 07 14:49:20.892 INFO [stdout] test src/lib.rs - PurchaseSubscriptionCancelCall (line 4926) ... ok
Dec 07 14:49:23.191 INFO [stdout] test src/lib.rs - PurchaseSubscriptionDeferCall (line 5438) ... ok
Dec 07 14:49:25.278 INFO [stdout] test src/lib.rs - PurchaseVoidedpurchaseListCall (line 4626) ... ok
Dec 07 14:49:26.866 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRevokeCall (line 3836) ... ok
Dec 07 14:49:27.527 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRefundCall (line 5182) ... ok
Dec 07 14:49:30.661 INFO [stdout] test src/lib.rs - ReviewGetCall (line 3284) ... ok
Dec 07 14:49:45.210 INFO [stdout] test src/lib.rs - ReviewListCall (line 3549) ... ok
Dec 07 14:49:47.136 INFO [stdout] test src/lib.rs - ReviewMethods (line 1699) ... ok
Dec 07 14:49:48.248 INFO [stdout] test src/lib.rs - ReviewReplyCall (line 2999) ... ok
Dec 07 14:49:48.248 INFO [stdout] 
Dec 07 14:49:48.248 INFO [stdout] test result: ok. 59 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 07 14:49:48.248 INFO [stdout] 
Dec 07 14:49:48.336 INFO [stderr] su: No module specific data is present
Dec 07 14:49:49.524 INFO running `"docker" "inspect" "19eab3ecb4215d8904a30a1bbeba275a4d828e295c76ea579b52d0f363865515"`
Dec 07 14:49:49.884 INFO running `"docker" "rm" "-f" "19eab3ecb4215d8904a30a1bbeba275a4d828e295c76ea579b52d0f363865515"`
Dec 07 14:49:50.200 INFO [stdout] 19eab3ecb4215d8904a30a1bbeba275a4d828e295c76ea579b52d0f363865515