[05:24:58.109] New invocation is queued and will start shortly
[05:24:59.222] Starting the invocation (attempt 1)
[05:24:59.265] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:24:59.265] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:24:59.266] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9033097962185520144",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"849a4f2762b4518542abda8267d6e7d792069415\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9033097962185520144",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/849a4f2762b4518542abda8267d6e7d792069415",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/849a4f2762b4518542abda8267d6e7d792069415",
"gitiles_ref:refs/heads/master"
]
}
[05:24:59.919] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1601529899351456",
"id": "8867667900216309184",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"849a4f2762b4518542abda8267d6e7d792069415\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601529899806325",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24101",
"builder:Linux Viz",
"buildset:commit/git/849a4f2762b4518542abda8267d6e7d792069415",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/849a4f2762b4518542abda8267d6e7d792069415",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033097962185520144",
"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/8867667900216309184/+/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": "1601529899806436",
"url": "https://ci.chromium.org/b/8867667900216309184",
"utcnow_ts": "1601529899911591"
}
}
[05:24:59.919] Task URL: https://ci.chromium.org/b/8867667900216309184
[05:24:59.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:2:0) after 1m0s
[05:25:47.178] Received PubSub notification, asking Buildbucket for the build status
[05:25:47.263] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:59.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:2:0)
[05:25:59.936] Timer tick, asking Buildbucket for the build status
[05:26:00.141] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:00.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:4:0) after 1m0s
[05:27:00.175] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:4:0)
[05:27:00.175] Timer tick, asking Buildbucket for the build status
[05:27:00.414] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:00.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:5:0) after 1m0s
[05:28:01.185] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:5:0)
[05:28:01.185] Timer tick, asking Buildbucket for the build status
[05:28:01.304] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:01.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:6:0) after 1m0s
[05:29:01.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:6:0)
[05:29:01.323] Timer tick, asking Buildbucket for the build status
[05:29:01.644] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:01.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:7:0) after 1m0s
[05:30:01.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:7:0)
[05:30:01.666] Timer tick, asking Buildbucket for the build status
[05:30:01.781] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:01.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:8:0) after 1m0s
[05:31:02.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:8:0)
[05:31:02.602] Timer tick, asking Buildbucket for the build status
[05:31:02.742] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:02.742] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:9:0) after 1m0s
[05:32:02.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:9:0)
[05:32:02.774] Timer tick, asking Buildbucket for the build status
[05:32:03.416] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:03.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:10:0) after 1m0s
[05:33:03.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:10:0)
[05:33:03.452] Timer tick, asking Buildbucket for the build status
[05:33:03.549] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:03.549] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:11:0) after 1m0s
[05:34:03.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:11:0)
[05:34:03.578] Timer tick, asking Buildbucket for the build status
[05:34:03.665] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:03.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:12:0) after 1m0s
[05:35:03.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:12:0)
[05:35:03.700] Timer tick, asking Buildbucket for the build status
[05:35:04.725] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:04.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:13:0) after 1m0s
[05:36:04.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:13:0)
[05:36:04.771] Timer tick, asking Buildbucket for the build status
[05:36:04.840] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:04.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:14:0) after 1m0s
[05:37:04.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:14:0)
[05:37:04.923] Timer tick, asking Buildbucket for the build status
[05:37:05.014] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:05.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:15:0) after 1m0s
[05:38:05.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:15:0)
[05:38:05.034] Timer tick, asking Buildbucket for the build status
[05:38:05.461] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:05.461] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:16:0) after 1m0s
[05:39:05.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:16:0)
[05:39:05.480] Timer tick, asking Buildbucket for the build status
[05:39:05.583] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:05.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:17:0) after 1m0s
[05:40:05.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:17:0)
[05:40:05.606] Timer tick, asking Buildbucket for the build status
[05:40:05.881] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:05.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:18:0) after 1m0s
[05:41:05.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:18:0)
[05:41:05.913] Timer tick, asking Buildbucket for the build status
[05:41:06.143] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:06.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:19:0) after 1m0s
[05:42:06.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:19:0)
[05:42:06.243] Timer tick, asking Buildbucket for the build status
[05:42:06.328] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:06.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:20:0) after 1m0s
[05:43:06.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:20:0)
[05:43:06.388] Timer tick, asking Buildbucket for the build status
[05:43:06.482] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:06.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:21:0) after 1m0s
[05:44:06.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:21:0)
[05:44:06.534] Timer tick, asking Buildbucket for the build status
[05:44:06.622] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:06.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:22:0) after 1m0s
[05:45:06.753] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:22:0)
[05:45:06.753] Timer tick, asking Buildbucket for the build status
[05:45:06.873] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:06.873] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:23:0) after 1m0s
[05:46:06.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:23:0)
[05:46:06.874] Timer tick, asking Buildbucket for the build status
[05:46:07.421] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:07.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:24:0) after 1m0s
[05:47:07.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:24:0)
[05:47:07.483] Timer tick, asking Buildbucket for the build status
[05:47:07.700] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:07.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:25:0) after 1m0s
[05:48:07.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:25:0)
[05:48:07.819] Timer tick, asking Buildbucket for the build status
[05:48:08.184] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:08.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:26:0) after 1m0s
[05:49:08.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:26:0)
[05:49:08.223] Timer tick, asking Buildbucket for the build status
[05:49:08.617] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:08.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:27:0) after 1m0s
[05:50:08.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:27:0)
[05:50:08.722] Timer tick, asking Buildbucket for the build status
[05:50:09.224] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:09.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:28:0) after 1m0s
[05:51:09.232] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:28:0)
[05:51:09.232] Timer tick, asking Buildbucket for the build status
[05:51:09.615] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:09.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:29:0) after 1m0s
[05:52:09.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:29:0)
[05:52:09.610] Timer tick, asking Buildbucket for the build status
[05:52:09.685] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:09.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:30:0) after 1m0s
[05:53:09.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:30:0)
[05:53:09.705] Timer tick, asking Buildbucket for the build status
[05:53:09.951] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:09.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:31:0) after 1m0s
[05:54:09.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:31:0)
[05:54:09.963] Timer tick, asking Buildbucket for the build status
[05:54:10.205] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:10.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:32:0) after 1m0s
[05:55:10.251] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:32:0)
[05:55:10.251] Timer tick, asking Buildbucket for the build status
[05:55:10.782] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:10.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:33:0) after 1m0s
[05:56:10.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:33:0)
[05:56:10.850] Timer tick, asking Buildbucket for the build status
[05:56:11.355] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:11.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:34:0) after 1m0s
[05:57:11.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:34:0)
[05:57:11.383] Timer tick, asking Buildbucket for the build status
[05:57:11.458] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:11.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:35:0) after 1m0s
[05:58:11.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:35:0)
[05:58:11.536] Timer tick, asking Buildbucket for the build status
[05:58:11.668] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:11.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:36:0) after 1m0s
[05:59:11.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:36:0)
[05:59:11.787] Timer tick, asking Buildbucket for the build status
[05:59:11.884] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:11.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:37:0) after 1m0s
[06:00:11.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:37:0)
[06:00:11.963] Timer tick, asking Buildbucket for the build status
[06:00:12.439] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:12.439] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:38:0) after 1m0s
[06:01:12.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:38:0)
[06:01:12.529] Timer tick, asking Buildbucket for the build status
[06:01:12.890] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:12.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:39:0) after 1m0s
[06:02:13.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:39:0)
[06:02:13.027] Timer tick, asking Buildbucket for the build status
[06:02:13.305] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:13.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:40:0) after 1m0s
[06:03:13.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:40:0)
[06:03:13.332] Timer tick, asking Buildbucket for the build status
[06:03:13.483] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:13.483] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:41:0) after 1m0s
[06:04:13.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:41:0)
[06:04:13.628] Timer tick, asking Buildbucket for the build status
[06:04:13.856] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:13.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:42:0) after 1m0s
[06:05:13.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:42:0)
[06:05:13.844] Timer tick, asking Buildbucket for the build status
[06:05:14.279] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:14.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:43:0) after 1m0s
[06:06:14.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:43:0)
[06:06:14.351] Timer tick, asking Buildbucket for the build status
[06:06:14.456] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:14.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:44:0) after 1m0s
[06:07:14.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:44:0)
[06:07:14.602] Timer tick, asking Buildbucket for the build status
[06:07:14.723] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:14.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:45:0) after 1m0s
[06:08:14.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:45:0)
[06:08:14.852] Timer tick, asking Buildbucket for the build status
[06:08:14.945] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:14.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:46:0) after 1m0s
[06:09:15.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:46:0)
[06:09:15.094] Timer tick, asking Buildbucket for the build status
[06:09:15.328] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:15.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:47:0) after 1m0s
[06:10:15.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:47:0)
[06:10:15.503] Timer tick, asking Buildbucket for the build status
[06:10:15.653] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:15.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:48:0) after 1m0s
[06:11:15.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:48:0)
[06:11:15.676] Timer tick, asking Buildbucket for the build status
[06:11:15.726] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:15.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:49:0) after 1m0s
[06:12:15.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:49:0)
[06:12:15.791] Timer tick, asking Buildbucket for the build status
[06:12:17.234] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:17.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:50:0) after 1m0s
[06:13:17.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:50:0)
[06:13:17.382] Timer tick, asking Buildbucket for the build status
[06:13:17.441] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:17.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:51:0) after 1m0s
[06:14:17.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:51:0)
[06:14:17.459] Timer tick, asking Buildbucket for the build status
[06:14:17.812] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:17.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:52:0) after 1m0s
[06:15:17.839] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:52:0)
[06:15:17.839] Timer tick, asking Buildbucket for the build status
[06:15:17.979] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:17.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:53:0) after 1m0s
[06:16:18.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:53:0)
[06:16:18.072] Timer tick, asking Buildbucket for the build status
[06:16:18.164] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:18.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:54:0) after 1m0s
[06:17:18.246] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:54:0)
[06:17:18.246] Timer tick, asking Buildbucket for the build status
[06:17:18.543] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:18.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:55:0) after 1m0s
[06:18:18.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:55:0)
[06:18:18.741] Timer tick, asking Buildbucket for the build status
[06:18:18.829] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:18.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:56:0) after 1m0s
[06:19:18.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:56:0)
[06:19:18.973] Timer tick, asking Buildbucket for the build status
[06:19:19.067] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:19.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:57:0) after 1m0s
[06:20:19.162] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:57:0)
[06:20:19.162] Timer tick, asking Buildbucket for the build status
[06:20:19.420] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:19.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:58:0) after 1m0s
[06:21:19.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:58:0)
[06:21:19.566] Timer tick, asking Buildbucket for the build status
[06:21:19.767] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:19.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:59:0) after 1m0s
[06:22:19.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:59:0)
[06:22:19.908] Timer tick, asking Buildbucket for the build status
[06:22:19.986] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:19.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:60:0) after 1m0s
[06:23:20.239] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:60:0)
[06:23:20.239] Timer tick, asking Buildbucket for the build status
[06:23:20.359] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:20.359] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:61:0) after 1m0s
[06:24:20.416] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:61:0)
[06:24:20.416] Timer tick, asking Buildbucket for the build status
[06:24:20.500] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:20.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:62:0) after 1m0s
[06:25:20.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:62:0)
[06:25:20.588] Timer tick, asking Buildbucket for the build status
[06:25:20.828] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:20.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:63:0) after 1m0s
[06:26:20.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:63:0)
[06:26:20.899] Timer tick, asking Buildbucket for the build status
[06:26:21.101] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:21.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:64:0) after 1m0s
[06:27:21.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:64:0)
[06:27:21.112] Timer tick, asking Buildbucket for the build status
[06:27:21.235] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:21.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:65:0) after 1m0s
[06:28:21.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:65:0)
[06:28:21.299] Timer tick, asking Buildbucket for the build status
[06:28:21.375] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:21.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:66:0) after 1m0s
[06:29:21.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:66:0)
[06:29:21.466] Timer tick, asking Buildbucket for the build status
[06:29:21.568] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:21.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:67:0) after 1m0s
[06:30:21.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:67:0)
[06:30:21.694] Timer tick, asking Buildbucket for the build status
[06:30:21.847] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:21.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:68:0) after 1m0s
[06:31:21.884] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:68:0)
[06:31:21.884] Timer tick, asking Buildbucket for the build status
[06:31:22.431] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:22.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:69:0) after 1m0s
[06:32:22.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:69:0)
[06:32:22.445] Timer tick, asking Buildbucket for the build status
[06:32:22.832] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:22.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:70:0) after 1m0s
[06:33:22.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:70:0)
[06:33:22.954] Timer tick, asking Buildbucket for the build status
[06:33:23.036] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:23.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:71:0) after 1m0s
[06:34:23.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:71:0)
[06:34:23.089] Timer tick, asking Buildbucket for the build status
[06:34:23.266] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:23.266] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:72:0) after 1m0s
[06:35:23.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:72:0)
[06:35:23.454] Timer tick, asking Buildbucket for the build status
[06:35:23.681] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:23.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:73:0) after 1m0s
[06:36:24.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:73:0)
[06:36:24.006] Timer tick, asking Buildbucket for the build status
[06:36:24.082] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:24.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:74:0) after 1m0s
[06:37:24.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:74:0)
[06:37:24.311] Timer tick, asking Buildbucket for the build status
[06:37:24.464] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:24.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:75:0) after 1m0s
[06:38:25.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:75:0)
[06:38:25.775] Timer tick, asking Buildbucket for the build status
[06:38:25.944] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:25.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:76:0) after 1m0s
[06:39:26.126] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:76:0)
[06:39:26.126] Timer tick, asking Buildbucket for the build status
[06:39:26.211] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:26.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:77:0) after 1m0s
[06:40:26.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:77:0)
[06:40:26.349] Timer tick, asking Buildbucket for the build status
[06:40:26.504] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:26.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:78:0) after 1m0s
[06:41:26.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:78:0)
[06:41:26.552] Timer tick, asking Buildbucket for the build status
[06:41:26.620] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:26.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:79:0) after 1m0s
[06:42:26.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:79:0)
[06:42:26.759] Timer tick, asking Buildbucket for the build status
[06:42:26.853] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:26.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:80:0) after 1m0s
[06:43:26.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:80:0)
[06:43:26.920] Timer tick, asking Buildbucket for the build status
[06:43:27.193] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:27.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:81:0) after 1m0s
[06:44:27.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:81:0)
[06:44:27.286] Timer tick, asking Buildbucket for the build status
[06:44:27.440] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:27.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:82:0) after 1m0s
[06:45:27.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:82:0)
[06:45:27.505] Timer tick, asking Buildbucket for the build status
[06:45:27.837] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:27.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:83:0) after 1m0s
[06:46:27.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:83:0)
[06:46:27.848] Timer tick, asking Buildbucket for the build status
[06:46:27.948] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:27.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:84:0) after 1m0s
[06:47:28.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:84:0)
[06:47:28.015] Timer tick, asking Buildbucket for the build status
[06:47:28.096] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:28.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:85:0) after 1m0s
[06:48:28.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:85:0)
[06:48:28.274] Timer tick, asking Buildbucket for the build status
[06:48:28.365] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:28.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:86:0) after 1m0s
[06:49:28.579] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:86:0)
[06:49:28.579] Timer tick, asking Buildbucket for the build status
[06:49:28.692] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:28.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:87:0) after 1m0s
[06:50:28.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:87:0)
[06:50:28.814] Timer tick, asking Buildbucket for the build status
[06:50:28.944] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:28.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:88:0) after 1m0s
[06:51:29.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:88:0)
[06:51:29.011] Timer tick, asking Buildbucket for the build status
[06:51:29.130] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:29.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:89:0) after 1m0s
[06:52:29.225] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:89:0)
[06:52:29.225] Timer tick, asking Buildbucket for the build status
[06:52:29.311] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:29.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:90:0) after 1m0s
[06:53:29.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:90:0)
[06:53:29.410] Timer tick, asking Buildbucket for the build status
[06:53:29.486] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:29.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:91:0) after 1m0s
[06:54:29.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:91:0)
[06:54:29.630] Timer tick, asking Buildbucket for the build status
[06:54:29.823] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:29.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:92:0) after 1m0s
[06:55:29.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:92:0)
[06:55:29.952] Timer tick, asking Buildbucket for the build status
[06:55:30.382] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:30.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:93:0) after 1m0s
[06:56:30.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:93:0)
[06:56:30.467] Timer tick, asking Buildbucket for the build status
[06:56:30.718] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:30.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:94:0) after 1m0s
[06:57:30.772] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:94:0)
[06:57:30.773] Timer tick, asking Buildbucket for the build status
[06:57:30.956] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:30.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:95:0) after 1m0s
[06:58:31.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:95:0)
[06:58:31.114] Timer tick, asking Buildbucket for the build status
[06:58:31.231] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:31.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:96:0) after 1m0s
[06:59:31.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:96:0)
[06:59:31.353] Timer tick, asking Buildbucket for the build status
[06:59:31.452] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:31.452] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:97:0) after 1m0s
[07:00:31.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:97:0)
[07:00:31.553] Timer tick, asking Buildbucket for the build status
[07:00:31.832] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:31.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:98:0) after 1m0s
[07:01:32.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:98:0)
[07:01:32.027] Timer tick, asking Buildbucket for the build status
[07:01:32.387] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:32.387] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:99:0) after 1m0s
[07:02:32.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:99:0)
[07:02:32.433] Timer tick, asking Buildbucket for the build status
[07:02:32.636] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:32.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:100:0) after 1m0s
[07:03:32.743] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:100:0)
[07:03:32.743] Timer tick, asking Buildbucket for the build status
[07:03:32.832] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:32.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:101:0) after 1m0s
[07:04:33.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:101:0)
[07:04:33.105] Timer tick, asking Buildbucket for the build status
[07:04:34.089] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:34.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:102:0) after 1m0s
[07:05:34.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:102:0)
[07:05:34.168] Timer tick, asking Buildbucket for the build status
[07:05:34.339] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:34.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:103:0) after 1m0s
[07:06:34.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:103:0)
[07:06:34.426] Timer tick, asking Buildbucket for the build status
[07:06:34.562] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:34.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:104:0) after 1m0s
[07:07:34.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:104:0)
[07:07:34.774] Timer tick, asking Buildbucket for the build status
[07:07:34.961] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:34.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:105:0) after 1m0s
[07:08:34.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:105:0)
[07:08:34.994] Timer tick, asking Buildbucket for the build status
[07:08:35.068] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:35.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:106:0) after 1m0s
[07:09:35.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:106:0)
[07:09:35.171] Timer tick, asking Buildbucket for the build status
[07:09:35.252] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:35.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:107:0) after 1m0s
[07:10:35.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:107:0)
[07:10:35.309] Timer tick, asking Buildbucket for the build status
[07:10:35.525] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:35.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:108:0) after 1m0s
[07:11:35.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:108:0)
[07:11:35.600] Timer tick, asking Buildbucket for the build status
[07:11:35.698] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:35.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:109:0) after 1m0s
[07:12:35.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:109:0)
[07:12:35.763] Timer tick, asking Buildbucket for the build status
[07:12:35.937] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:35.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:110:0) after 1m0s
[07:13:36.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:110:0)
[07:13:36.046] Timer tick, asking Buildbucket for the build status
[07:13:36.166] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:36.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:111:0) after 1m0s
[07:14:36.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:111:0)
[07:14:36.223] Timer tick, asking Buildbucket for the build status
[07:14:36.283] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:36.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:112:0) after 1m0s
[07:15:36.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:112:0)
[07:15:36.467] Timer tick, asking Buildbucket for the build status
[07:15:36.670] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:36.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:113:0) after 1m0s
[07:16:36.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:113:0)
[07:16:36.763] Timer tick, asking Buildbucket for the build status
[07:16:37.105] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:37.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:114:0) after 1m0s
[07:17:37.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:114:0)
[07:17:37.128] Timer tick, asking Buildbucket for the build status
[07:17:37.611] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:37.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:115:0) after 1m0s
[07:18:37.634] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:115:0)
[07:18:37.635] Timer tick, asking Buildbucket for the build status
[07:18:37.795] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:37.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:116:0) after 1m0s
[07:19:37.834] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:116:0)
[07:19:37.834] Timer tick, asking Buildbucket for the build status
[07:19:37.900] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:37.900] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:117:0) after 1m0s
[07:20:37.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:117:0)
[07:20:37.963] Timer tick, asking Buildbucket for the build status
[07:20:38.119] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:38.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:118:0) after 1m0s
[07:21:38.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:118:0)
[07:21:38.146] Timer tick, asking Buildbucket for the build status
[07:21:38.240] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:38.240] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:119:0) after 1m0s
[07:22:38.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:119:0)
[07:22:38.488] Timer tick, asking Buildbucket for the build status
[07:22:38.747] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:38.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:120:0) after 1m0s
[07:23:38.789] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:120:0)
[07:23:38.789] Timer tick, asking Buildbucket for the build status
[07:23:38.878] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:38.878] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:121:0) after 1m0s
[07:24:38.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:121:0)
[07:24:38.904] Timer tick, asking Buildbucket for the build status
[07:24:38.996] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:38.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:122:0) after 1m0s
[07:25:39.070] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:122:0)
[07:25:39.070] Timer tick, asking Buildbucket for the build status
[07:25:39.421] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:39.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:123:0) after 1m0s
[07:26:39.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:123:0)
[07:26:39.526] Timer tick, asking Buildbucket for the build status
[07:26:39.627] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:39.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:124:0) after 1m0s
[07:27:39.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:124:0)
[07:27:39.656] Timer tick, asking Buildbucket for the build status
[07:27:39.727] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:39.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:125:0) after 1m0s
[07:28:39.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:125:0)
[07:28:39.867] Timer tick, asking Buildbucket for the build status
[07:28:40.177] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:40.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:126:0) after 1m0s
[07:29:40.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:126:0)
[07:29:40.226] Timer tick, asking Buildbucket for the build status
[07:29:40.313] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:40.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:127:0) after 1m0s
[07:30:40.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:127:0)
[07:30:40.325] Timer tick, asking Buildbucket for the build status
[07:30:40.841] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:40.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:128:0) after 1m0s
[07:31:40.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:128:0)
[07:31:40.902] Timer tick, asking Buildbucket for the build status
[07:31:40.974] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:40.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:129:0) after 1m0s
[07:32:41.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:129:0)
[07:32:41.004] Timer tick, asking Buildbucket for the build status
[07:32:41.161] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:41.161] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:130:0) after 1m0s
[07:33:41.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:130:0)
[07:33:41.188] Timer tick, asking Buildbucket for the build status
[07:33:41.301] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:41.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:131:0) after 1m0s
[07:34:41.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:131:0)
[07:34:41.338] Timer tick, asking Buildbucket for the build status
[07:34:41.423] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:41.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:132:0) after 1m0s
[07:35:41.451] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:132:0)
[07:35:41.451] Timer tick, asking Buildbucket for the build status
[07:35:42.411] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:42.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:133:0) after 1m0s
[07:36:42.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:133:0)
[07:36:42.441] Timer tick, asking Buildbucket for the build status
[07:36:42.539] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:42.539] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:134:0) after 1m0s
[07:37:42.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:134:0)
[07:37:42.550] Timer tick, asking Buildbucket for the build status
[07:37:42.662] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:42.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:135:0) after 1m0s
[07:38:42.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:135:0)
[07:38:42.690] Timer tick, asking Buildbucket for the build status
[07:38:42.892] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:42.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:136:0) after 1m0s
[07:39:42.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:136:0)
[07:39:42.895] Timer tick, asking Buildbucket for the build status
[07:39:42.997] Build 8867667900216309184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:42.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9033097962185520144:137:0) after 1m0s
[07:40:30.258] Received PubSub notification, asking Buildbucket for the build status
[07:40:30.484] Build 8867667900216309184: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:40:30.484] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1601538029446486",
"created_by": "project:chromium",
"created_ts": "1601529899351456",
"failure_reason": "BUILD_FAILURE",
"id": "8867667900216309184",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"849a4f2762b4518542abda8267d6e7d792069415\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"5 Test Suite(s) failed.\\n\\n**vulkan_swiftshader_blink_web_tests** failed.\\n\\n**non_skia_renderer_swiftshader_blink_web_tests** failed.\\n\\n**skia_renderer_content_browsertests** failed.\\n\\n**vulkan_swiftshader_content_browsertests** failed.\\n\\n**non_skia_renderer_browser_tests** 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\": \"849a4f2762b4518542abda8267d6e7d792069415\", \"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-78-reme\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 24101, \"got_angle_revision\": \"11d94d866e23cdda9f040eebb66c09c4d9850064\", \"got_dawn_revision\": \"da5828c06b4c7cbe428283a1516a4fb73d5836be\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"849a4f2762b4518542abda8267d6e7d792069415\", \"got_revision_cp\": \"refs/heads/master@{#812553}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"de0105d0bb7a58985fb71095168a3b78079c5f0c\", \"got_v8_revision\": \"c4be5db90558c1c8dd93893203412b2360bbbedd\", \"got_v8_revision_cp\": \"refs/heads/8.7.240@{#1}\", \"got_webrtc_revision\": \"e39b378d4a921e9982f39ebb02a6145afb653956\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32216}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"849a4f2762b4518542abda8267d6e7d792069415\", \"swarm_hashes\": {\"blink_web_tests\": \"c0c5a2e2934d69861f3d5c9aaac6fe979d197c7e\", \"browser_tests\": \"4bf048a58e082acc6e01c8f9a63abc131562eec6\", \"content_browsertests\": \"e15cc94a31b16e32ffb2c8a7e3d6a62741ceb89b\"}, \"swarm_hashes_refs/heads/master(at){#812553}_without_patch\": {\"blink_web_tests\": \"c0c5a2e2934d69861f3d5c9aaac6fe979d197c7e\", \"browser_tests\": \"4bf048a58e082acc6e01c8f9a63abc131562eec6\", \"content_browsertests\": \"e15cc94a31b16e32ffb2c8a7e3d6a62741ceb89b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_70d81bd75c8665fc1e9b8928c4ede9b8726627f1f0cc1bd77b1f6c94d85edb40_v2\", \"builder_de45c485d8a81e16b8ff53eb76d94467eed5e11e118465311a13e72e2b141cb1_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-78-reme\"], \"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\": [\"5564-e41b668\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"5 Test Suite(s) failed.\\n\\n**vulkan_swiftshader_blink_web_tests** failed.\\n\\n**non_skia_renderer_swiftshader_blink_web_tests** failed.\\n\\n**skia_renderer_content_browsertests** failed.\\n\\n**vulkan_swiftshader_content_browsertests** failed.\\n\\n**non_skia_renderer_browser_tests** failed.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1601529946404086",
"status": "COMPLETED",
"status_changed_ts": "1601529946893654",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24101",
"builder:Linux Viz",
"buildset:commit/git/849a4f2762b4518542abda8267d6e7d792069415",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/849a4f2762b4518542abda8267d6e7d792069415",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033097962185520144",
"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/8867667900216309184/+/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:4efb7074a01ed510",
"user_agent:luci-scheduler"
],
"updated_ts": "1601538029576257",
"url": "https://ci.chromium.org/b/8867667900216309184",
"utcnow_ts": "1601538030454701"
}
}
[07:40:30.484] Invocation finished in 2h15m32.39405074s with status FAILED