[14:15:46.008] New invocation is queued and will start shortly
[14:15:48.596] Starting the invocation (attempt 1)
[14:15:48.625] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:15:48.625] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:15:48.625] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023280094869627952",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e4cd32f3a51febd411b4deab4e862844062db823\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9023280094869627952",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/e4cd32f3a51febd411b4deab4e862844062db823",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e4cd32f3a51febd411b4deab4e862844062db823",
"gitiles_ref:refs/heads/master"
]
}
[14:15:49.473] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610892948776030",
"id": "8857850031302259968",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e4cd32f3a51febd411b4deab4e862844062db823\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610892949180730",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25908",
"builder:Linux Viz",
"buildset:commit/git/e4cd32f3a51febd411b4deab4e862844062db823",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e4cd32f3a51febd411b4deab4e862844062db823",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023280094869627952",
"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/8857850031302259968/+/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": "1610892949180831",
"url": "https://ci.chromium.org/b/8857850031302259968",
"utcnow_ts": "1610892949466542"
}
}
[14:15:49.473] Task URL: https://ci.chromium.org/b/8857850031302259968
[14:15:49.473] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:2:0) after 1m0s
[14:16:52.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:2:0)
[14:16:52.658] Timer tick, asking Buildbucket for the build status
[14:16:52.881] Build 8857850031302259968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:52.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:3:0) after 1m0s
[14:17:52.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:3:0)
[14:17:52.941] Timer tick, asking Buildbucket for the build status
[14:17:53.444] Build 8857850031302259968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:53.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:4:0) after 1m0s
[14:18:53.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:4:0)
[14:18:53.573] Timer tick, asking Buildbucket for the build status
[14:18:53.651] Build 8857850031302259968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:53.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:5:0) after 1m0s
[14:19:53.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:5:0)
[14:19:53.728] Timer tick, asking Buildbucket for the build status
[14:19:53.828] Build 8857850031302259968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:53.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:6:0) after 1m0s
[14:20:53.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:6:0)
[14:20:53.874] Timer tick, asking Buildbucket for the build status
[14:20:54.068] Build 8857850031302259968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:20:54.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:7:0) after 1m0s
[14:21:10.576] Received PubSub notification, asking Buildbucket for the build status
[14:21:10.672] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:54.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:7:0)
[14:21:54.104] Timer tick, asking Buildbucket for the build status
[14:21:54.408] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:54.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:9:0) after 1m0s
[14:22:54.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:9:0)
[14:22:54.458] Timer tick, asking Buildbucket for the build status
[14:22:54.534] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:54.534] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:10:0) after 1m0s
[14:23:54.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:10:0)
[14:23:54.629] Timer tick, asking Buildbucket for the build status
[14:23:54.723] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:54.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:11:0) after 1m0s
[14:24:54.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:11:0)
[14:24:54.769] Timer tick, asking Buildbucket for the build status
[14:24:55.001] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:55.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:12:0) after 1m0s
[14:25:55.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:12:0)
[14:25:55.037] Timer tick, asking Buildbucket for the build status
[14:25:55.111] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:55.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:13:0) after 1m0s
[14:26:55.207] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:13:0)
[14:26:55.207] Timer tick, asking Buildbucket for the build status
[14:26:55.412] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:55.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:14:0) after 1m0s
[14:27:55.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:14:0)
[14:27:55.481] Timer tick, asking Buildbucket for the build status
[14:27:55.577] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:55.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:15:0) after 1m0s
[14:28:55.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:15:0)
[14:28:55.607] Timer tick, asking Buildbucket for the build status
[14:28:55.710] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:55.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:16:0) after 1m0s
[14:29:55.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:16:0)
[14:29:55.762] Timer tick, asking Buildbucket for the build status
[14:29:56.062] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:56.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:17:0) after 1m0s
[14:30:56.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:17:0)
[14:30:56.103] Timer tick, asking Buildbucket for the build status
[14:30:56.169] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:56.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:18:0) after 1m0s
[14:31:56.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:18:0)
[14:31:56.187] Timer tick, asking Buildbucket for the build status
[14:31:56.684] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:56.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:19:0) after 1m0s
[14:32:56.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:19:0)
[14:32:56.736] Timer tick, asking Buildbucket for the build status
[14:32:56.868] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:56.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:20:0) after 1m0s
[14:33:56.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:20:0)
[14:33:56.896] Timer tick, asking Buildbucket for the build status
[14:33:57.112] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:57.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:21:0) after 1m0s
[14:34:57.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:21:0)
[14:34:57.132] Timer tick, asking Buildbucket for the build status
[14:34:57.343] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:57.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:22:0) after 1m0s
[14:35:57.368] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:22:0)
[14:35:57.368] Timer tick, asking Buildbucket for the build status
[14:35:57.630] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:57.630] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:23:0) after 1m0s
[14:36:57.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:23:0)
[14:36:57.685] Timer tick, asking Buildbucket for the build status
[14:36:57.800] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:57.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:24:0) after 1m0s
[14:37:57.824] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:24:0)
[14:37:57.824] Timer tick, asking Buildbucket for the build status
[14:37:57.898] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:57.898] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:25:0) after 1m0s
[14:38:57.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:25:0)
[14:38:57.972] Timer tick, asking Buildbucket for the build status
[14:38:58.033] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:58.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:26:0) after 1m0s
[14:39:58.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:26:0)
[14:39:58.111] Timer tick, asking Buildbucket for the build status
[14:39:58.214] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:58.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:27:0) after 1m0s
[14:40:58.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:27:0)
[14:40:58.247] Timer tick, asking Buildbucket for the build status
[14:40:58.492] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:58.492] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:28:0) after 1m0s
[14:41:58.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:28:0)
[14:41:58.770] Timer tick, asking Buildbucket for the build status
[14:41:59.125] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:59.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:29:0) after 1m0s
[14:42:59.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:29:0)
[14:42:59.286] Timer tick, asking Buildbucket for the build status
[14:42:59.423] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:59.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:30:0) after 1m0s
[14:43:59.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:30:0)
[14:43:59.491] Timer tick, asking Buildbucket for the build status
[14:43:59.567] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:59.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:31:0) after 1m0s
[14:44:59.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:31:0)
[14:44:59.588] Timer tick, asking Buildbucket for the build status
[14:44:59.670] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:59.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:32:0) after 1m0s
[14:45:59.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:32:0)
[14:45:59.705] Timer tick, asking Buildbucket for the build status
[14:45:59.765] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:59.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:33:0) after 1m0s
[14:46:59.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:33:0)
[14:46:59.754] Timer tick, asking Buildbucket for the build status
[14:47:00.456] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:00.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:34:0) after 1m0s
[14:48:00.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:34:0)
[14:48:00.745] Timer tick, asking Buildbucket for the build status
[14:48:00.820] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:00.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:35:0) after 1m0s
[14:49:00.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:35:0)
[14:49:00.933] Timer tick, asking Buildbucket for the build status
[14:49:01.127] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:01.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:36:0) after 1m0s
[14:50:01.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:36:0)
[14:50:01.302] Timer tick, asking Buildbucket for the build status
[14:50:01.662] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:01.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:37:0) after 1m0s
[14:51:01.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:37:0)
[14:51:01.759] Timer tick, asking Buildbucket for the build status
[14:51:01.818] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:01.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:38:0) after 1m0s
[14:52:01.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:38:0)
[14:52:01.915] Timer tick, asking Buildbucket for the build status
[14:52:02.024] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:02.024] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:39:0) after 1m0s
[14:53:02.097] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:39:0)
[14:53:02.097] Timer tick, asking Buildbucket for the build status
[14:53:02.163] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:02.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:40:0) after 1m0s
[14:54:02.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:40:0)
[14:54:02.211] Timer tick, asking Buildbucket for the build status
[14:54:02.547] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:02.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:41:0) after 1m0s
[14:55:02.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:41:0)
[14:55:02.613] Timer tick, asking Buildbucket for the build status
[14:55:02.666] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:02.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:42:0) after 1m0s
[14:56:02.832] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:42:0)
[14:56:02.832] Timer tick, asking Buildbucket for the build status
[14:56:02.937] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:02.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:43:0) after 1m0s
[14:57:03.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:43:0)
[14:57:03.121] Timer tick, asking Buildbucket for the build status
[14:57:03.506] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:03.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:44:0) after 1m0s
[14:58:03.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:44:0)
[14:58:03.482] Timer tick, asking Buildbucket for the build status
[14:58:03.551] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:03.551] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:45:0) after 1m0s
[14:59:03.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:45:0)
[14:59:03.635] Timer tick, asking Buildbucket for the build status
[14:59:03.870] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:03.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:46:0) after 1m0s
[15:00:04.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:46:0)
[15:00:04.019] Timer tick, asking Buildbucket for the build status
[15:00:04.110] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:04.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:47:0) after 1m0s
[15:01:04.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:47:0)
[15:01:04.167] Timer tick, asking Buildbucket for the build status
[15:01:04.722] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:04.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:48:0) after 1m0s
[15:02:04.907] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:48:0)
[15:02:04.907] Timer tick, asking Buildbucket for the build status
[15:02:05.024] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:05.024] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:49:0) after 1m0s
[15:03:05.163] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:49:0)
[15:03:05.163] Timer tick, asking Buildbucket for the build status
[15:03:05.232] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:05.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:50:0) after 1m0s
[15:04:05.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:50:0)
[15:04:05.294] Timer tick, asking Buildbucket for the build status
[15:04:05.367] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:05.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:51:0) after 1m0s
[15:05:05.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:51:0)
[15:05:05.435] Timer tick, asking Buildbucket for the build status
[15:05:05.824] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:05.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:52:0) after 1m0s
[15:06:05.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:52:0)
[15:06:05.921] Timer tick, asking Buildbucket for the build status
[15:06:05.988] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:05.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:53:0) after 1m0s
[15:07:06.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:53:0)
[15:07:06.008] Timer tick, asking Buildbucket for the build status
[15:07:06.287] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:06.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:54:0) after 1m0s
[15:08:06.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:54:0)
[15:08:06.324] Timer tick, asking Buildbucket for the build status
[15:08:06.396] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:06.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:55:0) after 1m0s
[15:09:06.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:55:0)
[15:09:06.488] Timer tick, asking Buildbucket for the build status
[15:09:06.570] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:06.570] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:56:0) after 1m0s
[15:10:06.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:56:0)
[15:10:06.623] Timer tick, asking Buildbucket for the build status
[15:10:06.692] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:06.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:57:0) after 1m0s
[15:11:06.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:57:0)
[15:11:06.741] Timer tick, asking Buildbucket for the build status
[15:11:06.921] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:06.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:58:0) after 1m0s
[15:12:06.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:58:0)
[15:12:06.943] Timer tick, asking Buildbucket for the build status
[15:12:07.074] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:07.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:59:0) after 1m0s
[15:13:07.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:59:0)
[15:13:07.071] Timer tick, asking Buildbucket for the build status
[15:13:07.372] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:07.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:60:0) after 1m0s
[15:14:07.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:60:0)
[15:14:07.397] Timer tick, asking Buildbucket for the build status
[15:14:07.480] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:07.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:61:0) after 1m0s
[15:15:07.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:61:0)
[15:15:07.554] Timer tick, asking Buildbucket for the build status
[15:15:07.647] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:07.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:62:0) after 1m0s
[15:16:07.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:62:0)
[15:16:07.720] Timer tick, asking Buildbucket for the build status
[15:16:07.791] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:07.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:63:0) after 1m0s
[15:17:07.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:63:0)
[15:17:07.896] Timer tick, asking Buildbucket for the build status
[15:17:07.996] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:07.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:64:0) after 1m0s
[15:18:08.163] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:64:0)
[15:18:08.164] Timer tick, asking Buildbucket for the build status
[15:18:08.407] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:08.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:65:0) after 1m0s
[15:19:08.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:65:0)
[15:19:08.479] Timer tick, asking Buildbucket for the build status
[15:19:08.627] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:08.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:66:0) after 1m0s
[15:20:08.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:66:0)
[15:20:08.808] Timer tick, asking Buildbucket for the build status
[15:20:08.882] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:08.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:67:0) after 1m0s
[15:21:08.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:67:0)
[15:21:08.912] Timer tick, asking Buildbucket for the build status
[15:21:08.975] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:08.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:68:0) after 1m0s
[15:22:09.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:68:0)
[15:22:09.005] Timer tick, asking Buildbucket for the build status
[15:22:09.158] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:09.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:69:0) after 1m0s
[15:23:09.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:69:0)
[15:23:09.227] Timer tick, asking Buildbucket for the build status
[15:23:09.306] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:09.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:70:0) after 1m0s
[15:24:09.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:70:0)
[15:24:09.356] Timer tick, asking Buildbucket for the build status
[15:24:09.464] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:09.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:71:0) after 1m0s
[15:25:09.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:71:0)
[15:25:09.578] Timer tick, asking Buildbucket for the build status
[15:25:09.653] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:09.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:72:0) after 1m0s
[15:26:09.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:72:0)
[15:26:09.762] Timer tick, asking Buildbucket for the build status
[15:26:09.834] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:09.834] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:73:0) after 1m0s
[15:27:09.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:73:0)
[15:27:09.859] Timer tick, asking Buildbucket for the build status
[15:27:09.979] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:09.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:74:0) after 1m0s
[15:28:10.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:74:0)
[15:28:10.096] Timer tick, asking Buildbucket for the build status
[15:28:10.456] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:10.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:75:0) after 1m0s
[15:29:10.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:75:0)
[15:29:10.553] Timer tick, asking Buildbucket for the build status
[15:29:10.617] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:10.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:76:0) after 1m0s
[15:30:10.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:76:0)
[15:30:10.686] Timer tick, asking Buildbucket for the build status
[15:30:10.779] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:10.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:77:0) after 1m0s
[15:31:10.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:77:0)
[15:31:10.802] Timer tick, asking Buildbucket for the build status
[15:31:11.072] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:11.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:78:0) after 1m0s
[15:32:11.097] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:78:0)
[15:32:11.097] Timer tick, asking Buildbucket for the build status
[15:32:11.256] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:11.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:79:0) after 1m0s
[15:33:11.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:79:0)
[15:33:11.282] Timer tick, asking Buildbucket for the build status
[15:33:11.376] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:11.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:80:0) after 1m0s
[15:34:11.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:80:0)
[15:34:11.401] Timer tick, asking Buildbucket for the build status
[15:34:11.472] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:11.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:81:0) after 1m0s
[15:35:11.657] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:81:0)
[15:35:11.658] Timer tick, asking Buildbucket for the build status
[15:35:13.014] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:13.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:82:0) after 1m0s
[15:36:13.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:82:0)
[15:36:13.035] Timer tick, asking Buildbucket for the build status
[15:36:13.102] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:13.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:83:0) after 1m0s
[15:37:13.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:83:0)
[15:37:13.183] Timer tick, asking Buildbucket for the build status
[15:37:13.283] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:13.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:84:0) after 1m0s
[15:38:13.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:84:0)
[15:38:13.309] Timer tick, asking Buildbucket for the build status
[15:38:13.419] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:13.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:85:0) after 1m0s
[15:39:13.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:85:0)
[15:39:13.454] Timer tick, asking Buildbucket for the build status
[15:39:13.704] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:13.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:86:0) after 1m0s
[15:40:13.739] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:86:0)
[15:40:13.739] Timer tick, asking Buildbucket for the build status
[15:40:14.072] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:14.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:87:0) after 1m0s
[15:41:14.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:87:0)
[15:41:14.137] Timer tick, asking Buildbucket for the build status
[15:41:14.525] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:14.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:88:0) after 1m0s
[15:42:14.544] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:88:0)
[15:42:14.544] Timer tick, asking Buildbucket for the build status
[15:42:14.670] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:14.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:89:0) after 1m0s
[15:43:14.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:89:0)
[15:43:14.697] Timer tick, asking Buildbucket for the build status
[15:43:14.811] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:14.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:90:0) after 1m0s
[15:44:14.839] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:90:0)
[15:44:14.839] Timer tick, asking Buildbucket for the build status
[15:44:14.902] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:14.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:91:0) after 1m0s
[15:45:14.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:91:0)
[15:45:14.959] Timer tick, asking Buildbucket for the build status
[15:45:15.315] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:15.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:92:0) after 1m0s
[15:46:15.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:92:0)
[15:46:15.346] Timer tick, asking Buildbucket for the build status
[15:46:15.411] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:15.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:93:0) after 1m0s
[15:47:15.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:93:0)
[15:47:15.434] Timer tick, asking Buildbucket for the build status
[15:47:15.529] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:15.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:94:0) after 1m0s
[15:48:15.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:94:0)
[15:48:15.552] Timer tick, asking Buildbucket for the build status
[15:48:15.732] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:15.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:95:0) after 1m0s
[15:49:15.756] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:95:0)
[15:49:15.756] Timer tick, asking Buildbucket for the build status
[15:49:15.979] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:15.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:96:0) after 1m0s
[15:50:16.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:96:0)
[15:50:16.125] Timer tick, asking Buildbucket for the build status
[15:50:16.215] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:16.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:97:0) after 1m0s
[15:51:16.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:97:0)
[15:51:16.247] Timer tick, asking Buildbucket for the build status
[15:51:16.778] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:16.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:98:0) after 1m0s
[15:52:16.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:98:0)
[15:52:16.812] Timer tick, asking Buildbucket for the build status
[15:52:17.279] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:17.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:99:0) after 1m0s
[15:53:17.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:99:0)
[15:53:17.417] Timer tick, asking Buildbucket for the build status
[15:53:17.642] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:17.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:100:0) after 1m0s
[15:54:17.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:100:0)
[15:54:17.742] Timer tick, asking Buildbucket for the build status
[15:54:17.837] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:17.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:101:0) after 1m0s
[15:55:17.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:101:0)
[15:55:17.899] Timer tick, asking Buildbucket for the build status
[15:55:18.103] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:18.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:102:0) after 1m0s
[15:56:18.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:102:0)
[15:56:18.141] Timer tick, asking Buildbucket for the build status
[15:56:18.600] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:18.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:103:0) after 1m0s
[15:57:18.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:103:0)
[15:57:18.623] Timer tick, asking Buildbucket for the build status
[15:57:18.815] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:18.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:104:0) after 1m0s
[15:58:18.835] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:104:0)
[15:58:18.835] Timer tick, asking Buildbucket for the build status
[15:58:19.537] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:19.537] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:105:0) after 1m0s
[15:59:19.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:105:0)
[15:59:19.577] Timer tick, asking Buildbucket for the build status
[15:59:19.877] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:19.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:106:0) after 1m0s
[16:00:19.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:106:0)
[16:00:19.937] Timer tick, asking Buildbucket for the build status
[16:00:20.736] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:20.736] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:107:0) after 1m0s
[16:01:20.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:107:0)
[16:01:20.858] Timer tick, asking Buildbucket for the build status
[16:01:21.447] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:21.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:108:0) after 1m0s
[16:02:21.494] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:108:0)
[16:02:21.494] Timer tick, asking Buildbucket for the build status
[16:02:21.605] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:21.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:109:0) after 1m0s
[16:03:21.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:109:0)
[16:03:21.620] Timer tick, asking Buildbucket for the build status
[16:03:21.701] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:21.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:110:0) after 1m0s
[16:04:21.806] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:110:0)
[16:04:21.806] Timer tick, asking Buildbucket for the build status
[16:04:21.893] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:21.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:111:0) after 1m0s
[16:05:21.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:111:0)
[16:05:21.925] Timer tick, asking Buildbucket for the build status
[16:05:21.984] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:21.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:112:0) after 1m0s
[16:06:22.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:112:0)
[16:06:22.051] Timer tick, asking Buildbucket for the build status
[16:06:22.308] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:22.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:113:0) after 1m0s
[16:07:22.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:113:0)
[16:07:22.410] Timer tick, asking Buildbucket for the build status
[16:07:22.617] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:22.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:114:0) after 1m0s
[16:08:22.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:114:0)
[16:08:22.663] Timer tick, asking Buildbucket for the build status
[16:08:22.748] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:22.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:115:0) after 1m0s
[16:09:22.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:115:0)
[16:09:22.745] Timer tick, asking Buildbucket for the build status
[16:09:22.866] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:22.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:116:0) after 1m0s
[16:10:22.897] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:116:0)
[16:10:22.897] Timer tick, asking Buildbucket for the build status
[16:10:23.449] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:23.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:117:0) after 1m0s
[16:11:23.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:117:0)
[16:11:23.533] Timer tick, asking Buildbucket for the build status
[16:11:23.703] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:23.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:118:0) after 1m0s
[16:12:23.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:118:0)
[16:12:23.745] Timer tick, asking Buildbucket for the build status
[16:12:23.819] Build 8857850031302259968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:23.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023280094869627952:119:0) after 1m0s
[16:12:54.753] Received PubSub notification, asking Buildbucket for the build status
[16:12:54.888] Build 8857850031302259968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:12:54.888] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610899974041018",
"created_by": "project:chromium",
"created_ts": "1610892948776030",
"id": "8857850031302259968",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e4cd32f3a51febd411b4deab4e862844062db823\"}}",
"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\": \"e4cd32f3a51febd411b4deab4e862844062db823\", \"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-20-ma3c\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 25908, \"got_angle_revision\": \"ff170c6f508d45a48e573dcbf2a3001200dde61b\", \"got_dawn_revision\": \"04772515a8b613150b28bbed19b4c261e0b50e96\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"e4cd32f3a51febd411b4deab4e862844062db823\", \"got_revision_cp\": \"refs/heads/master@{#844359}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-viz-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e4cd32f3a51febd411b4deab4e862844062db823\", \"swarm_hashes_refs/heads/master(at){#844359}_without_patch\": {\"blink_web_tests\": \"e5400963ccb71c9c642bdab2a3cbee19437a3c16\", \"browser_tests\": \"401973fed0ebc22f5d0f8f60e86c33ab4bce7a2a\", \"content_browsertests\": \"d5c9a2469c0b0aaae819dc9e54f031cc9bdc15ca\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_70f3607e3f7d84b5c3698c0eed74813ee5c700477d5aec13835ae2ecc5ee2d5b_v2\", \"builder_80caf7430a3f42346120b86a5d4a8c20619a8e115748218c5e2ff55c5518ef8d_v2\", \"builder_9f02bd2de6eac19c457931accbc0b1e66d92a355209524fd30dfc8de3911a1f0_v2\", \"builder_be71ec2e45ff41f29ecdc3611cfb7c276cc0ff3138424d653aa2b7cfb51218d3_v2\", \"builder_fe96da9c6a19fa2fc3ad0c2b7020b7cb68e4a92161f4cd6cb7dc1d1e2d5af5dc_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-20-ma3c\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610893269687776",
"status": "COMPLETED",
"status_changed_ts": "1610899974435127",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25908",
"builder:Linux Viz",
"buildset:commit/git/e4cd32f3a51febd411b4deab4e862844062db823",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e4cd32f3a51febd411b4deab4e862844062db823",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023280094869627952",
"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/8857850031302259968/+/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:512985ab2e691210",
"user_agent:luci-scheduler"
],
"updated_ts": "1610899974440599",
"url": "https://ci.chromium.org/b/8857850031302259968",
"utcnow_ts": "1610899974875840"
}
}
[16:12:54.888] Invocation finished in 1h57m8.896565151s with status SUCCEEDED