[09:46:20.259] New invocation is queued and will start shortly
[09:46:21.382] Starting the invocation (attempt 1)
[09:46:21.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:46:21.427] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:46:21.427] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053375238740843744",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"86891cd21a09c09dbc0907dca1540a5194661b77\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053375238740843744",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/86891cd21a09c09dbc0907dca1540a5194661b77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86891cd21a09c09dbc0907dca1540a5194661b77",
"gitiles_ref:refs/heads/master"
]
}
[09:46:22.532] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582191981554956",
"id": "8887945176708201328",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86891cd21a09c09dbc0907dca1540a5194661b77\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582191982355405",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168819",
"builder:Linux Builder (dbg)",
"buildset:commit/git/86891cd21a09c09dbc0907dca1540a5194661b77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86891cd21a09c09dbc0907dca1540a5194661b77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053375238740843744",
"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/8887945176708201328/+/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": "1582191982355517",
"url": "https://ci.chromium.org/b/8887945176708201328",
"utcnow_ts": "1582191982522946"
}
}
[09:46:22.532] Task URL: https://ci.chromium.org/b/8887945176708201328
[09:46:22.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:2:0) after 1m0s
[09:47:22.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:2:0)
[09:47:22.965] Timer tick, asking Buildbucket for the build status
[09:47:23.162] Build 8887945176708201328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:23.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:3:0) after 1m0s
[09:48:23.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:3:0)
[09:48:23.383] Timer tick, asking Buildbucket for the build status
[09:48:23.605] Build 8887945176708201328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:23.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:4:0) after 1m0s
[09:49:23.803] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:4:0)
[09:49:23.803] Timer tick, asking Buildbucket for the build status
[09:49:23.998] Build 8887945176708201328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:23.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:5:0) after 1m0s
[09:50:24.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:5:0)
[09:50:24.102] Timer tick, asking Buildbucket for the build status
[09:50:24.232] Build 8887945176708201328: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:24.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:6:0) after 1m0s
[09:51:14.871] Received PubSub notification, asking Buildbucket for the build status
[09:51:14.950] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:24.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:6:0)
[09:51:24.434] Timer tick, asking Buildbucket for the build status
[09:51:24.513] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:24.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:8:0) after 1m0s
[09:52:24.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:8:0)
[09:52:24.677] Timer tick, asking Buildbucket for the build status
[09:52:24.882] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:24.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:9:0) after 1m0s
[09:53:25.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:9:0)
[09:53:25.075] Timer tick, asking Buildbucket for the build status
[09:53:25.230] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:25.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:10:0) after 1m0s
[09:54:25.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:10:0)
[09:54:25.297] Timer tick, asking Buildbucket for the build status
[09:54:25.682] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:25.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:11:0) after 1m0s
[09:55:25.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:11:0)
[09:55:25.744] Timer tick, asking Buildbucket for the build status
[09:55:25.899] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:25.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:12:0) after 1m0s
[09:56:26.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:12:0)
[09:56:26.075] Timer tick, asking Buildbucket for the build status
[09:56:26.181] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:26.181] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:13:0) after 1m0s
[09:57:26.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:13:0)
[09:57:26.351] Timer tick, asking Buildbucket for the build status
[09:57:26.484] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:26.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:14:0) after 1m0s
[09:58:26.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:14:0)
[09:58:26.638] Timer tick, asking Buildbucket for the build status
[09:58:26.855] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:26.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:15:0) after 1m0s
[09:59:26.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:15:0)
[09:59:26.910] Timer tick, asking Buildbucket for the build status
[09:59:27.023] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:27.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:16:0) after 1m0s
[10:00:27.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:16:0)
[10:00:27.183] Timer tick, asking Buildbucket for the build status
[10:00:27.365] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:27.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:17:0) after 1m0s
[10:01:27.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:17:0)
[10:01:27.461] Timer tick, asking Buildbucket for the build status
[10:01:27.583] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:27.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:18:0) after 1m0s
[10:02:27.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:18:0)
[10:02:27.666] Timer tick, asking Buildbucket for the build status
[10:02:27.839] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:27.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:19:0) after 1m0s
[10:03:27.893] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:19:0)
[10:03:27.893] Timer tick, asking Buildbucket for the build status
[10:03:28.011] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:28.011] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:20:0) after 1m0s
[10:04:28.047] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:20:0)
[10:04:28.047] Timer tick, asking Buildbucket for the build status
[10:04:28.201] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:28.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:21:0) after 1m0s
[10:05:28.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:21:0)
[10:05:28.312] Timer tick, asking Buildbucket for the build status
[10:05:28.485] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:28.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:22:0) after 1m0s
[10:06:28.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:22:0)
[10:06:28.606] Timer tick, asking Buildbucket for the build status
[10:06:28.733] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:28.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:23:0) after 1m0s
[10:07:28.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:23:0)
[10:07:28.817] Timer tick, asking Buildbucket for the build status
[10:07:28.908] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:28.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:24:0) after 1m0s
[10:08:28.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:24:0)
[10:08:28.992] Timer tick, asking Buildbucket for the build status
[10:08:29.241] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:29.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:25:0) after 1m0s
[10:09:29.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:25:0)
[10:09:29.296] Timer tick, asking Buildbucket for the build status
[10:09:29.406] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:29.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:26:0) after 1m0s
[10:10:29.473] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:26:0)
[10:10:29.473] Timer tick, asking Buildbucket for the build status
[10:10:29.696] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:29.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:27:0) after 1m0s
[10:11:29.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:27:0)
[10:11:29.787] Timer tick, asking Buildbucket for the build status
[10:11:29.913] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:29.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:28:0) after 1m0s
[10:12:29.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:28:0)
[10:12:29.937] Timer tick, asking Buildbucket for the build status
[10:12:32.061] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:32.061] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:29:0) after 1m0s
[10:13:32.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:29:0)
[10:13:32.114] Timer tick, asking Buildbucket for the build status
[10:13:32.424] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:32.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:30:0) after 1m0s
[10:14:32.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:30:0)
[10:14:32.537] Timer tick, asking Buildbucket for the build status
[10:14:32.663] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:32.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:31:0) after 1m0s
[10:15:32.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:31:0)
[10:15:32.719] Timer tick, asking Buildbucket for the build status
[10:15:32.848] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:32.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:32:0) after 1m0s
[10:16:32.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:32:0)
[10:16:32.880] Timer tick, asking Buildbucket for the build status
[10:16:33.136] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:33.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:33:0) after 1m0s
[10:17:33.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:33:0)
[10:17:33.178] Timer tick, asking Buildbucket for the build status
[10:17:33.660] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:33.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:34:0) after 1m0s
[10:18:33.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:34:0)
[10:18:33.720] Timer tick, asking Buildbucket for the build status
[10:18:33.803] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:33.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:35:0) after 1m0s
[10:19:35.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:35:0)
[10:19:35.124] Timer tick, asking Buildbucket for the build status
[10:19:35.344] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:35.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:36:0) after 1m0s
[10:20:35.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:36:0)
[10:20:35.547] Timer tick, asking Buildbucket for the build status
[10:20:35.938] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:35.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:37:0) after 1m0s
[10:21:36.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:37:0)
[10:21:36.120] Timer tick, asking Buildbucket for the build status
[10:21:36.283] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:36.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:38:0) after 1m0s
[10:22:37.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:38:0)
[10:22:37.474] Timer tick, asking Buildbucket for the build status
[10:22:37.577] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:37.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:39:0) after 1m0s
[10:23:37.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:39:0)
[10:23:37.620] Timer tick, asking Buildbucket for the build status
[10:23:37.914] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:37.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:40:0) after 1m0s
[10:24:38.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:40:0)
[10:24:38.604] Timer tick, asking Buildbucket for the build status
[10:24:38.724] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:38.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:41:0) after 1m0s
[10:25:38.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:41:0)
[10:25:38.867] Timer tick, asking Buildbucket for the build status
[10:25:39.214] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:39.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:42:0) after 1m0s
[10:26:39.492] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:42:0)
[10:26:39.492] Timer tick, asking Buildbucket for the build status
[10:26:39.661] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:39.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:43:0) after 1m0s
[10:27:39.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:43:0)
[10:27:39.813] Timer tick, asking Buildbucket for the build status
[10:27:40.376] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:40.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:44:0) after 1m0s
[10:28:40.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:44:0)
[10:28:40.513] Timer tick, asking Buildbucket for the build status
[10:28:40.642] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:40.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:45:0) after 1m0s
[10:29:40.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:45:0)
[10:29:40.805] Timer tick, asking Buildbucket for the build status
[10:29:41.118] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:41.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:46:0) after 1m0s
[10:30:41.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:46:0)
[10:30:41.343] Timer tick, asking Buildbucket for the build status
[10:30:41.525] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:41.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:47:0) after 1m0s
[10:31:41.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:47:0)
[10:31:41.599] Timer tick, asking Buildbucket for the build status
[10:31:41.696] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:41.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:48:0) after 1m0s
[10:32:41.751] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:48:0)
[10:32:41.751] Timer tick, asking Buildbucket for the build status
[10:32:41.859] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:41.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:49:0) after 1m0s
[10:33:41.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:49:0)
[10:33:41.984] Timer tick, asking Buildbucket for the build status
[10:33:42.246] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:42.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:50:0) after 1m0s
[10:34:42.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:50:0)
[10:34:42.351] Timer tick, asking Buildbucket for the build status
[10:34:42.465] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:42.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:51:0) after 1m0s
[10:35:42.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:51:0)
[10:35:42.580] Timer tick, asking Buildbucket for the build status
[10:35:42.939] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:42.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:52:0) after 1m0s
[10:36:43.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:52:0)
[10:36:43.098] Timer tick, asking Buildbucket for the build status
[10:36:43.222] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:43.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:53:0) after 1m0s
[10:37:43.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:53:0)
[10:37:43.353] Timer tick, asking Buildbucket for the build status
[10:37:43.453] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:43.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:54:0) after 1m0s
[10:38:43.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:54:0)
[10:38:43.497] Timer tick, asking Buildbucket for the build status
[10:38:43.602] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:43.602] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:55:0) after 1m0s
[10:39:43.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:55:0)
[10:39:43.777] Timer tick, asking Buildbucket for the build status
[10:39:44.074] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:44.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:56:0) after 1m0s
[10:40:44.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:56:0)
[10:40:44.259] Timer tick, asking Buildbucket for the build status
[10:40:44.407] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:44.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:57:0) after 1m0s
[10:41:44.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:57:0)
[10:41:44.530] Timer tick, asking Buildbucket for the build status
[10:41:44.693] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:44.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:58:0) after 1m0s
[10:42:44.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:58:0)
[10:42:44.714] Timer tick, asking Buildbucket for the build status
[10:42:44.885] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:44.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:59:0) after 1m0s
[10:43:44.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:59:0)
[10:43:44.981] Timer tick, asking Buildbucket for the build status
[10:43:45.067] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:45.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:60:0) after 1m0s
[10:44:45.234] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:60:0)
[10:44:45.234] Timer tick, asking Buildbucket for the build status
[10:44:45.426] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:45.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:61:0) after 1m0s
[10:45:45.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:61:0)
[10:45:45.501] Timer tick, asking Buildbucket for the build status
[10:45:45.678] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:45.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:62:0) after 1m0s
[10:46:45.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:62:0)
[10:46:45.742] Timer tick, asking Buildbucket for the build status
[10:46:45.880] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:45.880] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:63:0) after 1m0s
[10:47:45.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:63:0)
[10:47:45.908] Timer tick, asking Buildbucket for the build status
[10:47:46.316] Build 8887945176708201328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:46.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053375238740843744:64:0) after 1m0s
[10:48:34.959] Received PubSub notification, asking Buildbucket for the build status
[10:48:35.025] Build 8887945176708201328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:48:35.025] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582195713362358",
"created_by": "project:chromium",
"created_ts": "1582191981554956",
"id": "8887945176708201328",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86891cd21a09c09dbc0907dca1540a5194661b77\"}}",
"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-69-zljv\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168819, \"got_angle_revision\": \"2801bf43716e8642efe6132c50d2a1708756bba8\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"86891cd21a09c09dbc0907dca1540a5194661b77\", \"got_revision_cp\": \"refs/heads/master@{#743025}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"74d3f371f2015deeedb985a8b24ba242ea65caef\", \"got_v8_revision\": \"07a0ee92dfd89a66d57b9fb242d90e33607e3c44\", \"got_v8_revision_cp\": \"refs/heads/8.2.120@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"86891cd21a09c09dbc0907dca1540a5194661b77\", \"swarm_hashes_refs/heads/master(at){#743025}_without_patch\": {\"accessibility_unittests\": \"9b71eb27a025da9a0e019e37ba8bee95b266fa56\", \"angle_unittests\": \"7ea3cc25d0256ef058b558e084dbc4f438bb17d2\", \"app_shell_unittests\": \"d8286728fe271fe52c1ed41735a28892df418092\", \"aura_unittests\": \"d39e982e4f2461b95db9377ce16fa3d3cced3993\", \"base_unittests\": \"b42cddc5c21bfdf069687a59159d66dbe48ae145\", \"base_util_unittests\": \"774aa7bbcc06277cee27287eb6c31a57094fbfd8\", \"blink_common_unittests\": \"7280b5c7c42e6d1492b8b2d8739acff739d6c872\", \"blink_fuzzer_unittests\": \"843fa8cb829df88b5b5f09cf6c95888889253c7c\", \"blink_heap_unittests\": \"30d9073cf4928def94dfd08659e24e09c6eba548\", \"blink_platform_unittests\": \"f0baa6eabce7c9ec4db526c1dd32c8116bdda5a2\", \"blink_python_tests\": \"b6687bb69dd51dfae931e8e2d8110900b2c6c15b\", \"blink_unittests\": \"aaab09d6f039c15e3ca92d4775e511d756a2060a\", \"blink_web_tests\": \"343db5cac67b6b6f2e8e890c0f09382da282dea0\", \"boringssl_crypto_tests\": \"e134738b0b11b0c76b1f6ae86b8180418508349a\", \"boringssl_ssl_tests\": \"2f79b72cc9bea4cf1dfbeab03c32b34daa6e9708\", \"browser_tests\": \"633e50bd432965192496647fbf859f4c11a88323\", \"cacheinvalidation_unittests\": \"58101dc47a4877ae1278842a018e0c70e368c9f7\", \"capture_unittests\": \"cd2bd4eba91cea6d0cca989ecfef9a9780bd43dc\", \"cast_unittests\": \"6b171b26efbd9fc1ca541218ae84a03f3c3304a6\", \"cc_unittests\": \"e61846a86531f3a705ea53c65d9d937537b9bde1\", \"chrome_app_unittests\": \"69a13e5ee873587b1dcc4f345ad922141c9d8f6a\", \"chromedriver_unittests\": \"e05d5d5f42e0b42ed98c2de4c1b15b8e2bcaa209\", \"components_browsertests\": \"ed8b7c5393c58b988eb6475bd31c91ddace6014a\", \"components_unittests\": \"9836b8629d20a982cdc055b052025cb98751b016\", \"compositor_unittests\": \"a9ab1eeb38bba879dee26ef66ef78520b3fd0a56\", \"content_browsertests\": \"a2c8cadc5645e06a7ab40fbe45ca51c33dcd92ab\", \"content_shell_crash_test\": \"e7b003faf9d6f3f03d41cf358ab12a8af52be137\", \"content_unittests\": \"fef53f29329b29025f568d0dc3f7f2ce4231e52a\", \"crashpad_tests\": \"50f14793322b6fe88b1bc4a19d554cfa98dbc2eb\", \"cronet_tests\": \"5be3eae98e19f8876ab3b999bdf9102eb45325be\", \"cronet_unittests\": \"ce42bf4424f0655edd9cae88737da9a5495fbd8a\", \"crypto_unittests\": \"1a3c1bb87b9adc899c71c6a674e750c4eda2e0f1\", \"dbus_unittests\": \"cf3f5200c3b9aa67fa6545afbcd65a15038bf01a\", \"device_unittests\": \"e5a870e342453b1782ba33c26242953d8395cbbd\", \"display_unittests\": \"b12873ecff90e6e949bfb277e8a0a514ca1206f8\", \"events_unittests\": \"3fb5e7ce663a1e0c04bccaa8acce8755e32dd9f6\", \"extensions_browsertests\": \"e4456eb04a17000a90852d24c37b62b24b5ff4ab\", \"extensions_unittests\": \"2989f13acbf643ef68a0489dc7ba982f3a27b1c3\", \"filesystem_service_unittests\": \"780f1a45c5bdc4701e5dffb029dfb7fee802e12f\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"15ecc79f9a3d0761d61794fded7fb7830ccd812d\", \"gfx_unittests\": \"75ca013d297da202b55e73c38c958689b255e328\", \"gin_unittests\": \"8a7808d309805a8c18d91f4c68ff2c836b8daa7e\", \"google_apis_unittests\": \"7fc1503496dab8ffb8bcc2f25f331d915e69d629\", \"gpu_unittests\": \"f06ea93259d6eed71b0ef8b72fb7bed0f06b86a0\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"6542385fbcebfd1876b97303d131d873ed011186\", \"headless_browsertests\": \"e85ba75e67505d331399f7c3d8cc0f22697883ea\", \"headless_unittests\": \"5cbe556591b70e5497f911ec625fa6f71e083515\", \"interactive_ui_tests\": \"5221cdec6913c9505c524136f716deace476c130\", \"ipc_tests\": \"5bb7a33f733b3225c33db733723b0a5767bde059\", \"jingle_unittests\": \"22d779ee1ef2a798ee15869507e8757b0363e656\", \"latency_unittests\": \"56664df2b0def09627fcd99c1565d91a211ef4af\", \"libjingle_xmpp_unittests\": \"bfd00af7c5d30df82a6e1a938ff7ee809bf008f5\", \"media_blink_unittests\": \"427b1409ae6b048b002b07bae70d37e6be473087\", \"media_service_unittests\": \"064fc4f4d83f24a0bdde2498e2b2b0bc96c92c21\", \"media_unittests\": \"e08652ff2cd533ec14327e9010565fab495f9629\", \"message_center_unittests\": \"c5623dcfdb0a4c3472649372d535d50d3576a623\", \"metrics_python_tests\": \"18f8cad0c2a04a82b72da55ebfc931333db4a631\", \"midi_unittests\": \"75a5c69196df26799a72832114641b5f2dde2071\", \"mojo_core_unittests\": \"c54fd327134f787dbcd0e014c81323c81985d8fc\", \"mojo_unittests\": \"2a3a2e5fa645d9abdd5ea389ce8733b95d3783e4\", \"nacl_helper_nonsfi_unittests\": \"d7b6fde31300c1648322130abdddac1f8e56aade\", \"nacl_loader_unittests\": \"7d42defc387daff8bc543b0d6b100059afb8afc6\", \"native_theme_unittests\": \"f818d42c64ed0e125a9dc0d50997c3063fdcd271\", \"net_unittests\": \"8c213942856bce66cbbb131143aef39a2cd0b2e5\", \"pdf_unittests\": \"5b3d0877b41ab4502b5eb6934aefd002fec7f139\", \"perfetto_unittests\": \"7c5b7624fbfce2391fe9943e375f96c4099e3ada\", \"ppapi_unittests\": \"549859fb644e6f97303d2852d5c38c8daa33e93d\", \"printing_unittests\": \"a296617967f7a53c83174f984025aa8047038ecb\", \"remoting_unittests\": \"7062e1f55d4b1e771bd977410d86aa062d74c8d5\", \"sandbox_linux_unittests\": \"235180178d48bbdb5576d9f9634c38e2de9cb175\", \"service_manager_unittests\": \"a5f921342e60883d00d75e2c74c6de69b9dcd1b7\", \"services_unittests\": \"830891440f3fc18162beaf7470b897de81701bb6\", \"shell_dialogs_unittests\": \"6168b05dc877f7b9cd6058f1377af514990f515d\", \"skia_unittests\": \"8826fdf34984a76f93d32a7eb2456a1941d14da2\", \"snapshot_unittests\": \"ff432392cce5bf196df9586a3875901b16b50b13\", \"sql_unittests\": \"d2c42f946372cab6379d7672c3ee8a8f0c9433a4\", \"storage_unittests\": \"b9b6fb83da43ec6c7f60e650fbe9c621dc7fd6cc\", \"sync_integration_tests\": \"b94092e667b5f47b7e4b4ca36cd124de351ecb9d\", \"telemetry_gpu_unittests\": \"514749f66c0f6cae0eef5c717b747ae10051856d\", \"telemetry_perf_unittests\": \"aa8c0dc2ac4f36572198cab4901c83533dab0d80\", \"telemetry_unittests\": \"d54b2fa571be8fb6744c34378ab6bd89f2f5a873\", \"traffic_annotation_auditor_unittests\": \"a05ced982179bafc2cfd127ff1613aa752832926\", \"ui_base_unittests\": \"c615a89839d3ddec6f3d76941a25d8fa106cb685\", \"ui_touch_selection_unittests\": \"8fa3c6aa3deae947042b199c60639e1f0a5cc1c3\", \"unit_tests\": \"01d6bbf9614fb41f9ff5c0a2d8ed3e98a789c762\", \"url_unittests\": \"4eb3d41f2f4782909567e9d4d00548491f2b886b\", \"views_perftests\": \"771da056c37db99a62fb54d4666500c496e483a6\", \"views_unittests\": \"ad10baf7c763d27ff899042cddcbba0ce4c85db8\", \"viz_unittests\": \"4fe52026dc106a72bca60e5f1265e4f476772461\", \"vr_common_unittests\": \"188671f481930af2fa45275d4940737ecc5741c1\", \"vr_pixeltests\": \"bbf607858d928c985c628d9befc9df91d04518c7\", \"webdriver_wpt_tests\": \"9b1cee757bbb30409cccf8b147a9c99101e934f7\", \"weblayer_browsertests\": \"75c3082679f1685c3443fbb244916adcec316162\", \"wm_unittests\": \"382b976631c9352b1e3cba6b33b3d5107e348940\", \"wtf_unittests\": \"e9f599977c68623398966711202500e126c34be5\", \"xr_browser_tests\": \"53fc8aa7f96fde52e01ed94216b624209c6d6da5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3b0e28eebaae607a111b3d14afaf4a38944aa5084a33cb6cff32b5c412399a3d_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_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-69-zljv\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582192273868822",
"status": "COMPLETED",
"status_changed_ts": "1582195714075173",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168819",
"builder:Linux Builder (dbg)",
"buildset:commit/git/86891cd21a09c09dbc0907dca1540a5194661b77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/86891cd21a09c09dbc0907dca1540a5194661b77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053375238740843744",
"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/8887945176708201328/+/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:4a7acf4136af7110",
"user_agent:luci-scheduler"
],
"updated_ts": "1582195714137663",
"url": "https://ci.chromium.org/b/8887945176708201328",
"utcnow_ts": "1582195715009870"
}
}
[10:48:35.025] Invocation finished in 1h2m14.79276927s with status SUCCEEDED