[07:18:00.453] New invocation is queued and will start shortly
[07:18:01.553] Starting the invocation (attempt 1)
[07:18:01.594] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:18:01.594] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:18:01.594] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059635761535245856",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059635761535245856",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17",
"gitiles_ref:refs/heads/master"
]
}
[07:18:02.490] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576221481780660",
"id": "8894205699479012688",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576221482307164",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166032",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059635761535245856",
"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/8894205699479012688/+/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": "1576221482307269",
"url": "https://ci.chromium.org/b/8894205699479012688",
"utcnow_ts": "1576221482463711"
}
}
[07:18:02.490] Task URL: https://ci.chromium.org/b/8894205699479012688
[07:18:02.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:2:0) after 1m0s
[07:19:02.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:2:0)
[07:19:02.498] Timer tick, asking Buildbucket for the build status
[07:19:02.827] Build 8894205699479012688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:19:02.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:3:0) after 1m0s
[07:20:02.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:3:0)
[07:20:02.955] Timer tick, asking Buildbucket for the build status
[07:20:03.443] Build 8894205699479012688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:20:03.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:4:0) after 1m0s
[07:21:03.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:4:0)
[07:21:03.466] Timer tick, asking Buildbucket for the build status
[07:21:03.972] Build 8894205699479012688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:21:03.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:5:0) after 1m0s
[07:22:04.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:5:0)
[07:22:04.102] Timer tick, asking Buildbucket for the build status
[07:22:04.481] Build 8894205699479012688: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:22:04.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:6:0) after 1m0s
[07:22:08.316] Received PubSub notification, asking Buildbucket for the build status
[07:22:08.559] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:04.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:6:0)
[07:23:04.643] Timer tick, asking Buildbucket for the build status
[07:23:05.271] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:05.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:8:0) after 1m0s
[07:24:05.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:8:0)
[07:24:05.458] Timer tick, asking Buildbucket for the build status
[07:24:05.945] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:05.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:9:0) after 1m0s
[07:25:06.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:9:0)
[07:25:06.042] Timer tick, asking Buildbucket for the build status
[07:25:06.320] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:06.320] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:10:0) after 1m0s
[07:26:06.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:10:0)
[07:26:06.419] Timer tick, asking Buildbucket for the build status
[07:26:07.303] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:07.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:11:0) after 1m0s
[07:27:07.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:11:0)
[07:27:07.373] Timer tick, asking Buildbucket for the build status
[07:27:07.556] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:07.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:12:0) after 1m0s
[07:28:07.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:12:0)
[07:28:07.736] Timer tick, asking Buildbucket for the build status
[07:28:09.689] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:09.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:13:0) after 1m0s
[07:29:09.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:13:0)
[07:29:09.923] Timer tick, asking Buildbucket for the build status
[07:29:10.472] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:10.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:14:0) after 1m0s
[07:30:10.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:14:0)
[07:30:10.500] Timer tick, asking Buildbucket for the build status
[07:30:10.741] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:10.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:15:0) after 1m0s
[07:31:10.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:15:0)
[07:31:10.794] Timer tick, asking Buildbucket for the build status
[07:31:11.279] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:11.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:16:0) after 1m0s
[07:32:11.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:16:0)
[07:32:11.311] Timer tick, asking Buildbucket for the build status
[07:32:11.532] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:11.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:17:0) after 1m0s
[07:33:11.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:17:0)
[07:33:11.565] Timer tick, asking Buildbucket for the build status
[07:33:11.782] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:11.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:18:0) after 1m0s
[07:34:11.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:18:0)
[07:34:11.911] Timer tick, asking Buildbucket for the build status
[07:34:12.261] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:12.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:19:0) after 1m0s
[07:35:12.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:19:0)
[07:35:12.388] Timer tick, asking Buildbucket for the build status
[07:35:12.578] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:12.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:20:0) after 1m0s
[07:36:12.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:20:0)
[07:36:12.687] Timer tick, asking Buildbucket for the build status
[07:36:12.967] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:12.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:21:0) after 1m0s
[07:37:13.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:21:0)
[07:37:13.021] Timer tick, asking Buildbucket for the build status
[07:37:13.352] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:13.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:22:0) after 1m0s
[07:38:13.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:22:0)
[07:38:13.370] Timer tick, asking Buildbucket for the build status
[07:38:13.802] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:13.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:23:0) after 1m0s
[07:39:13.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:23:0)
[07:39:13.829] Timer tick, asking Buildbucket for the build status
[07:39:14.482] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:14.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:24:0) after 1m0s
[07:40:14.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:24:0)
[07:40:14.475] Timer tick, asking Buildbucket for the build status
[07:40:14.863] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:14.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:25:0) after 1m0s
[07:41:14.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:25:0)
[07:41:14.987] Timer tick, asking Buildbucket for the build status
[07:41:15.199] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:15.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:26:0) after 1m0s
[07:42:15.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:26:0)
[07:42:15.223] Timer tick, asking Buildbucket for the build status
[07:42:15.740] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:15.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:27:0) after 1m0s
[07:43:15.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:27:0)
[07:43:15.769] Timer tick, asking Buildbucket for the build status
[07:43:16.002] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:16.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:28:0) after 1m0s
[07:44:16.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:28:0)
[07:44:16.079] Timer tick, asking Buildbucket for the build status
[07:44:16.266] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:16.266] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:29:0) after 1m0s
[07:45:16.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:29:0)
[07:45:16.399] Timer tick, asking Buildbucket for the build status
[07:45:16.738] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:16.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:30:0) after 1m0s
[07:46:16.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:30:0)
[07:46:16.837] Timer tick, asking Buildbucket for the build status
[07:46:17.172] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:17.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:31:0) after 1m0s
[07:47:17.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:31:0)
[07:47:17.244] Timer tick, asking Buildbucket for the build status
[07:47:17.612] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:17.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:32:0) after 1m0s
[07:48:17.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:32:0)
[07:48:17.725] Timer tick, asking Buildbucket for the build status
[07:48:17.945] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:17.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:33:0) after 1m0s
[07:49:17.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:33:0)
[07:49:17.983] Timer tick, asking Buildbucket for the build status
[07:49:18.137] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:18.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:34:0) after 1m0s
[07:50:18.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:34:0)
[07:50:18.161] Timer tick, asking Buildbucket for the build status
[07:50:19.047] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:19.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:35:0) after 1m0s
[07:51:19.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:35:0)
[07:51:19.105] Timer tick, asking Buildbucket for the build status
[07:51:19.616] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:19.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:36:0) after 1m0s
[07:52:19.670] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:36:0)
[07:52:19.670] Timer tick, asking Buildbucket for the build status
[07:52:20.271] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:20.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:37:0) after 1m0s
[07:53:20.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:37:0)
[07:53:20.294] Timer tick, asking Buildbucket for the build status
[07:53:20.491] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:20.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:38:0) after 1m0s
[07:54:20.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:38:0)
[07:54:20.542] Timer tick, asking Buildbucket for the build status
[07:54:20.781] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:20.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:39:0) after 1m0s
[07:55:20.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:39:0)
[07:55:20.808] Timer tick, asking Buildbucket for the build status
[07:55:21.142] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:21.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:40:0) after 1m0s
[07:56:21.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:40:0)
[07:56:21.229] Timer tick, asking Buildbucket for the build status
[07:56:21.546] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:21.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:41:0) after 1m0s
[07:57:21.576] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:41:0)
[07:57:21.577] Timer tick, asking Buildbucket for the build status
[07:57:22.447] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:22.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:42:0) after 1m0s
[07:58:22.544] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:42:0)
[07:58:22.544] Timer tick, asking Buildbucket for the build status
[07:58:23.474] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:23.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:43:0) after 1m0s
[07:59:23.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:43:0)
[07:59:23.556] Timer tick, asking Buildbucket for the build status
[07:59:23.765] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:23.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:44:0) after 1m0s
[08:00:23.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:44:0)
[08:00:23.847] Timer tick, asking Buildbucket for the build status
[08:00:25.762] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:25.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:45:0) after 1m0s
[08:01:25.842] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:45:0)
[08:01:25.842] Timer tick, asking Buildbucket for the build status
[08:01:26.110] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:26.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:46:0) after 1m0s
[08:02:26.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:46:0)
[08:02:26.168] Timer tick, asking Buildbucket for the build status
[08:02:26.695] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:26.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:47:0) after 1m0s
[08:03:26.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:47:0)
[08:03:26.691] Timer tick, asking Buildbucket for the build status
[08:03:27.016] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:27.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:48:0) after 1m0s
[08:04:27.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:48:0)
[08:04:27.157] Timer tick, asking Buildbucket for the build status
[08:04:27.443] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:27.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:49:0) after 1m0s
[08:05:27.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:49:0)
[08:05:27.471] Timer tick, asking Buildbucket for the build status
[08:05:28.052] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:28.052] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:50:0) after 1m0s
[08:06:28.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:50:0)
[08:06:28.114] Timer tick, asking Buildbucket for the build status
[08:06:28.911] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:28.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:51:0) after 1m0s
[08:07:28.978] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:51:0)
[08:07:28.978] Timer tick, asking Buildbucket for the build status
[08:07:29.256] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:29.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:52:0) after 1m0s
[08:08:29.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:52:0)
[08:08:29.316] Timer tick, asking Buildbucket for the build status
[08:08:29.489] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:29.489] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:53:0) after 1m0s
[08:09:29.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:53:0)
[08:09:29.514] Timer tick, asking Buildbucket for the build status
[08:09:29.761] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:29.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:54:0) after 1m0s
[08:10:29.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:54:0)
[08:10:29.852] Timer tick, asking Buildbucket for the build status
[08:10:30.126] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:30.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:55:0) after 1m0s
[08:11:30.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:55:0)
[08:11:30.182] Timer tick, asking Buildbucket for the build status
[08:11:32.676] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:32.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:56:0) after 1m0s
[08:12:32.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:56:0)
[08:12:32.694] Timer tick, asking Buildbucket for the build status
[08:12:33.294] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:33.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:57:0) after 1m0s
[08:13:33.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:57:0)
[08:13:33.357] Timer tick, asking Buildbucket for the build status
[08:13:34.997] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:34.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:58:0) after 1m0s
[08:14:35.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:58:0)
[08:14:35.017] Timer tick, asking Buildbucket for the build status
[08:14:35.202] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:35.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:59:0) after 1m0s
[08:15:35.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:59:0)
[08:15:35.214] Timer tick, asking Buildbucket for the build status
[08:15:38.040] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:38.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:60:0) after 1m0s
[08:16:38.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:60:0)
[08:16:38.134] Timer tick, asking Buildbucket for the build status
[08:16:38.563] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:38.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:61:0) after 1m0s
[08:17:38.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:61:0)
[08:17:38.620] Timer tick, asking Buildbucket for the build status
[08:17:38.814] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:38.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:62:0) after 1m0s
[08:18:38.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:62:0)
[08:18:38.898] Timer tick, asking Buildbucket for the build status
[08:18:39.136] Build 8894205699479012688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:39.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059635761535245856:63:0) after 1m0s
[08:19:31.021] Received PubSub notification, asking Buildbucket for the build status
[08:19:31.234] Build 8894205699479012688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:19:31.234] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576225169420614",
"created_by": "project:chromium",
"created_ts": "1576221481780660",
"id": "8894205699479012688",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-12-ns42\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166032, \"got_angle_revision\": \"1d77542f568118a990c237debc517f64977e8397\", \"got_dawn_revision\": \"97fb51f4af11f672f69f3cb502ad5ca7a86b835d\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17\", \"got_revision_cp\": \"refs/heads/master@{#724540}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"ebb0bec915520c566271c0416e606def31a00947\", \"got_v8_revision\": \"67c7536f61350809f4b5b33e171de21a823bfa8d\", \"got_v8_revision_cp\": \"refs/heads/8.1.29@{#1}\", \"got_webrtc_revision\": \"f2d6fe62f23f13b974d50baa9ef60426a242af03\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30079}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17\", \"swarm_hashes_refs/heads/master(at){#724540}_without_patch\": {\"accessibility_unittests\": \"17d7b24f4b3f5d7832526a8d5aee7a201301a05f\", \"angle_unittests\": \"76a91695a00f8413734eb291dca67c903271ec59\", \"app_shell_unittests\": \"6b29bbb0e9fd83abae436abbbb959bed41e8fd56\", \"aura_unittests\": \"4ea5b33db46a8844b2e3fda01d4552159c470f03\", \"base_unittests\": \"9e7f94db87a84f96056cc49f7ef2116ce2c5b332\", \"base_util_unittests\": \"a1be7bb807da385967e5296522d405fcea3d4601\", \"blink_common_unittests\": \"6a55f4f304151aa9a9b2021a3766d98c7115649e\", \"blink_fuzzer_unittests\": \"955622552c9c21a631e4d7b339706f92f8201a19\", \"blink_heap_unittests\": \"0c52f53891370a6d043f301cb60eca441655f669\", \"blink_platform_unittests\": \"badd9670c22d6f9b5cb547a4d7c9a861f5678713\", \"blink_python_tests\": \"2e4f6226b142d0dc4ca31ed0d47447729e887b73\", \"blink_unittests\": \"6b9cbc4b092cb798ca2ba647b8576b4f3dd6fa8b\", \"blink_web_tests\": \"ee7233f56f0cfc4e8e779f5e3257c655bf3b2bbe\", \"boringssl_crypto_tests\": \"65632b4b1d912fab8d3a444fecb589c9f5794921\", \"boringssl_ssl_tests\": \"9c2f2ed1551047d97f4487bf04b1a646e5b8e723\", \"browser_tests\": \"72ed838e6da4399e5fb95f8b91799750e38b040d\", \"cacheinvalidation_unittests\": \"8bf553360a2edc9eb84d058b895d08ee15a19139\", \"capture_unittests\": \"cae2d6cb5dedda82748cf4ad4e8ffb2f92db3278\", \"cast_unittests\": \"5f03936c0b10972bf3acc7bba48d29ba2aed1d8f\", \"cc_unittests\": \"015e49277962a5e36aaca8d6a2e7b42a2a582e19\", \"chrome_app_unittests\": \"a5adb74d1cd3f9de9e3457353f166b1e2739fc34\", \"chromedriver_unittests\": \"9565f1e25cff12aa62acb42b3ed7e7be542ca4de\", \"components_browsertests\": \"4703742b6027e652fb6ab1acae137727753cacff\", \"components_unittests\": \"bd1f92b025dacbb1a8f15e2f243d5c9310306e3a\", \"compositor_unittests\": \"0df4cd4d0b9668cab6e6eb9766cf0d2e56422a73\", \"content_browsertests\": \"efc139b86e1f9907f141765084c1b8f13ec0b407\", \"content_shell_crash_test\": \"1331e5e33e091cb360827614564b81e00f745130\", \"content_unittests\": \"c777b34b2cf6f6fae0d2e8008f0d80e3804c2212\", \"crashpad_tests\": \"fb82144f4255c617ea57d262e0138c1b1c6c7bf7\", \"cronet_tests\": \"06c31479b588b18c00564853afaee90a09f28c10\", \"cronet_unittests\": \"f3ba0614aeabc12ccd20363d02bd04e35f470539\", \"crypto_unittests\": \"caa510f8e73a38f4bbb08da31ce1e02f3e6eca87\", \"dbus_unittests\": \"4d60437ed996a1045d193b27552819bce77448af\", \"device_unittests\": \"ab2735f0f122edb4a604eee691540a8525d64d1c\", \"devtools_lint_check\": \"2373bca44ab2775c9fb145411c618bea2460cfb8\", \"devtools_type_check\": \"c78b65ceb2362b9daa48e46072b0e189a9c5be5e\", \"display_unittests\": \"2b15ed745e20d5ee3735077492e8fcf52aa27de3\", \"events_unittests\": \"874f850afd1df7cd06a7d70a42571b3e3e46edf6\", \"extensions_browsertests\": \"ac335a0365d5cc710808bb4cb3ea63ce2ec30bb5\", \"extensions_unittests\": \"4fcf9f608ad680e277387236c760b4bd9f6c6637\", \"filesystem_service_unittests\": \"ce486a2a1d48fb25a4288312f8cb7782aca82292\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"3ec48fcb081ddd1a9de1bb8338b57eb18cfd37bb\", \"gfx_unittests\": \"097dc275f5d1626f2919beceacb798ade172b19b\", \"gin_unittests\": \"134c1c7c4c96c8051eb3b5bd7844ab5b7d1fa46c\", \"google_apis_unittests\": \"0a4cff4fe103340cc5f495c8d951706a12459247\", \"gpu_unittests\": \"b962139c594c625a0aae50a862242a87ca40780b\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"358d8256007f7910bfd7ecc718b26fa5722f0bb7\", \"headless_browsertests\": \"d229b6722477b5acbcd573a131db823b8435d5b1\", \"headless_unittests\": \"165d252dfff3b600fbc9385a623a325c5e886941\", \"interactive_ui_tests\": \"f414fccbe9e1ea6a78b2ab6bac5fca2889da571a\", \"ipc_tests\": \"8bd993893496295ae1e26ecc8a55704ee6d543a6\", \"jingle_unittests\": \"342fc6c35381ff5497f569b451cb765592e4fd35\", \"latency_unittests\": \"57609954d023d08e881c9ca8b67367d5a5998a6c\", \"libjingle_xmpp_unittests\": \"51a59170b06672d7f8c7d99a52d7782217294b06\", \"media_blink_unittests\": \"138c3f68e292b0f36e06d39566ab0608e4c5579d\", \"media_service_unittests\": \"653a050395ff4cf6bf4996522a0b84f4700802be\", \"media_unittests\": \"13b481aeacbdb5124ae74a09a36e103be93ecb17\", \"message_center_unittests\": \"e6322459889096360889cfab6fed2c879f3b0e94\", \"metrics_python_tests\": \"cb23ef6a52f66ab8cdd35c43292c177008a7b904\", \"midi_unittests\": \"3d334b3662f73a86a5caf2378b13ca0a2bd2c9c5\", \"mojo_core_unittests\": \"30fa23b1e2e2e1345fe5ff2753afa1cc7cd1309d\", \"mojo_unittests\": \"aa8812ad49590a9fc25c78565b6fa1271d57885a\", \"nacl_helper_nonsfi_unittests\": \"ba4bb99c9db75d318c7089c3225e75966372efe0\", \"nacl_loader_unittests\": \"3fe72fd60f2b30ceb7ee41b18c3bd8db0c5d2b1f\", \"native_theme_unittests\": \"a9f59ae2093d4ad252ac452727e242ce91ebcc5b\", \"net_unittests\": \"fe6bf8402754c4aef62f85d998f8f0018777fcb7\", \"pdf_unittests\": \"5a1d471449824307236ac4af3c4a3071638b632f\", \"perfetto_unittests\": \"a689c2c238e8bde6285b3c42ec34024e497a4c36\", \"ppapi_unittests\": \"e46ec7b0d01e8b0ed41e825c43b020321f824647\", \"printing_unittests\": \"3ba37d6bd17150b9d8a7fcab45eec506aa7ef446\", \"remoting_unittests\": \"c049794443ef1be917698fc2a9640f2a71a5d98d\", \"sandbox_linux_unittests\": \"c4790b6f72954456f00b03f27e5fe778541141d6\", \"service_manager_unittests\": \"51ade14f4afbb11b2fbcc3026d7a49e9891f5a6b\", \"services_unittests\": \"95357779f30e33d62ef3c8c3cc0a75a3095ed22d\", \"shell_dialogs_unittests\": \"ee338c421da28c35785a650b4acead9f5f6bfa10\", \"skia_unittests\": \"75a097784c76f029ff1f2dbb73e187fe72f4dd5e\", \"snapshot_unittests\": \"47eb55360b0f4a687430c9828680920438404663\", \"sql_unittests\": \"ca3e6602d5986fa7c25d9d6c5d1a009f912d8057\", \"storage_unittests\": \"99723daef5128da3ea6da9ed4b1dd3902b5844a4\", \"sync_integration_tests\": \"21d6230146873e9eea93d3e70cf5b98ce1ecbd44\", \"telemetry_gpu_unittests\": \"c751692f9c05786b2cf3620791e2e714a26ffa06\", \"telemetry_perf_unittests\": \"956f485dd9ced70ec59e97d7e8c19717f016536d\", \"telemetry_unittests\": \"cfc42e1c9339d40416bead7a4c0b4b022760c1a8\", \"traffic_annotation_auditor_unittests\": \"b876fe068b900c4edf6148f812ea0a574c7d7936\", \"ui_base_unittests\": \"9d6e7af47114417d789dccee0504c654158caed9\", \"ui_touch_selection_unittests\": \"459ac1a668aecf6c90d4630e3a23cc9fa10d9401\", \"unit_tests\": \"7671de5b1d9bf131d9ae12df737a9f036cbd80a8\", \"url_unittests\": \"a4d2bd663fa2bb78a765e11419e6f57e5f8e6fdd\", \"views_perftests\": \"9b00297e014e623a58d1fb70e5536cb63688c8d4\", \"views_unittests\": \"7da640a8a000256c40c184e463e67d34ae634949\", \"viz_unittests\": \"d1e0c7f5c1ef0b6ff243f1f9c847d3b3e1ed5801\", \"vr_common_unittests\": \"047e6277dce4c9d73928cd1b33c79d2ddf74d97e\", \"vr_pixeltests\": \"3c850f4c3c8eb672772da98bae76db7e0aade741\", \"webdriver_wpt_tests\": \"c538d23ff31c5fb152bdfa15eaf5379da9d6270a\", \"weblayer_browsertests\": \"6e37b2e288f9843f6ed82e4510b2ac9cbca51288\", \"wm_unittests\": \"d5dd4d4ea7f16c3df4ed3de869280cd11772c190\", \"wtf_unittests\": \"2e0243eedba2ee6dc2b46ba77a9403fa28d2fc7f\", \"xr_browser_tests\": \"adb13e62b181ccb17d52902e729dd63a5e4c6e32\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0f0f2920b57dc7c027977ee87520a3ddbf7f87ddb2741f9160320f85fd7d4389_v2\", \"builder_1267e646917891f5f926cdb94ab3368f0c9399d68d76518d691afb23f68811c9_v2\", \"builder_3b404fa22a3c1a181985100df1a34c166b035b5f7265e772f51e56790f9dc011_v2\", \"builder_69f5bab4a885266166e099964d6d1498f6d3761c92bc1d5b083a29cb822fe90c_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_ce52aa3ab9d9a450d9b5f3fb151dc5a055ee991845b7d9f6a360be4cd1eb07f1_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-12-ns42\"], \"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\": [\"4724-8894a57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576221727240501",
"status": "COMPLETED",
"status_changed_ts": "1576225170687660",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166032",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c1e753b7c5ce52897bbbc5f023a1f8e8eacbb17",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059635761535245856",
"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/8894205699479012688/+/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:4916f0a7a53c9e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1576225170696612",
"url": "https://ci.chromium.org/b/8894205699479012688",
"utcnow_ts": "1576225171224090"
}
}
[08:19:31.234] Invocation finished in 1h1m30.797985106s with status SUCCEEDED