[19:07:16.814] New invocation is queued and will start shortly
[19:07:17.854] Starting the invocation (attempt 1)
[19:07:17.929] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:07:17.929] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:07:17.929] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023624142294569328",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bfdb84b795de65d9190183d2a571d9c224bf8ad7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9023624142294569328",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/bfdb84b795de65d9190183d2a571d9c224bf8ad7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfdb84b795de65d9190183d2a571d9c224bf8ad7",
"gitiles_ref:refs/heads/master"
]
}
[19:07:18.849] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610564838064655",
"id": "8858194080320544544",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfdb84b795de65d9190183d2a571d9c224bf8ad7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610564838551518",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/179615",
"builder:Linux Builder (dbg)",
"buildset:commit/git/bfdb84b795de65d9190183d2a571d9c224bf8ad7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfdb84b795de65d9190183d2a571d9c224bf8ad7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023624142294569328",
"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/8858194080320544544/+/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": "1610564838551806",
"url": "https://ci.chromium.org/b/8858194080320544544",
"utcnow_ts": "1610564838830220"
}
}
[19:07:18.849] Task URL: https://ci.chromium.org/b/8858194080320544544
[19:07:18.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:2:0) after 1m0s
[19:08:08.167] Received PubSub notification, asking Buildbucket for the build status
[19:08:08.425] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:19.000] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:2:0)
[19:08:19.000] Timer tick, asking Buildbucket for the build status
[19:08:19.077] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:19.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:4:0) after 1m0s
[19:09:19.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:4:0)
[19:09:19.280] Timer tick, asking Buildbucket for the build status
[19:09:19.408] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:19.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:5:0) after 1m0s
[19:10:19.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:5:0)
[19:10:19.508] Timer tick, asking Buildbucket for the build status
[19:10:19.594] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:19.594] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:6:0) after 1m0s
[19:11:19.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:6:0)
[19:11:19.677] Timer tick, asking Buildbucket for the build status
[19:11:19.769] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:19.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:7:0) after 1m0s
[19:12:19.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:7:0)
[19:12:19.825] Timer tick, asking Buildbucket for the build status
[19:12:19.947] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:19.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:8:0) after 1m0s
[19:13:20.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:8:0)
[19:13:20.134] Timer tick, asking Buildbucket for the build status
[19:13:20.326] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:20.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:9:0) after 1m0s
[19:14:20.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:9:0)
[19:14:20.513] Timer tick, asking Buildbucket for the build status
[19:14:20.667] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:20.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:10:0) after 1m0s
[19:15:20.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:10:0)
[19:15:20.746] Timer tick, asking Buildbucket for the build status
[19:15:20.865] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:20.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:11:0) after 1m0s
[19:16:20.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:11:0)
[19:16:20.889] Timer tick, asking Buildbucket for the build status
[19:16:20.986] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:20.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:12:0) after 1m0s
[19:17:21.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:12:0)
[19:17:21.007] Timer tick, asking Buildbucket for the build status
[19:17:21.146] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:21.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:13:0) after 1m0s
[19:18:21.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:13:0)
[19:18:21.136] Timer tick, asking Buildbucket for the build status
[19:18:21.217] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:21.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:14:0) after 1m0s
[19:19:21.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:14:0)
[19:19:21.283] Timer tick, asking Buildbucket for the build status
[19:19:21.369] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:21.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:15:0) after 1m0s
[19:20:21.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:15:0)
[19:20:21.478] Timer tick, asking Buildbucket for the build status
[19:20:21.553] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:21.553] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:16:0) after 1m0s
[19:21:21.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:16:0)
[19:21:21.726] Timer tick, asking Buildbucket for the build status
[19:21:21.785] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:21.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:17:0) after 1m0s
[19:22:21.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:17:0)
[19:22:21.997] Timer tick, asking Buildbucket for the build status
[19:22:22.098] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:22.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:18:0) after 1m0s
[19:23:22.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:18:0)
[19:23:22.216] Timer tick, asking Buildbucket for the build status
[19:23:22.288] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:22.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:19:0) after 1m0s
[19:24:22.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:19:0)
[19:24:22.411] Timer tick, asking Buildbucket for the build status
[19:24:22.646] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:22.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:20:0) after 1m0s
[19:25:22.883] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:20:0)
[19:25:22.883] Timer tick, asking Buildbucket for the build status
[19:25:23.098] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:23.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:21:0) after 1m0s
[19:26:23.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:21:0)
[19:26:23.233] Timer tick, asking Buildbucket for the build status
[19:26:23.330] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:23.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:22:0) after 1m0s
[19:27:23.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:22:0)
[19:27:23.443] Timer tick, asking Buildbucket for the build status
[19:27:29.458] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:29.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:23:0) after 1m0s
[19:28:29.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:23:0)
[19:28:29.611] Timer tick, asking Buildbucket for the build status
[19:28:29.669] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:29.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:24:0) after 1m0s
[19:29:29.742] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:24:0)
[19:29:29.742] Timer tick, asking Buildbucket for the build status
[19:29:29.918] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:29.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:25:0) after 1m0s
[19:30:29.986] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:25:0)
[19:30:29.986] Timer tick, asking Buildbucket for the build status
[19:30:30.068] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:30.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:26:0) after 1m0s
[19:31:30.150] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:26:0)
[19:31:30.150] Timer tick, asking Buildbucket for the build status
[19:31:30.232] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:30.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:27:0) after 1m0s
[19:32:30.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:27:0)
[19:32:30.343] Timer tick, asking Buildbucket for the build status
[19:32:30.668] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:30.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:28:0) after 1m0s
[19:33:30.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:28:0)
[19:33:30.767] Timer tick, asking Buildbucket for the build status
[19:33:30.850] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:30.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:29:0) after 1m0s
[19:34:31.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:29:0)
[19:34:31.103] Timer tick, asking Buildbucket for the build status
[19:34:31.202] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:31.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:30:0) after 1m0s
[19:35:31.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:30:0)
[19:35:31.328] Timer tick, asking Buildbucket for the build status
[19:35:31.453] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:31.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:31:0) after 1m0s
[19:36:31.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:31:0)
[19:36:31.674] Timer tick, asking Buildbucket for the build status
[19:36:31.902] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:31.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:32:0) after 1m0s
[19:37:32.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:32:0)
[19:37:32.005] Timer tick, asking Buildbucket for the build status
[19:37:32.218] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:32.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:33:0) after 1m0s
[19:38:32.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:33:0)
[19:38:32.238] Timer tick, asking Buildbucket for the build status
[19:38:32.345] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:32.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:34:0) after 1m0s
[19:39:32.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:34:0)
[19:39:32.474] Timer tick, asking Buildbucket for the build status
[19:39:32.563] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:32.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:35:0) after 1m0s
[19:40:33.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:35:0)
[19:40:33.410] Timer tick, asking Buildbucket for the build status
[19:40:33.518] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:33.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:36:0) after 1m0s
[19:41:33.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:36:0)
[19:41:33.640] Timer tick, asking Buildbucket for the build status
[19:41:35.293] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:35.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:37:0) after 1m0s
[19:42:35.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:37:0)
[19:42:35.542] Timer tick, asking Buildbucket for the build status
[19:42:35.732] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:35.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:38:0) after 1m0s
[19:43:35.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:38:0)
[19:43:35.960] Timer tick, asking Buildbucket for the build status
[19:43:36.033] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:36.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:39:0) after 1m0s
[19:44:36.149] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:39:0)
[19:44:36.149] Timer tick, asking Buildbucket for the build status
[19:44:36.255] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:36.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:40:0) after 1m0s
[19:45:36.386] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:40:0)
[19:45:36.386] Timer tick, asking Buildbucket for the build status
[19:45:36.494] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:36.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:41:0) after 1m0s
[19:46:36.665] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:41:0)
[19:46:36.665] Timer tick, asking Buildbucket for the build status
[19:46:36.763] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:36.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:42:0) after 1m0s
[19:47:37.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:42:0)
[19:47:37.409] Timer tick, asking Buildbucket for the build status
[19:47:37.547] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:37.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:43:0) after 1m0s
[19:48:37.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:43:0)
[19:48:37.613] Timer tick, asking Buildbucket for the build status
[19:48:37.662] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:37.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:44:0) after 1m0s
[19:49:37.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:44:0)
[19:49:37.712] Timer tick, asking Buildbucket for the build status
[19:49:37.775] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:45:0) after 1m0s
[19:50:38.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:45:0)
[19:50:38.644] Timer tick, asking Buildbucket for the build status
[19:50:38.718] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:38.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:46:0) after 1m0s
[19:51:39.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:46:0)
[19:51:39.058] Timer tick, asking Buildbucket for the build status
[19:51:40.243] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:40.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:47:0) after 1m0s
[19:52:40.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:47:0)
[19:52:40.646] Timer tick, asking Buildbucket for the build status
[19:52:40.769] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:40.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:48:0) after 1m0s
[19:53:40.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:48:0)
[19:53:40.994] Timer tick, asking Buildbucket for the build status
[19:53:41.145] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:41.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:49:0) after 1m0s
[19:54:41.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:49:0)
[19:54:41.209] Timer tick, asking Buildbucket for the build status
[19:54:41.355] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:41.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:50:0) after 1m0s
[19:55:41.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:50:0)
[19:55:41.404] Timer tick, asking Buildbucket for the build status
[19:55:41.645] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:41.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:51:0) after 1m0s
[19:56:41.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:51:0)
[19:56:41.784] Timer tick, asking Buildbucket for the build status
[19:56:41.941] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:41.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:52:0) after 1m0s
[19:57:41.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:52:0)
[19:57:41.992] Timer tick, asking Buildbucket for the build status
[19:57:42.124] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:42.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:53:0) after 1m0s
[19:58:42.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:53:0)
[19:58:42.253] Timer tick, asking Buildbucket for the build status
[19:58:43.457] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:43.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:54:0) after 1m0s
[19:59:43.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:54:0)
[19:59:43.499] Timer tick, asking Buildbucket for the build status
[19:59:43.577] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:43.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:55:0) after 1m0s
[20:00:43.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:55:0)
[20:00:43.603] Timer tick, asking Buildbucket for the build status
[20:00:43.881] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:43.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:56:0) after 1m0s
[20:01:43.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:56:0)
[20:01:43.952] Timer tick, asking Buildbucket for the build status
[20:01:46.848] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:46.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:57:0) after 1m0s
[20:02:46.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:57:0)
[20:02:46.984] Timer tick, asking Buildbucket for the build status
[20:02:47.098] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:47.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:58:0) after 1m0s
[20:03:47.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:58:0)
[20:03:47.297] Timer tick, asking Buildbucket for the build status
[20:03:47.888] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:47.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:59:0) after 1m0s
[20:04:47.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:59:0)
[20:04:47.989] Timer tick, asking Buildbucket for the build status
[20:04:48.109] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:48.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:60:0) after 1m0s
[20:05:48.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:60:0)
[20:05:48.215] Timer tick, asking Buildbucket for the build status
[20:05:48.272] Build 8858194080320544544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:48.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9023624142294569328:61:0) after 1m0s
[20:05:50.992] Received PubSub notification, asking Buildbucket for the build status
[20:05:51.146] Build 8858194080320544544: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:05:51.146] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610568350192702",
"created_by": "project:chromium",
"created_ts": "1610564838064655",
"id": "8858194080320544544",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfdb84b795de65d9190183d2a571d9c224bf8ad7\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"bfdb84b795de65d9190183d2a571d9c224bf8ad7\", \"project\": \"chromium/src\"}], \"$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-86-8hyp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 179615, \"got_angle_revision\": \"215834139a79c03f66e48785a5d741be4e08c5cd\", \"got_dawn_revision\": \"111ba65a5e3dbd23a58dd63991e92e9300f44d13\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"bfdb84b795de65d9190183d2a571d9c224bf8ad7\", \"got_revision_cp\": \"refs/heads/master@{#843114}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"5f223d7fcc2e649223e2ca1e6d6532a98bd8ec95\", \"got_v8_revision_cp\": \"refs/heads/8.9.263@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bfdb84b795de65d9190183d2a571d9c224bf8ad7\", \"swarm_hashes_refs/heads/master(at){#843114}_without_patch\": {\"absl_hardening_tests\": \"0927419a8a6f402b881113bfc9dca25ecc930fef\", \"accessibility_unittests\": \"6f5188753f3224f2690fae7161ff0c04e8a5c691\", \"angle_unittests\": \"389af18445b72fcb0ca2fb6b2d5246071528be7f\", \"app_shell_unittests\": \"420dd7d58160ab67d957fd62d992d4f023ef2e7e\", \"aura_unittests\": \"8ebb2771d7b2671ce81803b289508d48dee7c9e4\", \"base_unittests\": \"17733364b2ec22e3ef84e0535e08fbfc313f7b98\", \"base_util_unittests\": \"e6902e421b0160141041294a53b9f1f6c47619c8\", \"blink_common_unittests\": \"7edad7e7c9cd1fb3ec40baa8c7238ad08437f782\", \"blink_fuzzer_unittests\": \"4cde7efb5a29c16ae607329b0d8711efdcea6fd6\", \"blink_heap_unittests\": \"42d067644b14b476274b78c82e9cf4bf682a84e7\", \"blink_platform_unittests\": \"32d4de3e9d884c4449b7b4c9be10ed7397bbf3bd\", \"blink_python_tests\": \"7e39c100b803336fd75709ce214d081193c309ef\", \"blink_unittests\": \"6a0045a876f850958fb003207a1a1e126596d2f7\", \"blink_web_tests\": \"75ef43d8c291888f50f8ae02f8edcf9971051622\", \"boringssl_crypto_tests\": \"9fbc61512df1a9b590f417f7799aeb3b2936ae71\", \"boringssl_ssl_tests\": \"de66e8447b744867e9498b049f510705845d8876\", \"browser_tests\": \"49381ab861b1ef993a48b1d4a25c8048bf9cbe0a\", \"capture_unittests\": \"9ed587a29004406bc1c2fd4888161bc2db71b9d9\", \"cast_unittests\": \"8df361a9115268108d07be2efd27678e37c8daa8\", \"cc_unittests\": \"4f5cda6e36b4d293c06b692a32905342d52bd440\", \"chrome_app_unittests\": \"cd9fd00c74cc294a4685213ad72251f42b0d8b7c\", \"chromedriver_unittests\": \"1bc1686f7447173c9c37bd5eb3785f1cb028bb95\", \"components_browsertests\": \"be99eba71eebe2b75b4301cf535fd117bfb2a3c6\", \"components_unittests\": \"98a21846fd9ad118a35a890e02d32ac6c0b8a40a\", \"compositor_unittests\": \"53a74d9af1d32fcca6fedbdeaa0ffba228118e49\", \"content_browsertests\": \"454d8d1af5f3e3ad1981cd7363badaadce0e4792\", \"content_shell_crash_test\": \"f2c0e97fa8f6ff0e9ab15770b82d764a3d5cb346\", \"content_unittests\": \"03077d07c7dfc7f41c5873e6f72b78151dc45f61\", \"crashpad_tests\": \"7cc19715347766545ac6440a2dee9ec1a416ce87\", \"cronet_tests\": \"86cb31047c5c41829fa409c9b9944e83db5dcff3\", \"cronet_unittests\": \"3c3287debb2715fab9b2e42b9326c127ab3bd937\", \"crypto_unittests\": \"26dd197a51df4891b6053b970135da916b2ef4a3\", \"dbus_unittests\": \"b8bb1836c71adfb64ab06b3bb990a9942bf4bd15\", \"device_unittests\": \"58943dbf8d68fe90f3d823f12cad06785449ac11\", \"display_unittests\": \"5be8c29f9dae5f7e58f7bd65ccd283db4825b6a4\", \"events_unittests\": \"de207c6530a9b77c33b8503794bbb221e35e769d\", \"extensions_browsertests\": \"c421731f5b57f08fe5a42e1150ff14bf07ff0322\", \"extensions_unittests\": \"19f9f9cc82d47ceb8618ce9377c32b8608a0883f\", \"filesystem_service_unittests\": \"a76b71d7218a29754a2ba6d6736125894c1c3df3\", \"flatbuffers_unittests\": \"25b512d24faefec22057a686bb0e2be1452a1abb\", \"gcm_unit_tests\": \"2f2ad85ab9549cb557685ce33044ed21669585e9\", \"gfx_unittests\": \"9728a185a00b824f2cd61432edb4af25338f16a2\", \"gin_unittests\": \"893ef4b7ff3ab7216119d377ac43962254eb3ac4\", \"google_apis_unittests\": \"c1d4e8659c5d4c4dfd411673883dd94e263140a5\", \"gpu_unittests\": \"ac86284bf242d7fc9984529b38f71da769d19fd7\", \"grit_python_unittests\": \"0b6d4d52edfdb860d49b4e2f566968b0e84ea187\", \"gwp_asan_unittests\": \"acd349b0fef7a8d46e5b73266259ebd46569eb03\", \"headless_browsertests\": \"5fb76f24e80a26a27bb9ed74d50e184ebf4505a0\", \"headless_unittests\": \"fc6e92b5b319fb5f7cf80b1005cf9fc6203655f8\", \"interactive_ui_tests\": \"20ae53321925ff61fd41237acbb372c4d689347d\", \"ipc_tests\": \"0643ae139251df198e501b0a494ce9b301b026d5\", \"jingle_unittests\": \"59c55e3cead32af8237e11244bc5041189e9d394\", \"latency_unittests\": \"c64d53ab0fdb4cb6547852e2a4d3f6c3103ae989\", \"libjingle_xmpp_unittests\": \"5bde24adafb2ef1dca23b568991a56d113bb4079\", \"liburlpattern_unittests\": \"124663569fb1d3493a13256f2e2d69e994568cc9\", \"media_blink_unittests\": \"a8a40f6016e326690daac4ac3acfba7847de2dfb\", \"media_unittests\": \"1752ac21350b06383a518fbfca71bccf66485d15\", \"message_center_unittests\": \"e9eff52ac982db39116535e06e7b33f23bd2f109\", \"metrics_python_tests\": \"99619b313a64fcf046bfcbe12bf499c2d51eab6d\", \"midi_unittests\": \"e78c5350a78f64d9b178d345fae4b573d30116b5\", \"mojo_core_unittests\": \"3eac5cff59aab1d0abbfd5bcc88299aec63da1d3\", \"mojo_unittests\": \"52a941ec7b875b17242fce94bedad42dcf954a17\", \"nacl_helper_nonsfi_unittests\": \"4c9716d1a912c7bb5a879a76df199508310547fd\", \"nacl_loader_unittests\": \"11685ff984b0ffca623da8d0b8f57bdef3c280ac\", \"native_theme_unittests\": \"f9e6f921ba8ffca4bdfe89fd0e4f7d4c3f712a9c\", \"net_unittests\": \"439f846e006be96b297a50c645252eb90bfa67ae\", \"openscreen_unittests\": \"eb6e501bd9969fa4aa475552c14b380f135a2cf9\", \"pdf_unittests\": \"4d8c68113e41a9b88f3414c209001284f1e8fb76\", \"perfetto_unittests\": \"2540f6f7bc114f056314c3733f79b448d43d63b8\", \"ppapi_unittests\": \"aa2dbcd9a5cde93d6c146572a63ded9c0e73ede3\", \"printing_unittests\": \"c8acbf9ea79028bc0fd026bd5e9801f236bdb70e\", \"remoting_unittests\": \"d8c6ffda2f77fdd20c25c5b845292c03ae22686b\", \"sandbox_linux_unittests\": \"6a1d0e0899cde144298f92dbaed3d4a62b9c5022\", \"service_manager_unittests\": \"1814f50322d63a882a2da452e225e154723d1bed\", \"services_unittests\": \"1006e2174b0504d62d5130b29b1182f32a8e7ee1\", \"shell_dialogs_unittests\": \"a2013d4b400c8afc5ae46adea3dcebbcbb569395\", \"skia_unittests\": \"86aefa0626ddab48e53a287cad3b0557809f5276\", \"snapshot_unittests\": \"7b79c00e1c69845984976ac3371c638ef9b227b5\", \"sql_unittests\": \"f98f013beb0a140077bd47cea9856293143ba13c\", \"storage_unittests\": \"5cf043e2b25e4d4b6d123783510c0bd1d95bb68a\", \"sync_integration_tests\": \"d748c1d1ed4daeb0211a1bd6767fde74d813ab3d\", \"telemetry_gpu_unittests\": \"3bb3b8ece951dbad7b229ef45c938c8ad5711351\", \"telemetry_perf_unittests\": \"ef396659ba523eb24bc908dd385fcbdc7072c3b2\", \"telemetry_unittests\": \"a90321790c3fe314304798884136a0a019252643\", \"traffic_annotation_auditor_unittests\": \"91b4847e30f7aab4db3f1f72a356c9cac3c27625\", \"ui_base_unittests\": \"312d057e67960ba12888fa0830d7a78cadf50313\", \"ui_touch_selection_unittests\": \"b36c6c2151bdfe9597e6633d232d3940fadf986e\", \"unit_tests\": \"f51bde37ba253f13353841271e8bc3ec7f370985\", \"url_unittests\": \"59b7ec2cfa5f4d12d193d0c1ad69c846df02fc39\", \"views_perftests\": \"23da36a5c3a15ab8621519b70e59d36e7c4e8c64\", \"views_unittests\": \"4a6856b5fd7ef5c4c619867a6a8b106c457e636e\", \"viz_unittests\": \"ead31646e16eee57ee2582d45ef293f71a41e644\", \"vr_common_unittests\": \"f76c2216e6f57e098517e954dc9240e287d57ff6\", \"vr_pixeltests\": \"d0d8e67bcfba7df60e04debb60b5c8c8305c123d\", \"webdriver_wpt_tests\": \"5f3cf6f4dd17c23e8ed8d5d58fcb8aafb39aa162\", \"weblayer_browsertests\": \"1bbed274048823dc094168a4ce2a065a2a4b8c94\", \"weblayer_unittests\": \"3997f30c29caec783b5c87bd5e22c9adc9556487\", \"wm_unittests\": \"b84da77eaba08ab0f0767702aa9024b142ba1c8f\", \"wtf_unittests\": \"eea87b3a26f0f7c87279cbae7b95bece0e557e80\", \"x11_unittests\": \"f453941d48db565d8ecf890953e71ea066a46fb4\", \"xr_browser_tests\": \"15e2578459c4b6ef6d21929faf6fe7f3cfca134d\", \"zlib_unittests\": \"2c6c0b428352d5357cb0f3b7757bdc38ca871cfb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1cac3a12be5110575bfda3df59662d0c101b80b5d04053a56e3716a56e114d29_v2\", \"builder_b492a6a54599c40c9efefa6e5d1cdf990fad3125ae5ed8fb1c6f132ff9c1d569_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_f0afc432815c064941e1e5f998abb72673d4068febb53723f4aedad56e1b9c62_v2\", \"builder_f182280557a3f6192de87a46aec9fb10feda9518a8c19d9f2a315c031f4a8a52_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-86-8hyp\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5661-c6f6250\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610564885952257",
"status": "COMPLETED",
"status_changed_ts": "1610568350646016",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/179615",
"builder:Linux Builder (dbg)",
"buildset:commit/git/bfdb84b795de65d9190183d2a571d9c224bf8ad7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bfdb84b795de65d9190183d2a571d9c224bf8ad7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023624142294569328",
"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/8858194080320544544/+/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:5115f6addf515910",
"user_agent:luci-scheduler"
],
"updated_ts": "1610568350655397",
"url": "https://ci.chromium.org/b/8858194080320544544",
"utcnow_ts": "1610568351127820"
}
}
[20:05:51.146] Invocation finished in 58m34.348448997s with status SUCCEEDED