[01:12:36.117] New invocation is queued and will start shortly
[01:12:37.700] Starting the invocation (attempt 1)
[01:12:37.748] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:12:37.748] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:12:37.748] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052139202654971728",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1c45fe3914bf21aaf1137fa1218747132439539e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052139202654971728",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/1c45fe3914bf21aaf1137fa1218747132439539e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c45fe3914bf21aaf1137fa1218747132439539e",
"gitiles_ref:refs/heads/master"
]
}
[01:12:38.565] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1583370757892781",
"id": "8886709140131131664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c45fe3914bf21aaf1137fa1218747132439539e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583370758381817",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169344",
"builder:Linux Builder (dbg)",
"buildset:commit/git/1c45fe3914bf21aaf1137fa1218747132439539e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c45fe3914bf21aaf1137fa1218747132439539e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052139202654971728",
"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/8886709140131131664/+/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": "1583370758381966",
"url": "https://ci.chromium.org/b/8886709140131131664",
"utcnow_ts": "1583370758553934"
}
}
[01:12:38.565] Task URL: https://ci.chromium.org/b/8886709140131131664
[01:12:38.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:2:0) after 1m0s
[01:13:38.590] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:2:0)
[01:13:38.590] Timer tick, asking Buildbucket for the build status
[01:13:38.714] Build 8886709140131131664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:38.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:3:0) after 1m0s
[01:14:38.689] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:3:0)
[01:14:38.689] Timer tick, asking Buildbucket for the build status
[01:14:38.776] Build 8886709140131131664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:38.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:4:0) after 1m0s
[01:15:38.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:4:0)
[01:15:38.801] Timer tick, asking Buildbucket for the build status
[01:15:39.113] Build 8886709140131131664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:39.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:5:0) after 1m0s
[01:16:39.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:5:0)
[01:16:39.208] Timer tick, asking Buildbucket for the build status
[01:16:39.616] Build 8886709140131131664: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:39.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:6:0) after 1m0s
[01:17:07.202] Received PubSub notification, asking Buildbucket for the build status
[01:17:07.453] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:39.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:6:0)
[01:17:39.629] Timer tick, asking Buildbucket for the build status
[01:17:39.795] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:39.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:8:0) after 1m0s
[01:18:39.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:8:0)
[01:18:39.816] Timer tick, asking Buildbucket for the build status
[01:18:40.045] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:40.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:9:0) after 1m0s
[01:19:40.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:9:0)
[01:19:40.066] Timer tick, asking Buildbucket for the build status
[01:19:40.191] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:40.191] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:10:0) after 1m0s
[01:20:40.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:10:0)
[01:20:40.295] Timer tick, asking Buildbucket for the build status
[01:20:40.462] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:40.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:11:0) after 1m0s
[01:21:40.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:11:0)
[01:21:40.481] Timer tick, asking Buildbucket for the build status
[01:21:40.572] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:40.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:12:0) after 1m0s
[01:22:40.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:12:0)
[01:22:40.614] Timer tick, asking Buildbucket for the build status
[01:22:40.870] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:40.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:13:0) after 1m0s
[01:23:40.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:13:0)
[01:23:40.966] Timer tick, asking Buildbucket for the build status
[01:23:41.089] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:41.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:14:0) after 1m0s
[01:24:41.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:14:0)
[01:24:41.109] Timer tick, asking Buildbucket for the build status
[01:24:41.233] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:41.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:15:0) after 1m0s
[01:25:41.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:15:0)
[01:25:41.253] Timer tick, asking Buildbucket for the build status
[01:25:41.782] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:41.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:16:0) after 1m0s
[01:26:41.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:16:0)
[01:26:41.809] Timer tick, asking Buildbucket for the build status
[01:26:41.895] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:41.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:17:0) after 1m0s
[01:27:41.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:17:0)
[01:27:41.918] Timer tick, asking Buildbucket for the build status
[01:27:42.379] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:42.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:18:0) after 1m0s
[01:28:42.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:18:0)
[01:28:42.405] Timer tick, asking Buildbucket for the build status
[01:28:42.512] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:42.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:19:0) after 1m0s
[01:29:42.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:19:0)
[01:29:42.588] Timer tick, asking Buildbucket for the build status
[01:29:42.784] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:42.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:20:0) after 1m0s
[01:30:42.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:20:0)
[01:30:42.909] Timer tick, asking Buildbucket for the build status
[01:30:43.204] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:43.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:21:0) after 1m0s
[01:31:43.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:21:0)
[01:31:43.252] Timer tick, asking Buildbucket for the build status
[01:31:43.647] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:43.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:22:0) after 1m0s
[01:32:43.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:22:0)
[01:32:43.622] Timer tick, asking Buildbucket for the build status
[01:32:43.864] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:43.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:23:0) after 1m0s
[01:33:43.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:23:0)
[01:33:43.895] Timer tick, asking Buildbucket for the build status
[01:33:44.499] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:44.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:24:0) after 1m0s
[01:34:44.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:24:0)
[01:34:44.519] Timer tick, asking Buildbucket for the build status
[01:34:44.779] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:44.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:25:0) after 1m0s
[01:35:44.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:25:0)
[01:35:44.812] Timer tick, asking Buildbucket for the build status
[01:35:45.110] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:45.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:26:0) after 1m0s
[01:36:45.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:26:0)
[01:36:45.116] Timer tick, asking Buildbucket for the build status
[01:36:45.477] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:45.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:27:0) after 1m0s
[01:37:45.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:27:0)
[01:37:45.549] Timer tick, asking Buildbucket for the build status
[01:37:45.862] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:45.862] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:28:0) after 1m0s
[01:38:45.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:28:0)
[01:38:45.935] Timer tick, asking Buildbucket for the build status
[01:38:46.130] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:46.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:29:0) after 1m0s
[01:39:46.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:29:0)
[01:39:46.161] Timer tick, asking Buildbucket for the build status
[01:39:46.598] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:46.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:30:0) after 1m0s
[01:40:46.670] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:30:0)
[01:40:46.671] Timer tick, asking Buildbucket for the build status
[01:40:46.964] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:46.964] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:31:0) after 1m0s
[01:41:46.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:31:0)
[01:41:46.987] Timer tick, asking Buildbucket for the build status
[01:41:47.129] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:47.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:32:0) after 1m0s
[01:42:47.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:32:0)
[01:42:47.184] Timer tick, asking Buildbucket for the build status
[01:42:47.405] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:47.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:33:0) after 1m0s
[01:43:47.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:33:0)
[01:43:47.424] Timer tick, asking Buildbucket for the build status
[01:43:47.507] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:47.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:34:0) after 1m0s
[01:44:47.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:34:0)
[01:44:47.586] Timer tick, asking Buildbucket for the build status
[01:44:47.972] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:47.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:35:0) after 1m0s
[01:45:48.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:35:0)
[01:45:48.015] Timer tick, asking Buildbucket for the build status
[01:45:48.127] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:48.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:36:0) after 1m0s
[01:46:48.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:36:0)
[01:46:48.189] Timer tick, asking Buildbucket for the build status
[01:46:48.319] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:48.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:37:0) after 1m0s
[01:47:48.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:37:0)
[01:47:48.347] Timer tick, asking Buildbucket for the build status
[01:47:48.572] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:48.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:38:0) after 1m0s
[01:48:48.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:38:0)
[01:48:48.675] Timer tick, asking Buildbucket for the build status
[01:48:49.218] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:49.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:39:0) after 1m0s
[01:49:49.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:39:0)
[01:49:49.254] Timer tick, asking Buildbucket for the build status
[01:49:49.471] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:49.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:40:0) after 1m0s
[01:50:49.541] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:40:0)
[01:50:49.541] Timer tick, asking Buildbucket for the build status
[01:50:49.685] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:49.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:41:0) after 1m0s
[01:51:49.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:41:0)
[01:51:49.697] Timer tick, asking Buildbucket for the build status
[01:51:49.797] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:49.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:42:0) after 1m0s
[01:52:49.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:42:0)
[01:52:49.864] Timer tick, asking Buildbucket for the build status
[01:52:50.006] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:50.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:43:0) after 1m0s
[01:53:50.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:43:0)
[01:53:50.300] Timer tick, asking Buildbucket for the build status
[01:53:50.664] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:50.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:44:0) after 1m0s
[01:54:50.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:44:0)
[01:54:50.847] Timer tick, asking Buildbucket for the build status
[01:54:50.995] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:50.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:45:0) after 1m0s
[01:55:51.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:45:0)
[01:55:51.015] Timer tick, asking Buildbucket for the build status
[01:55:51.145] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:51.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:46:0) after 1m0s
[01:56:51.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:46:0)
[01:56:51.195] Timer tick, asking Buildbucket for the build status
[01:56:51.291] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:51.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:47:0) after 1m0s
[01:57:51.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:47:0)
[01:57:51.617] Timer tick, asking Buildbucket for the build status
[01:57:51.710] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:51.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:48:0) after 1m0s
[01:58:51.833] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:48:0)
[01:58:51.833] Timer tick, asking Buildbucket for the build status
[01:58:52.070] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:52.070] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:49:0) after 1m0s
[01:59:52.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:49:0)
[01:59:52.103] Timer tick, asking Buildbucket for the build status
[01:59:52.416] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:52.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:50:0) after 1m0s
[02:00:52.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:50:0)
[02:00:52.441] Timer tick, asking Buildbucket for the build status
[02:00:52.533] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:52.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:51:0) after 1m0s
[02:01:52.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:51:0)
[02:01:52.625] Timer tick, asking Buildbucket for the build status
[02:01:52.782] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:52.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:52:0) after 1m0s
[02:02:52.832] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:52:0)
[02:02:52.832] Timer tick, asking Buildbucket for the build status
[02:02:53.167] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:53.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:53:0) after 1m0s
[02:03:53.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:53:0)
[02:03:53.219] Timer tick, asking Buildbucket for the build status
[02:03:53.510] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:53.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:54:0) after 1m0s
[02:04:53.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:54:0)
[02:04:53.552] Timer tick, asking Buildbucket for the build status
[02:04:53.741] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:53.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:55:0) after 1m0s
[02:05:53.786] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:55:0)
[02:05:53.787] Timer tick, asking Buildbucket for the build status
[02:05:53.971] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:53.971] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:56:0) after 1m0s
[02:06:54.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:56:0)
[02:06:54.004] Timer tick, asking Buildbucket for the build status
[02:06:54.217] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:54.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:57:0) after 1m0s
[02:07:54.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:57:0)
[02:07:54.222] Timer tick, asking Buildbucket for the build status
[02:07:54.515] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:54.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:58:0) after 1m0s
[02:08:54.631] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:58:0)
[02:08:54.631] Timer tick, asking Buildbucket for the build status
[02:08:54.719] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:54.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:59:0) after 1m0s
[02:09:54.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:59:0)
[02:09:54.841] Timer tick, asking Buildbucket for the build status
[02:09:54.961] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:54.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:60:0) after 1m0s
[02:10:55.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:60:0)
[02:10:55.054] Timer tick, asking Buildbucket for the build status
[02:10:55.429] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:55.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:61:0) after 1m0s
[02:11:55.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:61:0)
[02:11:55.497] Timer tick, asking Buildbucket for the build status
[02:11:55.694] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:55.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:62:0) after 1m0s
[02:12:55.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:62:0)
[02:12:55.771] Timer tick, asking Buildbucket for the build status
[02:12:55.912] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:55.912] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:63:0) after 1m0s
[02:13:55.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:63:0)
[02:13:55.956] Timer tick, asking Buildbucket for the build status
[02:13:56.152] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:56.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:64:0) after 1m0s
[02:14:56.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:64:0)
[02:14:56.261] Timer tick, asking Buildbucket for the build status
[02:14:56.616] Build 8886709140131131664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:56.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052139202654971728:65:0) after 1m0s
[02:15:19.302] Received PubSub notification, asking Buildbucket for the build status
[02:15:19.371] Build 8886709140131131664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:15:19.371] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1583374518459303",
"created_by": "project:chromium",
"created_ts": "1583370757892781",
"id": "8886709140131131664",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c45fe3914bf21aaf1137fa1218747132439539e\"}}",
"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-138-6m0j\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169344, \"got_angle_revision\": \"9459bae14b650fb951bb2dfbae3df39a4e3a171b\", \"got_dawn_revision\": \"e5534c4419d5ce657c649d11d03d588b2acbc18f\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"1c45fe3914bf21aaf1137fa1218747132439539e\", \"got_revision_cp\": \"refs/heads/master@{#747039}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"a095711c5d771437d7f9bfcbd78a82062e19c55e\", \"got_v8_revision\": \"da29ae6fac09bc6e694c3eb0afdcd94441cf2559\", \"got_v8_revision_cp\": \"refs/heads/8.2.261@{#1}\", \"got_webrtc_revision\": \"43d8e93fa7bf1ac74fc5f8f7dee56de3fbbd5ef8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30683}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1c45fe3914bf21aaf1137fa1218747132439539e\", \"swarm_hashes_refs/heads/master(at){#747039}_without_patch\": {\"accessibility_unittests\": \"cfeca36eeb98cbaf440b2251b2f45241dcd50031\", \"angle_unittests\": \"e57dca720bb068d29da7ea6a74d5451f46a5ba15\", \"app_shell_unittests\": \"2a5b03a3f5b777e514522e61463cb19c5f1825ab\", \"aura_unittests\": \"029537b260feb863bd2c3c85c44dcc1b660ade98\", \"base_unittests\": \"76d6c732461abd640ea55c6fbce0606cd160effb\", \"base_util_unittests\": \"b8caf7ad97216ee10b4d0d73dfa47543b3696432\", \"blink_common_unittests\": \"28cc1da1e7aa70fd83bb34cc29a01b1727b6027a\", \"blink_fuzzer_unittests\": \"13cf01bcc5105b3fd5badde1f3c79bf40c9d70f3\", \"blink_heap_unittests\": \"abdc67ee11f965bc665e72c6c253d4a79a560d21\", \"blink_platform_unittests\": \"b6939727db82647bf4707850bf68fd975f69e9d8\", \"blink_python_tests\": \"8feb1140e65dc88bc85832d3cdf9da2912e33a6e\", \"blink_unittests\": \"23526ca3cde6daeb5e3b02fbb8940950d480ea96\", \"blink_web_tests\": \"cde9d62e57c82fe90b7558c88e61cd4b66cb1389\", \"boringssl_crypto_tests\": \"a0ac6339993ccf6cd63b26d31277dd5ee541db2d\", \"boringssl_ssl_tests\": \"96e59d6c3a4d2cd247ec3902067ab69a9d3d1f79\", \"browser_tests\": \"01905b91e80bc8d1f9e4671fde87e3f2022edf22\", \"cacheinvalidation_unittests\": \"421aa8b1bba9575e030b74d939dad75ee29e4c9a\", \"capture_unittests\": \"af331dbc6a49113aadd919141e55b8decb20e7d8\", \"cast_unittests\": \"e8d8220aa34a15c247997e20ecee3317e117fee8\", \"cc_unittests\": \"37b63fa3acc0c9caad6689d7ed9cc7ab945e119f\", \"chrome_app_unittests\": \"601272bd335688b54fe57244e0d0f52c4519ee0d\", \"chromedriver_unittests\": \"e1d2f955e6f3a7ac3a9700c3a10e6dfcb1470fec\", \"components_browsertests\": \"bf3b907a680a976b7c68895813ce506bf191a04e\", \"components_unittests\": \"ee471c7def71d6e9e5895839ee0f232b7b6e108e\", \"compositor_unittests\": \"f6583604dde53eabd8a5a89b3d94db5740ed2138\", \"content_browsertests\": \"41829890231db61243e106aa23f6c28327aa29ad\", \"content_shell_crash_test\": \"aba844c6ff77db3f5cd8538bca016485bc41ee4c\", \"content_unittests\": \"7807335fcd915d0fe7d75864a0dece5834e8b74e\", \"crashpad_tests\": \"1e975325ed9fba11e1f7b811c083bc0f71766e49\", \"cronet_tests\": \"7f94f59dd0b91d543bf1da6e71bb9d1cd7213d17\", \"cronet_unittests\": \"4294af1586f7d3f7e0295d4eb7cf404a49139382\", \"crypto_unittests\": \"68a76e23412f0bc9f7524b9214e6e518d122bf15\", \"dbus_unittests\": \"7ff8745683baae38e1e79b0bf0a77d78e90beab3\", \"device_unittests\": \"ed797842fee26ca54c5a398eada0386118f84c99\", \"display_unittests\": \"e49baaa22e743e8311e065b36de5578763855cdc\", \"events_unittests\": \"87214d7ff99dd2ebf28cecd9fa339d76e041f761\", \"extensions_browsertests\": \"410c67e7679f034c2967b317d3a255222aeea2a3\", \"extensions_unittests\": \"f331aa2ffa7cc463f53fb2c144c217f813b92bdb\", \"filesystem_service_unittests\": \"e2bedf366446a30dd6b4c6476149cd0c07fbaaaf\", \"flatbuffers_unittests\": \"501dfa51280eae93fccecaec30d9257cff6dbcc8\", \"gcm_unit_tests\": \"130df2c96feb4be3bfd51409202a877d7001c176\", \"gfx_unittests\": \"eb08922d377756dcc71bedb117b193550bc91848\", \"gin_unittests\": \"f42591d4b1afb3d086d92c0e89595fc1eb18fa35\", \"google_apis_unittests\": \"29bef3eaaf49ac9e0c88f6e96e70450b9d6a37cc\", \"gpu_unittests\": \"4f622d5f9c421b58ea99e9cc8a2084a40a639814\", \"grit_python_unittests\": \"300ea8455288eab00cd459e887bd99a4f6468e8a\", \"gwp_asan_unittests\": \"90252b502bcaeb67b163fb12cde131c9535664c3\", \"headless_browsertests\": \"284e232e7ae28eef360055b36c1d3553cf73c3e7\", \"headless_unittests\": \"b52a15ffdcd12681db02fd906e0304367f585a73\", \"interactive_ui_tests\": \"a4ac3a04023c7267ca296866342c80a9ae686871\", \"ipc_tests\": \"ed4a6cd9394af20352064030cc2b1e746f6d32b6\", \"jingle_unittests\": \"7677ac0d7bb47857c2581cec9c3f3bdc6789aa24\", \"latency_unittests\": \"1df0013ea9835cc9fae6ef284c12159a094972a6\", \"libjingle_xmpp_unittests\": \"43ddcef1dda0ff302d635a09c6fabe08f0c2d9f3\", \"media_blink_unittests\": \"882b29a2a9cdd3ef7391b54b5dbe644a633c2ec2\", \"media_service_unittests\": \"2f2d8dc24cfd10e34c4b0789cd06cc3b60821798\", \"media_unittests\": \"c2473b2604db0460951861bc4c7b5a5c20561225\", \"message_center_unittests\": \"c0eb377f86e2cfc138dcc48d83ca93bff15139c5\", \"metrics_python_tests\": \"96ae3b31dc95657821218d40d3aedf517a60ff5b\", \"midi_unittests\": \"b2dc047b46673266c3ed0c33695533f345425cba\", \"mojo_core_unittests\": \"dde492e4eb25261aef3b787e6fec48416fc8ad53\", \"mojo_unittests\": \"41300533bc885e686f7b3ea9e727075113c04253\", \"nacl_helper_nonsfi_unittests\": \"ae2e7fea996c91254b412c2d70fe4c79f7b61b01\", \"nacl_loader_unittests\": \"d1aa89bd278099d8b5a7a404c58eeb8f7b60f197\", \"native_theme_unittests\": \"c02e28c23c896a404b0687f26f1aeb2236f77ec4\", \"net_unittests\": \"ee0f5dddbc56ee37fc0f5cd237e71814769844eb\", \"pdf_unittests\": \"ab4742cdbd357b5219ba351350e46f0380c8c096\", \"perfetto_unittests\": \"62b361098349d131e4a6c1382cc99aa8fef6717d\", \"ppapi_unittests\": \"cc4e444c557daacb6fb01b1327a85726bf5cef5e\", \"printing_unittests\": \"1ed08f74a71e7c23bbc1ef0da603982fd549012a\", \"remoting_unittests\": \"ea6d89b58dea8836d1cfcdcb336b37e61c87df5d\", \"sandbox_linux_unittests\": \"1c25126f739a42758d0ccd17d071f79bc97cca41\", \"service_manager_unittests\": \"b873ea877fbef55a0ad057c00e19393984fb048e\", \"services_unittests\": \"3cf79ed4bd6584fcd93a81ef91a543c740c2262d\", \"shell_dialogs_unittests\": \"8c84d34e80e3ce90becd2f7e0ead82541d5b6e7c\", \"skia_unittests\": \"3dae7094bdcc9ceb47c1090fa7e44bbc7136f38e\", \"snapshot_unittests\": \"379828429fccd077035253034d432268a5b35081\", \"sql_unittests\": \"52bc914d74939decf76ba14c8224b548caf8afa2\", \"storage_unittests\": \"18992572302559c4b75daa0a2f475446093b1557\", \"sync_integration_tests\": \"55947bbacbf61357da99b3532186991c7ae1377e\", \"telemetry_gpu_unittests\": \"a075372e870aa5b2780a03a3b6ad1e0489e6ffcc\", \"telemetry_perf_unittests\": \"5c9a06a93f1eda8a93c58832616f80f4b52b30f0\", \"telemetry_unittests\": \"049117412be0bd41bd6e2471982831a1adf84fda\", \"traffic_annotation_auditor_unittests\": \"447077afea0468f7121dce595057e589742ac744\", \"ui_base_unittests\": \"d057d99479d2233fd97160cff028e7ce2f5436a2\", \"ui_touch_selection_unittests\": \"96b647d93b257caea33922c24336248df16c0f13\", \"unit_tests\": \"442b3bfc6af8fa0530aa07244aaa18062dc57b15\", \"url_unittests\": \"a8255bc805da859b3e4c3360ca574157bd44e47f\", \"views_perftests\": \"5677325c5511ce78666e6139a488cdba87f24998\", \"views_unittests\": \"180a11cefd3c352d0a744bb1a800e4713793865d\", \"viz_unittests\": \"9a640707f5625f41e2011a9eb23e5e9524547a2e\", \"vr_common_unittests\": \"2c32cf537500d68f6351852ea0fa9aeb3271194c\", \"vr_pixeltests\": \"a9a99ade1976cc910ab47fd3a123f1e4c897f9f9\", \"webdriver_wpt_tests\": \"d77c97504f950ef53e80eb3b875df9985d435c1c\", \"weblayer_browsertests\": \"0af8f034177d09396fa282f091e1e9605fd4b967\", \"wm_unittests\": \"06899699e6062655aa3030f72eea42325f7517c4\", \"wtf_unittests\": \"6959272b19b880e21721e73d676332668bc62e33\", \"xr_browser_tests\": \"7df53a1dc657387ce246f690a60d6918170bf2bd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_430de131691c92065dcde51cfe816220b2f886d2d859c4fe33bcc0b34c123c61_v2\", \"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_a050691f36f3646c8cc368108f8edcf844a0d1683dc4a3731c64b1c7527ccb94_v2\", \"builder_bdaf1433b62bd3619468ac2d2b745ba32f34c58221b7630e6cf9dedcc4b44d8b_v2\", \"builder_f5b4454e99a04aaa5ba743b59cc70de19e20bebedef65e50e3b90534a8d6a5e4_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-138-6m0j\"], \"image\": [\"chrome-xenial-20022300-4c0e34b5b5e\"], \"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\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583371026636786",
"status": "COMPLETED",
"status_changed_ts": "1583374518820859",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169344",
"builder:Linux Builder (dbg)",
"buildset:commit/git/1c45fe3914bf21aaf1137fa1218747132439539e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c45fe3914bf21aaf1137fa1218747132439539e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052139202654971728",
"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/8886709140131131664/+/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:4ac111f33c661510",
"user_agent:luci-scheduler"
],
"updated_ts": "1583374518825086",
"url": "https://ci.chromium.org/b/8886709140131131664",
"utcnow_ts": "1583374519362062"
}
}
[02:15:19.371] Invocation finished in 1h2m43.270126309s with status SUCCEEDED