[22:53:49.451] New invocation is queued and will start shortly
[22:53:50.596] Starting the invocation (attempt 1)
[22:53:50.635] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1cd074e0b9b98ea783035f52650fed300fdd6579"
[22:53:50.635] Popped gitiles commit info from properties and tags
[22:53:50.635] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:53:50.636] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:53:50.637] Buildbucket request:
{
"requestId": "8912809799471696688",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8912809799471696688",
"job": "emscripten-releases/linux-test-suites",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "1cd074e0b9b98ea783035f52650fed300fdd6579"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@1cd074e0b9b98ea783035f52650fed300fdd6579",
"title": "1cd074e0b9b98ea783035f52650fed300fdd6579",
"url": "https://chromium.googlesource.com/emscripten-releases/+/1cd074e0b9b98ea783035f52650fed300fdd6579"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "1cd074e0b9b98ea783035f52650fed300fdd6579",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8912809799471696688"
},
{
"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"
}
}
[22:53:51.119] Scheduled build:
{
"id": "8747379737502409345",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-05-20T22:53:50.699648038Z",
"updateTime": "2024-05-20T22:53:50.699648038Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "1cd074e0b9b98ea783035f52650fed300fdd6579",
"ref": "refs/heads/main"
}
}
}
[22:53:51.119] Task URL: https://cr-buildbucket.appspot.com/build/8747379737502409345
[22:53:51.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:2:0) after 3m49s
[22:53:52.005] Received PubSub notification, asking Buildbucket for the build status
[22:53:52.033] Build status: SCHEDULED
[22:57:40.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:2:0)
[22:57:40.194] Build status: SCHEDULED
[22:57:40.194] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:4:0) after 8m39s
[23:06:19.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:4:0)
[23:06:19.250] Build status: SCHEDULED
[23:06:19.250] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:5:0) after 6m2s
[23:12:21.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:5:0)
[23:12:21.394] Build status: SCHEDULED
[23:12:21.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:6:0) after 1m6s
[23:13:27.425] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:6:0)
[23:13:27.456] Build status: SCHEDULED
[23:13:27.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:7:0) after 4m15s
[23:17:42.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:7:0)
[23:17:42.509] Build status: SCHEDULED
[23:17:42.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:8:0) after 9m24s
[23:27:06.669] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:8:0)
[23:27:06.766] Build status: SCHEDULED
[23:27:06.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:9:0) after 7m52s
[23:34:58.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:9:0)
[23:34:58.852] Build status: SCHEDULED
[23:34:58.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:10:0) after 5m40s
[23:40:38.876] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:10:0)
[23:40:38.899] Build status: SCHEDULED
[23:40:38.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:11:0) after 9m12s
[23:49:50.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:11:0)
[23:49:50.953] Build status: SCHEDULED
[23:49:50.953] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:12:0) after 7m47s
[23:57:38.267] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:12:0)
[23:57:38.291] Build status: SCHEDULED
[23:57:38.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:13:0) after 9m39s
[00:07:17.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:13:0)
[00:07:17.480] Build status: SCHEDULED
[00:07:17.480] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:14:0) after 3m31s
[00:10:48.498] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:14:0)
[00:10:48.515] Build status: SCHEDULED
[00:10:48.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:15:0) after 3m21s
[00:14:09.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:15:0)
[00:14:09.676] Build status: SCHEDULED
[00:14:09.676] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:16:0) after 5m38s
[00:19:47.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:16:0)
[00:19:47.720] Build status: SCHEDULED
[00:19:47.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:17:0) after 5m54s
[00:23:51.189] Received PubSub notification, asking Buildbucket for the build status
[00:23:51.254] Build status: STARTED
[00:25:41.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:17:0)
[00:25:41.806] Build status: STARTED
[00:25:41.806] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:19:0) after 8m29s
[00:34:10.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:19:0)
[00:34:10.933] Build status: STARTED
[00:34:10.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:20:0) after 6m1s
[00:40:12.032] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:20:0)
[00:40:12.064] Build status: STARTED
[00:40:12.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:21:0) after 5m53s
[00:46:05.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:21:0)
[00:46:05.359] Build status: STARTED
[00:46:05.359] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:22:0) after 3m1s
[00:49:06.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:22:0)
[00:49:06.588] Build status: STARTED
[00:49:06.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:23:0) after 9m42s
[00:58:48.901] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:23:0)
[00:58:48.928] Build status: STARTED
[00:58:48.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:24:0) after 3m20s
[01:02:09.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:24:0)
[01:02:09.443] Build status: STARTED
[01:02:09.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:25:0) after 8m29s
[01:10:38.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:25:0)
[01:10:38.445] Build status: STARTED
[01:10:38.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:26:0) after 9m20s
[01:19:58.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:26:0)
[01:19:58.643] Build status: STARTED
[01:19:58.643] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:27:0) after 2m52s
[01:22:50.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:27:0)
[01:22:50.916] Build status: STARTED
[01:22:50.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:28:0) after 4m32s
[01:27:23.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:28:0)
[01:27:23.113] Build status: STARTED
[01:27:23.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:29:0) after 5m46s
[01:33:09.140] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:29:0)
[01:33:09.158] Build status: STARTED
[01:33:09.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:30:0) after 6m51s
[01:40:00.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:30:0)
[01:40:00.288] Build status: STARTED
[01:40:00.288] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:31:0) after 7m44s
[01:47:44.452] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:31:0)
[01:47:44.620] Build status: STARTED
[01:47:44.620] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:32:0) after 4m2s
[01:51:46.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:32:0)
[01:51:46.672] Build status: STARTED
[01:51:46.672] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:33:0) after 1m9s
[01:52:55.689] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:33:0)
[01:52:55.779] Build status: STARTED
[01:52:55.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:34:0) after 3m41s
[01:56:36.801] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:34:0)
[01:56:36.848] Build status: STARTED
[01:56:36.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:35:0) after 8m54s
[02:05:30.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:35:0)
[02:05:31.263] Build status: STARTED
[02:05:31.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:36:0) after 7m24s
[02:12:55.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:36:0)
[02:12:55.299] Build status: STARTED
[02:12:55.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:37:0) after 3m28s
[02:16:23.363] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:37:0)
[02:16:23.392] Build status: STARTED
[02:16:23.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:38:0) after 2m10s
[02:18:33.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:38:0)
[02:18:33.562] Build status: STARTED
[02:18:33.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:39:0) after 1m18s
[02:19:51.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:39:0)
[02:19:51.900] Build status: STARTED
[02:19:51.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:40:0) after 2m2s
[02:21:54.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:40:0)
[02:21:54.039] Build status: STARTED
[02:21:54.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:41:0) after 6m9s
[02:28:03.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:41:0)
[02:28:03.094] Build status: STARTED
[02:28:03.094] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:42:0) after 3m6s
[02:31:09.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:42:0)
[02:31:09.191] Build status: STARTED
[02:31:09.191] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:43:0) after 4m29s
[02:35:38.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:43:0)
[02:35:38.244] Build status: STARTED
[02:35:38.244] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:44:0) after 4m51s
[02:40:29.452] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:44:0)
[02:40:29.483] Build status: STARTED
[02:40:29.483] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:45:0) after 2m23s
[02:42:52.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:45:0)
[02:42:52.671] Build status: STARTED
[02:42:52.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:46:0) after 8m0s
[02:50:52.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:46:0)
[02:50:52.715] Build status: STARTED
[02:50:52.715] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:47:0) after 4m9s
[02:55:01.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:47:0)
[02:55:01.763] Build status: STARTED
[02:55:01.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:48:0) after 3m19s
[02:58:20.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:48:0)
[02:58:20.799] Build status: STARTED
[02:58:20.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:49:0) after 5m57s
[03:04:17.936] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:49:0)
[03:04:17.960] Build status: STARTED
[03:04:17.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:50:0) after 6m21s
[03:10:38.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:50:0)
[03:10:38.994] Build status: STARTED
[03:10:38.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:51:0) after 1m4s
[03:11:43.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:51:0)
[03:11:43.071] Build status: STARTED
[03:11:43.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux-test-suites:8912809799471696688:52:0) after 3m20s
[03:12:48.796] Received PubSub notification, asking Buildbucket for the build status
[03:12:48.850] Build:
{
"id": "8747379737502409345",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux-test-suites"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-05-20T22:53:50.699648038Z",
"startTime": "2024-05-21T00:23:51.029785210Z",
"endTime": "2024-05-21T03:12:48.544001336Z",
"updateTime": "2024-05-21T03:12:48.544001336Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "1cd074e0b9b98ea783035f52650fed300fdd6579",
"ref": "refs/heads/main"
}
}
}
[03:12:48.850] Invocation finished in 4h18m59.413152719s with status SUCCEEDED