[00:26:38.922] New invocation is queued and will start shortly
[00:26:40.436] Starting the invocation (attempt 1)
[00:26:40.546] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:26:40.546] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:26:40.546] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028133897687068800",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d96563a1645a8acd747ce8fbb4b3cf85817be5bd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9028133897687068800",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/d96563a1645a8acd747ce8fbb4b3cf85817be5bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d96563a1645a8acd747ce8fbb4b3cf85817be5bd",
"gitiles_ref:refs/heads/master"
]
}
[00:26:41.504] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606264000723620",
"id": "8862703835136445920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d96563a1645a8acd747ce8fbb4b3cf85817be5bd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606264001210436",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43141",
"builder:linux-chromeos-rel",
"buildset:commit/git/d96563a1645a8acd747ce8fbb4b3cf85817be5bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d96563a1645a8acd747ce8fbb4b3cf85817be5bd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028133897687068800",
"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/8862703835136445920/+/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": "1606264001210759",
"url": "https://ci.chromium.org/b/8862703835136445920",
"utcnow_ts": "1606264001472767"
}
}
[00:26:41.504] Task URL: https://ci.chromium.org/b/8862703835136445920
[00:26:41.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:2:0) after 1m0s
[00:26:44.485] Received PubSub notification, asking Buildbucket for the build status
[00:26:44.594] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:41.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:2:0)
[00:27:41.610] Timer tick, asking Buildbucket for the build status
[00:27:41.722] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:41.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:4:0) after 1m0s
[00:28:41.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:4:0)
[00:28:41.772] Timer tick, asking Buildbucket for the build status
[00:28:42.113] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:42.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:5:0) after 1m0s
[00:29:42.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:5:0)
[00:29:42.175] Timer tick, asking Buildbucket for the build status
[00:29:42.412] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:42.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:6:0) after 1m0s
[00:30:42.533] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:6:0)
[00:30:42.533] Timer tick, asking Buildbucket for the build status
[00:30:42.706] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:42.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:7:0) after 1m0s
[00:31:42.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:7:0)
[00:31:42.734] Timer tick, asking Buildbucket for the build status
[00:31:42.817] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:42.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:8:0) after 1m0s
[00:32:42.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:8:0)
[00:32:42.818] Timer tick, asking Buildbucket for the build status
[00:32:42.880] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:42.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:9:0) after 1m0s
[00:33:42.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:9:0)
[00:33:42.896] Timer tick, asking Buildbucket for the build status
[00:33:42.956] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:42.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:10:0) after 1m0s
[00:34:43.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:10:0)
[00:34:43.031] Timer tick, asking Buildbucket for the build status
[00:34:43.404] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:43.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:11:0) after 1m0s
[00:35:43.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:11:0)
[00:35:43.442] Timer tick, asking Buildbucket for the build status
[00:35:43.841] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:43.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:12:0) after 1m0s
[00:36:43.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:12:0)
[00:36:43.874] Timer tick, asking Buildbucket for the build status
[00:36:43.990] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:43.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:13:0) after 1m0s
[00:37:44.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:13:0)
[00:37:44.136] Timer tick, asking Buildbucket for the build status
[00:37:44.211] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:44.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:14:0) after 1m0s
[00:38:44.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:14:0)
[00:38:44.189] Timer tick, asking Buildbucket for the build status
[00:38:44.332] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:44.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:15:0) after 1m0s
[00:39:44.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:15:0)
[00:39:44.358] Timer tick, asking Buildbucket for the build status
[00:39:44.457] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:44.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:16:0) after 1m0s
[00:40:44.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:16:0)
[00:40:44.476] Timer tick, asking Buildbucket for the build status
[00:40:44.542] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:44.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:17:0) after 1m0s
[00:41:44.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:17:0)
[00:41:44.550] Timer tick, asking Buildbucket for the build status
[00:41:44.913] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:44.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:18:0) after 1m0s
[00:42:44.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:18:0)
[00:42:44.919] Timer tick, asking Buildbucket for the build status
[00:42:45.037] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:45.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:19:0) after 1m0s
[00:43:45.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:19:0)
[00:43:45.153] Timer tick, asking Buildbucket for the build status
[00:43:45.218] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:45.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:20:0) after 1m0s
[00:44:45.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:20:0)
[00:44:45.236] Timer tick, asking Buildbucket for the build status
[00:44:45.298] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:45.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:21:0) after 1m0s
[00:45:45.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:21:0)
[00:45:45.352] Timer tick, asking Buildbucket for the build status
[00:45:45.421] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:45.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:22:0) after 1m0s
[00:46:45.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:22:0)
[00:46:45.493] Timer tick, asking Buildbucket for the build status
[00:46:45.622] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:45.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:23:0) after 1m0s
[00:47:45.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:23:0)
[00:47:45.672] Timer tick, asking Buildbucket for the build status
[00:47:45.815] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:45.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:24:0) after 1m0s
[00:48:45.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:24:0)
[00:48:45.899] Timer tick, asking Buildbucket for the build status
[00:48:46.011] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:46.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:25:0) after 1m0s
[00:49:46.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:25:0)
[00:49:46.115] Timer tick, asking Buildbucket for the build status
[00:49:46.405] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:46.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:26:0) after 1m0s
[00:50:46.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:26:0)
[00:50:46.452] Timer tick, asking Buildbucket for the build status
[00:50:46.607] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:46.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:27:0) after 1m0s
[00:51:46.629] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:27:0)
[00:51:46.629] Timer tick, asking Buildbucket for the build status
[00:51:46.733] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:46.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:28:0) after 1m0s
[00:52:46.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:28:0)
[00:52:46.805] Timer tick, asking Buildbucket for the build status
[00:52:47.165] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:47.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:29:0) after 1m0s
[00:53:47.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:29:0)
[00:53:47.216] Timer tick, asking Buildbucket for the build status
[00:53:47.283] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:47.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:30:0) after 1m0s
[00:54:47.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:30:0)
[00:54:47.349] Timer tick, asking Buildbucket for the build status
[00:54:47.414] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:47.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:31:0) after 1m0s
[00:55:47.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:31:0)
[00:55:47.482] Timer tick, asking Buildbucket for the build status
[00:55:47.553] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:47.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:32:0) after 1m0s
[00:56:47.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:32:0)
[00:56:47.725] Timer tick, asking Buildbucket for the build status
[00:56:47.820] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:47.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:33:0) after 1m0s
[00:57:48.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:33:0)
[00:57:48.022] Timer tick, asking Buildbucket for the build status
[00:57:48.293] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:48.293] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:34:0) after 1m0s
[00:58:48.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:34:0)
[00:58:48.395] Timer tick, asking Buildbucket for the build status
[00:58:48.465] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:48.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:35:0) after 1m0s
[00:59:48.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:35:0)
[00:59:48.508] Timer tick, asking Buildbucket for the build status
[00:59:48.743] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:48.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:36:0) after 1m0s
[01:00:48.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:36:0)
[01:00:48.815] Timer tick, asking Buildbucket for the build status
[01:00:48.911] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:48.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:37:0) after 1m0s
[01:01:49.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:37:0)
[01:01:49.005] Timer tick, asking Buildbucket for the build status
[01:01:49.110] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:49.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:38:0) after 1m0s
[01:02:49.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:38:0)
[01:02:49.212] Timer tick, asking Buildbucket for the build status
[01:02:49.417] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:49.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:39:0) after 1m0s
[01:03:49.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:39:0)
[01:03:49.547] Timer tick, asking Buildbucket for the build status
[01:03:49.812] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:49.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:40:0) after 1m0s
[01:04:49.992] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:40:0)
[01:04:49.992] Timer tick, asking Buildbucket for the build status
[01:04:50.148] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:50.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:41:0) after 1m0s
[01:05:50.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:41:0)
[01:05:50.266] Timer tick, asking Buildbucket for the build status
[01:05:51.186] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:51.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:42:0) after 1m0s
[01:06:51.227] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:42:0)
[01:06:51.227] Timer tick, asking Buildbucket for the build status
[01:06:51.372] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:51.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:43:0) after 1m0s
[01:07:51.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:43:0)
[01:07:51.491] Timer tick, asking Buildbucket for the build status
[01:07:51.553] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:51.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:44:0) after 1m0s
[01:08:51.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:44:0)
[01:08:51.661] Timer tick, asking Buildbucket for the build status
[01:08:51.828] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:51.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:45:0) after 1m0s
[01:09:51.912] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:45:0)
[01:09:51.912] Timer tick, asking Buildbucket for the build status
[01:09:52.024] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:52.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:46:0) after 1m0s
[01:10:52.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:46:0)
[01:10:52.095] Timer tick, asking Buildbucket for the build status
[01:10:52.851] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:52.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:47:0) after 1m0s
[01:11:52.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:47:0)
[01:11:52.873] Timer tick, asking Buildbucket for the build status
[01:11:52.988] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:52.988] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:48:0) after 1m0s
[01:12:53.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:48:0)
[01:12:53.046] Timer tick, asking Buildbucket for the build status
[01:12:53.155] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:53.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:49:0) after 1m0s
[01:13:53.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:49:0)
[01:13:53.188] Timer tick, asking Buildbucket for the build status
[01:13:53.514] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:53.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:50:0) after 1m0s
[01:14:53.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:50:0)
[01:14:53.532] Timer tick, asking Buildbucket for the build status
[01:14:53.702] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:53.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:51:0) after 1m0s
[01:15:53.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:51:0)
[01:15:53.775] Timer tick, asking Buildbucket for the build status
[01:15:53.910] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:53.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:52:0) after 1m0s
[01:16:53.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:52:0)
[01:16:53.955] Timer tick, asking Buildbucket for the build status
[01:16:54.163] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:54.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:53:0) after 1m0s
[01:17:54.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:53:0)
[01:17:54.253] Timer tick, asking Buildbucket for the build status
[01:17:54.394] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:54.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:54:0) after 1m0s
[01:18:54.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:54:0)
[01:18:54.417] Timer tick, asking Buildbucket for the build status
[01:18:54.736] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:54.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:55:0) after 1m0s
[01:19:54.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:55:0)
[01:19:54.767] Timer tick, asking Buildbucket for the build status
[01:19:55.406] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:55.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:56:0) after 1m0s
[01:20:55.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:56:0)
[01:20:55.438] Timer tick, asking Buildbucket for the build status
[01:20:55.808] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:55.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:57:0) after 1m0s
[01:21:55.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:57:0)
[01:21:55.832] Timer tick, asking Buildbucket for the build status
[01:21:55.956] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:55.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:58:0) after 1m0s
[01:22:56.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:58:0)
[01:22:56.002] Timer tick, asking Buildbucket for the build status
[01:22:56.422] Build 8862703835136445920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:56.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028133897687068800:59:0) after 1m0s
[01:23:48.966] Received PubSub notification, asking Buildbucket for the build status
[01:23:49.046] Build 8862703835136445920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:23:49.046] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606267428113098",
"created_by": "project:chromium",
"created_ts": "1606264000723620",
"id": "8862703835136445920",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d96563a1645a8acd747ce8fbb4b3cf85817be5bd\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"d96563a1645a8acd747ce8fbb4b3cf85817be5bd\", \"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-38-8lvh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43141, \"got_angle_revision\": \"9041ca0c340728abbb36fd7033a491e9d340e823\", \"got_dawn_revision\": \"90bac683b40fc0d051aca35cd455ef6baa77ddf2\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"d96563a1645a8acd747ce8fbb4b3cf85817be5bd\", \"got_revision_cp\": \"refs/heads/master@{#830797}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"0652fd726a951b9275547e541b83703cf77c35b0\", \"got_v8_revision_cp\": \"refs/heads/8.9.44@{#1}\", \"got_webrtc_revision\": \"3f77eb468fc4a9a7f5e2751d3d3cbbda38e95082\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32683}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d96563a1645a8acd747ce8fbb4b3cf85817be5bd\", \"swarm_hashes_refs/heads/master(at){#830797}_without_patch\": {\"absl_hardening_tests\": \"a74004c28377937e394cdf6ec41471f193505525\", \"accessibility_unittests\": \"ae16244c32a980d42dcf516be7ea13600ab04082\", \"angle_unittests\": \"3acf2483acbc1b7bb1fa78b582f81e999d9d12ee\", \"app_list_unittests\": \"7c6a32d6cb8f7e7fa3caaabe9ab9547a21efc67b\", \"app_shell_unittests\": \"70b10d882ca8f8ebba442db7e712ae2a2e1c563b\", \"ash_unittests\": \"0684586203d4146fdfd2da85b8a269aad31f5810\", \"aura_unittests\": \"777b707281f57ff5d7f98a836fb5fe6cea7d6141\", \"base_unittests\": \"5c6a03b7de0eec4b87473cf603011d00d8275a03\", \"base_util_unittests\": \"914f44b170a3efe85bfdef0c8be40a1afc69286b\", \"blink_common_unittests\": \"6ebd2b29abd86b45adee23c8fb9a892cbd7fca90\", \"blink_fuzzer_unittests\": \"dc1ce2ee9e1b57637b99d57d1a24ed2a25f5fe1a\", \"blink_heap_unittests\": \"b5ecfea6f717748d05fc6b267f9db4c4ee011c6c\", \"blink_platform_unittests\": \"cea9a5111f122ccedd1fabf29c231b8bf2b316b0\", \"blink_unittests\": \"7c3f49a4250e0bd9a44afe5cd2bbd8f22dcad13e\", \"boringssl_crypto_tests\": \"c11d516b4a84dbbf201e11ec177ae02d635ae967\", \"boringssl_ssl_tests\": \"9406dd9c09eaeb3b16170d9a60e7a14ca550f260\", \"browser_tests\": \"f10a7d3254377e36c2284f2c1b9e4029d38c44e0\", \"capture_unittests\": \"cfc940a9e1342e9a5e187ae3ab0428a982b6efc4\", \"cast_unittests\": \"d3aff4e4da68b8901f97e00a4b47d350935dc3e9\", \"cc_unittests\": \"e49fa31b99c0bde902f099e33015a934670c3207\", \"chrome_app_unittests\": \"78b3fad77ef25e627f327a2d352fcc30637d39bc\", \"chromedriver_unittests\": \"dba4135cfd56ae5c644d70f6484c37a087a50d93\", \"chromeos_components_unittests\": \"534ee20f8c7e043be92f22ee591cd0bcd564065f\", \"chromeos_unittests\": \"e81a523c8459ff7b584b199e4dbbd96aed2d1346\", \"components_browsertests\": \"e780b201ff7e031cb83b7209f7ac4fb3aed46595\", \"components_unittests\": \"8b8890ef948a2b4bb9d02d8cc5d67dc79427122a\", \"compositor_unittests\": \"187d63c94fbb56bb25ae8c30c5f365b8e82b99f3\", \"content_browsertests\": \"43b3cbd2eee2c9c4bbdcc6ba1388849de8d3b4ba\", \"content_unittests\": \"a1243fde786c5274bcd7aaba564e8eab832a4a39\", \"crashpad_tests\": \"b4546c281c6e055edf042f8774205875aca78569\", \"crypto_unittests\": \"f11e81345dbc5263d481a058ae74d9f1174de80b\", \"dbus_unittests\": \"063eeaf059680557c7114b702477e884cb52eb21\", \"device_unittests\": \"a580865ce583e8c267aae6be5ddafd8b652c4301\", \"display_unittests\": \"c5f7a7e29721a078c4f0303f43ae8b0b1f1acbf9\", \"events_unittests\": \"42f3ac7a1e7996f6c3136a03af147887685cd9d2\", \"exo_unittests\": \"67df5e1c1681153accf1b549274fc4679a092043\", \"extensions_browsertests\": \"bf54d2011e70376bd3801c1488467083b4a54beb\", \"extensions_unittests\": \"5d9b1b48fc9cc919d826d0edbbb002be3ce14f63\", \"filesystem_service_unittests\": \"1ca6045e8387b832f0e39930a25ae06a45a65273\", \"gcm_unit_tests\": \"b2e76a8879372daaba3e45846e711f78aecf536f\", \"gfx_unittests\": \"8eade2fdd4bcbb2c42901a6f29315d61716e39ef\", \"gin_unittests\": \"5ad4805b02574984483c454c3b076e9e3269c219\", \"gl_unittests_ozone\": \"4b06100812a4c3190d52eaaf6ca9a9669112be8d\", \"google_apis_unittests\": \"37138d65819717795a3be9a98b43c74d3191c059\", \"gpu_unittests\": \"51f2f85c3b22a565c6afaeb5424c602baa6e7df2\", \"gwp_asan_unittests\": \"eee92c9730f128deb33aa494fce889b652f76efc\", \"interactive_ui_tests\": \"06baf750cf929781b7b094bab5ad1f2078e44390\", \"ipc_tests\": \"219622427692b18ae5859e56967963b6f3691bd7\", \"jingle_unittests\": \"89b5dbee553fdfb4053576dd7b111b69b429ba47\", \"keyboard_unittests\": \"51371d4467320ba21868ae6b6f395918225aeb04\", \"latency_unittests\": \"540d711ed4982a358796efa41566beb6773954bd\", \"libjingle_xmpp_unittests\": \"8a3f404e32bffe1c19475f50b841b2252fd2fd84\", \"liburlpattern_unittests\": \"ef15d317609288e4b18b4488be7ef76c1262816d\", \"media_blink_unittests\": \"accf5e75ff79c57fd1c5438025bf82e0c52169f7\", \"media_unittests\": \"e47c36f84edc4c335cc418941827c14fe9f3f393\", \"message_center_unittests\": \"21af72fbc64b17eb90e73d75ca6fb93bd4a31ab3\", \"midi_unittests\": \"e8daf2483b274e05a93aa8e70ab6f41dbb7605ea\", \"mojo_core_unittests\": \"7e3f40fd3b5dca2d46f72aa6a5386b6a7489546f\", \"mojo_unittests\": \"836eae2ed8a3d2cdeeaa05e1f388b970e595add9\", \"nacl_helper_nonsfi_unittests\": \"00aa2c180d793818012d9d758a11b23c47733700\", \"nacl_loader_unittests\": \"00be044fd2483905d42eb225a6e27469a3dd5ed3\", \"native_theme_unittests\": \"b1e461067f3b7a04514953c7bedafeb55b8f9804\", \"net_unittests\": \"e8c4f7f177a5297bdc2d9f19a11a6343817dd55c\", \"ozone_gl_unittests\": \"e6250891d51ece2e823c5ef783ade5778dd64513\", \"ozone_unittests\": \"abb0965171b3a86a93de4fab8c07ec3cb2b37429\", \"ozone_x11_unittests\": \"500964658c2575756d2e785dc5e9cd71defe4033\", \"pdf_unittests\": \"f69762b771fcdf91d61e82b9772d1573a4593a49\", \"perfetto_unittests\": \"fce73861155e80baf97c5593f473d9bb87a8f615\", \"ppapi_unittests\": \"883e2fc26b1c8bcb2abf466105f26007f1102937\", \"printing_unittests\": \"d7212354c2d90cc1eb2f16e466b1e15cae0fbe86\", \"remoting_unittests\": \"5cc1cfe63429b7900c5cfcc167f20add707a16c2\", \"sandbox_linux_unittests\": \"c88f315306f579a4fd044745989f29c76fbdda5c\", \"service_manager_unittests\": \"00d420816a55822bb45ae196fc410a030237bbdc\", \"services_unittests\": \"b087ed377d6c80de9172af886d79e70f68b401f1\", \"shell_dialogs_unittests\": \"c659a8721866939092187442cb66fc5ed8cbd2a2\", \"skia_unittests\": \"dd448a033afcd54b6216cf353b579cc4b61f13dc\", \"snapshot_unittests\": \"778e01985fe7facacd853a6114afc9e07e47510d\", \"sql_unittests\": \"d8133865e57b8507ddb81b0f7da6a442c192388a\", \"storage_unittests\": \"67eb2070b9edd654ebdcf670d8de73adda26d104\", \"sync_integration_tests\": \"8bb5cd60452f8b6b93576b5d98193d9478f07cef\", \"traffic_annotation_auditor_unittests\": \"e933c7e7757ce3d038d75b173c0996380578188d\", \"ui_base_unittests\": \"38c5ecad212b3aa3ef75b5c7e17a2c5da11164f7\", \"ui_chromeos_unittests\": \"72a4b741c516fcfa0f2dea8bc6bff9948eb740f1\", \"ui_touch_selection_unittests\": \"845c68049232f8aed46f944fd226fc04fbf5c252\", \"unit_tests\": \"7bef87e50c46ef77d0ad9649edd5f06e2b6862f9\", \"url_unittests\": \"59bfe9c8e75e20a0cbf27b5cdacdfff3ccb101bf\", \"usage_time_limit_unittests\": \"19211f0f10929713df9f5a2b9824fd225ba6d206\", \"views_unittests\": \"2975d8c26365b67c6a3549f968b1cfdb299a53d7\", \"viz_unittests\": \"e5aadafda25e7431073b587ead725e8f514d61f9\", \"wayland_client_perftests\": \"aad49f1d1edfcca81a94b434d25a64a31ea8789e\", \"wm_unittests\": \"f7e64afd5b099981839aba5eeba09031c0f9e000\", \"wtf_unittests\": \"5363c30005def0d349b97f19a26b921334531b86\", \"zlib_unittests\": \"e60ed046dba995c7d893347df35fcec281c5f3d8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_7a9f94695aa83f7478015e01762e75301e67c95c681ec0592232bd343861e191_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_8bff6729eb7a20b41fa723231755491227dfae6025a6d103df6a00cfb6cc22b8_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9efb94071a208cb32f222f9589b2bf8c6e9d4ebc7884b1b49bd19cd2b21dc48e_v2\", \"builder_c66788656bdf2acb3179f9105d1e02eee4c98139f3cebcabd373c722039a64ae_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-38-8lvh\"], \"image\": [\"chrome-xenial-20111600-a937a23cefb\"], \"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\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606264003355307",
"status": "COMPLETED",
"status_changed_ts": "1606267428450596",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43141",
"builder:linux-chromeos-rel",
"buildset:commit/git/d96563a1645a8acd747ce8fbb4b3cf85817be5bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d96563a1645a8acd747ce8fbb4b3cf85817be5bd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028133897687068800",
"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/8862703835136445920/+/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:50159d1d3615ad10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606267428456078",
"url": "https://ci.chromium.org/b/8862703835136445920",
"utcnow_ts": "1606267429037421"
}
}
[01:23:49.046] Invocation finished in 57m10.139118325s with status SUCCEEDED