[00:42:54.706] New invocation is queued and will start shortly
[00:42:56.121] Starting the invocation (attempt 1)
[00:42:56.160] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:42:56.161] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:42:56.161] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018529596064979264",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3dd320947ff3743c10244df762e9091d5785821b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018529596064979264",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/3dd320947ff3743c10244df762e9091d5785821b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3dd320947ff3743c10244df762e9091d5785821b",
"gitiles_ref:refs/heads/master"
]
}
[00:42:58.276] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615423376353955",
"id": "8853099533675899856",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3dd320947ff3743c10244df762e9091d5785821b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615423378040575",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46062",
"builder:linux-chromeos-rel",
"buildset:commit/git/3dd320947ff3743c10244df762e9091d5785821b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3dd320947ff3743c10244df762e9091d5785821b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018529596064979264",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853099533675899856/+/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": "1615423378040760",
"url": "https://ci.chromium.org/b/8853099533675899856",
"utcnow_ts": "1615423378263751"
}
}
[00:42:58.276] Task URL: https://ci.chromium.org/b/8853099533675899856
[00:42:58.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:2:0) after 1m0s
[00:43:58.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:2:0)
[00:43:58.343] Timer tick, asking Buildbucket for the build status
[00:43:58.472] Build 8853099533675899856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:58.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:3:0) after 1m0s
[00:44:55.396] Received PubSub notification, asking Buildbucket for the build status
[00:44:55.493] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:58.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:3:0)
[00:44:58.440] Timer tick, asking Buildbucket for the build status
[00:44:58.539] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:58.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:5:0) after 1m0s
[00:45:58.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:5:0)
[00:45:58.585] Timer tick, asking Buildbucket for the build status
[00:45:58.755] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:58.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:6:0) after 1m0s
[00:46:58.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:6:0)
[00:46:58.835] Timer tick, asking Buildbucket for the build status
[00:46:59.241] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:59.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:7:0) after 1m0s
[00:47:59.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:7:0)
[00:47:59.417] Timer tick, asking Buildbucket for the build status
[00:47:59.528] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:59.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:8:0) after 1m0s
[00:48:59.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:8:0)
[00:48:59.611] Timer tick, asking Buildbucket for the build status
[00:48:59.676] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:59.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:9:0) after 1m0s
[00:49:59.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:9:0)
[00:49:59.742] Timer tick, asking Buildbucket for the build status
[00:49:59.807] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:59.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:10:0) after 1m0s
[00:51:00.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:10:0)
[00:51:00.007] Timer tick, asking Buildbucket for the build status
[00:51:00.152] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:00.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:11:0) after 1m0s
[00:52:00.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:11:0)
[00:52:00.164] Timer tick, asking Buildbucket for the build status
[00:52:00.238] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:00.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:12:0) after 1m0s
[00:53:00.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:12:0)
[00:53:00.273] Timer tick, asking Buildbucket for the build status
[00:53:00.629] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:00.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:13:0) after 1m0s
[00:54:00.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:13:0)
[00:54:00.691] Timer tick, asking Buildbucket for the build status
[00:54:00.769] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:00.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:14:0) after 1m0s
[00:55:00.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:14:0)
[00:55:00.916] Timer tick, asking Buildbucket for the build status
[00:55:01.002] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:01.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:15:0) after 1m0s
[00:56:01.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:15:0)
[00:56:01.219] Timer tick, asking Buildbucket for the build status
[00:56:01.328] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:01.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:16:0) after 1m0s
[00:57:01.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:16:0)
[00:57:01.378] Timer tick, asking Buildbucket for the build status
[00:57:01.525] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:01.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:17:0) after 1m0s
[00:58:01.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:17:0)
[00:58:01.650] Timer tick, asking Buildbucket for the build status
[00:58:01.746] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:01.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:18:0) after 1m0s
[00:59:01.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:18:0)
[00:59:01.803] Timer tick, asking Buildbucket for the build status
[00:59:02.246] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:02.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:19:0) after 1m0s
[01:00:02.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:19:0)
[01:00:02.276] Timer tick, asking Buildbucket for the build status
[01:00:02.511] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:02.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:20:0) after 1m0s
[01:01:02.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:20:0)
[01:01:02.856] Timer tick, asking Buildbucket for the build status
[01:01:02.991] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:02.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:21:0) after 1m0s
[01:02:02.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:21:0)
[01:02:02.965] Timer tick, asking Buildbucket for the build status
[01:02:03.043] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:03.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:22:0) after 1m0s
[01:03:03.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:22:0)
[01:03:03.122] Timer tick, asking Buildbucket for the build status
[01:03:03.461] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:03.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:23:0) after 1m0s
[01:04:03.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:23:0)
[01:04:03.490] Timer tick, asking Buildbucket for the build status
[01:04:03.595] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:03.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:24:0) after 1m0s
[01:05:03.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:24:0)
[01:05:03.641] Timer tick, asking Buildbucket for the build status
[01:05:03.718] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:03.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:25:0) after 1m0s
[01:06:03.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:25:0)
[01:06:03.739] Timer tick, asking Buildbucket for the build status
[01:06:03.821] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:03.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:26:0) after 1m0s
[01:07:03.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:26:0)
[01:07:03.844] Timer tick, asking Buildbucket for the build status
[01:07:04.230] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:04.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:27:0) after 1m0s
[01:08:04.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:27:0)
[01:08:04.287] Timer tick, asking Buildbucket for the build status
[01:08:04.378] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:04.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:28:0) after 1m0s
[01:09:04.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:28:0)
[01:09:04.423] Timer tick, asking Buildbucket for the build status
[01:09:04.495] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:04.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:29:0) after 1m0s
[01:10:04.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:29:0)
[01:10:04.498] Timer tick, asking Buildbucket for the build status
[01:10:04.915] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:04.915] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:30:0) after 1m0s
[01:11:05.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:30:0)
[01:11:05.019] Timer tick, asking Buildbucket for the build status
[01:11:05.139] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:05.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:31:0) after 1m0s
[01:12:05.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:31:0)
[01:12:05.241] Timer tick, asking Buildbucket for the build status
[01:12:05.312] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:05.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:32:0) after 1m0s
[01:13:05.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:32:0)
[01:13:05.401] Timer tick, asking Buildbucket for the build status
[01:13:05.481] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:05.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:33:0) after 1m0s
[01:14:05.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:33:0)
[01:14:05.582] Timer tick, asking Buildbucket for the build status
[01:14:05.683] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:05.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:34:0) after 1m0s
[01:15:05.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:34:0)
[01:15:05.750] Timer tick, asking Buildbucket for the build status
[01:15:05.831] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:05.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:35:0) after 1m0s
[01:16:05.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:35:0)
[01:16:05.895] Timer tick, asking Buildbucket for the build status
[01:16:06.149] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:06.149] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:36:0) after 1m0s
[01:17:06.180] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:36:0)
[01:17:06.180] Timer tick, asking Buildbucket for the build status
[01:17:06.406] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:06.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:37:0) after 1m0s
[01:18:06.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:37:0)
[01:18:06.499] Timer tick, asking Buildbucket for the build status
[01:18:06.667] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:06.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:38:0) after 1m0s
[01:19:06.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:38:0)
[01:19:06.723] Timer tick, asking Buildbucket for the build status
[01:19:07.025] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:07.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:39:0) after 1m0s
[01:20:07.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:39:0)
[01:20:07.030] Timer tick, asking Buildbucket for the build status
[01:20:07.112] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:07.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:40:0) after 1m0s
[01:21:07.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:40:0)
[01:21:07.157] Timer tick, asking Buildbucket for the build status
[01:21:07.306] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:07.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:41:0) after 1m0s
[01:22:07.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:41:0)
[01:22:07.829] Timer tick, asking Buildbucket for the build status
[01:22:08.022] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:08.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:42:0) after 1m0s
[01:23:08.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:42:0)
[01:23:08.061] Timer tick, asking Buildbucket for the build status
[01:23:08.812] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:08.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:43:0) after 1m0s
[01:24:08.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:43:0)
[01:24:08.901] Timer tick, asking Buildbucket for the build status
[01:24:08.993] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:08.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:44:0) after 1m0s
[01:25:09.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:44:0)
[01:25:09.036] Timer tick, asking Buildbucket for the build status
[01:25:09.146] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:09.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:45:0) after 1m0s
[01:26:09.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:45:0)
[01:26:09.204] Timer tick, asking Buildbucket for the build status
[01:26:09.347] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:09.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:46:0) after 1m0s
[01:27:09.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:46:0)
[01:27:09.383] Timer tick, asking Buildbucket for the build status
[01:27:09.461] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:09.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:47:0) after 1m0s
[01:28:09.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:47:0)
[01:28:09.572] Timer tick, asking Buildbucket for the build status
[01:28:09.668] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:09.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:48:0) after 1m0s
[01:29:09.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:48:0)
[01:29:09.787] Timer tick, asking Buildbucket for the build status
[01:29:10.022] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:10.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:49:0) after 1m0s
[01:30:10.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:49:0)
[01:30:10.166] Timer tick, asking Buildbucket for the build status
[01:30:10.274] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:10.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:50:0) after 1m0s
[01:31:10.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:50:0)
[01:31:10.398] Timer tick, asking Buildbucket for the build status
[01:31:10.747] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:10.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:51:0) after 1m0s
[01:32:10.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:51:0)
[01:32:10.812] Timer tick, asking Buildbucket for the build status
[01:32:11.308] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:11.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:52:0) after 1m0s
[01:33:11.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:52:0)
[01:33:11.382] Timer tick, asking Buildbucket for the build status
[01:33:11.483] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:11.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:53:0) after 1m0s
[01:34:11.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:53:0)
[01:34:11.498] Timer tick, asking Buildbucket for the build status
[01:34:11.638] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:11.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:54:0) after 1m0s
[01:35:11.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:54:0)
[01:35:11.784] Timer tick, asking Buildbucket for the build status
[01:35:11.861] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:11.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:55:0) after 1m0s
[01:36:11.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:55:0)
[01:36:11.936] Timer tick, asking Buildbucket for the build status
[01:36:12.007] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:12.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:56:0) after 1m0s
[01:37:12.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:56:0)
[01:37:12.073] Timer tick, asking Buildbucket for the build status
[01:37:12.156] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:12.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:57:0) after 1m0s
[01:38:12.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:57:0)
[01:38:12.265] Timer tick, asking Buildbucket for the build status
[01:38:12.379] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:12.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:58:0) after 1m0s
[01:39:12.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:58:0)
[01:39:12.424] Timer tick, asking Buildbucket for the build status
[01:39:12.621] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:12.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:59:0) after 1m0s
[01:40:12.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:59:0)
[01:40:12.649] Timer tick, asking Buildbucket for the build status
[01:40:12.730] Build 8853099533675899856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:12.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018529596064979264:60:0) after 1m0s
[01:40:41.854] Received PubSub notification, asking Buildbucket for the build status
[01:40:41.930] Build 8853099533675899856: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:40:41.930] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615426841234315",
"created_by": "project:chromium",
"created_ts": "1615423376353955",
"failure_reason": "BUILD_FAILURE",
"id": "8853099533675899856",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3dd320947ff3743c10244df762e9091d5785821b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- SplitViewOverviewSessionInClamshellTest.BothSnappedOverviewExitAnimationHistogramTest\"}, \"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\": \"3dd320947ff3743c10244df762e9091d5785821b\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-99-01s3\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46062, \"got_angle_revision\": \"331640e5c19e63209de0307ae37dc1ec69a1f4e0\", \"got_dawn_revision\": \"6d4617a2d28d8d3d4aa946abdbabb368d025b6b7\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"3dd320947ff3743c10244df762e9091d5785821b\", \"got_revision_cp\": \"refs/heads/master@{#861782}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a2fe8e1b2111e69d8846991f9d9bcc5c49505d1c\", \"got_v8_revision\": \"380d3d23acddd8b6eb5521fbf5cf30b9ff8ac301\", \"got_v8_revision_cp\": \"refs/heads/9.1.95@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3dd320947ff3743c10244df762e9091d5785821b\", \"swarm_hashes_refs/heads/master(at){#861782}_without_patch\": {\"absl_hardening_tests\": \"0b564998a45816dc9eed088e03e2dfd58a930189\", \"accessibility_unittests\": \"6faf3cfa0ed94935b6972141cabac43bf67603bd\", \"angle_unittests\": \"0c36c7e06b02a0f253bd51236706c825e912ceba\", \"app_list_unittests\": \"c5391c4250155e39004e2b77034836432abc096e\", \"app_shell_unittests\": \"1fd960ee9b3827bfe38ae2d2f4b551db6789f33b\", \"ash_unittests\": \"bd6a0a8df795200d8fe6542d0ffc78537f8782c5\", \"aura_unittests\": \"0e901920c270e56fa4a5a34946e664ffa73b94a1\", \"base_unittests\": \"9a9a7f1ef548232f7685232e4adb46a1c0a4aed0\", \"base_util_unittests\": \"230220ac551cb2cae5226f172fc149f5e0444892\", \"blink_common_unittests\": \"537cf6699abcac8b890278d6140656ca0fdd2c64\", \"blink_fuzzer_unittests\": \"4f2c54214b6e73ee33a33eb495b58a9a5520545a\", \"blink_heap_unittests\": \"7e9130db6c956233b18acd109a0798451ff27700\", \"blink_platform_unittests\": \"2aed8da622a77de5e271aaebab6e89a0ea6da358\", \"blink_unittests\": \"af2cb0771669e99308ff77af2382a8cb93f26afe\", \"boringssl_crypto_tests\": \"d58260ff0e1803d3d1f2d98ad640568ec12fc130\", \"boringssl_ssl_tests\": \"4d46b211134fd695e79ff1dd1112b7877dad6231\", \"browser_tests\": \"19a23ea8087f40a4b014d52aea0ea02b212295a7\", \"capture_unittests\": \"b0ae2a162c45280ee11dbe618961bdea1fed5823\", \"cast_unittests\": \"689aae421ae9747684864fd1911bcd2c4c582663\", \"cc_unittests\": \"184e27f3da75576fc75f40a785248a9b67b2ccb4\", \"chrome_app_unittests\": \"1c11e17a4817f20b9ff6b33b3b7b1622d7654d30\", \"chromedriver_unittests\": \"4b3a8053a2d6dfb6f974827d88d95c1686196cbb\", \"chromeos_components_unittests\": \"d81f6658628842805078c6975dcb7d113b8abc12\", \"chromeos_unittests\": \"e1b5b2071f46910540efa68e0420aa8ee7fa0743\", \"color_unittests\": \"59033fc5b54383727346d52024af8f3e1e4448e4\", \"components_browsertests\": \"b4b82798d9bdf71ef7caaf907571601c7ed12643\", \"components_unittests\": \"626ddf56a8fc64edbe47a1126f6aed501cc7591a\", \"compositor_unittests\": \"6a60d5e7a1521edf2ea6f34ab1bec963c2700eee\", \"content_browsertests\": \"f8917c15bf35fda3e3fcedb213defc57c72b394b\", \"content_unittests\": \"03f84ebefde728a99f8d6c9c1e930e6dad8f9f21\", \"crashpad_tests\": \"6e45ece0525eb708cd089dee43055dc070957d0c\", \"crypto_unittests\": \"bdaebc4e07994c31cb6dc939abd1fc3f74023030\", \"dbus_unittests\": \"ec5426ceb55f096ef41a93b4e9260f932f9ba385\", \"device_unittests\": \"990c03c6d49cd3cc7cc454d9799f34888f520559\", \"display_unittests\": \"179822b735dd57bc41322c6a05832f70f69be83a\", \"events_unittests\": \"2d1a15914f64eb9e27e80ce1c87a019dfbc08837\", \"exo_unittests\": \"6db2163fde06c8978f02eca3705297ef5d31c338\", \"extensions_browsertests\": \"eb88b128ed6fb19337147ca136c0a543c69b189e\", \"extensions_unittests\": \"fa7c235e1b6e707e0bd1a10aab6c04ab720aa12a\", \"filesystem_service_unittests\": \"82284bcd834bdec2219fa19fc7c5aa3d8af71c1e\", \"gcm_unit_tests\": \"bad9100eeb13d7af02999b8c82b9d5cbf6dd6529\", \"gfx_unittests\": \"ee4228f53de5664a0ea3ca5b72160a8d433e35a0\", \"gin_unittests\": \"574333f8a5d030da820fee950a5b3b25ae7b3be8\", \"gl_unittests_ozone\": \"5cfb0468d024982ea67b346882e19da1eb8a32cb\", \"google_apis_unittests\": \"a4a0773c1a23ad4465cb7e5531fe82b71f3dc98e\", \"gpu_unittests\": \"2fd17602201e1c2fa6ac737bde2a77b8e11729f1\", \"gwp_asan_unittests\": \"2086f32192d7f46723bd5f2e4c263cb0ef9d6cb4\", \"interactive_ui_tests\": \"fad7386c21c65d302b1c8f08f849be536508efc1\", \"ipc_tests\": \"1995851ac36e507cf0bb89ea390d053997ed3fb9\", \"jingle_unittests\": \"1b4c3d5f1d1301f9006dff8ae5a3b5dc180c954b\", \"keyboard_unittests\": \"062d6a782d7be7c48379fadfa9b2937b55ff4341\", \"latency_unittests\": \"5f9f2b93fb8661accf1d7e58f7d2ae0beff014c9\", \"libjingle_xmpp_unittests\": \"2410198752c14aea9e833ed00e7c999c17044414\", \"liburlpattern_unittests\": \"75ee5e347f8531d15301a9a4d3e27e87c29095de\", \"media_blink_unittests\": \"e05e76556bbffed8fe4a07711e2d8605456ec70d\", \"media_unittests\": \"74478232cee6ff6f2dc713bc5977567bd0194d45\", \"message_center_unittests\": \"a520f26c21987f147515389134e2df84eb244545\", \"midi_unittests\": \"9979884fb2e46f7167e76af14b7b2baf4b7cb041\", \"mojo_core_unittests\": \"6d4cc334035dd8ea154fc7edad978beb635ff5ce\", \"mojo_unittests\": \"1f2f1145b116b2dd5bf5cff491d4642c4f19b1aa\", \"nacl_helper_nonsfi_unittests\": \"0bac56c836e5c7284c62221ca59df992fcfccd50\", \"nacl_loader_unittests\": \"59183f9805cda052831e098aa4c4d99ac3e568d7\", \"native_theme_unittests\": \"1ac3dc08ca4815fde5ea3290f6488901ff8f71c7\", \"net_unittests\": \"691bbd785715430af8ced5ebbe2cbef11fa5b38d\", \"ozone_gl_unittests\": \"2213aade1f5cc586e72e30a93adc7f66f00140ed\", \"ozone_unittests\": \"dcbeb5d4137ee2f79f23fbc60b269a5da71d179c\", \"ozone_x11_unittests\": \"0689fa531ff7fedebd0d54146b6b506e1880c0c1\", \"pdf_unittests\": \"fbead841d69df7cb7ac6728ec308c8936f9d1350\", \"perfetto_unittests\": \"f1e534c2086c46dfeb1f624de532f5df6e001495\", \"ppapi_unittests\": \"8ba4505dca0aae35ca135beaaa3d42d5ef88598a\", \"printing_unittests\": \"1a0c4477d70ea5fb220961a2bc3c07a048e72543\", \"remoting_unittests\": \"c55d2d218281655db5dd0e3e1832ffc8285caecb\", \"sandbox_linux_unittests\": \"c53911fe07db6ce7991d67e1d75ce934ca9aff5a\", \"service_manager_unittests\": \"de13ce9b97b1e6160775688839eef54b518fde33\", \"services_unittests\": \"04dc778f1b557346dca40a934c5d64fc598125cf\", \"shell_dialogs_unittests\": \"9041ef36d560979bf6007fc1b9a34d8878547041\", \"shell_encryption_unittests\": \"092c74fea90136c21f6c8a53e62371719e1d8f5a\", \"skia_unittests\": \"333455d72d88a4eb4afa68d9d37d58987c547dee\", \"snapshot_unittests\": \"d45f10a3a7e6d9a7dc8daed8011717c79840faf4\", \"sql_unittests\": \"4fcf7ae1064ef2383bdee95ef280eda90da62578\", \"storage_unittests\": \"61da73505e24d4e7b7b4e1fa942215265f21c24b\", \"sync_integration_tests\": \"494cd8226fbb6fcb9d886602723c11f180a4a4ff\", \"traffic_annotation_auditor_unittests\": \"fe831477ab00ee9d1cac1b9ce576d86c3cbbb4cb\", \"ui_base_unittests\": \"a4b5c68ad2f8ec2e847dc8efd4f7e7a0c0bfb1e5\", \"ui_chromeos_unittests\": \"26d31081078351a4ba24e459765cb3337cdf279c\", \"ui_touch_selection_unittests\": \"c3a516a6ddaa1240e7b4c93f173b3df1cb107a41\", \"unit_tests\": \"2093c01a9097991604546a70763db421c67fa837\", \"url_unittests\": \"687559de44cc6ff489521f48f563b11d1b6885ff\", \"usage_time_limit_unittests\": \"bf74dbd35d4a1318cdbc01ba152f0d9f588f65ac\", \"views_unittests\": \"8114967301e288fcb1612e1a8a4685e669d45832\", \"viz_unittests\": \"934d8266123b2f16401c6d8a1f0900c53ca56d7b\", \"wayland_client_perftests\": \"6e603ffb6aab954e6f0882f81aaf85c8ff48f263\", \"wayland_client_tests\": \"45efbda093aced8884411db8b90bf99d789cf249\", \"wm_unittests\": \"9643ae0cd19361413265d4ff7f040aaefc3f878e\", \"wtf_unittests\": \"52bbb858546e3ce623b00a884eff0d3384ee4a46\", \"zlib_unittests\": \"4f2728bf9ddc0ab6d619bc9e6336a004d19c14b8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_000e1c4f189e74dd1a526f6a0536d3e0149cd531063dba85a70e270d680dde3a_v2\", \"builder_0b12ebe7b5a85484d05a2e374fff062d76e5173f31df9c66ec374db61ba113e3_v2\", \"builder_71ab4550ecc1db180544ee9bd5ebac58222d39ccc6e59d756fcde46903b3067c_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a429a0546f8e6c4723d24c56a52324080ff34901f86bcb95ca025de078e0f0c5_v2\", \"builder_a6d905fb3e765cf980c6458181cc20495ddb667b30aa758edddbed3b46166dd6_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-99-01s3\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- SplitViewOverviewSessionInClamshellTest.BothSnappedOverviewExitAnimationHistogramTest\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615423494563581",
"status": "COMPLETED",
"status_changed_ts": "1615423495006726",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46062",
"builder:linux-chromeos-rel",
"buildset:commit/git/3dd320947ff3743c10244df762e9091d5785821b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3dd320947ff3743c10244df762e9091d5785821b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018529596064979264",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8853099533675899856/+/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:52378e1c6939a810",
"user_agent:luci-scheduler"
],
"updated_ts": "1615426841234647",
"url": "https://ci.chromium.org/b/8853099533675899856",
"utcnow_ts": "1615426841922441"
}
}
[01:40:41.930] Invocation finished in 57m47.23947521s with status FAILED