[18:33:12.144] New invocation is queued and will start shortly
[18:33:13.252] Starting the invocation (attempt 1)
[18:33:13.392] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:13.392] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:13.392] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9076172526798729952",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"7882c4580ce7f67f18a36bfed1ac43a8152eabc6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9076172526798729952",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/7882c4580ce7f67f18a36bfed1ac43a8152eabc6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7882c4580ce7f67f18a36bfed1ac43a8152eabc6",
"gitiles_ref:refs/heads/master"
]
}
[18:33:14.352] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1560450793596103",
"id": "8910742464612255520",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7882c4580ce7f67f18a36bfed1ac43a8152eabc6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1560450794155924",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12870",
"builder:linux-chromeos-dbg",
"buildset:commit/git/7882c4580ce7f67f18a36bfed1ac43a8152eabc6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7882c4580ce7f67f18a36bfed1ac43a8152eabc6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076172526798729952",
"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/8910742464612255520/+/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": "1560450794156459",
"url": "https://ci.chromium.org/b/8910742464612255520",
"utcnow_ts": "1560450794292691"
}
}
[18:33:14.352] Task URL: https://ci.chromium.org/b/8910742464612255520
[18:33:14.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:2:0) after 1m0s
[18:34:14.366] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:2:0)
[18:34:14.366] Timer tick, asking Buildbucket for the build status
[18:34:15.119] Build 8910742464612255520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:15.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:3:0) after 1m0s
[18:35:15.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:3:0)
[18:35:15.183] Timer tick, asking Buildbucket for the build status
[18:35:15.485] Build 8910742464612255520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:15.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:4:0) after 1m0s
[18:36:22.464] Received PubSub notification, asking Buildbucket for the build status
[18:36:22.603] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:28.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:4:0)
[18:36:28.715] Timer tick, asking Buildbucket for the build status
[18:36:28.853] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:28.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:6:0) after 1m0s
[18:37:29.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:6:0)
[18:37:29.199] Timer tick, asking Buildbucket for the build status
[18:37:29.455] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:29.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:7:0) after 1m0s
[18:38:29.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:7:0)
[18:38:29.545] Timer tick, asking Buildbucket for the build status
[18:38:29.856] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:29.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:8:0) after 1m0s
[18:39:30.041] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:8:0)
[18:39:30.041] Timer tick, asking Buildbucket for the build status
[18:39:30.523] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:30.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:9:0) after 1m0s
[18:40:30.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:9:0)
[18:40:30.584] Timer tick, asking Buildbucket for the build status
[18:40:30.751] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:30.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:10:0) after 1m0s
[18:41:30.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:10:0)
[18:41:30.794] Timer tick, asking Buildbucket for the build status
[18:41:30.911] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:30.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:11:0) after 1m0s
[18:42:31.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:11:0)
[18:42:31.028] Timer tick, asking Buildbucket for the build status
[18:42:31.334] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:31.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:12:0) after 1m0s
[18:43:31.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:12:0)
[18:43:31.314] Timer tick, asking Buildbucket for the build status
[18:43:31.667] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:31.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:13:0) after 1m0s
[18:44:31.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:13:0)
[18:44:31.685] Timer tick, asking Buildbucket for the build status
[18:44:31.997] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:31.997] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:14:0) after 1m0s
[18:45:32.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:14:0)
[18:45:32.132] Timer tick, asking Buildbucket for the build status
[18:45:32.275] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:32.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:15:0) after 1m0s
[18:46:32.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:15:0)
[18:46:32.542] Timer tick, asking Buildbucket for the build status
[18:46:32.938] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:32.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:16:0) after 1m0s
[18:47:33.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:16:0)
[18:47:33.094] Timer tick, asking Buildbucket for the build status
[18:47:33.241] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:33.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:17:0) after 1m0s
[18:48:33.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:17:0)
[18:48:33.721] Timer tick, asking Buildbucket for the build status
[18:48:33.964] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:33.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:18:0) after 1m0s
[18:49:34.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:18:0)
[18:49:34.145] Timer tick, asking Buildbucket for the build status
[18:49:34.661] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:34.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:19:0) after 1m0s
[18:50:34.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:19:0)
[18:50:34.887] Timer tick, asking Buildbucket for the build status
[18:50:35.132] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:35.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:20:0) after 1m0s
[18:51:35.317] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:20:0)
[18:51:35.317] Timer tick, asking Buildbucket for the build status
[18:51:35.521] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:35.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:21:0) after 1m0s
[18:52:35.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:21:0)
[18:52:35.570] Timer tick, asking Buildbucket for the build status
[18:52:35.935] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:35.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:22:0) after 1m0s
[18:53:36.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:22:0)
[18:53:36.020] Timer tick, asking Buildbucket for the build status
[18:53:36.763] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:36.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:23:0) after 1m0s
[18:54:36.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:23:0)
[18:54:36.796] Timer tick, asking Buildbucket for the build status
[18:54:37.306] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:37.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:24:0) after 1m0s
[18:55:37.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:24:0)
[18:55:37.385] Timer tick, asking Buildbucket for the build status
[18:55:37.771] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:37.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:25:0) after 1m0s
[18:56:37.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:25:0)
[18:56:37.811] Timer tick, asking Buildbucket for the build status
[18:56:38.860] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:38.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:26:0) after 1m0s
[18:57:39.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:26:0)
[18:57:39.113] Timer tick, asking Buildbucket for the build status
[18:57:39.236] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:39.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:27:0) after 1m0s
[18:58:39.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:27:0)
[18:58:39.315] Timer tick, asking Buildbucket for the build status
[18:58:39.830] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:39.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:28:0) after 1m0s
[18:59:39.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:28:0)
[18:59:39.927] Timer tick, asking Buildbucket for the build status
[18:59:40.034] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:40.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:29:0) after 1m0s
[19:00:40.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:29:0)
[19:00:40.069] Timer tick, asking Buildbucket for the build status
[19:00:41.557] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:41.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:30:0) after 1m0s
[19:01:41.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:30:0)
[19:01:41.758] Timer tick, asking Buildbucket for the build status
[19:01:41.967] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:41.967] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:31:0) after 1m0s
[19:02:41.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:31:0)
[19:02:41.952] Timer tick, asking Buildbucket for the build status
[19:02:44.386] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:44.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:32:0) after 1m0s
[19:03:44.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:32:0)
[19:03:44.462] Timer tick, asking Buildbucket for the build status
[19:03:45.593] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:45.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:33:0) after 1m0s
[19:04:45.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:33:0)
[19:04:45.615] Timer tick, asking Buildbucket for the build status
[19:04:45.721] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:45.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:34:0) after 1m0s
[19:05:45.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:34:0)
[19:05:45.744] Timer tick, asking Buildbucket for the build status
[19:05:45.875] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:45.875] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:35:0) after 1m0s
[19:06:45.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:35:0)
[19:06:45.913] Timer tick, asking Buildbucket for the build status
[19:06:47.919] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:47.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:36:0) after 1m0s
[19:07:48.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:36:0)
[19:07:48.016] Timer tick, asking Buildbucket for the build status
[19:07:48.178] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:48.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:37:0) after 1m0s
[19:08:48.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:37:0)
[19:08:48.204] Timer tick, asking Buildbucket for the build status
[19:08:48.468] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:48.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:38:0) after 1m0s
[19:09:48.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:38:0)
[19:09:48.561] Timer tick, asking Buildbucket for the build status
[19:09:48.934] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:48.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:39:0) after 1m0s
[19:10:48.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:39:0)
[19:10:48.953] Timer tick, asking Buildbucket for the build status
[19:10:49.177] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:49.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:40:0) after 1m0s
[19:11:49.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:40:0)
[19:11:49.233] Timer tick, asking Buildbucket for the build status
[19:11:52.127] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:52.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:41:0) after 1m0s
[19:12:52.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:41:0)
[19:12:52.216] Timer tick, asking Buildbucket for the build status
[19:12:52.866] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:52.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:42:0) after 1m0s
[19:13:52.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:42:0)
[19:13:52.888] Timer tick, asking Buildbucket for the build status
[19:13:53.056] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:53.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:43:0) after 1m0s
[19:14:53.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:43:0)
[19:14:53.095] Timer tick, asking Buildbucket for the build status
[19:14:54.925] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:54.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:44:0) after 1m0s
[19:15:55.037] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:44:0)
[19:15:55.037] Timer tick, asking Buildbucket for the build status
[19:15:55.778] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:55.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:45:0) after 1m0s
[19:16:55.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:45:0)
[19:16:55.793] Timer tick, asking Buildbucket for the build status
[19:16:55.942] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:55.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:46:0) after 1m0s
[19:17:56.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:46:0)
[19:17:56.017] Timer tick, asking Buildbucket for the build status
[19:17:57.469] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:57.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:47:0) after 1m0s
[19:18:57.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:47:0)
[19:18:57.497] Timer tick, asking Buildbucket for the build status
[19:18:57.860] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:57.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:48:0) after 1m0s
[19:19:57.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:48:0)
[19:19:57.910] Timer tick, asking Buildbucket for the build status
[19:19:59.609] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:59.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:49:0) after 1m0s
[19:20:59.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:49:0)
[19:20:59.667] Timer tick, asking Buildbucket for the build status
[19:21:00.431] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:00.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:50:0) after 1m0s
[19:22:00.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:50:0)
[19:22:00.583] Timer tick, asking Buildbucket for the build status
[19:22:00.842] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:00.842] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:51:0) after 1m0s
[19:23:01.060] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:51:0)
[19:23:01.060] Timer tick, asking Buildbucket for the build status
[19:23:02.620] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:02.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:52:0) after 1m0s
[19:24:02.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:52:0)
[19:24:02.674] Timer tick, asking Buildbucket for the build status
[19:24:05.059] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:05.059] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:53:0) after 1m0s
[19:25:05.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:53:0)
[19:25:05.087] Timer tick, asking Buildbucket for the build status
[19:25:05.559] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:05.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:54:0) after 1m0s
[19:26:05.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:54:0)
[19:26:05.750] Timer tick, asking Buildbucket for the build status
[19:26:07.464] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:07.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:55:0) after 1m0s
[19:27:07.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:55:0)
[19:27:07.716] Timer tick, asking Buildbucket for the build status
[19:27:08.759] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:08.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:56:0) after 1m0s
[19:28:08.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:56:0)
[19:28:08.808] Timer tick, asking Buildbucket for the build status
[19:28:08.993] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:08.993] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:57:0) after 1m0s
[19:29:09.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:57:0)
[19:29:09.206] Timer tick, asking Buildbucket for the build status
[19:29:09.512] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:09.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:58:0) after 1m0s
[19:30:09.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:58:0)
[19:30:09.519] Timer tick, asking Buildbucket for the build status
[19:30:09.665] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:09.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:59:0) after 1m0s
[19:31:09.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:59:0)
[19:31:09.760] Timer tick, asking Buildbucket for the build status
[19:31:09.930] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:09.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:60:0) after 1m0s
[19:32:10.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:60:0)
[19:32:10.122] Timer tick, asking Buildbucket for the build status
[19:32:10.828] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:10.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:61:0) after 1m0s
[19:33:10.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:61:0)
[19:33:10.799] Timer tick, asking Buildbucket for the build status
[19:33:11.563] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:11.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:62:0) after 1m0s
[19:34:11.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:62:0)
[19:34:11.561] Timer tick, asking Buildbucket for the build status
[19:34:13.432] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:13.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:63:0) after 1m0s
[19:35:13.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:63:0)
[19:35:13.471] Timer tick, asking Buildbucket for the build status
[19:35:13.604] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:13.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:64:0) after 1m0s
[19:36:13.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:64:0)
[19:36:13.642] Timer tick, asking Buildbucket for the build status
[19:36:14.256] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:14.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:65:0) after 1m0s
[19:37:14.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:65:0)
[19:37:14.269] Timer tick, asking Buildbucket for the build status
[19:37:14.999] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:14.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:66:0) after 1m0s
[19:38:15.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:66:0)
[19:38:15.242] Timer tick, asking Buildbucket for the build status
[19:38:15.388] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:15.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:67:0) after 1m0s
[19:39:15.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:67:0)
[19:39:15.466] Timer tick, asking Buildbucket for the build status
[19:39:16.746] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:16.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:68:0) after 1m0s
[19:40:16.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:68:0)
[19:40:16.841] Timer tick, asking Buildbucket for the build status
[19:40:17.113] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:17.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:69:0) after 1m0s
[19:41:17.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:69:0)
[19:41:17.107] Timer tick, asking Buildbucket for the build status
[19:41:17.543] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:17.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:70:0) after 1m0s
[19:42:17.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:70:0)
[19:42:17.628] Timer tick, asking Buildbucket for the build status
[19:42:17.937] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:17.937] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:71:0) after 1m0s
[19:43:18.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:71:0)
[19:43:18.005] Timer tick, asking Buildbucket for the build status
[19:43:20.602] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:20.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:72:0) after 1m0s
[19:44:20.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:72:0)
[19:44:20.667] Timer tick, asking Buildbucket for the build status
[19:44:21.470] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:21.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:73:0) after 1m0s
[19:45:21.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:73:0)
[19:45:21.532] Timer tick, asking Buildbucket for the build status
[19:45:23.855] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:23.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:74:0) after 1m0s
[19:46:23.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:74:0)
[19:46:23.865] Timer tick, asking Buildbucket for the build status
[19:46:24.318] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:24.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:75:0) after 1m0s
[19:47:24.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:75:0)
[19:47:24.358] Timer tick, asking Buildbucket for the build status
[19:47:25.892] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:25.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:76:0) after 1m0s
[19:48:25.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:76:0)
[19:48:25.958] Timer tick, asking Buildbucket for the build status
[19:48:27.498] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:27.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:77:0) after 1m0s
[19:49:27.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:77:0)
[19:49:27.541] Timer tick, asking Buildbucket for the build status
[19:49:28.893] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:28.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:78:0) after 1m0s
[19:50:28.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:78:0)
[19:50:28.928] Timer tick, asking Buildbucket for the build status
[19:50:29.830] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:29.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:79:0) after 1m0s
[19:51:29.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:79:0)
[19:51:29.882] Timer tick, asking Buildbucket for the build status
[19:51:30.844] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:30.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:80:0) after 1m0s
[19:52:30.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:80:0)
[19:52:30.874] Timer tick, asking Buildbucket for the build status
[19:52:31.082] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:31.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:81:0) after 1m0s
[19:53:31.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:81:0)
[19:53:31.065] Timer tick, asking Buildbucket for the build status
[19:53:31.753] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:31.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:82:0) after 1m0s
[19:54:31.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:82:0)
[19:54:31.803] Timer tick, asking Buildbucket for the build status
[19:54:32.377] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:32.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:83:0) after 1m0s
[19:55:32.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:83:0)
[19:55:32.447] Timer tick, asking Buildbucket for the build status
[19:55:34.213] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:34.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:84:0) after 1m0s
[19:56:34.265] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:84:0)
[19:56:34.265] Timer tick, asking Buildbucket for the build status
[19:56:37.432] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:37.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:85:0) after 1m0s
[19:57:37.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:85:0)
[19:57:37.497] Timer tick, asking Buildbucket for the build status
[19:57:37.654] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:37.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:86:0) after 1m0s
[19:58:37.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:86:0)
[19:58:37.821] Timer tick, asking Buildbucket for the build status
[19:58:37.943] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:37.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:87:0) after 1m0s
[19:59:38.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:87:0)
[19:59:38.111] Timer tick, asking Buildbucket for the build status
[19:59:39.466] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:39.466] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:88:0) after 1m0s
[20:00:39.542] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:88:0)
[20:00:39.542] Timer tick, asking Buildbucket for the build status
[20:00:41.463] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:41.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:89:0) after 1m0s
[20:01:41.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:89:0)
[20:01:41.474] Timer tick, asking Buildbucket for the build status
[20:01:43.683] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:43.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:90:0) after 1m0s
[20:02:43.796] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:90:0)
[20:02:43.796] Timer tick, asking Buildbucket for the build status
[20:02:45.211] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:45.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:91:0) after 1m0s
[20:03:45.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:91:0)
[20:03:45.292] Timer tick, asking Buildbucket for the build status
[20:03:45.477] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:45.477] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:92:0) after 1m0s
[20:04:45.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:92:0)
[20:04:45.505] Timer tick, asking Buildbucket for the build status
[20:04:45.732] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:45.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:93:0) after 1m0s
[20:05:45.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:93:0)
[20:05:45.797] Timer tick, asking Buildbucket for the build status
[20:05:45.990] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:45.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:94:0) after 1m0s
[20:06:46.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:94:0)
[20:06:46.098] Timer tick, asking Buildbucket for the build status
[20:06:46.894] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:46.894] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:95:0) after 1m0s
[20:07:46.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:95:0)
[20:07:46.952] Timer tick, asking Buildbucket for the build status
[20:07:47.272] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:47.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:96:0) after 1m0s
[20:08:47.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:96:0)
[20:08:47.368] Timer tick, asking Buildbucket for the build status
[20:08:47.520] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:47.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:97:0) after 1m0s
[20:09:47.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:97:0)
[20:09:47.548] Timer tick, asking Buildbucket for the build status
[20:09:47.741] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:47.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:98:0) after 1m0s
[20:10:47.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:98:0)
[20:10:47.756] Timer tick, asking Buildbucket for the build status
[20:10:47.867] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:47.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:99:0) after 1m0s
[20:11:47.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:99:0)
[20:11:47.938] Timer tick, asking Buildbucket for the build status
[20:11:48.081] Build 8910742464612255520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:48.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9076172526798729952:100:0) after 1m0s
[20:12:40.265] Received PubSub notification, asking Buildbucket for the build status
[20:12:40.385] Build 8910742464612255520: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:12:40.385] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1560456759052972",
"created_by": "project:chromium",
"created_ts": "1560450793596103",
"failure_reason": "BUILD_FAILURE",
"id": "8910742464612255520",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7882c4580ce7f67f18a36bfed1ac43a8152eabc6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 tests failed: ['viz_browser_tests']\"}, \"properties\": {\"$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-6-ztol\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 12870, \"got_angle_revision\": \"444aa0fe64daf4dd38a5636bd86da9b7e8110257\", \"got_dawn_revision\": \"df69f2482462a870adeb89fb2dd91f5acd992c1e\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"7882c4580ce7f67f18a36bfed1ac43a8152eabc6\", \"got_revision_cp\": \"refs/heads/master@{#668905}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"e619ed57592ca2e2a69ab8fe2c463c810e8a7bc0\", \"got_v8_revision_cp\": \"refs/heads/7.7.64@{#1}\", \"got_webrtc_revision\": \"42848288872521545ff9310dd7c7a72f892c2e23\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28266}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"7882c4580ce7f67f18a36bfed1ac43a8152eabc6\", \"swarm_hashes\": {\"accessibility_unittests\": \"3c28e0d9beca9b4911eb096dab77c04998c7bdca\", \"angle_unittests\": \"9065e468e0ee6de7e06f97047a872b6afccb5614\", \"app_list_unittests\": \"6b1c18cf2e5d764f5263fd3ec0b3de32624acef7\", \"app_shell_unittests\": \"27c36eb523da0e8f99f7a2ef8bc2cf9aa246daf2\", \"ash_unittests\": \"ca8f52a99167a069e78eab84883d5ef761c76c37\", \"aura_unittests\": \"653e23b0091a60a15c492c8df3639713c1fe247e\", \"base_unittests\": \"14eb98e7ead14e60d17e8199c9e1c7c42f14b6f8\", \"base_util_unittests\": \"d3b483130ef58e952ec118b18aa23be1ddadf899\", \"blink_common_unittests\": \"d00dcf5b07a4704dda59d110fd7eccad5576c653\", \"blink_fuzzer_unittests\": \"bd00e8a9a52d6183ae6df71923778c04bedb287a\", \"blink_heap_unittests\": \"6e38e5e9adf71781f03bb970e219a2d59dd59070\", \"blink_platform_unittests\": \"1f7ae34fbb85b1b1c7d2fdcc9628868c60b841a0\", \"blink_unittests\": \"e546f459a4b5c5b2804195ba88d3a05161f0b68c\", \"boringssl_crypto_tests\": \"d02a021f31ca63397eef0f8aae0c85a081fecb3a\", \"boringssl_ssl_tests\": \"60013eebacebce2fd4d45e62b0251913db2456bb\", \"browser_tests\": \"4cf97d1addbbe9b1fc93f74afd856931e87f27d8\", \"cacheinvalidation_unittests\": \"5784a69abb128187c26b199d146c1f69be4f8a8d\", \"capture_unittests\": \"b272101fe73b825f07fba84b25e3c11d9e7ddca0\", \"cast_unittests\": \"d487b5f35803af72c3622d0e7991d8506827dbb2\", \"cc_unittests\": \"1b42437e5c285f1a429dcf492942111e83cbf6ad\", \"chrome_app_unittests\": \"11c63fb10fe2c96febb03df5d07af66032bdde7d\", \"chromedriver_unittests\": \"9de4cfcb609f4dfb190c33bd32aee19f2282545b\", \"chromeos_components_unittests\": \"52ef9daa18683a1926bacf712496b8311e52a577\", \"chromeos_unittests\": \"8a82c5d9e1f9c78acf3c858a1ad7a67d885ce67b\", \"components_browsertests\": \"4e7cd421f8573f345004dc3f18f58663bdbd25b8\", \"components_unittests\": \"e642afd2e8a62725ae6a60be4b2f2808bf09c348\", \"compositor_unittests\": \"085e852e70b6bafec160d1794fed207b86ec0b59\", \"content_browsertests\": \"8d540b8445aab1a322865a4587e2f213079974f8\", \"content_unittests\": \"3f445c36676c0af4b1a5935f53acbc9010555852\", \"crypto_unittests\": \"5630beb29d8867349af9ef9874e73040b39d3ef8\", \"dbus_unittests\": \"412babf09bedbafcec3b71b046cf884bde199f2f\", \"device_unittests\": \"ba316b855176f7a2d2b3dbcb6568713872e8dac2\", \"display_unittests\": \"04c2fe349e07d652c0ff2be772847633743fa626\", \"events_unittests\": \"d32e26c0d54ce8e8fd195c8dbed35feb86c0d104\", \"exo_unittests\": \"3ecd16ead46a383dfb756654550e8f7f1f0b0bde\", \"extensions_browsertests\": \"f2c299f8f5fa02367a39f2fc87604c209f20ac14\", \"extensions_unittests\": \"930f49bab0c305b46ca6f74a785aab37f979e2c5\", \"filesystem_service_unittests\": \"a886b1c010b14ea6907a4d5366307f8f78417d75\", \"gcm_unit_tests\": \"274f5cb417d2ce535956fb219a6539451c8e90d0\", \"gfx_unittests\": \"ff02041e5e96b359e898026263f6db8899c40d25\", \"gin_unittests\": \"18e7c791d8d52c5e49da6b5d2c0da4be6e64190e\", \"gl_unittests_ozone\": \"2494ced622e59d4377ad7ade8c83b87ab89a3377\", \"google_apis_unittests\": \"675326fe14de53c86a3c19ee7a88ef62401ce48f\", \"gpu_unittests\": \"a2ff99c2c47bc17fca93098dbaf34f6de33eae86\", \"interactive_ui_tests\": \"bcde5b519c15e6b9da85155672ee3523d85338bb\", \"ipc_tests\": \"1198802b80df38d34f6c21452191cce585f7fae2\", \"jingle_unittests\": \"1e57a1a6d21e8daad3768fe502488f55ccbc231b\", \"keyboard_unittests\": \"6929eff224d7de39824291ce22605c2102ceea2a\", \"latency_unittests\": \"67cb9c71b858d926c40b61958f989f72b864a8c7\", \"leveldb_service_unittests\": \"4ffe000281b0994b479d85b9b61e1194b2acbcec\", \"libjingle_xmpp_unittests\": \"9ded4ee24f21154449185f2c9bfd436c700eb35f\", \"media_blink_unittests\": \"531aa067daf3fe64b2de412eadb28b022c263cba\", \"media_service_unittests\": \"c54cc8e540f814970ff3a072b0c1ab11066241e6\", \"media_unittests\": \"f95686df9e2e0d688b0261b2438375e44c2376cc\", \"message_center_unittests\": \"56c9a1f012e2b41420a7848c9b5c4513f1e6ac4d\", \"midi_unittests\": \"18d5a80ebd663b49be6ba18704b97712bb8980a6\", \"mojo_core_unittests\": \"499e87b83cbe5ec137b381712acf22026ec861c0\", \"mojo_unittests\": \"644572e11f7dbadfc5693caafd3ef7ef85342b5c\", \"nacl_helper_nonsfi_unittests\": \"58d5514b2f927effc6e3a87e8b836cb9f1cebfad\", \"nacl_loader_unittests\": \"45f23bd939e638f19c8999417c701faf638cc09c\", \"native_theme_unittests\": \"477e70869f92d5ce8d6c3bcbca8ed2b7425ee98a\", \"net_unittests\": \"ece88c499a12b34c5e8c67c2c5351242d74efbe2\", \"ozone_gl_unittests\": \"997bb7f7545705a4f9ca2d68215fd9e998ee840f\", \"ozone_unittests\": \"38b192d9fca48dda652ca989040fe787ec1b5cf0\", \"ozone_x11_unittests\": \"29e18d433b822664c9292395b1cec0f299dde3cc\", \"pdf_unittests\": \"c16136665782662cad17d9b62c89815ff90123e0\", \"perfetto_unittests\": \"fdfcc25b8676d0ed2acc2987b1c36f9297fcf5c0\", \"ppapi_unittests\": \"9c1c2c1f0edc7f1eb526ff2d8cb8447da1c7a11f\", \"printing_unittests\": \"9b78f9f2bd1677531ba143fddbff4ca5fa3323e0\", \"remoting_unittests\": \"c8b070c3ff32c3b757b2d3b6a1624f189e4ff82e\", \"sandbox_linux_unittests\": \"11f036e0a2786f37aae2ee878413d30539934496\", \"service_manager_unittests\": \"a3af2ff836205f4ae593d0429bcf4a2c6daf32b5\", \"services_unittests\": \"f0dd09c8286c4cbfd97b60918865c626e55793fa\", \"shell_dialogs_unittests\": \"b2b90f49afc53a3b0591aec214f0daff4b862508\", \"skia_unittests\": \"8cc02750ffefd080ee5e531868f578540e94850e\", \"snapshot_unittests\": \"eef1d9ef9ed01d1b7b727f047ee6b53372c6511a\", \"sql_unittests\": \"77c93f8dc3f9cd8cef9f6f14e00281bc70fa396a\", \"storage_unittests\": \"1c1b6a1e32e363b40ce3b63a29ea363a552a68e5\", \"sync_integration_tests\": \"53ae66fb74db653ae96c6d900f2cfe8cac56c770\", \"traffic_annotation_auditor_unittests\": \"b9d197c558bf9951897347718355b3c2b204e3af\", \"ui_base_unittests\": \"fcc2b68858107a8e54ac0c7b7b13b076f83988b9\", \"ui_chromeos_unittests\": \"c9e591fe173de9cac71beb8f9269d97d0b08bce6\", \"ui_touch_selection_unittests\": \"65fc8c723926d49c8535ea9b1f0102ae510b5604\", \"unit_tests\": \"24ab14fc5b381fe0e593a44564d59b41149e299b\", \"url_unittests\": \"b8b31f0dd201acbdff00287fd6f6125bb302cb98\", \"usage_time_limit_unittests\": \"e1f2a1f615aaaf15238f4d4bdcc3ca592ed4cf91\", \"views_unittests\": \"b688ba3cfad442df715cbde23a290da3a5ea8758\", \"viz_unittests\": \"3196ba61d7fcc08e2808e59b648205b99a69e8bd\", \"wm_unittests\": \"2cefdac8c53dad1f5803a35a5f7254de361f1e7a\", \"wtf_unittests\": \"ea6155f03c7cdad7e46a12f55d6af62b67a6779a\"}, \"swarm_hashes_refs/heads/master(at){#668905}_without_patch\": {\"accessibility_unittests\": \"3c28e0d9beca9b4911eb096dab77c04998c7bdca\", \"angle_unittests\": \"9065e468e0ee6de7e06f97047a872b6afccb5614\", \"app_list_unittests\": \"6b1c18cf2e5d764f5263fd3ec0b3de32624acef7\", \"app_shell_unittests\": \"27c36eb523da0e8f99f7a2ef8bc2cf9aa246daf2\", \"ash_unittests\": \"ca8f52a99167a069e78eab84883d5ef761c76c37\", \"aura_unittests\": \"653e23b0091a60a15c492c8df3639713c1fe247e\", \"base_unittests\": \"14eb98e7ead14e60d17e8199c9e1c7c42f14b6f8\", \"base_util_unittests\": \"d3b483130ef58e952ec118b18aa23be1ddadf899\", \"blink_common_unittests\": \"d00dcf5b07a4704dda59d110fd7eccad5576c653\", \"blink_fuzzer_unittests\": \"bd00e8a9a52d6183ae6df71923778c04bedb287a\", \"blink_heap_unittests\": \"6e38e5e9adf71781f03bb970e219a2d59dd59070\", \"blink_platform_unittests\": \"1f7ae34fbb85b1b1c7d2fdcc9628868c60b841a0\", \"blink_unittests\": \"e546f459a4b5c5b2804195ba88d3a05161f0b68c\", \"boringssl_crypto_tests\": \"d02a021f31ca63397eef0f8aae0c85a081fecb3a\", \"boringssl_ssl_tests\": \"60013eebacebce2fd4d45e62b0251913db2456bb\", \"browser_tests\": \"4cf97d1addbbe9b1fc93f74afd856931e87f27d8\", \"cacheinvalidation_unittests\": \"5784a69abb128187c26b199d146c1f69be4f8a8d\", \"capture_unittests\": \"b272101fe73b825f07fba84b25e3c11d9e7ddca0\", \"cast_unittests\": \"d487b5f35803af72c3622d0e7991d8506827dbb2\", \"cc_unittests\": \"1b42437e5c285f1a429dcf492942111e83cbf6ad\", \"chrome_app_unittests\": \"11c63fb10fe2c96febb03df5d07af66032bdde7d\", \"chromedriver_unittests\": \"9de4cfcb609f4dfb190c33bd32aee19f2282545b\", \"chromeos_components_unittests\": \"52ef9daa18683a1926bacf712496b8311e52a577\", \"chromeos_unittests\": \"8a82c5d9e1f9c78acf3c858a1ad7a67d885ce67b\", \"components_browsertests\": \"4e7cd421f8573f345004dc3f18f58663bdbd25b8\", \"components_unittests\": \"e642afd2e8a62725ae6a60be4b2f2808bf09c348\", \"compositor_unittests\": \"085e852e70b6bafec160d1794fed207b86ec0b59\", \"content_browsertests\": \"8d540b8445aab1a322865a4587e2f213079974f8\", \"content_unittests\": \"3f445c36676c0af4b1a5935f53acbc9010555852\", \"crypto_unittests\": \"5630beb29d8867349af9ef9874e73040b39d3ef8\", \"dbus_unittests\": \"412babf09bedbafcec3b71b046cf884bde199f2f\", \"device_unittests\": \"ba316b855176f7a2d2b3dbcb6568713872e8dac2\", \"display_unittests\": \"04c2fe349e07d652c0ff2be772847633743fa626\", \"events_unittests\": \"d32e26c0d54ce8e8fd195c8dbed35feb86c0d104\", \"exo_unittests\": \"3ecd16ead46a383dfb756654550e8f7f1f0b0bde\", \"extensions_browsertests\": \"f2c299f8f5fa02367a39f2fc87604c209f20ac14\", \"extensions_unittests\": \"930f49bab0c305b46ca6f74a785aab37f979e2c5\", \"filesystem_service_unittests\": \"a886b1c010b14ea6907a4d5366307f8f78417d75\", \"gcm_unit_tests\": \"274f5cb417d2ce535956fb219a6539451c8e90d0\", \"gfx_unittests\": \"ff02041e5e96b359e898026263f6db8899c40d25\", \"gin_unittests\": \"18e7c791d8d52c5e49da6b5d2c0da4be6e64190e\", \"gl_unittests_ozone\": \"2494ced622e59d4377ad7ade8c83b87ab89a3377\", \"google_apis_unittests\": \"675326fe14de53c86a3c19ee7a88ef62401ce48f\", \"gpu_unittests\": \"a2ff99c2c47bc17fca93098dbaf34f6de33eae86\", \"interactive_ui_tests\": \"bcde5b519c15e6b9da85155672ee3523d85338bb\", \"ipc_tests\": \"1198802b80df38d34f6c21452191cce585f7fae2\", \"jingle_unittests\": \"1e57a1a6d21e8daad3768fe502488f55ccbc231b\", \"keyboard_unittests\": \"6929eff224d7de39824291ce22605c2102ceea2a\", \"latency_unittests\": \"67cb9c71b858d926c40b61958f989f72b864a8c7\", \"leveldb_service_unittests\": \"4ffe000281b0994b479d85b9b61e1194b2acbcec\", \"libjingle_xmpp_unittests\": \"9ded4ee24f21154449185f2c9bfd436c700eb35f\", \"media_blink_unittests\": \"531aa067daf3fe64b2de412eadb28b022c263cba\", \"media_service_unittests\": \"c54cc8e540f814970ff3a072b0c1ab11066241e6\", \"media_unittests\": \"f95686df9e2e0d688b0261b2438375e44c2376cc\", \"message_center_unittests\": \"56c9a1f012e2b41420a7848c9b5c4513f1e6ac4d\", \"midi_unittests\": \"18d5a80ebd663b49be6ba18704b97712bb8980a6\", \"mojo_core_unittests\": \"499e87b83cbe5ec137b381712acf22026ec861c0\", \"mojo_unittests\": \"644572e11f7dbadfc5693caafd3ef7ef85342b5c\", \"nacl_helper_nonsfi_unittests\": \"58d5514b2f927effc6e3a87e8b836cb9f1cebfad\", \"nacl_loader_unittests\": \"45f23bd939e638f19c8999417c701faf638cc09c\", \"native_theme_unittests\": \"477e70869f92d5ce8d6c3bcbca8ed2b7425ee98a\", \"net_unittests\": \"ece88c499a12b34c5e8c67c2c5351242d74efbe2\", \"ozone_gl_unittests\": \"997bb7f7545705a4f9ca2d68215fd9e998ee840f\", \"ozone_unittests\": \"38b192d9fca48dda652ca989040fe787ec1b5cf0\", \"ozone_x11_unittests\": \"29e18d433b822664c9292395b1cec0f299dde3cc\", \"pdf_unittests\": \"c16136665782662cad17d9b62c89815ff90123e0\", \"perfetto_unittests\": \"fdfcc25b8676d0ed2acc2987b1c36f9297fcf5c0\", \"ppapi_unittests\": \"9c1c2c1f0edc7f1eb526ff2d8cb8447da1c7a11f\", \"printing_unittests\": \"9b78f9f2bd1677531ba143fddbff4ca5fa3323e0\", \"remoting_unittests\": \"c8b070c3ff32c3b757b2d3b6a1624f189e4ff82e\", \"sandbox_linux_unittests\": \"11f036e0a2786f37aae2ee878413d30539934496\", \"service_manager_unittests\": \"a3af2ff836205f4ae593d0429bcf4a2c6daf32b5\", \"services_unittests\": \"f0dd09c8286c4cbfd97b60918865c626e55793fa\", \"shell_dialogs_unittests\": \"b2b90f49afc53a3b0591aec214f0daff4b862508\", \"skia_unittests\": \"8cc02750ffefd080ee5e531868f578540e94850e\", \"snapshot_unittests\": \"eef1d9ef9ed01d1b7b727f047ee6b53372c6511a\", \"sql_unittests\": \"77c93f8dc3f9cd8cef9f6f14e00281bc70fa396a\", \"storage_unittests\": \"1c1b6a1e32e363b40ce3b63a29ea363a552a68e5\", \"sync_integration_tests\": \"53ae66fb74db653ae96c6d900f2cfe8cac56c770\", \"traffic_annotation_auditor_unittests\": \"b9d197c558bf9951897347718355b3c2b204e3af\", \"ui_base_unittests\": \"fcc2b68858107a8e54ac0c7b7b13b076f83988b9\", \"ui_chromeos_unittests\": \"c9e591fe173de9cac71beb8f9269d97d0b08bce6\", \"ui_touch_selection_unittests\": \"65fc8c723926d49c8535ea9b1f0102ae510b5604\", \"unit_tests\": \"24ab14fc5b381fe0e593a44564d59b41149e299b\", \"url_unittests\": \"b8b31f0dd201acbdff00287fd6f6125bb302cb98\", \"usage_time_limit_unittests\": \"e1f2a1f615aaaf15238f4d4bdcc3ca592ed4cf91\", \"views_unittests\": \"b688ba3cfad442df715cbde23a290da3a5ea8758\", \"viz_unittests\": \"3196ba61d7fcc08e2808e59b648205b99a69e8bd\", \"wm_unittests\": \"2cefdac8c53dad1f5803a35a5f7254de361f1e7a\", \"wtf_unittests\": \"ea6155f03c7cdad7e46a12f55d6af62b67a6779a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_eae3a4dc277b4e2282e9fce002ae6861b5ee5509389502cf3acc9366ec79a2d3_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-6-ztol\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4339-6285c57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 tests failed: ['viz_browser_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1560450980529384",
"status": "COMPLETED",
"status_changed_ts": "1560450981777980",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/12870",
"builder:linux-chromeos-dbg",
"buildset:commit/git/7882c4580ce7f67f18a36bfed1ac43a8152eabc6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7882c4580ce7f67f18a36bfed1ac43a8152eabc6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076172526798729952",
"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/8910742464612255520/+/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:456aef0befd30d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1560456759638784",
"url": "https://ci.chromium.org/b/8910742464612255520",
"utcnow_ts": "1560456760374444"
}
}
[20:12:40.385] Invocation finished in 1h39m28.256713683s with status FAILED