[23:53:57.065] New invocation is queued and will start shortly
[23:53:58.207] Starting the invocation (attempt 1)
[23:53:58.242] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:53:58.242] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:53:58.242] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022337748995629840",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5623514d6a3e461415e6baeb1787aaf3c64ca834\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9022337748995629840",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5623514d6a3e461415e6baeb1787aaf3c64ca834",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5623514d6a3e461415e6baeb1787aaf3c64ca834",
"gitiles_ref:refs/heads/master"
]
}
[23:53:59.812] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611791638335148",
"id": "8856907686999623696",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5623514d6a3e461415e6baeb1787aaf3c64ca834\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611791639318780",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180045",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5623514d6a3e461415e6baeb1787aaf3c64ca834",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5623514d6a3e461415e6baeb1787aaf3c64ca834",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022337748995629840",
"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/8856907686999623696/+/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": "1611791639318917",
"url": "https://ci.chromium.org/b/8856907686999623696",
"utcnow_ts": "1611791639794176"
}
}
[23:53:59.812] Task URL: https://ci.chromium.org/b/8856907686999623696
[23:53:59.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:2:0) after 1m0s
[23:54:17.767] Received PubSub notification, asking Buildbucket for the build status
[23:54:17.849] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:59.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:2:0)
[23:54:59.952] Timer tick, asking Buildbucket for the build status
[23:55:00.259] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:00.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:4:0) after 1m0s
[23:56:00.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:4:0)
[23:56:00.326] Timer tick, asking Buildbucket for the build status
[23:56:00.953] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:00.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:5:0) after 1m0s
[23:57:01.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:5:0)
[23:57:01.488] Timer tick, asking Buildbucket for the build status
[23:57:01.620] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:01.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:6:0) after 1m0s
[23:58:01.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:6:0)
[23:58:01.669] Timer tick, asking Buildbucket for the build status
[23:58:01.745] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:01.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:7:0) after 1m0s
[23:59:01.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:7:0)
[23:59:01.749] Timer tick, asking Buildbucket for the build status
[23:59:02.291] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:02.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:8:0) after 1m0s
[00:00:02.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:8:0)
[00:00:02.317] Timer tick, asking Buildbucket for the build status
[00:00:02.493] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:02.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:9:0) after 1m0s
[00:01:02.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:9:0)
[00:01:02.471] Timer tick, asking Buildbucket for the build status
[00:01:03.739] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:03.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:10:0) after 1m0s
[00:02:03.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:10:0)
[00:02:03.764] Timer tick, asking Buildbucket for the build status
[00:02:04.244] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:04.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:11:0) after 1m0s
[00:03:04.340] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:11:0)
[00:03:04.340] Timer tick, asking Buildbucket for the build status
[00:03:04.448] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:04.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:12:0) after 1m0s
[00:04:04.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:12:0)
[00:04:04.488] Timer tick, asking Buildbucket for the build status
[00:04:04.767] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:04.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:13:0) after 1m0s
[00:05:04.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:13:0)
[00:05:04.856] Timer tick, asking Buildbucket for the build status
[00:05:06.903] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:06.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:14:0) after 1m0s
[00:06:06.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:14:0)
[00:06:06.946] Timer tick, asking Buildbucket for the build status
[00:06:07.145] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:07.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:15:0) after 1m0s
[00:07:07.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:15:0)
[00:07:07.165] Timer tick, asking Buildbucket for the build status
[00:07:07.569] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:07.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:16:0) after 1m0s
[00:08:07.591] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:16:0)
[00:08:07.591] Timer tick, asking Buildbucket for the build status
[00:08:07.688] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:07.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:17:0) after 1m0s
[00:09:07.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:17:0)
[00:09:07.738] Timer tick, asking Buildbucket for the build status
[00:09:08.267] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:08.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:18:0) after 1m0s
[00:10:08.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:18:0)
[00:10:08.323] Timer tick, asking Buildbucket for the build status
[00:10:08.397] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:08.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:19:0) after 1m0s
[00:11:08.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:19:0)
[00:11:08.826] Timer tick, asking Buildbucket for the build status
[00:11:09.352] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:09.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:20:0) after 1m0s
[00:12:09.374] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:20:0)
[00:12:09.374] Timer tick, asking Buildbucket for the build status
[00:12:09.672] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:09.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:21:0) after 1m0s
[00:13:09.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:21:0)
[00:13:09.700] Timer tick, asking Buildbucket for the build status
[00:13:09.853] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:09.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:22:0) after 1m0s
[00:14:09.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:22:0)
[00:14:09.940] Timer tick, asking Buildbucket for the build status
[00:14:10.113] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:10.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:23:0) after 1m0s
[00:15:10.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:23:0)
[00:15:10.199] Timer tick, asking Buildbucket for the build status
[00:15:10.322] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:10.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:24:0) after 1m0s
[00:16:10.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:24:0)
[00:16:10.338] Timer tick, asking Buildbucket for the build status
[00:16:10.470] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:10.470] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:25:0) after 1m0s
[00:17:10.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:25:0)
[00:17:10.666] Timer tick, asking Buildbucket for the build status
[00:17:10.983] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:10.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:26:0) after 1m0s
[00:18:11.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:26:0)
[00:18:11.064] Timer tick, asking Buildbucket for the build status
[00:18:11.262] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:11.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:27:0) after 1m0s
[00:19:11.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:27:0)
[00:19:11.331] Timer tick, asking Buildbucket for the build status
[00:19:11.770] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:11.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:28:0) after 1m0s
[00:20:12.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:28:0)
[00:20:12.034] Timer tick, asking Buildbucket for the build status
[00:20:12.159] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:12.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:29:0) after 1m0s
[00:21:12.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:29:0)
[00:21:12.347] Timer tick, asking Buildbucket for the build status
[00:21:12.590] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:12.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:30:0) after 1m0s
[00:22:12.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:30:0)
[00:22:12.609] Timer tick, asking Buildbucket for the build status
[00:22:12.768] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:12.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:31:0) after 1m0s
[00:23:12.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:31:0)
[00:23:12.841] Timer tick, asking Buildbucket for the build status
[00:23:13.040] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:13.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:32:0) after 1m0s
[00:24:13.050] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:32:0)
[00:24:13.050] Timer tick, asking Buildbucket for the build status
[00:24:13.369] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:13.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:33:0) after 1m0s
[00:25:13.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:33:0)
[00:25:13.415] Timer tick, asking Buildbucket for the build status
[00:25:13.638] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:13.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:34:0) after 1m0s
[00:26:13.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:34:0)
[00:26:13.719] Timer tick, asking Buildbucket for the build status
[00:26:13.785] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:13.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:35:0) after 1m0s
[00:27:13.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:35:0)
[00:27:13.787] Timer tick, asking Buildbucket for the build status
[00:27:13.914] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:13.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:36:0) after 1m0s
[00:28:13.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:36:0)
[00:28:13.987] Timer tick, asking Buildbucket for the build status
[00:28:14.434] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:14.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:37:0) after 1m0s
[00:29:14.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:37:0)
[00:29:14.488] Timer tick, asking Buildbucket for the build status
[00:29:14.846] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:14.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:38:0) after 1m0s
[00:30:14.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:38:0)
[00:30:14.895] Timer tick, asking Buildbucket for the build status
[00:30:15.106] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:15.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:39:0) after 1m0s
[00:31:15.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:39:0)
[00:31:15.189] Timer tick, asking Buildbucket for the build status
[00:31:15.409] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:15.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:40:0) after 1m0s
[00:32:15.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:40:0)
[00:32:15.501] Timer tick, asking Buildbucket for the build status
[00:32:15.570] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:15.570] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:41:0) after 1m0s
[00:33:15.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:41:0)
[00:33:15.626] Timer tick, asking Buildbucket for the build status
[00:33:15.734] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:15.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:42:0) after 1m0s
[00:34:15.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:42:0)
[00:34:15.784] Timer tick, asking Buildbucket for the build status
[00:34:15.969] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:15.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:43:0) after 1m0s
[00:35:16.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:43:0)
[00:35:16.087] Timer tick, asking Buildbucket for the build status
[00:35:16.261] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:16.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:44:0) after 1m0s
[00:36:16.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:44:0)
[00:36:16.304] Timer tick, asking Buildbucket for the build status
[00:36:16.396] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:16.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:45:0) after 1m0s
[00:37:16.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:45:0)
[00:37:16.452] Timer tick, asking Buildbucket for the build status
[00:37:16.536] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:16.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:46:0) after 1m0s
[00:38:16.537] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:46:0)
[00:38:16.537] Timer tick, asking Buildbucket for the build status
[00:38:16.868] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:16.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:47:0) after 1m0s
[00:39:16.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:47:0)
[00:39:16.914] Timer tick, asking Buildbucket for the build status
[00:39:17.514] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:17.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:48:0) after 1m0s
[00:40:17.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:48:0)
[00:40:17.585] Timer tick, asking Buildbucket for the build status
[00:40:17.690] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:17.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:49:0) after 1m0s
[00:41:17.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:49:0)
[00:41:17.728] Timer tick, asking Buildbucket for the build status
[00:41:18.324] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:18.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:50:0) after 1m0s
[00:42:18.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:50:0)
[00:42:18.475] Timer tick, asking Buildbucket for the build status
[00:42:18.597] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:18.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:51:0) after 1m0s
[00:43:18.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:51:0)
[00:43:18.940] Timer tick, asking Buildbucket for the build status
[00:43:19.073] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:19.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:52:0) after 1m0s
[00:44:19.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:52:0)
[00:44:19.678] Timer tick, asking Buildbucket for the build status
[00:44:19.856] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:19.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:53:0) after 1m0s
[00:45:19.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:53:0)
[00:45:19.918] Timer tick, asking Buildbucket for the build status
[00:45:20.051] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:20.051] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:54:0) after 1m0s
[00:46:20.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:54:0)
[00:46:20.371] Timer tick, asking Buildbucket for the build status
[00:46:20.445] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:20.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:55:0) after 1m0s
[00:47:20.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:55:0)
[00:47:20.582] Timer tick, asking Buildbucket for the build status
[00:47:20.726] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:20.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:56:0) after 1m0s
[00:48:20.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:56:0)
[00:48:20.910] Timer tick, asking Buildbucket for the build status
[00:48:24.892] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:24.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:57:0) after 1m0s
[00:49:26.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:57:0)
[00:49:26.004] Timer tick, asking Buildbucket for the build status
[00:49:26.269] Build 8856907686999623696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:26.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022337748995629840:58:0) after 1m0s
[00:50:21.183] Received PubSub notification, asking Buildbucket for the build status
[00:50:21.414] Build 8856907686999623696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:50:21.414] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611795020400988",
"created_by": "project:chromium",
"created_ts": "1611791638335148",
"id": "8856907686999623696",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5623514d6a3e461415e6baeb1787aaf3c64ca834\"}}",
"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\": \"5623514d6a3e461415e6baeb1787aaf3c64ca834\", \"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-174-bw0z\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180045, \"got_angle_revision\": \"52afb6f17cbd13a290c2798b565067f47d47af86\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"5623514d6a3e461415e6baeb1787aaf3c64ca834\", \"got_revision_cp\": \"refs/heads/master@{#847865}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"86f939547fc17da2ab1ed5a4b058bddb162dac9b\", \"got_v8_revision_cp\": \"refs/heads/9.0.87@{#1}\", \"got_webrtc_revision\": \"49dbad021e802197b3c0b966c03b4aa766a5b578\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33080}\", \"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\", \"prev_got_revision\": \"6b8482e20d6a3a833c89a10bbcf63f6276306e2c\", \"prev_got_revision_cp\": \"refs/heads/master@{#847681}\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5623514d6a3e461415e6baeb1787aaf3c64ca834\", \"swarm_hashes_refs/heads/master(at){#847865}_without_patch\": {\"absl_hardening_tests\": \"064e5f8115a72de7c57d917cf8b9856a5cd927b2\", \"accessibility_unittests\": \"adc21b004590a77f8de0148d4bd06e5341e6c606\", \"angle_unittests\": \"9cd1554eee6c0cb2442afe8afc81874cb8bdf106\", \"app_shell_unittests\": \"a2fdb4e66e0fb8753af7fc55f6cd07d18a42931c\", \"aura_unittests\": \"adcdb60cca2b8742cc1a232e5d2e2b29e31c680b\", \"base_unittests\": \"34120201e598e85f48aa5b573fab9ad8fac5951a\", \"base_util_unittests\": \"a2addc72480575d2846a777d333879374363e3ea\", \"blink_common_unittests\": \"d743dc496b3f841c86b04d83e43ceec632bf53b4\", \"blink_fuzzer_unittests\": \"99bc3933f6c7ab4ddef7ae029bf3544512093e9a\", \"blink_heap_unittests\": \"2e646ad3bcaf08885fb64b5639baaf1850171bcb\", \"blink_platform_unittests\": \"f13bfe42336074bd9549a47907a10aa5f6e0910d\", \"blink_python_tests\": \"6ed02baea9e7b45866ba0919bf3d7498fc6e6643\", \"blink_unittests\": \"657f75cad9d97abac4f9732088930b2e70b8e0b8\", \"blink_web_tests\": \"d488740b2a0af3b2959242f6dc32dd6e60d64932\", \"boringssl_crypto_tests\": \"5b3ae267d0866b6ab1ece8979e68c31aa702106e\", \"boringssl_ssl_tests\": \"fc696b3a507475d9093e3283b26b27d5efbf3c43\", \"browser_tests\": \"679068d3330ec9b8efe795aec3d89689a8cc620a\", \"capture_unittests\": \"fd021b8aef26fc35ebe21e2ba7af597ead765e2c\", \"cast_unittests\": \"20c679599ad370900d547c74469c455a98566c92\", \"cc_unittests\": \"8f511bba2333aecbe82b59ca2b04c6fc89da2294\", \"chrome_app_unittests\": \"33b51ac962fe0e8c33511de5501f1c775de41d2c\", \"chromedriver_unittests\": \"f28c895b9f49dd3963a38cd6541c6f584caa96c7\", \"components_browsertests\": \"5f0d01c55d9c5734b9b6d22e572a8441b318d45a\", \"components_unittests\": \"5f4d8b2190a9e40f6613849b7b622d742eaca1be\", \"compositor_unittests\": \"46a8bdf88694cf3320dde32ab119ccbb641cfed1\", \"content_browsertests\": \"8679c00de1e0db65d4ecbe7e637f8ca11842d7c4\", \"content_shell_crash_test\": \"8a35408e20cc68a20f2b1743e6b5de556675e333\", \"content_unittests\": \"230fc3e48db828f054446ae8c918676af8b772d0\", \"crashpad_tests\": \"f987a7ddbf66838efbd4fb0dac5332fc1b8e0689\", \"cronet_tests\": \"bed5340af639f98059faf653537aa7cfde37e862\", \"cronet_unittests\": \"9534f3a64d49a30139f9a4d85942a62b041cc388\", \"crypto_unittests\": \"c458d7d3afd68b3be4e9a64e884d718710bc6bbe\", \"dbus_unittests\": \"c07f5cac0ad6d1e21d15a46ccf9860ef548d9fa1\", \"device_unittests\": \"f6a427ab182e594bc26a29312f6ee8e79ad73b3c\", \"display_unittests\": \"e48385320230242a833605c75c891b8b69e8efd6\", \"events_unittests\": \"139e6a3caeefebfbe64b9adc5f83e27695d7910c\", \"extensions_browsertests\": \"41e077180fc0df38a25e772a69f5ebc3a8f996aa\", \"extensions_unittests\": \"023f930daa8df666d196233e829228861e2d44b1\", \"filesystem_service_unittests\": \"bb88586fd926efc08ceb94a9a86f38779766f73c\", \"flatbuffers_unittests\": \"bc94b5892d7faa8d4260fa7d28e336b3c313737d\", \"gcm_unit_tests\": \"d828c4e33c7e048c4334ab4b3f29ee3bc8f965f0\", \"gfx_unittests\": \"5b282e8a4fe8221e916ef18cf776dc04ee57a34d\", \"gin_unittests\": \"3545115651b328bb0ab5bc70da3a492ab8531419\", \"google_apis_unittests\": \"4556616c3d273be33199650db9841b1db422c2da\", \"gpu_unittests\": \"c9bd8dfb8b88bb8552bba1469329ea9d71e0729f\", \"grit_python_unittests\": \"b0890b035c0d25a1641db23ae376539c3f64cf64\", \"gwp_asan_unittests\": \"a08693583d5503f331ef1ed8a56ab86bded21a86\", \"headless_browsertests\": \"90bd6601faae34fa6df8c367c54c6138e10d4c63\", \"headless_unittests\": \"7d40eb71cf15e6c692aa39426779a8db4620cccc\", \"interactive_ui_tests\": \"f0f4cd0827dc771e334997d40c2971181edeae8e\", \"ipc_tests\": \"2cc8a75efe24e60c71f9970440c7ea6fde3e836f\", \"jingle_unittests\": \"02599c89200e19f0463a33637cece02a3730360c\", \"latency_unittests\": \"e29cb33b1e6c9c89f8524c030a430f04516c8d70\", \"libjingle_xmpp_unittests\": \"8a88f928a5fd7fb5d962422bd2ff5f2528d72273\", \"liburlpattern_unittests\": \"910c145f86497ef568807498cbd02b00f092c335\", \"media_blink_unittests\": \"20227f2582b7eca8f181c88cf18f3a1b605c63a3\", \"media_unittests\": \"c049a403d84ead62c0446f2dd8a1f8e192c187cd\", \"message_center_unittests\": \"ea12224570597140d47315add4f283ff348c202a\", \"metrics_python_tests\": \"2b75606d45448dbdc96ddfc01f7c5cf7003477fa\", \"midi_unittests\": \"379dbd578648b6f16cf30293c98b1339fc04f253\", \"mojo_core_unittests\": \"268fdfe233d8f80a72712ca318c5200c281ad804\", \"mojo_unittests\": \"14cd54dd8d92c0ae9ac68f0c97cfeb49d72330ba\", \"nacl_helper_nonsfi_unittests\": \"64a6b7def0704b512810c0a05b9e76ae0b1c2964\", \"nacl_loader_unittests\": \"6118d8f4556811487a9757d58649546bf4f9a53a\", \"native_theme_unittests\": \"bd67aafcbcfe88dfa6894f7aa7e57c45d60821b8\", \"net_unittests\": \"2d12d8a6b32d3b778d2f443db881f21a906abd6b\", \"openscreen_unittests\": \"73504cb2bf4d8a413cd10827c48f6f6eb6d4e0a3\", \"pdf_unittests\": \"8fe19afb7bc493abc82d97fdd8f14e5866a67b68\", \"perfetto_unittests\": \"24d9c286fdbf961c8a46a45d243db1407b5e5c36\", \"ppapi_unittests\": \"b9c55913a6f6f398b4aa1dacb95764e5fc1598dd\", \"printing_unittests\": \"36eb4ba9d521eaefa5efa26d5077ca4611066764\", \"remoting_unittests\": \"3745110863b64597b58c8f5a6e2cd747a3dabf05\", \"sandbox_linux_unittests\": \"53b93b7c6c150ba748fc122fdb130eb98c979acc\", \"service_manager_unittests\": \"41d13b1d60d6b91fa0a5d8d2f2e9f088e4531075\", \"services_unittests\": \"369603df74ef4bf89eb376c1a31a3835545fbbec\", \"shell_dialogs_unittests\": \"9ddc6d3961bf6bd33253bb714e0414472e0d6f3c\", \"skia_unittests\": \"84f1c6f930ab55b47022ecdcf86e4df8ef978e3b\", \"snapshot_unittests\": \"61f89aa404d5880813aaac6821a290c8a65c811a\", \"sql_unittests\": \"656039df24aa27a479ecad3d7d33516bfca17995\", \"storage_unittests\": \"5097d8dcce4e971e73c7799b053b7c79e8e62110\", \"sync_integration_tests\": \"f4e288b29001e046341fd5c05183df9a85eaef4f\", \"telemetry_gpu_unittests\": \"77c588e9949666b92df3756e546a0d2eba72ccf5\", \"telemetry_perf_unittests\": \"71fd7ed2462f1d99bd6b1c93745cf7bcab630caf\", \"telemetry_unittests\": \"e66aa21f5ca128da48f179d43622bf1089de9b14\", \"traffic_annotation_auditor_unittests\": \"d3d0700ac7e4b054d8a1a54f92848c0bd7a831fc\", \"ui_base_unittests\": \"fcc2f8dbca7dcf238a19bfd100b668f29a523cca\", \"ui_touch_selection_unittests\": \"582d3b2f016b16a9641ab326064eac152b01b1dc\", \"unit_tests\": \"3daad4beec9620f7beee1e07a12249aa23f8d921\", \"url_unittests\": \"36ab93a02c59214bf3e36a28377a15e6e421306e\", \"views_perftests\": \"a811456e8a730b087e020b1ee01fd4f3edd012ad\", \"views_unittests\": \"87b2e7087129d065183dbd400f31c2c9d7f0ba2e\", \"viz_unittests\": \"d25595c408f50e4fb0405e1689e31e7fd50132cf\", \"vr_common_unittests\": \"1fab57b36b2964e05116c0ea06ad9fcad6375c5b\", \"vr_pixeltests\": \"a52ce213d73f86487f6773934e8b4a0e6b645523\", \"webdriver_wpt_tests\": \"b2b8d1aa3723fa1c54f520553fc9bb9d71e7952a\", \"weblayer_browsertests\": \"b7a6e33a0c9ff2172c4c356c7aa0b1b2b06d097a\", \"weblayer_unittests\": \"fd9399cafa7aec60a4884fb4916a3701fe652950\", \"wm_unittests\": \"2212e8f764a0c2cfede172e946ed673187a1b78a\", \"wtf_unittests\": \"ba70cbc62e62d1a4227956826de8aa7fe6d0906b\", \"x11_unittests\": \"a69bc068e59bf4417c283c9d3f22df811fbf602c\", \"xr_browser_tests\": \"a97d1a83069a797acc555d4d3b1040e0a75180f0\", \"zlib_unittests\": \"2e4558677e3ca744bc29660a68d9e6e8a30c3e7d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_b60086edde2d210abd33c4d80848a46b6d9127aed49992e1b3da5a4d0158dc58_v2\", \"builder_c33cb68a27562f3c0030aeadb8872bc9395ca543fdfb70fdbe284a5ae715c202_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-174-bw0z\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5672-4713de1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611791657005507",
"status": "COMPLETED",
"status_changed_ts": "1611795020708376",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180045",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5623514d6a3e461415e6baeb1787aaf3c64ca834",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5623514d6a3e461415e6baeb1787aaf3c64ca834",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022337748995629840",
"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/8856907686999623696/+/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:515f162c6f347d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611795020715414",
"url": "https://ci.chromium.org/b/8856907686999623696",
"utcnow_ts": "1611795021397871"
}
}
[00:50:21.414] Invocation finished in 56m24.366731854s with status SUCCEEDED