[07:00:05.272] New invocation is queued and will start shortly
[07:00:06.606] Starting the invocation (attempt 1)
[07:00:06.718] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:00:06.718] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:00:06.718] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020770790360291328",
"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:9020770790360291328",
"scheduler_job_id:chromium/android-avd-packager",
"user_agent:luci-scheduler"
]
}
[07:00:07.564] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1613286006964562",
"id": "8855340727919555536",
"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": "1613286007358877",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/103",
"builder:android-avd-packager",
"scheduler_invocation_id:9020770790360291328",
"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/8855340727919555536/+/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": "1613286007359009",
"url": "https://ci.chromium.org/b/8855340727919555536",
"utcnow_ts": "1613286007550958"
}
}
[07:00:07.564] Task URL: https://ci.chromium.org/b/8855340727919555536
[07:00:07.564] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:2:0) after 1m0s
[07:01:07.736] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:2:0)
[07:01:07.736] Timer tick, asking Buildbucket for the build status
[07:01:08.048] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:08.048] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:3:0) after 1m0s
[07:02:08.170] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:3:0)
[07:02:08.170] Timer tick, asking Buildbucket for the build status
[07:02:08.603] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:08.603] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:4:0) after 1m0s
[07:03:08.655] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:4:0)
[07:03:08.655] Timer tick, asking Buildbucket for the build status
[07:03:08.802] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:08.802] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:5:0) after 1m0s
[07:04:08.817] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:5:0)
[07:04:08.817] Timer tick, asking Buildbucket for the build status
[07:04:08.980] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:08.980] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:6:0) after 1m0s
[07:05:09.013] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:6:0)
[07:05:09.013] Timer tick, asking Buildbucket for the build status
[07:05:09.109] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:09.109] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:7:0) after 1m0s
[07:06:09.183] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:7:0)
[07:06:09.183] Timer tick, asking Buildbucket for the build status
[07:06:09.484] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:09.484] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:8:0) after 1m0s
[07:07:09.566] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:8:0)
[07:07:09.566] Timer tick, asking Buildbucket for the build status
[07:07:09.663] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:09.663] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:9:0) after 1m0s
[07:08:09.668] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:9:0)
[07:08:09.668] Timer tick, asking Buildbucket for the build status
[07:08:09.732] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:09.732] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:10:0) after 1m0s
[07:09:09.781] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:10:0)
[07:09:09.781] Timer tick, asking Buildbucket for the build status
[07:09:09.859] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:09.859] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:11:0) after 1m0s
[07:10:09.893] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:11:0)
[07:10:09.893] Timer tick, asking Buildbucket for the build status
[07:10:09.962] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:09.962] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:12:0) after 1m0s
[07:11:09.978] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:12:0)
[07:11:09.978] Timer tick, asking Buildbucket for the build status
[07:11:10.158] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:10.158] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:13:0) after 1m0s
[07:12:10.181] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:13:0)
[07:12:10.181] Timer tick, asking Buildbucket for the build status
[07:12:10.326] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:10.326] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:14:0) after 1m0s
[07:13:10.409] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:14:0)
[07:13:10.409] Timer tick, asking Buildbucket for the build status
[07:13:10.562] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:10.562] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:15:0) after 1m0s
[07:14:10.570] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:15:0)
[07:14:10.570] Timer tick, asking Buildbucket for the build status
[07:14:10.712] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:10.712] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:16:0) after 1m0s
[07:15:10.841] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:16:0)
[07:15:10.841] Timer tick, asking Buildbucket for the build status
[07:15:10.920] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:10.920] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:17:0) after 1m0s
[07:16:10.908] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:17:0)
[07:16:10.908] Timer tick, asking Buildbucket for the build status
[07:16:10.990] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:10.990] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:18:0) after 1m0s
[07:17:11.014] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:18:0)
[07:17:11.014] Timer tick, asking Buildbucket for the build status
[07:17:11.140] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:11.140] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:19:0) after 1m0s
[07:18:11.180] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:19:0)
[07:18:11.180] Timer tick, asking Buildbucket for the build status
[07:18:11.431] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:11.431] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:20:0) after 1m0s
[07:19:11.442] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:20:0)
[07:19:11.442] Timer tick, asking Buildbucket for the build status
[07:19:11.621] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:11.621] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:21:0) after 1m0s
[07:20:11.678] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:21:0)
[07:20:11.678] Timer tick, asking Buildbucket for the build status
[07:20:11.745] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:11.745] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:22:0) after 1m0s
[07:21:11.765] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:22:0)
[07:21:11.765] Timer tick, asking Buildbucket for the build status
[07:21:11.941] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:11.942] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:23:0) after 1m0s
[07:22:11.971] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:23:0)
[07:22:11.971] Timer tick, asking Buildbucket for the build status
[07:22:12.115] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:12.115] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:24:0) after 1m0s
[07:23:12.230] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:24:0)
[07:23:12.230] Timer tick, asking Buildbucket for the build status
[07:23:12.410] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:12.410] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:25:0) after 1m0s
[07:24:12.521] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:25:0)
[07:24:12.521] Timer tick, asking Buildbucket for the build status
[07:24:12.613] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:12.613] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:26:0) after 1m0s
[07:25:12.825] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:26:0)
[07:25:12.825] Timer tick, asking Buildbucket for the build status
[07:25:12.897] Build 8855340727919555536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:12.897] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:27:0) after 1m0s
[07:25:27.104] Received PubSub notification, asking Buildbucket for the build status
[07:25:27.185] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:13.119] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:27:0)
[07:26:13.119] Timer tick, asking Buildbucket for the build status
[07:26:13.209] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:13.209] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:29:0) after 1m0s
[07:27:13.539] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:29:0)
[07:27:13.539] Timer tick, asking Buildbucket for the build status
[07:27:13.646] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:13.646] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:30:0) after 1m0s
[07:28:13.763] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:30:0)
[07:28:13.763] Timer tick, asking Buildbucket for the build status
[07:28:13.873] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:13.873] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:31:0) after 1m0s
[07:29:13.999] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:31:0)
[07:29:13.999] Timer tick, asking Buildbucket for the build status
[07:29:14.143] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:14.143] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:32:0) after 1m0s
[07:30:14.258] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:32:0)
[07:30:14.258] Timer tick, asking Buildbucket for the build status
[07:30:14.366] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:14.366] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:33:0) after 1m0s
[07:31:14.403] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:33:0)
[07:31:14.403] Timer tick, asking Buildbucket for the build status
[07:31:14.472] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:14.472] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:34:0) after 1m0s
[07:32:14.721] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:34:0)
[07:32:14.721] Timer tick, asking Buildbucket for the build status
[07:32:14.822] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:14.822] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:35:0) after 1m0s
[07:33:14.955] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:35:0)
[07:33:14.955] Timer tick, asking Buildbucket for the build status
[07:33:15.037] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:15.037] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:36:0) after 1m0s
[07:34:15.201] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:36:0)
[07:34:15.201] Timer tick, asking Buildbucket for the build status
[07:34:15.396] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:15.396] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:37:0) after 1m0s
[07:35:15.465] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:37:0)
[07:35:15.465] Timer tick, asking Buildbucket for the build status
[07:35:15.554] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:15.555] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:38:0) after 1m0s
[07:36:15.717] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:38:0)
[07:36:15.717] Timer tick, asking Buildbucket for the build status
[07:36:16.203] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:16.203] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:39:0) after 1m0s
[07:37:16.453] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:39:0)
[07:37:16.453] Timer tick, asking Buildbucket for the build status
[07:37:16.570] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:16.570] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:40:0) after 1m0s
[07:38:16.640] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:40:0)
[07:38:16.640] Timer tick, asking Buildbucket for the build status
[07:38:17.154] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:17.154] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:41:0) after 1m0s
[07:39:17.236] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:41:0)
[07:39:17.236] Timer tick, asking Buildbucket for the build status
[07:39:17.304] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:17.304] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:42:0) after 1m0s
[07:40:17.294] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:42:0)
[07:40:17.294] Timer tick, asking Buildbucket for the build status
[07:40:17.434] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:17.434] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:43:0) after 1m0s
[07:41:17.443] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:43:0)
[07:41:17.443] Timer tick, asking Buildbucket for the build status
[07:41:17.512] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:17.512] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:44:0) after 1m0s
[07:42:17.644] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:44:0)
[07:42:17.644] Timer tick, asking Buildbucket for the build status
[07:42:17.748] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:17.748] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:45:0) after 1m0s
[07:43:17.965] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:45:0)
[07:43:17.965] Timer tick, asking Buildbucket for the build status
[07:43:18.055] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:18.055] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:46:0) after 1m0s
[07:44:18.233] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:46:0)
[07:44:18.233] Timer tick, asking Buildbucket for the build status
[07:44:18.361] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:18.362] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:47:0) after 1m0s
[07:45:18.476] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:47:0)
[07:45:18.476] Timer tick, asking Buildbucket for the build status
[07:45:18.575] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:18.575] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:48:0) after 1m0s
[07:46:18.759] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:48:0)
[07:46:18.759] Timer tick, asking Buildbucket for the build status
[07:46:18.876] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:18.876] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:49:0) after 1m0s
[07:47:18.945] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:49:0)
[07:47:18.945] Timer tick, asking Buildbucket for the build status
[07:47:19.133] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:19.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:50:0) after 1m0s
[07:48:19.180] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:50:0)
[07:48:19.180] Timer tick, asking Buildbucket for the build status
[07:48:19.268] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:19.268] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:51:0) after 1m0s
[07:49:19.335] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:51:0)
[07:49:19.335] Timer tick, asking Buildbucket for the build status
[07:49:19.410] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:19.410] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:52:0) after 1m0s
[07:50:19.597] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:52:0)
[07:50:19.597] Timer tick, asking Buildbucket for the build status
[07:50:19.696] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:19.701] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:53:0) after 1m0s
[07:51:19.768] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:53:0)
[07:51:19.768] Timer tick, asking Buildbucket for the build status
[07:51:19.930] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:19.930] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:54:0) after 1m0s
[07:52:19.948] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:54:0)
[07:52:19.948] Timer tick, asking Buildbucket for the build status
[07:52:20.125] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:20.125] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:55:0) after 1m0s
[07:53:20.142] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:55:0)
[07:53:20.142] Timer tick, asking Buildbucket for the build status
[07:53:20.216] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:20.216] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:56:0) after 1m0s
[07:54:20.239] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:56:0)
[07:54:20.239] Timer tick, asking Buildbucket for the build status
[07:54:20.325] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:20.325] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:57:0) after 1m0s
[07:55:20.387] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:57:0)
[07:55:20.387] Timer tick, asking Buildbucket for the build status
[07:55:20.491] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:20.491] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:58:0) after 1m0s
[07:56:20.470] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:58:0)
[07:56:20.470] Timer tick, asking Buildbucket for the build status
[07:56:21.018] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:21.018] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:59:0) after 1m0s
[07:57:21.117] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:59:0)
[07:57:21.117] Timer tick, asking Buildbucket for the build status
[07:57:21.205] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:21.205] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:60:0) after 1m0s
[07:58:21.205] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:60:0)
[07:58:21.205] Timer tick, asking Buildbucket for the build status
[07:58:21.428] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:21.428] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:61:0) after 1m0s
[07:59:21.482] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:61:0)
[07:59:21.482] Timer tick, asking Buildbucket for the build status
[07:59:21.636] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:21.636] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:62:0) after 1m0s
[08:00:21.666] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:62:0)
[08:00:21.666] Timer tick, asking Buildbucket for the build status
[08:00:21.878] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:21.878] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:63:0) after 1m0s
[08:01:21.929] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:63:0)
[08:01:21.929] Timer tick, asking Buildbucket for the build status
[08:01:22.221] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:22.221] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:64:0) after 1m0s
[08:02:22.248] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:64:0)
[08:02:22.248] Timer tick, asking Buildbucket for the build status
[08:02:22.335] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:22.335] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:65:0) after 1m0s
[08:03:22.363] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:65:0)
[08:03:22.363] Timer tick, asking Buildbucket for the build status
[08:03:22.452] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:22.452] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:66:0) after 1m0s
[08:04:22.513] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:66:0)
[08:04:22.513] Timer tick, asking Buildbucket for the build status
[08:04:22.583] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:22.583] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:67:0) after 1m0s
[08:05:22.678] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:67:0)
[08:05:22.678] Timer tick, asking Buildbucket for the build status
[08:05:22.896] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:22.896] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:68:0) after 1m0s
[08:06:22.917] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:68:0)
[08:06:22.917] Timer tick, asking Buildbucket for the build status
[08:06:23.207] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:23.207] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:69:0) after 1m0s
[08:07:23.322] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:69:0)
[08:07:23.322] Timer tick, asking Buildbucket for the build status
[08:07:23.447] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:23.447] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:70:0) after 1m0s
[08:08:23.465] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:70:0)
[08:08:23.465] Timer tick, asking Buildbucket for the build status
[08:08:23.531] Build 8855340727919555536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:23.531] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9020770790360291328:71:0) after 1m0s
[08:08:48.334] Received PubSub notification, asking Buildbucket for the build status
[08:08:48.416] Build 8855340727919555536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:08:48.416] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1613290127716535",
"created_by": "project:chromium",
"created_ts": "1613286006964562",
"id": "8855340727919555536",
"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_android27.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-central1-b-8-32-82vq\", \"builder_group\": \"chromium.packager\", \"buildername\": \"android-avd-packager\", \"buildnumber\": 103, \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"f882c8c981ae5f0be0d565449c8aec22bcd08517\", \"got_revision_cp\": \"refs/heads/master@{#853890}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"554b7ee535b3eca3f041dc45d00de5cbd490729c\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#1}\", \"got_webrtc_revision\": \"f28d7e89b7a3c985d83f59d16b9f3c3f45ef7997\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33224}\", \"path_config\": \"generic\", \"recipe\": \"android/avd_packager\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1baf36be136c6e37e5283db20727ce1fca753922b4729c01ac3834cd33897d7d_v2\", \"builder_34caa451ddae04e30af534f20a2f04cca4108dc27b0a92529062f4f2e114a8e9_v2\", \"builder_3ebf4dd509a5805e98d79f047f39cac1c13d897e075c42798b95fb1279fe5d7c_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_53a0dbabd2cebac1b8e64bcbfab336eb0021d1dc40b2d4ba5321eb0a070d508c_v2\", \"builder_7b27d20986eac2bbcc1513665f8799c69dbe398236c21423fd80a70475184e3c_v2\", \"builder_a6d905fb3e765cf980c6458181cc20495ddb667b30aa758edddbed3b46166dd6_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-central1-b-8-32-82vq\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5690-40331dd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-cipd-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613287526538092",
"status": "COMPLETED",
"status_changed_ts": "1613290127950310",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/103",
"builder:android-avd-packager",
"scheduler_invocation_id:9020770790360291328",
"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/8855340727919555536/+/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:51b82878a3275d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613290127954563",
"url": "https://ci.chromium.org/b/8855340727919555536",
"utcnow_ts": "1613290128406439"
}
}
[08:08:48.416] Invocation finished in 1h8m43.162938727s with status SUCCEEDED