[16:30:54.195] New invocation is queued and will start shortly
[16:30:55.304] Starting the invocation (attempt 1)
[16:30:55.338] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:30:55.338] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:30:55.338] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9033327856090420544",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9033327856090420544",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4",
"gitiles_ref:refs/heads/master"
]
}
[16:30:58.105] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1601310655434400",
"id": "8867897794125992496",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601310657891272",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24077",
"builder:Linux Viz",
"buildset:commit/git/b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033327856090420544",
"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/8867897794125992496/+/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": "1601310657891791",
"url": "https://ci.chromium.org/b/8867897794125992496",
"utcnow_ts": "1601310658091151"
}
}
[16:30:58.105] Task URL: https://ci.chromium.org/b/8867897794125992496
[16:30:58.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:2:0) after 1m0s
[16:31:00.944] Received PubSub notification, asking Buildbucket for the build status
[16:31:01.084] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:58.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:2:0)
[16:31:58.120] Timer tick, asking Buildbucket for the build status
[16:31:58.326] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:58.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:4:0) after 1m0s
[16:32:58.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:4:0)
[16:32:58.416] Timer tick, asking Buildbucket for the build status
[16:32:58.592] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:58.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:5:0) after 1m0s
[16:33:58.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:5:0)
[16:33:58.667] Timer tick, asking Buildbucket for the build status
[16:33:59.626] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:59.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:6:0) after 1m0s
[16:35:00.032] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:6:0)
[16:35:00.032] Timer tick, asking Buildbucket for the build status
[16:35:00.377] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:00.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:7:0) after 1m0s
[16:36:00.451] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:7:0)
[16:36:00.451] Timer tick, asking Buildbucket for the build status
[16:36:01.046] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:01.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:8:0) after 1m0s
[16:37:02.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:8:0)
[16:37:02.188] Timer tick, asking Buildbucket for the build status
[16:37:02.317] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:02.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:9:0) after 1m0s
[16:38:02.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:9:0)
[16:38:02.663] Timer tick, asking Buildbucket for the build status
[16:38:02.764] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:02.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:10:0) after 1m0s
[16:39:02.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:10:0)
[16:39:02.898] Timer tick, asking Buildbucket for the build status
[16:39:03.164] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:03.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:11:0) after 1m0s
[16:40:03.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:11:0)
[16:40:03.273] Timer tick, asking Buildbucket for the build status
[16:40:03.378] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:03.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:12:0) after 1m0s
[16:41:03.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:12:0)
[16:41:03.411] Timer tick, asking Buildbucket for the build status
[16:41:03.675] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:03.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:13:0) after 1m0s
[16:42:03.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:13:0)
[16:42:03.749] Timer tick, asking Buildbucket for the build status
[16:42:03.889] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:03.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:14:0) after 1m0s
[16:43:03.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:14:0)
[16:43:03.922] Timer tick, asking Buildbucket for the build status
[16:43:04.111] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:04.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:15:0) after 1m0s
[16:44:04.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:15:0)
[16:44:04.205] Timer tick, asking Buildbucket for the build status
[16:44:04.301] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:04.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:16:0) after 1m0s
[16:45:04.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:16:0)
[16:45:04.382] Timer tick, asking Buildbucket for the build status
[16:45:04.471] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:04.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:17:0) after 1m0s
[16:46:04.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:17:0)
[16:46:04.493] Timer tick, asking Buildbucket for the build status
[16:46:04.664] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:04.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:18:0) after 1m0s
[16:47:04.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:18:0)
[16:47:04.728] Timer tick, asking Buildbucket for the build status
[16:47:04.843] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:04.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:19:0) after 1m0s
[16:48:04.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:19:0)
[16:48:04.885] Timer tick, asking Buildbucket for the build status
[16:48:05.006] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:05.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:20:0) after 1m0s
[16:49:05.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:20:0)
[16:49:05.043] Timer tick, asking Buildbucket for the build status
[16:49:05.227] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:05.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:21:0) after 1m0s
[16:50:05.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:21:0)
[16:50:05.243] Timer tick, asking Buildbucket for the build status
[16:50:05.496] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:05.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:22:0) after 1m0s
[16:51:05.548] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:22:0)
[16:51:05.548] Timer tick, asking Buildbucket for the build status
[16:51:05.662] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:05.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:23:0) after 1m0s
[16:52:05.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:23:0)
[16:52:05.754] Timer tick, asking Buildbucket for the build status
[16:52:05.918] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:05.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:24:0) after 1m0s
[16:53:06.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:24:0)
[16:53:06.079] Timer tick, asking Buildbucket for the build status
[16:53:06.248] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:06.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:25:0) after 1m0s
[16:54:06.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:25:0)
[16:54:06.504] Timer tick, asking Buildbucket for the build status
[16:54:06.899] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:06.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:26:0) after 1m0s
[16:55:07.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:26:0)
[16:55:07.132] Timer tick, asking Buildbucket for the build status
[16:55:07.215] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:07.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:27:0) after 1m0s
[16:56:07.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:27:0)
[16:56:07.257] Timer tick, asking Buildbucket for the build status
[16:56:07.449] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:07.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:28:0) after 1m0s
[16:57:07.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:28:0)
[16:57:07.868] Timer tick, asking Buildbucket for the build status
[16:57:08.684] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:08.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:29:0) after 1m0s
[16:58:08.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:29:0)
[16:58:08.976] Timer tick, asking Buildbucket for the build status
[16:58:09.081] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:09.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:30:0) after 1m0s
[16:59:09.150] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:30:0)
[16:59:09.150] Timer tick, asking Buildbucket for the build status
[16:59:09.249] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:09.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:31:0) after 1m0s
[17:00:09.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:31:0)
[17:00:09.378] Timer tick, asking Buildbucket for the build status
[17:00:09.448] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:09.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:32:0) after 1m0s
[17:01:09.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:32:0)
[17:01:09.648] Timer tick, asking Buildbucket for the build status
[17:01:10.308] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:10.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:33:0) after 1m0s
[17:02:10.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:33:0)
[17:02:10.400] Timer tick, asking Buildbucket for the build status
[17:02:10.516] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:10.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:34:0) after 1m0s
[17:03:10.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:34:0)
[17:03:10.659] Timer tick, asking Buildbucket for the build status
[17:03:10.798] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:10.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:35:0) after 1m0s
[17:04:10.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:35:0)
[17:04:10.856] Timer tick, asking Buildbucket for the build status
[17:04:10.967] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:10.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:36:0) after 1m0s
[17:05:11.101] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:36:0)
[17:05:11.102] Timer tick, asking Buildbucket for the build status
[17:05:11.178] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:11.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:37:0) after 1m0s
[17:06:11.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:37:0)
[17:06:11.290] Timer tick, asking Buildbucket for the build status
[17:06:11.569] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:11.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:38:0) after 1m0s
[17:07:11.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:38:0)
[17:07:11.695] Timer tick, asking Buildbucket for the build status
[17:07:11.857] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:11.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:39:0) after 1m0s
[17:08:11.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:39:0)
[17:08:11.887] Timer tick, asking Buildbucket for the build status
[17:08:12.093] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:12.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:40:0) after 1m0s
[17:09:12.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:40:0)
[17:09:12.315] Timer tick, asking Buildbucket for the build status
[17:09:12.504] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:12.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:41:0) after 1m0s
[17:10:12.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:41:0)
[17:10:12.630] Timer tick, asking Buildbucket for the build status
[17:10:12.753] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:12.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:42:0) after 1m0s
[17:11:12.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:42:0)
[17:11:12.846] Timer tick, asking Buildbucket for the build status
[17:11:13.026] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:13.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:43:0) after 1m0s
[17:12:13.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:43:0)
[17:12:13.086] Timer tick, asking Buildbucket for the build status
[17:12:13.192] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:13.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:44:0) after 1m0s
[17:13:13.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:44:0)
[17:13:13.303] Timer tick, asking Buildbucket for the build status
[17:13:13.457] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:13.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:45:0) after 1m0s
[17:14:13.528] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:45:0)
[17:14:13.528] Timer tick, asking Buildbucket for the build status
[17:14:13.663] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:13.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:46:0) after 1m0s
[17:15:13.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:46:0)
[17:15:13.711] Timer tick, asking Buildbucket for the build status
[17:15:13.783] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:13.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:47:0) after 1m0s
[17:16:13.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:47:0)
[17:16:13.830] Timer tick, asking Buildbucket for the build status
[17:16:13.969] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:13.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:48:0) after 1m0s
[17:17:14.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:48:0)
[17:17:14.111] Timer tick, asking Buildbucket for the build status
[17:17:14.273] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:14.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:49:0) after 1m0s
[17:18:14.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:49:0)
[17:18:14.311] Timer tick, asking Buildbucket for the build status
[17:18:14.495] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:14.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:50:0) after 1m0s
[17:19:14.747] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:50:0)
[17:19:14.747] Timer tick, asking Buildbucket for the build status
[17:19:14.846] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:14.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:51:0) after 1m0s
[17:20:14.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:51:0)
[17:20:14.878] Timer tick, asking Buildbucket for the build status
[17:20:15.836] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:15.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:52:0) after 1m0s
[17:21:15.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:52:0)
[17:21:15.925] Timer tick, asking Buildbucket for the build status
[17:21:16.029] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:16.029] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:53:0) after 1m0s
[17:22:16.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:53:0)
[17:22:16.087] Timer tick, asking Buildbucket for the build status
[17:22:16.210] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:16.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:54:0) after 1m0s
[17:23:16.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:54:0)
[17:23:16.338] Timer tick, asking Buildbucket for the build status
[17:23:16.466] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:16.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:55:0) after 1m0s
[17:24:16.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:55:0)
[17:24:16.619] Timer tick, asking Buildbucket for the build status
[17:24:16.747] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:16.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:56:0) after 1m0s
[17:25:16.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:56:0)
[17:25:16.818] Timer tick, asking Buildbucket for the build status
[17:25:16.903] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:16.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:57:0) after 1m0s
[17:26:16.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:57:0)
[17:26:16.987] Timer tick, asking Buildbucket for the build status
[17:26:17.381] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:17.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:58:0) after 1m0s
[17:27:17.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:58:0)
[17:27:17.457] Timer tick, asking Buildbucket for the build status
[17:27:18.671] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:18.671] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:59:0) after 1m0s
[17:28:18.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:59:0)
[17:28:18.741] Timer tick, asking Buildbucket for the build status
[17:28:18.835] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:18.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:60:0) after 1m0s
[17:29:18.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:60:0)
[17:29:18.917] Timer tick, asking Buildbucket for the build status
[17:29:19.012] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:19.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:61:0) after 1m0s
[17:30:19.083] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:61:0)
[17:30:19.083] Timer tick, asking Buildbucket for the build status
[17:30:19.698] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:19.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:62:0) after 1m0s
[17:31:19.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:62:0)
[17:31:19.777] Timer tick, asking Buildbucket for the build status
[17:31:19.961] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:19.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:63:0) after 1m0s
[17:32:20.010] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:63:0)
[17:32:20.011] Timer tick, asking Buildbucket for the build status
[17:32:21.447] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:21.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:64:0) after 1m0s
[17:33:21.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:64:0)
[17:33:21.554] Timer tick, asking Buildbucket for the build status
[17:33:22.061] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:22.061] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:65:0) after 1m0s
[17:34:22.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:65:0)
[17:34:22.103] Timer tick, asking Buildbucket for the build status
[17:34:22.683] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:22.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:66:0) after 1m0s
[17:35:22.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:66:0)
[17:35:22.779] Timer tick, asking Buildbucket for the build status
[17:35:22.843] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:22.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:67:0) after 1m0s
[17:36:22.901] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:67:0)
[17:36:22.901] Timer tick, asking Buildbucket for the build status
[17:36:23.414] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:23.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:68:0) after 1m0s
[17:37:23.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:68:0)
[17:37:23.517] Timer tick, asking Buildbucket for the build status
[17:37:23.618] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:23.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:69:0) after 1m0s
[17:38:23.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:69:0)
[17:38:23.685] Timer tick, asking Buildbucket for the build status
[17:38:23.773] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:23.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:70:0) after 1m0s
[17:39:23.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:70:0)
[17:39:23.909] Timer tick, asking Buildbucket for the build status
[17:39:23.989] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:23.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:71:0) after 1m0s
[17:40:24.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:71:0)
[17:40:24.015] Timer tick, asking Buildbucket for the build status
[17:40:24.084] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:24.084] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:72:0) after 1m0s
[17:41:24.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:72:0)
[17:41:24.147] Timer tick, asking Buildbucket for the build status
[17:41:24.371] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:24.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:73:0) after 1m0s
[17:42:24.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:73:0)
[17:42:24.497] Timer tick, asking Buildbucket for the build status
[17:42:24.605] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:24.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:74:0) after 1m0s
[17:43:24.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:74:0)
[17:43:24.679] Timer tick, asking Buildbucket for the build status
[17:43:24.783] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:24.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:75:0) after 1m0s
[17:44:24.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:75:0)
[17:44:24.864] Timer tick, asking Buildbucket for the build status
[17:44:25.199] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:25.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:76:0) after 1m0s
[17:45:25.251] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:76:0)
[17:45:25.251] Timer tick, asking Buildbucket for the build status
[17:45:25.331] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:25.331] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:77:0) after 1m0s
[17:46:25.372] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:77:0)
[17:46:25.372] Timer tick, asking Buildbucket for the build status
[17:46:25.533] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:25.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:78:0) after 1m0s
[17:47:25.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:78:0)
[17:47:25.578] Timer tick, asking Buildbucket for the build status
[17:47:25.870] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:25.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:79:0) after 1m0s
[17:48:25.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:79:0)
[17:48:25.867] Timer tick, asking Buildbucket for the build status
[17:48:26.066] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:26.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:80:0) after 1m0s
[17:49:26.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:80:0)
[17:49:26.159] Timer tick, asking Buildbucket for the build status
[17:49:26.359] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:26.359] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:81:0) after 1m0s
[17:50:26.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:81:0)
[17:50:26.599] Timer tick, asking Buildbucket for the build status
[17:50:26.798] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:26.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:82:0) after 1m0s
[17:51:26.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:82:0)
[17:51:26.838] Timer tick, asking Buildbucket for the build status
[17:51:26.998] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:26.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:83:0) after 1m0s
[17:52:27.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:83:0)
[17:52:27.107] Timer tick, asking Buildbucket for the build status
[17:52:27.256] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:27.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:84:0) after 1m0s
[17:53:27.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:84:0)
[17:53:27.345] Timer tick, asking Buildbucket for the build status
[17:53:27.445] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:27.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:85:0) after 1m0s
[17:54:27.490] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:85:0)
[17:54:27.490] Timer tick, asking Buildbucket for the build status
[17:54:27.896] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:27.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:86:0) after 1m0s
[17:55:27.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:86:0)
[17:55:27.918] Timer tick, asking Buildbucket for the build status
[17:55:28.114] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:28.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:87:0) after 1m0s
[17:56:28.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:87:0)
[17:56:28.135] Timer tick, asking Buildbucket for the build status
[17:56:28.445] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:28.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:88:0) after 1m0s
[17:57:28.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:88:0)
[17:57:28.543] Timer tick, asking Buildbucket for the build status
[17:57:28.611] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:28.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:89:0) after 1m0s
[17:58:28.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:89:0)
[17:58:28.655] Timer tick, asking Buildbucket for the build status
[17:58:28.932] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:28.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:90:0) after 1m0s
[17:59:28.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:90:0)
[17:59:28.989] Timer tick, asking Buildbucket for the build status
[17:59:29.086] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:29.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:91:0) after 1m0s
[18:00:29.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:91:0)
[18:00:29.115] Timer tick, asking Buildbucket for the build status
[18:00:29.197] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:29.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:92:0) after 1m0s
[18:01:29.264] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:92:0)
[18:01:29.264] Timer tick, asking Buildbucket for the build status
[18:01:29.658] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:29.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:93:0) after 1m0s
[18:02:29.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:93:0)
[18:02:29.730] Timer tick, asking Buildbucket for the build status
[18:02:29.812] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:29.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:94:0) after 1m0s
[18:03:29.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:94:0)
[18:03:29.918] Timer tick, asking Buildbucket for the build status
[18:03:30.031] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:30.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:95:0) after 1m0s
[18:04:30.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:95:0)
[18:04:30.159] Timer tick, asking Buildbucket for the build status
[18:04:30.257] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:30.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:96:0) after 1m0s
[18:05:30.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:96:0)
[18:05:30.279] Timer tick, asking Buildbucket for the build status
[18:05:30.518] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:30.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:97:0) after 1m0s
[18:06:30.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:97:0)
[18:06:30.776] Timer tick, asking Buildbucket for the build status
[18:06:30.943] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:30.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:98:0) after 1m0s
[18:07:31.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:98:0)
[18:07:31.036] Timer tick, asking Buildbucket for the build status
[18:07:31.303] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:31.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:99:0) after 1m0s
[18:08:31.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:99:0)
[18:08:31.468] Timer tick, asking Buildbucket for the build status
[18:08:35.406] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:35.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:100:0) after 1m0s
[18:09:35.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:100:0)
[18:09:35.506] Timer tick, asking Buildbucket for the build status
[18:09:36.671] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:36.671] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:101:0) after 1m0s
[18:10:36.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:101:0)
[18:10:36.758] Timer tick, asking Buildbucket for the build status
[18:10:36.840] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:36.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:102:0) after 1m0s
[18:11:36.961] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:102:0)
[18:11:36.961] Timer tick, asking Buildbucket for the build status
[18:11:37.292] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:37.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:103:0) after 1m0s
[18:12:37.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:103:0)
[18:12:37.435] Timer tick, asking Buildbucket for the build status
[18:12:37.528] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:37.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:104:0) after 1m0s
[18:13:37.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:104:0)
[18:13:37.668] Timer tick, asking Buildbucket for the build status
[18:13:37.735] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:37.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:105:0) after 1m0s
[18:14:37.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:105:0)
[18:14:37.963] Timer tick, asking Buildbucket for the build status
[18:14:38.037] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:38.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:106:0) after 1m0s
[18:15:38.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:106:0)
[18:15:38.376] Timer tick, asking Buildbucket for the build status
[18:15:38.569] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:38.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:107:0) after 1m0s
[18:16:38.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:107:0)
[18:16:38.620] Timer tick, asking Buildbucket for the build status
[18:16:38.772] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:38.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:108:0) after 1m0s
[18:17:38.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:108:0)
[18:17:38.943] Timer tick, asking Buildbucket for the build status
[18:17:39.032] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:39.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:109:0) after 1m0s
[18:18:39.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:109:0)
[18:18:39.297] Timer tick, asking Buildbucket for the build status
[18:18:39.520] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:39.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:110:0) after 1m0s
[18:19:39.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:110:0)
[18:19:39.642] Timer tick, asking Buildbucket for the build status
[18:19:39.725] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:39.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:111:0) after 1m0s
[18:20:39.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:111:0)
[18:20:39.896] Timer tick, asking Buildbucket for the build status
[18:20:39.999] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:40.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:112:0) after 1m0s
[18:21:40.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:112:0)
[18:21:40.536] Timer tick, asking Buildbucket for the build status
[18:21:40.724] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:40.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:113:0) after 1m0s
[18:22:41.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:113:0)
[18:22:41.127] Timer tick, asking Buildbucket for the build status
[18:22:41.600] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:41.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:114:0) after 1m0s
[18:23:42.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:114:0)
[18:23:42.192] Timer tick, asking Buildbucket for the build status
[18:23:42.328] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:42.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:115:0) after 1m0s
[18:24:42.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:115:0)
[18:24:42.477] Timer tick, asking Buildbucket for the build status
[18:24:42.633] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:42.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:116:0) after 1m0s
[18:25:42.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:116:0)
[18:25:42.877] Timer tick, asking Buildbucket for the build status
[18:25:43.165] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:43.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:117:0) after 1m0s
[18:26:43.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:117:0)
[18:26:43.279] Timer tick, asking Buildbucket for the build status
[18:26:43.408] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:43.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:118:0) after 1m0s
[18:27:43.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:118:0)
[18:27:43.542] Timer tick, asking Buildbucket for the build status
[18:27:43.633] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:43.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:119:0) after 1m0s
[18:28:43.772] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:119:0)
[18:28:43.772] Timer tick, asking Buildbucket for the build status
[18:28:43.992] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:43.992] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:120:0) after 1m0s
[18:29:44.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:120:0)
[18:29:44.199] Timer tick, asking Buildbucket for the build status
[18:29:44.304] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:44.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:121:0) after 1m0s
[18:30:44.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:121:0)
[18:30:44.588] Timer tick, asking Buildbucket for the build status
[18:30:44.950] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:44.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:122:0) after 1m0s
[18:31:45.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:122:0)
[18:31:45.001] Timer tick, asking Buildbucket for the build status
[18:31:45.127] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:45.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:123:0) after 1m0s
[18:32:45.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:123:0)
[18:32:45.283] Timer tick, asking Buildbucket for the build status
[18:32:45.361] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:45.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:124:0) after 1m0s
[18:33:45.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:124:0)
[18:33:45.477] Timer tick, asking Buildbucket for the build status
[18:33:45.586] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:45.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:125:0) after 1m0s
[18:34:45.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:125:0)
[18:34:45.664] Timer tick, asking Buildbucket for the build status
[18:34:46.813] Build 8867897794125992496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:46.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033327856090420544:126:0) after 1m0s
[18:34:48.628] Received PubSub notification, asking Buildbucket for the build status
[18:34:48.717] Build 8867897794125992496: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:34:48.717] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1601318087813225",
"created_by": "project:chromium",
"created_ts": "1601310655434400",
"failure_reason": "BUILD_FAILURE",
"id": "8867897794125992496",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"5 Test Suite(s) failed.\\n\\n**skia_renderer_content_browsertests** failed.\\n\\n**vulkan_swiftshader_blink_web_tests** failed.\\n\\n**non_skia_renderer_browser_tests** failed.\\n\\n**non_skia_renderer_swiftshader_blink_web_tests** failed.\\n\\n**vulkan_swiftshader_content_browsertests** failed.\"}, \"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\": \"b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-40-quw7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 24077, \"got_angle_revision\": \"059781eddd4ec3316b319f4b3394c9f65a3020f5\", \"got_dawn_revision\": \"145f115c5496a0c4ab17d13a20410143b0ce03d4\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4\", \"got_revision_cp\": \"refs/heads/master@{#811260}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"b94db86cf9b3369ebd3c99ffe0d4072bac0a7b75\", \"got_v8_revision\": \"685e35de12fcc6ab69258bbfda40706c9b3aa8f9\", \"got_v8_revision_cp\": \"refs/heads/8.7.206@{#1}\", \"got_webrtc_revision\": \"b6103ff5f992a907ab000bea2528ca9075ed2e41\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32207}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4\", \"swarm_hashes\": {\"blink_web_tests\": \"2cc6719f8bb59811aad894f8cfd8983e7b0dd326\", \"browser_tests\": \"f5989aa78138e3f7b296a8b16ab62e52f145b159\", \"content_browsertests\": \"423a8bd3784d266b3061ec6672273e843df0d6f3\"}, \"swarm_hashes_refs/heads/master(at){#811260}_without_patch\": {\"blink_web_tests\": \"2cc6719f8bb59811aad894f8cfd8983e7b0dd326\", \"browser_tests\": \"f5989aa78138e3f7b296a8b16ab62e52f145b159\", \"content_browsertests\": \"423a8bd3784d266b3061ec6672273e843df0d6f3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_444451e75b4df0df69e3430ad64f26102f737e4856098a1cc2a4d04bc5a1dbec_v2\", \"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-40-quw7\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"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\": [\"5541-5d998a0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"5 Test Suite(s) failed.\\n\\n**skia_renderer_content_browsertests** failed.\\n\\n**vulkan_swiftshader_blink_web_tests** failed.\\n\\n**non_skia_renderer_browser_tests** failed.\\n\\n**non_skia_renderer_swiftshader_blink_web_tests** failed.\\n\\n**vulkan_swiftshader_content_browsertests** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1601310659940335",
"status": "COMPLETED",
"status_changed_ts": "1601310660457839",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24077",
"builder:Linux Viz",
"buildset:commit/git/b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b37c27dcd406dd3f15e4aaefafea3f3bfaff53c4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033327856090420544",
"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/8867897794125992496/+/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:4eee5f16e3621610",
"user_agent:luci-scheduler"
],
"updated_ts": "1601318087895800",
"url": "https://ci.chromium.org/b/8867897794125992496",
"utcnow_ts": "1601318088707116"
}
}
[18:34:48.717] Invocation finished in 2h3m54.540304129s with status FAILED