[22:45:06.119] New invocation is queued and will start shortly
[22:45:06.119] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:45:07.274] Starting the invocation (attempt 1)
[22:45:07.302] Ignoring gitiles_ref tag without the buildset tag
[22:45:07.302] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:45:07.303] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:45:07.303] Buildbucket request:
{
"requestId": "8860807689510125056",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8860807689510125056",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "145.0.7579.0"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "0cce8980-d97e-4e1b-98cc-ada827d5b852",
"title": "publish_tarball/0",
"url": "https://cr-buildbucket.appspot.com/build/8695381134187656945"
}
]
},
"version": "145.0.7579.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8860807689510125056"
},
{
"key": "scheduler_job_id",
"value": "infra/Build From Tarball"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "publish_tarball"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:45:08.067] Scheduled build:
{
"id": "8695377627552730641",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2025-12-15T22:45:07.357820289Z",
"updateTime": "2025-12-15T22:45:07.357820289Z",
"status": "SCHEDULED",
"input": {
}
}
[22:45:08.067] Task URL: https://cr-buildbucket.appspot.com/build/8695377627552730641
[22:45:08.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:2:0) after 4m52s
[22:45:12.463] Received PubSub notification, asking Buildbucket for the build status
[22:45:12.507] Build status: SCHEDULED
[22:45:20.418] Received PubSub notification, asking Buildbucket for the build status
[22:45:20.498] Build status: STARTED
[22:50:00.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:2:0)
[22:50:00.141] Build status: STARTED
[22:50:00.141] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:5:0) after 5m45s
[22:55:45.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:5:0)
[22:55:45.324] Build status: STARTED
[22:55:45.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:6:0) after 8m48s
[23:04:33.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:6:0)
[23:04:33.362] Build status: STARTED
[23:04:33.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:7:0) after 3m25s
[23:07:58.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:7:0)
[23:07:58.446] Build status: STARTED
[23:07:58.446] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:8:0) after 6m55s
[23:14:53.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:8:0)
[23:14:53.531] Build status: STARTED
[23:14:53.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:9:0) after 9m32s
[23:24:25.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:9:0)
[23:24:25.585] Build status: STARTED
[23:24:25.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:10:0) after 5m22s
[23:29:47.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:10:0)
[23:29:47.773] Build status: STARTED
[23:29:47.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:11:0) after 4m10s
[23:33:57.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:11:0)
[23:33:57.835] Build status: STARTED
[23:33:57.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:12:0) after 7m39s
[23:41:36.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:12:0)
[23:41:36.870] Build status: STARTED
[23:41:36.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:13:0) after 8m49s
[23:50:25.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:13:0)
[23:50:25.917] Build status: STARTED
[23:50:25.917] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:14:0) after 1m34s
[23:51:59.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:14:0)
[23:51:59.948] Build status: STARTED
[23:51:59.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:15:0) after 7m20s
[23:59:19.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:15:0)
[23:59:20.021] Build status: STARTED
[23:59:20.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:16:0) after 8m36s
[00:07:56.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:16:0)
[00:07:56.135] Build status: STARTED
[00:07:56.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:17:0) after 4m5s
[00:12:01.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:17:0)
[00:12:01.219] Build status: STARTED
[00:12:01.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:18:0) after 3m44s
[00:15:45.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:18:0)
[00:15:45.313] Build status: STARTED
[00:15:45.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:19:0) after 9m30s
[00:25:15.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:19:0)
[00:25:15.366] Build status: STARTED
[00:25:15.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:20:0) after 7m51s
[00:33:06.379] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:20:0)
[00:33:06.431] Build status: STARTED
[00:33:06.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:21:0) after 5m48s
[00:38:54.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:21:0)
[00:38:54.517] Build status: STARTED
[00:38:54.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:22:0) after 9m22s
[00:48:16.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:22:0)
[00:48:16.653] Build status: STARTED
[00:48:16.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:23:0) after 9m10s
[00:57:26.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:23:0)
[00:57:26.680] Build status: STARTED
[00:57:26.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:24:0) after 7m4s
[01:04:30.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:24:0)
[01:04:30.875] Build status: STARTED
[01:04:30.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:25:0) after 3m26s
[01:07:56.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:25:0)
[01:07:56.919] Build status: STARTED
[01:07:56.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:26:0) after 7m5s
[01:15:01.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:26:0)
[01:15:02.006] Build status: STARTED
[01:15:02.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:27:0) after 5m57s
[01:20:59.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:27:0)
[01:20:59.112] Build status: STARTED
[01:20:59.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:28:0) after 1m12s
[01:22:11.160] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:28:0)
[01:22:11.185] Build status: STARTED
[01:22:11.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:29:0) after 6m26s
[01:28:37.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:29:0)
[01:28:37.193] Build status: STARTED
[01:28:37.193] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:30:0) after 1m40s
[01:30:17.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:30:0)
[01:30:17.220] Build status: STARTED
[01:30:17.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:31:0) after 7m29s
[01:37:46.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:31:0)
[01:37:46.300] Build status: STARTED
[01:37:46.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:32:0) after 4m26s
[01:42:12.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:32:0)
[01:42:12.294] Build status: STARTED
[01:42:12.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:33:0) after 5m35s
[01:47:47.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:33:0)
[01:47:47.376] Build status: STARTED
[01:47:47.376] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:34:0) after 7m52s
[01:55:39.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:34:0)
[01:55:39.443] Build status: STARTED
[01:55:39.444] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:35:0) after 5m19s
[02:00:58.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:35:0)
[02:00:58.491] Build status: STARTED
[02:00:58.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:36:0) after 5m42s
[02:06:40.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:36:0)
[02:06:40.558] Build status: STARTED
[02:06:40.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:37:0) after 2m29s
[02:09:09.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:37:0)
[02:09:09.688] Build status: STARTED
[02:09:09.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:38:0) after 1m37s
[02:10:46.707] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:38:0)
[02:10:46.725] Build status: STARTED
[02:10:46.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:39:0) after 4m6s
[02:14:52.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:39:0)
[02:14:52.823] Build status: STARTED
[02:14:52.823] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:40:0) after 4m14s
[02:19:06.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:40:0)
[02:19:07.083] Build status: STARTED
[02:19:07.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:41:0) after 7m13s
[02:26:20.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:41:0)
[02:26:20.115] Build status: STARTED
[02:26:20.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:42:0) after 7m37s
[02:33:57.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:42:0)
[02:33:57.192] Build status: STARTED
[02:33:57.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:43:0) after 4m8s
[02:38:05.209] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:43:0)
[02:38:05.244] Build status: STARTED
[02:38:05.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:44:0) after 4m2s
[02:42:07.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:44:0)
[02:42:07.337] Build status: STARTED
[02:42:07.337] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:45:0) after 8m14s
[02:50:21.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:45:0)
[02:50:21.380] Build status: STARTED
[02:50:21.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:46:0) after 1m16s
[02:51:37.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:46:0)
[02:51:37.712] Build status: STARTED
[02:51:37.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:47:0) after 5m35s
[02:57:12.728] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:47:0)
[02:57:12.756] Build status: STARTED
[02:57:12.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:48:0) after 2m25s
[02:59:37.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:48:0)
[02:59:37.846] Build status: STARTED
[02:59:37.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:49:0) after 9m40s
[03:09:17.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:49:0)
[03:09:17.927] Build status: STARTED
[03:09:17.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:50:0) after 2m9s
[03:11:26.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:50:0)
[03:11:26.964] Build status: STARTED
[03:11:26.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:51:0) after 6m47s
[03:18:13.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:51:0)
[03:18:13.944] Build status: STARTED
[03:18:13.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:52:0) after 5m49s
[03:24:03.037] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:52:0)
[03:24:03.097] Build status: STARTED
[03:24:03.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:53:0) after 2m0s
[03:26:03.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:53:0)
[03:26:03.547] Build status: STARTED
[03:26:03.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:54:0) after 1m35s
[03:27:38.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:54:0)
[03:27:38.588] Build status: STARTED
[03:27:38.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:55:0) after 3m45s
[03:31:23.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:55:0)
[03:31:23.652] Build status: STARTED
[03:31:23.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:56:0) after 1m48s
[03:33:11.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:56:0)
[03:33:11.784] Build status: STARTED
[03:33:11.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:57:0) after 2m2s
[03:35:13.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:57:0)
[03:35:13.865] Build status: STARTED
[03:35:13.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:58:0) after 5m36s
[03:40:49.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:58:0)
[03:40:49.959] Build status: STARTED
[03:40:49.959] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:59:0) after 2m24s
[03:43:14.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:59:0)
[03:43:14.030] Build status: STARTED
[03:43:14.030] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:60:0) after 7m8s
[03:50:22.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:60:0)
[03:50:22.119] Build status: STARTED
[03:50:22.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:61:0) after 2m12s
[03:52:34.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:61:0)
[03:52:34.126] Build status: STARTED
[03:52:34.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:62:0) after 5m18s
[03:57:52.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:62:0)
[03:57:52.161] Build status: STARTED
[03:57:52.162] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:63:0) after 6m4s
[04:03:56.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:63:0)
[04:03:56.198] Build status: STARTED
[04:03:56.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:64:0) after 6m18s
[04:10:14.217] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:64:0)
[04:10:14.230] Build status: STARTED
[04:10:14.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:65:0) after 9m9s
[04:19:23.281] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:65:0)
[04:19:23.299] Build status: STARTED
[04:19:23.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:66:0) after 8m57s
[04:28:20.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:66:0)
[04:28:20.425] Build status: STARTED
[04:28:20.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:67:0) after 9m41s
[04:38:01.468] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:67:0)
[04:38:01.503] Build status: STARTED
[04:38:01.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:68:0) after 2m32s
[04:40:33.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:68:0)
[04:40:33.589] Build status: STARTED
[04:40:33.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:69:0) after 7m48s
[04:48:21.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:69:0)
[04:48:21.596] Build status: STARTED
[04:48:21.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:70:0) after 5m8s
[04:53:29.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:70:0)
[04:53:29.586] Build status: STARTED
[04:53:29.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:71:0) after 3m33s
[04:57:02.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:71:0)
[04:57:02.649] Build status: STARTED
[04:57:02.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8860807689510125056:72:0) after 8m49s
[04:57:33.289] Received PubSub notification, asking Buildbucket for the build status
[04:57:33.339] Build:
{
"id": "8695377627552730641",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2025-12-15T22:45:07.357820289Z",
"startTime": "2025-12-15T22:45:15.360961340Z",
"endTime": "2025-12-16T04:57:31.170472978Z",
"updateTime": "2025-12-16T04:57:31.170472978Z",
"status": "FAILURE",
"input": {
}
}
[04:57:33.339] Invocation finished in 6h12m27.231864988s with status FAILED