[23:21:57.129] New invocation is queued and will start shortly
[23:21:58.300] Starting the invocation (attempt 1)
[23:21:58.333] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:21:58.333] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:21:58.333] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023155134890446608",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c1f8a30605e2e0141f921e64a95fd3eb81ba9251\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023155134890446608",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/c1f8a30605e2e0141f921e64a95fd3eb81ba9251",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c1f8a30605e2e0141f921e64a95fd3eb81ba9251",
"gitiles_ref:refs/heads/master"
]
}
[23:21:58.976] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611012118509684",
"id": "8857725072780068624",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c1f8a30605e2e0141f921e64a95fd3eb81ba9251\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611012118843562",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44712",
"builder:linux-chromeos-rel",
"buildset:commit/git/c1f8a30605e2e0141f921e64a95fd3eb81ba9251",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c1f8a30605e2e0141f921e64a95fd3eb81ba9251",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023155134890446608",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857725072780068624/+/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": "1611012118843689",
"url": "https://ci.chromium.org/b/8857725072780068624",
"utcnow_ts": "1611012118964571"
}
}
[23:21:58.976] Task URL: https://ci.chromium.org/b/8857725072780068624
[23:21:58.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:2:0) after 1m0s
[23:22:48.747] Received PubSub notification, asking Buildbucket for the build status
[23:22:48.820] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:58.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:2:0)
[23:22:58.996] Timer tick, asking Buildbucket for the build status
[23:22:59.090] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:59.091] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:4:0) after 1m0s
[23:23:59.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:4:0)
[23:23:59.148] Timer tick, asking Buildbucket for the build status
[23:23:59.268] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:59.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:5:0) after 1m0s
[23:24:59.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:5:0)
[23:24:59.277] Timer tick, asking Buildbucket for the build status
[23:24:59.379] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:59.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:6:0) after 1m0s
[23:25:59.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:6:0)
[23:25:59.403] Timer tick, asking Buildbucket for the build status
[23:25:59.501] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:59.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:7:0) after 1m0s
[23:26:59.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:7:0)
[23:26:59.546] Timer tick, asking Buildbucket for the build status
[23:26:59.645] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:59.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:8:0) after 1m0s
[23:27:59.634] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:8:0)
[23:27:59.634] Timer tick, asking Buildbucket for the build status
[23:27:59.775] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:59.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:9:0) after 1m0s
[23:28:59.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:9:0)
[23:28:59.873] Timer tick, asking Buildbucket for the build status
[23:28:59.936] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:59.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:10:0) after 1m0s
[23:29:59.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:10:0)
[23:29:59.985] Timer tick, asking Buildbucket for the build status
[23:30:00.175] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:00.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:11:0) after 1m0s
[23:31:00.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:11:0)
[23:31:00.890] Timer tick, asking Buildbucket for the build status
[23:31:01.016] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:01.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:12:0) after 1m0s
[23:32:01.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:12:0)
[23:32:01.433] Timer tick, asking Buildbucket for the build status
[23:32:01.616] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:01.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:13:0) after 1m0s
[23:33:01.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:13:0)
[23:33:01.669] Timer tick, asking Buildbucket for the build status
[23:33:01.926] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:01.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:14:0) after 1m0s
[23:34:02.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:14:0)
[23:34:02.001] Timer tick, asking Buildbucket for the build status
[23:34:02.071] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:02.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:15:0) after 1m0s
[23:35:02.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:15:0)
[23:35:02.108] Timer tick, asking Buildbucket for the build status
[23:35:02.245] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:02.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:16:0) after 1m0s
[23:36:02.282] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:16:0)
[23:36:02.282] Timer tick, asking Buildbucket for the build status
[23:36:02.427] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:02.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:17:0) after 1m0s
[23:37:02.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:17:0)
[23:37:02.651] Timer tick, asking Buildbucket for the build status
[23:37:02.741] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:02.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:18:0) after 1m0s
[23:38:02.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:18:0)
[23:38:02.913] Timer tick, asking Buildbucket for the build status
[23:38:03.182] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:03.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:19:0) after 1m0s
[23:39:03.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:19:0)
[23:39:03.289] Timer tick, asking Buildbucket for the build status
[23:39:03.591] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:03.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:20:0) after 1m0s
[23:40:03.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:20:0)
[23:40:03.859] Timer tick, asking Buildbucket for the build status
[23:40:03.972] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:03.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:21:0) after 1m0s
[23:41:04.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:21:0)
[23:41:04.076] Timer tick, asking Buildbucket for the build status
[23:41:04.239] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:04.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:22:0) after 1m0s
[23:42:04.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:22:0)
[23:42:04.320] Timer tick, asking Buildbucket for the build status
[23:42:04.631] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:04.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:23:0) after 1m0s
[23:43:04.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:23:0)
[23:43:04.655] Timer tick, asking Buildbucket for the build status
[23:43:04.713] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:04.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:24:0) after 1m0s
[23:44:04.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:24:0)
[23:44:04.825] Timer tick, asking Buildbucket for the build status
[23:44:04.883] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:04.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:25:0) after 1m0s
[23:45:04.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:25:0)
[23:45:04.950] Timer tick, asking Buildbucket for the build status
[23:45:06.093] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:06.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:26:0) after 1m0s
[23:46:06.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:26:0)
[23:46:06.123] Timer tick, asking Buildbucket for the build status
[23:46:06.223] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:06.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:27:0) after 1m0s
[23:47:06.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:27:0)
[23:47:06.303] Timer tick, asking Buildbucket for the build status
[23:47:06.403] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:06.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:28:0) after 1m0s
[23:48:06.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:28:0)
[23:48:06.386] Timer tick, asking Buildbucket for the build status
[23:48:06.443] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:06.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:29:0) after 1m0s
[23:49:06.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:29:0)
[23:49:06.523] Timer tick, asking Buildbucket for the build status
[23:49:06.714] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:06.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:30:0) after 1m0s
[23:50:06.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:30:0)
[23:50:06.827] Timer tick, asking Buildbucket for the build status
[23:50:07.042] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:07.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:31:0) after 1m0s
[23:51:07.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:31:0)
[23:51:07.079] Timer tick, asking Buildbucket for the build status
[23:51:07.322] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:07.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:32:0) after 1m0s
[23:52:07.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:32:0)
[23:52:07.381] Timer tick, asking Buildbucket for the build status
[23:52:07.595] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:07.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:33:0) after 1m0s
[23:53:07.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:33:0)
[23:53:07.616] Timer tick, asking Buildbucket for the build status
[23:53:08.125] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:08.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:34:0) after 1m0s
[23:54:08.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:34:0)
[23:54:08.162] Timer tick, asking Buildbucket for the build status
[23:54:08.254] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:08.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:35:0) after 1m0s
[23:55:08.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:35:0)
[23:55:08.276] Timer tick, asking Buildbucket for the build status
[23:55:08.334] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:08.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:36:0) after 1m0s
[23:56:08.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:36:0)
[23:56:08.353] Timer tick, asking Buildbucket for the build status
[23:56:08.420] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:08.420] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:37:0) after 1m0s
[23:57:08.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:37:0)
[23:57:08.477] Timer tick, asking Buildbucket for the build status
[23:57:08.679] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:08.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:38:0) after 1m0s
[23:58:08.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:38:0)
[23:58:08.743] Timer tick, asking Buildbucket for the build status
[23:58:08.835] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:08.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:39:0) after 1m0s
[23:59:08.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:39:0)
[23:59:08.862] Timer tick, asking Buildbucket for the build status
[23:59:08.939] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:08.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:40:0) after 1m0s
[00:00:08.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:40:0)
[00:00:08.979] Timer tick, asking Buildbucket for the build status
[00:00:09.053] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:09.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:41:0) after 1m0s
[00:01:09.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:41:0)
[00:01:09.706] Timer tick, asking Buildbucket for the build status
[00:01:09.776] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:09.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:42:0) after 1m0s
[00:02:09.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:42:0)
[00:02:09.748] Timer tick, asking Buildbucket for the build status
[00:02:09.866] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:09.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:43:0) after 1m0s
[00:03:09.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:43:0)
[00:03:09.972] Timer tick, asking Buildbucket for the build status
[00:03:10.422] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:10.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:44:0) after 1m0s
[00:04:10.492] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:44:0)
[00:04:10.492] Timer tick, asking Buildbucket for the build status
[00:04:10.599] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:10.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:45:0) after 1m0s
[00:05:10.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:45:0)
[00:05:10.661] Timer tick, asking Buildbucket for the build status
[00:05:11.004] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:11.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:46:0) after 1m0s
[00:06:11.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:46:0)
[00:06:11.064] Timer tick, asking Buildbucket for the build status
[00:06:11.184] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:11.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:47:0) after 1m0s
[00:07:11.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:47:0)
[00:07:11.228] Timer tick, asking Buildbucket for the build status
[00:07:11.785] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:11.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:48:0) after 1m0s
[00:08:11.841] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:48:0)
[00:08:11.841] Timer tick, asking Buildbucket for the build status
[00:08:12.380] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:12.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:49:0) after 1m0s
[00:09:12.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:49:0)
[00:09:12.456] Timer tick, asking Buildbucket for the build status
[00:09:12.601] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:12.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:50:0) after 1m0s
[00:10:12.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:50:0)
[00:10:12.736] Timer tick, asking Buildbucket for the build status
[00:10:12.813] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:12.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:51:0) after 1m0s
[00:11:12.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:51:0)
[00:11:12.805] Timer tick, asking Buildbucket for the build status
[00:11:12.922] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:12.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:52:0) after 1m0s
[00:12:12.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:52:0)
[00:12:12.958] Timer tick, asking Buildbucket for the build status
[00:12:13.515] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:13.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:53:0) after 1m0s
[00:13:13.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:53:0)
[00:13:13.517] Timer tick, asking Buildbucket for the build status
[00:13:13.616] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:13.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:54:0) after 1m0s
[00:14:13.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:54:0)
[00:14:13.672] Timer tick, asking Buildbucket for the build status
[00:14:13.823] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:13.823] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:55:0) after 1m0s
[00:15:13.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:55:0)
[00:15:13.948] Timer tick, asking Buildbucket for the build status
[00:15:14.055] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:14.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:56:0) after 1m0s
[00:16:14.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:56:0)
[00:16:14.108] Timer tick, asking Buildbucket for the build status
[00:16:14.216] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:14.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:57:0) after 1m0s
[00:17:14.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:57:0)
[00:17:14.314] Timer tick, asking Buildbucket for the build status
[00:17:14.687] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:14.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:58:0) after 1m0s
[00:18:14.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:58:0)
[00:18:14.826] Timer tick, asking Buildbucket for the build status
[00:18:15.143] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:15.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:59:0) after 1m0s
[00:19:15.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:59:0)
[00:19:15.257] Timer tick, asking Buildbucket for the build status
[00:19:15.364] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:15.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:60:0) after 1m0s
[00:20:15.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:60:0)
[00:20:15.420] Timer tick, asking Buildbucket for the build status
[00:20:15.637] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:15.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:61:0) after 1m0s
[00:21:15.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:61:0)
[00:21:15.718] Timer tick, asking Buildbucket for the build status
[00:21:16.193] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:16.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:62:0) after 1m0s
[00:22:16.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:62:0)
[00:22:16.247] Timer tick, asking Buildbucket for the build status
[00:22:16.956] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:16.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:63:0) after 1m0s
[00:23:17.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:63:0)
[00:23:17.039] Timer tick, asking Buildbucket for the build status
[00:23:17.383] Build 8857725072780068624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:17.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023155134890446608:64:0) after 1m0s
[00:23:24.301] Received PubSub notification, asking Buildbucket for the build status
[00:23:24.373] Build 8857725072780068624: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:23:24.373] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611015802637861",
"created_by": "project:chromium",
"created_ts": "1611012118509684",
"failure_reason": "BUILD_FAILURE",
"id": "8857725072780068624",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c1f8a30605e2e0141f921e64a95fd3eb81ba9251\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- FileSystemProviderApiTest.Unmount\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"c1f8a30605e2e0141f921e64a95fd3eb81ba9251\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-96-zdq0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44712, \"got_angle_revision\": \"679d118787e7dab0cfddc0d7339700539155990a\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"c1f8a30605e2e0141f921e64a95fd3eb81ba9251\", \"got_revision_cp\": \"refs/heads/master@{#844626}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"e02dfe6cf35d7b3724c84b162c628b2c960bd9c7\", \"got_v8_revision_cp\": \"refs/heads/9.0.5@{#1}\", \"got_webrtc_revision\": \"5cf0ef0022c7e044fdfed7c511fe9806a2dd7a68\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33029}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c1f8a30605e2e0141f921e64a95fd3eb81ba9251\", \"swarm_hashes_refs/heads/master(at){#844626}_without_patch\": {\"absl_hardening_tests\": \"51a9da70fe4012182270fa89f052e1535d84932b\", \"accessibility_unittests\": \"0d1567f8a2e1ba2edf139d48f8bee3f9fc62be32\", \"angle_unittests\": \"0b732e60bea425e24aa8a2495d61c1bd6a172c76\", \"app_list_unittests\": \"9a6dd4d2c1fc829fb4683b60c66de8806514f7e9\", \"app_shell_unittests\": \"9846ff021b51a1881d8c4ef5da4b13de73cebc67\", \"ash_unittests\": \"a938134a6ad333b180c76801369e529d7b43daa5\", \"aura_unittests\": \"c635d0c26e38ea57af3db8b7f781bb784c72398c\", \"base_unittests\": \"8287d2cc2484ff57824983d4530c16ce51516b69\", \"base_util_unittests\": \"7ec597bc0e78cd19f8b4cc24385be5bdf44f92e1\", \"blink_common_unittests\": \"903f1cf116bba4f064ad7c086ede3dcb3675c752\", \"blink_fuzzer_unittests\": \"1d10780d8d24439fbac612d6b947f3267ad937cd\", \"blink_heap_unittests\": \"dfeddbed251710e291746d7fea359226c13b5a2a\", \"blink_platform_unittests\": \"0b3d95c2443b194da64679686d05b71906164fba\", \"blink_unittests\": \"7c4622e4f29caccc0fe2daea3d6f1dbb9214254f\", \"boringssl_crypto_tests\": \"8a12e71653353526bfdb290413d25ff24339ab30\", \"boringssl_ssl_tests\": \"5075805217708b97f7bceb5d55738ef062d573cd\", \"browser_tests\": \"4bd54c9ee192b5cd053ee7a5a06db82fd0276f91\", \"capture_unittests\": \"15344e9f90fc1f44f52ea108e7e2f2e1f73ebc24\", \"cast_unittests\": \"50cbc7d9d4291a3355a16da4198230ebf5e00818\", \"cc_unittests\": \"14263c0aaf486def0730abf3255e359f68ab9183\", \"chrome_app_unittests\": \"5381b4ba5e1b7f128d95e9a03d044a477ba0054b\", \"chromedriver_unittests\": \"2501340ecd0576b2a89d9d91055de25a0cc2450b\", \"chromeos_components_unittests\": \"5e4a54a79a14da50bfa332ba80b242cce0e6d01c\", \"chromeos_unittests\": \"b12dad849ed5222d3396e3aaac92baa8c7f4ee90\", \"components_browsertests\": \"836ceff57c3bf6a9e943be3566c8e6f789ae3420\", \"components_unittests\": \"78f3eb56823e1f09905d9b70ef66f01745a2f54a\", \"compositor_unittests\": \"468e9a915246a82e77095dd785d3a33a3c9076cf\", \"content_browsertests\": \"2edea3baf3f5ad5e157bc2f19906079584cd4354\", \"content_unittests\": \"f2b7266fef7bccf15d8b739050e230d636b1d02a\", \"crashpad_tests\": \"50c1807b31f43a63334ff760cf130ddac4892322\", \"crypto_unittests\": \"791d786c257bf6bbe0307214054d11874cac86cf\", \"dbus_unittests\": \"f6247f5b82f2b424280445131ad72aee9dafdb77\", \"device_unittests\": \"f9520cda27fe355d963be1140d0fa7708b4787c4\", \"display_unittests\": \"6635d911fa3bf0b4cbe1e60a659674905c5acb44\", \"events_unittests\": \"e6a6a5c8c0d71338c783934815173258981484da\", \"exo_unittests\": \"a34248433d102ea221bee81fd6d7198858101e13\", \"extensions_browsertests\": \"2979806b1f1c3ab49ee7a6b1e13d6a373c1232f5\", \"extensions_unittests\": \"ecc28a2d41c913d5efafd576105b177cc5a875e9\", \"filesystem_service_unittests\": \"f5d91543c4de065bd93485cbe783b981e97c46fc\", \"gcm_unit_tests\": \"7a8e8b853771cf7cfac2630613fb16941e13efd1\", \"gfx_unittests\": \"35c6607c2ac2d96abef041a035b0a433d9cd9ed7\", \"gin_unittests\": \"4e651b2464b202c1be8f8b34f8d7cd5bc0a9798c\", \"gl_unittests_ozone\": \"8e4de8a77dcce01ef420158ddca5cb1b7053b0ba\", \"google_apis_unittests\": \"84291be4058e56600e2524f1514ba8f3d4337cf1\", \"gpu_unittests\": \"fd676132d5887ffc3b113e1fcff3af26ce710d1e\", \"gwp_asan_unittests\": \"460723265acca271e9acd43ee4303e347714b584\", \"interactive_ui_tests\": \"203b41bcf5d7f13a057c3bfcc74ba08ad31ce9db\", \"ipc_tests\": \"6fdeddad28857c8fdfc64c065c8a01308d988dee\", \"jingle_unittests\": \"a62f10af52891e0d3ac3cd659411da6ad40b4dbd\", \"keyboard_unittests\": \"b2026796d439d5e049b2fa0f4a78cd38eae499d9\", \"latency_unittests\": \"c97b7efe96c6f822ddd33e86c3b2f37b7bae97cc\", \"libjingle_xmpp_unittests\": \"2e7477cc063c0f9d24b815375dbbcefed0046585\", \"liburlpattern_unittests\": \"8083b9c5ec47e23ac76beabd27bd1cd0e97ba4c7\", \"media_blink_unittests\": \"d231d5889629761c21229f2cc8986ef284d24395\", \"media_unittests\": \"e1bc9cec06246e65fd8e0c8343b9ba6697eb852c\", \"message_center_unittests\": \"1af7b9f1bef4b7192bcb8333b78c44ccf979cb36\", \"midi_unittests\": \"b2aaf46ba956cb98146d8989a85f7351937a4c1d\", \"mojo_core_unittests\": \"1ac58800a3e77cc044feff845fa4b3f0302cf424\", \"mojo_unittests\": \"07d9e262d9d9d306ae5046f1af4fe0031f58edb7\", \"nacl_helper_nonsfi_unittests\": \"92cb83718c489f4e6ae2461185ece6c293a01638\", \"nacl_loader_unittests\": \"fdbaa24a2a3d5536f6e379a5e0553dfff44a4e2c\", \"native_theme_unittests\": \"de327ceec1650d6e81250faa2b6fa768bc3b746f\", \"net_unittests\": \"295339ee52ee54787e653b7d7ba91a7c52e250ed\", \"ozone_gl_unittests\": \"5ea950c48a8c8c662510db599cd873df2a33bd45\", \"ozone_unittests\": \"9f33fcd1929bb4f4958cbf4097d1f9d3da347d8a\", \"ozone_x11_unittests\": \"fd75099f1373dbe2771fe4fd067f76f5cb14e399\", \"pdf_unittests\": \"714cb6094e4156b6e1fe7507cf4a1fc1753f9cd0\", \"perfetto_unittests\": \"88668f556b0c3bdff8c350268dc9e034d8ae543d\", \"ppapi_unittests\": \"0996d45770ab4f3ed7f360fa0ac31619af0a3136\", \"printing_unittests\": \"933f65a13f72219e23d6836b845107178c8ca4b6\", \"remoting_unittests\": \"3cb27b027c2b2b2353324d58e0cd7448b845d715\", \"sandbox_linux_unittests\": \"12efb0b4d39b1166370ac6af51e525274334370d\", \"service_manager_unittests\": \"b6f1bb33baed70481b0bfc528b54ab199bf40b97\", \"services_unittests\": \"7e72f9dfd5e28aff94767339ff4aacaf43e89c57\", \"shell_dialogs_unittests\": \"1acb8472653c92dae952e7baa335eb8940cef325\", \"shell_encryption_unittests\": \"e968576fc71604637ce94a5bca3cde5f4ca4e9cf\", \"skia_unittests\": \"cea3b87c05594f20e952817629af3e0c90da1d60\", \"snapshot_unittests\": \"5fd3b9995c0f551fbd2ecb58599c45959da75615\", \"sql_unittests\": \"65120ac2d14c31ab154190e6fd95143e2834ffa1\", \"storage_unittests\": \"23f8aa7c18fe0e15648c4f9f47c08b4b67185784\", \"sync_integration_tests\": \"3b1b635f6a69e9db24070b9dd07d985fba489f6f\", \"traffic_annotation_auditor_unittests\": \"2f00cc71acc309190108d2b234aaaed2876a84ef\", \"ui_base_unittests\": \"7333359d3dc837d32257f6860dd591e0ae32249e\", \"ui_chromeos_unittests\": \"7a60bd6811f690da5ee4f98ac649b2027afe49b2\", \"ui_touch_selection_unittests\": \"a20275fc5e907777d80052d91022273e4cf0d98a\", \"unit_tests\": \"8deab83879a41451906e8209c968e0995af72944\", \"url_unittests\": \"741f2508d0e00930803332b484066d1e014b4fc4\", \"usage_time_limit_unittests\": \"e995ef73aa118d029aad47d74d31425509048c39\", \"views_unittests\": \"4eca22916d87eab4aa168871782208596a9c43d6\", \"viz_unittests\": \"488ee2c9b351d4475c4f42b912bbd4fa90431ba2\", \"wayland_client_perftests\": \"d05301bcf12ec702f7b5e39e543572095b048ad8\", \"wm_unittests\": \"6d87126926d7b690119b5d478268a052d866c90b\", \"wtf_unittests\": \"a335b89b19d31c945660e6c5e469398c40e946d8\", \"zlib_unittests\": \"d0c100301ef70f8170f88141794374d92a1012f9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_609b54f795c46f6da98bce3e3c3edeb2c2e2d72c7759cc5040a86edccd734c7c_v2\", \"builder_83d41522ef30b186d170824fe4af0f819b6d8e33bbc59ab4098fd9fd1af4e43b_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9efb94071a208cb32f222f9589b2bf8c6e9d4ebc7884b1b49bd19cd2b21dc48e_v2\", \"builder_f950d00180feebb913a151f4148df44a669def32bdb4d2b9a63ead64ebacc911_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-96-zdq0\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- FileSystemProviderApiTest.Unmount\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611012167335498",
"status": "COMPLETED",
"status_changed_ts": "1611012167936353",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44712",
"builder:linux-chromeos-rel",
"buildset:commit/git/c1f8a30605e2e0141f921e64a95fd3eb81ba9251",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c1f8a30605e2e0141f921e64a95fd3eb81ba9251",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023155134890446608",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857725072780068624/+/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:51309fa20fa7f210",
"user_agent:luci-scheduler"
],
"updated_ts": "1611015803801730",
"url": "https://ci.chromium.org/b/8857725072780068624",
"utcnow_ts": "1611015804361970"
}
}
[00:23:24.373] Invocation finished in 1h1m27.260221525s with status FAILED