[01:31:54.290] New invocation is queued and will start shortly
[01:31:55.398] Starting the invocation (attempt 1)
[01:31:55.495] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:31:55.495] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:31:55.496] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022150392378528544",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b573de25591606e78c9100bdaf7c0852b2a2da07\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022150392378528544",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/b573de25591606e78c9100bdaf7c0852b2a2da07",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b573de25591606e78c9100bdaf7c0852b2a2da07",
"gitiles_ref:refs/heads/master"
]
}
[01:31:56.442] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611970315666859",
"id": "8856720330238126480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b573de25591606e78c9100bdaf7c0852b2a2da07\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611970316155052",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45013",
"builder:linux-chromeos-rel",
"buildset:commit/git/b573de25591606e78c9100bdaf7c0852b2a2da07",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b573de25591606e78c9100bdaf7c0852b2a2da07",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022150392378528544",
"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/8856720330238126480/+/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": "1611970316155264",
"url": "https://ci.chromium.org/b/8856720330238126480",
"utcnow_ts": "1611970316429108"
}
}
[01:31:56.442] Task URL: https://ci.chromium.org/b/8856720330238126480
[01:31:56.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:2:0) after 1m0s
[01:32:56.508] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:2:0)
[01:32:56.508] Timer tick, asking Buildbucket for the build status
[01:32:56.750] Build 8856720330238126480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:56.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:3:0) after 1m0s
[01:33:56.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:3:0)
[01:33:56.889] Timer tick, asking Buildbucket for the build status
[01:33:56.981] Build 8856720330238126480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:56.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:4:0) after 1m0s
[01:34:23.713] Received PubSub notification, asking Buildbucket for the build status
[01:34:23.774] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:57.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:4:0)
[01:34:57.045] Timer tick, asking Buildbucket for the build status
[01:34:57.395] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:57.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:6:0) after 1m0s
[01:35:57.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:6:0)
[01:35:57.474] Timer tick, asking Buildbucket for the build status
[01:35:57.576] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:57.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:7:0) after 1m0s
[01:36:57.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:7:0)
[01:36:57.619] Timer tick, asking Buildbucket for the build status
[01:36:57.777] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:57.777] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:8:0) after 1m0s
[01:37:57.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:8:0)
[01:37:57.846] Timer tick, asking Buildbucket for the build status
[01:37:58.158] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:58.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:9:0) after 1m0s
[01:38:58.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:9:0)
[01:38:58.319] Timer tick, asking Buildbucket for the build status
[01:38:58.646] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:58.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:10:0) after 1m0s
[01:39:58.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:10:0)
[01:39:58.732] Timer tick, asking Buildbucket for the build status
[01:39:58.887] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:58.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:11:0) after 1m0s
[01:40:58.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:11:0)
[01:40:58.935] Timer tick, asking Buildbucket for the build status
[01:40:59.040] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:59.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:12:0) after 1m0s
[01:41:59.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:12:0)
[01:41:59.093] Timer tick, asking Buildbucket for the build status
[01:41:59.239] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:59.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:13:0) after 1m0s
[01:42:59.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:13:0)
[01:42:59.342] Timer tick, asking Buildbucket for the build status
[01:42:59.399] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:59.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:14:0) after 1m0s
[01:43:59.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:14:0)
[01:43:59.451] Timer tick, asking Buildbucket for the build status
[01:43:59.717] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:59.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:15:0) after 1m0s
[01:44:59.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:15:0)
[01:44:59.700] Timer tick, asking Buildbucket for the build status
[01:44:59.770] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:59.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:16:0) after 1m0s
[01:45:59.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:16:0)
[01:45:59.793] Timer tick, asking Buildbucket for the build status
[01:45:59.859] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:59.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:17:0) after 1m0s
[01:46:59.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:17:0)
[01:46:59.844] Timer tick, asking Buildbucket for the build status
[01:46:59.949] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:59.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:18:0) after 1m0s
[01:47:59.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:18:0)
[01:47:59.997] Timer tick, asking Buildbucket for the build status
[01:48:00.074] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:00.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:19:0) after 1m0s
[01:49:00.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:19:0)
[01:49:00.117] Timer tick, asking Buildbucket for the build status
[01:49:00.233] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:00.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:20:0) after 1m0s
[01:50:00.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:20:0)
[01:50:00.511] Timer tick, asking Buildbucket for the build status
[01:50:00.780] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:00.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:21:0) after 1m0s
[01:51:01.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:21:0)
[01:51:01.284] Timer tick, asking Buildbucket for the build status
[01:51:01.351] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:01.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:22:0) after 1m0s
[01:52:01.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:22:0)
[01:52:01.350] Timer tick, asking Buildbucket for the build status
[01:52:01.496] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:01.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:23:0) after 1m0s
[01:53:01.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:23:0)
[01:53:01.515] Timer tick, asking Buildbucket for the build status
[01:53:01.610] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:01.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:24:0) after 1m0s
[01:54:01.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:24:0)
[01:54:01.846] Timer tick, asking Buildbucket for the build status
[01:54:01.919] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:01.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:25:0) after 1m0s
[01:55:01.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:25:0)
[01:55:01.941] Timer tick, asking Buildbucket for the build status
[01:55:01.993] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:01.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:26:0) after 1m0s
[01:56:02.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:26:0)
[01:56:02.030] Timer tick, asking Buildbucket for the build status
[01:56:02.505] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:02.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:27:0) after 1m0s
[01:57:02.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:27:0)
[01:57:02.560] Timer tick, asking Buildbucket for the build status
[01:57:02.728] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:02.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:28:0) after 1m0s
[01:58:02.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:28:0)
[01:58:02.766] Timer tick, asking Buildbucket for the build status
[01:58:02.860] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:02.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:29:0) after 1m0s
[01:59:02.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:29:0)
[01:59:02.984] Timer tick, asking Buildbucket for the build status
[01:59:03.066] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:03.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:30:0) after 1m0s
[02:00:03.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:30:0)
[02:00:03.197] Timer tick, asking Buildbucket for the build status
[02:00:03.550] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:03.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:31:0) after 1m0s
[02:01:03.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:31:0)
[02:01:03.570] Timer tick, asking Buildbucket for the build status
[02:01:03.640] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:03.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:32:0) after 1m0s
[02:02:03.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:32:0)
[02:02:03.687] Timer tick, asking Buildbucket for the build status
[02:02:04.110] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:04.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:33:0) after 1m0s
[02:03:04.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:33:0)
[02:03:04.163] Timer tick, asking Buildbucket for the build status
[02:03:04.268] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:04.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:34:0) after 1m0s
[02:04:04.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:34:0)
[02:04:04.332] Timer tick, asking Buildbucket for the build status
[02:04:04.422] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:04.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:35:0) after 1m0s
[02:05:04.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:35:0)
[02:05:04.532] Timer tick, asking Buildbucket for the build status
[02:05:04.624] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:04.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:36:0) after 1m0s
[02:06:04.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:36:0)
[02:06:04.620] Timer tick, asking Buildbucket for the build status
[02:06:04.715] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:04.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:37:0) after 1m0s
[02:07:04.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:37:0)
[02:07:04.722] Timer tick, asking Buildbucket for the build status
[02:07:04.873] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:04.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:38:0) after 1m0s
[02:08:04.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:38:0)
[02:08:04.879] Timer tick, asking Buildbucket for the build status
[02:08:04.995] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:04.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:39:0) after 1m0s
[02:09:05.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:39:0)
[02:09:05.054] Timer tick, asking Buildbucket for the build status
[02:09:05.395] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:05.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:40:0) after 1m0s
[02:10:05.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:40:0)
[02:10:05.493] Timer tick, asking Buildbucket for the build status
[02:10:05.812] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:05.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:41:0) after 1m0s
[02:11:05.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:41:0)
[02:11:05.953] Timer tick, asking Buildbucket for the build status
[02:11:06.459] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:06.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:42:0) after 1m0s
[02:12:06.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:42:0)
[02:12:06.817] Timer tick, asking Buildbucket for the build status
[02:12:06.950] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:06.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:43:0) after 1m0s
[02:13:07.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:43:0)
[02:13:07.046] Timer tick, asking Buildbucket for the build status
[02:13:07.164] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:07.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:44:0) after 1m0s
[02:14:07.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:44:0)
[02:14:07.229] Timer tick, asking Buildbucket for the build status
[02:14:07.396] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:07.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:45:0) after 1m0s
[02:15:07.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:45:0)
[02:15:07.480] Timer tick, asking Buildbucket for the build status
[02:15:07.594] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:07.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:46:0) after 1m0s
[02:16:07.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:46:0)
[02:16:07.742] Timer tick, asking Buildbucket for the build status
[02:16:07.973] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:07.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:47:0) after 1m0s
[02:17:08.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:47:0)
[02:17:08.121] Timer tick, asking Buildbucket for the build status
[02:17:08.248] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:08.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:48:0) after 1m0s
[02:18:08.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:48:0)
[02:18:08.239] Timer tick, asking Buildbucket for the build status
[02:18:08.617] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:08.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:49:0) after 1m0s
[02:19:08.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:49:0)
[02:19:08.645] Timer tick, asking Buildbucket for the build status
[02:19:08.761] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:08.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:50:0) after 1m0s
[02:20:08.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:50:0)
[02:20:08.816] Timer tick, asking Buildbucket for the build status
[02:20:08.925] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:08.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:51:0) after 1m0s
[02:21:08.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:51:0)
[02:21:08.924] Timer tick, asking Buildbucket for the build status
[02:21:09.185] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:09.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:52:0) after 1m0s
[02:22:09.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:52:0)
[02:22:09.246] Timer tick, asking Buildbucket for the build status
[02:22:09.367] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:09.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:53:0) after 1m0s
[02:23:09.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:53:0)
[02:23:09.401] Timer tick, asking Buildbucket for the build status
[02:23:09.551] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:09.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:54:0) after 1m0s
[02:24:09.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:54:0)
[02:24:09.566] Timer tick, asking Buildbucket for the build status
[02:24:09.704] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:09.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:55:0) after 1m0s
[02:25:09.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:55:0)
[02:25:09.716] Timer tick, asking Buildbucket for the build status
[02:25:10.643] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:10.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:56:0) after 1m0s
[02:26:10.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:56:0)
[02:26:10.696] Timer tick, asking Buildbucket for the build status
[02:26:10.905] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:10.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:57:0) after 1m0s
[02:27:10.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:57:0)
[02:27:10.940] Timer tick, asking Buildbucket for the build status
[02:27:11.260] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:11.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:58:0) after 1m0s
[02:28:11.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:58:0)
[02:28:11.408] Timer tick, asking Buildbucket for the build status
[02:28:11.532] Build 8856720330238126480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:11.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022150392378528544:59:0) after 1m0s
[02:28:15.874] Received PubSub notification, asking Buildbucket for the build status
[02:28:15.995] Build 8856720330238126480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:28:15.995] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611973694977070",
"created_by": "project:chromium",
"created_ts": "1611970315666859",
"id": "8856720330238126480",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b573de25591606e78c9100bdaf7c0852b2a2da07\"}}",
"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\": \"b573de25591606e78c9100bdaf7c0852b2a2da07\", \"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-53-7cwa\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45013, \"got_angle_revision\": \"74f7be6d1fd020a432dee63222fa090e4d8df651\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"b573de25591606e78c9100bdaf7c0852b2a2da07\", \"got_revision_cp\": \"refs/heads/master@{#848836}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"c5bdac64cf221b561c1678a59badfbeacd534d46\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33101}\", \"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\": \"b573de25591606e78c9100bdaf7c0852b2a2da07\", \"swarm_hashes_refs/heads/master(at){#848836}_without_patch\": {\"absl_hardening_tests\": \"46585d3a2ae5551bd7391ea675118a527f3d2920\", \"accessibility_unittests\": \"ce8ab60770a33f952c4688d4b2658d4820d134f7\", \"angle_unittests\": \"92f4861701cb6257397ef4adb9924d8d67d01616\", \"app_list_unittests\": \"2e88b3d3985615cf552c3a873d26c6e517283e54\", \"app_shell_unittests\": \"4776b7d6b59d258655494d35784187962159e2b5\", \"ash_unittests\": \"c543feb6246794b026dda786292d7e6e954ba0d6\", \"aura_unittests\": \"ef2b7177a7f620be9a1c99553d8830f7fd2c9652\", \"base_unittests\": \"60fdffd73eaee26b93fe71f8ad26fb1a36e6c129\", \"base_util_unittests\": \"8981c9b59e8fef9eaed7541f41b7306a049408c7\", \"blink_common_unittests\": \"d042e601b56cc9135c9ac8961284daee30149c35\", \"blink_fuzzer_unittests\": \"c4e155a91f790efa994327f3db0344bf2b430fc5\", \"blink_heap_unittests\": \"963531c5c958ea012edb09997ad565d2b9bb8c55\", \"blink_platform_unittests\": \"e89b5a261a1ecd87f543f8e769905d1129e189f9\", \"blink_unittests\": \"b1fbfc4c98bfcf2f4c3d44e1cd34c429828463ed\", \"boringssl_crypto_tests\": \"626ae57819736dd9ed44f80df2166a9f8b6c828b\", \"boringssl_ssl_tests\": \"94acb3823e1db16e26971befd905d2aecee545cf\", \"browser_tests\": \"987525fdf879accd4fad7de525f99b273006b7fb\", \"capture_unittests\": \"81e8b46b37e10a382adb74779fab353305f990e7\", \"cast_unittests\": \"0dfa2abdc1dfa9d68e0f7d11ea3c2452bda607f9\", \"cc_unittests\": \"a41b44e9c649a1d149abf500fd03cbd631add9d9\", \"chrome_app_unittests\": \"ec35ea38543abe30353f0c1ab45ede6be3bd5d71\", \"chromedriver_unittests\": \"52aa82d3800603c71b0e3ce6135c512f3ad8a105\", \"chromeos_components_unittests\": \"5c5bafa631597e22536ed673c610016fa325ccb0\", \"chromeos_unittests\": \"b50c8309285a02a794388187bbb7b09803a29f92\", \"components_browsertests\": \"a9e69a24d0870748fde617009cfc7952efeb34e2\", \"components_unittests\": \"6b265751828a507b3efaa9a5ec1c7bae0a0bcd0b\", \"compositor_unittests\": \"ed2816c8e2a52ba18363c4ab25954926a2a263f4\", \"content_browsertests\": \"6b0a71991ee579ec800cb8c35451f7fe8af06fca\", \"content_unittests\": \"a16d8935665cf389b241efd85216906db8ed241f\", \"crashpad_tests\": \"6f2d02fa7b14836832c184114e2de3c9eb296da4\", \"crypto_unittests\": \"ede510b5404da883201c1a6d021dca4724ba68ea\", \"dbus_unittests\": \"05861f7061a373f74325b3f4314d682f21c565c1\", \"device_unittests\": \"0f35ffbd1392069ca7b5b1b79406c9b5777b87b6\", \"display_unittests\": \"ee0fb48a79d7c04c36ea7de2d56050f3e58201ac\", \"events_unittests\": \"d0eb56994b9e634462ee7938bc4f429e8fd4b091\", \"exo_unittests\": \"44d9fda27d474a63a6a012631ae34c27294461e1\", \"extensions_browsertests\": \"db11b32eb65bfe804d1b8ebf0051ff6e4e2abc40\", \"extensions_unittests\": \"f4f7ad665289cc1321b06c1d21fe0cec887d084d\", \"filesystem_service_unittests\": \"f8b7368d436c4a7d745113dabcc773d2cfbafd0b\", \"gcm_unit_tests\": \"804db0abfab1b205c3cd7264a34272cf0ad529a7\", \"gfx_unittests\": \"db0731834801160fc52a9383640736b0afca23cf\", \"gin_unittests\": \"2d17195ad88ebc4edaf1f3275643c675ca4edd34\", \"gl_unittests_ozone\": \"79c84e65c989d49f841d2768dc2995297c86a423\", \"google_apis_unittests\": \"bc5c11a7caa31be97e44a05ec2bb1fba7cadbcc5\", \"gpu_unittests\": \"afff30c975451706486a4741c74be2ab3ff56f88\", \"gwp_asan_unittests\": \"d9b3fed1f1e139903ad4bb1b2d41b370fcdf9e8e\", \"interactive_ui_tests\": \"28b7a9d4b55155d7aaa4245b054ea04642ef9ed4\", \"ipc_tests\": \"e20d99ba37825befc76272c8b949a7b2fb179cce\", \"jingle_unittests\": \"5a18bec149b4dd281c27ec45b5e29b3e89999305\", \"keyboard_unittests\": \"b89f1364e94bd0d8088912917528f66bf6e790e1\", \"latency_unittests\": \"da2aab9a36eb3761ff09c600142a6768f9bb5187\", \"libjingle_xmpp_unittests\": \"a118ddf9ad7c3bee179ee4f4c9dcefeee3992efa\", \"liburlpattern_unittests\": \"019c3273243e7508c5bd39530e22e17e4247fd72\", \"media_blink_unittests\": \"50768190b961fcc8365a4ef8cf9b545e98dc87c4\", \"media_unittests\": \"18929deddcf00b597ab2bf846b87d4f8358d3744\", \"message_center_unittests\": \"323c6f4d65103330aa7a14b1fcc7e31103ee59c5\", \"midi_unittests\": \"a3dc980c54523663e4b996fa6b8e4b3915768068\", \"mojo_core_unittests\": \"c8c4ebcdd659ca646342ddea994b1e90091f2ef0\", \"mojo_unittests\": \"7c4431cd7ed24fefae95c5cfba76dfffd05ac7b6\", \"nacl_helper_nonsfi_unittests\": \"ec4a1c94254ee75c10fe781819cae3338d372dfc\", \"nacl_loader_unittests\": \"ecae586c63678974cc1cd0fa0b24442b6b03613d\", \"native_theme_unittests\": \"5f3fb6396bc7531e1f700ceaf932ab50fb4fc47d\", \"net_unittests\": \"baa467b2f5cfa30911a05ebe4115f27b55d62206\", \"ozone_gl_unittests\": \"23bdbc245917b0dbb0c6d2d8e6a48a3c3a1ef79c\", \"ozone_unittests\": \"a8eafdb6e3e8360fdbfdbcddbb0fc61b8ac939c6\", \"ozone_x11_unittests\": \"3d4e227fac9465f7c6ab96200935c1602eed4332\", \"pdf_unittests\": \"15560ada54fb704c0872bcc075a3ab00785f1f48\", \"perfetto_unittests\": \"e8e6b37aae5d49271461ad2840df174645cd8805\", \"ppapi_unittests\": \"617c2e12e9886a3b21cca74318d9c5cb366888b4\", \"printing_unittests\": \"4bf3760dc6fdd00773a4f8126b3ea47887c4fc7e\", \"remoting_unittests\": \"4531a54538a8485c12a27521101b742ad31d954b\", \"sandbox_linux_unittests\": \"6495547049482df1dc2228f4515c408b21d1afaa\", \"service_manager_unittests\": \"ed6ae15d0c352e05d63fa304231e7dd7f9ad3a9e\", \"services_unittests\": \"e8454ee37772200bf50ab8cad9652f8b1b0dcbaa\", \"shell_dialogs_unittests\": \"0dfd629d444ed8da024e46952a8755aa5114f92b\", \"shell_encryption_unittests\": \"cf58706dfbec708ae093aba24d60673ed6416b04\", \"skia_unittests\": \"bce2b9ca1f11bb938f93d6050b5d8ce877c42e24\", \"snapshot_unittests\": \"7eadf6dfbf5778f44c48909a1fee57756eb22df6\", \"sql_unittests\": \"179fe047815936ab74609144154573190f38eaff\", \"storage_unittests\": \"3471c106c5553d571843cc233c36752184b29a72\", \"sync_integration_tests\": \"dba9b253157a4c35c4080cbf5814411b23aafe54\", \"traffic_annotation_auditor_unittests\": \"bf52559d7574f717ae6d33bc8271d4d92e1a2eff\", \"ui_base_unittests\": \"2422afb339a77e5b0ab363be93964ab2ccc32923\", \"ui_chromeos_unittests\": \"0a61286f8a13bbdb2405171c3a7bd88039cc5e28\", \"ui_touch_selection_unittests\": \"7253ce88f3cc847d542a5ecaa94f701f2ab1b7b4\", \"unit_tests\": \"b2a371bf353792a6dde62ef197f996ab11074b4d\", \"url_unittests\": \"70895e4e581a3adc12e11c5d2ecc418d95e69270\", \"usage_time_limit_unittests\": \"85b1e23d421844da75720745591ef694c76dfd01\", \"views_unittests\": \"dc9bd11ee74d7634ab5c579d9c18cf70b1f77773\", \"viz_unittests\": \"fa4a9dffa1e1fa10ba895c8a97add8456f5791af\", \"wayland_client_perftests\": \"55fc4a5286faa87f42d2345c098f43dd81f55b67\", \"wm_unittests\": \"85252a68a651bff7acc38c46acc7466191848cce\", \"wtf_unittests\": \"61e675934c555928cc1a4a20f1a03c4361af4ae9\", \"zlib_unittests\": \"34dbe2318a0cea145d78d7254f6460b5160f504d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2da0bee4e22251567f731cb4ff4725bbbd697db00236c0e7ca4bd76c7874f95c_v2\", \"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_71571186c069bf1b966218e7a22ae0e5b91b31e38e06bc6997883c49bda8b59a_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-53-7cwa\"], \"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\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611970463155444",
"status": "COMPLETED",
"status_changed_ts": "1611973695446642",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45013",
"builder:linux-chromeos-rel",
"buildset:commit/git/b573de25591606e78c9100bdaf7c0852b2a2da07",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b573de25591606e78c9100bdaf7c0852b2a2da07",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022150392378528544",
"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/8856720330238126480/+/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:5169bc928461d910",
"user_agent:luci-scheduler"
],
"updated_ts": "1611973695451279",
"url": "https://ci.chromium.org/b/8856720330238126480",
"utcnow_ts": "1611973695959777"
}
}
[02:28:15.995] Invocation finished in 56m21.75304906s with status SUCCEEDED