[08:06:22.824] New invocation is queued and will start shortly
[08:06:24.058] Starting the invocation (attempt 1)
[08:06:24.131] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:06:24.131] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:06:24.131] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059723315146924304",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ae9fa18b1d74e686027470ab2378fc349cfd14e3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059723315146924304",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/ae9fa18b1d74e686027470ab2378fc349cfd14e3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae9fa18b1d74e686027470ab2378fc349cfd14e3",
"gitiles_ref:refs/heads/master"
]
}
[08:06:25.147] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576137984314525",
"id": "8894293252918571792",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ae9fa18b1d74e686027470ab2378fc349cfd14e3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576137984736104",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165988",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ae9fa18b1d74e686027470ab2378fc349cfd14e3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae9fa18b1d74e686027470ab2378fc349cfd14e3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059723315146924304",
"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/8894293252918571792/+/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": "1576137984736273",
"url": "https://ci.chromium.org/b/8894293252918571792",
"utcnow_ts": "1576137985120671"
}
}
[08:06:25.147] Task URL: https://ci.chromium.org/b/8894293252918571792
[08:06:25.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:2:0) after 1m0s
[08:07:25.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:2:0)
[08:07:25.206] Timer tick, asking Buildbucket for the build status
[08:07:25.482] Build 8894293252918571792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:07:25.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:3:0) after 1m0s
[08:08:25.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:3:0)
[08:08:25.518] Timer tick, asking Buildbucket for the build status
[08:08:25.953] Build 8894293252918571792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:08:25.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:4:0) after 1m0s
[08:09:25.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:4:0)
[08:09:25.973] Timer tick, asking Buildbucket for the build status
[08:09:26.258] Build 8894293252918571792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:09:26.258] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:5:0) after 1m0s
[08:10:26.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:5:0)
[08:10:26.263] Timer tick, asking Buildbucket for the build status
[08:10:26.531] Build 8894293252918571792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:10:26.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:6:0) after 1m0s
[08:11:14.419] Received PubSub notification, asking Buildbucket for the build status
[08:11:16.437] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:26.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:6:0)
[08:11:26.623] Timer tick, asking Buildbucket for the build status
[08:11:26.930] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:26.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:8:0) after 1m0s
[08:12:26.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:8:0)
[08:12:26.948] Timer tick, asking Buildbucket for the build status
[08:12:27.329] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:27.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:9:0) after 1m0s
[08:13:27.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:9:0)
[08:13:27.375] Timer tick, asking Buildbucket for the build status
[08:13:27.622] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:27.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:10:0) after 1m0s
[08:14:27.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:10:0)
[08:14:27.676] Timer tick, asking Buildbucket for the build status
[08:14:28.082] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:28.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:11:0) after 1m0s
[08:15:28.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:11:0)
[08:15:28.105] Timer tick, asking Buildbucket for the build status
[08:15:28.982] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:28.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:12:0) after 1m0s
[08:16:29.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:12:0)
[08:16:29.035] Timer tick, asking Buildbucket for the build status
[08:16:29.982] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:29.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:13:0) after 1m0s
[08:17:30.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:13:0)
[08:17:30.045] Timer tick, asking Buildbucket for the build status
[08:17:30.478] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:30.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:14:0) after 1m0s
[08:18:30.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:14:0)
[08:18:30.499] Timer tick, asking Buildbucket for the build status
[08:18:30.872] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:30.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:15:0) after 1m0s
[08:19:30.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:15:0)
[08:19:30.965] Timer tick, asking Buildbucket for the build status
[08:19:31.524] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:31.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:16:0) after 1m0s
[08:20:31.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:16:0)
[08:20:31.600] Timer tick, asking Buildbucket for the build status
[08:20:31.916] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:31.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:17:0) after 1m0s
[08:21:31.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:17:0)
[08:21:31.889] Timer tick, asking Buildbucket for the build status
[08:21:34.827] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:34.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:18:0) after 1m0s
[08:22:34.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:18:0)
[08:22:34.848] Timer tick, asking Buildbucket for the build status
[08:22:35.163] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:35.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:19:0) after 1m0s
[08:23:35.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:19:0)
[08:23:35.171] Timer tick, asking Buildbucket for the build status
[08:23:35.742] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:35.742] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:20:0) after 1m0s
[08:24:35.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:20:0)
[08:24:35.814] Timer tick, asking Buildbucket for the build status
[08:24:36.064] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:36.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:21:0) after 1m0s
[08:25:36.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:21:0)
[08:25:36.151] Timer tick, asking Buildbucket for the build status
[08:25:36.675] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:36.675] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:22:0) after 1m0s
[08:26:36.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:22:0)
[08:26:36.764] Timer tick, asking Buildbucket for the build status
[08:26:37.284] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:37.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:23:0) after 1m0s
[08:27:37.612] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:23:0)
[08:27:37.612] Timer tick, asking Buildbucket for the build status
[08:27:37.808] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:37.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:24:0) after 1m0s
[08:28:37.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:24:0)
[08:28:37.934] Timer tick, asking Buildbucket for the build status
[08:28:38.136] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:38.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:25:0) after 1m0s
[08:29:38.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:25:0)
[08:29:38.212] Timer tick, asking Buildbucket for the build status
[08:29:38.776] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:38.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:26:0) after 1m0s
[08:30:39.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:26:0)
[08:30:39.187] Timer tick, asking Buildbucket for the build status
[08:30:39.372] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:39.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:27:0) after 1m0s
[08:31:40.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:27:0)
[08:31:40.151] Timer tick, asking Buildbucket for the build status
[08:31:40.492] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:40.492] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:28:0) after 1m0s
[08:32:40.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:28:0)
[08:32:40.955] Timer tick, asking Buildbucket for the build status
[08:32:41.891] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:41.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:29:0) after 1m0s
[08:33:42.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:29:0)
[08:33:42.166] Timer tick, asking Buildbucket for the build status
[08:33:42.707] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:30:0) after 1m0s
[08:34:42.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:30:0)
[08:34:42.831] Timer tick, asking Buildbucket for the build status
[08:34:43.308] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:43.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:31:0) after 1m0s
[08:35:43.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:31:0)
[08:35:43.382] Timer tick, asking Buildbucket for the build status
[08:35:43.583] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:43.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:32:0) after 1m0s
[08:36:43.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:32:0)
[08:36:43.610] Timer tick, asking Buildbucket for the build status
[08:36:44.534] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:44.534] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:33:0) after 1m0s
[08:37:44.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:33:0)
[08:37:44.610] Timer tick, asking Buildbucket for the build status
[08:37:45.422] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:45.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:34:0) after 1m0s
[08:38:45.546] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:34:0)
[08:38:45.546] Timer tick, asking Buildbucket for the build status
[08:38:45.750] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:45.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:35:0) after 1m0s
[08:39:45.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:35:0)
[08:39:45.816] Timer tick, asking Buildbucket for the build status
[08:39:46.883] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:46.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:36:0) after 1m0s
[08:40:46.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:36:0)
[08:40:46.955] Timer tick, asking Buildbucket for the build status
[08:40:47.344] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:47.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:37:0) after 1m0s
[08:41:47.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:37:0)
[08:41:47.458] Timer tick, asking Buildbucket for the build status
[08:41:48.402] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:48.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:38:0) after 1m0s
[08:42:48.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:38:0)
[08:42:48.423] Timer tick, asking Buildbucket for the build status
[08:42:49.225] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:49.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:39:0) after 1m0s
[08:43:49.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:39:0)
[08:43:49.296] Timer tick, asking Buildbucket for the build status
[08:43:54.273] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:54.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:40:0) after 1m0s
[08:44:54.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:40:0)
[08:44:54.322] Timer tick, asking Buildbucket for the build status
[08:44:54.796] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:54.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:41:0) after 1m0s
[08:45:54.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:41:0)
[08:45:54.838] Timer tick, asking Buildbucket for the build status
[08:45:55.437] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:55.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:42:0) after 1m0s
[08:46:55.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:42:0)
[08:46:55.481] Timer tick, asking Buildbucket for the build status
[08:46:55.661] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:55.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:43:0) after 1m0s
[08:47:55.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:43:0)
[08:47:55.684] Timer tick, asking Buildbucket for the build status
[08:47:56.197] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:56.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:44:0) after 1m0s
[08:48:56.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:44:0)
[08:48:56.253] Timer tick, asking Buildbucket for the build status
[08:49:04.028] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:04.028] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:45:0) after 1m0s
[08:50:04.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:45:0)
[08:50:04.015] Timer tick, asking Buildbucket for the build status
[08:50:04.502] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:04.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:46:0) after 1m0s
[08:51:04.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:46:0)
[08:51:04.525] Timer tick, asking Buildbucket for the build status
[08:51:04.872] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:04.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:47:0) after 1m0s
[08:52:05.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:47:0)
[08:52:05.022] Timer tick, asking Buildbucket for the build status
[08:52:05.461] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:05.461] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:48:0) after 1m0s
[08:53:05.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:48:0)
[08:53:05.583] Timer tick, asking Buildbucket for the build status
[08:53:05.823] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:05.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:49:0) after 1m0s
[08:54:05.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:49:0)
[08:54:05.870] Timer tick, asking Buildbucket for the build status
[08:54:06.030] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:06.030] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:50:0) after 1m0s
[08:55:06.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:50:0)
[08:55:06.161] Timer tick, asking Buildbucket for the build status
[08:55:06.579] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:06.579] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:51:0) after 1m0s
[08:56:06.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:51:0)
[08:56:06.643] Timer tick, asking Buildbucket for the build status
[08:56:07.727] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:07.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:52:0) after 1m0s
[08:57:07.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:52:0)
[08:57:07.825] Timer tick, asking Buildbucket for the build status
[08:57:08.014] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:08.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:53:0) after 1m0s
[08:58:08.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:53:0)
[08:58:08.068] Timer tick, asking Buildbucket for the build status
[08:58:08.469] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:08.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:54:0) after 1m0s
[08:59:08.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:54:0)
[08:59:08.503] Timer tick, asking Buildbucket for the build status
[08:59:08.703] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:08.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:55:0) after 1m0s
[09:00:08.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:55:0)
[09:00:08.809] Timer tick, asking Buildbucket for the build status
[09:00:10.418] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:10.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:56:0) after 1m0s
[09:01:10.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:56:0)
[09:01:10.524] Timer tick, asking Buildbucket for the build status
[09:01:10.707] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:10.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:57:0) after 1m0s
[09:02:10.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:57:0)
[09:02:10.860] Timer tick, asking Buildbucket for the build status
[09:02:11.330] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:11.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:58:0) after 1m0s
[09:03:11.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:58:0)
[09:03:11.408] Timer tick, asking Buildbucket for the build status
[09:03:11.683] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:11.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:59:0) after 1m0s
[09:04:11.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:59:0)
[09:04:11.708] Timer tick, asking Buildbucket for the build status
[09:04:12.563] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:12.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:60:0) after 1m0s
[09:05:12.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:60:0)
[09:05:12.560] Timer tick, asking Buildbucket for the build status
[09:05:12.995] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:12.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:61:0) after 1m0s
[09:06:13.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:61:0)
[09:06:13.039] Timer tick, asking Buildbucket for the build status
[09:06:13.361] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:13.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:62:0) after 1m0s
[09:07:13.520] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:62:0)
[09:07:13.520] Timer tick, asking Buildbucket for the build status
[09:07:13.763] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:13.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:63:0) after 1m0s
[09:08:13.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:63:0)
[09:08:13.838] Timer tick, asking Buildbucket for the build status
[09:08:14.087] Build 8894293252918571792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:14.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059723315146924304:64:0) after 1m0s
[09:08:22.350] Received PubSub notification, asking Buildbucket for the build status
[09:08:22.519] Build 8894293252918571792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:08:22.519] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576141701431151",
"created_by": "project:chromium",
"created_ts": "1576137984314525",
"id": "8894293252918571792",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ae9fa18b1d74e686027470ab2378fc349cfd14e3\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-22-yxzf\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165988, \"got_angle_revision\": \"3c75d244d6f9ef1eb9f6f010f01828658fe9d38a\", \"got_dawn_revision\": \"af094e6a88d01073a4e5e15fa1b268a72bbb73aa\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"ae9fa18b1d74e686027470ab2378fc349cfd14e3\", \"got_revision_cp\": \"refs/heads/master@{#724158}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"07958d452f93b6513ebf5cbae64a1325c1755dbf\", \"got_v8_revision\": \"5a2f2203c80defe0adc943a2c15ff51da7b24196\", \"got_v8_revision_cp\": \"refs/heads/8.1.18@{#1}\", \"got_webrtc_revision\": \"26fe811623627e1089a8df2c86a89dda2cd9d338\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30068}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ae9fa18b1d74e686027470ab2378fc349cfd14e3\", \"swarm_hashes_refs/heads/master(at){#724158}_without_patch\": {\"accessibility_unittests\": \"7233942c5f8072a3a68537f2206776631ff4c2b5\", \"angle_unittests\": \"37ce57746b0b63809cb165f7d3c0d0ed7c191038\", \"app_shell_unittests\": \"0e17429771d30af894790973e21422f8f97699d2\", \"aura_unittests\": \"cc4371995609f9026b808230a830360ee0cc3adc\", \"base_unittests\": \"29a26c81b251af725ac7577a3213e74f74729f55\", \"base_util_unittests\": \"60c6fe2a86835d8af897e09b387a03f58239d47d\", \"blink_common_unittests\": \"3a4c64c7f9be486e1bfac94413d37fc501ec10cc\", \"blink_fuzzer_unittests\": \"14d8a7ca504c17aab8c10694ecbe61cf5dc699f7\", \"blink_heap_unittests\": \"e6b7f86dc8a6b6a651017e450b5232c97e0d4014\", \"blink_platform_unittests\": \"fcc19c6726906854c3f6dc81f546fb5e131cc946\", \"blink_python_tests\": \"417b0082d8fcdfd4185663fe3e694ba072bda9dd\", \"blink_unittests\": \"2b7d986cfca08ca3f737a5b07f178d5492fd8f03\", \"blink_web_tests\": \"0e001652eb4a55e0bbfe89ac53c8467f05309389\", \"boringssl_crypto_tests\": \"410b7988047d7a617833066738ad8e49df0dcb8b\", \"boringssl_ssl_tests\": \"929263cc9dd0355821309e5b74b280b2dd24f69d\", \"browser_tests\": \"65eb36d45654d962ed5c3bf905092bbc91cd2a81\", \"cacheinvalidation_unittests\": \"a52df5f9dc39c37ef420b5a5c2f8d918cede2412\", \"capture_unittests\": \"2d0f8b96bcc30e999ba574c7a36ddc88b6bb9a01\", \"cast_unittests\": \"361359efa71f55d84b23d9a37d9a62204c82a1b6\", \"cc_unittests\": \"6fffc151434c559cc23305716fa7556a2768f1d1\", \"chrome_app_unittests\": \"466b2ae2587ac483fc1d8e0e19409b3a25762fdb\", \"chromedriver_unittests\": \"333808b0d30e0820ee665a6197fe96600cf6ed70\", \"components_browsertests\": \"743aa168eefd82d70d72dd0e20ae7c9bc143e6c5\", \"components_unittests\": \"55d2d25fa889783fb7008914c1e9480b6f01c208\", \"compositor_unittests\": \"f0adf84d3e92b0c9409cc464a45c7a64dc0afb59\", \"content_browsertests\": \"4278b5d39700e627685172d6098c87722cbd150d\", \"content_shell_crash_test\": \"dd660be861a74d69387af175e158004afde052da\", \"content_unittests\": \"988723d49b0342affb8115e3f8bfb931963b36a3\", \"crashpad_tests\": \"2f3aeabc762ae8ecc3ad92249effe7226afbdae8\", \"cronet_tests\": \"a3fcc08f55386cacf59dc895a3a686efceacdc1e\", \"cronet_unittests\": \"0bd3df7e3a17f455f82ca704373d75524e044e8c\", \"crypto_unittests\": \"fa8c775f8969a00859b575d0a4a377c3a1710c83\", \"dbus_unittests\": \"dd4f4221eed7716000d1c445d8ce2999c032e8bf\", \"device_unittests\": \"349d695a023b12f94232146b6272ed7a233f0049\", \"devtools_lint_check\": \"3994115c4b732aed12456cf5cc9789d13f42c1d3\", \"devtools_type_check\": \"1f8d8220f4115fa74a4a484012a66f272bd12ea0\", \"display_unittests\": \"88e8c29fdb7d166c34e0d9ea9a3818644ee3e642\", \"events_unittests\": \"106c3fc2cc2059376e35c8cacf25694066e67447\", \"extensions_browsertests\": \"1048ca0373bc82023d99d2bd4cb9b770d48143e6\", \"extensions_unittests\": \"08ebf6186e32b1f5ae514e6dda72948aa88deed4\", \"filesystem_service_unittests\": \"47e34072d20ecc15c6035de33c366c4107672bb4\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"db3befe8139d0f8654c378968f63082c0610dc1d\", \"gfx_unittests\": \"ffd5c990b48126c0db16edcd055cb284ffebcf03\", \"gin_unittests\": \"476fa8a0b5cf0dbead2f0ee3f6527809cb1efe1b\", \"google_apis_unittests\": \"1272b6aea7631180236823f48b7360955029016e\", \"gpu_unittests\": \"f56998f089d8af369f98dc848a813fc94a1162cb\", \"grit_python_unittests\": \"c949091fb2717b913781a9a7cb15efe1537c4608\", \"gwp_asan_unittests\": \"60b5d05d48c97061aa5aa0d7a26961a3c3b584a3\", \"headless_browsertests\": \"47b06ac3435853c8913f63586ee6402aa48c794c\", \"headless_unittests\": \"67e07457be08e2e2cbdcbeb3203e7275c6425567\", \"interactive_ui_tests\": \"8189946fee471e341031419620f219ee9a9da121\", \"ipc_tests\": \"52942d588d36846eb4d3b886846140d673c3e9fd\", \"jingle_unittests\": \"c3daef94c3dadfb1a26128e87e3e664cbcf64bd1\", \"latency_unittests\": \"66eab6ef08f526ff7e30f951a0cebfd8e21d7f29\", \"libjingle_xmpp_unittests\": \"c60d19931e15d785ddbb314e2fcedf7936e7de27\", \"media_blink_unittests\": \"73e7baa828ad1e9461e6ee167770de20662ad5d1\", \"media_service_unittests\": \"1b0860b1b46a8a67d2603e613a6a499f0bc526ee\", \"media_unittests\": \"55de06a7116be1077dc9ac01f717bd6135ecf2c6\", \"message_center_unittests\": \"1a7aa72feb1bc2c23fc0383a9212cfb50970515a\", \"metrics_python_tests\": \"3613463d64c471564e0509484a97dcaf55b9a763\", \"midi_unittests\": \"053170e32c175adaf08acf099b7a973127b4c576\", \"mojo_core_unittests\": \"c3e80e22cfa187f36a596db77010f40b13862aa7\", \"mojo_unittests\": \"4d25ed4bab961cd8cf51f8fd333aa289bd4aba08\", \"nacl_helper_nonsfi_unittests\": \"ed962444207e6c4521190ca79f0f2e1e6452fa42\", \"nacl_loader_unittests\": \"df8152ae932154bc30501a81c5478b359d61c898\", \"native_theme_unittests\": \"c1bb01eb55f36f905ac79def897b1a40b0c30316\", \"net_unittests\": \"3d593784c9361f175b935d53a6a510136b417783\", \"pdf_unittests\": \"43b91839bb87104431883a450fe8a82a71a9ade3\", \"perfetto_unittests\": \"202cbdf6f65ca953258d1b6bc4e26eab3d6c2abf\", \"ppapi_unittests\": \"c7f1cf0e813eb483dd4e1e6859c420d27c1173df\", \"printing_unittests\": \"ebb576fe053f708f4232fe0b3be9b101eb485dd3\", \"remoting_unittests\": \"972db0ad1950c646cfd943f6d01c8184dfbe70b7\", \"sandbox_linux_unittests\": \"0906aa1b8f3342ff38350a8b5a387b46fce8dd03\", \"service_manager_unittests\": \"8d75099bd883c42c692120e5bd06e8863b8f0c2b\", \"services_unittests\": \"966641d93a439c3ae00901b6e9f90dd4af506443\", \"shell_dialogs_unittests\": \"2d481fb03e1c1be9d51b0bd3d7ea025d159d2ef7\", \"skia_unittests\": \"48016b2f3442a94af9cf7e13aa0a252658d5d264\", \"snapshot_unittests\": \"c0f42e294ae2db493863ca473148f663f6225ae6\", \"sql_unittests\": \"962a576a4aae12ca52fbad300baa58e8ad7f559b\", \"storage_unittests\": \"d8b9058bf6925fb8780b0a16a996bb0ac021736e\", \"sync_integration_tests\": \"5c610f63643806440fcec4d1643f9e723e9b5357\", \"telemetry_gpu_unittests\": \"d7a2203225dad9443f07f32dc9c627b393c49b2a\", \"telemetry_perf_unittests\": \"7ba4471d525605d30fd892f1096d36addcf98da3\", \"telemetry_unittests\": \"ec9b098ff3832230c4735de162d47739d1ba87cd\", \"traffic_annotation_auditor_unittests\": \"41d56ff9df0df23c5df40c12b92817bf357cceeb\", \"ui_base_unittests\": \"fedf9398987a6f4761fbb8c54c1d023e1354a96b\", \"ui_touch_selection_unittests\": \"250e52d5b01323aba5199282184f991aaff3786b\", \"unit_tests\": \"ec8f7cdb15e8aebe8b35852455082c0d1f8da36c\", \"url_unittests\": \"4a5a2bb7ab629d493767910bd94693b0e1196734\", \"views_perftests\": \"27a17dec2a1ce65c843ecf7603b2db47f4d0c590\", \"views_unittests\": \"c09ec1efcec2c6e0fd1f99ccc36e61f70a4a3090\", \"viz_unittests\": \"3febee38fa6c1b24e2cafb244cf5bc3d0eba20ed\", \"vr_common_unittests\": \"225af45f77b6fb75ca2278112db8a86d5c36b6ec\", \"vr_pixeltests\": \"abb4e1355dc26b4db4768df00c71ac2f17967646\", \"webdriver_wpt_tests\": \"ed6c564694c9bad1f9d8d8d3cfe28b9b0bba8a62\", \"weblayer_browsertests\": \"e05d574f57bed4a4a8583a5f37fb8c0581743ccf\", \"wm_unittests\": \"bb27f5a7d1a3569ea3cf97c2509387f47fce75fb\", \"wtf_unittests\": \"c84fbcbd5331aecee1ef7f3f959acff46d8ce8be\", \"xr_browser_tests\": \"9188f9cf578386caec2b08de0dac31be2d815925\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1f6efd1a63ab00e2ded981a0276806cd2aaf7978081f3ef44b6752422059bb79_v2\", \"builder_3b0e28eebaae607a111b3d14afaf4a38944aa5084a33cb6cff32b5c412399a3d_v2\", \"builder_497278b5819f10111ab639898a894ad19d361b24d185228ca986cf767de7a7ef_v2\", \"builder_69f5bab4a885266166e099964d6d1498f6d3761c92bc1d5b083a29cb822fe90c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-22-yxzf\"], \"image\": [\"chrome-xenial-19120100-f3a09baa543\"], \"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\": [\"4717-c661281\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576138273028381",
"status": "COMPLETED",
"status_changed_ts": "1576141701959320",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165988",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ae9fa18b1d74e686027470ab2378fc349cfd14e3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae9fa18b1d74e686027470ab2378fc349cfd14e3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059723315146924304",
"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/8894293252918571792/+/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:4911f6bc07599410",
"user_agent:luci-scheduler"
],
"updated_ts": "1576141701963559",
"url": "https://ci.chromium.org/b/8894293252918571792",
"utcnow_ts": "1576141702506161"
}
}
[09:08:22.519] Invocation finished in 1h1m59.71373963s with status SUCCEEDED