[01:21:22.103] New invocation is queued and will start shortly
[01:21:23.184] Starting the invocation (attempt 1)
[01:21:23.252] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:21:23.252] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:21:23.252] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083122813637196912",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"6aa18804b4902bedc73248590288b6d3785ba221\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083122813637196912",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/6aa18804b4902bedc73248590288b6d3785ba221",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6aa18804b4902bedc73248590288b6d3785ba221",
"gitiles_ref:refs/heads/master"
]
}
[01:21:25.647] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553822484156797",
"id": "8917692750812187504",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6aa18804b4902bedc73248590288b6d3785ba221\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553822485334195",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11590",
"builder:linux-chromeos-dbg",
"buildset:commit/git/6aa18804b4902bedc73248590288b6d3785ba221",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6aa18804b4902bedc73248590288b6d3785ba221",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083122813637196912",
"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/8917692750812187504/+/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": "1553822485334309",
"url": "https://ci.chromium.org/b/8917692750812187504",
"utcnow_ts": "1553822485630271"
}
}
[01:21:25.647] Task URL: https://ci.chromium.org/b/8917692750812187504
[01:21:25.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:2:0) after 1m0s
[01:22:25.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:2:0)
[01:22:25.666] Timer tick, asking Buildbucket for the build status
[01:22:25.790] Build 8917692750812187504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:25.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:3:0) after 1m0s
[01:23:25.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:3:0)
[01:23:25.804] Timer tick, asking Buildbucket for the build status
[01:23:26.052] Build 8917692750812187504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:26.052] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:4:0) after 1m0s
[01:24:26.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:4:0)
[01:24:26.071] Timer tick, asking Buildbucket for the build status
[01:24:26.468] Build 8917692750812187504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:26.469] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:5:0) after 1m0s
[01:25:26.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:5:0)
[01:25:26.473] Timer tick, asking Buildbucket for the build status
[01:25:26.587] Build 8917692750812187504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:26.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:6:0) after 1m0s
[01:26:26.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:6:0)
[01:26:26.608] Timer tick, asking Buildbucket for the build status
[01:26:26.721] Build 8917692750812187504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:26.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:7:0) after 1m0s
[01:27:26.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:7:0)
[01:27:26.707] Timer tick, asking Buildbucket for the build status
[01:27:26.877] Build 8917692750812187504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:26.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:8:0) after 1m0s
[01:27:51.939] Received PubSub notification, asking Buildbucket for the build status
[01:27:52.173] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:26.900] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:8:0)
[01:28:26.900] Timer tick, asking Buildbucket for the build status
[01:28:27.068] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:27.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:10:0) after 1m0s
[01:29:27.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:10:0)
[01:29:27.091] Timer tick, asking Buildbucket for the build status
[01:29:28.420] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:28.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:11:0) after 1m0s
[01:30:28.419] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:11:0)
[01:30:28.419] Timer tick, asking Buildbucket for the build status
[01:30:29.039] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:29.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:12:0) after 1m0s
[01:31:29.060] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:12:0)
[01:31:29.060] Timer tick, asking Buildbucket for the build status
[01:31:29.303] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:29.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:13:0) after 1m0s
[01:32:29.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:13:0)
[01:32:29.318] Timer tick, asking Buildbucket for the build status
[01:32:29.509] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:29.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:14:0) after 1m0s
[01:33:29.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:14:0)
[01:33:29.530] Timer tick, asking Buildbucket for the build status
[01:33:29.681] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:29.681] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:15:0) after 1m0s
[01:34:29.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:15:0)
[01:34:29.700] Timer tick, asking Buildbucket for the build status
[01:34:29.807] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:29.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:16:0) after 1m0s
[01:35:29.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:16:0)
[01:35:29.813] Timer tick, asking Buildbucket for the build status
[01:35:29.941] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:29.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:17:0) after 1m0s
[01:36:29.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:17:0)
[01:36:29.947] Timer tick, asking Buildbucket for the build status
[01:36:30.104] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:30.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:18:0) after 1m0s
[01:37:30.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:18:0)
[01:37:30.131] Timer tick, asking Buildbucket for the build status
[01:37:30.270] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:30.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:19:0) after 1m0s
[01:38:30.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:19:0)
[01:38:30.293] Timer tick, asking Buildbucket for the build status
[01:38:30.861] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:30.861] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:20:0) after 1m0s
[01:39:30.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:20:0)
[01:39:30.882] Timer tick, asking Buildbucket for the build status
[01:39:30.981] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:30.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:21:0) after 1m0s
[01:40:30.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:21:0)
[01:40:30.973] Timer tick, asking Buildbucket for the build status
[01:40:31.192] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:31.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:22:0) after 1m0s
[01:41:31.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:22:0)
[01:41:31.262] Timer tick, asking Buildbucket for the build status
[01:41:31.440] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:31.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:23:0) after 1m0s
[01:42:31.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:23:0)
[01:42:31.463] Timer tick, asking Buildbucket for the build status
[01:42:31.770] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:31.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:24:0) after 1m0s
[01:43:31.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:24:0)
[01:43:31.791] Timer tick, asking Buildbucket for the build status
[01:43:32.099] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:32.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:25:0) after 1m0s
[01:44:32.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:25:0)
[01:44:32.128] Timer tick, asking Buildbucket for the build status
[01:44:32.300] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:32.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:26:0) after 1m0s
[01:45:32.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:26:0)
[01:45:32.337] Timer tick, asking Buildbucket for the build status
[01:45:32.497] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:32.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:27:0) after 1m0s
[01:46:32.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:27:0)
[01:46:32.526] Timer tick, asking Buildbucket for the build status
[01:46:32.657] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:32.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:28:0) after 1m0s
[01:47:32.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:28:0)
[01:47:32.678] Timer tick, asking Buildbucket for the build status
[01:47:33.284] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:33.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:29:0) after 1m0s
[01:48:33.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:29:0)
[01:48:33.305] Timer tick, asking Buildbucket for the build status
[01:48:33.686] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:33.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:30:0) after 1m0s
[01:49:33.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:30:0)
[01:49:33.707] Timer tick, asking Buildbucket for the build status
[01:49:33.973] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:33.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:31:0) after 1m0s
[01:50:33.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:31:0)
[01:50:33.991] Timer tick, asking Buildbucket for the build status
[01:50:34.503] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:34.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:32:0) after 1m0s
[01:51:34.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:32:0)
[01:51:34.530] Timer tick, asking Buildbucket for the build status
[01:51:34.700] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:34.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:33:0) after 1m0s
[01:52:34.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:33:0)
[01:52:34.701] Timer tick, asking Buildbucket for the build status
[01:52:34.806] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:34.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:34:0) after 1m0s
[01:53:34.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:34:0)
[01:53:34.820] Timer tick, asking Buildbucket for the build status
[01:53:35.062] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:35.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:35:0) after 1m0s
[01:54:35.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:35:0)
[01:54:35.059] Timer tick, asking Buildbucket for the build status
[01:54:35.852] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:35.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:36:0) after 1m0s
[01:55:35.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:36:0)
[01:55:35.858] Timer tick, asking Buildbucket for the build status
[01:55:37.030] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:37.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:37:0) after 1m0s
[01:56:37.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:37:0)
[01:56:37.061] Timer tick, asking Buildbucket for the build status
[01:56:37.205] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:37.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:38:0) after 1m0s
[01:57:37.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:38:0)
[01:57:37.233] Timer tick, asking Buildbucket for the build status
[01:57:37.381] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:37.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:39:0) after 1m0s
[01:58:37.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:39:0)
[01:58:37.375] Timer tick, asking Buildbucket for the build status
[01:58:38.227] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:38.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:40:0) after 1m0s
[01:59:38.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:40:0)
[01:59:38.246] Timer tick, asking Buildbucket for the build status
[01:59:38.427] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:38.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:41:0) after 1m0s
[02:00:38.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:41:0)
[02:00:38.531] Timer tick, asking Buildbucket for the build status
[02:00:38.884] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:38.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:42:0) after 1m0s
[02:01:38.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:42:0)
[02:01:38.908] Timer tick, asking Buildbucket for the build status
[02:01:39.101] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:39.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:43:0) after 1m0s
[02:02:39.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:43:0)
[02:02:39.137] Timer tick, asking Buildbucket for the build status
[02:02:39.371] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:39.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:44:0) after 1m0s
[02:03:39.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:44:0)
[02:03:39.436] Timer tick, asking Buildbucket for the build status
[02:03:39.932] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:39.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:45:0) after 1m0s
[02:04:39.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:45:0)
[02:04:39.956] Timer tick, asking Buildbucket for the build status
[02:04:40.292] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:40.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:46:0) after 1m0s
[02:05:40.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:46:0)
[02:05:40.307] Timer tick, asking Buildbucket for the build status
[02:05:40.733] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:40.733] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:47:0) after 1m0s
[02:06:40.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:47:0)
[02:06:40.756] Timer tick, asking Buildbucket for the build status
[02:06:41.317] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:41.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:48:0) after 1m0s
[02:07:41.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:48:0)
[02:07:41.354] Timer tick, asking Buildbucket for the build status
[02:07:41.525] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:41.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:49:0) after 1m0s
[02:08:41.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:49:0)
[02:08:41.545] Timer tick, asking Buildbucket for the build status
[02:08:41.721] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:41.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:50:0) after 1m0s
[02:09:41.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:50:0)
[02:09:41.742] Timer tick, asking Buildbucket for the build status
[02:09:41.922] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:41.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:51:0) after 1m0s
[02:10:41.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:51:0)
[02:10:41.954] Timer tick, asking Buildbucket for the build status
[02:10:42.707] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:52:0) after 1m0s
[02:11:42.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:52:0)
[02:11:42.730] Timer tick, asking Buildbucket for the build status
[02:11:42.866] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:42.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:53:0) after 1m0s
[02:12:42.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:53:0)
[02:12:42.897] Timer tick, asking Buildbucket for the build status
[02:12:43.094] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:43.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:54:0) after 1m0s
[02:13:43.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:54:0)
[02:13:43.118] Timer tick, asking Buildbucket for the build status
[02:13:43.253] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:43.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:55:0) after 1m0s
[02:14:43.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:55:0)
[02:14:43.244] Timer tick, asking Buildbucket for the build status
[02:14:43.413] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:43.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:56:0) after 1m0s
[02:15:43.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:56:0)
[02:15:43.435] Timer tick, asking Buildbucket for the build status
[02:15:43.615] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:43.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:57:0) after 1m0s
[02:16:43.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:57:0)
[02:16:43.591] Timer tick, asking Buildbucket for the build status
[02:16:43.710] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:43.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:58:0) after 1m0s
[02:17:43.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:58:0)
[02:17:43.733] Timer tick, asking Buildbucket for the build status
[02:17:43.838] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:43.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:59:0) after 1m0s
[02:18:43.817] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:59:0)
[02:18:43.817] Timer tick, asking Buildbucket for the build status
[02:18:43.905] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:43.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:60:0) after 1m0s
[02:19:43.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:60:0)
[02:19:43.928] Timer tick, asking Buildbucket for the build status
[02:19:44.244] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:44.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:61:0) after 1m0s
[02:20:44.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:61:0)
[02:20:44.267] Timer tick, asking Buildbucket for the build status
[02:20:44.536] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:44.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:62:0) after 1m0s
[02:21:44.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:62:0)
[02:21:44.604] Timer tick, asking Buildbucket for the build status
[02:21:44.783] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:44.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:63:0) after 1m0s
[02:22:44.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:63:0)
[02:22:44.809] Timer tick, asking Buildbucket for the build status
[02:22:44.919] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:44.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:64:0) after 1m0s
[02:23:44.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:64:0)
[02:23:44.965] Timer tick, asking Buildbucket for the build status
[02:23:45.158] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:45.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:65:0) after 1m0s
[02:24:45.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:65:0)
[02:24:45.181] Timer tick, asking Buildbucket for the build status
[02:24:45.483] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:45.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:66:0) after 1m0s
[02:25:45.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:66:0)
[02:25:45.483] Timer tick, asking Buildbucket for the build status
[02:25:45.632] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:45.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:67:0) after 1m0s
[02:26:45.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:67:0)
[02:26:45.656] Timer tick, asking Buildbucket for the build status
[02:26:45.866] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:45.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:68:0) after 1m0s
[02:27:45.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:68:0)
[02:27:45.890] Timer tick, asking Buildbucket for the build status
[02:27:46.605] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:46.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:69:0) after 1m0s
[02:28:46.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:69:0)
[02:28:46.627] Timer tick, asking Buildbucket for the build status
[02:28:47.300] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:47.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:70:0) after 1m0s
[02:29:47.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:70:0)
[02:29:47.288] Timer tick, asking Buildbucket for the build status
[02:29:47.408] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:47.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:71:0) after 1m0s
[02:30:47.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:71:0)
[02:30:47.386] Timer tick, asking Buildbucket for the build status
[02:30:47.999] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:48.000] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:72:0) after 1m0s
[02:31:48.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:72:0)
[02:31:48.022] Timer tick, asking Buildbucket for the build status
[02:31:48.197] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:48.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:73:0) after 1m0s
[02:32:48.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:73:0)
[02:32:48.221] Timer tick, asking Buildbucket for the build status
[02:32:48.364] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:48.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:74:0) after 1m0s
[02:33:48.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:74:0)
[02:33:48.391] Timer tick, asking Buildbucket for the build status
[02:33:48.630] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:48.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:75:0) after 1m0s
[02:34:48.634] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:75:0)
[02:34:48.634] Timer tick, asking Buildbucket for the build status
[02:34:48.886] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:48.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:76:0) after 1m0s
[02:35:48.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:76:0)
[02:35:48.929] Timer tick, asking Buildbucket for the build status
[02:35:49.257] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:49.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:77:0) after 1m0s
[02:36:49.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:77:0)
[02:36:49.281] Timer tick, asking Buildbucket for the build status
[02:36:50.005] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:50.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:78:0) after 1m0s
[02:37:50.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:78:0)
[02:37:50.030] Timer tick, asking Buildbucket for the build status
[02:37:50.160] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:50.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:79:0) after 1m0s
[02:38:50.176] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:79:0)
[02:38:50.176] Timer tick, asking Buildbucket for the build status
[02:38:50.701] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:50.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:80:0) after 1m0s
[02:39:50.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:80:0)
[02:39:50.704] Timer tick, asking Buildbucket for the build status
[02:39:52.187] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:52.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:81:0) after 1m0s
[02:40:52.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:81:0)
[02:40:52.222] Timer tick, asking Buildbucket for the build status
[02:40:52.380] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:52.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:82:0) after 1m0s
[02:41:52.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:82:0)
[02:41:52.438] Timer tick, asking Buildbucket for the build status
[02:41:53.197] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:53.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:83:0) after 1m0s
[02:42:53.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:83:0)
[02:42:53.195] Timer tick, asking Buildbucket for the build status
[02:42:53.350] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:53.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:84:0) after 1m0s
[02:43:53.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:84:0)
[02:43:53.383] Timer tick, asking Buildbucket for the build status
[02:43:53.493] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:53.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:85:0) after 1m0s
[02:44:53.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:85:0)
[02:44:53.502] Timer tick, asking Buildbucket for the build status
[02:44:53.743] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:53.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:86:0) after 1m0s
[02:45:53.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:86:0)
[02:45:53.772] Timer tick, asking Buildbucket for the build status
[02:45:54.579] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:54.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:87:0) after 1m0s
[02:46:54.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:87:0)
[02:46:54.603] Timer tick, asking Buildbucket for the build status
[02:46:56.401] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:56.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:88:0) after 1m0s
[02:47:56.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:88:0)
[02:47:56.408] Timer tick, asking Buildbucket for the build status
[02:47:56.706] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:56.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:89:0) after 1m0s
[02:48:56.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:89:0)
[02:48:56.691] Timer tick, asking Buildbucket for the build status
[02:48:56.799] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:56.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:90:0) after 1m0s
[02:49:56.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:90:0)
[02:49:56.813] Timer tick, asking Buildbucket for the build status
[02:49:57.862] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:57.862] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:91:0) after 1m0s
[02:50:57.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:91:0)
[02:50:57.883] Timer tick, asking Buildbucket for the build status
[02:50:58.091] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:58.091] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:92:0) after 1m0s
[02:51:58.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:92:0)
[02:51:58.115] Timer tick, asking Buildbucket for the build status
[02:51:58.311] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:58.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:93:0) after 1m0s
[02:52:58.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:93:0)
[02:52:58.334] Timer tick, asking Buildbucket for the build status
[02:52:58.447] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:58.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:94:0) after 1m0s
[02:53:58.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:94:0)
[02:53:58.472] Timer tick, asking Buildbucket for the build status
[02:53:58.857] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:58.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:95:0) after 1m0s
[02:54:58.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:95:0)
[02:54:58.845] Timer tick, asking Buildbucket for the build status
[02:54:59.180] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:59.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:96:0) after 1m0s
[02:55:59.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:96:0)
[02:55:59.201] Timer tick, asking Buildbucket for the build status
[02:56:00.169] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:00.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:97:0) after 1m0s
[02:57:00.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:97:0)
[02:57:00.242] Timer tick, asking Buildbucket for the build status
[02:57:00.532] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:00.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:98:0) after 1m0s
[02:58:00.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:98:0)
[02:58:00.557] Timer tick, asking Buildbucket for the build status
[02:58:00.813] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:00.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:99:0) after 1m0s
[02:59:00.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:99:0)
[02:59:00.838] Timer tick, asking Buildbucket for the build status
[02:59:01.063] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:01.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:100:0) after 1m0s
[03:00:01.090] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:100:0)
[03:00:01.090] Timer tick, asking Buildbucket for the build status
[03:00:01.395] Build 8917692750812187504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:01.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083122813637196912:101:0) after 1m0s
[03:00:03.122] Received PubSub notification, asking Buildbucket for the build status
[03:00:03.687] Build 8917692750812187504: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[03:00:03.687] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553828401343774",
"created_by": "project:chromium",
"created_ts": "1553822484156797",
"failure_reason": "BUILD_FAILURE",
"id": "8917692750812187504",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6aa18804b4902bedc73248590288b6d3785ba221\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 11590, \"got_angle_revision\": \"345cb856214156b485cb0d0b1bb781b9e91c24fa\", \"got_dawn_revision\": \"10c24684ab9cd1c747e251d7cb4590c43eecad6b\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"6aa18804b4902bedc73248590288b6d3785ba221\", \"got_revision_cp\": \"refs/heads/master@{#645619}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"370905e268aed09b47e08d593d95f4f31f79cfc6\", \"got_v8_revision_cp\": \"refs/heads/7.5.138@{#1}\", \"got_webrtc_revision\": \"b118d428499844749df6dd28aa3dbc8d5e1484fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27352}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"6aa18804b4902bedc73248590288b6d3785ba221\", \"swarm_hashes\": {\"accessibility_unittests\": \"5de5240ba37de619d0cd3db73b5c1e2ecf616bc0\", \"angle_unittests\": \"b76b1d7c817893b6214858a9adf28d49101e67e6\", \"app_list_unittests\": \"58c8316c85323951ba6187404cb8e9e4699b1915\", \"app_shell_unittests\": \"b32d375a2a57a50048c7dbab135d7bdc070928e3\", \"ash_unittests\": \"301d2a57101e78c4199756d17fe71c0fc5e1f507\", \"aura_unittests\": \"91474613c8f2c05cba5698ef280c2575bf65144f\", \"base_unittests\": \"e15edaf6c91e9d0150a6f7cbd66bc72599143cc7\", \"blink_common_unittests\": \"57c7f30b773ca1c42b8284436b9606e93428c7ba\", \"blink_fuzzer_unittests\": \"e93c67a4c9260fea8f30054e7a608643ef2f7ac2\", \"blink_heap_unittests\": \"4d9d8dc5ecafb0625d13847e853ecd5dff46b3dc\", \"blink_platform_unittests\": \"50396e6f3e66859c62289972217f2dc5683b0a9e\", \"blink_unittests\": \"ef94c6ee5bb9aff932bbb946019759d0fa6fb7e0\", \"boringssl_crypto_tests\": \"03babc285e1c0593eb59f589133b82004a418e72\", \"boringssl_ssl_tests\": \"c80304a26a07e96423bb4207d5ffc3fc1c80f36b\", \"browser_tests\": \"b2f648e9f4c8dd4244824b396cac988ffa013a8d\", \"cacheinvalidation_unittests\": \"dfc181f720eaa78396ccecf170f0be21eb9c056d\", \"capture_unittests\": \"0fbe7dface9852874af05cb45adba674a0a9209d\", \"cast_unittests\": \"dae1aea74611ba0dc529be83853b680bdb87a0f4\", \"cc_unittests\": \"7324e7e1ae3a35c53b9a6ccf58ed60da7579de19\", \"chrome_app_unittests\": \"5204227809c10b7310594379890f26f80c5dc47c\", \"chromedriver_unittests\": \"7ef61ef0094f14df4165fc26fabad0ef66b04418\", \"chromeos_components_unittests\": \"f5042e29b5b04ed8f6afa9566e3993b1358dd1bc\", \"chromeos_unittests\": \"62cf5237c8e022bc9c7074590a191e55354e4031\", \"components_browsertests\": \"fd8ed8c5be6cc86c4583a35197f76c51c173afd5\", \"components_unittests\": \"cb6a9c79d6d750669eeadec91da6330422d9d8a4\", \"compositor_unittests\": \"33c44322dac85755880218ec45a13d034c97dc41\", \"content_browsertests\": \"fdbe32b1beadcb726c8a3f908bd32c17c3e55d7f\", \"content_unittests\": \"fc10b49110ac4439f48f825800c5459b6e3f1091\", \"crypto_unittests\": \"4819cf1c5fe9bce80bfbfd76a4077c2e34aab011\", \"dbus_unittests\": \"b4bdc645b11b69015040c618acd9a417abb0aa5e\", \"device_unittests\": \"e4a43c8a50a2f9af5e4d17d6f8d1e4dbd4e0ea51\", \"display_unittests\": \"ccb3ceb113c27468aeeb1debd85791efa0481f03\", \"events_unittests\": \"c9bd3406e40843ec6a99d8d76b84b02bf914018c\", \"exo_unittests\": \"a4e0263237b72a27f9c40cc524e84774a36b291a\", \"extensions_browsertests\": \"80b714805251205113c8e36b52e0d06b1f881e1d\", \"extensions_unittests\": \"853d64548e3d29665cad945abf773866a27ade3b\", \"filesystem_service_unittests\": \"061fa32267fce0a0fe181d8513f356b4c9f449b9\", \"gcm_unit_tests\": \"5dc27f34cfa437e63d907c068e197d925cf3425f\", \"gfx_unittests\": \"465bf2e03dd9f2f4e9d5732b87726751457e4800\", \"gin_unittests\": \"ae210c3e4217d29a0937e86d2b3d0e73eae29b1b\", \"gl_unittests_ozone\": \"12cc0b6727d6afeda3fd0b2b2b0be8aa668dfab9\", \"google_apis_unittests\": \"3a4c9ce29039228db861a368ba42fbf1bf8a5b06\", \"gpu_unittests\": \"46c3503207dfa59576e846b59428ffd9ce50a56a\", \"interactive_ui_tests\": \"07149fc5434312b92482a9bb6f4e1f9fbfbcdbe2\", \"ipc_tests\": \"3ca27a70e9af4e417fee8361c04c77a46c10c119\", \"jingle_unittests\": \"46032e9b8c18f5665af3f51fe0a6c65869d63f89\", \"keyboard_unittests\": \"0b0089363f5bae75ecee4fa8f0c32383f4000867\", \"latency_unittests\": \"0d3bc09b873257651a79d6e0b3d568447c272d95\", \"leveldb_service_unittests\": \"476203e7be08bcdae4dff81a5810082cbb276a84\", \"libjingle_xmpp_unittests\": \"038f7f218ab1f334c43488eae162112dae78ea38\", \"media_blink_unittests\": \"16c0f6ffc29b5f16f1a5b023816ea9859b33c946\", \"media_service_unittests\": \"d7c7638ec95124ed3bafac843f5b9e9364f0786c\", \"media_unittests\": \"7e3459e8a23449c2e11575a6d7b2b404843dbdab\", \"message_center_unittests\": \"164d9907ce1a7bdb34063a7df93040f18be4425e\", \"midi_unittests\": \"fa400f27cb9e1f3cd5d7e5d8812aaf4781af4ede\", \"mojo_core_unittests\": \"e7140e2f258363d3c93380596965480e82d665e3\", \"mojo_unittests\": \"a9f02537a596810d4c1ce92eea4f36f8c6607a54\", \"nacl_helper_nonsfi_unittests\": \"40893c3e7546816a2fa30d63beb9d547a0fa8316\", \"nacl_loader_unittests\": \"41eaaf2b0aaea6cf91f6240e01ada8b01187798a\", \"native_theme_unittests\": \"ac79940d089aec1b52e622bcf4e929246cc9c58e\", \"net_unittests\": \"7d2b7f33c610ef69ca04af52591c37406a773f4f\", \"ozone_gl_unittests\": \"56b72fd99df7c809eb6580d44f895894f4350d48\", \"ozone_unittests\": \"11587c15167451a4444cdad37d7fa9f7658c6b0b\", \"ozone_x11_unittests\": \"29aeb5e055a43a70cc8d929d7a870960374bfdf4\", \"pdf_unittests\": \"941a86e3877a566027833a30839eaec41102063d\", \"perfetto_unittests\": \"d11d64c1a0197e0f7934bec86411010104721f5b\", \"ppapi_unittests\": \"37fccc1757417bb505d59f4417e820961dc9e58e\", \"printing_unittests\": \"0c531a407a3ac6fade232ded859d50c5116af494\", \"remoting_unittests\": \"5cee75d574ea4b5da1848b682a6721e1e29ab1f3\", \"sandbox_linux_unittests\": \"3ef12bb961140977a46979295908efb8d06d6958\", \"service_manager_unittests\": \"43241a9103359cea42f9d9560bcb3442fa16bbe9\", \"services_unittests\": \"5406052f78d4ec6bd4d0d334f2a27e2b22cf86bf\", \"shell_dialogs_unittests\": \"09602563b979ac0d7b54a6e8da00595bfe07fd70\", \"skia_unittests\": \"f0b1f53a0d3deabec25da6597fe4af70aa626fab\", \"snapshot_unittests\": \"9948102c10639a13db061a295cea95245858aa99\", \"sql_unittests\": \"3506bb864cbf9c378ee48c19bb59296c7af012d8\", \"storage_unittests\": \"5aa0cc84f02263da0949e5227a2e47379a566fb0\", \"sync_integration_tests\": \"9f59a5c5bf371a2044101d6f0be9e5ed56bc7a71\", \"traffic_annotation_auditor_unittests\": \"3fe3aff88c5ef00c6303d5e98b5531e038e0e9f1\", \"ui_base_unittests\": \"1ad528c86d1b54260cd95aeb46b87ba8fd8ae49f\", \"ui_chromeos_unittests\": \"cf24da4ddddce8a91ccb8c5f183f247f6cde3d42\", \"ui_touch_selection_unittests\": \"6d4987ecd4a57874fc00150fa4e521913bc20ceb\", \"unit_tests\": \"47cb4ae236ffbd49d74c164376e3888329cd09fe\", \"url_unittests\": \"4e2b6c268dcd81b5772d763215ca47f821bf7f1c\", \"usage_time_limit_unittests\": \"e1f5a1cc381c5165844c67e817ec6458300d8218\", \"views_unittests\": \"0b3da161c55d3c2ecd717e6895cb19880688f5b3\", \"viz_unittests\": \"dabfe17f078cc1fc74ac2cd0c3336d8f393c5e75\", \"wm_unittests\": \"4527ef06b25d00ca4bbe46f3ee1f2f9667a65552\", \"wtf_unittests\": \"39a29a32e2bf317c8a43c44271d5f7f9cba31bb9\"}, \"swarm_hashes_refs/heads/master(at){#645619}_without_patch\": {\"accessibility_unittests\": \"5de5240ba37de619d0cd3db73b5c1e2ecf616bc0\", \"angle_unittests\": \"b76b1d7c817893b6214858a9adf28d49101e67e6\", \"app_list_unittests\": \"58c8316c85323951ba6187404cb8e9e4699b1915\", \"app_shell_unittests\": \"b32d375a2a57a50048c7dbab135d7bdc070928e3\", \"ash_unittests\": \"301d2a57101e78c4199756d17fe71c0fc5e1f507\", \"aura_unittests\": \"91474613c8f2c05cba5698ef280c2575bf65144f\", \"base_unittests\": \"e15edaf6c91e9d0150a6f7cbd66bc72599143cc7\", \"blink_common_unittests\": \"57c7f30b773ca1c42b8284436b9606e93428c7ba\", \"blink_fuzzer_unittests\": \"e93c67a4c9260fea8f30054e7a608643ef2f7ac2\", \"blink_heap_unittests\": \"4d9d8dc5ecafb0625d13847e853ecd5dff46b3dc\", \"blink_platform_unittests\": \"50396e6f3e66859c62289972217f2dc5683b0a9e\", \"blink_unittests\": \"ef94c6ee5bb9aff932bbb946019759d0fa6fb7e0\", \"boringssl_crypto_tests\": \"03babc285e1c0593eb59f589133b82004a418e72\", \"boringssl_ssl_tests\": \"c80304a26a07e96423bb4207d5ffc3fc1c80f36b\", \"browser_tests\": \"b2f648e9f4c8dd4244824b396cac988ffa013a8d\", \"cacheinvalidation_unittests\": \"dfc181f720eaa78396ccecf170f0be21eb9c056d\", \"capture_unittests\": \"0fbe7dface9852874af05cb45adba674a0a9209d\", \"cast_unittests\": \"dae1aea74611ba0dc529be83853b680bdb87a0f4\", \"cc_unittests\": \"7324e7e1ae3a35c53b9a6ccf58ed60da7579de19\", \"chrome_app_unittests\": \"5204227809c10b7310594379890f26f80c5dc47c\", \"chromedriver_unittests\": \"7ef61ef0094f14df4165fc26fabad0ef66b04418\", \"chromeos_components_unittests\": \"f5042e29b5b04ed8f6afa9566e3993b1358dd1bc\", \"chromeos_unittests\": \"62cf5237c8e022bc9c7074590a191e55354e4031\", \"components_browsertests\": \"fd8ed8c5be6cc86c4583a35197f76c51c173afd5\", \"components_unittests\": \"cb6a9c79d6d750669eeadec91da6330422d9d8a4\", \"compositor_unittests\": \"33c44322dac85755880218ec45a13d034c97dc41\", \"content_browsertests\": \"fdbe32b1beadcb726c8a3f908bd32c17c3e55d7f\", \"content_unittests\": \"fc10b49110ac4439f48f825800c5459b6e3f1091\", \"crypto_unittests\": \"4819cf1c5fe9bce80bfbfd76a4077c2e34aab011\", \"dbus_unittests\": \"b4bdc645b11b69015040c618acd9a417abb0aa5e\", \"device_unittests\": \"e4a43c8a50a2f9af5e4d17d6f8d1e4dbd4e0ea51\", \"display_unittests\": \"ccb3ceb113c27468aeeb1debd85791efa0481f03\", \"events_unittests\": \"c9bd3406e40843ec6a99d8d76b84b02bf914018c\", \"exo_unittests\": \"a4e0263237b72a27f9c40cc524e84774a36b291a\", \"extensions_browsertests\": \"80b714805251205113c8e36b52e0d06b1f881e1d\", \"extensions_unittests\": \"853d64548e3d29665cad945abf773866a27ade3b\", \"filesystem_service_unittests\": \"061fa32267fce0a0fe181d8513f356b4c9f449b9\", \"gcm_unit_tests\": \"5dc27f34cfa437e63d907c068e197d925cf3425f\", \"gfx_unittests\": \"465bf2e03dd9f2f4e9d5732b87726751457e4800\", \"gin_unittests\": \"ae210c3e4217d29a0937e86d2b3d0e73eae29b1b\", \"gl_unittests_ozone\": \"12cc0b6727d6afeda3fd0b2b2b0be8aa668dfab9\", \"google_apis_unittests\": \"3a4c9ce29039228db861a368ba42fbf1bf8a5b06\", \"gpu_unittests\": \"46c3503207dfa59576e846b59428ffd9ce50a56a\", \"interactive_ui_tests\": \"07149fc5434312b92482a9bb6f4e1f9fbfbcdbe2\", \"ipc_tests\": \"3ca27a70e9af4e417fee8361c04c77a46c10c119\", \"jingle_unittests\": \"46032e9b8c18f5665af3f51fe0a6c65869d63f89\", \"keyboard_unittests\": \"0b0089363f5bae75ecee4fa8f0c32383f4000867\", \"latency_unittests\": \"0d3bc09b873257651a79d6e0b3d568447c272d95\", \"leveldb_service_unittests\": \"476203e7be08bcdae4dff81a5810082cbb276a84\", \"libjingle_xmpp_unittests\": \"038f7f218ab1f334c43488eae162112dae78ea38\", \"media_blink_unittests\": \"16c0f6ffc29b5f16f1a5b023816ea9859b33c946\", \"media_service_unittests\": \"d7c7638ec95124ed3bafac843f5b9e9364f0786c\", \"media_unittests\": \"7e3459e8a23449c2e11575a6d7b2b404843dbdab\", \"message_center_unittests\": \"164d9907ce1a7bdb34063a7df93040f18be4425e\", \"midi_unittests\": \"fa400f27cb9e1f3cd5d7e5d8812aaf4781af4ede\", \"mojo_core_unittests\": \"e7140e2f258363d3c93380596965480e82d665e3\", \"mojo_unittests\": \"a9f02537a596810d4c1ce92eea4f36f8c6607a54\", \"nacl_helper_nonsfi_unittests\": \"40893c3e7546816a2fa30d63beb9d547a0fa8316\", \"nacl_loader_unittests\": \"41eaaf2b0aaea6cf91f6240e01ada8b01187798a\", \"native_theme_unittests\": \"ac79940d089aec1b52e622bcf4e929246cc9c58e\", \"net_unittests\": \"7d2b7f33c610ef69ca04af52591c37406a773f4f\", \"ozone_gl_unittests\": \"56b72fd99df7c809eb6580d44f895894f4350d48\", \"ozone_unittests\": \"11587c15167451a4444cdad37d7fa9f7658c6b0b\", \"ozone_x11_unittests\": \"29aeb5e055a43a70cc8d929d7a870960374bfdf4\", \"pdf_unittests\": \"941a86e3877a566027833a30839eaec41102063d\", \"perfetto_unittests\": \"d11d64c1a0197e0f7934bec86411010104721f5b\", \"ppapi_unittests\": \"37fccc1757417bb505d59f4417e820961dc9e58e\", \"printing_unittests\": \"0c531a407a3ac6fade232ded859d50c5116af494\", \"remoting_unittests\": \"5cee75d574ea4b5da1848b682a6721e1e29ab1f3\", \"sandbox_linux_unittests\": \"3ef12bb961140977a46979295908efb8d06d6958\", \"service_manager_unittests\": \"43241a9103359cea42f9d9560bcb3442fa16bbe9\", \"services_unittests\": \"5406052f78d4ec6bd4d0d334f2a27e2b22cf86bf\", \"shell_dialogs_unittests\": \"09602563b979ac0d7b54a6e8da00595bfe07fd70\", \"skia_unittests\": \"f0b1f53a0d3deabec25da6597fe4af70aa626fab\", \"snapshot_unittests\": \"9948102c10639a13db061a295cea95245858aa99\", \"sql_unittests\": \"3506bb864cbf9c378ee48c19bb59296c7af012d8\", \"storage_unittests\": \"5aa0cc84f02263da0949e5227a2e47379a566fb0\", \"sync_integration_tests\": \"9f59a5c5bf371a2044101d6f0be9e5ed56bc7a71\", \"traffic_annotation_auditor_unittests\": \"3fe3aff88c5ef00c6303d5e98b5531e038e0e9f1\", \"ui_base_unittests\": \"1ad528c86d1b54260cd95aeb46b87ba8fd8ae49f\", \"ui_chromeos_unittests\": \"cf24da4ddddce8a91ccb8c5f183f247f6cde3d42\", \"ui_touch_selection_unittests\": \"6d4987ecd4a57874fc00150fa4e521913bc20ceb\", \"unit_tests\": \"47cb4ae236ffbd49d74c164376e3888329cd09fe\", \"url_unittests\": \"4e2b6c268dcd81b5772d763215ca47f821bf7f1c\", \"usage_time_limit_unittests\": \"e1f5a1cc381c5165844c67e817ec6458300d8218\", \"views_unittests\": \"0b3da161c55d3c2ecd717e6895cb19880688f5b3\", \"viz_unittests\": \"dabfe17f078cc1fc74ac2cd0c3336d8f393c5e75\", \"wm_unittests\": \"4527ef06b25d00ca4bbe46f3ee1f2f9667a65552\", \"wtf_unittests\": \"39a29a32e2bf317c8a43c44271d5f7f9cba31bb9\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 tests failed: ['non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553822871414475",
"status": "COMPLETED",
"status_changed_ts": "1553822871722049",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11590",
"builder:linux-chromeos-dbg",
"buildset:commit/git/6aa18804b4902bedc73248590288b6d3785ba221",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6aa18804b4902bedc73248590288b6d3785ba221",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083122813637196912",
"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/8917692750812187504/+/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:43dfdb1451d57110",
"user_agent:luci-scheduler"
],
"updated_ts": "1553828402688893",
"url": "https://ci.chromium.org/b/8917692750812187504",
"utcnow_ts": "1553828403672196"
}
}
[03:00:03.687] Invocation finished in 1h38m41.60827331s with status FAILED