[07:00:03.114] New invocation is queued and will start shortly
[07:00:05.328] Starting the invocation (attempt 1)
[07:00:05.362] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:00:05.362] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:00:05.363] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028380937798925488",
"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:9028380937798925488",
"scheduler_job_id:chromium/android-avd-packager",
"user_agent:luci-scheduler"
]
}
[07:00:06.668] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606028405469452",
"id": "8862950874665270800",
"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": "1606028406432681",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/87",
"builder:android-avd-packager",
"scheduler_invocation_id:9028380937798925488",
"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/8862950874665270800/+/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": "1606028406432831",
"url": "https://ci.chromium.org/b/8862950874665270800",
"utcnow_ts": "1606028406643889"
}
}
[07:00:06.668] Task URL: https://ci.chromium.org/b/8862950874665270800
[07:00:06.668] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:2:0) after 1m0s
[07:01:07.378] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:2:0)
[07:01:07.378] Timer tick, asking Buildbucket for the build status
[07:01:07.554] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:01:07.554] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:3:0) after 1m0s
[07:02:08.443] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:3:0)
[07:02:08.443] Timer tick, asking Buildbucket for the build status
[07:02:08.710] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:02:08.710] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:4:0) after 1m0s
[07:03:08.785] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:4:0)
[07:03:08.785] Timer tick, asking Buildbucket for the build status
[07:03:08.911] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:03:08.911] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:5:0) after 1m0s
[07:04:08.984] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:5:0)
[07:04:08.984] Timer tick, asking Buildbucket for the build status
[07:04:09.057] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:04:09.057] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:6:0) after 1m0s
[07:05:09.085] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:6:0)
[07:05:09.085] Timer tick, asking Buildbucket for the build status
[07:05:09.165] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:05:09.165] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:7:0) after 1m0s
[07:06:09.262] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:7:0)
[07:06:09.262] Timer tick, asking Buildbucket for the build status
[07:06:09.353] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:06:09.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:8:0) after 1m0s
[07:07:09.386] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:8:0)
[07:07:09.386] Timer tick, asking Buildbucket for the build status
[07:07:09.639] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:07:09.639] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:9:0) after 1m0s
[07:08:09.644] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:9:0)
[07:08:09.644] Timer tick, asking Buildbucket for the build status
[07:08:09.834] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:08:09.834] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:10:0) after 1m0s
[07:09:09.873] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:10:0)
[07:09:09.873] Timer tick, asking Buildbucket for the build status
[07:09:09.970] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:09:09.970] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:11:0) after 1m0s
[07:10:11.703] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:11:0)
[07:10:11.703] Timer tick, asking Buildbucket for the build status
[07:10:11.913] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:10:11.913] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:12:0) after 1m0s
[07:11:11.915] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:12:0)
[07:11:11.915] Timer tick, asking Buildbucket for the build status
[07:11:12.127] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:11:12.127] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:13:0) after 1m0s
[07:12:12.188] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:13:0)
[07:12:12.188] Timer tick, asking Buildbucket for the build status
[07:12:12.398] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:12:12.398] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:14:0) after 1m0s
[07:13:12.485] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:14:0)
[07:13:12.485] Timer tick, asking Buildbucket for the build status
[07:13:12.569] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:13:12.569] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:15:0) after 1m0s
[07:14:12.619] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:15:0)
[07:14:12.619] Timer tick, asking Buildbucket for the build status
[07:14:12.704] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:14:12.704] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:16:0) after 1m0s
[07:15:12.778] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:16:0)
[07:15:12.778] Timer tick, asking Buildbucket for the build status
[07:15:12.893] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:15:12.894] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:17:0) after 1m0s
[07:16:13.051] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:17:0)
[07:16:13.051] Timer tick, asking Buildbucket for the build status
[07:16:13.123] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:16:13.123] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:18:0) after 1m0s
[07:17:13.248] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:18:0)
[07:17:13.248] Timer tick, asking Buildbucket for the build status
[07:17:13.428] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:13.428] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:19:0) after 1m0s
[07:18:13.498] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:19:0)
[07:18:13.498] Timer tick, asking Buildbucket for the build status
[07:18:13.578] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:13.578] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:20:0) after 1m0s
[07:19:13.680] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:20:0)
[07:19:13.680] Timer tick, asking Buildbucket for the build status
[07:19:13.779] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:13.779] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:21:0) after 1m0s
[07:20:14.037] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:21:0)
[07:20:14.037] Timer tick, asking Buildbucket for the build status
[07:20:14.128] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:14.128] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:22:0) after 1m0s
[07:21:14.259] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:22:0)
[07:21:14.259] Timer tick, asking Buildbucket for the build status
[07:21:14.416] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:14.416] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:23:0) after 1m0s
[07:22:14.437] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:23:0)
[07:22:14.437] Timer tick, asking Buildbucket for the build status
[07:22:14.508] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:14.508] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:24:0) after 1m0s
[07:23:14.822] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:24:0)
[07:23:14.822] Timer tick, asking Buildbucket for the build status
[07:23:14.890] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:23:14.890] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:25:0) after 1m0s
[07:24:14.976] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:25:0)
[07:24:14.976] Timer tick, asking Buildbucket for the build status
[07:24:15.178] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:24:15.178] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:26:0) after 1m0s
[07:25:15.277] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:26:0)
[07:25:15.277] Timer tick, asking Buildbucket for the build status
[07:25:15.377] Build 8862950874665270800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:25:15.377] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:27:0) after 1m0s
[07:26:11.726] Received PubSub notification, asking Buildbucket for the build status
[07:26:11.807] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:15.498] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:27:0)
[07:26:15.498] Timer tick, asking Buildbucket for the build status
[07:26:15.579] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:15.579] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:29:0) after 1m0s
[07:27:15.634] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:29:0)
[07:27:15.634] Timer tick, asking Buildbucket for the build status
[07:27:15.743] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:15.743] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:30:0) after 1m0s
[07:28:15.780] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:30:0)
[07:28:15.780] Timer tick, asking Buildbucket for the build status
[07:28:16.031] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:16.031] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:31:0) after 1m0s
[07:29:16.101] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:31:0)
[07:29:16.101] Timer tick, asking Buildbucket for the build status
[07:29:16.244] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:16.244] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:32:0) after 1m0s
[07:30:16.357] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:32:0)
[07:30:16.357] Timer tick, asking Buildbucket for the build status
[07:30:16.693] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:16.693] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:33:0) after 1m0s
[07:31:16.768] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:33:0)
[07:31:16.768] Timer tick, asking Buildbucket for the build status
[07:31:16.834] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:16.834] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:34:0) after 1m0s
[07:32:16.841] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:34:0)
[07:32:16.841] Timer tick, asking Buildbucket for the build status
[07:32:16.936] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:16.936] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:35:0) after 1m0s
[07:33:17.020] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:35:0)
[07:33:17.020] Timer tick, asking Buildbucket for the build status
[07:33:17.309] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:17.309] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:36:0) after 1m0s
[07:34:17.344] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:36:0)
[07:34:17.344] Timer tick, asking Buildbucket for the build status
[07:34:17.404] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:17.404] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:37:0) after 1m0s
[07:35:17.446] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:37:0)
[07:35:17.446] Timer tick, asking Buildbucket for the build status
[07:35:17.541] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:17.541] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:38:0) after 1m0s
[07:36:17.573] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:38:0)
[07:36:17.573] Timer tick, asking Buildbucket for the build status
[07:36:17.673] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:17.673] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:39:0) after 1m0s
[07:37:17.696] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:39:0)
[07:37:17.696] Timer tick, asking Buildbucket for the build status
[07:37:17.755] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:17.755] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:40:0) after 1m0s
[07:38:17.818] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:40:0)
[07:38:17.818] Timer tick, asking Buildbucket for the build status
[07:38:17.888] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:17.888] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:41:0) after 1m0s
[07:39:17.953] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:41:0)
[07:39:17.953] Timer tick, asking Buildbucket for the build status
[07:39:18.034] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:18.034] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:42:0) after 1m0s
[07:40:18.149] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:42:0)
[07:40:18.149] Timer tick, asking Buildbucket for the build status
[07:40:18.263] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:18.263] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:43:0) after 1m0s
[07:41:20.009] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:43:0)
[07:41:20.009] Timer tick, asking Buildbucket for the build status
[07:41:20.104] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:20.104] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:44:0) after 1m0s
[07:42:20.450] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:44:0)
[07:42:20.450] Timer tick, asking Buildbucket for the build status
[07:42:20.515] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:20.515] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:45:0) after 1m0s
[07:43:20.653] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:45:0)
[07:43:20.653] Timer tick, asking Buildbucket for the build status
[07:43:20.881] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:20.881] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:46:0) after 1m0s
[07:44:23.651] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:46:0)
[07:44:23.651] Timer tick, asking Buildbucket for the build status
[07:44:23.755] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:23.755] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:47:0) after 1m0s
[07:45:23.849] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:47:0)
[07:45:23.849] Timer tick, asking Buildbucket for the build status
[07:45:23.949] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:23.949] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:48:0) after 1m0s
[07:46:24.032] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:48:0)
[07:46:24.032] Timer tick, asking Buildbucket for the build status
[07:46:24.096] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:24.096] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:49:0) after 1m0s
[07:47:24.125] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:49:0)
[07:47:24.125] Timer tick, asking Buildbucket for the build status
[07:47:24.187] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:24.187] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:50:0) after 1m0s
[07:48:24.208] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:50:0)
[07:48:24.208] Timer tick, asking Buildbucket for the build status
[07:48:24.390] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:24.390] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:51:0) after 1m0s
[07:49:24.386] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:51:0)
[07:49:24.386] Timer tick, asking Buildbucket for the build status
[07:49:24.493] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:24.493] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:52:0) after 1m0s
[07:50:24.580] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:52:0)
[07:50:24.580] Timer tick, asking Buildbucket for the build status
[07:50:24.900] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:24.900] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:53:0) after 1m0s
[07:51:24.954] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:53:0)
[07:51:24.954] Timer tick, asking Buildbucket for the build status
[07:51:25.027] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:25.027] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:54:0) after 1m0s
[07:52:25.088] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:54:0)
[07:52:25.088] Timer tick, asking Buildbucket for the build status
[07:52:25.367] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:25.367] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:55:0) after 1m0s
[07:53:25.386] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:55:0)
[07:53:25.386] Timer tick, asking Buildbucket for the build status
[07:53:25.451] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:25.451] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:56:0) after 1m0s
[07:54:25.485] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:56:0)
[07:54:25.485] Timer tick, asking Buildbucket for the build status
[07:54:25.566] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:25.566] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:57:0) after 1m0s
[07:55:25.618] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:57:0)
[07:55:25.618] Timer tick, asking Buildbucket for the build status
[07:55:25.732] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:25.732] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:58:0) after 1m0s
[07:56:25.752] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:58:0)
[07:56:25.752] Timer tick, asking Buildbucket for the build status
[07:56:25.808] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:25.808] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:59:0) after 1m0s
[07:57:25.822] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:59:0)
[07:57:25.822] Timer tick, asking Buildbucket for the build status
[07:57:26.245] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:26.245] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:60:0) after 1m0s
[07:58:26.311] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:60:0)
[07:58:26.311] Timer tick, asking Buildbucket for the build status
[07:58:26.364] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:26.364] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:61:0) after 1m0s
[07:59:26.336] Handling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:61:0)
[07:59:26.336] Timer tick, asking Buildbucket for the build status
[07:59:26.438] Build 8862950874665270800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:26.438] Scheduling timer "check-buildbucket-build-status" (chromium/android-avd-packager:9028380937798925488:62:0) after 1m0s
[08:00:00.183] Received PubSub notification, asking Buildbucket for the build status
[08:00:00.297] Build 8862950874665270800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:00:00.297] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606031999721600",
"created_by": "project:chromium",
"created_ts": "1606028405469452",
"id": "8862950874665270800",
"parameters_json": "{\"builder_name\": \"android-avd-packager\", \"properties\": {}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"avd_configs\": [\"tools/android/avd/proto/creation/generic_android23.textpb\", \"tools/android/avd/proto/creation/generic_android28.textpb\", \"tools/android/avd/proto/creation/generic_android29.textpb\", \"tools/android/avd/proto/creation/generic_android30.textpb\", \"tools/android/avd/proto/creation/generic_playstore_android28.textpb\", \"tools/android/avd/proto/creation/generic_playstore_android30.textpb\"], \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-51-7iaq\", \"buildername\": \"android-avd-packager\", \"buildnumber\": 87, \"got_angle_revision\": \"ebf890fcf9f3e297dcb0736b31bce3e63d63c4d7\", \"got_dawn_revision\": \"546a7c98c89718f2dadf1ae07088fa7cf2ccb53f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"5a96f755c666427bee5c9dc021d25d0949fa5330\", \"got_revision_cp\": \"refs/heads/master@{#830027}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"805e8b5987e5fd07adbe998f0c21f84cb20468b8\", \"got_v8_revision_cp\": \"refs/heads/8.9.32@{#1}\", \"got_webrtc_revision\": \"7d75f2ca782f38006bb65bc67533e582190454e8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32659}\", \"path_config\": \"generic\", \"recipe\": \"android/avd_packager\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_4bb8df61718ee3173d82ea540402eadde21707c87b43c35a861cfb17546437c1_v2\", \"builder_61893a5c8ae9b884bb20ec7e5a61377525a63e6bcd3c290923a393e48475e916_v2\", \"builder_75bb63162a23d4b1c0ff37321417ec47e6c57c7535580350abbb418df2b18732_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_e952ecb177c3aa407c442a905d84d05be4034274537a5522f4d6fcc7004f535c_v2\", \"builder_f4b8f4f6129cb4df136e456be7e1c63050d36a7ff0d881dcaa5efbe04f243905_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-51-7iaq\"], \"image\": [\"chrome-xenial-20111600-a937a23cefb\"], \"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\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-cipd-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606029971115210",
"status": "COMPLETED",
"status_changed_ts": "1606031999975767",
"tags": [
"build_address:luci.chromium.ci/android-avd-packager/87",
"builder:android-avd-packager",
"scheduler_invocation_id:9028380937798925488",
"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/8862950874665270800/+/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:5007923e23b8b610",
"user_agent:luci-scheduler"
],
"updated_ts": "1606031999980596",
"url": "https://ci.chromium.org/b/8862950874665270800",
"utcnow_ts": "1606032000271268"
}
}
[08:00:00.297] Invocation finished in 59m57.200872067s with status SUCCEEDED