[03:07:20.570] New invocation is queued and will start shortly
[03:07:22.179] Starting the invocation (attempt 1)
[03:07:22.244] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:07:22.245] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:07:22.245] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054578101876300432",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cc86cf8b465af67422a572246659eaa6e00c35bd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054578101876300432",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/cc86cf8b465af67422a572246659eaa6e00c35bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc86cf8b465af67422a572246659eaa6e00c35bd",
"gitiles_ref:refs/heads/master"
]
}
[03:07:22.837] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581044842385104",
"id": "8889148039309179888",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc86cf8b465af67422a572246659eaa6e00c35bd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581044842710449",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168321",
"builder:Linux Builder (dbg)",
"buildset:commit/git/cc86cf8b465af67422a572246659eaa6e00c35bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc86cf8b465af67422a572246659eaa6e00c35bd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054578101876300432",
"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/8889148039309179888/+/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": "1581044842710786",
"url": "https://ci.chromium.org/b/8889148039309179888",
"utcnow_ts": "1581044842826223"
}
}
[03:07:22.837] Task URL: https://ci.chromium.org/b/8889148039309179888
[03:07:22.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:2:0) after 1m0s
[03:08:22.887] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:2:0)
[03:08:22.887] Timer tick, asking Buildbucket for the build status
[03:08:23.031] Build 8889148039309179888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:23.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:3:0) after 1m0s
[03:09:23.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:3:0)
[03:09:23.027] Timer tick, asking Buildbucket for the build status
[03:09:23.299] Build 8889148039309179888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:23.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:4:0) after 1m0s
[03:10:23.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:4:0)
[03:10:23.297] Timer tick, asking Buildbucket for the build status
[03:10:24.047] Build 8889148039309179888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:24.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:5:0) after 1m0s
[03:11:24.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:5:0)
[03:11:24.135] Timer tick, asking Buildbucket for the build status
[03:11:24.491] Build 8889148039309179888: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:24.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:6:0) after 1m0s
[03:12:04.548] Received PubSub notification, asking Buildbucket for the build status
[03:12:04.662] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:24.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:6:0)
[03:12:24.521] Timer tick, asking Buildbucket for the build status
[03:12:24.628] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:24.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:8:0) after 1m0s
[03:13:24.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:8:0)
[03:13:24.646] Timer tick, asking Buildbucket for the build status
[03:13:24.830] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:24.830] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:9:0) after 1m0s
[03:14:24.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:9:0)
[03:14:24.852] Timer tick, asking Buildbucket for the build status
[03:14:25.068] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:25.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:10:0) after 1m0s
[03:15:25.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:10:0)
[03:15:25.086] Timer tick, asking Buildbucket for the build status
[03:15:25.282] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:25.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:11:0) after 1m0s
[03:16:25.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:11:0)
[03:16:25.357] Timer tick, asking Buildbucket for the build status
[03:16:25.505] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:25.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:12:0) after 1m0s
[03:17:25.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:12:0)
[03:17:25.533] Timer tick, asking Buildbucket for the build status
[03:17:26.491] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:26.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:13:0) after 1m0s
[03:18:26.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:13:0)
[03:18:26.649] Timer tick, asking Buildbucket for the build status
[03:18:26.814] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:26.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:14:0) after 1m0s
[03:19:26.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:14:0)
[03:19:26.840] Timer tick, asking Buildbucket for the build status
[03:19:26.931] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:26.931] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:15:0) after 1m0s
[03:20:26.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:15:0)
[03:20:26.920] Timer tick, asking Buildbucket for the build status
[03:20:27.769] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:27.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:16:0) after 1m0s
[03:21:27.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:16:0)
[03:21:27.793] Timer tick, asking Buildbucket for the build status
[03:21:28.249] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:28.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:17:0) after 1m0s
[03:22:28.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:17:0)
[03:22:28.286] Timer tick, asking Buildbucket for the build status
[03:22:28.440] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:28.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:18:0) after 1m0s
[03:23:28.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:18:0)
[03:23:28.423] Timer tick, asking Buildbucket for the build status
[03:23:28.982] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:28.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:19:0) after 1m0s
[03:24:29.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:19:0)
[03:24:29.001] Timer tick, asking Buildbucket for the build status
[03:24:29.151] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:29.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:20:0) after 1m0s
[03:25:29.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:20:0)
[03:25:29.256] Timer tick, asking Buildbucket for the build status
[03:25:30.374] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:30.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:21:0) after 1m0s
[03:26:30.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:21:0)
[03:26:30.434] Timer tick, asking Buildbucket for the build status
[03:26:30.715] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:30.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:22:0) after 1m0s
[03:27:30.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:22:0)
[03:27:30.909] Timer tick, asking Buildbucket for the build status
[03:27:31.590] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:31.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:23:0) after 1m0s
[03:28:31.579] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:23:0)
[03:28:31.579] Timer tick, asking Buildbucket for the build status
[03:28:31.717] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:31.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:24:0) after 1m0s
[03:29:31.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:24:0)
[03:29:31.850] Timer tick, asking Buildbucket for the build status
[03:29:31.977] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:31.978] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:25:0) after 1m0s
[03:30:32.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:25:0)
[03:30:32.038] Timer tick, asking Buildbucket for the build status
[03:30:32.347] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:32.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:26:0) after 1m0s
[03:31:32.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:26:0)
[03:31:32.488] Timer tick, asking Buildbucket for the build status
[03:31:32.710] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:32.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:27:0) after 1m0s
[03:32:32.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:27:0)
[03:32:32.777] Timer tick, asking Buildbucket for the build status
[03:32:32.937] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:32.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:28:0) after 1m0s
[03:33:32.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:28:0)
[03:33:32.953] Timer tick, asking Buildbucket for the build status
[03:33:33.102] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:33.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:29:0) after 1m0s
[03:34:33.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:29:0)
[03:34:33.177] Timer tick, asking Buildbucket for the build status
[03:34:34.186] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:34.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:30:0) after 1m0s
[03:35:34.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:30:0)
[03:35:34.211] Timer tick, asking Buildbucket for the build status
[03:35:34.509] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:34.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:31:0) after 1m0s
[03:36:34.631] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:31:0)
[03:36:34.631] Timer tick, asking Buildbucket for the build status
[03:36:34.860] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:34.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:32:0) after 1m0s
[03:37:34.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:32:0)
[03:37:34.911] Timer tick, asking Buildbucket for the build status
[03:37:35.110] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:35.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:33:0) after 1m0s
[03:38:35.232] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:33:0)
[03:38:35.232] Timer tick, asking Buildbucket for the build status
[03:38:35.479] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:35.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:34:0) after 1m0s
[03:39:35.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:34:0)
[03:39:35.504] Timer tick, asking Buildbucket for the build status
[03:39:35.681] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:35.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:35:0) after 1m0s
[03:40:35.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:35:0)
[03:40:35.704] Timer tick, asking Buildbucket for the build status
[03:40:35.960] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:35.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:36:0) after 1m0s
[03:41:35.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:36:0)
[03:41:35.977] Timer tick, asking Buildbucket for the build status
[03:41:36.364] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:36.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:37:0) after 1m0s
[03:42:36.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:37:0)
[03:42:36.482] Timer tick, asking Buildbucket for the build status
[03:42:36.631] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:36.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:38:0) after 1m0s
[03:43:36.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:38:0)
[03:43:36.632] Timer tick, asking Buildbucket for the build status
[03:43:36.768] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:36.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:39:0) after 1m0s
[03:44:36.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:39:0)
[03:44:36.810] Timer tick, asking Buildbucket for the build status
[03:44:37.128] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:37.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:40:0) after 1m0s
[03:45:37.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:40:0)
[03:45:37.142] Timer tick, asking Buildbucket for the build status
[03:45:37.372] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:37.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:41:0) after 1m0s
[03:46:37.392] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:41:0)
[03:46:37.392] Timer tick, asking Buildbucket for the build status
[03:46:37.529] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:37.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:42:0) after 1m0s
[03:47:37.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:42:0)
[03:47:37.678] Timer tick, asking Buildbucket for the build status
[03:47:38.333] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:38.333] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:43:0) after 1m0s
[03:48:38.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:43:0)
[03:48:38.569] Timer tick, asking Buildbucket for the build status
[03:48:38.883] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:38.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:44:0) after 1m0s
[03:49:38.985] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:44:0)
[03:49:38.985] Timer tick, asking Buildbucket for the build status
[03:49:39.128] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:39.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:45:0) after 1m0s
[03:50:39.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:45:0)
[03:50:39.253] Timer tick, asking Buildbucket for the build status
[03:50:39.405] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:39.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:46:0) after 1m0s
[03:51:39.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:46:0)
[03:51:39.480] Timer tick, asking Buildbucket for the build status
[03:51:39.649] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:39.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:47:0) after 1m0s
[03:52:39.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:47:0)
[03:52:39.654] Timer tick, asking Buildbucket for the build status
[03:52:40.056] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:40.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:48:0) after 1m0s
[03:53:40.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:48:0)
[03:53:40.171] Timer tick, asking Buildbucket for the build status
[03:53:40.513] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:40.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:49:0) after 1m0s
[03:54:40.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:49:0)
[03:54:40.556] Timer tick, asking Buildbucket for the build status
[03:54:40.712] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:40.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:50:0) after 1m0s
[03:55:40.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:50:0)
[03:55:40.704] Timer tick, asking Buildbucket for the build status
[03:55:41.665] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:41.665] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:51:0) after 1m0s
[03:56:41.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:51:0)
[03:56:41.736] Timer tick, asking Buildbucket for the build status
[03:56:41.960] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:41.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:52:0) after 1m0s
[03:57:42.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:52:0)
[03:57:42.059] Timer tick, asking Buildbucket for the build status
[03:57:43.282] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:43.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:53:0) after 1m0s
[03:58:43.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:53:0)
[03:58:43.303] Timer tick, asking Buildbucket for the build status
[03:58:43.493] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:43.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:54:0) after 1m0s
[03:59:43.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:54:0)
[03:59:43.593] Timer tick, asking Buildbucket for the build status
[03:59:43.737] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:43.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:55:0) after 1m0s
[04:00:44.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:55:0)
[04:00:44.039] Timer tick, asking Buildbucket for the build status
[04:00:44.187] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:44.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:56:0) after 1m0s
[04:01:44.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:56:0)
[04:01:44.304] Timer tick, asking Buildbucket for the build status
[04:01:44.480] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:44.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:57:0) after 1m0s
[04:02:44.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:57:0)
[04:02:44.630] Timer tick, asking Buildbucket for the build status
[04:02:46.987] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:46.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:58:0) after 1m0s
[04:03:47.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:58:0)
[04:03:47.096] Timer tick, asking Buildbucket for the build status
[04:03:47.295] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:47.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:59:0) after 1m0s
[04:04:47.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:59:0)
[04:04:47.394] Timer tick, asking Buildbucket for the build status
[04:04:47.563] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:47.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:60:0) after 1m0s
[04:05:47.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:60:0)
[04:05:47.643] Timer tick, asking Buildbucket for the build status
[04:05:47.817] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:47.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:61:0) after 1m0s
[04:06:47.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:61:0)
[04:06:47.845] Timer tick, asking Buildbucket for the build status
[04:06:48.002] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:48.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:62:0) after 1m0s
[04:07:48.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:62:0)
[04:07:48.082] Timer tick, asking Buildbucket for the build status
[04:07:48.751] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:48.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:63:0) after 1m0s
[04:08:48.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:63:0)
[04:08:48.773] Timer tick, asking Buildbucket for the build status
[04:08:48.940] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:48.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:64:0) after 1m0s
[04:09:48.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:64:0)
[04:09:48.999] Timer tick, asking Buildbucket for the build status
[04:09:49.170] Build 8889148039309179888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:49.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054578101876300432:65:0) after 1m0s
[04:10:05.530] Received PubSub notification, asking Buildbucket for the build status
[04:10:05.656] Build 8889148039309179888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:10:05.656] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581048604598542",
"created_by": "project:chromium",
"created_ts": "1581044842385104",
"id": "8889148039309179888",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc86cf8b465af67422a572246659eaa6e00c35bd\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-86-uuao\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168321, \"got_angle_revision\": \"463c318ab75995b87c3238e695f843ed95de1e28\", \"got_dawn_revision\": \"c30635174e125bda00771900089c34751e28faeb\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"cc86cf8b465af67422a572246659eaa6e00c35bd\", \"got_revision_cp\": \"refs/heads/master@{#739253}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"dd48b7e2d06e74e1d20da5f43224b4ae99873e64\", \"got_v8_revision\": \"624f9f49e78ad57fc114a50d4d2a97ff3f25a841\", \"got_v8_revision_cp\": \"refs/heads/8.2.25@{#1}\", \"got_webrtc_revision\": \"f12231d74209baf159de2c1abfdd764e28dd613b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30477}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc86cf8b465af67422a572246659eaa6e00c35bd\", \"swarm_hashes_refs/heads/master(at){#739253}_without_patch\": {\"accessibility_unittests\": \"8cbf93afa3df963ae40d1bf110ba6a04c4ff36fd\", \"angle_unittests\": \"29708a2b9cee87dc18ca1a843fe8227b9f9b88eb\", \"app_shell_unittests\": \"a4182617207fa9bba463d0fe93d712ca4708893e\", \"aura_unittests\": \"ed38ebf12f37e235717d6dcbe75826d5971f5341\", \"base_unittests\": \"f844543e0de1a8d9b0ddfd5d9a1ffe0a22d10e6a\", \"base_util_unittests\": \"906910cddb87f4755d82dd435d27747f6c1badb6\", \"blink_common_unittests\": \"77938161591ed52e89605edf79c093d350838ed4\", \"blink_fuzzer_unittests\": \"9583164d173a3e634847316fbe82b124ff536764\", \"blink_heap_unittests\": \"9deccce972963c1eaa3aad32f55678f1879b1bc4\", \"blink_platform_unittests\": \"bf04aaa86ace1bf1d6068472dadc24150b85b894\", \"blink_python_tests\": \"780fcf080bf6b963e9f62ab0b0413ba4b3af55bf\", \"blink_unittests\": \"d954d6ffd92358337d5db40120a357890fcea8f2\", \"blink_web_tests\": \"5acc008bd33f83151da6ddc79367b97ae9824ca3\", \"boringssl_crypto_tests\": \"a10a169aa28122b4122bdeb0f64c700c8bf46507\", \"boringssl_ssl_tests\": \"bc5cd69267a8515d9e847f2b03d2067fdabc86ca\", \"browser_tests\": \"aa37afcd5141e9b598ca6391b33b96c77cb6ca2a\", \"cacheinvalidation_unittests\": \"f2964eee9064f6349c1800d9577e0d91db078200\", \"capture_unittests\": \"550da80d337d1290ec9240e8bbb78df881981ccb\", \"cast_unittests\": \"a0812925a685c0a044f1640821e40e6e04e1fc95\", \"cc_unittests\": \"cd1c2af76aa03b14560b7f12e84ce9e583f3c8d9\", \"chrome_app_unittests\": \"e29308a189b97319bbb01c8cbac8a3f74cd6b844\", \"chromedriver_unittests\": \"7ed79c9fcfab19e1d23967a944ccac8d32e7921e\", \"components_browsertests\": \"89fec562accd5185464cce1450866652c641ffae\", \"components_unittests\": \"6081830076eaaa28e434b3b3cd1e3768f2ea0201\", \"compositor_unittests\": \"7b6566efa6542ac4d53e22e489f08eccd515fdf5\", \"content_browsertests\": \"840885fc8dfe26a4df8d84ea4c01b2654d2bef5d\", \"content_shell_crash_test\": \"1d3ae56b083b3236d8112c1d396e5f62e86b4768\", \"content_unittests\": \"d86c7a7c07d4a0e96a6d4644d61c6c6e9293c8c8\", \"crashpad_tests\": \"51b1d37b0fd48ea4473d84f994fb1d1db7c77bac\", \"cronet_tests\": \"7ce06cf42094dfc1f647fb4ec6036f19a388dd0e\", \"cronet_unittests\": \"c1e91daa72a92e844edefc88bdf1fc708be6e482\", \"crypto_unittests\": \"da018043f50d9d3b7a233d656e6b612f675fb53e\", \"dbus_unittests\": \"7950e48e51f7f1989f606f49f7843e1395ef832d\", \"device_unittests\": \"b44a3aeda5c1364b1c218f55c46fcfa55b005c3a\", \"display_unittests\": \"ff53e98b304fce59c0cb7261576040d9ea76de74\", \"events_unittests\": \"dca8bbda7870b22e516df97078f147528d7ce94b\", \"extensions_browsertests\": \"8b58c775d82dd735396fef94ea4b0cbce46d51e0\", \"extensions_unittests\": \"6b8d398925b33f35e29c5150da76afe58cce2ab5\", \"filesystem_service_unittests\": \"0cf3d157d8a259c231e514645804148024469a34\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"aa70c280b1da2118f83d20df3d4570a78193f4d5\", \"gfx_unittests\": \"f8858e2254c8155a3455b0435e32f7301f304747\", \"gin_unittests\": \"6fe7b5385d64ac9afc5404fca67fea42cec989a4\", \"google_apis_unittests\": \"d805145fb1a6261bf9435d003ede064ae00ce1dd\", \"gpu_unittests\": \"8994e611dfb530b6654e9f4001f8239c74ea9521\", \"grit_python_unittests\": \"099090c88e4fb3ff73a77dec4109bf7081d8e891\", \"gwp_asan_unittests\": \"d7fc741ea7c6bb3421d6034614fc16efa73dab04\", \"headless_browsertests\": \"f94434db32f8131cd19d9eb72958f03bfc73e91d\", \"headless_unittests\": \"86477ad20d7afe0468ebff8c2dd41ec93a1aee96\", \"interactive_ui_tests\": \"21d2661e4286c0789d16b1cc56e9a460b39ec394\", \"ipc_tests\": \"233ea27f2de7ae067c009d01eceaf11317bd8b35\", \"jingle_unittests\": \"2b05bd2fb6e4ea5fb01df67fcbea5ea1880f9868\", \"latency_unittests\": \"e421ec568c09d68dc569fbb95249e06ab57f4b03\", \"libjingle_xmpp_unittests\": \"4781e1d276cbbc832c791411e796129201915925\", \"media_blink_unittests\": \"1431cbd2c0d6b10a8b8310c522fb1060127a71ec\", \"media_service_unittests\": \"46afc6d8b5f3311bd32660b557ab18d52060990c\", \"media_unittests\": \"b9c9afd836155f7d01cb7f7d7a4c932ed9a3e731\", \"message_center_unittests\": \"6372c80f2363f41f2fa3cbf1bbedf74dafb379da\", \"metrics_python_tests\": \"e7a2dabfc2cd3e1ee61efb635735a8ecda012cfe\", \"midi_unittests\": \"48768a04acf50b187fca29d09a943e3649e58f79\", \"mojo_core_unittests\": \"aa18c7f183e2d8bdce03bd7e444209f6601b69d4\", \"mojo_unittests\": \"11a8ab96488f5f5754108dc222836a19bd6159a0\", \"nacl_helper_nonsfi_unittests\": \"1245060376498636e2000814a0d299fcbc7fc830\", \"nacl_loader_unittests\": \"bbc944546dc947655d67b6fc87eb863cb94d00b4\", \"native_theme_unittests\": \"445af2f8ee31993134fc52860e4339d49b1e6bda\", \"net_unittests\": \"b2e33bb64aa5373ef7daaaf761232a3bb3f0334b\", \"pdf_unittests\": \"b4e830b8f4c8090eb49684bfd6f2ea9c848ef098\", \"perfetto_unittests\": \"0a52a0381dd95f47eddfa44e1ae4d4d9d88c77b5\", \"ppapi_unittests\": \"295b26d883fa22368dcd7ca2011f2cf5cc1abb2b\", \"printing_unittests\": \"e44103a4b61af6b035719845d738011879befd31\", \"remoting_unittests\": \"d138749b800cafb1c09d29fd0aa5efa2cb6d3971\", \"sandbox_linux_unittests\": \"d89303ddbeb387c2412b01385e70a65d4e4ea8bd\", \"service_manager_unittests\": \"38b9ef90223afc18be0d00c83ebb2453f97bfbe2\", \"services_unittests\": \"7b60de7a5bf960fec78283f7c55819fda22dcd88\", \"shell_dialogs_unittests\": \"443d7a0206be0bbef20aa1407bd5dc0d564723fe\", \"skia_unittests\": \"0dff800c741dba0d9b273dd85f3069e91bbeecd2\", \"snapshot_unittests\": \"7cbfd9fefa5362aba47547df63075fe269ce18e9\", \"sql_unittests\": \"1847f8f72ab7a055794377234dd1bd991fb3c652\", \"storage_unittests\": \"0df3e4119712d60d6f00835b252d0da22f26ad66\", \"sync_integration_tests\": \"02c4c9a6c3e1d72b12e00f174e3918b6b4478eb0\", \"telemetry_gpu_unittests\": \"40a61bb722b4f3e697aa023b488126859ffb88d4\", \"telemetry_perf_unittests\": \"8a05e4ab59ae94e63db9d22f785a9eeb69fbd385\", \"telemetry_unittests\": \"d1ba26f179f6ccd8054dda798f2d9086c45aaa5c\", \"traffic_annotation_auditor_unittests\": \"36e660fbc2ecf08337ceaf6b7413d0640f310add\", \"ui_base_unittests\": \"922da806867cc440e87ed484a5ef70fa4537a7de\", \"ui_touch_selection_unittests\": \"e06d5420cd06b4c083d7478d0fd964674fac4f87\", \"unit_tests\": \"1b3f685023869b88be4c2781d611986b73937e87\", \"url_unittests\": \"63fc71c4c79689dc09e4d372cfa577e65ad3b90e\", \"views_perftests\": \"6c037ca35578a45236a43ca596e811e09af77b8a\", \"views_unittests\": \"c99604845c28cb4ce2ed5ec5e77570cfc4dce9d0\", \"viz_unittests\": \"f6f3711d7d396b61d28b82199a0403fd698a2340\", \"vr_common_unittests\": \"1a2347b5cde408cbbccb19ec2613c6ab0baffe12\", \"vr_pixeltests\": \"1bb1c57cd758b83fdae2688e9a244de36c084591\", \"webdriver_wpt_tests\": \"746462a1026b545c67298278e0659019a83017c0\", \"weblayer_browsertests\": \"1ba39526e0eda0c9eaeec8fb74bc63aaa1842842\", \"wm_unittests\": \"aff9559cf1ce2fef7945622eab9ee3d9052def56\", \"wtf_unittests\": \"f1c090a3a349d4b96f79f0b540bbdfcf1c8e3669\", \"xr_browser_tests\": \"edde1aa2a8e486685fec3dd2c1ed6c0a3c69d703\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_ced67bd9e4a31c2a429bce6c01367a471355a6320e5e7684f07304ef1e327147_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-86-uuao\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581045123810160",
"status": "COMPLETED",
"status_changed_ts": "1581048605147473",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168321",
"builder:Linux Builder (dbg)",
"buildset:commit/git/cc86cf8b465af67422a572246659eaa6e00c35bd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc86cf8b465af67422a572246659eaa6e00c35bd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054578101876300432",
"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/8889148039309179888/+/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:4a366f4a7b6a4a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1581048605168402",
"url": "https://ci.chromium.org/b/8889148039309179888",
"utcnow_ts": "1581048605646282"
}
}
[04:10:05.656] Invocation finished in 1h2m45.102538811s with status SUCCEEDED