[08:08:55.231] New invocation is queued and will start shortly
[08:08:56.414] Starting the invocation (attempt 1)
[08:08:56.461] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:08:56.462] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:08:56.462] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017776759891774208",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"92913f0f6320ea47d905790c51a6906731244026\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9017776759891774208",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/92913f0f6320ea47d905790c51a6906731244026",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/92913f0f6320ea47d905790c51a6906731244026",
"gitiles_ref:refs/heads/master"
]
}
[08:08:58.211] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616141336590846",
"id": "8852346697801667392",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"92913f0f6320ea47d905790c51a6906731244026\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616141337001889",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46266",
"builder:linux-chromeos-rel",
"buildset:commit/git/92913f0f6320ea47d905790c51a6906731244026",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/92913f0f6320ea47d905790c51a6906731244026",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017776759891774208",
"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/8852346697801667392/+/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": "1616141337002056",
"url": "https://ci.chromium.org/b/8852346697801667392",
"utcnow_ts": "1616141338192756"
}
}
[08:08:58.211] Task URL: https://ci.chromium.org/b/8852346697801667392
[08:08:58.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:2:0) after 1m0s
[08:09:07.214] Received PubSub notification, asking Buildbucket for the build status
[08:09:07.444] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:58.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:2:0)
[08:09:58.335] Timer tick, asking Buildbucket for the build status
[08:09:58.568] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:58.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:4:0) after 1m0s
[08:10:58.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:4:0)
[08:10:58.640] Timer tick, asking Buildbucket for the build status
[08:10:58.713] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:58.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:5:0) after 1m0s
[08:11:58.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:5:0)
[08:11:58.757] Timer tick, asking Buildbucket for the build status
[08:11:59.075] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:59.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:6:0) after 1m0s
[08:12:59.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:6:0)
[08:12:59.120] Timer tick, asking Buildbucket for the build status
[08:12:59.185] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:59.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:7:0) after 1m0s
[08:13:59.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:7:0)
[08:13:59.254] Timer tick, asking Buildbucket for the build status
[08:13:59.541] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:59.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:8:0) after 1m0s
[08:14:59.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:8:0)
[08:14:59.555] Timer tick, asking Buildbucket for the build status
[08:14:59.655] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:59.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:9:0) after 1m0s
[08:15:59.738] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:9:0)
[08:15:59.738] Timer tick, asking Buildbucket for the build status
[08:15:59.796] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:59.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:10:0) after 1m0s
[08:16:59.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:10:0)
[08:16:59.875] Timer tick, asking Buildbucket for the build status
[08:17:00.276] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:00.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:11:0) after 1m0s
[08:18:00.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:11:0)
[08:18:00.298] Timer tick, asking Buildbucket for the build status
[08:18:00.363] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:00.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:12:0) after 1m0s
[08:19:00.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:12:0)
[08:19:00.408] Timer tick, asking Buildbucket for the build status
[08:19:00.759] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:00.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:13:0) after 1m0s
[08:20:00.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:13:0)
[08:20:00.806] Timer tick, asking Buildbucket for the build status
[08:20:00.872] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:00.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:14:0) after 1m0s
[08:21:00.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:14:0)
[08:21:00.872] Timer tick, asking Buildbucket for the build status
[08:21:01.034] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:01.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:15:0) after 1m0s
[08:22:01.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:15:0)
[08:22:01.070] Timer tick, asking Buildbucket for the build status
[08:22:01.164] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:01.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:16:0) after 1m0s
[08:23:01.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:16:0)
[08:23:01.206] Timer tick, asking Buildbucket for the build status
[08:23:01.275] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:01.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:17:0) after 1m0s
[08:24:01.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:17:0)
[08:24:01.281] Timer tick, asking Buildbucket for the build status
[08:24:01.998] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:01.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:18:0) after 1m0s
[08:25:02.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:18:0)
[08:25:02.061] Timer tick, asking Buildbucket for the build status
[08:25:02.213] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:02.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:19:0) after 1m0s
[08:26:02.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:19:0)
[08:26:02.215] Timer tick, asking Buildbucket for the build status
[08:26:02.282] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:02.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:20:0) after 1m0s
[08:27:02.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:20:0)
[08:27:02.280] Timer tick, asking Buildbucket for the build status
[08:27:02.435] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:02.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:21:0) after 1m0s
[08:28:02.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:21:0)
[08:28:02.438] Timer tick, asking Buildbucket for the build status
[08:28:02.505] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:02.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:22:0) after 1m0s
[08:29:02.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:22:0)
[08:29:02.588] Timer tick, asking Buildbucket for the build status
[08:29:02.926] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:02.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:23:0) after 1m0s
[08:30:02.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:23:0)
[08:30:02.988] Timer tick, asking Buildbucket for the build status
[08:30:03.099] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:03.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:24:0) after 1m0s
[08:31:03.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:24:0)
[08:31:03.141] Timer tick, asking Buildbucket for the build status
[08:31:03.213] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:03.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:25:0) after 1m0s
[08:32:03.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:25:0)
[08:32:03.359] Timer tick, asking Buildbucket for the build status
[08:32:03.604] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:03.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:26:0) after 1m0s
[08:33:03.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:26:0)
[08:33:03.632] Timer tick, asking Buildbucket for the build status
[08:33:03.807] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:03.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:27:0) after 1m0s
[08:34:03.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:27:0)
[08:34:03.833] Timer tick, asking Buildbucket for the build status
[08:34:03.917] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:03.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:28:0) after 1m0s
[08:35:03.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:28:0)
[08:35:03.998] Timer tick, asking Buildbucket for the build status
[08:35:04.179] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:04.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:29:0) after 1m0s
[08:36:04.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:29:0)
[08:36:04.285] Timer tick, asking Buildbucket for the build status
[08:36:04.864] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:04.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:30:0) after 1m0s
[08:37:04.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:30:0)
[08:37:04.931] Timer tick, asking Buildbucket for the build status
[08:37:04.991] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:04.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:31:0) after 1m0s
[08:38:05.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:31:0)
[08:38:05.077] Timer tick, asking Buildbucket for the build status
[08:38:05.141] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:05.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:32:0) after 1m0s
[08:39:05.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:32:0)
[08:39:05.245] Timer tick, asking Buildbucket for the build status
[08:39:05.397] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:05.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:33:0) after 1m0s
[08:40:05.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:33:0)
[08:40:05.411] Timer tick, asking Buildbucket for the build status
[08:40:05.485] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:05.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:34:0) after 1m0s
[08:41:05.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:34:0)
[08:41:05.502] Timer tick, asking Buildbucket for the build status
[08:41:05.605] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:05.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:35:0) after 1m0s
[08:42:05.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:35:0)
[08:42:05.785] Timer tick, asking Buildbucket for the build status
[08:42:05.857] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:05.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:36:0) after 1m0s
[08:43:05.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:36:0)
[08:43:05.930] Timer tick, asking Buildbucket for the build status
[08:43:05.987] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:05.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:37:0) after 1m0s
[08:44:06.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:37:0)
[08:44:06.046] Timer tick, asking Buildbucket for the build status
[08:44:06.228] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:06.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:38:0) after 1m0s
[08:45:06.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:38:0)
[08:45:06.212] Timer tick, asking Buildbucket for the build status
[08:45:06.283] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:06.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:39:0) after 1m0s
[08:46:06.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:39:0)
[08:46:06.311] Timer tick, asking Buildbucket for the build status
[08:46:06.373] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:06.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:40:0) after 1m0s
[08:47:06.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:40:0)
[08:47:06.410] Timer tick, asking Buildbucket for the build status
[08:47:06.478] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:06.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:41:0) after 1m0s
[08:48:06.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:41:0)
[08:48:06.565] Timer tick, asking Buildbucket for the build status
[08:48:06.738] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:06.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:42:0) after 1m0s
[08:49:06.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:42:0)
[08:49:06.839] Timer tick, asking Buildbucket for the build status
[08:49:07.033] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:07.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:43:0) after 1m0s
[08:50:07.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:43:0)
[08:50:07.168] Timer tick, asking Buildbucket for the build status
[08:50:07.234] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:07.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:44:0) after 1m0s
[08:51:07.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:44:0)
[08:51:07.331] Timer tick, asking Buildbucket for the build status
[08:51:07.396] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:07.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:45:0) after 1m0s
[08:52:07.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:45:0)
[08:52:07.480] Timer tick, asking Buildbucket for the build status
[08:52:07.544] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:07.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:46:0) after 1m0s
[08:53:07.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:46:0)
[08:53:07.552] Timer tick, asking Buildbucket for the build status
[08:53:07.620] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:07.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:47:0) after 1m0s
[08:54:07.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:47:0)
[08:54:07.739] Timer tick, asking Buildbucket for the build status
[08:54:07.823] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:07.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:48:0) after 1m0s
[08:55:07.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:48:0)
[08:55:07.888] Timer tick, asking Buildbucket for the build status
[08:55:07.975] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:07.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:49:0) after 1m0s
[08:56:08.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:49:0)
[08:56:08.017] Timer tick, asking Buildbucket for the build status
[08:56:08.083] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:08.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:50:0) after 1m0s
[08:57:08.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:50:0)
[08:57:08.125] Timer tick, asking Buildbucket for the build status
[08:57:08.243] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:08.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:51:0) after 1m0s
[08:58:08.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:51:0)
[08:58:08.267] Timer tick, asking Buildbucket for the build status
[08:58:08.337] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:08.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:52:0) after 1m0s
[08:59:08.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:52:0)
[08:59:08.359] Timer tick, asking Buildbucket for the build status
[08:59:08.754] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:08.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:53:0) after 1m0s
[09:00:08.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:53:0)
[09:00:08.762] Timer tick, asking Buildbucket for the build status
[09:00:08.890] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:08.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:54:0) after 1m0s
[09:01:08.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:54:0)
[09:01:08.906] Timer tick, asking Buildbucket for the build status
[09:01:09.031] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:09.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:55:0) after 1m0s
[09:02:09.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:55:0)
[09:02:09.048] Timer tick, asking Buildbucket for the build status
[09:02:09.130] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:09.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:56:0) after 1m0s
[09:03:09.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:56:0)
[09:03:09.175] Timer tick, asking Buildbucket for the build status
[09:03:09.243] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:09.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:57:0) after 1m0s
[09:04:09.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:57:0)
[09:04:09.324] Timer tick, asking Buildbucket for the build status
[09:04:17.009] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:17.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:58:0) after 1m0s
[09:05:17.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:58:0)
[09:05:17.029] Timer tick, asking Buildbucket for the build status
[09:05:17.507] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:17.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:59:0) after 1m0s
[09:06:17.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:59:0)
[09:06:17.556] Timer tick, asking Buildbucket for the build status
[09:06:17.975] Build 8852346697801667392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:17.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017776759891774208:60:0) after 1m0s
[09:07:13.150] Received PubSub notification, asking Buildbucket for the build status
[09:07:13.542] Build 8852346697801667392: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:07:13.542] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616144832751923",
"created_by": "project:chromium",
"created_ts": "1616141336590846",
"failure_reason": "BUILD_FAILURE",
"id": "8852346697801667392",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"92913f0f6320ea47d905790c51a6906731244026\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PageLoadMetricsBrowserTest.MainFrameIntersectionCrossOriginScrolled\"}, \"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\": \"92913f0f6320ea47d905790c51a6906731244026\", \"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-83-a7j4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46266, \"got_angle_revision\": \"ba0bd78574b21a297e22ee2f8ac1efdeff0a9026\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"92913f0f6320ea47d905790c51a6906731244026\", \"got_revision_cp\": \"refs/heads/master@{#864593}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"43315277e45e866e927722377d36b04ca3c1014b\", \"got_v8_revision_cp\": \"refs/heads/9.1.158@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"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\": \"92913f0f6320ea47d905790c51a6906731244026\", \"swarm_hashes_refs/heads/master(at){#864593}_without_patch\": {\"absl_hardening_tests\": \"a614ea13e0028045b408da9ee61e7fb144544386\", \"accessibility_unittests\": \"b0c0db6ee533733e17d41b6d7eaedb9cf28d1c33\", \"angle_unittests\": \"9442598c3e120e9cec9593b1f840a1692bd2a990\", \"app_list_unittests\": \"e957a1d4d18730b6175df4ff25a584e941d5158a\", \"app_shell_unittests\": \"a5d135fd9cb3d1715bd47af11b2a075e8fb12aed\", \"ash_unittests\": \"67394ee7f93f7454fec6f50465319b211b75b721\", \"aura_unittests\": \"1cf620c835158e76b8a8be8194065d2ba34bfe49\", \"base_unittests\": \"8ed8987fd6e9bba7dbc09ca48725fe9df0eb74e5\", \"base_util_unittests\": \"a096b503a128d8e04d9569b704986d1e62c1a470\", \"blink_common_unittests\": \"51884352844cc45b8bc1fa325c800752bd5732e2\", \"blink_fuzzer_unittests\": \"a79194d6c7f0f0a32a2af3eb98586fb49f4c2935\", \"blink_heap_unittests\": \"2c3cd597f2142ac28684790b28bebd760f83f846\", \"blink_platform_unittests\": \"1bf00965a786919e80fa2ccceac1cb8658b82741\", \"blink_unittests\": \"591506d201a5febda8c8f7193624e730b223a159\", \"boringssl_crypto_tests\": \"9363d4367679469f5848081ebe88d8d49dfe5e09\", \"boringssl_ssl_tests\": \"d484d6f6b7dd3a92d80d183d7a2fd7b4f032bd26\", \"browser_tests\": \"f4fe7202187a03efb2d8a87dd8b65113ddb2e764\", \"capture_unittests\": \"4c78655e3cde53e88cc52f28d5916443d70252fb\", \"cast_unittests\": \"33c535a9c4a511190aaf5eb8dc8b91748e3b56ce\", \"cc_unittests\": \"31d782d016da8803c5092d25013032fb5a0c1ba4\", \"chrome_app_unittests\": \"475e9966486909d5ee4c534a35bcba9acccfb713\", \"chromedriver_unittests\": \"9bea27f0dc04ff66aa6ce6576436c656d0a46535\", \"chromeos_components_unittests\": \"94ab48cc6bbcaf4cf5e6b280714f8d5599244455\", \"chromeos_unittests\": \"41d395d1bbff52de5f690e918c7e7e6ab4627b52\", \"color_unittests\": \"837a4acde0d2d363c53c37f19b8bd0d6fe6ba0be\", \"components_browsertests\": \"e9015e506042b122d18e2bfdee026901f11e52df\", \"components_unittests\": \"2db51267be2c9fb07f9203e5527ff2d4d20e0dfd\", \"compositor_unittests\": \"aa8209e45b2f3d4d093df1ff034e592cc72d0dc5\", \"content_browsertests\": \"a873d4b236c526dfc481a5a5f4a374ec11d39f6f\", \"content_unittests\": \"8d1a63558901d378fa40b9ac05d6d3b76c26e50f\", \"crashpad_tests\": \"2669b68eef4f4f2770b3fb98fbd6a354a0cd39ca\", \"crypto_unittests\": \"8cf170dce901ea2857e6e39c5af7415362b055d6\", \"dbus_unittests\": \"801f42e87bc9e1ce323aa388b74caaaad6da17b5\", \"device_unittests\": \"e6d74e5c34953fd4f3f382cef59e7de510c28e16\", \"display_unittests\": \"9722bbf6303584ebcde2ced74da31a5b63eb30ad\", \"events_unittests\": \"5e5f00fe69628a8878645246f21d605e11d165f8\", \"exo_unittests\": \"0d50ebd2b46fa482b05b45594d9f6bb6aaca76dc\", \"extensions_browsertests\": \"7326450fd0da6011bee24ca1b357a8af6cc4a8dc\", \"extensions_unittests\": \"09a82ad1b0119bebdc4178e013ec4eaaf1a0fda7\", \"filesystem_service_unittests\": \"8610125c6961e041f4e6cbb9f1109effe7480c73\", \"gcm_unit_tests\": \"16b3402737a0665c33cdb7dd01eeaf405adfbfe5\", \"gfx_unittests\": \"3650fc81cd4078a77d8dc8a7610d4aa72a445887\", \"gin_unittests\": \"98875c96cbd0d89d8ac1f4a586772ff811121ccd\", \"gl_unittests_ozone\": \"b53f9c07be7a95d8ccecdee37b17e945d5f3ad5d\", \"google_apis_unittests\": \"c39213275618b81b9140ba0c1abb325b43167ef8\", \"gpu_unittests\": \"ba44180aee105d2aee8536518946de2b0f5d1d4b\", \"gwp_asan_unittests\": \"8aced90ebb82a433233925a00bb82ccf0e82393a\", \"interactive_ui_tests\": \"ff41e47126e57ce49ae802efbbab245da30b42e9\", \"ipc_tests\": \"6307922877a85b67ceb9241f4561425642edaf62\", \"jingle_unittests\": \"47338ca7329976aca41c5ac3178ebc938e2f4d50\", \"keyboard_unittests\": \"a5928b1782bab97e623e6dfabb47c064483c8e00\", \"latency_unittests\": \"d352e2294418a6c08771c92eccb2c49208a02559\", \"libjingle_xmpp_unittests\": \"513c1c9f69a0998f2eae97f96cd9c22d6b46f288\", \"liburlpattern_unittests\": \"5bd3e7926ad5e890e436c923a20ec36fdeec5838\", \"media_blink_unittests\": \"0951731b38c82de3a153c3d153b1ae9fdddc9245\", \"media_unittests\": \"15f9e8a0347c5449a37b622667ecc23960080391\", \"message_center_unittests\": \"80d8d8be0feeed561a4110f5ec3f199390d3bf48\", \"midi_unittests\": \"d0fd30a0ab98aeacb59e8e601a7ed28b9f44d938\", \"mojo_core_unittests\": \"f05a9d6ae07f4360bcd4dde807b97ec82f260b39\", \"mojo_unittests\": \"98b1ae299b8c83db2f9b46eba4b55246a482b243\", \"nacl_helper_nonsfi_unittests\": \"2303090f5708d7498095081408f60c6bd23f52b5\", \"nacl_loader_unittests\": \"21b5d3ba553a986d55b0e1ac361a7c7f8055e363\", \"native_theme_unittests\": \"3bd023f785043206e86c2154443864fd9beac053\", \"net_unittests\": \"b04b0e1bb0bce4251aa4f7326cfa269b38937068\", \"ozone_gl_unittests\": \"af42614e96472940e79ad77c1ee05da41cca2b53\", \"ozone_unittests\": \"b2897be6d93d78c5fe43a1336c3b0299a2e246a0\", \"ozone_x11_unittests\": \"0315dac974ce13006a3af3eec99d7757b9c28218\", \"pdf_unittests\": \"b7c15adb24d90340845e9eb916416649ca2a50e3\", \"perfetto_unittests\": \"9ac370c82d12c5a24fa0b858cd798c9fec04edf3\", \"ppapi_unittests\": \"2491ab97fee8fb10e42f9c28317a2998a69b10e3\", \"printing_unittests\": \"26c09e047b0f63f362aa8da9dc2ff9b22ea0308e\", \"remoting_unittests\": \"1b46c5ba524a5b73c03bfb106135654b7dff6058\", \"sandbox_linux_unittests\": \"7b7241c40820941b25602b35297a6fc73f5c673a\", \"service_manager_unittests\": \"975675dc70c861b68dc37589060a8425cef17f6e\", \"services_unittests\": \"9b7bcc97bdf07309f3c2123ed89398337e4f1868\", \"shell_dialogs_unittests\": \"639bad3272bb79193731d3f1628d73df1b059841\", \"shell_encryption_unittests\": \"809372826de1b41bc9de13035f40d4782652fe69\", \"skia_unittests\": \"26cff6827d8b0f893b4d5f1562d187d1045bba01\", \"snapshot_unittests\": \"7fe1d7e2dd42de0bddced1987308396d55de6c82\", \"sql_unittests\": \"ea218693d060708ba235663de38f91625b53e278\", \"storage_unittests\": \"44cc057bbdfa4f31326d807e81e614253de70661\", \"sync_integration_tests\": \"22dc7640ff05116177f2ba48ccae0603771770be\", \"traffic_annotation_auditor_unittests\": \"03994f096babd6f52f1d0ef0a61ca90b5061d3fc\", \"ui_base_unittests\": \"6cc91f4262d400cbfa73fd44ae8f9ef6b1c04594\", \"ui_chromeos_unittests\": \"998d22b9fc569343e7150309f38112f9a381b10e\", \"ui_touch_selection_unittests\": \"2f845112077a910031655c34e8298614476b3221\", \"unit_tests\": \"3c5376673c7a89e74a05abc699a94fa1287ca5d0\", \"url_unittests\": \"6e1500b2047ca58c7adad752122fbd3db6298269\", \"usage_time_limit_unittests\": \"30061225d5ff7f96e67dcf329b0cd424c453dfb6\", \"views_unittests\": \"d51ebd89b60be0c5791e73ac1d9aae0b57f2c729\", \"viz_unittests\": \"57573992e2c472d34ecf7df2b7bc1a93c321b558\", \"wayland_client_perftests\": \"f3f4ba9474ed7364571a9cc840901e933cddf390\", \"wayland_client_tests\": \"8f5e86c7e9a25b23ed42de16754da6210c14222f\", \"wm_unittests\": \"2040d50cf00efe7f6b0f348bbe63e025db82e45a\", \"wtf_unittests\": \"7d32dae642bd972870f2ab59c8cd5fb7e05323db\", \"zlib_unittests\": \"08cb0979e5817cecf1302a088ef27548d20fcc9e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_28be8b4e850be8bd7a9db0671ff876681ccdf0c2c048860e172c20e0c8b9b79b_v2\", \"builder_69f2a046c912b41289f97ea97f722be7d6fdad062770d964bdaa123063be4a56_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_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-83-a7j4\"], \"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\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- PageLoadMetricsBrowserTest.MainFrameIntersectionCrossOriginScrolled\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616141346222522",
"status": "COMPLETED",
"status_changed_ts": "1616141346908712",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46266",
"builder:linux-chromeos-rel",
"buildset:commit/git/92913f0f6320ea47d905790c51a6906731244026",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/92913f0f6320ea47d905790c51a6906731244026",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017776759891774208",
"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/8852346697801667392/+/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:5262594e85c7ad10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616144832752320",
"url": "https://ci.chromium.org/b/8852346697801667392",
"utcnow_ts": "1616144833530818"
}
}
[09:07:13.542] Invocation finished in 58m18.327913422s with status FAILED