[17:01:49.452] New invocation is queued and will start shortly
[17:01:50.922] Starting the invocation (attempt 1)
[17:01:50.961] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:01:50.961] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:01:50.961] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030879792618207152",
"parameters_json": "{\"builder_name\":\"Linux CFI\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux CFI",
"scheduler_invocation_id:9030879792618207152",
"scheduler_job_id:chromium/Linux CFI",
"user_agent:luci-scheduler",
"buildset:commit/git/1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9",
"gitiles_ref:refs/heads/master"
]
}
[17:01:52.126] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603645311080563",
"id": "8865449730247625904",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603645311901282",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/18765",
"builder:Linux CFI",
"buildset:commit/git/1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030879792618207152",
"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/8865449730247625904/+/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": "1603645311901427",
"url": "https://ci.chromium.org/b/8865449730247625904",
"utcnow_ts": "1603645312105727"
}
}
[17:01:52.126] Task URL: https://ci.chromium.org/b/8865449730247625904
[17:01:52.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:2:0) after 1m0s
[17:01:53.477] Received PubSub notification, asking Buildbucket for the build status
[17:01:53.565] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:52.132] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:2:0)
[17:02:52.132] Timer tick, asking Buildbucket for the build status
[17:02:52.244] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:52.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:4:0) after 1m0s
[17:03:52.277] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:4:0)
[17:03:52.277] Timer tick, asking Buildbucket for the build status
[17:03:52.522] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:52.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:5:0) after 1m0s
[17:04:52.557] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:5:0)
[17:04:52.557] Timer tick, asking Buildbucket for the build status
[17:04:52.749] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:52.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:6:0) after 1m0s
[17:05:52.769] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:6:0)
[17:05:52.769] Timer tick, asking Buildbucket for the build status
[17:05:53.032] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:53.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:7:0) after 1m0s
[17:06:53.052] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:7:0)
[17:06:53.052] Timer tick, asking Buildbucket for the build status
[17:06:53.751] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:53.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:8:0) after 1m0s
[17:07:53.757] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:8:0)
[17:07:53.757] Timer tick, asking Buildbucket for the build status
[17:07:53.824] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:53.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:9:0) after 1m0s
[17:08:53.857] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:9:0)
[17:08:53.857] Timer tick, asking Buildbucket for the build status
[17:08:53.951] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:53.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:10:0) after 1m0s
[17:09:53.941] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:10:0)
[17:09:53.941] Timer tick, asking Buildbucket for the build status
[17:09:54.022] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:54.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:11:0) after 1m0s
[17:10:54.115] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:11:0)
[17:10:54.115] Timer tick, asking Buildbucket for the build status
[17:10:54.174] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:54.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:12:0) after 1m0s
[17:11:54.146] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:12:0)
[17:11:54.146] Timer tick, asking Buildbucket for the build status
[17:11:54.254] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:54.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:13:0) after 1m0s
[17:12:54.305] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:13:0)
[17:12:54.305] Timer tick, asking Buildbucket for the build status
[17:12:54.357] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:54.357] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:14:0) after 1m0s
[17:13:54.389] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:14:0)
[17:13:54.389] Timer tick, asking Buildbucket for the build status
[17:13:54.467] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:54.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:15:0) after 1m0s
[17:14:54.491] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:15:0)
[17:14:54.491] Timer tick, asking Buildbucket for the build status
[17:14:54.555] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:54.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:16:0) after 1m0s
[17:15:54.653] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:16:0)
[17:15:54.653] Timer tick, asking Buildbucket for the build status
[17:15:54.717] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:54.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:17:0) after 1m0s
[17:16:54.736] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:17:0)
[17:16:54.736] Timer tick, asking Buildbucket for the build status
[17:16:56.182] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:56.182] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:18:0) after 1m0s
[17:17:56.208] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:18:0)
[17:17:56.208] Timer tick, asking Buildbucket for the build status
[17:17:56.265] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:56.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:19:0) after 1m0s
[17:18:56.286] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:19:0)
[17:18:56.286] Timer tick, asking Buildbucket for the build status
[17:18:56.347] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:56.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:20:0) after 1m0s
[17:19:56.421] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:20:0)
[17:19:56.421] Timer tick, asking Buildbucket for the build status
[17:19:56.493] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:56.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:21:0) after 1m0s
[17:20:56.598] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:21:0)
[17:20:56.598] Timer tick, asking Buildbucket for the build status
[17:20:56.699] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:56.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:22:0) after 1m0s
[17:21:56.768] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:22:0)
[17:21:56.768] Timer tick, asking Buildbucket for the build status
[17:21:56.878] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:56.878] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:23:0) after 1m0s
[17:22:56.931] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:23:0)
[17:22:56.931] Timer tick, asking Buildbucket for the build status
[17:22:56.993] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:56.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:24:0) after 1m0s
[17:23:57.079] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:24:0)
[17:23:57.079] Timer tick, asking Buildbucket for the build status
[17:23:57.146] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:57.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:25:0) after 1m0s
[17:24:57.200] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:25:0)
[17:24:57.200] Timer tick, asking Buildbucket for the build status
[17:24:57.408] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:57.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:26:0) after 1m0s
[17:25:57.464] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:26:0)
[17:25:57.464] Timer tick, asking Buildbucket for the build status
[17:25:57.529] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:57.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:27:0) after 1m0s
[17:26:57.534] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:27:0)
[17:26:57.534] Timer tick, asking Buildbucket for the build status
[17:26:57.630] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:57.630] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:28:0) after 1m0s
[17:27:57.704] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:28:0)
[17:27:57.704] Timer tick, asking Buildbucket for the build status
[17:27:57.760] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:57.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:29:0) after 1m0s
[17:28:57.929] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:29:0)
[17:28:57.929] Timer tick, asking Buildbucket for the build status
[17:28:57.993] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:57.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:30:0) after 1m0s
[17:29:58.081] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:30:0)
[17:29:58.081] Timer tick, asking Buildbucket for the build status
[17:29:58.151] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:58.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:31:0) after 1m0s
[17:30:58.232] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:31:0)
[17:30:58.232] Timer tick, asking Buildbucket for the build status
[17:30:58.337] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:58.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:32:0) after 1m0s
[17:31:58.932] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:32:0)
[17:31:58.932] Timer tick, asking Buildbucket for the build status
[17:31:59.593] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:59.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:33:0) after 1m0s
[17:32:59.675] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:33:0)
[17:32:59.675] Timer tick, asking Buildbucket for the build status
[17:32:59.917] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:59.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:34:0) after 1m0s
[17:34:00.012] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:34:0)
[17:34:00.012] Timer tick, asking Buildbucket for the build status
[17:34:00.294] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:00.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:35:0) after 1m0s
[17:35:00.361] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:35:0)
[17:35:00.361] Timer tick, asking Buildbucket for the build status
[17:35:00.428] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:00.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:36:0) after 1m0s
[17:36:00.593] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:36:0)
[17:36:00.593] Timer tick, asking Buildbucket for the build status
[17:36:00.989] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:00.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:37:0) after 1m0s
[17:37:01.010] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:37:0)
[17:37:01.010] Timer tick, asking Buildbucket for the build status
[17:37:01.152] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:01.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:38:0) after 1m0s
[17:38:01.208] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:38:0)
[17:38:01.208] Timer tick, asking Buildbucket for the build status
[17:38:01.272] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:01.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:39:0) after 1m0s
[17:39:01.388] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:39:0)
[17:39:01.388] Timer tick, asking Buildbucket for the build status
[17:39:01.442] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:01.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:40:0) after 1m0s
[17:40:01.468] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:40:0)
[17:40:01.468] Timer tick, asking Buildbucket for the build status
[17:40:01.539] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:01.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:41:0) after 1m0s
[17:41:01.568] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:41:0)
[17:41:01.568] Timer tick, asking Buildbucket for the build status
[17:41:01.643] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:01.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:42:0) after 1m0s
[17:42:01.763] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:42:0)
[17:42:01.763] Timer tick, asking Buildbucket for the build status
[17:42:01.888] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:01.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:43:0) after 1m0s
[17:43:01.911] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:43:0)
[17:43:01.911] Timer tick, asking Buildbucket for the build status
[17:43:01.974] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:01.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:44:0) after 1m0s
[17:44:02.182] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:44:0)
[17:44:02.182] Timer tick, asking Buildbucket for the build status
[17:44:02.241] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:02.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:45:0) after 1m0s
[17:45:02.417] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:45:0)
[17:45:02.417] Timer tick, asking Buildbucket for the build status
[17:45:02.563] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:02.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:46:0) after 1m0s
[17:46:02.886] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:46:0)
[17:46:02.886] Timer tick, asking Buildbucket for the build status
[17:46:02.949] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:02.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:47:0) after 1m0s
[17:47:02.977] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:47:0)
[17:47:02.977] Timer tick, asking Buildbucket for the build status
[17:47:03.077] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:03.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:48:0) after 1m0s
[17:48:03.228] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:48:0)
[17:48:03.228] Timer tick, asking Buildbucket for the build status
[17:48:03.522] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:03.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:49:0) after 1m0s
[17:49:03.739] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:49:0)
[17:49:03.739] Timer tick, asking Buildbucket for the build status
[17:49:05.588] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:05.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:50:0) after 1m0s
[17:50:05.664] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:50:0)
[17:50:05.664] Timer tick, asking Buildbucket for the build status
[17:50:05.810] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:05.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:51:0) after 1m0s
[17:51:05.888] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:51:0)
[17:51:05.888] Timer tick, asking Buildbucket for the build status
[17:51:05.942] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:05.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:52:0) after 1m0s
[17:52:05.947] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:52:0)
[17:52:05.947] Timer tick, asking Buildbucket for the build status
[17:52:06.157] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:06.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:53:0) after 1m0s
[17:53:06.176] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:53:0)
[17:53:06.176] Timer tick, asking Buildbucket for the build status
[17:53:06.296] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:06.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:54:0) after 1m0s
[17:54:06.497] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:54:0)
[17:54:06.497] Timer tick, asking Buildbucket for the build status
[17:54:06.559] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:06.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:55:0) after 1m0s
[17:55:06.757] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:55:0)
[17:55:06.758] Timer tick, asking Buildbucket for the build status
[17:55:06.822] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:06.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:56:0) after 1m0s
[17:56:06.843] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:56:0)
[17:56:06.843] Timer tick, asking Buildbucket for the build status
[17:56:06.937] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:06.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:57:0) after 1m0s
[17:57:06.975] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:57:0)
[17:57:06.975] Timer tick, asking Buildbucket for the build status
[17:57:07.079] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:07.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:58:0) after 1m0s
[17:58:07.107] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:58:0)
[17:58:07.107] Timer tick, asking Buildbucket for the build status
[17:58:07.174] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:07.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:59:0) after 1m0s
[17:59:07.202] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:59:0)
[17:59:07.202] Timer tick, asking Buildbucket for the build status
[17:59:07.460] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:07.460] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:60:0) after 1m0s
[18:00:07.546] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:60:0)
[18:00:07.546] Timer tick, asking Buildbucket for the build status
[18:00:07.637] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:07.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:61:0) after 1m0s
[18:01:08.815] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:61:0)
[18:01:08.815] Timer tick, asking Buildbucket for the build status
[18:01:08.891] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:08.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:62:0) after 1m0s
[18:02:09.029] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:62:0)
[18:02:09.029] Timer tick, asking Buildbucket for the build status
[18:02:09.160] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:09.160] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:63:0) after 1m0s
[18:03:09.270] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:63:0)
[18:03:09.270] Timer tick, asking Buildbucket for the build status
[18:03:09.356] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:09.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:64:0) after 1m0s
[18:04:09.510] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:64:0)
[18:04:09.510] Timer tick, asking Buildbucket for the build status
[18:04:09.568] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:09.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:65:0) after 1m0s
[18:05:09.728] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:65:0)
[18:05:09.728] Timer tick, asking Buildbucket for the build status
[18:05:09.814] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:09.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:66:0) after 1m0s
[18:06:09.864] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:66:0)
[18:06:09.864] Timer tick, asking Buildbucket for the build status
[18:06:09.934] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:09.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:67:0) after 1m0s
[18:07:10.015] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:67:0)
[18:07:10.015] Timer tick, asking Buildbucket for the build status
[18:07:10.120] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:10.120] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:68:0) after 1m0s
[18:08:10.143] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:68:0)
[18:08:10.143] Timer tick, asking Buildbucket for the build status
[18:08:10.216] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:10.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:69:0) after 1m0s
[18:09:10.243] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:69:0)
[18:09:10.243] Timer tick, asking Buildbucket for the build status
[18:09:10.309] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:10.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:70:0) after 1m0s
[18:10:10.364] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:70:0)
[18:10:10.364] Timer tick, asking Buildbucket for the build status
[18:10:10.450] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:10.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:71:0) after 1m0s
[18:11:10.547] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:71:0)
[18:11:10.547] Timer tick, asking Buildbucket for the build status
[18:11:10.656] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:10.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:72:0) after 1m0s
[18:12:10.656] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:72:0)
[18:12:10.656] Timer tick, asking Buildbucket for the build status
[18:12:10.821] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:10.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:73:0) after 1m0s
[18:13:10.895] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:73:0)
[18:13:10.895] Timer tick, asking Buildbucket for the build status
[18:13:10.961] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:10.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:74:0) after 1m0s
[18:14:11.101] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:74:0)
[18:14:11.101] Timer tick, asking Buildbucket for the build status
[18:14:11.171] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:11.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:75:0) after 1m0s
[18:15:11.297] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:75:0)
[18:15:11.297] Timer tick, asking Buildbucket for the build status
[18:15:11.420] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:11.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:76:0) after 1m0s
[18:16:11.525] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:76:0)
[18:16:11.525] Timer tick, asking Buildbucket for the build status
[18:16:11.809] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:11.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:77:0) after 1m0s
[18:17:11.932] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:77:0)
[18:17:11.932] Timer tick, asking Buildbucket for the build status
[18:17:12.049] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:12.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:78:0) after 1m0s
[18:18:12.174] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:78:0)
[18:18:12.174] Timer tick, asking Buildbucket for the build status
[18:18:12.242] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:12.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:79:0) after 1m0s
[18:19:12.437] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:79:0)
[18:19:12.437] Timer tick, asking Buildbucket for the build status
[18:19:12.691] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:12.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:80:0) after 1m0s
[18:20:12.719] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:80:0)
[18:20:12.719] Timer tick, asking Buildbucket for the build status
[18:20:13.026] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:13.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:81:0) after 1m0s
[18:21:13.175] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:81:0)
[18:21:13.175] Timer tick, asking Buildbucket for the build status
[18:21:13.445] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:13.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:82:0) after 1m0s
[18:22:13.498] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:82:0)
[18:22:13.498] Timer tick, asking Buildbucket for the build status
[18:22:13.646] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:13.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:83:0) after 1m0s
[18:23:13.695] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:83:0)
[18:23:13.695] Timer tick, asking Buildbucket for the build status
[18:23:13.766] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:13.766] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:84:0) after 1m0s
[18:24:13.787] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:84:0)
[18:24:13.787] Timer tick, asking Buildbucket for the build status
[18:24:13.859] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:13.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:85:0) after 1m0s
[18:25:13.887] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:85:0)
[18:25:13.887] Timer tick, asking Buildbucket for the build status
[18:25:14.054] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:14.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:86:0) after 1m0s
[18:26:14.193] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:86:0)
[18:26:14.193] Timer tick, asking Buildbucket for the build status
[18:26:14.253] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:14.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:87:0) after 1m0s
[18:27:14.332] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:87:0)
[18:27:14.332] Timer tick, asking Buildbucket for the build status
[18:27:14.432] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:14.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:88:0) after 1m0s
[18:28:14.469] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:88:0)
[18:28:14.469] Timer tick, asking Buildbucket for the build status
[18:28:14.655] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:14.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:89:0) after 1m0s
[18:29:16.164] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:89:0)
[18:29:16.164] Timer tick, asking Buildbucket for the build status
[18:29:16.232] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:16.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:90:0) after 1m0s
[18:30:16.432] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:90:0)
[18:30:16.432] Timer tick, asking Buildbucket for the build status
[18:30:16.501] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:16.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:91:0) after 1m0s
[18:31:16.659] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:91:0)
[18:31:16.659] Timer tick, asking Buildbucket for the build status
[18:31:16.718] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:16.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:92:0) after 1m0s
[18:32:16.995] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:92:0)
[18:32:16.995] Timer tick, asking Buildbucket for the build status
[18:32:17.123] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:17.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:93:0) after 1m0s
[18:33:17.145] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:93:0)
[18:33:17.145] Timer tick, asking Buildbucket for the build status
[18:33:17.293] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:17.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:94:0) after 1m0s
[18:34:17.320] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:94:0)
[18:34:17.320] Timer tick, asking Buildbucket for the build status
[18:34:17.379] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:17.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:95:0) after 1m0s
[18:35:17.477] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:95:0)
[18:35:17.477] Timer tick, asking Buildbucket for the build status
[18:35:17.706] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:17.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:96:0) after 1m0s
[18:36:17.799] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:96:0)
[18:36:17.799] Timer tick, asking Buildbucket for the build status
[18:36:17.890] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:17.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:97:0) after 1m0s
[18:37:17.992] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:97:0)
[18:37:17.992] Timer tick, asking Buildbucket for the build status
[18:37:18.104] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:18.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:98:0) after 1m0s
[18:38:18.099] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:98:0)
[18:38:18.099] Timer tick, asking Buildbucket for the build status
[18:38:18.222] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:18.223] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:99:0) after 1m0s
[18:39:18.294] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:99:0)
[18:39:18.294] Timer tick, asking Buildbucket for the build status
[18:39:18.455] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:18.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:100:0) after 1m0s
[18:40:18.499] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:100:0)
[18:40:18.499] Timer tick, asking Buildbucket for the build status
[18:40:18.622] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:18.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:101:0) after 1m0s
[18:41:18.676] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:101:0)
[18:41:18.676] Timer tick, asking Buildbucket for the build status
[18:41:19.019] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:19.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:102:0) after 1m0s
[18:42:19.047] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:102:0)
[18:42:19.047] Timer tick, asking Buildbucket for the build status
[18:42:19.184] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:19.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:103:0) after 1m0s
[18:43:19.242] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:103:0)
[18:43:19.242] Timer tick, asking Buildbucket for the build status
[18:43:19.526] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:19.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:104:0) after 1m0s
[18:44:19.567] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:104:0)
[18:44:19.567] Timer tick, asking Buildbucket for the build status
[18:44:19.732] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:19.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:105:0) after 1m0s
[18:45:19.759] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:105:0)
[18:45:19.759] Timer tick, asking Buildbucket for the build status
[18:45:19.871] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:19.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:106:0) after 1m0s
[18:46:19.896] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:106:0)
[18:46:19.896] Timer tick, asking Buildbucket for the build status
[18:46:20.193] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:20.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:107:0) after 1m0s
[18:47:20.255] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:107:0)
[18:47:20.255] Timer tick, asking Buildbucket for the build status
[18:47:20.375] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:20.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:108:0) after 1m0s
[18:48:20.423] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:108:0)
[18:48:20.423] Timer tick, asking Buildbucket for the build status
[18:48:20.542] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:20.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:109:0) after 1m0s
[18:49:20.703] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:109:0)
[18:49:20.703] Timer tick, asking Buildbucket for the build status
[18:49:21.116] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:21.116] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:110:0) after 1m0s
[18:50:27.001] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:110:0)
[18:50:27.001] Timer tick, asking Buildbucket for the build status
[18:50:27.123] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:27.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:111:0) after 1m0s
[18:51:27.298] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:111:0)
[18:51:27.298] Timer tick, asking Buildbucket for the build status
[18:51:27.586] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:27.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:112:0) after 1m0s
[18:52:27.711] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:112:0)
[18:52:27.711] Timer tick, asking Buildbucket for the build status
[18:52:27.830] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:27.830] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:113:0) after 1m0s
[18:53:27.921] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:113:0)
[18:53:27.922] Timer tick, asking Buildbucket for the build status
[18:53:27.986] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:27.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:114:0) after 1m0s
[18:54:28.064] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:114:0)
[18:54:28.064] Timer tick, asking Buildbucket for the build status
[18:54:28.162] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:28.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:115:0) after 1m0s
[18:55:28.296] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:115:0)
[18:55:28.296] Timer tick, asking Buildbucket for the build status
[18:55:28.370] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:28.370] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:116:0) after 1m0s
[18:56:28.465] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:116:0)
[18:56:28.465] Timer tick, asking Buildbucket for the build status
[18:56:28.566] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:28.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:117:0) after 1m0s
[18:57:28.634] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:117:0)
[18:57:28.634] Timer tick, asking Buildbucket for the build status
[18:57:28.757] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:28.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:118:0) after 1m0s
[18:58:28.852] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:118:0)
[18:58:28.852] Timer tick, asking Buildbucket for the build status
[18:58:28.968] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:28.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:119:0) after 1m0s
[18:59:29.031] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:119:0)
[18:59:29.031] Timer tick, asking Buildbucket for the build status
[18:59:29.132] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:29.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:120:0) after 1m0s
[19:00:29.162] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:120:0)
[19:00:29.162] Timer tick, asking Buildbucket for the build status
[19:00:29.265] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:29.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:121:0) after 1m0s
[19:01:29.355] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:121:0)
[19:01:29.355] Timer tick, asking Buildbucket for the build status
[19:01:29.538] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:29.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:122:0) after 1m0s
[19:02:29.601] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:122:0)
[19:02:29.601] Timer tick, asking Buildbucket for the build status
[19:02:29.728] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:29.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:123:0) after 1m0s
[19:03:29.808] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:123:0)
[19:03:29.808] Timer tick, asking Buildbucket for the build status
[19:03:29.909] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:29.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:124:0) after 1m0s
[19:04:30.006] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:124:0)
[19:04:30.006] Timer tick, asking Buildbucket for the build status
[19:04:30.128] Build 8865449730247625904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:30.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:9030879792618207152:125:0) after 1m0s
[19:05:03.077] Received PubSub notification, asking Buildbucket for the build status
[19:05:03.190] Build 8865449730247625904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:05:03.190] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603652702132446",
"created_by": "project:chromium",
"created_ts": "1603645311080563",
"id": "8865449730247625904",
"parameters_json": "{\"builder_name\": \"Linux CFI\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9\"}}",
"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\": \"1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9\", \"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\": 18765, \"got_angle_revision\": \"7a0263547cda936b320d80c650b69d7522ee1a9f\", \"got_dawn_revision\": \"42103bc2e911144745d8d8930b07ba7e58553a34\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9\", \"got_revision_cp\": \"refs/heads/master@{#820626}\", \"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\": \"e6fa238f76e50a8caa0547074f75443bbfd7b986\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32482}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9\", \"swarm_hashes_refs/heads/master(at){#820626}_without_patch\": {\"absl_hardening_tests\": \"64f62512e9f03cbf530bff5711b084547b2ef664\", \"accessibility_unittests\": \"3739945aa5d8bcfcbd0b29739c55080409f2ce3f\", \"angle_unittests\": \"a1749d9f60c0db4381604a60b18caf14ec8ac235\", \"app_shell_unittests\": \"2224966bd7ac790b73e09271bf2426cda5097be6\", \"aura_unittests\": \"253cadcd2c59e8111ce703f2abacc01a9365bf23\", \"base_unittests\": \"6c4ed79285daf6081f34b2f9bb7e635e41abf453\", \"base_util_unittests\": \"bd7ba5f422993e3378e5f50327aef1d8f9db4923\", \"blink_common_unittests\": \"e3097465c45ca3d32b7a112b93b041b34b9846d8\", \"blink_fuzzer_unittests\": \"d57eb4db31814d594bf90d1e9fd51d10d0c6196e\", \"blink_heap_unittests\": \"858256cca36a20e79604c738e6b2dc863134fd8b\", \"blink_platform_unittests\": \"4530df48133fb8f419f956e1ba13d2c0bf386030\", \"blink_unittests\": \"a405173f70ef282180d8ae8eefdfe3c2fac57108\", \"boringssl_crypto_tests\": \"e149e781e9928a29b4dd9583f17cfba686900c7b\", \"boringssl_ssl_tests\": \"67e991e557e4bcf147544aa74198c7c6c19a1c54\", \"browser_tests\": \"091e209ace927dafa5694aff56f1056b83e9f54e\", \"capture_unittests\": \"fa4ac5d79c1400e172191442130c361328df14ac\", \"cast_unittests\": \"ae27d0c76df19edfe7c7621686230f7cee08b67e\", \"cc_unittests\": \"5dc0cd6943f6b8886fce267b95a7579bb902c5ac\", \"chrome_app_unittests\": \"b4f7075070e1115b15b0914644b7ea957d1197d9\", \"chromedriver_unittests\": \"ca8c5a646f5db24a5a89c7136c8b218e57c71445\", \"components_browsertests\": \"798d9de481df3c927900e9f9f14f3592f6f5ffe0\", \"components_unittests\": \"662e0b7888aa63295312cd73d037f244a7241856\", \"compositor_unittests\": \"613f679dcdd7726b6cb3b816691a0894a335dd71\", \"content_browsertests\": \"10acd0d5af4b0462dcd809811b82e0c90a9298fb\", \"content_unittests\": \"87e00cffb4690edd696610d8e66f17358ef4d102\", \"cronet_tests\": \"8a6906d066bac73fdc0cab61beee5c142ccd5d27\", \"cronet_unittests\": \"6aaef5946e0b40575c7349514f6781966a6d7b2d\", \"crypto_unittests\": \"1fa4a8923d25457a5b0e23a99d4d2bede6a7ceba\", \"dbus_unittests\": \"0182c7b13835f4cce4225d6092d3350a1308b816\", \"device_unittests\": \"3bec8d43475965b7e1bf68fb4410ce35c333e28b\", \"display_unittests\": \"b083b1ea9576796a2858c957c0909fb1146e195e\", \"events_unittests\": \"8ef9b84a71101b4167b482a24d690dfdc2136133\", \"extensions_browsertests\": \"9c376e927f53219a9bffd327d6ed7e03d740f973\", \"extensions_unittests\": \"69b8e96bfdf8da944329c1bdfe547609d55294ea\", \"filesystem_service_unittests\": \"2bb333643bcb36a135fd6714b5f327ee11cc718d\", \"gcm_unit_tests\": \"7307effccef0d30f1cb4a52b77a464a4d6a2acbb\", \"gfx_unittests\": \"9d2925415d170443fa8fde393a3da6a3ba15b8d5\", \"gin_unittests\": \"bd528d53cfacc7a2bbf1957bc96d1be081594092\", \"gl_tests\": \"ec6045f4fd0b962951ab8ce28b496abf6fd4be14\", \"gl_unittests\": \"9a9a3003e7929cc05dbd4d3e38ee2b5c1b88a43c\", \"google_apis_unittests\": \"d2c7c8271e96e2624cadce9a0545cffb9eff560c\", \"gpu_unittests\": \"4a23d26b2a0b3ad57cbedec28708101c02e7178a\", \"gwp_asan_unittests\": \"9b4c1237e71d0d3384f222e9fd8250de35a3b406\", \"headless_browsertests\": \"82047b899289230457c16317dedd7dc87e5679db\", \"headless_unittests\": \"c664f6b613da99e853cddfb6e1f8874af5171286\", \"interactive_ui_tests\": \"113e6eac40a2baeb7d6013fc50e5ff96eceb80a1\", \"ipc_tests\": \"a4d94a368215d9d345e814b042dcce7ac33f25ed\", \"jingle_unittests\": \"3140f400d2c1a870a3d812f629f67900d7500f63\", \"latency_unittests\": \"b9c1ec9b7ccc127eeb829a92f18bb8f9ef27990c\", \"libjingle_xmpp_unittests\": \"04bc62d64e01dd731326a93760cec313311785d2\", \"media_blink_unittests\": \"21e06a1d54699e560977ee36cf900577bab6905e\", \"media_unittests\": \"4e8f3483aefee04c876e56c545fc59ccf268abe3\", \"message_center_unittests\": \"5152c2eea2469412acb1ae7ee917329b2e0fd6b1\", \"midi_unittests\": \"0abd54edda4ebf01018e9d9c5bc061b2ad985c84\", \"mojo_core_unittests\": \"f11ade16466cc34749b7a32dbc3aca860f9a8d20\", \"mojo_unittests\": \"39af4c06160e05b9f764f997273b9cc269779dde\", \"nacl_helper_nonsfi_unittests\": \"2ee71dae2e47ae9d8a7e39a784c5186400d7319d\", \"nacl_loader_unittests\": \"31d6f68d713bf17be6527c805f2f8bc2c07877cf\", \"native_theme_unittests\": \"054ee7039471ff4c8835f8ad9704397c9d2a297a\", \"net_unittests\": \"653cbf6086987250af9d460b52c012a3d8f74cee\", \"pdf_unittests\": \"f3500f88e6bb5c50053ea4ddd7c632f752e0d1ed\", \"perfetto_unittests\": \"f52f5e9c31bddf461a7f0a9cc7119b1210c495d0\", \"ppapi_unittests\": \"2c5a29d3071f25ddc2985d9e72d769e74cef803a\", \"printing_unittests\": \"bb674dd07ad20f936fda8ac49aa1208002fca6a3\", \"remoting_unittests\": \"aed29d84300ebb317bc71165bc5d7985fded2c20\", \"sandbox_linux_unittests\": \"7a9d081b42297dcd6d9879ba15d2147670786d1a\", \"service_manager_unittests\": \"ed6710718d1533a35d0d33ffb196c22619004ac8\", \"services_unittests\": \"cd2f02a6293f0d7cdf8301bace0ef3ff397ef431\", \"shell_dialogs_unittests\": \"e4bff8ce0fb6fc46da259adad2bbdee2914f6fe4\", \"skia_unittests\": \"ca5c1aea2304b33ea083b8bf3f9eb9c73ba02bd5\", \"snapshot_unittests\": \"2fd7012b6ee1156c33c6d33a35c8c8d34f67a310\", \"sql_unittests\": \"6824602b65e1d34bad5d7b33da6d089fb19ad4af\", \"storage_unittests\": \"f874edb50690193e000388e9bc97b52cdefa793d\", \"sync_integration_tests\": \"9a0322e57e2832d0a6d721071976d395b99f8ad3\", \"traffic_annotation_auditor_unittests\": \"955e2c3d311b7a44b732e8f58efc6c360097ab94\", \"ui_base_unittests\": \"84f91b4639b051ee7fd75b52304588ecf1e87ab2\", \"ui_touch_selection_unittests\": \"1b7e23a66aa7008855db2c464e6d7e37e809b9d1\", \"unit_tests\": \"d77dbcc16aaded851e9d00783d617c0ee9bc88d4\", \"url_unittests\": \"f939f523c4c4deb05921789ac76b9aaf5d747b47\", \"views_unittests\": \"67b57d9c775d35e4037479b45a492d1507e10386\", \"viz_unittests\": \"82d2225b93f685b9c66968296082f28dc1cfbdac\", \"vr_common_unittests\": \"aa371c4990ddc77989fcbb7e719b8b5b6e497d7e\", \"vr_pixeltests\": \"3d257494de1ad177db5a1ca8a3ddf80d9685f077\", \"wm_unittests\": \"52d1f7a9eb7746deb128f3f5bc5ff5fd5e042033\", \"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": "1603645312818392",
"status": "COMPLETED",
"status_changed_ts": "1603652702509822",
"tags": [
"build_address:luci.chromium.ci/Linux CFI/18765",
"builder:Linux CFI",
"buildset:commit/git/1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f10fb35bc3e3ad6b37e7ec2f055958658cc8cb9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030879792618207152",
"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/8865449730247625904/+/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:4f79871562420b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603652702518484",
"url": "https://ci.chromium.org/b/8865449730247625904",
"utcnow_ts": "1603652703175335"
}
}
[19:05:03.190] Invocation finished in 2h3m13.754788721s with status SUCCEEDED