[02:31:48.477] New invocation is queued and will start shortly
[02:31:49.580] Starting the invocation (attempt 1)
[02:31:49.649] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:31:49.649] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:31:49.649] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083752560777127888",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"1aac03db98dbfe4359a16379cf7cfbc8d25ce69a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083752560777127888",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/1aac03db98dbfe4359a16379cf7cfbc8d25ce69a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1aac03db98dbfe4359a16379cf7cfbc8d25ce69a",
"gitiles_ref:refs/heads/master"
]
}
[02:31:50.951] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553221909852097",
"id": "8918322498613003744",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1aac03db98dbfe4359a16379cf7cfbc8d25ce69a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553221910832358",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11490",
"builder:linux-chromeos-dbg",
"buildset:commit/git/1aac03db98dbfe4359a16379cf7cfbc8d25ce69a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1aac03db98dbfe4359a16379cf7cfbc8d25ce69a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083752560777127888",
"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/8918322498613003744/+/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:43bc0f0778bab510",
"user_agent:luci-scheduler"
],
"updated_ts": "1553221910832452",
"url": "https://ci.chromium.org/b/8918322498613003744",
"utcnow_ts": "1553221910942203"
}
}
[02:31:50.951] Task URL: https://ci.chromium.org/b/8918322498613003744
[02:31:50.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:2:0) after 1m0s
[02:32:51.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:2:0)
[02:32:51.007] Timer tick, asking Buildbucket for the build status
[02:32:51.150] Build 8918322498613003744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:51.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:3:0) after 1m0s
[02:33:51.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:3:0)
[02:33:51.195] Timer tick, asking Buildbucket for the build status
[02:33:51.292] Build 8918322498613003744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:51.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:4:0) after 1m0s
[02:34:51.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:4:0)
[02:34:51.330] Timer tick, asking Buildbucket for the build status
[02:34:51.486] Build 8918322498613003744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:51.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:5:0) after 1m0s
[02:35:51.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:5:0)
[02:35:51.505] Timer tick, asking Buildbucket for the build status
[02:35:51.710] Build 8918322498613003744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:51.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:6:0) after 1m0s
[02:36:51.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:6:0)
[02:36:51.777] Timer tick, asking Buildbucket for the build status
[02:36:52.121] Build 8918322498613003744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:52.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:7:0) after 1m0s
[02:37:52.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:7:0)
[02:37:52.200] Timer tick, asking Buildbucket for the build status
[02:37:52.966] Build 8918322498613003744: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:52.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:8:0) after 1m0s
[02:38:19.926] Received PubSub notification, asking Buildbucket for the build status
[02:38:20.031] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:52.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:8:0)
[02:38:52.977] Timer tick, asking Buildbucket for the build status
[02:38:53.288] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:53.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:10:0) after 1m0s
[02:39:53.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:10:0)
[02:39:53.307] Timer tick, asking Buildbucket for the build status
[02:39:53.571] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:53.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:11:0) after 1m0s
[02:40:53.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:11:0)
[02:40:53.591] Timer tick, asking Buildbucket for the build status
[02:40:54.573] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:54.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:12:0) after 1m0s
[02:41:54.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:12:0)
[02:41:54.559] Timer tick, asking Buildbucket for the build status
[02:41:54.821] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:54.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:13:0) after 1m0s
[02:42:54.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:13:0)
[02:42:54.839] Timer tick, asking Buildbucket for the build status
[02:42:55.261] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:55.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:14:0) after 1m0s
[02:43:55.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:14:0)
[02:43:55.303] Timer tick, asking Buildbucket for the build status
[02:43:55.572] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:55.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:15:0) after 1m0s
[02:44:55.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:15:0)
[02:44:55.616] Timer tick, asking Buildbucket for the build status
[02:44:55.754] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:55.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:16:0) after 1m0s
[02:45:55.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:16:0)
[02:45:55.779] Timer tick, asking Buildbucket for the build status
[02:45:55.940] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:55.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:17:0) after 1m0s
[02:46:55.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:17:0)
[02:46:55.975] Timer tick, asking Buildbucket for the build status
[02:46:56.562] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:56.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:18:0) after 1m0s
[02:47:56.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:18:0)
[02:47:56.585] Timer tick, asking Buildbucket for the build status
[02:47:56.715] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:56.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:19:0) after 1m0s
[02:48:56.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:19:0)
[02:48:56.734] Timer tick, asking Buildbucket for the build status
[02:48:56.820] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:56.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:20:0) after 1m0s
[02:49:56.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:20:0)
[02:49:56.827] Timer tick, asking Buildbucket for the build status
[02:49:56.940] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:56.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:21:0) after 1m0s
[02:50:57.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:21:0)
[02:50:57.002] Timer tick, asking Buildbucket for the build status
[02:50:57.158] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:57.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:22:0) after 1m0s
[02:51:57.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:22:0)
[02:51:57.136] Timer tick, asking Buildbucket for the build status
[02:51:57.370] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:57.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:23:0) after 1m0s
[02:52:57.406] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:23:0)
[02:52:57.406] Timer tick, asking Buildbucket for the build status
[02:52:58.276] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:58.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:24:0) after 1m0s
[02:53:58.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:24:0)
[02:53:58.301] Timer tick, asking Buildbucket for the build status
[02:53:58.412] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:58.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:25:0) after 1m0s
[02:54:58.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:25:0)
[02:54:58.434] Timer tick, asking Buildbucket for the build status
[02:54:59.172] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:59.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:26:0) after 1m0s
[02:55:59.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:26:0)
[02:55:59.192] Timer tick, asking Buildbucket for the build status
[02:55:59.359] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:59.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:27:0) after 1m0s
[02:56:59.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:27:0)
[02:56:59.357] Timer tick, asking Buildbucket for the build status
[02:57:00.117] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:00.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:28:0) after 1m0s
[02:58:00.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:28:0)
[02:58:00.146] Timer tick, asking Buildbucket for the build status
[02:58:00.282] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:00.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:29:0) after 1m0s
[02:59:00.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:29:0)
[02:59:00.291] Timer tick, asking Buildbucket for the build status
[02:59:00.406] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:00.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:30:0) after 1m0s
[03:00:00.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:30:0)
[03:00:00.431] Timer tick, asking Buildbucket for the build status
[03:00:00.891] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:00.891] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:31:0) after 1m0s
[03:01:00.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:31:0)
[03:01:00.916] Timer tick, asking Buildbucket for the build status
[03:01:01.378] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:01.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:32:0) after 1m0s
[03:02:01.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:32:0)
[03:02:01.400] Timer tick, asking Buildbucket for the build status
[03:02:02.408] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:02.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:33:0) after 1m0s
[03:03:02.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:33:0)
[03:03:02.425] Timer tick, asking Buildbucket for the build status
[03:03:03.279] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:03.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:34:0) after 1m0s
[03:04:03.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:34:0)
[03:04:03.318] Timer tick, asking Buildbucket for the build status
[03:04:03.944] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:03.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:35:0) after 1m0s
[03:05:05.012] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:35:0)
[03:05:05.012] Timer tick, asking Buildbucket for the build status
[03:05:05.785] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:05.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:36:0) after 1m0s
[03:06:05.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:36:0)
[03:06:05.846] Timer tick, asking Buildbucket for the build status
[03:06:06.267] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:06.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:37:0) after 1m0s
[03:07:06.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:37:0)
[03:07:06.289] Timer tick, asking Buildbucket for the build status
[03:07:06.621] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:06.621] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:38:0) after 1m0s
[03:08:06.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:38:0)
[03:08:06.657] Timer tick, asking Buildbucket for the build status
[03:08:07.102] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:07.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:39:0) after 1m0s
[03:09:07.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:39:0)
[03:09:07.127] Timer tick, asking Buildbucket for the build status
[03:09:07.278] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:07.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:40:0) after 1m0s
[03:10:07.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:40:0)
[03:10:07.299] Timer tick, asking Buildbucket for the build status
[03:10:09.232] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:09.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:41:0) after 1m0s
[03:11:09.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:41:0)
[03:11:09.288] Timer tick, asking Buildbucket for the build status
[03:11:09.462] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:09.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:42:0) after 1m0s
[03:12:09.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:42:0)
[03:12:09.494] Timer tick, asking Buildbucket for the build status
[03:12:09.815] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:09.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:43:0) after 1m0s
[03:13:10.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:43:0)
[03:13:10.168] Timer tick, asking Buildbucket for the build status
[03:13:10.576] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:10.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:44:0) after 1m0s
[03:14:10.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:44:0)
[03:14:10.567] Timer tick, asking Buildbucket for the build status
[03:14:11.589] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:11.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:45:0) after 1m0s
[03:15:11.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:45:0)
[03:15:11.582] Timer tick, asking Buildbucket for the build status
[03:15:12.100] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:12.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:46:0) after 1m0s
[03:16:12.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:46:0)
[03:16:12.120] Timer tick, asking Buildbucket for the build status
[03:16:12.687] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:12.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:47:0) after 1m0s
[03:17:12.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:47:0)
[03:17:12.673] Timer tick, asking Buildbucket for the build status
[03:17:13.193] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:13.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:48:0) after 1m0s
[03:18:13.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:48:0)
[03:18:13.213] Timer tick, asking Buildbucket for the build status
[03:18:13.388] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:13.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:49:0) after 1m0s
[03:19:13.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:49:0)
[03:19:13.428] Timer tick, asking Buildbucket for the build status
[03:19:14.691] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:14.691] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:50:0) after 1m0s
[03:20:14.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:50:0)
[03:20:14.675] Timer tick, asking Buildbucket for the build status
[03:20:14.775] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:14.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:51:0) after 1m0s
[03:21:15.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:51:0)
[03:21:15.233] Timer tick, asking Buildbucket for the build status
[03:21:16.409] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:16.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:52:0) after 1m0s
[03:22:16.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:52:0)
[03:22:16.435] Timer tick, asking Buildbucket for the build status
[03:22:16.638] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:16.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:53:0) after 1m0s
[03:23:16.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:53:0)
[03:23:16.662] Timer tick, asking Buildbucket for the build status
[03:23:16.900] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:16.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:54:0) after 1m0s
[03:24:16.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:54:0)
[03:24:16.922] Timer tick, asking Buildbucket for the build status
[03:24:17.298] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:17.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:55:0) after 1m0s
[03:25:17.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:55:0)
[03:25:17.292] Timer tick, asking Buildbucket for the build status
[03:25:17.449] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:17.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:56:0) after 1m0s
[03:26:17.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:56:0)
[03:26:17.541] Timer tick, asking Buildbucket for the build status
[03:26:17.718] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:17.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:57:0) after 1m0s
[03:27:17.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:57:0)
[03:27:17.719] Timer tick, asking Buildbucket for the build status
[03:27:17.874] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:17.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:58:0) after 1m0s
[03:28:17.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:58:0)
[03:28:17.887] Timer tick, asking Buildbucket for the build status
[03:28:18.885] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:18.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:59:0) after 1m0s
[03:29:18.866] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:59:0)
[03:29:18.867] Timer tick, asking Buildbucket for the build status
[03:29:19.571] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:19.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:60:0) after 1m0s
[03:30:19.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:60:0)
[03:30:19.594] Timer tick, asking Buildbucket for the build status
[03:30:19.704] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:19.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:61:0) after 1m0s
[03:31:19.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:61:0)
[03:31:19.715] Timer tick, asking Buildbucket for the build status
[03:31:19.895] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:19.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:62:0) after 1m0s
[03:32:19.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:62:0)
[03:32:19.905] Timer tick, asking Buildbucket for the build status
[03:32:20.390] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:20.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:63:0) after 1m0s
[03:33:20.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:63:0)
[03:33:20.418] Timer tick, asking Buildbucket for the build status
[03:33:20.602] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:20.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:64:0) after 1m0s
[03:34:20.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:64:0)
[03:34:20.606] Timer tick, asking Buildbucket for the build status
[03:34:21.203] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:21.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:65:0) after 1m0s
[03:35:21.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:65:0)
[03:35:21.209] Timer tick, asking Buildbucket for the build status
[03:35:21.791] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:21.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:66:0) after 1m0s
[03:36:21.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:66:0)
[03:36:21.845] Timer tick, asking Buildbucket for the build status
[03:36:22.354] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:22.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:67:0) after 1m0s
[03:37:22.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:67:0)
[03:37:22.591] Timer tick, asking Buildbucket for the build status
[03:37:22.775] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:22.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:68:0) after 1m0s
[03:38:22.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:68:0)
[03:38:22.801] Timer tick, asking Buildbucket for the build status
[03:38:23.973] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:23.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:69:0) after 1m0s
[03:39:23.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:69:0)
[03:39:23.956] Timer tick, asking Buildbucket for the build status
[03:39:24.070] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:24.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:70:0) after 1m0s
[03:40:24.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:70:0)
[03:40:24.084] Timer tick, asking Buildbucket for the build status
[03:40:25.019] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:25.019] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:71:0) after 1m0s
[03:41:25.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:71:0)
[03:41:25.039] Timer tick, asking Buildbucket for the build status
[03:41:25.215] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:25.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:72:0) after 1m0s
[03:42:25.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:72:0)
[03:42:25.316] Timer tick, asking Buildbucket for the build status
[03:42:25.638] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:25.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:73:0) after 1m0s
[03:43:25.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:73:0)
[03:43:25.678] Timer tick, asking Buildbucket for the build status
[03:43:26.330] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:26.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:74:0) after 1m0s
[03:44:26.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:74:0)
[03:44:26.343] Timer tick, asking Buildbucket for the build status
[03:44:26.780] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:26.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:75:0) after 1m0s
[03:45:26.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:75:0)
[03:45:26.785] Timer tick, asking Buildbucket for the build status
[03:45:26.975] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:26.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:76:0) after 1m0s
[03:46:26.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:76:0)
[03:46:26.967] Timer tick, asking Buildbucket for the build status
[03:46:27.379] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:27.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:77:0) after 1m0s
[03:47:27.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:77:0)
[03:47:27.391] Timer tick, asking Buildbucket for the build status
[03:47:27.562] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:27.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:78:0) after 1m0s
[03:48:27.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:78:0)
[03:48:27.550] Timer tick, asking Buildbucket for the build status
[03:48:27.796] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:27.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:79:0) after 1m0s
[03:49:27.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:79:0)
[03:49:27.808] Timer tick, asking Buildbucket for the build status
[03:49:28.055] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:28.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:80:0) after 1m0s
[03:50:28.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:80:0)
[03:50:28.076] Timer tick, asking Buildbucket for the build status
[03:50:28.952] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:28.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:81:0) after 1m0s
[03:51:28.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:81:0)
[03:51:28.953] Timer tick, asking Buildbucket for the build status
[03:51:29.099] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:29.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:82:0) after 1m0s
[03:52:29.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:82:0)
[03:52:29.120] Timer tick, asking Buildbucket for the build status
[03:52:29.479] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:29.479] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:83:0) after 1m0s
[03:53:29.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:83:0)
[03:53:29.502] Timer tick, asking Buildbucket for the build status
[03:53:29.811] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:29.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:84:0) after 1m0s
[03:54:29.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:84:0)
[03:54:29.807] Timer tick, asking Buildbucket for the build status
[03:54:29.974] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:29.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:85:0) after 1m0s
[03:55:30.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:85:0)
[03:55:30.018] Timer tick, asking Buildbucket for the build status
[03:55:30.577] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:30.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:86:0) after 1m0s
[03:56:30.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:86:0)
[03:56:30.566] Timer tick, asking Buildbucket for the build status
[03:56:30.778] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:30.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:87:0) after 1m0s
[03:57:30.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:87:0)
[03:57:30.763] Timer tick, asking Buildbucket for the build status
[03:57:30.893] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:30.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:88:0) after 1m0s
[03:58:30.917] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:88:0)
[03:58:30.917] Timer tick, asking Buildbucket for the build status
[03:58:31.026] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:31.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:89:0) after 1m0s
[03:59:31.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:89:0)
[03:59:31.042] Timer tick, asking Buildbucket for the build status
[03:59:31.196] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:31.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:90:0) after 1m0s
[04:00:31.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:90:0)
[04:00:31.207] Timer tick, asking Buildbucket for the build status
[04:00:31.526] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:31.526] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:91:0) after 1m0s
[04:01:31.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:91:0)
[04:01:31.552] Timer tick, asking Buildbucket for the build status
[04:01:32.551] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:32.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:92:0) after 1m0s
[04:02:32.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:92:0)
[04:02:32.555] Timer tick, asking Buildbucket for the build status
[04:02:33.070] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:33.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:93:0) after 1m0s
[04:03:33.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:93:0)
[04:03:33.081] Timer tick, asking Buildbucket for the build status
[04:03:34.193] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:34.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:94:0) after 1m0s
[04:04:34.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:94:0)
[04:04:34.215] Timer tick, asking Buildbucket for the build status
[04:04:34.416] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:34.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:95:0) after 1m0s
[04:05:34.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:95:0)
[04:05:34.411] Timer tick, asking Buildbucket for the build status
[04:05:34.594] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:34.594] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:96:0) after 1m0s
[04:06:34.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:96:0)
[04:06:34.613] Timer tick, asking Buildbucket for the build status
[04:06:34.801] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:34.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:97:0) after 1m0s
[04:07:34.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:97:0)
[04:07:34.790] Timer tick, asking Buildbucket for the build status
[04:07:35.689] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:35.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:98:0) after 1m0s
[04:08:35.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:98:0)
[04:08:35.717] Timer tick, asking Buildbucket for the build status
[04:08:35.839] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:35.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:99:0) after 1m0s
[04:09:35.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:99:0)
[04:09:35.896] Timer tick, asking Buildbucket for the build status
[04:09:36.092] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:36.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:100:0) after 1m0s
[04:10:36.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:100:0)
[04:10:36.079] Timer tick, asking Buildbucket for the build status
[04:10:37.055] Build 8918322498613003744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:37.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083752560777127888:101:0) after 1m0s
[04:10:54.921] Received PubSub notification, asking Buildbucket for the build status
[04:10:55.284] Build 8918322498613003744: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:10:55.284] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553227854111009",
"created_by": "project:chromium",
"created_ts": "1553221909852097",
"failure_reason": "BUILD_FAILURE",
"id": "8918322498613003744",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1aac03db98dbfe4359a16379cf7cfbc8d25ce69a\"}}",
"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\": 11490, \"got_angle_revision\": \"3e8a8d5b8567d08855d500c85bcf7cdcc4653f9a\", \"got_dawn_revision\": \"e105f962cf0020ee96a5a14a881c0de4ad46706c\", \"got_nacl_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"got_revision\": \"1aac03db98dbfe4359a16379cf7cfbc8d25ce69a\", \"got_revision_cp\": \"refs/heads/master@{#643233}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"c4cbeac0c0dcce8ac9351199474eb58917de2df7\", \"got_v8_revision_cp\": \"refs/heads/7.5.86@{#1}\", \"got_webrtc_revision\": \"741daaf0393c4ba42c1bfc9c88907aaa09349987\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27227}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1aac03db98dbfe4359a16379cf7cfbc8d25ce69a\", \"swarm_hashes\": {\"accessibility_unittests\": \"4a7da441be685ebd808d7c7d02f57bbe7cdfa370\", \"angle_unittests\": \"2158193525c97101754efd41de60db590517e908\", \"app_list_unittests\": \"e1d153518a9d4fb6266cff476d2ce4e64cb43805\", \"app_shell_unittests\": \"9cec0eaa476005c4a77a7c37aef4eb9efd496992\", \"ash_unittests\": \"65a9f8c213f86d2d9aaf2ceed4f23f628f0d0b1d\", \"aura_unittests\": \"9577d81e6952da81c0371c51d493012b6830cc5f\", \"base_unittests\": \"153163230a14ddd057583c35a01b469e7f84efc9\", \"blink_common_unittests\": \"5bba3b19069e54900d239a5b476ec6c07f4bba47\", \"blink_fuzzer_unittests\": \"b14c8ca3f66d73d054a815a1195932d753fcddc3\", \"blink_heap_unittests\": \"d4c2dd3812e5e2e270c32226e5f32577f5d78df4\", \"blink_platform_unittests\": \"c8915b6f81c8beaccf93140eb4d985a9b0175c82\", \"blink_unittests\": \"b2136d0de679266a61c4c0aa2fa616bf58190fb3\", \"boringssl_crypto_tests\": \"143dd4a70d9119a92bd0be51c5e28fcbf5b63b32\", \"boringssl_ssl_tests\": \"764d0af444c58c52429ea1f96778a1a6ee113188\", \"browser_tests\": \"515b04b0652932729a02c1153c1c40e2d8219ad8\", \"cacheinvalidation_unittests\": \"abe9d769150059c71fee395f86b84098839bbae0\", \"capture_unittests\": \"69b58816d4ef2d6332245953ba0306776d66b651\", \"cast_unittests\": \"37101aab2edb674ac0ec47b60387cb642de8cd13\", \"cc_unittests\": \"59ca07bf421c27285773be095154b66b82e580c5\", \"chrome_app_unittests\": \"3970935584d8ee93608474d22c73e533df0763e4\", \"chromedriver_unittests\": \"617eeda4cd6e9bc89d30b3996d70207a7690cb06\", \"chromeos_components_unittests\": \"cb2dec2b1b5bff9db31940c8276efd07b111b055\", \"chromeos_unittests\": \"a2126acdf1deb47a14af1f56a2d87f766a6c92cf\", \"components_browsertests\": \"9dd0b27029911e612f35a79052ccc583e5cfcc78\", \"components_unittests\": \"9febcfa22fe1b4faa2d0b659241ee9f9cfe49a90\", \"compositor_unittests\": \"7620a03d087ea03af691d04e2c5e914d815bbf95\", \"content_browsertests\": \"b4f4bda6e75cfda17125ee0fa8d5589ebbac0b20\", \"content_unittests\": \"380b47b468809853f954691d20a8d22249c432e0\", \"crypto_unittests\": \"8379f6d62c1734b1b6b2a268263d487a4504e7de\", \"dbus_unittests\": \"1bcd680eacf613fa55b97fef1d103e1d211090bb\", \"device_unittests\": \"682070828b321f27f7986826777d8a95134e1f77\", \"display_unittests\": \"b35a8560b1002d49d85331d9f9769d77fc9e5112\", \"events_unittests\": \"3299ea25e87caaafe0dd1e8a4527aa78c5b34638\", \"exo_unittests\": \"5bf454068881c2b961dccad0c1cc9c9340118092\", \"extensions_browsertests\": \"1fa2db1d03f106c24d1dbe99a0847081cf57170d\", \"extensions_unittests\": \"65b5590da3d806deb9b0a15186181f1fac1e6928\", \"filesystem_service_unittests\": \"821fc749bc2ef313b8c666840c8ddbf7fd390332\", \"gcm_unit_tests\": \"84f2c55a10dfc5d8488edc0bed0de4654909155f\", \"gfx_unittests\": \"fde6a553c8d04ea8060286dfa48ca09a6a0c4407\", \"gin_unittests\": \"1c88ac2f8fb31c1442a006c13b1dbc419a70d89e\", \"gl_unittests_ozone\": \"2c94709651aaef68d0f08c3e4102222538c0291c\", \"google_apis_unittests\": \"1ab5fade76a4b5f664e108f42c424ca3ae916ba3\", \"gpu_unittests\": \"89056a1bd2114219bb14182b739c44ac1d6227c2\", \"interactive_ui_tests\": \"f7a9cb7bb51ff15c02c42e8565b57a905d0bc4d6\", \"ipc_tests\": \"7f7d3efa880a900b2fc9db5511fe96f941c53a0d\", \"jingle_unittests\": \"fc06edc9678faf1757e9a4fb00f21555d229c502\", \"keyboard_unittests\": \"285b24d4f5a1548d3a86341fb8f92e9e96ef10b0\", \"latency_unittests\": \"fbcaf39c8e45b610a64fa26924ce974b74ded606\", \"leveldb_service_unittests\": \"ac5428e7adeb457fb24d8faa0e3670f210922a85\", \"libjingle_xmpp_unittests\": \"9f7aae2e2bc31f12f56b4c56ef99e7dc5fe9432a\", \"media_blink_unittests\": \"c1d0f5ac79ba4dd86ed10bd6b895356cd856f225\", \"media_service_unittests\": \"1d199354de2d31ee2bee9a71e1d8678b8b6b0060\", \"media_unittests\": \"e5544a98289346f31e6629e3b7da4a8636bb6a8b\", \"message_center_unittests\": \"fa5152a276f9c4c5f25edbf4e8ddefa95d5a3d58\", \"midi_unittests\": \"d48d229ad173f6d607b5117544d47b2d50681df9\", \"mojo_core_unittests\": \"2d67eeb3488f46960673843004117e5069cf7518\", \"mojo_unittests\": \"ac90f2b62f324e7fac3306fdc05cc47fd2116a62\", \"nacl_helper_nonsfi_unittests\": \"6f8aa8c2a1973f2728c146f95749bdaacc193fd5\", \"nacl_loader_unittests\": \"556f817d9bf236efadd791c3718d2662350c6842\", \"native_theme_unittests\": \"4c1e6dd92afdafb9d86687d31d010c20908b7d67\", \"net_unittests\": \"a53e229036811ec3c3cb11304f4135655d43c4c4\", \"ozone_gl_unittests\": \"e25c75aabe75db6937f64d561d39914278aec3f3\", \"ozone_unittests\": \"2da07b38163b41d04b39550187a5f82ee2c86016\", \"ozone_x11_unittests\": \"cddeae97db5661d36dc287865dc7bf94a4866336\", \"pdf_unittests\": \"b2ed01e37a359e7277c167ed00fe98ca68f84963\", \"perfetto_unittests\": \"cdaf2e9a8fbee7702da64daf7d81ae2f6657e910\", \"ppapi_unittests\": \"0939f7b4eebd17b1475b23fa694dd2ea1322e889\", \"printing_unittests\": \"c05487f179438c01ae04563fdd49e94368b403e1\", \"remoting_unittests\": \"26a80dc337201fb1d1cf2016aeb2c511a1e9e799\", \"sandbox_linux_unittests\": \"603a02028ed5daf3bda29b75faadeff202ae3191\", \"service_manager_unittests\": \"490e873aa397072a8be0f86659dae3a96aba5bca\", \"services_unittests\": \"aff15dc9dbb63cfd8366732981759ec0a380d536\", \"shell_dialogs_unittests\": \"2d95af576e4cf9c984c5ae39940f79e23173b087\", \"skia_unittests\": \"4853e6434847aa69fc39b02fe25486986dc62a0b\", \"snapshot_unittests\": \"1be6fcf90472820c539112d21b247dba8ddac70d\", \"sql_unittests\": \"bd081c5e5126e9b02e4afa710edc1a8218f9b3d9\", \"storage_unittests\": \"38b7e3ab8ea64387d558ed7884ae88d010afb1d0\", \"sync_integration_tests\": \"bbdca24f456fba87b0906b32fd00eb894edb3a59\", \"traffic_annotation_auditor_unittests\": \"7ba0e79894a57346890a645ec1f3693925889dff\", \"ui_base_unittests\": \"9bd8862a9e83aadf239aa0d9967bf50737a00ce3\", \"ui_chromeos_unittests\": \"4d12be10fc3c0dd244fa00ef9df0b5b22c896b61\", \"ui_touch_selection_unittests\": \"ad40784ce38374eb5ddcc1e4d909c35481276db3\", \"unit_tests\": \"0d554ce022ec41c9c612e0e29004588716ad211a\", \"url_unittests\": \"2d4190c716b499c7cbece95e57c3d35106cea254\", \"usage_time_limit_unittests\": \"b7b36925ddfa9e8b382cd037ab228159721f83e3\", \"views_unittests\": \"085534cbd75e262ad8d208f5ebcff5a57faefbb5\", \"viz_unittests\": \"f7fea4a14c677945df50f6a53105664c6723ecb9\", \"wm_unittests\": \"2471cf72d03156eb057cb7e8f3fd954ef5251e78\", \"wtf_unittests\": \"b57b376f54bed045410c401bd6277dd117e4e5db\"}, \"swarm_hashes_refs/heads/master(at){#643233}_without_patch\": {\"accessibility_unittests\": \"4a7da441be685ebd808d7c7d02f57bbe7cdfa370\", \"angle_unittests\": \"2158193525c97101754efd41de60db590517e908\", \"app_list_unittests\": \"e1d153518a9d4fb6266cff476d2ce4e64cb43805\", \"app_shell_unittests\": \"9cec0eaa476005c4a77a7c37aef4eb9efd496992\", \"ash_unittests\": \"65a9f8c213f86d2d9aaf2ceed4f23f628f0d0b1d\", \"aura_unittests\": \"9577d81e6952da81c0371c51d493012b6830cc5f\", \"base_unittests\": \"153163230a14ddd057583c35a01b469e7f84efc9\", \"blink_common_unittests\": \"5bba3b19069e54900d239a5b476ec6c07f4bba47\", \"blink_fuzzer_unittests\": \"b14c8ca3f66d73d054a815a1195932d753fcddc3\", \"blink_heap_unittests\": \"d4c2dd3812e5e2e270c32226e5f32577f5d78df4\", \"blink_platform_unittests\": \"c8915b6f81c8beaccf93140eb4d985a9b0175c82\", \"blink_unittests\": \"b2136d0de679266a61c4c0aa2fa616bf58190fb3\", \"boringssl_crypto_tests\": \"143dd4a70d9119a92bd0be51c5e28fcbf5b63b32\", \"boringssl_ssl_tests\": \"764d0af444c58c52429ea1f96778a1a6ee113188\", \"browser_tests\": \"515b04b0652932729a02c1153c1c40e2d8219ad8\", \"cacheinvalidation_unittests\": \"abe9d769150059c71fee395f86b84098839bbae0\", \"capture_unittests\": \"69b58816d4ef2d6332245953ba0306776d66b651\", \"cast_unittests\": \"37101aab2edb674ac0ec47b60387cb642de8cd13\", \"cc_unittests\": \"59ca07bf421c27285773be095154b66b82e580c5\", \"chrome_app_unittests\": \"3970935584d8ee93608474d22c73e533df0763e4\", \"chromedriver_unittests\": \"617eeda4cd6e9bc89d30b3996d70207a7690cb06\", \"chromeos_components_unittests\": \"cb2dec2b1b5bff9db31940c8276efd07b111b055\", \"chromeos_unittests\": \"a2126acdf1deb47a14af1f56a2d87f766a6c92cf\", \"components_browsertests\": \"9dd0b27029911e612f35a79052ccc583e5cfcc78\", \"components_unittests\": \"9febcfa22fe1b4faa2d0b659241ee9f9cfe49a90\", \"compositor_unittests\": \"7620a03d087ea03af691d04e2c5e914d815bbf95\", \"content_browsertests\": \"b4f4bda6e75cfda17125ee0fa8d5589ebbac0b20\", \"content_unittests\": \"380b47b468809853f954691d20a8d22249c432e0\", \"crypto_unittests\": \"8379f6d62c1734b1b6b2a268263d487a4504e7de\", \"dbus_unittests\": \"1bcd680eacf613fa55b97fef1d103e1d211090bb\", \"device_unittests\": \"682070828b321f27f7986826777d8a95134e1f77\", \"display_unittests\": \"b35a8560b1002d49d85331d9f9769d77fc9e5112\", \"events_unittests\": \"3299ea25e87caaafe0dd1e8a4527aa78c5b34638\", \"exo_unittests\": \"5bf454068881c2b961dccad0c1cc9c9340118092\", \"extensions_browsertests\": \"1fa2db1d03f106c24d1dbe99a0847081cf57170d\", \"extensions_unittests\": \"65b5590da3d806deb9b0a15186181f1fac1e6928\", \"filesystem_service_unittests\": \"821fc749bc2ef313b8c666840c8ddbf7fd390332\", \"gcm_unit_tests\": \"84f2c55a10dfc5d8488edc0bed0de4654909155f\", \"gfx_unittests\": \"fde6a553c8d04ea8060286dfa48ca09a6a0c4407\", \"gin_unittests\": \"1c88ac2f8fb31c1442a006c13b1dbc419a70d89e\", \"gl_unittests_ozone\": \"2c94709651aaef68d0f08c3e4102222538c0291c\", \"google_apis_unittests\": \"1ab5fade76a4b5f664e108f42c424ca3ae916ba3\", \"gpu_unittests\": \"89056a1bd2114219bb14182b739c44ac1d6227c2\", \"interactive_ui_tests\": \"f7a9cb7bb51ff15c02c42e8565b57a905d0bc4d6\", \"ipc_tests\": \"7f7d3efa880a900b2fc9db5511fe96f941c53a0d\", \"jingle_unittests\": \"fc06edc9678faf1757e9a4fb00f21555d229c502\", \"keyboard_unittests\": \"285b24d4f5a1548d3a86341fb8f92e9e96ef10b0\", \"latency_unittests\": \"fbcaf39c8e45b610a64fa26924ce974b74ded606\", \"leveldb_service_unittests\": \"ac5428e7adeb457fb24d8faa0e3670f210922a85\", \"libjingle_xmpp_unittests\": \"9f7aae2e2bc31f12f56b4c56ef99e7dc5fe9432a\", \"media_blink_unittests\": \"c1d0f5ac79ba4dd86ed10bd6b895356cd856f225\", \"media_service_unittests\": \"1d199354de2d31ee2bee9a71e1d8678b8b6b0060\", \"media_unittests\": \"e5544a98289346f31e6629e3b7da4a8636bb6a8b\", \"message_center_unittests\": \"fa5152a276f9c4c5f25edbf4e8ddefa95d5a3d58\", \"midi_unittests\": \"d48d229ad173f6d607b5117544d47b2d50681df9\", \"mojo_core_unittests\": \"2d67eeb3488f46960673843004117e5069cf7518\", \"mojo_unittests\": \"ac90f2b62f324e7fac3306fdc05cc47fd2116a62\", \"nacl_helper_nonsfi_unittests\": \"6f8aa8c2a1973f2728c146f95749bdaacc193fd5\", \"nacl_loader_unittests\": \"556f817d9bf236efadd791c3718d2662350c6842\", \"native_theme_unittests\": \"4c1e6dd92afdafb9d86687d31d010c20908b7d67\", \"net_unittests\": \"a53e229036811ec3c3cb11304f4135655d43c4c4\", \"ozone_gl_unittests\": \"e25c75aabe75db6937f64d561d39914278aec3f3\", \"ozone_unittests\": \"2da07b38163b41d04b39550187a5f82ee2c86016\", \"ozone_x11_unittests\": \"cddeae97db5661d36dc287865dc7bf94a4866336\", \"pdf_unittests\": \"b2ed01e37a359e7277c167ed00fe98ca68f84963\", \"perfetto_unittests\": \"cdaf2e9a8fbee7702da64daf7d81ae2f6657e910\", \"ppapi_unittests\": \"0939f7b4eebd17b1475b23fa694dd2ea1322e889\", \"printing_unittests\": \"c05487f179438c01ae04563fdd49e94368b403e1\", \"remoting_unittests\": \"26a80dc337201fb1d1cf2016aeb2c511a1e9e799\", \"sandbox_linux_unittests\": \"603a02028ed5daf3bda29b75faadeff202ae3191\", \"service_manager_unittests\": \"490e873aa397072a8be0f86659dae3a96aba5bca\", \"services_unittests\": \"aff15dc9dbb63cfd8366732981759ec0a380d536\", \"shell_dialogs_unittests\": \"2d95af576e4cf9c984c5ae39940f79e23173b087\", \"skia_unittests\": \"4853e6434847aa69fc39b02fe25486986dc62a0b\", \"snapshot_unittests\": \"1be6fcf90472820c539112d21b247dba8ddac70d\", \"sql_unittests\": \"bd081c5e5126e9b02e4afa710edc1a8218f9b3d9\", \"storage_unittests\": \"38b7e3ab8ea64387d558ed7884ae88d010afb1d0\", \"sync_integration_tests\": \"bbdca24f456fba87b0906b32fd00eb894edb3a59\", \"traffic_annotation_auditor_unittests\": \"7ba0e79894a57346890a645ec1f3693925889dff\", \"ui_base_unittests\": \"9bd8862a9e83aadf239aa0d9967bf50737a00ce3\", \"ui_chromeos_unittests\": \"4d12be10fc3c0dd244fa00ef9df0b5b22c896b61\", \"ui_touch_selection_unittests\": \"ad40784ce38374eb5ddcc1e4d909c35481276db3\", \"unit_tests\": \"0d554ce022ec41c9c612e0e29004588716ad211a\", \"url_unittests\": \"2d4190c716b499c7cbece95e57c3d35106cea254\", \"usage_time_limit_unittests\": \"b7b36925ddfa9e8b382cd037ab228159721f83e3\", \"views_unittests\": \"085534cbd75e262ad8d208f5ebcff5a57faefbb5\", \"viz_unittests\": \"f7fea4a14c677945df50f6a53105664c6723ecb9\", \"wm_unittests\": \"2471cf72d03156eb057cb7e8f3fd954ef5251e78\", \"wtf_unittests\": \"b57b376f54bed045410c401bd6277dd117e4e5db\"}}, \"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\": [\"4213-ad7b457\"], \"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": "1553222299220871",
"status": "COMPLETED",
"status_changed_ts": "1553222299629611",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11490",
"builder:linux-chromeos-dbg",
"buildset:commit/git/1aac03db98dbfe4359a16379cf7cfbc8d25ce69a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1aac03db98dbfe4359a16379cf7cfbc8d25ce69a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083752560777127888",
"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/8918322498613003744/+/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:43bc0f0778bab510",
"user_agent:luci-scheduler"
],
"updated_ts": "1553227854480314",
"url": "https://ci.chromium.org/b/8918322498613003744",
"utcnow_ts": "1553227855274237"
}
}
[04:10:55.284] Invocation finished in 1h39m6.87909686s with status FAILED