[20:02:08.581] New invocation is queued and will start shortly
[20:02:09.647] Starting the invocation (attempt 1)
[20:02:09.671] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c2fe42a40397f7b13d6d634964ddeacc4ae8ce80"
[20:02:09.671] Popped gitiles commit info from properties and tags
[20:02:09.671] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:02:09.671] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:02:09.672] Buildbucket request:
{
  "requestId": "8864894805492092272",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "properties": {
      "$recipe_engine/scheduler": {
            "hostname": "luci-scheduler.appspot.com",
            "invocation": "8864894805492092272",
            "job": "emscripten-releases/linux-test-suites",
            "triggers": [
                  {
                        "gitiles": {
                              "ref": "refs/heads/main",
                              "repo": "https://chromium.googlesource.com/emscripten-releases",
                              "revision": "c2fe42a40397f7b13d6d634964ddeacc4ae8ce80"
                            },
                        "id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@c2fe42a40397f7b13d6d634964ddeacc4ae8ce80",
                        "title": "c2fe42a40397f7b13d6d634964ddeacc4ae8ce80",
                        "url": "https://chromium.googlesource.com/emscripten-releases/+/c2fe42a40397f7b13d6d634964ddeacc4ae8ce80"
                      }
                ]
          }
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "emscripten-releases",
    "id": "c2fe42a40397f7b13d6d634964ddeacc4ae8ce80",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8864894805492092272"
    },
    {
      "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"
  }
}
[20:02:10.033] Scheduled build:
{
  "id": "8699464743635560161",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "createdBy": "project:emscripten-releases",
  "createTime": "2025-10-31T20:02:09.721615009Z",
  "updateTime": "2025-10-31T20:02:09.721615009Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "emscripten-releases",
      "id": "c2fe42a40397f7b13d6d634964ddeacc4ae8ce80",
      "ref": "refs/heads/main"
    }
  }
}
[20:02:10.033] Task URL: https://cr-buildbucket.appspot.com/build/8699464743635560161
[20:02:10.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:2:0) after 6m22s
[20:02:10.998] Received PubSub notification, asking Buildbucket for the build status
[20:02:11.021] Build status: SCHEDULED
[20:06:23.556] Received PubSub notification, asking Buildbucket for the build status
[20:06:23.584] Build status: STARTED
[20:08:32.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:2:0)
[20:08:32.139] Build status: STARTED
[20:08:32.139] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:5:0) after 3m50s
[20:12:22.269] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:5:0)
[20:12:22.296] Build status: STARTED
[20:12:22.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:6:0) after 5m31s
[20:17:53.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:6:0)
[20:17:53.443] Build status: STARTED
[20:17:53.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:7:0) after 4m59s
[20:22:52.409] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:7:0)
[20:22:52.436] Build status: STARTED
[20:22:52.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:8:0) after 7m50s
[20:30:42.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:8:0)
[20:30:42.514] Build status: STARTED
[20:30:42.514] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:9:0) after 3m48s
[20:34:30.525] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:9:0)
[20:34:30.552] Build status: STARTED
[20:34:30.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:10:0) after 6m2s
[20:40:32.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:10:0)
[20:40:32.779] Build status: STARTED
[20:40:32.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:11:0) after 9m35s
[20:50:07.793] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:11:0)
[20:50:07.815] Build status: STARTED
[20:50:07.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:12:0) after 2m4s
[20:52:11.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:12:0)
[20:52:11.815] Build status: STARTED
[20:52:11.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:13:0) after 6m18s
[20:58:29.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:13:0)
[20:58:29.988] Build status: STARTED
[20:58:29.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:14:0) after 7m47s
[21:06:16.998] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:14:0)
[21:06:17.228] Build status: STARTED
[21:06:17.228] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:15:0) after 8m59s
[21:15:16.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:15:0)
[21:15:16.257] Build status: STARTED
[21:15:16.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:16:0) after 3m32s
[21:18:48.270] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:16:0)
[21:18:48.289] Build status: STARTED
[21:18:48.289] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:17:0) after 4m25s
[21:23:13.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:17:0)
[21:23:13.323] Build status: STARTED
[21:23:13.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:18:0) after 2m45s
[21:25:58.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:18:0)
[21:25:58.329] Build status: STARTED
[21:25:58.329] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:19:0) after 5m44s
[21:31:42.387] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:19:0)
[21:31:42.405] Build status: STARTED
[21:31:42.405] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:20:0) after 9m38s
[21:41:20.423] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:20:0)
[21:41:20.474] Build status: STARTED
[21:41:20.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:21:0) after 4m33s
[21:45:53.552] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:21:0)
[21:45:53.600] Build status: STARTED
[21:45:53.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:22:0) after 4m22s
[21:50:15.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:22:0)
[21:50:15.581] Build status: STARTED
[21:50:15.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:23:0) after 3m18s
[21:53:33.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:23:0)
[21:53:33.621] Build status: STARTED
[21:53:33.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:24:0) after 1m33s
[21:55:06.632] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:24:0)
[21:55:06.696] Build status: STARTED
[21:55:06.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:25:0) after 1m38s
[21:56:44.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:25:0)
[21:56:44.772] Build status: STARTED
[21:56:44.772] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:26:0) after 4m14s
[22:00:58.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:26:0)
[22:00:58.897] Build status: STARTED
[22:00:58.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:27:0) after 2m26s
[22:03:24.895] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:27:0)
[22:03:24.946] Build status: STARTED
[22:03:24.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:28:0) after 5m8s
[22:08:33.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:28:0)
[22:08:33.029] Build status: STARTED
[22:08:33.029] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:29:0) after 8m38s
[22:17:11.204] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:29:0)
[22:17:11.236] Build status: STARTED
[22:17:11.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:30:0) after 5m30s
[22:22:41.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:30:0)
[22:22:41.272] Build status: STARTED
[22:22:41.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:31:0) after 9m49s
[22:32:30.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:31:0)
[22:32:30.437] Build status: STARTED
[22:32:30.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:32:0) after 1m17s
[22:33:47.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:32:0)
[22:33:47.699] Build status: STARTED
[22:33:47.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:33:0) after 5m3s
[22:38:50.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:33:0)
[22:38:50.782] Build status: STARTED
[22:38:50.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:34:0) after 6m38s
[22:45:28.763] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:34:0)
[22:45:28.781] Build status: STARTED
[22:45:28.781] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:35:0) after 8m16s
[22:53:44.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:35:0)
[22:53:44.900] Build status: STARTED
[22:53:44.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:36:0) after 3m3s
[22:56:47.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:36:0)
[22:56:47.993] Build status: STARTED
[22:56:47.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:37:0) after 9m17s
[23:06:05.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:37:0)
[23:06:05.029] Build status: STARTED
[23:06:05.029] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8864894805492092272:38:0) after 6m50s
[23:11:06.317] Received PubSub notification, asking Buildbucket for the build status
[23:11:06.397] Build:
{
  "id": "8699464743635560161",
  "builder": {
    "project": "emscripten-releases",
    "bucket": "ci",
    "builder": "linux-test-suites"
  },
  "createdBy": "project:emscripten-releases",
  "createTime": "2025-10-31T20:02:09.721615009Z",
  "startTime": "2025-10-31T20:06:23.237342059Z",
  "endTime": "2025-10-31T23:11:05.443389377Z",
  "updateTime": "2025-10-31T23:11:05.443389377Z",
  "status": "FAILURE",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "emscripten-releases",
      "id": "c2fe42a40397f7b13d6d634964ddeacc4ae8ce80",
      "ref": "refs/heads/main"
    }
  }
}
[23:11:06.397] Invocation finished in 3h8m57.830484519s with status FAILED