[20:39:47.279] New invocation is queued and will start shortly
[20:39:48.345] Starting the invocation (attempt 1)
[20:39:48.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:39:48.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:39:48.406] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018726086023658224",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bbdced286133a180c1b7cd07f08cb94e64f6e9bf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018726086023658224",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/bbdced286133a180c1b7cd07f08cb94e64f6e9bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbdced286133a180c1b7cd07f08cb94e64f6e9bf",
"gitiles_ref:refs/heads/master"
]
}
[20:39:49.053] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615235988519309",
"id": "8853296024061409632",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbdced286133a180c1b7cd07f08cb94e64f6e9bf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615235988925858",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45999",
"builder:linux-chromeos-rel",
"buildset:commit/git/bbdced286133a180c1b7cd07f08cb94e64f6e9bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbdced286133a180c1b7cd07f08cb94e64f6e9bf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018726086023658224",
"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/8853296024061409632/+/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": "1615235988925979",
"url": "https://ci.chromium.org/b/8853296024061409632",
"utcnow_ts": "1615235989047333"
}
}
[20:39:49.053] Task URL: https://ci.chromium.org/b/8853296024061409632
[20:39:49.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:2:0) after 1m0s
[20:40:49.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:2:0)
[20:40:49.144] Timer tick, asking Buildbucket for the build status
[20:40:49.255] Build 8853296024061409632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:49.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:3:0) after 1m0s
[20:41:26.144] Received PubSub notification, asking Buildbucket for the build status
[20:41:26.322] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:49.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:3:0)
[20:41:49.367] Timer tick, asking Buildbucket for the build status
[20:41:49.436] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:49.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:5:0) after 1m0s
[20:42:50.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:5:0)
[20:42:50.046] Timer tick, asking Buildbucket for the build status
[20:42:50.151] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:50.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:6:0) after 1m0s
[20:43:50.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:6:0)
[20:43:50.306] Timer tick, asking Buildbucket for the build status
[20:43:50.424] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:50.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:7:0) after 1m0s
[20:44:50.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:7:0)
[20:44:50.582] Timer tick, asking Buildbucket for the build status
[20:44:50.638] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:50.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:8:0) after 1m0s
[20:45:50.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:8:0)
[20:45:50.724] Timer tick, asking Buildbucket for the build status
[20:45:50.963] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:50.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:9:0) after 1m0s
[20:46:51.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:9:0)
[20:46:51.034] Timer tick, asking Buildbucket for the build status
[20:46:51.121] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:51.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:10:0) after 1m0s
[20:47:51.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:10:0)
[20:47:51.532] Timer tick, asking Buildbucket for the build status
[20:47:51.618] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:51.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:11:0) after 1m0s
[20:48:51.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:11:0)
[20:48:51.766] Timer tick, asking Buildbucket for the build status
[20:48:51.922] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:51.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:12:0) after 1m0s
[20:49:51.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:12:0)
[20:49:51.935] Timer tick, asking Buildbucket for the build status
[20:49:52.021] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:52.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:13:0) after 1m0s
[20:50:54.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:13:0)
[20:50:54.319] Timer tick, asking Buildbucket for the build status
[20:50:54.406] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:54.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:14:0) after 1m0s
[20:51:54.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:14:0)
[20:51:54.476] Timer tick, asking Buildbucket for the build status
[20:51:54.583] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:54.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:15:0) after 1m0s
[20:52:54.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:15:0)
[20:52:54.889] Timer tick, asking Buildbucket for the build status
[20:52:54.955] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:54.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:16:0) after 1m0s
[20:53:55.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:16:0)
[20:53:55.420] Timer tick, asking Buildbucket for the build status
[20:53:58.021] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:58.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:17:0) after 1m0s
[20:54:58.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:17:0)
[20:54:58.303] Timer tick, asking Buildbucket for the build status
[20:54:58.377] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:58.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:18:0) after 1m0s
[20:55:58.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:18:0)
[20:55:58.518] Timer tick, asking Buildbucket for the build status
[20:56:00.146] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:00.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:19:0) after 1m0s
[20:57:00.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:19:0)
[20:57:00.493] Timer tick, asking Buildbucket for the build status
[20:57:00.704] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:00.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:20:0) after 1m0s
[20:58:00.723] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:20:0)
[20:58:00.723] Timer tick, asking Buildbucket for the build status
[20:58:00.851] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:00.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:21:0) after 1m0s
[20:59:01.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:21:0)
[20:59:01.187] Timer tick, asking Buildbucket for the build status
[20:59:01.760] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:01.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:22:0) after 1m0s
[21:00:01.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:22:0)
[21:00:01.821] Timer tick, asking Buildbucket for the build status
[21:00:01.882] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:01.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:23:0) after 1m0s
[21:01:01.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:23:0)
[21:01:01.953] Timer tick, asking Buildbucket for the build status
[21:01:02.038] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:02.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:24:0) after 1m0s
[21:02:02.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:24:0)
[21:02:02.141] Timer tick, asking Buildbucket for the build status
[21:02:02.259] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:02.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:25:0) after 1m0s
[21:03:02.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:25:0)
[21:03:02.537] Timer tick, asking Buildbucket for the build status
[21:03:04.012] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:04.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:26:0) after 1m0s
[21:04:04.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:26:0)
[21:04:04.581] Timer tick, asking Buildbucket for the build status
[21:04:04.996] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:04.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:27:0) after 1m0s
[21:05:05.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:27:0)
[21:05:05.037] Timer tick, asking Buildbucket for the build status
[21:05:05.120] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:05.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:28:0) after 1m0s
[21:06:05.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:28:0)
[21:06:05.162] Timer tick, asking Buildbucket for the build status
[21:06:05.458] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:05.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:29:0) after 1m0s
[21:07:05.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:29:0)
[21:07:05.563] Timer tick, asking Buildbucket for the build status
[21:07:05.767] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:05.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:30:0) after 1m0s
[21:08:05.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:30:0)
[21:08:05.840] Timer tick, asking Buildbucket for the build status
[21:08:05.926] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:05.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:31:0) after 1m0s
[21:09:05.969] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:31:0)
[21:09:05.969] Timer tick, asking Buildbucket for the build status
[21:09:06.075] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:06.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:32:0) after 1m0s
[21:10:06.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:32:0)
[21:10:06.088] Timer tick, asking Buildbucket for the build status
[21:10:06.165] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:06.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:33:0) after 1m0s
[21:11:06.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:33:0)
[21:11:06.351] Timer tick, asking Buildbucket for the build status
[21:11:06.422] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:06.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:34:0) after 1m0s
[21:12:06.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:34:0)
[21:12:06.461] Timer tick, asking Buildbucket for the build status
[21:12:06.543] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:06.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:35:0) after 1m0s
[21:13:06.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:35:0)
[21:13:06.606] Timer tick, asking Buildbucket for the build status
[21:13:06.690] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:06.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:36:0) after 1m0s
[21:14:06.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:36:0)
[21:14:06.760] Timer tick, asking Buildbucket for the build status
[21:14:06.858] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:06.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:37:0) after 1m0s
[21:15:06.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:37:0)
[21:15:06.959] Timer tick, asking Buildbucket for the build status
[21:15:07.072] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:07.072] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:38:0) after 1m0s
[21:16:07.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:38:0)
[21:16:07.119] Timer tick, asking Buildbucket for the build status
[21:16:09.292] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:09.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:39:0) after 1m0s
[21:17:09.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:39:0)
[21:17:09.330] Timer tick, asking Buildbucket for the build status
[21:17:09.455] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:09.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:40:0) after 1m0s
[21:18:09.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:40:0)
[21:18:09.504] Timer tick, asking Buildbucket for the build status
[21:18:09.752] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:09.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:41:0) after 1m0s
[21:19:09.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:41:0)
[21:19:09.806] Timer tick, asking Buildbucket for the build status
[21:19:10.130] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:10.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:42:0) after 1m0s
[21:20:10.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:42:0)
[21:20:10.235] Timer tick, asking Buildbucket for the build status
[21:20:10.315] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:10.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:43:0) after 1m0s
[21:21:10.347] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:43:0)
[21:21:10.347] Timer tick, asking Buildbucket for the build status
[21:21:10.631] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:10.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:44:0) after 1m0s
[21:22:10.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:44:0)
[21:22:10.693] Timer tick, asking Buildbucket for the build status
[21:22:10.793] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:10.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:45:0) after 1m0s
[21:23:10.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:45:0)
[21:23:10.821] Timer tick, asking Buildbucket for the build status
[21:23:10.969] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:10.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:46:0) after 1m0s
[21:24:11.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:46:0)
[21:24:11.051] Timer tick, asking Buildbucket for the build status
[21:24:11.153] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:11.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:47:0) after 1m0s
[21:25:11.202] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:47:0)
[21:25:11.202] Timer tick, asking Buildbucket for the build status
[21:25:11.425] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:11.425] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:48:0) after 1m0s
[21:26:11.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:48:0)
[21:26:11.420] Timer tick, asking Buildbucket for the build status
[21:26:11.793] Build 8853296024061409632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:11.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018726086023658224:49:0) after 1m0s
[21:26:35.465] Received PubSub notification, asking Buildbucket for the build status
[21:26:35.557] Build 8853296024061409632: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:26:35.557] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615238795074725",
"created_by": "project:chromium",
"created_ts": "1615235988519309",
"failure_reason": "BUILD_FAILURE",
"id": "8853296024061409632",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbdced286133a180c1b7cd07f08cb94e64f6e9bf\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- DemoSetupArcSupportedTest.ShowOfflineSetupOptionOnNetworkList\"}, \"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\": \"bbdced286133a180c1b7cd07f08cb94e64f6e9bf\", \"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-9-oniv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45999, \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"bbdced286133a180c1b7cd07f08cb94e64f6e9bf\", \"got_revision_cp\": \"refs/heads/master@{#860840}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"ad7609182d57f01afd2e2769869b7c3e23282119\", \"got_v8_revision_cp\": \"refs/heads/9.1.65@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbdced286133a180c1b7cd07f08cb94e64f6e9bf\", \"swarm_hashes_refs/heads/master(at){#860840}_without_patch\": {\"absl_hardening_tests\": \"42033aee87f14f7caae70e62ce07b154032900b0\", \"accessibility_unittests\": \"e00fc7c9b50cfdd4f6ed1a8a532483de8a98546c\", \"angle_unittests\": \"ae8a186f45d0e569998715fdeb3323f25ec421e1\", \"app_list_unittests\": \"bc87c8aa862bc00787e06c8753ea4dc3676c65fb\", \"app_shell_unittests\": \"cc700e11c288e56f732fc8a39ff1639aa5041055\", \"ash_unittests\": \"23109d208ef627d64ae397e5e3dc0a7780dde3a2\", \"aura_unittests\": \"e313c34f56874d90e988d405425fe9792e7e18d0\", \"base_unittests\": \"85a562ea4f0c4d1cbc9e436b34495911af5fcd48\", \"base_util_unittests\": \"f038265b759aa2a44fb55f4b054878943e935c27\", \"blink_common_unittests\": \"65f7177f513ad9273f08f3c3b730ae17412f15cf\", \"blink_fuzzer_unittests\": \"778c4876db0ad4f3edd3a36a90a712ff7a7958bc\", \"blink_heap_unittests\": \"a90ea163802eafdbd170c3e4456cd9cfc4c9c041\", \"blink_platform_unittests\": \"8f69643a87aa34b82d9377a66cebd488d44e9549\", \"blink_unittests\": \"dba02a3f750b775bf385f38725766710fa17c02b\", \"boringssl_crypto_tests\": \"04d26bf044b9c9cba6645718c183748dc8917c6a\", \"boringssl_ssl_tests\": \"883a3697a0e11e7f91411a460bed70f7730065e2\", \"browser_tests\": \"4ac5d61ffb890de2c9f18b759d2b48d52ff4407b\", \"capture_unittests\": \"443cc16063e5d6b4efea547d3ed23f2fbad45792\", \"cast_unittests\": \"f1d6f417f35d6bd32cd253d024c427a1363a5286\", \"cc_unittests\": \"d2275e13809a9d73d756db99996868d809220722\", \"chrome_app_unittests\": \"013f0190a3b5db8c54609c8f94b1b1cface0c705\", \"chromedriver_unittests\": \"b3b9a69facba36502bbbc1c48d24784c39d200f2\", \"chromeos_components_unittests\": \"414b2cd7623585f42d099f893d1d85a30c39f72e\", \"chromeos_unittests\": \"35c9d2addf5189b7212ed91b9ffe697c1ea9652f\", \"color_unittests\": \"ac3824aa95cc57ae914501408bc71e3b67398f2c\", \"components_browsertests\": \"6163d1a5bbc5e9bf376ebeb32e2299aa7a2b32e5\", \"components_unittests\": \"17c1e349c77fb02d55eb98972b9d660d255484c5\", \"compositor_unittests\": \"7054f464b7b930ef116a16c7123052ea1e76b842\", \"content_browsertests\": \"4e3f7354d464878b7f0c560bde47d2563c47e32e\", \"content_unittests\": \"9ce5ac7df4a46eccdcdbd993cefba83ccfc90720\", \"crashpad_tests\": \"bed9a7f8091a7b7043cf2486d8ba7a1f30211a81\", \"crypto_unittests\": \"39f801dfad3f82028b384212eed182d094b979bb\", \"dbus_unittests\": \"557c61afa249290d1caa8d183c2190badb06714f\", \"device_unittests\": \"c68da5c5a159dabcd779c1045c7ff8f786301a4e\", \"display_unittests\": \"3ca1c0035757ca6ac845dd7c7633a3952f2b3009\", \"events_unittests\": \"2d277d10ab92c6f79e11f3acf07afd0770aa39ac\", \"exo_unittests\": \"953a8fa02d423c7c5d749dd759d7c98473e15195\", \"extensions_browsertests\": \"ebf3c0943f6fa13871c515c6389015e6a36d8167\", \"extensions_unittests\": \"360b5511c30660af67e2b697ef13eb004d0f8e0f\", \"filesystem_service_unittests\": \"04aa712b4f93a427b7f3187aed405690545ca3d2\", \"gcm_unit_tests\": \"aa8baedd7979fe987323776758f50fab08fbcd5e\", \"gfx_unittests\": \"18559c73ca6133810fd01394f45532a76f784f61\", \"gin_unittests\": \"329dec55a833377164ee5c63d70c22b0d8ccca8b\", \"gl_unittests_ozone\": \"75f0c07070ffb283a8e7c4012b8ef0275b82618b\", \"google_apis_unittests\": \"2a3dafb2969ddeb19e824f7b1789f7dd854638ab\", \"gpu_unittests\": \"533884b71745b02e8f3c6a5ce2e0a27407f28ad7\", \"gwp_asan_unittests\": \"1232c099c48f9b072d6f9459c4325b964cb9dd36\", \"interactive_ui_tests\": \"c1f9c76257d73d7dfccfb8f91fd217c3a33c810b\", \"ipc_tests\": \"526671e855b620836875a6d9fe77f6231ff77563\", \"jingle_unittests\": \"30c762c5cbe03e16e762a67e9bc39e3b945b4bf0\", \"keyboard_unittests\": \"d1e9d5c8cd2ba8be74eef053bc01f27b27360a61\", \"latency_unittests\": \"6b873ed11ccc03b9be66ea57e1a37c58f1884309\", \"libjingle_xmpp_unittests\": \"058025b421a779e83a418359c5e4222ed321e233\", \"liburlpattern_unittests\": \"feb9f41ec9af2f92127c0d8cb7dea692021e1e48\", \"media_blink_unittests\": \"7bf19f4e383ef9a01cf669684d7c6e333d4a3ac0\", \"media_unittests\": \"637b0c31f6f9cc973dcce1a70a64110409efa321\", \"message_center_unittests\": \"c7f8b8783162eac9746ea1257cab1663f9a7c9e7\", \"midi_unittests\": \"63d809856d9f4f0c9914050daa2cc001f6293a9f\", \"mojo_core_unittests\": \"469ee37ca12e97e3ff0055491a34a84f9743409b\", \"mojo_unittests\": \"3636c09b45dfc543b2e6a1fb9881936f21fcdd8f\", \"nacl_helper_nonsfi_unittests\": \"be286d5c9bf95fd06396cd13dfb57a993fbacb37\", \"nacl_loader_unittests\": \"4bcd369b5229efba8dd1b865af79a7f96744e8b8\", \"native_theme_unittests\": \"8f6e8deca768eb8fef7794b3a951d216225a3d58\", \"net_unittests\": \"f3f67d18f5dad696f8631de2bc2d52d158bd0677\", \"ozone_gl_unittests\": \"d8e36113334339f2ba99c075e4b5ffb28ebee9fc\", \"ozone_unittests\": \"4eed2a21e598f83f10ff06def6e97b3ad280f776\", \"ozone_x11_unittests\": \"d83badd7c3c31427800b9e7c969036a258a5a852\", \"pdf_unittests\": \"04d8f8f74e5148ebce244de9b4a49e16a26f706d\", \"perfetto_unittests\": \"da691ad0c558d7d71f84a9c733901e170837fbb0\", \"ppapi_unittests\": \"cb7b1ea9c5655b2f4af4e99e76560414c7987012\", \"printing_unittests\": \"778d3e366a501bb542b8f3e91b6700bd3608d486\", \"remoting_unittests\": \"e35ccf813f247f2e7fa7fab38758be1c76f69330\", \"sandbox_linux_unittests\": \"023c06d1e658192614e99797d9d20a0a9aeeeeff\", \"service_manager_unittests\": \"112071f5e91e98f31bb59553bfc8c14da264a498\", \"services_unittests\": \"98c3831f72da82529279850617d960bc78dd8443\", \"shell_dialogs_unittests\": \"2529c564d588a2e849d6b7810d8a7efdb6368277\", \"shell_encryption_unittests\": \"8a970c6110ce7d4908b468486e311d726372cd66\", \"skia_unittests\": \"785e4dbaed6c83a144c619f83bcb93a14d15283a\", \"snapshot_unittests\": \"6896265ca20edbca12f6919942a0fbde451edb16\", \"sql_unittests\": \"147f27658d54072471e57c99ccb2163e5cda7cfd\", \"storage_unittests\": \"5a597b114ab9bcbb3ec5ad47c76c7d1d21748f18\", \"sync_integration_tests\": \"7d638f9704aaeefebae4e476028bd66b6551606a\", \"traffic_annotation_auditor_unittests\": \"5576be0a170faf8f1a5087779ee63506b7607790\", \"ui_base_unittests\": \"496dc48f4124acb6a91aba069b99abe530ea690b\", \"ui_chromeos_unittests\": \"be0ad181338cb7ea7d5196b456fcb08330ddcf6b\", \"ui_touch_selection_unittests\": \"3747b62fff16ca5c8767d483fb507983f854b451\", \"unit_tests\": \"0871dd18f965dbfb1a3da49ee2f80ddefb47693f\", \"url_unittests\": \"d40284dcc4f48ffc011e2c73a1048075c611c1b3\", \"usage_time_limit_unittests\": \"bbd91edb9627fdab16fed3397fb62d64ee0baa63\", \"views_unittests\": \"dd05e642e23044e225101692ec4f20d33791b571\", \"viz_unittests\": \"eeed1b10a9b1169cad2d5e0e4e9af943156f0ede\", \"wayland_client_perftests\": \"af452876d59b2111a6eacdbb18ead47537963049\", \"wayland_client_tests\": \"2e17c9d12e594c86b34212455b2cdfb4fc045d71\", \"wm_unittests\": \"6eba22cadc4901c55cd1e967dacfd0019c6cdaea\", \"wtf_unittests\": \"9f9cb3abd684d3d1b10a73f794fe8e26afe02d3e\", \"zlib_unittests\": \"41f1d8fdbf0390c74e6d2133d19a39292f2a1c8f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_7d3316d3e62555e2d69f10e77e29eb3429b9b1f483dcbd92e41f25657b896dff_v2\", \"builder_8a4e9d5e5a37a98f1521a3a36ddb77cf3e170b8528c1bf60d5d419f9d69b17e3_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_ced96a74d42b033f0c2af7945046235886933cd52f84e329d5240b2ff40f9657_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_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-9-oniv\"], \"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\": [\"5744-5c0a4b4\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- DemoSetupArcSupportedTest.ShowOfflineSetupOptionOnNetworkList\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615236085181782",
"status": "COMPLETED",
"status_changed_ts": "1615236085593644",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45999",
"builder:linux-chromeos-rel",
"buildset:commit/git/bbdced286133a180c1b7cd07f08cb94e64f6e9bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbdced286133a180c1b7cd07f08cb94e64f6e9bf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018726086023658224",
"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/8853296024061409632/+/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:522c62c6c926eb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615238795075120",
"url": "https://ci.chromium.org/b/8853296024061409632",
"utcnow_ts": "1615238795549128"
}
}
[21:26:35.557] Invocation finished in 46m48.293112355s with status FAILED