[14:08:10.255] New invocation is queued and will start shortly
[14:08:11.377] Starting the invocation (attempt 1)
[14:08:11.450] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:08:11.450] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:08:11.450] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031071911868868896",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d42167ebafe3dc799306e95549a2be4acf9e2f5a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9031071911868868896",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"gitiles_ref:refs/heads/master"
]
}
[14:09:11.413] Failed to add buildbucket build - Put https://cr-buildbucket.appspot.com/_ah/api/buildbucket/v1/builds?alt=json&prettyPrint=false: API error 5 (urlfetch: DEADLINE_EXCEEDED)
[14:09:11.414] The invocation will be retried
[14:09:16.503] Starting the invocation (attempt 2)
[14:09:16.593] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:09:16.593] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:09:16.593] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031071911868868896",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d42167ebafe3dc799306e95549a2be4acf9e2f5a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9031071911868868896",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"gitiles_ref:refs/heads/master"
]
}
[14:09:17.283] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603462156745603",
"id": "8865641781486913504",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d42167ebafe3dc799306e95549a2be4acf9e2f5a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603462157144452",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/20979",
"builder:linux-chromeos-dbg",
"buildset:commit/git/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031071911868868896",
"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/8865641781486913504/+/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": "1603462157144622",
"url": "https://ci.chromium.org/b/8865641781486913504",
"utcnow_ts": "1603462157273976"
}
}
[14:09:17.283] Task URL: https://ci.chromium.org/b/8865641781486913504
[14:09:17.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:5:0) after 1m0s
[14:09:24.501] Received PubSub notification, asking Buildbucket for the build status
[14:09:24.607] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:17.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:5:0)
[14:10:17.335] Timer tick, asking Buildbucket for the build status
[14:10:17.542] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:17.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:7:0) after 1m0s
[14:11:17.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:7:0)
[14:11:17.627] Timer tick, asking Buildbucket for the build status
[14:11:17.882] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:17.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:8:0) after 1m0s
[14:12:17.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:8:0)
[14:12:17.910] Timer tick, asking Buildbucket for the build status
[14:12:19.414] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:19.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:9:0) after 1m0s
[14:13:19.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:9:0)
[14:13:19.511] Timer tick, asking Buildbucket for the build status
[14:13:19.616] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:19.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:10:0) after 1m0s
[14:14:19.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:10:0)
[14:14:19.677] Timer tick, asking Buildbucket for the build status
[14:14:19.784] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:19.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:11:0) after 1m0s
[14:15:19.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:11:0)
[14:15:19.836] Timer tick, asking Buildbucket for the build status
[14:15:19.950] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:19.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:12:0) after 1m0s
[14:16:20.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:12:0)
[14:16:20.018] Timer tick, asking Buildbucket for the build status
[14:16:20.245] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:20.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:13:0) after 1m0s
[14:17:20.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:13:0)
[14:17:20.353] Timer tick, asking Buildbucket for the build status
[14:17:20.552] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:20.552] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:14:0) after 1m0s
[14:18:20.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:14:0)
[14:18:20.657] Timer tick, asking Buildbucket for the build status
[14:18:20.837] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:20.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:15:0) after 1m0s
[14:19:20.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:15:0)
[14:19:20.870] Timer tick, asking Buildbucket for the build status
[14:19:20.985] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:20.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:16:0) after 1m0s
[14:20:21.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:16:0)
[14:20:21.008] Timer tick, asking Buildbucket for the build status
[14:20:21.077] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:21.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:17:0) after 1m0s
[14:21:21.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:17:0)
[14:21:21.119] Timer tick, asking Buildbucket for the build status
[14:21:21.191] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:21.191] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:18:0) after 1m0s
[14:22:21.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:18:0)
[14:22:21.252] Timer tick, asking Buildbucket for the build status
[14:22:21.330] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:21.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:19:0) after 1m0s
[14:23:21.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:19:0)
[14:23:21.368] Timer tick, asking Buildbucket for the build status
[14:23:21.732] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:21.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:20:0) after 1m0s
[14:24:21.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:20:0)
[14:24:21.754] Timer tick, asking Buildbucket for the build status
[14:24:21.884] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:21.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:21:0) after 1m0s
[14:25:21.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:21:0)
[14:25:21.937] Timer tick, asking Buildbucket for the build status
[14:25:22.014] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:22.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:22:0) after 1m0s
[14:26:22.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:22:0)
[14:26:22.406] Timer tick, asking Buildbucket for the build status
[14:26:22.534] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:22.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:23:0) after 1m0s
[14:27:22.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:23:0)
[14:27:22.676] Timer tick, asking Buildbucket for the build status
[14:27:22.906] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:22.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:24:0) after 1m0s
[14:28:23.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:24:0)
[14:28:23.134] Timer tick, asking Buildbucket for the build status
[14:28:23.346] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:23.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:25:0) after 1m0s
[14:29:23.590] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:25:0)
[14:29:23.590] Timer tick, asking Buildbucket for the build status
[14:29:24.054] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:24.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:26:0) after 1m0s
[14:30:24.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:26:0)
[14:30:24.182] Timer tick, asking Buildbucket for the build status
[14:30:24.280] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:24.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:27:0) after 1m0s
[14:31:24.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:27:0)
[14:31:24.486] Timer tick, asking Buildbucket for the build status
[14:31:24.693] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:24.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:28:0) after 1m0s
[14:32:24.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:28:0)
[14:32:24.746] Timer tick, asking Buildbucket for the build status
[14:32:24.824] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:24.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:29:0) after 1m0s
[14:33:24.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:29:0)
[14:33:24.863] Timer tick, asking Buildbucket for the build status
[14:33:25.102] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:25.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:30:0) after 1m0s
[14:34:25.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:30:0)
[14:34:25.168] Timer tick, asking Buildbucket for the build status
[14:34:25.317] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:25.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:31:0) after 1m0s
[14:35:25.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:31:0)
[14:35:25.382] Timer tick, asking Buildbucket for the build status
[14:35:25.457] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:25.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:32:0) after 1m0s
[14:36:25.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:32:0)
[14:36:25.530] Timer tick, asking Buildbucket for the build status
[14:36:25.783] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:25.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:33:0) after 1m0s
[14:37:25.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:33:0)
[14:37:25.770] Timer tick, asking Buildbucket for the build status
[14:37:25.927] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:25.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:34:0) after 1m0s
[14:38:26.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:34:0)
[14:38:26.012] Timer tick, asking Buildbucket for the build status
[14:38:26.106] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:26.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:35:0) after 1m0s
[14:39:26.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:35:0)
[14:39:26.152] Timer tick, asking Buildbucket for the build status
[14:39:26.593] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:26.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:36:0) after 1m0s
[14:40:26.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:36:0)
[14:40:26.676] Timer tick, asking Buildbucket for the build status
[14:40:26.846] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:26.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:37:0) after 1m0s
[14:41:26.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:37:0)
[14:41:26.874] Timer tick, asking Buildbucket for the build status
[14:41:26.947] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:26.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:38:0) after 1m0s
[14:42:27.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:38:0)
[14:42:27.057] Timer tick, asking Buildbucket for the build status
[14:42:27.337] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:27.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:39:0) after 1m0s
[14:43:27.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:39:0)
[14:43:27.404] Timer tick, asking Buildbucket for the build status
[14:43:27.541] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:27.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:40:0) after 1m0s
[14:44:27.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:40:0)
[14:44:27.568] Timer tick, asking Buildbucket for the build status
[14:44:27.688] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:27.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:41:0) after 1m0s
[14:45:27.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:41:0)
[14:45:27.689] Timer tick, asking Buildbucket for the build status
[14:45:27.839] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:27.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:42:0) after 1m0s
[14:46:27.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:42:0)
[14:46:27.898] Timer tick, asking Buildbucket for the build status
[14:46:28.234] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:28.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:43:0) after 1m0s
[14:47:28.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:43:0)
[14:47:28.274] Timer tick, asking Buildbucket for the build status
[14:47:28.347] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:28.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:44:0) after 1m0s
[14:48:28.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:44:0)
[14:48:28.415] Timer tick, asking Buildbucket for the build status
[14:48:28.503] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:28.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:45:0) after 1m0s
[14:49:28.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:45:0)
[14:49:28.525] Timer tick, asking Buildbucket for the build status
[14:49:28.889] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:28.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:46:0) after 1m0s
[14:50:28.903] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:46:0)
[14:50:28.903] Timer tick, asking Buildbucket for the build status
[14:50:29.008] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:29.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:47:0) after 1m0s
[14:51:29.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:47:0)
[14:51:29.074] Timer tick, asking Buildbucket for the build status
[14:51:29.197] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:29.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:48:0) after 1m0s
[14:52:29.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:48:0)
[14:52:29.193] Timer tick, asking Buildbucket for the build status
[14:52:29.350] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:29.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:49:0) after 1m0s
[14:53:29.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:49:0)
[14:53:29.402] Timer tick, asking Buildbucket for the build status
[14:53:29.496] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:29.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:50:0) after 1m0s
[14:54:29.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:50:0)
[14:54:29.568] Timer tick, asking Buildbucket for the build status
[14:54:29.758] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:29.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:51:0) after 1m0s
[14:55:29.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:51:0)
[14:55:29.808] Timer tick, asking Buildbucket for the build status
[14:55:29.995] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:29.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:52:0) after 1m0s
[14:56:30.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:52:0)
[14:56:30.093] Timer tick, asking Buildbucket for the build status
[14:56:30.212] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:30.212] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:53:0) after 1m0s
[14:57:30.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:53:0)
[14:57:30.332] Timer tick, asking Buildbucket for the build status
[14:57:30.477] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:30.477] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:54:0) after 1m0s
[14:58:30.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:54:0)
[14:58:30.560] Timer tick, asking Buildbucket for the build status
[14:58:30.655] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:30.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:55:0) after 1m0s
[14:59:30.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:55:0)
[14:59:30.724] Timer tick, asking Buildbucket for the build status
[14:59:30.876] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:30.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:56:0) after 1m0s
[15:00:30.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:56:0)
[15:00:30.971] Timer tick, asking Buildbucket for the build status
[15:00:31.068] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:31.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:57:0) after 1m0s
[15:01:31.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:57:0)
[15:01:31.092] Timer tick, asking Buildbucket for the build status
[15:01:31.183] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:31.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:58:0) after 1m0s
[15:02:31.241] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:58:0)
[15:02:31.241] Timer tick, asking Buildbucket for the build status
[15:02:31.352] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:31.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:59:0) after 1m0s
[15:03:31.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:59:0)
[15:03:31.400] Timer tick, asking Buildbucket for the build status
[15:03:31.996] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:31.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:60:0) after 1m0s
[15:04:32.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:60:0)
[15:04:32.022] Timer tick, asking Buildbucket for the build status
[15:04:32.367] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:32.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:61:0) after 1m0s
[15:05:32.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:61:0)
[15:05:32.505] Timer tick, asking Buildbucket for the build status
[15:05:32.603] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:32.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:62:0) after 1m0s
[15:06:32.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:62:0)
[15:06:32.659] Timer tick, asking Buildbucket for the build status
[15:06:32.754] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:32.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:63:0) after 1m0s
[15:07:32.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:63:0)
[15:07:32.813] Timer tick, asking Buildbucket for the build status
[15:07:32.927] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:32.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:64:0) after 1m0s
[15:08:33.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:64:0)
[15:08:33.047] Timer tick, asking Buildbucket for the build status
[15:08:33.192] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:33.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:65:0) after 1m0s
[15:09:33.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:65:0)
[15:09:33.353] Timer tick, asking Buildbucket for the build status
[15:09:33.487] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:33.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:66:0) after 1m0s
[15:10:33.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:66:0)
[15:10:33.496] Timer tick, asking Buildbucket for the build status
[15:10:33.628] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:33.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:67:0) after 1m0s
[15:11:33.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:67:0)
[15:11:33.706] Timer tick, asking Buildbucket for the build status
[15:11:33.842] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:33.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:68:0) after 1m0s
[15:12:33.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:68:0)
[15:12:33.879] Timer tick, asking Buildbucket for the build status
[15:12:34.183] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:34.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:69:0) after 1m0s
[15:13:34.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:69:0)
[15:13:34.262] Timer tick, asking Buildbucket for the build status
[15:13:34.346] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:34.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:70:0) after 1m0s
[15:14:34.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:70:0)
[15:14:34.403] Timer tick, asking Buildbucket for the build status
[15:14:34.547] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:34.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:71:0) after 1m0s
[15:15:35.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:71:0)
[15:15:35.138] Timer tick, asking Buildbucket for the build status
[15:15:35.583] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:35.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:72:0) after 1m0s
[15:16:35.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:72:0)
[15:16:35.835] Timer tick, asking Buildbucket for the build status
[15:16:35.947] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:35.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:73:0) after 1m0s
[15:17:36.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:73:0)
[15:17:36.096] Timer tick, asking Buildbucket for the build status
[15:17:36.179] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:36.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:74:0) after 1m0s
[15:18:36.211] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:74:0)
[15:18:36.211] Timer tick, asking Buildbucket for the build status
[15:18:36.292] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:36.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:75:0) after 1m0s
[15:19:36.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:75:0)
[15:19:36.287] Timer tick, asking Buildbucket for the build status
[15:19:36.418] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:36.418] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:76:0) after 1m0s
[15:20:36.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:76:0)
[15:20:36.494] Timer tick, asking Buildbucket for the build status
[15:20:36.567] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:36.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:77:0) after 1m0s
[15:21:36.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:77:0)
[15:21:36.717] Timer tick, asking Buildbucket for the build status
[15:21:37.112] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:37.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:78:0) after 1m0s
[15:22:37.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:78:0)
[15:22:37.160] Timer tick, asking Buildbucket for the build status
[15:22:37.310] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:37.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:79:0) after 1m0s
[15:23:37.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:79:0)
[15:23:37.354] Timer tick, asking Buildbucket for the build status
[15:23:37.484] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:37.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:80:0) after 1m0s
[15:24:37.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:80:0)
[15:24:37.511] Timer tick, asking Buildbucket for the build status
[15:24:37.636] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:37.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:81:0) after 1m0s
[15:25:37.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:81:0)
[15:25:37.774] Timer tick, asking Buildbucket for the build status
[15:25:37.857] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:37.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:82:0) after 1m0s
[15:26:37.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:82:0)
[15:26:37.950] Timer tick, asking Buildbucket for the build status
[15:26:38.050] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:38.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:83:0) after 1m0s
[15:27:38.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:83:0)
[15:27:38.197] Timer tick, asking Buildbucket for the build status
[15:27:38.280] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:38.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:84:0) after 1m0s
[15:28:38.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:84:0)
[15:28:38.668] Timer tick, asking Buildbucket for the build status
[15:28:38.952] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:38.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:85:0) after 1m0s
[15:29:38.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:85:0)
[15:29:38.983] Timer tick, asking Buildbucket for the build status
[15:29:39.094] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:39.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:86:0) after 1m0s
[15:30:39.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:86:0)
[15:30:39.417] Timer tick, asking Buildbucket for the build status
[15:30:39.731] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:39.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:87:0) after 1m0s
[15:31:39.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:87:0)
[15:31:39.923] Timer tick, asking Buildbucket for the build status
[15:31:40.021] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:40.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:88:0) after 1m0s
[15:32:40.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:88:0)
[15:32:40.825] Timer tick, asking Buildbucket for the build status
[15:32:40.904] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:40.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:89:0) after 1m0s
[15:33:41.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:89:0)
[15:33:41.250] Timer tick, asking Buildbucket for the build status
[15:33:41.397] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:41.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:90:0) after 1m0s
[15:34:41.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:90:0)
[15:34:41.589] Timer tick, asking Buildbucket for the build status
[15:34:41.748] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:41.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:91:0) after 1m0s
[15:35:41.870] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:91:0)
[15:35:41.870] Timer tick, asking Buildbucket for the build status
[15:35:41.947] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:41.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:92:0) after 1m0s
[15:36:42.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:92:0)
[15:36:42.151] Timer tick, asking Buildbucket for the build status
[15:36:42.413] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:42.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:93:0) after 1m0s
[15:37:42.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:93:0)
[15:37:42.446] Timer tick, asking Buildbucket for the build status
[15:37:42.513] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:42.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:94:0) after 1m0s
[15:38:42.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:94:0)
[15:38:42.711] Timer tick, asking Buildbucket for the build status
[15:38:42.805] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:42.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:95:0) after 1m0s
[15:39:42.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:95:0)
[15:39:42.952] Timer tick, asking Buildbucket for the build status
[15:39:43.074] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:43.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:96:0) after 1m0s
[15:40:43.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:96:0)
[15:40:43.349] Timer tick, asking Buildbucket for the build status
[15:40:43.433] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:43.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:97:0) after 1m0s
[15:41:43.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:97:0)
[15:41:43.538] Timer tick, asking Buildbucket for the build status
[15:41:43.636] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:43.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:98:0) after 1m0s
[15:42:43.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:98:0)
[15:42:43.660] Timer tick, asking Buildbucket for the build status
[15:42:43.750] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:43.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:99:0) after 1m0s
[15:43:43.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:99:0)
[15:43:43.866] Timer tick, asking Buildbucket for the build status
[15:43:43.950] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:43.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:100:0) after 1m0s
[15:44:44.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:100:0)
[15:44:44.175] Timer tick, asking Buildbucket for the build status
[15:44:44.719] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:44.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:101:0) after 1m0s
[15:45:44.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:101:0)
[15:45:44.878] Timer tick, asking Buildbucket for the build status
[15:45:44.971] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:44.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:102:0) after 1m0s
[15:46:45.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:102:0)
[15:46:45.017] Timer tick, asking Buildbucket for the build status
[15:46:45.093] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:45.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:103:0) after 1m0s
[15:47:45.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:103:0)
[15:47:45.159] Timer tick, asking Buildbucket for the build status
[15:47:45.421] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:45.421] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:104:0) after 1m0s
[15:48:45.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:104:0)
[15:48:45.430] Timer tick, asking Buildbucket for the build status
[15:48:45.545] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:45.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:105:0) after 1m0s
[15:49:45.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:105:0)
[15:49:45.568] Timer tick, asking Buildbucket for the build status
[15:49:45.682] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:45.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:106:0) after 1m0s
[15:50:45.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:106:0)
[15:50:45.682] Timer tick, asking Buildbucket for the build status
[15:50:45.768] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:45.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:107:0) after 1m0s
[15:51:45.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:107:0)
[15:51:45.815] Timer tick, asking Buildbucket for the build status
[15:51:45.972] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:45.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:108:0) after 1m0s
[15:52:45.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:108:0)
[15:52:45.996] Timer tick, asking Buildbucket for the build status
[15:52:46.244] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:46.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:109:0) after 1m0s
[15:53:46.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:109:0)
[15:53:46.337] Timer tick, asking Buildbucket for the build status
[15:53:46.439] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:46.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:110:0) after 1m0s
[15:54:46.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:110:0)
[15:54:46.454] Timer tick, asking Buildbucket for the build status
[15:54:46.632] Build 8865641781486913504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:46.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031071911868868896:111:0) after 1m0s
[15:54:46.935] Received PubSub notification, asking Buildbucket for the build status
[15:54:47.258] Build 8865641781486913504: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[15:54:47.258] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603468486221053",
"created_by": "project:chromium",
"created_ts": "1603462156745603",
"failure_reason": "BUILD_FAILURE",
"id": "8865641781486913504",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d42167ebafe3dc799306e95549a2be4acf9e2f5a\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- UsingOptGuide/LiteVideoKeyedServiceBrowserTest.LiteVideoCanApplyLiteVideo_Reload/true\\n\\n- All/LoginPromptBrowserTest.CancelRedundantAuths/1\\n\\n- ArcCertStoreBridgeTest.ListCertificatesTest\\n\\n- AllForms/FormStructureBrowserTest.DataDrivenHeuristics/144\\n\\n- ...538 more failure(s) (542 total)...\"}, \"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\": \"d42167ebafe3dc799306e95549a2be4acf9e2f5a\", \"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-9-csso\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 20979, \"got_angle_revision\": \"5641e3c185a85c04f40441698d693773b1e9e808\", \"got_dawn_revision\": \"ade9a5235c73656699662ec9371a9d42fea34ac6\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"d42167ebafe3dc799306e95549a2be4acf9e2f5a\", \"got_revision_cp\": \"refs/heads/master@{#820222}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"cdd7e6fa2dcc5c9b616b5974b2d3951d9a3856b9\", \"got_v8_revision_cp\": \"refs/heads/8.8.152@{#2}\", \"got_webrtc_revision\": \"871500da90418cc3e80e8a0eef257ce11abadd27\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32475}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d42167ebafe3dc799306e95549a2be4acf9e2f5a\", \"swarm_hashes_refs/heads/master(at){#820222}_without_patch\": {\"absl_hardening_tests\": \"09feb96776df3d076437ad293619a8e2422bf4ba\", \"accessibility_unittests\": \"022cb03188c0dc1e3b1a2e21b69360eede39fddd\", \"app_list_unittests\": \"198b85f73a1c71b82a4c7f7d90f837d300d52d07\", \"app_shell_unittests\": \"4c77ce40e3d16d94227c7b65ac6e0e4622d07e06\", \"ash_unittests\": \"5276c66bd814bdc88218a8435fed9977e9359a3a\", \"aura_unittests\": \"4943584056108d3073172f1aa8f3eb6ec1b83f3f\", \"base_unittests\": \"a88602048534b9b6ed5e8004d1b21bec09cc0f71\", \"base_util_unittests\": \"937d6a35da77b6c29ca375363910542085f71767\", \"blink_common_unittests\": \"b2e8892f21cb8479af696d05efadce753c7fbe0e\", \"blink_fuzzer_unittests\": \"6bc6ccf5d1d3e7c3d44ec9fa05a25bb60844bb31\", \"blink_heap_unittests\": \"af298da12f336402b8de9a9eac09879ac12fce8c\", \"blink_platform_unittests\": \"732d8e984ccaa7748011c25786e8b103d0388261\", \"blink_unittests\": \"6eae4501faea95d2a217f0307f1dff20baacbdef\", \"boringssl_crypto_tests\": \"f96412b0590b95142e199562c8552e3ba811e2e2\", \"boringssl_ssl_tests\": \"e6f63a4d76deeafbf54ff032588e30e7d0b1ea4e\", \"browser_tests\": \"2eec9e030c6aae599b2bd3eee26980b3e8228fa6\", \"capture_unittests\": \"03e2fba50175a2b72db518c65b53bb292e76e6f5\", \"cast_unittests\": \"ac0aa5fd586df6400308f131342c3706af1081b1\", \"cc_unittests\": \"0a6578ef3788c9105040237dd0fc3acc0a356b30\", \"chrome_app_unittests\": \"8d37d65bd50e9101d2a2132a7eb827643c649358\", \"chromedriver_unittests\": \"4bb393cb2e66bdc4041211948f0e30bf24ae449f\", \"chromeos_components_unittests\": \"a36422a0d5a1d3130586f5b454801d82535e6681\", \"chromeos_unittests\": \"ba1a88aaaa18e9b994dcd5e86385500bdf9049aa\", \"components_browsertests\": \"4f9cd96f4a26327ad332cd95754097b78976adb6\", \"components_unittests\": \"7bd8be31d89c7802ceeaf7ce2177551ca3008747\", \"compositor_unittests\": \"7a861c53006be9052ae4969f5c26c540329fd304\", \"content_browsertests\": \"0e77e4daaea53582e9b0e3eee0bc0ba4a21433c5\", \"content_unittests\": \"f52ac3997b63e4d4507872fd84dfbaadd8bb6a09\", \"crypto_unittests\": \"9c5171a7b7238c50dd6cd30a15211b85b6dc3130\", \"dbus_unittests\": \"e1aeb7a57509ba764292830a22ad67429dec49a3\", \"device_unittests\": \"4cf8214ed9c974d1b51464544b4c08f61b573704\", \"display_unittests\": \"f3c207e61ab302ca2dc2a6bb90fc24e624d106c9\", \"events_unittests\": \"dd99c7935f71e3698e3535f04f4cc17fced521a3\", \"exo_unittests\": \"0b5356490f21e1d9a6777bfbdd39aad1aecb114f\", \"extensions_browsertests\": \"3a5fd68bb9c1acb053e1873821ea1a20bf003e1c\", \"extensions_unittests\": \"6c5f11a148583f288685fc96dbd9167eb48983b4\", \"filesystem_service_unittests\": \"fce7af72a41ae11f27c8d80514efceddc116302e\", \"gcm_unit_tests\": \"07887b7f491f9f06d1c1798b430ba6326e13002d\", \"gfx_unittests\": \"ff55acd129fb5be427987a76f0904b83e67ed55c\", \"gin_unittests\": \"961fc140cb6d14150149752cb93a7962d4cd210d\", \"gl_unittests_ozone\": \"6c2d8e6be8687ea1a6c62e00e33757c232095e4f\", \"google_apis_unittests\": \"29e36d00b0bf1a64a61ff4cd35fb58da5ae8a52b\", \"gpu_unittests\": \"6053bfbc4eaf4c987930b4b30973d24f50c2b988\", \"gwp_asan_unittests\": \"241ee1693052a52ff4e9c4c562d6e0063e920bb3\", \"interactive_ui_tests\": \"560b864f4fe77720a0f83facdb0985c38222eab7\", \"ipc_tests\": \"a784d576bc1f2b616fe874fad212997d55ec2de8\", \"jingle_unittests\": \"de222ceb1020fa6ceb298cd7bf037eeb984ff9ed\", \"keyboard_unittests\": \"e986b1e6ff7f0d4de48a3aee0e3ee56c54100c4b\", \"latency_unittests\": \"ed6911cc8d35417fbc8884335e3cf173889c77ac\", \"libjingle_xmpp_unittests\": \"39691a679bc927daeb4d6e45700f67b5943dbd12\", \"media_blink_unittests\": \"363d4aefe7b03c708a6be61f33fafa060e750f84\", \"media_unittests\": \"d9fa562f7f8718efca97b4b178cd8e6a64e13885\", \"message_center_unittests\": \"e154019a52f981431655db912ada9c62a66e87cc\", \"midi_unittests\": \"5e1ad7ebd49c3f141257e3da9f825721a03a0f97\", \"mojo_core_unittests\": \"2d3972828d564e0882f2dee7d1571263a140dd35\", \"mojo_unittests\": \"cfebd512d6981554abf4697fbb936a47b2b133ab\", \"nacl_helper_nonsfi_unittests\": \"2d2c2491e7cef2adddd39b697fe0ceabfc55f697\", \"nacl_loader_unittests\": \"a02833cac6b21c6e39e575f5bd250e20c5dbd94d\", \"native_theme_unittests\": \"1d2dbf017460272718edc3ab78b7cc99cdef7986\", \"net_unittests\": \"f6c85058eefaf05a1bcd8cf0f07f2f867aff5eee\", \"ozone_gl_unittests\": \"da95622792e34e80e77bdcd884d5d491c40922ca\", \"ozone_unittests\": \"e16844adae621d0c64bc9ae76f325482a6f59647\", \"ozone_x11_unittests\": \"cca312427c0324c3bbb4174e206a0eecc8c0af92\", \"pdf_unittests\": \"abf61542ab6b39dad993b379359f47ac089e0822\", \"perfetto_unittests\": \"93b477fa92dab599e8289e4d64cb75a3a3dec4a5\", \"ppapi_unittests\": \"e954cebdb8326daf94c29811b70f8cb2591ddfda\", \"printing_unittests\": \"e510eff9dd9e860c569cc1afc0822ed1ae5280ac\", \"remoting_unittests\": \"4bbd6cd1aa0afeed7db09275be8c0e8ef040a894\", \"sandbox_linux_unittests\": \"5c87145514920d81a7e8645895f66bfcbfe5ffd2\", \"service_manager_unittests\": \"3ee7aa765f18576f2626a33c1d3bd023e45806a6\", \"services_unittests\": \"963cbd214e6ac124e4ed2755a74a55f8d8e8d924\", \"shell_dialogs_unittests\": \"ffb7a782024d00250973a2e14f210db714c174b9\", \"skia_unittests\": \"e9fbc618f499a64286dd774a6459315555ade99f\", \"snapshot_unittests\": \"f6169902fc2045809f7d060a7466d88daa47a292\", \"sql_unittests\": \"81f58175f8c7d52813983c40e98d2e28a1d2aa21\", \"standalone_angle_unittests\": \"b2b77503f281004cb903f3756462e996e6edc5ce\", \"storage_unittests\": \"fe580fa851da19f90da323dd52f9e3457ad5f898\", \"sync_integration_tests\": \"8b0977c2bd0abcc8b90c891bdb115d66c4172c6f\", \"traffic_annotation_auditor_unittests\": \"ecdbeb70a51e93d5b0edef64aaaa268ebec94061\", \"ui_base_unittests\": \"02a762d41574d6d28a8e24ffe71280245a777561\", \"ui_chromeos_unittests\": \"af941292cea34ea13d726df9f7ba00afa2e7effc\", \"ui_touch_selection_unittests\": \"1170d581b22bc86ed3d32a009a6f3ad658c916c9\", \"unit_tests\": \"2daecadf9f42b6332e1d10a47638d7397871a9d3\", \"url_unittests\": \"8525473c5336a9f9125aeba71d7f1fa9f2ec0a3a\", \"usage_time_limit_unittests\": \"9b52ee53b00f69ca7535aacf443f2d4a9c17ef03\", \"views_unittests\": \"4d19de5f04400b9914606615c09cf9d79b7db2b1\", \"viz_unittests\": \"f0743ac56ed9253bee1c81a8bbbebb8b89eb9205\", \"wm_unittests\": \"31cf09108845eb80befa25bef9305d550ea151d4\", \"wtf_unittests\": \"5dc7ba2f082d4cb0628b83f337f15c204b1ab7f0\", \"zlib_unittests\": \"b823b191065b416c408e503f77baba3216137f8a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_dacf669b49e3ec352df995227b58e62a545590985cca62e76e0831f920af4ccd_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-9-csso\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"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\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- UsingOptGuide/LiteVideoKeyedServiceBrowserTest.LiteVideoCanApplyLiteVideo_Reload/true\\n\\n- All/LoginPromptBrowserTest.CancelRedundantAuths/1\\n\\n- ArcCertStoreBridgeTest.ListCertificatesTest\\n\\n- AllForms/FormStructureBrowserTest.DataDrivenHeuristics/144\\n\\n- ...538 more failure(s) (542 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603462163704676",
"status": "COMPLETED",
"status_changed_ts": "1603462164259166",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/20979",
"builder:linux-chromeos-dbg",
"buildset:commit/git/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d42167ebafe3dc799306e95549a2be4acf9e2f5a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031071911868868896",
"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/8865641781486913504/+/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:4f6e9c5da9d20e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603468486636451",
"url": "https://ci.chromium.org/b/8865641781486913504",
"utcnow_ts": "1603468487234652"
}
}
[15:54:47.258] Invocation finished in 1h46m37.017882293s with status FAILED