[01:46:14.880] New invocation is queued and will start shortly
[01:46:15.982] Starting the invocation (attempt 1)
[01:46:16.045] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:46:16.045] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:46:16.045] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023508444464467024",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9023508444464467024",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"gitiles_ref:refs/heads/master"
]
}
[01:46:16.753] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610675176154263",
"id": "8858078382447896336",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610675176602092",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25881",
"builder:Linux Viz",
"buildset:commit/git/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023508444464467024",
"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/8858078382447896336/+/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": "1610675176602364",
"url": "https://ci.chromium.org/b/8858078382447896336",
"utcnow_ts": "1610675176745620"
}
}
[01:46:16.753] Task URL: https://ci.chromium.org/b/8858078382447896336
[01:46:16.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:2:0) after 1m0s
[01:47:16.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:2:0)
[01:47:16.864] Timer tick, asking Buildbucket for the build status
[01:47:17.215] Build 8858078382447896336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:17.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:3:0) after 1m0s
[01:48:17.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:3:0)
[01:48:17.297] Timer tick, asking Buildbucket for the build status
[01:48:17.399] Build 8858078382447896336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:17.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:4:0) after 1m0s
[01:49:17.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:4:0)
[01:49:17.436] Timer tick, asking Buildbucket for the build status
[01:49:17.538] Build 8858078382447896336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:17.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:5:0) after 1m0s
[01:50:17.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:5:0)
[01:50:17.655] Timer tick, asking Buildbucket for the build status
[01:50:17.912] Build 8858078382447896336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:17.912] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:6:0) after 1m0s
[01:51:02.721] Received PubSub notification, asking Buildbucket for the build status
[01:51:02.798] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:17.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:6:0)
[01:51:17.949] Timer tick, asking Buildbucket for the build status
[01:51:18.336] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:18.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:8:0) after 1m0s
[01:52:18.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:8:0)
[01:52:18.447] Timer tick, asking Buildbucket for the build status
[01:52:18.702] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:18.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:9:0) after 1m0s
[01:53:18.806] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:9:0)
[01:53:18.806] Timer tick, asking Buildbucket for the build status
[01:53:19.012] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:19.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:10:0) after 1m0s
[01:54:19.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:10:0)
[01:54:19.144] Timer tick, asking Buildbucket for the build status
[01:54:19.229] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:19.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:11:0) after 1m0s
[01:55:19.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:11:0)
[01:55:19.286] Timer tick, asking Buildbucket for the build status
[01:55:19.657] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:19.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:12:0) after 1m0s
[01:56:19.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:12:0)
[01:56:19.726] Timer tick, asking Buildbucket for the build status
[01:56:19.828] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:19.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:13:0) after 1m0s
[01:57:19.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:13:0)
[01:57:19.931] Timer tick, asking Buildbucket for the build status
[01:57:20.004] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:20.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:14:0) after 1m0s
[01:58:20.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:14:0)
[01:58:20.141] Timer tick, asking Buildbucket for the build status
[01:58:20.210] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:20.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:15:0) after 1m0s
[01:59:20.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:15:0)
[01:59:20.316] Timer tick, asking Buildbucket for the build status
[01:59:20.384] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:20.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:16:0) after 1m0s
[02:00:20.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:16:0)
[02:00:20.422] Timer tick, asking Buildbucket for the build status
[02:00:21.126] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:21.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:17:0) after 1m0s
[02:01:21.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:17:0)
[02:01:21.158] Timer tick, asking Buildbucket for the build status
[02:01:21.321] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:21.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:18:0) after 1m0s
[02:02:21.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:18:0)
[02:02:21.385] Timer tick, asking Buildbucket for the build status
[02:02:21.522] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:21.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:19:0) after 1m0s
[02:03:21.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:19:0)
[02:03:21.587] Timer tick, asking Buildbucket for the build status
[02:03:21.677] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:21.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:20:0) after 1m0s
[02:04:21.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:20:0)
[02:04:21.746] Timer tick, asking Buildbucket for the build status
[02:04:21.814] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:21.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:21:0) after 1m0s
[02:05:21.926] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:21:0)
[02:05:21.926] Timer tick, asking Buildbucket for the build status
[02:05:22.062] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:22.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:22:0) after 1m0s
[02:06:22.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:22:0)
[02:06:22.254] Timer tick, asking Buildbucket for the build status
[02:06:22.880] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:22.880] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:23:0) after 1m0s
[02:07:22.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:23:0)
[02:07:22.909] Timer tick, asking Buildbucket for the build status
[02:07:23.047] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:23.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:24:0) after 1m0s
[02:08:23.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:24:0)
[02:08:23.093] Timer tick, asking Buildbucket for the build status
[02:08:23.248] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:23.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:25:0) after 1m0s
[02:09:23.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:25:0)
[02:09:23.305] Timer tick, asking Buildbucket for the build status
[02:09:23.445] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:23.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:26:0) after 1m0s
[02:10:23.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:26:0)
[02:10:23.481] Timer tick, asking Buildbucket for the build status
[02:10:23.694] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:23.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:27:0) after 1m0s
[02:11:23.739] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:27:0)
[02:11:23.739] Timer tick, asking Buildbucket for the build status
[02:11:24.313] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:24.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:28:0) after 1m0s
[02:12:24.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:28:0)
[02:12:24.384] Timer tick, asking Buildbucket for the build status
[02:12:24.495] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:24.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:29:0) after 1m0s
[02:13:24.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:29:0)
[02:13:24.474] Timer tick, asking Buildbucket for the build status
[02:13:24.677] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:24.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:30:0) after 1m0s
[02:14:24.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:30:0)
[02:14:24.741] Timer tick, asking Buildbucket for the build status
[02:14:24.807] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:24.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:31:0) after 1m0s
[02:15:25.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:31:0)
[02:15:25.008] Timer tick, asking Buildbucket for the build status
[02:15:25.108] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:25.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:32:0) after 1m0s
[02:16:25.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:32:0)
[02:16:25.177] Timer tick, asking Buildbucket for the build status
[02:16:25.304] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:25.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:33:0) after 1m0s
[02:17:25.364] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:33:0)
[02:17:25.364] Timer tick, asking Buildbucket for the build status
[02:17:25.440] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:25.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:34:0) after 1m0s
[02:18:25.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:34:0)
[02:18:25.465] Timer tick, asking Buildbucket for the build status
[02:18:25.593] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:25.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:35:0) after 1m0s
[02:19:25.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:35:0)
[02:19:25.718] Timer tick, asking Buildbucket for the build status
[02:19:26.060] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:26.060] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:36:0) after 1m0s
[02:20:26.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:36:0)
[02:20:26.085] Timer tick, asking Buildbucket for the build status
[02:20:26.148] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:26.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:37:0) after 1m0s
[02:21:26.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:37:0)
[02:21:26.205] Timer tick, asking Buildbucket for the build status
[02:21:26.318] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:26.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:38:0) after 1m0s
[02:22:26.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:38:0)
[02:22:26.339] Timer tick, asking Buildbucket for the build status
[02:22:26.721] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:26.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:39:0) after 1m0s
[02:23:26.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:39:0)
[02:23:26.760] Timer tick, asking Buildbucket for the build status
[02:23:26.833] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:26.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:40:0) after 1m0s
[02:24:26.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:40:0)
[02:24:26.922] Timer tick, asking Buildbucket for the build status
[02:24:26.990] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:26.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:41:0) after 1m0s
[02:25:27.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:41:0)
[02:25:27.012] Timer tick, asking Buildbucket for the build status
[02:25:28.389] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:28.389] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:42:0) after 1m0s
[02:26:28.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:42:0)
[02:26:28.479] Timer tick, asking Buildbucket for the build status
[02:26:28.635] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:28.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:43:0) after 1m0s
[02:27:28.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:43:0)
[02:27:28.729] Timer tick, asking Buildbucket for the build status
[02:27:29.023] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:29.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:44:0) after 1m0s
[02:28:29.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:44:0)
[02:28:29.086] Timer tick, asking Buildbucket for the build status
[02:28:29.220] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:29.220] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:45:0) after 1m0s
[02:29:29.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:45:0)
[02:29:29.303] Timer tick, asking Buildbucket for the build status
[02:29:29.559] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:29.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:46:0) after 1m0s
[02:30:29.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:46:0)
[02:30:29.711] Timer tick, asking Buildbucket for the build status
[02:30:30.119] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:30.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:47:0) after 1m0s
[02:31:30.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:47:0)
[02:31:30.170] Timer tick, asking Buildbucket for the build status
[02:31:30.301] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:30.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:48:0) after 1m0s
[02:32:30.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:48:0)
[02:32:30.339] Timer tick, asking Buildbucket for the build status
[02:32:30.598] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:30.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:49:0) after 1m0s
[02:33:30.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:49:0)
[02:33:30.603] Timer tick, asking Buildbucket for the build status
[02:33:30.681] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:30.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:50:0) after 1m0s
[02:34:30.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:50:0)
[02:34:30.814] Timer tick, asking Buildbucket for the build status
[02:34:30.886] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:30.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:51:0) after 1m0s
[02:35:30.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:51:0)
[02:35:30.904] Timer tick, asking Buildbucket for the build status
[02:35:31.152] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:31.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:52:0) after 1m0s
[02:36:31.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:52:0)
[02:36:31.135] Timer tick, asking Buildbucket for the build status
[02:36:31.435] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:31.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:53:0) after 1m0s
[02:37:31.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:53:0)
[02:37:31.466] Timer tick, asking Buildbucket for the build status
[02:37:31.661] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:31.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:54:0) after 1m0s
[02:38:31.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:54:0)
[02:38:31.683] Timer tick, asking Buildbucket for the build status
[02:38:31.825] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:31.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:55:0) after 1m0s
[02:39:31.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:55:0)
[02:39:31.989] Timer tick, asking Buildbucket for the build status
[02:39:32.078] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:32.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:56:0) after 1m0s
[02:40:32.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:56:0)
[02:40:32.124] Timer tick, asking Buildbucket for the build status
[02:40:32.216] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:32.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:57:0) after 1m0s
[02:41:32.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:57:0)
[02:41:32.214] Timer tick, asking Buildbucket for the build status
[02:41:32.352] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:32.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:58:0) after 1m0s
[02:42:32.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:58:0)
[02:42:32.435] Timer tick, asking Buildbucket for the build status
[02:42:32.934] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:32.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:59:0) after 1m0s
[02:43:32.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:59:0)
[02:43:32.941] Timer tick, asking Buildbucket for the build status
[02:43:33.004] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:33.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:60:0) after 1m0s
[02:44:33.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:60:0)
[02:44:33.062] Timer tick, asking Buildbucket for the build status
[02:44:33.172] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:33.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:61:0) after 1m0s
[02:45:33.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:61:0)
[02:45:33.205] Timer tick, asking Buildbucket for the build status
[02:45:33.364] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:33.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:62:0) after 1m0s
[02:46:33.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:62:0)
[02:46:33.447] Timer tick, asking Buildbucket for the build status
[02:46:33.907] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:33.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:63:0) after 1m0s
[02:47:33.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:63:0)
[02:47:33.933] Timer tick, asking Buildbucket for the build status
[02:47:34.451] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:34.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:64:0) after 1m0s
[02:48:34.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:64:0)
[02:48:34.513] Timer tick, asking Buildbucket for the build status
[02:48:34.879] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:34.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:65:0) after 1m0s
[02:49:34.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:65:0)
[02:49:34.917] Timer tick, asking Buildbucket for the build status
[02:49:35.424] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:35.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:66:0) after 1m0s
[02:50:35.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:66:0)
[02:50:35.447] Timer tick, asking Buildbucket for the build status
[02:50:35.679] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:35.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:67:0) after 1m0s
[02:51:35.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:67:0)
[02:51:35.731] Timer tick, asking Buildbucket for the build status
[02:51:35.836] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:35.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:68:0) after 1m0s
[02:52:35.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:68:0)
[02:52:35.867] Timer tick, asking Buildbucket for the build status
[02:52:35.924] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:35.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:69:0) after 1m0s
[02:53:35.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:69:0)
[02:53:35.976] Timer tick, asking Buildbucket for the build status
[02:53:36.067] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:36.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:70:0) after 1m0s
[02:54:36.095] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:70:0)
[02:54:36.095] Timer tick, asking Buildbucket for the build status
[02:54:36.156] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:36.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:71:0) after 1m0s
[02:55:36.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:71:0)
[02:55:36.205] Timer tick, asking Buildbucket for the build status
[02:55:36.388] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:36.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:72:0) after 1m0s
[02:56:36.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:72:0)
[02:56:36.438] Timer tick, asking Buildbucket for the build status
[02:56:36.581] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:36.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:73:0) after 1m0s
[02:57:36.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:73:0)
[02:57:36.681] Timer tick, asking Buildbucket for the build status
[02:57:36.758] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:36.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:74:0) after 1m0s
[02:58:36.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:74:0)
[02:58:36.851] Timer tick, asking Buildbucket for the build status
[02:58:38.038] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:38.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:75:0) after 1m0s
[02:59:38.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:75:0)
[02:59:38.090] Timer tick, asking Buildbucket for the build status
[02:59:38.172] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:38.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:76:0) after 1m0s
[03:00:38.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:76:0)
[03:00:38.208] Timer tick, asking Buildbucket for the build status
[03:00:38.286] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:38.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:77:0) after 1m0s
[03:01:38.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:77:0)
[03:01:38.399] Timer tick, asking Buildbucket for the build status
[03:01:38.648] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:38.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:78:0) after 1m0s
[03:02:38.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:78:0)
[03:02:38.716] Timer tick, asking Buildbucket for the build status
[03:02:38.829] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:38.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:79:0) after 1m0s
[03:03:38.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:79:0)
[03:03:38.954] Timer tick, asking Buildbucket for the build status
[03:03:39.248] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:39.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:80:0) after 1m0s
[03:04:39.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:80:0)
[03:04:39.332] Timer tick, asking Buildbucket for the build status
[03:04:39.409] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:39.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:81:0) after 1m0s
[03:05:39.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:81:0)
[03:05:39.510] Timer tick, asking Buildbucket for the build status
[03:05:39.740] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:39.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:82:0) after 1m0s
[03:06:39.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:82:0)
[03:06:39.758] Timer tick, asking Buildbucket for the build status
[03:06:40.383] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:40.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:83:0) after 1m0s
[03:07:40.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:83:0)
[03:07:40.413] Timer tick, asking Buildbucket for the build status
[03:07:40.800] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:40.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:84:0) after 1m0s
[03:08:40.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:84:0)
[03:08:40.967] Timer tick, asking Buildbucket for the build status
[03:08:41.119] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:41.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:85:0) after 1m0s
[03:09:41.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:85:0)
[03:09:41.158] Timer tick, asking Buildbucket for the build status
[03:09:41.290] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:41.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:86:0) after 1m0s
[03:10:41.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:86:0)
[03:10:41.441] Timer tick, asking Buildbucket for the build status
[03:10:41.809] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:41.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:87:0) after 1m0s
[03:11:41.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:87:0)
[03:11:41.898] Timer tick, asking Buildbucket for the build status
[03:11:42.148] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:42.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:88:0) after 1m0s
[03:12:42.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:88:0)
[03:12:42.171] Timer tick, asking Buildbucket for the build status
[03:12:42.667] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:42.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:89:0) after 1m0s
[03:13:42.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:89:0)
[03:13:42.691] Timer tick, asking Buildbucket for the build status
[03:13:42.807] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:42.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:90:0) after 1m0s
[03:14:42.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:90:0)
[03:14:42.847] Timer tick, asking Buildbucket for the build status
[03:14:42.918] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:42.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:91:0) after 1m0s
[03:15:42.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:91:0)
[03:15:42.984] Timer tick, asking Buildbucket for the build status
[03:15:43.131] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:43.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:92:0) after 1m0s
[03:16:43.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:92:0)
[03:16:43.161] Timer tick, asking Buildbucket for the build status
[03:16:43.733] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:43.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:93:0) after 1m0s
[03:17:43.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:93:0)
[03:17:43.833] Timer tick, asking Buildbucket for the build status
[03:17:43.942] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:43.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:94:0) after 1m0s
[03:18:44.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:94:0)
[03:18:44.075] Timer tick, asking Buildbucket for the build status
[03:18:44.192] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:44.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:95:0) after 1m0s
[03:19:44.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:95:0)
[03:19:44.318] Timer tick, asking Buildbucket for the build status
[03:19:44.431] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:44.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:96:0) after 1m0s
[03:20:46.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:96:0)
[03:20:46.744] Timer tick, asking Buildbucket for the build status
[03:20:46.912] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:46.912] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:97:0) after 1m0s
[03:21:46.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:97:0)
[03:21:46.946] Timer tick, asking Buildbucket for the build status
[03:21:47.104] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:47.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:98:0) after 1m0s
[03:22:47.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:98:0)
[03:22:47.177] Timer tick, asking Buildbucket for the build status
[03:22:47.244] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:47.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:99:0) after 1m0s
[03:23:47.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:99:0)
[03:23:47.303] Timer tick, asking Buildbucket for the build status
[03:23:47.381] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:47.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:100:0) after 1m0s
[03:24:47.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:100:0)
[03:24:47.408] Timer tick, asking Buildbucket for the build status
[03:24:47.472] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:47.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:101:0) after 1m0s
[03:25:47.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:101:0)
[03:25:47.547] Timer tick, asking Buildbucket for the build status
[03:25:47.621] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:47.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:102:0) after 1m0s
[03:26:47.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:102:0)
[03:26:47.654] Timer tick, asking Buildbucket for the build status
[03:26:47.746] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:47.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:103:0) after 1m0s
[03:27:47.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:103:0)
[03:27:47.785] Timer tick, asking Buildbucket for the build status
[03:27:48.069] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:48.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:104:0) after 1m0s
[03:28:48.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:104:0)
[03:28:48.119] Timer tick, asking Buildbucket for the build status
[03:28:48.555] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:48.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:105:0) after 1m0s
[03:29:48.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:105:0)
[03:29:48.639] Timer tick, asking Buildbucket for the build status
[03:29:48.888] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:48.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:106:0) after 1m0s
[03:30:48.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:106:0)
[03:30:48.969] Timer tick, asking Buildbucket for the build status
[03:30:49.033] Build 8858078382447896336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:49.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023508444464467024:107:0) after 1m0s
[03:31:05.592] Received PubSub notification, asking Buildbucket for the build status
[03:31:05.670] Build 8858078382447896336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:31:05.670] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610681464404196",
"created_by": "project:chromium",
"created_ts": "1610675176154263",
"id": "8858078382447896336",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\"}}",
"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\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\", \"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-east1-d-8-21-015b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 25881, \"got_angle_revision\": \"c07ef602741b3b051ac4823b5a74327279ffc68b\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\", \"got_revision_cp\": \"refs/heads/master@{#843877}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"25baaa4b7d2b1a3315bab27765c29ac43a8b96ec\", \"swarm_hashes_refs/heads/master(at){#843877}_without_patch\": {\"blink_web_tests\": \"1849e9ca157d3df5465ce1676b2adb832279845a\", \"browser_tests\": \"b82f1522480ee04a206ec401cc3465d057529d5a\", \"content_browsertests\": \"174e86bf494e8d21b262fe3dba4c0561fd901af8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_73a5b2ce307b316a1240917eaad41a92e4adc44903c6789e05a5f10300306cda_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-east1-d-8-21-015b\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610675462206106",
"status": "COMPLETED",
"status_changed_ts": "1610681465031670",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25881",
"builder:Linux Viz",
"buildset:commit/git/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/25baaa4b7d2b1a3315bab27765c29ac43a8b96ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023508444464467024",
"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/8858078382447896336/+/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:511c8a4e7248b710",
"user_agent:luci-scheduler"
],
"updated_ts": "1610681465113107",
"url": "https://ci.chromium.org/b/8858078382447896336",
"utcnow_ts": "1610681465661157"
}
}
[03:31:05.670] Invocation finished in 1h44m50.823049158s with status SUCCEEDED