[23:14:36.431] New invocation is queued and will start shortly
[23:14:37.582] Starting the invocation (attempt 1)
[23:14:37.628] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:14:37.631] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:14:37.631] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022159030369877632",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dbf31cefd353bace3b6c2cc29837df0d9d5660c1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022159030369877632",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/dbf31cefd353bace3b6c2cc29837df0d9d5660c1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbf31cefd353bace3b6c2cc29837df0d9d5660c1",
"gitiles_ref:refs/heads/master"
]
}
[23:14:38.284] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611962077759201",
"id": "8856728968310150944",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dbf31cefd353bace3b6c2cc29837df0d9d5660c1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611962078144030",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45011",
"builder:linux-chromeos-rel",
"buildset:commit/git/dbf31cefd353bace3b6c2cc29837df0d9d5660c1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbf31cefd353bace3b6c2cc29837df0d9d5660c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022159030369877632",
"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/8856728968310150944/+/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": "1611962078144170",
"url": "https://ci.chromium.org/b/8856728968310150944",
"utcnow_ts": "1611962078274538"
}
}
[23:14:38.284] Task URL: https://ci.chromium.org/b/8856728968310150944
[23:14:38.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:2:0) after 1m0s
[23:14:40.482] Received PubSub notification, asking Buildbucket for the build status
[23:14:40.555] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:38.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:2:0)
[23:15:38.362] Timer tick, asking Buildbucket for the build status
[23:15:38.662] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:38.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:4:0) after 1m0s
[23:16:38.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:4:0)
[23:16:38.770] Timer tick, asking Buildbucket for the build status
[23:16:38.930] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:38.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:5:0) after 1m0s
[23:17:39.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:5:0)
[23:17:39.012] Timer tick, asking Buildbucket for the build status
[23:17:39.327] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:39.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:6:0) after 1m0s
[23:18:39.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:6:0)
[23:18:39.392] Timer tick, asking Buildbucket for the build status
[23:18:39.555] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:39.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:7:0) after 1m0s
[23:19:39.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:7:0)
[23:19:39.593] Timer tick, asking Buildbucket for the build status
[23:19:39.683] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:39.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:8:0) after 1m0s
[23:20:39.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:8:0)
[23:20:39.666] Timer tick, asking Buildbucket for the build status
[23:20:39.734] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:39.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:9:0) after 1m0s
[23:21:39.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:9:0)
[23:21:39.791] Timer tick, asking Buildbucket for the build status
[23:21:39.887] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:39.887] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:10:0) after 1m0s
[23:22:40.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:10:0)
[23:22:40.085] Timer tick, asking Buildbucket for the build status
[23:22:40.203] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:40.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:11:0) after 1m0s
[23:23:40.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:11:0)
[23:23:40.274] Timer tick, asking Buildbucket for the build status
[23:23:40.328] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:40.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:12:0) after 1m0s
[23:24:40.360] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:12:0)
[23:24:40.360] Timer tick, asking Buildbucket for the build status
[23:24:40.902] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:40.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:13:0) after 1m0s
[23:25:40.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:13:0)
[23:25:40.929] Timer tick, asking Buildbucket for the build status
[23:25:41.222] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:41.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:14:0) after 1m0s
[23:26:52.060] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:14:0)
[23:26:52.060] Timer tick, asking Buildbucket for the build status
[23:26:52.342] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:52.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:15:0) after 1m0s
[23:27:52.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:15:0)
[23:27:52.406] Timer tick, asking Buildbucket for the build status
[23:27:52.464] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:52.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:16:0) after 1m0s
[23:28:52.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:16:0)
[23:28:52.563] Timer tick, asking Buildbucket for the build status
[23:28:52.757] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:52.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:17:0) after 1m0s
[23:29:52.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:17:0)
[23:29:52.956] Timer tick, asking Buildbucket for the build status
[23:29:53.078] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:53.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:18:0) after 1m0s
[23:30:53.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:18:0)
[23:30:53.157] Timer tick, asking Buildbucket for the build status
[23:30:53.454] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:53.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:19:0) after 1m0s
[23:31:53.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:19:0)
[23:31:53.445] Timer tick, asking Buildbucket for the build status
[23:31:53.495] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:53.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:20:0) after 1m0s
[23:32:54.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:20:0)
[23:32:54.048] Timer tick, asking Buildbucket for the build status
[23:32:54.151] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:54.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:21:0) after 1m0s
[23:33:54.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:21:0)
[23:33:54.321] Timer tick, asking Buildbucket for the build status
[23:33:54.399] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:54.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:22:0) after 1m0s
[23:34:54.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:22:0)
[23:34:54.687] Timer tick, asking Buildbucket for the build status
[23:34:54.824] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:54.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:23:0) after 1m0s
[23:35:55.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:23:0)
[23:35:55.051] Timer tick, asking Buildbucket for the build status
[23:35:55.218] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:55.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:24:0) after 1m0s
[23:36:55.294] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:24:0)
[23:36:55.294] Timer tick, asking Buildbucket for the build status
[23:36:55.350] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:55.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:25:0) after 1m0s
[23:37:55.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:25:0)
[23:37:55.638] Timer tick, asking Buildbucket for the build status
[23:37:55.701] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:55.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:26:0) after 1m0s
[23:38:55.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:26:0)
[23:38:55.827] Timer tick, asking Buildbucket for the build status
[23:38:55.960] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:55.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:27:0) after 1m0s
[23:39:56.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:27:0)
[23:39:56.115] Timer tick, asking Buildbucket for the build status
[23:39:56.411] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:56.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:28:0) after 1m0s
[23:40:56.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:28:0)
[23:40:56.551] Timer tick, asking Buildbucket for the build status
[23:40:56.700] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:56.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:29:0) after 1m0s
[23:41:56.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:29:0)
[23:41:56.765] Timer tick, asking Buildbucket for the build status
[23:41:56.997] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:56.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:30:0) after 1m0s
[23:42:57.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:30:0)
[23:42:57.058] Timer tick, asking Buildbucket for the build status
[23:42:57.132] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:57.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:31:0) after 1m0s
[23:43:57.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:31:0)
[23:43:57.177] Timer tick, asking Buildbucket for the build status
[23:43:57.254] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:57.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:32:0) after 1m0s
[23:44:57.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:32:0)
[23:44:57.242] Timer tick, asking Buildbucket for the build status
[23:44:57.362] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:57.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:33:0) after 1m0s
[23:45:57.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:33:0)
[23:45:57.411] Timer tick, asking Buildbucket for the build status
[23:45:57.476] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:57.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:34:0) after 1m0s
[23:46:57.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:34:0)
[23:46:57.496] Timer tick, asking Buildbucket for the build status
[23:46:57.567] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:57.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:35:0) after 1m0s
[23:47:57.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:35:0)
[23:47:57.576] Timer tick, asking Buildbucket for the build status
[23:47:57.655] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:57.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:36:0) after 1m0s
[23:48:57.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:36:0)
[23:48:57.743] Timer tick, asking Buildbucket for the build status
[23:48:57.926] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:57.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:37:0) after 1m0s
[23:49:57.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:37:0)
[23:49:57.960] Timer tick, asking Buildbucket for the build status
[23:49:58.212] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:58.212] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:38:0) after 1m0s
[23:50:58.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:38:0)
[23:50:58.298] Timer tick, asking Buildbucket for the build status
[23:50:58.354] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:58.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:39:0) after 1m0s
[23:51:58.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:39:0)
[23:51:58.441] Timer tick, asking Buildbucket for the build status
[23:51:58.502] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:58.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:40:0) after 1m0s
[23:52:59.751] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:40:0)
[23:52:59.751] Timer tick, asking Buildbucket for the build status
[23:52:59.954] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:59.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:41:0) after 1m0s
[23:54:00.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:41:0)
[23:54:00.151] Timer tick, asking Buildbucket for the build status
[23:54:00.461] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:00.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:42:0) after 1m0s
[23:55:01.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:42:0)
[23:55:01.084] Timer tick, asking Buildbucket for the build status
[23:55:01.324] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:01.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:43:0) after 1m0s
[23:56:01.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:43:0)
[23:56:01.529] Timer tick, asking Buildbucket for the build status
[23:56:01.627] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:01.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:44:0) after 1m0s
[23:57:02.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:44:0)
[23:57:02.113] Timer tick, asking Buildbucket for the build status
[23:57:02.321] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:02.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:45:0) after 1m0s
[23:58:02.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:45:0)
[23:58:02.364] Timer tick, asking Buildbucket for the build status
[23:58:02.478] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:02.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:46:0) after 1m0s
[23:59:02.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:46:0)
[23:59:02.575] Timer tick, asking Buildbucket for the build status
[23:59:02.642] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:02.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:47:0) after 1m0s
[00:00:02.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:47:0)
[00:00:02.785] Timer tick, asking Buildbucket for the build status
[00:00:02.948] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:02.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:48:0) after 1m0s
[00:01:03.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:48:0)
[00:01:03.033] Timer tick, asking Buildbucket for the build status
[00:01:03.230] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:03.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:49:0) after 1m0s
[00:02:03.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:49:0)
[00:02:03.273] Timer tick, asking Buildbucket for the build status
[00:02:03.535] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:03.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:50:0) after 1m0s
[00:03:03.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:50:0)
[00:03:03.585] Timer tick, asking Buildbucket for the build status
[00:03:03.713] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:03.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:51:0) after 1m0s
[00:04:03.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:51:0)
[00:04:03.809] Timer tick, asking Buildbucket for the build status
[00:04:04.473] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:04.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:52:0) after 1m0s
[00:05:04.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:52:0)
[00:05:04.564] Timer tick, asking Buildbucket for the build status
[00:05:04.737] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:04.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:53:0) after 1m0s
[00:06:04.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:53:0)
[00:06:04.940] Timer tick, asking Buildbucket for the build status
[00:06:05.147] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:05.147] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:54:0) after 1m0s
[00:07:05.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:54:0)
[00:07:05.206] Timer tick, asking Buildbucket for the build status
[00:07:05.342] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:05.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:55:0) after 1m0s
[00:08:05.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:55:0)
[00:08:05.497] Timer tick, asking Buildbucket for the build status
[00:08:05.675] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:05.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:56:0) after 1m0s
[00:09:06.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:56:0)
[00:09:06.092] Timer tick, asking Buildbucket for the build status
[00:09:06.205] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:06.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:57:0) after 1m0s
[00:10:06.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:57:0)
[00:10:06.303] Timer tick, asking Buildbucket for the build status
[00:10:06.490] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:06.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:58:0) after 1m0s
[00:11:06.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:58:0)
[00:11:06.788] Timer tick, asking Buildbucket for the build status
[00:11:06.950] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:06.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:59:0) after 1m0s
[00:12:07.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:59:0)
[00:12:07.149] Timer tick, asking Buildbucket for the build status
[00:12:07.275] Build 8856728968310150944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:07.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022159030369877632:60:0) after 1m0s
[00:12:56.235] Received PubSub notification, asking Buildbucket for the build status
[00:12:56.309] Build 8856728968310150944: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:12:56.309] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611965575076952",
"created_by": "project:chromium",
"created_ts": "1611962077759201",
"id": "8856728968310150944",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dbf31cefd353bace3b6c2cc29837df0d9d5660c1\"}}",
"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\": \"dbf31cefd353bace3b6c2cc29837df0d9d5660c1\", \"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\": 45011, \"got_angle_revision\": \"74f7be6d1fd020a432dee63222fa090e4d8df651\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"dbf31cefd353bace3b6c2cc29837df0d9d5660c1\", \"got_revision_cp\": \"refs/heads/master@{#848777}\", \"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\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"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\": \"dbf31cefd353bace3b6c2cc29837df0d9d5660c1\", \"swarm_hashes_refs/heads/master(at){#848777}_without_patch\": {\"absl_hardening_tests\": \"46585d3a2ae5551bd7391ea675118a527f3d2920\", \"accessibility_unittests\": \"59487784b632639da3cc3ce4b9db2fe358f2d0e6\", \"angle_unittests\": \"8a02ce0f46cfb7222027cd2a1c89579e3c4e95b4\", \"app_list_unittests\": \"10dffdd763ca232e1ab3f38b508214f94119ab23\", \"app_shell_unittests\": \"d98211857e313f60942ab60cee27455a0db72ed7\", \"ash_unittests\": \"c031583d9542e139928b779b7707f5ed84c3e72a\", \"aura_unittests\": \"bdf1f130837604ff036f4efe8d4f18ad41b8d300\", \"base_unittests\": \"fefc1c03ae4471e5d799c335044765a8573110f2\", \"base_util_unittests\": \"8981c9b59e8fef9eaed7541f41b7306a049408c7\", \"blink_common_unittests\": \"2a1af1b4d278e3fe6ee20382c777cad57c47c59a\", \"blink_fuzzer_unittests\": \"725ee41b93c8bf687892b648d520cc435e312560\", \"blink_heap_unittests\": \"5e75bca8b2fcb2eaf1520901ef1c8ca88d709b3d\", \"blink_platform_unittests\": \"83f4bab792b5ea8af324de04ee793310a4babe85\", \"blink_unittests\": \"4cb5b8b5a1494c64f910af12aa6ac31d12176eea\", \"boringssl_crypto_tests\": \"626ae57819736dd9ed44f80df2166a9f8b6c828b\", \"boringssl_ssl_tests\": \"94acb3823e1db16e26971befd905d2aecee545cf\", \"browser_tests\": \"39874372e3e9a7e1c59b26f0f6a1361673327ec4\", \"capture_unittests\": \"fd9f2a5d5aab05a1d796eae7416edef0ba93a2b2\", \"cast_unittests\": \"4c0afdf304e8fdb47bb73236ccb38be4cb4169a1\", \"cc_unittests\": \"93a985b16a5235f5ae6d01ecead2eed174bece80\", \"chrome_app_unittests\": \"2c1770dda7d0bf7e455500d6f2a01fdd9883f314\", \"chromedriver_unittests\": \"b71ba10f325b640b1037d62546c385193706eca5\", \"chromeos_components_unittests\": \"5c33fb7154e9821dd4bc8b588d79fae10c92b237\", \"chromeos_unittests\": \"6b6277112ebb126f8a741b43e20cf6a4e5364883\", \"components_browsertests\": \"a0a0331979f46bde9e84ecf324c233b68fdef30e\", \"components_unittests\": \"3c3a5d7817cce4d2bb08d8c3ab06c044aa42e496\", \"compositor_unittests\": \"2ecb2052067029288aa6487da1c64b13c9f40d31\", \"content_browsertests\": \"64d83cf3eed129abe3c4bad33d41dcd8c4e58f72\", \"content_unittests\": \"edc91294bffabcdec364d70a50da9c1df6e72d7a\", \"crashpad_tests\": \"c00d03a9c15c79fd2db415e1a4dda5688e10e2c9\", \"crypto_unittests\": \"ede510b5404da883201c1a6d021dca4724ba68ea\", \"dbus_unittests\": \"05861f7061a373f74325b3f4314d682f21c565c1\", \"device_unittests\": \"ad916b1a0d47fa9e71dfa1a7f472acb6ba8900b1\", \"display_unittests\": \"259a2fe38701c8e8060c817ed54d0b0b43c120fb\", \"events_unittests\": \"212aa4edac1ecfb1d43236e81093c11624510b1d\", \"exo_unittests\": \"ceca35032e401bb863554c3fba41f25d53aef657\", \"extensions_browsertests\": \"9889d9f57333d452647f2dd46fdd12f75b575a00\", \"extensions_unittests\": \"21477b0a1c685137620e6b2ea5287216bfcc9a16\", \"filesystem_service_unittests\": \"f8b7368d436c4a7d745113dabcc773d2cfbafd0b\", \"gcm_unit_tests\": \"25670dc1ce167729ab6ac1998118217ba61763c1\", \"gfx_unittests\": \"a10a8f4f0b7744e943faa17a9f8a404ee9aaeeb1\", \"gin_unittests\": \"3257cf35a7c76c13f2856617f0984a0d3ed5bf7d\", \"gl_unittests_ozone\": \"399c57bb29470aae1bb45888ae5e7dba03b2f57b\", \"google_apis_unittests\": \"aa35e92498d2c17aeb11f40c8f87a2bd0f260477\", \"gpu_unittests\": \"394b35ee4688d8a48ba4bb03f1f4ee28aeb21ccd\", \"gwp_asan_unittests\": \"aea9e0fe05bc6c621f455aee69997e9caea10e69\", \"interactive_ui_tests\": \"82553be2e82acd85fa3d84eb84db98983625d50c\", \"ipc_tests\": \"e20d99ba37825befc76272c8b949a7b2fb179cce\", \"jingle_unittests\": \"7637a8043b760b81c507ef6d45a3449995c7899e\", \"keyboard_unittests\": \"22dbbf7992475228cdf3506637bc2f8ff33d2634\", \"latency_unittests\": \"1518fbd4a85585b26111a7f848d5e35a5e574f05\", \"libjingle_xmpp_unittests\": \"f06fef9a5fe8a89a4684e82f383c8c0ad3c1667b\", \"liburlpattern_unittests\": \"019c3273243e7508c5bd39530e22e17e4247fd72\", \"media_blink_unittests\": \"abe7524daa741bdab035414f8e5cc52ebdc808de\", \"media_unittests\": \"e74a7da5593967ea411e715c9197f6a668724d60\", \"message_center_unittests\": \"a40b78e099ef70a86382ff7a0822a2cfe20ee3d4\", \"midi_unittests\": \"a3dc980c54523663e4b996fa6b8e4b3915768068\", \"mojo_core_unittests\": \"c8c4ebcdd659ca646342ddea994b1e90091f2ef0\", \"mojo_unittests\": \"4dfe26d2a880da9e2fb97c3e5863aa44d32b4538\", \"nacl_helper_nonsfi_unittests\": \"ec4a1c94254ee75c10fe781819cae3338d372dfc\", \"nacl_loader_unittests\": \"508768cead63840702543c8520f4b5b8bb144532\", \"native_theme_unittests\": \"10bea2dffdcf97cc1db0908ba2e83a68672bbf21\", \"net_unittests\": \"ab833e1f56fb611910d970c8c150ac252215f19b\", \"ozone_gl_unittests\": \"e72131952bd154326df390748d96ab55ceff5e3a\", \"ozone_unittests\": \"c7b7586ca2df7f3c079443463fcfa1a206f8919d\", \"ozone_x11_unittests\": \"acc803212a27f3a325bc7805423d9e89f5e6bcc2\", \"pdf_unittests\": \"712eca128f1483edbf727fa8f3dcccba953f8a92\", \"perfetto_unittests\": \"c6e3740999a522c6e1ec4a21cee2f9773675b77b\", \"ppapi_unittests\": \"5fe7b837029a26ea51bd3c7f04c6cdc24a4614c8\", \"printing_unittests\": \"7ae3b041827874b21c18025efe2a5f4553d04966\", \"remoting_unittests\": \"fb06fc032e4a029c7b733819ba651c48bc167075\", \"sandbox_linux_unittests\": \"6495547049482df1dc2228f4515c408b21d1afaa\", \"service_manager_unittests\": \"178c990d3d73c97780a5bf86546037a67870a64c\", \"services_unittests\": \"48044a9c7c745b2ea467f9c55f1cb81b67da2aa8\", \"shell_dialogs_unittests\": \"5aa1e49350dc3a2bb8f97f7263b85d4007c26f9c\", \"shell_encryption_unittests\": \"cf58706dfbec708ae093aba24d60673ed6416b04\", \"skia_unittests\": \"45ab958ec92ab447e002dbb5f48559606aa3b340\", \"snapshot_unittests\": \"6d499be3c78d9b411f2330fafa8d7f014903d9d6\", \"sql_unittests\": \"179fe047815936ab74609144154573190f38eaff\", \"storage_unittests\": \"ba9c98592b84583d7b4702608820d408034f1071\", \"sync_integration_tests\": \"f3134d5ae2d06235db23acf4090c423aaf5f693b\", \"traffic_annotation_auditor_unittests\": \"bf52559d7574f717ae6d33bc8271d4d92e1a2eff\", \"ui_base_unittests\": \"c34dcc1bfb62d1b3f25736c5e2ec3b6814bbe23f\", \"ui_chromeos_unittests\": \"9160724460854fdb063f04d8e0fac22717b00f32\", \"ui_touch_selection_unittests\": \"9e021dae8b6dbbd3bbe322dd7da43a8c9cfaa005\", \"unit_tests\": \"1eff211b324edf66101cb01012dbba48407c447b\", \"url_unittests\": \"70895e4e581a3adc12e11c5d2ecc418d95e69270\", \"usage_time_limit_unittests\": \"85b1e23d421844da75720745591ef694c76dfd01\", \"views_unittests\": \"dd06eaaee1677e0ec183ba9f5a50660526b6dc0e\", \"viz_unittests\": \"9c340696c1d8a6563760ad1ee5d188b1a400367a\", \"wayland_client_perftests\": \"56807c88baa61934d712933ef8792b216a554a22\", \"wm_unittests\": \"16b23f17f4f58e3bcb2e4e9b42b55d405a27249b\", \"wtf_unittests\": \"2add79d3bdcaf4cb2f01d4e4ce844d33ecf24f53\", \"zlib_unittests\": \"71554517f7577e7dec2ee5e0f2dcda2e8e35d210\"}}, \"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": "1611962079420072",
"status": "COMPLETED",
"status_changed_ts": "1611965575588725",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45011",
"builder:linux-chromeos-rel",
"buildset:commit/git/dbf31cefd353bace3b6c2cc29837df0d9d5660c1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbf31cefd353bace3b6c2cc29837df0d9d5660c1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022159030369877632",
"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/8856728968310150944/+/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:51693edc9cfdd110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611965575595352",
"url": "https://ci.chromium.org/b/8856728968310150944",
"utcnow_ts": "1611965576298297"
}
}
[00:12:56.309] Invocation finished in 58m19.89785161s with status SUCCEEDED