[21:49:48.106] New invocation is queued and will start shortly
[21:49:49.535] Starting the invocation (attempt 1)
[21:49:49.570] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9dd0c51810ed6b7d493711f8090b310c7356eb5a"
[21:49:49.570] Popped gitiles commit info from properties and tags
[21:49:49.570] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:49:49.570] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:49:49.570] Buildbucket request:
{
"requestId": "8963366934662279760",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8963366934662279760",
"job": "emscripten-releases/linux-test-suites",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "9dd0c51810ed6b7d493711f8090b310c7356eb5a"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@9dd0c51810ed6b7d493711f8090b310c7356eb5a",
"title": "9dd0c51810ed6b7d493711f8090b310c7356eb5a",
"url": "https://chromium.googlesource.com/emscripten-releases/+/9dd0c51810ed6b7d493711f8090b310c7356eb5a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "9dd0c51810ed6b7d493711f8090b310c7356eb5a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8963366934662279760"
},
{
"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"
}
}
[21:49:49.929] Scheduled build:
{
"id": "8797936872389166449",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-11-09T21:49:49.646107627Z",
"updateTime": "2022-11-09T21:49:49.646107627Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "9dd0c51810ed6b7d493711f8090b310c7356eb5a",
"ref": "refs/heads/main"
}
}
}
[21:49:49.930] Task URL: https://cr-buildbucket.appspot.com/build/8797936872389166449
[21:49:49.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:2:0) after 2m8s
[21:51:04.479] Received PubSub notification, asking Buildbucket for the build status
[21:51:04.537] Build status: STARTED
[21:51:58.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:2:0)
[21:51:58.046] Build status: STARTED
[21:51:58.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:4:0) after 1m19s
[21:53:17.881] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:4:0)
[21:53:17.905] Build status: STARTED
[21:53:17.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:5:0) after 8m12s
[22:01:29.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:5:0)
[22:01:29.958] Build status: STARTED
[22:01:29.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:6:0) after 2m3s
[22:03:33.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:6:0)
[22:03:33.076] Build status: STARTED
[22:03:33.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:7:0) after 4m50s
[22:08:23.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:7:0)
[22:08:23.202] Build status: STARTED
[22:08:23.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:8:0) after 7m32s
[22:15:55.474] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:8:0)
[22:15:55.499] Build status: STARTED
[22:15:55.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:9:0) after 1m21s
[22:17:16.737] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:9:0)
[22:17:16.773] Build status: STARTED
[22:17:16.773] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:10:0) after 1m37s
[22:18:54.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:10:0)
[22:18:54.067] Build status: STARTED
[22:18:54.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:11:0) after 6m5s
[22:24:59.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:11:0)
[22:24:59.717] Build status: STARTED
[22:24:59.717] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:12:0) after 1m53s
[22:26:52.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:12:0)
[22:26:52.943] Build status: STARTED
[22:26:52.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:13:0) after 9m24s
[22:36:16.975] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:13:0)
[22:36:17.003] Build status: STARTED
[22:36:17.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:14:0) after 4m4s
[22:40:21.768] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:14:0)
[22:40:21.795] Build status: STARTED
[22:40:21.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:15:0) after 7m13s
[22:47:34.896] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:15:0)
[22:47:34.935] Build status: STARTED
[22:47:34.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:16:0) after 5m37s
[22:53:11.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:16:0)
[22:53:11.986] Build status: STARTED
[22:53:11.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:17:0) after 6m24s
[22:59:36.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:17:0)
[22:59:36.129] Build status: STARTED
[22:59:36.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:18:0) after 1m18s
[23:00:54.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:18:0)
[23:00:54.354] Build status: STARTED
[23:00:54.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:19:0) after 4m42s
[23:05:36.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:19:0)
[23:05:36.408] Build status: STARTED
[23:05:36.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:20:0) after 1m1s
[23:06:37.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:20:0)
[23:06:37.633] Build status: STARTED
[23:06:37.634] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:21:0) after 3m35s
[23:10:12.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:21:0)
[23:10:12.748] Build status: STARTED
[23:10:12.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:22:0) after 2m28s
[23:12:40.773] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:22:0)
[23:12:40.803] Build status: STARTED
[23:12:40.803] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:23:0) after 9m57s
[23:22:37.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:23:0)
[23:22:37.987] Build status: STARTED
[23:22:37.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:24:0) after 3m24s
[23:26:02.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:24:0)
[23:26:02.031] Build status: STARTED
[23:26:02.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:25:0) after 9m28s
[23:35:30.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:25:0)
[23:35:30.365] Build status: STARTED
[23:35:30.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:26:0) after 3m29s
[23:38:59.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:26:0)
[23:38:59.440] Build status: STARTED
[23:38:59.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:27:0) after 3m58s
[23:42:57.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:27:0)
[23:42:57.512] Build status: STARTED
[23:42:57.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:28:0) after 9m4s
[23:52:01.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:28:0)
[23:52:01.658] Build status: STARTED
[23:52:01.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:29:0) after 5m21s
[23:57:23.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:29:0)
[23:57:23.335] Build status: STARTED
[23:57:23.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:30:0) after 5m49s
[00:03:12.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:30:0)
[00:03:12.388] Build status: STARTED
[00:03:12.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:31:0) after 5m33s
[00:08:45.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:31:0)
[00:08:45.519] Build status: STARTED
[00:08:45.519] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:32:0) after 4m4s
[00:12:49.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:32:0)
[00:12:49.602] Build status: STARTED
[00:12:49.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:33:0) after 6m11s
[00:19:00.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:33:0)
[00:19:00.652] Build status: STARTED
[00:19:00.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:34:0) after 5m29s
[00:24:29.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:34:0)
[00:24:29.702] Build status: STARTED
[00:24:29.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:35:0) after 6m28s
[00:30:58.291] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:35:0)
[00:30:58.320] Build status: STARTED
[00:30:58.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8963366934662279760:36:0) after 2m8s
[00:31:06.320] Received PubSub notification, asking Buildbucket for the build status
[00:31:06.366] Build:
{
"id": "8797936872389166449",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2022-11-09T21:49:49.646107627Z",
"startTime": "2022-11-09T21:51:03.446339Z",
"endTime": "2022-11-10T00:31:05.979780794Z",
"updateTime": "2022-11-10T00:31:05.979780794Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "9dd0c51810ed6b7d493711f8090b310c7356eb5a",
"ref": "refs/heads/main"
}
}
}
[00:31:06.366] Invocation finished in 2h41m18.271530819s with status FAILED