[09:15:54.454] New invocation is queued and will start shortly
[09:15:55.577] Starting the invocation (attempt 1)
[09:15:55.615] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:15:55.615] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:15:55.615] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023027169484779152",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"afa7f8c1a12ce231fd5907d365ea65e4e68f523a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023027169484779152",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/afa7f8c1a12ce231fd5907d365ea65e4e68f523a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/afa7f8c1a12ce231fd5907d365ea65e4e68f523a",
"gitiles_ref:refs/heads/master"
]
}
[09:15:56.383] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611134155696521",
"id": "8857597107515684320",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"afa7f8c1a12ce231fd5907d365ea65e4e68f523a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611134156102276",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44758",
"builder:linux-chromeos-rel",
"buildset:commit/git/afa7f8c1a12ce231fd5907d365ea65e4e68f523a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/afa7f8c1a12ce231fd5907d365ea65e4e68f523a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023027169484779152",
"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/8857597107515684320/+/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": "1611134156102397",
"url": "https://ci.chromium.org/b/8857597107515684320",
"utcnow_ts": "1611134156374815"
}
}
[09:15:56.383] Task URL: https://ci.chromium.org/b/8857597107515684320
[09:15:56.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:2:0) after 1m0s
[09:16:23.282] Received PubSub notification, asking Buildbucket for the build status
[09:16:23.503] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:56.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:2:0)
[09:16:56.428] Timer tick, asking Buildbucket for the build status
[09:16:56.535] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:56.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:4:0) after 1m0s
[09:17:56.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:4:0)
[09:17:56.534] Timer tick, asking Buildbucket for the build status
[09:17:56.782] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:56.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:5:0) after 1m0s
[09:18:56.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:5:0)
[09:18:56.827] Timer tick, asking Buildbucket for the build status
[09:18:56.930] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:56.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:6:0) after 1m0s
[09:19:56.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:6:0)
[09:19:56.937] Timer tick, asking Buildbucket for the build status
[09:19:57.082] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:57.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:7:0) after 1m0s
[09:20:57.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:7:0)
[09:20:57.099] Timer tick, asking Buildbucket for the build status
[09:20:57.203] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:57.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:8:0) after 1m0s
[09:21:57.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:8:0)
[09:21:57.388] Timer tick, asking Buildbucket for the build status
[09:21:57.480] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:57.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:9:0) after 1m0s
[09:22:57.487] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:9:0)
[09:22:57.487] Timer tick, asking Buildbucket for the build status
[09:22:57.666] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:57.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:10:0) after 1m0s
[09:23:57.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:10:0)
[09:23:57.727] Timer tick, asking Buildbucket for the build status
[09:23:57.808] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:57.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:11:0) after 1m0s
[09:24:57.992] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:11:0)
[09:24:57.993] Timer tick, asking Buildbucket for the build status
[09:24:58.378] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:58.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:12:0) after 1m0s
[09:25:58.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:12:0)
[09:25:58.466] Timer tick, asking Buildbucket for the build status
[09:25:58.622] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:58.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:13:0) after 1m0s
[09:26:58.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:13:0)
[09:26:58.669] Timer tick, asking Buildbucket for the build status
[09:26:58.749] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:58.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:14:0) after 1m0s
[09:27:58.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:14:0)
[09:27:58.809] Timer tick, asking Buildbucket for the build status
[09:27:58.873] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:58.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:15:0) after 1m0s
[09:28:58.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:15:0)
[09:28:58.907] Timer tick, asking Buildbucket for the build status
[09:28:59.114] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:59.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:16:0) after 1m0s
[09:29:59.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:16:0)
[09:29:59.157] Timer tick, asking Buildbucket for the build status
[09:29:59.283] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:59.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:17:0) after 1m0s
[09:30:59.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:17:0)
[09:30:59.320] Timer tick, asking Buildbucket for the build status
[09:30:59.448] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:59.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:18:0) after 1m0s
[09:31:59.475] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:18:0)
[09:31:59.475] Timer tick, asking Buildbucket for the build status
[09:31:59.651] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:59.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:19:0) after 1m0s
[09:32:59.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:19:0)
[09:32:59.672] Timer tick, asking Buildbucket for the build status
[09:32:59.813] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:59.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:20:0) after 1m0s
[09:33:59.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:20:0)
[09:33:59.885] Timer tick, asking Buildbucket for the build status
[09:34:00.270] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:00.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:21:0) after 1m0s
[09:35:00.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:21:0)
[09:35:00.286] Timer tick, asking Buildbucket for the build status
[09:35:00.530] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:00.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:22:0) after 1m0s
[09:36:00.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:22:0)
[09:36:00.552] Timer tick, asking Buildbucket for the build status
[09:36:00.668] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:00.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:23:0) after 1m0s
[09:37:00.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:23:0)
[09:37:00.687] Timer tick, asking Buildbucket for the build status
[09:37:00.782] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:00.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:24:0) after 1m0s
[09:38:01.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:24:0)
[09:38:01.543] Timer tick, asking Buildbucket for the build status
[09:38:01.959] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:01.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:25:0) after 1m0s
[09:39:02.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:25:0)
[09:39:02.053] Timer tick, asking Buildbucket for the build status
[09:39:02.142] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:02.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:26:0) after 1m0s
[09:40:02.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:26:0)
[09:40:02.212] Timer tick, asking Buildbucket for the build status
[09:40:02.317] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:02.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:27:0) after 1m0s
[09:41:02.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:27:0)
[09:41:02.336] Timer tick, asking Buildbucket for the build status
[09:41:02.747] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:02.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:28:0) after 1m0s
[09:42:02.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:28:0)
[09:42:02.900] Timer tick, asking Buildbucket for the build status
[09:42:02.964] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:02.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:29:0) after 1m0s
[09:43:03.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:29:0)
[09:43:03.096] Timer tick, asking Buildbucket for the build status
[09:43:03.174] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:03.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:30:0) after 1m0s
[09:44:03.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:30:0)
[09:44:03.259] Timer tick, asking Buildbucket for the build status
[09:44:03.405] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:03.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:31:0) after 1m0s
[09:45:03.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:31:0)
[09:45:03.444] Timer tick, asking Buildbucket for the build status
[09:45:03.518] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:03.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:32:0) after 1m0s
[09:46:03.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:32:0)
[09:46:03.559] Timer tick, asking Buildbucket for the build status
[09:46:03.809] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:03.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:33:0) after 1m0s
[09:47:03.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:33:0)
[09:47:03.877] Timer tick, asking Buildbucket for the build status
[09:47:03.941] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:03.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:34:0) after 1m0s
[09:48:03.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:34:0)
[09:48:03.957] Timer tick, asking Buildbucket for the build status
[09:48:04.047] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:04.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:35:0) after 1m0s
[09:49:04.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:35:0)
[09:49:04.103] Timer tick, asking Buildbucket for the build status
[09:49:04.279] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:04.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:36:0) after 1m0s
[09:50:04.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:36:0)
[09:50:04.368] Timer tick, asking Buildbucket for the build status
[09:50:04.530] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:04.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:37:0) after 1m0s
[09:51:04.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:37:0)
[09:51:04.538] Timer tick, asking Buildbucket for the build status
[09:51:04.807] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:04.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:38:0) after 1m0s
[09:52:04.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:38:0)
[09:52:04.843] Timer tick, asking Buildbucket for the build status
[09:52:04.924] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:04.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:39:0) after 1m0s
[09:53:05.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:39:0)
[09:53:05.012] Timer tick, asking Buildbucket for the build status
[09:53:05.922] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:05.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:40:0) after 1m0s
[09:54:05.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:40:0)
[09:54:05.991] Timer tick, asking Buildbucket for the build status
[09:54:06.061] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:06.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:41:0) after 1m0s
[09:55:06.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:41:0)
[09:55:06.065] Timer tick, asking Buildbucket for the build status
[09:55:06.131] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:06.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:42:0) after 1m0s
[09:56:06.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:42:0)
[09:56:06.133] Timer tick, asking Buildbucket for the build status
[09:56:06.320] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:06.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:43:0) after 1m0s
[09:57:06.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:43:0)
[09:57:06.398] Timer tick, asking Buildbucket for the build status
[09:57:06.539] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:06.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:44:0) after 1m0s
[09:58:07.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:44:0)
[09:58:07.922] Timer tick, asking Buildbucket for the build status
[09:58:08.144] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:08.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:45:0) after 1m0s
[09:59:08.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:45:0)
[09:59:08.395] Timer tick, asking Buildbucket for the build status
[09:59:08.496] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:08.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:46:0) after 1m0s
[10:00:08.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:46:0)
[10:00:08.628] Timer tick, asking Buildbucket for the build status
[10:00:08.841] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:08.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:47:0) after 1m0s
[10:01:09.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:47:0)
[10:01:09.121] Timer tick, asking Buildbucket for the build status
[10:01:09.226] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:09.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:48:0) after 1m0s
[10:02:09.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:48:0)
[10:02:09.413] Timer tick, asking Buildbucket for the build status
[10:02:09.540] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:09.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:49:0) after 1m0s
[10:03:09.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:49:0)
[10:03:09.561] Timer tick, asking Buildbucket for the build status
[10:03:09.842] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:09.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:50:0) after 1m0s
[10:04:09.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:50:0)
[10:04:09.882] Timer tick, asking Buildbucket for the build status
[10:04:11.512] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:11.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:51:0) after 1m0s
[10:05:11.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:51:0)
[10:05:11.589] Timer tick, asking Buildbucket for the build status
[10:05:12.383] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:12.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:52:0) after 1m0s
[10:06:12.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:52:0)
[10:06:12.467] Timer tick, asking Buildbucket for the build status
[10:06:12.847] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:12.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:53:0) after 1m0s
[10:07:12.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:53:0)
[10:07:12.867] Timer tick, asking Buildbucket for the build status
[10:07:13.015] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:13.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:54:0) after 1m0s
[10:08:13.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:54:0)
[10:08:13.078] Timer tick, asking Buildbucket for the build status
[10:08:13.376] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:13.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:55:0) after 1m0s
[10:09:13.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:55:0)
[10:09:13.430] Timer tick, asking Buildbucket for the build status
[10:09:13.582] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:13.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:56:0) after 1m0s
[10:10:13.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:56:0)
[10:10:13.602] Timer tick, asking Buildbucket for the build status
[10:10:13.733] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:13.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:57:0) after 1m0s
[10:11:13.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:57:0)
[10:11:13.842] Timer tick, asking Buildbucket for the build status
[10:11:14.013] Build 8857597107515684320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:14.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023027169484779152:58:0) after 1m0s
[10:11:30.965] Received PubSub notification, asking Buildbucket for the build status
[10:11:31.036] Build 8857597107515684320: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:11:31.036] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611137489979698",
"created_by": "project:chromium",
"created_ts": "1611134155696521",
"id": "8857597107515684320",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"afa7f8c1a12ce231fd5907d365ea65e4e68f523a\"}}",
"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\": \"afa7f8c1a12ce231fd5907d365ea65e4e68f523a\", \"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-65-yzph\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44758, \"got_angle_revision\": \"192a0147c3fe465650b3a3ef45ea61c02b2de5a1\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"afa7f8c1a12ce231fd5907d365ea65e4e68f523a\", \"got_revision_cp\": \"refs/heads/master@{#845108}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"22be66d4071284ffeeef424adc068e9e400835ab\", \"got_v8_revision\": \"69c461e9bd40c5c45d553d3d19bb2771e32b4d64\", \"got_v8_revision_cp\": \"refs/heads/9.0.18@{#1}\", \"got_webrtc_revision\": \"5eb527cf7fb28e22d0687c0295cb9f1bb458160c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33034}\", \"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\": \"afa7f8c1a12ce231fd5907d365ea65e4e68f523a\", \"swarm_hashes_refs/heads/master(at){#845108}_without_patch\": {\"absl_hardening_tests\": \"4767f927d898e6fd5b931ab9ba7c7b063651b93c\", \"accessibility_unittests\": \"061b3cecdec2b43498e8b10901f87185116486f0\", \"angle_unittests\": \"ced84566a73bc051b5c2917b6ea2ffb296287f53\", \"app_list_unittests\": \"20c97734d4f54dab74f0248aeacb4157c75c4cb9\", \"app_shell_unittests\": \"351945ef540275279f84b657c059e7642d7527ee\", \"ash_unittests\": \"ae315fb6cb800f354df2049f060c1a680a8dc768\", \"aura_unittests\": \"d1839c6a0394b68e6c9a59b3a1396c90e681612f\", \"base_unittests\": \"22a3da8659c4b04b924b8a23d920c654fa077f3d\", \"base_util_unittests\": \"f997a83cfd9d6aa6af78e38edf59725b845a69d4\", \"blink_common_unittests\": \"2edf3268fde93c2440a1a3a1b41dde140a59315f\", \"blink_fuzzer_unittests\": \"2f4cc3fd079b53ca55f07096c080574fe55992aa\", \"blink_heap_unittests\": \"c95d6db3883b34fdadfd39e61afb652b87bddf38\", \"blink_platform_unittests\": \"6d302e65f67682436ce00d4efde358fdc3289501\", \"blink_unittests\": \"581fbbbc70eeeb0234c7cf507d67666c92035372\", \"boringssl_crypto_tests\": \"607aeda92f0a65ed54f5277d0014f4c93b429942\", \"boringssl_ssl_tests\": \"e49e5de5a0ccd0e64126f1b02db682ade2596fde\", \"browser_tests\": \"4dbc838e646c80c334176a886c9ef9998ca8bdec\", \"capture_unittests\": \"fca8d843dd3e368295105f3a9cb4c8146ddb225b\", \"cast_unittests\": \"8771ffb342476c2b9735df430f5c448c2b3feea1\", \"cc_unittests\": \"d1cc398a36eb0d807c724ea81a19b8774df574ac\", \"chrome_app_unittests\": \"1233dd2aa48e2dec1fce0c05074afccae2d69bd4\", \"chromedriver_unittests\": \"68af48e3c25ccfeae2818cd984678ad8cfe4bacf\", \"chromeos_components_unittests\": \"0806e3837a913df9c2156f0da4af96c316004f30\", \"chromeos_unittests\": \"37c4785e70c1963c4c47eac97b0b3d866667bece\", \"components_browsertests\": \"f8cb7a4101fa8731bcece186e3b1f285698e5960\", \"components_unittests\": \"00f95898a6e281377a15551790c22e8a89c20f0f\", \"compositor_unittests\": \"0dc0ad86e8f315ce0bc5297f305dd0d5682e7f74\", \"content_browsertests\": \"ac5dae3ef17a223ff9674f765004fdf6f2404ee6\", \"content_unittests\": \"38e0d3b8d8230f689fda1e0e7ffb1f2c9fd08240\", \"crashpad_tests\": \"b33fb818123c9dfbe776b3e17bd355320c5fb41c\", \"crypto_unittests\": \"43be19d903c8f56137a631d541c68dd9ddccceea\", \"dbus_unittests\": \"d9856378c21d3e8a6a76a2008496e5abc5de4027\", \"device_unittests\": \"2fdaa043a3314bd8a2442837a4da024b78171eb9\", \"display_unittests\": \"0999a818c5e576fef544507c8d58b879038d6a2f\", \"events_unittests\": \"fd0b863c87db7c13ac20cce70829f185e51ea0e7\", \"exo_unittests\": \"52530f3d2992bb889adc4019acdb14692f8a61fa\", \"extensions_browsertests\": \"b4502e4ffa1e438e145293cb598cf7533fada06f\", \"extensions_unittests\": \"6348c6c1dcb9fa836f7abb27c7a454e073700a76\", \"filesystem_service_unittests\": \"c97d17a4221db2f02829897d7b3390d398792a07\", \"gcm_unit_tests\": \"58412b7b5439fcd71fedf9a90dd2a0e9e4cff9cb\", \"gfx_unittests\": \"7dc8d20533f5e9d457383af1d0b30f655311f52c\", \"gin_unittests\": \"bb00dd36aba4e0ed873a57832838e9f5998a5c51\", \"gl_unittests_ozone\": \"8bb3705f392350e4f18855a8b0f00c0d99064c4b\", \"google_apis_unittests\": \"5b5ffd7e876225342f28eaffb5482e985915c495\", \"gpu_unittests\": \"85aac22d04f3b97e2c3806ce29157fec44c0ae50\", \"gwp_asan_unittests\": \"ce06764402fcba2e4b55dec0279c3f70c0818ad7\", \"interactive_ui_tests\": \"240be55a50a093be5afd0443061586df1c249b76\", \"ipc_tests\": \"937faa48aa788eaf941f18a9b8c22db77e773ea7\", \"jingle_unittests\": \"924fdb1cf5f9142993774deb27b54f2108069713\", \"keyboard_unittests\": \"bba06a3b300fb9be8449985b8d98d8714a91339c\", \"latency_unittests\": \"262f0213a8901fb3166692d8741d72e522bbb44a\", \"libjingle_xmpp_unittests\": \"c11faac12f1c20ac7eba0721a6ac93b77611a610\", \"liburlpattern_unittests\": \"a6bfbf9d2e627cdaec9a4ec0f1e79418c813b938\", \"media_blink_unittests\": \"299c2746f607e34b7d3a409fda58d3880a3462e7\", \"media_unittests\": \"e7ddf21fc2585b745a0bf166f4dbd5012c855909\", \"message_center_unittests\": \"636861b714ee83a84a7da1191324470c5ba8daa6\", \"midi_unittests\": \"45d3d0228125533f3a492f70b80a068ff2be3121\", \"mojo_core_unittests\": \"ac8263f90395096a6238bf45c654ec6123830aaf\", \"mojo_unittests\": \"225308d08f5618a5709711d2de123409689cc848\", \"nacl_helper_nonsfi_unittests\": \"34bdd76a5dfa2e0c6deac254a2236e146c103472\", \"nacl_loader_unittests\": \"10c952510d67a69c2dee66f9bdafb27da9f67478\", \"native_theme_unittests\": \"87e83f6f566c330d8fbf80cb7b092ed5bb39dd42\", \"net_unittests\": \"fdb79a0ee7addac45de2f0349ce21e62ddf6c24c\", \"ozone_gl_unittests\": \"24ea328158d27a64f4b0ea1fb5c3ce07e84f9c0c\", \"ozone_unittests\": \"70d66ff404e42f3d70cc4ae6414200c97942a5ce\", \"ozone_x11_unittests\": \"0b7aed572e1d4da8798dc2ce7f30acf11270d249\", \"pdf_unittests\": \"bb2354d51409f0951d0201904a9ab0669bb725f8\", \"perfetto_unittests\": \"d6b58341369a218ce386244fd703c85942cd4097\", \"ppapi_unittests\": \"494f08e585d5c7f0a68cf58232a41a0e0cc505a2\", \"printing_unittests\": \"8e4bf84bf6490d2d4a065fe8286a0c368c465b45\", \"remoting_unittests\": \"0dfcccbf5a670855757378c370ec0a1a1ed3222d\", \"sandbox_linux_unittests\": \"f7d6383e207fb40d3cae571fd0d29c16c02c45cc\", \"service_manager_unittests\": \"8d7050f808c79cf72d8d58c46ffdc8682e169a58\", \"services_unittests\": \"7bd6612a84cc16ea9ec8fc77b3f3cc2bd95a22d0\", \"shell_dialogs_unittests\": \"f4a241bfd7618b61166248d1d01b6324c94a958c\", \"shell_encryption_unittests\": \"17fc0f877c05ae9d47c5512d00a4e178642e21d9\", \"skia_unittests\": \"88f58e89c76a1c010f83d9e6a35299b7738dc68e\", \"snapshot_unittests\": \"7e8633230dfee02d9667df57f703af80bce6021c\", \"sql_unittests\": \"a56e2a2978fba995525a6ee1e96bcece0f0e588c\", \"storage_unittests\": \"aa648583420f2b0a87a502b684f866e2f4de83e0\", \"sync_integration_tests\": \"8dfae930eaf54e7c4d975bb2fa695c04f65ffc54\", \"traffic_annotation_auditor_unittests\": \"97f69a3d4e4ad582284dad579afb40518bdd66dd\", \"ui_base_unittests\": \"8d673420931b6a3f4e0fb0806130a6ffb8893c69\", \"ui_chromeos_unittests\": \"e71d09ec9cfd49b8ad4ff748b0eff073349dade2\", \"ui_touch_selection_unittests\": \"f52fcd92499b2370468d1b05dbe33ffd993b647f\", \"unit_tests\": \"054f074ecb548b5e38229cdedeea54fc165404bc\", \"url_unittests\": \"e9fdbfc58b66e5853ff2af5b99e0edc452be2bf0\", \"usage_time_limit_unittests\": \"aa6124a8188db762afa83f28915927d38bf7ada9\", \"views_unittests\": \"7a2f808e009ca57d136dd97c66eb5ce39d5f2523\", \"viz_unittests\": \"642dca58acad16bb228b416898af64dcf9ded6ed\", \"wayland_client_perftests\": \"0f566f1eb773727c7ad17130ca6bbfb6323b4811\", \"wm_unittests\": \"ff7a397c42d2284e5c9bfbfb0254f0cd696984f6\", \"wtf_unittests\": \"91b431cd947445f22db4adfbf56e1422b48d7e0d\", \"zlib_unittests\": \"dff1d2dad7cdcce911d3f679ff07a49374997c16\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3e35691df8e10f0f9a2876627ba5bcbf70dc06793975d132d4535f10bccc6635_v2\", \"builder_6012964051d5021e7307eb12ad598b318c657120f21d3654d81218fef56916e6_v2\", \"builder_8f1f1a811acb8ac7cee91f001f34cb98db55e889611ed2a31914ce7c854c1541_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9ab1612fdb00db29a8d60b17a842600a8e29ecb37944be685dafbc3ddd01329a_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"builder_ce22d0fc5c297d03141230de59f52ebaf9005395a667e8b14df59e499796620f_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-65-yzph\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611134182417674",
"status": "COMPLETED",
"status_changed_ts": "1611137490331703",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44758",
"builder:linux-chromeos-rel",
"buildset:commit/git/afa7f8c1a12ce231fd5907d365ea65e4e68f523a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/afa7f8c1a12ce231fd5907d365ea65e4e68f523a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023027169484779152",
"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/8857597107515684320/+/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:5137e5c746105110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611137490337627",
"url": "https://ci.chromium.org/b/8857597107515684320",
"utcnow_ts": "1611137491027445"
}
}
[10:11:31.036] Invocation finished in 55m36.601816541s with status SUCCEEDED