[02:52:50.978] New invocation is queued and will start shortly
[02:52:52.079] Starting the invocation (attempt 1)
[02:52:52.122] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:52:52.122] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:52:52.122] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018702615013570240",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0f2726ee6a733f6ae507ca07d797c3c4dd61f606\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9018702615013570240",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/0f2726ee6a733f6ae507ca07d797c3c4dd61f606",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f2726ee6a733f6ae507ca07d797c3c4dd61f606",
"gitiles_ref:refs/heads/master"
]
}
[02:52:53.300] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615258372266730",
"id": "8853272553001504144",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0f2726ee6a733f6ae507ca07d797c3c4dd61f606\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615258372989374",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46005",
"builder:linux-chromeos-rel",
"buildset:commit/git/0f2726ee6a733f6ae507ca07d797c3c4dd61f606",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f2726ee6a733f6ae507ca07d797c3c4dd61f606",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018702615013570240",
"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/8853272553001504144/+/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": "1615258372989954",
"url": "https://ci.chromium.org/b/8853272553001504144",
"utcnow_ts": "1615258373292637"
}
}
[02:52:53.300] Task URL: https://ci.chromium.org/b/8853272553001504144
[02:52:53.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:2:0) after 1m0s
[02:53:00.977] Received PubSub notification, asking Buildbucket for the build status
[02:53:01.175] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:53.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:2:0)
[02:53:53.354] Timer tick, asking Buildbucket for the build status
[02:53:53.432] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:53.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:4:0) after 1m0s
[02:54:53.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:4:0)
[02:54:53.469] Timer tick, asking Buildbucket for the build status
[02:54:53.554] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:53.554] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:5:0) after 1m0s
[02:55:53.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:5:0)
[02:55:53.661] Timer tick, asking Buildbucket for the build status
[02:55:53.738] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:53.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:6:0) after 1m0s
[02:56:53.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:6:0)
[02:56:53.746] Timer tick, asking Buildbucket for the build status
[02:56:53.810] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:53.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:7:0) after 1m0s
[02:57:53.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:7:0)
[02:57:53.814] Timer tick, asking Buildbucket for the build status
[02:57:53.882] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:53.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:8:0) after 1m0s
[02:58:53.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:8:0)
[02:58:53.917] Timer tick, asking Buildbucket for the build status
[02:58:54.006] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:54.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:9:0) after 1m0s
[02:59:54.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:9:0)
[02:59:54.099] Timer tick, asking Buildbucket for the build status
[02:59:54.168] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:54.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:10:0) after 1m0s
[03:00:54.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:10:0)
[03:00:54.308] Timer tick, asking Buildbucket for the build status
[03:00:54.793] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.793] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:11:0) after 1m0s
[03:01:54.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:11:0)
[03:01:54.901] Timer tick, asking Buildbucket for the build status
[03:01:54.966] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:54.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:12:0) after 1m0s
[03:02:55.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:12:0)
[03:02:55.147] Timer tick, asking Buildbucket for the build status
[03:02:56.076] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:56.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:13:0) after 1m0s
[03:03:56.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:13:0)
[03:03:56.192] Timer tick, asking Buildbucket for the build status
[03:03:56.328] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:56.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:14:0) after 1m0s
[03:04:56.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:14:0)
[03:04:56.698] Timer tick, asking Buildbucket for the build status
[03:04:56.909] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:56.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:15:0) after 1m0s
[03:05:56.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:15:0)
[03:05:56.926] Timer tick, asking Buildbucket for the build status
[03:05:56.987] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:56.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:16:0) after 1m0s
[03:06:56.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:16:0)
[03:06:56.987] Timer tick, asking Buildbucket for the build status
[03:06:57.062] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:57.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:17:0) after 1m0s
[03:07:57.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:17:0)
[03:07:57.277] Timer tick, asking Buildbucket for the build status
[03:07:57.376] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:57.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:18:0) after 1m0s
[03:08:57.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:18:0)
[03:08:57.472] Timer tick, asking Buildbucket for the build status
[03:08:57.559] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:57.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:19:0) after 1m0s
[03:09:57.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:19:0)
[03:09:57.602] Timer tick, asking Buildbucket for the build status
[03:09:57.827] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:57.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:20:0) after 1m0s
[03:10:57.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:20:0)
[03:10:57.866] Timer tick, asking Buildbucket for the build status
[03:10:57.959] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:57.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:21:0) after 1m0s
[03:11:58.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:21:0)
[03:11:58.017] Timer tick, asking Buildbucket for the build status
[03:11:58.112] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:58.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:22:0) after 1m0s
[03:12:58.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:22:0)
[03:12:58.106] Timer tick, asking Buildbucket for the build status
[03:12:58.244] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:58.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:23:0) after 1m0s
[03:13:58.299] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:23:0)
[03:13:58.299] Timer tick, asking Buildbucket for the build status
[03:13:58.384] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:58.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:24:0) after 1m0s
[03:14:58.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:24:0)
[03:14:58.411] Timer tick, asking Buildbucket for the build status
[03:14:58.502] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:58.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:25:0) after 1m0s
[03:15:58.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:25:0)
[03:15:58.532] Timer tick, asking Buildbucket for the build status
[03:15:58.707] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:58.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:26:0) after 1m0s
[03:16:58.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:26:0)
[03:16:58.749] Timer tick, asking Buildbucket for the build status
[03:16:58.864] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:58.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:27:0) after 1m0s
[03:17:58.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:27:0)
[03:17:58.958] Timer tick, asking Buildbucket for the build status
[03:17:59.039] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:59.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:28:0) after 1m0s
[03:18:59.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:28:0)
[03:18:59.117] Timer tick, asking Buildbucket for the build status
[03:18:59.185] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:59.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:29:0) after 1m0s
[03:19:59.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:29:0)
[03:19:59.338] Timer tick, asking Buildbucket for the build status
[03:19:59.432] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:59.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:30:0) after 1m0s
[03:20:59.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:30:0)
[03:20:59.536] Timer tick, asking Buildbucket for the build status
[03:20:59.606] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:59.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:31:0) after 1m0s
[03:21:59.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:31:0)
[03:21:59.899] Timer tick, asking Buildbucket for the build status
[03:21:59.987] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:59.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:32:0) after 1m0s
[03:23:00.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:32:0)
[03:23:00.114] Timer tick, asking Buildbucket for the build status
[03:23:00.308] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:00.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:33:0) after 1m0s
[03:24:00.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:33:0)
[03:24:00.339] Timer tick, asking Buildbucket for the build status
[03:24:00.424] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:00.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:34:0) after 1m0s
[03:25:00.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:34:0)
[03:25:00.500] Timer tick, asking Buildbucket for the build status
[03:25:00.853] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:00.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:35:0) after 1m0s
[03:26:00.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:35:0)
[03:26:00.929] Timer tick, asking Buildbucket for the build status
[03:26:00.996] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:00.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:36:0) after 1m0s
[03:27:01.098] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:36:0)
[03:27:01.098] Timer tick, asking Buildbucket for the build status
[03:27:02.118] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:02.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:37:0) after 1m0s
[03:28:02.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:37:0)
[03:28:02.340] Timer tick, asking Buildbucket for the build status
[03:28:02.844] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:02.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:38:0) after 1m0s
[03:29:02.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:38:0)
[03:29:02.822] Timer tick, asking Buildbucket for the build status
[03:29:02.904] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:02.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:39:0) after 1m0s
[03:30:02.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:39:0)
[03:30:02.975] Timer tick, asking Buildbucket for the build status
[03:30:03.071] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:03.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:40:0) after 1m0s
[03:31:03.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:40:0)
[03:31:03.092] Timer tick, asking Buildbucket for the build status
[03:31:03.189] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:03.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:41:0) after 1m0s
[03:32:03.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:41:0)
[03:32:03.263] Timer tick, asking Buildbucket for the build status
[03:32:03.413] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:03.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:42:0) after 1m0s
[03:33:03.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:42:0)
[03:33:03.450] Timer tick, asking Buildbucket for the build status
[03:33:03.553] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:03.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:43:0) after 1m0s
[03:34:03.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:43:0)
[03:34:03.568] Timer tick, asking Buildbucket for the build status
[03:34:03.657] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:03.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:44:0) after 1m0s
[03:35:03.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:44:0)
[03:35:03.674] Timer tick, asking Buildbucket for the build status
[03:35:03.790] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:03.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:45:0) after 1m0s
[03:36:03.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:45:0)
[03:36:03.835] Timer tick, asking Buildbucket for the build status
[03:36:03.920] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:03.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:46:0) after 1m0s
[03:37:03.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:46:0)
[03:37:03.927] Timer tick, asking Buildbucket for the build status
[03:37:04.092] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:04.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:47:0) after 1m0s
[03:38:04.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:47:0)
[03:38:04.145] Timer tick, asking Buildbucket for the build status
[03:38:04.235] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:04.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:48:0) after 1m0s
[03:39:04.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:48:0)
[03:39:04.240] Timer tick, asking Buildbucket for the build status
[03:39:04.331] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:04.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:49:0) after 1m0s
[03:40:04.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:49:0)
[03:40:04.386] Timer tick, asking Buildbucket for the build status
[03:40:04.452] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:04.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:50:0) after 1m0s
[03:41:04.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:50:0)
[03:41:04.436] Timer tick, asking Buildbucket for the build status
[03:41:04.523] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:04.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:51:0) after 1m0s
[03:42:04.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:51:0)
[03:42:04.594] Timer tick, asking Buildbucket for the build status
[03:42:04.705] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:04.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:52:0) after 1m0s
[03:43:04.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:52:0)
[03:43:04.868] Timer tick, asking Buildbucket for the build status
[03:43:05.079] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:05.079] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:53:0) after 1m0s
[03:44:05.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:53:0)
[03:44:05.121] Timer tick, asking Buildbucket for the build status
[03:44:05.205] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:05.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:54:0) after 1m0s
[03:45:05.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:54:0)
[03:45:05.319] Timer tick, asking Buildbucket for the build status
[03:45:05.406] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:05.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:55:0) after 1m0s
[03:46:06.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:55:0)
[03:46:06.065] Timer tick, asking Buildbucket for the build status
[03:46:06.201] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:06.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:56:0) after 1m0s
[03:47:06.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:56:0)
[03:47:06.189] Timer tick, asking Buildbucket for the build status
[03:47:06.279] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:06.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:57:0) after 1m0s
[03:48:06.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:57:0)
[03:48:06.418] Timer tick, asking Buildbucket for the build status
[03:48:06.712] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:06.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:58:0) after 1m0s
[03:49:06.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:58:0)
[03:49:06.814] Timer tick, asking Buildbucket for the build status
[03:49:06.957] Build 8853272553001504144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:06.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9018702615013570240:59:0) after 1m0s
[03:50:01.921] Received PubSub notification, asking Buildbucket for the build status
[03:50:02.005] Build 8853272553001504144: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:50:02.005] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615261800891921",
"created_by": "project:chromium",
"created_ts": "1615258372266730",
"id": "8853272553001504144",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0f2726ee6a733f6ae507ca07d797c3c4dd61f606\"}}",
"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\": \"0f2726ee6a733f6ae507ca07d797c3c4dd61f606\", \"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-36-qcsb\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46005, \"got_angle_revision\": \"faa3915ee3f06dade41a2bc260d850b99c5c6845\", \"got_dawn_revision\": \"7c3b5740db6be782ca4d2e78139568b67db1fc0f\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"0f2726ee6a733f6ae507ca07d797c3c4dd61f606\", \"got_revision_cp\": \"refs/heads/master@{#861002}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"bbda0c17c6ca186d829fa99e712875657d9a1155\", \"got_v8_revision\": \"5c86b9ed5fdab5a53c326ae37b77effcef0b67ff\", \"got_v8_revision_cp\": \"refs/heads/9.1.75@{#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\": \"0f2726ee6a733f6ae507ca07d797c3c4dd61f606\", \"swarm_hashes_refs/heads/master(at){#861002}_without_patch\": {\"absl_hardening_tests\": \"bb8dcc1cc8bc8162dd1bfd383f396d7632d81d8f\", \"accessibility_unittests\": \"ab005b6ed6ec85565e04641505346e4432b499dc\", \"angle_unittests\": \"daa2c53440f64a7a3b1cb51bb21110a3135408a5\", \"app_list_unittests\": \"1ca624c62a49e833d41196ba5ea5bfcfe0b92f79\", \"app_shell_unittests\": \"da1aa1c689523328d84901c16d9f0425caa40a8c\", \"ash_unittests\": \"d90b66f3e852e754b343931a1d184ed1ef9d3159\", \"aura_unittests\": \"8f3d18d9d0a1d21095dd9dd5d61c42d4e444ae26\", \"base_unittests\": \"67f85f0a9b9e31bf91872537c15ee1e181b6697c\", \"base_util_unittests\": \"7b426144fa790d6d66fc33c221c5fabc6b10082e\", \"blink_common_unittests\": \"eddfc184e6d130313a292135f0e25905201a60a4\", \"blink_fuzzer_unittests\": \"72c5559beaf0904be70ed4c5596c76d41f3e3961\", \"blink_heap_unittests\": \"e9e0b176a23682ffd1323e56113ed88fdc713721\", \"blink_platform_unittests\": \"26a2ac0831bc6c8e4132ba279d1cdd31e741a0a1\", \"blink_unittests\": \"12787be501076089ba073655323deeb12872c6e0\", \"boringssl_crypto_tests\": \"10d4112d4917eaa4f9627df883c96764c021795a\", \"boringssl_ssl_tests\": \"4163a3fbc047f5374d1905e2d675e9e2cdf0332a\", \"browser_tests\": \"2796455c3d5d09d52ac8f10fbe1ca7ff6689d0fa\", \"capture_unittests\": \"67951acc253927d918ca5e78a2bd3c73856e7e6d\", \"cast_unittests\": \"30ec426fa7f741bf222b956b4d0f620c554b237e\", \"cc_unittests\": \"4883b2d2a6880f72bdc912770a1208cd639481ed\", \"chrome_app_unittests\": \"f6f47ab566c56fc63c3c66e4e1b713cd64c0199b\", \"chromedriver_unittests\": \"6daa1c31bfcb522f525ae059c57d882dedbca06f\", \"chromeos_components_unittests\": \"1178f689996fa370e9adfeb2ea42d34f8bd24d04\", \"chromeos_unittests\": \"b9921927300b5e17bec1efc6f49a034f692bcc06\", \"color_unittests\": \"340b367e0086ecf0d6d64c861cb3b1e6bad248fe\", \"components_browsertests\": \"18897fd1452d3957a1652bc2ba0207cda5addd95\", \"components_unittests\": \"f32cdbe1b6ba65751ffadec48a620ccaa0c27449\", \"compositor_unittests\": \"90560ce8c335e20471628a9a927e49a483353e94\", \"content_browsertests\": \"66011f22cac8e3ca012d872d8be01f065dd81bff\", \"content_unittests\": \"0c4b2a75a45849b25f803603f426dcddde7e8123\", \"crashpad_tests\": \"a0d77d0167edb1c28234a5b66a3de0689a0c687b\", \"crypto_unittests\": \"0d49a4e88f24711a0df8d6b415a11b83907c313b\", \"dbus_unittests\": \"75617d28a2d1250f08be68da93429118ff3306ec\", \"device_unittests\": \"5b4afdd66f03f0fab447aec1da26b20872dfc73e\", \"display_unittests\": \"f43e57ac79e7fdb128403105859790d34a59dbab\", \"events_unittests\": \"00b0c64ee0152b11a4bc3cf752775d3b7a82eaea\", \"exo_unittests\": \"23d0703246c11774e8775accf66c289e161fec47\", \"extensions_browsertests\": \"5039997ce823c0d97057f0b858a4e917c52428dc\", \"extensions_unittests\": \"0ba84f11f16b8f8259196c4490c9694cc50b7fdc\", \"filesystem_service_unittests\": \"1a37d617977575aa204d480bf7f485f3993dee4b\", \"gcm_unit_tests\": \"2ae653b13ddbb03b3a122f1e12cbd2bd605440c1\", \"gfx_unittests\": \"c444ba8578497519406c98c699b4f9f5f9e0833a\", \"gin_unittests\": \"8a9a50a57a262300eb288ab56cf9c51a03d832a0\", \"gl_unittests_ozone\": \"ba376a59df4c6613c744a84cf29361bb7755be13\", \"google_apis_unittests\": \"e6336e18978e2a556af5ea1099f9a15b070c8edf\", \"gpu_unittests\": \"b5f80d03b7df3286f62fee888dbc7050eea8d01f\", \"gwp_asan_unittests\": \"80f08918cec688d85b12a570692947b2a7cdad78\", \"interactive_ui_tests\": \"997624a90d4e0a03389715065d60e924a72081b5\", \"ipc_tests\": \"5953c04d65de7ec4921ba6fa6c119e504a225384\", \"jingle_unittests\": \"3007b1956b03cf11037f9e6c0cb179b2d6c1960c\", \"keyboard_unittests\": \"41cd4e1e849f960874e588833fef5b2c2a0a50e9\", \"latency_unittests\": \"55f753f94f7857f4bc728eaed3c8dafa45395904\", \"libjingle_xmpp_unittests\": \"35e6bbd4f71ccd1ef345cbfebf2ba2a059122883\", \"liburlpattern_unittests\": \"9953acbb056acb72208bd278399187968684e36e\", \"media_blink_unittests\": \"e694d840ac1ee70f1eb78bfb7de021df380dd2e8\", \"media_unittests\": \"7e48630bb720b2a3e88ed3127ba6dd1bbaae6a2a\", \"message_center_unittests\": \"0fb60c75a18503dbf1d83da76678d07419e18f4e\", \"midi_unittests\": \"f31eac835055e4e17facf6b5ebcee57851d47c3a\", \"mojo_core_unittests\": \"ec92a0c45cfa07582d8e05469c8f9431b1d2d569\", \"mojo_unittests\": \"94064be53bedd7c41a82746bb691e35f090e2b2d\", \"nacl_helper_nonsfi_unittests\": \"27194bac108457e4fa3c8f0cbb62a2049eddf122\", \"nacl_loader_unittests\": \"983e4876df25a8eaaccf858f2f92eb87c359035b\", \"native_theme_unittests\": \"4bd0d85ce3abaac83103234bced6518e5ecd4d49\", \"net_unittests\": \"33d7b138091e2537dc69cec8e795cace7b7faadd\", \"ozone_gl_unittests\": \"1af9ced9cab91544b7bc952824b6c0fe82229045\", \"ozone_unittests\": \"45c5e849c0c74610d9217f18a8226f983f2a8b8e\", \"ozone_x11_unittests\": \"d2110e78a92197f1035d50a3a48f986a5db237ee\", \"pdf_unittests\": \"22c95aaef8a5276c893194174b0d6101976a361e\", \"perfetto_unittests\": \"7e74f5de9c1201aabfa96871cc787cf2dbc7bb1f\", \"ppapi_unittests\": \"9e6dd9d57b7f54efcf10f4b93f479a87b4f55a7b\", \"printing_unittests\": \"98510ddbb635ce2177cfdf415421492ccafa1cd0\", \"remoting_unittests\": \"7e6c1c97b72c11bf7cbdaee8156a393f40d9794b\", \"sandbox_linux_unittests\": \"943301499b331f47f74921f70cbba813a47591df\", \"service_manager_unittests\": \"a30617940d5cc9d96e45e0b16d766daf8ab9f004\", \"services_unittests\": \"2ff7774374586079ba6866b5ccebd0ac5bfb82a9\", \"shell_dialogs_unittests\": \"edbf4ca5d4b8adb550b1c54c272a6e4807f7d61f\", \"shell_encryption_unittests\": \"9575ddcf21cf32ef8bcb7e38095f1ac51e5c7d53\", \"skia_unittests\": \"c6fb32b92e916248eccb206c724531995752b19f\", \"snapshot_unittests\": \"8b5fd542983010e5b436e4a15cbb2b25bb9c1483\", \"sql_unittests\": \"1345a74c544948a5b4a9ff620d21647bc51491f6\", \"storage_unittests\": \"5e70f6960e2ee3ce017f4f4c1a546f899768678a\", \"sync_integration_tests\": \"cd2a9f2c5177e219f01aa741c1f1bc998f63db9a\", \"traffic_annotation_auditor_unittests\": \"d7f3553777681dfe87ccc5e855ef376a4b8e30d9\", \"ui_base_unittests\": \"2185537aa22365c62bcfd8a738dde0cda43f94bf\", \"ui_chromeos_unittests\": \"d9766936d5f77723d19c6f290f53a4d3bedfced5\", \"ui_touch_selection_unittests\": \"4ef3d230ab71747dfe57eb178d2cc3736f9d23f8\", \"unit_tests\": \"cb09525a32e54e0cff36b76f335c89a4422916a5\", \"url_unittests\": \"03bf74314e6028c5560ae7ac656581a2bd8c1d16\", \"usage_time_limit_unittests\": \"68b3c95c01a3c55bc64ae0562a157e1b7573457e\", \"views_unittests\": \"e97d74558e1cf3cfe4281eae981b50f83a489a64\", \"viz_unittests\": \"ec04f9d1a4d5204eb51f7317787c03899f46f691\", \"wayland_client_perftests\": \"086351b69475a2d03d158c17423bdee0b3dfa8dd\", \"wayland_client_tests\": \"11fea0964a0a66387beef318c7f74de6f4c74d3f\", \"wm_unittests\": \"dea64f38301222e113a9883b3aa4f1f5e945fc35\", \"wtf_unittests\": \"d1235fcb9fcdd38cf9e47b76fe4a394fb833b4ae\", \"zlib_unittests\": \"23aa18474302b095b5c466a839c772cfa37cb532\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_043ff3f52d8d01364b204065bddda74d1a1fba0b8da9301cf8e96315f69303d6_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_dcef017f754690f3e0b2809d85023c2a50e42950d4504e0de4071c06ccf1a2cc_v2\", \"builder_f5b4454e99a04aaa5ba743b59cc70de19e20bebedef65e50e3b90534a8d6a5e4_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-36-qcsb\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615258380179202",
"status": "COMPLETED",
"status_changed_ts": "1615261801211906",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46005",
"builder:linux-chromeos-rel",
"buildset:commit/git/0f2726ee6a733f6ae507ca07d797c3c4dd61f606",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0f2726ee6a733f6ae507ca07d797c3c4dd61f606",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018702615013570240",
"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/8853272553001504144/+/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:522db855e1105810",
"user_agent:luci-scheduler"
],
"updated_ts": "1615261801216839",
"url": "https://ci.chromium.org/b/8853272553001504144",
"utcnow_ts": "1615261801995938"
}
}
[03:50:02.005] Invocation finished in 57m11.041088677s with status SUCCEEDED