[07:00:03.346] New invocation is queued and will start shortly
[07:00:04.802] Starting the invocation (attempt 1)
[07:00:04.843] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:00:04.844] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:00:04.844] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018868256082625424",
"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:9018868256082625424",
"scheduler_job_id:chromium/android-avd-packager",
"user_agent:luci-scheduler"
]
}
[07:00:06.424] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615100404941696",
"id": "8853438193747180672",
"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": "1615100405809416",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/106",
"builder:android-avd-packager",
"scheduler_invocation_id:9018868256082625424",
"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/8853438193747180672/+/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": "1615100405810152",
"url": "https://ci.chromium.org/b/8853438193747180672",
"utcnow_ts": "1615100406397844"
}
}
[07:00:06.424] Task URL: https://ci.chromium.org/b/8853438193747180672
[07:00:06.424] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:2:0) after 1m0s
[07:01:06.730] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:2:0)
[07:01:06.730] Timer tick, asking Buildbucket for the build status
[07:01:06.793] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:06.793] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:3:0) after 1m0s
[07:02:06.867] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:3:0)
[07:02:06.867] Timer tick, asking Buildbucket for the build status
[07:02:07.201] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:07.201] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:4:0) after 1m0s
[07:03:07.505] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:4:0)
[07:03:07.505] Timer tick, asking Buildbucket for the build status
[07:03:07.556] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:07.556] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:5:0) after 1m0s
[07:04:07.622] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:5:0)
[07:04:07.622] Timer tick, asking Buildbucket for the build status
[07:04:07.754] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:07.754] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:6:0) after 1m0s
[07:05:07.884] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:6:0)
[07:05:07.884] Timer tick, asking Buildbucket for the build status
[07:05:07.955] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:07.955] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:7:0) after 1m0s
[07:06:08.043] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:7:0)
[07:06:08.044] Timer tick, asking Buildbucket for the build status
[07:06:08.141] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:08.141] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:8:0) after 1m0s
[07:07:08.226] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:8:0)
[07:07:08.226] Timer tick, asking Buildbucket for the build status
[07:07:08.296] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:08.296] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:9:0) after 1m0s
[07:08:08.333] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:9:0)
[07:08:08.333] Timer tick, asking Buildbucket for the build status
[07:08:08.549] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:08.549] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:10:0) after 1m0s
[07:09:08.542] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:10:0)
[07:09:08.542] Timer tick, asking Buildbucket for the build status
[07:09:08.647] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:08.647] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:11:0) after 1m0s
[07:10:08.681] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:11:0)
[07:10:08.681] Timer tick, asking Buildbucket for the build status
[07:10:08.805] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:08.805] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:12:0) after 1m0s
[07:11:08.843] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:12:0)
[07:11:08.843] Timer tick, asking Buildbucket for the build status
[07:11:08.923] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:08.923] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:13:0) after 1m0s
[07:12:08.992] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:13:0)
[07:12:08.992] Timer tick, asking Buildbucket for the build status
[07:12:09.145] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:09.145] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:14:0) after 1m0s
[07:13:09.237] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:14:0)
[07:13:09.237] Timer tick, asking Buildbucket for the build status
[07:13:09.291] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:09.291] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:15:0) after 1m0s
[07:14:09.405] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:15:0)
[07:14:09.405] Timer tick, asking Buildbucket for the build status
[07:14:09.472] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:09.472] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:16:0) after 1m0s
[07:15:09.491] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:16:0)
[07:15:09.491] Timer tick, asking Buildbucket for the build status
[07:15:09.887] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:09.887] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:17:0) after 1m0s
[07:16:10.055] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:17:0)
[07:16:10.055] Timer tick, asking Buildbucket for the build status
[07:16:10.321] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:10.321] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:18:0) after 1m0s
[07:17:10.341] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:18:0)
[07:17:10.341] Timer tick, asking Buildbucket for the build status
[07:17:10.418] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:10.418] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:19:0) after 1m0s
[07:18:10.466] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:19:0)
[07:18:10.466] Timer tick, asking Buildbucket for the build status
[07:18:10.540] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:10.540] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:20:0) after 1m0s
[07:19:10.569] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:20:0)
[07:19:10.569] Timer tick, asking Buildbucket for the build status
[07:19:10.633] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:10.634] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:21:0) after 1m0s
[07:20:10.755] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:21:0)
[07:20:10.755] Timer tick, asking Buildbucket for the build status
[07:20:10.833] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:10.833] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:22:0) after 1m0s
[07:21:10.858] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:22:0)
[07:21:10.858] Timer tick, asking Buildbucket for the build status
[07:21:11.022] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:11.022] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:23:0) after 1m0s
[07:22:11.043] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:23:0)
[07:22:11.043] Timer tick, asking Buildbucket for the build status
[07:22:11.111] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:11.111] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:24:0) after 1m0s
[07:23:11.148] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:24:0)
[07:23:11.148] Timer tick, asking Buildbucket for the build status
[07:23:11.215] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:11.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:25:0) after 1m0s
[07:24:11.254] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:25:0)
[07:24:11.254] Timer tick, asking Buildbucket for the build status
[07:24:11.310] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:11.310] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:26:0) after 1m0s
[07:25:11.342] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:26:0)
[07:25:11.342] Timer tick, asking Buildbucket for the build status
[07:25:11.417] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:11.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:27:0) after 1m0s
[07:26:11.467] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:27:0)
[07:26:11.467] Timer tick, asking Buildbucket for the build status
[07:26:11.542] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:11.542] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:28:0) after 1m0s
[07:27:11.697] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:28:0)
[07:27:11.697] Timer tick, asking Buildbucket for the build status
[07:27:11.884] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:11.884] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:29:0) after 1m0s
[07:28:12.002] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:29:0)
[07:28:12.002] Timer tick, asking Buildbucket for the build status
[07:28:12.120] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:12.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:30:0) after 1m0s
[07:29:12.171] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:30:0)
[07:29:12.171] Timer tick, asking Buildbucket for the build status
[07:29:12.228] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:12.228] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:31:0) after 1m0s
[07:30:12.262] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:31:0)
[07:30:12.262] Timer tick, asking Buildbucket for the build status
[07:30:12.381] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:12.381] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:32:0) after 1m0s
[07:31:12.462] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:32:0)
[07:31:12.462] Timer tick, asking Buildbucket for the build status
[07:31:12.624] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:12.624] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:33:0) after 1m0s
[07:32:12.699] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:33:0)
[07:32:12.699] Timer tick, asking Buildbucket for the build status
[07:32:12.943] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:12.943] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:34:0) after 1m0s
[07:33:12.962] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:34:0)
[07:33:12.962] Timer tick, asking Buildbucket for the build status
[07:33:13.049] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:13.049] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:35:0) after 1m0s
[07:34:13.078] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:35:0)
[07:34:13.078] Timer tick, asking Buildbucket for the build status
[07:34:13.143] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:13.143] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:36:0) after 1m0s
[07:35:13.143] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:36:0)
[07:35:13.143] Timer tick, asking Buildbucket for the build status
[07:35:13.222] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:13.222] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:37:0) after 1m0s
[07:36:13.280] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:37:0)
[07:36:13.280] Timer tick, asking Buildbucket for the build status
[07:36:13.345] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:36:13.345] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:38:0) after 1m0s
[07:37:13.368] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:38:0)
[07:37:13.368] Timer tick, asking Buildbucket for the build status
[07:37:13.453] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:37:13.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:39:0) after 1m0s
[07:38:13.504] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:39:0)
[07:38:13.504] Timer tick, asking Buildbucket for the build status
[07:38:13.569] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:38:13.569] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:40:0) after 1m0s
[07:39:13.615] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:40:0)
[07:39:13.615] Timer tick, asking Buildbucket for the build status
[07:39:13.675] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:39:13.675] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:41:0) after 1m0s
[07:40:13.692] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:41:0)
[07:40:13.692] Timer tick, asking Buildbucket for the build status
[07:40:13.763] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:40:13.763] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:42:0) after 1m0s
[07:41:13.811] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:42:0)
[07:41:13.811] Timer tick, asking Buildbucket for the build status
[07:41:13.896] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:41:13.896] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:43:0) after 1m0s
[07:42:13.874] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:43:0)
[07:42:13.874] Timer tick, asking Buildbucket for the build status
[07:42:13.938] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:42:13.938] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:44:0) after 1m0s
[07:43:13.954] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:44:0)
[07:43:13.954] Timer tick, asking Buildbucket for the build status
[07:43:14.017] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:43:14.017] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:45:0) after 1m0s
[07:44:14.039] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:45:0)
[07:44:14.039] Timer tick, asking Buildbucket for the build status
[07:44:14.134] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:44:14.134] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:46:0) after 1m0s
[07:45:14.202] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:46:0)
[07:45:14.202] Timer tick, asking Buildbucket for the build status
[07:45:14.269] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:45:14.269] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:47:0) after 1m0s
[07:46:14.245] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:47:0)
[07:46:14.245] Timer tick, asking Buildbucket for the build status
[07:46:14.312] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:46:14.312] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:48:0) after 1m0s
[07:47:14.333] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:48:0)
[07:47:14.333] Timer tick, asking Buildbucket for the build status
[07:47:14.387] Build 8853438193747180672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:47:14.387] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:49:0) after 1m0s
[07:47:35.754] Received PubSub notification, asking Buildbucket for the build status
[07:47:35.948] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:14.401] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:49:0)
[07:48:14.401] Timer tick, asking Buildbucket for the build status
[07:48:14.537] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:14.537] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:51:0) after 1m0s
[07:49:14.551] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:51:0)
[07:49:14.551] Timer tick, asking Buildbucket for the build status
[07:49:14.642] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:14.642] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:52:0) after 1m0s
[07:50:14.658] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:52:0)
[07:50:14.658] Timer tick, asking Buildbucket for the build status
[07:50:14.758] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:14.758] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:53:0) after 1m0s
[07:51:14.759] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:53:0)
[07:51:14.759] Timer tick, asking Buildbucket for the build status
[07:51:14.813] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:14.813] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:54:0) after 1m0s
[07:52:14.864] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:54:0)
[07:52:14.864] Timer tick, asking Buildbucket for the build status
[07:52:14.954] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:14.954] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:55:0) after 1m0s
[07:53:14.973] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:55:0)
[07:53:14.973] Timer tick, asking Buildbucket for the build status
[07:53:15.039] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:15.039] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:56:0) after 1m0s
[07:54:15.066] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:56:0)
[07:54:15.066] Timer tick, asking Buildbucket for the build status
[07:54:15.310] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:15.310] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:57:0) after 1m0s
[07:55:15.354] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:57:0)
[07:55:15.354] Timer tick, asking Buildbucket for the build status
[07:55:15.541] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:15.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:58:0) after 1m0s
[07:56:15.581] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:58:0)
[07:56:15.581] Timer tick, asking Buildbucket for the build status
[07:56:15.903] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:15.903] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:59:0) after 1m0s
[07:57:16.020] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:59:0)
[07:57:16.020] Timer tick, asking Buildbucket for the build status
[07:57:16.113] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:16.113] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:60:0) after 1m0s
[07:58:16.197] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:60:0)
[07:58:16.197] Timer tick, asking Buildbucket for the build status
[07:58:16.417] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:16.417] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:61:0) after 1m0s
[07:59:16.434] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:61:0)
[07:59:16.434] Timer tick, asking Buildbucket for the build status
[07:59:16.493] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:16.493] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:62:0) after 1m0s
[08:00:16.495] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:62:0)
[08:00:16.495] Timer tick, asking Buildbucket for the build status
[08:00:16.627] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:16.628] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:63:0) after 1m0s
[08:01:16.709] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:63:0)
[08:01:16.709] Timer tick, asking Buildbucket for the build status
[08:01:16.771] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:16.771] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:64:0) after 1m0s
[08:02:16.803] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:64:0)
[08:02:16.803] Timer tick, asking Buildbucket for the build status
[08:02:16.940] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:16.940] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:65:0) after 1m0s
[08:03:16.961] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:65:0)
[08:03:16.961] Timer tick, asking Buildbucket for the build status
[08:03:17.035] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:17.035] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:66:0) after 1m0s
[08:04:17.063] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:66:0)
[08:04:17.063] Timer tick, asking Buildbucket for the build status
[08:04:17.136] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:17.136] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:67:0) after 1m0s
[08:05:17.174] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:67:0)
[08:05:17.174] Timer tick, asking Buildbucket for the build status
[08:05:17.250] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:17.250] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:68:0) after 1m0s
[08:06:17.283] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:68:0)
[08:06:17.283] Timer tick, asking Buildbucket for the build status
[08:06:17.503] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:17.503] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:69:0) after 1m0s
[08:07:17.530] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:69:0)
[08:07:17.530] Timer tick, asking Buildbucket for the build status
[08:07:17.593] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:17.593] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:70:0) after 1m0s
[08:08:17.609] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:70:0)
[08:08:17.609] Timer tick, asking Buildbucket for the build status
[08:08:17.677] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:17.677] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:71:0) after 1m0s
[08:09:18.167] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:71:0)
[08:09:18.167] Timer tick, asking Buildbucket for the build status
[08:09:18.259] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:18.259] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:72:0) after 1m0s
[08:10:18.281] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:72:0)
[08:10:18.281] Timer tick, asking Buildbucket for the build status
[08:10:18.377] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:18.377] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:73:0) after 1m0s
[08:11:18.524] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:73:0)
[08:11:18.524] Timer tick, asking Buildbucket for the build status
[08:11:18.644] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:18.644] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:74:0) after 1m0s
[08:12:18.789] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:74:0)
[08:12:18.789] Timer tick, asking Buildbucket for the build status
[08:12:18.854] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:18.854] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:75:0) after 1m0s
[08:13:19.016] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:75:0)
[08:13:19.016] Timer tick, asking Buildbucket for the build status
[08:13:19.084] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:19.084] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:76:0) after 1m0s
[08:14:19.678] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:76:0)
[08:14:19.678] Timer tick, asking Buildbucket for the build status
[08:14:19.851] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:19.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:77:0) after 1m0s
[08:15:19.862] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:77:0)
[08:15:19.862] Timer tick, asking Buildbucket for the build status
[08:15:19.937] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:19.937] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:78:0) after 1m0s
[08:16:19.961] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:78:0)
[08:16:19.961] Timer tick, asking Buildbucket for the build status
[08:16:20.153] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:20.153] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:79:0) after 1m0s
[08:17:20.182] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:79:0)
[08:17:20.182] Timer tick, asking Buildbucket for the build status
[08:17:20.900] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:20.900] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:80:0) after 1m0s
[08:18:20.916] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:80:0)
[08:18:20.916] Timer tick, asking Buildbucket for the build status
[08:18:21.130] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:21.130] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:81:0) after 1m0s
[08:19:21.154] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:81:0)
[08:19:21.154] Timer tick, asking Buildbucket for the build status
[08:19:21.248] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:21.248] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:82:0) after 1m0s
[08:20:21.247] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:82:0)
[08:20:21.247] Timer tick, asking Buildbucket for the build status
[08:20:21.319] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:21.320] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:83:0) after 1m0s
[08:21:21.376] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:83:0)
[08:21:21.376] Timer tick, asking Buildbucket for the build status
[08:21:21.633] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:21.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:84:0) after 1m0s
[08:22:21.686] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:84:0)
[08:22:21.686] Timer tick, asking Buildbucket for the build status
[08:22:21.809] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:21.809] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:85:0) after 1m0s
[08:23:21.917] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:85:0)
[08:23:21.917] Timer tick, asking Buildbucket for the build status
[08:23:22.126] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:22.126] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:86:0) after 1m0s
[08:24:22.146] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:86:0)
[08:24:22.146] Timer tick, asking Buildbucket for the build status
[08:24:22.431] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:22.432] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:87:0) after 1m0s
[08:25:22.471] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:87:0)
[08:25:22.471] Timer tick, asking Buildbucket for the build status
[08:25:22.565] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:22.565] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:88:0) after 1m0s
[08:26:22.587] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:88:0)
[08:26:22.587] Timer tick, asking Buildbucket for the build status
[08:26:22.665] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:22.665] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:89:0) after 1m0s
[08:27:22.715] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:89:0)
[08:27:22.715] Timer tick, asking Buildbucket for the build status
[08:27:22.931] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:22.931] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:90:0) after 1m0s
[08:28:22.997] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:90:0)
[08:28:22.997] Timer tick, asking Buildbucket for the build status
[08:28:23.129] Build 8853438193747180672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:23.129] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9018868256082625424:91:0) after 1m0s
[08:29:09.498] Received PubSub notification, asking Buildbucket for the build status
[08:29:09.596] Build 8853438193747180672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:29:09.596] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615105748468151",
"created_by": "project:chromium",
"created_ts": "1615100404941696",
"id": "8853438193747180672",
"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-25-xygg\", \"builder_group\": \"chromium.packager\", \"buildername\": \"android-avd-packager\", \"buildnumber\": 106, \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"39deb4da7f8d18d5a91fcfeb8131a9906253455a\", \"got_revision_cp\": \"refs/heads/master@{#860577}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"f8b883fbba96b155409eb00da93826216bea9fc0\", \"got_v8_revision_cp\": \"refs/heads/9.1.60@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"path_config\": \"generic\", \"recipe\": \"android/avd_packager\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4026221a727cf2e856e94035b8eeb49c4c65d62e8974e693170551540c198531_v2\", \"builder_70ef596996d367c7f68cf89eddb06eadb0885858105f6ae42320cb486b0e9d9f_v2\", \"builder_9d891a598cf8ebf0ae5ddfdbe2fc0861535c9a49467e7412fefe4675a15b7f18_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-25-xygg\"], \"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\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-cipd-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615103255099011",
"status": "COMPLETED",
"status_changed_ts": "1615105748977859",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/106",
"builder:android-avd-packager",
"scheduler_invocation_id:9018868256082625424",
"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/8853438193747180672/+/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:52244dfb64fd7910",
"user_agent:luci-scheduler"
],
"updated_ts": "1615105748988577",
"url": "https://ci.chromium.org/b/8853438193747180672",
"utcnow_ts": "1615105749589971"
}
}
[08:29:09.596] Invocation finished in 1h29m6.266649067s with status SUCCEEDED