[02:55:35.574] New invocation is queued and will start shortly
[02:55:36.890] Starting the invocation (attempt 1)
[02:55:36.927] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:55:36.927] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:55:36.927] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052585707861098928",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5cad4d74161453f81409bb0dcf51bc63a3511578\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052585707861098928",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5cad4d74161453f81409bb0dcf51bc63a3511578",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cad4d74161453f81409bb0dcf51bc63a3511578",
"gitiles_ref:refs/heads/master"
]
}
[02:55:38.074] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582944937007687",
"id": "8887155645690684480",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5cad4d74161453f81409bb0dcf51bc63a3511578\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582944937714909",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169157",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5cad4d74161453f81409bb0dcf51bc63a3511578",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cad4d74161453f81409bb0dcf51bc63a3511578",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052585707861098928",
"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/8887155645690684480/+/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": "1582944937715096",
"url": "https://ci.chromium.org/b/8887155645690684480",
"utcnow_ts": "1582944938048516"
}
}
[02:55:38.074] Task URL: https://ci.chromium.org/b/8887155645690684480
[02:55:38.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:2:0) after 1m0s
[02:56:38.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:2:0)
[02:56:38.116] Timer tick, asking Buildbucket for the build status
[02:56:38.225] Build 8887155645690684480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:38.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:3:0) after 1m0s
[02:57:38.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:3:0)
[02:57:38.262] Timer tick, asking Buildbucket for the build status
[02:57:38.369] Build 8887155645690684480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:38.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:4:0) after 1m0s
[02:58:38.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:4:0)
[02:58:38.370] Timer tick, asking Buildbucket for the build status
[02:58:38.831] Build 8887155645690684480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:38.831] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:5:0) after 1m0s
[02:59:38.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:5:0)
[02:59:38.856] Timer tick, asking Buildbucket for the build status
[02:59:39.126] Build 8887155645690684480: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:39.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:6:0) after 1m0s
[03:00:03.043] Received PubSub notification, asking Buildbucket for the build status
[03:00:03.201] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:39.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:6:0)
[03:00:39.147] Timer tick, asking Buildbucket for the build status
[03:00:39.244] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:39.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:8:0) after 1m0s
[03:01:39.272] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:8:0)
[03:01:39.272] Timer tick, asking Buildbucket for the build status
[03:01:39.405] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:39.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:9:0) after 1m0s
[03:02:39.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:9:0)
[03:02:39.542] Timer tick, asking Buildbucket for the build status
[03:02:39.607] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:39.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:10:0) after 1m0s
[03:03:39.638] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:10:0)
[03:03:39.638] Timer tick, asking Buildbucket for the build status
[03:03:40.810] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:40.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:11:0) after 1m0s
[03:04:40.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:11:0)
[03:04:40.829] Timer tick, asking Buildbucket for the build status
[03:04:41.256] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:41.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:12:0) after 1m0s
[03:05:41.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:12:0)
[03:05:41.286] Timer tick, asking Buildbucket for the build status
[03:05:41.444] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:41.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:13:0) after 1m0s
[03:06:41.509] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:13:0)
[03:06:41.509] Timer tick, asking Buildbucket for the build status
[03:06:41.745] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:41.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:14:0) after 1m0s
[03:07:41.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:14:0)
[03:07:41.825] Timer tick, asking Buildbucket for the build status
[03:07:41.943] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:41.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:15:0) after 1m0s
[03:08:41.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:15:0)
[03:08:41.966] Timer tick, asking Buildbucket for the build status
[03:08:42.496] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:42.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:16:0) after 1m0s
[03:09:42.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:16:0)
[03:09:42.513] Timer tick, asking Buildbucket for the build status
[03:09:42.686] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:42.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:17:0) after 1m0s
[03:10:42.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:17:0)
[03:10:42.708] Timer tick, asking Buildbucket for the build status
[03:10:42.863] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:42.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:18:0) after 1m0s
[03:11:42.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:18:0)
[03:11:42.889] Timer tick, asking Buildbucket for the build status
[03:11:43.011] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:43.011] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:19:0) after 1m0s
[03:12:43.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:19:0)
[03:12:43.068] Timer tick, asking Buildbucket for the build status
[03:12:43.205] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:43.206] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:20:0) after 1m0s
[03:13:43.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:20:0)
[03:13:43.242] Timer tick, asking Buildbucket for the build status
[03:13:43.429] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:43.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:21:0) after 1m0s
[03:14:43.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:21:0)
[03:14:43.441] Timer tick, asking Buildbucket for the build status
[03:14:43.531] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:43.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:22:0) after 1m0s
[03:15:43.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:22:0)
[03:15:43.675] Timer tick, asking Buildbucket for the build status
[03:15:43.783] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:43.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:23:0) after 1m0s
[03:16:43.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:23:0)
[03:16:43.967] Timer tick, asking Buildbucket for the build status
[03:16:44.119] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:44.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:24:0) after 1m0s
[03:17:44.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:24:0)
[03:17:44.189] Timer tick, asking Buildbucket for the build status
[03:17:44.292] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:44.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:25:0) after 1m0s
[03:18:44.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:25:0)
[03:18:44.317] Timer tick, asking Buildbucket for the build status
[03:18:45.049] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:45.050] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:26:0) after 1m0s
[03:19:45.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:26:0)
[03:19:45.064] Timer tick, asking Buildbucket for the build status
[03:19:45.196] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:45.196] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:27:0) after 1m0s
[03:20:45.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:27:0)
[03:20:45.252] Timer tick, asking Buildbucket for the build status
[03:20:45.580] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:45.580] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:28:0) after 1m0s
[03:21:45.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:28:0)
[03:21:45.606] Timer tick, asking Buildbucket for the build status
[03:21:45.751] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:45.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:29:0) after 1m0s
[03:22:45.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:29:0)
[03:22:45.868] Timer tick, asking Buildbucket for the build status
[03:22:46.016] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:46.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:30:0) after 1m0s
[03:23:46.492] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:30:0)
[03:23:46.492] Timer tick, asking Buildbucket for the build status
[03:23:46.638] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:46.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:31:0) after 1m0s
[03:24:46.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:31:0)
[03:24:46.726] Timer tick, asking Buildbucket for the build status
[03:24:46.913] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:46.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:32:0) after 1m0s
[03:25:47.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:32:0)
[03:25:47.035] Timer tick, asking Buildbucket for the build status
[03:25:47.185] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:47.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:33:0) after 1m0s
[03:26:47.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:33:0)
[03:26:47.294] Timer tick, asking Buildbucket for the build status
[03:26:47.414] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:47.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:34:0) after 1m0s
[03:27:47.439] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:34:0)
[03:27:47.439] Timer tick, asking Buildbucket for the build status
[03:27:47.541] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:47.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:35:0) after 1m0s
[03:28:47.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:35:0)
[03:28:47.592] Timer tick, asking Buildbucket for the build status
[03:28:48.302] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:48.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:36:0) after 1m0s
[03:29:48.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:36:0)
[03:29:48.382] Timer tick, asking Buildbucket for the build status
[03:29:48.536] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:48.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:37:0) after 1m0s
[03:30:48.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:37:0)
[03:30:48.568] Timer tick, asking Buildbucket for the build status
[03:30:48.664] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:48.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:38:0) after 1m0s
[03:31:48.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:38:0)
[03:31:48.713] Timer tick, asking Buildbucket for the build status
[03:31:49.577] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:49.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:39:0) after 1m0s
[03:32:49.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:39:0)
[03:32:49.887] Timer tick, asking Buildbucket for the build status
[03:32:50.141] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:50.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:40:0) after 1m0s
[03:33:50.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:40:0)
[03:33:50.182] Timer tick, asking Buildbucket for the build status
[03:33:50.305] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:50.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:41:0) after 1m0s
[03:34:50.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:41:0)
[03:34:50.342] Timer tick, asking Buildbucket for the build status
[03:34:50.530] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:50.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:42:0) after 1m0s
[03:35:50.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:42:0)
[03:35:50.600] Timer tick, asking Buildbucket for the build status
[03:35:50.707] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:50.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:43:0) after 1m0s
[03:36:50.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:43:0)
[03:36:50.727] Timer tick, asking Buildbucket for the build status
[03:36:50.895] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:50.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:44:0) after 1m0s
[03:37:50.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:44:0)
[03:37:50.992] Timer tick, asking Buildbucket for the build status
[03:37:51.092] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:51.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:45:0) after 1m0s
[03:38:51.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:45:0)
[03:38:51.130] Timer tick, asking Buildbucket for the build status
[03:38:51.345] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:51.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:46:0) after 1m0s
[03:39:51.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:46:0)
[03:39:51.371] Timer tick, asking Buildbucket for the build status
[03:39:51.508] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:51.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:47:0) after 1m0s
[03:40:51.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:47:0)
[03:40:51.531] Timer tick, asking Buildbucket for the build status
[03:40:51.673] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:51.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:48:0) after 1m0s
[03:41:51.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:48:0)
[03:41:51.749] Timer tick, asking Buildbucket for the build status
[03:41:51.925] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:51.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:49:0) after 1m0s
[03:42:52.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:49:0)
[03:42:52.004] Timer tick, asking Buildbucket for the build status
[03:42:52.109] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:52.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:50:0) after 1m0s
[03:43:52.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:50:0)
[03:43:52.201] Timer tick, asking Buildbucket for the build status
[03:43:52.637] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:52.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:51:0) after 1m0s
[03:44:52.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:51:0)
[03:44:52.640] Timer tick, asking Buildbucket for the build status
[03:44:52.758] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:52.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:52:0) after 1m0s
[03:45:52.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:52:0)
[03:45:52.837] Timer tick, asking Buildbucket for the build status
[03:45:52.951] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:52.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:53:0) after 1m0s
[03:46:52.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:53:0)
[03:46:52.930] Timer tick, asking Buildbucket for the build status
[03:46:53.183] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:53.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:54:0) after 1m0s
[03:47:53.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:54:0)
[03:47:53.322] Timer tick, asking Buildbucket for the build status
[03:47:53.474] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:53.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:55:0) after 1m0s
[03:48:53.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:55:0)
[03:48:53.524] Timer tick, asking Buildbucket for the build status
[03:48:53.651] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:53.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:56:0) after 1m0s
[03:49:53.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:56:0)
[03:49:53.822] Timer tick, asking Buildbucket for the build status
[03:49:55.865] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:55.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:57:0) after 1m0s
[03:50:55.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:57:0)
[03:50:55.966] Timer tick, asking Buildbucket for the build status
[03:50:56.249] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:56.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:58:0) after 1m0s
[03:51:56.319] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:58:0)
[03:51:56.319] Timer tick, asking Buildbucket for the build status
[03:51:56.544] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:56.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:59:0) after 1m0s
[03:52:56.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:59:0)
[03:52:56.582] Timer tick, asking Buildbucket for the build status
[03:52:56.696] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:56.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:60:0) after 1m0s
[03:53:56.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:60:0)
[03:53:56.738] Timer tick, asking Buildbucket for the build status
[03:53:56.889] Build 8887155645690684480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:56.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052585707861098928:61:0) after 1m0s
[03:54:42.234] Received PubSub notification, asking Buildbucket for the build status
[03:54:42.326] Build 8887155645690684480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:54:42.326] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582948481161140",
"created_by": "project:chromium",
"created_ts": "1582944937007687",
"id": "8887155645690684480",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5cad4d74161453f81409bb0dcf51bc63a3511578\"}}",
"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-89-esnr\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169157, \"got_angle_revision\": \"54bc3dab2064ef775eeb3701b58cae02e187088f\", \"got_dawn_revision\": \"3ded65e8072e0cdccd0d0edebccffde37d4428b0\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"5cad4d74161453f81409bb0dcf51bc63a3511578\", \"got_revision_cp\": \"refs/heads/master@{#745732}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"416d374522757fc042282b3ae61c53f5510421fc\", \"got_v8_revision\": \"5260234d46ef0a981e417cef45efcab3d09a05c8\", \"got_v8_revision_cp\": \"refs/heads/8.2.220@{#1}\", \"got_webrtc_revision\": \"a08b2384a3245082cd40e8682676252ebebb1fe8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30652}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5cad4d74161453f81409bb0dcf51bc63a3511578\", \"swarm_hashes_refs/heads/master(at){#745732}_without_patch\": {\"accessibility_unittests\": \"01ac24a09369ac34738a5f71e8e5b99175b7bc88\", \"angle_unittests\": \"b56c2cb2458a9ec7f54859f27c469b734742083b\", \"app_shell_unittests\": \"43a40d88978620320b8a1e09d43b89b0049dbe45\", \"aura_unittests\": \"6a63594e16196644491c450f7d599eb8c9e0e363\", \"base_unittests\": \"945122ad2c477e1f67b8ac64d0432179fd465bb5\", \"base_util_unittests\": \"5db992cfc3da615e5dfc90747309908db29dd90d\", \"blink_common_unittests\": \"8b769d17b16123e7d60393c05140e5e8865cb152\", \"blink_fuzzer_unittests\": \"409d4072933fa07ad9c9cf0663d171881f447368\", \"blink_heap_unittests\": \"d7c868ce10d26ee17216307147c6228bf339ad7e\", \"blink_platform_unittests\": \"3997021acf8ed5c68b42c0f2b0763e33515ab4cd\", \"blink_python_tests\": \"c6128b0514ce909723dbf66b6b52e84628e859b4\", \"blink_unittests\": \"3f54946d56f5ba18950097e5bbcc25c8d517fb32\", \"blink_web_tests\": \"72fb35c8bb3f20f739cbad41714f1dfd4a9d66b9\", \"boringssl_crypto_tests\": \"9ba3935b936699e8259db7a6e829df0a7d4c758b\", \"boringssl_ssl_tests\": \"7fabd142474678e51d0aa4b0ba70ace12e11caf4\", \"browser_tests\": \"d3d8f46a3996389dbcc63bd107617f471e179e26\", \"cacheinvalidation_unittests\": \"e01161b3d27d17bfda7499747d8ee7cc898b6b02\", \"capture_unittests\": \"785960b400fb0a85ad543a119e8aa0ffdd1abfc0\", \"cast_unittests\": \"bb0665cccec5d44b7261e0c3d3343ca31b0dec8d\", \"cc_unittests\": \"0a1aa5b9f387372d9344af53595ed6c3fbcace10\", \"chrome_app_unittests\": \"e8b4823f63bbc4edc5a0d4dee7e4fa4a5048336f\", \"chromedriver_unittests\": \"7163ad0a31ea884114e3508a2fa4ed74bea21f4f\", \"components_browsertests\": \"83790def5f54fdc8b6376648f316e6e8f5b30a7e\", \"components_unittests\": \"f12134810d4e85c1407f59d6eefa85aaab71639f\", \"compositor_unittests\": \"45fb5370f2cb0fdc0b3277c2b151fc43a5402d66\", \"content_browsertests\": \"76e17ee4413956a3086e4f75d5089253f1b7c2ba\", \"content_shell_crash_test\": \"7856627d3cf914765adcb4181e82f1e3a230d625\", \"content_unittests\": \"ec9f4b51dfdd999e33264aeddf1ff8e06e929c46\", \"crashpad_tests\": \"b444654862aa725cdf154bba3b78c39415dfd3ca\", \"cronet_tests\": \"726c649fdb7be82b7aaf6f0e8c94742b430b930d\", \"cronet_unittests\": \"98b2f5dc9f667e5712957005416b7991e12ceca3\", \"crypto_unittests\": \"1e42ff829d2bf5b8a5020aa39236bdc774c00c1a\", \"dbus_unittests\": \"500e8cf8b7fd143852bca78e4b4a16b138809b34\", \"device_unittests\": \"248c83b4a4d0a6d538331cbe391a1432f3897a0f\", \"display_unittests\": \"fabd8e823b39835788614aa7629c859379226745\", \"events_unittests\": \"252d7b62fe57a2d70fb51edac9010f79f26bdef8\", \"extensions_browsertests\": \"eb13eecd3135f5b890bd368f467fa59c6c783a66\", \"extensions_unittests\": \"3ecd561933faa387d505d640571fe6545c55daab\", \"filesystem_service_unittests\": \"ebf41fbf99e1c96555e9bf55f5edcc4f7a8d97b3\", \"flatbuffers_unittests\": \"9b972b84314b0e944fad99f4c5be3f50c351ec0c\", \"gcm_unit_tests\": \"699a9759cd35140cfe6a9d269d9b4cc0c913320f\", \"gfx_unittests\": \"1cff32c42af6eeb0360c6ebe65ed0ab249e62b63\", \"gin_unittests\": \"e0f5dfd36ab033960a3ddfb08c05fa45d10c4480\", \"google_apis_unittests\": \"b70a4aebad6a0148f1372fd9865a3ef8dcfb37e1\", \"gpu_unittests\": \"a431debb85899ab69b5bc182cae09d6770352720\", \"grit_python_unittests\": \"8d3af1ca6ce7611b3ef6d2516a78cd61f915c28b\", \"gwp_asan_unittests\": \"4de124633548f442cb6785126644f2100d002951\", \"headless_browsertests\": \"b5aac923bedd6ac98b729d8df929279928b60376\", \"headless_unittests\": \"fc850a6971bcbf53b285a72ff8be74f5ae65ac1d\", \"interactive_ui_tests\": \"5eb6347f3cf78ed69a0fc10a6f0d73f232bf1b47\", \"ipc_tests\": \"e13385a6ad3d01495280e3bd637151ec6aee4f7f\", \"jingle_unittests\": \"bf4bcccdabc1b5e6d2afb59fefc463307c1f594c\", \"latency_unittests\": \"dc47bae969d93089b8e17e1cec98c17ba916148e\", \"libjingle_xmpp_unittests\": \"e8a9fe24975c26a45303e11cc0ff1ebffedb1522\", \"media_blink_unittests\": \"cfa156471732d7b6e0b054f8d100a13fc38162a2\", \"media_service_unittests\": \"233ea885ce6eb91a561e7aba367b42215e121d5d\", \"media_unittests\": \"5f2726709cef484473b65570676fdd73dbf93a04\", \"message_center_unittests\": \"34eb89f2b93fc3282176c6c929eaaace6435306e\", \"metrics_python_tests\": \"a69a7e25bf1312d9106ff6da83de505252ee1f54\", \"midi_unittests\": \"55aba00afb9be6d442305fad10a0616e655a0318\", \"mojo_core_unittests\": \"9b1d2b0f1429a94c1e4f407b0df8bcdb5e969235\", \"mojo_unittests\": \"ed66dac27877244810bfb69cc76168665eeada2c\", \"nacl_helper_nonsfi_unittests\": \"a2b618f2e9fd05f2d714e5c94e74c5384e1bd449\", \"nacl_loader_unittests\": \"4132c819a5764c0787eac036391799d8a00d05d5\", \"native_theme_unittests\": \"2c3c8bd2184b49832ef9b70c8eda600dac6eaabd\", \"net_unittests\": \"0a853ed5a4420650616fb3b68e95c678c447dda8\", \"pdf_unittests\": \"faaba882a8b6eb3d658b0f35f9990ecc77edbec2\", \"perfetto_unittests\": \"930dd7ac39d9991551953b88c3965e81f79eeb3c\", \"ppapi_unittests\": \"31c155b130d9faeec6e42f310da666914f0f94c0\", \"printing_unittests\": \"dbcba0ef6c42b71fb9985f76f4963165f7ac39a6\", \"remoting_unittests\": \"ae472a84dc08d720e2527fbb65c9ebb7dc59154c\", \"sandbox_linux_unittests\": \"bc8a8a6b149b8a48728cfd16042c0b14bbf56332\", \"service_manager_unittests\": \"d3920ec1ead50771ebe25e7df9726b00b4099a3f\", \"services_unittests\": \"e5579399135f4f4df1fcf5d4309a834a5f940f4d\", \"shell_dialogs_unittests\": \"18c6b023f8172d82aa270c031da326169ac1d0e8\", \"skia_unittests\": \"4465789db72a90e733ea33abb2ba3ca891960009\", \"snapshot_unittests\": \"13aa2e3c320945790348784d8db2e0cd869d9195\", \"sql_unittests\": \"dec8fb31cd596c63b58522b8b29bec19cde96c1e\", \"storage_unittests\": \"1c1a8408799e7415da2d1bfe8b3e6f300462866b\", \"sync_integration_tests\": \"3fda2dac07f5113dbb1b1f30c16895dc5608de4b\", \"telemetry_gpu_unittests\": \"c670630173d32ededf40665826076fbca382606a\", \"telemetry_perf_unittests\": \"ae7778105f87da8e537348ec22e45920f16bb444\", \"telemetry_unittests\": \"25403dacbd10b289bbe2e9f6aa1d9f0621b3a6b2\", \"traffic_annotation_auditor_unittests\": \"f9c906c57e3c23afda4431f2c2f68227a665f9ce\", \"ui_base_unittests\": \"628fc0a54b3fd850c41f33eeef1215df8838e226\", \"ui_touch_selection_unittests\": \"8b3c88c39b5bbff0c7d7e5eecbff4bf7439f3d85\", \"unit_tests\": \"ca26ce330a7dec6a579b7c2f0dc8b690966cb556\", \"url_unittests\": \"d06de6ba80cdb0b2ef05ec4ef62a8dca3251e1cd\", \"views_perftests\": \"03f4003f8b7ba71b1acb5911c1ad069ab39a905a\", \"views_unittests\": \"24c2192f3c062b5c8eea5e1ccbda65b4a963620c\", \"viz_unittests\": \"2e35b33f9718c108f9d9f32480555f84e4a1b8bd\", \"vr_common_unittests\": \"961423b8bb507d3f74a9135ddc89537802c9a250\", \"vr_pixeltests\": \"0b3b631c20f238cbdb1615670906de8d8fff7fc2\", \"webdriver_wpt_tests\": \"a76704ad2332f1e8022a565a44310007a252e6f9\", \"weblayer_browsertests\": \"00ed5d6a90e648cfd278a9fde4c092069b63b740\", \"wm_unittests\": \"7c8f9e860d2d41b44fe9bf2eda67c54921272e8f\", \"wtf_unittests\": \"f7afcdbca74960ab698d9dcc5844298f00b6c408\", \"xr_browser_tests\": \"03048fae37cee53c747efb6ba163f717c29c9f67\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0f0f2920b57dc7c027977ee87520a3ddbf7f87ddb2741f9160320f85fd7d4389_v2\", \"builder_151f80801b9f29276c11c5f05dab27ce5673f9c9ba442aaf2cf20aab9215f522_v2\", \"builder_2b25d58305a6755482534765a65f2521477c19af0731994631a8cf25b9f3b571_v2\", \"builder_7e09b81c8d06479eaec2630704ae544ecbf4fef920ed2a3aec656c848d7a04d5_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_f568157a4ec00fb9568da05a054c670841713183d1f68e80f18d1b79a7a19e1f_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-89-esnr\"], \"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": "1582945202110734",
"status": "COMPLETED",
"status_changed_ts": "1582948481851181",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169157",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5cad4d74161453f81409bb0dcf51bc63a3511578",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5cad4d74161453f81409bb0dcf51bc63a3511578",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052585707861098928",
"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/8887155645690684480/+/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:4aa7b07025e3ba10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582948481907193",
"url": "https://ci.chromium.org/b/8887155645690684480",
"utcnow_ts": "1582948482319162"
}
}
[03:54:42.326] Invocation finished in 59m6.772189809s with status SUCCEEDED