[01:42:21.195] New invocation is queued and will start shortly
[01:42:22.615] Starting the invocation (attempt 1)
[01:42:22.664] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:42:22.664] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:42:22.664] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032296595226704816",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"85309cdca43f0eacdc0c523537bc0b195f4e7b69\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9032296595226704816",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/85309cdca43f0eacdc0c523537bc0b195f4e7b69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85309cdca43f0eacdc0c523537bc0b195f4e7b69",
"gitiles_ref:refs/heads/master"
]
}
[01:42:23.349] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1602294142781012",
"id": "8866866532897402256",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"85309cdca43f0eacdc0c523537bc0b195f4e7b69\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602294143190961",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/176513",
"builder:Linux Builder (dbg)",
"buildset:commit/git/85309cdca43f0eacdc0c523537bc0b195f4e7b69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85309cdca43f0eacdc0c523537bc0b195f4e7b69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032296595226704816",
"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/8866866532897402256/+/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": "1602294143191116",
"url": "https://ci.chromium.org/b/8866866532897402256",
"utcnow_ts": "1602294143341576"
}
}
[01:42:23.349] Task URL: https://ci.chromium.org/b/8866866532897402256
[01:42:23.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:2:0) after 1m0s
[01:42:28.571] Received PubSub notification, asking Buildbucket for the build status
[01:42:28.764] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:23.527] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:2:0)
[01:43:23.527] Timer tick, asking Buildbucket for the build status
[01:43:23.693] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:23.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:4:0) after 1m0s
[01:44:23.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:4:0)
[01:44:23.786] Timer tick, asking Buildbucket for the build status
[01:44:24.012] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:24.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:5:0) after 1m0s
[01:45:24.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:5:0)
[01:45:24.057] Timer tick, asking Buildbucket for the build status
[01:45:24.205] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:24.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:6:0) after 1m0s
[01:46:24.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:6:0)
[01:46:24.259] Timer tick, asking Buildbucket for the build status
[01:46:24.393] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:24.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:7:0) after 1m0s
[01:47:24.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:7:0)
[01:47:24.414] Timer tick, asking Buildbucket for the build status
[01:47:24.505] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:24.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:8:0) after 1m0s
[01:48:24.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:8:0)
[01:48:24.722] Timer tick, asking Buildbucket for the build status
[01:48:24.881] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:24.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:9:0) after 1m0s
[01:49:24.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:9:0)
[01:49:24.965] Timer tick, asking Buildbucket for the build status
[01:49:25.023] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:25.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:10:0) after 1m0s
[01:50:25.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:10:0)
[01:50:25.123] Timer tick, asking Buildbucket for the build status
[01:50:25.178] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:25.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:11:0) after 1m0s
[01:51:25.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:11:0)
[01:51:25.369] Timer tick, asking Buildbucket for the build status
[01:51:25.499] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:25.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:12:0) after 1m0s
[01:52:25.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:12:0)
[01:52:25.569] Timer tick, asking Buildbucket for the build status
[01:52:25.686] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:25.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:13:0) after 1m0s
[01:53:25.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:13:0)
[01:53:25.767] Timer tick, asking Buildbucket for the build status
[01:53:25.839] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:25.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:14:0) after 1m0s
[01:54:26.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:14:0)
[01:54:26.071] Timer tick, asking Buildbucket for the build status
[01:54:26.134] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:26.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:15:0) after 1m0s
[01:55:26.230] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:15:0)
[01:55:26.230] Timer tick, asking Buildbucket for the build status
[01:55:26.299] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:26.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:16:0) after 1m0s
[01:56:26.355] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:16:0)
[01:56:26.355] Timer tick, asking Buildbucket for the build status
[01:56:26.548] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:26.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:17:0) after 1m0s
[01:57:26.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:17:0)
[01:57:26.601] Timer tick, asking Buildbucket for the build status
[01:57:27.078] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:27.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:18:0) after 1m0s
[01:58:27.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:18:0)
[01:58:27.072] Timer tick, asking Buildbucket for the build status
[01:58:27.142] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:27.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:19:0) after 1m0s
[01:59:27.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:19:0)
[01:59:27.202] Timer tick, asking Buildbucket for the build status
[01:59:27.301] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:27.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:20:0) after 1m0s
[02:00:27.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:20:0)
[02:00:27.347] Timer tick, asking Buildbucket for the build status
[02:00:27.522] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:27.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:21:0) after 1m0s
[02:01:27.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:21:0)
[02:01:27.611] Timer tick, asking Buildbucket for the build status
[02:01:27.685] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:27.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:22:0) after 1m0s
[02:02:27.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:22:0)
[02:02:27.810] Timer tick, asking Buildbucket for the build status
[02:02:27.945] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:27.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:23:0) after 1m0s
[02:03:28.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:23:0)
[02:03:28.131] Timer tick, asking Buildbucket for the build status
[02:03:28.226] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:28.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:24:0) after 1m0s
[02:04:28.509] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:24:0)
[02:04:28.509] Timer tick, asking Buildbucket for the build status
[02:04:28.569] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:28.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:25:0) after 1m0s
[02:05:28.689] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:25:0)
[02:05:28.689] Timer tick, asking Buildbucket for the build status
[02:05:28.843] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:28.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:26:0) after 1m0s
[02:06:28.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:26:0)
[02:06:28.967] Timer tick, asking Buildbucket for the build status
[02:06:29.072] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:29.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:27:0) after 1m0s
[02:07:29.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:27:0)
[02:07:29.158] Timer tick, asking Buildbucket for the build status
[02:07:29.370] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:29.370] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:28:0) after 1m0s
[02:08:29.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:28:0)
[02:08:29.394] Timer tick, asking Buildbucket for the build status
[02:08:29.693] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:29.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:29:0) after 1m0s
[02:09:29.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:29:0)
[02:09:29.737] Timer tick, asking Buildbucket for the build status
[02:09:29.807] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:29.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:30:0) after 1m0s
[02:10:29.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:30:0)
[02:10:29.927] Timer tick, asking Buildbucket for the build status
[02:10:29.990] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:29.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:31:0) after 1m0s
[02:11:30.083] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:31:0)
[02:11:30.083] Timer tick, asking Buildbucket for the build status
[02:11:30.154] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:30.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:32:0) after 1m0s
[02:12:30.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:32:0)
[02:12:30.259] Timer tick, asking Buildbucket for the build status
[02:12:30.376] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:30.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:33:0) after 1m0s
[02:13:30.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:33:0)
[02:13:30.464] Timer tick, asking Buildbucket for the build status
[02:13:30.534] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:30.534] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:34:0) after 1m0s
[02:14:30.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:34:0)
[02:14:30.610] Timer tick, asking Buildbucket for the build status
[02:14:30.718] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:30.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:35:0) after 1m0s
[02:15:30.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:35:0)
[02:15:30.741] Timer tick, asking Buildbucket for the build status
[02:15:30.823] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:30.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:36:0) after 1m0s
[02:16:30.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:36:0)
[02:16:30.898] Timer tick, asking Buildbucket for the build status
[02:16:30.970] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:30.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:37:0) after 1m0s
[02:17:30.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:37:0)
[02:17:30.994] Timer tick, asking Buildbucket for the build status
[02:17:31.105] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:31.105] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:38:0) after 1m0s
[02:18:31.167] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:38:0)
[02:18:31.167] Timer tick, asking Buildbucket for the build status
[02:18:31.244] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:31.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:39:0) after 1m0s
[02:19:31.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:39:0)
[02:19:31.305] Timer tick, asking Buildbucket for the build status
[02:19:31.512] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:31.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:40:0) after 1m0s
[02:20:31.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:40:0)
[02:20:31.536] Timer tick, asking Buildbucket for the build status
[02:20:31.643] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:31.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:41:0) after 1m0s
[02:21:31.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:41:0)
[02:21:31.686] Timer tick, asking Buildbucket for the build status
[02:21:31.790] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:31.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:42:0) after 1m0s
[02:22:31.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:42:0)
[02:22:31.846] Timer tick, asking Buildbucket for the build status
[02:22:32.074] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:32.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:43:0) after 1m0s
[02:23:32.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:43:0)
[02:23:32.128] Timer tick, asking Buildbucket for the build status
[02:23:32.218] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:32.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:44:0) after 1m0s
[02:24:32.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:44:0)
[02:24:32.259] Timer tick, asking Buildbucket for the build status
[02:24:32.420] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:32.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:45:0) after 1m0s
[02:25:32.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:45:0)
[02:25:32.462] Timer tick, asking Buildbucket for the build status
[02:25:32.708] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:32.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:46:0) after 1m0s
[02:26:32.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:46:0)
[02:26:32.730] Timer tick, asking Buildbucket for the build status
[02:26:32.993] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:32.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:47:0) after 1m0s
[02:27:33.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:47:0)
[02:27:33.016] Timer tick, asking Buildbucket for the build status
[02:27:33.169] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:33.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:48:0) after 1m0s
[02:28:33.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:48:0)
[02:28:33.190] Timer tick, asking Buildbucket for the build status
[02:28:33.303] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:33.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:49:0) after 1m0s
[02:29:33.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:49:0)
[02:29:33.327] Timer tick, asking Buildbucket for the build status
[02:29:33.394] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:33.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:50:0) after 1m0s
[02:30:33.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:50:0)
[02:30:33.511] Timer tick, asking Buildbucket for the build status
[02:30:33.929] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:33.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:51:0) after 1m0s
[02:31:34.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:51:0)
[02:31:34.027] Timer tick, asking Buildbucket for the build status
[02:31:34.106] Build 8866866532897402256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:34.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9032296595226704816:52:0) after 1m0s
[02:31:51.308] Received PubSub notification, asking Buildbucket for the build status
[02:31:51.430] Build 8866866532897402256: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:31:51.430] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1602297110156357",
"created_by": "project:chromium",
"created_ts": "1602294142781012",
"id": "8866866532897402256",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"85309cdca43f0eacdc0c523537bc0b195f4e7b69\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"85309cdca43f0eacdc0c523537bc0b195f4e7b69\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-102-8h21\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 176513, \"got_angle_revision\": \"b2ff69f856d98312bbfe14f80d084b7d8795281f\", \"got_dawn_revision\": \"1c25198384e7ecfebbd3ac84203d3b1d46a0e228\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"85309cdca43f0eacdc0c523537bc0b195f4e7b69\", \"got_revision_cp\": \"refs/heads/master@{#815893}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"0aca3ca9427d1d2aa8d8e706de62360c61af3598\", \"got_v8_revision\": \"07b0b1dcde4a99294b8028d83f4ea244885cc091\", \"got_v8_revision_cp\": \"refs/heads/8.8.62@{#1}\", \"got_webrtc_revision\": \"294729f33cbfac484a4b77c29dd42f03ffe65fa2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32373}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"85309cdca43f0eacdc0c523537bc0b195f4e7b69\", \"swarm_hashes_refs/heads/master(at){#815893}_without_patch\": {\"absl_hardening_tests\": \"a173f4dc49dc9e5443221a812ce2683dd46409a9\", \"accessibility_unittests\": \"2abd926ba81d3d279d40d628368f4593c284da76\", \"app_shell_unittests\": \"f58eb2adfdadea73d5eabb2faa08f14c50657145\", \"aura_unittests\": \"dbf9dac6b0e746a7cbe4794042898f00ce660f0e\", \"base_unittests\": \"54ce59cb733874c7b83da75ba02739515f0472db\", \"base_util_unittests\": \"ec55b2a290aa8a15929601370efde779ba12cded\", \"blink_common_unittests\": \"1e5a1b0cbc767e4950fc3c00d37997e6045ee453\", \"blink_fuzzer_unittests\": \"36add9e6aaef1e343de1d18d5dd2d05590306aa6\", \"blink_heap_unittests\": \"45afb25f1aa0c8022d932adf7ecf7dda4cef9f0f\", \"blink_platform_unittests\": \"3063c77dd6b593d52af435afe6ed8949bfcd6df2\", \"blink_python_tests\": \"3c9c7c556b1f7e8bd3d2ae3e582986798b588f8c\", \"blink_unittests\": \"daf99b22f005acc91faf6b69d6cb7b44a472dcd9\", \"blink_web_tests\": \"403a731829f44d386cd122258b6fccbf6720ef36\", \"boringssl_crypto_tests\": \"dc9b2f9d71f27db45641db518d53087c1148b5e0\", \"boringssl_ssl_tests\": \"6f640201cc8ab052e7d22ac28010324baf798e14\", \"browser_tests\": \"287f8bed51416ab838630cf53897230ccfc6c644\", \"capture_unittests\": \"da186154a5bb1767a75d33f7a927ea6efb6eeb43\", \"cast_unittests\": \"79427d36678bfa319e409a03570749b0e6f3f7b1\", \"cc_unittests\": \"62707dbf9bffedf12f16747f8e05ab13a0f69083\", \"chrome_app_unittests\": \"210ddd44ec8c418aa1da4db1ed87083b2ef72d75\", \"chromedriver_unittests\": \"3265b556e9ad29bc07db883c34505884ce5cab14\", \"components_browsertests\": \"fedd13f5e9b3be7461805e3a295c2c0d603b9974\", \"components_unittests\": \"686009d2507f74880b8695d41e9744aaf7e0549b\", \"compositor_unittests\": \"8165698d11895de1aac7c906fe252b66a6bc08af\", \"content_browsertests\": \"98a0ee8a77265575e14cabee88c042aa488c2542\", \"content_shell_crash_test\": \"126aed35744ba8cd883e76cdccdc8aa8ca328fa0\", \"content_unittests\": \"2cfda4a8e7c7eb828a81aebe6c3301c0b8a1e525\", \"crashpad_tests\": \"4dd823f1653abdd57b7d2eab64ec5f6cdb03c15b\", \"cronet_tests\": \"634d0298cb17cf87e3c6d615339a7bcf2fbf535d\", \"cronet_unittests\": \"e80710a3f15900fda3e2e7b1e039c97c2f9ae379\", \"crypto_unittests\": \"13c9ee3a22140b45ee1a2c4c8b4019e46ad3479c\", \"dbus_unittests\": \"6ca17005898f746e259c634901ce16c758af135b\", \"device_unittests\": \"0ccc117b8ad78e1f50ce049aab0a4108d0702f89\", \"display_unittests\": \"82e9109a3a7e2fac74a70261c1f22b53029224da\", \"events_unittests\": \"1cb8ec98f2f1786a717e63d1f4bfc625cc3cd511\", \"extension_docserver_python_unittests\": \"cf4805fc7877a7cce58fe84609423f6e8a6c3dba\", \"extensions_browsertests\": \"d9a853a76037d40d515b3c2e2fac17961a17a0c0\", \"extensions_unittests\": \"1b3e2891bb83e20d44588c111b5bce914d53ca9c\", \"filesystem_service_unittests\": \"8e946d46d5c31af28b9d9eb5a6cc434c28294819\", \"flatbuffers_unittests\": \"7c821f13730963d6e07deae074f4fffab315d766\", \"gcm_unit_tests\": \"a28422449ba58db97e01e321cad5d4ccb8f787a6\", \"gfx_unittests\": \"3d8ea4bca38b940d9b2888edd68ab3abeac889e6\", \"gin_unittests\": \"1629fea2534458e434702c08770b0ddddded5986\", \"google_apis_unittests\": \"b1919aec0b12b3062b6b58fb773a465f7d40e01b\", \"gpu_unittests\": \"d693c6d2942f02925c9459db21c81bb7f0f30ff7\", \"grit_python_unittests\": \"55fa961d85005331568451ce869e032331bd4b90\", \"gwp_asan_unittests\": \"a905ca9b6b9480fc00a0c4106a193f35b469abcb\", \"headless_browsertests\": \"d3791a22ec99a3cd7225dda5f80492a1f08175b7\", \"headless_unittests\": \"4785e865ec579a40112ca8a9bf74fdc19047df4b\", \"interactive_ui_tests\": \"ca7267285aec72f91ecbb5df4b0fc582efbcfe83\", \"ipc_tests\": \"5d9d1fb78d20bfe6d9ee0a307184e31872fa8ef5\", \"jingle_unittests\": \"e1d7269b3de4ea573918bbbaea927c3e42ee2860\", \"latency_unittests\": \"9c588adff7001120278184b8db63136b98ccd557\", \"libjingle_xmpp_unittests\": \"b8e72794e0f506f9e5126d054bd6d25c84b4150c\", \"media_blink_unittests\": \"0c63c405b7354b4cd5a096ccc69f61a838677459\", \"media_unittests\": \"ecb51fae93723d209ae72aa1ef0a920d4b3e0126\", \"message_center_unittests\": \"45a7a3e2da5e5cc57831a52717cbfadd94fc74b7\", \"metrics_python_tests\": \"3fcbb771d93e568f76f7d97c1f8a5a3562a7baa2\", \"midi_unittests\": \"7711289fa783951e3923a7a90d1efd4d37246e2e\", \"mojo_core_unittests\": \"4453e15f7c87b0f9df2c25a38603f623ecf2a89f\", \"mojo_unittests\": \"a7ad41353788443ea7d40efba1585d7a5fe3fac8\", \"nacl_helper_nonsfi_unittests\": \"e7ad4784f0b843f7391f4b353f6026c16b294f7c\", \"nacl_loader_unittests\": \"07d5d80b39b5f8f841997579daf20bcb1b18a9b1\", \"native_theme_unittests\": \"f76c312d16119b4c3a112130535f38b6edf19917\", \"net_unittests\": \"ed89af80448c47f20514d4a3801f455d2e4c5999\", \"openscreen_unittests\": \"97cef6539fae2164aad1fdef6c5fce738d334352\", \"pdf_unittests\": \"2bc7db23b88bd8eead926275bb8c278fbe50ed7a\", \"perfetto_unittests\": \"bab85377c29a313fc8ff603f6b8784d3db201076\", \"ppapi_unittests\": \"75dd1b080d93b9dc5b7f3fe47154d769955924ee\", \"printing_unittests\": \"b3515cfacfb348ab79593645087c02aeb2b8de66\", \"remoting_unittests\": \"efcd25028baafe98faa89ce4812349a71fd19173\", \"sandbox_linux_unittests\": \"efdd4e571400bc7b50636cb346a4f538f35f09f3\", \"service_manager_unittests\": \"81253a600ca6cc0d346260a99029ea74ebb6830b\", \"services_unittests\": \"fd67c4cbecea661bc00bc0e7b80c067297a0eb04\", \"shell_dialogs_unittests\": \"1bc09970feff27635c921cb38a5ad55d7fd02a69\", \"skia_unittests\": \"d5ba598fc038f251dfb68d71b8694a29470ea415\", \"snapshot_unittests\": \"593422338084f450fa93c3559381e34026ced898\", \"sql_unittests\": \"d0d6d916a1026ac46c17cdc86d352fec5a20402c\", \"standalone_angle_unittests\": \"f16b025e43964c0e347dbdcda13bf3e7e228f5b1\", \"storage_unittests\": \"126faf1bd43513ba1d1cbd825502236e07e2700e\", \"sync_integration_tests\": \"38a35b9a562c97c9e77176ba2540027ac7b8266b\", \"telemetry_gpu_unittests\": \"2ae28282b43c38f7c2d65bea205c05adff927037\", \"telemetry_perf_unittests\": \"b59f725b5f409ae862f3798520a5867b7b307999\", \"telemetry_unittests\": \"be1786d5575943e93c81b4ef6471d9d383499850\", \"traffic_annotation_auditor_unittests\": \"2847df9e4fd1d09f0907e7cc2e343eb93051f5fa\", \"ui_base_unittests\": \"667c131811e7ee7e9db3df2f5346b5db14b264b4\", \"ui_touch_selection_unittests\": \"f759da864fcab7edd422ea53fb751da154a9adad\", \"unit_tests\": \"3eb91fe1ced84ff64f886a285422365163b642c8\", \"url_unittests\": \"50b030812460bcd38b6a5aabfefe3e89fcd3d987\", \"views_perftests\": \"5ccd56942d5ab5b50196c217a32152b33ad4da77\", \"views_unittests\": \"889bedf95e49878115fe8f3e69030a56689862a5\", \"viz_unittests\": \"5d53f170d2cb3a91facb60cd3f13dd1fe23bbf1d\", \"vr_common_unittests\": \"6413f0cff704ed322dc5eeb0a1044f14d23219ec\", \"vr_pixeltests\": \"6f90d4d986626d74b5333d75589799ace1425bfa\", \"webdriver_wpt_tests\": \"cfba2811a1d7220c3960b93b596a5dfff024736a\", \"weblayer_browsertests\": \"87ece9158cd7bce1c651cd37a131521d22d28a6f\", \"weblayer_unittests\": \"3485265aa423838ff14b3b8a77821195f995fde3\", \"wm_unittests\": \"0b7aede20b63c4936f42ad474fa26dcb4ae5201a\", \"wtf_unittests\": \"5bc903b0bdbd8820132f477f67f4dbb2ca58ead6\", \"x11_unittests\": \"7b2cb2d9c24f8946c481587266059e1027d13c40\", \"xr_browser_tests\": \"ff5c9667a6c0f3c4927bc896fdcaae7a4a581404\", \"zlib_unittests\": \"19c461d0b6bd9b0e7ea388ec5818dfe02aaac29f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_151f80801b9f29276c11c5f05dab27ce5673f9c9ba442aaf2cf20aab9215f522_v2\", \"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_5ae174071c5d6730648bf4e0d5db68d27bfbde0d9c5b879a557aa1bc8e90c36b_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_e7ad59aa200d68ebcc1a02549d22b636b3b4d989b688240b853e1dce9c0a2c9f_v2\", \"builder_ffe623326ac74578ce917ff55ef6cefa75094e5ad3ad5fe3409a729d79be647a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-102-8h21\"], \"image\": [\"chrome-xenial-20091600-9ddf7e422ac\"], \"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\": [\"5570-fa4adf0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1602294147942110",
"status": "COMPLETED",
"status_changed_ts": "1602297110466648",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/176513",
"builder:Linux Builder (dbg)",
"buildset:commit/git/85309cdca43f0eacdc0c523537bc0b195f4e7b69",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/85309cdca43f0eacdc0c523537bc0b195f4e7b69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032296595226704816",
"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/8866866532897402256/+/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:4f28fde2681d0810",
"user_agent:luci-scheduler"
],
"updated_ts": "1602297110472671",
"url": "https://ci.chromium.org/b/8866866532897402256",
"utcnow_ts": "1602297111414639"
}
}
[02:31:51.430] Invocation finished in 49m30.253472108s with status SUCCEEDED