[07:24:24.419] New invocation is queued and will start shortly
[07:24:25.595] Starting the invocation (attempt 1)
[07:24:25.627] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:24:25.627] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:24:25.627] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021312842128875696",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3784e09eeaae8d82bebdfd70e217619ab1a2f58b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021312842128875696",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/3784e09eeaae8d82bebdfd70e217619ab1a2f58b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3784e09eeaae8d82bebdfd70e217619ab1a2f58b",
"gitiles_ref:refs/heads/master"
]
}
[07:24:26.199] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1612769065708798",
"id": "8855882780114387936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3784e09eeaae8d82bebdfd70e217619ab1a2f58b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612769066043237",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45230",
"builder:linux-chromeos-rel",
"buildset:commit/git/3784e09eeaae8d82bebdfd70e217619ab1a2f58b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3784e09eeaae8d82bebdfd70e217619ab1a2f58b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021312842128875696",
"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/8855882780114387936/+/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": "1612769066043346",
"url": "https://ci.chromium.org/b/8855882780114387936",
"utcnow_ts": "1612769066191813"
}
}
[07:24:26.199] Task URL: https://ci.chromium.org/b/8855882780114387936
[07:24:26.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:2:0) after 1m0s
[07:24:29.343] Received PubSub notification, asking Buildbucket for the build status
[07:24:29.416] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:26.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:2:0)
[07:25:26.292] Timer tick, asking Buildbucket for the build status
[07:25:26.399] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:26.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:4:0) after 1m0s
[07:26:26.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:4:0)
[07:26:26.420] Timer tick, asking Buildbucket for the build status
[07:26:26.804] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:26.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:5:0) after 1m0s
[07:27:26.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:5:0)
[07:27:26.825] Timer tick, asking Buildbucket for the build status
[07:27:26.943] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:26.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:6:0) after 1m0s
[07:28:26.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:6:0)
[07:28:26.962] Timer tick, asking Buildbucket for the build status
[07:28:27.109] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:27.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:7:0) after 1m0s
[07:29:27.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:7:0)
[07:29:27.129] Timer tick, asking Buildbucket for the build status
[07:29:27.482] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:27.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:8:0) after 1m0s
[07:30:27.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:8:0)
[07:30:27.547] Timer tick, asking Buildbucket for the build status
[07:30:27.706] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:27.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:9:0) after 1m0s
[07:31:27.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:9:0)
[07:31:27.717] Timer tick, asking Buildbucket for the build status
[07:31:27.801] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:27.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:10:0) after 1m0s
[07:32:27.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:10:0)
[07:32:27.855] Timer tick, asking Buildbucket for the build status
[07:32:28.006] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:28.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:11:0) after 1m0s
[07:33:28.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:11:0)
[07:33:28.057] Timer tick, asking Buildbucket for the build status
[07:33:28.125] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:28.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:12:0) after 1m0s
[07:34:28.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:12:0)
[07:34:28.142] Timer tick, asking Buildbucket for the build status
[07:34:28.434] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:28.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:13:0) after 1m0s
[07:35:28.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:13:0)
[07:35:28.451] Timer tick, asking Buildbucket for the build status
[07:35:28.538] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:28.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:14:0) after 1m0s
[07:36:28.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:14:0)
[07:36:28.558] Timer tick, asking Buildbucket for the build status
[07:36:28.727] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:28.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:15:0) after 1m0s
[07:37:28.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:15:0)
[07:37:28.747] Timer tick, asking Buildbucket for the build status
[07:37:28.818] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:28.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:16:0) after 1m0s
[07:38:28.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:16:0)
[07:38:28.846] Timer tick, asking Buildbucket for the build status
[07:38:29.097] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:29.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:17:0) after 1m0s
[07:39:29.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:17:0)
[07:39:29.111] Timer tick, asking Buildbucket for the build status
[07:39:29.274] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:29.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:18:0) after 1m0s
[07:40:29.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:18:0)
[07:40:29.325] Timer tick, asking Buildbucket for the build status
[07:40:29.678] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:29.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:19:0) after 1m0s
[07:41:29.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:19:0)
[07:41:29.751] Timer tick, asking Buildbucket for the build status
[07:41:29.824] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:29.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:20:0) after 1m0s
[07:42:29.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:20:0)
[07:42:29.852] Timer tick, asking Buildbucket for the build status
[07:42:29.944] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:29.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:21:0) after 1m0s
[07:43:30.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:21:0)
[07:43:30.050] Timer tick, asking Buildbucket for the build status
[07:43:31.081] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:31.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:22:0) after 1m0s
[07:44:31.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:22:0)
[07:44:31.113] Timer tick, asking Buildbucket for the build status
[07:44:31.331] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:31.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:23:0) after 1m0s
[07:45:31.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:23:0)
[07:45:31.398] Timer tick, asking Buildbucket for the build status
[07:45:32.022] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:32.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:24:0) after 1m0s
[07:46:32.029] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:24:0)
[07:46:32.029] Timer tick, asking Buildbucket for the build status
[07:46:32.711] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:32.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:25:0) after 1m0s
[07:47:32.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:25:0)
[07:47:32.889] Timer tick, asking Buildbucket for the build status
[07:47:32.947] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:32.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:26:0) after 1m0s
[07:48:32.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:26:0)
[07:48:32.976] Timer tick, asking Buildbucket for the build status
[07:48:33.733] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:33.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:27:0) after 1m0s
[07:49:33.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:27:0)
[07:49:33.826] Timer tick, asking Buildbucket for the build status
[07:49:33.922] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:33.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:28:0) after 1m0s
[07:50:33.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:28:0)
[07:50:33.981] Timer tick, asking Buildbucket for the build status
[07:50:34.123] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:34.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:29:0) after 1m0s
[07:51:34.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:29:0)
[07:51:34.143] Timer tick, asking Buildbucket for the build status
[07:51:34.204] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:34.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:30:0) after 1m0s
[07:52:34.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:30:0)
[07:52:34.221] Timer tick, asking Buildbucket for the build status
[07:52:34.287] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:34.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:31:0) after 1m0s
[07:53:34.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:31:0)
[07:53:34.309] Timer tick, asking Buildbucket for the build status
[07:53:34.604] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:34.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:32:0) after 1m0s
[07:54:34.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:32:0)
[07:54:34.633] Timer tick, asking Buildbucket for the build status
[07:54:34.828] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:34.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:33:0) after 1m0s
[07:55:34.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:33:0)
[07:55:34.896] Timer tick, asking Buildbucket for the build status
[07:55:34.983] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:34.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:34:0) after 1m0s
[07:56:35.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:34:0)
[07:56:35.007] Timer tick, asking Buildbucket for the build status
[07:56:35.055] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:35.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:35:0) after 1m0s
[07:57:35.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:35:0)
[07:57:35.065] Timer tick, asking Buildbucket for the build status
[07:57:35.168] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:35.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:36:0) after 1m0s
[07:58:35.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:36:0)
[07:58:35.242] Timer tick, asking Buildbucket for the build status
[07:58:36.098] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:36.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:37:0) after 1m0s
[07:59:36.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:37:0)
[07:59:36.169] Timer tick, asking Buildbucket for the build status
[07:59:36.331] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:36.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:38:0) after 1m0s
[08:00:36.356] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:38:0)
[08:00:36.356] Timer tick, asking Buildbucket for the build status
[08:00:36.780] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:36.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:39:0) after 1m0s
[08:01:36.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:39:0)
[08:01:36.818] Timer tick, asking Buildbucket for the build status
[08:01:37.048] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:37.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:40:0) after 1m0s
[08:02:37.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:40:0)
[08:02:37.107] Timer tick, asking Buildbucket for the build status
[08:02:37.496] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:37.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:41:0) after 1m0s
[08:03:37.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:41:0)
[08:03:37.643] Timer tick, asking Buildbucket for the build status
[08:03:37.741] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:37.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:42:0) after 1m0s
[08:04:37.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:42:0)
[08:04:37.806] Timer tick, asking Buildbucket for the build status
[08:04:38.142] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:38.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:43:0) after 1m0s
[08:05:38.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:43:0)
[08:05:38.168] Timer tick, asking Buildbucket for the build status
[08:05:38.294] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:38.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:44:0) after 1m0s
[08:06:38.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:44:0)
[08:06:38.329] Timer tick, asking Buildbucket for the build status
[08:06:38.433] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:38.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:45:0) after 1m0s
[08:07:38.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:45:0)
[08:07:38.458] Timer tick, asking Buildbucket for the build status
[08:07:38.587] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:38.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:46:0) after 1m0s
[08:08:38.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:46:0)
[08:08:38.611] Timer tick, asking Buildbucket for the build status
[08:08:38.821] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:38.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:47:0) after 1m0s
[08:09:38.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:47:0)
[08:09:38.860] Timer tick, asking Buildbucket for the build status
[08:09:39.003] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:39.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:48:0) after 1m0s
[08:10:39.063] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:48:0)
[08:10:39.063] Timer tick, asking Buildbucket for the build status
[08:10:39.191] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:39.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:49:0) after 1m0s
[08:11:39.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:49:0)
[08:11:39.248] Timer tick, asking Buildbucket for the build status
[08:11:39.633] Build 8855882780114387936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:39.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021312842128875696:50:0) after 1m0s
[08:11:56.462] Received PubSub notification, asking Buildbucket for the build status
[08:11:57.747] Build 8855882780114387936: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:11:57.747] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612771914835117",
"created_by": "project:chromium",
"created_ts": "1612769065708798",
"failure_reason": "BUILD_FAILURE",
"id": "8855882780114387936",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3784e09eeaae8d82bebdfd70e217619ab1a2f58b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ExtensionApiTabTest.TabAudible\"}, \"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\": \"3784e09eeaae8d82bebdfd70e217619ab1a2f58b\", \"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-94-1j8q\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45230, \"got_angle_revision\": \"6b20e1c884885eb6a5603e88a561a3ba21cc615b\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"3784e09eeaae8d82bebdfd70e217619ab1a2f58b\", \"got_revision_cp\": \"refs/heads/master@{#851620}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"462378addab907ab7452d38baccb27fe2ef7289a\", \"got_v8_revision_cp\": \"refs/heads/9.0.138@{#1}\", \"got_webrtc_revision\": \"c3c63c8ceaf4ac3646b1e17bfe8a97e7102f0913\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33185}\", \"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\": \"3784e09eeaae8d82bebdfd70e217619ab1a2f58b\", \"swarm_hashes_refs/heads/master(at){#851620}_without_patch\": {\"absl_hardening_tests\": \"0a1275ca387a1871f814e9e3bf3b1ce01e3d3bb6\", \"accessibility_unittests\": \"2414a91b6f41b3ca5511f82576ccd9a43031204e\", \"angle_unittests\": \"f27c0ca6f499dd2561f7086cbf08add619e13eb0\", \"app_list_unittests\": \"d0710c70a8d77a98f96ccd08b8a95576a6e27066\", \"app_shell_unittests\": \"e699f504350ca2ec4b4d645f5fa19799e63e36df\", \"ash_unittests\": \"ffdb04dfee04ddba5c3c1d2d9436b99109212562\", \"aura_unittests\": \"971d64fa2c85ef440f1c05a5cbe53f4c808190fb\", \"base_unittests\": \"27e77e095fa2c23135ff1efb510043481fd9a353\", \"base_util_unittests\": \"b532506f4ed1be57e92234dd0f72c87d45a98bd2\", \"blink_common_unittests\": \"1c81c937f201fb81279e610e42886651c22a2c3e\", \"blink_fuzzer_unittests\": \"ecff76626dacee2815e184d85e9cd33263e78010\", \"blink_heap_unittests\": \"6b9edb497fbbdf29c91235cc31e452561495258c\", \"blink_platform_unittests\": \"3b25329e5b3878b4e5d86681b935c7cc0ed3cb9a\", \"blink_unittests\": \"baf209a5c8cf054b7e4d0a7d6fcf245b9d7e1364\", \"boringssl_crypto_tests\": \"cb1be731c226a6d2ed70db075f5d0aa7251bc045\", \"boringssl_ssl_tests\": \"c9145bcb0aac768232680c736dafa2caf29b7f54\", \"browser_tests\": \"bcc36804ba3af5edd2732963e6797f95d308bd6a\", \"capture_unittests\": \"d12f3369e722aca0ba784d281a493869b7c85883\", \"cast_unittests\": \"c5aa229f62fb2bd525d13ffd8dcf2052fcb97d35\", \"cc_unittests\": \"00c937da0cd57bae8c9fbde3f336dcfa1cfd08c0\", \"chrome_app_unittests\": \"1b65810154a842628623dba7b2623b44d2f9965f\", \"chromedriver_unittests\": \"1d4445e9c3700b3c118504add54247f59b6994f0\", \"chromeos_components_unittests\": \"db8a38eb07a82711ce4828bba346be403a155dce\", \"chromeos_unittests\": \"2bb2fc161b55e02d5af23be875e0ca958326acf6\", \"components_browsertests\": \"ef821b4c3f4036af536ec5c9f3756192517e70e7\", \"components_unittests\": \"0eb575e6f7c42cf6555bb6584b9ce6cd6bdd4304\", \"compositor_unittests\": \"d1038dc29ff2c86a938ee81779d7f93ad34e8c6e\", \"content_browsertests\": \"8acabb74f7f307f1adf87e36153cd9f2cfcbdc76\", \"content_unittests\": \"4ff0d5b18d95d32afe104d39fcada77158ee4971\", \"crashpad_tests\": \"7daf4d1c96d02b8a2ab0ae590aa0ebb64b64f6c9\", \"crypto_unittests\": \"a6e13c19b1d0971be7ba10d7b7610bce8cb64895\", \"dbus_unittests\": \"586c9f5edb22dc5c7edbc09f48822af8410f3268\", \"device_unittests\": \"12580e211ffbd2bebbe93e3444aee56d03137466\", \"display_unittests\": \"0d5bb7470cd11fe994bf7b2487625c6072197636\", \"events_unittests\": \"9dd5ddabababbe1d66a0a90ac8f4ab429d81d217\", \"exo_unittests\": \"39ed94018650bfedfa5a91834f713390dd724e3d\", \"extensions_browsertests\": \"8347c5d3b6096baad988c5e8dbff27226d820dd2\", \"extensions_unittests\": \"1ae317b235955a114ed4815369aa1f4f5b9db724\", \"filesystem_service_unittests\": \"ca273ea5bbb6d59f44d660740e89fa2dc14ebe0f\", \"gcm_unit_tests\": \"bed59159db538d2793ed8b8fd9499db8f21cfc15\", \"gfx_unittests\": \"c5eba0392484aa24b089a0c1f4bb8c410290c737\", \"gin_unittests\": \"07d4115daf3bd239cf8de592e813f932ede20bca\", \"gl_unittests_ozone\": \"ed85923155e72ea949ba6ad9cdab40bb4df7bbc8\", \"google_apis_unittests\": \"8cda16a004e26eb60edb02f94a7996870691cf81\", \"gpu_unittests\": \"71fd73d68ac105c8b596e4efa71803fada9dc49a\", \"gwp_asan_unittests\": \"db92ffcf727bcb790ceaef603426ce086095a482\", \"interactive_ui_tests\": \"e12af1e6bb2a604ffa7b85819674dd1b40c196bb\", \"ipc_tests\": \"b5b308daeee791992d1dfa2fe26c0c28da45fcbe\", \"jingle_unittests\": \"0ebb0b8df44e1c8302ed28508e41cfe3c39b840c\", \"keyboard_unittests\": \"60b3ad49394b43877d8d8f7e854fa57de6620271\", \"latency_unittests\": \"4ac6e6a222de8af8dfa4b0a668cf44af0c948639\", \"libjingle_xmpp_unittests\": \"3c5b81617baf43e661dbdefb9a493a8aac326b42\", \"liburlpattern_unittests\": \"f006f2b1da2f7ea3bff6aa391536fd30902b55db\", \"media_blink_unittests\": \"2f83b979570b5de172d2972eb5689c4b9105ce97\", \"media_unittests\": \"59335c4dec09f362d0892beea80160ea7c3abc41\", \"message_center_unittests\": \"7c0b95ba07880fe3c47ebb66419c8dc2e4bd4e2e\", \"midi_unittests\": \"bded905099ec4f8fc020f4fcfca655b7eea2e3eb\", \"mojo_core_unittests\": \"535a53e1652ff2bf1cbe25160dfa1cc9e874e7ff\", \"mojo_unittests\": \"4797a2a58ed64c9829712cfa1ba9e8dd4f2bf865\", \"nacl_helper_nonsfi_unittests\": \"72b51ca1fa805da0ccec0c0d758b4f75aadb789b\", \"nacl_loader_unittests\": \"5d6e8c909b05dec24f2af4db9af1030f0d033884\", \"native_theme_unittests\": \"9166a898913c5da551de865f981736941b04de6a\", \"net_unittests\": \"57641dd09e1d21a507ba8adbf7bf9e3eedaa31e4\", \"ozone_gl_unittests\": \"30031d90114533e70a3b64058507dd517fb40ed3\", \"ozone_unittests\": \"028577c89292072b9a7ac7f20c4bf579d103c421\", \"ozone_x11_unittests\": \"eafc7e5bdc44f23d304f44a0f2378b1c6dfd0b7c\", \"pdf_unittests\": \"e5b912e441f610f3b175c8113cfa50fe40b6d61c\", \"perfetto_unittests\": \"72e26f4347e2e9135f057d90057e396f66dd1bb7\", \"ppapi_unittests\": \"6c5fb145cd89b65d647d5946de0a9f774bb4fcfe\", \"printing_unittests\": \"21a71142c258f9c6c602347baa862a6f0ced3de9\", \"remoting_unittests\": \"20639ce4a49e8ae98be9393b0138e4ec54095147\", \"sandbox_linux_unittests\": \"0e875bf8a519062382720a242e7375a0cacbd705\", \"service_manager_unittests\": \"27a34e062e437233ff3b8fe81685703e7aaeac2f\", \"services_unittests\": \"bfb8b36912b38527a8f5a536b71e8aea27c2dd23\", \"shell_dialogs_unittests\": \"f2e7cf85e689037041aef6592ecaffeb2a4ceaa4\", \"shell_encryption_unittests\": \"cc0cbf47ba867c9acae6af45309063d569386408\", \"skia_unittests\": \"43d33efcd0878ffa6b24cfe241a29a69327c9b7a\", \"snapshot_unittests\": \"8929539990139ae90ef0043336d74eef0dfd5684\", \"sql_unittests\": \"f220d5e61fbb710848561259dcca65f4b11b38fa\", \"storage_unittests\": \"869b06cbcc551c0527b7b3a4a69da0dd1647d781\", \"sync_integration_tests\": \"0fbaeb17b1f2cc2dd1b3676b190534546fecaba4\", \"traffic_annotation_auditor_unittests\": \"23236310e6231399cacbfcfefb6ee2cfe7474338\", \"ui_base_unittests\": \"43b22d8a34ac9b1c5cb287aa922bbb9325ae12eb\", \"ui_chromeos_unittests\": \"84e6978133ad82d3ac8e650a69d50a9059671546\", \"ui_touch_selection_unittests\": \"80eb57b9fa47f38437fad2be0634a2a31e687c3f\", \"unit_tests\": \"944a1914712ab80b8d99b2d65fafee435c2a9f54\", \"url_unittests\": \"e048e3b1d872e7d7b1e934a675c4563846094429\", \"usage_time_limit_unittests\": \"9c3d231cb7ddee6858e4d5cab443d92d246f4783\", \"views_unittests\": \"1106bee699ed54135da3348549f8ddd4936fb37f\", \"viz_unittests\": \"bbec7d60c8de12c940225841c277f9c12a9f436c\", \"wayland_client_perftests\": \"50acaf4c2e5096b74a2e6bfe17c52214ca5c9ef9\", \"wm_unittests\": \"735ddedae7068cbff8c566c92a0319ab3756db0e\", \"wtf_unittests\": \"cef57e068a6f0914d161e3f39e75f1c68832be0d\", \"zlib_unittests\": \"4c24c206f9972da769e308ee74b2beca2ac68882\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_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-94-1j8q\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5681-01e7fd1\"], \"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- ExtensionApiTabTest.TabAudible\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612769067889647",
"status": "COMPLETED",
"status_changed_ts": "1612769068627060",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45230",
"builder:linux-chromeos-rel",
"buildset:commit/git/3784e09eeaae8d82bebdfd70e217619ab1a2f58b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3784e09eeaae8d82bebdfd70e217619ab1a2f58b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021312842128875696",
"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/8855882780114387936/+/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:51995884fad66610",
"user_agent:luci-scheduler"
],
"updated_ts": "1612771915702430",
"url": "https://ci.chromium.org/b/8855882780114387936",
"utcnow_ts": "1612771917659660"
}
}
[08:11:57.747] Invocation finished in 47m33.344009785s with status FAILED