[16:04:59.374] New invocation is queued and will start shortly
[16:05:00.534] Starting the invocation (attempt 1)
[16:05:00.580] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:05:00.584] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:05:00.584] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018471583946459648",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c4423c0abc77ca79dc628d74c083b1450ce0f37f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9018471583946459648",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/c4423c0abc77ca79dc628d74c083b1450ce0f37f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c4423c0abc77ca79dc628d74c083b1450ce0f37f",
"gitiles_ref:refs/heads/master"
]
}
[16:05:02.446] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615478700735327",
"id": "8853041521855940512",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c4423c0abc77ca79dc628d74c083b1450ce0f37f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615478702125812",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/26756",
"builder:Linux Viz",
"buildset:commit/git/c4423c0abc77ca79dc628d74c083b1450ce0f37f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c4423c0abc77ca79dc628d74c083b1450ce0f37f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018471583946459648",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853041521855940512/+/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": "1615478702127013",
"url": "https://ci.chromium.org/b/8853041521855940512",
"utcnow_ts": "1615478702425247"
}
}
[16:05:02.446] Task URL: https://ci.chromium.org/b/8853041521855940512
[16:05:02.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:2:0) after 1m0s
[16:06:02.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:2:0)
[16:06:02.438] Timer tick, asking Buildbucket for the build status
[16:06:02.538] Build 8853041521855940512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:06:02.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:3:0) after 1m0s
[16:07:02.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:3:0)
[16:07:02.549] Timer tick, asking Buildbucket for the build status
[16:07:02.616] Build 8853041521855940512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:07:02.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:4:0) after 1m0s
[16:08:02.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:4:0)
[16:08:02.691] Timer tick, asking Buildbucket for the build status
[16:08:02.753] Build 8853041521855940512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:08:02.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:5:0) after 1m0s
[16:09:02.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:5:0)
[16:09:02.775] Timer tick, asking Buildbucket for the build status
[16:09:02.841] Build 8853041521855940512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:09:02.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:6:0) after 1m0s
[16:10:02.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:6:0)
[16:10:02.848] Timer tick, asking Buildbucket for the build status
[16:10:02.937] Build 8853041521855940512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:02.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:7:0) after 1m0s
[16:11:03.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:7:0)
[16:11:03.018] Timer tick, asking Buildbucket for the build status
[16:11:03.105] Build 8853041521855940512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:03.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:8:0) after 1m0s
[16:11:09.673] Received PubSub notification, asking Buildbucket for the build status
[16:11:09.846] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:03.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:8:0)
[16:12:03.134] Timer tick, asking Buildbucket for the build status
[16:12:03.196] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:03.196] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:10:0) after 1m0s
[16:13:03.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:10:0)
[16:13:03.214] Timer tick, asking Buildbucket for the build status
[16:13:03.463] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:03.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:11:0) after 1m0s
[16:14:03.495] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:11:0)
[16:14:03.495] Timer tick, asking Buildbucket for the build status
[16:14:03.559] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:03.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:12:0) after 1m0s
[16:15:03.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:12:0)
[16:15:03.587] Timer tick, asking Buildbucket for the build status
[16:15:03.788] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:03.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:13:0) after 1m0s
[16:16:03.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:13:0)
[16:16:03.874] Timer tick, asking Buildbucket for the build status
[16:16:04.112] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:04.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:14:0) after 1m0s
[16:17:05.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:14:0)
[16:17:05.229] Timer tick, asking Buildbucket for the build status
[16:17:05.414] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:05.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:15:0) after 1m0s
[16:18:05.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:15:0)
[16:18:05.667] Timer tick, asking Buildbucket for the build status
[16:18:05.750] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:05.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:16:0) after 1m0s
[16:19:05.947] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:16:0)
[16:19:05.947] Timer tick, asking Buildbucket for the build status
[16:19:06.242] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:06.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:17:0) after 1m0s
[16:20:06.377] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:17:0)
[16:20:06.377] Timer tick, asking Buildbucket for the build status
[16:20:06.468] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:06.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:18:0) after 1m0s
[16:21:06.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:18:0)
[16:21:06.550] Timer tick, asking Buildbucket for the build status
[16:21:06.609] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:06.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:19:0) after 1m0s
[16:22:06.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:19:0)
[16:22:06.711] Timer tick, asking Buildbucket for the build status
[16:22:07.044] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:07.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:20:0) after 1m0s
[16:23:07.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:20:0)
[16:23:07.208] Timer tick, asking Buildbucket for the build status
[16:23:07.263] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:07.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:21:0) after 1m0s
[16:24:07.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:21:0)
[16:24:07.427] Timer tick, asking Buildbucket for the build status
[16:24:07.488] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:07.488] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:22:0) after 1m0s
[16:25:07.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:22:0)
[16:25:07.705] Timer tick, asking Buildbucket for the build status
[16:25:07.800] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:07.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:23:0) after 1m0s
[16:26:07.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:23:0)
[16:26:07.820] Timer tick, asking Buildbucket for the build status
[16:26:08.114] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:08.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:24:0) after 1m0s
[16:27:08.153] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:24:0)
[16:27:08.153] Timer tick, asking Buildbucket for the build status
[16:27:08.525] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:08.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:25:0) after 1m0s
[16:28:08.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:25:0)
[16:28:08.669] Timer tick, asking Buildbucket for the build status
[16:28:08.730] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:08.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:26:0) after 1m0s
[16:29:08.884] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:26:0)
[16:29:08.884] Timer tick, asking Buildbucket for the build status
[16:29:08.947] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:08.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:27:0) after 1m0s
[16:30:09.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:27:0)
[16:30:09.078] Timer tick, asking Buildbucket for the build status
[16:30:09.166] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:09.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:28:0) after 1m0s
[16:31:09.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:28:0)
[16:31:09.316] Timer tick, asking Buildbucket for the build status
[16:31:09.541] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:09.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:29:0) after 1m0s
[16:32:09.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:29:0)
[16:32:09.635] Timer tick, asking Buildbucket for the build status
[16:32:09.705] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:09.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:30:0) after 1m0s
[16:33:09.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:30:0)
[16:33:09.794] Timer tick, asking Buildbucket for the build status
[16:33:10.152] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:10.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:31:0) after 1m0s
[16:34:10.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:31:0)
[16:34:10.196] Timer tick, asking Buildbucket for the build status
[16:34:10.439] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:10.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:32:0) after 1m0s
[16:35:10.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:32:0)
[16:35:10.514] Timer tick, asking Buildbucket for the build status
[16:35:10.596] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:10.596] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:33:0) after 1m0s
[16:36:10.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:33:0)
[16:36:10.583] Timer tick, asking Buildbucket for the build status
[16:36:10.755] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:10.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:34:0) after 1m0s
[16:37:10.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:34:0)
[16:37:10.766] Timer tick, asking Buildbucket for the build status
[16:37:10.839] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:10.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:35:0) after 1m0s
[16:38:10.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:35:0)
[16:38:10.826] Timer tick, asking Buildbucket for the build status
[16:38:10.904] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:10.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:36:0) after 1m0s
[16:39:10.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:36:0)
[16:39:10.932] Timer tick, asking Buildbucket for the build status
[16:39:11.113] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:11.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:37:0) after 1m0s
[16:40:11.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:37:0)
[16:40:11.202] Timer tick, asking Buildbucket for the build status
[16:40:11.304] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:11.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:38:0) after 1m0s
[16:41:11.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:38:0)
[16:41:11.414] Timer tick, asking Buildbucket for the build status
[16:41:11.539] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:11.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:39:0) after 1m0s
[16:42:11.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:39:0)
[16:42:11.619] Timer tick, asking Buildbucket for the build status
[16:42:12.250] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:12.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:40:0) after 1m0s
[16:43:12.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:40:0)
[16:43:12.247] Timer tick, asking Buildbucket for the build status
[16:43:12.424] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:12.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:41:0) after 1m0s
[16:44:12.460] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:41:0)
[16:44:12.460] Timer tick, asking Buildbucket for the build status
[16:44:12.532] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:12.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:42:0) after 1m0s
[16:45:12.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:42:0)
[16:45:12.553] Timer tick, asking Buildbucket for the build status
[16:45:12.828] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:12.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:43:0) after 1m0s
[16:46:12.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:43:0)
[16:46:12.925] Timer tick, asking Buildbucket for the build status
[16:46:13.016] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:13.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:44:0) after 1m0s
[16:47:13.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:44:0)
[16:47:13.127] Timer tick, asking Buildbucket for the build status
[16:47:13.456] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:13.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:45:0) after 1m0s
[16:48:13.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:45:0)
[16:48:13.557] Timer tick, asking Buildbucket for the build status
[16:48:13.793] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:13.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:46:0) after 1m0s
[16:49:13.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:46:0)
[16:49:13.866] Timer tick, asking Buildbucket for the build status
[16:49:13.990] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:13.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:47:0) after 1m0s
[16:50:14.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:47:0)
[16:50:14.016] Timer tick, asking Buildbucket for the build status
[16:50:14.086] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:14.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:48:0) after 1m0s
[16:51:14.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:48:0)
[16:51:14.169] Timer tick, asking Buildbucket for the build status
[16:51:14.604] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:14.604] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:49:0) after 1m0s
[16:52:14.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:49:0)
[16:52:14.592] Timer tick, asking Buildbucket for the build status
[16:52:14.672] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:14.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:50:0) after 1m0s
[16:53:14.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:50:0)
[16:53:14.794] Timer tick, asking Buildbucket for the build status
[16:53:15.089] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:15.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:51:0) after 1m0s
[16:54:15.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:51:0)
[16:54:15.283] Timer tick, asking Buildbucket for the build status
[16:54:15.358] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:15.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:52:0) after 1m0s
[16:55:15.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:52:0)
[16:55:15.508] Timer tick, asking Buildbucket for the build status
[16:55:15.598] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:15.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:53:0) after 1m0s
[16:56:15.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:53:0)
[16:56:15.652] Timer tick, asking Buildbucket for the build status
[16:56:15.948] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:15.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:54:0) after 1m0s
[16:57:15.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:54:0)
[16:57:15.955] Timer tick, asking Buildbucket for the build status
[16:57:16.092] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:16.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:55:0) after 1m0s
[16:58:16.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:55:0)
[16:58:16.205] Timer tick, asking Buildbucket for the build status
[16:58:16.270] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:16.270] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:56:0) after 1m0s
[16:59:16.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:56:0)
[16:59:16.328] Timer tick, asking Buildbucket for the build status
[16:59:16.413] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:16.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:57:0) after 1m0s
[17:00:16.430] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:57:0)
[17:00:16.430] Timer tick, asking Buildbucket for the build status
[17:00:16.665] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:16.665] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:58:0) after 1m0s
[17:01:16.698] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:58:0)
[17:01:16.698] Timer tick, asking Buildbucket for the build status
[17:01:16.924] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:16.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:59:0) after 1m0s
[17:02:16.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:59:0)
[17:02:16.935] Timer tick, asking Buildbucket for the build status
[17:02:17.114] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:17.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:60:0) after 1m0s
[17:03:17.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:60:0)
[17:03:17.183] Timer tick, asking Buildbucket for the build status
[17:03:17.265] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:17.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:61:0) after 1m0s
[17:04:17.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:61:0)
[17:04:17.429] Timer tick, asking Buildbucket for the build status
[17:04:17.646] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:17.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:62:0) after 1m0s
[17:05:17.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:62:0)
[17:05:17.697] Timer tick, asking Buildbucket for the build status
[17:05:17.926] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:17.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:63:0) after 1m0s
[17:06:18.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:63:0)
[17:06:18.051] Timer tick, asking Buildbucket for the build status
[17:06:18.133] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:18.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:64:0) after 1m0s
[17:07:18.220] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:64:0)
[17:07:18.220] Timer tick, asking Buildbucket for the build status
[17:07:18.420] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:18.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:65:0) after 1m0s
[17:08:18.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:65:0)
[17:08:18.518] Timer tick, asking Buildbucket for the build status
[17:08:18.588] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:18.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:66:0) after 1m0s
[17:09:18.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:66:0)
[17:09:18.730] Timer tick, asking Buildbucket for the build status
[17:09:18.889] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:18.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:67:0) after 1m0s
[17:10:18.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:67:0)
[17:10:18.929] Timer tick, asking Buildbucket for the build status
[17:10:19.353] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:19.353] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:68:0) after 1m0s
[17:11:19.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:68:0)
[17:11:19.402] Timer tick, asking Buildbucket for the build status
[17:11:19.467] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:19.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:69:0) after 1m0s
[17:12:19.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:69:0)
[17:12:19.626] Timer tick, asking Buildbucket for the build status
[17:12:20.066] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:20.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:70:0) after 1m0s
[17:13:20.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:70:0)
[17:13:20.136] Timer tick, asking Buildbucket for the build status
[17:13:20.435] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:20.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:71:0) after 1m0s
[17:14:20.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:71:0)
[17:14:20.610] Timer tick, asking Buildbucket for the build status
[17:14:20.698] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:20.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:72:0) after 1m0s
[17:15:20.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:72:0)
[17:15:20.770] Timer tick, asking Buildbucket for the build status
[17:15:21.014] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:21.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:73:0) after 1m0s
[17:16:21.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:73:0)
[17:16:21.112] Timer tick, asking Buildbucket for the build status
[17:16:21.190] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:21.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:74:0) after 1m0s
[17:17:21.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:74:0)
[17:17:21.281] Timer tick, asking Buildbucket for the build status
[17:17:21.728] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:21.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:75:0) after 1m0s
[17:18:21.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:75:0)
[17:18:21.870] Timer tick, asking Buildbucket for the build status
[17:18:21.939] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:21.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:76:0) after 1m0s
[17:19:22.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:76:0)
[17:19:22.063] Timer tick, asking Buildbucket for the build status
[17:19:22.135] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:22.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:77:0) after 1m0s
[17:20:22.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:77:0)
[17:20:22.224] Timer tick, asking Buildbucket for the build status
[17:20:22.369] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:22.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:78:0) after 1m0s
[17:21:22.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:78:0)
[17:21:22.429] Timer tick, asking Buildbucket for the build status
[17:21:22.808] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:22.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:79:0) after 1m0s
[17:22:25.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:79:0)
[17:22:25.704] Timer tick, asking Buildbucket for the build status
[17:22:25.786] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:25.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:80:0) after 1m0s
[17:23:25.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:80:0)
[17:23:25.929] Timer tick, asking Buildbucket for the build status
[17:23:26.076] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:26.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:81:0) after 1m0s
[17:24:26.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:81:0)
[17:24:26.231] Timer tick, asking Buildbucket for the build status
[17:24:26.291] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:26.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:82:0) after 1m0s
[17:25:27.264] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:82:0)
[17:25:27.264] Timer tick, asking Buildbucket for the build status
[17:25:27.358] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:27.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:83:0) after 1m0s
[17:26:27.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:83:0)
[17:26:27.518] Timer tick, asking Buildbucket for the build status
[17:26:27.799] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:27.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:84:0) after 1m0s
[17:27:27.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:84:0)
[17:27:27.933] Timer tick, asking Buildbucket for the build status
[17:27:28.008] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:28.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:85:0) after 1m0s
[17:28:28.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:85:0)
[17:28:28.413] Timer tick, asking Buildbucket for the build status
[17:28:28.529] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:28.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:86:0) after 1m0s
[17:29:28.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:86:0)
[17:29:28.682] Timer tick, asking Buildbucket for the build status
[17:29:28.848] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:28.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:87:0) after 1m0s
[17:30:28.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:87:0)
[17:30:28.910] Timer tick, asking Buildbucket for the build status
[17:30:29.053] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:29.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:88:0) after 1m0s
[17:31:29.083] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:88:0)
[17:31:29.083] Timer tick, asking Buildbucket for the build status
[17:31:29.147] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:29.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:89:0) after 1m0s
[17:32:29.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:89:0)
[17:32:29.167] Timer tick, asking Buildbucket for the build status
[17:32:29.250] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:29.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:90:0) after 1m0s
[17:33:29.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:90:0)
[17:33:29.324] Timer tick, asking Buildbucket for the build status
[17:33:29.401] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:91:0) after 1m0s
[17:34:29.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:91:0)
[17:34:29.485] Timer tick, asking Buildbucket for the build status
[17:34:29.550] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:29.550] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:92:0) after 1m0s
[17:35:29.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:92:0)
[17:35:29.644] Timer tick, asking Buildbucket for the build status
[17:35:29.753] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:29.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:93:0) after 1m0s
[17:36:29.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:93:0)
[17:36:29.777] Timer tick, asking Buildbucket for the build status
[17:36:29.859] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:29.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:94:0) after 1m0s
[17:37:29.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:94:0)
[17:37:29.930] Timer tick, asking Buildbucket for the build status
[17:37:29.998] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:29.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:95:0) after 1m0s
[17:38:30.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:95:0)
[17:38:30.022] Timer tick, asking Buildbucket for the build status
[17:38:30.297] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:30.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:96:0) after 1m0s
[17:39:30.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:96:0)
[17:39:30.308] Timer tick, asking Buildbucket for the build status
[17:39:30.434] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:30.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:97:0) after 1m0s
[17:40:30.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:97:0)
[17:40:30.483] Timer tick, asking Buildbucket for the build status
[17:40:30.608] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:30.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:98:0) after 1m0s
[17:41:30.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:98:0)
[17:41:30.801] Timer tick, asking Buildbucket for the build status
[17:41:30.875] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:30.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:99:0) after 1m0s
[17:42:31.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:99:0)
[17:42:31.114] Timer tick, asking Buildbucket for the build status
[17:42:31.448] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:31.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:100:0) after 1m0s
[17:43:31.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:100:0)
[17:43:31.679] Timer tick, asking Buildbucket for the build status
[17:43:31.750] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:31.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:101:0) after 1m0s
[17:44:31.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:101:0)
[17:44:31.880] Timer tick, asking Buildbucket for the build status
[17:44:31.950] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:31.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:102:0) after 1m0s
[17:45:32.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:102:0)
[17:45:32.238] Timer tick, asking Buildbucket for the build status
[17:45:32.311] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:32.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:103:0) after 1m0s
[17:46:32.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:103:0)
[17:46:32.638] Timer tick, asking Buildbucket for the build status
[17:46:32.781] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:32.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:104:0) after 1m0s
[17:47:32.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:104:0)
[17:47:32.894] Timer tick, asking Buildbucket for the build status
[17:47:33.017] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:33.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:105:0) after 1m0s
[17:48:33.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:105:0)
[17:48:33.173] Timer tick, asking Buildbucket for the build status
[17:48:33.232] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:33.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:106:0) after 1m0s
[17:49:33.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:106:0)
[17:49:33.300] Timer tick, asking Buildbucket for the build status
[17:49:33.371] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:33.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:107:0) after 1m0s
[17:50:33.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:107:0)
[17:50:33.437] Timer tick, asking Buildbucket for the build status
[17:50:33.521] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:33.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:108:0) after 1m0s
[17:51:33.581] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:108:0)
[17:51:33.581] Timer tick, asking Buildbucket for the build status
[17:51:33.725] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:33.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:109:0) after 1m0s
[17:52:33.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:109:0)
[17:52:33.823] Timer tick, asking Buildbucket for the build status
[17:52:34.123] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:34.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:110:0) after 1m0s
[17:53:34.364] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:110:0)
[17:53:34.364] Timer tick, asking Buildbucket for the build status
[17:53:34.456] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:34.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:111:0) after 1m0s
[17:54:34.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:111:0)
[17:54:34.525] Timer tick, asking Buildbucket for the build status
[17:54:34.745] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:34.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:112:0) after 1m0s
[17:55:34.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:112:0)
[17:55:34.783] Timer tick, asking Buildbucket for the build status
[17:55:34.861] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:34.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:113:0) after 1m0s
[17:56:34.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:113:0)
[17:56:34.939] Timer tick, asking Buildbucket for the build status
[17:56:35.068] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:35.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:114:0) after 1m0s
[17:57:35.095] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:114:0)
[17:57:35.095] Timer tick, asking Buildbucket for the build status
[17:57:35.167] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:35.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:115:0) after 1m0s
[17:58:35.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:115:0)
[17:58:35.192] Timer tick, asking Buildbucket for the build status
[17:58:35.615] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:35.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:116:0) after 1m0s
[17:59:35.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:116:0)
[17:59:35.606] Timer tick, asking Buildbucket for the build status
[17:59:35.679] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:35.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:117:0) after 1m0s
[18:00:35.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:117:0)
[18:00:35.764] Timer tick, asking Buildbucket for the build status
[18:00:35.900] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:35.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:118:0) after 1m0s
[18:01:35.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:118:0)
[18:01:35.965] Timer tick, asking Buildbucket for the build status
[18:01:36.109] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:36.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:119:0) after 1m0s
[18:02:36.160] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:119:0)
[18:02:36.160] Timer tick, asking Buildbucket for the build status
[18:02:36.288] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:36.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:120:0) after 1m0s
[18:03:36.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:120:0)
[18:03:36.309] Timer tick, asking Buildbucket for the build status
[18:03:36.382] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:36.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:121:0) after 1m0s
[18:04:36.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:121:0)
[18:04:36.481] Timer tick, asking Buildbucket for the build status
[18:04:36.542] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:36.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:122:0) after 1m0s
[18:05:36.612] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:122:0)
[18:05:36.612] Timer tick, asking Buildbucket for the build status
[18:05:37.054] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:37.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:123:0) after 1m0s
[18:06:37.081] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:123:0)
[18:06:37.081] Timer tick, asking Buildbucket for the build status
[18:06:39.120] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:39.120] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:124:0) after 1m0s
[18:07:39.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:124:0)
[18:07:39.181] Timer tick, asking Buildbucket for the build status
[18:07:39.254] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:39.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:125:0) after 1m0s
[18:08:39.284] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:125:0)
[18:08:39.284] Timer tick, asking Buildbucket for the build status
[18:08:39.399] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:39.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:126:0) after 1m0s
[18:09:39.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:126:0)
[18:09:39.432] Timer tick, asking Buildbucket for the build status
[18:09:39.813] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:39.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:127:0) after 1m0s
[18:10:39.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:127:0)
[18:10:39.805] Timer tick, asking Buildbucket for the build status
[18:10:40.212] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:40.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:128:0) after 1m0s
[18:11:40.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:128:0)
[18:11:40.243] Timer tick, asking Buildbucket for the build status
[18:11:40.337] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:40.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:129:0) after 1m0s
[18:12:40.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:129:0)
[18:12:40.395] Timer tick, asking Buildbucket for the build status
[18:12:40.513] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:40.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:130:0) after 1m0s
[18:13:40.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:130:0)
[18:13:40.583] Timer tick, asking Buildbucket for the build status
[18:13:40.649] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:40.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:131:0) after 1m0s
[18:14:40.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:131:0)
[18:14:40.723] Timer tick, asking Buildbucket for the build status
[18:14:40.821] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:40.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:132:0) after 1m0s
[18:15:40.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:132:0)
[18:15:40.942] Timer tick, asking Buildbucket for the build status
[18:15:41.020] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:41.020] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:133:0) after 1m0s
[18:16:41.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:133:0)
[18:16:41.112] Timer tick, asking Buildbucket for the build status
[18:16:41.213] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:41.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:134:0) after 1m0s
[18:17:41.393] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:134:0)
[18:17:41.393] Timer tick, asking Buildbucket for the build status
[18:17:41.476] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:41.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:135:0) after 1m0s
[18:18:41.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:135:0)
[18:18:41.562] Timer tick, asking Buildbucket for the build status
[18:18:41.628] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:41.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:136:0) after 1m0s
[18:19:41.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:136:0)
[18:19:41.677] Timer tick, asking Buildbucket for the build status
[18:19:41.766] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:41.766] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:137:0) after 1m0s
[18:20:41.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:137:0)
[18:20:41.773] Timer tick, asking Buildbucket for the build status
[18:20:41.854] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:41.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:138:0) after 1m0s
[18:21:41.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:138:0)
[18:21:41.934] Timer tick, asking Buildbucket for the build status
[18:21:46.087] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:46.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:139:0) after 1m0s
[18:22:46.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:139:0)
[18:22:46.109] Timer tick, asking Buildbucket for the build status
[18:22:46.189] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:46.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:140:0) after 1m0s
[18:23:46.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:140:0)
[18:23:46.165] Timer tick, asking Buildbucket for the build status
[18:23:46.392] Build 8853041521855940512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:46.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9018471583946459648:141:0) after 1m0s
[18:24:25.104] Received PubSub notification, asking Buildbucket for the build status
[18:24:25.254] Build 8853041521855940512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:24:25.254] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615487064471717",
"created_by": "project:chromium",
"created_ts": "1615478700735327",
"id": "8853041521855940512",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c4423c0abc77ca79dc628d74c083b1450ce0f37f\"}}",
"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\": \"c4423c0abc77ca79dc628d74c083b1450ce0f37f\", \"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-210-sw4j\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 26756, \"got_angle_revision\": \"44b9579cf7b8d35cac13f9e2d70c57791ddfd0e2\", \"got_dawn_revision\": \"b458fc835925a222699fb62358c22047c70bd565\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"c4423c0abc77ca79dc628d74c083b1450ce0f37f\", \"got_revision_cp\": \"refs/heads/master@{#861985}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a2fe8e1b2111e69d8846991f9d9bcc5c49505d1c\", \"got_v8_revision\": \"7ba969d6a1a9590f32ffb196d393d271ce775575\", \"got_v8_revision_cp\": \"refs/heads/9.1.100@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": false, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-viz-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c4423c0abc77ca79dc628d74c083b1450ce0f37f\", \"swarm_hashes_refs/heads/master(at){#861985}_without_patch\": {\"blink_web_tests\": \"2b239029826ecd8c2872cc45c283b47b6343cb86\", \"browser_tests\": \"8389e69224876e282d53727fb6dfb5276543f5a7\", \"content_browsertests\": \"279b2b013f788177972932de4b3ca81cb5ea38aa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093d924ecb526dae9a1dcf9db4725f8944eec2b4c0f2ddc6c2ac4a04802f7f15_v2\", \"builder_3ff20984f80f48d33e2b03ffc12bed5306b6d9761e5e01dd0c6daff69f57abc3_v2\", \"builder_544ce64b59aa2a62f93b2d1058d8c643105c17650fa0dc2da4c94ddccafb95f6_v2\", \"builder_7d3316d3e62555e2d69f10e77e29eb3429b9b1f483dcbd92e41f25657b896dff_v2\", \"builder_983dbffb8cc86de1533c3acacd740027aafc9d2942de9f299ba8447770e514ac_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_dacf669b49e3ec352df995227b58e62a545590985cca62e76e0831f920af4ccd_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-210-sw4j\"], \"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": "1615479068597467",
"status": "COMPLETED",
"status_changed_ts": "1615487064907865",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/26756",
"builder:Linux Viz",
"buildset:commit/git/c4423c0abc77ca79dc628d74c083b1450ce0f37f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c4423c0abc77ca79dc628d74c083b1450ce0f37f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018471583946459648",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853041521855940512/+/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:523ada492bf26310",
"user_agent:luci-scheduler"
],
"updated_ts": "1615487064912380",
"url": "https://ci.chromium.org/b/8853041521855940512",
"utcnow_ts": "1615487065245903"
}
}
[18:24:25.254] Invocation finished in 2h19m25.895885749s with status SUCCEEDED