[00:48:09.621] New invocation is queued and will start shortly
[00:48:10.689] Starting the invocation (attempt 1)
[00:48:10.724] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:48:10.724] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:48:10.724] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019344638549840512",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3464378ad220b9508251df7f3345b57f0ccbefe8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9019344638549840512",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/3464378ad220b9508251df7f3345b57f0ccbefe8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3464378ad220b9508251df7f3345b57f0ccbefe8",
"gitiles_ref:refs/heads/master"
]
}
[00:48:12.735] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614646091128892",
"id": "8853914576308023504",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3464378ad220b9508251df7f3345b57f0ccbefe8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614646092401262",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/26606",
"builder:Linux Viz",
"buildset:commit/git/3464378ad220b9508251df7f3345b57f0ccbefe8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3464378ad220b9508251df7f3345b57f0ccbefe8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019344638549840512",
"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/8853914576308023504/+/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": "1614646092401416",
"url": "https://ci.chromium.org/b/8853914576308023504",
"utcnow_ts": "1614646092719902"
}
}
[00:48:12.737] Task URL: https://ci.chromium.org/b/8853914576308023504
[00:48:12.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:2:0) after 1m0s
[00:48:16.047] Received PubSub notification, asking Buildbucket for the build status
[00:48:17.249] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:12.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:2:0)
[00:49:12.776] Timer tick, asking Buildbucket for the build status
[00:49:12.868] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:12.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:4:0) after 1m0s
[00:50:12.893] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:4:0)
[00:50:12.893] Timer tick, asking Buildbucket for the build status
[00:50:13.238] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:13.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:5:0) after 1m0s
[00:51:13.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:5:0)
[00:51:13.286] Timer tick, asking Buildbucket for the build status
[00:51:13.355] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:13.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:6:0) after 1m0s
[00:52:13.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:6:0)
[00:52:13.413] Timer tick, asking Buildbucket for the build status
[00:52:14.021] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:14.021] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:7:0) after 1m0s
[00:53:14.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:7:0)
[00:53:14.106] Timer tick, asking Buildbucket for the build status
[00:53:14.267] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:14.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:8:0) after 1m0s
[00:54:14.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:8:0)
[00:54:14.320] Timer tick, asking Buildbucket for the build status
[00:54:14.392] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:14.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:9:0) after 1m0s
[00:55:14.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:9:0)
[00:55:14.389] Timer tick, asking Buildbucket for the build status
[00:55:14.531] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:14.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:10:0) after 1m0s
[00:56:14.575] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:10:0)
[00:56:14.575] Timer tick, asking Buildbucket for the build status
[00:56:14.638] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:14.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:11:0) after 1m0s
[00:57:14.634] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:11:0)
[00:57:14.634] Timer tick, asking Buildbucket for the build status
[00:57:14.809] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:14.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:12:0) after 1m0s
[00:58:14.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:12:0)
[00:58:14.829] Timer tick, asking Buildbucket for the build status
[00:58:14.913] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:14.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:13:0) after 1m0s
[00:59:14.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:13:0)
[00:59:14.962] Timer tick, asking Buildbucket for the build status
[00:59:15.075] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:15.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:14:0) after 1m0s
[01:00:15.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:14:0)
[01:00:15.624] Timer tick, asking Buildbucket for the build status
[01:00:15.863] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:15.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:15:0) after 1m0s
[01:01:15.872] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:15:0)
[01:01:15.872] Timer tick, asking Buildbucket for the build status
[01:01:15.980] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:15.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:16:0) after 1m0s
[01:02:16.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:16:0)
[01:02:16.353] Timer tick, asking Buildbucket for the build status
[01:02:16.414] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:16.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:17:0) after 1m0s
[01:03:16.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:17:0)
[01:03:16.553] Timer tick, asking Buildbucket for the build status
[01:03:16.717] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:16.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:18:0) after 1m0s
[01:04:16.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:18:0)
[01:04:16.735] Timer tick, asking Buildbucket for the build status
[01:04:16.856] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:16.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:19:0) after 1m0s
[01:05:16.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:19:0)
[01:05:16.867] Timer tick, asking Buildbucket for the build status
[01:05:17.122] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:17.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:20:0) after 1m0s
[01:06:17.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:20:0)
[01:06:17.137] Timer tick, asking Buildbucket for the build status
[01:06:17.213] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:17.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:21:0) after 1m0s
[01:07:17.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:21:0)
[01:07:17.286] Timer tick, asking Buildbucket for the build status
[01:07:18.394] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:18.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:22:0) after 1m0s
[01:08:18.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:22:0)
[01:08:18.438] Timer tick, asking Buildbucket for the build status
[01:08:18.510] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:18.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:23:0) after 1m0s
[01:09:18.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:23:0)
[01:09:18.553] Timer tick, asking Buildbucket for the build status
[01:09:18.642] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:18.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:24:0) after 1m0s
[01:10:18.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:24:0)
[01:10:18.692] Timer tick, asking Buildbucket for the build status
[01:10:18.809] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:18.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:25:0) after 1m0s
[01:11:18.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:25:0)
[01:11:18.802] Timer tick, asking Buildbucket for the build status
[01:11:18.881] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:18.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:26:0) after 1m0s
[01:12:18.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:26:0)
[01:12:18.890] Timer tick, asking Buildbucket for the build status
[01:12:19.754] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:19.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:27:0) after 1m0s
[01:13:19.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:27:0)
[01:13:19.834] Timer tick, asking Buildbucket for the build status
[01:13:19.899] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:19.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:28:0) after 1m0s
[01:14:20.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:28:0)
[01:14:20.075] Timer tick, asking Buildbucket for the build status
[01:14:20.155] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:20.155] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:29:0) after 1m0s
[01:15:20.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:29:0)
[01:15:20.275] Timer tick, asking Buildbucket for the build status
[01:15:20.368] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:20.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:30:0) after 1m0s
[01:16:20.374] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:30:0)
[01:16:20.374] Timer tick, asking Buildbucket for the build status
[01:16:20.627] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:20.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:31:0) after 1m0s
[01:17:20.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:31:0)
[01:17:20.653] Timer tick, asking Buildbucket for the build status
[01:17:20.748] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:20.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:32:0) after 1m0s
[01:18:20.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:32:0)
[01:18:20.844] Timer tick, asking Buildbucket for the build status
[01:18:20.919] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:20.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:33:0) after 1m0s
[01:19:21.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:33:0)
[01:19:21.005] Timer tick, asking Buildbucket for the build status
[01:19:21.082] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:21.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:34:0) after 1m0s
[01:20:21.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:34:0)
[01:20:21.299] Timer tick, asking Buildbucket for the build status
[01:20:22.159] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:22.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:35:0) after 1m0s
[01:21:22.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:35:0)
[01:21:22.475] Timer tick, asking Buildbucket for the build status
[01:21:22.608] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:22.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:36:0) after 1m0s
[01:22:22.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:36:0)
[01:22:22.768] Timer tick, asking Buildbucket for the build status
[01:22:22.831] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:22.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:37:0) after 1m0s
[01:23:22.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:37:0)
[01:23:22.999] Timer tick, asking Buildbucket for the build status
[01:23:23.083] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:23.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:38:0) after 1m0s
[01:24:23.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:38:0)
[01:24:23.128] Timer tick, asking Buildbucket for the build status
[01:24:25.070] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:25.070] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:39:0) after 1m0s
[01:25:25.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:39:0)
[01:25:25.113] Timer tick, asking Buildbucket for the build status
[01:25:25.229] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:25.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:40:0) after 1m0s
[01:26:25.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:40:0)
[01:26:25.222] Timer tick, asking Buildbucket for the build status
[01:26:26.185] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:26.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:41:0) after 1m0s
[01:27:26.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:41:0)
[01:27:26.247] Timer tick, asking Buildbucket for the build status
[01:27:26.315] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:26.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:42:0) after 1m0s
[01:28:26.392] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:42:0)
[01:28:26.392] Timer tick, asking Buildbucket for the build status
[01:28:27.804] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:27.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:43:0) after 1m0s
[01:29:27.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:43:0)
[01:29:27.962] Timer tick, asking Buildbucket for the build status
[01:29:28.354] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:28.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:44:0) after 1m0s
[01:30:28.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:44:0)
[01:30:28.408] Timer tick, asking Buildbucket for the build status
[01:30:28.496] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:28.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:45:0) after 1m0s
[01:31:28.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:45:0)
[01:31:28.558] Timer tick, asking Buildbucket for the build status
[01:31:30.338] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:30.338] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:46:0) after 1m0s
[01:32:30.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:46:0)
[01:32:30.358] Timer tick, asking Buildbucket for the build status
[01:32:30.435] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:30.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:47:0) after 1m0s
[01:33:30.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:47:0)
[01:33:30.436] Timer tick, asking Buildbucket for the build status
[01:33:30.515] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:30.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:48:0) after 1m0s
[01:34:30.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:48:0)
[01:34:30.558] Timer tick, asking Buildbucket for the build status
[01:34:30.746] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:30.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:49:0) after 1m0s
[01:35:30.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:49:0)
[01:35:30.765] Timer tick, asking Buildbucket for the build status
[01:35:30.838] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:30.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:50:0) after 1m0s
[01:36:30.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:50:0)
[01:36:30.885] Timer tick, asking Buildbucket for the build status
[01:36:31.052] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:31.052] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:51:0) after 1m0s
[01:37:31.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:51:0)
[01:37:31.073] Timer tick, asking Buildbucket for the build status
[01:37:31.226] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:31.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:52:0) after 1m0s
[01:38:31.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:52:0)
[01:38:31.297] Timer tick, asking Buildbucket for the build status
[01:38:31.393] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:31.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:53:0) after 1m0s
[01:39:31.473] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:53:0)
[01:39:31.473] Timer tick, asking Buildbucket for the build status
[01:39:31.543] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:31.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:54:0) after 1m0s
[01:40:31.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:54:0)
[01:40:31.570] Timer tick, asking Buildbucket for the build status
[01:40:31.668] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:31.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:55:0) after 1m0s
[01:41:31.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:55:0)
[01:41:31.703] Timer tick, asking Buildbucket for the build status
[01:41:31.904] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:31.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:56:0) after 1m0s
[01:42:31.950] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:56:0)
[01:42:31.950] Timer tick, asking Buildbucket for the build status
[01:42:32.027] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:32.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:57:0) after 1m0s
[01:43:32.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:57:0)
[01:43:32.051] Timer tick, asking Buildbucket for the build status
[01:43:32.472] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:32.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:58:0) after 1m0s
[01:44:32.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:58:0)
[01:44:32.468] Timer tick, asking Buildbucket for the build status
[01:44:32.542] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:32.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:59:0) after 1m0s
[01:45:32.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:59:0)
[01:45:32.566] Timer tick, asking Buildbucket for the build status
[01:45:32.669] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:32.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:60:0) after 1m0s
[01:46:32.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:60:0)
[01:46:32.713] Timer tick, asking Buildbucket for the build status
[01:46:32.851] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:32.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:61:0) after 1m0s
[01:47:32.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:61:0)
[01:47:32.906] Timer tick, asking Buildbucket for the build status
[01:47:33.057] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:33.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:62:0) after 1m0s
[01:48:33.126] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:62:0)
[01:48:33.126] Timer tick, asking Buildbucket for the build status
[01:48:33.247] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:33.247] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:63:0) after 1m0s
[01:49:33.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:63:0)
[01:49:33.294] Timer tick, asking Buildbucket for the build status
[01:49:33.454] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:33.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:64:0) after 1m0s
[01:50:33.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:64:0)
[01:50:33.573] Timer tick, asking Buildbucket for the build status
[01:50:33.667] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:33.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:65:0) after 1m0s
[01:51:33.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:65:0)
[01:51:33.672] Timer tick, asking Buildbucket for the build status
[01:51:33.765] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:33.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:66:0) after 1m0s
[01:52:33.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:66:0)
[01:52:33.819] Timer tick, asking Buildbucket for the build status
[01:52:33.916] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:33.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:67:0) after 1m0s
[01:53:33.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:67:0)
[01:53:33.962] Timer tick, asking Buildbucket for the build status
[01:53:34.049] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:34.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:68:0) after 1m0s
[01:54:34.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:68:0)
[01:54:34.373] Timer tick, asking Buildbucket for the build status
[01:54:34.859] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:34.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:69:0) after 1m0s
[01:55:34.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:69:0)
[01:55:34.892] Timer tick, asking Buildbucket for the build status
[01:55:34.999] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:34.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:70:0) after 1m0s
[01:56:35.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:70:0)
[01:56:35.130] Timer tick, asking Buildbucket for the build status
[01:56:35.539] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:35.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:71:0) after 1m0s
[01:57:35.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:71:0)
[01:57:35.829] Timer tick, asking Buildbucket for the build status
[01:57:35.889] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:35.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:72:0) after 1m0s
[01:58:35.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:72:0)
[01:58:35.981] Timer tick, asking Buildbucket for the build status
[01:58:36.358] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:36.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:73:0) after 1m0s
[01:59:36.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:73:0)
[01:59:36.536] Timer tick, asking Buildbucket for the build status
[01:59:36.619] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:36.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:74:0) after 1m0s
[02:00:36.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:74:0)
[02:00:36.749] Timer tick, asking Buildbucket for the build status
[02:00:37.166] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:37.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:75:0) after 1m0s
[02:01:37.835] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:75:0)
[02:01:37.835] Timer tick, asking Buildbucket for the build status
[02:01:38.344] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:38.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:76:0) after 1m0s
[02:02:38.431] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:76:0)
[02:02:38.431] Timer tick, asking Buildbucket for the build status
[02:02:38.524] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:38.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:77:0) after 1m0s
[02:03:38.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:77:0)
[02:03:38.682] Timer tick, asking Buildbucket for the build status
[02:03:38.769] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:38.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:78:0) after 1m0s
[02:04:38.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:78:0)
[02:04:38.918] Timer tick, asking Buildbucket for the build status
[02:04:38.982] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:38.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:79:0) after 1m0s
[02:05:39.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:79:0)
[02:05:39.475] Timer tick, asking Buildbucket for the build status
[02:05:39.560] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:39.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:80:0) after 1m0s
[02:06:39.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:80:0)
[02:06:39.628] Timer tick, asking Buildbucket for the build status
[02:06:39.703] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:39.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:81:0) after 1m0s
[02:07:39.944] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:81:0)
[02:07:39.944] Timer tick, asking Buildbucket for the build status
[02:07:40.008] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:40.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:82:0) after 1m0s
[02:08:40.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:82:0)
[02:08:40.061] Timer tick, asking Buildbucket for the build status
[02:08:40.214] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:40.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:83:0) after 1m0s
[02:09:40.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:83:0)
[02:09:40.410] Timer tick, asking Buildbucket for the build status
[02:09:40.806] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:40.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:84:0) after 1m0s
[02:10:40.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:84:0)
[02:10:40.959] Timer tick, asking Buildbucket for the build status
[02:10:41.067] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:41.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:85:0) after 1m0s
[02:11:41.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:85:0)
[02:11:41.190] Timer tick, asking Buildbucket for the build status
[02:11:41.456] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:41.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:86:0) after 1m0s
[02:12:41.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:86:0)
[02:12:41.526] Timer tick, asking Buildbucket for the build status
[02:12:41.592] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:41.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:87:0) after 1m0s
[02:13:41.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:87:0)
[02:13:41.586] Timer tick, asking Buildbucket for the build status
[02:13:41.677] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:41.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:88:0) after 1m0s
[02:14:41.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:88:0)
[02:14:41.780] Timer tick, asking Buildbucket for the build status
[02:14:41.954] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:41.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:89:0) after 1m0s
[02:15:42.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:89:0)
[02:15:42.007] Timer tick, asking Buildbucket for the build status
[02:15:42.138] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:42.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:90:0) after 1m0s
[02:16:42.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:90:0)
[02:16:42.169] Timer tick, asking Buildbucket for the build status
[02:16:42.412] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:42.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:91:0) after 1m0s
[02:17:42.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:91:0)
[02:17:42.425] Timer tick, asking Buildbucket for the build status
[02:17:42.525] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:42.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:92:0) after 1m0s
[02:18:42.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:92:0)
[02:18:42.623] Timer tick, asking Buildbucket for the build status
[02:18:43.422] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:43.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:93:0) after 1m0s
[02:19:43.448] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:93:0)
[02:19:43.448] Timer tick, asking Buildbucket for the build status
[02:19:43.538] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:43.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:94:0) after 1m0s
[02:20:43.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:94:0)
[02:20:43.516] Timer tick, asking Buildbucket for the build status
[02:20:43.646] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:43.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:95:0) after 1m0s
[02:21:44.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:95:0)
[02:21:44.414] Timer tick, asking Buildbucket for the build status
[02:21:44.486] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:44.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:96:0) after 1m0s
[02:22:44.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:96:0)
[02:22:44.730] Timer tick, asking Buildbucket for the build status
[02:22:44.835] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:44.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:97:0) after 1m0s
[02:23:45.010] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:97:0)
[02:23:45.010] Timer tick, asking Buildbucket for the build status
[02:23:45.309] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:45.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:98:0) after 1m0s
[02:24:45.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:98:0)
[02:24:45.354] Timer tick, asking Buildbucket for the build status
[02:24:45.435] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:45.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:99:0) after 1m0s
[02:25:45.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:99:0)
[02:25:45.703] Timer tick, asking Buildbucket for the build status
[02:25:45.860] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:45.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:100:0) after 1m0s
[02:26:46.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:100:0)
[02:26:46.015] Timer tick, asking Buildbucket for the build status
[02:26:46.082] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:46.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:101:0) after 1m0s
[02:27:46.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:101:0)
[02:27:46.183] Timer tick, asking Buildbucket for the build status
[02:27:46.239] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:46.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:102:0) after 1m0s
[02:28:46.473] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:102:0)
[02:28:46.473] Timer tick, asking Buildbucket for the build status
[02:28:46.542] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:46.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:103:0) after 1m0s
[02:29:46.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:103:0)
[02:29:46.871] Timer tick, asking Buildbucket for the build status
[02:29:46.987] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:46.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:104:0) after 1m0s
[02:30:47.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:104:0)
[02:30:47.125] Timer tick, asking Buildbucket for the build status
[02:30:47.224] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:47.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:105:0) after 1m0s
[02:31:47.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:105:0)
[02:31:47.252] Timer tick, asking Buildbucket for the build status
[02:31:47.322] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:47.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:106:0) after 1m0s
[02:32:47.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:106:0)
[02:32:47.372] Timer tick, asking Buildbucket for the build status
[02:32:47.446] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:47.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:107:0) after 1m0s
[02:33:47.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:107:0)
[02:33:47.475] Timer tick, asking Buildbucket for the build status
[02:33:47.563] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:47.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:108:0) after 1m0s
[02:34:47.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:108:0)
[02:34:47.608] Timer tick, asking Buildbucket for the build status
[02:34:47.722] Build 8853914576308023504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:47.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9019344638549840512:109:0) after 1m0s
[02:34:59.831] Received PubSub notification, asking Buildbucket for the build status
[02:34:59.897] Build 8853914576308023504: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:34:59.897] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614652499018988",
"created_by": "project:chromium",
"created_ts": "1614646091128892",
"id": "8853914576308023504",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3464378ad220b9508251df7f3345b57f0ccbefe8\"}}",
"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\": \"3464378ad220b9508251df7f3345b57f0ccbefe8\", \"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-216-of7x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 26606, \"got_angle_revision\": \"5b82fa2e842475cd93c6a6119b6e07635054e836\", \"got_dawn_revision\": \"931182485579d2eec3b24c1f2df646870f80da68\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"3464378ad220b9508251df7f3345b57f0ccbefe8\", \"got_revision_cp\": \"refs/heads/master@{#858805}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"740e24e201f80287f4151c15f61fa684ea53dd3c\", \"got_v8_revision_cp\": \"refs/heads/9.1.9@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"3464378ad220b9508251df7f3345b57f0ccbefe8\", \"swarm_hashes_refs/heads/master(at){#858805}_without_patch\": {\"blink_web_tests\": \"9b2ce09e9ce3311aafc6ab7a56616f42f33c0cdc\", \"browser_tests\": \"097a9adee0f1f9873ade3b3437cc637fca23b22d\", \"content_browsertests\": \"8fee7b8903d260545d880be62553855579a3240f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_9b7d5777aa750248ebad695eb4fcd35fb9fc6d25bb624af41f2fa2acf3d9e522_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-216-of7x\"], \"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\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614646093812203",
"status": "COMPLETED",
"status_changed_ts": "1614652499419876",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/26606",
"builder:Linux Viz",
"buildset:commit/git/3464378ad220b9508251df7f3345b57f0ccbefe8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3464378ad220b9508251df7f3345b57f0ccbefe8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019344638549840512",
"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/8853914576308023504/+/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:520939acf2ddae10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614652499445103",
"url": "https://ci.chromium.org/b/8853914576308023504",
"utcnow_ts": "1614652499889380"
}
}
[02:34:59.897] Invocation finished in 1h46m50.291229092s with status SUCCEEDED