[20:01:10.367] New invocation is queued and will start shortly
[20:01:11.598] Starting the invocation (attempt 1)
[20:01:11.649] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:01:11.649] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:01:11.649] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022352394139525072",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"84c1288826a9f18e39940beb14d0488bbec6e490\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022352394139525072",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/84c1288826a9f18e39940beb14d0488bbec6e490",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84c1288826a9f18e39940beb14d0488bbec6e490",
"gitiles_ref:refs/heads/master"
]
}
[20:01:12.294] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611777671767824",
"id": "8856922332007577984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84c1288826a9f18e39940beb14d0488bbec6e490\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611777672140788",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44954",
"builder:linux-chromeos-rel",
"buildset:commit/git/84c1288826a9f18e39940beb14d0488bbec6e490",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84c1288826a9f18e39940beb14d0488bbec6e490",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022352394139525072",
"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/8856922332007577984/+/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": "1611777672140973",
"url": "https://ci.chromium.org/b/8856922332007577984",
"utcnow_ts": "1611777672284955"
}
}
[20:01:12.294] Task URL: https://ci.chromium.org/b/8856922332007577984
[20:01:12.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:2:0) after 1m0s
[20:01:31.082] Received PubSub notification, asking Buildbucket for the build status
[20:01:31.217] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:12.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:2:0)
[20:02:12.403] Timer tick, asking Buildbucket for the build status
[20:02:12.679] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:12.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:4:0) after 1m0s
[20:03:12.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:4:0)
[20:03:12.867] Timer tick, asking Buildbucket for the build status
[20:03:12.962] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:12.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:5:0) after 1m0s
[20:04:12.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:5:0)
[20:04:12.986] Timer tick, asking Buildbucket for the build status
[20:04:13.134] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:13.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:6:0) after 1m0s
[20:05:13.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:6:0)
[20:05:13.203] Timer tick, asking Buildbucket for the build status
[20:05:13.326] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:13.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:7:0) after 1m0s
[20:06:13.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:7:0)
[20:06:13.355] Timer tick, asking Buildbucket for the build status
[20:06:13.432] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:13.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:8:0) after 1m0s
[20:07:13.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:8:0)
[20:07:13.432] Timer tick, asking Buildbucket for the build status
[20:07:13.616] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:13.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:9:0) after 1m0s
[20:08:13.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:9:0)
[20:08:13.622] Timer tick, asking Buildbucket for the build status
[20:08:13.774] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:13.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:10:0) after 1m0s
[20:09:14.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:10:0)
[20:09:14.225] Timer tick, asking Buildbucket for the build status
[20:09:14.448] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:14.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:11:0) after 1m0s
[20:10:14.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:11:0)
[20:10:14.668] Timer tick, asking Buildbucket for the build status
[20:10:14.857] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:14.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:12:0) after 1m0s
[20:11:14.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:12:0)
[20:11:14.897] Timer tick, asking Buildbucket for the build status
[20:11:15.127] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:15.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:13:0) after 1m0s
[20:12:15.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:13:0)
[20:12:15.332] Timer tick, asking Buildbucket for the build status
[20:12:15.448] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:15.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:14:0) after 1m0s
[20:13:15.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:14:0)
[20:13:15.492] Timer tick, asking Buildbucket for the build status
[20:13:15.555] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:15.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:15:0) after 1m0s
[20:14:15.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:15:0)
[20:14:15.665] Timer tick, asking Buildbucket for the build status
[20:14:15.907] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:15.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:16:0) after 1m0s
[20:15:15.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:16:0)
[20:15:15.989] Timer tick, asking Buildbucket for the build status
[20:15:16.144] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:16.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:17:0) after 1m0s
[20:16:16.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:17:0)
[20:16:16.297] Timer tick, asking Buildbucket for the build status
[20:16:16.424] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:16.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:18:0) after 1m0s
[20:17:16.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:18:0)
[20:17:16.566] Timer tick, asking Buildbucket for the build status
[20:17:16.632] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:16.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:19:0) after 1m0s
[20:18:16.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:19:0)
[20:18:16.742] Timer tick, asking Buildbucket for the build status
[20:18:17.000] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:17.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:20:0) after 1m0s
[20:19:17.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:20:0)
[20:19:17.035] Timer tick, asking Buildbucket for the build status
[20:19:17.123] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:17.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:21:0) after 1m0s
[20:20:17.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:21:0)
[20:20:17.146] Timer tick, asking Buildbucket for the build status
[20:20:17.219] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:17.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:22:0) after 1m0s
[20:21:17.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:22:0)
[20:21:17.337] Timer tick, asking Buildbucket for the build status
[20:21:17.521] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:17.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:23:0) after 1m0s
[20:22:17.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:23:0)
[20:22:17.536] Timer tick, asking Buildbucket for the build status
[20:22:17.621] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:17.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:24:0) after 1m0s
[20:23:17.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:24:0)
[20:23:17.697] Timer tick, asking Buildbucket for the build status
[20:23:17.771] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:17.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:25:0) after 1m0s
[20:24:17.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:25:0)
[20:24:17.826] Timer tick, asking Buildbucket for the build status
[20:24:17.891] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:17.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:26:0) after 1m0s
[20:25:17.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:26:0)
[20:25:17.937] Timer tick, asking Buildbucket for the build status
[20:25:18.022] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:18.022] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:27:0) after 1m0s
[20:26:18.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:27:0)
[20:26:18.189] Timer tick, asking Buildbucket for the build status
[20:26:18.354] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:18.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:28:0) after 1m0s
[20:27:18.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:28:0)
[20:27:18.459] Timer tick, asking Buildbucket for the build status
[20:27:18.527] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:18.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:29:0) after 1m0s
[20:28:18.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:29:0)
[20:28:18.697] Timer tick, asking Buildbucket for the build status
[20:28:18.771] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:18.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:30:0) after 1m0s
[20:29:18.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:30:0)
[20:29:18.837] Timer tick, asking Buildbucket for the build status
[20:29:18.906] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:18.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:31:0) after 1m0s
[20:30:18.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:31:0)
[20:30:18.964] Timer tick, asking Buildbucket for the build status
[20:30:19.195] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:19.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:32:0) after 1m0s
[20:31:19.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:32:0)
[20:31:19.318] Timer tick, asking Buildbucket for the build status
[20:31:19.483] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:19.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:33:0) after 1m0s
[20:32:19.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:33:0)
[20:32:19.560] Timer tick, asking Buildbucket for the build status
[20:32:19.637] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:19.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:34:0) after 1m0s
[20:33:19.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:34:0)
[20:33:19.689] Timer tick, asking Buildbucket for the build status
[20:33:19.744] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:19.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:35:0) after 1m0s
[20:34:19.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:35:0)
[20:34:19.765] Timer tick, asking Buildbucket for the build status
[20:34:19.901] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:19.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:36:0) after 1m0s
[20:35:19.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:36:0)
[20:35:19.929] Timer tick, asking Buildbucket for the build status
[20:35:20.094] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:20.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:37:0) after 1m0s
[20:36:20.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:37:0)
[20:36:20.137] Timer tick, asking Buildbucket for the build status
[20:36:20.581] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:20.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:38:0) after 1m0s
[20:37:20.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:38:0)
[20:37:20.591] Timer tick, asking Buildbucket for the build status
[20:37:20.691] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:20.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:39:0) after 1m0s
[20:38:20.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:39:0)
[20:38:20.841] Timer tick, asking Buildbucket for the build status
[20:38:20.920] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:20.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:40:0) after 1m0s
[20:39:21.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:40:0)
[20:39:21.047] Timer tick, asking Buildbucket for the build status
[20:39:21.160] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:21.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:41:0) after 1m0s
[20:40:21.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:41:0)
[20:40:21.242] Timer tick, asking Buildbucket for the build status
[20:40:21.306] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:21.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:42:0) after 1m0s
[20:41:21.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:42:0)
[20:41:21.493] Timer tick, asking Buildbucket for the build status
[20:41:22.452] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:22.452] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:43:0) after 1m0s
[20:42:22.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:43:0)
[20:42:22.698] Timer tick, asking Buildbucket for the build status
[20:42:22.843] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:22.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:44:0) after 1m0s
[20:43:22.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:44:0)
[20:43:22.974] Timer tick, asking Buildbucket for the build status
[20:43:23.248] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:23.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:45:0) after 1m0s
[20:44:23.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:45:0)
[20:44:23.303] Timer tick, asking Buildbucket for the build status
[20:44:23.749] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:23.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:46:0) after 1m0s
[20:45:23.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:46:0)
[20:45:23.827] Timer tick, asking Buildbucket for the build status
[20:45:23.972] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:23.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:47:0) after 1m0s
[20:46:24.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:47:0)
[20:46:24.018] Timer tick, asking Buildbucket for the build status
[20:46:24.221] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:24.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:48:0) after 1m0s
[20:47:24.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:48:0)
[20:47:24.381] Timer tick, asking Buildbucket for the build status
[20:47:24.524] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:24.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:49:0) after 1m0s
[20:48:24.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:49:0)
[20:48:24.994] Timer tick, asking Buildbucket for the build status
[20:48:25.653] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:25.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:50:0) after 1m0s
[20:49:25.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:50:0)
[20:49:25.736] Timer tick, asking Buildbucket for the build status
[20:49:26.118] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:26.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:51:0) after 1m0s
[20:50:26.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:51:0)
[20:50:26.232] Timer tick, asking Buildbucket for the build status
[20:50:26.432] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:26.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:52:0) after 1m0s
[20:51:26.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:52:0)
[20:51:26.495] Timer tick, asking Buildbucket for the build status
[20:51:26.698] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:26.698] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:53:0) after 1m0s
[20:52:26.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:53:0)
[20:52:26.746] Timer tick, asking Buildbucket for the build status
[20:52:27.172] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:27.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:54:0) after 1m0s
[20:53:27.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:54:0)
[20:53:27.195] Timer tick, asking Buildbucket for the build status
[20:53:27.582] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:27.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:55:0) after 1m0s
[20:54:27.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:55:0)
[20:54:27.656] Timer tick, asking Buildbucket for the build status
[20:54:27.859] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:27.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:56:0) after 1m0s
[20:55:27.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:56:0)
[20:55:27.941] Timer tick, asking Buildbucket for the build status
[20:55:28.191] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:28.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:57:0) after 1m0s
[20:56:28.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:57:0)
[20:56:28.219] Timer tick, asking Buildbucket for the build status
[20:56:28.480] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:28.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:58:0) after 1m0s
[20:57:28.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:58:0)
[20:57:28.526] Timer tick, asking Buildbucket for the build status
[20:57:28.654] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:28.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:59:0) after 1m0s
[20:58:28.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:59:0)
[20:58:28.745] Timer tick, asking Buildbucket for the build status
[20:58:29.488] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:29.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:60:0) after 1m0s
[20:59:29.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:60:0)
[20:59:29.554] Timer tick, asking Buildbucket for the build status
[20:59:29.867] Build 8856922332007577984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:29.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022352394139525072:61:0) after 1m0s
[21:00:08.303] Received PubSub notification, asking Buildbucket for the build status
[21:00:08.497] Build 8856922332007577984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:00:08.497] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611781204347708",
"created_by": "project:chromium",
"created_ts": "1611777671767824",
"id": "8856922332007577984",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84c1288826a9f18e39940beb14d0488bbec6e490\"}}",
"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\": \"84c1288826a9f18e39940beb14d0488bbec6e490\", \"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-23-q58c\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44954, \"got_angle_revision\": \"63b4df5913692d24b0b871a1a8db3a5165d116e9\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"84c1288826a9f18e39940beb14d0488bbec6e490\", \"got_revision_cp\": \"refs/heads/master@{#847739}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"74ef1d1cf560c22c37a43f202a6275c8ed92ae97\", \"got_v8_revision_cp\": \"refs/heads/9.0.85@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\", \"prev_got_revision\": \"69d52c98acfa75a2580e2ae7beba6b1320244d56\", \"prev_got_revision_cp\": \"refs/heads/master@{#847663}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84c1288826a9f18e39940beb14d0488bbec6e490\", \"swarm_hashes_refs/heads/master(at){#847739}_without_patch\": {\"absl_hardening_tests\": \"ba47532e0184d1ec1795c03e4eb4806e08803548\", \"accessibility_unittests\": \"d306d87c55ff5e6e31b1e44a172e0f01f422c353\", \"angle_unittests\": \"d5c9ddd75a613ce7dc699b2fe3dbb451d803d3d3\", \"app_list_unittests\": \"990c2cc6f94d0541dc58081859399c0dc3649e80\", \"app_shell_unittests\": \"66b6aa53c8d1ea30fd30e4c0327f060843c3ec5f\", \"ash_unittests\": \"cb6d32d332655817b6c4a5caa7c7d421111e2cae\", \"aura_unittests\": \"4eb4f4793ca78e9182f20d1065528fb58a25b06d\", \"base_unittests\": \"8fd7892fa475667edc7c75329228b017f54e3102\", \"base_util_unittests\": \"8dcf5e343fe8f402d60d1c592e2eb15ab487b443\", \"blink_common_unittests\": \"7a7e1799a821d9dd07025c7489deba6059e5327f\", \"blink_fuzzer_unittests\": \"86df4ce13136748c8154270e22c97ba99e4a55c0\", \"blink_heap_unittests\": \"6229d1428f80dc86679f8e3b40c6357e7ea331e7\", \"blink_platform_unittests\": \"836e3069e72bcda8e92df2d9537c446125cec86c\", \"blink_unittests\": \"22a0990fbd6be3aa50ffa6edb518c78109688ef1\", \"boringssl_crypto_tests\": \"f12ff3549d4adaa7f10ad9b33ffbb5f0a4d74604\", \"boringssl_ssl_tests\": \"6fbcd485beabb6dd09bf961e8864ae0094cbc0a8\", \"browser_tests\": \"23e00acab148420af56d0ccc46d96929115c9a68\", \"capture_unittests\": \"27ad88b8e4b78580f8a5a2eed7b45172298e6ccd\", \"cast_unittests\": \"c154d9b93eeaf7e5ffb43eabc9679eed91b0602f\", \"cc_unittests\": \"c69eef59f1d126eca53a06e1b9b09b939783da22\", \"chrome_app_unittests\": \"a117b85446572576aca15186c600672ffc70eae9\", \"chromedriver_unittests\": \"d8afcdd1236d9437f5610a5e6f3b96a39cba4148\", \"chromeos_components_unittests\": \"d96b5eb9f81cf84d2af93c80ba00bea08291f018\", \"chromeos_unittests\": \"3206a08e608996f1c9bb2be316314c7aaf9a5ba6\", \"components_browsertests\": \"174807cf7844836bc12748b0400812ba583d60cd\", \"components_unittests\": \"12727400965b7e371cde63f8029c2fa9e5f036c1\", \"compositor_unittests\": \"5eba5072fb23a735d4be58e55958fb3e6d619ec9\", \"content_browsertests\": \"e4a38cc8cdb5007ac5bf8eb2e2e0b2dfe83c3f26\", \"content_unittests\": \"f5a325c265d4c7197fe8bea3d2adaa6c8b84d9bc\", \"crashpad_tests\": \"7fa9abd985f58c1d50e40225d54c95eea123c6bd\", \"crypto_unittests\": \"53b837ee02b6160f6aef8b76eda4a2b02a339261\", \"dbus_unittests\": \"015920727bc0c3c412121cc8040f5296163737fc\", \"device_unittests\": \"957c550f267fbc039450115139c70cda687937f9\", \"display_unittests\": \"176d1afbfafcf8c1b4c309bf09a5b8c7a5f54d73\", \"events_unittests\": \"8a67abbaaeaa699f1a50c6d610e363e0be738b67\", \"exo_unittests\": \"37df1edee1eb7f7948b9ad54f323327dd5d54d54\", \"extensions_browsertests\": \"4ec807952a4c36a3f1f0b63352db5fff86a46bc1\", \"extensions_unittests\": \"fd2820035d3a60988c83f061a5082c077451059f\", \"filesystem_service_unittests\": \"ad3fb7b2a3a1d178632c61a947beed2f2e637dba\", \"gcm_unit_tests\": \"d8ad18da374f490c05c40d3aed8bbc4cc1e0a00e\", \"gfx_unittests\": \"baf80e82d1b581a4b4967250f8912f0f539b627a\", \"gin_unittests\": \"ae2b7d12872eb723371749688586d7ffed8a8587\", \"gl_unittests_ozone\": \"805c7b828b97b8b8c7b923b6f2e208e35dfe9e47\", \"google_apis_unittests\": \"436980e1454fc2a98c3b3279486bc51ebf5a1d64\", \"gpu_unittests\": \"bb1d974d0bcebdb38241a970f99eb07bf97a57e0\", \"gwp_asan_unittests\": \"f3eb3a0276ac8d5e4583141210f6e1f65070d7e5\", \"interactive_ui_tests\": \"a0f6e7f2278e451666d01482b8cd98883bb8f1f8\", \"ipc_tests\": \"7183af26b3720aba06647b7858f9de2fd561e9ec\", \"jingle_unittests\": \"cfaac0756a7404b1645f6bf522d6e91abc78c8e5\", \"keyboard_unittests\": \"16935eaacaf30b3cf616c864830aa994f8780fd8\", \"latency_unittests\": \"2ca1e78aca73693d8884f821472f61d736605af0\", \"libjingle_xmpp_unittests\": \"c609a5678f23962adb7efc23e2e8aa3894b1a327\", \"liburlpattern_unittests\": \"e9d3f4d2f4dc0d87c27553d99b8251c1ac519f55\", \"media_blink_unittests\": \"e4deab3b5e337a2ec5bac95a45f0c086ebfd8ef6\", \"media_unittests\": \"8b604ee4f96d06cac57ea2c67b453d148cb82bc2\", \"message_center_unittests\": \"d9fae8a8c5d9240f03f5dfd8521ee35b0d14862d\", \"midi_unittests\": \"c75271bfadb17bbbe32b0b7d0ab188ebcbbc6517\", \"mojo_core_unittests\": \"304663ffdcff882843d68866c65e7aadc5aabc77\", \"mojo_unittests\": \"6325b29d99a396b1e275a9f4e6a57c4ced879c38\", \"nacl_helper_nonsfi_unittests\": \"5ac7dd5681cc613f9075423a45193fa6c7424533\", \"nacl_loader_unittests\": \"434f08b622626162e77a000784a38fb0ca6c6d9f\", \"native_theme_unittests\": \"6557f728488d5326659e211639ab2e3b644c5092\", \"net_unittests\": \"9e4e78dc96a129c3c36b47c9edddac0f2420916d\", \"ozone_gl_unittests\": \"9da1c36047160f5f0e0d6f24188f99e508069745\", \"ozone_unittests\": \"4d775749c0234819692172e6026fc2cbbacf6f7a\", \"ozone_x11_unittests\": \"77e5071249967852c95c24ac9e1b74fe6d3f0312\", \"pdf_unittests\": \"5e9b1836d176d000a72b6ea557455aefaf157377\", \"perfetto_unittests\": \"528ba31a71cad5f10baac1820112e399b98f4f68\", \"ppapi_unittests\": \"a257248749842a4b2b134433ce21e84ed4ceb1ae\", \"printing_unittests\": \"b01c6480691d4cea7e4e240ee152ade98faa6dac\", \"remoting_unittests\": \"3a5b4e7e6259797cc88390c74816cfa3cec38abe\", \"sandbox_linux_unittests\": \"0307df5f5311a1c7286ba70f6c030dbfa0e53ad6\", \"service_manager_unittests\": \"02d7ddb271a9d71aa9a62e3cf6d793ade0f935c7\", \"services_unittests\": \"1bd011a0d8318fadb567816c532aae6b838444a2\", \"shell_dialogs_unittests\": \"d8607cea3fc703c434825e9d0f305eddc544f6ca\", \"shell_encryption_unittests\": \"0711da466cb5b6836c839832ecd2c4523bf66c3b\", \"skia_unittests\": \"408b8a79edf69595fd4a65ba8e4f8d666fe8872f\", \"snapshot_unittests\": \"d2db023e416fd6c7268b2dca66118a5e9d651ab6\", \"sql_unittests\": \"21c770f908d63e94b1cb9c731e8e544b4e97ee3b\", \"storage_unittests\": \"b7814146ddaff03835ce3ce10bf5114283fdbba8\", \"sync_integration_tests\": \"9d3562c4aa348e061f6bcaa46c4505335656bf0d\", \"traffic_annotation_auditor_unittests\": \"e766ed67bd735a71ab76337cc27c1ddb1a39d612\", \"ui_base_unittests\": \"d80e29e50b80766bc595b6d8814140445bb5153c\", \"ui_chromeos_unittests\": \"b35a21fd8a047cc78b85a5d5a1cf4ffda8a99209\", \"ui_touch_selection_unittests\": \"a67e7db0a695f06c41b1ac518077b7d0b609d65a\", \"unit_tests\": \"814315dd1512ca38d96a8d45f922fcc8a428798c\", \"url_unittests\": \"25ccee9c1eb31fc383bdf9bf53b04885a537c38a\", \"usage_time_limit_unittests\": \"fdfcedb56fa6de8c79584837538464045ed8f4b4\", \"views_unittests\": \"7fc0c5fde7238e30f7ed000e1ae3ed91141df058\", \"viz_unittests\": \"005064f9bc58a8d29708fd152a37353f11f15498\", \"wayland_client_perftests\": \"32da0c4e4b5ea3135afab07023d14a604b94a986\", \"wm_unittests\": \"888adbf24a2eade01790bf4d2822108242548bee\", \"wtf_unittests\": \"ef1b07246d0b32941798b2862f1dca7309ad3c68\", \"zlib_unittests\": \"64b451fa316d9483e46d4f32a69c413479aba0f6\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_219e0ffc8c25f5e0b87c50b06543131c770ba4c60d610054ab97561314494a41_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_abc31495eda36a292af84de2d2556c5a9957390958fb6f471694dbdb8b84ffff_v2\", \"builder_eb9e31050ddbebce4ba6cbaea9a0445903ee5f3e90bdb319e3517a7851b1b7f7_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-23-q58c\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611777689187652",
"status": "COMPLETED",
"status_changed_ts": "1611781205598768",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44954",
"builder:linux-chromeos-rel",
"buildset:commit/git/84c1288826a9f18e39940beb14d0488bbec6e490",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84c1288826a9f18e39940beb14d0488bbec6e490",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022352394139525072",
"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/8856922332007577984/+/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:515e410d9f262110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611781205787723",
"url": "https://ci.chromium.org/b/8856922332007577984",
"utcnow_ts": "1611781208483012"
}
}
[21:00:08.497] Invocation finished in 58m58.146955841s with status SUCCEEDED