[08:55:44.647] New invocation is queued and will start shortly
[08:55:45.733] Starting the invocation (attempt 1)
[08:55:45.767] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1622ccf50b24420877287dfa014e8bad5be9193a"
[08:55:45.767] Popped gitiles commit info from properties and tags
[08:55:45.767] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:55:45.768] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:55:45.768] Buildbucket request:
{
  "requestId": "8865208522583373664",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8865208522583373664",
            "job": "emscripten-releases/linux-test-suites",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/emscripten-releases",
                              "revision": "1622ccf50b24420877287dfa014e8bad5be9193a"
                            },
                        "id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@1622ccf50b24420877287dfa014e8bad5be9193a",
                        "title": "1622ccf50b24420877287dfa014e8bad5be9193a",
                        "url": "https://chromium.googlesource.com/emscripten-releases/+/1622ccf50b24420877287dfa014e8bad5be9193a"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "emscripten-releases",
    "id": "1622ccf50b24420877287dfa014e8bad5be9193a",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8865208522583373664"
    },
    {
      "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"
  }
}
[08:55:46.752] Scheduled build:
{
  "id": "8699778460615061297",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "createdBy": "project:emscripten-releases",
  "createTime": "2025-10-28T08:55:45.895382654Z",
  "updateTime": "2025-10-28T08:55:45.895382654Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "emscripten-releases",
      "id": "1622ccf50b24420877287dfa014e8bad5be9193a",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[08:55:46.752] Task URL: https://cr-buildbucket.appspot.com/build/8699778460615061297
[08:55:46.752] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:2:0) after 5m24s
[08:55:47.251] Received PubSub notification, asking Buildbucket for the build status
[08:55:47.285] Build status: SCHEDULED
[08:55:51.505] Received PubSub notification, asking Buildbucket for the build status
[08:55:51.521] Build status: STARTED
[09:01:10.866] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:2:0)
[09:01:10.890] Build status: STARTED
[09:01:10.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:5:0) after 8m37s
[09:09:47.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:5:0)
[09:09:47.987] Build status: STARTED
[09:09:47.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:6:0) after 1m16s
[09:11:04.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:6:0)
[09:11:04.075] Build status: STARTED
[09:11:04.075] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:7:0) after 9m56s
[09:21:00.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:7:0)
[09:21:00.176] Build status: STARTED
[09:21:00.176] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:8:0) after 7m6s
[09:28:06.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:8:0)
[09:28:06.224] Build status: STARTED
[09:28:06.225] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:9:0) after 3m3s
[09:31:09.269] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:9:0)
[09:31:09.303] Build status: STARTED
[09:31:09.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:10:0) after 6m52s
[09:38:01.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:10:0)
[09:38:01.374] Build status: STARTED
[09:38:01.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:11:0) after 6m15s
[09:44:16.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:11:0)
[09:44:16.474] Build status: STARTED
[09:44:16.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:12:0) after 9m0s
[09:53:16.509] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:12:0)
[09:53:16.532] Build status: STARTED
[09:53:16.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:13:0) after 3m34s
[09:56:50.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:13:0)
[09:56:50.663] Build status: STARTED
[09:56:50.663] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:14:0) after 2m14s
[09:59:04.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:14:0)
[09:59:04.707] Build status: STARTED
[09:59:04.707] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:15:0) after 9m58s
[10:09:02.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:15:0)
[10:09:02.743] Build status: STARTED
[10:09:02.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:16:0) after 2m32s
[10:11:34.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:16:0)
[10:11:34.779] Build status: STARTED
[10:11:34.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:17:0) after 8m2s
[10:19:36.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:17:0)
[10:19:36.834] Build status: STARTED
[10:19:36.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:18:0) after 3m47s
[10:23:23.881] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:18:0)
[10:23:23.921] Build status: STARTED
[10:23:23.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:19:0) after 2m4s
[10:25:27.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:19:0)
[10:25:28.011] Build status: STARTED
[10:25:28.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:20:0) after 3m37s
[10:29:05.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:20:0)
[10:29:05.101] Build status: STARTED
[10:29:05.101] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:21:0) after 1m12s
[10:30:17.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:21:0)
[10:30:17.157] Build status: STARTED
[10:30:17.157] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:22:0) after 2m6s
[10:32:23.190] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:22:0)
[10:32:23.216] Build status: STARTED
[10:32:23.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:23:0) after 4m35s
[10:36:58.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:23:0)
[10:36:58.247] Build status: STARTED
[10:36:58.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:24:0) after 4m26s
[10:41:24.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:24:0)
[10:41:24.312] Build status: STARTED
[10:41:24.312] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:25:0) after 9m35s
[10:50:59.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:25:0)
[10:50:59.369] Build status: STARTED
[10:50:59.369] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:26:0) after 1m53s
[10:52:52.386] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:26:0)
[10:52:52.410] Build status: STARTED
[10:52:52.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:27:0) after 3m10s
[10:56:02.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:27:0)
[10:56:02.547] Build status: STARTED
[10:56:02.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:28:0) after 5m16s
[11:01:18.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:28:0)
[11:01:18.639] Build status: STARTED
[11:01:18.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:29:0) after 5m31s
[11:06:49.654] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:29:0)
[11:06:49.671] Build status: STARTED
[11:06:49.672] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:30:0) after 1m52s
[11:08:41.686] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:30:0)
[11:08:41.711] Build status: STARTED
[11:08:41.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:31:0) after 4m46s
[11:13:27.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:31:0)
[11:13:27.911] Build status: STARTED
[11:13:27.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:32:0) after 3m7s
[11:16:34.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:32:0)
[11:16:34.992] Build status: STARTED
[11:16:34.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:33:0) after 4m18s
[11:20:53.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:33:0)
[11:20:53.023] Build status: STARTED
[11:20:53.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:34:0) after 8m56s
[11:29:49.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:34:0)
[11:29:49.057] Build status: STARTED
[11:29:49.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:35:0) after 5m31s
[11:35:20.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:35:0)
[11:35:20.144] Build status: STARTED
[11:35:20.144] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:36:0) after 3m55s
[11:39:15.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:36:0)
[11:39:15.208] Build status: STARTED
[11:39:15.208] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:37:0) after 1m50s
[11:41:05.223] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:37:0)
[11:41:05.320] Build status: STARTED
[11:41:05.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:38:0) after 7m19s
[11:48:24.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:38:0)
[11:48:24.379] Build status: STARTED
[11:48:24.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:39:0) after 6m21s
[11:54:45.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:39:0)
[11:54:45.382] Build status: STARTED
[11:54:45.382] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8865208522583373664:40:0) after 9m59s
[11:56:16.868] Received PubSub notification, asking Buildbucket for the build status
[11:56:16.922] Build:
{
  "id": "8699778460615061297",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "createdBy": "project:emscripten-releases",
  "createTime": "2025-10-28T08:55:45.895382654Z",
  "startTime": "2025-10-28T08:55:51.325791900Z",
  "endTime": "2025-10-28T11:56:16.679424493Z",
  "updateTime": "2025-10-28T11:56:16.679424493Z",
  "status": "FAILURE",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "emscripten-releases",
      "id": "1622ccf50b24420877287dfa014e8bad5be9193a",
      "ref": "refs/heads/main"
    }
  },
  "canary": true
}
[11:56:16.922] Invocation finished in 3h0m32.288479145s with status FAILED