[13:14:58.299] New invocation is queued and will start shortly
[13:14:59.423] Starting the invocation (attempt 1)
[13:14:59.524] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:14:59.524] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:14:59.524] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059250915431797584",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6557061ac09e95eef58a10361be076101b0b0dcb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059250915431797584",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/6557061ac09e95eef58a10361be076101b0b0dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6557061ac09e95eef58a10361be076101b0b0dcb",
"gitiles_ref:refs/heads/master"
]
}
[13:15:00.695] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576588499722417",
"id": "8893820853273897296",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6557061ac09e95eef58a10361be076101b0b0dcb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576588500357928",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166207",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6557061ac09e95eef58a10361be076101b0b0dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6557061ac09e95eef58a10361be076101b0b0dcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059250915431797584",
"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/8893820853273897296/+/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": "1576588500358082",
"url": "https://ci.chromium.org/b/8893820853273897296",
"utcnow_ts": "1576588500664215"
}
}
[13:15:00.695] Task URL: https://ci.chromium.org/b/8893820853273897296
[13:15:00.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:2:0) after 1m0s
[13:16:00.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:2:0)
[13:16:00.858] Timer tick, asking Buildbucket for the build status
[13:16:01.034] Build 8893820853273897296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:01.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:3:0) after 1m0s
[13:17:01.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:3:0)
[13:17:01.072] Timer tick, asking Buildbucket for the build status
[13:17:01.955] Build 8893820853273897296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:01.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:4:0) after 1m0s
[13:18:01.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:4:0)
[13:18:01.981] Timer tick, asking Buildbucket for the build status
[13:18:02.231] Build 8893820853273897296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:02.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:5:0) after 1m0s
[13:19:02.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:5:0)
[13:19:02.314] Timer tick, asking Buildbucket for the build status
[13:19:02.637] Build 8893820853273897296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:02.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:6:0) after 1m0s
[13:20:02.930] Received PubSub notification, asking Buildbucket for the build status
[13:20:03.261] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:05.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:6:0)
[13:20:05.057] Timer tick, asking Buildbucket for the build status
[13:20:05.184] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:05.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:8:0) after 1m0s
[13:21:05.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:8:0)
[13:21:05.438] Timer tick, asking Buildbucket for the build status
[13:21:06.307] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:06.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:9:0) after 1m0s
[13:22:06.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:9:0)
[13:22:06.442] Timer tick, asking Buildbucket for the build status
[13:22:07.120] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:07.120] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:10:0) after 1m0s
[13:23:07.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:10:0)
[13:23:07.189] Timer tick, asking Buildbucket for the build status
[13:23:07.464] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:07.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:11:0) after 1m0s
[13:24:07.546] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:11:0)
[13:24:07.546] Timer tick, asking Buildbucket for the build status
[13:24:07.866] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:07.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:12:0) after 1m0s
[13:25:08.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:12:0)
[13:25:08.254] Timer tick, asking Buildbucket for the build status
[13:25:08.662] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:08.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:13:0) after 1m0s
[13:26:08.756] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:13:0)
[13:26:08.756] Timer tick, asking Buildbucket for the build status
[13:26:10.871] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:10.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:14:0) after 1m0s
[13:27:11.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:14:0)
[13:27:11.067] Timer tick, asking Buildbucket for the build status
[13:27:11.535] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:11.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:15:0) after 1m0s
[13:28:12.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:15:0)
[13:28:12.013] Timer tick, asking Buildbucket for the build status
[13:28:12.440] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:12.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:16:0) after 1m0s
[13:29:12.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:16:0)
[13:29:12.525] Timer tick, asking Buildbucket for the build status
[13:29:12.736] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:12.736] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:17:0) after 1m0s
[13:30:12.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:17:0)
[13:30:12.833] Timer tick, asking Buildbucket for the build status
[13:30:13.092] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:13.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:18:0) after 1m0s
[13:31:13.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:18:0)
[13:31:13.104] Timer tick, asking Buildbucket for the build status
[13:31:13.336] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:13.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:19:0) after 1m0s
[13:32:13.313] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:19:0)
[13:32:13.313] Timer tick, asking Buildbucket for the build status
[13:32:13.528] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:13.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:20:0) after 1m0s
[13:33:13.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:20:0)
[13:33:13.595] Timer tick, asking Buildbucket for the build status
[13:33:15.718] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:15.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:21:0) after 1m0s
[13:34:15.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:21:0)
[13:34:15.844] Timer tick, asking Buildbucket for the build status
[13:34:16.103] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:16.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:22:0) after 1m0s
[13:35:16.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:22:0)
[13:35:16.120] Timer tick, asking Buildbucket for the build status
[13:35:16.451] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:16.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:23:0) after 1m0s
[13:36:16.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:23:0)
[13:36:16.614] Timer tick, asking Buildbucket for the build status
[13:36:16.871] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:16.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:24:0) after 1m0s
[13:37:16.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:24:0)
[13:37:16.987] Timer tick, asking Buildbucket for the build status
[13:37:17.606] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:17.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:25:0) after 1m0s
[13:38:17.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:25:0)
[13:38:17.663] Timer tick, asking Buildbucket for the build status
[13:38:18.636] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:18.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:26:0) after 1m0s
[13:39:18.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:26:0)
[13:39:18.808] Timer tick, asking Buildbucket for the build status
[13:39:19.023] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:19.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:27:0) after 1m0s
[13:40:19.077] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:27:0)
[13:40:19.077] Timer tick, asking Buildbucket for the build status
[13:40:19.415] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:19.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:28:0) after 1m0s
[13:41:19.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:28:0)
[13:41:19.445] Timer tick, asking Buildbucket for the build status
[13:41:19.863] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:19.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:29:0) after 1m0s
[13:42:19.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:29:0)
[13:42:19.860] Timer tick, asking Buildbucket for the build status
[13:42:21.637] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:21.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:30:0) after 1m0s
[13:43:21.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:30:0)
[13:43:21.717] Timer tick, asking Buildbucket for the build status
[13:43:22.125] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:22.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:31:0) after 1m0s
[13:44:22.239] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:31:0)
[13:44:22.239] Timer tick, asking Buildbucket for the build status
[13:44:22.653] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:22.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:32:0) after 1m0s
[13:45:22.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:32:0)
[13:45:22.786] Timer tick, asking Buildbucket for the build status
[13:45:22.926] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:22.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:33:0) after 1m0s
[13:46:23.097] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:33:0)
[13:46:23.097] Timer tick, asking Buildbucket for the build status
[13:46:23.298] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:23.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:34:0) after 1m0s
[13:47:23.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:34:0)
[13:47:23.432] Timer tick, asking Buildbucket for the build status
[13:47:23.714] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:23.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:35:0) after 1m0s
[13:48:23.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:35:0)
[13:48:23.763] Timer tick, asking Buildbucket for the build status
[13:48:24.135] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:24.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:36:0) after 1m0s
[13:49:24.221] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:36:0)
[13:49:24.221] Timer tick, asking Buildbucket for the build status
[13:49:24.532] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:24.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:37:0) after 1m0s
[13:50:24.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:37:0)
[13:50:24.676] Timer tick, asking Buildbucket for the build status
[13:50:25.195] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:25.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:38:0) after 1m0s
[13:51:25.281] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:38:0)
[13:51:25.281] Timer tick, asking Buildbucket for the build status
[13:51:25.850] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:25.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:39:0) after 1m0s
[13:52:25.924] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:39:0)
[13:52:25.924] Timer tick, asking Buildbucket for the build status
[13:52:26.613] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:26.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:40:0) after 1m0s
[13:53:26.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:40:0)
[13:53:26.643] Timer tick, asking Buildbucket for the build status
[13:53:26.904] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:26.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:41:0) after 1m0s
[13:54:26.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:41:0)
[13:54:26.941] Timer tick, asking Buildbucket for the build status
[13:54:27.896] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:27.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:42:0) after 1m0s
[13:55:27.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:42:0)
[13:55:27.960] Timer tick, asking Buildbucket for the build status
[13:55:28.493] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:28.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:43:0) after 1m0s
[13:56:28.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:43:0)
[13:56:28.515] Timer tick, asking Buildbucket for the build status
[13:56:28.740] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:28.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:44:0) after 1m0s
[13:57:28.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:44:0)
[13:57:28.794] Timer tick, asking Buildbucket for the build status
[13:57:31.217] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:31.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:45:0) after 1m0s
[13:58:31.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:45:0)
[13:58:31.255] Timer tick, asking Buildbucket for the build status
[13:58:31.432] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:31.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:46:0) after 1m0s
[13:59:31.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:46:0)
[13:59:31.489] Timer tick, asking Buildbucket for the build status
[13:59:31.887] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:31.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:47:0) after 1m0s
[14:00:31.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:47:0)
[14:00:31.972] Timer tick, asking Buildbucket for the build status
[14:00:32.474] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:32.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:48:0) after 1m0s
[14:01:32.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:48:0)
[14:01:32.510] Timer tick, asking Buildbucket for the build status
[14:01:32.918] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:32.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:49:0) after 1m0s
[14:02:32.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:49:0)
[14:02:32.976] Timer tick, asking Buildbucket for the build status
[14:02:33.232] Build 8893820853273897296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:33.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059250915431797584:50:0) after 1m0s
[14:02:40.476] Received PubSub notification, asking Buildbucket for the build status
[14:02:40.927] Build 8893820853273897296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:02:40.927] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576591359255478",
"created_by": "project:chromium",
"created_ts": "1576588499722417",
"id": "8893820853273897296",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6557061ac09e95eef58a10361be076101b0b0dcb\"}}",
"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-east1-d-8-93-r4pm\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166207, \"got_angle_revision\": \"7587588595901010a7b8d8683d06796ae420edb8\", \"got_dawn_revision\": \"1bbbe8f52da580bd245af733e068eaabacf72731\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"6557061ac09e95eef58a10361be076101b0b0dcb\", \"got_revision_cp\": \"refs/heads/master@{#725503}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"fbbfeb700295d1fe1ec6419a93594e5e1540ea32\", \"got_v8_revision\": \"fcd6bfb5692f613f69388a6648c1acabef8b262a\", \"got_v8_revision_cp\": \"refs/heads/8.1.47@{#1}\", \"got_webrtc_revision\": \"3a8df884d1691260c93da86ce864f8c890e9d155\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30103}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6557061ac09e95eef58a10361be076101b0b0dcb\", \"swarm_hashes_refs/heads/master(at){#725503}_without_patch\": {\"accessibility_unittests\": \"beff6afc37b3cf48bf2388af7c1b041e98714f05\", \"angle_unittests\": \"eb27a8405f5a6f2873f82bd755d7f2861e6df9e1\", \"app_shell_unittests\": \"2939d131831c2fd56300d0da6d9274b6fa3e0962\", \"aura_unittests\": \"c4c1b4e7a8034b369cf9bc587c7dec5e938ff6d0\", \"base_unittests\": \"cb346d7ab6feb57b1433ca347bdaba87c99dc94c\", \"base_util_unittests\": \"d63537b581dbdb1a4e700d94c4732b5af7458dfa\", \"blink_common_unittests\": \"b9ca4e664d20dfb44e75e44d1caae136f2773c33\", \"blink_fuzzer_unittests\": \"5e7c0e63fbff933291dfc190875946c76c131ac8\", \"blink_heap_unittests\": \"9be0da2eda1d65b0323537c0ac852a84628e9904\", \"blink_platform_unittests\": \"11ee6188b24b6da44942603d23de9688c8509e9b\", \"blink_python_tests\": \"bf988f870b42cda9bf6c232aa093dda73e3e9282\", \"blink_unittests\": \"53926d7241e6c278f7007514daa26bcfea685793\", \"blink_web_tests\": \"95cde6d04a05e71c8afaa766aed5af1124da9773\", \"boringssl_crypto_tests\": \"2b3438fae1f5284809a2e15e686fb9ea0ab43ef7\", \"boringssl_ssl_tests\": \"4419018ff4c026bf8aacc01ac79419b72cd84f25\", \"browser_tests\": \"037c5c82a7d641dd451644049ae51bcb38103695\", \"cacheinvalidation_unittests\": \"4684c5482aeb8ee957277a0d31462af78a23cba2\", \"capture_unittests\": \"ae95dbf6082c346feb7e0ae5fc95b8c4c43491f3\", \"cast_unittests\": \"a7ea8afd4c1662e7e38c23e6ed795d7846c52b4d\", \"cc_unittests\": \"34e8720a1233c5f5bbc91aeaa305e3bee524e02b\", \"chrome_app_unittests\": \"5c3acf330119dd96689baf5c811099eb3b1df241\", \"chromedriver_unittests\": \"e769447b35a8b254812af356136cfc0b22a78969\", \"components_browsertests\": \"ed5a338e91db373f340b093ca6f31a50e75fc1aa\", \"components_unittests\": \"6a9b6302be0a903aec46c068cfac9a6239e15a42\", \"compositor_unittests\": \"2da35a8506761a46865d7db591b6f1f97639d583\", \"content_browsertests\": \"70a4937d9fea528120e0ae9e752caeb98220ca19\", \"content_shell_crash_test\": \"91508449c30ef2e213d8e11a4b9ca15545b8f8a2\", \"content_unittests\": \"28c88cf0ce78e39171ed74ad2f8c38cce59d0e39\", \"crashpad_tests\": \"ccf664b07e088fc054e8ab166b2e4048ff18552b\", \"cronet_tests\": \"f6e733bf811d1d47f821bb84116ecb15375a7395\", \"cronet_unittests\": \"048b086fd45332b26babda3ecbf39beb37fc2202\", \"crypto_unittests\": \"d676d6e57641766941de1a2f10dc6dc0e985b7bc\", \"dbus_unittests\": \"0e1c6879ddc0b9216303ae0fc9750275dac6bab3\", \"device_unittests\": \"3c4978e2831086a8d9fe9fec5635149cd6747f6e\", \"devtools_lint_check\": \"fcb8cf9a64cb0eda979295ae7d5ac2a9513eca19\", \"devtools_type_check\": \"1d7ebb6533069309b9de28c6c8d6eb6f47d08033\", \"display_unittests\": \"9f3209ef9bfffc1520658526b559c171f0e0935e\", \"events_unittests\": \"4df2cb42d7b93b2065aacd10009ce9413a5614ce\", \"extensions_browsertests\": \"5644b5904a316592f401e5c6323b76af1486dcb7\", \"extensions_unittests\": \"1e27f02d88a7d308dd8d316e8f2ef0c7656b9832\", \"filesystem_service_unittests\": \"0542bc850280ecdab0b452586d431912493db214\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"e8e5bf06144a160fb8124acfd1878a5bc12245be\", \"gfx_unittests\": \"50a0359c98d37282c82355f9098b974c78912941\", \"gin_unittests\": \"a37ec82cf3afa32e061cf04186aa18a892666f25\", \"google_apis_unittests\": \"c4e825075e8d787423c474fcc7af5fc117559f3d\", \"gpu_unittests\": \"fb467ee7f96acf20affb2c8b8046744778482f2e\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"ca32531f1781ed40df41a978c4eca4e257257e01\", \"headless_browsertests\": \"2c7604260a7745d6aa71aa45860371c7ffc5169a\", \"headless_unittests\": \"e7700b6bf373a896ed643b5d3d06c257efd63254\", \"interactive_ui_tests\": \"ad792f8545242c29df2643bda78a7d23f4c057e9\", \"ipc_tests\": \"d438ed1937241e7cd72022d2153d1f85e7cba321\", \"jingle_unittests\": \"824fcfec5b863972ffce7f9198be1796f5304d79\", \"latency_unittests\": \"3c3b22828da661acb3bb6952b4e4c46d3385ec06\", \"libjingle_xmpp_unittests\": \"c7b57851a661b45adaff04e49abad85e9f6bf971\", \"media_blink_unittests\": \"9ec003e8d1aff39c5e34860d19b2e140af22df0a\", \"media_service_unittests\": \"0cba40fecfdf255663f39aa8c74f7f9d44a213e4\", \"media_unittests\": \"8343c9b718d30040c72b45bcb79de5cc24243e1f\", \"message_center_unittests\": \"6f2809015419bfc0a87b531659bbc6c5ee897a4f\", \"metrics_python_tests\": \"750d227852bd5ebd94041d9758f4893ee93cd22d\", \"midi_unittests\": \"fd49b31d066938b305a06fbaf176159292b37649\", \"mojo_core_unittests\": \"6346723486534d5be796cba288ec1655698c12b0\", \"mojo_unittests\": \"99d98e90ecab8f6cd85a626f2b9b1d7df2fee7db\", \"nacl_helper_nonsfi_unittests\": \"3d8558f43cc7a071137e0d48cadb0c46a3efb997\", \"nacl_loader_unittests\": \"4f00471e6837bdecb8191800bdad3a92f71130a9\", \"native_theme_unittests\": \"14c01bde99ec60724077a2af460e2088991a6d58\", \"net_unittests\": \"c33149c554166b4b48abdc1af9e64e1b96efd989\", \"pdf_unittests\": \"659e68f304e0e2e6e067a305d39eb51a430eca98\", \"perfetto_unittests\": \"bd0ab448552d6b6f0b52e9ec79db5c955abe9faa\", \"ppapi_unittests\": \"ad6d3dc0335710adf35c15fd28413f325c70c351\", \"printing_unittests\": \"43fb6ffca7fb2880c5d0076dd2489c69d0b07ef1\", \"remoting_unittests\": \"3c9a38b4a195e3ae55988a47ad2db6fa23983873\", \"sandbox_linux_unittests\": \"9540f64c28a57ebd84975d3d85627fc765150c7f\", \"service_manager_unittests\": \"6098e6d6fb8b87754f0a4449d66454466bd31dc4\", \"services_unittests\": \"0350901f2e2475743eadf050479ce0858ab74a63\", \"shell_dialogs_unittests\": \"f3903ba9063444a3c3e3ea3746770f1699693431\", \"skia_unittests\": \"f1679af77083f18816906b369df8130418ee573c\", \"snapshot_unittests\": \"a213ecce75f514969f2244084bb659d65dda39ae\", \"sql_unittests\": \"a4b4d24587de267a5fdc4e62a1ccc64031543ddf\", \"storage_unittests\": \"fd5f929e6367b7e87eaa516b31db88bf6beb4fb1\", \"sync_integration_tests\": \"eea82d54864e73ab14408bac30ebee41d67d8de9\", \"telemetry_gpu_unittests\": \"7350fd5eadaaed6c375cf4598da67c5b87de4d9d\", \"telemetry_perf_unittests\": \"22f3a5cd37d32a23bb130cca9d3438e56fabc32e\", \"telemetry_unittests\": \"a54e217c435d202ae5a1a572696bece3086aabc3\", \"traffic_annotation_auditor_unittests\": \"e1130ba14fc095873142e910be078a36342b31c1\", \"ui_base_unittests\": \"99fd0455729f7a67860a704f254a37e8df5b229f\", \"ui_touch_selection_unittests\": \"775a6155f934dd844087c959bc43fbd83c452bc8\", \"unit_tests\": \"76bbee51246829a15038639d9a7c98afd67d440e\", \"url_unittests\": \"9f956c7b19d856c269e848e2dcfe98234447047d\", \"views_perftests\": \"e555e297ff72b5a0779f7bdf06b9dbb4c119be83\", \"views_unittests\": \"a93c1c13c9cf963631b4fb08a0af7b5be26803d2\", \"viz_unittests\": \"18ce2b044bee3b06c856337ea2b116effe511ded\", \"vr_common_unittests\": \"853390c67a34f93c0ae1889aa5e4c803af87761d\", \"vr_pixeltests\": \"e5457e0b857330f18f082a2db7686c87c65201fa\", \"webdriver_wpt_tests\": \"cdae9161c861e09560d0c37a96294ae23a1b5fd2\", \"weblayer_browsertests\": \"c7e1c32015162091f32a6c3cd22d240265aaf66b\", \"wm_unittests\": \"0770160b905e9a81bb9a954c4b8ce8d96e83afb0\", \"wtf_unittests\": \"4b7b9adbf383c37a159db929d3e191135d548579\", \"xr_browser_tests\": \"4eb20e1645d9fc0b1a0b8ac5b78e57ae8b0fec4f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_756d7885c4e2fdf98a0ecf3a0240e3f6188de5be53188a09baa9eae15dedd66f_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-east1-d-8-93-r4pm\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"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\": [\"4730-f2fc813\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576588801969002",
"status": "COMPLETED",
"status_changed_ts": "1576591359786637",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166207",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6557061ac09e95eef58a10361be076101b0b0dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6557061ac09e95eef58a10361be076101b0b0dcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059250915431797584",
"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/8893820853273897296/+/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:492cd101e4ee9110",
"user_agent:luci-scheduler"
],
"updated_ts": "1576591359800705",
"url": "https://ci.chromium.org/b/8893820853273897296",
"utcnow_ts": "1576591360904924"
}
}
[14:02:40.927] Invocation finished in 47m42.647456387s with status SUCCEEDED