[23:47:17.309] New invocation is queued and will start shortly
[23:47:19.465] Starting the invocation (attempt 1)
[23:47:19.495] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/39e7108ceed28ff140e1c8371791177eff9ba08f"
[23:47:19.495] Popped gitiles commit info from properties and tags
[23:47:19.496] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:47:19.496] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:47:19.496] Buildbucket request:
{
  "requestId": "8864608849653579280",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8864608849653579280",
            "job": "emscripten-releases/linux-test-suites",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/emscripten-releases",
                              "revision": "39e7108ceed28ff140e1c8371791177eff9ba08f"
                            },
                        "id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@39e7108ceed28ff140e1c8371791177eff9ba08f",
                        "title": "39e7108ceed28ff140e1c8371791177eff9ba08f",
                        "url": "https://chromium.googlesource.com/emscripten-releases/+/39e7108ceed28ff140e1c8371791177eff9ba08f"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "emscripten-releases",
    "id": "39e7108ceed28ff140e1c8371791177eff9ba08f",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8864608849653579280"
    },
    {
      "key": "scheduler_job_id",
      "value": "emscripten-releases/linux-test-suites"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[23:47:19.829] Scheduled build:
{
  "id": "8699178786643449985",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "createdBy": "project:emscripten-releases",
  "createTime": "2025-11-03T23:47:19.560953128Z",
  "updateTime": "2025-11-03T23:47:19.560953128Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "emscripten-releases",
      "id": "39e7108ceed28ff140e1c8371791177eff9ba08f",
      "ref": "refs/heads/main"
    }
  }
}
[23:47:19.829] Task URL: https://cr-buildbucket.appspot.com/build/8699178786643449985
[23:47:19.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:2:0) after 1m21s
[23:47:20.873] Received PubSub notification, asking Buildbucket for the build status
[23:47:20.920] Build status: SCHEDULED
[23:47:25.139] Received PubSub notification, asking Buildbucket for the build status
[23:47:25.154] Build status: STARTED
[23:48:40.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:2:0)
[23:48:41.041] Build status: STARTED
[23:48:41.041] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:5:0) after 2m33s
[23:51:14.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:5:0)
[23:51:14.221] Build status: STARTED
[23:51:14.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:6:0) after 8m43s
[23:59:57.332] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:6:0)
[23:59:57.357] Build status: STARTED
[23:59:57.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:7:0) after 9m5s
[00:09:02.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:7:0)
[00:09:02.492] Build status: STARTED
[00:09:02.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:8:0) after 8m34s
[00:17:36.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:8:0)
[00:17:36.549] Build status: STARTED
[00:17:36.549] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:9:0) after 9m22s
[00:26:58.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:9:0)
[00:26:58.722] Build status: STARTED
[00:26:58.722] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:10:0) after 1m0s
[00:27:58.903] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:10:0)
[00:27:58.921] Build status: STARTED
[00:27:58.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:11:0) after 2m21s
[00:30:19.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:11:0)
[00:30:20.026] Build status: STARTED
[00:30:20.026] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:12:0) after 2m13s
[00:32:33.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:12:0)
[00:32:33.064] Build status: STARTED
[00:32:33.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:13:0) after 1m44s
[00:34:17.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:13:0)
[00:34:17.109] Build status: STARTED
[00:34:17.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:14:0) after 1m44s
[00:36:01.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:14:0)
[00:36:01.210] Build status: STARTED
[00:36:01.210] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:15:0) after 9m38s
[00:45:39.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:15:0)
[00:45:39.199] Build status: STARTED
[00:45:39.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:16:0) after 4m34s
[00:50:13.270] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:16:0)
[00:50:13.284] Build status: STARTED
[00:50:13.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:17:0) after 9m30s
[00:59:43.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:17:0)
[00:59:43.374] Build status: STARTED
[00:59:43.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:18:0) after 4m43s
[01:04:26.388] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:18:0)
[01:04:26.403] Build status: STARTED
[01:04:26.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:19:0) after 5m31s
[01:09:57.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:19:0)
[01:09:57.398] Build status: STARTED
[01:09:57.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:20:0) after 1m5s
[01:11:02.421] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:20:0)
[01:11:02.450] Build status: STARTED
[01:11:02.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:21:0) after 5m2s
[01:16:04.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:21:0)
[01:16:04.521] Build status: STARTED
[01:16:04.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:22:0) after 8m34s
[01:24:38.598] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:22:0)
[01:24:38.621] Build status: STARTED
[01:24:38.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:23:0) after 6m2s
[01:30:40.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:23:0)
[01:30:40.651] Build status: STARTED
[01:30:40.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:24:0) after 1m3s
[01:31:43.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:24:0)
[01:31:43.684] Build status: STARTED
[01:31:43.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:25:0) after 2m0s
[01:33:43.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:25:0)
[01:33:43.719] Build status: STARTED
[01:33:43.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:26:0) after 5m37s
[01:39:20.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:26:0)
[01:39:20.742] Build status: STARTED
[01:39:20.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:27:0) after 7m59s
[01:47:19.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:27:0)
[01:47:19.802] Build status: STARTED
[01:47:19.803] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:28:0) after 6m14s
[01:53:34.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:28:0)
[01:53:34.152] Build status: STARTED
[01:53:34.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:29:0) after 9m7s
[02:02:41.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:29:0)
[02:02:41.278] Build status: STARTED
[02:02:41.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:30:0) after 6m32s
[02:09:13.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:30:0)
[02:09:13.396] Build status: STARTED
[02:09:13.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:31:0) after 8m26s
[02:17:39.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:31:0)
[02:17:39.441] Build status: STARTED
[02:17:39.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:32:0) after 3m34s
[02:21:13.453] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:32:0)
[02:21:13.472] Build status: STARTED
[02:21:13.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:33:0) after 1m59s
[02:23:12.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:33:0)
[02:23:12.513] Build status: STARTED
[02:23:12.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:34:0) after 6m53s
[02:30:05.529] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:34:0)
[02:30:05.548] Build status: STARTED
[02:30:05.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:35:0) after 3m4s
[02:33:09.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:35:0)
[02:33:09.622] Build status: STARTED
[02:33:09.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:36:0) after 2m59s
[02:36:08.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:36:0)
[02:36:08.654] Build status: STARTED
[02:36:08.654] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:37:0) after 4m34s
[02:40:42.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:37:0)
[02:40:42.735] Build status: STARTED
[02:40:42.735] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:38:0) after 7m5s
[02:47:47.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:38:0)
[02:47:47.721] Build status: STARTED
[02:47:47.721] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:39:0) after 1m54s
[02:49:41.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:39:0)
[02:49:41.793] Build status: STARTED
[02:49:41.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864608849653579280:40:0) after 5m29s
[02:53:46.649] Received PubSub notification, asking Buildbucket for the build status
[02:53:46.690] Build:
{
  "id": "8699178786643449985",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "createdBy": "project:emscripten-releases",
  "createTime": "2025-11-03T23:47:19.560953128Z",
  "startTime": "2025-11-03T23:47:24.965012886Z",
  "endTime": "2025-11-04T02:53:46.380707636Z",
  "updateTime": "2025-11-04T02:53:46.380707636Z",
  "status": "FAILURE",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "emscripten-releases",
      "id": "39e7108ceed28ff140e1c8371791177eff9ba08f",
      "ref": "refs/heads/main"
    }
  }
}
[02:53:46.690] Invocation finished in 3h6m29.385458485s with status FAILED