[19:18:18.740] New invocation is queued and will start shortly
[19:18:18.740] Triggered by user:joshuaseaton@google.com
[19:18:19.894] Starting the invocation (attempt 1)
[19:18:19.952] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:18:19.952] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:18:19.952] Buildbucket request:
{
"bucket": "luci.fuchsia.ci",
"client_operation_id": "9063395516468058272",
"parameters_json": "{\"builder_name\":\"fastboot-mac-x64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"id\":\"d8d7e0c5f6aa1bc5\",\"title\":\"Triggered via web UI\",\"webui\":{}}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:fastboot-mac-x64",
"scheduler_invocation_id:9063395516468058272",
"scheduler_job_id:fuchsia/ci-fastboot-mac-x64",
"user_agent:luci-scheduler"
]
}
[19:18:23.055] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:fuchsia",
"created_ts": "1572635902515562",
"id": "8897965451842818800",
"parameters_json": "{\"builder_name\": \"fastboot-mac-x64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"id\": \"d8d7e0c5f6aa1bc5\", \"title\": \"Triggered via web UI\", \"webui\": {}}]}}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572635902827270",
"tags": [
"builder:fastboot-mac-x64",
"scheduler_invocation_id:9063395516468058272",
"scheduler_job_id:fuchsia/ci-fastboot-mac-x64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8897965451842818800/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:fastboot",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1572635902827416",
"url": "https://ci.chromium.org/b/8897965451842818800",
"utcnow_ts": "1572635903048485"
}
}
[19:18:23.055] Task URL: https://ci.chromium.org/b/8897965451842818800
[19:18:23.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:2:0) after 1m0s
[19:19:08.255] Received PubSub notification, asking Buildbucket for the build status
[19:19:08.783] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:23.116] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:2:0)
[19:19:23.116] Timer tick, asking Buildbucket for the build status
[19:19:23.361] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:23.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:4:0) after 1m0s
[19:20:23.446] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:4:0)
[19:20:23.446] Timer tick, asking Buildbucket for the build status
[19:20:24.031] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:24.031] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:5:0) after 1m0s
[19:21:24.121] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:5:0)
[19:21:24.121] Timer tick, asking Buildbucket for the build status
[19:21:24.703] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:24.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:6:0) after 1m0s
[19:22:24.842] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:6:0)
[19:22:24.842] Timer tick, asking Buildbucket for the build status
[19:22:25.066] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:25.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:7:0) after 1m0s
[19:23:25.184] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:7:0)
[19:23:25.184] Timer tick, asking Buildbucket for the build status
[19:23:26.506] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:26.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:8:0) after 1m0s
[19:24:26.662] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:8:0)
[19:24:26.662] Timer tick, asking Buildbucket for the build status
[19:24:26.984] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:26.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:9:0) after 1m0s
[19:25:27.031] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:9:0)
[19:25:27.031] Timer tick, asking Buildbucket for the build status
[19:25:28.046] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:28.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:10:0) after 1m0s
[19:26:28.094] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:10:0)
[19:26:28.094] Timer tick, asking Buildbucket for the build status
[19:26:28.277] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:28.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:11:0) after 1m0s
[19:27:28.299] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:11:0)
[19:27:28.299] Timer tick, asking Buildbucket for the build status
[19:27:28.623] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:28.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:12:0) after 1m0s
[19:28:28.770] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:12:0)
[19:28:28.770] Timer tick, asking Buildbucket for the build status
[19:28:28.950] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:28.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:13:0) after 1m0s
[19:29:29.148] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:13:0)
[19:29:29.148] Timer tick, asking Buildbucket for the build status
[19:29:29.786] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:29.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:14:0) after 1m0s
[19:30:29.809] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:14:0)
[19:30:29.809] Timer tick, asking Buildbucket for the build status
[19:30:29.900] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:29.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:15:0) after 1m0s
[19:31:30.073] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:15:0)
[19:31:30.073] Timer tick, asking Buildbucket for the build status
[19:31:30.279] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:30.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:16:0) after 1m0s
[19:32:30.367] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:16:0)
[19:32:30.367] Timer tick, asking Buildbucket for the build status
[19:32:30.587] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:30.587] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:17:0) after 1m0s
[19:33:30.856] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:17:0)
[19:33:30.857] Timer tick, asking Buildbucket for the build status
[19:33:31.134] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:31.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:18:0) after 1m0s
[19:34:31.367] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:18:0)
[19:34:31.367] Timer tick, asking Buildbucket for the build status
[19:34:33.325] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:33.325] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:19:0) after 1m0s
[19:35:33.432] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:19:0)
[19:35:33.432] Timer tick, asking Buildbucket for the build status
[19:35:33.649] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:33.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:20:0) after 1m0s
[19:36:33.787] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:20:0)
[19:36:33.787] Timer tick, asking Buildbucket for the build status
[19:36:34.018] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:34.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:21:0) after 1m0s
[19:37:34.177] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:21:0)
[19:37:34.177] Timer tick, asking Buildbucket for the build status
[19:37:34.506] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:34.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:22:0) after 1m0s
[19:38:34.575] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:22:0)
[19:38:34.575] Timer tick, asking Buildbucket for the build status
[19:38:34.916] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:34.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:23:0) after 1m0s
[19:39:35.131] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:23:0)
[19:39:35.131] Timer tick, asking Buildbucket for the build status
[19:39:35.273] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:35.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:24:0) after 1m0s
[19:40:35.363] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:24:0)
[19:40:35.363] Timer tick, asking Buildbucket for the build status
[19:40:35.703] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:35.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:25:0) after 1m0s
[19:41:35.837] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:25:0)
[19:41:35.837] Timer tick, asking Buildbucket for the build status
[19:41:36.089] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:36.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:26:0) after 1m0s
[19:42:36.460] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:26:0)
[19:42:36.460] Timer tick, asking Buildbucket for the build status
[19:42:37.772] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:37.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:27:0) after 1m0s
[19:43:37.947] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:27:0)
[19:43:37.947] Timer tick, asking Buildbucket for the build status
[19:43:38.110] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:38.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:28:0) after 1m0s
[19:44:38.277] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:28:0)
[19:44:38.277] Timer tick, asking Buildbucket for the build status
[19:44:38.449] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:38.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:29:0) after 1m0s
[19:45:39.033] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:29:0)
[19:45:39.033] Timer tick, asking Buildbucket for the build status
[19:45:40.018] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:40.018] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:30:0) after 1m0s
[19:46:40.105] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:30:0)
[19:46:40.105] Timer tick, asking Buildbucket for the build status
[19:46:41.658] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:41.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:31:0) after 1m0s
[19:47:42.027] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:31:0)
[19:47:42.027] Timer tick, asking Buildbucket for the build status
[19:47:42.191] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:42.191] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:32:0) after 1m0s
[19:48:42.216] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:32:0)
[19:48:42.216] Timer tick, asking Buildbucket for the build status
[19:48:42.510] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:42.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:33:0) after 1m0s
[19:49:42.571] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:33:0)
[19:49:42.571] Timer tick, asking Buildbucket for the build status
[19:49:43.044] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:43.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:34:0) after 1m0s
[19:50:43.049] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:34:0)
[19:50:43.049] Timer tick, asking Buildbucket for the build status
[19:50:43.194] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:43.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:35:0) after 1m0s
[19:51:43.246] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:35:0)
[19:51:43.246] Timer tick, asking Buildbucket for the build status
[19:51:44.147] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:44.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:36:0) after 1m0s
[19:52:44.228] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:36:0)
[19:52:44.228] Timer tick, asking Buildbucket for the build status
[19:52:44.396] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:44.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:37:0) after 1m0s
[19:53:44.454] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:37:0)
[19:53:44.454] Timer tick, asking Buildbucket for the build status
[19:53:44.569] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:44.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:38:0) after 1m0s
[19:54:44.726] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:38:0)
[19:54:44.726] Timer tick, asking Buildbucket for the build status
[19:54:45.028] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:45.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:39:0) after 1m0s
[19:55:45.182] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:39:0)
[19:55:45.182] Timer tick, asking Buildbucket for the build status
[19:55:45.518] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:45.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:40:0) after 1m0s
[19:56:45.645] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:40:0)
[19:56:45.645] Timer tick, asking Buildbucket for the build status
[19:56:45.748] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:45.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:41:0) after 1m0s
[19:57:45.902] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:41:0)
[19:57:45.902] Timer tick, asking Buildbucket for the build status
[19:57:46.330] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:46.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:42:0) after 1m0s
[19:58:46.393] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:42:0)
[19:58:46.393] Timer tick, asking Buildbucket for the build status
[19:58:46.883] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:46.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:43:0) after 1m0s
[19:59:46.945] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:43:0)
[19:59:46.945] Timer tick, asking Buildbucket for the build status
[19:59:47.649] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:47.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:44:0) after 1m0s
[20:00:47.711] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:44:0)
[20:00:47.711] Timer tick, asking Buildbucket for the build status
[20:00:48.817] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:48.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:45:0) after 1m0s
[20:01:48.884] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:45:0)
[20:01:48.884] Timer tick, asking Buildbucket for the build status
[20:01:49.597] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:49.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:46:0) after 1m0s
[20:02:49.952] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:46:0)
[20:02:49.952] Timer tick, asking Buildbucket for the build status
[20:02:50.222] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:50.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:47:0) after 1m0s
[20:03:50.227] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:47:0)
[20:03:50.227] Timer tick, asking Buildbucket for the build status
[20:03:50.339] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:50.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:48:0) after 1m0s
[20:04:50.575] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:48:0)
[20:04:50.575] Timer tick, asking Buildbucket for the build status
[20:04:50.873] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:50.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:49:0) after 1m0s
[20:05:51.153] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:49:0)
[20:05:51.153] Timer tick, asking Buildbucket for the build status
[20:05:52.872] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:52.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:50:0) after 1m0s
[20:06:53.081] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:50:0)
[20:06:53.081] Timer tick, asking Buildbucket for the build status
[20:06:53.956] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:53.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:51:0) after 1m0s
[20:07:54.142] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:51:0)
[20:07:54.143] Timer tick, asking Buildbucket for the build status
[20:07:54.287] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:54.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:52:0) after 1m0s
[20:08:54.477] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:52:0)
[20:08:54.477] Timer tick, asking Buildbucket for the build status
[20:08:56.273] Build 8897965451842818800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:56.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fastboot-mac-x64:9063395516468058272:53:0) after 1m0s
[20:09:50.657] Received PubSub notification, asking Buildbucket for the build status
[20:09:50.988] Build 8897965451842818800: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:09:50.988] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1572638990097448",
"created_by": "project:fuchsia",
"created_ts": "1572635902515562",
"failure_reason": "BUILD_FAILURE",
"id": "8897965451842818800",
"parameters_json": "{\"builder_name\": \"fastboot-mac-x64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"id\": \"d8d7e0c5f6aa1bc5\", \"title\": \"Triggered via web UI\", \"webui\": {}}]}}}",
"project": "fuchsia",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"CIPD('register fuchsia/third_party/fastboot/${platform}') failed with: \\\"user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com\\\" has no required WRITER role in prefix \\\"fuchsia/third_party/fastboot/mac-amd64\\\"\"}, \"properties\": {\"$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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"id\": \"d8d7e0c5f6aa1bc5\", \"title\": \"Triggered via web UI\", \"webui\": {}}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"build354-a9\", \"buildername\": \"fastboot-mac-x64\", \"mastername\": \"fuchsia\", \"path_config\": \"generic\", \"recipe\": \"fastboot\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\", \"symbol\", \"vpython\"], \"cores\": [\"4\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-i7-4578U\"], \"gce\": [\"0\"], \"gpu\": [\"8086\", \"8086:0a2e\"], \"hidpi\": [\"0\"], \"id\": [\"build354-a9\"], \"mac_model\": [\"Macmini7,1\"], \"machine_type\": [\"n1-standard-4\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\"], \"pool\": [\"luci.fuchsia.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4621-f8a5b1c\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-mtv\", \"us-mtv-chops\", \"us-mtv-chops-a9\"]}}, \"ui\": {\"info\": \"CIPD('register fuchsia/third_party/fastboot/${platform}') failed with: \\\"user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com\\\" has no required WRITER role in prefix \\\"fuchsia/third_party/fastboot/mac-amd64\\\"\"}}",
"service_account": "fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1572635944854747",
"status": "COMPLETED",
"status_changed_ts": "1572635947870881",
"tags": [
"builder:fastboot-mac-x64",
"scheduler_invocation_id:9063395516468058272",
"scheduler_job_id:fuchsia/ci-fastboot-mac-x64",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8897965451842818800/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:fastboot",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:4841398cb44c0810",
"user_agent:luci-scheduler"
],
"updated_ts": "1572638990187793",
"url": "https://ci.chromium.org/b/8897965451842818800",
"utcnow_ts": "1572638990978605"
}
}
[20:09:50.988] Invocation finished in 51m32.267972451s with status FAILED