[10:01:14.817] New invocation is queued and will start shortly
[10:01:15.971] Starting the invocation (attempt 1)
[10:01:16.024] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:01:16.026] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:01:16.026] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9044948782845263488",
"parameters_json": "{\"builder_name\":\"Linux CFI\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"54f4402907f208e00a3c607cb7840f3931551e83\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux CFI",
"scheduler_invocation_id:9044948782845263488",
"scheduler_job_id:chromium/Linux CFI",
"user_agent:luci-scheduler",
"buildset:commit/git/54f4402907f208e00a3c607cb7840f3931551e83",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54f4402907f208e00a3c607cb7840f3931551e83",
"gitiles_ref:refs/heads/master"
]
}
[10:01:16.791] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1590228076174786",
"id": "8879518720755921904",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54f4402907f208e00a3c607cb7840f3931551e83\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590228076651128",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/17304",
"builder:Linux CFI",
"buildset:commit/git/54f4402907f208e00a3c607cb7840f3931551e83",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54f4402907f208e00a3c607cb7840f3931551e83",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044948782845263488",
"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/8879518720755921904/+/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": "1590228076651246",
"url": "https://ci.chromium.org/b/8879518720755921904",
"utcnow_ts": "1590228076783422"
}
}
[10:01:16.791] Task URL: https://ci.chromium.org/b/8879518720755921904
[10:01:16.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:2:0) after 1m0s
[10:02:16.814] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:2:0)
[10:02:16.814] Timer tick, asking Buildbucket for the build status
[10:02:16.985] Build 8879518720755921904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:16.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:3:0) after 1m0s
[10:03:12.074] Received PubSub notification, asking Buildbucket for the build status
[10:03:12.232] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:17.130] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:3:0)
[10:03:17.130] Timer tick, asking Buildbucket for the build status
[10:03:17.242] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:17.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:5:0) after 1m0s
[10:04:17.288] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:5:0)
[10:04:17.288] Timer tick, asking Buildbucket for the build status
[10:04:17.419] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:17.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:6:0) after 1m0s
[10:05:17.441] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:6:0)
[10:05:17.441] Timer tick, asking Buildbucket for the build status
[10:05:17.780] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:17.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:7:0) after 1m0s
[10:06:17.805] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:7:0)
[10:06:17.805] Timer tick, asking Buildbucket for the build status
[10:06:18.048] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:18.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:8:0) after 1m0s
[10:07:18.213] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:8:0)
[10:07:18.213] Timer tick, asking Buildbucket for the build status
[10:07:18.484] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:18.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:9:0) after 1m0s
[10:08:18.588] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:9:0)
[10:08:18.588] Timer tick, asking Buildbucket for the build status
[10:08:18.757] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:18.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:10:0) after 1m0s
[10:09:18.801] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:10:0)
[10:09:18.802] Timer tick, asking Buildbucket for the build status
[10:09:18.947] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:18.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:11:0) after 1m0s
[10:10:18.986] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:11:0)
[10:10:18.986] Timer tick, asking Buildbucket for the build status
[10:10:19.201] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:19.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:12:0) after 1m0s
[10:11:19.296] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:12:0)
[10:11:19.296] Timer tick, asking Buildbucket for the build status
[10:11:19.688] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:19.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:13:0) after 1m0s
[10:12:19.767] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:13:0)
[10:12:19.767] Timer tick, asking Buildbucket for the build status
[10:12:20.074] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:20.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:14:0) after 1m0s
[10:13:20.144] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:14:0)
[10:13:20.144] Timer tick, asking Buildbucket for the build status
[10:13:20.296] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:20.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:15:0) after 1m0s
[10:14:20.323] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:15:0)
[10:14:20.323] Timer tick, asking Buildbucket for the build status
[10:14:20.435] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:20.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:16:0) after 1m0s
[10:15:20.467] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:16:0)
[10:15:20.467] Timer tick, asking Buildbucket for the build status
[10:15:20.728] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:20.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:17:0) after 1m0s
[10:16:20.828] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:17:0)
[10:16:20.828] Timer tick, asking Buildbucket for the build status
[10:16:21.123] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:21.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:18:0) after 1m0s
[10:17:21.146] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:18:0)
[10:17:21.146] Timer tick, asking Buildbucket for the build status
[10:17:21.398] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:21.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:19:0) after 1m0s
[10:18:21.478] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:19:0)
[10:18:21.478] Timer tick, asking Buildbucket for the build status
[10:18:21.752] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:21.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:20:0) after 1m0s
[10:19:21.874] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:20:0)
[10:19:21.874] Timer tick, asking Buildbucket for the build status
[10:19:21.957] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:21.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:21:0) after 1m0s
[10:20:22.027] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:21:0)
[10:20:22.027] Timer tick, asking Buildbucket for the build status
[10:20:22.206] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:22.206] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:22:0) after 1m0s
[10:21:22.347] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:22:0)
[10:21:22.347] Timer tick, asking Buildbucket for the build status
[10:21:22.496] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:22.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:23:0) after 1m0s
[10:22:22.497] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:23:0)
[10:22:22.497] Timer tick, asking Buildbucket for the build status
[10:22:22.691] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:22.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:24:0) after 1m0s
[10:23:22.719] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:24:0)
[10:23:22.719] Timer tick, asking Buildbucket for the build status
[10:23:23.090] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:23.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:25:0) after 1m0s
[10:24:23.232] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:25:0)
[10:24:23.232] Timer tick, asking Buildbucket for the build status
[10:24:23.371] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:23.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:26:0) after 1m0s
[10:25:23.418] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:26:0)
[10:25:23.418] Timer tick, asking Buildbucket for the build status
[10:25:23.548] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:23.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:27:0) after 1m0s
[10:26:23.524] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:27:0)
[10:26:23.524] Timer tick, asking Buildbucket for the build status
[10:26:23.856] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:23.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:28:0) after 1m0s
[10:27:23.874] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:28:0)
[10:27:23.874] Timer tick, asking Buildbucket for the build status
[10:27:24.022] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:24.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:29:0) after 1m0s
[10:28:24.058] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:29:0)
[10:28:24.058] Timer tick, asking Buildbucket for the build status
[10:28:24.322] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:24.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:30:0) after 1m0s
[10:29:24.344] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:30:0)
[10:29:24.344] Timer tick, asking Buildbucket for the build status
[10:29:24.430] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:24.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:31:0) after 1m0s
[10:30:24.455] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:31:0)
[10:30:24.455] Timer tick, asking Buildbucket for the build status
[10:30:24.594] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:24.594] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:32:0) after 1m0s
[10:31:24.630] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:32:0)
[10:31:24.630] Timer tick, asking Buildbucket for the build status
[10:31:24.866] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:24.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:33:0) after 1m0s
[10:32:24.944] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:33:0)
[10:32:24.944] Timer tick, asking Buildbucket for the build status
[10:32:25.110] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:25.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:34:0) after 1m0s
[10:33:25.182] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:34:0)
[10:33:25.182] Timer tick, asking Buildbucket for the build status
[10:33:25.498] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:25.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:35:0) after 1m0s
[10:34:25.549] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:35:0)
[10:34:25.549] Timer tick, asking Buildbucket for the build status
[10:34:25.773] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:25.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:36:0) after 1m0s
[10:35:25.846] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:36:0)
[10:35:25.846] Timer tick, asking Buildbucket for the build status
[10:35:26.003] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:26.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:37:0) after 1m0s
[10:36:26.069] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:37:0)
[10:36:26.069] Timer tick, asking Buildbucket for the build status
[10:36:26.360] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:26.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:38:0) after 1m0s
[10:37:26.439] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:38:0)
[10:37:26.439] Timer tick, asking Buildbucket for the build status
[10:37:26.564] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:26.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:39:0) after 1m0s
[10:38:26.588] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:39:0)
[10:38:26.588] Timer tick, asking Buildbucket for the build status
[10:38:26.849] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:26.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:40:0) after 1m0s
[10:39:26.879] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:40:0)
[10:39:26.879] Timer tick, asking Buildbucket for the build status
[10:39:27.157] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:27.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:41:0) after 1m0s
[10:40:27.177] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:41:0)
[10:40:27.177] Timer tick, asking Buildbucket for the build status
[10:40:27.378] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:27.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:42:0) after 1m0s
[10:41:27.404] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:42:0)
[10:41:27.404] Timer tick, asking Buildbucket for the build status
[10:41:27.612] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:27.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:43:0) after 1m0s
[10:42:27.659] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:43:0)
[10:42:27.659] Timer tick, asking Buildbucket for the build status
[10:42:27.789] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:27.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:44:0) after 1m0s
[10:43:28.569] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:44:0)
[10:43:28.569] Timer tick, asking Buildbucket for the build status
[10:43:28.746] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:28.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:45:0) after 1m0s
[10:44:29.033] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:45:0)
[10:44:29.033] Timer tick, asking Buildbucket for the build status
[10:44:29.151] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:29.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:46:0) after 1m0s
[10:45:29.467] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:46:0)
[10:45:29.467] Timer tick, asking Buildbucket for the build status
[10:45:29.764] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:29.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:47:0) after 1m0s
[10:46:30.048] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:47:0)
[10:46:30.048] Timer tick, asking Buildbucket for the build status
[10:46:30.178] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:30.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:48:0) after 1m0s
[10:47:30.452] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:48:0)
[10:47:30.452] Timer tick, asking Buildbucket for the build status
[10:47:30.790] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:30.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:49:0) after 1m0s
[10:48:30.916] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:49:0)
[10:48:30.916] Timer tick, asking Buildbucket for the build status
[10:48:31.162] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:31.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:50:0) after 1m0s
[10:49:31.285] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:50:0)
[10:49:31.285] Timer tick, asking Buildbucket for the build status
[10:49:31.655] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:31.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:51:0) after 1m0s
[10:50:31.715] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:51:0)
[10:50:31.715] Timer tick, asking Buildbucket for the build status
[10:50:31.822] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:31.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:52:0) after 1m0s
[10:51:31.921] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:52:0)
[10:51:31.921] Timer tick, asking Buildbucket for the build status
[10:51:32.415] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:32.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:53:0) after 1m0s
[10:52:32.448] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:53:0)
[10:52:32.448] Timer tick, asking Buildbucket for the build status
[10:52:32.818] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:32.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:54:0) after 1m0s
[10:53:32.890] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:54:0)
[10:53:32.890] Timer tick, asking Buildbucket for the build status
[10:53:33.068] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:33.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:55:0) after 1m0s
[10:54:33.096] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:55:0)
[10:54:33.096] Timer tick, asking Buildbucket for the build status
[10:54:33.258] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:33.258] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:56:0) after 1m0s
[10:55:33.292] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:56:0)
[10:55:33.292] Timer tick, asking Buildbucket for the build status
[10:55:33.439] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:33.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:57:0) after 1m0s
[10:56:33.503] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:57:0)
[10:56:33.503] Timer tick, asking Buildbucket for the build status
[10:56:33.644] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:33.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:58:0) after 1m0s
[10:57:33.802] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:58:0)
[10:57:33.802] Timer tick, asking Buildbucket for the build status
[10:57:33.951] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:33.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:59:0) after 1m0s
[10:58:34.111] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:59:0)
[10:58:34.111] Timer tick, asking Buildbucket for the build status
[10:58:34.221] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:34.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:60:0) after 1m0s
[10:59:34.364] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:60:0)
[10:59:34.364] Timer tick, asking Buildbucket for the build status
[10:59:34.497] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:34.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:61:0) after 1m0s
[11:00:34.724] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:61:0)
[11:00:34.724] Timer tick, asking Buildbucket for the build status
[11:00:35.004] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:35.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:62:0) after 1m0s
[11:01:35.047] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:62:0)
[11:01:35.047] Timer tick, asking Buildbucket for the build status
[11:01:35.695] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:35.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:63:0) after 1m0s
[11:02:35.729] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:63:0)
[11:02:35.729] Timer tick, asking Buildbucket for the build status
[11:02:35.873] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:35.873] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:64:0) after 1m0s
[11:03:36.045] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:64:0)
[11:03:36.045] Timer tick, asking Buildbucket for the build status
[11:03:36.171] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:36.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:65:0) after 1m0s
[11:04:36.480] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:65:0)
[11:04:36.481] Timer tick, asking Buildbucket for the build status
[11:04:36.614] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:36.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:66:0) after 1m0s
[11:05:36.839] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:66:0)
[11:05:36.839] Timer tick, asking Buildbucket for the build status
[11:05:37.252] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:37.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:67:0) after 1m0s
[11:06:37.439] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:67:0)
[11:06:37.439] Timer tick, asking Buildbucket for the build status
[11:06:37.569] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:37.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:68:0) after 1m0s
[11:07:37.727] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:68:0)
[11:07:37.727] Timer tick, asking Buildbucket for the build status
[11:07:37.926] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:37.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:69:0) after 1m0s
[11:08:37.943] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:69:0)
[11:08:37.943] Timer tick, asking Buildbucket for the build status
[11:08:38.094] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:38.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:70:0) after 1m0s
[11:09:38.277] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:70:0)
[11:09:38.277] Timer tick, asking Buildbucket for the build status
[11:09:38.524] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:38.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:71:0) after 1m0s
[11:10:38.813] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:71:0)
[11:10:38.813] Timer tick, asking Buildbucket for the build status
[11:10:39.845] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:39.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:72:0) after 1m0s
[11:11:40.108] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:72:0)
[11:11:40.108] Timer tick, asking Buildbucket for the build status
[11:11:40.546] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:40.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:73:0) after 1m0s
[11:12:40.733] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:73:0)
[11:12:40.733] Timer tick, asking Buildbucket for the build status
[11:12:40.855] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:40.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:74:0) after 1m0s
[11:13:41.069] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:74:0)
[11:13:41.069] Timer tick, asking Buildbucket for the build status
[11:13:41.199] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:41.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:75:0) after 1m0s
[11:14:41.403] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:75:0)
[11:14:41.403] Timer tick, asking Buildbucket for the build status
[11:14:41.546] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:41.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:76:0) after 1m0s
[11:15:41.671] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:76:0)
[11:15:41.671] Timer tick, asking Buildbucket for the build status
[11:15:41.793] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:41.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:77:0) after 1m0s
[11:16:41.914] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:77:0)
[11:16:41.914] Timer tick, asking Buildbucket for the build status
[11:16:42.086] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:42.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:78:0) after 1m0s
[11:17:42.156] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:78:0)
[11:17:42.156] Timer tick, asking Buildbucket for the build status
[11:17:42.339] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:42.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:79:0) after 1m0s
[11:18:42.456] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:79:0)
[11:18:42.456] Timer tick, asking Buildbucket for the build status
[11:18:42.812] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:42.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:80:0) after 1m0s
[11:19:42.855] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:80:0)
[11:19:42.855] Timer tick, asking Buildbucket for the build status
[11:19:43.124] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:43.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:81:0) after 1m0s
[11:20:43.209] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:81:0)
[11:20:43.209] Timer tick, asking Buildbucket for the build status
[11:20:43.340] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:43.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:82:0) after 1m0s
[11:21:43.466] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:82:0)
[11:21:43.466] Timer tick, asking Buildbucket for the build status
[11:21:43.621] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:43.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:83:0) after 1m0s
[11:22:43.704] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:83:0)
[11:22:43.704] Timer tick, asking Buildbucket for the build status
[11:22:43.821] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:43.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:84:0) after 1m0s
[11:23:44.003] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:84:0)
[11:23:44.003] Timer tick, asking Buildbucket for the build status
[11:23:44.195] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:44.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:85:0) after 1m0s
[11:24:44.244] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:85:0)
[11:24:44.244] Timer tick, asking Buildbucket for the build status
[11:24:44.422] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:44.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:86:0) after 1m0s
[11:25:44.445] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:86:0)
[11:25:44.445] Timer tick, asking Buildbucket for the build status
[11:25:45.088] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:45.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:87:0) after 1m0s
[11:26:45.221] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:87:0)
[11:26:45.221] Timer tick, asking Buildbucket for the build status
[11:26:45.393] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:45.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:88:0) after 1m0s
[11:27:45.449] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:88:0)
[11:27:45.449] Timer tick, asking Buildbucket for the build status
[11:27:45.687] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:45.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:89:0) after 1m0s
[11:28:45.702] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:89:0)
[11:28:45.702] Timer tick, asking Buildbucket for the build status
[11:28:46.189] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:46.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:90:0) after 1m0s
[11:29:46.315] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:90:0)
[11:29:46.315] Timer tick, asking Buildbucket for the build status
[11:29:47.194] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:47.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:91:0) after 1m0s
[11:30:47.282] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:91:0)
[11:30:47.282] Timer tick, asking Buildbucket for the build status
[11:30:47.747] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:47.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:92:0) after 1m0s
[11:31:47.921] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:92:0)
[11:31:47.921] Timer tick, asking Buildbucket for the build status
[11:31:48.432] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:48.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:93:0) after 1m0s
[11:32:48.447] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:93:0)
[11:32:48.447] Timer tick, asking Buildbucket for the build status
[11:32:48.626] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:48.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:94:0) after 1m0s
[11:33:48.660] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:94:0)
[11:33:48.660] Timer tick, asking Buildbucket for the build status
[11:33:48.904] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:48.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:95:0) after 1m0s
[11:34:49.004] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:95:0)
[11:34:49.004] Timer tick, asking Buildbucket for the build status
[11:34:49.768] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:49.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:96:0) after 1m0s
[11:35:49.855] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:96:0)
[11:35:49.855] Timer tick, asking Buildbucket for the build status
[11:35:50.066] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:50.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:97:0) after 1m0s
[11:36:50.149] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:97:0)
[11:36:50.149] Timer tick, asking Buildbucket for the build status
[11:36:50.690] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:50.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:98:0) after 1m0s
[11:37:50.765] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:98:0)
[11:37:50.765] Timer tick, asking Buildbucket for the build status
[11:37:50.943] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:50.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:99:0) after 1m0s
[11:38:50.969] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:99:0)
[11:38:50.969] Timer tick, asking Buildbucket for the build status
[11:38:51.152] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:51.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:100:0) after 1m0s
[11:39:51.283] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:100:0)
[11:39:51.283] Timer tick, asking Buildbucket for the build status
[11:39:51.609] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:51.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:101:0) after 1m0s
[11:40:51.649] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:101:0)
[11:40:51.649] Timer tick, asking Buildbucket for the build status
[11:40:51.976] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:51.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:102:0) after 1m0s
[11:41:52.103] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:102:0)
[11:41:52.103] Timer tick, asking Buildbucket for the build status
[11:41:52.495] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:52.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:103:0) after 1m0s
[11:42:52.675] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:103:0)
[11:42:52.675] Timer tick, asking Buildbucket for the build status
[11:42:52.856] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:52.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:104:0) after 1m0s
[11:43:52.855] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:104:0)
[11:43:52.855] Timer tick, asking Buildbucket for the build status
[11:43:52.988] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:52.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:105:0) after 1m0s
[11:44:53.126] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:105:0)
[11:44:53.126] Timer tick, asking Buildbucket for the build status
[11:44:53.942] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:53.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:106:0) after 1m0s
[11:45:54.038] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:106:0)
[11:45:54.038] Timer tick, asking Buildbucket for the build status
[11:45:54.236] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:54.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:107:0) after 1m0s
[11:46:54.312] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:107:0)
[11:46:54.312] Timer tick, asking Buildbucket for the build status
[11:46:54.539] Build 8879518720755921904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:54.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9044948782845263488:108:0) after 1m0s
[11:47:35.788] Received PubSub notification, asking Buildbucket for the build status
[11:47:35.947] Build 8879518720755921904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:47:35.947] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1590234453637713",
"created_by": "project:chromium",
"created_ts": "1590228076174786",
"id": "8879518720755921904",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54f4402907f208e00a3c607cb7840f3931551e83\"}}",
"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\": 17304, \"got_angle_revision\": \"d3d7b95bac1957900d346637600adc9eacf1fd64\", \"got_dawn_revision\": \"65ee6497d620110546181c1dfbd0cc3064f9f7f9\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"54f4402907f208e00a3c607cb7840f3931551e83\", \"got_revision_cp\": \"refs/heads/master@{#771385}\", \"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\": \"54f4402907f208e00a3c607cb7840f3931551e83\", \"swarm_hashes\": {\"accessibility_unittests\": \"072f4cd0595e131453d4ad0f35017f0331192428\", \"angle_unittests\": \"94ce9f4dd4c9368eacb4904a4b4e09c64db4a764\", \"app_shell_unittests\": \"296297237f9983363c00952e05bf21228ab06fdb\", \"aura_unittests\": \"8d30e88ab380962c1daeab783d8f3fcb5e12b47d\", \"base_unittests\": \"3da128b36c51971bc2445e9d3e05dc18cda32ed5\", \"base_util_unittests\": \"124339b0d109499d10f64fe041332392f9dfa95c\", \"blink_common_unittests\": \"1463d372c9bc375a9e5a44215057dd61be7b5065\", \"blink_fuzzer_unittests\": \"53da1ff7d712a9c90d14eb4b4caa6d6283d9a41b\", \"blink_heap_unittests\": \"3ffd7b4860c04d9f5dc5d2c3e45dc07a579acf8d\", \"blink_platform_unittests\": \"efe2c4a2420f12a1ea6932205c159c63ac4624a8\", \"blink_unittests\": \"95d490550b38b938b13358044b921fb73538a0a4\", \"boringssl_crypto_tests\": \"fca57694a4470968e905eebcf56a61f81dc5b45d\", \"boringssl_ssl_tests\": \"928144690816b20a935e1b49ef509f46a37464e5\", \"browser_tests\": \"2d58e8a2ec4957208bc66f21ccda102fd6cd1c37\", \"cacheinvalidation_unittests\": \"05bee409c500620199ee09bde31517da309be76c\", \"capture_unittests\": \"bfac38e6e55bf1d68cc80744e86d2e9377257bc2\", \"cast_unittests\": \"b6290331bd07cfc9262979a926790553b5672269\", \"cc_unittests\": \"67c3a2ce7198358e5922ca34f709d96d0d730152\", \"chrome_app_unittests\": \"444439d70417ad19bfe7b8a7302d312945964d79\", \"chromedriver_unittests\": \"15d9fed5d1524de337132c9e124bc1a51124ad98\", \"components_browsertests\": \"6b72c14edb5ea97d574e212d90ad6f1283a28812\", \"components_unittests\": \"7b68336cb88beaacb03989c75103f4d4a2a82d48\", \"compositor_unittests\": \"7afaf9f0d9856852e12fe5d975658565e67be765\", \"content_browsertests\": \"242ee1128a81986876639daef717d67df588ace6\", \"content_unittests\": \"8925cdc676213b1925ebd887df02d6e8ed7bd084\", \"cronet_tests\": \"bef94b584b55663475741f78bd50c80eb0a69055\", \"cronet_unittests\": \"10240728dac39455d99908cebcbc66189bac3e3d\", \"crypto_unittests\": \"71b635ef54049cd821baab206545f3b6fe56d97b\", \"dbus_unittests\": \"bbc8f1d23dbbf9388c03b3d96d9da36c8c509165\", \"device_unittests\": \"6e4cb66761518872b9468285f67917009c31a35b\", \"display_unittests\": \"d96da5c065d234632dfecf77ed26fa42936c2992\", \"events_unittests\": \"1c31e6516118edb25981b75a11b88f25083db6dd\", \"extensions_browsertests\": \"c6ea12772272ee488346218eb5cb06df6d4a9236\", \"extensions_unittests\": \"a4d294f57c18e9a3456596dc25a6a33b2ad1f6d9\", \"filesystem_service_unittests\": \"8dc33a2b98891d0f790ab99c0131ff0de22b0f98\", \"gcm_unit_tests\": \"98c769f051250c43e13e81cfc48d0655ad9ff391\", \"gfx_unittests\": \"e0c0d78424c6bcdf721fc0021f26d83bdcde51e0\", \"gin_unittests\": \"0755418763f6d0c984449b2b5c6a24ffe1c01cb8\", \"gl_tests\": \"11704c31120982e14c49bdad559921173435296d\", \"gl_unittests\": \"f6b2edd0cdf8523c965d15b5709bf29e06140e4b\", \"google_apis_unittests\": \"5c5a3529bbc0d3f354009fa49247e378e7634c80\", \"gpu_unittests\": \"69b4f8b9d61a0b103331fc2570ff421b57022a60\", \"gwp_asan_unittests\": \"7fd61a6b2568d6f57f1bfe1331b4d3dbb03a42cc\", \"headless_browsertests\": \"935be7b57d7a11a3d837657190980b43f4104fbe\", \"headless_unittests\": \"377904192e44a559ca6370426670b90bf266e596\", \"interactive_ui_tests\": \"72fec72e2894f537857a4ba765ec59ab3bdb9815\", \"ipc_tests\": \"1f86306768b710d31a7cb4c46bad46a401510a04\", \"jingle_unittests\": \"96c6c3507d86fd51ea1cb3e28f0c9ad81890387b\", \"latency_unittests\": \"6ca05581c0c92fd6e165fc9d92f3242e12ca2125\", \"libjingle_xmpp_unittests\": \"0eb9748c1fa7acb75d6f8da09511c6cdeed49bbd\", \"media_blink_unittests\": \"3ecde1e5624235b81f4c22fa5c747a8e11ca0a5f\", \"media_unittests\": \"328e3b24b1847daee409e3e824357908ac176a58\", \"message_center_unittests\": \"15552943d9c46aef51248077b84c8923f0ae55f3\", \"midi_unittests\": \"1ffc396e8f9808f268c0e337758feeaf81be8fbc\", \"mojo_core_unittests\": \"7237d38ff19fb77d4f0f1aeb19dcde099c1e4dd9\", \"mojo_unittests\": \"d6b9aba3ce498464d1cf176bf90ea09169a0931a\", \"nacl_helper_nonsfi_unittests\": \"b08c3eae88a60087a6ed68031cf14a8b4e7a2f70\", \"nacl_loader_unittests\": \"673c7c2d8587663cf885b896aaa01f627ecec5ee\", \"native_theme_unittests\": \"7e0d8b9877e493de7a7851c7d4a456b5bacb26cc\", \"net_unittests\": \"119041445dc492bf7a2b93b0f31388666cdb07fe\", \"pdf_unittests\": \"8f473822e0062a75594ed1f4bbfeadf70bc96a97\", \"perfetto_unittests\": \"d1181d9b9d65077e8cd86200804bf6d297a2cf1e\", \"ppapi_unittests\": \"3e62fc251d0d87f9bd2c5643f5943239eecc6d46\", \"printing_unittests\": \"fd6738efbc71921103c01fbcf60ff36706f4de70\", \"remoting_unittests\": \"b8b7677816d0472918937717a82e2d421fd483b7\", \"sandbox_linux_unittests\": \"148f50253f412c147bd4bce5fe83b33b3232b97f\", \"service_manager_unittests\": \"081ef688565abf8cebdf989e83e03216e9fe06a8\", \"services_unittests\": \"7532721ff95128289279e8c3494eb5795182c5c0\", \"shell_dialogs_unittests\": \"8e6087711df04722fd596165094258b8343c1202\", \"skia_unittests\": \"ffc0d94d94f9354bfea6f354921631714ae0c1b5\", \"snapshot_unittests\": \"245c9893cd3aff4e3f3d9b0ce0a30c987a3b1bd2\", \"sql_unittests\": \"8d4d53b70d098484d91ee51204ef2c38019d0818\", \"storage_unittests\": \"9ff401a32de6d1151fd524822e534d77505d8282\", \"sync_integration_tests\": \"74fc23315e953d4259a258890b2a2eb77fe5465e\", \"traffic_annotation_auditor_unittests\": \"666920733712e4439d07e37db7e34cb72ae8ae05\", \"ui_base_unittests\": \"2574e3838fe1413a3d0a0b40afeb52a899e7948a\", \"ui_touch_selection_unittests\": \"9c926be948dcd48c8bdb1fa7d122404ec22aa769\", \"unit_tests\": \"86d949cf3016bf6c139e45400eaf070685d3da35\", \"url_unittests\": \"61158c0f391cd81d2e9bf6a035de3b6ca454f5aa\", \"views_unittests\": \"2f99184d5734582125354bbd410fc8ce466586a6\", \"viz_unittests\": \"ace9179fd4b8b64beb8d884607e4f8153c4f093f\", \"vr_common_unittests\": \"4e1e12b92a11cd15c80be3673ba90bc6a34d9075\", \"vr_pixeltests\": \"9a18d6648bce60e5e596aa4bbac93430fc9c2506\", \"wm_unittests\": \"3b7a949d0c5110588763f7ed35b95aba78bbec67\", \"wtf_unittests\": \"7a10fca2259fa089f6dc1a02351655be50bcbfdd\"}, \"swarm_hashes_refs/heads/master(at){#771385}_without_patch\": {\"accessibility_unittests\": \"072f4cd0595e131453d4ad0f35017f0331192428\", \"angle_unittests\": \"94ce9f4dd4c9368eacb4904a4b4e09c64db4a764\", \"app_shell_unittests\": \"296297237f9983363c00952e05bf21228ab06fdb\", \"aura_unittests\": \"8d30e88ab380962c1daeab783d8f3fcb5e12b47d\", \"base_unittests\": \"3da128b36c51971bc2445e9d3e05dc18cda32ed5\", \"base_util_unittests\": \"124339b0d109499d10f64fe041332392f9dfa95c\", \"blink_common_unittests\": \"1463d372c9bc375a9e5a44215057dd61be7b5065\", \"blink_fuzzer_unittests\": \"53da1ff7d712a9c90d14eb4b4caa6d6283d9a41b\", \"blink_heap_unittests\": \"3ffd7b4860c04d9f5dc5d2c3e45dc07a579acf8d\", \"blink_platform_unittests\": \"efe2c4a2420f12a1ea6932205c159c63ac4624a8\", \"blink_unittests\": \"95d490550b38b938b13358044b921fb73538a0a4\", \"boringssl_crypto_tests\": \"fca57694a4470968e905eebcf56a61f81dc5b45d\", \"boringssl_ssl_tests\": \"928144690816b20a935e1b49ef509f46a37464e5\", \"browser_tests\": \"2d58e8a2ec4957208bc66f21ccda102fd6cd1c37\", \"cacheinvalidation_unittests\": \"05bee409c500620199ee09bde31517da309be76c\", \"capture_unittests\": \"bfac38e6e55bf1d68cc80744e86d2e9377257bc2\", \"cast_unittests\": \"b6290331bd07cfc9262979a926790553b5672269\", \"cc_unittests\": \"67c3a2ce7198358e5922ca34f709d96d0d730152\", \"chrome_app_unittests\": \"444439d70417ad19bfe7b8a7302d312945964d79\", \"chromedriver_unittests\": \"15d9fed5d1524de337132c9e124bc1a51124ad98\", \"components_browsertests\": \"6b72c14edb5ea97d574e212d90ad6f1283a28812\", \"components_unittests\": \"7b68336cb88beaacb03989c75103f4d4a2a82d48\", \"compositor_unittests\": \"7afaf9f0d9856852e12fe5d975658565e67be765\", \"content_browsertests\": \"242ee1128a81986876639daef717d67df588ace6\", \"content_unittests\": \"8925cdc676213b1925ebd887df02d6e8ed7bd084\", \"cronet_tests\": \"bef94b584b55663475741f78bd50c80eb0a69055\", \"cronet_unittests\": \"10240728dac39455d99908cebcbc66189bac3e3d\", \"crypto_unittests\": \"71b635ef54049cd821baab206545f3b6fe56d97b\", \"dbus_unittests\": \"bbc8f1d23dbbf9388c03b3d96d9da36c8c509165\", \"device_unittests\": \"6e4cb66761518872b9468285f67917009c31a35b\", \"display_unittests\": \"d96da5c065d234632dfecf77ed26fa42936c2992\", \"events_unittests\": \"1c31e6516118edb25981b75a11b88f25083db6dd\", \"extensions_browsertests\": \"c6ea12772272ee488346218eb5cb06df6d4a9236\", \"extensions_unittests\": \"a4d294f57c18e9a3456596dc25a6a33b2ad1f6d9\", \"filesystem_service_unittests\": \"8dc33a2b98891d0f790ab99c0131ff0de22b0f98\", \"gcm_unit_tests\": \"98c769f051250c43e13e81cfc48d0655ad9ff391\", \"gfx_unittests\": \"e0c0d78424c6bcdf721fc0021f26d83bdcde51e0\", \"gin_unittests\": \"0755418763f6d0c984449b2b5c6a24ffe1c01cb8\", \"gl_tests\": \"11704c31120982e14c49bdad559921173435296d\", \"gl_unittests\": \"f6b2edd0cdf8523c965d15b5709bf29e06140e4b\", \"google_apis_unittests\": \"5c5a3529bbc0d3f354009fa49247e378e7634c80\", \"gpu_unittests\": \"69b4f8b9d61a0b103331fc2570ff421b57022a60\", \"gwp_asan_unittests\": \"7fd61a6b2568d6f57f1bfe1331b4d3dbb03a42cc\", \"headless_browsertests\": \"935be7b57d7a11a3d837657190980b43f4104fbe\", \"headless_unittests\": \"377904192e44a559ca6370426670b90bf266e596\", \"interactive_ui_tests\": \"72fec72e2894f537857a4ba765ec59ab3bdb9815\", \"ipc_tests\": \"1f86306768b710d31a7cb4c46bad46a401510a04\", \"jingle_unittests\": \"96c6c3507d86fd51ea1cb3e28f0c9ad81890387b\", \"latency_unittests\": \"6ca05581c0c92fd6e165fc9d92f3242e12ca2125\", \"libjingle_xmpp_unittests\": \"0eb9748c1fa7acb75d6f8da09511c6cdeed49bbd\", \"media_blink_unittests\": \"3ecde1e5624235b81f4c22fa5c747a8e11ca0a5f\", \"media_unittests\": \"328e3b24b1847daee409e3e824357908ac176a58\", \"message_center_unittests\": \"15552943d9c46aef51248077b84c8923f0ae55f3\", \"midi_unittests\": \"1ffc396e8f9808f268c0e337758feeaf81be8fbc\", \"mojo_core_unittests\": \"7237d38ff19fb77d4f0f1aeb19dcde099c1e4dd9\", \"mojo_unittests\": \"d6b9aba3ce498464d1cf176bf90ea09169a0931a\", \"nacl_helper_nonsfi_unittests\": \"b08c3eae88a60087a6ed68031cf14a8b4e7a2f70\", \"nacl_loader_unittests\": \"673c7c2d8587663cf885b896aaa01f627ecec5ee\", \"native_theme_unittests\": \"7e0d8b9877e493de7a7851c7d4a456b5bacb26cc\", \"net_unittests\": \"119041445dc492bf7a2b93b0f31388666cdb07fe\", \"pdf_unittests\": \"8f473822e0062a75594ed1f4bbfeadf70bc96a97\", \"perfetto_unittests\": \"d1181d9b9d65077e8cd86200804bf6d297a2cf1e\", \"ppapi_unittests\": \"3e62fc251d0d87f9bd2c5643f5943239eecc6d46\", \"printing_unittests\": \"fd6738efbc71921103c01fbcf60ff36706f4de70\", \"remoting_unittests\": \"b8b7677816d0472918937717a82e2d421fd483b7\", \"sandbox_linux_unittests\": \"148f50253f412c147bd4bce5fe83b33b3232b97f\", \"service_manager_unittests\": \"081ef688565abf8cebdf989e83e03216e9fe06a8\", \"services_unittests\": \"7532721ff95128289279e8c3494eb5795182c5c0\", \"shell_dialogs_unittests\": \"8e6087711df04722fd596165094258b8343c1202\", \"skia_unittests\": \"ffc0d94d94f9354bfea6f354921631714ae0c1b5\", \"snapshot_unittests\": \"245c9893cd3aff4e3f3d9b0ce0a30c987a3b1bd2\", \"sql_unittests\": \"8d4d53b70d098484d91ee51204ef2c38019d0818\", \"storage_unittests\": \"9ff401a32de6d1151fd524822e534d77505d8282\", \"sync_integration_tests\": \"74fc23315e953d4259a258890b2a2eb77fe5465e\", \"traffic_annotation_auditor_unittests\": \"666920733712e4439d07e37db7e34cb72ae8ae05\", \"ui_base_unittests\": \"2574e3838fe1413a3d0a0b40afeb52a899e7948a\", \"ui_touch_selection_unittests\": \"9c926be948dcd48c8bdb1fa7d122404ec22aa769\", \"unit_tests\": \"86d949cf3016bf6c139e45400eaf070685d3da35\", \"url_unittests\": \"61158c0f391cd81d2e9bf6a035de3b6ca454f5aa\", \"views_unittests\": \"2f99184d5734582125354bbd410fc8ce466586a6\", \"viz_unittests\": \"ace9179fd4b8b64beb8d884607e4f8153c4f093f\", \"vr_common_unittests\": \"4e1e12b92a11cd15c80be3673ba90bc6a34d9075\", \"vr_pixeltests\": \"9a18d6648bce60e5e596aa4bbac93430fc9c2506\", \"wm_unittests\": \"3b7a949d0c5110588763f7ed35b95aba78bbec67\", \"wtf_unittests\": \"7a10fca2259fa089f6dc1a02351655be50bcbfdd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0264569d20b29e1d5e6df044c8d1223ed84f9f22180c83dac41e8db0ff2b71db_v2\", \"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": "1590228190120960",
"status": "COMPLETED",
"status_changed_ts": "1590234454435106",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/17304",
"builder:Linux CFI",
"buildset:commit/git/54f4402907f208e00a3c607cb7840f3931551e83",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54f4402907f208e00a3c607cb7840f3931551e83",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044948782845263488",
"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/8879518720755921904/+/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:4c59cc5bda5ac610",
"user_agent:luci-scheduler"
],
"updated_ts": "1590234454452231",
"url": "https://ci.chromium.org/b/8879518720755921904",
"utcnow_ts": "1590234455931255"
}
}
[11:47:35.947] Invocation finished in 1h46m21.149121692s with status SUCCEEDED