[23:52:08.072] New invocation is queued and will start shortly
[23:52:09.158] Starting the invocation (attempt 1)
[23:52:09.198] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/0d045343d70a8309ec92c2cc46c21ee90c68344f"
[23:52:09.198] Popped gitiles commit info from properties and tags
[23:52:09.198] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[23:52:09.198] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[23:52:09.199] Buildbucket request:
{
"requestId": "9005124439664247328",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "ea842a500412c84edbfbc6f72201e88642daed1b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@ea842a500412c84edbfbc6f72201e88642daed1b",
"title": "ea842a500412c84edbfbc6f72201e88642daed1b",
"url": "https://chromium.googlesource.com/infra/infra/+/ea842a500412c84edbfbc6f72201e88642daed1b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "961637798bb45921757385abe4d59dcbd1e5e9a5"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@961637798bb45921757385abe4d59dcbd1e5e9a5",
"title": "961637798bb45921757385abe4d59dcbd1e5e9a5",
"url": "https://chromium.googlesource.com/infra/infra/+/961637798bb45921757385abe4d59dcbd1e5e9a5"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "f101548be149eee4d6908cd2b513a3f28583a8cf"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@f101548be149eee4d6908cd2b513a3f28583a8cf",
"title": "f101548be149eee4d6908cd2b513a3f28583a8cf",
"url": "https://chromium.googlesource.com/infra/infra/+/f101548be149eee4d6908cd2b513a3f28583a8cf"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4363529d8c3a5d37f8f38bf4486a1c22785c5f7d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4363529d8c3a5d37f8f38bf4486a1c22785c5f7d",
"title": "4363529d8c3a5d37f8f38bf4486a1c22785c5f7d",
"url": "https://chromium.googlesource.com/infra/infra/+/4363529d8c3a5d37f8f38bf4486a1c22785c5f7d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "07d67c9518aa95d6b92564cdd304b562b0da71ac"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@07d67c9518aa95d6b92564cdd304b562b0da71ac",
"title": "07d67c9518aa95d6b92564cdd304b562b0da71ac",
"url": "https://chromium.googlesource.com/infra/infra/+/07d67c9518aa95d6b92564cdd304b562b0da71ac"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2d42333587f60f7918667be0df4e117dccfe4f15"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2d42333587f60f7918667be0df4e117dccfe4f15",
"title": "2d42333587f60f7918667be0df4e117dccfe4f15",
"url": "https://chromium.googlesource.com/infra/infra/+/2d42333587f60f7918667be0df4e117dccfe4f15"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4ec3d73f87d156b9580af0c1b7f1a308ddca063b"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4ec3d73f87d156b9580af0c1b7f1a308ddca063b",
"title": "4ec3d73f87d156b9580af0c1b7f1a308ddca063b",
"url": "https://chromium.googlesource.com/infra/infra/+/4ec3d73f87d156b9580af0c1b7f1a308ddca063b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "dc6a03585de94c9bb146154f16df9e1d6ff763fe"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@dc6a03585de94c9bb146154f16df9e1d6ff763fe",
"title": "dc6a03585de94c9bb146154f16df9e1d6ff763fe",
"url": "https://chromium.googlesource.com/infra/infra/+/dc6a03585de94c9bb146154f16df9e1d6ff763fe"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "60af7d06a75689d314d2424aa7a6b094d449c008"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@60af7d06a75689d314d2424aa7a6b094d449c008",
"title": "60af7d06a75689d314d2424aa7a6b094d449c008",
"url": "https://chromium.googlesource.com/infra/infra/+/60af7d06a75689d314d2424aa7a6b094d449c008"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c3942506cb1c72edb9c99f11024c760e6b1e9280"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c3942506cb1c72edb9c99f11024c760e6b1e9280",
"title": "c3942506cb1c72edb9c99f11024c760e6b1e9280",
"url": "https://chromium.googlesource.com/infra/infra/+/c3942506cb1c72edb9c99f11024c760e6b1e9280"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "8505207bfa45ccb40d5662e367db221c08a3089d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@8505207bfa45ccb40d5662e367db221c08a3089d",
"title": "8505207bfa45ccb40d5662e367db221c08a3089d",
"url": "https://chromium.googlesource.com/infra/infra/+/8505207bfa45ccb40d5662e367db221c08a3089d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "cfbe65c8d052f128936f887e6b8c840164c1bb0c"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@cfbe65c8d052f128936f887e6b8c840164c1bb0c",
"title": "cfbe65c8d052f128936f887e6b8c840164c1bb0c",
"url": "https://chromium.googlesource.com/infra/infra/+/cfbe65c8d052f128936f887e6b8c840164c1bb0c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4b75473f329e1965a27cbca55903c55c6eeaa9ad"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4b75473f329e1965a27cbca55903c55c6eeaa9ad",
"title": "4b75473f329e1965a27cbca55903c55c6eeaa9ad",
"url": "https://chromium.googlesource.com/infra/infra/+/4b75473f329e1965a27cbca55903c55c6eeaa9ad"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "0e6306c14c9410101218578b67b339f5821ce4cc"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0e6306c14c9410101218578b67b339f5821ce4cc",
"title": "0e6306c14c9410101218578b67b339f5821ce4cc",
"url": "https://chromium.googlesource.com/infra/infra/+/0e6306c14c9410101218578b67b339f5821ce4cc"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "af20376ca9262236a20cc1a7257e53074c708b52"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@af20376ca9262236a20cc1a7257e53074c708b52",
"title": "af20376ca9262236a20cc1a7257e53074c708b52",
"url": "https://chromium.googlesource.com/infra/infra/+/af20376ca9262236a20cc1a7257e53074c708b52"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "164e09028a8838f26731c64250cb1d5f3ff352e2"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@164e09028a8838f26731c64250cb1d5f3ff352e2",
"title": "164e09028a8838f26731c64250cb1d5f3ff352e2",
"url": "https://chromium.googlesource.com/infra/infra/+/164e09028a8838f26731c64250cb1d5f3ff352e2"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "c5ce6789cd366898c4e7e72f5fdfe3f2c6175553"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@c5ce6789cd366898c4e7e72f5fdfe3f2c6175553",
"title": "c5ce6789cd366898c4e7e72f5fdfe3f2c6175553",
"url": "https://chromium.googlesource.com/infra/infra/+/c5ce6789cd366898c4e7e72f5fdfe3f2c6175553"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "1f293bd2a8d51db1ac3bb4366297eb1822f9ef00"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@1f293bd2a8d51db1ac3bb4366297eb1822f9ef00",
"title": "1f293bd2a8d51db1ac3bb4366297eb1822f9ef00",
"url": "https://chromium.googlesource.com/infra/infra/+/1f293bd2a8d51db1ac3bb4366297eb1822f9ef00"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "4c4acc34162aa4058b103d818d23d6c9b977b724"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@4c4acc34162aa4058b103d818d23d6c9b977b724",
"title": "4c4acc34162aa4058b103d818d23d6c9b977b724",
"url": "https://chromium.googlesource.com/infra/infra/+/4c4acc34162aa4058b103d818d23d6c9b977b724"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "2ead66b6e4a777f8ea2d5d6cf74d59645f602e7f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@2ead66b6e4a777f8ea2d5d6cf74d59645f602e7f",
"title": "2ead66b6e4a777f8ea2d5d6cf74d59645f602e7f",
"url": "https://chromium.googlesource.com/infra/infra/+/2ead66b6e4a777f8ea2d5d6cf74d59645f602e7f"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "66ecd7dd2ec43082f92fe1093b294bb3174c7415"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@66ecd7dd2ec43082f92fe1093b294bb3174c7415",
"title": "66ecd7dd2ec43082f92fe1093b294bb3174c7415",
"url": "https://chromium.googlesource.com/infra/infra/+/66ecd7dd2ec43082f92fe1093b294bb3174c7415"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "067f61a31169135adc4010e1ebe47617551dcb1f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@067f61a31169135adc4010e1ebe47617551dcb1f",
"title": "067f61a31169135adc4010e1ebe47617551dcb1f",
"url": "https://chromium.googlesource.com/infra/infra/+/067f61a31169135adc4010e1ebe47617551dcb1f"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "5034c2b7a8622bd1fc55a0ebb709598e00459a6d"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@5034c2b7a8622bd1fc55a0ebb709598e00459a6d",
"title": "5034c2b7a8622bd1fc55a0ebb709598e00459a6d",
"url": "https://chromium.googlesource.com/infra/infra/+/5034c2b7a8622bd1fc55a0ebb709598e00459a6d"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://chromium.googlesource.com/infra/infra",
"revision": "0d045343d70a8309ec92c2cc46c21ee90c68344f"
},
"id": "https://chromium.googlesource.com/infra/infra/+/refs/heads/master@0d045343d70a8309ec92c2cc46c21ee90c68344f",
"title": "0d045343d70a8309ec92c2cc46c21ee90c68344f",
"url": "https://chromium.googlesource.com/infra/infra/+/0d045343d70a8309ec92c2cc46c21ee90c68344f"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "0d045343d70a8309ec92c2cc46c21ee90c68344f",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9005124439664247328"
},
{
"key": "scheduler_job_id",
"value": "infra/infra-continuous-win10-64"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[23:52:09.685] Scheduled build:
{
"id": "8839694377752442961",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 20332,
"createdBy": "project:infra",
"createTime": "2021-08-05T23:52:09.264855461Z",
"updateTime": "2021-08-05T23:52:09.504282975Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "0d045343d70a8309ec92c2cc46c21ee90c68344f",
"ref": "refs/heads/master"
}
}
}
[23:52:09.685] Task URL: https://cr-buildbucket-dev.appspot.com/build/8839694377752442961
[23:52:09.685] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:2:0) after 4m19s
[23:56:28.706] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:2:0)
[23:56:28.740] Build status: SCHEDULED
[23:56:28.740] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:3:0) after 2m52s
[23:59:20.813] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:3:0)
[23:59:20.840] Build status: SCHEDULED
[23:59:20.840] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:4:0) after 5m50s
[00:05:10.863] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:4:0)
[00:05:10.890] Build status: SCHEDULED
[00:05:10.890] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:5:0) after 1m14s
[00:06:24.918] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:5:0)
[00:06:24.945] Build status: SCHEDULED
[00:06:24.945] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:6:0) after 4m8s
[00:10:32.987] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:6:0)
[00:10:33.016] Build status: SCHEDULED
[00:10:33.016] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:7:0) after 4m3s
[00:14:36.038] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:7:0)
[00:14:36.075] Build status: SCHEDULED
[00:14:36.075] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:8:0) after 9m49s
[00:24:25.108] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:8:0)
[00:24:25.151] Build status: SCHEDULED
[00:24:25.151] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:9:0) after 2m22s
[00:26:47.194] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:9:0)
[00:26:47.227] Build status: SCHEDULED
[00:26:47.227] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:10:0) after 6m57s
[00:33:44.255] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:10:0)
[00:33:44.290] Build status: SCHEDULED
[00:33:44.290] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:11:0) after 4m2s
[00:37:46.311] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:11:0)
[00:37:46.345] Build status: SCHEDULED
[00:37:46.345] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:12:0) after 5m0s
[00:42:46.369] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:12:0)
[00:42:46.394] Build status: SCHEDULED
[00:42:46.394] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:13:0) after 9m1s
[00:51:47.418] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:13:0)
[00:51:47.450] Build status: SCHEDULED
[00:51:47.450] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:14:0) after 4m4s
[00:55:51.472] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:14:0)
[00:55:51.508] Build status: SCHEDULED
[00:55:51.508] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:15:0) after 3m50s
[00:59:41.530] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:15:0)
[00:59:41.563] Build status: SCHEDULED
[00:59:41.563] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:16:0) after 7m1s
[01:06:42.590] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:16:0)
[01:06:42.618] Build status: SCHEDULED
[01:06:42.618] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:17:0) after 7m55s
[01:14:37.677] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:17:0)
[01:14:37.718] Build status: SCHEDULED
[01:14:37.718] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:18:0) after 9m42s
[01:24:19.746] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:18:0)
[01:24:19.775] Build status: SCHEDULED
[01:24:19.775] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:19:0) after 1m11s
[01:25:30.798] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:19:0)
[01:25:30.829] Build status: SCHEDULED
[01:25:30.829] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:20:0) after 6m32s
[01:32:02.852] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:20:0)
[01:32:02.910] Build status: SCHEDULED
[01:32:02.910] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:21:0) after 7m47s
[01:39:49.933] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:21:0)
[01:39:49.959] Build status: SCHEDULED
[01:39:49.959] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:22:0) after 1m37s
[01:41:18.152] Received PubSub notification, asking Buildbucket for the build status
[01:41:18.181] Build status: STARTED
[01:41:26.982] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:22:0)
[01:41:27.015] Build status: STARTED
[01:41:27.015] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:24:0) after 1m40s
[01:43:07.048] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:24:0)
[01:43:07.084] Build status: STARTED
[01:43:07.084] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:25:0) after 5m37s
[01:48:44.112] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:25:0)
[01:48:44.151] Build status: STARTED
[01:48:44.151] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9005124439664247328:26:0) after 6m30s
[01:49:11.161] Received PubSub notification, asking Buildbucket for the build status
[01:49:11.205] Build:
{
"id": "8839694377752442961",
"builder": {
"project": "infra",
"bucket": "ci",
"builder": "infra-continuous-win10-64"
},
"number": 20332,
"createdBy": "project:infra",
"createTime": "2021-08-05T23:52:09.264855461Z",
"startTime": "2021-08-06T01:41:15.241698Z",
"endTime": "2021-08-06T01:49:10.648534473Z",
"updateTime": "2021-08-06T01:49:10.649074838Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "infra/infra",
"id": "0d045343d70a8309ec92c2cc46c21ee90c68344f",
"ref": "refs/heads/master"
}
}
}
[01:49:11.205] Invocation finished in 1h57m3.148275556s with status FAILED