[07:00:02.330] New invocation is queued and will start shortly
[07:00:03.476] Starting the invocation (attempt 1)
[07:00:03.511] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:00:03.513] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:00:03.514] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025844223577432560",
"parameters_json": "{\"builder_name\":\"android-avd-packager\",\"properties\":{}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-avd-packager",
"scheduler_invocation_id:9025844223577432560",
"scheduler_job_id:chromium/android-avd-packager",
"user_agent:luci-scheduler"
]
}
[07:00:04.824] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608447603713713",
"id": "8860414161447694032",
"parameters_json": "{\"builder_name\": \"android-avd-packager\", \"properties\": {}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-cipd-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608447604603907",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/93",
"builder:android-avd-packager",
"scheduler_invocation_id:9025844223577432560",
"scheduler_job_id:chromium/android-avd-packager",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860414161447694032/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:android/avd_packager",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1608447604604237",
"url": "https://ci.chromium.org/b/8860414161447694032",
"utcnow_ts": "1608447604817408"
}
}
[07:00:04.824] Task URL: https://ci.chromium.org/b/8860414161447694032
[07:00:04.824] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:2:0) after 1m0s
[07:01:04.822] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:2:0)
[07:01:04.822] Timer tick, asking Buildbucket for the build status
[07:01:05.070] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:05.070] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:3:0) after 1m0s
[07:02:05.327] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:3:0)
[07:02:05.327] Timer tick, asking Buildbucket for the build status
[07:02:05.481] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:05.481] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:4:0) after 1m0s
[07:03:05.501] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:4:0)
[07:03:05.501] Timer tick, asking Buildbucket for the build status
[07:03:05.801] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:05.801] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:5:0) after 1m0s
[07:04:05.898] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:5:0)
[07:04:05.898] Timer tick, asking Buildbucket for the build status
[07:04:05.974] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:05.974] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:6:0) after 1m0s
[07:05:05.990] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:6:0)
[07:05:05.990] Timer tick, asking Buildbucket for the build status
[07:05:06.072] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:06.072] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:7:0) after 1m0s
[07:06:06.098] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:7:0)
[07:06:06.098] Timer tick, asking Buildbucket for the build status
[07:06:06.188] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:06.188] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:8:0) after 1m0s
[07:07:06.266] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:8:0)
[07:07:06.266] Timer tick, asking Buildbucket for the build status
[07:07:06.342] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:06.342] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:9:0) after 1m0s
[07:08:06.361] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:9:0)
[07:08:06.361] Timer tick, asking Buildbucket for the build status
[07:08:06.429] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:06.429] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:10:0) after 1m0s
[07:09:06.486] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:10:0)
[07:09:06.487] Timer tick, asking Buildbucket for the build status
[07:09:06.553] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:06.553] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:11:0) after 1m0s
[07:10:06.650] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:11:0)
[07:10:06.650] Timer tick, asking Buildbucket for the build status
[07:10:07.002] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:07.002] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:12:0) after 1m0s
[07:11:07.240] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:12:0)
[07:11:07.240] Timer tick, asking Buildbucket for the build status
[07:11:07.349] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:07.349] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:13:0) after 1m0s
[07:12:07.379] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:13:0)
[07:12:07.379] Timer tick, asking Buildbucket for the build status
[07:12:07.440] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:07.440] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:14:0) after 1m0s
[07:13:07.524] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:14:0)
[07:13:07.524] Timer tick, asking Buildbucket for the build status
[07:13:07.590] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:07.590] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:15:0) after 1m0s
[07:14:07.652] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:15:0)
[07:14:07.652] Timer tick, asking Buildbucket for the build status
[07:14:07.716] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:07.716] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:16:0) after 1m0s
[07:15:07.757] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:16:0)
[07:15:07.757] Timer tick, asking Buildbucket for the build status
[07:15:07.851] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:07.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:17:0) after 1m0s
[07:16:07.968] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:17:0)
[07:16:07.968] Timer tick, asking Buildbucket for the build status
[07:16:08.040] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:08.040] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:18:0) after 1m0s
[07:17:08.164] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:18:0)
[07:17:08.164] Timer tick, asking Buildbucket for the build status
[07:17:08.263] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:08.263] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:19:0) after 1m0s
[07:18:08.387] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:19:0)
[07:18:08.387] Timer tick, asking Buildbucket for the build status
[07:18:08.453] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:08.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:20:0) after 1m0s
[07:19:08.572] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:20:0)
[07:19:08.572] Timer tick, asking Buildbucket for the build status
[07:19:08.643] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:08.643] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:21:0) after 1m0s
[07:20:08.766] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:21:0)
[07:20:08.766] Timer tick, asking Buildbucket for the build status
[07:20:08.845] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:08.845] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:22:0) after 1m0s
[07:21:08.865] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:22:0)
[07:21:08.865] Timer tick, asking Buildbucket for the build status
[07:21:08.996] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:08.996] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:23:0) after 1m0s
[07:22:09.057] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:23:0)
[07:22:09.057] Timer tick, asking Buildbucket for the build status
[07:22:09.122] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:09.122] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:24:0) after 1m0s
[07:23:09.099] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:24:0)
[07:23:09.099] Timer tick, asking Buildbucket for the build status
[07:23:09.214] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:09.214] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:25:0) after 1m0s
[07:24:09.286] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:25:0)
[07:24:09.286] Timer tick, asking Buildbucket for the build status
[07:24:09.355] Build 8860414161447694032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:09.355] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:26:0) after 1m0s
[07:25:07.172] Received PubSub notification, asking Buildbucket for the build status
[07:25:07.241] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:09.452] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:26:0)
[07:25:09.452] Timer tick, asking Buildbucket for the build status
[07:25:09.515] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:09.515] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:28:0) after 1m0s
[07:26:09.541] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:28:0)
[07:26:09.541] Timer tick, asking Buildbucket for the build status
[07:26:09.655] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:09.655] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:29:0) after 1m0s
[07:27:09.719] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:29:0)
[07:27:09.719] Timer tick, asking Buildbucket for the build status
[07:27:09.827] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:09.827] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:30:0) after 1m0s
[07:28:09.862] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:30:0)
[07:28:09.862] Timer tick, asking Buildbucket for the build status
[07:28:10.074] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:10.074] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:31:0) after 1m0s
[07:29:10.105] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:31:0)
[07:29:10.105] Timer tick, asking Buildbucket for the build status
[07:29:10.224] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:10.224] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:32:0) after 1m0s
[07:30:10.347] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:32:0)
[07:30:10.347] Timer tick, asking Buildbucket for the build status
[07:30:10.439] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:10.439] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:33:0) after 1m0s
[07:31:10.866] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:33:0)
[07:31:10.866] Timer tick, asking Buildbucket for the build status
[07:31:10.948] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:10.948] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:34:0) after 1m0s
[07:32:11.211] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:34:0)
[07:32:11.211] Timer tick, asking Buildbucket for the build status
[07:32:11.412] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:11.412] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:35:0) after 1m0s
[07:33:11.629] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:35:0)
[07:33:11.629] Timer tick, asking Buildbucket for the build status
[07:33:11.678] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:11.678] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:36:0) after 1m0s
[07:34:11.862] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:36:0)
[07:34:11.863] Timer tick, asking Buildbucket for the build status
[07:34:12.584] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:12.584] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:37:0) after 1m0s
[07:35:12.819] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:37:0)
[07:35:12.819] Timer tick, asking Buildbucket for the build status
[07:35:12.904] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:12.904] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:38:0) after 1m0s
[07:36:12.939] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:38:0)
[07:36:12.939] Timer tick, asking Buildbucket for the build status
[07:36:13.008] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:13.008] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:39:0) after 1m0s
[07:37:13.127] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:39:0)
[07:37:13.127] Timer tick, asking Buildbucket for the build status
[07:37:13.410] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:13.411] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:40:0) after 1m0s
[07:38:13.561] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:40:0)
[07:38:13.561] Timer tick, asking Buildbucket for the build status
[07:38:13.905] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:13.905] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:41:0) after 1m0s
[07:39:14.013] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:41:0)
[07:39:14.013] Timer tick, asking Buildbucket for the build status
[07:39:14.089] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:14.089] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:42:0) after 1m0s
[07:40:14.160] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:42:0)
[07:40:14.160] Timer tick, asking Buildbucket for the build status
[07:40:14.276] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:14.276] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:43:0) after 1m0s
[07:41:14.315] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:43:0)
[07:41:14.315] Timer tick, asking Buildbucket for the build status
[07:41:14.361] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:14.361] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:44:0) after 1m0s
[07:42:14.355] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:44:0)
[07:42:14.355] Timer tick, asking Buildbucket for the build status
[07:42:14.404] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:14.404] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:45:0) after 1m0s
[07:43:14.519] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:45:0)
[07:43:14.519] Timer tick, asking Buildbucket for the build status
[07:43:14.589] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:14.589] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:46:0) after 1m0s
[07:44:14.660] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:46:0)
[07:44:14.660] Timer tick, asking Buildbucket for the build status
[07:44:14.746] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:14.746] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:47:0) after 1m0s
[07:45:14.768] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:47:0)
[07:45:14.768] Timer tick, asking Buildbucket for the build status
[07:45:14.879] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:14.879] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:48:0) after 1m0s
[07:46:14.941] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:48:0)
[07:46:14.941] Timer tick, asking Buildbucket for the build status
[07:46:15.005] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:15.005] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:49:0) after 1m0s
[07:47:15.028] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:49:0)
[07:47:15.028] Timer tick, asking Buildbucket for the build status
[07:47:15.086] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:15.086] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:50:0) after 1m0s
[07:48:15.110] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:50:0)
[07:48:15.110] Timer tick, asking Buildbucket for the build status
[07:48:15.166] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:15.166] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:51:0) after 1m0s
[07:49:15.194] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:51:0)
[07:49:15.194] Timer tick, asking Buildbucket for the build status
[07:49:15.253] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:15.253] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:52:0) after 1m0s
[07:50:15.277] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:52:0)
[07:50:15.277] Timer tick, asking Buildbucket for the build status
[07:50:15.361] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:15.361] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:53:0) after 1m0s
[07:51:15.423] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:53:0)
[07:51:15.423] Timer tick, asking Buildbucket for the build status
[07:51:15.499] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:15.499] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:54:0) after 1m0s
[07:52:15.552] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:54:0)
[07:52:15.552] Timer tick, asking Buildbucket for the build status
[07:52:15.603] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:15.603] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:55:0) after 1m0s
[07:53:15.697] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:55:0)
[07:53:15.697] Timer tick, asking Buildbucket for the build status
[07:53:15.812] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:15.812] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:56:0) after 1m0s
[07:54:15.840] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:56:0)
[07:54:15.840] Timer tick, asking Buildbucket for the build status
[07:54:16.119] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:16.119] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:57:0) after 1m0s
[07:55:16.189] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:57:0)
[07:55:16.189] Timer tick, asking Buildbucket for the build status
[07:55:16.658] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:16.658] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:58:0) after 1m0s
[07:56:16.767] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:58:0)
[07:56:16.767] Timer tick, asking Buildbucket for the build status
[07:56:16.926] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:16.926] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:59:0) after 1m0s
[07:57:17.044] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:59:0)
[07:57:17.044] Timer tick, asking Buildbucket for the build status
[07:57:17.171] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:17.171] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:60:0) after 1m0s
[07:58:17.201] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:60:0)
[07:58:17.201] Timer tick, asking Buildbucket for the build status
[07:58:17.257] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:17.257] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:61:0) after 1m0s
[07:59:17.277] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:61:0)
[07:59:17.277] Timer tick, asking Buildbucket for the build status
[07:59:17.384] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:17.384] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:62:0) after 1m0s
[08:00:17.418] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:62:0)
[08:00:17.419] Timer tick, asking Buildbucket for the build status
[08:00:17.586] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:17.586] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:63:0) after 1m0s
[08:01:17.624] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:63:0)
[08:01:17.624] Timer tick, asking Buildbucket for the build status
[08:01:17.869] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:17.869] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:64:0) after 1m0s
[08:02:17.897] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:64:0)
[08:02:17.897] Timer tick, asking Buildbucket for the build status
[08:02:18.037] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:18.037] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:65:0) after 1m0s
[08:03:18.031] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:65:0)
[08:03:18.031] Timer tick, asking Buildbucket for the build status
[08:03:18.092] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:18.092] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:66:0) after 1m0s
[08:04:18.166] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:66:0)
[08:04:18.166] Timer tick, asking Buildbucket for the build status
[08:04:18.256] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:18.256] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:67:0) after 1m0s
[08:05:18.457] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:67:0)
[08:05:18.457] Timer tick, asking Buildbucket for the build status
[08:05:18.642] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:18.642] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:68:0) after 1m0s
[08:06:18.669] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:68:0)
[08:06:18.669] Timer tick, asking Buildbucket for the build status
[08:06:18.717] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:18.717] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:69:0) after 1m0s
[08:07:18.746] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:69:0)
[08:07:18.746] Timer tick, asking Buildbucket for the build status
[08:07:18.793] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:18.793] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:70:0) after 1m0s
[08:08:18.914] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:70:0)
[08:08:18.914] Timer tick, asking Buildbucket for the build status
[08:08:18.963] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:18.963] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:71:0) after 1m0s
[08:09:18.952] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:71:0)
[08:09:18.952] Timer tick, asking Buildbucket for the build status
[08:09:19.332] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:19.332] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:72:0) after 1m0s
[08:10:19.357] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:72:0)
[08:10:19.357] Timer tick, asking Buildbucket for the build status
[08:10:19.524] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:19.524] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:73:0) after 1m0s
[08:11:19.547] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:73:0)
[08:11:19.547] Timer tick, asking Buildbucket for the build status
[08:11:19.637] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:19.637] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:74:0) after 1m0s
[08:12:19.664] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:74:0)
[08:12:19.664] Timer tick, asking Buildbucket for the build status
[08:12:19.768] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:19.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:75:0) after 1m0s
[08:13:19.794] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:75:0)
[08:13:19.794] Timer tick, asking Buildbucket for the build status
[08:13:19.939] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:19.939] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:76:0) after 1m0s
[08:14:19.960] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:76:0)
[08:14:19.960] Timer tick, asking Buildbucket for the build status
[08:14:20.008] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:20.008] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:77:0) after 1m0s
[08:15:20.105] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:77:0)
[08:15:20.105] Timer tick, asking Buildbucket for the build status
[08:15:20.516] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:20.516] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:78:0) after 1m0s
[08:16:20.537] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:78:0)
[08:16:20.537] Timer tick, asking Buildbucket for the build status
[08:16:20.680] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:20.680] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:79:0) after 1m0s
[08:17:20.670] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:79:0)
[08:17:20.670] Timer tick, asking Buildbucket for the build status
[08:17:20.723] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:20.723] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:80:0) after 1m0s
[08:18:20.778] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:80:0)
[08:18:20.778] Timer tick, asking Buildbucket for the build status
[08:18:20.988] Build 8860414161447694032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:20.988] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9025844223577432560:81:0) after 1m0s
[08:19:14.011] Received PubSub notification, asking Buildbucket for the build status
[08:19:14.116] Build 8860414161447694032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:19:14.116] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608452353021021",
"created_by": "project:chromium",
"created_ts": "1608447603713713",
"id": "8860414161447694032",
"parameters_json": "{\"builder_name\": \"android-avd-packager\", \"properties\": {}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"avd_configs\": [\"tools/android/avd/proto/creation/generic_android23.textpb\", \"tools/android/avd/proto/creation/generic_android27.textpb\", \"tools/android/avd/proto/creation/generic_android28.textpb\", \"tools/android/avd/proto/creation/generic_android29.textpb\", \"tools/android/avd/proto/creation/generic_android30.textpb\", \"tools/android/avd/proto/creation/generic_playstore_android28.textpb\", \"tools/android/avd/proto/creation/generic_playstore_android30.textpb\"], \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-56-v65t\", \"buildername\": \"android-avd-packager\", \"buildnumber\": 93, \"got_angle_revision\": \"ed9213bca0643edb65b7d39a938210419398a1b3\", \"got_dawn_revision\": \"5d4fd88a7403aa255d49919d011af54c691b344a\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"5d0ec096e35ebf0728c93225e7307d4bb3e72a1c\", \"got_revision_cp\": \"refs/heads/master@{#838550}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"73bf5d7db9ade92643424d79b47ac70900843225\", \"got_v8_revision_cp\": \"refs/heads/8.9.178@{#1}\", \"got_webrtc_revision\": \"ef9c4e07a351c9396c72e72efaa1be3df728c440\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32863}\", \"path_config\": \"generic\", \"recipe\": \"android/avd_packager\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_06f1ed350fecda52f561db3eb00cb39e105b14f5c02d94078111c7babc8da8c6_v2\", \"builder_425d15596feb4c1a31f11fc1911a8217c0e7ee1a27198f37227d51247058a439_v2\", \"builder_6448ef6328c2efecc3415fa6504c190b95489d0a924b27e14d9dc0a4e83a39f8_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-56-v65t\"], \"image\": [\"chrome-xenial-20121300-d43316a0aad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-cipd-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608449095382278",
"status": "COMPLETED",
"status_changed_ts": "1608452353345914",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/93",
"builder:android-avd-packager",
"scheduler_invocation_id:9025844223577432560",
"scheduler_job_id:chromium/android-avd-packager",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8860414161447694032/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:android/avd_packager",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:5097c4421ab4cb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608452353350879",
"url": "https://ci.chromium.org/b/8860414161447694032",
"utcnow_ts": "1608452354100755"
}
}
[08:19:14.116] Invocation finished in 1h19m11.818224824s with status SUCCEEDED