[02:11:12.180] New invocation is queued and will start shortly
[02:11:13.323] Starting the invocation (attempt 1)
[02:11:13.361] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:11:13.361] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:11:13.361] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022510307783515072",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"87b160afff0c61a9437a9585370520123f1425fc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022510307783515072",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/87b160afff0c61a9437a9585370520123f1425fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87b160afff0c61a9437a9585370520123f1425fc",
"gitiles_ref:refs/heads/master"
]
}
[02:11:14.167] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611627073462365",
"id": "8857080245776207472",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"87b160afff0c61a9437a9585370520123f1425fc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611627073967492",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44900",
"builder:linux-chromeos-rel",
"buildset:commit/git/87b160afff0c61a9437a9585370520123f1425fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87b160afff0c61a9437a9585370520123f1425fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022510307783515072",
"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/8857080245776207472/+/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": "1611627073967594",
"url": "https://ci.chromium.org/b/8857080245776207472",
"utcnow_ts": "1611627074160355"
}
}
[02:11:14.167] Task URL: https://ci.chromium.org/b/8857080245776207472
[02:11:14.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:2:0) after 1m0s
[02:12:14.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:2:0)
[02:12:14.247] Timer tick, asking Buildbucket for the build status
[02:12:14.369] Build 8857080245776207472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:14.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:3:0) after 1m0s
[02:13:14.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:3:0)
[02:13:14.351] Timer tick, asking Buildbucket for the build status
[02:13:14.677] Build 8857080245776207472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:14.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:4:0) after 1m0s
[02:14:14.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:4:0)
[02:14:14.650] Timer tick, asking Buildbucket for the build status
[02:14:14.727] Build 8857080245776207472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:14.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:5:0) after 1m0s
[02:15:12.621] Received PubSub notification, asking Buildbucket for the build status
[02:15:12.708] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:14.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:5:0)
[02:15:14.776] Timer tick, asking Buildbucket for the build status
[02:15:14.862] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:14.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:7:0) after 1m0s
[02:16:15.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:7:0)
[02:16:15.135] Timer tick, asking Buildbucket for the build status
[02:16:16.233] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:16.233] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:8:0) after 1m0s
[02:17:16.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:8:0)
[02:17:16.236] Timer tick, asking Buildbucket for the build status
[02:17:16.322] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:16.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:9:0) after 1m0s
[02:18:16.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:9:0)
[02:18:16.349] Timer tick, asking Buildbucket for the build status
[02:18:16.421] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:16.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:10:0) after 1m0s
[02:19:16.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:10:0)
[02:19:16.513] Timer tick, asking Buildbucket for the build status
[02:19:16.608] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:16.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:11:0) after 1m0s
[02:20:16.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:11:0)
[02:20:16.712] Timer tick, asking Buildbucket for the build status
[02:20:16.828] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:16.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:12:0) after 1m0s
[02:21:16.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:12:0)
[02:21:16.906] Timer tick, asking Buildbucket for the build status
[02:21:17.115] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:17.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:13:0) after 1m0s
[02:22:17.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:13:0)
[02:22:17.177] Timer tick, asking Buildbucket for the build status
[02:22:17.451] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:17.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:14:0) after 1m0s
[02:23:17.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:14:0)
[02:23:17.471] Timer tick, asking Buildbucket for the build status
[02:23:17.607] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:17.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:15:0) after 1m0s
[02:24:17.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:15:0)
[02:24:17.760] Timer tick, asking Buildbucket for the build status
[02:24:17.826] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:17.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:16:0) after 1m0s
[02:25:17.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:16:0)
[02:25:17.942] Timer tick, asking Buildbucket for the build status
[02:25:18.341] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:18.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:17:0) after 1m0s
[02:26:18.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:17:0)
[02:26:18.407] Timer tick, asking Buildbucket for the build status
[02:26:18.959] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:18.959] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:18:0) after 1m0s
[02:27:19.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:18:0)
[02:27:19.087] Timer tick, asking Buildbucket for the build status
[02:27:19.158] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:19.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:19:0) after 1m0s
[02:28:19.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:19:0)
[02:28:19.278] Timer tick, asking Buildbucket for the build status
[02:28:19.361] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:19.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:20:0) after 1m0s
[02:29:19.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:20:0)
[02:29:19.396] Timer tick, asking Buildbucket for the build status
[02:29:19.491] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:19.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:21:0) after 1m0s
[02:30:19.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:21:0)
[02:30:19.494] Timer tick, asking Buildbucket for the build status
[02:30:19.605] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:19.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:22:0) after 1m0s
[02:31:19.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:22:0)
[02:31:19.638] Timer tick, asking Buildbucket for the build status
[02:31:19.964] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:19.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:23:0) after 1m0s
[02:32:20.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:23:0)
[02:32:20.027] Timer tick, asking Buildbucket for the build status
[02:32:20.103] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:20.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:24:0) after 1m0s
[02:33:20.098] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:24:0)
[02:33:20.098] Timer tick, asking Buildbucket for the build status
[02:33:20.159] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:20.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:25:0) after 1m0s
[02:34:20.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:25:0)
[02:34:20.172] Timer tick, asking Buildbucket for the build status
[02:34:20.297] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:20.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:26:0) after 1m0s
[02:35:20.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:26:0)
[02:35:20.347] Timer tick, asking Buildbucket for the build status
[02:35:20.786] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:20.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:27:0) after 1m0s
[02:36:20.924] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:27:0)
[02:36:20.925] Timer tick, asking Buildbucket for the build status
[02:36:21.077] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:21.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:28:0) after 1m0s
[02:37:21.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:28:0)
[02:37:21.212] Timer tick, asking Buildbucket for the build status
[02:37:21.350] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:21.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:29:0) after 1m0s
[02:38:21.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:29:0)
[02:38:21.371] Timer tick, asking Buildbucket for the build status
[02:38:21.606] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:21.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:30:0) after 1m0s
[02:39:21.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:30:0)
[02:39:21.681] Timer tick, asking Buildbucket for the build status
[02:39:21.753] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:21.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:31:0) after 1m0s
[02:40:21.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:31:0)
[02:40:21.831] Timer tick, asking Buildbucket for the build status
[02:40:21.895] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:21.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:32:0) after 1m0s
[02:41:21.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:32:0)
[02:41:21.913] Timer tick, asking Buildbucket for the build status
[02:41:24.619] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:24.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:33:0) after 1m0s
[02:42:24.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:33:0)
[02:42:24.844] Timer tick, asking Buildbucket for the build status
[02:42:24.896] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:24.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:34:0) after 1m0s
[02:43:25.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:34:0)
[02:43:25.093] Timer tick, asking Buildbucket for the build status
[02:43:25.172] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:25.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:35:0) after 1m0s
[02:44:25.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:35:0)
[02:44:25.312] Timer tick, asking Buildbucket for the build status
[02:44:25.374] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:25.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:36:0) after 1m0s
[02:45:25.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:36:0)
[02:45:25.480] Timer tick, asking Buildbucket for the build status
[02:45:25.557] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:25.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:37:0) after 1m0s
[02:46:25.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:37:0)
[02:46:25.741] Timer tick, asking Buildbucket for the build status
[02:46:26.112] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:26.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:38:0) after 1m0s
[02:47:26.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:38:0)
[02:47:26.152] Timer tick, asking Buildbucket for the build status
[02:47:26.287] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:26.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:39:0) after 1m0s
[02:48:26.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:39:0)
[02:48:26.473] Timer tick, asking Buildbucket for the build status
[02:48:29.082] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:29.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:40:0) after 1m0s
[02:49:29.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:40:0)
[02:49:29.131] Timer tick, asking Buildbucket for the build status
[02:49:29.213] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:29.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:41:0) after 1m0s
[02:50:29.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:41:0)
[02:50:29.551] Timer tick, asking Buildbucket for the build status
[02:50:29.863] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:29.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:42:0) after 1m0s
[02:51:29.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:42:0)
[02:51:29.962] Timer tick, asking Buildbucket for the build status
[02:51:30.349] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:30.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:43:0) after 1m0s
[02:52:30.501] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:43:0)
[02:52:30.501] Timer tick, asking Buildbucket for the build status
[02:52:32.530] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:32.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:44:0) after 1m0s
[02:53:32.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:44:0)
[02:53:32.577] Timer tick, asking Buildbucket for the build status
[02:53:32.748] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:32.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:45:0) after 1m0s
[02:54:32.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:45:0)
[02:54:32.808] Timer tick, asking Buildbucket for the build status
[02:54:32.966] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:32.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:46:0) after 1m0s
[02:55:33.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:46:0)
[02:55:33.012] Timer tick, asking Buildbucket for the build status
[02:55:33.356] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:33.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:47:0) after 1m0s
[02:56:33.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:47:0)
[02:56:33.385] Timer tick, asking Buildbucket for the build status
[02:56:33.656] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:33.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:48:0) after 1m0s
[02:57:33.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:48:0)
[02:57:33.652] Timer tick, asking Buildbucket for the build status
[02:57:33.751] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:33.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:49:0) after 1m0s
[02:58:33.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:49:0)
[02:58:33.752] Timer tick, asking Buildbucket for the build status
[02:58:33.914] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:50:0) after 1m0s
[02:59:33.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:50:0)
[02:59:33.910] Timer tick, asking Buildbucket for the build status
[02:59:34.188] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:34.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:51:0) after 1m0s
[03:00:35.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:51:0)
[03:00:35.747] Timer tick, asking Buildbucket for the build status
[03:00:35.830] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:35.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:52:0) after 1m0s
[03:01:36.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:52:0)
[03:01:36.153] Timer tick, asking Buildbucket for the build status
[03:01:36.322] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:36.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:53:0) after 1m0s
[03:02:36.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:53:0)
[03:02:36.571] Timer tick, asking Buildbucket for the build status
[03:02:37.332] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:37.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:54:0) after 1m0s
[03:03:37.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:54:0)
[03:03:37.450] Timer tick, asking Buildbucket for the build status
[03:03:37.602] Build 8857080245776207472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:37.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022510307783515072:55:0) after 1m0s
[03:03:42.952] Received PubSub notification, asking Buildbucket for the build status
[03:03:43.669] Build 8857080245776207472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:03:43.669] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611630221437770",
"created_by": "project:chromium",
"created_ts": "1611627073462365",
"id": "8857080245776207472",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"87b160afff0c61a9437a9585370520123f1425fc\"}}",
"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\": \"87b160afff0c61a9437a9585370520123f1425fc\", \"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\": 44900, \"got_angle_revision\": \"6689a54d8a046063fad176ffc0fcc1c2cfbe02e8\", \"got_dawn_revision\": \"2a799fe3bf352dce1c17efff2f20fa94ffe216f6\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"87b160afff0c61a9437a9585370520123f1425fc\", \"got_revision_cp\": \"refs/heads/master@{#847000}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"646a27707e56b349e041daff12c4761fcee8b1ad\", \"got_v8_revision\": \"d6d0f46aa6da8d55541ad02aee1099a3a81a59f7\", \"got_v8_revision_cp\": \"refs/heads/9.0.64@{#1}\", \"got_webrtc_revision\": \"ded6636cf43904448ee926d1f2b4352c8a957ca6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33068}\", \"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\": \"87b160afff0c61a9437a9585370520123f1425fc\", \"swarm_hashes_refs/heads/master(at){#847000}_without_patch\": {\"absl_hardening_tests\": \"96593f3862d26d163aafe9539849b76ad196c80e\", \"accessibility_unittests\": \"0ed1c6d9401f7b272729bb5a0e89c17363e1e149\", \"angle_unittests\": \"f9c1f21a6c378d0834bf7b60b221277387184ed8\", \"app_list_unittests\": \"d40ed3c464337c75e12b93fdf5c6d14e4f4498bd\", \"app_shell_unittests\": \"b340452fd967484f5a3b3b122d34f7a2d54c6129\", \"ash_unittests\": \"a7bae0f090e39a77208d28d28cf09427313843cf\", \"aura_unittests\": \"1293a046c0d10ceb67c1aa7b98d0c970daa4bc10\", \"base_unittests\": \"6c01e62c524914707c542e0141f87bb11982a1b2\", \"base_util_unittests\": \"a26a9bbd2890d76a4a2ac3c6ada67dbab1b5d341\", \"blink_common_unittests\": \"621d4084525f4ae14773e4b66d98270a789d1cfb\", \"blink_fuzzer_unittests\": \"357ae503a6de09b948e5c1d5da2cea5708699072\", \"blink_heap_unittests\": \"468b79c73fd58e6875ced488c700b9ec835e5e28\", \"blink_platform_unittests\": \"36f00330144affde31e8e94beaa8074d35bddc41\", \"blink_unittests\": \"d3ab6381c763f3bdadd92a0fdabd4dac7228d9a9\", \"boringssl_crypto_tests\": \"433b055cbbb6f7a6071874dcf6f0a8d1c7ad9d64\", \"boringssl_ssl_tests\": \"b39f93e4797cf80f0a5e3d5d8ecb0a9bc7d39636\", \"browser_tests\": \"f75a4dab25380e1a4481db3f75063f8942d883a3\", \"capture_unittests\": \"ea6271e28eb19c1c93bbde311da0cd453de29e0c\", \"cast_unittests\": \"606e391611938508493302504bdc0e406f5ac8d5\", \"cc_unittests\": \"4fe137c45f12d8374242c15c8b1a020d94627a5d\", \"chrome_app_unittests\": \"446dbdbc80472dd15a68ba4ccaca739b12798eea\", \"chromedriver_unittests\": \"f52e1ab5b09930866cca08eec9b42eedde5f51c9\", \"chromeos_components_unittests\": \"3f55700310d86ccfab5f9374af693c38b31a994a\", \"chromeos_unittests\": \"25500b34322f4f4e363da8b19c26f402815f3dc9\", \"components_browsertests\": \"f1b16087908697d42336a6e92c4adf23ee41efe5\", \"components_unittests\": \"895fc7c18165432dd38ec3396f22602033f6025c\", \"compositor_unittests\": \"c691135fa0e99f2617f1d2d6c1c32e0a832f1c45\", \"content_browsertests\": \"6239aeb3f5acf0a2bb8b2402592b3edf2eb3b418\", \"content_unittests\": \"c694745af5910ee778a37db0a2b8588bb58d2081\", \"crashpad_tests\": \"506c39980e06dd000967b2a8ee061e2527bcbfbb\", \"crypto_unittests\": \"f080103480aa6cffa5f199ce2028d31b4cddea67\", \"dbus_unittests\": \"24d7bed5f77ebd1824d04bc573b5f8f141e2e2e0\", \"device_unittests\": \"12833e4e6b98b158449634ce7227502b11008026\", \"display_unittests\": \"b3383b5e17ebf0716271215451fcbda87e9f21e8\", \"events_unittests\": \"0a069b73a760080b8f1f96482dba4941826510ed\", \"exo_unittests\": \"e0c7bc176aebeed009d118c4e5229c660aa9bf84\", \"extensions_browsertests\": \"ab1a38330fb53a2b11334adaae6d8bfc4a61de88\", \"extensions_unittests\": \"6949044795e84bbad05291ef3cef2451986334f7\", \"filesystem_service_unittests\": \"f5656a6dda3772344c0fc5559c027784a74dc61b\", \"gcm_unit_tests\": \"4b379f48c2f1d22eed193c09d4fa91a8f9efc24c\", \"gfx_unittests\": \"e3eb65ff5f30eaa2e476edcb4b3cd29c4940b7f4\", \"gin_unittests\": \"ba7b9a87e1182638d83a9afa85ea0b4b10b51f68\", \"gl_unittests_ozone\": \"67374bd933d319680e6bd181c21cd39cdc37f1e4\", \"google_apis_unittests\": \"c4ee84b539b482361c980768aeaa58811cbc9cf0\", \"gpu_unittests\": \"016c002a88fba6624823d830fc6fe28e33a2e9a0\", \"gwp_asan_unittests\": \"ceceaeabb503eabcb1d79a88e1f5627395d49fd2\", \"interactive_ui_tests\": \"7f578d372d3c1aab699db33d2061bf47c65e21e3\", \"ipc_tests\": \"5866ee72ca97f2da528d78a9a780a80b8f41c57f\", \"jingle_unittests\": \"2abf3b34416271937788297247a7f9b6e1a75876\", \"keyboard_unittests\": \"cc67a5d886f29475d954e5eca6db8bf868069db4\", \"latency_unittests\": \"8d3ac8c9258650f41079c3f9dfc18f2510e32037\", \"libjingle_xmpp_unittests\": \"686f2e602b908eae362eac186b6bfdd224b6f7d5\", \"liburlpattern_unittests\": \"242ef21377740dcbf59c4fe4f2ad13b903e5e0b1\", \"media_blink_unittests\": \"2f63ff2d3cdc9647efebb4f9f051de4449ae8887\", \"media_unittests\": \"b7dbc6fa3043e9397d43b59af0d07e7238504f30\", \"message_center_unittests\": \"d4f176ba7c77be364c2fbe86148e229a0fe1bb05\", \"midi_unittests\": \"3511a78e6cd58177883532a556fb1583ae9e6f01\", \"mojo_core_unittests\": \"ca95b0613eb4070e10e5b52c2992cf30b94d36e0\", \"mojo_unittests\": \"3967a8ad0dc289dad92b4484644b41e0d07e3b6f\", \"nacl_helper_nonsfi_unittests\": \"0b8cacbd8e0c8407c1b8a94f88dfb39f63022e49\", \"nacl_loader_unittests\": \"3ef6f4b9dae093027fd8988490e335fc79d90ede\", \"native_theme_unittests\": \"bdc2a7c4510ba98ac0b41fe52d69096f7c3634c7\", \"net_unittests\": \"8d8cb593015188201cb70778ff2bee9b9abad5cd\", \"ozone_gl_unittests\": \"e98782d0eb8cf48d340360101de68ca567ba212d\", \"ozone_unittests\": \"5708947ec91d285e785cfdb8a6c73f5808346b89\", \"ozone_x11_unittests\": \"ee67af71f478a0b11019da5c18a81c72b34d6f63\", \"pdf_unittests\": \"a85a61bad8cec7ee30a4e41fd9659343f5115459\", \"perfetto_unittests\": \"6f9c0127bcd8139939a46b67ca3dfe0519f29cf7\", \"ppapi_unittests\": \"481c531a05efc8024acd8569cab87727dfb1035e\", \"printing_unittests\": \"74ed6487859034c736b19aef4defa7827bdd0b8f\", \"remoting_unittests\": \"f9a7c7a90bf67be45913bd5928be6a9567370f32\", \"sandbox_linux_unittests\": \"daf8415fab74362a2a941da2c16d6bb71feed773\", \"service_manager_unittests\": \"66dc92267d2c4ac7a311b9fa3a8ff35027c17555\", \"services_unittests\": \"1f346b76d06b8ea9694caee22f766d3efb4b0113\", \"shell_dialogs_unittests\": \"2c5af0eaa0304171c5db0394225771542563e116\", \"shell_encryption_unittests\": \"556420bd0ec7847096df8aa588a9c73387429ccf\", \"skia_unittests\": \"f48ff939b1df36e4d21eea2ff6647cb08f18a52e\", \"snapshot_unittests\": \"539ef3aa5729d460053788e9101a83499eb5a9e2\", \"sql_unittests\": \"4db67cf5b0f031daa0213f18f1e9bc9fe7d66a7f\", \"storage_unittests\": \"fc9ed64ba55c5a4ffb424ad7f9dc53dc09e31ec3\", \"sync_integration_tests\": \"07c0672ea11ca26c25495bf61d5a7e1458624fde\", \"traffic_annotation_auditor_unittests\": \"efd0214eec72e40514caafc6f8979590e70e60e0\", \"ui_base_unittests\": \"cefc87dccbbbe8c3c67613ef509160e8cddd889f\", \"ui_chromeos_unittests\": \"7288085fad11eb4f40cfa9b6cd450204520068e8\", \"ui_touch_selection_unittests\": \"ce1aef9bf5f6564b497b412c238f0741138b1c9f\", \"unit_tests\": \"cf66aa745ff3a8e4c7d32d7326f241528ea86f39\", \"url_unittests\": \"e5279485c6e38630b21be906c4a39b413b4ccbff\", \"usage_time_limit_unittests\": \"0b7e23ae74d428fcbdd29215f638aba6b7a52154\", \"views_unittests\": \"8b457f4ffe531fb719b5f0a83f684f3c0afc5289\", \"viz_unittests\": \"d1af6565f48f476266e1ff2992c8ce7319fd4070\", \"wayland_client_perftests\": \"6ecd096eabf27f6a2592f4948cd2b5b9d75d692e\", \"wm_unittests\": \"8578028391085eac13243875e2517f4b3b3fd20b\", \"wtf_unittests\": \"dc675cf8ee769e7657adf7fed4cf9a1380280c26\", \"zlib_unittests\": \"5d776b4d312991aad8f640bb144494101e9b47c4\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_03684948df0f250a19c7b639204de5133dfe487bdadd877fd8990d1b9d0fd4b0_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-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": "1611627311778619",
"status": "COMPLETED",
"status_changed_ts": "1611630221932024",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44900",
"builder:linux-chromeos-rel",
"buildset:commit/git/87b160afff0c61a9437a9585370520123f1425fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/87b160afff0c61a9437a9585370520123f1425fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022510307783515072",
"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/8857080245776207472/+/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:5155471adc5d6410",
"user_agent:luci-scheduler"
],
"updated_ts": "1611630221943822",
"url": "https://ci.chromium.org/b/8857080245776207472",
"utcnow_ts": "1611630223642865"
}
}
[03:03:43.669] Invocation finished in 52m31.504965564s with status SUCCEEDED