[21:38:08.225] New invocation is queued and will start shortly
[21:38:09.548] Starting the invocation (attempt 1)
[21:38:09.589] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:38:09.589] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:38:09.589] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052243292585974048",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d6cca334cfb236db354c8fcf4b391f0e37948faa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052243292585974048",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/d6cca334cfb236db354c8fcf4b391f0e37948faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6cca334cfb236db354c8fcf4b391f0e37948faa",
"gitiles_ref:refs/heads/master"
]
}
[21:38:10.454] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1583271489728862",
"id": "8886813230345059968",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d6cca334cfb236db354c8fcf4b391f0e37948faa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583271490297379",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169295",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d6cca334cfb236db354c8fcf4b391f0e37948faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6cca334cfb236db354c8fcf4b391f0e37948faa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052243292585974048",
"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/8886813230345059968/+/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": "1583271490297530",
"url": "https://ci.chromium.org/b/8886813230345059968",
"utcnow_ts": "1583271490443507"
}
}
[21:38:10.454] Task URL: https://ci.chromium.org/b/8886813230345059968
[21:38:10.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:2:0) after 1m0s
[21:39:10.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:2:0)
[21:39:10.629] Timer tick, asking Buildbucket for the build status
[21:39:10.803] Build 8886813230345059968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:10.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:3:0) after 1m0s
[21:40:10.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:3:0)
[21:40:10.810] Timer tick, asking Buildbucket for the build status
[21:40:11.008] Build 8886813230345059968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:11.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:4:0) after 1m0s
[21:41:11.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:4:0)
[21:41:11.086] Timer tick, asking Buildbucket for the build status
[21:41:11.216] Build 8886813230345059968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:11.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:5:0) after 1m0s
[21:42:11.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:5:0)
[21:42:11.276] Timer tick, asking Buildbucket for the build status
[21:42:11.399] Build 8886813230345059968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:11.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:6:0) after 1m0s
[21:43:11.047] Received PubSub notification, asking Buildbucket for the build status
[21:43:11.231] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:11.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:6:0)
[21:43:11.429] Timer tick, asking Buildbucket for the build status
[21:43:11.519] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:11.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:8:0) after 1m0s
[21:44:11.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:8:0)
[21:44:11.654] Timer tick, asking Buildbucket for the build status
[21:44:12.033] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:12.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:9:0) after 1m0s
[21:45:12.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:9:0)
[21:45:12.085] Timer tick, asking Buildbucket for the build status
[21:45:12.227] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:12.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:10:0) after 1m0s
[21:46:12.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:10:0)
[21:46:12.341] Timer tick, asking Buildbucket for the build status
[21:46:12.466] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:12.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:11:0) after 1m0s
[21:47:12.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:11:0)
[21:47:12.504] Timer tick, asking Buildbucket for the build status
[21:47:12.637] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:12.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:12:0) after 1m0s
[21:48:12.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:12:0)
[21:48:12.777] Timer tick, asking Buildbucket for the build status
[21:48:13.204] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:13.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:13:0) after 1m0s
[21:49:13.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:13:0)
[21:49:13.208] Timer tick, asking Buildbucket for the build status
[21:49:13.328] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:13.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:14:0) after 1m0s
[21:50:13.392] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:14:0)
[21:50:13.392] Timer tick, asking Buildbucket for the build status
[21:50:13.516] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:13.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:15:0) after 1m0s
[21:51:13.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:15:0)
[21:51:13.560] Timer tick, asking Buildbucket for the build status
[21:51:13.675] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:13.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:16:0) after 1m0s
[21:52:13.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:16:0)
[21:52:13.793] Timer tick, asking Buildbucket for the build status
[21:52:13.926] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:13.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:17:0) after 1m0s
[21:53:13.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:17:0)
[21:53:13.972] Timer tick, asking Buildbucket for the build status
[21:53:14.517] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:14.517] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:18:0) after 1m0s
[21:54:14.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:18:0)
[21:54:14.517] Timer tick, asking Buildbucket for the build status
[21:54:14.793] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:14.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:19:0) after 1m0s
[21:55:14.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:19:0)
[21:55:14.838] Timer tick, asking Buildbucket for the build status
[21:55:15.026] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:15.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:20:0) after 1m0s
[21:56:15.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:20:0)
[21:56:15.045] Timer tick, asking Buildbucket for the build status
[21:56:15.167] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:15.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:21:0) after 1m0s
[21:57:15.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:21:0)
[21:57:15.219] Timer tick, asking Buildbucket for the build status
[21:57:15.534] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:15.534] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:22:0) after 1m0s
[21:58:15.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:22:0)
[21:58:15.570] Timer tick, asking Buildbucket for the build status
[21:58:15.806] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:15.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:23:0) after 1m0s
[21:59:15.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:23:0)
[21:59:15.826] Timer tick, asking Buildbucket for the build status
[21:59:16.055] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:16.055] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:24:0) after 1m0s
[22:00:16.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:24:0)
[22:00:16.092] Timer tick, asking Buildbucket for the build status
[22:00:16.559] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:16.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:25:0) after 1m0s
[22:01:16.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:25:0)
[22:01:16.638] Timer tick, asking Buildbucket for the build status
[22:01:16.855] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:16.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:26:0) after 1m0s
[22:02:16.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:26:0)
[22:02:16.966] Timer tick, asking Buildbucket for the build status
[22:02:17.107] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:17.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:27:0) after 1m0s
[22:03:17.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:27:0)
[22:03:17.213] Timer tick, asking Buildbucket for the build status
[22:03:17.367] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:17.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:28:0) after 1m0s
[22:04:17.545] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:28:0)
[22:04:17.545] Timer tick, asking Buildbucket for the build status
[22:04:17.689] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:17.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:29:0) after 1m0s
[22:05:17.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:29:0)
[22:05:17.794] Timer tick, asking Buildbucket for the build status
[22:05:17.911] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:17.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:30:0) after 1m0s
[22:06:18.053] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:30:0)
[22:06:18.053] Timer tick, asking Buildbucket for the build status
[22:06:18.302] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:18.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:31:0) after 1m0s
[22:07:18.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:31:0)
[22:07:18.378] Timer tick, asking Buildbucket for the build status
[22:07:18.617] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:18.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:32:0) after 1m0s
[22:08:18.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:32:0)
[22:08:18.703] Timer tick, asking Buildbucket for the build status
[22:08:18.984] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:18.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:33:0) after 1m0s
[22:09:19.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:33:0)
[22:09:19.019] Timer tick, asking Buildbucket for the build status
[22:09:19.148] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:19.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:34:0) after 1m0s
[22:10:19.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:34:0)
[22:10:19.182] Timer tick, asking Buildbucket for the build status
[22:10:19.357] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:19.357] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:35:0) after 1m0s
[22:11:19.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:35:0)
[22:11:19.441] Timer tick, asking Buildbucket for the build status
[22:11:19.707] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:19.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:36:0) after 1m0s
[22:12:19.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:36:0)
[22:12:19.819] Timer tick, asking Buildbucket for the build status
[22:12:20.012] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:20.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:37:0) after 1m0s
[22:13:20.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:37:0)
[22:13:20.131] Timer tick, asking Buildbucket for the build status
[22:13:20.405] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:20.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:38:0) after 1m0s
[22:14:20.431] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:38:0)
[22:14:20.431] Timer tick, asking Buildbucket for the build status
[22:14:20.656] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:20.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:39:0) after 1m0s
[22:15:20.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:39:0)
[22:15:20.796] Timer tick, asking Buildbucket for the build status
[22:15:20.970] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:20.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:40:0) after 1m0s
[22:16:21.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:40:0)
[22:16:21.036] Timer tick, asking Buildbucket for the build status
[22:16:21.276] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:21.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:41:0) after 1m0s
[22:17:21.348] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:41:0)
[22:17:21.348] Timer tick, asking Buildbucket for the build status
[22:17:21.463] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:21.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:42:0) after 1m0s
[22:18:21.494] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:42:0)
[22:18:21.494] Timer tick, asking Buildbucket for the build status
[22:18:21.919] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:21.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:43:0) after 1m0s
[22:19:22.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:43:0)
[22:19:22.043] Timer tick, asking Buildbucket for the build status
[22:19:22.222] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:22.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:44:0) after 1m0s
[22:20:22.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:44:0)
[22:20:22.366] Timer tick, asking Buildbucket for the build status
[22:20:22.663] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:22.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:45:0) after 1m0s
[22:21:22.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:45:0)
[22:21:22.813] Timer tick, asking Buildbucket for the build status
[22:21:23.049] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:23.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:46:0) after 1m0s
[22:22:23.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:46:0)
[22:22:23.156] Timer tick, asking Buildbucket for the build status
[22:22:23.556] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:23.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:47:0) after 1m0s
[22:23:23.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:47:0)
[22:23:23.628] Timer tick, asking Buildbucket for the build status
[22:23:23.798] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:23.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:48:0) after 1m0s
[22:24:23.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:48:0)
[22:24:23.849] Timer tick, asking Buildbucket for the build status
[22:24:23.982] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:23.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:49:0) after 1m0s
[22:25:24.060] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:49:0)
[22:25:24.060] Timer tick, asking Buildbucket for the build status
[22:25:24.340] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:24.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:50:0) after 1m0s
[22:26:24.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:50:0)
[22:26:24.410] Timer tick, asking Buildbucket for the build status
[22:26:24.525] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:24.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:51:0) after 1m0s
[22:27:24.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:51:0)
[22:27:24.559] Timer tick, asking Buildbucket for the build status
[22:27:24.673] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:24.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:52:0) after 1m0s
[22:28:24.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:52:0)
[22:28:24.760] Timer tick, asking Buildbucket for the build status
[22:28:25.000] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:25.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:53:0) after 1m0s
[22:29:25.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:53:0)
[22:29:25.139] Timer tick, asking Buildbucket for the build status
[22:29:25.376] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:25.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:54:0) after 1m0s
[22:30:25.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:54:0)
[22:30:25.519] Timer tick, asking Buildbucket for the build status
[22:30:25.869] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:25.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:55:0) after 1m0s
[22:31:25.926] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:55:0)
[22:31:25.926] Timer tick, asking Buildbucket for the build status
[22:31:27.065] Build 8886813230345059968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:27.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052243292585974048:56:0) after 1m0s
[22:31:44.259] Received PubSub notification, asking Buildbucket for the build status
[22:31:44.377] Build 8886813230345059968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:31:44.377] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1583274703277412",
"created_by": "project:chromium",
"created_ts": "1583271489728862",
"id": "8886813230345059968",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d6cca334cfb236db354c8fcf4b391f0e37948faa\"}}",
"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-east1-d-8-8-9t7l\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169295, \"got_angle_revision\": \"f8b28678163603f8fab1c45e19507c7500299021\", \"got_dawn_revision\": \"6f92b9160b197ba49b084faa7764f865b0c9ea92\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"d6cca334cfb236db354c8fcf4b391f0e37948faa\", \"got_revision_cp\": \"refs/heads/master@{#746528}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"126720bd2e578fec077a57d877ac64c46b18cd52\", \"got_v8_revision\": \"65b70d61b93c092a2cf5c0bcf264c1d594fdc6fb\", \"got_v8_revision_cp\": \"refs/heads/8.2.247@{#1}\", \"got_webrtc_revision\": \"5e1ea251895c08fbc48cb595b02f9257cedeac75\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30674}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d6cca334cfb236db354c8fcf4b391f0e37948faa\", \"swarm_hashes_refs/heads/master(at){#746528}_without_patch\": {\"accessibility_unittests\": \"d8852bdfc27686b86395d0c882bac96287e4d308\", \"angle_unittests\": \"e227d26b3640e36c2377cf0e5040432bbbeded53\", \"app_shell_unittests\": \"32598f031a226ae972342e73c85974a6aca56b9e\", \"aura_unittests\": \"347c18607b081c64cd66637af5692491ba0f1016\", \"base_unittests\": \"811cc44ee8df77fa2be550b5671e824eb79a5849\", \"base_util_unittests\": \"f7a44cbf7436e6874c7ffcd4b6faeda9005dc7df\", \"blink_common_unittests\": \"5d9e68a81ed3edcf3ded6cdcd004bc84f8945157\", \"blink_fuzzer_unittests\": \"4930d2cb9e57a8b82eda069256de6729a16e5198\", \"blink_heap_unittests\": \"8af6bbaafe48ae21a8b78764ff2f2568021e1eaf\", \"blink_platform_unittests\": \"987a12c87b646a5efec6c053d92b4f98ce4477a3\", \"blink_python_tests\": \"2c844db9fd39d4eb965dc4f549107c9646a42ffb\", \"blink_unittests\": \"19d940f50809dff37e55d36fa7b77ee434ce251f\", \"blink_web_tests\": \"b71af6c759ad4b75652af231f8f8e6da1ae6530e\", \"boringssl_crypto_tests\": \"4a176ff8f5e8f0e6d23b6c518fb844ca58c1061f\", \"boringssl_ssl_tests\": \"e50c51c80dce35986daa869be9801235c0edc02f\", \"browser_tests\": \"937891d12126f4c1c0e5d135d3afe442ac88dd71\", \"cacheinvalidation_unittests\": \"b766bbf2d0564191d82770b941ffb50588ffe6a3\", \"capture_unittests\": \"6ff739556e3846c8676d00377d74c87a6725a03a\", \"cast_unittests\": \"e265bf9962a04e957529ca1fb857f9df07a565dd\", \"cc_unittests\": \"c5a74992f86ef7b0a09c302a17da8d05e5be6697\", \"chrome_app_unittests\": \"f34bfb8803bd3f5636ed5fc364687479ce1531d3\", \"chromedriver_unittests\": \"065866c1e3ed4cf3524c8d2c5dc33ae7bacca535\", \"components_browsertests\": \"26b923bb56a91592a5af8653f064bf451439b5f0\", \"components_unittests\": \"a8d42f9c8ccdccb6e5b48869b97fafbee1f31ce1\", \"compositor_unittests\": \"221a17dfb432409876e3b64a5f903c3e5c1fff43\", \"content_browsertests\": \"3910d42475972018e6458eda8969c8c80f7f8600\", \"content_shell_crash_test\": \"bc061f016a1105556fafdc5231d6adf29fc6e692\", \"content_unittests\": \"b813e2eab98f78d8ac9c8502ab17b23f4c949b5d\", \"crashpad_tests\": \"c47645027b4a91174435c9bbb3b386bbf592e129\", \"cronet_tests\": \"51780628fde5abf593a87906c1fe4265131617fe\", \"cronet_unittests\": \"ad3c28c68a9b7b3986ff993411a84087e4a72fb7\", \"crypto_unittests\": \"39f33f7bc352005976f00607fe24c188213297bf\", \"dbus_unittests\": \"a7be3c004c45f524ed930c94a2a71b33b2e314f4\", \"device_unittests\": \"3bf3e8550396b6f3518bf8bcf09d0224f6113851\", \"display_unittests\": \"af92434a556109e397ea21eb61dddb32082470c7\", \"events_unittests\": \"2f00de8d42b0dabb85bebc50cb3a2882b6dfb3d2\", \"extensions_browsertests\": \"ea1bccdce41a4267a607b4974b6c699c9ffc94bb\", \"extensions_unittests\": \"61fdf1d37893b61c46983695391f4b66641d2082\", \"filesystem_service_unittests\": \"93d68a8174b32dbb23e4af087e541e444390f23f\", \"flatbuffers_unittests\": \"9b972b84314b0e944fad99f4c5be3f50c351ec0c\", \"gcm_unit_tests\": \"df9060a43ee27e1b2e5680d2661b630cfc16b3ad\", \"gfx_unittests\": \"1e5254cc8ba50ab57c5b3843ab946659198f3597\", \"gin_unittests\": \"8f202781e41379b09e0146ac700dd98ada3b2e1f\", \"google_apis_unittests\": \"f8b6d51094e247cc05bc95ea475f4a8d1d881120\", \"gpu_unittests\": \"96a5b1e91f2a434f0045dd5a579b2b33846a0554\", \"grit_python_unittests\": \"8d3af1ca6ce7611b3ef6d2516a78cd61f915c28b\", \"gwp_asan_unittests\": \"0dd466eadf025a8bccba97e31869f9441c7d8003\", \"headless_browsertests\": \"53e81743a9bd2252f25d5725fe934a1fe604435e\", \"headless_unittests\": \"78b36ee7941fb54d0510ee9c0a4dd81f13268a7f\", \"interactive_ui_tests\": \"ce13523228f150d1872e90547c4112287b62dc76\", \"ipc_tests\": \"0e219f95243ee8a2dfd68aaa8db7aaa3085331e8\", \"jingle_unittests\": \"ce09f6da1d1aa9ab29abc5d061567397c56db4b8\", \"latency_unittests\": \"4d2d96812914cbfd1db2adf8ea1c7ca260eb65c1\", \"libjingle_xmpp_unittests\": \"a9d3198dc4cf1ca59cd9535ed21c5f0c6482f336\", \"media_blink_unittests\": \"903a22ec322bfe952aa37d8fcda3df8a9dfafb3a\", \"media_service_unittests\": \"86c5c3a1de3b42390f37f6e03a15bb79e153ed48\", \"media_unittests\": \"e172214295ea87dd31e5f0580895d727b5808ad7\", \"message_center_unittests\": \"e70302155900ceb0387156ac423d246fcab88fff\", \"metrics_python_tests\": \"898a783a781d4a1063e510d48fe707b3b0cedcff\", \"midi_unittests\": \"f56ac8b969742e8bae4ea91c1bd0cc7b973b284b\", \"mojo_core_unittests\": \"cc88588b73236869934bfc65cf4becf44ba2c6e2\", \"mojo_unittests\": \"5c54e28b62265d2c211172c88c9a8b595584cc89\", \"nacl_helper_nonsfi_unittests\": \"0e5960ad8230292c9f636c9cc80773ea418cff65\", \"nacl_loader_unittests\": \"4e77ceef6261928c12a1dc3d65cec66bbee04344\", \"native_theme_unittests\": \"dca681bbd564034d77a1c4d561b3313fee97a03d\", \"net_unittests\": \"81ebd307ec84394c583e0aeef2196405238501cd\", \"pdf_unittests\": \"34eb955376cdc9695c3e8faa99f9f20ca5de4185\", \"perfetto_unittests\": \"6a00af0264d66159ac213a795f32ab91586fabdc\", \"ppapi_unittests\": \"4dc840c0a85833b3fa0b9bf90d1ecff8fd4a6c2d\", \"printing_unittests\": \"557030b1e270230ed6d05ad23cb03a036d8979ef\", \"remoting_unittests\": \"aa41ba37bb57211f9862fed537ae67e4d58305b3\", \"sandbox_linux_unittests\": \"7a893d90dd2a9b7777f267ecb2bfadc0c58ff54d\", \"service_manager_unittests\": \"c7ebcbbb2ff7b5c29dbaa1ff26e518312887d767\", \"services_unittests\": \"3a1e3439147fe653e7c1b32c6cdae593b3c5cc6e\", \"shell_dialogs_unittests\": \"20cae0b5166946e009ea2affee53a9e904e00ba1\", \"skia_unittests\": \"21e9549d658185fdc604fc189075134dabcecc69\", \"snapshot_unittests\": \"1bdbf99416765948a68f3ee262a1bdf5edf3f13b\", \"sql_unittests\": \"a6a6c7f27b79349b708810f734e6b250f3c27f11\", \"storage_unittests\": \"9ed002771c67ccca7697e744e6d85d0a6cbd5a94\", \"sync_integration_tests\": \"99e97de28059bba07e58db71836e0b2188f93c59\", \"telemetry_gpu_unittests\": \"33e55bf607cbf6447c879bb40c017414999a3b89\", \"telemetry_perf_unittests\": \"1247c9dfc0b496dc014e721081a5c428f014ef7b\", \"telemetry_unittests\": \"4bc9dbd72df6e38323121add94d272edf4ee3122\", \"traffic_annotation_auditor_unittests\": \"e1f590bb87f07b71e658bd448eeb9d7ae8aa1edd\", \"ui_base_unittests\": \"0d4ea7a088217bc8994b5a0edf6a24e96c9e6603\", \"ui_touch_selection_unittests\": \"6e31c85d2493049bceb1e4aefa1653bcb242aa4f\", \"unit_tests\": \"c41da4ddc70729501dbd966532a790daf0926fff\", \"url_unittests\": \"e6525d011bfb61e8cdd2454bb0e1368aced1eb60\", \"views_perftests\": \"5fff0bd5e7a5e51cadb3cd4a0e8b7538e650f814\", \"views_unittests\": \"a47fa66c91e67618c3d6ce6798a1d166200974ef\", \"viz_unittests\": \"125cce85a8b45a5ec8da093f490895d31664920b\", \"vr_common_unittests\": \"107b8b7a572cc86b649984e0e53406abfa01cc68\", \"vr_pixeltests\": \"c0fecb3ca3c182ab4bbf408dcde446e56775507c\", \"webdriver_wpt_tests\": \"545e08a8c62c221d419049005c7250ee280eaf55\", \"weblayer_browsertests\": \"7ae5daf5b19965e1133e3d99cd4b453cd3376b1f\", \"wm_unittests\": \"4997fb20a0bc830a0f2b4754ea0123e0941eab76\", \"wtf_unittests\": \"4daeb234d26cbd70e180ccd2c419280b0d1f9a66\", \"xr_browser_tests\": \"555e4c0c7726ca7d842d0c53980daa54bff6b8df\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_043ff3f52d8d01364b204065bddda74d1a1fba0b8da9301cf8e96315f69303d6_v2\", \"builder_ced67bd9e4a31c2a429bce6c01367a471355a6320e5e7684f07304ef1e327147_v2\", \"builder_fdb8dca0f90a2608c4f778e5f29672efafa0e8947deee24935132ecfe3180a21_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-east1-d-8-8-9t7l\"], \"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\": [\"4840-d3c86f9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583271789874115",
"status": "COMPLETED",
"status_changed_ts": "1583274703690380",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169295",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d6cca334cfb236db354c8fcf4b391f0e37948faa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6cca334cfb236db354c8fcf4b391f0e37948faa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052243292585974048",
"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/8886813230345059968/+/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:4abb273c350ddc10",
"user_agent:luci-scheduler"
],
"updated_ts": "1583274703700400",
"url": "https://ci.chromium.org/b/8886813230345059968",
"utcnow_ts": "1583274704366762"
}
}
[22:31:44.377] Invocation finished in 53m36.169079093s with status SUCCEEDED