[04:46:06.690] New invocation is queued and will start shortly
[04:46:07.801] Starting the invocation (attempt 1)
[04:46:07.855] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:46:07.855] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:46:07.855] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9051400994066367248",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"11b7b4a0e9352b433ead6c280bdae369090467be\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9051400994066367248",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/11b7b4a0e9352b433ead6c280bdae369090467be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/11b7b4a0e9352b433ead6c280bdae369090467be",
"gitiles_ref:refs/heads/master"
]
}
[04:46:08.835] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1584074768037216",
"id": "8885970931989244592",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"11b7b4a0e9352b433ead6c280bdae369090467be\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1584074768584241",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169686",
"builder:Linux Builder (dbg)",
"buildset:commit/git/11b7b4a0e9352b433ead6c280bdae369090467be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/11b7b4a0e9352b433ead6c280bdae369090467be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051400994066367248",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8885970931989244592/+/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": "1584074768584390",
"url": "https://ci.chromium.org/b/8885970931989244592",
"utcnow_ts": "1584074768826616"
}
}
[04:46:08.835] Task URL: https://ci.chromium.org/b/8885970931989244592
[04:46:08.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:2:0) after 1m0s
[04:47:08.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:2:0)
[04:47:08.918] Timer tick, asking Buildbucket for the build status
[04:47:09.049] Build 8885970931989244592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:47:09.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:3:0) after 1m0s
[04:48:09.069] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:3:0)
[04:48:09.069] Timer tick, asking Buildbucket for the build status
[04:48:09.201] Build 8885970931989244592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:48:09.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:4:0) after 1m0s
[04:49:09.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:4:0)
[04:49:09.285] Timer tick, asking Buildbucket for the build status
[04:49:09.356] Build 8885970931989244592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:49:09.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:5:0) after 1m0s
[04:50:09.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:5:0)
[04:50:09.421] Timer tick, asking Buildbucket for the build status
[04:50:09.763] Build 8885970931989244592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:50:09.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:6:0) after 1m0s
[04:51:02.945] Received PubSub notification, asking Buildbucket for the build status
[04:51:03.032] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:09.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:6:0)
[04:51:09.811] Timer tick, asking Buildbucket for the build status
[04:51:09.906] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:09.906] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:8:0) after 1m0s
[04:52:10.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:8:0)
[04:52:10.013] Timer tick, asking Buildbucket for the build status
[04:52:10.221] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:10.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:9:0) after 1m0s
[04:53:10.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:9:0)
[04:53:10.322] Timer tick, asking Buildbucket for the build status
[04:53:10.463] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:10.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:10:0) after 1m0s
[04:54:10.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:10:0)
[04:54:10.553] Timer tick, asking Buildbucket for the build status
[04:54:10.641] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:10.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:11:0) after 1m0s
[04:55:10.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:11:0)
[04:55:10.754] Timer tick, asking Buildbucket for the build status
[04:55:10.880] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:10.880] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:12:0) after 1m0s
[04:56:11.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:12:0)
[04:56:11.018] Timer tick, asking Buildbucket for the build status
[04:56:11.107] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:11.107] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:13:0) after 1m0s
[04:57:11.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:13:0)
[04:57:11.165] Timer tick, asking Buildbucket for the build status
[04:57:11.316] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:11.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:14:0) after 1m0s
[04:58:11.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:14:0)
[04:58:11.426] Timer tick, asking Buildbucket for the build status
[04:58:11.568] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:11.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:15:0) after 1m0s
[04:59:11.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:15:0)
[04:59:11.667] Timer tick, asking Buildbucket for the build status
[04:59:11.769] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:11.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:16:0) after 1m0s
[05:00:11.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:16:0)
[05:00:11.838] Timer tick, asking Buildbucket for the build status
[05:00:11.961] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:11.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:17:0) after 1m0s
[05:01:12.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:17:0)
[05:01:12.090] Timer tick, asking Buildbucket for the build status
[05:01:12.205] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:12.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:18:0) after 1m0s
[05:02:12.273] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:18:0)
[05:02:12.273] Timer tick, asking Buildbucket for the build status
[05:02:12.720] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:12.720] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:19:0) after 1m0s
[05:03:12.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:19:0)
[05:03:12.782] Timer tick, asking Buildbucket for the build status
[05:03:13.072] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:13.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:20:0) after 1m0s
[05:04:13.099] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:20:0)
[05:04:13.099] Timer tick, asking Buildbucket for the build status
[05:04:13.168] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:13.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:21:0) after 1m0s
[05:05:13.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:21:0)
[05:05:13.238] Timer tick, asking Buildbucket for the build status
[05:05:13.450] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:13.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:22:0) after 1m0s
[05:06:13.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:22:0)
[05:06:13.471] Timer tick, asking Buildbucket for the build status
[05:06:13.669] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:13.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:23:0) after 1m0s
[05:07:13.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:23:0)
[05:07:13.725] Timer tick, asking Buildbucket for the build status
[05:07:13.894] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:24:0) after 1m0s
[05:08:13.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:24:0)
[05:08:13.920] Timer tick, asking Buildbucket for the build status
[05:08:14.037] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:14.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:25:0) after 1m0s
[05:09:14.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:25:0)
[05:09:14.027] Timer tick, asking Buildbucket for the build status
[05:09:14.157] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:14.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:26:0) after 1m0s
[05:10:14.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:26:0)
[05:10:14.214] Timer tick, asking Buildbucket for the build status
[05:10:14.324] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:14.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:27:0) after 1m0s
[05:11:14.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:27:0)
[05:11:14.399] Timer tick, asking Buildbucket for the build status
[05:11:14.557] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:14.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:28:0) after 1m0s
[05:12:14.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:28:0)
[05:12:14.652] Timer tick, asking Buildbucket for the build status
[05:12:14.790] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:14.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:29:0) after 1m0s
[05:13:14.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:29:0)
[05:13:14.809] Timer tick, asking Buildbucket for the build status
[05:13:14.985] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:14.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:30:0) after 1m0s
[05:14:15.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:30:0)
[05:14:15.049] Timer tick, asking Buildbucket for the build status
[05:14:15.407] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:15.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:31:0) after 1m0s
[05:15:15.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:31:0)
[05:15:15.497] Timer tick, asking Buildbucket for the build status
[05:15:15.732] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:15.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:32:0) after 1m0s
[05:16:15.821] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:32:0)
[05:16:15.821] Timer tick, asking Buildbucket for the build status
[05:16:15.972] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:15.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:33:0) after 1m0s
[05:17:16.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:33:0)
[05:17:16.057] Timer tick, asking Buildbucket for the build status
[05:17:16.159] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:16.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:34:0) after 1m0s
[05:18:16.163] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:34:0)
[05:18:16.163] Timer tick, asking Buildbucket for the build status
[05:18:16.284] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:16.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:35:0) after 1m0s
[05:19:16.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:35:0)
[05:19:16.476] Timer tick, asking Buildbucket for the build status
[05:19:16.591] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:16.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:36:0) after 1m0s
[05:20:16.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:36:0)
[05:20:16.786] Timer tick, asking Buildbucket for the build status
[05:20:16.885] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:16.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:37:0) after 1m0s
[05:21:16.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:37:0)
[05:21:16.957] Timer tick, asking Buildbucket for the build status
[05:21:17.090] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:17.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:38:0) after 1m0s
[05:22:17.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:38:0)
[05:22:17.168] Timer tick, asking Buildbucket for the build status
[05:22:17.420] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:17.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:39:0) after 1m0s
[05:23:17.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:39:0)
[05:23:17.476] Timer tick, asking Buildbucket for the build status
[05:23:17.573] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:17.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:40:0) after 1m0s
[05:24:17.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:40:0)
[05:24:17.568] Timer tick, asking Buildbucket for the build status
[05:24:17.866] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:17.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:41:0) after 1m0s
[05:25:17.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:41:0)
[05:25:17.891] Timer tick, asking Buildbucket for the build status
[05:25:18.053] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:18.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:42:0) after 1m0s
[05:26:18.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:42:0)
[05:26:18.111] Timer tick, asking Buildbucket for the build status
[05:26:18.277] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:18.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:43:0) after 1m0s
[05:27:18.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:43:0)
[05:27:18.338] Timer tick, asking Buildbucket for the build status
[05:27:18.497] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:18.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:44:0) after 1m0s
[05:28:18.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:44:0)
[05:28:18.538] Timer tick, asking Buildbucket for the build status
[05:28:18.640] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:18.640] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:45:0) after 1m0s
[05:29:18.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:45:0)
[05:29:18.761] Timer tick, asking Buildbucket for the build status
[05:29:18.851] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:18.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:46:0) after 1m0s
[05:30:18.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:46:0)
[05:30:18.918] Timer tick, asking Buildbucket for the build status
[05:30:19.022] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:19.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:47:0) after 1m0s
[05:31:19.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:47:0)
[05:31:19.125] Timer tick, asking Buildbucket for the build status
[05:31:19.533] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:19.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:48:0) after 1m0s
[05:32:19.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:48:0)
[05:32:19.681] Timer tick, asking Buildbucket for the build status
[05:32:20.028] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:20.028] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:49:0) after 1m0s
[05:33:20.091] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:49:0)
[05:33:20.091] Timer tick, asking Buildbucket for the build status
[05:33:20.225] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:20.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:50:0) after 1m0s
[05:34:20.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:50:0)
[05:34:20.335] Timer tick, asking Buildbucket for the build status
[05:34:20.501] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:20.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:51:0) after 1m0s
[05:35:20.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:51:0)
[05:35:20.682] Timer tick, asking Buildbucket for the build status
[05:35:20.806] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:20.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:52:0) after 1m0s
[05:36:20.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:52:0)
[05:36:20.795] Timer tick, asking Buildbucket for the build status
[05:36:20.913] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:20.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:53:0) after 1m0s
[05:37:20.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:53:0)
[05:37:20.934] Timer tick, asking Buildbucket for the build status
[05:37:21.167] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:21.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:54:0) after 1m0s
[05:38:21.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:54:0)
[05:38:21.280] Timer tick, asking Buildbucket for the build status
[05:38:21.497] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:21.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:55:0) after 1m0s
[05:39:21.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:55:0)
[05:39:21.585] Timer tick, asking Buildbucket for the build status
[05:39:21.730] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:21.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:56:0) after 1m0s
[05:40:21.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:56:0)
[05:40:21.746] Timer tick, asking Buildbucket for the build status
[05:40:22.021] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:22.021] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:57:0) after 1m0s
[05:41:22.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:57:0)
[05:41:22.022] Timer tick, asking Buildbucket for the build status
[05:41:22.521] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:22.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:58:0) after 1m0s
[05:42:22.561] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:58:0)
[05:42:22.561] Timer tick, asking Buildbucket for the build status
[05:42:23.027] Build 8885970931989244592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:23.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051400994066367248:59:0) after 1m0s
[05:42:40.808] Received PubSub notification, asking Buildbucket for the build status
[05:42:41.127] Build 8885970931989244592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:42:41.127] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1584078159374164",
"created_by": "project:chromium",
"created_ts": "1584074768037216",
"id": "8885970931989244592",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"11b7b4a0e9352b433ead6c280bdae369090467be\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-148-p0vb\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169686, \"got_angle_revision\": \"6963f58c7678be5c4ce9543724fa03081732400b\", \"got_dawn_revision\": \"0338b7ae194ac8bd295afd64a264ff6ee3e58526\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"11b7b4a0e9352b433ead6c280bdae369090467be\", \"got_revision_cp\": \"refs/heads/master@{#750045}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"18daa81e3d3dd15936e201688d5914f7758d5cb0\", \"got_v8_revision\": \"506142ad90f3a4d686c6367f4a720f53eeae38af\", \"got_v8_revision_cp\": \"refs/heads/8.2.297@{#1}\", \"got_webrtc_revision\": \"4b6ba7c207903cab654769160d626ab42b1b152a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30783}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"11b7b4a0e9352b433ead6c280bdae369090467be\", \"swarm_hashes_refs/heads/master(at){#750045}_without_patch\": {\"accessibility_unittests\": \"40253220ea250085db28558d318fc9aed63028f5\", \"angle_unittests\": \"5f1b059169c03c50011f8313b9dc043ba508da99\", \"app_shell_unittests\": \"f40192a6a734b2cd684b6697f4fbd6f59088101b\", \"aura_unittests\": \"30f28e6de5c6ee39079d8310c23154572b3bff7b\", \"base_unittests\": \"1f3046967a7a786b9c2a7dfcc156f86f7031a18d\", \"base_util_unittests\": \"43789c31125f8f81b1f43b82cb6fb5dea1a78d83\", \"blink_common_unittests\": \"6fe1a83b08b77d720bfd65ede3c066137530204c\", \"blink_fuzzer_unittests\": \"8210724140aaae3a92d6581c039168414e2f0083\", \"blink_heap_unittests\": \"c2fea01c2fbcd193f342c0b61126a470c7a24cf8\", \"blink_platform_unittests\": \"5241dfb0ef83a6ba29bda67a72bcc61bb556b7f4\", \"blink_python_tests\": \"c34c3308643d147139b298c1480d6fbee52d9157\", \"blink_unittests\": \"02f8444521749dd7710549f11d6415827ff4800f\", \"blink_web_tests\": \"1ce0810a6f4ecb055d9997537f81df61ea0ff4bd\", \"boringssl_crypto_tests\": \"74c84dcdea6a0d70c4e619aa018a2c788cb494f2\", \"boringssl_ssl_tests\": \"c59baa971bb25b5313eee54d391b78ebb29aed44\", \"browser_tests\": \"195e3b31854550c3b59c620675f925f6e594752a\", \"cacheinvalidation_unittests\": \"0d9659de1ea4e042ac79571c98dd1929266552fe\", \"capture_unittests\": \"a03374a7334877c007c191c718b14eb92bf515af\", \"cast_unittests\": \"540ec9322cf10b83ef622562475eea71f57352fe\", \"cc_unittests\": \"52536e0ccad178e9618c40f456a0a460097d4af8\", \"chrome_app_unittests\": \"d9014c5b25e0165fbdad70769451eee119230240\", \"chromedriver_unittests\": \"aba0371a27a193bf2c3dcd4314d41e4ee8a28d38\", \"components_browsertests\": \"05788f69bbc3134c5c717d75895963962c207e0f\", \"components_unittests\": \"4eaaea533d5883790c687b564b99c6e735875dbc\", \"compositor_unittests\": \"e6c898ab6ed6fa31400a6085238387873338f2a4\", \"content_browsertests\": \"84476b3fb50ac1043d31dc83ba4ba5927c046797\", \"content_shell_crash_test\": \"f5c2a9af250b8e1b9329d4e2a0cf03685e7d9f49\", \"content_unittests\": \"3c36f092a7a6ea065f64529d6bcd8a010ef42afe\", \"crashpad_tests\": \"2fbd765c0a0e3a4ed60d7aa78065f0a1b364cba4\", \"cronet_tests\": \"a93f2b0d6a022acb9f83cd90dadbf614182187fa\", \"cronet_unittests\": \"e306bbd67af86f5c0eb292d0699d56d79c3be704\", \"crypto_unittests\": \"1094b216a5b09150cabb89f278a002bf73c334b9\", \"dbus_unittests\": \"d3ce890ef8bc849729bb8d240f975227b3903c9d\", \"device_unittests\": \"731bcfd28f7a7d904dd44a39f95e1560413deb76\", \"display_unittests\": \"6866035c34052833b67ec27f74ba0392aaf73201\", \"events_unittests\": \"4225cda0897b10f0f10f0aebc039ab0bd24d3e81\", \"extensions_browsertests\": \"33efeb7fe9976cbbedb1035bde56e81bf56b37d5\", \"extensions_unittests\": \"78b314ec9bdbc3d8ba59ba952743a35e775867fa\", \"filesystem_service_unittests\": \"a1d61df02b3399acf35cda2fd0b4c5d31b000f82\", \"flatbuffers_unittests\": \"0eefb95c23ed43cdbbd4a53788f9cd8b48581ad6\", \"gcm_unit_tests\": \"e056a84e37aa1ad4dcc782b4c2d9474c72b55e42\", \"gfx_unittests\": \"4516a1023ff4b1356d7fb746ad83182c0916cf45\", \"gin_unittests\": \"48cbfd8ef498fbd931782cb758b64dfe81cf9eb4\", \"google_apis_unittests\": \"0276e1274986db834bad480135366699987286d9\", \"gpu_unittests\": \"026936fad554bba6985a8ccabc349b370907d3c4\", \"grit_python_unittests\": \"f5fdde22ab94b64ae733a1cb2e3ac6512612c7c9\", \"gwp_asan_unittests\": \"83c7bdf3f44834e17b657033fb8f54f833e37c65\", \"headless_browsertests\": \"2b5cb76708a44debab54e6985a6f4bbbcb6329e6\", \"headless_unittests\": \"a32a6b04ca36e0d54cb008ebe0dc5c8814fcd8e8\", \"interactive_ui_tests\": \"f6903784e02e38e6a94ec647955c9c7ad6764b67\", \"ipc_tests\": \"2f9d44eafc799a198c235a66587fd86795c4e000\", \"jingle_unittests\": \"edf75ef967a490502f7149acff46af3569093e76\", \"latency_unittests\": \"91d14ee280259270724ef88cddc3b21c513db6b9\", \"libjingle_xmpp_unittests\": \"433b3aa6af602ca6a0d8ed1828d28b9144dee2fd\", \"media_blink_unittests\": \"ede8982cac43e52b348356aa7ba55f992b2abfc4\", \"media_service_unittests\": \"c4c52e35db8662cf2876a47283657ae38cb4093e\", \"media_unittests\": \"cb75684db36bfcf57315b4c7a2529edae8d3bd49\", \"message_center_unittests\": \"e3096364b9b4b88c6d5fa90467dc28df48ba0c5c\", \"metrics_python_tests\": \"bda7412e36dfc4c8a5733ea4fd3ee19ecf0d73ed\", \"midi_unittests\": \"3ce8edd9fd714b20e7a6295416f784a107b2f480\", \"mojo_core_unittests\": \"ad943fc5657a3244b5643860bf5a381efb11bad3\", \"mojo_unittests\": \"83c608921d79d21add9e8bb49dd884baa02bfffb\", \"nacl_helper_nonsfi_unittests\": \"46adc7d748de6c8d2d9f8b5a35b4761c95aa1d95\", \"nacl_loader_unittests\": \"2cacb03cd8391a13e648ada09ef2885908735b63\", \"native_theme_unittests\": \"d94d5312a0176d5baed0d065cec93cdceca0f38b\", \"net_unittests\": \"28fca26a8479b920387d5096a97309557010622a\", \"pdf_unittests\": \"297bc3a10607dee291a754c2b209bb14d177dcee\", \"perfetto_unittests\": \"db687ddf157af0e345f1daa7ab185df8cd83c1f1\", \"ppapi_unittests\": \"d6a13d9470cd705f389bbff5f45e2cc0055d2bd2\", \"printing_unittests\": \"92fb55f274ccdb2bd7e6b12149d5db8cb91f08bb\", \"remoting_unittests\": \"450f2514e9b6a928e964d625b9a78e3040890f11\", \"sandbox_linux_unittests\": \"2c07b57b1782ef0179cbfd9169062c05c8e83ec2\", \"service_manager_unittests\": \"bb7f01027a5eef4147d9f4461cf4eef6c228bec1\", \"services_unittests\": \"6cd7f03203f5284709521c685c9c4c2c87d69650\", \"shell_dialogs_unittests\": \"745b3f5765c2ce83e67e3192852dd5811e13d10a\", \"skia_unittests\": \"92174223614d5f8ab901efccdf6d79429f9d0b28\", \"snapshot_unittests\": \"e133102b7f95e0661b974a4b73d7b81a7a2ed2f7\", \"sql_unittests\": \"06ff34a630f29b1d270fb8a469eb7127d257f775\", \"storage_unittests\": \"a61ef3fea4863e7551ea976261c6d28664c9d33c\", \"sync_integration_tests\": \"bd53edff107ba134cf059f9440b469e3f7922e2a\", \"telemetry_gpu_unittests\": \"5f89f29f31097159842fca70fbef2024e449c35e\", \"telemetry_perf_unittests\": \"f46f067dcda1e0f7563134757c79f9dba6768444\", \"telemetry_unittests\": \"9642048517c45c8310ce8da5a0285dbe70cce6bf\", \"traffic_annotation_auditor_unittests\": \"a42d380d682aa7689f2eb5a80910336ed5f644ab\", \"ui_base_unittests\": \"3e4e5f43386391c3a52384f27497228c3b5d5452\", \"ui_touch_selection_unittests\": \"a7af9a4193bc71c7b8564e545225edb246e5d0b2\", \"unit_tests\": \"dc5c54ddc92a5e23e363d488072388e2c5b488b8\", \"url_unittests\": \"af38755f07197bac3f829e1fbe616ef2b2ec6b57\", \"views_perftests\": \"b5a69e11de064ffa4e515768edd9c4a4325775c5\", \"views_unittests\": \"d8e44203a4831c5043280ec0ffe4599cf33d0e3f\", \"viz_unittests\": \"fb5163a333b1a73af92e8ffc678b7c5c62cab099\", \"vr_common_unittests\": \"3e6d0fb8cf6be8bfd062358b468377f863bff664\", \"vr_pixeltests\": \"60e326bdf9cfb470e90428c291336fba541b012b\", \"webdriver_wpt_tests\": \"df4589cbf784a0f845ccf7fde490f071fbb44299\", \"weblayer_browsertests\": \"6266cc80a08ed6f919abcaaedc0e69e2f90ab0bc\", \"wm_unittests\": \"f118feecf63f31b8ac32c6f5f1b0ad4ff1995fb0\", \"wtf_unittests\": \"e37c279f1f1bb21f84e046013bfef5df749246d4\", \"xr_browser_tests\": \"bbf88f27bd34df4d3063666d424b30777c333d8b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1267e646917891f5f926cdb94ab3368f0c9399d68d76518d691afb23f68811c9_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_8116c80c2882afcd67a910417bbd6fba6f94de5911d922ccd599199b9d07f827_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-148-p0vb\"], \"image\": [\"chrome-xenial-20030100-2bddf837f90\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4874-3598771\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1584075062047293",
"status": "COMPLETED",
"status_changed_ts": "1584078160269203",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169686",
"builder:Linux Builder (dbg)",
"buildset:commit/git/11b7b4a0e9352b433ead6c280bdae369090467be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/11b7b4a0e9352b433ead6c280bdae369090467be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051400994066367248",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8885970931989244592/+/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:4aeb084a09d14410",
"user_agent:luci-scheduler"
],
"updated_ts": "1584078160286295",
"url": "https://ci.chromium.org/b/8885970931989244592",
"utcnow_ts": "1584078161116343"
}
}
[05:42:41.127] Invocation finished in 56m34.454404908s with status SUCCEEDED