[04:54:10.369] New invocation is queued and will start shortly
[04:54:11.553] Starting the invocation (attempt 1)
[04:54:11.590] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:54:11.592] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:54:11.592] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030925572511440304",
"parameters_json": "{\"builder_name\":\"Linux CFI\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3a47b622f08058daa6445ad2799aa6a123179628\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux CFI",
"scheduler_invocation_id:9030925572511440304",
"scheduler_job_id:chromium/Linux CFI",
"user_agent:luci-scheduler",
"buildset:commit/git/3a47b622f08058daa6445ad2799aa6a123179628",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a47b622f08058daa6445ad2799aa6a123179628",
"gitiles_ref:refs/heads/master"
]
}
[04:54:12.152] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603601651672642",
"id": "8865495510454623072",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3a47b622f08058daa6445ad2799aa6a123179628\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603601652030435",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/18759",
"builder:Linux CFI",
"buildset:commit/git/3a47b622f08058daa6445ad2799aa6a123179628",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a47b622f08058daa6445ad2799aa6a123179628",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030925572511440304",
"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/8865495510454623072/+/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": "1603601652030543",
"url": "https://ci.chromium.org/b/8865495510454623072",
"utcnow_ts": "1603601652144073"
}
}
[04:54:12.152] Task URL: https://ci.chromium.org/b/8865495510454623072
[04:54:12.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:2:0) after 1m0s
[04:54:15.619] Received PubSub notification, asking Buildbucket for the build status
[04:54:15.685] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:12.181] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:2:0)
[04:55:12.181] Timer tick, asking Buildbucket for the build status
[04:55:12.408] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:12.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:4:0) after 1m0s
[04:56:12.428] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:4:0)
[04:56:12.429] Timer tick, asking Buildbucket for the build status
[04:56:12.548] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:12.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:5:0) after 1m0s
[04:57:12.573] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:5:0)
[04:57:12.573] Timer tick, asking Buildbucket for the build status
[04:57:12.678] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:12.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:6:0) after 1m0s
[04:58:12.708] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:6:0)
[04:58:12.708] Timer tick, asking Buildbucket for the build status
[04:58:12.794] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:12.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:7:0) after 1m0s
[04:59:13.031] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:7:0)
[04:59:13.031] Timer tick, asking Buildbucket for the build status
[04:59:13.086] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:13.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:8:0) after 1m0s
[05:00:13.408] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:8:0)
[05:00:13.408] Timer tick, asking Buildbucket for the build status
[05:00:14.424] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:14.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:9:0) after 1m0s
[05:01:14.459] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:9:0)
[05:01:14.459] Timer tick, asking Buildbucket for the build status
[05:01:15.983] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:15.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:10:0) after 1m0s
[05:02:16.007] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:10:0)
[05:02:16.007] Timer tick, asking Buildbucket for the build status
[05:02:16.083] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:16.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:11:0) after 1m0s
[05:03:16.583] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:11:0)
[05:03:16.583] Timer tick, asking Buildbucket for the build status
[05:03:16.688] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:16.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:12:0) after 1m0s
[05:04:16.710] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:12:0)
[05:04:16.710] Timer tick, asking Buildbucket for the build status
[05:04:16.813] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:16.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:13:0) after 1m0s
[05:05:16.883] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:13:0)
[05:05:16.883] Timer tick, asking Buildbucket for the build status
[05:05:16.941] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:16.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:14:0) after 1m0s
[05:06:16.946] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:14:0)
[05:06:16.946] Timer tick, asking Buildbucket for the build status
[05:06:17.007] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:17.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:15:0) after 1m0s
[05:07:17.064] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:15:0)
[05:07:17.064] Timer tick, asking Buildbucket for the build status
[05:07:17.224] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:17.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:16:0) after 1m0s
[05:08:17.244] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:16:0)
[05:08:17.244] Timer tick, asking Buildbucket for the build status
[05:08:17.309] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:17.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:17:0) after 1m0s
[05:09:17.304] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:17:0)
[05:09:17.305] Timer tick, asking Buildbucket for the build status
[05:09:17.582] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:17.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:18:0) after 1m0s
[05:10:17.691] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:18:0)
[05:10:17.691] Timer tick, asking Buildbucket for the build status
[05:10:17.762] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:17.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:19:0) after 1m0s
[05:11:17.782] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:19:0)
[05:11:17.782] Timer tick, asking Buildbucket for the build status
[05:11:17.908] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:17.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:20:0) after 1m0s
[05:12:17.979] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:20:0)
[05:12:17.979] Timer tick, asking Buildbucket for the build status
[05:12:18.209] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:18.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:21:0) after 1m0s
[05:13:18.229] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:21:0)
[05:13:18.229] Timer tick, asking Buildbucket for the build status
[05:13:18.313] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:18.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:22:0) after 1m0s
[05:14:18.334] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:22:0)
[05:14:18.334] Timer tick, asking Buildbucket for the build status
[05:14:18.454] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:18.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:23:0) after 1m0s
[05:15:18.497] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:23:0)
[05:15:18.497] Timer tick, asking Buildbucket for the build status
[05:15:18.565] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:18.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:24:0) after 1m0s
[05:16:18.662] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:24:0)
[05:16:18.662] Timer tick, asking Buildbucket for the build status
[05:16:18.737] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:18.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:25:0) after 1m0s
[05:17:18.818] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:25:0)
[05:17:18.818] Timer tick, asking Buildbucket for the build status
[05:17:18.892] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:18.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:26:0) after 1m0s
[05:18:18.916] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:26:0)
[05:18:18.916] Timer tick, asking Buildbucket for the build status
[05:18:18.980] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:18.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:27:0) after 1m0s
[05:19:19.005] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:27:0)
[05:19:19.005] Timer tick, asking Buildbucket for the build status
[05:19:19.111] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:19.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:28:0) after 1m0s
[05:20:19.133] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:28:0)
[05:20:19.133] Timer tick, asking Buildbucket for the build status
[05:20:19.233] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:19.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:29:0) after 1m0s
[05:21:19.303] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:29:0)
[05:21:19.303] Timer tick, asking Buildbucket for the build status
[05:21:19.388] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:19.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:30:0) after 1m0s
[05:22:19.463] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:30:0)
[05:22:19.463] Timer tick, asking Buildbucket for the build status
[05:22:19.992] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:19.992] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:31:0) after 1m0s
[05:23:20.071] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:31:0)
[05:23:20.071] Timer tick, asking Buildbucket for the build status
[05:23:20.376] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:20.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:32:0) after 1m0s
[05:24:20.398] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:32:0)
[05:24:20.398] Timer tick, asking Buildbucket for the build status
[05:24:20.493] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:20.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:33:0) after 1m0s
[05:25:20.587] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:33:0)
[05:25:20.587] Timer tick, asking Buildbucket for the build status
[05:25:20.653] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:20.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:34:0) after 1m0s
[05:26:20.713] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:34:0)
[05:26:20.713] Timer tick, asking Buildbucket for the build status
[05:26:20.777] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:20.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:35:0) after 1m0s
[05:27:20.865] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:35:0)
[05:27:20.865] Timer tick, asking Buildbucket for the build status
[05:27:20.938] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:20.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:36:0) after 1m0s
[05:28:20.959] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:36:0)
[05:28:20.959] Timer tick, asking Buildbucket for the build status
[05:28:21.017] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:21.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:37:0) after 1m0s
[05:29:21.042] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:37:0)
[05:29:21.042] Timer tick, asking Buildbucket for the build status
[05:29:21.577] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:21.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:38:0) after 1m0s
[05:30:21.597] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:38:0)
[05:30:21.597] Timer tick, asking Buildbucket for the build status
[05:30:21.673] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:21.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:39:0) after 1m0s
[05:31:21.713] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:39:0)
[05:31:21.713] Timer tick, asking Buildbucket for the build status
[05:31:21.775] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:21.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:40:0) after 1m0s
[05:32:21.785] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:40:0)
[05:32:21.785] Timer tick, asking Buildbucket for the build status
[05:32:21.855] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:21.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:41:0) after 1m0s
[05:33:21.876] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:41:0)
[05:33:21.876] Timer tick, asking Buildbucket for the build status
[05:33:22.124] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:22.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:42:0) after 1m0s
[05:34:22.195] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:42:0)
[05:34:22.195] Timer tick, asking Buildbucket for the build status
[05:34:22.404] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:22.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:43:0) after 1m0s
[05:35:22.497] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:43:0)
[05:35:22.497] Timer tick, asking Buildbucket for the build status
[05:35:22.586] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:22.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:44:0) after 1m0s
[05:36:22.608] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:44:0)
[05:36:22.608] Timer tick, asking Buildbucket for the build status
[05:36:22.809] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:22.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:45:0) after 1m0s
[05:37:22.892] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:45:0)
[05:37:22.892] Timer tick, asking Buildbucket for the build status
[05:37:22.977] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:22.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:46:0) after 1m0s
[05:38:23.014] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:46:0)
[05:38:23.014] Timer tick, asking Buildbucket for the build status
[05:38:23.097] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:23.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:47:0) after 1m0s
[05:39:23.120] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:47:0)
[05:39:23.120] Timer tick, asking Buildbucket for the build status
[05:39:23.240] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:23.240] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:48:0) after 1m0s
[05:40:23.364] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:48:0)
[05:40:23.364] Timer tick, asking Buildbucket for the build status
[05:40:23.620] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:23.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:49:0) after 1m0s
[05:41:23.650] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:49:0)
[05:41:23.650] Timer tick, asking Buildbucket for the build status
[05:41:23.727] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:23.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:50:0) after 1m0s
[05:42:23.750] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:50:0)
[05:42:23.750] Timer tick, asking Buildbucket for the build status
[05:42:24.146] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:24.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:51:0) after 1m0s
[05:43:24.202] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:51:0)
[05:43:24.202] Timer tick, asking Buildbucket for the build status
[05:43:24.694] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:24.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:52:0) after 1m0s
[05:44:24.731] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:52:0)
[05:44:24.731] Timer tick, asking Buildbucket for the build status
[05:44:24.834] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:24.834] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:53:0) after 1m0s
[05:45:24.889] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:53:0)
[05:45:24.889] Timer tick, asking Buildbucket for the build status
[05:45:24.979] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:24.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:54:0) after 1m0s
[05:46:24.973] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:54:0)
[05:46:24.973] Timer tick, asking Buildbucket for the build status
[05:46:25.040] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:25.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:55:0) after 1m0s
[05:47:25.063] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:55:0)
[05:47:25.063] Timer tick, asking Buildbucket for the build status
[05:47:25.218] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:25.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:56:0) after 1m0s
[05:48:25.280] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:56:0)
[05:48:25.280] Timer tick, asking Buildbucket for the build status
[05:48:25.366] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:25.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:57:0) after 1m0s
[05:49:25.365] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:57:0)
[05:49:25.365] Timer tick, asking Buildbucket for the build status
[05:49:25.464] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:25.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:58:0) after 1m0s
[05:50:25.536] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:58:0)
[05:50:25.536] Timer tick, asking Buildbucket for the build status
[05:50:25.610] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:25.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:59:0) after 1m0s
[05:51:26.297] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:59:0)
[05:51:26.297] Timer tick, asking Buildbucket for the build status
[05:51:26.369] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:26.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:60:0) after 1m0s
[05:52:27.241] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:60:0)
[05:52:27.241] Timer tick, asking Buildbucket for the build status
[05:52:27.547] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:27.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:61:0) after 1m0s
[05:53:27.675] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:61:0)
[05:53:27.675] Timer tick, asking Buildbucket for the build status
[05:53:28.113] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:28.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:62:0) after 1m0s
[05:54:28.314] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:62:0)
[05:54:28.314] Timer tick, asking Buildbucket for the build status
[05:54:28.426] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:28.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:63:0) after 1m0s
[05:55:28.667] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:63:0)
[05:55:28.667] Timer tick, asking Buildbucket for the build status
[05:55:28.806] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:28.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:64:0) after 1m0s
[05:56:28.889] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:64:0)
[05:56:28.889] Timer tick, asking Buildbucket for the build status
[05:56:29.080] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:29.080] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:65:0) after 1m0s
[05:57:29.105] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:65:0)
[05:57:29.105] Timer tick, asking Buildbucket for the build status
[05:57:29.569] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:29.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:66:0) after 1m0s
[05:58:29.644] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:66:0)
[05:58:29.644] Timer tick, asking Buildbucket for the build status
[05:58:29.697] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:29.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:67:0) after 1m0s
[05:59:29.820] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:67:0)
[05:59:29.820] Timer tick, asking Buildbucket for the build status
[05:59:29.973] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:29.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:68:0) after 1m0s
[06:00:30.101] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:68:0)
[06:00:30.101] Timer tick, asking Buildbucket for the build status
[06:00:30.381] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:30.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:69:0) after 1m0s
[06:01:38.509] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:69:0)
[06:01:38.509] Timer tick, asking Buildbucket for the build status
[06:01:38.593] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:38.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:70:0) after 1m0s
[06:02:38.652] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:70:0)
[06:02:38.652] Timer tick, asking Buildbucket for the build status
[06:02:38.721] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:38.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:71:0) after 1m0s
[06:03:38.841] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:71:0)
[06:03:38.841] Timer tick, asking Buildbucket for the build status
[06:03:38.908] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:38.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:72:0) after 1m0s
[06:04:39.073] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:72:0)
[06:04:39.073] Timer tick, asking Buildbucket for the build status
[06:04:39.180] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:39.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:73:0) after 1m0s
[06:05:39.252] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:73:0)
[06:05:39.252] Timer tick, asking Buildbucket for the build status
[06:05:39.317] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:39.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:74:0) after 1m0s
[06:06:39.326] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:74:0)
[06:06:39.326] Timer tick, asking Buildbucket for the build status
[06:06:39.664] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:39.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:75:0) after 1m0s
[06:07:39.758] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:75:0)
[06:07:39.758] Timer tick, asking Buildbucket for the build status
[06:07:40.298] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:40.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:76:0) after 1m0s
[06:08:40.304] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:76:0)
[06:08:40.304] Timer tick, asking Buildbucket for the build status
[06:08:40.375] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:40.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:77:0) after 1m0s
[06:09:40.415] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:77:0)
[06:09:40.415] Timer tick, asking Buildbucket for the build status
[06:09:40.536] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:40.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:78:0) after 1m0s
[06:10:40.562] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:78:0)
[06:10:40.562] Timer tick, asking Buildbucket for the build status
[06:10:40.641] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:40.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:79:0) after 1m0s
[06:11:40.711] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:79:0)
[06:11:40.711] Timer tick, asking Buildbucket for the build status
[06:11:40.772] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:40.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:80:0) after 1m0s
[06:12:40.795] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:80:0)
[06:12:40.795] Timer tick, asking Buildbucket for the build status
[06:12:40.869] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:40.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:81:0) after 1m0s
[06:13:40.861] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:81:0)
[06:13:40.861] Timer tick, asking Buildbucket for the build status
[06:13:40.926] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:40.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:82:0) after 1m0s
[06:14:41.048] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:82:0)
[06:14:41.048] Timer tick, asking Buildbucket for the build status
[06:14:41.310] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:41.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:83:0) after 1m0s
[06:15:42.652] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:83:0)
[06:15:42.652] Timer tick, asking Buildbucket for the build status
[06:15:42.745] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:42.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:84:0) after 1m0s
[06:16:42.820] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:84:0)
[06:16:42.820] Timer tick, asking Buildbucket for the build status
[06:16:42.888] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:42.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:85:0) after 1m0s
[06:17:43.217] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:85:0)
[06:17:43.217] Timer tick, asking Buildbucket for the build status
[06:17:43.306] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:43.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:86:0) after 1m0s
[06:18:43.400] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:86:0)
[06:18:43.400] Timer tick, asking Buildbucket for the build status
[06:18:43.457] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:43.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:87:0) after 1m0s
[06:19:43.592] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:87:0)
[06:19:43.592] Timer tick, asking Buildbucket for the build status
[06:19:43.689] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:43.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:88:0) after 1m0s
[06:20:43.675] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:88:0)
[06:20:43.675] Timer tick, asking Buildbucket for the build status
[06:20:44.303] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:44.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:89:0) after 1m0s
[06:21:44.339] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:89:0)
[06:21:44.339] Timer tick, asking Buildbucket for the build status
[06:21:44.622] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:44.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:90:0) after 1m0s
[06:22:44.688] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:90:0)
[06:22:44.688] Timer tick, asking Buildbucket for the build status
[06:22:44.745] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:44.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:91:0) after 1m0s
[06:23:44.865] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:91:0)
[06:23:44.865] Timer tick, asking Buildbucket for the build status
[06:23:44.957] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:44.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:92:0) after 1m0s
[06:24:45.087] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:92:0)
[06:24:45.087] Timer tick, asking Buildbucket for the build status
[06:24:45.195] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:45.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:93:0) after 1m0s
[06:25:45.329] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:93:0)
[06:25:45.329] Timer tick, asking Buildbucket for the build status
[06:25:45.391] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:45.391] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:94:0) after 1m0s
[06:26:45.715] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:94:0)
[06:26:45.715] Timer tick, asking Buildbucket for the build status
[06:26:45.773] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:45.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:95:0) after 1m0s
[06:27:48.758] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:95:0)
[06:27:48.758] Timer tick, asking Buildbucket for the build status
[06:27:48.825] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:48.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:96:0) after 1m0s
[06:28:48.865] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:96:0)
[06:28:48.865] Timer tick, asking Buildbucket for the build status
[06:28:48.940] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:48.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:97:0) after 1m0s
[06:29:48.962] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:97:0)
[06:29:48.962] Timer tick, asking Buildbucket for the build status
[06:29:49.062] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:49.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:98:0) after 1m0s
[06:30:49.118] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:98:0)
[06:30:49.118] Timer tick, asking Buildbucket for the build status
[06:30:49.225] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:49.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:99:0) after 1m0s
[06:31:49.249] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:99:0)
[06:31:49.249] Timer tick, asking Buildbucket for the build status
[06:31:49.358] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:49.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:100:0) after 1m0s
[06:32:49.378] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:100:0)
[06:32:49.378] Timer tick, asking Buildbucket for the build status
[06:32:49.497] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:101:0) after 1m0s
[06:33:49.538] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:101:0)
[06:33:49.538] Timer tick, asking Buildbucket for the build status
[06:33:49.653] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:49.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:102:0) after 1m0s
[06:34:49.684] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:102:0)
[06:34:49.684] Timer tick, asking Buildbucket for the build status
[06:34:49.797] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:49.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:103:0) after 1m0s
[06:35:49.872] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:103:0)
[06:35:49.872] Timer tick, asking Buildbucket for the build status
[06:35:50.278] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:50.278] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:104:0) after 1m0s
[06:36:50.295] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:104:0)
[06:36:50.295] Timer tick, asking Buildbucket for the build status
[06:36:50.894] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:50.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:105:0) after 1m0s
[06:37:50.888] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:105:0)
[06:37:50.888] Timer tick, asking Buildbucket for the build status
[06:37:51.236] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:51.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:106:0) after 1m0s
[06:38:51.270] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:106:0)
[06:38:51.270] Timer tick, asking Buildbucket for the build status
[06:38:51.385] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:51.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:107:0) after 1m0s
[06:39:51.429] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:107:0)
[06:39:51.429] Timer tick, asking Buildbucket for the build status
[06:39:51.542] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:51.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:108:0) after 1m0s
[06:40:51.573] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:108:0)
[06:40:51.573] Timer tick, asking Buildbucket for the build status
[06:40:51.681] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:51.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:109:0) after 1m0s
[06:41:51.704] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:109:0)
[06:41:51.704] Timer tick, asking Buildbucket for the build status
[06:41:51.820] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:51.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:110:0) after 1m0s
[06:42:51.843] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:110:0)
[06:42:51.843] Timer tick, asking Buildbucket for the build status
[06:42:51.917] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:51.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:111:0) after 1m0s
[06:43:51.966] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:111:0)
[06:43:51.966] Timer tick, asking Buildbucket for the build status
[06:43:52.042] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:52.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:112:0) after 1m0s
[06:44:52.138] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:112:0)
[06:44:52.138] Timer tick, asking Buildbucket for the build status
[06:44:52.257] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:52.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:113:0) after 1m0s
[06:45:52.309] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:113:0)
[06:45:52.309] Timer tick, asking Buildbucket for the build status
[06:45:52.667] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:52.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:114:0) after 1m0s
[06:46:52.830] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:114:0)
[06:46:52.830] Timer tick, asking Buildbucket for the build status
[06:46:52.921] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:52.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:115:0) after 1m0s
[06:47:52.972] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:115:0)
[06:47:52.972] Timer tick, asking Buildbucket for the build status
[06:47:53.058] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:53.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:116:0) after 1m0s
[06:48:53.182] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:116:0)
[06:48:53.182] Timer tick, asking Buildbucket for the build status
[06:48:53.335] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:53.335] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:117:0) after 1m0s
[06:49:53.355] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:117:0)
[06:49:53.356] Timer tick, asking Buildbucket for the build status
[06:49:53.526] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:53.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:118:0) after 1m0s
[06:50:53.592] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:118:0)
[06:50:53.592] Timer tick, asking Buildbucket for the build status
[06:50:53.675] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:53.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:119:0) after 1m0s
[06:51:53.767] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:119:0)
[06:51:53.767] Timer tick, asking Buildbucket for the build status
[06:51:53.843] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:53.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:120:0) after 1m0s
[06:52:53.869] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:120:0)
[06:52:53.869] Timer tick, asking Buildbucket for the build status
[06:52:55.576] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:55.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:121:0) after 1m0s
[06:53:55.680] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:121:0)
[06:53:55.680] Timer tick, asking Buildbucket for the build status
[06:53:55.808] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:55.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:122:0) after 1m0s
[06:54:55.803] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:122:0)
[06:54:55.803] Timer tick, asking Buildbucket for the build status
[06:54:55.917] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:55.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:123:0) after 1m0s
[06:55:55.989] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:123:0)
[06:55:55.989] Timer tick, asking Buildbucket for the build status
[06:55:56.138] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:56.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:124:0) after 1m0s
[06:56:56.161] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:124:0)
[06:56:56.161] Timer tick, asking Buildbucket for the build status
[06:56:56.459] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:56.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:125:0) after 1m0s
[06:57:56.501] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:125:0)
[06:57:56.501] Timer tick, asking Buildbucket for the build status
[06:57:57.233] Build 8865495510454623072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:57.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030925572511440304:126:0) after 1m0s
[06:58:47.363] Received PubSub notification, asking Buildbucket for the build status
[06:58:47.448] Build 8865495510454623072: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:58:47.448] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603609126014497",
"created_by": "project:chromium",
"created_ts": "1603601651672642",
"id": "8865495510454623072",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3a47b622f08058daa6445ad2799aa6a123179628\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"3a47b622f08058daa6445ad2799aa6a123179628\", \"project\": \"chromium/src\"}], \"$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-8law\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"Linux CFI\", \"buildnumber\": 18759, \"got_angle_revision\": \"7a0263547cda936b320d80c650b69d7522ee1a9f\", \"got_dawn_revision\": \"42103bc2e911144745d8d8930b07ba7e58553a34\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"3a47b622f08058daa6445ad2799aa6a123179628\", \"got_revision_cp\": \"refs/heads/master@{#820599}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"73fe59b55662be0ff07e79da1ece8b9e42415dca\", \"got_v8_revision_cp\": \"refs/heads/8.8.171@{#1}\", \"got_webrtc_revision\": \"3065e6ef3016a906d1db82e1d4322cbd53d43247\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32481}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3a47b622f08058daa6445ad2799aa6a123179628\", \"swarm_hashes_refs/heads/master(at){#820599}_without_patch\": {\"absl_hardening_tests\": \"64f62512e9f03cbf530bff5711b084547b2ef664\", \"accessibility_unittests\": \"3739945aa5d8bcfcbd0b29739c55080409f2ce3f\", \"angle_unittests\": \"a1749d9f60c0db4381604a60b18caf14ec8ac235\", \"app_shell_unittests\": \"6e8bb6afb8862b17eb55cf16d4d6403b4f16ea38\", \"aura_unittests\": \"3afb3fde93f1c0fce381856dfbf640a870eeb872\", \"base_unittests\": \"6c4ed79285daf6081f34b2f9bb7e635e41abf453\", \"base_util_unittests\": \"bd7ba5f422993e3378e5f50327aef1d8f9db4923\", \"blink_common_unittests\": \"e3097465c45ca3d32b7a112b93b041b34b9846d8\", \"blink_fuzzer_unittests\": \"d57eb4db31814d594bf90d1e9fd51d10d0c6196e\", \"blink_heap_unittests\": \"858256cca36a20e79604c738e6b2dc863134fd8b\", \"blink_platform_unittests\": \"8593572019f97e29cdb38aa86b8c267a25d6a488\", \"blink_unittests\": \"5d422ab9ffe1ae740ceba233794d1c57069aca36\", \"boringssl_crypto_tests\": \"e149e781e9928a29b4dd9583f17cfba686900c7b\", \"boringssl_ssl_tests\": \"67e991e557e4bcf147544aa74198c7c6c19a1c54\", \"browser_tests\": \"288ed0cbf46c72b21cf2001cf78d3151de00b731\", \"capture_unittests\": \"c65a1e95a130051627305b7996906eee72a91638\", \"cast_unittests\": \"ae27d0c76df19edfe7c7621686230f7cee08b67e\", \"cc_unittests\": \"fc69925be7a984d981d588d8bc45ee6254e656e4\", \"chrome_app_unittests\": \"ade3b1156dbd97b671115c1b9d6cc0547122cd61\", \"chromedriver_unittests\": \"a1c70837a00a7c2f12b4b788dfaec3fe08dcf0c1\", \"components_browsertests\": \"877de2af09741837ca07521c1f25821b2f9cbd7f\", \"components_unittests\": \"70a417492c4824d3685db01ac462c5dfc8e9b3ca\", \"compositor_unittests\": \"980351779ade1d763d96a54442d9faf1278012f0\", \"content_browsertests\": \"cb20737ae20e4d80d2cde4511716fafa4f3ec244\", \"content_unittests\": \"90ccfe57deaccf5aea84d73fa0cac08d9ce1ce54\", \"cronet_tests\": \"8a6906d066bac73fdc0cab61beee5c142ccd5d27\", \"cronet_unittests\": \"6aaef5946e0b40575c7349514f6781966a6d7b2d\", \"crypto_unittests\": \"1fa4a8923d25457a5b0e23a99d4d2bede6a7ceba\", \"dbus_unittests\": \"0182c7b13835f4cce4225d6092d3350a1308b816\", \"device_unittests\": \"3bec8d43475965b7e1bf68fb4410ce35c333e28b\", \"display_unittests\": \"fe60f68ce7141d28f72f1485b74fd438a096b809\", \"events_unittests\": \"8ef9b84a71101b4167b482a24d690dfdc2136133\", \"extensions_browsertests\": \"d4c278302596d696e2ec42bc950a40b8815b401d\", \"extensions_unittests\": \"f26d36d947f8ebfbfdeee419b57e7c4bf547264a\", \"filesystem_service_unittests\": \"2bb333643bcb36a135fd6714b5f327ee11cc718d\", \"gcm_unit_tests\": \"7307effccef0d30f1cb4a52b77a464a4d6a2acbb\", \"gfx_unittests\": \"8ca44e73139ee7faf44077ac33c167ba4979ce3d\", \"gin_unittests\": \"bd528d53cfacc7a2bbf1957bc96d1be081594092\", \"gl_tests\": \"c47af5e7ca0a95d9663e174aaddf9b57fbd0e5c0\", \"gl_unittests\": \"5418abebbe792f29b70816b14c8953151b26343b\", \"google_apis_unittests\": \"d2c7c8271e96e2624cadce9a0545cffb9eff560c\", \"gpu_unittests\": \"c3115c8aebb6a0e578451d4e9bd6ffb61c70b92c\", \"gwp_asan_unittests\": \"9b4c1237e71d0d3384f222e9fd8250de35a3b406\", \"headless_browsertests\": \"a193be301be418aeaf84dfbf56d741f8a5c94642\", \"headless_unittests\": \"c664f6b613da99e853cddfb6e1f8874af5171286\", \"interactive_ui_tests\": \"4e23e94c2e5b5fa13b8b17078d54bbef061aef54\", \"ipc_tests\": \"a4d94a368215d9d345e814b042dcce7ac33f25ed\", \"jingle_unittests\": \"3140f400d2c1a870a3d812f629f67900d7500f63\", \"latency_unittests\": \"b9c1ec9b7ccc127eeb829a92f18bb8f9ef27990c\", \"libjingle_xmpp_unittests\": \"04bc62d64e01dd731326a93760cec313311785d2\", \"media_blink_unittests\": \"6dcbaee05d0615ce2333a519ecd314a16a7e5d45\", \"media_unittests\": \"93ca74f3a961c72da07c5a144980c49a1027ba44\", \"message_center_unittests\": \"921c6994377559e109c300c84070a3888af5958b\", \"midi_unittests\": \"0abd54edda4ebf01018e9d9c5bc061b2ad985c84\", \"mojo_core_unittests\": \"f11ade16466cc34749b7a32dbc3aca860f9a8d20\", \"mojo_unittests\": \"39af4c06160e05b9f764f997273b9cc269779dde\", \"nacl_helper_nonsfi_unittests\": \"2ee71dae2e47ae9d8a7e39a784c5186400d7319d\", \"nacl_loader_unittests\": \"dcea8fcf54aa24131ca35971cb1d75bdf20ece9a\", \"native_theme_unittests\": \"054ee7039471ff4c8835f8ad9704397c9d2a297a\", \"net_unittests\": \"653cbf6086987250af9d460b52c012a3d8f74cee\", \"pdf_unittests\": \"f3500f88e6bb5c50053ea4ddd7c632f752e0d1ed\", \"perfetto_unittests\": \"f52f5e9c31bddf461a7f0a9cc7119b1210c495d0\", \"ppapi_unittests\": \"6bc59ee10e37827aa8a7b9d2b1227d694337d94f\", \"printing_unittests\": \"bb674dd07ad20f936fda8ac49aa1208002fca6a3\", \"remoting_unittests\": \"9eb00c14e711a0efaa1cc04a1bd0599eda749693\", \"sandbox_linux_unittests\": \"7a9d081b42297dcd6d9879ba15d2147670786d1a\", \"service_manager_unittests\": \"ed6710718d1533a35d0d33ffb196c22619004ac8\", \"services_unittests\": \"f0e2e0f69c41b0d6c85d43cb9d0068fd9fea1721\", \"shell_dialogs_unittests\": \"e4bff8ce0fb6fc46da259adad2bbdee2914f6fe4\", \"skia_unittests\": \"ca5c1aea2304b33ea083b8bf3f9eb9c73ba02bd5\", \"snapshot_unittests\": \"66db5e292f8d7388c0414476fde57a1efe64f134\", \"sql_unittests\": \"6824602b65e1d34bad5d7b33da6d089fb19ad4af\", \"storage_unittests\": \"f874edb50690193e000388e9bc97b52cdefa793d\", \"sync_integration_tests\": \"51434725601eb06b10a277fcff14135c10136653\", \"traffic_annotation_auditor_unittests\": \"955e2c3d311b7a44b732e8f58efc6c360097ab94\", \"ui_base_unittests\": \"84f91b4639b051ee7fd75b52304588ecf1e87ab2\", \"ui_touch_selection_unittests\": \"1b7e23a66aa7008855db2c464e6d7e37e809b9d1\", \"unit_tests\": \"4c4cba24612d608c04043c1b64cfdf1bf6aa2534\", \"url_unittests\": \"f939f523c4c4deb05921789ac76b9aaf5d747b47\", \"views_unittests\": \"43e4f9aa672c3a2e22670613500b221232fdc029\", \"viz_unittests\": \"a7fe9dc2d812f1daa50efb0a0cff2be949ceb7df\", \"vr_common_unittests\": \"926f2254b73d47b64540e91f9ca21f0245c335bf\", \"vr_pixeltests\": \"97214a7b8c5721f6c7109cc0722d29594205a5fb\", \"wm_unittests\": \"04b23e51bdcffd23e60a82ce83b7acc3e29fdea3\", \"wtf_unittests\": \"14cc959b47235f1eea6d42d826a7e4c537c3569e\", \"zlib_unittests\": \"742eb25b54aa81e53626b9a97f5900e337217451\"}}, \"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-8law\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603601654745688",
"status": "COMPLETED",
"status_changed_ts": "1603609126331487",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/18759",
"builder:Linux CFI",
"buildset:commit/git/3a47b622f08058daa6445ad2799aa6a123179628",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a47b622f08058daa6445ad2799aa6a123179628",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030925572511440304",
"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/8865495510454623072/+/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:4f76ece3d3600410",
"user_agent:luci-scheduler"
],
"updated_ts": "1603609126336243",
"url": "https://ci.chromium.org/b/8865495510454623072",
"utcnow_ts": "1603609127439187"
}
}
[06:58:47.448] Invocation finished in 2h4m37.121634886s with status SUCCEEDED