[01:09:55.461] New invocation is queued and will start shortly
[01:09:56.769] Starting the invocation (attempt 1)
[01:09:56.808] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:09:56.808] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:09:56.808] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034925947504390816",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ed1afefe7f7669adbb6fc29b0ebae6b4f0940052\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9034925947504390816",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/ed1afefe7f7669adbb6fc29b0ebae6b4f0940052",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ed1afefe7f7669adbb6fc29b0ebae6b4f0940052",
"gitiles_ref:refs/heads/master"
]
}
[01:09:59.679] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599786596947655",
"id": "8869495885278177072",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ed1afefe7f7669adbb6fc29b0ebae6b4f0940052\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599786597864521",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/23845",
"builder:Linux Viz",
"buildset:commit/git/ed1afefe7f7669adbb6fc29b0ebae6b4f0940052",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ed1afefe7f7669adbb6fc29b0ebae6b4f0940052",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034925947504390816",
"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/8869495885278177072/+/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": "1599786597867538",
"url": "https://ci.chromium.org/b/8869495885278177072",
"utcnow_ts": "1599786599666076"
}
}
[01:09:59.679] Task URL: https://ci.chromium.org/b/8869495885278177072
[01:09:59.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:2:0) after 1m0s
[01:10:59.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:2:0)
[01:10:59.771] Timer tick, asking Buildbucket for the build status
[01:11:00.350] Build 8869495885278177072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:00.350] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:3:0) after 1m0s
[01:12:01.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:3:0)
[01:12:01.226] Timer tick, asking Buildbucket for the build status
[01:12:01.413] Build 8869495885278177072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:01.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:4:0) after 1m0s
[01:13:01.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:4:0)
[01:13:01.668] Timer tick, asking Buildbucket for the build status
[01:13:01.754] Build 8869495885278177072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:01.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:5:0) after 1m0s
[01:14:03.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:5:0)
[01:14:03.058] Timer tick, asking Buildbucket for the build status
[01:14:03.190] Build 8869495885278177072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:03.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:6:0) after 1m0s
[01:15:03.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:6:0)
[01:15:03.248] Timer tick, asking Buildbucket for the build status
[01:15:03.539] Build 8869495885278177072: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:03.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:7:0) after 1m0s
[01:15:25.458] Received PubSub notification, asking Buildbucket for the build status
[01:15:25.590] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:03.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:7:0)
[01:16:03.604] Timer tick, asking Buildbucket for the build status
[01:16:03.708] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:03.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:9:0) after 1m0s
[01:17:03.724] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:9:0)
[01:17:03.724] Timer tick, asking Buildbucket for the build status
[01:17:03.843] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:03.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:10:0) after 1m0s
[01:18:03.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:10:0)
[01:18:03.946] Timer tick, asking Buildbucket for the build status
[01:18:04.218] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:04.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:11:0) after 1m0s
[01:19:04.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:11:0)
[01:19:04.381] Timer tick, asking Buildbucket for the build status
[01:19:04.528] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:04.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:12:0) after 1m0s
[01:20:04.590] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:12:0)
[01:20:04.590] Timer tick, asking Buildbucket for the build status
[01:20:04.824] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:04.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:13:0) after 1m0s
[01:21:05.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:13:0)
[01:21:05.158] Timer tick, asking Buildbucket for the build status
[01:21:05.442] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:05.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:14:0) after 1m0s
[01:22:05.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:14:0)
[01:22:05.493] Timer tick, asking Buildbucket for the build status
[01:22:05.839] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:05.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:15:0) after 1m0s
[01:23:06.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:15:0)
[01:23:06.079] Timer tick, asking Buildbucket for the build status
[01:23:06.695] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:06.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:16:0) after 1m0s
[01:24:06.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:16:0)
[01:24:06.800] Timer tick, asking Buildbucket for the build status
[01:24:06.907] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:06.907] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:17:0) after 1m0s
[01:25:06.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:17:0)
[01:25:06.929] Timer tick, asking Buildbucket for the build status
[01:25:07.078] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:07.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:18:0) after 1m0s
[01:26:07.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:18:0)
[01:26:07.117] Timer tick, asking Buildbucket for the build status
[01:26:07.244] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:07.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:19:0) after 1m0s
[01:27:07.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:19:0)
[01:27:07.274] Timer tick, asking Buildbucket for the build status
[01:27:07.429] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:07.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:20:0) after 1m0s
[01:28:07.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:20:0)
[01:28:07.426] Timer tick, asking Buildbucket for the build status
[01:28:07.563] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:07.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:21:0) after 1m0s
[01:29:07.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:21:0)
[01:29:07.611] Timer tick, asking Buildbucket for the build status
[01:29:07.673] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:07.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:22:0) after 1m0s
[01:30:07.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:22:0)
[01:30:07.717] Timer tick, asking Buildbucket for the build status
[01:30:08.260] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:08.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:23:0) after 1m0s
[01:31:08.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:23:0)
[01:31:08.395] Timer tick, asking Buildbucket for the build status
[01:31:09.915] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:09.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:24:0) after 1m0s
[01:32:09.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:24:0)
[01:32:09.960] Timer tick, asking Buildbucket for the build status
[01:32:10.042] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:10.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:25:0) after 1m0s
[01:33:10.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:25:0)
[01:33:10.066] Timer tick, asking Buildbucket for the build status
[01:33:10.289] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:10.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:26:0) after 1m0s
[01:34:10.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:26:0)
[01:34:10.301] Timer tick, asking Buildbucket for the build status
[01:34:12.202] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:12.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:27:0) after 1m0s
[01:35:12.234] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:27:0)
[01:35:12.234] Timer tick, asking Buildbucket for the build status
[01:35:12.339] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:12.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:28:0) after 1m0s
[01:36:12.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:28:0)
[01:36:12.356] Timer tick, asking Buildbucket for the build status
[01:36:12.424] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:12.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:29:0) after 1m0s
[01:37:12.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:29:0)
[01:37:12.472] Timer tick, asking Buildbucket for the build status
[01:37:12.602] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:12.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:30:0) after 1m0s
[01:38:12.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:30:0)
[01:38:12.632] Timer tick, asking Buildbucket for the build status
[01:38:12.732] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:12.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:31:0) after 1m0s
[01:39:12.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:31:0)
[01:39:12.720] Timer tick, asking Buildbucket for the build status
[01:39:12.805] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:12.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:32:0) after 1m0s
[01:40:12.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:32:0)
[01:40:12.813] Timer tick, asking Buildbucket for the build status
[01:40:13.156] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:13.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:33:0) after 1m0s
[01:41:13.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:33:0)
[01:41:13.238] Timer tick, asking Buildbucket for the build status
[01:41:13.385] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:13.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:34:0) after 1m0s
[01:42:13.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:34:0)
[01:42:13.489] Timer tick, asking Buildbucket for the build status
[01:42:13.551] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:13.551] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:35:0) after 1m0s
[01:43:13.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:35:0)
[01:43:13.729] Timer tick, asking Buildbucket for the build status
[01:43:13.893] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:36:0) after 1m0s
[01:44:13.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:36:0)
[01:44:13.951] Timer tick, asking Buildbucket for the build status
[01:44:14.023] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:14.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:37:0) after 1m0s
[01:45:14.095] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:37:0)
[01:45:14.095] Timer tick, asking Buildbucket for the build status
[01:45:14.216] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:14.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:38:0) after 1m0s
[01:46:14.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:38:0)
[01:46:14.390] Timer tick, asking Buildbucket for the build status
[01:46:14.578] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:14.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:39:0) after 1m0s
[01:47:15.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:39:0)
[01:47:15.124] Timer tick, asking Buildbucket for the build status
[01:47:15.236] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:15.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:40:0) after 1m0s
[01:48:15.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:40:0)
[01:48:15.429] Timer tick, asking Buildbucket for the build status
[01:48:15.506] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:15.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:41:0) after 1m0s
[01:49:15.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:41:0)
[01:49:15.802] Timer tick, asking Buildbucket for the build status
[01:49:15.950] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:15.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:42:0) after 1m0s
[01:50:16.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:42:0)
[01:50:16.120] Timer tick, asking Buildbucket for the build status
[01:50:17.524] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:17.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:43:0) after 1m0s
[01:51:17.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:43:0)
[01:51:17.604] Timer tick, asking Buildbucket for the build status
[01:51:18.174] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:18.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:44:0) after 1m0s
[01:52:18.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:44:0)
[01:52:18.174] Timer tick, asking Buildbucket for the build status
[01:52:18.434] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:18.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:45:0) after 1m0s
[01:53:18.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:45:0)
[01:53:18.804] Timer tick, asking Buildbucket for the build status
[01:53:19.133] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:19.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:46:0) after 1m0s
[01:54:19.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:46:0)
[01:54:19.180] Timer tick, asking Buildbucket for the build status
[01:54:19.309] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:19.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:47:0) after 1m0s
[01:55:19.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:47:0)
[01:55:19.372] Timer tick, asking Buildbucket for the build status
[01:55:19.467] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:19.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:48:0) after 1m0s
[01:56:19.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:48:0)
[01:56:19.526] Timer tick, asking Buildbucket for the build status
[01:56:19.605] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:19.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:49:0) after 1m0s
[01:57:19.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:49:0)
[01:57:19.642] Timer tick, asking Buildbucket for the build status
[01:57:19.721] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:19.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:50:0) after 1m0s
[01:58:19.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:50:0)
[01:58:19.814] Timer tick, asking Buildbucket for the build status
[01:58:20.013] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:20.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:51:0) after 1m0s
[01:59:20.108] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:51:0)
[01:59:20.108] Timer tick, asking Buildbucket for the build status
[01:59:20.198] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:20.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:52:0) after 1m0s
[02:00:20.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:52:0)
[02:00:20.289] Timer tick, asking Buildbucket for the build status
[02:00:20.451] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:20.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:53:0) after 1m0s
[02:01:21.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:53:0)
[02:01:21.327] Timer tick, asking Buildbucket for the build status
[02:01:21.441] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:21.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:54:0) after 1m0s
[02:02:21.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:54:0)
[02:02:21.478] Timer tick, asking Buildbucket for the build status
[02:02:21.548] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:21.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:55:0) after 1m0s
[02:03:21.591] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:55:0)
[02:03:21.591] Timer tick, asking Buildbucket for the build status
[02:03:21.696] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:21.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:56:0) after 1m0s
[02:04:21.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:56:0)
[02:04:21.696] Timer tick, asking Buildbucket for the build status
[02:04:22.096] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:22.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:57:0) after 1m0s
[02:05:22.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:57:0)
[02:05:22.144] Timer tick, asking Buildbucket for the build status
[02:05:22.302] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:22.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:58:0) after 1m0s
[02:06:22.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:58:0)
[02:06:22.372] Timer tick, asking Buildbucket for the build status
[02:06:22.497] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:22.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:59:0) after 1m0s
[02:07:23.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:59:0)
[02:07:23.079] Timer tick, asking Buildbucket for the build status
[02:07:23.178] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:23.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:60:0) after 1m0s
[02:08:23.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:60:0)
[02:08:23.451] Timer tick, asking Buildbucket for the build status
[02:08:23.549] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:23.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:61:0) after 1m0s
[02:09:23.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:61:0)
[02:09:23.606] Timer tick, asking Buildbucket for the build status
[02:09:23.782] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:23.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:62:0) after 1m0s
[02:10:23.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:62:0)
[02:10:23.903] Timer tick, asking Buildbucket for the build status
[02:10:24.037] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:24.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:63:0) after 1m0s
[02:11:24.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:63:0)
[02:11:24.168] Timer tick, asking Buildbucket for the build status
[02:11:24.233] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:24.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:64:0) after 1m0s
[02:12:24.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:64:0)
[02:12:24.454] Timer tick, asking Buildbucket for the build status
[02:12:24.714] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:24.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:65:0) after 1m0s
[02:13:24.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:65:0)
[02:13:24.922] Timer tick, asking Buildbucket for the build status
[02:13:25.038] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:25.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:66:0) after 1m0s
[02:14:25.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:66:0)
[02:14:25.134] Timer tick, asking Buildbucket for the build status
[02:14:25.313] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:25.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:67:0) after 1m0s
[02:15:25.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:67:0)
[02:15:25.445] Timer tick, asking Buildbucket for the build status
[02:15:25.568] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:25.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:68:0) after 1m0s
[02:16:25.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:68:0)
[02:16:25.671] Timer tick, asking Buildbucket for the build status
[02:16:25.752] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:25.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:69:0) after 1m0s
[02:17:25.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:69:0)
[02:17:25.874] Timer tick, asking Buildbucket for the build status
[02:17:25.932] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:25.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:70:0) after 1m0s
[02:18:26.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:70:0)
[02:18:26.202] Timer tick, asking Buildbucket for the build status
[02:18:26.283] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:26.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:71:0) after 1m0s
[02:19:26.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:71:0)
[02:19:26.311] Timer tick, asking Buildbucket for the build status
[02:19:26.393] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:26.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:72:0) after 1m0s
[02:20:26.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:72:0)
[02:20:26.476] Timer tick, asking Buildbucket for the build status
[02:20:26.616] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:26.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:73:0) after 1m0s
[02:21:26.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:73:0)
[02:21:26.681] Timer tick, asking Buildbucket for the build status
[02:21:26.771] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:26.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:74:0) after 1m0s
[02:22:26.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:74:0)
[02:22:26.886] Timer tick, asking Buildbucket for the build status
[02:22:26.989] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:26.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:75:0) after 1m0s
[02:23:27.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:75:0)
[02:23:27.210] Timer tick, asking Buildbucket for the build status
[02:23:27.296] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:27.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:76:0) after 1m0s
[02:24:27.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:76:0)
[02:24:27.324] Timer tick, asking Buildbucket for the build status
[02:24:27.610] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:27.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:77:0) after 1m0s
[02:25:27.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:77:0)
[02:25:27.726] Timer tick, asking Buildbucket for the build status
[02:25:27.970] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:27.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:78:0) after 1m0s
[02:26:28.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:78:0)
[02:26:28.084] Timer tick, asking Buildbucket for the build status
[02:26:28.156] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:28.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:79:0) after 1m0s
[02:27:28.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:79:0)
[02:27:28.186] Timer tick, asking Buildbucket for the build status
[02:27:28.307] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:28.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:80:0) after 1m0s
[02:28:28.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:80:0)
[02:28:28.508] Timer tick, asking Buildbucket for the build status
[02:28:28.576] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:28.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:81:0) after 1m0s
[02:29:28.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:81:0)
[02:29:28.709] Timer tick, asking Buildbucket for the build status
[02:29:28.978] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:28.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:82:0) after 1m0s
[02:30:29.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:82:0)
[02:30:29.082] Timer tick, asking Buildbucket for the build status
[02:30:29.211] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:29.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:83:0) after 1m0s
[02:31:29.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:83:0)
[02:31:29.224] Timer tick, asking Buildbucket for the build status
[02:31:29.409] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:29.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:84:0) after 1m0s
[02:32:29.460] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:84:0)
[02:32:29.460] Timer tick, asking Buildbucket for the build status
[02:32:29.525] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:29.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:85:0) after 1m0s
[02:33:29.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:85:0)
[02:33:29.551] Timer tick, asking Buildbucket for the build status
[02:33:29.726] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:29.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:86:0) after 1m0s
[02:34:29.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:86:0)
[02:34:29.925] Timer tick, asking Buildbucket for the build status
[02:34:30.020] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:30.020] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:87:0) after 1m0s
[02:35:30.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:87:0)
[02:35:30.048] Timer tick, asking Buildbucket for the build status
[02:35:30.148] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:30.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:88:0) after 1m0s
[02:36:30.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:88:0)
[02:36:30.276] Timer tick, asking Buildbucket for the build status
[02:36:30.404] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:30.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:89:0) after 1m0s
[02:37:30.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:89:0)
[02:37:30.410] Timer tick, asking Buildbucket for the build status
[02:37:30.792] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:30.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:90:0) after 1m0s
[02:38:30.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:90:0)
[02:38:30.817] Timer tick, asking Buildbucket for the build status
[02:38:30.964] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:30.964] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:91:0) after 1m0s
[02:39:31.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:91:0)
[02:39:31.106] Timer tick, asking Buildbucket for the build status
[02:39:31.284] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:31.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:92:0) after 1m0s
[02:40:31.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:92:0)
[02:40:31.316] Timer tick, asking Buildbucket for the build status
[02:40:31.413] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:31.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:93:0) after 1m0s
[02:41:31.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:93:0)
[02:41:31.406] Timer tick, asking Buildbucket for the build status
[02:41:31.764] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:31.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:94:0) after 1m0s
[02:42:31.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:94:0)
[02:42:31.830] Timer tick, asking Buildbucket for the build status
[02:42:31.996] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:31.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:95:0) after 1m0s
[02:43:32.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:95:0)
[02:43:32.082] Timer tick, asking Buildbucket for the build status
[02:43:32.143] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:32.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:96:0) after 1m0s
[02:44:32.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:96:0)
[02:44:32.380] Timer tick, asking Buildbucket for the build status
[02:44:34.427] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:34.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:97:0) after 1m0s
[02:45:34.453] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:97:0)
[02:45:34.453] Timer tick, asking Buildbucket for the build status
[02:45:34.635] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:34.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:98:0) after 1m0s
[02:46:34.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:98:0)
[02:46:34.691] Timer tick, asking Buildbucket for the build status
[02:46:34.757] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:34.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:99:0) after 1m0s
[02:47:34.803] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:99:0)
[02:47:34.803] Timer tick, asking Buildbucket for the build status
[02:47:34.915] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:34.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:100:0) after 1m0s
[02:48:35.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:100:0)
[02:48:35.007] Timer tick, asking Buildbucket for the build status
[02:48:35.081] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:35.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:101:0) after 1m0s
[02:49:35.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:101:0)
[02:49:35.136] Timer tick, asking Buildbucket for the build status
[02:49:35.229] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:35.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:102:0) after 1m0s
[02:50:35.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:102:0)
[02:50:35.254] Timer tick, asking Buildbucket for the build status
[02:50:35.716] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:35.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:103:0) after 1m0s
[02:51:35.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:103:0)
[02:51:35.828] Timer tick, asking Buildbucket for the build status
[02:51:36.098] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:36.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:104:0) after 1m0s
[02:52:36.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:104:0)
[02:52:36.137] Timer tick, asking Buildbucket for the build status
[02:52:36.316] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:36.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:105:0) after 1m0s
[02:53:36.330] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:105:0)
[02:53:36.330] Timer tick, asking Buildbucket for the build status
[02:53:36.980] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:36.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:106:0) after 1m0s
[02:54:37.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:106:0)
[02:54:37.171] Timer tick, asking Buildbucket for the build status
[02:54:37.248] Build 8869495885278177072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:37.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034925947504390816:107:0) after 1m0s
[02:54:51.706] Received PubSub notification, asking Buildbucket for the build status
[02:54:51.799] Build 8869495885278177072: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:54:51.799] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599792890938935",
"created_by": "project:chromium",
"created_ts": "1599786596947655",
"id": "8869495885278177072",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ed1afefe7f7669adbb6fc29b0ebae6b4f0940052\"}}",
"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\": \"ed1afefe7f7669adbb6fc29b0ebae6b4f0940052\", \"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-65-f0v2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 23845, \"got_angle_revision\": \"1bd71b48fb5cd5bf393f730d3f542e493a1e324f\", \"got_dawn_revision\": \"9427c97d9c2917d66e79e49ffaa718aac2e7d6ad\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"ed1afefe7f7669adbb6fc29b0ebae6b4f0940052\", \"got_revision_cp\": \"refs/heads/master@{#806001}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"41974f57973ebcdab4cfde6cc60354f6b79a5901\", \"got_v8_revision\": \"bb4817544d7ce327fc432a8229efa213952ca979\", \"got_v8_revision_cp\": \"refs/heads/8.7.76@{#1}\", \"got_webrtc_revision\": \"8b9424e9da8584d8099a25599ba5b04978ec02d1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32074}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ed1afefe7f7669adbb6fc29b0ebae6b4f0940052\", \"swarm_hashes\": {\"blink_web_tests\": \"05e86f7181e81f2d10a88dd401cab67e58cf021e\", \"browser_tests\": \"c2dbff03533868a0bc8f275eb4ba7208617e0a90\", \"content_browsertests\": \"6a2f63f74bb6a10e391d2580460eddc73ee2165e\"}, \"swarm_hashes_refs/heads/master(at){#806001}_without_patch\": {\"blink_web_tests\": \"05e86f7181e81f2d10a88dd401cab67e58cf021e\", \"browser_tests\": \"c2dbff03533868a0bc8f275eb4ba7208617e0a90\", \"content_browsertests\": \"6a2f63f74bb6a10e391d2580460eddc73ee2165e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_279625ee090c361ea8cf031374b7b020d1191c526e0ef1068ab634999c69ee70_v2\", \"builder_3b0e28eebaae607a111b3d14afaf4a38944aa5084a33cb6cff32b5c412399a3d_v2\", \"builder_430de131691c92065dcde51cfe816220b2f886d2d859c4fe33bcc0b34c123c61_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_6cd5ded23cefc640ae511b7a529237cb2561e39c1afb96e401c63016ddcf35a6_v2\", \"builder_afb3a13a9ac26806dacbdf892b44ad95d1cf9306e831af9093db192124098659_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-65-f0v2\"], \"image\": [\"chrome-xenial-20090600-f98a2a06cf8\"], \"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\": [\"5513-ee65082\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1599786924607984",
"status": "COMPLETED",
"status_changed_ts": "1599792891340570",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/23845",
"builder:Linux Viz",
"buildset:commit/git/ed1afefe7f7669adbb6fc29b0ebae6b4f0940052",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ed1afefe7f7669adbb6fc29b0ebae6b4f0940052",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034925947504390816",
"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/8869495885278177072/+/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:4e9387d14ef28d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599792891345657",
"url": "https://ci.chromium.org/b/8869495885278177072",
"utcnow_ts": "1599792891779547"
}
}
[02:54:51.800] Invocation finished in 1h44m56.360687467s with status SUCCEEDED