[07:00:02.452] New invocation is queued and will start shortly
[07:00:03.663] Starting the invocation (attempt 1)
[07:00:03.697] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:00:03.697] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:00:03.697] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022673329609218176",
"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:9022673329609218176",
"scheduler_job_id:chromium/android-avd-packager",
"user_agent:luci-scheduler"
]
}
[07:00:04.507] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611471603801706",
"id": "8857243267531287104",
"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": "1611471604308778",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/98",
"builder:android-avd-packager",
"scheduler_invocation_id:9022673329609218176",
"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/8857243267531287104/+/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": "1611471604311811",
"url": "https://ci.chromium.org/b/8857243267531287104",
"utcnow_ts": "1611471604499461"
}
}
[07:00:04.507] Task URL: https://ci.chromium.org/b/8857243267531287104
[07:00:04.507] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:2:0) after 1m0s
[07:01:04.792] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:2:0)
[07:01:04.792] Timer tick, asking Buildbucket for the build status
[07:01:05.033] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:05.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:3:0) after 1m0s
[07:02:05.162] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:3:0)
[07:02:05.162] Timer tick, asking Buildbucket for the build status
[07:02:05.422] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:05.422] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:4:0) after 1m0s
[07:03:05.481] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:4:0)
[07:03:05.481] Timer tick, asking Buildbucket for the build status
[07:03:05.576] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:05.576] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:5:0) after 1m0s
[07:04:05.785] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:5:0)
[07:04:05.785] Timer tick, asking Buildbucket for the build status
[07:04:05.902] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:05.902] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:6:0) after 1m0s
[07:05:05.966] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:6:0)
[07:05:05.966] Timer tick, asking Buildbucket for the build status
[07:05:06.071] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:06.071] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:7:0) after 1m0s
[07:06:06.166] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:7:0)
[07:06:06.166] Timer tick, asking Buildbucket for the build status
[07:06:06.245] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:06.245] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:8:0) after 1m0s
[07:07:06.428] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:8:0)
[07:07:06.428] Timer tick, asking Buildbucket for the build status
[07:07:06.483] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:06.484] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:9:0) after 1m0s
[07:08:06.512] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:9:0)
[07:08:06.512] Timer tick, asking Buildbucket for the build status
[07:08:06.769] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:06.769] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:10:0) after 1m0s
[07:09:06.822] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:10:0)
[07:09:06.822] Timer tick, asking Buildbucket for the build status
[07:09:06.948] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:06.948] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:11:0) after 1m0s
[07:10:07.079] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:11:0)
[07:10:07.079] Timer tick, asking Buildbucket for the build status
[07:10:07.193] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:07.193] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:12:0) after 1m0s
[07:11:07.185] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:12:0)
[07:11:07.185] Timer tick, asking Buildbucket for the build status
[07:11:07.317] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:07.317] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:13:0) after 1m0s
[07:12:07.338] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:13:0)
[07:12:07.338] Timer tick, asking Buildbucket for the build status
[07:12:07.469] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:07.469] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:14:0) after 1m0s
[07:13:07.499] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:14:0)
[07:13:07.499] Timer tick, asking Buildbucket for the build status
[07:13:07.705] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:07.705] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:15:0) after 1m0s
[07:14:07.820] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:15:0)
[07:14:07.820] Timer tick, asking Buildbucket for the build status
[07:14:07.951] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:07.951] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:16:0) after 1m0s
[07:15:08.083] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:16:0)
[07:15:08.083] Timer tick, asking Buildbucket for the build status
[07:15:08.159] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:08.159] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:17:0) after 1m0s
[07:16:08.243] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:17:0)
[07:16:08.243] Timer tick, asking Buildbucket for the build status
[07:16:08.484] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:08.484] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:18:0) after 1m0s
[07:17:08.539] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:18:0)
[07:17:08.539] Timer tick, asking Buildbucket for the build status
[07:17:08.802] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:08.802] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:19:0) after 1m0s
[07:18:08.824] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:19:0)
[07:18:08.824] Timer tick, asking Buildbucket for the build status
[07:18:08.996] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:08.996] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:20:0) after 1m0s
[07:19:09.055] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:20:0)
[07:19:09.056] Timer tick, asking Buildbucket for the build status
[07:19:09.131] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:09.131] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:21:0) after 1m0s
[07:20:09.152] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:21:0)
[07:20:09.152] Timer tick, asking Buildbucket for the build status
[07:20:09.245] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:09.245] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:22:0) after 1m0s
[07:21:09.268] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:22:0)
[07:21:09.268] Timer tick, asking Buildbucket for the build status
[07:21:09.506] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:09.506] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:23:0) after 1m0s
[07:22:09.497] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:23:0)
[07:22:09.497] Timer tick, asking Buildbucket for the build status
[07:22:09.564] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:09.564] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:24:0) after 1m0s
[07:23:09.586] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:24:0)
[07:23:09.586] Timer tick, asking Buildbucket for the build status
[07:23:09.695] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:09.695] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:25:0) after 1m0s
[07:24:09.756] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:25:0)
[07:24:09.756] Timer tick, asking Buildbucket for the build status
[07:24:09.831] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:09.831] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:26:0) after 1m0s
[07:25:09.877] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:26:0)
[07:25:09.877] Timer tick, asking Buildbucket for the build status
[07:25:09.964] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:09.964] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:27:0) after 1m0s
[07:26:09.945] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:27:0)
[07:26:09.945] Timer tick, asking Buildbucket for the build status
[07:26:10.138] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:26:10.138] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:28:0) after 1m0s
[07:27:10.158] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:28:0)
[07:27:10.158] Timer tick, asking Buildbucket for the build status
[07:27:10.224] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:27:10.224] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:29:0) after 1m0s
[07:28:10.308] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:29:0)
[07:28:10.308] Timer tick, asking Buildbucket for the build status
[07:28:10.373] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:28:10.373] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:30:0) after 1m0s
[07:29:10.393] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:30:0)
[07:29:10.393] Timer tick, asking Buildbucket for the build status
[07:29:10.472] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:29:10.472] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:31:0) after 1m0s
[07:30:10.504] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:31:0)
[07:30:10.504] Timer tick, asking Buildbucket for the build status
[07:30:10.874] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:30:10.874] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:32:0) after 1m0s
[07:31:10.900] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:32:0)
[07:31:10.900] Timer tick, asking Buildbucket for the build status
[07:31:10.965] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:31:10.965] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:33:0) after 1m0s
[07:32:10.992] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:33:0)
[07:32:10.992] Timer tick, asking Buildbucket for the build status
[07:32:11.084] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:32:11.084] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:34:0) after 1m0s
[07:33:11.105] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:34:0)
[07:33:11.105] Timer tick, asking Buildbucket for the build status
[07:33:11.182] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:33:11.182] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:35:0) after 1m0s
[07:34:11.200] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:35:0)
[07:34:11.200] Timer tick, asking Buildbucket for the build status
[07:34:11.271] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:34:11.271] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:36:0) after 1m0s
[07:35:11.361] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:36:0)
[07:35:11.361] Timer tick, asking Buildbucket for the build status
[07:35:11.466] Build 8857243267531287104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:11.466] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:37:0) after 1m0s
[07:35:32.566] Received PubSub notification, asking Buildbucket for the build status
[07:35:32.758] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:11.493] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:37:0)
[07:36:11.493] Timer tick, asking Buildbucket for the build status
[07:36:11.676] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:11.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:39:0) after 1m0s
[07:37:11.707] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:39:0)
[07:37:11.707] Timer tick, asking Buildbucket for the build status
[07:37:11.779] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:11.779] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:40:0) after 1m0s
[07:38:11.798] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:40:0)
[07:38:11.798] Timer tick, asking Buildbucket for the build status
[07:38:11.885] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:11.885] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:41:0) after 1m0s
[07:39:11.965] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:41:0)
[07:39:11.965] Timer tick, asking Buildbucket for the build status
[07:39:12.062] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:12.062] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:42:0) after 1m0s
[07:40:12.127] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:42:0)
[07:40:12.127] Timer tick, asking Buildbucket for the build status
[07:40:12.209] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:12.209] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:43:0) after 1m0s
[07:41:12.263] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:43:0)
[07:41:12.263] Timer tick, asking Buildbucket for the build status
[07:41:12.315] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:12.315] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:44:0) after 1m0s
[07:42:12.428] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:44:0)
[07:42:12.428] Timer tick, asking Buildbucket for the build status
[07:42:12.737] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:12.737] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:45:0) after 1m0s
[07:43:12.751] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:45:0)
[07:43:12.751] Timer tick, asking Buildbucket for the build status
[07:43:12.805] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:12.805] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:46:0) after 1m0s
[07:44:12.819] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:46:0)
[07:44:12.819] Timer tick, asking Buildbucket for the build status
[07:44:12.888] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:12.888] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:47:0) after 1m0s
[07:45:12.985] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:47:0)
[07:45:12.986] Timer tick, asking Buildbucket for the build status
[07:45:13.214] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:13.215] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:48:0) after 1m0s
[07:46:13.262] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:48:0)
[07:46:13.262] Timer tick, asking Buildbucket for the build status
[07:46:13.361] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:13.361] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:49:0) after 1m0s
[07:47:13.386] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:49:0)
[07:47:13.386] Timer tick, asking Buildbucket for the build status
[07:47:13.453] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:13.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:50:0) after 1m0s
[07:48:13.491] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:50:0)
[07:48:13.491] Timer tick, asking Buildbucket for the build status
[07:48:13.550] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:13.550] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:51:0) after 1m0s
[07:49:13.576] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:51:0)
[07:49:13.576] Timer tick, asking Buildbucket for the build status
[07:49:13.633] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:13.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:52:0) after 1m0s
[07:50:13.642] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:52:0)
[07:50:13.642] Timer tick, asking Buildbucket for the build status
[07:50:13.881] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:13.881] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:53:0) after 1m0s
[07:51:13.902] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:53:0)
[07:51:13.902] Timer tick, asking Buildbucket for the build status
[07:51:13.984] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:13.984] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:54:0) after 1m0s
[07:52:14.015] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:54:0)
[07:52:14.015] Timer tick, asking Buildbucket for the build status
[07:52:14.075] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:14.075] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:55:0) after 1m0s
[07:53:14.099] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:55:0)
[07:53:14.099] Timer tick, asking Buildbucket for the build status
[07:53:14.432] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:14.432] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:56:0) after 1m0s
[07:54:14.449] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:56:0)
[07:54:14.449] Timer tick, asking Buildbucket for the build status
[07:54:14.518] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:14.518] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:57:0) after 1m0s
[07:55:14.574] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:57:0)
[07:55:14.574] Timer tick, asking Buildbucket for the build status
[07:55:14.754] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:14.754] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:58:0) after 1m0s
[07:56:14.738] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:58:0)
[07:56:14.738] Timer tick, asking Buildbucket for the build status
[07:56:14.843] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:14.843] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:59:0) after 1m0s
[07:57:14.869] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:59:0)
[07:57:14.869] Timer tick, asking Buildbucket for the build status
[07:57:14.921] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:14.921] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:60:0) after 1m0s
[07:58:14.986] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:60:0)
[07:58:14.986] Timer tick, asking Buildbucket for the build status
[07:58:15.041] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:15.041] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:61:0) after 1m0s
[07:59:15.065] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:61:0)
[07:59:15.065] Timer tick, asking Buildbucket for the build status
[07:59:15.217] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:15.217] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:62:0) after 1m0s
[08:00:15.239] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:62:0)
[08:00:15.239] Timer tick, asking Buildbucket for the build status
[08:00:15.479] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:15.479] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:63:0) after 1m0s
[08:01:15.509] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:63:0)
[08:01:15.509] Timer tick, asking Buildbucket for the build status
[08:01:15.574] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:15.574] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:64:0) after 1m0s
[08:02:15.598] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:64:0)
[08:02:15.598] Timer tick, asking Buildbucket for the build status
[08:02:15.653] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:15.653] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:65:0) after 1m0s
[08:03:15.685] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:65:0)
[08:03:15.685] Timer tick, asking Buildbucket for the build status
[08:03:15.752] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:15.752] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:66:0) after 1m0s
[08:04:15.748] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:66:0)
[08:04:15.748] Timer tick, asking Buildbucket for the build status
[08:04:15.884] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:15.884] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:67:0) after 1m0s
[08:05:15.949] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:67:0)
[08:05:15.949] Timer tick, asking Buildbucket for the build status
[08:05:16.051] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:16.051] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:68:0) after 1m0s
[08:06:16.072] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:68:0)
[08:06:16.072] Timer tick, asking Buildbucket for the build status
[08:06:16.137] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:16.137] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:69:0) after 1m0s
[08:07:16.167] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:69:0)
[08:07:16.167] Timer tick, asking Buildbucket for the build status
[08:07:16.244] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:16.244] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:70:0) after 1m0s
[08:08:16.284] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:70:0)
[08:08:16.284] Timer tick, asking Buildbucket for the build status
[08:08:16.350] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:16.350] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:71:0) after 1m0s
[08:09:16.356] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:71:0)
[08:09:16.356] Timer tick, asking Buildbucket for the build status
[08:09:16.512] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:16.512] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:72:0) after 1m0s
[08:10:16.561] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:72:0)
[08:10:16.561] Timer tick, asking Buildbucket for the build status
[08:10:16.680] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:16.680] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:73:0) after 1m0s
[08:11:16.703] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:73:0)
[08:11:16.703] Timer tick, asking Buildbucket for the build status
[08:11:16.767] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:16.767] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:74:0) after 1m0s
[08:12:16.813] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:74:0)
[08:12:16.813] Timer tick, asking Buildbucket for the build status
[08:12:16.895] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:16.895] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:75:0) after 1m0s
[08:13:16.876] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:75:0)
[08:13:16.876] Timer tick, asking Buildbucket for the build status
[08:13:16.927] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:16.927] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:76:0) after 1m0s
[08:14:16.980] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:76:0)
[08:14:16.980] Timer tick, asking Buildbucket for the build status
[08:14:17.083] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:17.083] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:77:0) after 1m0s
[08:15:23.069] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:77:0)
[08:15:23.069] Timer tick, asking Buildbucket for the build status
[08:15:23.195] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:23.195] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:78:0) after 1m0s
[08:16:23.170] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:78:0)
[08:16:23.170] Timer tick, asking Buildbucket for the build status
[08:16:23.222] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:23.222] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:79:0) after 1m0s
[08:17:23.222] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:79:0)
[08:17:23.222] Timer tick, asking Buildbucket for the build status
[08:17:23.275] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:23.275] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:80:0) after 1m0s
[08:18:23.358] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:80:0)
[08:18:23.358] Timer tick, asking Buildbucket for the build status
[08:18:23.430] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:23.430] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:81:0) after 1m0s
[08:19:23.483] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:81:0)
[08:19:23.483] Timer tick, asking Buildbucket for the build status
[08:19:23.775] Build 8857243267531287104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:23.775] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9022673329609218176:82:0) after 1m0s
[08:20:10.353] Received PubSub notification, asking Buildbucket for the build status
[08:20:10.503] Build 8857243267531287104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:20:10.503] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611476409595121",
"created_by": "project:chromium",
"created_ts": "1611471603801706",
"id": "8857243267531287104",
"parameters_json": "{\"builder_name\": \"android-avd-packager\", \"properties\": {}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"avd_configs\": [\"tools/android/avd/proto/creation/generic_android23.textpb\", \"tools/android/avd/proto/creation/generic_android27.textpb\", \"tools/android/avd/proto/creation/generic_android28.textpb\", \"tools/android/avd/proto/creation/generic_android29.textpb\", \"tools/android/avd/proto/creation/generic_android30.textpb\", \"tools/android/avd/proto/creation/generic_playstore_android28.textpb\", \"tools/android/avd/proto/creation/generic_playstore_android30.textpb\"], \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-30-6ksf\", \"builder_group\": \"chromium.packager\", \"buildername\": \"android-avd-packager\", \"buildnumber\": 98, \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"4f5dd68d9edfd7315745b1c7400211596c8941e8\", \"got_revision_cp\": \"refs/heads/master@{#846566}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"path_config\": \"generic\", \"recipe\": \"android/avd_packager\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_151f80801b9f29276c11c5f05dab27ce5673f9c9ba442aaf2cf20aab9215f522_v2\", \"builder_158d0f10fa5d87b7b90de0551ce233e88e46559602c021beec49ce89728c3318_v2\", \"builder_1bdcc2c1d38386cf72a342843f986ec12bfab33633905ef2709b97cea999d0f5_v2\", \"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_33498e86c0181efb6d75c3f73059d9522f6d5d2b3b1c7a2dc2a1c6b75cd205cd_v2\", \"builder_eb75bb97ad9a82e6e51bc242be3605b13562b7c1ffaba99d94159b9b70e03009_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-30-6ksf\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-cipd-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611473730996931",
"status": "COMPLETED",
"status_changed_ts": "1611476410007692",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/98",
"builder:android-avd-packager",
"scheduler_invocation_id:9022673329609218176",
"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/8857243267531287104/+/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:514c02d4aa63bb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611476410012223",
"url": "https://ci.chromium.org/b/8857243267531287104",
"utcnow_ts": "1611476410495134"
}
}
[08:20:10.503] Invocation finished in 1h20m8.067137056s with status SUCCEEDED