[00:28:34.593] New invocation is queued and will start shortly
[00:28:35.664] Starting the invocation (attempt 1)
[00:28:35.729] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:28:35.729] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:28:35.729] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019980049419952736",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4366379f59059225d17cd224e81f3585ccd8f037\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019980049419952736",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4366379f59059225d17cd224e81f3585ccd8f037",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4366379f59059225d17cd224e81f3585ccd8f037",
"gitiles_ref:refs/heads/master"
]
}
[00:28:36.650] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1614040115826211",
"id": "8854549987466242480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4366379f59059225d17cd224e81f3585ccd8f037\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614040116228837",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45644",
"builder:linux-chromeos-rel",
"buildset:commit/git/4366379f59059225d17cd224e81f3585ccd8f037",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4366379f59059225d17cd224e81f3585ccd8f037",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019980049419952736",
"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/8854549987466242480/+/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": "1614040116229199",
"url": "https://ci.chromium.org/b/8854549987466242480",
"utcnow_ts": "1614040116638076"
}
}
[00:28:36.650] Task URL: https://ci.chromium.org/b/8854549987466242480
[00:28:36.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:2:0) after 1m0s
[00:28:39.649] Received PubSub notification, asking Buildbucket for the build status
[00:28:39.721] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:36.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:2:0)
[00:29:36.721] Timer tick, asking Buildbucket for the build status
[00:29:36.813] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:36.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:4:0) after 1m0s
[00:30:36.843] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:4:0)
[00:30:36.843] Timer tick, asking Buildbucket for the build status
[00:30:37.104] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:37.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:5:0) after 1m0s
[00:31:37.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:5:0)
[00:31:37.120] Timer tick, asking Buildbucket for the build status
[00:31:37.348] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:37.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:6:0) after 1m0s
[00:32:37.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:6:0)
[00:32:37.378] Timer tick, asking Buildbucket for the build status
[00:32:37.443] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:37.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:7:0) after 1m0s
[00:33:37.449] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:7:0)
[00:33:37.449] Timer tick, asking Buildbucket for the build status
[00:33:37.551] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:37.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:8:0) after 1m0s
[00:34:37.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:8:0)
[00:34:37.583] Timer tick, asking Buildbucket for the build status
[00:34:37.744] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:37.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:9:0) after 1m0s
[00:35:37.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:9:0)
[00:35:37.822] Timer tick, asking Buildbucket for the build status
[00:35:37.920] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:37.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:10:0) after 1m0s
[00:36:37.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:10:0)
[00:36:37.939] Timer tick, asking Buildbucket for the build status
[00:36:38.080] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:38.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:11:0) after 1m0s
[00:37:38.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:11:0)
[00:37:38.105] Timer tick, asking Buildbucket for the build status
[00:37:38.205] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:38.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:12:0) after 1m0s
[00:38:38.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:12:0)
[00:38:38.269] Timer tick, asking Buildbucket for the build status
[00:38:38.505] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:38.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:13:0) after 1m0s
[00:39:38.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:13:0)
[00:39:38.531] Timer tick, asking Buildbucket for the build status
[00:39:38.604] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:38.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:14:0) after 1m0s
[00:40:38.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:14:0)
[00:40:38.694] Timer tick, asking Buildbucket for the build status
[00:40:38.834] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:38.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:15:0) after 1m0s
[00:41:38.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:15:0)
[00:41:38.952] Timer tick, asking Buildbucket for the build status
[00:41:39.036] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:39.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:16:0) after 1m0s
[00:42:39.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:16:0)
[00:42:39.105] Timer tick, asking Buildbucket for the build status
[00:42:39.682] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:39.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:17:0) after 1m0s
[00:43:39.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:17:0)
[00:43:39.851] Timer tick, asking Buildbucket for the build status
[00:43:40.039] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:40.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:18:0) after 1m0s
[00:44:40.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:18:0)
[00:44:40.066] Timer tick, asking Buildbucket for the build status
[00:44:40.137] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:40.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:19:0) after 1m0s
[00:45:40.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:19:0)
[00:45:40.211] Timer tick, asking Buildbucket for the build status
[00:45:40.433] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:40.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:20:0) after 1m0s
[00:46:40.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:20:0)
[00:46:40.415] Timer tick, asking Buildbucket for the build status
[00:46:40.495] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:40.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:21:0) after 1m0s
[00:47:40.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:21:0)
[00:47:40.615] Timer tick, asking Buildbucket for the build status
[00:47:40.812] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:40.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:22:0) after 1m0s
[00:48:40.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:22:0)
[00:48:40.883] Timer tick, asking Buildbucket for the build status
[00:48:41.119] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:41.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:23:0) after 1m0s
[00:49:41.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:23:0)
[00:49:41.163] Timer tick, asking Buildbucket for the build status
[00:49:41.331] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:41.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:24:0) after 1m0s
[00:50:41.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:24:0)
[00:50:41.397] Timer tick, asking Buildbucket for the build status
[00:50:41.928] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:41.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:25:0) after 1m0s
[00:51:41.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:25:0)
[00:51:41.973] Timer tick, asking Buildbucket for the build status
[00:51:42.054] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:42.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:26:0) after 1m0s
[00:52:42.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:26:0)
[00:52:42.159] Timer tick, asking Buildbucket for the build status
[00:52:42.420] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:42.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:27:0) after 1m0s
[00:53:42.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:27:0)
[00:53:42.526] Timer tick, asking Buildbucket for the build status
[00:53:42.634] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:42.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:28:0) after 1m0s
[00:54:42.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:28:0)
[00:54:42.760] Timer tick, asking Buildbucket for the build status
[00:54:42.995] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:42.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:29:0) after 1m0s
[00:55:43.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:29:0)
[00:55:43.119] Timer tick, asking Buildbucket for the build status
[00:55:43.404] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:43.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:30:0) after 1m0s
[00:56:43.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:30:0)
[00:56:43.468] Timer tick, asking Buildbucket for the build status
[00:56:43.545] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:43.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:31:0) after 1m0s
[00:57:43.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:31:0)
[00:57:43.569] Timer tick, asking Buildbucket for the build status
[00:57:43.647] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:43.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:32:0) after 1m0s
[00:58:43.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:32:0)
[00:58:43.719] Timer tick, asking Buildbucket for the build status
[00:58:44.055] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:44.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:33:0) after 1m0s
[00:59:44.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:33:0)
[00:59:44.083] Timer tick, asking Buildbucket for the build status
[00:59:44.200] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:44.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:34:0) after 1m0s
[01:00:44.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:34:0)
[01:00:44.498] Timer tick, asking Buildbucket for the build status
[01:00:44.670] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:44.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:35:0) after 1m0s
[01:01:44.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:35:0)
[01:01:44.710] Timer tick, asking Buildbucket for the build status
[01:01:44.835] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:44.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:36:0) after 1m0s
[01:02:44.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:36:0)
[01:02:44.866] Timer tick, asking Buildbucket for the build status
[01:02:45.141] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:45.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:37:0) after 1m0s
[01:03:45.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:37:0)
[01:03:45.199] Timer tick, asking Buildbucket for the build status
[01:03:45.305] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:45.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:38:0) after 1m0s
[01:04:45.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:38:0)
[01:04:45.322] Timer tick, asking Buildbucket for the build status
[01:04:45.419] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:45.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:39:0) after 1m0s
[01:05:45.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:39:0)
[01:05:45.509] Timer tick, asking Buildbucket for the build status
[01:05:45.766] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:45.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:40:0) after 1m0s
[01:06:45.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:40:0)
[01:06:45.872] Timer tick, asking Buildbucket for the build status
[01:06:45.974] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:45.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:41:0) after 1m0s
[01:07:46.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:41:0)
[01:07:46.036] Timer tick, asking Buildbucket for the build status
[01:07:46.184] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:46.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:42:0) after 1m0s
[01:08:46.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:42:0)
[01:08:46.248] Timer tick, asking Buildbucket for the build status
[01:08:46.455] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:46.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:43:0) after 1m0s
[01:09:46.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:43:0)
[01:09:46.558] Timer tick, asking Buildbucket for the build status
[01:09:46.698] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:46.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:44:0) after 1m0s
[01:10:46.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:44:0)
[01:10:46.717] Timer tick, asking Buildbucket for the build status
[01:10:46.812] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:46.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:45:0) after 1m0s
[01:11:46.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:45:0)
[01:11:46.838] Timer tick, asking Buildbucket for the build status
[01:11:47.158] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:47.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:46:0) after 1m0s
[01:12:47.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:46:0)
[01:12:47.216] Timer tick, asking Buildbucket for the build status
[01:12:47.326] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:47.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:47:0) after 1m0s
[01:13:47.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:47:0)
[01:13:47.380] Timer tick, asking Buildbucket for the build status
[01:13:47.810] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:47.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:48:0) after 1m0s
[01:14:47.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:48:0)
[01:14:47.803] Timer tick, asking Buildbucket for the build status
[01:14:47.898] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:47.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:49:0) after 1m0s
[01:15:47.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:49:0)
[01:15:47.933] Timer tick, asking Buildbucket for the build status
[01:15:48.166] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:48.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:50:0) after 1m0s
[01:16:48.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:50:0)
[01:16:48.264] Timer tick, asking Buildbucket for the build status
[01:16:48.388] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:48.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:51:0) after 1m0s
[01:17:48.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:51:0)
[01:17:48.411] Timer tick, asking Buildbucket for the build status
[01:17:48.486] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:48.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:52:0) after 1m0s
[01:18:48.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:52:0)
[01:18:48.570] Timer tick, asking Buildbucket for the build status
[01:18:48.690] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:48.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:53:0) after 1m0s
[01:19:48.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:53:0)
[01:19:48.705] Timer tick, asking Buildbucket for the build status
[01:19:48.796] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:48.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:54:0) after 1m0s
[01:20:48.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:54:0)
[01:20:48.818] Timer tick, asking Buildbucket for the build status
[01:20:48.910] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:48.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:55:0) after 1m0s
[01:21:48.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:55:0)
[01:21:48.955] Timer tick, asking Buildbucket for the build status
[01:21:49.042] Build 8854549987466242480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:49.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019980049419952736:56:0) after 1m0s
[01:21:59.646] Received PubSub notification, asking Buildbucket for the build status
[01:22:00.224] Build 8854549987466242480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:22:00.224] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614043319070340",
"created_by": "project:chromium",
"created_ts": "1614040115826211",
"id": "8854549987466242480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4366379f59059225d17cd224e81f3585ccd8f037\"}}",
"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\": \"4366379f59059225d17cd224e81f3585ccd8f037\", \"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-23-98xn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45644, \"got_angle_revision\": \"4a95488c0edfb4590c808ee01079069da5a6b2b1\", \"got_dawn_revision\": \"2d3c2e355337037dfa6873536bfb3e3a558bf9ed\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"4366379f59059225d17cd224e81f3585ccd8f037\", \"got_revision_cp\": \"refs/heads/master@{#856529}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"47f385bf2cd19da4cb3cebc6ff3186859728a2fd\", \"got_v8_revision_cp\": \"refs/heads/9.0.236@{#1}\", \"got_webrtc_revision\": \"6fe3fa14c6686ba9c51095b97ad2e6833a9b03e5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33319}\", \"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\": \"4366379f59059225d17cd224e81f3585ccd8f037\", \"swarm_hashes_refs/heads/master(at){#856529}_without_patch\": {\"absl_hardening_tests\": \"3c9dd4826c8c4ccb318415964985f914e5ce86cb\", \"accessibility_unittests\": \"f276914ee696616cf1796d84fe85b558e9ce2ba4\", \"angle_unittests\": \"66ddb5f95106e1aa167e9360ab01a2c5c30141b0\", \"app_list_unittests\": \"fa907db89e104c989bf802a88b7b658e3b28bd76\", \"app_shell_unittests\": \"535b47274f30189882237387bf9539f8cec4eda2\", \"ash_unittests\": \"bddb78daecd681fb5d6f5f95ae01655bfd0a2e81\", \"aura_unittests\": \"56585c8c6354a8dfe68f05dc7c86b522385ce6ff\", \"base_unittests\": \"370266c1316a4682504481c4bd28187c8ca162a4\", \"base_util_unittests\": \"593fe48f705ac661ab4a13573c98851530f796b1\", \"blink_common_unittests\": \"5462b4b631e8f5c4ff700f00ee5a669ccb544eaf\", \"blink_fuzzer_unittests\": \"f77241779263986cf4403d4f4f67a20752d86ee6\", \"blink_heap_unittests\": \"67c3e86f988d9830242f7829bf6b80fc7616037e\", \"blink_platform_unittests\": \"397bf662ce192f9832ad2db89b65292b8018c658\", \"blink_unittests\": \"746f2f7e12c9aee23982ceca3a304809e96ee51f\", \"boringssl_crypto_tests\": \"daa0263a682720a16b6b10fe8a47c9d059576e02\", \"boringssl_ssl_tests\": \"ab4d22d30a37855f9635bd40ced5d0b3495acbb1\", \"browser_tests\": \"7a554109aa2b74bbfa99f2b168f8ce3ff3e134b3\", \"capture_unittests\": \"5f3a7f34b5bf4c213401fd1c941962c9e9e56d2a\", \"cast_unittests\": \"8d9346636dc8fe0674824b0a81c1d77fa6817bb7\", \"cc_unittests\": \"3148d96cc8f1def6a45b0094aa4d40da6f2e1d9b\", \"chrome_app_unittests\": \"075a74d8a09360b574042d857cf3b2337bd87bc2\", \"chromedriver_unittests\": \"6e5d499d0168471d958767501c945f49e78b7a2a\", \"chromeos_components_unittests\": \"87c2a7e162eb560d9667cba8483270b0ac310f63\", \"chromeos_unittests\": \"8d3423d4b240930efda6e8604cccbbc04d80c462\", \"components_browsertests\": \"62846057576c9398edf092452e5e32ab667d12fb\", \"components_unittests\": \"fc14c0a3bddd88bc5d775289b632727f5c357dd7\", \"compositor_unittests\": \"5914bad99b5c7d080414246242b8e1a8490d1ce1\", \"content_browsertests\": \"a41c57e89f1b7394ab998ff75c2a4bff2ac23bf6\", \"content_unittests\": \"8d4f33096501dff68ca10392f92df5ff8a93d748\", \"crashpad_tests\": \"bea33522882e4ec4252b663002a1dfd539dae484\", \"crypto_unittests\": \"c0314ba79acac3e4652c583b8d3dab341c18a1ba\", \"dbus_unittests\": \"c27090b5f95da29d2ec8d6962aba6e5c249d9b87\", \"device_unittests\": \"be324f5695d219f85260300f2562d9d72ab768db\", \"display_unittests\": \"110ef14dd8c0b23e3663ba27a6d9affe916be8c1\", \"events_unittests\": \"ae312f286e614cf4b0146c78d131443ee1f2a5d9\", \"exo_unittests\": \"baf9460f4886f6b4222ebda7188856bb2ad87b54\", \"extensions_browsertests\": \"47425c8e799f3fb7fb2865a003893796faa15040\", \"extensions_unittests\": \"7dc0c6638716aa9a612990534ed2f5346714fa3d\", \"filesystem_service_unittests\": \"830e213a3820d9f0a6153ce897687646c7f81ae6\", \"gcm_unit_tests\": \"81eaf8d498b2d5cf2222604ef63c87cba6b03da4\", \"gfx_unittests\": \"8709f49e49cfa0d7f091849b8ba161a62230314a\", \"gin_unittests\": \"9569cc596f107a145116f04ee1a4291e574da083\", \"gl_unittests_ozone\": \"fac01a2d4b0b57c3bf895ae234236ab0e166a272\", \"google_apis_unittests\": \"bb0cdf4b9882fc527abc5eaddf6de799278f5168\", \"gpu_unittests\": \"9dc43e537739ec754095412e97e5f2cf952e81eb\", \"gwp_asan_unittests\": \"5ae64a5423f55d6001b438525641fdea425dce4f\", \"interactive_ui_tests\": \"652dd057f6538b9c152311ae5de7acfe5a248356\", \"ipc_tests\": \"b20e2e061e97b1cdce8f09cd4cb5d4bebef89070\", \"jingle_unittests\": \"dd8e500e545515960e7809c6a6e0db2703e38313\", \"keyboard_unittests\": \"8bc2dfdba38c6ef4f83b405b9b53627f05533fe0\", \"latency_unittests\": \"bffc4462db039d14327559776b46e3817cdf0089\", \"libjingle_xmpp_unittests\": \"1fc05fce2e990c5180f0679ba6ee3eebccf8c792\", \"liburlpattern_unittests\": \"358bb1e5b18275939432edb50ce11cad0ad58b40\", \"media_blink_unittests\": \"b33f22f4cfc6a4d256f153518036b4300f5bc08a\", \"media_unittests\": \"16ae979810bfa4a9768c0df9a0cc30630268ca2f\", \"message_center_unittests\": \"f9e0609703a96fc8d654643f1b0c5f2dedba1cb2\", \"midi_unittests\": \"a3c82d016dbf6c3cd295ab9ca9170f10dc288ad6\", \"mojo_core_unittests\": \"4a81f675a7f3a1a63659d59092b795ac2e63fe55\", \"mojo_unittests\": \"4c8df6e04432840a61d73ae9f45e129a0f20a684\", \"nacl_helper_nonsfi_unittests\": \"1e7eba67fcbfe09ec0a881e17811fc58b0b19747\", \"nacl_loader_unittests\": \"fb34369a1d8f46c03dd0ccfe57685a71728a5a83\", \"native_theme_unittests\": \"45c0dc1bc6da17ae23a80dda66b9d984e43776b3\", \"net_unittests\": \"028421173dac454a15c9206b0543da6a5d5a312f\", \"ozone_gl_unittests\": \"eb9836d2c0b7fcf3f13914f966655074d364d2c4\", \"ozone_unittests\": \"08f8aba01ed5779fe65e3b0ab2b2e6fa67daaf87\", \"ozone_x11_unittests\": \"eddeb1fba0e381a88b71ef59f505bd5361946a85\", \"pdf_unittests\": \"b44f66bca26129039bceb71539b77371ede5680a\", \"perfetto_unittests\": \"f62ba088519aff6ecacfe02237deba4c0f39d895\", \"ppapi_unittests\": \"07046f9a91395891b4ab211c6e0330e44f62a12c\", \"printing_unittests\": \"4199624771c85c63cad77617f88c94ff98c9d4e4\", \"remoting_unittests\": \"d7e22cd15229e74979df58f276e5a9ccd4dcb772\", \"sandbox_linux_unittests\": \"c95c343c6a88804b0704598c5e4e545c3968a25a\", \"service_manager_unittests\": \"11c00d5ec9bf3615141237bf0020b81c2f098abd\", \"services_unittests\": \"48f236debe29494f2582c25d6c0d6cad9be71b56\", \"shell_dialogs_unittests\": \"5d7ead790a574b06e9501fbe2abe8e14f1720bc9\", \"shell_encryption_unittests\": \"38b00278a809a9cb555d94d7ab8c1fc3b77296db\", \"skia_unittests\": \"1c970bda4bbe72e2bae337d95c77a24e1cf31bb7\", \"snapshot_unittests\": \"a9a4c0de7676c18e5ace46c4e0d5e6b1fcf66295\", \"sql_unittests\": \"443fd91f92e078c1ff3632b7d27c97f0560c6309\", \"storage_unittests\": \"78ae624e8cb1cc396bcdf8a2ade8d6f3613b574c\", \"sync_integration_tests\": \"3fab10b403c16cae517cac2d014b690a1a027eb0\", \"traffic_annotation_auditor_unittests\": \"e85fb08347aeb7dc3a4dbbb796948fa2e77e2d8d\", \"ui_base_unittests\": \"40899d845d6b9b500c3a29e83e7471a951972377\", \"ui_chromeos_unittests\": \"4fc70949d87b99e0c585d61e7f9e26386a03dcac\", \"ui_touch_selection_unittests\": \"bc3a3fc0b481852f7de23b025ed09329431e1581\", \"unit_tests\": \"6585d4e93f5efc0dae7ed1f736755089efa35019\", \"url_unittests\": \"e409c10b884e7f63dc85da7544444632cf538a93\", \"usage_time_limit_unittests\": \"c872d4fa4a5ec619054f3417424a5a3d22573e20\", \"views_unittests\": \"eca6e01219847f7052501067b344607c6f3740bc\", \"viz_unittests\": \"3a0c688dc28c207cb627505c384e71f584459389\", \"wayland_client_perftests\": \"badc039b583dc5583f4331b117dbf9c957e720b9\", \"wm_unittests\": \"e276a3db91d0b005d5539d4a4207da91c4d26654\", \"wtf_unittests\": \"2c7e764b73202c4c38ffcc6b59d17ca15bebb406\", \"zlib_unittests\": \"b24526bc66621bcd565d889fb7c11a0bf703f430\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2bbcd421f4eb1113f3a23522b280d70ced47ef846cc2e40eada60721dff8d17e_v2\", \"builder_6e4a31244c7251e88cf04b6a466eafe8dff7b745833b61f340cac0d6618c3644_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"builder_cb8f28a5d59bb80cbafed89b56af03ba2ea85f5e24941631d330b6bd1d886cb5_v2\", \"builder_e9d7d0a19fc068adf341a224b6eb4c464e3f53ed9d47979ef0c80d8aa2ba711c_v2\", \"builder_f0afc432815c064941e1e5f998abb72673d4068febb53723f4aedad56e1b9c62_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-23-98xn\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614040119075848",
"status": "COMPLETED",
"status_changed_ts": "1614043319313587",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45644",
"builder:linux-chromeos-rel",
"buildset:commit/git/4366379f59059225d17cd224e81f3585ccd8f037",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4366379f59059225d17cd224e81f3585ccd8f037",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019980049419952736",
"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/8854549987466242480/+/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:51e51b354c8afe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614043319318321",
"url": "https://ci.chromium.org/b/8854549987466242480",
"utcnow_ts": "1614043320022175"
}
}
[01:22:00.224] Invocation finished in 53m25.644981882s with status SUCCEEDED