[17:23:42.374] New invocation is queued and will start shortly
[17:23:43.442] Starting the invocation (attempt 1)
[17:23:43.479] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:23:43.479] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:23:43.479] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024717822203459616",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4339fa1d87c5b9507dbb62b322e4f07bbf7561a0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9024717822203459616",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4339fa1d87c5b9507dbb62b322e4f07bbf7561a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4339fa1d87c5b9507dbb62b322e4f07bbf7561a0",
"gitiles_ref:refs/heads/master"
]
}
[17:23:44.186] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609521823564442",
"id": "8859287760292700800",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4339fa1d87c5b9507dbb62b322e4f07bbf7561a0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609521823927106",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44252",
"builder:linux-chromeos-rel",
"buildset:commit/git/4339fa1d87c5b9507dbb62b322e4f07bbf7561a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4339fa1d87c5b9507dbb62b322e4f07bbf7561a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024717822203459616",
"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/8859287760292700800/+/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": "1609521823927273",
"url": "https://ci.chromium.org/b/8859287760292700800",
"utcnow_ts": "1609521824161642"
}
}
[17:23:44.186] Task URL: https://ci.chromium.org/b/8859287760292700800
[17:23:44.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:2:0) after 1m0s
[17:24:35.270] Received PubSub notification, asking Buildbucket for the build status
[17:24:35.351] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:44.423] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:2:0)
[17:24:44.423] Timer tick, asking Buildbucket for the build status
[17:24:44.619] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:44.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:4:0) after 1m0s
[17:25:44.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:4:0)
[17:25:44.643] Timer tick, asking Buildbucket for the build status
[17:25:44.831] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:44.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:5:0) after 1m0s
[17:26:44.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:5:0)
[17:26:44.925] Timer tick, asking Buildbucket for the build status
[17:26:44.979] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:44.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:6:0) after 1m0s
[17:27:45.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:6:0)
[17:27:45.024] Timer tick, asking Buildbucket for the build status
[17:27:45.109] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:45.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:7:0) after 1m0s
[17:28:45.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:7:0)
[17:28:45.179] Timer tick, asking Buildbucket for the build status
[17:28:45.260] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:45.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:8:0) after 1m0s
[17:29:45.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:8:0)
[17:29:45.350] Timer tick, asking Buildbucket for the build status
[17:29:45.462] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:45.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:9:0) after 1m0s
[17:30:45.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:9:0)
[17:30:45.487] Timer tick, asking Buildbucket for the build status
[17:30:45.716] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:45.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:10:0) after 1m0s
[17:31:45.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:10:0)
[17:31:45.732] Timer tick, asking Buildbucket for the build status
[17:31:46.010] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:46.010] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:11:0) after 1m0s
[17:32:46.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:11:0)
[17:32:46.026] Timer tick, asking Buildbucket for the build status
[17:32:46.106] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:46.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:12:0) after 1m0s
[17:33:46.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:12:0)
[17:33:46.127] Timer tick, asking Buildbucket for the build status
[17:33:46.229] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:46.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:13:0) after 1m0s
[17:34:46.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:13:0)
[17:34:46.299] Timer tick, asking Buildbucket for the build status
[17:34:46.414] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:46.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:14:0) after 1m0s
[17:35:46.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:14:0)
[17:35:46.431] Timer tick, asking Buildbucket for the build status
[17:35:46.674] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:46.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:15:0) after 1m0s
[17:36:46.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:15:0)
[17:36:46.692] Timer tick, asking Buildbucket for the build status
[17:36:46.757] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:46.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:16:0) after 1m0s
[17:37:46.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:16:0)
[17:37:46.775] Timer tick, asking Buildbucket for the build status
[17:37:47.068] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:47.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:17:0) after 1m0s
[17:38:47.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:17:0)
[17:38:47.088] Timer tick, asking Buildbucket for the build status
[17:38:47.312] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:47.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:18:0) after 1m0s
[17:39:47.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:18:0)
[17:39:47.342] Timer tick, asking Buildbucket for the build status
[17:39:47.634] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:47.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:19:0) after 1m0s
[17:40:47.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:19:0)
[17:40:47.670] Timer tick, asking Buildbucket for the build status
[17:40:47.737] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:47.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:20:0) after 1m0s
[17:41:47.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:20:0)
[17:41:47.947] Timer tick, asking Buildbucket for the build status
[17:41:48.030] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:48.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:21:0) after 1m0s
[17:42:48.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:21:0)
[17:42:48.121] Timer tick, asking Buildbucket for the build status
[17:42:48.221] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:48.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:22:0) after 1m0s
[17:43:48.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:22:0)
[17:43:48.290] Timer tick, asking Buildbucket for the build status
[17:43:48.421] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:48.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:23:0) after 1m0s
[17:44:48.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:23:0)
[17:44:48.441] Timer tick, asking Buildbucket for the build status
[17:44:48.501] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:48.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:24:0) after 1m0s
[17:45:48.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:24:0)
[17:45:48.718] Timer tick, asking Buildbucket for the build status
[17:45:48.782] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:48.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:25:0) after 1m0s
[17:46:48.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:25:0)
[17:46:48.959] Timer tick, asking Buildbucket for the build status
[17:46:49.038] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:49.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:26:0) after 1m0s
[17:47:49.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:26:0)
[17:47:49.116] Timer tick, asking Buildbucket for the build status
[17:47:49.173] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:49.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:27:0) after 1m0s
[17:48:49.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:27:0)
[17:48:49.211] Timer tick, asking Buildbucket for the build status
[17:48:49.302] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:49.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:28:0) after 1m0s
[17:49:49.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:28:0)
[17:49:49.366] Timer tick, asking Buildbucket for the build status
[17:49:49.430] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:49.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:29:0) after 1m0s
[17:50:49.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:29:0)
[17:50:49.591] Timer tick, asking Buildbucket for the build status
[17:50:49.800] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:49.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:30:0) after 1m0s
[17:51:49.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:30:0)
[17:51:49.864] Timer tick, asking Buildbucket for the build status
[17:51:49.925] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:49.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:31:0) after 1m0s
[17:52:49.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:31:0)
[17:52:49.908] Timer tick, asking Buildbucket for the build status
[17:52:50.096] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:50.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:32:0) after 1m0s
[17:53:50.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:32:0)
[17:53:50.142] Timer tick, asking Buildbucket for the build status
[17:53:50.290] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:50.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:33:0) after 1m0s
[17:54:50.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:33:0)
[17:54:50.358] Timer tick, asking Buildbucket for the build status
[17:54:50.493] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:50.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:34:0) after 1m0s
[17:55:50.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:34:0)
[17:55:50.560] Timer tick, asking Buildbucket for the build status
[17:55:50.617] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:50.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:35:0) after 1m0s
[17:56:50.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:35:0)
[17:56:50.705] Timer tick, asking Buildbucket for the build status
[17:56:50.777] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:50.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:36:0) after 1m0s
[17:57:50.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:36:0)
[17:57:50.829] Timer tick, asking Buildbucket for the build status
[17:57:51.227] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:51.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:37:0) after 1m0s
[17:58:51.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:37:0)
[17:58:51.268] Timer tick, asking Buildbucket for the build status
[17:58:51.377] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:51.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:38:0) after 1m0s
[17:59:51.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:38:0)
[17:59:51.393] Timer tick, asking Buildbucket for the build status
[17:59:51.599] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:51.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:39:0) after 1m0s
[18:00:51.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:39:0)
[18:00:51.671] Timer tick, asking Buildbucket for the build status
[18:00:51.742] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:51.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:40:0) after 1m0s
[18:01:51.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:40:0)
[18:01:51.869] Timer tick, asking Buildbucket for the build status
[18:01:51.961] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:51.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:41:0) after 1m0s
[18:02:51.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:41:0)
[18:02:51.980] Timer tick, asking Buildbucket for the build status
[18:02:52.319] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:52.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:42:0) after 1m0s
[18:03:52.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:42:0)
[18:03:52.376] Timer tick, asking Buildbucket for the build status
[18:03:52.488] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:52.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:43:0) after 1m0s
[18:04:52.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:43:0)
[18:04:52.554] Timer tick, asking Buildbucket for the build status
[18:04:52.669] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:52.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:44:0) after 1m0s
[18:05:52.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:44:0)
[18:05:52.705] Timer tick, asking Buildbucket for the build status
[18:05:52.806] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:52.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:45:0) after 1m0s
[18:06:52.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:45:0)
[18:06:52.826] Timer tick, asking Buildbucket for the build status
[18:06:52.982] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:52.982] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:46:0) after 1m0s
[18:07:53.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:46:0)
[18:07:53.005] Timer tick, asking Buildbucket for the build status
[18:07:53.105] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:53.105] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:47:0) after 1m0s
[18:08:53.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:47:0)
[18:08:53.128] Timer tick, asking Buildbucket for the build status
[18:08:53.275] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:53.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:48:0) after 1m0s
[18:09:53.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:48:0)
[18:09:53.277] Timer tick, asking Buildbucket for the build status
[18:09:53.380] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:53.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:49:0) after 1m0s
[18:10:53.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:49:0)
[18:10:53.411] Timer tick, asking Buildbucket for the build status
[18:10:53.629] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:53.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:50:0) after 1m0s
[18:11:53.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:50:0)
[18:11:53.711] Timer tick, asking Buildbucket for the build status
[18:11:53.825] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:53.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:51:0) after 1m0s
[18:12:53.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:51:0)
[18:12:53.896] Timer tick, asking Buildbucket for the build status
[18:12:54.006] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:54.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:52:0) after 1m0s
[18:13:54.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:52:0)
[18:13:54.036] Timer tick, asking Buildbucket for the build status
[18:13:54.139] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:54.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:53:0) after 1m0s
[18:14:54.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:53:0)
[18:14:54.178] Timer tick, asking Buildbucket for the build status
[18:14:54.841] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:54.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:54:0) after 1m0s
[18:15:54.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:54:0)
[18:15:54.889] Timer tick, asking Buildbucket for the build status
[18:15:55.328] Build 8859287760292700800: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:55.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024717822203459616:55:0) after 1m0s
[18:16:40.016] Received PubSub notification, asking Buildbucket for the build status
[18:16:40.082] Build 8859287760292700800: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:16:40.082] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609524999299350",
"created_by": "project:chromium",
"created_ts": "1609521823564442",
"id": "8859287760292700800",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4339fa1d87c5b9507dbb62b322e4f07bbf7561a0\"}}",
"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\": \"4339fa1d87c5b9507dbb62b322e4f07bbf7561a0\", \"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-26-vr21\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44252, \"got_angle_revision\": \"bf02c31bec07a549cc8eea26ad0e63143954643d\", \"got_dawn_revision\": \"86980018b3f2786aeb5ded1220ec94bc927fa6b2\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"4339fa1d87c5b9507dbb62b322e4f07bbf7561a0\", \"got_revision_cp\": \"refs/heads/master@{#839905}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"ff29e249d3179766a40c9486183559fe3e5c1456\", \"got_v8_revision\": \"a3f2933bb6d06bd7473ccbed77714874e6bf63b3\", \"got_v8_revision_cp\": \"refs/heads/8.9.201@{#1}\", \"got_webrtc_revision\": \"6215ba804eb500f3e28b39088c73af3c4f4cd10a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32897}\", \"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\": \"4339fa1d87c5b9507dbb62b322e4f07bbf7561a0\", \"swarm_hashes_refs/heads/master(at){#839905}_without_patch\": {\"absl_hardening_tests\": \"63a01a7263e0727b3e229c21f6b0fb4a1fcb59b3\", \"accessibility_unittests\": \"5ba8a7aaf23c2ddc02e27d37deae39b61f7ae6d4\", \"angle_unittests\": \"4d01872908c625c67953543d7489aa810a667b97\", \"app_list_unittests\": \"2db6d90e9df8ef5a2e8ebe16807fc769c75ec5f3\", \"app_shell_unittests\": \"7b861a2719cbd44b53b99e7c51d5008b0dceb145\", \"ash_unittests\": \"51fea6b144b58b621e0782db74bb13fa5dd8990b\", \"aura_unittests\": \"e02e71c9f9208106e703cfbfd35b3d269d391a24\", \"base_unittests\": \"6e716d9648f1a36fb485b5e184c83dbd524a06f2\", \"base_util_unittests\": \"252d40ce448b1062b37983f327f7eceeed25d02d\", \"blink_common_unittests\": \"14f1b80b387c1d1661e4dfc83d9e2551da3d0403\", \"blink_fuzzer_unittests\": \"8cb99f7023d12ddda0d2bb7c11b17474b4836cfe\", \"blink_heap_unittests\": \"588fcb547951c68db79561ccfe9017c394f600df\", \"blink_platform_unittests\": \"c394d8401b95d412f005acf34016d9e957ba385d\", \"blink_unittests\": \"c50858088c3bf95d3bd5efe5a1bb2ac52ec032a0\", \"boringssl_crypto_tests\": \"4e51aa5f88b028386c22c28ffcbaa1fa2edc8b1b\", \"boringssl_ssl_tests\": \"cae79cbf78b5fd411a97aa081f5cbbf1120c4f52\", \"browser_tests\": \"5c8fc501ac7900c87ddf6cfb98a0383899fcb043\", \"capture_unittests\": \"0be4fbc50b8979df45c18a67766488592301aee7\", \"cast_unittests\": \"ae4b976cbc1cdfe646195791ad743bade3adeaac\", \"cc_unittests\": \"ab15a7d82953135f9e2c81ae72a082c3b3097dac\", \"chrome_app_unittests\": \"d247cf1352d2848354be63b6155b5ce718b69b91\", \"chromedriver_unittests\": \"2c98162f368ea14ddee860e9bd2c335f264e9a09\", \"chromeos_components_unittests\": \"1b17b4dd8f9e98f092e16ffae2cb4ae85c773e19\", \"chromeos_unittests\": \"ca2fa7e6ea730e10cb77611e989def039d2d6ab8\", \"components_browsertests\": \"5735209c41deb55350d3985e057d2a0c22976569\", \"components_unittests\": \"42000dc3e6f0e373f609f6e4b75709d9b9687c7a\", \"compositor_unittests\": \"01c9b885ddbd5f7f94f5493e27e04a6a6ecf9f56\", \"content_browsertests\": \"76a6fe52763f3e2c8a71df8d082cdff17551457c\", \"content_unittests\": \"3cf82662d4bd31222632c64954b2ab139a5ecd65\", \"crashpad_tests\": \"9748a2387fc32d34da28d566d804fc526d84a438\", \"crypto_unittests\": \"79436f72b9370ffbe102f4443cac9d817c6f877e\", \"dbus_unittests\": \"b4bb857ea9adf9f789e278c0f9b901db5c2ee4ef\", \"device_unittests\": \"c782e40b4c210faff3bc55e5b1b435a56bb1c65d\", \"display_unittests\": \"0517215eb72a18e68d8e70031656dfcbcb41ea61\", \"events_unittests\": \"02949f020f6f10fa3c393238549824ede3ae60ee\", \"exo_unittests\": \"474646bcafafe594b920e292a247af4d0baad9ba\", \"extensions_browsertests\": \"123186b82d7daba41a68f2f08d18a34d25cf63a8\", \"extensions_unittests\": \"4ba2df687eac977bcfdf61ac3a9052e7fde61d73\", \"filesystem_service_unittests\": \"178d49724d09aa38327495b1309e75fdd52d38a5\", \"gcm_unit_tests\": \"e096e8c1cbcb0abf7bacdff21a61d5ec849f238c\", \"gfx_unittests\": \"d0943c32fb246b1358943f2ba3cb2f19da481941\", \"gin_unittests\": \"8852308294bb41972f7bc05d5a3c3323fc276f12\", \"gl_unittests_ozone\": \"fbdcbde7412755631c1154083c53fda2115422ba\", \"google_apis_unittests\": \"eaae744a772d5c1dc586320f57ccc298df513813\", \"gpu_unittests\": \"22195530dc5f0f6643a7e6333631ac2a7244361f\", \"gwp_asan_unittests\": \"9b10b525c11706242ad901192a8374f32973c8ca\", \"interactive_ui_tests\": \"e8d8c069796767ac5f909c7ef5b99c09dc6fbd25\", \"ipc_tests\": \"e964894c7497c2c7fac9d9614d57446d8f53a88b\", \"jingle_unittests\": \"f84a9b21ea621da4bf6bbad54fb26056e37bbf32\", \"keyboard_unittests\": \"e0151e834e498c53eafe3ba50327ff010b428c6f\", \"latency_unittests\": \"4e0dff24abb247bd5d840243d452f342a6d854f9\", \"libjingle_xmpp_unittests\": \"9b61e658a4fe2577a1aa49cc2ea8de83215ca714\", \"liburlpattern_unittests\": \"bcdf823641572c43c379b0e6f44c5e6a227bae83\", \"media_blink_unittests\": \"8ce8272e671b9ad7c126fcd85de4110f2b6c0616\", \"media_unittests\": \"72d1691619b623437ed787efa17d788e83405df0\", \"message_center_unittests\": \"82cc432b2c74c6b1db0203b887ed628e80aec9a1\", \"midi_unittests\": \"c9813da86f6aa48524be3bbddfe81499bab8c981\", \"mojo_core_unittests\": \"c03ccc8290bdcbf990c3ed611d3cd3abc1ba6f72\", \"mojo_unittests\": \"0fd296d873b14d97ca75064c95c75123507cc237\", \"nacl_helper_nonsfi_unittests\": \"e0eb45e5639f7f87b602a04a6eafb617126e685b\", \"nacl_loader_unittests\": \"b6e44d21c56ce1864da6d4ac72e22b8b92925533\", \"native_theme_unittests\": \"e6395730ad2a4d1f92b962f1c5da8f466993cfe3\", \"net_unittests\": \"760c8d51d0d0d08cce79d4553ce4240d4598f8bc\", \"ozone_gl_unittests\": \"e7bba484ca73b0c1a66fa8939eec36db3f8f2af5\", \"ozone_unittests\": \"aca1c8beb3d16ee868266be5957574e845207d33\", \"ozone_x11_unittests\": \"3244f14200d2d198793f06bc9837a46165c9c173\", \"pdf_unittests\": \"1945cf7263ba79c9341b04e74e4726cb967cefc7\", \"perfetto_unittests\": \"d609a58ee06ce2bd45a9e617c552e0fff690d570\", \"ppapi_unittests\": \"613719db0e8a36fdae6c66db5fe4b6bad459cf63\", \"printing_unittests\": \"5979f7f0e3616dfbae6c00e19ed04864ffc093ad\", \"remoting_unittests\": \"abc19f457de719a0e89d0fe937fdaa4ffc08d8f1\", \"sandbox_linux_unittests\": \"f8019407ca199987453075957e8f86b321ff3130\", \"service_manager_unittests\": \"65c4feb439fa1369d209527b8ee8204e4fd6ea0c\", \"services_unittests\": \"cb60700d179e94d9deade5829084633e219f2f1a\", \"shell_dialogs_unittests\": \"1067df2843119743dadb885bb30bfa00fa3c79d1\", \"shell_encryption_unittests\": \"3469fc7669e7005070fba9126d6a844bf0868669\", \"skia_unittests\": \"fb14d405c2458554972d3be0baf4a8d33a021847\", \"snapshot_unittests\": \"7d88ba4f3f9b1a620dd0b9431ae8cf8fc376e074\", \"sql_unittests\": \"c34041f87e9f6ac4b98ac3d0f441dbba5fcbf4ca\", \"storage_unittests\": \"4faf5a80a0730141272cd7b5af1b34c466ec198a\", \"sync_integration_tests\": \"33801eafb2f9bb8101f25838401d3d8237ef7264\", \"traffic_annotation_auditor_unittests\": \"b82fd487ba6bce011aac49fc2db51f71da1ce96a\", \"ui_base_unittests\": \"99eb3e0c0fb49651b369e05434212ad8f1a4b793\", \"ui_chromeos_unittests\": \"01dfcb9719bf9e5a2ce677e271f6993c2ca0fbc6\", \"ui_touch_selection_unittests\": \"5d07f7d5d25bc1d61b0b9c59c63795b82f41e0d2\", \"unit_tests\": \"d1d325c13876cbff378642303188ab46649f848c\", \"url_unittests\": \"aebbc0253533cbbe9476f2b55081a67f6b4b7af7\", \"usage_time_limit_unittests\": \"d2f157702839a7f7448bc815edbe5e5fd29eea79\", \"views_unittests\": \"a123abfbbc57b135a7314142d62b0a296d2d393c\", \"viz_unittests\": \"1becf0dc44dcb5288505dd41227b3273d920abcb\", \"wayland_client_perftests\": \"02c94c67e5f7423bdaa55acc008f7eb0edcad97e\", \"wm_unittests\": \"f4b8c64f3042f3ad7d127515e540f3f40366d88f\", \"wtf_unittests\": \"fbd3632c0eeb88d62e386a7ed8a0cef3d595632a\", \"zlib_unittests\": \"192707aa157417e291ce15412c769737ec731809\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a50faa98e00aefb88600aa330f70664f9b4b54ee0ff51cb8b03a7f0a0b737b38_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-26-vr21\"], \"image\": [\"chrome-xenial-20122700-a13233ce10d\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609521858511595",
"status": "COMPLETED",
"status_changed_ts": "1609524999564078",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44252",
"builder:linux-chromeos-rel",
"buildset:commit/git/4339fa1d87c5b9507dbb62b322e4f07bbf7561a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4339fa1d87c5b9507dbb62b322e4f07bbf7561a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024717822203459616",
"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/8859287760292700800/+/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:50d7cb8de687af10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609524999591033",
"url": "https://ci.chromium.org/b/8859287760292700800",
"utcnow_ts": "1609525000070849"
}
}
[18:16:40.082] Invocation finished in 52m57.723438325s with status SUCCEEDED