[06:18:54.940] New invocation is queued and will start shortly
[06:18:56.532] Starting the invocation (attempt 1)
[06:18:56.584] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:18:56.585] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:18:56.585] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031282628792283184",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4f33eb21df48eb70b10559c91dc9d5c20ebd5620\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9031282628792283184",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4f33eb21df48eb70b10559c91dc9d5c20ebd5620",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f33eb21df48eb70b10559c91dc9d5c20ebd5620",
"gitiles_ref:refs/heads/master"
]
}
[06:18:57.133] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603261136689547",
"id": "8865852566293700784",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f33eb21df48eb70b10559c91dc9d5c20ebd5620\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603261137022481",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42137",
"builder:linux-chromeos-rel",
"buildset:commit/git/4f33eb21df48eb70b10559c91dc9d5c20ebd5620",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f33eb21df48eb70b10559c91dc9d5c20ebd5620",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031282628792283184",
"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/8865852566293700784/+/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": "1603261137022597",
"url": "https://ci.chromium.org/b/8865852566293700784",
"utcnow_ts": "1603261137121285"
}
}
[06:18:57.133] Task URL: https://ci.chromium.org/b/8865852566293700784
[06:18:57.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:2:0) after 1m0s
[06:19:39.347] Received PubSub notification, asking Buildbucket for the build status
[06:19:39.426] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:57.217] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:2:0)
[06:19:57.217] Timer tick, asking Buildbucket for the build status
[06:19:57.316] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:57.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:4:0) after 1m0s
[06:20:57.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:4:0)
[06:20:57.452] Timer tick, asking Buildbucket for the build status
[06:20:57.678] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:57.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:5:0) after 1m0s
[06:21:57.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:5:0)
[06:21:57.749] Timer tick, asking Buildbucket for the build status
[06:21:58.053] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:58.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:6:0) after 1m0s
[06:22:58.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:6:0)
[06:22:58.115] Timer tick, asking Buildbucket for the build status
[06:22:59.711] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:7:0) after 1m0s
[06:23:59.925] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:7:0)
[06:23:59.925] Timer tick, asking Buildbucket for the build status
[06:24:00.235] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:00.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:8:0) after 1m0s
[06:25:00.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:8:0)
[06:25:00.326] Timer tick, asking Buildbucket for the build status
[06:25:00.435] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:00.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:9:0) after 1m0s
[06:26:00.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:9:0)
[06:26:00.565] Timer tick, asking Buildbucket for the build status
[06:26:00.630] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:00.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:10:0) after 1m0s
[06:27:00.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:10:0)
[06:27:00.685] Timer tick, asking Buildbucket for the build status
[06:27:00.749] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:00.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:11:0) after 1m0s
[06:28:01.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:11:0)
[06:28:01.586] Timer tick, asking Buildbucket for the build status
[06:28:01.647] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:01.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:12:0) after 1m0s
[06:29:03.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:12:0)
[06:29:03.075] Timer tick, asking Buildbucket for the build status
[06:29:03.637] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:03.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:13:0) after 1m0s
[06:30:03.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:13:0)
[06:30:03.943] Timer tick, asking Buildbucket for the build status
[06:30:04.024] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:04.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:14:0) after 1m0s
[06:31:04.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:14:0)
[06:31:04.084] Timer tick, asking Buildbucket for the build status
[06:31:04.280] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:04.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:15:0) after 1m0s
[06:32:04.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:15:0)
[06:32:04.313] Timer tick, asking Buildbucket for the build status
[06:32:04.403] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:04.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:16:0) after 1m0s
[06:33:04.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:16:0)
[06:33:04.457] Timer tick, asking Buildbucket for the build status
[06:33:04.705] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:04.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:17:0) after 1m0s
[06:34:04.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:17:0)
[06:34:04.808] Timer tick, asking Buildbucket for the build status
[06:34:05.102] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:05.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:18:0) after 1m0s
[06:35:05.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:18:0)
[06:35:05.181] Timer tick, asking Buildbucket for the build status
[06:35:05.245] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:05.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:19:0) after 1m0s
[06:36:05.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:19:0)
[06:36:05.351] Timer tick, asking Buildbucket for the build status
[06:36:05.426] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:05.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:20:0) after 1m0s
[06:37:05.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:20:0)
[06:37:05.448] Timer tick, asking Buildbucket for the build status
[06:37:05.618] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:05.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:21:0) after 1m0s
[06:38:05.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:21:0)
[06:38:05.676] Timer tick, asking Buildbucket for the build status
[06:38:05.766] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:05.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:22:0) after 1m0s
[06:39:05.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:22:0)
[06:39:05.805] Timer tick, asking Buildbucket for the build status
[06:39:05.931] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:05.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:23:0) after 1m0s
[06:40:06.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:23:0)
[06:40:06.081] Timer tick, asking Buildbucket for the build status
[06:40:06.150] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:06.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:24:0) after 1m0s
[06:41:06.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:24:0)
[06:41:06.183] Timer tick, asking Buildbucket for the build status
[06:41:09.416] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:09.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:25:0) after 1m0s
[06:42:09.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:25:0)
[06:42:09.572] Timer tick, asking Buildbucket for the build status
[06:42:09.635] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:09.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:26:0) after 1m0s
[06:43:09.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:26:0)
[06:43:09.718] Timer tick, asking Buildbucket for the build status
[06:43:09.925] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:09.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:27:0) after 1m0s
[06:44:09.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:27:0)
[06:44:09.951] Timer tick, asking Buildbucket for the build status
[06:44:10.065] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:10.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:28:0) after 1m0s
[06:45:10.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:28:0)
[06:45:10.197] Timer tick, asking Buildbucket for the build status
[06:45:10.298] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:10.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:29:0) after 1m0s
[06:46:10.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:29:0)
[06:46:10.322] Timer tick, asking Buildbucket for the build status
[06:46:11.705] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:11.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:30:0) after 1m0s
[06:47:11.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:30:0)
[06:47:11.863] Timer tick, asking Buildbucket for the build status
[06:47:11.948] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:11.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:31:0) after 1m0s
[06:48:11.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:31:0)
[06:48:11.985] Timer tick, asking Buildbucket for the build status
[06:48:12.060] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:12.060] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:32:0) after 1m0s
[06:49:12.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:32:0)
[06:49:12.103] Timer tick, asking Buildbucket for the build status
[06:49:12.381] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:12.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:33:0) after 1m0s
[06:50:12.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:33:0)
[06:50:12.509] Timer tick, asking Buildbucket for the build status
[06:50:12.611] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:12.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:34:0) after 1m0s
[06:51:12.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:34:0)
[06:51:12.657] Timer tick, asking Buildbucket for the build status
[06:51:12.728] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:12.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:35:0) after 1m0s
[06:52:12.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:35:0)
[06:52:12.774] Timer tick, asking Buildbucket for the build status
[06:52:12.862] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:12.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:36:0) after 1m0s
[06:53:12.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:36:0)
[06:53:12.935] Timer tick, asking Buildbucket for the build status
[06:53:13.013] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:13.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:37:0) after 1m0s
[06:54:13.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:37:0)
[06:54:13.084] Timer tick, asking Buildbucket for the build status
[06:54:13.263] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:13.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:38:0) after 1m0s
[06:55:13.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:38:0)
[06:55:13.293] Timer tick, asking Buildbucket for the build status
[06:55:13.418] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:13.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:39:0) after 1m0s
[06:56:13.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:39:0)
[06:56:13.443] Timer tick, asking Buildbucket for the build status
[06:56:13.542] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:13.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:40:0) after 1m0s
[06:57:13.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:40:0)
[06:57:13.568] Timer tick, asking Buildbucket for the build status
[06:57:13.642] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:13.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:41:0) after 1m0s
[06:58:13.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:41:0)
[06:58:13.715] Timer tick, asking Buildbucket for the build status
[06:58:13.821] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:13.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:42:0) after 1m0s
[06:59:13.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:42:0)
[06:59:13.943] Timer tick, asking Buildbucket for the build status
[06:59:14.061] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:14.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:43:0) after 1m0s
[07:00:14.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:43:0)
[07:00:14.157] Timer tick, asking Buildbucket for the build status
[07:00:14.512] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:14.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:44:0) after 1m0s
[07:01:14.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:44:0)
[07:01:14.537] Timer tick, asking Buildbucket for the build status
[07:01:16.005] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:16.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:45:0) after 1m0s
[07:02:16.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:45:0)
[07:02:16.047] Timer tick, asking Buildbucket for the build status
[07:02:16.179] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:16.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:46:0) after 1m0s
[07:03:16.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:46:0)
[07:03:16.253] Timer tick, asking Buildbucket for the build status
[07:03:16.364] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:16.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:47:0) after 1m0s
[07:04:16.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:47:0)
[07:04:16.497] Timer tick, asking Buildbucket for the build status
[07:04:16.798] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:16.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:48:0) after 1m0s
[07:05:16.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:48:0)
[07:05:16.902] Timer tick, asking Buildbucket for the build status
[07:05:17.121] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:17.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:49:0) after 1m0s
[07:06:17.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:49:0)
[07:06:17.162] Timer tick, asking Buildbucket for the build status
[07:06:17.274] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:17.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:50:0) after 1m0s
[07:07:17.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:50:0)
[07:07:17.344] Timer tick, asking Buildbucket for the build status
[07:07:17.683] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:17.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:51:0) after 1m0s
[07:08:17.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:51:0)
[07:08:17.721] Timer tick, asking Buildbucket for the build status
[07:08:18.098] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:18.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:52:0) after 1m0s
[07:09:18.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:52:0)
[07:09:18.143] Timer tick, asking Buildbucket for the build status
[07:09:18.264] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:18.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:53:0) after 1m0s
[07:10:18.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:53:0)
[07:10:18.301] Timer tick, asking Buildbucket for the build status
[07:10:18.424] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:18.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:54:0) after 1m0s
[07:11:18.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:54:0)
[07:11:18.503] Timer tick, asking Buildbucket for the build status
[07:11:18.642] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:18.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:55:0) after 1m0s
[07:12:18.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:55:0)
[07:12:18.732] Timer tick, asking Buildbucket for the build status
[07:12:18.874] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:18.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:56:0) after 1m0s
[07:13:18.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:56:0)
[07:13:18.984] Timer tick, asking Buildbucket for the build status
[07:13:19.123] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:19.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:57:0) after 1m0s
[07:14:19.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:57:0)
[07:14:19.158] Timer tick, asking Buildbucket for the build status
[07:14:19.297] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:19.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:58:0) after 1m0s
[07:15:19.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:58:0)
[07:15:19.424] Timer tick, asking Buildbucket for the build status
[07:15:19.566] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:19.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:59:0) after 1m0s
[07:16:19.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:59:0)
[07:16:19.595] Timer tick, asking Buildbucket for the build status
[07:16:20.408] Build 8865852566293700784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:20.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031282628792283184:60:0) after 1m0s
[07:16:55.453] Received PubSub notification, asking Buildbucket for the build status
[07:16:55.531] Build 8865852566293700784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:16:55.531] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603264612576986",
"created_by": "project:chromium",
"created_ts": "1603261136689547",
"id": "8865852566293700784",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f33eb21df48eb70b10559c91dc9d5c20ebd5620\"}}",
"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\": \"4f33eb21df48eb70b10559c91dc9d5c20ebd5620\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-74-luct\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42137, \"got_angle_revision\": \"9d160b0bba68f5cebb8f66c894c84f95b608eb72\", \"got_dawn_revision\": \"cca03ca6bfe936cea2cda29e0c72fb0d0fde2850\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"4f33eb21df48eb70b10559c91dc9d5c20ebd5620\", \"got_revision_cp\": \"refs/heads/master@{#819256}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"84f5eeb6dd9b225f465f93737fa76aad7de355cf\", \"got_v8_revision\": \"d20e0a345b623a1373a8833f30c5f7f153b902d7\", \"got_v8_revision_cp\": \"refs/heads/8.8.147@{#1}\", \"got_webrtc_revision\": \"7bc3356fbcb9b95ea2c810ae2f3152930d54577b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32449}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f33eb21df48eb70b10559c91dc9d5c20ebd5620\", \"swarm_hashes_refs/heads/master(at){#819256}_without_patch\": {\"absl_hardening_tests\": \"db7587b8b114b453b00184152793c3fcae07519e\", \"accessibility_unittests\": \"b33e2fd7c71cfe64b212d01c832176da76a1082f\", \"app_list_unittests\": \"b245eaf8d5d868fc9fa6094c3e1697af76d3d175\", \"app_shell_unittests\": \"4057f66cbf7655febb57fddb5f19b00333ca8345\", \"ash_unittests\": \"737edd97fab6c2829bd874adbfbe460aebc7be76\", \"aura_unittests\": \"ad411f00ce6b75ea0bc414cb8b7fdb960592a364\", \"base_unittests\": \"4ae558967b4c5c6b60b2b6aaffba225e645ce54b\", \"base_util_unittests\": \"dde9c527ac18ed2b78708f69c7fdc660b4c13ad2\", \"blink_common_unittests\": \"3702b859f4eeeb659c3b8f250aa9c2a1e9568743\", \"blink_fuzzer_unittests\": \"1ded316f4294d48d91fe6e38a009aa83568dda0b\", \"blink_heap_unittests\": \"c5fbd2aeac7b2e96217df07b079b8ecc9949b8d6\", \"blink_platform_unittests\": \"d21cc31d81dfa51fbef3ffa9d0e866ce6f951dd6\", \"blink_unittests\": \"c14824dd65215e37ec4d6caf3e999c460b4f60a5\", \"boringssl_crypto_tests\": \"e199f71b19d6168880877572d496cf9db6c648fc\", \"boringssl_ssl_tests\": \"7d1084b05d564be65c062b6c1c091838906e7bdb\", \"browser_tests\": \"aaf084fa193eaa93010b1e1060b259d28f8fd10b\", \"capture_unittests\": \"54d6498e002007edb5bdaa96c2bfde99e1e80e4c\", \"cast_unittests\": \"cf962771f8e75efd71eac137510d13d402d23d30\", \"cc_unittests\": \"47cf457b6a5a33efecfdfeba0a2b044af8c650ad\", \"chrome_app_unittests\": \"88a60f8904c7683df361cbb224d26cff62a97ac1\", \"chromedriver_unittests\": \"dc1a9b6759097effae0007d05263ac18d0fb6b4c\", \"chromeos_components_unittests\": \"64066fdd3212ee7f2b6f5ad6f8d2f85ea86d2ca3\", \"chromeos_unittests\": \"66e3d8905e6965dd20ee4b98807b9fc45d2e2aa8\", \"components_browsertests\": \"49ba6e3e86b095bab17122601e5a139ae9ac56a8\", \"components_unittests\": \"66c0b279adf8e60a34f96e500b1ba67b26bf044a\", \"compositor_unittests\": \"7ce8c9931fc1e97133551d7beaa4af636d545237\", \"content_browsertests\": \"4949dfa95aa1589520ee8ebc40ce91e060ae7b18\", \"content_unittests\": \"d829205a9f9b717d477306e9760e226e1ce40297\", \"crypto_unittests\": \"3c5fff692edce684f27b456bed918994a3cc5c92\", \"dbus_unittests\": \"130ebb0c825d98b6497145fd32e13ba03cf8bbcb\", \"device_unittests\": \"1fb1f03a090edd71c70264ce54c4ef2befaa9aa9\", \"display_unittests\": \"342fd59847087fc148f68ccd9dbb72f71af370cd\", \"events_unittests\": \"7d2f77412dc50b3efbd321f8311e22fe2b0f2319\", \"exo_unittests\": \"58e7ec3d17bd2d3f3cc282cbc26d8102119e3c11\", \"extensions_browsertests\": \"59da1b4cae2594284415b54374dd1db040171e95\", \"extensions_unittests\": \"11c5ad11740868fb83b3e29290d65476eeb9b9cb\", \"filesystem_service_unittests\": \"e21052e87d7b7760881ecb683c73a76ff175d13f\", \"gcm_unit_tests\": \"9bffb70af63e3a63e4307b9be00480e58b19dfd5\", \"gfx_unittests\": \"c167c0c5a122ba418d54bc52120f5777ffd8c622\", \"gin_unittests\": \"1c6125baac185ecf6f4d91db6ac68b7a9dbbda90\", \"gl_unittests_ozone\": \"dfbc48891524ff92dca6d698d30ad4bfb72fdd6d\", \"google_apis_unittests\": \"6f9986e3efe11c17091b408357561c7137fc9583\", \"gpu_unittests\": \"7d7c693096cbba0cf441fc2a568f6bcd342f7556\", \"gwp_asan_unittests\": \"3986efcbc20a5a3dd20550f19ba49223bafb5da4\", \"interactive_ui_tests\": \"d8245c991e9be1cadb1da393d30b3b64954b4e0a\", \"ipc_tests\": \"3f44d5c5d00dafcb007ad43073f42a27732a7387\", \"jingle_unittests\": \"c170885a546ca089ae3e05228e338ccd8432e74b\", \"keyboard_unittests\": \"8ec88b879de069da1b526025cc729a3081f27b7c\", \"latency_unittests\": \"3f05c8f307445b346c5115d348b6b883b2f9f07c\", \"libjingle_xmpp_unittests\": \"2908e1b48a16b32761f7bd3afd187fa2f5138e73\", \"media_blink_unittests\": \"74fa763c0bbc67cec91b93b9d22b5cdbd987b158\", \"media_unittests\": \"548991d7891071c33fd2dd60277c9d773aa6f6ae\", \"message_center_unittests\": \"4bf586154dfe276506a33bd2c154414e10d7e037\", \"midi_unittests\": \"947d50465262e3af7b184a6fb4ff0f72da14e809\", \"mojo_core_unittests\": \"ab928602f29d0abb870daf4576e20c769db4aee8\", \"mojo_unittests\": \"d10745999950e90f7f960c1b0e1a39ad77668210\", \"nacl_helper_nonsfi_unittests\": \"76f7b9c4d39f7854994f92685e836879fadbbedb\", \"nacl_loader_unittests\": \"0032c3c95da06c35cbfe5defecdf5d0b582d6dc4\", \"native_theme_unittests\": \"f8f590c5a64494255df588fc4e5ed5079e7d5d5e\", \"net_unittests\": \"fea45aa339dd43c838eafd957265bcf9e0e18a64\", \"ozone_gl_unittests\": \"e3af85519c0b541dc722e66888338d8a5fd8e649\", \"ozone_unittests\": \"d3af90ceaa03aa57ff5a3f35bf520c3f9074eaf9\", \"ozone_x11_unittests\": \"244e022a8ff2854fcf2859442d93c90ca18db010\", \"pdf_unittests\": \"b65c62f16bf9f7e44967477845604b86f7aac334\", \"perfetto_unittests\": \"4acaf6aef95592a416d3258ebfb249da3bf8ef9a\", \"ppapi_unittests\": \"bd66edea227e69c3c35374b47b005fe0d18f03ec\", \"printing_unittests\": \"18cd0e4bbc51bd64e3910b71899eeca5ee6624c0\", \"remoting_unittests\": \"930299fda0e64d898841377d9955cef87cd267af\", \"sandbox_linux_unittests\": \"f5ec4506d5c6694f74913969cd7dc3922ff107d9\", \"service_manager_unittests\": \"fe3ae4e73c628e5ef0ce2b233b8aa5e4723a452e\", \"services_unittests\": \"f0cc6b24a25553692685e4bea98c78f1d997c185\", \"shell_dialogs_unittests\": \"52effe12ff649e2532d3ec350223a631d53139fd\", \"skia_unittests\": \"46c135ca60539f92f546293d5e5af51854647723\", \"snapshot_unittests\": \"11c512cbb7161732016182cf9de3f20d04652985\", \"sql_unittests\": \"ec9b24379c227a9977c262ee92974003631839e5\", \"standalone_angle_unittests\": \"39fd004dc98e0afa12c8038b432ef5bf791a7e2e\", \"storage_unittests\": \"db5e19db99e74efb8aa9fa5aa20e6c8389a47eac\", \"sync_integration_tests\": \"e3ff0d317d0a473ebaf8014c2486e4e4f7e2faba\", \"traffic_annotation_auditor_unittests\": \"41f8f1dc98331811ee558be03ea08cdf5256350d\", \"ui_base_unittests\": \"f8cc5fc31b60e54463c9b5787e928524efa55874\", \"ui_chromeos_unittests\": \"fb2d93274883604e7bf13ead02af236af2e6558b\", \"ui_touch_selection_unittests\": \"a94e417babb6196a8fcbc6eb1822b24cce3b9017\", \"unit_tests\": \"76edea45a3499734758a697b73619a0629514fb7\", \"url_unittests\": \"b1bbac759c4e7414182167213bbba803db9e203b\", \"usage_time_limit_unittests\": \"a9ee7df74d56f75fae20b842b662c572b6e970b4\", \"views_unittests\": \"280c81c4a63b0affad568f18114d6482279ceb1a\", \"viz_unittests\": \"636ee54efbb108214b8395b58002d2fb6d50c5e0\", \"wayland_client_perftests\": \"79d9108cf7fb979dd74df8aa56075f653e59ae11\", \"wm_unittests\": \"e56f707540ba44145de82a8e2ba664d492052fd1\", \"wtf_unittests\": \"b38775c48d2aeeb7ba8b1a96653b30dcd8c031ba\", \"zlib_unittests\": \"8320a3ec757bdbd4bfeff2c192d82162b7665b29\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_34caa451ddae04e30af534f20a2f04cca4108dc27b0a92529062f4f2e114a8e9_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_9c30056936b0e6c113d792a7251a67e37568746469ef96a03dc78e237458f3d4_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"builder_e4464922e5c60bddfcc3d01cfc511ef7f4a711bded636fa98c66f03a573264ee_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-74-luct\"], \"image\": [\"chrome-xenial-20101600-2d400e1627f\"], \"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\": [\"5601-97d7e7d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603261178410876",
"status": "COMPLETED",
"status_changed_ts": "1603264613319849",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42137",
"builder:linux-chromeos-rel",
"buildset:commit/git/4f33eb21df48eb70b10559c91dc9d5c20ebd5620",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f33eb21df48eb70b10559c91dc9d5c20ebd5620",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031282628792283184",
"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/8865852566293700784/+/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:4f62a10a3a2d5910",
"user_agent:luci-scheduler"
],
"updated_ts": "1603264613399180",
"url": "https://ci.chromium.org/b/8865852566293700784",
"utcnow_ts": "1603264615520691"
}
}
[07:16:55.531] Invocation finished in 58m0.609086785s with status SUCCEEDED