[01:25:46.673] New invocation is queued and will start shortly
[01:25:47.765] Starting the invocation (attempt 1)
[01:25:47.829] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:25:47.830] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:25:47.830] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023419135349407392",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"948e014946cfc9af2a8a7de7dc1328c8de65e1c8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023419135349407392",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/948e014946cfc9af2a8a7de7dc1328c8de65e1c8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/948e014946cfc9af2a8a7de7dc1328c8de65e1c8",
"gitiles_ref:refs/heads/master"
]
}
[01:25:48.924] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610760347976585",
"id": "8857989073318485072",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"948e014946cfc9af2a8a7de7dc1328c8de65e1c8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610760348628927",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44656",
"builder:linux-chromeos-rel",
"buildset:commit/git/948e014946cfc9af2a8a7de7dc1328c8de65e1c8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/948e014946cfc9af2a8a7de7dc1328c8de65e1c8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023419135349407392",
"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/8857989073318485072/+/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": "1610760348629047",
"url": "https://ci.chromium.org/b/8857989073318485072",
"utcnow_ts": "1610760348907651"
}
}
[01:25:48.924] Task URL: https://ci.chromium.org/b/8857989073318485072
[01:25:48.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:2:0) after 1m0s
[01:25:52.174] Received PubSub notification, asking Buildbucket for the build status
[01:25:52.336] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:48.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:2:0)
[01:26:48.995] Timer tick, asking Buildbucket for the build status
[01:26:49.109] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:49.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:4:0) after 1m0s
[01:27:49.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:4:0)
[01:27:49.153] Timer tick, asking Buildbucket for the build status
[01:27:49.226] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:49.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:5:0) after 1m0s
[01:28:49.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:5:0)
[01:28:49.265] Timer tick, asking Buildbucket for the build status
[01:28:49.377] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:49.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:6:0) after 1m0s
[01:29:49.500] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:6:0)
[01:29:49.500] Timer tick, asking Buildbucket for the build status
[01:29:49.597] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:49.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:7:0) after 1m0s
[01:30:49.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:7:0)
[01:30:49.646] Timer tick, asking Buildbucket for the build status
[01:30:50.182] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:50.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:8:0) after 1m0s
[01:31:50.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:8:0)
[01:31:50.257] Timer tick, asking Buildbucket for the build status
[01:31:50.433] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:50.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:9:0) after 1m0s
[01:32:50.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:9:0)
[01:32:50.588] Timer tick, asking Buildbucket for the build status
[01:32:50.687] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:50.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:10:0) after 1m0s
[01:33:50.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:10:0)
[01:33:50.712] Timer tick, asking Buildbucket for the build status
[01:33:50.772] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:50.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:11:0) after 1m0s
[01:34:50.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:11:0)
[01:34:50.792] Timer tick, asking Buildbucket for the build status
[01:34:50.948] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:50.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:12:0) after 1m0s
[01:35:50.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:12:0)
[01:35:50.958] Timer tick, asking Buildbucket for the build status
[01:35:51.068] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:51.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:13:0) after 1m0s
[01:36:51.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:13:0)
[01:36:51.115] Timer tick, asking Buildbucket for the build status
[01:36:51.506] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:51.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:14:0) after 1m0s
[01:37:51.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:14:0)
[01:37:51.529] Timer tick, asking Buildbucket for the build status
[01:37:51.601] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:51.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:15:0) after 1m0s
[01:38:51.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:15:0)
[01:38:51.622] Timer tick, asking Buildbucket for the build status
[01:38:51.694] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:51.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:16:0) after 1m0s
[01:39:51.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:16:0)
[01:39:51.787] Timer tick, asking Buildbucket for the build status
[01:39:51.984] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:51.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:17:0) after 1m0s
[01:40:52.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:17:0)
[01:40:52.034] Timer tick, asking Buildbucket for the build status
[01:40:52.155] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:52.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:18:0) after 1m0s
[01:41:52.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:18:0)
[01:41:52.176] Timer tick, asking Buildbucket for the build status
[01:41:52.311] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:52.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:19:0) after 1m0s
[01:42:52.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:19:0)
[01:42:52.337] Timer tick, asking Buildbucket for the build status
[01:42:52.405] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:52.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:20:0) after 1m0s
[01:43:52.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:20:0)
[01:43:52.419] Timer tick, asking Buildbucket for the build status
[01:43:52.804] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:52.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:21:0) after 1m0s
[01:44:52.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:21:0)
[01:44:52.825] Timer tick, asking Buildbucket for the build status
[01:44:53.055] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:53.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:22:0) after 1m0s
[01:45:53.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:22:0)
[01:45:53.104] Timer tick, asking Buildbucket for the build status
[01:45:53.292] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:53.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:23:0) after 1m0s
[01:46:53.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:23:0)
[01:46:53.322] Timer tick, asking Buildbucket for the build status
[01:46:53.395] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:53.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:24:0) after 1m0s
[01:47:53.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:24:0)
[01:47:53.426] Timer tick, asking Buildbucket for the build status
[01:47:53.824] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:53.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:25:0) after 1m0s
[01:48:53.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:25:0)
[01:48:53.879] Timer tick, asking Buildbucket for the build status
[01:48:54.096] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:54.096] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:26:0) after 1m0s
[01:49:54.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:26:0)
[01:49:54.144] Timer tick, asking Buildbucket for the build status
[01:49:54.277] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:54.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:27:0) after 1m0s
[01:50:54.326] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:27:0)
[01:50:54.326] Timer tick, asking Buildbucket for the build status
[01:50:54.436] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:54.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:28:0) after 1m0s
[01:51:54.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:28:0)
[01:51:54.519] Timer tick, asking Buildbucket for the build status
[01:51:54.766] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:54.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:29:0) after 1m0s
[01:52:54.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:29:0)
[01:52:54.814] Timer tick, asking Buildbucket for the build status
[01:52:54.871] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:54.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:30:0) after 1m0s
[01:53:54.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:30:0)
[01:53:54.919] Timer tick, asking Buildbucket for the build status
[01:53:54.982] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:54.982] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:31:0) after 1m0s
[01:54:55.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:31:0)
[01:54:55.084] Timer tick, asking Buildbucket for the build status
[01:54:55.312] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:55.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:32:0) after 1m0s
[01:55:55.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:32:0)
[01:55:55.345] Timer tick, asking Buildbucket for the build status
[01:55:55.445] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:55.445] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:33:0) after 1m0s
[01:56:55.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:33:0)
[01:56:55.485] Timer tick, asking Buildbucket for the build status
[01:56:55.586] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:55.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:34:0) after 1m0s
[01:57:55.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:34:0)
[01:57:55.619] Timer tick, asking Buildbucket for the build status
[01:57:55.680] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:55.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:35:0) after 1m0s
[01:58:55.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:35:0)
[01:58:55.701] Timer tick, asking Buildbucket for the build status
[01:58:55.789] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:55.789] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:36:0) after 1m0s
[01:59:55.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:36:0)
[01:59:55.819] Timer tick, asking Buildbucket for the build status
[01:59:55.934] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:55.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:37:0) after 1m0s
[02:00:56.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:37:0)
[02:00:56.057] Timer tick, asking Buildbucket for the build status
[02:00:56.183] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:56.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:38:0) after 1m0s
[02:01:56.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:38:0)
[02:01:56.198] Timer tick, asking Buildbucket for the build status
[02:01:56.358] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:56.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:39:0) after 1m0s
[02:02:56.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:39:0)
[02:02:56.405] Timer tick, asking Buildbucket for the build status
[02:02:56.550] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:56.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:40:0) after 1m0s
[02:03:56.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:40:0)
[02:03:56.687] Timer tick, asking Buildbucket for the build status
[02:03:56.797] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:56.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:41:0) after 1m0s
[02:04:56.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:41:0)
[02:04:56.883] Timer tick, asking Buildbucket for the build status
[02:04:56.997] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:56.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:42:0) after 1m0s
[02:05:57.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:42:0)
[02:05:57.113] Timer tick, asking Buildbucket for the build status
[02:05:57.239] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:57.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:43:0) after 1m0s
[02:06:57.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:43:0)
[02:06:57.327] Timer tick, asking Buildbucket for the build status
[02:06:57.449] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:57.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:44:0) after 1m0s
[02:07:57.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:44:0)
[02:07:57.483] Timer tick, asking Buildbucket for the build status
[02:07:57.622] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:57.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:45:0) after 1m0s
[02:08:57.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:45:0)
[02:08:57.774] Timer tick, asking Buildbucket for the build status
[02:08:57.883] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:57.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:46:0) after 1m0s
[02:09:57.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:46:0)
[02:09:57.983] Timer tick, asking Buildbucket for the build status
[02:09:58.127] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:58.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:47:0) after 1m0s
[02:10:58.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:47:0)
[02:10:58.224] Timer tick, asking Buildbucket for the build status
[02:10:58.748] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:58.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:48:0) after 1m0s
[02:11:58.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:48:0)
[02:11:58.873] Timer tick, asking Buildbucket for the build status
[02:11:59.124] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:59.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:49:0) after 1m0s
[02:12:59.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:49:0)
[02:12:59.183] Timer tick, asking Buildbucket for the build status
[02:12:59.297] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:59.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:50:0) after 1m0s
[02:13:59.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:50:0)
[02:13:59.431] Timer tick, asking Buildbucket for the build status
[02:13:59.540] Build 8857989073318485072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:59.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023419135349407392:51:0) after 1m0s
[02:14:30.991] Received PubSub notification, asking Buildbucket for the build status
[02:14:31.187] Build 8857989073318485072: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:14:31.187] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610763269247032",
"created_by": "project:chromium",
"created_ts": "1610760347976585",
"id": "8857989073318485072",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"948e014946cfc9af2a8a7de7dc1328c8de65e1c8\"}}",
"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\": \"948e014946cfc9af2a8a7de7dc1328c8de65e1c8\", \"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-10-8gq6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44656, \"got_angle_revision\": \"8f5ca26678c12d54d2981a84e48a5304746ec824\", \"got_dawn_revision\": \"04772515a8b613150b28bbed19b4c261e0b50e96\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"948e014946cfc9af2a8a7de7dc1328c8de65e1c8\", \"got_revision_cp\": \"refs/heads/master@{#844327}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"149733cead636de93d96c5c64f30168d5f6bb03f\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"948e014946cfc9af2a8a7de7dc1328c8de65e1c8\", \"swarm_hashes_refs/heads/master(at){#844327}_without_patch\": {\"absl_hardening_tests\": \"9781adecc6ab2942ee7069507629bb7621a257e4\", \"accessibility_unittests\": \"33562956d045baf3b2c4afb79ec3ce5ef1cc664b\", \"angle_unittests\": \"7804caec3e3f0b61f7d8798c4708247fd2d1f77a\", \"app_list_unittests\": \"d291aea9f501ff0dc193acd905e47fdb04b57836\", \"app_shell_unittests\": \"b24f943b0ace5bc43dd630902bd39f1b1b018ab8\", \"ash_unittests\": \"dae7e0ba0adb96f7b91bcdabbdc36be53bbd9d76\", \"aura_unittests\": \"d878d2fc118a21b94582260ad3342db1f0862307\", \"base_unittests\": \"e013efceb301943226c455658c52daf569494d6f\", \"base_util_unittests\": \"625013b7fef875354f4b5b16c3d23bc85719b73f\", \"blink_common_unittests\": \"9d5f299e0c1124fec0852aa68eafd1787b2f4a52\", \"blink_fuzzer_unittests\": \"af59c06f798b9ebd11b8f362c0df998f09c33fb7\", \"blink_heap_unittests\": \"3b80479c8117bcabc437d6a343f2140959e4a5a8\", \"blink_platform_unittests\": \"c3ed2082f35d1acf69db54cd503c316ba1752a23\", \"blink_unittests\": \"3fc739278484df514b92c905b34508cef443bcfd\", \"boringssl_crypto_tests\": \"42a83f2ea5dc5e6effd8a843d84fb86303aacf57\", \"boringssl_ssl_tests\": \"11d88e0050939eba91988d9326915ffc4bcb462d\", \"browser_tests\": \"0a6292738d56b24c009ef448b692ad568648fbee\", \"capture_unittests\": \"20396b63a2ad4b8ac553171d347f41bfb388be54\", \"cast_unittests\": \"fbf2ba0639bb5bd7dee2ea0d7c099828d16d99a5\", \"cc_unittests\": \"5edc02acd16af0a404d1f3b88144cf06440cc894\", \"chrome_app_unittests\": \"5472804bbef418d051e01d9aadb9dab04e94ca8f\", \"chromedriver_unittests\": \"8a453e8a47633564dea231dda342e51a853802fa\", \"chromeos_components_unittests\": \"9635c7d2e8e666172d00f2a9e9ea31dfcc3785e5\", \"chromeos_unittests\": \"85c533a442938c5dd069fdfda0d97b5d5b01f79d\", \"components_browsertests\": \"46197389106da53fdfe1ba504e4de49e5677f5b7\", \"components_unittests\": \"c9aa1b4fb11dc12ffcf7ce4c69727a5fcf8f5be3\", \"compositor_unittests\": \"2e0ef42845fe57fa99edfebb7d7cf8992fd5bcfd\", \"content_browsertests\": \"9f38e491bf3c2ad8bf99981390bc5931b77de250\", \"content_unittests\": \"0abbffac1bd1a49f370561bed465ed399ce2ce5f\", \"crashpad_tests\": \"a23c731121f55ae2236ac58786d90b75235fa0ce\", \"crypto_unittests\": \"5721804fcd840d19b1b87ab804001d8a98c89654\", \"dbus_unittests\": \"812c415a34081cebe02cdce3c19eb9a6f9e639d7\", \"device_unittests\": \"666cd350a03286b90f8b72a9d2899752b27bd9e5\", \"display_unittests\": \"bb670c47220fa09e27ff94da3163cf684abe2cc4\", \"events_unittests\": \"0c57e5557123a56658bce017b03a0a70d464d054\", \"exo_unittests\": \"9eb3fb48189a99fcb02ee74a0fcf5108cae61e80\", \"extensions_browsertests\": \"05cc6a28f2ce1d4f51938f4b8f38c6371a37b4e5\", \"extensions_unittests\": \"c358da4483d1bb267a43599650f6f75c9e9d9c73\", \"filesystem_service_unittests\": \"6dd1a746ed44867710df343938f4883b99a46e3f\", \"gcm_unit_tests\": \"08141f1605c8b6b39e9053bb4a4bbbce006bfa66\", \"gfx_unittests\": \"6a37d47437c77cc106946daad31df15f7d5cb36b\", \"gin_unittests\": \"35ea23d2e08483d6d2b38d144f8cfe2063d9f59a\", \"gl_unittests_ozone\": \"ebd57222872a7055954ba22221e94515ee8aac25\", \"google_apis_unittests\": \"616eea8e6faf3be46759a663d76fb4ecb9381934\", \"gpu_unittests\": \"9b22e892897f1ff2b56f30df5b54a7476ca716a9\", \"gwp_asan_unittests\": \"bc265910a38a07212ccaf6dcac265255dfe6833a\", \"interactive_ui_tests\": \"b43cddabe2b1604bcecf960e85dbab432bd887f1\", \"ipc_tests\": \"61b48c89d5d1cc9710498fe62f771b4f13c6d02f\", \"jingle_unittests\": \"0f3ebd8d70bd06d26d103b19b62753c86867c26d\", \"keyboard_unittests\": \"b3ee42b0fd00be880ec05678e62756d8a1ddbf31\", \"latency_unittests\": \"bc1de6c63bd727046690aded34077fcbc6890e7b\", \"libjingle_xmpp_unittests\": \"cd6b9c67723a008194bf78d00c462e2652590d3a\", \"liburlpattern_unittests\": \"7e49de3cdc4ed022e2195696583ae2719270a531\", \"media_blink_unittests\": \"602b377b6ae57bb6e029c0fcdd2486a58306ed7d\", \"media_unittests\": \"fea9c3e59b29c146c24c1bce6a3bec825c90edb8\", \"message_center_unittests\": \"d72d918468551b21ba4aa4e838022e35ba394c10\", \"midi_unittests\": \"a04591ce269a63a43ac680c86b2699ce321af046\", \"mojo_core_unittests\": \"eb78f11eeee5d5c449596b6bd6f643a7c1082e82\", \"mojo_unittests\": \"251f683f04a350034cb22ee4da46de624b7ec9a7\", \"nacl_helper_nonsfi_unittests\": \"e9f29b34bdab5b5d8f3589345f50b8f05f81ef9b\", \"nacl_loader_unittests\": \"e18570797f11c5f2e0e1a57dc1e86210f3b6ba48\", \"native_theme_unittests\": \"2190037cbe6cdc1866a20bd4c505a134f9f5b0a8\", \"net_unittests\": \"331d12d7df83850debdd116b0fb0a51ae4f7690f\", \"ozone_gl_unittests\": \"04052f921581b5ce74a43c7e3623339c019c0470\", \"ozone_unittests\": \"1c2e07147907a7a799beb1a79557a774ba2b7d43\", \"ozone_x11_unittests\": \"75262c2932bfac54a0e507eaf62a8695d594574e\", \"pdf_unittests\": \"2fe5d0b3219f77950718c6142df0c4e8ca011e80\", \"perfetto_unittests\": \"aba7961cd7d4aebbcc40f68baa86b9e3a8a9e4cc\", \"ppapi_unittests\": \"9162e7bb05f1cc27efe091a1530f16509f2b2365\", \"printing_unittests\": \"34431fb9dc5a3010edc768e469fbec9dcaf76765\", \"remoting_unittests\": \"aee0b16da3ac40829b4abe8d6331a4a69830ffa7\", \"sandbox_linux_unittests\": \"877f999c00f4f0280764f1303d05dbd3c95eb8da\", \"service_manager_unittests\": \"016f3f6f4b2134d2d6238a77cccd22418ea4d985\", \"services_unittests\": \"0862f4de8a7e884d287cdb741f8f32cfe0d39f46\", \"shell_dialogs_unittests\": \"8e4ff2fb44699cf32a4a01873c023db1da63fa09\", \"shell_encryption_unittests\": \"52f1ec31c954e45d4b5b02bdabb4fd74fe517084\", \"skia_unittests\": \"e97f93350bbeb6cdcffbccf833be2ce967a0f9df\", \"snapshot_unittests\": \"91848bab2b51e25ac95ae42ec75b1ac21334a486\", \"sql_unittests\": \"0ef7bf3084f18e7e621e6645322d0394d5d99362\", \"storage_unittests\": \"d109f5c92291e05584bd64876419a3635c8394e8\", \"sync_integration_tests\": \"24e5c44c6cf27e849e4bcb6e86d6456b5b921c93\", \"traffic_annotation_auditor_unittests\": \"84343c3400d43cd147956b577ee04f2b503de54c\", \"ui_base_unittests\": \"b97deea43d57c1c8ce421f79ced3f31115415b5f\", \"ui_chromeos_unittests\": \"d1b83e5412e39bdbddda40ed47ac5d5c28eab47e\", \"ui_touch_selection_unittests\": \"c35e8fcdae395104bc483aa55736262826f215bb\", \"unit_tests\": \"293ee07dd08dd7411d47842b4bf74624df095392\", \"url_unittests\": \"36679f1a2ca5284ea251a340ee50ec6bde78c81d\", \"usage_time_limit_unittests\": \"9f40f33d0daf25954fdb520b08eeebac7d15e9f2\", \"views_unittests\": \"84b109a16c8ebd488a5c118026bb1116309893f0\", \"viz_unittests\": \"fe969eeb1ffbf4df123d6520b5c59446acd030dd\", \"wayland_client_perftests\": \"5bca0f5d08c5363439d705b508212cf6437e69b3\", \"wm_unittests\": \"01464d5330962e1e50bf4824ce7f20d8fce0dc75\", \"wtf_unittests\": \"797fe9eadd2c3982f0bf94507aacb688dcc0d5e4\", \"zlib_unittests\": \"740e1c1d5a49b6f57be910a3cd8a887b494e7d8e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_12fe258969bd458c0c491cc8ec8a110de21b19ba6d4540c003334ed929b828ba_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a50faa98e00aefb88600aa330f70664f9b4b54ee0ff51cb8b03a7f0a0b737b38_v2\", \"builder_fe5305c96c631c9bcc74a68d877d31a94a8d3c28663b28d2fadfc8f798de4ec0_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-10-8gq6\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610760351462941",
"status": "COMPLETED",
"status_changed_ts": "1610763270041331",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44656",
"builder:linux-chromeos-rel",
"buildset:commit/git/948e014946cfc9af2a8a7de7dc1328c8de65e1c8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/948e014946cfc9af2a8a7de7dc1328c8de65e1c8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023419135349407392",
"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/8857989073318485072/+/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:51219dedeeb68310",
"user_agent:luci-scheduler"
],
"updated_ts": "1610763270047690",
"url": "https://ci.chromium.org/b/8857989073318485072",
"utcnow_ts": "1610763271150487"
}
}
[02:14:31.187] Invocation finished in 48m44.531615293s with status SUCCEEDED