[23:24:12.435] New invocation is queued and will start shortly
[23:24:13.551] Starting the invocation (attempt 1)
[23:24:13.589] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:24:13.589] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:24:13.589] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018081562900001584",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"31c60275f85d3695e46b0354ba9c150ef47d3f66\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018081562900001584",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/31c60275f85d3695e46b0354ba9c150ef47d3f66",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31c60275f85d3695e46b0354ba9c150ef47d3f66",
"gitiles_ref:refs/heads/master"
]
}
[23:24:14.835] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615850653696070",
"id": "8852651500908751936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"31c60275f85d3695e46b0354ba9c150ef47d3f66\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615850654600573",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46167",
"builder:linux-chromeos-rel",
"buildset:commit/git/31c60275f85d3695e46b0354ba9c150ef47d3f66",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31c60275f85d3695e46b0354ba9c150ef47d3f66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018081562900001584",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852651500908751936/+/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": "1615850654600734",
"url": "https://ci.chromium.org/b/8852651500908751936",
"utcnow_ts": "1615850654786256"
}
}
[23:24:14.835] Task URL: https://ci.chromium.org/b/8852651500908751936
[23:24:14.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:2:0) after 1m0s
[23:25:15.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:2:0)
[23:25:15.191] Timer tick, asking Buildbucket for the build status
[23:25:15.530] Build 8852651500908751936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:15.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:3:0) after 1m0s
[23:26:15.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:3:0)
[23:26:15.566] Timer tick, asking Buildbucket for the build status
[23:26:16.244] Build 8852651500908751936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:16.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:4:0) after 1m0s
[23:27:16.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:4:0)
[23:27:16.566] Timer tick, asking Buildbucket for the build status
[23:27:17.000] Build 8852651500908751936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:17.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:5:0) after 1m0s
[23:28:17.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:5:0)
[23:28:17.233] Timer tick, asking Buildbucket for the build status
[23:28:17.289] Build 8852651500908751936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:17.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:6:0) after 1m0s
[23:29:05.655] Received PubSub notification, asking Buildbucket for the build status
[23:29:05.743] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:17.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:6:0)
[23:29:17.427] Timer tick, asking Buildbucket for the build status
[23:29:17.514] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:17.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:8:0) after 1m0s
[23:30:18.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:8:0)
[23:30:18.175] Timer tick, asking Buildbucket for the build status
[23:30:18.371] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:18.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:9:0) after 1m0s
[23:31:18.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:9:0)
[23:31:18.516] Timer tick, asking Buildbucket for the build status
[23:31:18.625] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:18.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:10:0) after 1m0s
[23:32:18.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:10:0)
[23:32:18.691] Timer tick, asking Buildbucket for the build status
[23:32:18.796] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:18.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:11:0) after 1m0s
[23:33:18.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:11:0)
[23:33:18.828] Timer tick, asking Buildbucket for the build status
[23:33:19.048] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:19.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:12:0) after 1m0s
[23:34:19.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:12:0)
[23:34:19.140] Timer tick, asking Buildbucket for the build status
[23:34:19.778] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:19.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:13:0) after 1m0s
[23:35:19.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:13:0)
[23:35:19.815] Timer tick, asking Buildbucket for the build status
[23:35:19.922] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:19.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:14:0) after 1m0s
[23:36:19.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:14:0)
[23:36:19.938] Timer tick, asking Buildbucket for the build status
[23:36:20.057] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:20.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:15:0) after 1m0s
[23:37:20.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:15:0)
[23:37:20.374] Timer tick, asking Buildbucket for the build status
[23:37:20.455] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:20.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:16:0) after 1m0s
[23:38:21.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:16:0)
[23:38:21.260] Timer tick, asking Buildbucket for the build status
[23:38:21.374] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:21.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:17:0) after 1m0s
[23:39:22.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:17:0)
[23:39:22.735] Timer tick, asking Buildbucket for the build status
[23:39:22.819] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:22.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:18:0) after 1m0s
[23:40:23.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:18:0)
[23:40:23.000] Timer tick, asking Buildbucket for the build status
[23:40:24.127] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:24.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:19:0) after 1m0s
[23:41:24.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:19:0)
[23:41:24.297] Timer tick, asking Buildbucket for the build status
[23:41:24.354] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:24.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:20:0) after 1m0s
[23:42:24.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:20:0)
[23:42:24.426] Timer tick, asking Buildbucket for the build status
[23:42:25.423] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:25.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:21:0) after 1m0s
[23:43:25.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:21:0)
[23:43:25.704] Timer tick, asking Buildbucket for the build status
[23:43:26.451] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:26.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:22:0) after 1m0s
[23:44:26.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:22:0)
[23:44:26.506] Timer tick, asking Buildbucket for the build status
[23:44:26.697] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:26.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:23:0) after 1m0s
[23:45:26.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:23:0)
[23:45:26.714] Timer tick, asking Buildbucket for the build status
[23:45:26.948] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:26.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:24:0) after 1m0s
[23:46:27.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:24:0)
[23:46:27.032] Timer tick, asking Buildbucket for the build status
[23:46:27.173] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:27.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:25:0) after 1m0s
[23:47:27.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:25:0)
[23:47:27.440] Timer tick, asking Buildbucket for the build status
[23:47:27.525] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:27.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:26:0) after 1m0s
[23:48:27.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:26:0)
[23:48:27.785] Timer tick, asking Buildbucket for the build status
[23:48:27.936] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:27.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:27:0) after 1m0s
[23:49:27.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:27:0)
[23:49:27.972] Timer tick, asking Buildbucket for the build status
[23:49:28.112] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:28.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:28:0) after 1m0s
[23:50:28.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:28:0)
[23:50:28.105] Timer tick, asking Buildbucket for the build status
[23:50:28.344] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:28.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:29:0) after 1m0s
[23:51:28.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:29:0)
[23:51:28.412] Timer tick, asking Buildbucket for the build status
[23:51:28.570] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:28.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:30:0) after 1m0s
[23:52:28.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:30:0)
[23:52:28.606] Timer tick, asking Buildbucket for the build status
[23:52:28.720] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:28.720] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:31:0) after 1m0s
[23:53:29.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:31:0)
[23:53:29.241] Timer tick, asking Buildbucket for the build status
[23:53:29.370] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:29.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:32:0) after 1m0s
[23:54:29.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:32:0)
[23:54:29.426] Timer tick, asking Buildbucket for the build status
[23:54:29.514] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:29.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:33:0) after 1m0s
[23:55:29.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:33:0)
[23:55:29.758] Timer tick, asking Buildbucket for the build status
[23:55:29.834] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:29.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:34:0) after 1m0s
[23:56:30.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:34:0)
[23:56:30.393] Timer tick, asking Buildbucket for the build status
[23:56:30.524] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:30.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:35:0) after 1m0s
[23:57:30.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:35:0)
[23:57:30.625] Timer tick, asking Buildbucket for the build status
[23:57:30.709] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:30.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:36:0) after 1m0s
[23:58:30.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:36:0)
[23:58:30.731] Timer tick, asking Buildbucket for the build status
[23:58:30.822] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:30.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:37:0) after 1m0s
[23:59:30.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:37:0)
[23:59:30.862] Timer tick, asking Buildbucket for the build status
[23:59:30.975] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:30.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:38:0) after 1m0s
[00:00:31.009] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:38:0)
[00:00:31.009] Timer tick, asking Buildbucket for the build status
[00:00:31.274] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:31.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:39:0) after 1m0s
[00:01:31.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:39:0)
[00:01:31.282] Timer tick, asking Buildbucket for the build status
[00:01:31.584] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:31.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:40:0) after 1m0s
[00:02:31.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:40:0)
[00:02:31.627] Timer tick, asking Buildbucket for the build status
[00:02:31.711] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:31.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:41:0) after 1m0s
[00:03:31.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:41:0)
[00:03:31.896] Timer tick, asking Buildbucket for the build status
[00:03:32.022] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:32.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:42:0) after 1m0s
[00:04:32.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:42:0)
[00:04:32.087] Timer tick, asking Buildbucket for the build status
[00:04:32.507] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:32.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:43:0) after 1m0s
[00:05:32.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:43:0)
[00:05:32.542] Timer tick, asking Buildbucket for the build status
[00:05:32.652] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:32.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:44:0) after 1m0s
[00:06:32.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:44:0)
[00:06:32.723] Timer tick, asking Buildbucket for the build status
[00:06:32.812] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:32.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:45:0) after 1m0s
[00:07:32.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:45:0)
[00:07:32.887] Timer tick, asking Buildbucket for the build status
[00:07:33.019] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:33.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:46:0) after 1m0s
[00:08:33.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:46:0)
[00:08:33.166] Timer tick, asking Buildbucket for the build status
[00:08:33.414] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:33.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:47:0) after 1m0s
[00:09:33.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:47:0)
[00:09:33.556] Timer tick, asking Buildbucket for the build status
[00:09:33.856] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:33.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:48:0) after 1m0s
[00:10:33.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:48:0)
[00:10:33.916] Timer tick, asking Buildbucket for the build status
[00:10:33.977] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:33.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:49:0) after 1m0s
[00:11:34.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:49:0)
[00:11:34.049] Timer tick, asking Buildbucket for the build status
[00:11:34.144] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:34.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:50:0) after 1m0s
[00:12:34.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:50:0)
[00:12:34.139] Timer tick, asking Buildbucket for the build status
[00:12:34.352] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:34.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:51:0) after 1m0s
[00:13:34.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:51:0)
[00:13:34.446] Timer tick, asking Buildbucket for the build status
[00:13:34.535] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:34.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:52:0) after 1m0s
[00:14:34.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:52:0)
[00:14:34.647] Timer tick, asking Buildbucket for the build status
[00:14:34.774] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:34.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:53:0) after 1m0s
[00:15:34.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:53:0)
[00:15:34.945] Timer tick, asking Buildbucket for the build status
[00:15:35.064] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:35.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:54:0) after 1m0s
[00:16:35.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:54:0)
[00:16:35.585] Timer tick, asking Buildbucket for the build status
[00:16:36.164] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:36.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:55:0) after 1m0s
[00:17:36.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:55:0)
[00:17:36.312] Timer tick, asking Buildbucket for the build status
[00:17:37.883] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:37.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:56:0) after 1m0s
[00:18:38.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:56:0)
[00:18:38.201] Timer tick, asking Buildbucket for the build status
[00:18:38.318] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:38.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:57:0) after 1m0s
[00:19:38.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:57:0)
[00:19:38.425] Timer tick, asking Buildbucket for the build status
[00:19:38.504] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:38.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:58:0) after 1m0s
[00:20:38.578] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:58:0)
[00:20:38.578] Timer tick, asking Buildbucket for the build status
[00:20:38.645] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:38.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:59:0) after 1m0s
[00:21:38.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:59:0)
[00:21:38.878] Timer tick, asking Buildbucket for the build status
[00:21:39.021] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:39.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:60:0) after 1m0s
[00:22:39.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:60:0)
[00:22:39.117] Timer tick, asking Buildbucket for the build status
[00:22:39.243] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:39.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:61:0) after 1m0s
[00:23:39.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:61:0)
[00:23:39.824] Timer tick, asking Buildbucket for the build status
[00:23:41.126] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:41.126] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:62:0) after 1m0s
[00:24:42.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:62:0)
[00:24:42.149] Timer tick, asking Buildbucket for the build status
[00:24:42.262] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:42.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:63:0) after 1m0s
[00:25:42.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:63:0)
[00:25:42.908] Timer tick, asking Buildbucket for the build status
[00:25:42.995] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:42.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:64:0) after 1m0s
[00:26:43.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:64:0)
[00:26:43.411] Timer tick, asking Buildbucket for the build status
[00:26:43.562] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:43.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:65:0) after 1m0s
[00:27:43.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:65:0)
[00:27:43.616] Timer tick, asking Buildbucket for the build status
[00:27:43.684] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:43.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:66:0) after 1m0s
[00:28:43.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:66:0)
[00:28:43.767] Timer tick, asking Buildbucket for the build status
[00:28:43.836] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:43.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:67:0) after 1m0s
[00:29:43.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:67:0)
[00:29:43.920] Timer tick, asking Buildbucket for the build status
[00:29:44.004] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:44.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:68:0) after 1m0s
[00:30:44.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:68:0)
[00:30:44.162] Timer tick, asking Buildbucket for the build status
[00:30:44.251] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:44.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:69:0) after 1m0s
[00:31:44.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:69:0)
[00:31:44.322] Timer tick, asking Buildbucket for the build status
[00:31:44.400] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:44.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:70:0) after 1m0s
[00:32:44.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:70:0)
[00:32:44.689] Timer tick, asking Buildbucket for the build status
[00:32:44.760] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:44.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:71:0) after 1m0s
[00:33:44.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:71:0)
[00:33:44.917] Timer tick, asking Buildbucket for the build status
[00:33:45.048] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:72:0) after 1m0s
[00:34:45.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:72:0)
[00:34:45.109] Timer tick, asking Buildbucket for the build status
[00:34:45.243] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:45.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:73:0) after 1m0s
[00:35:45.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:73:0)
[00:35:45.264] Timer tick, asking Buildbucket for the build status
[00:35:46.460] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:46.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:74:0) after 1m0s
[00:36:46.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:74:0)
[00:36:46.581] Timer tick, asking Buildbucket for the build status
[00:36:46.728] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:46.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:75:0) after 1m0s
[00:37:46.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:75:0)
[00:37:46.769] Timer tick, asking Buildbucket for the build status
[00:37:46.867] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:46.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:76:0) after 1m0s
[00:38:47.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:76:0)
[00:38:47.459] Timer tick, asking Buildbucket for the build status
[00:38:48.753] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:48.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:77:0) after 1m0s
[00:39:49.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:77:0)
[00:39:49.232] Timer tick, asking Buildbucket for the build status
[00:39:49.363] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:49.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:78:0) after 1m0s
[00:40:49.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:78:0)
[00:40:49.570] Timer tick, asking Buildbucket for the build status
[00:40:50.636] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:50.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:79:0) after 1m0s
[00:41:51.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:79:0)
[00:41:51.243] Timer tick, asking Buildbucket for the build status
[00:41:52.177] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:52.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:80:0) after 1m0s
[00:42:52.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:80:0)
[00:42:52.462] Timer tick, asking Buildbucket for the build status
[00:42:52.670] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:52.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:81:0) after 1m0s
[00:43:52.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:81:0)
[00:43:52.892] Timer tick, asking Buildbucket for the build status
[00:43:52.978] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:52.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:82:0) after 1m0s
[00:44:53.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:82:0)
[00:44:53.058] Timer tick, asking Buildbucket for the build status
[00:44:53.734] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:53.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:83:0) after 1m0s
[00:45:53.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:83:0)
[00:45:53.864] Timer tick, asking Buildbucket for the build status
[00:45:55.687] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:55.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:84:0) after 1m0s
[00:46:55.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:84:0)
[00:46:55.788] Timer tick, asking Buildbucket for the build status
[00:46:55.868] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:55.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:85:0) after 1m0s
[00:47:55.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:85:0)
[00:47:55.888] Timer tick, asking Buildbucket for the build status
[00:47:56.318] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:56.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:86:0) after 1m0s
[00:48:56.340] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:86:0)
[00:48:56.340] Timer tick, asking Buildbucket for the build status
[00:48:56.432] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:56.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:87:0) after 1m0s
[00:49:56.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:87:0)
[00:49:56.477] Timer tick, asking Buildbucket for the build status
[00:49:56.650] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:56.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:88:0) after 1m0s
[00:50:56.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:88:0)
[00:50:56.672] Timer tick, asking Buildbucket for the build status
[00:50:56.752] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:56.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:89:0) after 1m0s
[00:51:56.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:89:0)
[00:51:56.784] Timer tick, asking Buildbucket for the build status
[00:51:56.847] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:56.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:90:0) after 1m0s
[00:52:56.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:90:0)
[00:52:56.859] Timer tick, asking Buildbucket for the build status
[00:52:56.961] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:56.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:91:0) after 1m0s
[00:53:56.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:91:0)
[00:53:56.981] Timer tick, asking Buildbucket for the build status
[00:53:57.079] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:57.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:92:0) after 1m0s
[00:54:57.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:92:0)
[00:54:57.101] Timer tick, asking Buildbucket for the build status
[00:54:57.355] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:57.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:93:0) after 1m0s
[00:55:57.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:93:0)
[00:55:57.395] Timer tick, asking Buildbucket for the build status
[00:55:57.554] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:57.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:94:0) after 1m0s
[00:56:57.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:94:0)
[00:56:57.572] Timer tick, asking Buildbucket for the build status
[00:56:57.655] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:57.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:95:0) after 1m0s
[00:57:57.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:95:0)
[00:57:57.683] Timer tick, asking Buildbucket for the build status
[00:57:57.835] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:57.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:96:0) after 1m0s
[00:58:57.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:96:0)
[00:58:57.849] Timer tick, asking Buildbucket for the build status
[00:58:58.349] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:58.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:97:0) after 1m0s
[00:59:58.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:97:0)
[00:59:58.341] Timer tick, asking Buildbucket for the build status
[00:59:58.629] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:58.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:98:0) after 1m0s
[01:00:58.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:98:0)
[01:00:58.608] Timer tick, asking Buildbucket for the build status
[01:00:58.809] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:58.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:99:0) after 1m0s
[01:01:58.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:99:0)
[01:01:58.806] Timer tick, asking Buildbucket for the build status
[01:01:58.989] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:58.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:100:0) after 1m0s
[01:02:59.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:100:0)
[01:02:59.013] Timer tick, asking Buildbucket for the build status
[01:02:59.133] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:59.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:101:0) after 1m0s
[01:03:59.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:101:0)
[01:03:59.161] Timer tick, asking Buildbucket for the build status
[01:03:59.851] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:59.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:102:0) after 1m0s
[01:04:59.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:102:0)
[01:04:59.932] Timer tick, asking Buildbucket for the build status
[01:05:00.374] Build 8852651500908751936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:00.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018081562900001584:103:0) after 1m0s
[01:05:07.875] Received PubSub notification, asking Buildbucket for the build status
[01:05:08.363] Build 8852651500908751936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:05:08.363] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615856706337397",
"created_by": "project:chromium",
"created_ts": "1615850653696070",
"id": "8852651500908751936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"31c60275f85d3695e46b0354ba9c150ef47d3f66\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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\": \"31c60275f85d3695e46b0354ba9c150ef47d3f66\", \"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-us-central1-b-8-96-dwph\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46167, \"got_angle_revision\": \"06d194e2ae7b1d7e0eda0c0c911eff92dec7d3d1\", \"got_dawn_revision\": \"8c40cad1c92d7e85154bbae4cb92539e2200d139\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"31c60275f85d3695e46b0354ba9c150ef47d3f66\", \"got_revision_cp\": \"refs/heads/master@{#863032}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"69b79eec6533f113920a5dd863ee2dea7d0c64e7\", \"got_v8_revision\": \"6b214099c60660a75b414785481afafc74a50628\", \"got_v8_revision_cp\": \"refs/heads/9.1.127@{#1}\", \"got_webrtc_revision\": \"6097b0fac0946a29d59a9266ea656c39b3fd7336\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33456}\", \"is_cached\": false, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"31c60275f85d3695e46b0354ba9c150ef47d3f66\", \"swarm_hashes_refs/heads/master(at){#863032}_without_patch\": {\"absl_hardening_tests\": \"fed50e7a1884f128b1b52199496a8dd2b9d1c283\", \"accessibility_unittests\": \"f50ecfd8dabc762962480d10c0616b9a809a278d\", \"angle_unittests\": \"255d1fc4f55e8c4b0141e41ec9a38c614a4b0829\", \"app_list_unittests\": \"c30aa7d6a118dd6a2c6abe888d6816d20f87fc03\", \"app_shell_unittests\": \"5b6558bd23f851efb3e61cc74b8d5d9074a8f1e3\", \"ash_unittests\": \"77d15d6dc2464d60e5ebb95574d3f79e46ba10f0\", \"aura_unittests\": \"a52252574fc81b5ab4d29cdf8ffef6233c5914a1\", \"base_unittests\": \"83ecf63f6a8d87b19190716092914a637b6140e4\", \"base_util_unittests\": \"7e5c3e0c374d8a68b1ef449d79062b78cd68e1c1\", \"blink_common_unittests\": \"dbdceb36e0441c1dfc6de7f394e236591613d13f\", \"blink_fuzzer_unittests\": \"52348cf5bfc3298836c151a2179ed31364598ab9\", \"blink_heap_unittests\": \"45eec1fcae4977bcd847cd6ef3e8bdbb4b30ac85\", \"blink_platform_unittests\": \"52d5ce667d964c038e703f615b8b50a0749f53dc\", \"blink_unittests\": \"dc296046cbde8b05421cc37f2d31ffa39d7c7571\", \"boringssl_crypto_tests\": \"d3c1a93aa6982f242da624b69c76e7a0628b9939\", \"boringssl_ssl_tests\": \"4934ed997331d63fb132aa94798d1dd5106da20a\", \"browser_tests\": \"dc83e966786c7e5a665039316873d5dbca39fab8\", \"capture_unittests\": \"ae570f7e3960133e14e90b43c940f4ead2dbe3df\", \"cast_unittests\": \"2405299b4b8dc6f8612fe2bc7e6ae6442a35f592\", \"cc_unittests\": \"a621926a307c28bb5ed079c56646a3faad695d33\", \"chrome_app_unittests\": \"62ba2c434942b04f1b1a3704ab36a7c379c4e9f8\", \"chromedriver_unittests\": \"b926259ddec53cf875b999e020a5f075beaa3a6c\", \"chromeos_components_unittests\": \"5c85ca640aa1911dbdd27a0c9a0e4599242422fe\", \"chromeos_unittests\": \"7f4050542212766f852bb5a80562684a2c765583\", \"color_unittests\": \"41a877f7c511a6e001a0a258a22f4ee1791920a9\", \"components_browsertests\": \"f3ba96ef528bb1b9715f1d3d36261191da7ad257\", \"components_unittests\": \"c0796364dd86defa83b4e869e273933b68ff823a\", \"compositor_unittests\": \"1288d014fcae43a3832b38f5cae458cb16146131\", \"content_browsertests\": \"7238644b3e835422f6c5e696a0b44789bda0dbf1\", \"content_unittests\": \"6bb615e110ef79167cdd8c6b12107c9de958df17\", \"crashpad_tests\": \"04f32a07637d393498490aa3fa2cbdcb0e97a887\", \"crypto_unittests\": \"3616b199251614da905827bbe3cc23f37a7fc2b9\", \"dbus_unittests\": \"488a8b658f7cb21060c28a8b1fab973479d54042\", \"device_unittests\": \"1fff1d1cb8fcdde08ec519bb197183e65068d2e0\", \"display_unittests\": \"027b88befa3319a8aa88589454a5542caa587053\", \"events_unittests\": \"443c033be0410b229f9b7c91ec41eef95bc2d800\", \"exo_unittests\": \"675fc860ad93b63d21a52f0264de116b37e707c0\", \"extensions_browsertests\": \"958741a875b1f7b4164ab06152ca77a45b951890\", \"extensions_unittests\": \"3fc54d2fa99f7edf39310f60059a6ba8b1a8f523\", \"filesystem_service_unittests\": \"7eb18940a5198ff4e313cce00af89128ad3b6ce7\", \"gcm_unit_tests\": \"94baa5e060d9cfb3d00b978655a32c00055997f1\", \"gfx_unittests\": \"dd368cffd6c0ad65cabbf4e2e0b6332d7ada214c\", \"gin_unittests\": \"fad32794fe10ebad861102fa5860ebaa49e62605\", \"gl_unittests_ozone\": \"9c3688ff6e28cbee469dee468b4f42c0c96f7367\", \"google_apis_unittests\": \"24e535c677834b612884a680f4858a1d0c156d75\", \"gpu_unittests\": \"f63c284c153d033a065bd987cc5b9d6a79051e29\", \"gwp_asan_unittests\": \"ba8dc3f002795902a52ef5f986781dc92917af9e\", \"interactive_ui_tests\": \"91ee02d746109e47913917d3df18951b33f4740e\", \"ipc_tests\": \"18ed1af18c472551f0d336f58bf629df1053bda8\", \"jingle_unittests\": \"d97bc49f882d22370a1f64c8d291c0b534075448\", \"keyboard_unittests\": \"a96f68d3974b88a15376e34a05c9903b9528e38c\", \"latency_unittests\": \"ac2e63a5bbf99a62eb61fcedf1f53d252327978d\", \"libjingle_xmpp_unittests\": \"e9e8ea29390aacefb36239c20b7eded299caa279\", \"liburlpattern_unittests\": \"d0e612385a17d5723070a0ef3dcd4b94a503adc1\", \"media_blink_unittests\": \"32bc7040bb1165b9bb9ed4c0c94e980ab62ad3a5\", \"media_unittests\": \"850b801680f9ae80068820b56b142f8e9d9f94b5\", \"message_center_unittests\": \"ee8317b4d9ac6d0900792eb2078f9ebf37f72292\", \"midi_unittests\": \"a313a4b381ee4fce92919f11df0e952bad0830c2\", \"mojo_core_unittests\": \"b5d111e5d2d436a9ffc8d58104f9dd917e868f15\", \"mojo_unittests\": \"fbaf881e69959b301a489eadac7f1958b83871e4\", \"nacl_helper_nonsfi_unittests\": \"92d667fb5bbbebabd442610cea33224eebde072f\", \"nacl_loader_unittests\": \"f0f5900e49f7fe1e50798ea61b46641310f2414a\", \"native_theme_unittests\": \"d334083a1702c877db0c90d18629787a5a4a573a\", \"net_unittests\": \"6e71c8331699f47ee8e2cc913d46edc99c5a68ab\", \"ozone_gl_unittests\": \"23578d2a70f6795bf75c64a348fc5e15ed5b36bc\", \"ozone_unittests\": \"c4bfc5e038c0a2483af967d7e1ee94195f593b64\", \"ozone_x11_unittests\": \"8443fae15fd4f9c1b4a8a4a7311fd6f22f316027\", \"pdf_unittests\": \"db9d4a484e3e12dff229585e8ba9780660372f51\", \"perfetto_unittests\": \"311f6e6426847056f12a7568867a2cc915ddd537\", \"ppapi_unittests\": \"7cc0da0ab1ee92646e4e1bda303dc1ac61666fce\", \"printing_unittests\": \"d263cedc9d047567ae0588ba868133520887d3dc\", \"remoting_unittests\": \"56f640f4fd59971a6c567062b9680027b2ca23d0\", \"sandbox_linux_unittests\": \"2be67207c90401f4409873b413b5edbbb3a9821b\", \"service_manager_unittests\": \"763d71a1efa3a7b860b896f6b53d8e8aa8f91392\", \"services_unittests\": \"ff953409b2c81226948e0f5e40255c531de44cbb\", \"shell_dialogs_unittests\": \"b9b4aa7a8da1a100717613a7a52d600e58ef1588\", \"shell_encryption_unittests\": \"f78aceeff80572474cfe19820103d3d4f79f09c7\", \"skia_unittests\": \"1389890e7f1c8cbc52433c7550827f6a884c9a68\", \"snapshot_unittests\": \"edfa98b5e360ac75910bce357f5149e9a040bad0\", \"sql_unittests\": \"b22f856d4b1d213ce75b528d2523fce9f58f5a10\", \"storage_unittests\": \"b6ea6c4911932ff4033a6a5a854a4f3caf0b4b99\", \"sync_integration_tests\": \"3199dcc1595e8ec64c34af77600c0dfb8c80b709\", \"traffic_annotation_auditor_unittests\": \"eb1510ae9082d5982a4a2801a5429cbd1aa9e0b9\", \"ui_base_unittests\": \"8da0becad2ffb6af25ebac03e7050d5381833750\", \"ui_chromeos_unittests\": \"bfba709f0df5e52da4fa0c91cca733c68b4f378b\", \"ui_touch_selection_unittests\": \"08d80ca4cf89c40d64b4e3465610b2bd04fb784b\", \"unit_tests\": \"3469e80540d5d761c48c850bbb46922d82c5f9aa\", \"url_unittests\": \"e4bbd5b5bac2e67b8118e792bb420c7be8f35cc4\", \"usage_time_limit_unittests\": \"df16cc7acd82d8383406c31dafddbe78f52a4b52\", \"views_unittests\": \"a4cd66c97e39eee4d72317773576a88511718b8c\", \"viz_unittests\": \"12274c4bc3e6b4e97a2490aea8aa0da929ec03a6\", \"wayland_client_perftests\": \"074d3f56120bd436417c5766868dcbec61027773\", \"wayland_client_tests\": \"cdafc0220ae00f24de7119454be8a07c8a57888a\", \"wm_unittests\": \"0d3b8bf43235721f94c3854c613df08ac2bee9ca\", \"wtf_unittests\": \"602013a95ee6a0ad9337ce889f317fe362307e20\", \"zlib_unittests\": \"c4b022db66fe51115a29b9d00533dc3f1187be55\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0b457c29832afe9869549a9d3fb1269d99c2cbc2ef2da9533a15fddd8d201757_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-96-dwph\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615850945104115",
"status": "COMPLETED",
"status_changed_ts": "1615856706740093",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46167",
"builder:linux-chromeos-rel",
"buildset:commit/git/31c60275f85d3695e46b0354ba9c150ef47d3f66",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31c60275f85d3695e46b0354ba9c150ef47d3f66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018081562900001584",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852651500908751936/+/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:525105d4ebcd5510",
"user_agent:luci-scheduler"
],
"updated_ts": "1615856706854121",
"url": "https://ci.chromium.org/b/8852651500908751936",
"utcnow_ts": "1615856708340131"
}
}
[01:05:08.363] Invocation finished in 1h40m55.949551634s with status SUCCEEDED