[11:28:06.212] New invocation is queued and will start shortly
[11:28:07.341] Starting the invocation (attempt 1)
[11:28:07.385] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:28:07.386] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:28:07.386] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056177342134010048",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b03536eb5ebac7ef8e81d41a5496686173445c00\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056177342134010048",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b03536eb5ebac7ef8e81d41a5496686173445c00",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b03536eb5ebac7ef8e81d41a5496686173445c00",
"gitiles_ref:refs/heads/master"
]
}
[11:28:08.291] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579519687586263",
"id": "8890747280028523552",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b03536eb5ebac7ef8e81d41a5496686173445c00\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579519688028114",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167588",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b03536eb5ebac7ef8e81d41a5496686173445c00",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b03536eb5ebac7ef8e81d41a5496686173445c00",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056177342134010048",
"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/8890747280028523552/+/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": "1579519688028248",
"url": "https://ci.chromium.org/b/8890747280028523552",
"utcnow_ts": "1579519688277672"
}
}
[11:28:08.291] Task URL: https://ci.chromium.org/b/8890747280028523552
[11:28:08.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:2:0) after 1m0s
[11:29:08.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:2:0)
[11:29:08.388] Timer tick, asking Buildbucket for the build status
[11:29:08.553] Build 8890747280028523552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:29:08.553] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:3:0) after 1m0s
[11:30:08.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:3:0)
[11:30:08.583] Timer tick, asking Buildbucket for the build status
[11:30:10.729] Build 8890747280028523552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:10.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:4:0) after 1m0s
[11:31:10.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:4:0)
[11:31:10.775] Timer tick, asking Buildbucket for the build status
[11:31:11.126] Build 8890747280028523552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:11.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:5:0) after 1m0s
[11:32:11.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:5:0)
[11:32:11.124] Timer tick, asking Buildbucket for the build status
[11:32:11.262] Build 8890747280028523552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:11.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:6:0) after 1m0s
[11:33:02.397] Received PubSub notification, asking Buildbucket for the build status
[11:33:02.769] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:11.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:6:0)
[11:33:11.323] Timer tick, asking Buildbucket for the build status
[11:33:11.517] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:11.517] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:8:0) after 1m0s
[11:34:11.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:8:0)
[11:34:11.582] Timer tick, asking Buildbucket for the build status
[11:34:12.235] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:12.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:9:0) after 1m0s
[11:35:12.451] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:9:0)
[11:35:12.451] Timer tick, asking Buildbucket for the build status
[11:35:12.692] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:12.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:10:0) after 1m0s
[11:36:12.789] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:10:0)
[11:36:12.789] Timer tick, asking Buildbucket for the build status
[11:36:13.037] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:13.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:11:0) after 1m0s
[11:37:13.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:11:0)
[11:37:13.116] Timer tick, asking Buildbucket for the build status
[11:37:13.278] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:13.278] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:12:0) after 1m0s
[11:38:13.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:12:0)
[11:38:13.285] Timer tick, asking Buildbucket for the build status
[11:38:13.438] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:13.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:13:0) after 1m0s
[11:39:13.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:13:0)
[11:39:13.522] Timer tick, asking Buildbucket for the build status
[11:39:13.710] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:13.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:14:0) after 1m0s
[11:40:13.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:14:0)
[11:40:13.702] Timer tick, asking Buildbucket for the build status
[11:40:14.231] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:14.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:15:0) after 1m0s
[11:41:14.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:15:0)
[11:41:14.309] Timer tick, asking Buildbucket for the build status
[11:41:14.565] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:14.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:16:0) after 1m0s
[11:42:14.581] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:16:0)
[11:42:14.581] Timer tick, asking Buildbucket for the build status
[11:42:14.913] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:14.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:17:0) after 1m0s
[11:43:14.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:17:0)
[11:43:14.938] Timer tick, asking Buildbucket for the build status
[11:43:15.591] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:15.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:18:0) after 1m0s
[11:44:15.634] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:18:0)
[11:44:15.634] Timer tick, asking Buildbucket for the build status
[11:44:15.799] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:15.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:19:0) after 1m0s
[11:45:15.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:19:0)
[11:45:15.820] Timer tick, asking Buildbucket for the build status
[11:45:16.095] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:16.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:20:0) after 1m0s
[11:46:16.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:20:0)
[11:46:16.159] Timer tick, asking Buildbucket for the build status
[11:46:16.507] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:16.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:21:0) after 1m0s
[11:47:16.527] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:21:0)
[11:47:16.527] Timer tick, asking Buildbucket for the build status
[11:47:16.690] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:16.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:22:0) after 1m0s
[11:48:16.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:22:0)
[11:48:16.811] Timer tick, asking Buildbucket for the build status
[11:48:17.060] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:17.060] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:23:0) after 1m0s
[11:49:17.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:23:0)
[11:49:17.058] Timer tick, asking Buildbucket for the build status
[11:49:17.231] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:17.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:24:0) after 1m0s
[11:50:17.239] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:24:0)
[11:50:17.239] Timer tick, asking Buildbucket for the build status
[11:50:17.414] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:17.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:25:0) after 1m0s
[11:51:17.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:25:0)
[11:51:17.505] Timer tick, asking Buildbucket for the build status
[11:51:17.642] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:17.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:26:0) after 1m0s
[11:52:17.688] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:26:0)
[11:52:17.688] Timer tick, asking Buildbucket for the build status
[11:52:17.801] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:17.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:27:0) after 1m0s
[11:53:17.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:27:0)
[11:53:17.955] Timer tick, asking Buildbucket for the build status
[11:53:18.236] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:18.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:28:0) after 1m0s
[11:54:18.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:28:0)
[11:54:18.314] Timer tick, asking Buildbucket for the build status
[11:54:19.186] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:19.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:29:0) after 1m0s
[11:55:19.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:29:0)
[11:55:19.343] Timer tick, asking Buildbucket for the build status
[11:55:19.593] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:19.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:30:0) after 1m0s
[11:56:19.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:30:0)
[11:56:19.719] Timer tick, asking Buildbucket for the build status
[11:56:19.856] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:19.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:31:0) after 1m0s
[11:57:20.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:31:0)
[11:57:20.113] Timer tick, asking Buildbucket for the build status
[11:57:20.317] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:20.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:32:0) after 1m0s
[11:58:20.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:32:0)
[11:58:20.335] Timer tick, asking Buildbucket for the build status
[11:58:20.885] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:20.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:33:0) after 1m0s
[11:59:20.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:33:0)
[11:59:20.965] Timer tick, asking Buildbucket for the build status
[11:59:21.164] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:21.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:34:0) after 1m0s
[12:00:21.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:34:0)
[12:00:21.397] Timer tick, asking Buildbucket for the build status
[12:00:21.678] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:21.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:35:0) after 1m0s
[12:01:22.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:35:0)
[12:01:22.539] Timer tick, asking Buildbucket for the build status
[12:01:23.615] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:23.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:36:0) after 1m0s
[12:02:23.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:36:0)
[12:02:23.655] Timer tick, asking Buildbucket for the build status
[12:02:24.151] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:24.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:37:0) after 1m0s
[12:03:24.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:37:0)
[12:03:24.214] Timer tick, asking Buildbucket for the build status
[12:03:24.828] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:24.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:38:0) after 1m0s
[12:04:24.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:38:0)
[12:04:24.942] Timer tick, asking Buildbucket for the build status
[12:04:25.278] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:25.278] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:39:0) after 1m0s
[12:05:25.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:39:0)
[12:05:25.261] Timer tick, asking Buildbucket for the build status
[12:05:25.388] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:25.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:40:0) after 1m0s
[12:06:25.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:40:0)
[12:06:25.435] Timer tick, asking Buildbucket for the build status
[12:06:26.747] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:26.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:41:0) after 1m0s
[12:07:26.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:41:0)
[12:07:26.886] Timer tick, asking Buildbucket for the build status
[12:07:27.219] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:27.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:42:0) after 1m0s
[12:08:27.260] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:42:0)
[12:08:27.260] Timer tick, asking Buildbucket for the build status
[12:08:27.385] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:27.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:43:0) after 1m0s
[12:09:27.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:43:0)
[12:09:27.489] Timer tick, asking Buildbucket for the build status
[12:09:27.702] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:27.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:44:0) after 1m0s
[12:10:27.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:44:0)
[12:10:27.808] Timer tick, asking Buildbucket for the build status
[12:10:27.952] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:27.952] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:45:0) after 1m0s
[12:11:28.010] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:45:0)
[12:11:28.010] Timer tick, asking Buildbucket for the build status
[12:11:28.431] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:28.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:46:0) after 1m0s
[12:12:28.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:46:0)
[12:12:28.469] Timer tick, asking Buildbucket for the build status
[12:12:28.612] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:28.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:47:0) after 1m0s
[12:13:28.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:47:0)
[12:13:28.673] Timer tick, asking Buildbucket for the build status
[12:13:28.864] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:28.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:48:0) after 1m0s
[12:14:29.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:48:0)
[12:14:29.104] Timer tick, asking Buildbucket for the build status
[12:14:29.403] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:29.403] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:49:0) after 1m0s
[12:15:29.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:49:0)
[12:15:29.570] Timer tick, asking Buildbucket for the build status
[12:15:29.777] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:29.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:50:0) after 1m0s
[12:16:29.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:50:0)
[12:16:29.896] Timer tick, asking Buildbucket for the build status
[12:16:30.074] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:30.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:51:0) after 1m0s
[12:17:30.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:51:0)
[12:17:30.103] Timer tick, asking Buildbucket for the build status
[12:17:30.462] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:30.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:52:0) after 1m0s
[12:18:30.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:52:0)
[12:18:30.480] Timer tick, asking Buildbucket for the build status
[12:18:32.789] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:32.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:53:0) after 1m0s
[12:19:32.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:53:0)
[12:19:32.820] Timer tick, asking Buildbucket for the build status
[12:19:33.001] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:33.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:54:0) after 1m0s
[12:20:33.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:54:0)
[12:20:33.182] Timer tick, asking Buildbucket for the build status
[12:20:33.354] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:33.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:55:0) after 1m0s
[12:21:33.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:55:0)
[12:21:33.445] Timer tick, asking Buildbucket for the build status
[12:21:33.578] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:33.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:56:0) after 1m0s
[12:22:33.724] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:56:0)
[12:22:33.724] Timer tick, asking Buildbucket for the build status
[12:22:34.271] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:34.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:57:0) after 1m0s
[12:23:34.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:57:0)
[12:23:34.328] Timer tick, asking Buildbucket for the build status
[12:23:34.586] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:34.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:58:0) after 1m0s
[12:24:34.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:58:0)
[12:24:34.683] Timer tick, asking Buildbucket for the build status
[12:24:34.870] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:34.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:59:0) after 1m0s
[12:25:34.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:59:0)
[12:25:34.965] Timer tick, asking Buildbucket for the build status
[12:25:35.190] Build 8890747280028523552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:35.190] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056177342134010048:60:0) after 1m0s
[12:25:49.301] Received PubSub notification, asking Buildbucket for the build status
[12:25:50.086] Build 8890747280028523552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:25:50.086] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579523147364364",
"created_by": "project:chromium",
"created_ts": "1579519687586263",
"id": "8890747280028523552",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b03536eb5ebac7ef8e81d41a5496686173445c00\"}}",
"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-42-g0bl\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167588, \"got_angle_revision\": \"0aba975494ce1a52aa64985c7d76aa37065b5b8d\", \"got_dawn_revision\": \"d8f8c29bb25a5a1658da37aae05b9b4cd4353640\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"b03536eb5ebac7ef8e81d41a5496686173445c00\", \"got_revision_cp\": \"refs/heads/master@{#733304}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"11cb891a01a2b47cc2135ef19ca18046662476e0\", \"got_v8_revision\": \"abd1a0fc04476bbb27ef2dfda2e444cc1467f5f6\", \"got_v8_revision_cp\": \"refs/heads/8.1.224@{#1}\", \"got_webrtc_revision\": \"52c3688f08ac6466e5cb2f0dcccbd08759d8bfdd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30313}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b03536eb5ebac7ef8e81d41a5496686173445c00\", \"swarm_hashes_refs/heads/master(at){#733304}_without_patch\": {\"accessibility_unittests\": \"57a253d10fd4972a9cbb8935b8a1cd624dde39e4\", \"angle_unittests\": \"39d7b7fee9ccd2ceba2000ade26e6b587047ae7d\", \"app_shell_unittests\": \"58ee67439f59ab0774c9e578a036f14a7aab5922\", \"aura_unittests\": \"bbcc52ed875f707658266ebf0ff4145d3ed7238c\", \"base_unittests\": \"92a57ce822e81a782705e672edefddb6cfe6258c\", \"base_util_unittests\": \"7a6f67a4b2ba8c480340516d530851a286a49d61\", \"blink_common_unittests\": \"af2c5668f91fadde44aac98fb73e8b8672d76075\", \"blink_fuzzer_unittests\": \"141d0a95e7ee8499f5cb720648c84a83d186c00b\", \"blink_heap_unittests\": \"bf96b2126f6b1acb09e02ebada95f49b27ccf96f\", \"blink_platform_unittests\": \"58e0ad101473f3fbc932e4dbb1ea8f79fc10f8a7\", \"blink_python_tests\": \"5640832e88d5dd756af1742993ec539fd5860e5f\", \"blink_unittests\": \"256acb18a2a02812372e0280d8bfe617cd73b511\", \"blink_web_tests\": \"e8009c98b8f66f77ca9b48f166ecf8d29bde9dbc\", \"boringssl_crypto_tests\": \"f610055cab9570b5a1f12e73d6e9a33b73f17c96\", \"boringssl_ssl_tests\": \"e1d29dee410addfec08ddb431737dbab630ad96f\", \"browser_tests\": \"6378a634dded230791605d29ae247083453e1bc4\", \"cacheinvalidation_unittests\": \"a0442f973972013f4c478d4b7422e4a1a3a9e50c\", \"capture_unittests\": \"a950b8f6bf6473616a521717da2fd2a0001e9803\", \"cast_unittests\": \"b1478f9f097f992bf33ff6044303955ebbe12568\", \"cc_unittests\": \"f4fff9c615257949d0175d2f72939c8c05c71147\", \"chrome_app_unittests\": \"285c680c4d0ea77f1c55a9bdfddc75a5e53da9bc\", \"chromedriver_unittests\": \"3eebc862999c1e017ded6514d448ede12659b4aa\", \"components_browsertests\": \"fe006f31de5d3216f9bf6814904df7080412b85d\", \"components_unittests\": \"0e4cbefb07cd77dfd37b5e719d4694cb8ab9d4ad\", \"compositor_unittests\": \"3fe254fccbf20347146a1303996e67d0d9e0ddbe\", \"content_browsertests\": \"28d0c354495230baf0a28bdb74da81b73135d033\", \"content_shell_crash_test\": \"f3043135b777a825af022a1706643318d065c70f\", \"content_unittests\": \"0d7118f71852d37baa889e69a1764fccbe8cfc48\", \"crashpad_tests\": \"3e65ef9aed4ae618c0dab5eb7b73c15afa82e8cc\", \"cronet_tests\": \"cfa6132f06f33abe2a6fba9041b94bcb5d50c49d\", \"cronet_unittests\": \"8f52a2958ba29b3e89b000451f82cbb047c59715\", \"crypto_unittests\": \"2d8735c3917aacdbbf63b57c3e2f00c61fb7a67c\", \"dbus_unittests\": \"8ba20e09619a526053593b9a17d85fa77dc3e23a\", \"device_unittests\": \"7e92325f975b4eaf5da852355f408c625ad422d5\", \"display_unittests\": \"f7dafa0e99fab3395d090b525227d97778cc64d3\", \"events_unittests\": \"74d9a832c7c516f28a27d335aab499cf75298ee2\", \"extensions_browsertests\": \"4f62540b137017c3c1fcf3e616a9be2e43c4b379\", \"extensions_unittests\": \"8919a710c4cfe7989ce1a968be6ecd424a3f76ed\", \"filesystem_service_unittests\": \"c1ccc29013fa889ee930ecc410ed331f043f34dd\", \"flatbuffers_unittests\": \"92c174f535dbe8e15f58589d59dc88efc29a10d4\", \"gcm_unit_tests\": \"67175717abffe9146df636473a147d6d82afeb71\", \"gfx_unittests\": \"23532da4b20097e27a0ca44ee3d9f99c71123f92\", \"gin_unittests\": \"259de30fff2a2e7847f688f4963216134b818292\", \"google_apis_unittests\": \"d545e1630724f746945db8fff411a2dcab1aec55\", \"gpu_unittests\": \"0a4235956eb4f4a5af9d216d06406ccfbd105941\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"bd7613db09eb9067832d19f65107e336edf84cbd\", \"headless_browsertests\": \"e908fc638b344ede5e8ff4b2388d142f7f6e7d27\", \"headless_unittests\": \"cae5a80545b6286e932d9823dd89700efc4dd8ad\", \"interactive_ui_tests\": \"0d1bface26ba64c1218a796309f2ceb98b446e43\", \"ipc_tests\": \"dfd78cb2c680f490a1867d229eff88bb8607b35f\", \"jingle_unittests\": \"45772d1b55b77bf2aeca4a4fa28c6ef51aa6cbcd\", \"latency_unittests\": \"52990a5469f864d57ab280c94fbda93c991c393b\", \"libjingle_xmpp_unittests\": \"0a56de1024189b2822d3d7af746c0e410eeb626b\", \"media_blink_unittests\": \"88b5b873cb23247a0bafd39974837a2668223e5e\", \"media_service_unittests\": \"a0821e790c996f822e8b57f44028821fa6496edb\", \"media_unittests\": \"e0c372007e8ea43d26b3fb7c2c8b317efecfb424\", \"message_center_unittests\": \"1926b7444d714782277a9ce5a4dfb5d77a031569\", \"metrics_python_tests\": \"e4f1700a9e3b7f6f7e8bc1ac526a51b1392195ec\", \"midi_unittests\": \"c1766112a44b48f3b198bd975408f99e49fd8611\", \"mojo_core_unittests\": \"4dcbb493c996c325096af2dc7d1ceaea2fac0f4e\", \"mojo_unittests\": \"7ec836fb6dd9be1c4b87fabb29c00bdde4cb4977\", \"nacl_helper_nonsfi_unittests\": \"86af53dc783008d06efe2fa97c71e702f6f642f7\", \"nacl_loader_unittests\": \"824957aae720442bc091394e6c7f73832ad58b20\", \"native_theme_unittests\": \"81220f16c5437353196ff2ca67ebdd44af923116\", \"net_unittests\": \"a7857eae4ad1b919f9e661efa2d7cd9ca2c93f8e\", \"pdf_unittests\": \"80649a811474040f3822469730c6f88511d6f823\", \"perfetto_unittests\": \"18807d51acc019c4ca33923631fd4105c94388fd\", \"ppapi_unittests\": \"20bfdbe8eb12fed177e514bdb86ca4a5735f8839\", \"printing_unittests\": \"50fc732e1b8e347b8c9ac9e6937ba4582ff296f9\", \"remoting_unittests\": \"5b5cd016c95dcba2789f8a00682459e6a8651a98\", \"sandbox_linux_unittests\": \"98110d91c51dc80a56cd74016e612e5902c6ea48\", \"service_manager_unittests\": \"5f56cae4b903227267685cf424ef59b94832095e\", \"services_unittests\": \"d6d971b833b34edf7951bebc093fd6403a91a6c9\", \"shell_dialogs_unittests\": \"22f9d8e0ee34dd7901ddcb654788e8660f12c335\", \"skia_unittests\": \"57a80deec48fcc3412167e97762dc2279b28aa15\", \"snapshot_unittests\": \"a1878c88bf738c6a476bb62bea94259087d90790\", \"sql_unittests\": \"8a6503f5d1b1b452a2a4cc6421007e26279a7765\", \"storage_unittests\": \"44c7fab1103e8dbe2df83e823cb2d33f8462270f\", \"sync_integration_tests\": \"2baad4c9f6567d7fc55a49abb562af8e23ca4ab9\", \"telemetry_gpu_unittests\": \"8a3dbab99b36875896ca160687904ba4be005dbe\", \"telemetry_perf_unittests\": \"f66734800ba4e6560e0c0070ba6e3cddfbd4a9a8\", \"telemetry_unittests\": \"85cf010e830f3ae9dbeb7a73b8ae96dc17fbdf48\", \"traffic_annotation_auditor_unittests\": \"c72f26547edd98d335e0a40f758407a131306155\", \"ui_base_unittests\": \"af1cbc620a5fe1eaf9d9cd9b23d319781133467d\", \"ui_touch_selection_unittests\": \"608a03d68c37c243eb499c2e8b011f33f96380c9\", \"unit_tests\": \"89e08ef40c20b40a4d9de4c9b063959b9ca2c4db\", \"url_unittests\": \"f7c187c89ea40843f69b957ecd66d7572ab9ba3b\", \"views_perftests\": \"590546a32d116e1f45d46ae2fcad972c98912d9d\", \"views_unittests\": \"9d9b8534c8d6e4337693b21d4a4536a10482908d\", \"viz_unittests\": \"25884015acfca4cdee0cf8f9528f0aee63b53f36\", \"vr_common_unittests\": \"93ac09df37cf292671a9374953c08a0422f3bc81\", \"vr_pixeltests\": \"d6ae2489e05a57505eaa4ef8e70d44a14b14fa61\", \"webdriver_wpt_tests\": \"e87dd0d0da3f67a659ff597e59ce15fe7c02228d\", \"weblayer_browsertests\": \"daa0c2a1233f275532bdab739dd9da5958861558\", \"wm_unittests\": \"96f69c85d14ec04f322ef0b444b7f9df2a5ed45b\", \"wtf_unittests\": \"cae506f25fa3ec7d82359a9b8b9150845fcd734f\", \"xr_browser_tests\": \"a9a04a67bc02b9ee69b82f11aa9c42f2c626abd7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_7e09b81c8d06479eaec2630704ae544ecbf4fef920ed2a3aec656c848d7a04d5_v2\", \"builder_86caf262f74ae694bec00fbb86541daa2df96eb35c1a4d9a06c3c1dcd129237e_v2\", \"builder_e4464922e5c60bddfcc3d01cfc511ef7f4a711bded636fa98c66f03a573264ee_v2\", \"builder_ee0cba3c54f4de5afe190f5f1f18eea3c236437d0f634917582eff9316b81bdc_v2\", \"builder_ee5c11e7a332f55fd7a24d7319a67a6829cd4b81b48e997032d91622b214e82b_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-42-g0bl\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4784-e3296b5\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579519981417739",
"status": "COMPLETED",
"status_changed_ts": "1579523148863021",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167588",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b03536eb5ebac7ef8e81d41a5496686173445c00",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b03536eb5ebac7ef8e81d41a5496686173445c00",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056177342134010048",
"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/8890747280028523552/+/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:49db87472ed7d210",
"user_agent:luci-scheduler"
],
"updated_ts": "1579523148867224",
"url": "https://ci.chromium.org/b/8890747280028523552",
"utcnow_ts": "1579523150068829"
}
}
[12:25:50.086] Invocation finished in 57m43.892258458s with status SUCCEEDED