[19:56:22.475] New invocation is queued and will start shortly
[19:56:24.575] Starting the invocation (attempt 1)
[19:56:24.600] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/f96ad16a34a38886ac79b5a838486e667c9807df"
[19:56:24.600] Popped gitiles commit info from properties and tags
[19:56:24.600] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:56:24.600] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:56:24.600] Buildbucket request:
{
"requestId": "8908019324413617232",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8908019324413617232",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "f96ad16a34a38886ac79b5a838486e667c9807df"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@f96ad16a34a38886ac79b5a838486e667c9807df",
"title": "f96ad16a34a38886ac79b5a838486e667c9807df",
"url": "https://chromium.googlesource.com/emscripten-releases/+/f96ad16a34a38886ac79b5a838486e667c9807df"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "f96ad16a34a38886ac79b5a838486e667c9807df",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8908019324413617232"
},
{
"key": "scheduler_job_id",
"value": "emscripten-releases/linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:56:25.090] Scheduled build:
{
"id": "8742589261452551601",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-07-12T19:56:24.671529035Z",
"updateTime": "2024-07-12T19:56:24.671529035Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "f96ad16a34a38886ac79b5a838486e667c9807df",
"ref": "refs/heads/main"
}
}
}
[19:56:25.090] Task URL: https://cr-buildbucket.appspot.com/build/8742589261452551601
[19:56:25.090] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:2:0) after 9m19s
[19:56:26.020] Received PubSub notification, asking Buildbucket for the build status
[19:56:26.041] Build status: SCHEDULED
[20:05:44.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:2:0)
[20:05:44.132] Build status: SCHEDULED
[20:05:44.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:4:0) after 2m24s
[20:08:08.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:4:0)
[20:08:08.229] Build status: SCHEDULED
[20:08:08.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:5:0) after 8m32s
[20:16:40.296] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:5:0)
[20:16:40.324] Build status: SCHEDULED
[20:16:40.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:6:0) after 7m42s
[20:24:22.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:6:0)
[20:24:22.491] Build status: SCHEDULED
[20:24:22.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:7:0) after 4m19s
[20:28:41.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:7:0)
[20:28:41.543] Build status: SCHEDULED
[20:28:41.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:8:0) after 7m31s
[20:36:12.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:8:0)
[20:36:12.639] Build status: SCHEDULED
[20:36:12.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:9:0) after 4m16s
[20:40:28.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:9:0)
[20:40:28.737] Build status: SCHEDULED
[20:40:28.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:10:0) after 9m12s
[20:49:40.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:10:0)
[20:49:40.820] Build status: SCHEDULED
[20:49:40.820] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:11:0) after 3m26s
[20:53:06.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:11:0)
[20:53:06.880] Build status: SCHEDULED
[20:53:06.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:12:0) after 1m5s
[20:54:11.869] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:12:0)
[20:54:11.903] Build status: SCHEDULED
[20:54:11.903] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:13:0) after 6m55s
[21:01:06.931] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:13:0)
[21:01:06.948] Build status: SCHEDULED
[21:01:06.948] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:14:0) after 9m39s
[21:10:45.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:14:0)
[21:10:45.993] Build status: SCHEDULED
[21:10:45.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:15:0) after 1m4s
[21:11:50.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:15:0)
[21:11:50.039] Build status: SCHEDULED
[21:11:50.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:16:0) after 3m36s
[21:15:26.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:16:0)
[21:15:26.152] Build status: SCHEDULED
[21:15:26.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:17:0) after 1m21s
[21:16:47.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:17:0)
[21:16:47.338] Build status: SCHEDULED
[21:16:47.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:18:0) after 9m25s
[21:26:12.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:18:0)
[21:26:12.374] Build status: SCHEDULED
[21:26:12.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:19:0) after 3m5s
[21:29:17.422] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:19:0)
[21:29:17.442] Build status: SCHEDULED
[21:29:17.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:20:0) after 3m55s
[21:33:12.454] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:20:0)
[21:33:12.478] Build status: SCHEDULED
[21:33:12.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:21:0) after 5m51s
[21:39:03.505] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:21:0)
[21:39:03.551] Build status: SCHEDULED
[21:39:03.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:22:0) after 9m17s
[21:48:20.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:22:0)
[21:48:20.724] Build status: SCHEDULED
[21:48:20.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:23:0) after 7m16s
[21:55:36.809] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:23:0)
[21:55:36.843] Build status: SCHEDULED
[21:55:36.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:24:0) after 3m23s
[21:58:59.877] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:24:0)
[21:58:59.896] Build status: SCHEDULED
[21:58:59.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:25:0) after 7m32s
[22:06:31.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:25:0)
[22:06:31.923] Build status: SCHEDULED
[22:06:31.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:26:0) after 1m0s
[22:07:32.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:26:0)
[22:07:32.092] Build status: SCHEDULED
[22:07:32.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:27:0) after 7m15s
[22:14:47.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:27:0)
[22:14:47.323] Build status: SCHEDULED
[22:14:47.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:28:0) after 7m58s
[22:22:45.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:28:0)
[22:22:45.371] Build status: SCHEDULED
[22:22:45.371] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:29:0) after 1m52s
[22:24:37.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:29:0)
[22:24:37.441] Build status: SCHEDULED
[22:24:37.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:30:0) after 2m41s
[22:27:18.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:30:0)
[22:27:18.503] Build status: SCHEDULED
[22:27:18.503] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:31:0) after 2m43s
[22:30:01.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:31:0)
[22:30:01.548] Build status: SCHEDULED
[22:30:01.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:32:0) after 6m9s
[22:36:10.563] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:32:0)
[22:36:10.642] Build status: SCHEDULED
[22:36:10.642] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:33:0) after 6m36s
[22:42:46.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:33:0)
[22:42:46.856] Build status: SCHEDULED
[22:42:46.856] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:34:0) after 9m20s
[22:52:06.883] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:34:0)
[22:52:06.903] Build status: SCHEDULED
[22:52:06.903] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:35:0) after 5m54s
[22:58:00.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:35:0)
[22:58:01.117] Build status: SCHEDULED
[22:58:01.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:36:0) after 3m23s
[23:01:24.140] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:36:0)
[23:01:24.159] Build status: SCHEDULED
[23:01:24.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:37:0) after 4m1s
[23:05:25.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:37:0)
[23:05:25.215] Build status: SCHEDULED
[23:05:25.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:38:0) after 1m41s
[23:07:06.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:38:0)
[23:07:06.369] Build status: SCHEDULED
[23:07:06.369] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:39:0) after 3m23s
[23:10:29.459] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:39:0)
[23:10:29.477] Build status: SCHEDULED
[23:10:29.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:40:0) after 6m48s
[23:14:54.406] Received PubSub notification, asking Buildbucket for the build status
[23:14:54.431] Build status: STARTED
[23:17:17.454] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:40:0)
[23:17:17.472] Build status: STARTED
[23:17:17.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:42:0) after 7m56s
[23:25:13.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:42:0)
[23:25:13.572] Build status: STARTED
[23:25:13.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:43:0) after 8m34s
[23:33:47.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:43:0)
[23:33:47.613] Build status: STARTED
[23:33:47.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:44:0) after 2m16s
[23:36:03.634] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:44:0)
[23:36:03.668] Build status: STARTED
[23:36:03.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:45:0) after 4m43s
[23:40:46.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:45:0)
[23:40:46.833] Build status: STARTED
[23:40:46.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:46:0) after 1m35s
[23:42:22.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:46:0)
[23:42:22.048] Build status: STARTED
[23:42:22.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:47:0) after 6m2s
[23:48:24.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:47:0)
[23:48:24.110] Build status: STARTED
[23:48:24.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:48:0) after 3m21s
[23:51:45.127] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:48:0)
[23:51:45.146] Build status: STARTED
[23:51:45.146] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:49:0) after 2m8s
[23:53:53.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:49:0)
[23:53:53.247] Build status: STARTED
[23:53:53.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:50:0) after 4m52s
[23:58:45.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:50:0)
[23:58:45.501] Build status: STARTED
[23:58:45.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:51:0) after 1m57s
[00:00:43.145] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:51:0)
[00:00:43.202] Build status: STARTED
[00:00:43.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:52:0) after 3m37s
[00:04:20.232] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:52:0)
[00:04:20.266] Build status: STARTED
[00:04:20.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:53:0) after 3m45s
[00:08:05.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:53:0)
[00:08:05.326] Build status: STARTED
[00:08:05.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:54:0) after 2m18s
[00:10:23.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:54:0)
[00:10:23.385] Build status: STARTED
[00:10:23.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:55:0) after 5m33s
[00:15:56.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:55:0)
[00:15:56.419] Build status: STARTED
[00:15:56.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:56:0) after 5m43s
[00:21:39.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:56:0)
[00:21:39.555] Build status: STARTED
[00:21:39.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:57:0) after 8m5s
[00:29:44.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:57:0)
[00:29:44.825] Build status: STARTED
[00:29:44.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:58:0) after 5m27s
[00:35:11.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:58:0)
[00:35:11.862] Build status: STARTED
[00:35:11.862] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:59:0) after 6m37s
[00:41:48.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:59:0)
[00:41:48.951] Build status: STARTED
[00:41:48.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:60:0) after 5m53s
[00:47:41.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:60:0)
[00:47:41.990] Build status: STARTED
[00:47:41.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:61:0) after 6m52s
[00:54:34.054] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:61:0)
[00:54:34.071] Build status: STARTED
[00:54:34.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8908019324413617232:62:0) after 9m39s
[00:56:16.262] Received PubSub notification, asking Buildbucket for the build status
[00:56:16.300] Build:
{
"id": "8742589261452551601",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-07-12T19:56:24.671529035Z",
"startTime": "2024-07-12T23:14:54.105539007Z",
"endTime": "2024-07-13T00:56:15.940888955Z",
"updateTime": "2024-07-13T00:56:15.940888955Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "f96ad16a34a38886ac79b5a838486e667c9807df",
"ref": "refs/heads/main"
}
}
}
[00:56:16.300] Invocation finished in 4h59m53.835819568s with status SUCCEEDED