[07:00:05.680] New invocation is queued and will start shortly
[07:00:06.810] Starting the invocation (attempt 1)
[07:00:06.851] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:00:06.853] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:00:06.853] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029015113873367264",
"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:9029015113873367264",
"scheduler_job_id:chromium/android-avd-packager",
"user_agent:luci-scheduler"
]
}
[07:00:07.597] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605423606981225",
"id": "8863585051844535248",
"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": "1605423607432564",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/86",
"builder:android-avd-packager",
"scheduler_invocation_id:9029015113873367264",
"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/8863585051844535248/+/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": "1605423607432683",
"url": "https://ci.chromium.org/b/8863585051844535248",
"utcnow_ts": "1605423607562304"
}
}
[07:00:07.597] Task URL: https://ci.chromium.org/b/8863585051844535248
[07:00:07.597] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:2:0) after 1m0s
[07:01:07.678] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:2:0)
[07:01:07.678] Timer tick, asking Buildbucket for the build status
[07:01:07.769] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:07.769] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:3:0) after 1m0s
[07:02:07.843] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:3:0)
[07:02:07.843] Timer tick, asking Buildbucket for the build status
[07:02:07.934] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:07.934] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:4:0) after 1m0s
[07:03:08.089] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:4:0)
[07:03:08.089] Timer tick, asking Buildbucket for the build status
[07:03:08.166] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:08.166] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:5:0) after 1m0s
[07:04:08.219] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:5:0)
[07:04:08.219] Timer tick, asking Buildbucket for the build status
[07:04:08.314] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:08.314] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:6:0) after 1m0s
[07:05:08.394] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:6:0)
[07:05:08.394] Timer tick, asking Buildbucket for the build status
[07:05:08.476] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:08.476] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:7:0) after 1m0s
[07:06:08.499] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:7:0)
[07:06:08.499] Timer tick, asking Buildbucket for the build status
[07:06:08.604] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:08.604] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:8:0) after 1m0s
[07:07:08.663] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:8:0)
[07:07:08.663] Timer tick, asking Buildbucket for the build status
[07:07:08.751] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:08.751] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:9:0) after 1m0s
[07:08:08.751] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:9:0)
[07:08:08.751] Timer tick, asking Buildbucket for the build status
[07:08:08.818] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:08.818] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:10:0) after 1m0s
[07:09:08.809] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:10:0)
[07:09:08.809] Timer tick, asking Buildbucket for the build status
[07:09:08.881] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:08.881] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:11:0) after 1m0s
[07:10:08.916] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:11:0)
[07:10:08.916] Timer tick, asking Buildbucket for the build status
[07:10:08.995] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:08.995] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:12:0) after 1m0s
[07:11:09.014] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:12:0)
[07:11:09.014] Timer tick, asking Buildbucket for the build status
[07:11:09.106] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:09.106] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:13:0) after 1m0s
[07:12:09.129] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:13:0)
[07:12:09.129] Timer tick, asking Buildbucket for the build status
[07:12:09.234] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:09.234] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:14:0) after 1m0s
[07:13:09.277] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:14:0)
[07:13:09.277] Timer tick, asking Buildbucket for the build status
[07:13:09.397] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:09.397] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:15:0) after 1m0s
[07:14:09.494] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:15:0)
[07:14:09.494] Timer tick, asking Buildbucket for the build status
[07:14:09.742] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:09.742] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:16:0) after 1m0s
[07:15:09.785] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:16:0)
[07:15:09.785] Timer tick, asking Buildbucket for the build status
[07:15:09.852] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:09.852] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:17:0) after 1m0s
[07:16:09.890] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:17:0)
[07:16:09.890] Timer tick, asking Buildbucket for the build status
[07:16:10.091] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:10.092] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:18:0) after 1m0s
[07:17:10.877] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:18:0)
[07:17:10.877] Timer tick, asking Buildbucket for the build status
[07:17:10.960] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:10.960] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:19:0) after 1m0s
[07:18:11.472] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:19:0)
[07:18:11.472] Timer tick, asking Buildbucket for the build status
[07:18:11.583] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:11.583] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:20:0) after 1m0s
[07:19:11.716] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:20:0)
[07:19:11.716] Timer tick, asking Buildbucket for the build status
[07:19:11.785] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:11.785] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:21:0) after 1m0s
[07:20:11.838] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:21:0)
[07:20:11.838] Timer tick, asking Buildbucket for the build status
[07:20:11.908] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:11.908] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:22:0) after 1m0s
[07:21:11.930] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:22:0)
[07:21:11.930] Timer tick, asking Buildbucket for the build status
[07:21:12.219] Build 8863585051844535248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:12.219] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:23:0) after 1m0s
[07:21:13.691] Received PubSub notification, asking Buildbucket for the build status
[07:21:13.761] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:12.196] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:23:0)
[07:22:12.196] Timer tick, asking Buildbucket for the build status
[07:22:15.865] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:15.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:25:0) after 1m0s
[07:23:15.923] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:25:0)
[07:23:15.923] Timer tick, asking Buildbucket for the build status
[07:23:16.185] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:16.185] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:26:0) after 1m0s
[07:24:16.220] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:26:0)
[07:24:16.220] Timer tick, asking Buildbucket for the build status
[07:24:16.291] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:16.291] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:27:0) after 1m0s
[07:25:16.317] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:27:0)
[07:25:16.317] Timer tick, asking Buildbucket for the build status
[07:25:16.421] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:16.421] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:28:0) after 1m0s
[07:26:16.421] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:28:0)
[07:26:16.421] Timer tick, asking Buildbucket for the build status
[07:26:16.547] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:16.547] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:29:0) after 1m0s
[07:27:16.600] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:29:0)
[07:27:16.600] Timer tick, asking Buildbucket for the build status
[07:27:16.820] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:16.820] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:30:0) after 1m0s
[07:28:16.816] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:30:0)
[07:28:16.816] Timer tick, asking Buildbucket for the build status
[07:28:17.376] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:17.376] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:31:0) after 1m0s
[07:29:17.428] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:31:0)
[07:29:17.428] Timer tick, asking Buildbucket for the build status
[07:29:17.519] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:17.519] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:32:0) after 1m0s
[07:30:17.493] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:32:0)
[07:30:17.493] Timer tick, asking Buildbucket for the build status
[07:30:17.831] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:17.831] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:33:0) after 1m0s
[07:31:17.830] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:33:0)
[07:31:17.830] Timer tick, asking Buildbucket for the build status
[07:31:17.917] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:17.917] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:34:0) after 1m0s
[07:32:17.936] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:34:0)
[07:32:17.936] Timer tick, asking Buildbucket for the build status
[07:32:17.992] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:17.992] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:35:0) after 1m0s
[07:33:18.036] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:35:0)
[07:33:18.036] Timer tick, asking Buildbucket for the build status
[07:33:18.140] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:18.140] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:36:0) after 1m0s
[07:34:18.151] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:36:0)
[07:34:18.151] Timer tick, asking Buildbucket for the build status
[07:34:18.221] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:18.221] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:37:0) after 1m0s
[07:35:18.245] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:37:0)
[07:35:18.246] Timer tick, asking Buildbucket for the build status
[07:35:18.401] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:18.401] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:38:0) after 1m0s
[07:36:18.421] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:38:0)
[07:36:18.421] Timer tick, asking Buildbucket for the build status
[07:36:18.474] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:18.474] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:39:0) after 1m0s
[07:37:18.470] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:39:0)
[07:37:18.470] Timer tick, asking Buildbucket for the build status
[07:37:18.531] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:18.531] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:40:0) after 1m0s
[07:38:18.590] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:40:0)
[07:38:18.590] Timer tick, asking Buildbucket for the build status
[07:38:18.652] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:18.652] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:41:0) after 1m0s
[07:39:18.687] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:41:0)
[07:39:18.687] Timer tick, asking Buildbucket for the build status
[07:39:18.810] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:18.810] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:42:0) after 1m0s
[07:40:18.822] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:42:0)
[07:40:18.822] Timer tick, asking Buildbucket for the build status
[07:40:18.964] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:18.964] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:43:0) after 1m0s
[07:41:19.044] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:43:0)
[07:41:19.044] Timer tick, asking Buildbucket for the build status
[07:41:19.116] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:19.116] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:44:0) after 1m0s
[07:42:19.245] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:44:0)
[07:42:19.245] Timer tick, asking Buildbucket for the build status
[07:42:19.308] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:19.308] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:45:0) after 1m0s
[07:43:19.329] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:45:0)
[07:43:19.329] Timer tick, asking Buildbucket for the build status
[07:43:19.407] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:19.407] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:46:0) after 1m0s
[07:44:19.529] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:46:0)
[07:44:19.529] Timer tick, asking Buildbucket for the build status
[07:44:19.626] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:19.626] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:47:0) after 1m0s
[07:45:19.747] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:47:0)
[07:45:19.747] Timer tick, asking Buildbucket for the build status
[07:45:19.980] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:19.980] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:48:0) after 1m0s
[07:46:20.086] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:48:0)
[07:46:20.086] Timer tick, asking Buildbucket for the build status
[07:46:20.236] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:20.236] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:49:0) after 1m0s
[07:47:20.292] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:49:0)
[07:47:20.292] Timer tick, asking Buildbucket for the build status
[07:47:20.351] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:20.351] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:50:0) after 1m0s
[07:48:20.335] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:50:0)
[07:48:20.335] Timer tick, asking Buildbucket for the build status
[07:48:20.417] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:20.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:51:0) after 1m0s
[07:49:20.494] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:51:0)
[07:49:20.494] Timer tick, asking Buildbucket for the build status
[07:49:20.551] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:20.551] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:52:0) after 1m0s
[07:50:20.588] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:52:0)
[07:50:20.588] Timer tick, asking Buildbucket for the build status
[07:50:20.719] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:20.719] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:53:0) after 1m0s
[07:51:20.744] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:53:0)
[07:51:20.744] Timer tick, asking Buildbucket for the build status
[07:51:20.851] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:20.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:54:0) after 1m0s
[07:52:20.879] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:54:0)
[07:52:20.879] Timer tick, asking Buildbucket for the build status
[07:52:20.981] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:20.981] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:55:0) after 1m0s
[07:53:21.561] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:55:0)
[07:53:21.561] Timer tick, asking Buildbucket for the build status
[07:53:21.622] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:21.622] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:56:0) after 1m0s
[07:54:21.846] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:56:0)
[07:54:21.846] Timer tick, asking Buildbucket for the build status
[07:54:21.934] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:21.934] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:57:0) after 1m0s
[07:55:22.107] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:57:0)
[07:55:22.107] Timer tick, asking Buildbucket for the build status
[07:55:22.200] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:22.200] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:58:0) after 1m0s
[07:56:22.409] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:58:0)
[07:56:22.409] Timer tick, asking Buildbucket for the build status
[07:56:22.461] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:22.461] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:59:0) after 1m0s
[07:57:22.680] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:59:0)
[07:57:22.680] Timer tick, asking Buildbucket for the build status
[07:57:22.751] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:22.751] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:60:0) after 1m0s
[07:58:22.909] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:60:0)
[07:58:22.909] Timer tick, asking Buildbucket for the build status
[07:58:23.124] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:23.124] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:61:0) after 1m0s
[07:59:23.144] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:61:0)
[07:59:23.144] Timer tick, asking Buildbucket for the build status
[07:59:23.336] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:23.336] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:62:0) after 1m0s
[08:00:23.461] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:62:0)
[08:00:23.461] Timer tick, asking Buildbucket for the build status
[08:00:23.629] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:23.629] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:63:0) after 1m0s
[08:01:24.106] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:63:0)
[08:01:24.106] Timer tick, asking Buildbucket for the build status
[08:01:24.333] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:24.333] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:64:0) after 1m0s
[08:02:24.406] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:64:0)
[08:02:24.406] Timer tick, asking Buildbucket for the build status
[08:02:25.424] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:25.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:65:0) after 1m0s
[08:03:25.512] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:65:0)
[08:03:25.512] Timer tick, asking Buildbucket for the build status
[08:03:25.588] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:25.588] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:66:0) after 1m0s
[08:04:25.710] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:66:0)
[08:04:25.710] Timer tick, asking Buildbucket for the build status
[08:04:26.032] Build 8863585051844535248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:26.032] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9029015113873367264:67:0) after 1m0s
[08:04:58.629] Received PubSub notification, asking Buildbucket for the build status
[08:04:58.712] Build 8863585051844535248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:04:58.712] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605427496053694",
"created_by": "project:chromium",
"created_ts": "1605423606981225",
"id": "8863585051844535248",
"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_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-48-4hpj\", \"buildername\": \"android-avd-packager\", \"buildnumber\": 86, \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"aabde6c88f91f450b5845c8a720356bc4382ed01\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"b582a513ec042c25192fd4263b8fe7c99c1c7db1\", \"got_revision_cp\": \"refs/heads/master@{#827630}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"99df1af2aed6a8d10bc62dc348f63d6c084c1949\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32609}\", \"path_config\": \"generic\", \"recipe\": \"android/avd_packager\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_6887d226fea03ae8e203d0a1d4c9e197b929dd99abf2bafb38063e06e39bc1ba_v2\", \"builder_b388131f0fa9c382582f3d9f703e881c3c8067eb20dc1a07ab0cfa192d8814bc_v2\", \"builder_f5b4454e99a04aaa5ba743b59cc70de19e20bebedef65e50e3b90534a8d6a5e4_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-48-4hpj\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"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\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-cipd-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605424873072020",
"status": "COMPLETED",
"status_changed_ts": "1605427497494423",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/86",
"builder:android-avd-packager",
"scheduler_invocation_id:9029015113873367264",
"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/8863585051844535248/+/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:4fe385bb5051b310",
"user_agent:luci-scheduler"
],
"updated_ts": "1605427497540974",
"url": "https://ci.chromium.org/b/8863585051844535248",
"utcnow_ts": "1605427498692061"
}
}
[08:04:58.712] Invocation finished in 1h4m53.049386071s with status SUCCEEDED