[21:02:54.691] New invocation is queued and will start shortly
[21:02:55.815] Starting the invocation (attempt 1)
[21:02:55.856] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:02:55.858] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:02:55.858] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9044997751476511872",
"parameters_json": "{\"builder_name\":\"Linux CFI\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0a7274d4f41f27675b67991b839fca7584f1991c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux CFI",
"scheduler_invocation_id:9044997751476511872",
"scheduler_job_id:chromium/Linux CFI",
"user_agent:luci-scheduler",
"buildset:commit/git/0a7274d4f41f27675b67991b839fca7584f1991c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a7274d4f41f27675b67991b839fca7584f1991c",
"gitiles_ref:refs/heads/master"
]
}
[21:02:56.521] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1590181375974517",
"id": "8879567689465176800",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a7274d4f41f27675b67991b839fca7584f1991c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590181376407152",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/17297",
"builder:Linux CFI",
"buildset:commit/git/0a7274d4f41f27675b67991b839fca7584f1991c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a7274d4f41f27675b67991b839fca7584f1991c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044997751476511872",
"scheduler_job_id:chromium/Linux CFI",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879567689465176800/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1590181376407269",
"url": "https://ci.chromium.org/b/8879567689465176800",
"utcnow_ts": "1590181376512770"
}
}
[21:02:56.521] Task URL: https://ci.chromium.org/b/8879567689465176800
[21:02:56.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:2:0) after 1m0s
[21:03:56.835] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:2:0)
[21:03:56.835] Timer tick, asking Buildbucket for the build status
[21:03:57.405] Build 8879567689465176800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:57.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:3:0) after 1m0s
[21:04:57.551] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:3:0)
[21:04:57.551] Timer tick, asking Buildbucket for the build status
[21:04:57.815] Build 8879567689465176800: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:57.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:4:0) after 1m0s
[21:05:40.506] Received PubSub notification, asking Buildbucket for the build status
[21:05:40.872] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:57.825] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:4:0)
[21:05:57.825] Timer tick, asking Buildbucket for the build status
[21:05:58.049] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:58.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:6:0) after 1m0s
[21:06:58.134] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:6:0)
[21:06:58.134] Timer tick, asking Buildbucket for the build status
[21:06:58.281] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:58.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:7:0) after 1m0s
[21:07:58.368] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:7:0)
[21:07:58.368] Timer tick, asking Buildbucket for the build status
[21:07:58.808] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:58.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:8:0) after 1m0s
[21:08:58.878] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:8:0)
[21:08:58.878] Timer tick, asking Buildbucket for the build status
[21:08:59.046] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:59.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:9:0) after 1m0s
[21:09:59.099] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:9:0)
[21:09:59.099] Timer tick, asking Buildbucket for the build status
[21:09:59.432] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:59.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:10:0) after 1m0s
[21:10:59.451] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:10:0)
[21:10:59.451] Timer tick, asking Buildbucket for the build status
[21:10:59.823] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:59.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:11:0) after 1m0s
[21:11:59.901] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:11:0)
[21:11:59.901] Timer tick, asking Buildbucket for the build status
[21:12:00.137] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:00.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:12:0) after 1m0s
[21:13:00.194] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:12:0)
[21:13:00.194] Timer tick, asking Buildbucket for the build status
[21:13:00.457] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:00.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:13:0) after 1m0s
[21:14:00.524] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:13:0)
[21:14:00.524] Timer tick, asking Buildbucket for the build status
[21:14:00.683] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:00.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:14:0) after 1m0s
[21:15:00.692] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:14:0)
[21:15:00.692] Timer tick, asking Buildbucket for the build status
[21:15:01.332] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:01.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:15:0) after 1m0s
[21:16:02.424] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:15:0)
[21:16:02.424] Timer tick, asking Buildbucket for the build status
[21:16:02.515] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:02.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:16:0) after 1m0s
[21:17:02.544] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:16:0)
[21:17:02.544] Timer tick, asking Buildbucket for the build status
[21:17:02.881] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:02.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:17:0) after 1m0s
[21:18:02.871] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:17:0)
[21:18:02.871] Timer tick, asking Buildbucket for the build status
[21:18:03.120] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:03.120] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:18:0) after 1m0s
[21:19:03.147] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:18:0)
[21:19:03.147] Timer tick, asking Buildbucket for the build status
[21:19:03.483] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:03.483] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:19:0) after 1m0s
[21:20:03.574] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:19:0)
[21:20:03.574] Timer tick, asking Buildbucket for the build status
[21:20:04.733] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:04.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:20:0) after 1m0s
[21:21:04.887] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:20:0)
[21:21:04.887] Timer tick, asking Buildbucket for the build status
[21:21:05.111] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:05.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:21:0) after 1m0s
[21:22:05.224] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:21:0)
[21:22:05.224] Timer tick, asking Buildbucket for the build status
[21:22:05.507] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:05.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:22:0) after 1m0s
[21:23:05.585] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:22:0)
[21:23:05.585] Timer tick, asking Buildbucket for the build status
[21:23:05.725] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:05.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:23:0) after 1m0s
[21:24:05.838] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:23:0)
[21:24:05.839] Timer tick, asking Buildbucket for the build status
[21:24:06.251] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:06.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:24:0) after 1m0s
[21:25:06.311] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:24:0)
[21:25:06.311] Timer tick, asking Buildbucket for the build status
[21:25:06.443] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:06.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:25:0) after 1m0s
[21:26:06.529] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:25:0)
[21:26:06.529] Timer tick, asking Buildbucket for the build status
[21:26:06.679] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:06.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:26:0) after 1m0s
[21:27:06.724] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:26:0)
[21:27:06.724] Timer tick, asking Buildbucket for the build status
[21:27:07.626] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:07.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:27:0) after 1m0s
[21:28:07.661] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:27:0)
[21:28:07.661] Timer tick, asking Buildbucket for the build status
[21:28:07.892] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:07.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:28:0) after 1m0s
[21:29:07.937] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:28:0)
[21:29:07.937] Timer tick, asking Buildbucket for the build status
[21:29:08.114] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:08.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:29:0) after 1m0s
[21:30:08.113] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:29:0)
[21:30:08.113] Timer tick, asking Buildbucket for the build status
[21:30:08.232] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:08.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:30:0) after 1m0s
[21:31:08.895] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:30:0)
[21:31:08.895] Timer tick, asking Buildbucket for the build status
[21:31:09.038] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:09.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:31:0) after 1m0s
[21:32:09.228] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:31:0)
[21:32:09.228] Timer tick, asking Buildbucket for the build status
[21:32:09.548] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:09.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:32:0) after 1m0s
[21:33:09.675] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:32:0)
[21:33:09.675] Timer tick, asking Buildbucket for the build status
[21:33:09.865] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:09.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:33:0) after 1m0s
[21:34:09.964] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:33:0)
[21:34:09.964] Timer tick, asking Buildbucket for the build status
[21:34:10.328] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:10.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:34:0) after 1m0s
[21:35:10.627] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:34:0)
[21:35:10.627] Timer tick, asking Buildbucket for the build status
[21:35:11.163] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:11.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:35:0) after 1m0s
[21:36:11.510] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:35:0)
[21:36:11.510] Timer tick, asking Buildbucket for the build status
[21:36:11.855] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:11.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:36:0) after 1m0s
[21:37:11.985] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:36:0)
[21:37:11.985] Timer tick, asking Buildbucket for the build status
[21:37:12.125] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:12.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:37:0) after 1m0s
[21:38:12.288] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:37:0)
[21:38:12.288] Timer tick, asking Buildbucket for the build status
[21:38:12.449] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:12.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:38:0) after 1m0s
[21:39:12.611] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:38:0)
[21:39:12.611] Timer tick, asking Buildbucket for the build status
[21:39:12.753] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:12.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:39:0) after 1m0s
[21:40:12.886] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:39:0)
[21:40:12.886] Timer tick, asking Buildbucket for the build status
[21:40:13.171] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:13.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:40:0) after 1m0s
[21:41:13.185] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:40:0)
[21:41:13.185] Timer tick, asking Buildbucket for the build status
[21:41:13.430] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:13.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:41:0) after 1m0s
[21:42:13.473] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:41:0)
[21:42:13.473] Timer tick, asking Buildbucket for the build status
[21:42:13.623] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:13.623] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:42:0) after 1m0s
[21:43:13.656] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:42:0)
[21:43:13.656] Timer tick, asking Buildbucket for the build status
[21:43:13.966] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:13.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:43:0) after 1m0s
[21:44:14.097] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:43:0)
[21:44:14.097] Timer tick, asking Buildbucket for the build status
[21:44:14.281] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:14.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:44:0) after 1m0s
[21:45:14.342] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:44:0)
[21:45:14.342] Timer tick, asking Buildbucket for the build status
[21:45:14.575] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:14.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:45:0) after 1m0s
[21:46:14.607] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:45:0)
[21:46:14.607] Timer tick, asking Buildbucket for the build status
[21:46:14.776] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:14.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:46:0) after 1m0s
[21:47:14.821] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:46:0)
[21:47:14.821] Timer tick, asking Buildbucket for the build status
[21:47:14.929] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:14.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:47:0) after 1m0s
[21:48:15.119] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:47:0)
[21:48:15.119] Timer tick, asking Buildbucket for the build status
[21:48:15.506] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:15.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:48:0) after 1m0s
[21:49:15.641] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:48:0)
[21:49:15.641] Timer tick, asking Buildbucket for the build status
[21:49:15.735] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:15.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:49:0) after 1m0s
[21:50:15.774] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:49:0)
[21:50:15.774] Timer tick, asking Buildbucket for the build status
[21:50:16.055] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:16.055] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:50:0) after 1m0s
[21:51:16.092] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:50:0)
[21:51:16.092] Timer tick, asking Buildbucket for the build status
[21:51:16.374] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:16.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:51:0) after 1m0s
[21:52:16.399] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:51:0)
[21:52:16.399] Timer tick, asking Buildbucket for the build status
[21:52:16.587] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:16.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:52:0) after 1m0s
[21:53:16.619] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:52:0)
[21:53:16.619] Timer tick, asking Buildbucket for the build status
[21:53:16.754] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:16.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:53:0) after 1m0s
[21:54:16.764] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:53:0)
[21:54:16.764] Timer tick, asking Buildbucket for the build status
[21:54:17.124] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:17.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:54:0) after 1m0s
[21:55:17.156] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:54:0)
[21:55:17.156] Timer tick, asking Buildbucket for the build status
[21:55:17.628] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:17.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:55:0) after 1m0s
[21:56:17.712] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:55:0)
[21:56:17.712] Timer tick, asking Buildbucket for the build status
[21:56:17.996] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:17.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:56:0) after 1m0s
[21:57:18.105] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:56:0)
[21:57:18.105] Timer tick, asking Buildbucket for the build status
[21:57:18.372] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:18.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:57:0) after 1m0s
[21:58:18.573] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:57:0)
[21:58:18.573] Timer tick, asking Buildbucket for the build status
[21:58:18.719] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:18.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:58:0) after 1m0s
[21:59:18.810] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:58:0)
[21:59:18.810] Timer tick, asking Buildbucket for the build status
[21:59:19.070] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:19.070] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:59:0) after 1m0s
[22:00:19.045] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:59:0)
[22:00:19.045] Timer tick, asking Buildbucket for the build status
[22:00:19.194] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:19.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:60:0) after 1m0s
[22:01:19.298] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:60:0)
[22:01:19.298] Timer tick, asking Buildbucket for the build status
[22:01:19.436] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:19.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:61:0) after 1m0s
[22:02:19.462] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:61:0)
[22:02:19.462] Timer tick, asking Buildbucket for the build status
[22:02:19.690] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:19.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:62:0) after 1m0s
[22:03:19.726] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:62:0)
[22:03:19.726] Timer tick, asking Buildbucket for the build status
[22:03:19.910] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:19.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:63:0) after 1m0s
[22:04:19.998] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:63:0)
[22:04:19.998] Timer tick, asking Buildbucket for the build status
[22:04:20.235] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:20.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:64:0) after 1m0s
[22:05:20.264] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:64:0)
[22:05:20.264] Timer tick, asking Buildbucket for the build status
[22:05:20.606] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:20.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:65:0) after 1m0s
[22:06:20.632] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:65:0)
[22:06:20.632] Timer tick, asking Buildbucket for the build status
[22:06:20.714] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:20.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:66:0) after 1m0s
[22:07:20.756] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:66:0)
[22:07:20.756] Timer tick, asking Buildbucket for the build status
[22:07:20.843] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:20.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:67:0) after 1m0s
[22:08:21.474] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:67:0)
[22:08:21.474] Timer tick, asking Buildbucket for the build status
[22:08:21.637] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:21.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:68:0) after 1m0s
[22:09:21.733] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:68:0)
[22:09:21.734] Timer tick, asking Buildbucket for the build status
[22:09:21.864] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:21.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:69:0) after 1m0s
[22:10:21.887] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:69:0)
[22:10:21.887] Timer tick, asking Buildbucket for the build status
[22:10:22.016] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:22.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:70:0) after 1m0s
[22:11:22.144] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:70:0)
[22:11:22.144] Timer tick, asking Buildbucket for the build status
[22:11:22.346] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:22.346] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:71:0) after 1m0s
[22:12:22.436] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:71:0)
[22:12:22.436] Timer tick, asking Buildbucket for the build status
[22:12:22.566] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:22.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:72:0) after 1m0s
[22:13:22.732] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:72:0)
[22:13:22.732] Timer tick, asking Buildbucket for the build status
[22:13:22.848] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:22.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:73:0) after 1m0s
[22:14:22.925] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:73:0)
[22:14:22.925] Timer tick, asking Buildbucket for the build status
[22:14:23.038] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:23.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:74:0) after 1m0s
[22:15:23.072] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:74:0)
[22:15:23.072] Timer tick, asking Buildbucket for the build status
[22:15:23.555] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:23.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:75:0) after 1m0s
[22:16:23.631] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:75:0)
[22:16:23.631] Timer tick, asking Buildbucket for the build status
[22:16:23.847] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:23.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:76:0) after 1m0s
[22:17:23.900] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:76:0)
[22:17:23.900] Timer tick, asking Buildbucket for the build status
[22:17:24.283] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:24.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:77:0) after 1m0s
[22:18:24.329] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:77:0)
[22:18:24.329] Timer tick, asking Buildbucket for the build status
[22:18:24.491] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:24.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:78:0) after 1m0s
[22:19:24.558] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:78:0)
[22:19:24.558] Timer tick, asking Buildbucket for the build status
[22:19:24.706] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:24.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:79:0) after 1m0s
[22:20:24.799] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:79:0)
[22:20:24.799] Timer tick, asking Buildbucket for the build status
[22:20:24.956] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:24.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:80:0) after 1m0s
[22:21:24.982] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:80:0)
[22:21:24.982] Timer tick, asking Buildbucket for the build status
[22:21:25.475] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:25.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:81:0) after 1m0s
[22:22:25.550] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:81:0)
[22:22:25.550] Timer tick, asking Buildbucket for the build status
[22:22:25.872] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:25.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:82:0) after 1m0s
[22:23:25.963] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:82:0)
[22:23:25.963] Timer tick, asking Buildbucket for the build status
[22:23:26.180] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:26.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:83:0) after 1m0s
[22:24:26.252] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:83:0)
[22:24:26.252] Timer tick, asking Buildbucket for the build status
[22:24:26.488] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:26.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:84:0) after 1m0s
[22:25:26.564] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:84:0)
[22:25:26.564] Timer tick, asking Buildbucket for the build status
[22:25:26.708] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:26.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:85:0) after 1m0s
[22:26:26.742] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:85:0)
[22:26:26.742] Timer tick, asking Buildbucket for the build status
[22:26:26.877] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:26.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:86:0) after 1m0s
[22:27:26.941] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:86:0)
[22:27:26.941] Timer tick, asking Buildbucket for the build status
[22:27:27.193] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:27.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:87:0) after 1m0s
[22:28:27.230] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:87:0)
[22:28:27.230] Timer tick, asking Buildbucket for the build status
[22:28:27.829] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:27.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:88:0) after 1m0s
[22:29:27.884] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:88:0)
[22:29:27.884] Timer tick, asking Buildbucket for the build status
[22:29:28.343] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:28.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:89:0) after 1m0s
[22:30:28.377] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:89:0)
[22:30:28.377] Timer tick, asking Buildbucket for the build status
[22:30:28.597] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:28.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:90:0) after 1m0s
[22:31:28.630] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:90:0)
[22:31:28.630] Timer tick, asking Buildbucket for the build status
[22:31:28.778] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:28.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:91:0) after 1m0s
[22:32:28.851] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:91:0)
[22:32:28.852] Timer tick, asking Buildbucket for the build status
[22:32:28.996] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:28.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:92:0) after 1m0s
[22:33:29.058] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:92:0)
[22:33:29.058] Timer tick, asking Buildbucket for the build status
[22:33:29.221] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:29.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:93:0) after 1m0s
[22:34:29.283] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:93:0)
[22:34:29.283] Timer tick, asking Buildbucket for the build status
[22:34:29.683] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:29.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:94:0) after 1m0s
[22:35:29.706] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:94:0)
[22:35:29.706] Timer tick, asking Buildbucket for the build status
[22:35:30.006] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:30.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:95:0) after 1m0s
[22:36:30.031] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:95:0)
[22:36:30.031] Timer tick, asking Buildbucket for the build status
[22:36:30.206] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:30.206] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:96:0) after 1m0s
[22:37:30.240] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:96:0)
[22:37:30.240] Timer tick, asking Buildbucket for the build status
[22:37:30.643] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:30.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:97:0) after 1m0s
[22:38:30.673] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:97:0)
[22:38:30.673] Timer tick, asking Buildbucket for the build status
[22:38:30.803] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:30.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:98:0) after 1m0s
[22:39:30.871] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:98:0)
[22:39:30.871] Timer tick, asking Buildbucket for the build status
[22:39:31.114] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:31.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:99:0) after 1m0s
[22:40:31.146] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:99:0)
[22:40:31.146] Timer tick, asking Buildbucket for the build status
[22:40:32.994] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:32.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:100:0) after 1m0s
[22:41:33.124] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:100:0)
[22:41:33.124] Timer tick, asking Buildbucket for the build status
[22:41:33.251] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:33.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:101:0) after 1m0s
[22:42:33.360] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:101:0)
[22:42:33.360] Timer tick, asking Buildbucket for the build status
[22:42:33.546] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:33.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:102:0) after 1m0s
[22:43:33.591] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:102:0)
[22:43:33.591] Timer tick, asking Buildbucket for the build status
[22:43:33.716] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:33.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:103:0) after 1m0s
[22:44:33.786] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:103:0)
[22:44:33.786] Timer tick, asking Buildbucket for the build status
[22:44:34.064] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:34.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:104:0) after 1m0s
[22:45:34.145] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:104:0)
[22:45:34.145] Timer tick, asking Buildbucket for the build status
[22:45:35.597] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:35.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:105:0) after 1m0s
[22:46:35.671] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:105:0)
[22:46:35.671] Timer tick, asking Buildbucket for the build status
[22:46:36.346] Build 8879567689465176800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:36.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044997751476511872:106:0) after 1m0s
[22:47:09.502] Received PubSub notification, asking Buildbucket for the build status
[22:47:09.876] Build 8879567689465176800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:47:09.876] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1590187628035713",
"created_by": "project:chromium",
"created_ts": "1590181375974517",
"id": "8879567689465176800",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a7274d4f41f27675b67991b839fca7584f1991c\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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}, \"bot_id\": \"luci-chromium-ci-xenial-32-19-uv3s\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux CFI\", \"buildnumber\": 17297, \"got_angle_revision\": \"c9ebf905761c5c4554dc4348666fb7b1ccbddc8c\", \"got_dawn_revision\": \"65ee6497d620110546181c1dfbd0cc3064f9f7f9\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"0a7274d4f41f27675b67991b839fca7584f1991c\", \"got_revision_cp\": \"refs/heads/master@{#771357}\", \"got_swarming_client_revision\": \"7e8636295f4763a5f5aace1e700b17a4af3c81b7\", \"got_swiftshader_revision\": \"cc5cda0f997d8280712920f2749bbdfe7a0f4dc3\", \"got_v8_revision\": \"414d114aaaad4f0cac357eb2705a71e3ca7bf898\", \"got_v8_revision_cp\": \"refs/heads/8.5.34@{#1}\", \"got_webrtc_revision\": \"3cc1a6509bf6143f9d52d76a7648a9c8d3bdccea\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31336}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.memory\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a7274d4f41f27675b67991b839fca7584f1991c\", \"swarm_hashes\": {\"accessibility_unittests\": \"f0153c87620335821a85041d1ace69d867bdbcd4\", \"angle_unittests\": \"94ce9f4dd4c9368eacb4904a4b4e09c64db4a764\", \"app_shell_unittests\": \"53768623b3b1eb9f20daa81807e585cc92f4f6bf\", \"aura_unittests\": \"47643f100f7e1374aed60e3e2683bbc9d5b1ca2d\", \"base_unittests\": \"3da128b36c51971bc2445e9d3e05dc18cda32ed5\", \"base_util_unittests\": \"124339b0d109499d10f64fe041332392f9dfa95c\", \"blink_common_unittests\": \"9a81473fef2cc22f492d35faea4557c94168d906\", \"blink_fuzzer_unittests\": \"a4222782defb1ae988030978d33c571a139a43a6\", \"blink_heap_unittests\": \"1e1e8dd9216ecb3c9c63e9cde382190a1d69f849\", \"blink_platform_unittests\": \"08f598ace0082953db839a78e4394ee0868b2010\", \"blink_unittests\": \"9cad8adf37507d5db89ef5c5e56979c74d93a74a\", \"boringssl_crypto_tests\": \"fca57694a4470968e905eebcf56a61f81dc5b45d\", \"boringssl_ssl_tests\": \"928144690816b20a935e1b49ef509f46a37464e5\", \"browser_tests\": \"274860cdcef0e9c63aac22240f84b13ba9f7fa2d\", \"cacheinvalidation_unittests\": \"05bee409c500620199ee09bde31517da309be76c\", \"capture_unittests\": \"586fc04aca99621f006a7e574cc0115b86d8c42b\", \"cast_unittests\": \"7007f8dd3f826c083be6b8fdbb5a7f6d559525bc\", \"cc_unittests\": \"d34707be83eed6bf0afe63f6692e1f7d4f4424b8\", \"chrome_app_unittests\": \"b5ccd4afce7c40fd40170150e9984c46ced9fb73\", \"chromedriver_unittests\": \"889ff85b5c239bc48d98ceec42033fb529a04d05\", \"components_browsertests\": \"eb23ad3d5877b26b3465610047c36098bdaea7b1\", \"components_unittests\": \"645c6fcb1a4c801843458612fab7c32178016c07\", \"compositor_unittests\": \"16710979d7cb5d0408de01503a42ab45ee7ffb37\", \"content_browsertests\": \"cf02d515d069106cbf9668e39e868d7d009e1e8a\", \"content_unittests\": \"acd5be1f71186f7fae465a57332be82893b17f1c\", \"cronet_tests\": \"bd683ce850a3a5c1086c2ef3ffea6b35859c3f9b\", \"cronet_unittests\": \"ee4919eddc9d07dc3d477f7fe18e9ab1fe8916c6\", \"crypto_unittests\": \"71b635ef54049cd821baab206545f3b6fe56d97b\", \"dbus_unittests\": \"bbc8f1d23dbbf9388c03b3d96d9da36c8c509165\", \"device_unittests\": \"0d35ec6aab025b931be5804b1e80629f4b0d2f6c\", \"display_unittests\": \"d96da5c065d234632dfecf77ed26fa42936c2992\", \"events_unittests\": \"04f1d22c5686105184b6481faad8b6fa469e9a36\", \"extensions_browsertests\": \"2b862f30ae0fc6636c8010a791877490ab2f2343\", \"extensions_unittests\": \"45feb989edede2eb0a4f826f2599508675ce98bc\", \"filesystem_service_unittests\": \"005f110153d8002bbc99fb399d18ccaa507ccee4\", \"gcm_unit_tests\": \"11124f531e50bfb75e2a479388dfd908bb55b9e3\", \"gfx_unittests\": \"e00d1206330c36231e842ec03e7d5801daba0b17\", \"gin_unittests\": \"0755418763f6d0c984449b2b5c6a24ffe1c01cb8\", \"gl_tests\": \"270ce4ca01efb7071d4a3fa53696e72fcf28617e\", \"gl_unittests\": \"3270fe33004d413300d9770469780aba376585c6\", \"google_apis_unittests\": \"64e917f00fdac38995817583c6a7a0de9804d657\", \"gpu_unittests\": \"18bc6fa8fa2bc20afee8eba87521030a6cdafe78\", \"gwp_asan_unittests\": \"7fd61a6b2568d6f57f1bfe1331b4d3dbb03a42cc\", \"headless_browsertests\": \"961003db1fa4961241888551fd9ac4a8a7e69b69\", \"headless_unittests\": \"b1adafd5e99045b06ad49bbbe2459951d11ee748\", \"interactive_ui_tests\": \"52de487cafb73717677fe68a7401893d0fe59e1e\", \"ipc_tests\": \"834421cf688b2b08b8e2c436cd3a42de0da6c7ed\", \"jingle_unittests\": \"be13d35050ce8348ac7d2aa0c80369a19a6796fd\", \"latency_unittests\": \"8f1a742a16f9444e624f492e290e38f1b048be9e\", \"libjingle_xmpp_unittests\": \"0eb9748c1fa7acb75d6f8da09511c6cdeed49bbd\", \"media_blink_unittests\": \"d2e2499b997d34750a9f97f64f38e83121e8fe50\", \"media_unittests\": \"19f6c0893ef59a0d96946337f69ead90dcf7342b\", \"message_center_unittests\": \"d5f6737406614814895bc4f3f4a7f1f7b97e8d07\", \"midi_unittests\": \"1ffc396e8f9808f268c0e337758feeaf81be8fbc\", \"mojo_core_unittests\": \"7237d38ff19fb77d4f0f1aeb19dcde099c1e4dd9\", \"mojo_unittests\": \"eb64c9b0fe27cc113ce22906c1a8a18c589ee51f\", \"nacl_helper_nonsfi_unittests\": \"b08c3eae88a60087a6ed68031cf14a8b4e7a2f70\", \"nacl_loader_unittests\": \"b74942ba0747c85c280aae2cad674958b8fa7bc0\", \"native_theme_unittests\": \"7e0d8b9877e493de7a7851c7d4a456b5bacb26cc\", \"net_unittests\": \"119041445dc492bf7a2b93b0f31388666cdb07fe\", \"pdf_unittests\": \"dd08277c1c2d463e7001e345c150d4286480ce52\", \"perfetto_unittests\": \"d1181d9b9d65077e8cd86200804bf6d297a2cf1e\", \"ppapi_unittests\": \"c278201a9a8306ae770c0ba7362efcaba744b80c\", \"printing_unittests\": \"6625eb646387e11e8317984469985222b87d5807\", \"remoting_unittests\": \"ed2fac9a5fa2849fe36d385e75b733ad6dad58dc\", \"sandbox_linux_unittests\": \"148f50253f412c147bd4bce5fe83b33b3232b97f\", \"service_manager_unittests\": \"619c7ddd287fd5866574768f6c2680ea46e8cc30\", \"services_unittests\": \"6fd6843037cdc72a55335dfee2f073d29933fb1e\", \"shell_dialogs_unittests\": \"89cb40b2c4291c5d9cf698bc0dfe2e82ffb9f4d8\", \"skia_unittests\": \"fc9a97344520c50935797b0490aad226d4ca55da\", \"snapshot_unittests\": \"936d0b10f22803fe07561bc207cd20e7b2760a83\", \"sql_unittests\": \"8d4d53b70d098484d91ee51204ef2c38019d0818\", \"storage_unittests\": \"e3ed9e2228714de486256a4205eaa952ea2c82ed\", \"sync_integration_tests\": \"7e9ab406e2ff64e65ecee18006636df5b3fdd773\", \"traffic_annotation_auditor_unittests\": \"666920733712e4439d07e37db7e34cb72ae8ae05\", \"ui_base_unittests\": \"0803bc68bfa1eb88647ef0f1652bd8d9ca036c74\", \"ui_touch_selection_unittests\": \"b5f7eb1cd5a205ac3d5a886f5f090033ff9983ee\", \"unit_tests\": \"32448c611a22f8ed2878127e46e677180f44d0d3\", \"url_unittests\": \"e829717e4e5a9d5627ac8057c95990c064b87334\", \"views_unittests\": \"ae40af8698759d75490435ab4a62bf2e16891854\", \"viz_unittests\": \"8667cc15dea396c57432c4abf460cabcdd8eb153\", \"vr_common_unittests\": \"4942acc5a947c935c7c688c7b424fc1d3a2b43bb\", \"vr_pixeltests\": \"be8ef3d10d0568aaf0a15a255c491aa047b64d45\", \"wm_unittests\": \"4cd48169e371c394ee841d567439da0ceee8437b\", \"wtf_unittests\": \"7a10fca2259fa089f6dc1a02351655be50bcbfdd\"}, \"swarm_hashes_refs/heads/master(at){#771357}_without_patch\": {\"accessibility_unittests\": \"f0153c87620335821a85041d1ace69d867bdbcd4\", \"angle_unittests\": \"94ce9f4dd4c9368eacb4904a4b4e09c64db4a764\", \"app_shell_unittests\": \"53768623b3b1eb9f20daa81807e585cc92f4f6bf\", \"aura_unittests\": \"47643f100f7e1374aed60e3e2683bbc9d5b1ca2d\", \"base_unittests\": \"3da128b36c51971bc2445e9d3e05dc18cda32ed5\", \"base_util_unittests\": \"124339b0d109499d10f64fe041332392f9dfa95c\", \"blink_common_unittests\": \"9a81473fef2cc22f492d35faea4557c94168d906\", \"blink_fuzzer_unittests\": \"a4222782defb1ae988030978d33c571a139a43a6\", \"blink_heap_unittests\": \"1e1e8dd9216ecb3c9c63e9cde382190a1d69f849\", \"blink_platform_unittests\": \"08f598ace0082953db839a78e4394ee0868b2010\", \"blink_unittests\": \"9cad8adf37507d5db89ef5c5e56979c74d93a74a\", \"boringssl_crypto_tests\": \"fca57694a4470968e905eebcf56a61f81dc5b45d\", \"boringssl_ssl_tests\": \"928144690816b20a935e1b49ef509f46a37464e5\", \"browser_tests\": \"274860cdcef0e9c63aac22240f84b13ba9f7fa2d\", \"cacheinvalidation_unittests\": \"05bee409c500620199ee09bde31517da309be76c\", \"capture_unittests\": \"586fc04aca99621f006a7e574cc0115b86d8c42b\", \"cast_unittests\": \"7007f8dd3f826c083be6b8fdbb5a7f6d559525bc\", \"cc_unittests\": \"d34707be83eed6bf0afe63f6692e1f7d4f4424b8\", \"chrome_app_unittests\": \"b5ccd4afce7c40fd40170150e9984c46ced9fb73\", \"chromedriver_unittests\": \"889ff85b5c239bc48d98ceec42033fb529a04d05\", \"components_browsertests\": \"eb23ad3d5877b26b3465610047c36098bdaea7b1\", \"components_unittests\": \"645c6fcb1a4c801843458612fab7c32178016c07\", \"compositor_unittests\": \"16710979d7cb5d0408de01503a42ab45ee7ffb37\", \"content_browsertests\": \"cf02d515d069106cbf9668e39e868d7d009e1e8a\", \"content_unittests\": \"acd5be1f71186f7fae465a57332be82893b17f1c\", \"cronet_tests\": \"bd683ce850a3a5c1086c2ef3ffea6b35859c3f9b\", \"cronet_unittests\": \"ee4919eddc9d07dc3d477f7fe18e9ab1fe8916c6\", \"crypto_unittests\": \"71b635ef54049cd821baab206545f3b6fe56d97b\", \"dbus_unittests\": \"bbc8f1d23dbbf9388c03b3d96d9da36c8c509165\", \"device_unittests\": \"0d35ec6aab025b931be5804b1e80629f4b0d2f6c\", \"display_unittests\": \"d96da5c065d234632dfecf77ed26fa42936c2992\", \"events_unittests\": \"04f1d22c5686105184b6481faad8b6fa469e9a36\", \"extensions_browsertests\": \"2b862f30ae0fc6636c8010a791877490ab2f2343\", \"extensions_unittests\": \"45feb989edede2eb0a4f826f2599508675ce98bc\", \"filesystem_service_unittests\": \"005f110153d8002bbc99fb399d18ccaa507ccee4\", \"gcm_unit_tests\": \"11124f531e50bfb75e2a479388dfd908bb55b9e3\", \"gfx_unittests\": \"e00d1206330c36231e842ec03e7d5801daba0b17\", \"gin_unittests\": \"0755418763f6d0c984449b2b5c6a24ffe1c01cb8\", \"gl_tests\": \"270ce4ca01efb7071d4a3fa53696e72fcf28617e\", \"gl_unittests\": \"3270fe33004d413300d9770469780aba376585c6\", \"google_apis_unittests\": \"64e917f00fdac38995817583c6a7a0de9804d657\", \"gpu_unittests\": \"18bc6fa8fa2bc20afee8eba87521030a6cdafe78\", \"gwp_asan_unittests\": \"7fd61a6b2568d6f57f1bfe1331b4d3dbb03a42cc\", \"headless_browsertests\": \"961003db1fa4961241888551fd9ac4a8a7e69b69\", \"headless_unittests\": \"b1adafd5e99045b06ad49bbbe2459951d11ee748\", \"interactive_ui_tests\": \"52de487cafb73717677fe68a7401893d0fe59e1e\", \"ipc_tests\": \"834421cf688b2b08b8e2c436cd3a42de0da6c7ed\", \"jingle_unittests\": \"be13d35050ce8348ac7d2aa0c80369a19a6796fd\", \"latency_unittests\": \"8f1a742a16f9444e624f492e290e38f1b048be9e\", \"libjingle_xmpp_unittests\": \"0eb9748c1fa7acb75d6f8da09511c6cdeed49bbd\", \"media_blink_unittests\": \"d2e2499b997d34750a9f97f64f38e83121e8fe50\", \"media_unittests\": \"19f6c0893ef59a0d96946337f69ead90dcf7342b\", \"message_center_unittests\": \"d5f6737406614814895bc4f3f4a7f1f7b97e8d07\", \"midi_unittests\": \"1ffc396e8f9808f268c0e337758feeaf81be8fbc\", \"mojo_core_unittests\": \"7237d38ff19fb77d4f0f1aeb19dcde099c1e4dd9\", \"mojo_unittests\": \"eb64c9b0fe27cc113ce22906c1a8a18c589ee51f\", \"nacl_helper_nonsfi_unittests\": \"b08c3eae88a60087a6ed68031cf14a8b4e7a2f70\", \"nacl_loader_unittests\": \"b74942ba0747c85c280aae2cad674958b8fa7bc0\", \"native_theme_unittests\": \"7e0d8b9877e493de7a7851c7d4a456b5bacb26cc\", \"net_unittests\": \"119041445dc492bf7a2b93b0f31388666cdb07fe\", \"pdf_unittests\": \"dd08277c1c2d463e7001e345c150d4286480ce52\", \"perfetto_unittests\": \"d1181d9b9d65077e8cd86200804bf6d297a2cf1e\", \"ppapi_unittests\": \"c278201a9a8306ae770c0ba7362efcaba744b80c\", \"printing_unittests\": \"6625eb646387e11e8317984469985222b87d5807\", \"remoting_unittests\": \"ed2fac9a5fa2849fe36d385e75b733ad6dad58dc\", \"sandbox_linux_unittests\": \"148f50253f412c147bd4bce5fe83b33b3232b97f\", \"service_manager_unittests\": \"619c7ddd287fd5866574768f6c2680ea46e8cc30\", \"services_unittests\": \"6fd6843037cdc72a55335dfee2f073d29933fb1e\", \"shell_dialogs_unittests\": \"89cb40b2c4291c5d9cf698bc0dfe2e82ffb9f4d8\", \"skia_unittests\": \"fc9a97344520c50935797b0490aad226d4ca55da\", \"snapshot_unittests\": \"936d0b10f22803fe07561bc207cd20e7b2760a83\", \"sql_unittests\": \"8d4d53b70d098484d91ee51204ef2c38019d0818\", \"storage_unittests\": \"e3ed9e2228714de486256a4205eaa952ea2c82ed\", \"sync_integration_tests\": \"7e9ab406e2ff64e65ecee18006636df5b3fdd773\", \"traffic_annotation_auditor_unittests\": \"666920733712e4439d07e37db7e34cb72ae8ae05\", \"ui_base_unittests\": \"0803bc68bfa1eb88647ef0f1652bd8d9ca036c74\", \"ui_touch_selection_unittests\": \"b5f7eb1cd5a205ac3d5a886f5f090033ff9983ee\", \"unit_tests\": \"32448c611a22f8ed2878127e46e677180f44d0d3\", \"url_unittests\": \"e829717e4e5a9d5627ac8057c95990c064b87334\", \"views_unittests\": \"ae40af8698759d75490435ab4a62bf2e16891854\", \"viz_unittests\": \"8667cc15dea396c57432c4abf460cabcdd8eb153\", \"vr_common_unittests\": \"4942acc5a947c935c7c688c7b424fc1d3a2b43bb\", \"vr_pixeltests\": \"be8ef3d10d0568aaf0a15a255c491aa047b64d45\", \"wm_unittests\": \"4cd48169e371c394ee841d567439da0ceee8437b\", \"wtf_unittests\": \"7a10fca2259fa089f6dc1a02351655be50bcbfdd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4546ef179f50eb727f19fe6abd7705a7b8921ff8263e4e2e8e2644dd615ef1a7_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-19-uv3s\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5100-d2625c1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1590181539800648",
"status": "COMPLETED",
"status_changed_ts": "1590187628585472",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/17297",
"builder:Linux CFI",
"buildset:commit/git/0a7274d4f41f27675b67991b839fca7584f1991c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a7274d4f41f27675b67991b839fca7584f1991c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044997751476511872",
"scheduler_job_id:chromium/Linux CFI",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879567689465176800/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4c5703bbbd662b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1590187628616780",
"url": "https://ci.chromium.org/b/8879567689465176800",
"utcnow_ts": "1590187629841687"
}
}
[22:47:09.876] Invocation finished in 1h44m15.20400068s with status SUCCEEDED