[18:33:44.636] New invocation is queued and will start shortly
[18:33:45.727] Starting the invocation (attempt 1)
[18:33:45.799] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:45.799] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:45.799] Buildbucket request:
{
"bucket": "luci.fuchsia.prod",
"client_operation_id": "9019911776591346128",
"parameters_json": "{\"builder_name\":\"gcc-prod-trigger\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"1a48f0027d52f507da1817406279c1b81a6fa262\"},\"id\":\"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@1a48f0027d52f507da1817406279c1b81a6fa262\",\"title\":\"1a48f0027d52f507da1817406279c1b81a6fa262\",\"url\":\"https://gnu.googlesource.com/binutils-gdb/+/1a48f0027d52f507da1817406279c1b81a6fa262\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://gnu.googlesource.com/binutils-gdb\",\"revision\":\"1a48f0027d52f507da1817406279c1b81a6fa262\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc-prod-trigger",
"scheduler_invocation_id:9019911776591346128",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"user_agent:luci-scheduler",
"buildset:commit/git/1a48f0027d52f507da1817406279c1b81a6fa262",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/1a48f0027d52f507da1817406279c1b81a6fa262",
"gitiles_ref:refs/heads/master"
]
}
[18:33:46.583] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"created_by": "project:fuchsia",
"created_ts": "1614105226010016",
"id": "8854481714489555312",
"parameters_json": "{\"builder_name\": \"gcc-prod-trigger\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"1a48f0027d52f507da1817406279c1b81a6fa262\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@1a48f0027d52f507da1817406279c1b81a6fa262\", \"title\": \"1a48f0027d52f507da1817406279c1b81a6fa262\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/1a48f0027d52f507da1817406279c1b81a6fa262\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"1a48f0027d52f507da1817406279c1b81a6fa262\"}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614105226259559",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/1a48f0027d52f507da1817406279c1b81a6fa262",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/1a48f0027d52f507da1817406279c1b81a6fa262",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019911776591346128",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8854481714489555312/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:toolchain_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1614105226259669",
"url": "https://ci.chromium.org/b/8854481714489555312",
"utcnow_ts": "1614105226576643"
}
}
[18:33:46.583] Task URL: https://ci.chromium.org/b/8854481714489555312
[18:33:46.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:2:0) after 1m0s
[18:33:54.289] Received PubSub notification, asking Buildbucket for the build status
[18:33:54.458] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:46.604] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:2:0)
[18:34:46.604] Timer tick, asking Buildbucket for the build status
[18:34:46.798] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:46.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:4:0) after 1m0s
[18:35:46.920] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:4:0)
[18:35:46.920] Timer tick, asking Buildbucket for the build status
[18:35:46.981] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:46.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:5:0) after 1m0s
[18:36:47.061] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:5:0)
[18:36:47.061] Timer tick, asking Buildbucket for the build status
[18:36:47.186] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:47.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:6:0) after 1m0s
[18:37:47.385] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:6:0)
[18:37:47.385] Timer tick, asking Buildbucket for the build status
[18:37:47.452] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:47.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:7:0) after 1m0s
[18:38:47.542] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:7:0)
[18:38:47.542] Timer tick, asking Buildbucket for the build status
[18:38:47.619] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:47.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:8:0) after 1m0s
[18:39:47.752] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:8:0)
[18:39:47.752] Timer tick, asking Buildbucket for the build status
[18:39:47.834] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:47.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:9:0) after 1m0s
[18:40:47.866] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:9:0)
[18:40:47.866] Timer tick, asking Buildbucket for the build status
[18:40:47.953] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:47.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:10:0) after 1m0s
[18:41:48.116] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:10:0)
[18:41:48.116] Timer tick, asking Buildbucket for the build status
[18:41:48.424] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:48.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:11:0) after 1m0s
[18:42:48.464] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:11:0)
[18:42:48.464] Timer tick, asking Buildbucket for the build status
[18:42:48.596] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:48.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:12:0) after 1m0s
[18:43:48.724] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:12:0)
[18:43:48.724] Timer tick, asking Buildbucket for the build status
[18:43:48.846] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:48.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:13:0) after 1m0s
[18:44:48.879] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:13:0)
[18:44:48.879] Timer tick, asking Buildbucket for the build status
[18:44:49.043] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:49.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:14:0) after 1m0s
[18:45:49.070] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:14:0)
[18:45:49.070] Timer tick, asking Buildbucket for the build status
[18:45:49.158] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:49.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:15:0) after 1m0s
[18:46:49.174] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:15:0)
[18:46:49.174] Timer tick, asking Buildbucket for the build status
[18:46:49.340] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:49.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:16:0) after 1m0s
[18:47:49.456] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:16:0)
[18:47:49.456] Timer tick, asking Buildbucket for the build status
[18:47:49.543] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:49.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:17:0) after 1m0s
[18:48:49.690] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:17:0)
[18:48:49.690] Timer tick, asking Buildbucket for the build status
[18:48:49.783] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:49.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:18:0) after 1m0s
[18:49:49.853] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:18:0)
[18:49:49.853] Timer tick, asking Buildbucket for the build status
[18:49:49.934] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:49.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:19:0) after 1m0s
[18:50:49.940] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:19:0)
[18:50:49.940] Timer tick, asking Buildbucket for the build status
[18:50:50.010] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:50.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:20:0) after 1m0s
[18:51:49.991] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:20:0)
[18:51:49.991] Timer tick, asking Buildbucket for the build status
[18:51:50.065] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:50.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:21:0) after 1m0s
[18:52:50.114] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:21:0)
[18:52:50.114] Timer tick, asking Buildbucket for the build status
[18:52:50.487] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:50.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:22:0) after 1m0s
[18:53:50.496] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:22:0)
[18:53:50.496] Timer tick, asking Buildbucket for the build status
[18:53:50.611] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:50.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:23:0) after 1m0s
[18:54:50.664] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:23:0)
[18:54:50.664] Timer tick, asking Buildbucket for the build status
[18:54:50.931] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:50.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:24:0) after 1m0s
[18:55:50.968] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:24:0)
[18:55:50.968] Timer tick, asking Buildbucket for the build status
[18:55:51.265] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:51.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:25:0) after 1m0s
[18:56:51.308] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:25:0)
[18:56:51.308] Timer tick, asking Buildbucket for the build status
[18:56:51.671] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:51.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:26:0) after 1m0s
[18:57:51.669] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:26:0)
[18:57:51.669] Timer tick, asking Buildbucket for the build status
[18:57:51.768] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:51.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:27:0) after 1m0s
[18:58:51.811] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:27:0)
[18:58:51.811] Timer tick, asking Buildbucket for the build status
[18:58:51.919] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:51.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:28:0) after 1m0s
[18:59:51.978] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:28:0)
[18:59:51.978] Timer tick, asking Buildbucket for the build status
[18:59:52.168] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:52.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:29:0) after 1m0s
[19:00:52.146] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:29:0)
[19:00:52.146] Timer tick, asking Buildbucket for the build status
[19:00:52.218] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:52.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:30:0) after 1m0s
[19:01:52.266] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:30:0)
[19:01:52.266] Timer tick, asking Buildbucket for the build status
[19:01:52.434] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:52.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:31:0) after 1m0s
[19:02:52.484] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:31:0)
[19:02:52.484] Timer tick, asking Buildbucket for the build status
[19:02:52.643] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:52.643] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:32:0) after 1m0s
[19:03:52.677] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:32:0)
[19:03:52.677] Timer tick, asking Buildbucket for the build status
[19:03:52.771] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:52.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:33:0) after 1m0s
[19:04:52.852] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:33:0)
[19:04:52.852] Timer tick, asking Buildbucket for the build status
[19:04:52.927] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:52.927] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:34:0) after 1m0s
[19:05:53.043] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:34:0)
[19:05:53.043] Timer tick, asking Buildbucket for the build status
[19:05:53.106] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:53.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:35:0) after 1m0s
[19:06:53.127] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:35:0)
[19:06:53.127] Timer tick, asking Buildbucket for the build status
[19:06:53.639] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:53.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:36:0) after 1m0s
[19:07:53.737] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:36:0)
[19:07:53.737] Timer tick, asking Buildbucket for the build status
[19:07:53.851] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:53.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:37:0) after 1m0s
[19:08:53.897] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:37:0)
[19:08:53.897] Timer tick, asking Buildbucket for the build status
[19:08:54.029] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:54.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:38:0) after 1m0s
[19:09:54.090] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:38:0)
[19:09:54.090] Timer tick, asking Buildbucket for the build status
[19:09:54.163] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:54.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:39:0) after 1m0s
[19:10:54.178] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:39:0)
[19:10:54.178] Timer tick, asking Buildbucket for the build status
[19:10:54.310] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:54.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:40:0) after 1m0s
[19:11:54.395] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:40:0)
[19:11:54.396] Timer tick, asking Buildbucket for the build status
[19:11:54.619] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:54.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:41:0) after 1m0s
[19:12:54.644] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:41:0)
[19:12:54.645] Timer tick, asking Buildbucket for the build status
[19:12:54.737] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:54.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:42:0) after 1m0s
[19:13:54.812] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:42:0)
[19:13:54.812] Timer tick, asking Buildbucket for the build status
[19:13:55.165] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:55.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:43:0) after 1m0s
[19:14:55.184] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:43:0)
[19:14:55.184] Timer tick, asking Buildbucket for the build status
[19:14:55.258] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:55.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:44:0) after 1m0s
[19:15:55.283] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:44:0)
[19:15:55.283] Timer tick, asking Buildbucket for the build status
[19:15:55.354] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:55.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:45:0) after 1m0s
[19:16:55.481] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:45:0)
[19:16:55.481] Timer tick, asking Buildbucket for the build status
[19:16:55.573] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:55.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:46:0) after 1m0s
[19:17:55.695] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:46:0)
[19:17:55.695] Timer tick, asking Buildbucket for the build status
[19:17:55.765] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:55.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:47:0) after 1m0s
[19:18:55.865] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:47:0)
[19:18:55.865] Timer tick, asking Buildbucket for the build status
[19:18:55.949] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:55.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:48:0) after 1m0s
[19:19:56.020] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:48:0)
[19:19:56.020] Timer tick, asking Buildbucket for the build status
[19:19:56.091] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:56.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:49:0) after 1m0s
[19:20:56.192] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:49:0)
[19:20:56.192] Timer tick, asking Buildbucket for the build status
[19:20:56.598] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:56.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:50:0) after 1m0s
[19:21:56.673] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:50:0)
[19:21:56.673] Timer tick, asking Buildbucket for the build status
[19:21:56.925] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:56.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:51:0) after 1m0s
[19:22:56.968] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:51:0)
[19:22:56.968] Timer tick, asking Buildbucket for the build status
[19:22:57.033] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:57.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:52:0) after 1m0s
[19:23:57.065] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:52:0)
[19:23:57.065] Timer tick, asking Buildbucket for the build status
[19:23:57.288] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:57.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:53:0) after 1m0s
[19:24:57.332] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:53:0)
[19:24:57.332] Timer tick, asking Buildbucket for the build status
[19:24:57.437] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:57.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:54:0) after 1m0s
[19:25:57.463] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:54:0)
[19:25:57.463] Timer tick, asking Buildbucket for the build status
[19:25:57.614] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:57.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:55:0) after 1m0s
[19:26:57.654] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:55:0)
[19:26:57.654] Timer tick, asking Buildbucket for the build status
[19:26:57.741] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:57.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:56:0) after 1m0s
[19:27:57.736] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:56:0)
[19:27:57.736] Timer tick, asking Buildbucket for the build status
[19:27:57.815] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:57.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:57:0) after 1m0s
[19:28:57.840] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:57:0)
[19:28:57.840] Timer tick, asking Buildbucket for the build status
[19:28:57.954] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:57.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:58:0) after 1m0s
[19:29:57.970] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:58:0)
[19:29:57.970] Timer tick, asking Buildbucket for the build status
[19:29:58.077] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:58.077] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:59:0) after 1m0s
[19:30:58.172] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:59:0)
[19:30:58.172] Timer tick, asking Buildbucket for the build status
[19:30:58.251] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:58.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:60:0) after 1m0s
[19:31:58.284] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:60:0)
[19:31:58.284] Timer tick, asking Buildbucket for the build status
[19:31:58.392] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:58.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:61:0) after 1m0s
[19:32:58.436] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:61:0)
[19:32:58.436] Timer tick, asking Buildbucket for the build status
[19:32:58.512] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:58.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:62:0) after 1m0s
[19:33:58.530] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:62:0)
[19:33:58.530] Timer tick, asking Buildbucket for the build status
[19:33:58.625] Build 8854481714489555312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:58.625] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:9019911776591346128:63:0) after 1m0s
[19:34:19.479] Received PubSub notification, asking Buildbucket for the build status
[19:34:19.796] Build 8854481714489555312: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:34:19.796] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.prod",
"canary_preference": "PROD",
"completed_ts": "1614108858936351",
"created_by": "project:fuchsia",
"created_ts": "1614105226010016",
"id": "8854481714489555312",
"parameters_json": "{\"builder_name\": \"gcc-prod-trigger\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"1a48f0027d52f507da1817406279c1b81a6fa262\"}, \"id\": \"https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@1a48f0027d52f507da1817406279c1b81a6fa262\", \"title\": \"1a48f0027d52f507da1817406279c1b81a6fa262\", \"url\": \"https://gnu.googlesource.com/binutils-gdb/+/1a48f0027d52f507da1817406279c1b81a6fa262\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://gnu.googlesource.com/binutils-gdb\", \"revision\": \"1a48f0027d52f507da1817406279c1b81a6fa262\"}}",
"project": "fuchsia",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder\", \"git\", \"goma_v2\", \"symbol\", \"vpython\"], \"cores\": [\"2\"], \"cpu\": [\"x64\", \"x86\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"fuchsia-infra\"], \"gpu\": [\"none\"], \"id\": [\"fuchsia-prod-e2-2-ssd0-us-central1-a-0-milk\"], \"image\": [\"fuchsia-debian-9-v20210121-f1877f1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-2\"], \"os\": [\"Debian\", \"Debian-9\", \"Debian-9.13\", \"Linux\"], \"pool\": [\"luci.fuchsia.prod\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5706-397711c\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-a\"]}}}",
"service_account": "fuchsia-prod-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1614105233629664",
"status": "COMPLETED",
"status_changed_ts": "1614105234028840",
"tags": [
"builder:gcc-prod-trigger",
"buildset:commit/git/1a48f0027d52f507da1817406279c1b81a6fa262",
"buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/1a48f0027d52f507da1817406279c1b81a6fa262",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019911776591346128",
"scheduler_job_id:fuchsia/gcc-prod-trigger-3e002780",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8854481714489555312/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:os:Debian",
"swarming_tag:recipe_name:toolchain_trigger",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bundles/fuchsia.googlesource.com/infra/recipes",
"swarming_task_id:51e8fcb75ca01110",
"user_agent:luci-scheduler"
],
"updated_ts": "1614108858936605",
"url": "https://ci.chromium.org/b/8854481714489555312",
"utcnow_ts": "1614108859776056"
}
}
[19:34:19.796] Invocation finished in 1h0m35.174045131s with status SUCCEEDED