Dec 07 14:42:41.907 INFO testing google-androidpublisher2-1.0.8+20181009 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 14:42:41.907 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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:42:42.405 INFO [stdout] 372345bb7ab76142c79a2d0cab12221e44fafb1a1fc368c58d84ddb10f65e6b5
Dec 07 14:42:42.407 INFO running `"docker" "start" "-a" "372345bb7ab76142c79a2d0cab12221e44fafb1a1fc368c58d84ddb10f65e6b5"`
Dec 07 14:42:44.084 INFO [stderr] usermod: no changes
Dec 07 14:42:44.479 INFO [stderr]    Compiling google-androidpublisher2 v1.0.8+20181009 (/source)
Dec 07 14:42:57.009 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.89s
Dec 07 14:42:57.021 INFO [stderr] su: No module specific data is present
Dec 07 14:42:58.268 INFO running `"docker" "inspect" "372345bb7ab76142c79a2d0cab12221e44fafb1a1fc368c58d84ddb10f65e6b5"`
Dec 07 14:42:58.556 INFO running `"docker" "rm" "-f" "372345bb7ab76142c79a2d0cab12221e44fafb1a1fc368c58d84ddb10f65e6b5"`
Dec 07 14:42:58.792 INFO [stdout] 372345bb7ab76142c79a2d0cab12221e44fafb1a1fc368c58d84ddb10f65e6b5
Dec 07 14:42:58.798 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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:42:59.296 INFO [stdout] 2e79d5dd7dcd0e9e048e36e6600d269fa62f33d36b317a732d71a15b6593b286
Dec 07 14:42:59.298 INFO running `"docker" "start" "-a" "2e79d5dd7dcd0e9e048e36e6600d269fa62f33d36b317a732d71a15b6593b286"`
Dec 07 14:43:01.067 INFO [stderr] usermod: no changes
Dec 07 14:43:01.268 INFO [stderr]    Compiling google-androidpublisher2 v1.0.8+20181009 (/source)
Dec 07 14:43:12.585 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.49s
Dec 07 14:43:12.606 INFO [stderr] su: No module specific data is present
Dec 07 14:43:13.494 INFO running `"docker" "inspect" "2e79d5dd7dcd0e9e048e36e6600d269fa62f33d36b317a732d71a15b6593b286"`
Dec 07 14:43:13.857 INFO running `"docker" "rm" "-f" "2e79d5dd7dcd0e9e048e36e6600d269fa62f33d36b317a732d71a15b6593b286"`
Dec 07 14:43:14.184 INFO [stdout] 2e79d5dd7dcd0e9e048e36e6600d269fa62f33d36b317a732d71a15b6593b286
Dec 07 14:43:14.186 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-5/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/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:43:14.604 INFO [stdout] a143cbca2182e5af2596f09f1b40d04645cdea31a83e1fdc51a4d416d2ff1ab4
Dec 07 14:43:14.606 INFO running `"docker" "start" "-a" "a143cbca2182e5af2596f09f1b40d04645cdea31a83e1fdc51a4d416d2ff1ab4"`
Dec 07 14:43:16.640 INFO [stderr] usermod: no changes
Dec 07 14:43:16.933 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.27s
Dec 07 14:43:16.945 INFO [stderr]      Running /target/debug/deps/google_androidpublisher2-2d3acdca659dc876
Dec 07 14:43:16.951 INFO [stdout] 
Dec 07 14:43:16.951 INFO [stdout] running 0 tests
Dec 07 14:43:16.951 INFO [stdout] 
Dec 07 14:43:16.951 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 14:43:16.951 INFO [stdout] 
Dec 07 14:43:16.951 INFO [stderr]    Doc-tests google-androidpublisher2
Dec 07 14:43:24.780 INFO [stdout] 
Dec 07 14:43:24.780 INFO [stdout] running 66 tests
Dec 07 14:43:24.815 INFO [stdout] test src/lib.rs -  (line 65) ... ignored
Dec 07 14:44:23.014 INFO [stdout] test src/lib.rs - EditApklistingGetCall (line 16856) ... ok
Dec 07 14:44:23.500 INFO [stdout] test src/lib.rs - EditApklistingDeleteallCall (line 8019) ... ok
Dec 07 14:44:23.976 INFO [stdout] test src/lib.rs - EditApkAddexternallyhostedCall (line 7733) ... ok
Dec 07 14:44:24.772 INFO [stdout] test src/lib.rs - AndroidPublisher (line 284) ... ok
Dec 07 14:44:24.784 INFO [stdout] test src/lib.rs -  (line 98) ... test src/lib.rs -  (line 98) has been running for over 60 seconds
Dec 07 14:44:24.796 INFO [stdout] test src/lib.rs - EditApkListCall (line 11215) ... test src/lib.rs - EditApkListCall (line 11215) has been running for over 60 seconds
Dec 07 14:44:24.816 INFO [stdout] test src/lib.rs - EditApkUploadCall (line 10543) ... test src/lib.rs - EditApkUploadCall (line 10543) has been running for over 60 seconds
Dec 07 14:44:24.817 INFO [stdout] test src/lib.rs - EditApklistingDeleteCall (line 7221) ... test src/lib.rs - EditApklistingDeleteCall (line 7221) has been running for over 60 seconds
Dec 07 14:44:25.305 INFO [stdout] test src/lib.rs -  (line 98) ... ok
Dec 07 14:44:25.421 INFO [stdout] test src/lib.rs - EditApkListCall (line 11215) ... ok
Dec 07 14:44:27.624 INFO [stdout] test src/lib.rs - EditApklistingDeleteCall (line 7221) ... ok
Dec 07 14:44:29.285 INFO [stdout] test src/lib.rs - EditApkUploadCall (line 10543) ... ok
Dec 07 14:45:23.024 INFO [stdout] test src/lib.rs - EditApklistingListCall (line 17431) ... test src/lib.rs - EditApklistingListCall (line 17431) has been running for over 60 seconds
Dec 07 14:45:23.500 INFO [stdout] test src/lib.rs - EditApklistingPatchCall (line 11470) ... test src/lib.rs - EditApklistingPatchCall (line 11470) has been running for over 60 seconds
Dec 07 14:45:23.984 INFO [stdout] test src/lib.rs - EditApklistingUpdateCall (line 10907) ... test src/lib.rs - EditApklistingUpdateCall (line 10907) has been running for over 60 seconds
Dec 07 14:45:24.780 INFO [stdout] test src/lib.rs - EditBundleListCall (line 12045) ... test src/lib.rs - EditBundleListCall (line 12045) has been running for over 60 seconds
Dec 07 14:45:25.308 INFO [stdout] test src/lib.rs - EditBundleUploadCall (line 14786) ... test src/lib.rs - EditBundleUploadCall (line 14786) has been running for over 60 seconds
Dec 07 14:45:25.424 INFO [stdout] test src/lib.rs - EditCommitCall (line 13756) ... test src/lib.rs - EditCommitCall (line 13756) has been running for over 60 seconds
Dec 07 14:45:27.632 INFO [stdout] test src/lib.rs - EditDeleteCall (line 12300) ... test src/lib.rs - EditDeleteCall (line 12300) has been running for over 60 seconds
Dec 07 14:45:28.444 INFO [stdout] test src/lib.rs - EditApklistingListCall (line 17431) ... ok
Dec 07 14:45:29.196 INFO [stdout] test src/lib.rs - EditDeleteCall (line 12300) ... ok
Dec 07 14:45:29.292 INFO [stdout] test src/lib.rs - EditDeobfuscationfileUploadCall (line 5883) ... test src/lib.rs - EditDeobfuscationfileUploadCall (line 5883) has been running for over 60 seconds
Dec 07 14:45:29.417 INFO [stdout] test src/lib.rs - EditApklistingUpdateCall (line 10907) ... ok
Dec 07 14:45:29.530 INFO [stdout] test src/lib.rs - EditBundleUploadCall (line 14786) ... ok
Dec 07 14:45:29.956 INFO [stdout] test src/lib.rs - EditBundleListCall (line 12045) ... ok
Dec 07 14:45:30.082 INFO [stdout] test src/lib.rs - EditCommitCall (line 13756) ... ok
Dec 07 14:45:32.463 INFO [stdout] test src/lib.rs - EditApklistingPatchCall (line 11470) ... ok
Dec 07 14:45:36.728 INFO [stdout] test src/lib.rs - EditDeobfuscationfileUploadCall (line 5883) ... ok
Dec 07 14:46:26.105 INFO [stdout] test src/lib.rs - EditDetailUpdateCall (line 8275) ... ok
Dec 07 14:46:28.444 INFO [stdout] test src/lib.rs - EditDetailGetCall (line 6966) ... test src/lib.rs - EditDetailGetCall (line 6966) has been running for over 60 seconds
Dec 07 14:46:29.196 INFO [stdout] test src/lib.rs - EditDetailPatchCall (line 16016) ... test src/lib.rs - EditDetailPatchCall (line 16016) has been running for over 60 seconds
Dec 07 14:46:29.556 INFO [stdout] test src/lib.rs - EditExpansionfileGetCall (line 15460) ... test src/lib.rs - EditExpansionfileGetCall (line 15460) has been running for over 60 seconds
Dec 07 14:46:29.954 INFO [stdout] test src/lib.rs - EditExpansionfilePatchCall (line 8828) ... test src/lib.rs - EditExpansionfilePatchCall (line 8828) has been running for over 60 seconds
Dec 07 14:46:30.096 INFO [stdout] test src/lib.rs - EditExpansionfileUpdateCall (line 6657) ... test src/lib.rs - EditExpansionfileUpdateCall (line 6657) has been running for over 60 seconds
Dec 07 14:46:31.968 INFO [stdout] test src/lib.rs - EditExpansionfileGetCall (line 15460) ... ok
Dec 07 14:46:32.472 INFO [stdout] test src/lib.rs - EditExpansionfileUploadCall (line 12544) ... test src/lib.rs - EditExpansionfileUploadCall (line 12544) has been running for over 60 seconds
Dec 07 14:46:33.200 INFO [stdout] test src/lib.rs - EditExpansionfilePatchCall (line 8828) ... ok
Dec 07 14:46:33.686 INFO [stdout] test src/lib.rs - EditDetailGetCall (line 6966) ... ok
Dec 07 14:46:34.620 INFO [stdout] test src/lib.rs - EditExpansionfileUpdateCall (line 6657) ... ok
Dec 07 14:46:36.736 INFO [stdout] test src/lib.rs - EditGetCall (line 10011) ... test src/lib.rs - EditGetCall (line 10011) has been running for over 60 seconds
Dec 07 14:46:37.460 INFO [stdout] test src/lib.rs - EditDetailPatchCall (line 16016) ... ok
Dec 07 14:46:37.880 INFO [stdout] test src/lib.rs - EditExpansionfileUploadCall (line 12544) ... ok
Dec 07 14:46:39.724 INFO [stdout] test src/lib.rs - EditGetCall (line 10011) ... ok
Dec 07 14:47:19.320 INFO [stdout] test src/lib.rs - EditImageDeleteCall (line 10266) ... ok
Dec 07 14:47:29.463 INFO [stdout] test src/lib.rs - EditImageUploadCall (line 6270) ... ok
Dec 07 14:47:32.000 INFO [stdout] test src/lib.rs - EditImageDeleteallCall (line 15738) ... test src/lib.rs - EditImageDeleteallCall (line 15738) has been running for over 60 seconds
Dec 07 14:47:32.000 INFO [stdout] test src/lib.rs - EditInsertCall (line 12931) ... ok
Dec 07 14:47:32.644 INFO [stdout] test src/lib.rs - EditListingDeleteallCall (line 7489) ... ok
Dec 07 14:47:33.208 INFO [stdout] test src/lib.rs - EditImageListCall (line 9137) ... test src/lib.rs - EditImageListCall (line 9137) has been running for over 60 seconds
Dec 07 14:47:34.888 INFO [stdout] test src/lib.rs - EditListingDeleteCall (line 16600) ... ok
Dec 07 14:47:34.920 INFO [stdout] test src/lib.rs - EditImageDeleteallCall (line 15738) ... ok
Dec 07 14:47:35.596 INFO [stdout] test src/lib.rs - EditListingGetCall (line 11780) ... ok
Dec 07 14:47:36.624 INFO [stdout] test src/lib.rs - EditImageListCall (line 9137) ... ok
Dec 07 14:48:19.324 INFO [stdout] test src/lib.rs - EditListingListCall (line 13205) ... test src/lib.rs - EditListingListCall (line 13205) has been running for over 60 seconds
Dec 07 14:48:24.492 INFO [stdout] test src/lib.rs - EditListingListCall (line 13205) ... ok
Dec 07 14:48:29.464 INFO [stdout] test src/lib.rs - EditListingPatchCall (line 9713) ... test src/lib.rs - EditListingPatchCall (line 9713) has been running for over 60 seconds
Dec 07 14:48:31.196 INFO [stdout] test src/lib.rs - EditTrackGetCall (line 8561) ... ok
Dec 07 14:48:32.000 INFO [stdout] test src/lib.rs - EditListingUpdateCall (line 15162) ... test src/lib.rs - EditListingUpdateCall (line 15162) has been running for over 60 seconds
Dec 07 14:48:32.472 INFO [stdout] test src/lib.rs - EditMethods (line 2004) ... ok
Dec 07 14:48:32.756 INFO [stdout] test src/lib.rs - EditListingPatchCall (line 9713) ... ok
Dec 07 14:48:32.988 INFO [stdout] test src/lib.rs - EditTesterGetCall (line 14264) ... ok
Dec 07 14:48:34.916 INFO [stdout] test src/lib.rs - EditTesterPatchCall (line 13458) ... test src/lib.rs - EditTesterPatchCall (line 13458) has been running for over 60 seconds
Dec 07 14:48:35.604 INFO [stdout] test src/lib.rs - EditTesterUpdateCall (line 17133) ... test src/lib.rs - EditTesterUpdateCall (line 17133) has been running for over 60 seconds
Dec 07 14:48:35.708 INFO [stdout] test src/lib.rs - EditTesterUpdateCall (line 17133) ... ok
Dec 07 14:48:36.821 INFO [stdout] test src/lib.rs - EditListingUpdateCall (line 15162) ... ok
Dec 07 14:48:38.702 INFO [stdout] test src/lib.rs - EditTesterPatchCall (line 13458) ... ok
Dec 07 14:49:24.504 INFO [stdout] test src/lib.rs - EditTrackListCall (line 14011) ... test src/lib.rs - EditTrackListCall (line 14011) has been running for over 60 seconds
Dec 07 14:49:25.986 INFO [stdout] test src/lib.rs - InappproductDeleteCall (line 18516) ... ok
Dec 07 14:49:30.376 INFO [stdout] test src/lib.rs - InappproductGetCall (line 18760) ... ok
Dec 07 14:49:31.200 INFO [stdout] test src/lib.rs - EditTrackPatchCall (line 16302) ... test src/lib.rs - EditTrackPatchCall (line 16302) has been running for over 60 seconds
Dec 07 14:49:32.476 INFO [stdout] test src/lib.rs - EditTrackUpdateCall (line 9415) ... test src/lib.rs - EditTrackUpdateCall (line 9415) has been running for over 60 seconds
Dec 07 14:49:32.577 INFO [stdout] test src/lib.rs - EditValidateCall (line 14531) ... ok
Dec 07 14:49:32.812 INFO [stdout] test src/lib.rs - EditTrackListCall (line 14011) ... ok
Dec 07 14:49:35.490 INFO [stdout] test src/lib.rs - EditTrackUpdateCall (line 9415) ... ok
Dec 07 14:49:36.405 INFO [stdout] test src/lib.rs - EditTrackPatchCall (line 16302) ... ok
Dec 07 14:49:36.828 INFO [stdout] test src/lib.rs - InappproductInsertCall (line 18230) ... test src/lib.rs - InappproductInsertCall (line 18230) has been running for over 60 seconds
Dec 07 14:49:37.392 INFO [stdout] test src/lib.rs - InappproductListCall (line 17954) ... ok
Dec 07 14:49:38.176 INFO [stdout] test src/lib.rs - InappproductInsertCall (line 18230) ... ok
Dec 07 14:50:25.992 INFO [stdout] test src/lib.rs - InappproductMethods (line 2974) ... test src/lib.rs - InappproductMethods (line 2974) has been running for over 60 seconds
Dec 07 14:50:30.388 INFO [stdout] test src/lib.rs - InappproductPatchCall (line 19312) ... test src/lib.rs - InappproductPatchCall (line 19312) has been running for over 60 seconds
Dec 07 14:50:31.377 INFO [stdout] test src/lib.rs - OrderRefundCall (line 17698) ... ok
Dec 07 14:50:32.156 INFO [stdout] test src/lib.rs - InappproductMethods (line 2974) ... ok
Dec 07 14:50:32.580 INFO [stdout] test src/lib.rs - InappproductUpdateCall (line 19014) ... test src/lib.rs - InappproductUpdateCall (line 19014) has been running for over 60 seconds
Dec 07 14:50:32.816 INFO [stdout] test src/lib.rs - OrderMethods (line 2912) ... test src/lib.rs - OrderMethods (line 2912) has been running for over 60 seconds
Dec 07 14:50:33.748 INFO [stdout] test src/lib.rs - InappproductPatchCall (line 19312) ... ok
Dec 07 14:50:35.656 INFO [stdout] test src/lib.rs - InappproductUpdateCall (line 19014) ... ok
Dec 07 14:50:36.228 INFO [stdout] test src/lib.rs - PurchaseSubscriptionCancelCall (line 5073) ... ok
Dec 07 14:50:36.408 INFO [stdout] test src/lib.rs - PurchaseMethods (line 1812) ... test src/lib.rs - PurchaseMethods (line 1812) has been running for over 60 seconds
Dec 07 14:50:37.408 INFO [stdout] test src/lib.rs - PurchaseProductGetCall (line 4239) ... test src/lib.rs - PurchaseProductGetCall (line 4239) has been running for over 60 seconds
Dec 07 14:50:37.428 INFO [stdout] test src/lib.rs - PurchaseMethods (line 1812) ... ok
Dec 07 14:50:37.524 INFO [stdout] test src/lib.rs - OrderMethods (line 2912) ... ok
Dec 07 14:50:39.976 INFO [stdout] test src/lib.rs - PurchaseProductGetCall (line 4239) ... ok
Dec 07 14:51:25.935 INFO [stdout] test src/lib.rs - PurchaseSubscriptionGetCall (line 4506) ... ok
Dec 07 14:51:28.532 INFO [stdout] test src/lib.rs - PurchaseSubscriptionDeferCall (line 5585) ... ok
Dec 07 14:51:32.028 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRevokeCall (line 3983) ... ok
Dec 07 14:51:33.300 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRefundCall (line 5329) ... ok
Dec 07 14:51:34.392 INFO [stdout] test src/lib.rs - PurchaseVoidedpurchaseListCall (line 4773) ... ok
Dec 07 14:51:34.812 INFO [stdout] test src/lib.rs - ReviewListCall (line 3696) ... ok
Dec 07 14:51:37.432 INFO [stdout] test src/lib.rs - ReviewGetCall (line 3431) ... test src/lib.rs - ReviewGetCall (line 3431) has been running for over 60 seconds
Dec 07 14:51:37.485 INFO [stdout] test src/lib.rs - ReviewMethods (line 1708) ... ok
Dec 07 14:51:38.086 INFO [stdout] test src/lib.rs - ReviewGetCall (line 3431) ... ok
Dec 07 14:51:44.161 INFO [stdout] test src/lib.rs - ReviewReplyCall (line 3146) ... ok
Dec 07 14:51:44.161 INFO [stdout] 
Dec 07 14:51:44.161 INFO [stdout] test result: ok. 65 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 07 14:51:44.161 INFO [stdout] 
Dec 07 14:51:44.238 INFO [stderr] su: No module specific data is present
Dec 07 14:51:45.403 INFO running `"docker" "inspect" "a143cbca2182e5af2596f09f1b40d04645cdea31a83e1fdc51a4d416d2ff1ab4"`
Dec 07 14:51:45.740 INFO running `"docker" "rm" "-f" "a143cbca2182e5af2596f09f1b40d04645cdea31a83e1fdc51a4d416d2ff1ab4"`
Dec 07 14:51:46.076 INFO [stdout] a143cbca2182e5af2596f09f1b40d04645cdea31a83e1fdc51a4d416d2ff1ab4