Dec 15 12:38:21.390 INFO testing google-androidpublisher3-1.0.8+20181009 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 12:38:21.390 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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:38:21.943 INFO [stdout] 054f79ecac7773a111a53e71b208aee762025b0b4f367f28d5378369a320b3dc
Dec 15 12:38:21.945 INFO running `"docker" "start" "-a" "054f79ecac7773a111a53e71b208aee762025b0b4f367f28d5378369a320b3dc"`
Dec 15 12:38:23.514 INFO [stderr] usermod: no changes
Dec 15 12:38:23.774 INFO [stderr]    Compiling google-androidpublisher3 v1.0.8+20181009 (/source)
Dec 15 12:38:35.856 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.32s
Dec 15 12:38:35.883 INFO [stderr] su: No module specific data is present
Dec 15 12:38:36.979 INFO running `"docker" "inspect" "054f79ecac7773a111a53e71b208aee762025b0b4f367f28d5378369a320b3dc"`
Dec 15 12:38:37.291 INFO running `"docker" "rm" "-f" "054f79ecac7773a111a53e71b208aee762025b0b4f367f28d5378369a320b3dc"`
Dec 15 12:38:37.546 INFO [stdout] 054f79ecac7773a111a53e71b208aee762025b0b4f367f28d5378369a320b3dc
Dec 15 12:38:37.553 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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:38:38.054 INFO [stdout] fd81c19fccffea61e8f64bcb22130d151a10606d0be66c6ce56ff7beea00ea47
Dec 15 12:38:38.056 INFO running `"docker" "start" "-a" "fd81c19fccffea61e8f64bcb22130d151a10606d0be66c6ce56ff7beea00ea47"`
Dec 15 12:38:39.738 INFO [stderr] usermod: no changes
Dec 15 12:38:39.981 INFO [stderr]    Compiling google-androidpublisher3 v1.0.8+20181009 (/source)
Dec 15 12:38:50.220 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.46s
Dec 15 12:38:50.235 INFO [stderr] su: No module specific data is present
Dec 15 12:38:51.626 INFO running `"docker" "inspect" "fd81c19fccffea61e8f64bcb22130d151a10606d0be66c6ce56ff7beea00ea47"`
Dec 15 12:38:51.955 INFO running `"docker" "rm" "-f" "fd81c19fccffea61e8f64bcb22130d151a10606d0be66c6ce56ff7beea00ea47"`
Dec 15 12:38:52.347 INFO [stdout] fd81c19fccffea61e8f64bcb22130d151a10606d0be66c6ce56ff7beea00ea47
Dec 15 12:38:52.355 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-4/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/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:38:52.853 INFO [stdout] 53c14f0a455b3fb429ba8286fa25923ccbfb6268ab996d0189c40f75236e8b1d
Dec 15 12:38:52.855 INFO running `"docker" "start" "-a" "53c14f0a455b3fb429ba8286fa25923ccbfb6268ab996d0189c40f75236e8b1d"`
Dec 15 12:38:55.063 INFO [stderr] usermod: no changes
Dec 15 12:38:55.381 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.30s
Dec 15 12:38:55.393 INFO [stderr]      Running /target/debug/deps/google_androidpublisher3-e6f3985fe1393566
Dec 15 12:38:55.396 INFO [stdout] 
Dec 15 12:38:55.396 INFO [stdout] running 0 tests
Dec 15 12:38:55.396 INFO [stdout] 
Dec 15 12:38:55.396 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 12:38:55.396 INFO [stdout] 
Dec 15 12:38:55.403 INFO [stderr]    Doc-tests google-androidpublisher3
Dec 15 12:39:04.292 INFO [stdout] 
Dec 15 12:39:04.292 INFO [stdout] running 60 tests
Dec 15 12:39:04.335 INFO [stdout] test src/lib.rs -  (line 65) ... ignored
Dec 15 12:39:59.503 INFO [stdout] test src/lib.rs - EditBundleUploadCall (line 13495) ... ok
Dec 15 12:40:03.299 INFO [stdout] test src/lib.rs - EditApkListCall (line 10234) ... ok
Dec 15 12:40:04.303 INFO [stdout] test src/lib.rs -  (line 98) ... test src/lib.rs -  (line 98) has been running for over 60 seconds
Dec 15 12:40:04.303 INFO [stdout] test src/lib.rs - EditApkUploadCall (line 9872) ... test src/lib.rs - EditApkUploadCall (line 9872) has been running for over 60 seconds
Dec 15 12:40:04.303 INFO [stdout] test src/lib.rs - EditApkAddexternallyhostedCall (line 7318) ... test src/lib.rs - EditApkAddexternallyhostedCall (line 7318) has been running for over 60 seconds
Dec 15 12:40:04.303 INFO [stdout] test src/lib.rs - AndroidPublisher (line 284) ... test src/lib.rs - AndroidPublisher (line 284) has been running for over 60 seconds
Dec 15 12:40:04.323 INFO [stdout] test src/lib.rs - EditBundleListCall (line 10754) ... test src/lib.rs - EditBundleListCall (line 10754) has been running for over 60 seconds
Dec 15 12:40:04.339 INFO [stdout] test src/lib.rs - EditCommitCall (line 12465) ... test src/lib.rs - EditCommitCall (line 12465) has been running for over 60 seconds
Dec 15 12:40:04.406 INFO [stdout] test src/lib.rs - EditCommitCall (line 12465) ... ok
Dec 15 12:40:04.907 INFO [stdout] test src/lib.rs - AndroidPublisher (line 284) ... ok
Dec 15 12:40:05.064 INFO [stdout] test src/lib.rs - EditApkAddexternallyhostedCall (line 7318) ... ok
Dec 15 12:40:06.238 INFO [stdout] test src/lib.rs - EditApkUploadCall (line 9872) ... ok
Dec 15 12:40:06.643 INFO [stdout] test src/lib.rs -  (line 98) ... ok
Dec 15 12:40:06.939 INFO [stdout] test src/lib.rs - EditBundleListCall (line 10754) ... ok
Dec 15 12:40:58.413 INFO [stdout] test src/lib.rs - EditDeleteCall (line 11009) ... ok
Dec 15 12:41:02.315 INFO [stdout] test src/lib.rs - EditDeobfuscationfileUploadCall (line 5736) ... ok
Dec 15 12:41:02.585 INFO [stdout] test src/lib.rs - EditDetailGetCall (line 6819) ... ok
Dec 15 12:41:04.707 INFO [stdout] test src/lib.rs - EditExpansionfilePatchCall (line 8157) ... ok
Dec 15 12:41:04.907 INFO [stdout] test src/lib.rs - EditDetailPatchCall (line 14725) ... test src/lib.rs - EditDetailPatchCall (line 14725) has been running for over 60 seconds
Dec 15 12:41:05.064 INFO [stdout] test src/lib.rs - EditDetailUpdateCall (line 7604) ... test src/lib.rs - EditDetailUpdateCall (line 7604) has been running for over 60 seconds
Dec 15 12:41:06.239 INFO [stdout] test src/lib.rs - EditExpansionfileGetCall (line 14169) ... test src/lib.rs - EditExpansionfileGetCall (line 14169) has been running for over 60 seconds
Dec 15 12:41:06.475 INFO [stdout] test src/lib.rs - EditDetailPatchCall (line 14725) ... ok
Dec 15 12:41:06.907 INFO [stdout] test src/lib.rs - EditDetailUpdateCall (line 7604) ... ok
Dec 15 12:41:06.943 INFO [stdout] test src/lib.rs - EditExpansionfileUpdateCall (line 6510) ... test src/lib.rs - EditExpansionfileUpdateCall (line 6510) has been running for over 60 seconds
Dec 15 12:41:09.779 INFO [stdout] test src/lib.rs - EditExpansionfileUpdateCall (line 6510) ... ok
Dec 15 12:41:10.911 INFO [stdout] test src/lib.rs - EditExpansionfileGetCall (line 14169) ... ok
Dec 15 12:41:58.427 INFO [stdout] test src/lib.rs - EditExpansionfileUploadCall (line 11253) ... test src/lib.rs - EditExpansionfileUploadCall (line 11253) has been running for over 60 seconds
Dec 15 12:41:59.755 INFO [stdout] test src/lib.rs - EditImageListCall (line 8466) ... ok
Dec 15 12:42:02.335 INFO [stdout] test src/lib.rs - EditGetCall (line 9340) ... test src/lib.rs - EditGetCall (line 9340) has been running for over 60 seconds
Dec 15 12:42:02.611 INFO [stdout] test src/lib.rs - EditImageDeleteCall (line 9595) ... test src/lib.rs - EditImageDeleteCall (line 9595) has been running for over 60 seconds
Dec 15 12:42:03.523 INFO [stdout] test src/lib.rs - EditGetCall (line 9340) ... ok
Dec 15 12:42:04.131 INFO [stdout] test src/lib.rs - EditExpansionfileUploadCall (line 11253) ... ok
Dec 15 12:42:04.330 INFO [stdout] test src/lib.rs - EditImageDeleteCall (line 9595) ... ok
Dec 15 12:42:04.715 INFO [stdout] test src/lib.rs - EditImageDeleteallCall (line 14447) ... test src/lib.rs - EditImageDeleteallCall (line 14447) has been running for over 60 seconds
Dec 15 12:42:05.895 INFO [stdout] test src/lib.rs - EditInsertCall (line 11640) ... ok
Dec 15 12:42:06.555 INFO [stdout] test src/lib.rs - EditImageDeleteallCall (line 14447) ... ok
Dec 15 12:42:06.915 INFO [stdout] test src/lib.rs - EditImageUploadCall (line 6123) ... test src/lib.rs - EditImageUploadCall (line 6123) has been running for over 60 seconds
Dec 15 12:42:07.271 INFO [stdout] test src/lib.rs - EditImageUploadCall (line 6123) ... ok
Dec 15 12:42:10.923 INFO [stdout] test src/lib.rs - EditListingDeleteCall (line 15309) ... test src/lib.rs - EditListingDeleteCall (line 15309) has been running for over 60 seconds
Dec 15 12:42:15.587 INFO [stdout] test src/lib.rs - EditListingDeleteCall (line 15309) ... ok
Dec 15 12:42:59.001 INFO [stdout] test src/lib.rs - EditListingListCall (line 11914) ... ok
Dec 15 12:42:59.767 INFO [stdout] test src/lib.rs - EditListingDeleteallCall (line 7074) ... test src/lib.rs - EditListingDeleteallCall (line 7074) has been running for over 60 seconds
Dec 15 12:43:03.527 INFO [stdout] test src/lib.rs - EditListingGetCall (line 10489) ... test src/lib.rs - EditListingGetCall (line 10489) has been running for over 60 seconds
Dec 15 12:43:04.331 INFO [stdout] test src/lib.rs - EditListingPatchCall (line 9042) ... test src/lib.rs - EditListingPatchCall (line 9042) has been running for over 60 seconds
Dec 15 12:43:05.899 INFO [stdout] test src/lib.rs - EditListingUpdateCall (line 13871) ... test src/lib.rs - EditListingUpdateCall (line 13871) has been running for over 60 seconds
Dec 15 12:43:06.267 INFO [stdout] test src/lib.rs - EditListingDeleteallCall (line 7074) ... ok
Dec 15 12:43:06.563 INFO [stdout] test src/lib.rs - EditMethods (line 1995) ... test src/lib.rs - EditMethods (line 1995) has been running for over 60 seconds
Dec 15 12:43:07.283 INFO [stdout] test src/lib.rs - EditTesterGetCall (line 12973) ... test src/lib.rs - EditTesterGetCall (line 12973) has been running for over 60 seconds
Dec 15 12:43:08.255 INFO [stdout] test src/lib.rs - EditListingGetCall (line 10489) ... ok
Dec 15 12:43:10.555 INFO [stdout] test src/lib.rs - EditListingPatchCall (line 9042) ... ok
Dec 15 12:43:11.867 INFO [stdout] test src/lib.rs - EditTesterGetCall (line 12973) ... ok
Dec 15 12:43:11.868 INFO [stdout] test src/lib.rs - EditMethods (line 1995) ... ok
Dec 15 12:43:12.723 INFO [stdout] test src/lib.rs - EditListingUpdateCall (line 13871) ... ok
Dec 15 12:43:15.591 INFO [stdout] test src/lib.rs - EditTesterPatchCall (line 12167) ... test src/lib.rs - EditTesterPatchCall (line 12167) has been running for over 60 seconds
Dec 15 12:43:20.847 INFO [stdout] test src/lib.rs - EditTesterPatchCall (line 12167) ... ok
Dec 15 12:43:59.015 INFO [stdout] test src/lib.rs - EditTesterUpdateCall (line 15563) ... test src/lib.rs - EditTesterUpdateCall (line 15563) has been running for over 60 seconds
Dec 15 12:44:01.187 INFO [stdout] test src/lib.rs - EditTesterUpdateCall (line 15563) ... ok
Dec 15 12:44:06.299 INFO [stdout] test src/lib.rs - EditTrackGetCall (line 7890) ... test src/lib.rs - EditTrackGetCall (line 7890) has been running for over 60 seconds
Dec 15 12:44:06.847 INFO [stdout] test src/lib.rs - EditTrackPatchCall (line 15011) ... ok
Dec 15 12:44:08.262 INFO [stdout] test src/lib.rs - EditTrackListCall (line 12720) ... test src/lib.rs - EditTrackListCall (line 12720) has been running for over 60 seconds
Dec 15 12:44:09.623 INFO [stdout] test src/lib.rs - EditTrackGetCall (line 7890) ... ok
Dec 15 12:44:10.085 INFO [stdout] test src/lib.rs - EditTrackListCall (line 12720) ... ok
Dec 15 12:44:11.879 INFO [stdout] test src/lib.rs - EditTrackUpdateCall (line 8744) ... test src/lib.rs - EditTrackUpdateCall (line 8744) has been running for over 60 seconds
Dec 15 12:44:11.879 INFO [stdout] test src/lib.rs - EditValidateCall (line 13240) ... test src/lib.rs - EditValidateCall (line 13240) has been running for over 60 seconds
Dec 15 12:44:12.727 INFO [stdout] test src/lib.rs - InappproductDeleteCall (line 16977) ... test src/lib.rs - InappproductDeleteCall (line 16977) has been running for over 60 seconds
Dec 15 12:44:12.839 INFO [stdout] test src/lib.rs - EditValidateCall (line 13240) ... ok
Dec 15 12:44:14.375 INFO [stdout] test src/lib.rs - EditTrackUpdateCall (line 8744) ... ok
Dec 15 12:44:14.699 INFO [stdout] test src/lib.rs - InappproductDeleteCall (line 16977) ... ok
Dec 15 12:44:20.871 INFO [stdout] test src/lib.rs - InappproductGetCall (line 17221) ... test src/lib.rs - InappproductGetCall (line 17221) has been running for over 60 seconds
Dec 15 12:44:24.023 INFO [stdout] test src/lib.rs - InappproductGetCall (line 17221) ... ok
Dec 15 12:45:01.199 INFO [stdout] test src/lib.rs - InappproductInsertCall (line 16691) ... test src/lib.rs - InappproductInsertCall (line 16691) has been running for over 60 seconds
Dec 15 12:45:06.387 INFO [stdout] test src/lib.rs - InappproductInsertCall (line 16691) ... ok
Dec 15 12:45:06.735 INFO [stdout] test src/lib.rs - InappproductListCall (line 16415) ... ok
Dec 15 12:45:07.403 INFO [stdout] test src/lib.rs - InappproductPatchCall (line 17475) ... ok
Dec 15 12:45:09.627 INFO [stdout] test src/lib.rs - InappproductMethods (line 2827) ... test src/lib.rs - InappproductMethods (line 2827) has been running for over 60 seconds
Dec 15 12:45:12.830 INFO [stdout] test src/lib.rs - InappproductUpdateCall (line 16117) ... test src/lib.rs - InappproductUpdateCall (line 16117) has been running for over 60 seconds
Dec 15 12:45:13.333 INFO [stdout] test src/lib.rs - InappproductMethods (line 2827) ... ok
Dec 15 12:45:14.375 INFO [stdout] test src/lib.rs - OrderMethods (line 2765) ... test src/lib.rs - OrderMethods (line 2765) has been running for over 60 seconds
Dec 15 12:45:14.699 INFO [stdout] test src/lib.rs - OrderRefundCall (line 15861) ... test src/lib.rs - OrderRefundCall (line 15861) has been running for over 60 seconds
Dec 15 12:45:17.259 INFO [stdout] test src/lib.rs - InappproductUpdateCall (line 16117) ... ok
Dec 15 12:45:19.295 INFO [stdout] test src/lib.rs - OrderRefundCall (line 15861) ... ok
Dec 15 12:45:19.479 INFO [stdout] test src/lib.rs - OrderMethods (line 2765) ... ok
Dec 15 12:45:23.967 INFO [stdout] test src/lib.rs - PurchaseMethods (line 1803) ... ok
Dec 15 12:46:04.343 INFO [stdout] test src/lib.rs - PurchaseProductGetCall (line 4092) ... ok
Dec 15 12:46:06.746 INFO [stdout] test src/lib.rs - PurchaseSubscriptionCancelCall (line 4926) ... test src/lib.rs - PurchaseSubscriptionCancelCall (line 4926) has been running for over 60 seconds
Dec 15 12:46:07.423 INFO [stdout] test src/lib.rs - PurchaseSubscriptionDeferCall (line 5438) ... test src/lib.rs - PurchaseSubscriptionDeferCall (line 5438) has been running for over 60 seconds
Dec 15 12:46:07.843 INFO [stdout] test src/lib.rs - PurchaseSubscriptionCancelCall (line 4926) ... ok
Dec 15 12:46:08.295 INFO [stdout] test src/lib.rs - PurchaseSubscriptionDeferCall (line 5438) ... ok
Dec 15 12:46:11.803 INFO [stdout] test src/lib.rs - PurchaseSubscriptionGetCall (line 4359) ... ok
Dec 15 12:46:17.255 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRefundCall (line 5182) ... test src/lib.rs - PurchaseSubscriptionRefundCall (line 5182) has been running for over 60 seconds
Dec 15 12:46:19.299 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRevokeCall (line 3836) ... test src/lib.rs - PurchaseSubscriptionRevokeCall (line 3836) has been running for over 60 seconds
Dec 15 12:46:19.483 INFO [stdout] test src/lib.rs - PurchaseVoidedpurchaseListCall (line 4626) ... test src/lib.rs - PurchaseVoidedpurchaseListCall (line 4626) has been running for over 60 seconds
Dec 15 12:46:19.495 INFO [stdout] test src/lib.rs - PurchaseVoidedpurchaseListCall (line 4626) ... ok
Dec 15 12:46:19.573 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRefundCall (line 5182) ... ok
Dec 15 12:46:20.616 INFO [stdout] test src/lib.rs - PurchaseSubscriptionRevokeCall (line 3836) ... ok
Dec 15 12:46:23.507 INFO [stdout] test src/lib.rs - ReviewGetCall (line 3284) ... ok
Dec 15 12:46:35.198 INFO [stdout] test src/lib.rs - ReviewReplyCall (line 2999) ... ok
Dec 15 12:46:35.715 INFO [stdout] test src/lib.rs - ReviewListCall (line 3549) ... ok
Dec 15 12:46:36.533 INFO [stdout] test src/lib.rs - ReviewMethods (line 1699) ... ok
Dec 15 12:46:36.533 INFO [stdout] 
Dec 15 12:46:36.533 INFO [stdout] test result: ok. 59 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 15 12:46:36.533 INFO [stdout] 
Dec 15 12:46:36.582 INFO [stderr] su: No module specific data is present
Dec 15 12:46:37.364 INFO running `"docker" "inspect" "53c14f0a455b3fb429ba8286fa25923ccbfb6268ab996d0189c40f75236e8b1d"`
Dec 15 12:46:37.715 INFO running `"docker" "rm" "-f" "53c14f0a455b3fb429ba8286fa25923ccbfb6268ab996d0189c40f75236e8b1d"`
Dec 15 12:46:37.994 INFO [stdout] 53c14f0a455b3fb429ba8286fa25923ccbfb6268ab996d0189c40f75236e8b1d