[09:05:12.673] New invocation is queued and will start shortly
[09:05:13.955] Starting the invocation (attempt 1)
[09:05:14.001] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:05:14.001] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:05:14.001] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022574857609679952",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9427efac938309f94f9508872f05160a6ca7b8d4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022574857609679952",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/9427efac938309f94f9508872f05160a6ca7b8d4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9427efac938309f94f9508872f05160a6ca7b8d4",
"gitiles_ref:refs/heads/master"
]
}
[09:05:15.145] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611565514206326",
"id": "8857144795334624416",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9427efac938309f94f9508872f05160a6ca7b8d4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611565514961574",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22334",
"builder:linux-chromeos-dbg",
"buildset:commit/git/9427efac938309f94f9508872f05160a6ca7b8d4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9427efac938309f94f9508872f05160a6ca7b8d4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022574857609679952",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857144795334624416/+/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": "1611565514961689",
"url": "https://ci.chromium.org/b/8857144795334624416",
"utcnow_ts": "1611565515136029"
}
}
[09:05:15.145] Task URL: https://ci.chromium.org/b/8857144795334624416
[09:05:15.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:2:0) after 1m0s
[09:06:15.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:2:0)
[09:06:15.154] Timer tick, asking Buildbucket for the build status
[09:06:15.311] Build 8857144795334624416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:15.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:3:0) after 1m0s
[09:07:15.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:3:0)
[09:07:15.329] Timer tick, asking Buildbucket for the build status
[09:07:15.414] Build 8857144795334624416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:15.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:4:0) after 1m0s
[09:08:15.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:4:0)
[09:08:15.421] Timer tick, asking Buildbucket for the build status
[09:08:16.313] Build 8857144795334624416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:16.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:5:0) after 1m0s
[09:09:16.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:5:0)
[09:09:16.334] Timer tick, asking Buildbucket for the build status
[09:09:26.395] Build 8857144795334624416: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:26.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:6:0) after 1m0s
[09:10:03.564] Received PubSub notification, asking Buildbucket for the build status
[09:10:03.652] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:26.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:6:0)
[09:10:26.416] Timer tick, asking Buildbucket for the build status
[09:10:28.012] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:28.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:8:0) after 1m0s
[09:11:28.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:8:0)
[09:11:28.033] Timer tick, asking Buildbucket for the build status
[09:11:28.151] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:28.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:9:0) after 1m0s
[09:12:28.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:9:0)
[09:12:28.171] Timer tick, asking Buildbucket for the build status
[09:12:28.253] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:28.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:10:0) after 1m0s
[09:13:28.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:10:0)
[09:13:28.278] Timer tick, asking Buildbucket for the build status
[09:13:28.347] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:28.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:11:0) after 1m0s
[09:14:28.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:11:0)
[09:14:28.322] Timer tick, asking Buildbucket for the build status
[09:14:28.528] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:28.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:12:0) after 1m0s
[09:15:28.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:12:0)
[09:15:28.547] Timer tick, asking Buildbucket for the build status
[09:15:29.016] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:29.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:13:0) after 1m0s
[09:16:29.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:13:0)
[09:16:29.050] Timer tick, asking Buildbucket for the build status
[09:16:29.172] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:29.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:14:0) after 1m0s
[09:17:29.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:14:0)
[09:17:29.173] Timer tick, asking Buildbucket for the build status
[09:17:29.393] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:29.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:15:0) after 1m0s
[09:18:29.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:15:0)
[09:18:29.439] Timer tick, asking Buildbucket for the build status
[09:18:29.504] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:29.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:16:0) after 1m0s
[09:19:29.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:16:0)
[09:19:29.496] Timer tick, asking Buildbucket for the build status
[09:19:29.575] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:29.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:17:0) after 1m0s
[09:20:29.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:17:0)
[09:20:29.617] Timer tick, asking Buildbucket for the build status
[09:20:29.762] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:29.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:18:0) after 1m0s
[09:21:29.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:18:0)
[09:21:29.783] Timer tick, asking Buildbucket for the build status
[09:21:29.851] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:29.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:19:0) after 1m0s
[09:22:29.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:19:0)
[09:22:29.922] Timer tick, asking Buildbucket for the build status
[09:22:30.135] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:30.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:20:0) after 1m0s
[09:23:30.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:20:0)
[09:23:30.162] Timer tick, asking Buildbucket for the build status
[09:23:30.409] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:30.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:21:0) after 1m0s
[09:24:30.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:21:0)
[09:24:30.483] Timer tick, asking Buildbucket for the build status
[09:24:30.646] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:30.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:22:0) after 1m0s
[09:25:30.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:22:0)
[09:25:30.671] Timer tick, asking Buildbucket for the build status
[09:25:30.819] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:30.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:23:0) after 1m0s
[09:26:30.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:23:0)
[09:26:30.914] Timer tick, asking Buildbucket for the build status
[09:26:31.027] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:31.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:24:0) after 1m0s
[09:27:31.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:24:0)
[09:27:31.207] Timer tick, asking Buildbucket for the build status
[09:27:31.310] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:31.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:25:0) after 1m0s
[09:28:31.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:25:0)
[09:28:31.317] Timer tick, asking Buildbucket for the build status
[09:28:31.376] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:31.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:26:0) after 1m0s
[09:29:31.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:26:0)
[09:29:31.396] Timer tick, asking Buildbucket for the build status
[09:29:31.479] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:31.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:27:0) after 1m0s
[09:30:31.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:27:0)
[09:30:31.503] Timer tick, asking Buildbucket for the build status
[09:30:31.618] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:31.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:28:0) after 1m0s
[09:31:31.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:28:0)
[09:31:31.633] Timer tick, asking Buildbucket for the build status
[09:31:31.831] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:31.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:29:0) after 1m0s
[09:32:31.932] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:29:0)
[09:32:31.932] Timer tick, asking Buildbucket for the build status
[09:32:31.979] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:31.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:30:0) after 1m0s
[09:33:32.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:30:0)
[09:33:32.026] Timer tick, asking Buildbucket for the build status
[09:33:32.098] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:32.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:31:0) after 1m0s
[09:34:32.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:31:0)
[09:34:32.132] Timer tick, asking Buildbucket for the build status
[09:34:32.204] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:32.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:32:0) after 1m0s
[09:35:32.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:32:0)
[09:35:32.192] Timer tick, asking Buildbucket for the build status
[09:35:32.371] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:32.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:33:0) after 1m0s
[09:36:32.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:33:0)
[09:36:32.482] Timer tick, asking Buildbucket for the build status
[09:36:32.756] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:32.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:34:0) after 1m0s
[09:37:32.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:34:0)
[09:37:32.791] Timer tick, asking Buildbucket for the build status
[09:37:32.889] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:32.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:35:0) after 1m0s
[09:38:32.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:35:0)
[09:38:32.986] Timer tick, asking Buildbucket for the build status
[09:38:33.049] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:33.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:36:0) after 1m0s
[09:39:33.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:36:0)
[09:39:33.124] Timer tick, asking Buildbucket for the build status
[09:39:33.221] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:33.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:37:0) after 1m0s
[09:40:33.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:37:0)
[09:40:33.291] Timer tick, asking Buildbucket for the build status
[09:40:33.381] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:33.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:38:0) after 1m0s
[09:41:33.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:38:0)
[09:41:33.434] Timer tick, asking Buildbucket for the build status
[09:41:33.545] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:33.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:39:0) after 1m0s
[09:42:33.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:39:0)
[09:42:33.597] Timer tick, asking Buildbucket for the build status
[09:42:33.727] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:33.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:40:0) after 1m0s
[09:43:33.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:40:0)
[09:43:33.838] Timer tick, asking Buildbucket for the build status
[09:43:34.013] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:34.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:41:0) after 1m0s
[09:44:34.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:41:0)
[09:44:34.195] Timer tick, asking Buildbucket for the build status
[09:44:34.265] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:34.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:42:0) after 1m0s
[09:45:34.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:42:0)
[09:45:34.370] Timer tick, asking Buildbucket for the build status
[09:45:34.558] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:34.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:43:0) after 1m0s
[09:46:34.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:43:0)
[09:46:34.597] Timer tick, asking Buildbucket for the build status
[09:46:34.700] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:34.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:44:0) after 1m0s
[09:47:34.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:44:0)
[09:47:34.718] Timer tick, asking Buildbucket for the build status
[09:47:34.792] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:34.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:45:0) after 1m0s
[09:48:34.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:45:0)
[09:48:34.762] Timer tick, asking Buildbucket for the build status
[09:48:34.932] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:34.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:46:0) after 1m0s
[09:49:34.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:46:0)
[09:49:34.978] Timer tick, asking Buildbucket for the build status
[09:49:35.054] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:35.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:47:0) after 1m0s
[09:50:35.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:47:0)
[09:50:35.076] Timer tick, asking Buildbucket for the build status
[09:50:35.176] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:35.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:48:0) after 1m0s
[09:51:35.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:48:0)
[09:51:35.209] Timer tick, asking Buildbucket for the build status
[09:51:35.406] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:35.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:49:0) after 1m0s
[09:52:35.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:49:0)
[09:52:35.429] Timer tick, asking Buildbucket for the build status
[09:52:35.486] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:35.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:50:0) after 1m0s
[09:53:35.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:50:0)
[09:53:35.514] Timer tick, asking Buildbucket for the build status
[09:53:35.581] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:35.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:51:0) after 1m0s
[09:54:35.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:51:0)
[09:54:35.627] Timer tick, asking Buildbucket for the build status
[09:54:35.685] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:35.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:52:0) after 1m0s
[09:55:35.738] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:52:0)
[09:55:35.738] Timer tick, asking Buildbucket for the build status
[09:55:35.840] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:35.840] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:53:0) after 1m0s
[09:56:35.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:53:0)
[09:56:35.861] Timer tick, asking Buildbucket for the build status
[09:56:35.933] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:35.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:54:0) after 1m0s
[09:57:35.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:54:0)
[09:57:35.966] Timer tick, asking Buildbucket for the build status
[09:57:36.042] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:36.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:55:0) after 1m0s
[09:58:36.099] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:55:0)
[09:58:36.099] Timer tick, asking Buildbucket for the build status
[09:58:36.272] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:36.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:56:0) after 1m0s
[09:59:36.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:56:0)
[09:59:36.331] Timer tick, asking Buildbucket for the build status
[09:59:36.559] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:36.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:57:0) after 1m0s
[10:00:36.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:57:0)
[10:00:36.584] Timer tick, asking Buildbucket for the build status
[10:00:36.765] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:36.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:58:0) after 1m0s
[10:01:36.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:58:0)
[10:01:36.763] Timer tick, asking Buildbucket for the build status
[10:01:36.872] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:36.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:59:0) after 1m0s
[10:02:36.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:59:0)
[10:02:36.926] Timer tick, asking Buildbucket for the build status
[10:02:37.012] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:37.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:60:0) after 1m0s
[10:03:37.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:60:0)
[10:03:37.129] Timer tick, asking Buildbucket for the build status
[10:03:37.206] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:37.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:61:0) after 1m0s
[10:04:37.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:61:0)
[10:04:37.283] Timer tick, asking Buildbucket for the build status
[10:04:37.606] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:37.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:62:0) after 1m0s
[10:05:37.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:62:0)
[10:05:37.687] Timer tick, asking Buildbucket for the build status
[10:05:37.869] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:37.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:63:0) after 1m0s
[10:06:37.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:63:0)
[10:06:37.906] Timer tick, asking Buildbucket for the build status
[10:06:38.318] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:38.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:64:0) after 1m0s
[10:07:38.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:64:0)
[10:07:38.342] Timer tick, asking Buildbucket for the build status
[10:07:38.446] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:38.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:65:0) after 1m0s
[10:08:38.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:65:0)
[10:08:38.452] Timer tick, asking Buildbucket for the build status
[10:08:38.557] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:38.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:66:0) after 1m0s
[10:09:38.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:66:0)
[10:09:38.610] Timer tick, asking Buildbucket for the build status
[10:09:38.708] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:38.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:67:0) after 1m0s
[10:10:38.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:67:0)
[10:10:38.745] Timer tick, asking Buildbucket for the build status
[10:10:38.851] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:38.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:68:0) after 1m0s
[10:11:38.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:68:0)
[10:11:38.879] Timer tick, asking Buildbucket for the build status
[10:11:39.221] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:39.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:69:0) after 1m0s
[10:12:39.239] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:69:0)
[10:12:39.239] Timer tick, asking Buildbucket for the build status
[10:12:39.736] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:39.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:70:0) after 1m0s
[10:13:39.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:70:0)
[10:13:39.779] Timer tick, asking Buildbucket for the build status
[10:13:39.966] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:39.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:71:0) after 1m0s
[10:14:40.031] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:71:0)
[10:14:40.031] Timer tick, asking Buildbucket for the build status
[10:14:40.296] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:40.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:72:0) after 1m0s
[10:15:40.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:72:0)
[10:15:40.308] Timer tick, asking Buildbucket for the build status
[10:15:40.625] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:40.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:73:0) after 1m0s
[10:16:40.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:73:0)
[10:16:40.701] Timer tick, asking Buildbucket for the build status
[10:16:40.851] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:40.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:74:0) after 1m0s
[10:17:40.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:74:0)
[10:17:40.900] Timer tick, asking Buildbucket for the build status
[10:17:41.020] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:41.020] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:75:0) after 1m0s
[10:18:41.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:75:0)
[10:18:41.062] Timer tick, asking Buildbucket for the build status
[10:18:41.145] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:41.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:76:0) after 1m0s
[10:19:41.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:76:0)
[10:19:41.176] Timer tick, asking Buildbucket for the build status
[10:19:41.317] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:41.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:77:0) after 1m0s
[10:20:41.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:77:0)
[10:20:41.366] Timer tick, asking Buildbucket for the build status
[10:20:41.470] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:41.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:78:0) after 1m0s
[10:21:41.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:78:0)
[10:21:41.566] Timer tick, asking Buildbucket for the build status
[10:21:41.656] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:41.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:79:0) after 1m0s
[10:22:41.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:79:0)
[10:22:41.812] Timer tick, asking Buildbucket for the build status
[10:22:41.896] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:41.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:80:0) after 1m0s
[10:23:41.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:80:0)
[10:23:41.987] Timer tick, asking Buildbucket for the build status
[10:23:42.124] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:42.124] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:81:0) after 1m0s
[10:24:42.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:81:0)
[10:24:42.278] Timer tick, asking Buildbucket for the build status
[10:24:42.682] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:42.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:82:0) after 1m0s
[10:25:42.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:82:0)
[10:25:42.745] Timer tick, asking Buildbucket for the build status
[10:25:42.875] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:42.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:83:0) after 1m0s
[10:26:42.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:83:0)
[10:26:42.900] Timer tick, asking Buildbucket for the build status
[10:26:43.043] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:43.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:84:0) after 1m0s
[10:27:43.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:84:0)
[10:27:43.065] Timer tick, asking Buildbucket for the build status
[10:27:43.137] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:43.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:85:0) after 1m0s
[10:28:43.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:85:0)
[10:28:43.123] Timer tick, asking Buildbucket for the build status
[10:28:43.309] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:43.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:86:0) after 1m0s
[10:29:43.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:86:0)
[10:29:43.343] Timer tick, asking Buildbucket for the build status
[10:29:43.447] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:87:0) after 1m0s
[10:30:43.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:87:0)
[10:30:43.474] Timer tick, asking Buildbucket for the build status
[10:30:43.600] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:43.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:88:0) after 1m0s
[10:31:43.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:88:0)
[10:31:43.714] Timer tick, asking Buildbucket for the build status
[10:31:43.790] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:43.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:89:0) after 1m0s
[10:32:43.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:89:0)
[10:32:43.823] Timer tick, asking Buildbucket for the build status
[10:32:43.911] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:43.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:90:0) after 1m0s
[10:33:43.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:90:0)
[10:33:43.984] Timer tick, asking Buildbucket for the build status
[10:33:44.062] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:44.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:91:0) after 1m0s
[10:34:44.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:91:0)
[10:34:44.251] Timer tick, asking Buildbucket for the build status
[10:34:44.451] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:44.451] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:92:0) after 1m0s
[10:35:44.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:92:0)
[10:35:44.464] Timer tick, asking Buildbucket for the build status
[10:35:44.631] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:44.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:93:0) after 1m0s
[10:36:44.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:93:0)
[10:36:44.793] Timer tick, asking Buildbucket for the build status
[10:36:44.876] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:44.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:94:0) after 1m0s
[10:37:45.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:94:0)
[10:37:45.124] Timer tick, asking Buildbucket for the build status
[10:37:45.204] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:45.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:95:0) after 1m0s
[10:38:45.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:95:0)
[10:38:45.446] Timer tick, asking Buildbucket for the build status
[10:38:45.510] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:45.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:96:0) after 1m0s
[10:39:45.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:96:0)
[10:39:45.727] Timer tick, asking Buildbucket for the build status
[10:39:45.826] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:45.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:97:0) after 1m0s
[10:40:46.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:97:0)
[10:40:46.036] Timer tick, asking Buildbucket for the build status
[10:40:46.728] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:46.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:98:0) after 1m0s
[10:41:46.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:98:0)
[10:41:46.929] Timer tick, asking Buildbucket for the build status
[10:41:47.205] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:47.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:99:0) after 1m0s
[10:42:47.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:99:0)
[10:42:47.292] Timer tick, asking Buildbucket for the build status
[10:42:47.567] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:47.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:100:0) after 1m0s
[10:43:47.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:100:0)
[10:43:47.714] Timer tick, asking Buildbucket for the build status
[10:43:47.841] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:47.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:101:0) after 1m0s
[10:44:47.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:101:0)
[10:44:47.952] Timer tick, asking Buildbucket for the build status
[10:44:48.613] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:48.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:102:0) after 1m0s
[10:45:48.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:102:0)
[10:45:48.631] Timer tick, asking Buildbucket for the build status
[10:45:48.869] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:48.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:103:0) after 1m0s
[10:46:48.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:103:0)
[10:46:48.882] Timer tick, asking Buildbucket for the build status
[10:46:48.971] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:48.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:104:0) after 1m0s
[10:47:49.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:104:0)
[10:47:49.065] Timer tick, asking Buildbucket for the build status
[10:47:49.134] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:49.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:105:0) after 1m0s
[10:48:49.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:105:0)
[10:48:49.192] Timer tick, asking Buildbucket for the build status
[10:48:49.436] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:49.436] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:106:0) after 1m0s
[10:49:49.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:106:0)
[10:49:49.544] Timer tick, asking Buildbucket for the build status
[10:49:49.649] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:49.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:107:0) after 1m0s
[10:50:49.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:107:0)
[10:50:49.746] Timer tick, asking Buildbucket for the build status
[10:50:49.885] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:49.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:108:0) after 1m0s
[10:51:50.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:108:0)
[10:51:50.004] Timer tick, asking Buildbucket for the build status
[10:51:50.100] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:50.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:109:0) after 1m0s
[10:52:50.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:109:0)
[10:52:50.155] Timer tick, asking Buildbucket for the build status
[10:52:50.629] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:50.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:110:0) after 1m0s
[10:53:50.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:110:0)
[10:53:50.699] Timer tick, asking Buildbucket for the build status
[10:53:50.770] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:50.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:111:0) after 1m0s
[10:54:50.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:111:0)
[10:54:50.881] Timer tick, asking Buildbucket for the build status
[10:54:51.062] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:51.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:112:0) after 1m0s
[10:55:51.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:112:0)
[10:55:51.223] Timer tick, asking Buildbucket for the build status
[10:55:51.604] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:51.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:113:0) after 1m0s
[10:56:51.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:113:0)
[10:56:51.710] Timer tick, asking Buildbucket for the build status
[10:56:51.953] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:51.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:114:0) after 1m0s
[10:57:52.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:114:0)
[10:57:52.016] Timer tick, asking Buildbucket for the build status
[10:57:52.255] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:52.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:115:0) after 1m0s
[10:58:52.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:115:0)
[10:58:52.307] Timer tick, asking Buildbucket for the build status
[10:58:52.608] Build 8857144795334624416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:52.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022574857609679952:116:0) after 1m0s
[10:59:35.022] Received PubSub notification, asking Buildbucket for the build status
[10:59:35.098] Build 8857144795334624416: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:59:35.098] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611572373064219",
"created_by": "project:chromium",
"created_ts": "1611565514206326",
"id": "8857144795334624416",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9427efac938309f94f9508872f05160a6ca7b8d4\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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\": \"9427efac938309f94f9508872f05160a6ca7b8d4\", \"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-197-j1hi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22334, \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"9427efac938309f94f9508872f05160a6ca7b8d4\", \"got_revision_cp\": \"refs/heads/master@{#846648}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9427efac938309f94f9508872f05160a6ca7b8d4\", \"swarm_hashes_refs/heads/master(at){#846648}_without_patch\": {\"absl_hardening_tests\": \"a3bd4b078a9f594562a4f2c6252dbdce7dbaf521\", \"accessibility_unittests\": \"fd95cb439330e0e1dd6b00b6f831119090fd06a6\", \"angle_unittests\": \"b115aa65c3d018216f882663c23552155eaa230a\", \"app_list_unittests\": \"190588cacecaadc33212aaf5032946dc0b834afd\", \"app_shell_unittests\": \"5b052fa83bc816ad9798cfcfcb612717b7365ca7\", \"ash_unittests\": \"87f517122fb92838a41fbbfc041cb44cd5c2999e\", \"aura_unittests\": \"e87d84756e102a47d47fda62c865bc9537f4b83a\", \"base_unittests\": \"5d89cf795c3193c3368e1bab3719cfe8f5928140\", \"base_util_unittests\": \"12dae3edfad4eb2a751d11c33684e3f9711ddca6\", \"blink_common_unittests\": \"fa70b70c506e6126d8ebe831da3fd942d5a7492e\", \"blink_fuzzer_unittests\": \"341e1a3da506ebbd42b6a448f37ca93bb5be21e4\", \"blink_heap_unittests\": \"802882315727a2f41b23c71de538d8380d2183dc\", \"blink_platform_unittests\": \"1c3be94b02b766b8d74e480dbf27a84ac62330ab\", \"blink_unittests\": \"bcd27e9c75d342588f6a0f26ea2bf6f041f9ed76\", \"boringssl_crypto_tests\": \"d18498d5161a89821f60049f177d17a189f1ac1c\", \"boringssl_ssl_tests\": \"203b25c35171c125cfbc18c61936df84ba20b908\", \"browser_tests\": \"e826081f9e3bbf811cbef0fee4558d37217d7b68\", \"capture_unittests\": \"29c80df15aa0dc8e1433e7ad066018406dcc67e9\", \"cast_unittests\": \"5bc959db5069fff28813a320670b8af97e67a2cd\", \"cc_unittests\": \"c2b0bb38492e99ec5ae0e49bcaaf7d4ba1fb3340\", \"chrome_app_unittests\": \"87438cf6baeebe079cde3162344e17cb0fda0291\", \"chromedriver_unittests\": \"5f1f6383b3c78d51eea1c5d655f807b82c144cc9\", \"chromeos_components_unittests\": \"7fbb4980950b55925467ef0ea84a0b048c6cd2e7\", \"chromeos_unittests\": \"7365dbf23e5e59a5881767d2c5bf19a5f7baaf26\", \"components_browsertests\": \"dabb51d48564df108528390e2deb3433166b85e8\", \"components_unittests\": \"e07db9aa1c5c5d73691183bd3a2aca12d6345ac1\", \"compositor_unittests\": \"650269cffd2bdf24850ad6c91de7ba8e34b8ad7a\", \"content_browsertests\": \"f7bc784363aa138894c7806146a4a329503bb327\", \"content_unittests\": \"02f113eb80859081333511f6ddbd7c1f15aa18da\", \"crashpad_tests\": \"d0420808fbffdfeaec058facb56adb0daebf6a3f\", \"crypto_unittests\": \"23e9e54a086c15aaea103dc19c18ac00db0795cb\", \"dbus_unittests\": \"cf80aaaa41d01df8af2b134e3cebe1d3d0d980e8\", \"device_unittests\": \"1afe7ee76d71ff5ec7c31bf73250b99eaad1d170\", \"display_unittests\": \"850facba9c036b8e1bac7a02aeff2ca6c70bbed2\", \"events_unittests\": \"dbd4339f30e808ea4d3b4711495653aa662fcae5\", \"exo_unittests\": \"b0453a4d6c8e76e4a5e0bf6b37de8a2a4d46356b\", \"extensions_browsertests\": \"c417c805d31f9b363ce9709851775b412fc8a513\", \"extensions_unittests\": \"3b15a20c2073ced06dc69fe356dd79b05a75db51\", \"filesystem_service_unittests\": \"b80a984bfcfa470cfc45932e3bc7de9b284172d9\", \"gcm_unit_tests\": \"a363f988ccafc2295db7197d36225a96d156ae47\", \"gfx_unittests\": \"a61cd14972889af03ff10af1de13f563a0f7440e\", \"gin_unittests\": \"c22309d9e0f76160b07aeaa4877cc90b9f9c4869\", \"gl_unittests_ozone\": \"a67008cab59bec74bc6551a6a57c8771f6703158\", \"google_apis_unittests\": \"bf67fe81e955330b91d8f70f5099b50429f549f5\", \"gpu_unittests\": \"f5cf82be0999456e36633d16a5a3b07e7dd20463\", \"gwp_asan_unittests\": \"33e5463c96ba9d12d5050976a9bde30f38bf0c2f\", \"interactive_ui_tests\": \"088454de0bf51439b2c7685aea9ca7c24610c245\", \"ipc_tests\": \"caace76828c07d0cfa8ce7c06a9f6265b9d49331\", \"jingle_unittests\": \"080a3207f34d4d62b6fbff60d19874287c37fe07\", \"keyboard_unittests\": \"cec38dbf2c0dda5f03db446120ac4ef10a3352d1\", \"latency_unittests\": \"4bc2f8c7723a0223f4042fa41b76dae6b0d5f4e7\", \"libjingle_xmpp_unittests\": \"2d44f5307d33f186cea3c89c81ee41955c2fc47a\", \"liburlpattern_unittests\": \"4dcc14172e32b2470af3c1ef92fe8b928f005a83\", \"media_blink_unittests\": \"55025ab9b925ccb00526f56026d5f43818b030c6\", \"media_unittests\": \"b447e213a75cd5dc950e5003a0f83090718c65df\", \"message_center_unittests\": \"2f62dfc17e1e306e4216a81df5d6c95fc137807e\", \"midi_unittests\": \"59d9d04e01f4d13e9818b0e35df99fc1206e73ab\", \"mojo_core_unittests\": \"2d6068938942d1043c61d8a2c1068497a12419ff\", \"mojo_unittests\": \"d87fe15ea751383be6c082d3411b286503ab39a0\", \"nacl_helper_nonsfi_unittests\": \"f3c7be4dfb040885b8dedaf44399517a302df467\", \"nacl_loader_unittests\": \"1105f4b10863faff4b26d9d6b6d6bd73255e9858\", \"native_theme_unittests\": \"46399f638e467feed087df98ef4e9b4f660e507a\", \"net_unittests\": \"f47422dde97533466d11614ce253206571a433b5\", \"ozone_gl_unittests\": \"c0ed959073eb0ad69aecc4e33f0e56bf3461acab\", \"ozone_unittests\": \"930122a8dc680a3b7776f794e6fa233f54b9d862\", \"ozone_x11_unittests\": \"429475438edea562f9469e1434225256649db62c\", \"pdf_unittests\": \"662c7eeb883f7a8a168b033c1c53bf064ba9b51e\", \"perfetto_unittests\": \"51f87dd4922912657977133b98ecff9edb228492\", \"ppapi_unittests\": \"a93899772499e07ae84eb062f5266558daaac7e6\", \"printing_unittests\": \"cc290c6dadf541deedbd3e8cb614fb1df49568b8\", \"remoting_unittests\": \"7be9a0cdd4fdcfa5a489a8528b4d913468f71316\", \"sandbox_linux_unittests\": \"87fddbcf7184b9e45062242efdf5a1d185f7d271\", \"service_manager_unittests\": \"700a1534ed95cbd6f8362c7a3ffba924da5aa37d\", \"services_unittests\": \"21143df628a8b75f911c5164c725ec2422ee7baf\", \"shell_dialogs_unittests\": \"7b7ab936628b412a026118cfe302fb7e359d36d8\", \"shell_encryption_unittests\": \"97f72235aaeab1f242ae8785ba54f60e6f683d86\", \"skia_unittests\": \"18634d689e3d44afaae24ca7636e22483c7aa92a\", \"snapshot_unittests\": \"967f6769b7b3091daaad7fd73f2e317665a37e4a\", \"sql_unittests\": \"e9b8134b70cb790b78419755f5741a9f15c1e5fa\", \"storage_unittests\": \"da86851bc54e1ceca0962747b109f5116f28d8e6\", \"sync_integration_tests\": \"f1ec463d0b74a293667aacbe79721ff85ee13908\", \"traffic_annotation_auditor_unittests\": \"13c5abd6eb06ca0898dd1a5edfdae87c1bb1d90a\", \"ui_base_unittests\": \"96f9f87663a5cc0870de9cb299be5f43111b9b63\", \"ui_chromeos_unittests\": \"1fb26ba599968698475a5365e515ccecbedf6eb1\", \"ui_touch_selection_unittests\": \"671a28d52b57d4f32fee6638c5580ed2830e8776\", \"unit_tests\": \"ec97dd5d6bb2894e6a414b9180e91ae3cff80f7b\", \"url_unittests\": \"57d1481c20b737ba4027526b833052f0c84c3aa9\", \"usage_time_limit_unittests\": \"ac2a9500c9568098f93af80e23a5a29ad935706c\", \"views_unittests\": \"5b4b57182a6d7aa21f2eb6089b8286b0da0edba2\", \"viz_unittests\": \"181064511037c83b3ce26bfcf769d3e6291fbfaa\", \"wm_unittests\": \"3b21002f22e52428046c70a706beb0c9a4bec9f6\", \"wtf_unittests\": \"923edfa76b8a39e3c5c8a82e0a455c00ac23a4d2\", \"zlib_unittests\": \"3a4b441cf295279b23fd9b4be9dec5a39012eb65\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02c50c25350278ea3e2feb85c748c248af9e0ccc93852ce717c8651b544f7a96_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-197-j1hi\"], \"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": "1611565802409408",
"status": "COMPLETED",
"status_changed_ts": "1611572373598347",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22334",
"builder:linux-chromeos-dbg",
"buildset:commit/git/9427efac938309f94f9508872f05160a6ca7b8d4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9427efac938309f94f9508872f05160a6ca7b8d4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022574857609679952",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857144795334624416/+/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:51519bcc1e7b7310",
"user_agent:luci-scheduler"
],
"updated_ts": "1611572373704428",
"url": "https://ci.chromium.org/b/8857144795334624416",
"utcnow_ts": "1611572375090042"
}
}
[10:59:35.098] Invocation finished in 1h54m22.445922559s with status SUCCEEDED