[11:59:04.493] New invocation is queued and will start shortly
[11:59:05.622] Starting the invocation (attempt 1)
[11:59:05.651] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6761da90f894bdf79809cb494b4a437acc607f68"
[11:59:05.652] Popped gitiles commit info from properties and tags
[11:59:05.652] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:59:05.652] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:59:05.652] Buildbucket request:
{
"requestId": "8924538001392096720",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8924538001392096720",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "6761da90f894bdf79809cb494b4a437acc607f68"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@6761da90f894bdf79809cb494b4a437acc607f68",
"title": "6761da90f894bdf79809cb494b4a437acc607f68",
"url": "https://chromium.googlesource.com/emscripten-releases/+/6761da90f894bdf79809cb494b4a437acc607f68"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "6761da90f894bdf79809cb494b4a437acc607f68",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8924538001392096720"
},
{
"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"
}
}
[11:59:06.196] Scheduled build:
{
"id": "8759107939430333297",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-01-12T11:59:05.743164952Z",
"updateTime": "2024-01-12T11:59:05.743164952Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "6761da90f894bdf79809cb494b4a437acc607f68",
"ref": "refs/heads/main"
}
}
}
[11:59:06.196] Task URL: https://cr-buildbucket.appspot.com/build/8759107939430333297
[11:59:06.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:2:0) after 8m58s
[11:59:07.098] Received PubSub notification, asking Buildbucket for the build status
[11:59:07.131] Build status: SCHEDULED
[12:08:04.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:2:0)
[12:08:04.339] Build status: SCHEDULED
[12:08:04.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:4:0) after 2m33s
[12:10:37.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:4:0)
[12:10:37.372] Build status: SCHEDULED
[12:10:37.372] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:5:0) after 8m30s
[12:19:07.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:5:0)
[12:19:07.422] Build status: SCHEDULED
[12:19:07.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:6:0) after 1m21s
[12:20:29.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:6:0)
[12:20:29.039] Build status: SCHEDULED
[12:20:29.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:7:0) after 7m22s
[12:27:51.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:7:0)
[12:27:51.148] Build status: SCHEDULED
[12:27:51.148] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:8:0) after 4m31s
[12:32:22.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:8:0)
[12:32:22.183] Build status: SCHEDULED
[12:32:22.183] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:9:0) after 4m9s
[12:36:31.204] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:9:0)
[12:36:31.333] Build status: SCHEDULED
[12:36:31.333] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:10:0) after 6m3s
[12:42:34.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:10:0)
[12:42:34.480] Build status: SCHEDULED
[12:42:34.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:11:0) after 9m30s
[12:52:04.702] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:11:0)
[12:52:04.725] Build status: SCHEDULED
[12:52:04.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:12:0) after 3m11s
[12:55:15.996] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:12:0)
[12:55:16.033] Build status: SCHEDULED
[12:55:16.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:13:0) after 3m53s
[12:59:09.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:13:0)
[12:59:09.221] Build status: SCHEDULED
[12:59:09.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:14:0) after 1m25s
[13:00:34.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:14:0)
[13:00:34.270] Build status: SCHEDULED
[13:00:34.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:15:0) after 2m14s
[13:02:48.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:15:0)
[13:02:48.490] Build status: SCHEDULED
[13:02:48.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:16:0) after 3m59s
[13:06:47.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:16:0)
[13:06:47.595] Build status: SCHEDULED
[13:06:47.595] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:17:0) after 5m12s
[13:11:59.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:17:0)
[13:11:59.678] Build status: SCHEDULED
[13:11:59.678] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:18:0) after 9m31s
[13:21:30.694] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:18:0)
[13:21:30.720] Build status: SCHEDULED
[13:21:30.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:19:0) after 2m5s
[13:23:35.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:19:0)
[13:23:35.840] Build status: SCHEDULED
[13:23:35.840] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:20:0) after 5m36s
[13:29:11.964] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:20:0)
[13:29:12.000] Build status: SCHEDULED
[13:29:12.000] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:21:0) after 3m18s
[13:32:30.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:21:0)
[13:32:30.170] Build status: SCHEDULED
[13:32:30.170] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:22:0) after 2m15s
[13:34:45.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:22:0)
[13:34:45.608] Build status: SCHEDULED
[13:34:45.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:23:0) after 6m7s
[13:40:52.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:23:0)
[13:40:52.889] Build status: SCHEDULED
[13:40:52.889] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:24:0) after 5m32s
[13:46:24.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:24:0)
[13:46:24.931] Build status: SCHEDULED
[13:46:24.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:25:0) after 2m29s
[13:48:54.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:25:0)
[13:48:54.050] Build status: SCHEDULED
[13:48:54.050] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:26:0) after 2m11s
[13:51:05.054] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:26:0)
[13:51:05.075] Build status: SCHEDULED
[13:51:05.075] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:27:0) after 4m42s
[13:55:47.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:27:0)
[13:55:47.112] Build status: SCHEDULED
[13:55:47.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:28:0) after 8m15s
[14:04:02.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:28:0)
[14:04:02.299] Build status: SCHEDULED
[14:04:02.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:29:0) after 9m31s
[14:13:33.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:29:0)
[14:13:33.530] Build status: SCHEDULED
[14:13:33.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:30:0) after 1m47s
[14:15:20.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:30:0)
[14:15:20.582] Build status: SCHEDULED
[14:15:20.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:31:0) after 9m46s
[14:25:06.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:31:0)
[14:25:06.724] Build status: SCHEDULED
[14:25:06.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:32:0) after 4m9s
[14:29:16.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:32:0)
[14:29:16.876] Build status: SCHEDULED
[14:29:16.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:33:0) after 3m26s
[14:32:42.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:33:0)
[14:32:43.016] Build status: SCHEDULED
[14:32:43.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:34:0) after 2m27s
[14:35:10.068] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:34:0)
[14:35:10.087] Build status: SCHEDULED
[14:35:10.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:35:0) after 2m20s
[14:37:30.107] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:35:0)
[14:37:30.128] Build status: SCHEDULED
[14:37:30.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:36:0) after 7m35s
[14:45:05.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:36:0)
[14:45:05.299] Build status: SCHEDULED
[14:45:05.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:37:0) after 6m26s
[14:51:31.314] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:37:0)
[14:51:31.340] Build status: SCHEDULED
[14:51:31.340] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:38:0) after 4m29s
[14:56:00.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:38:0)
[14:56:00.622] Build status: SCHEDULED
[14:56:00.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:39:0) after 9m0s
[15:05:00.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:39:0)
[15:05:00.794] Build status: SCHEDULED
[15:05:00.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:40:0) after 5m0s
[15:10:00.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:40:0)
[15:10:00.976] Build status: SCHEDULED
[15:10:00.976] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:41:0) after 7m17s
[15:17:18.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:41:0)
[15:17:18.139] Build status: SCHEDULED
[15:17:18.139] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:42:0) after 2m13s
[15:19:31.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:42:0)
[15:19:31.550] Build status: SCHEDULED
[15:19:31.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:43:0) after 6m59s
[15:26:30.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:43:0)
[15:26:30.785] Build status: SCHEDULED
[15:26:30.785] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:44:0) after 1m17s
[15:27:47.871] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:44:0)
[15:27:47.901] Build status: SCHEDULED
[15:27:47.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:45:0) after 9m25s
[15:37:12.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:45:0)
[15:37:12.971] Build status: SCHEDULED
[15:37:12.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:46:0) after 6m15s
[15:39:36.409] Received PubSub notification, asking Buildbucket for the build status
[15:39:36.436] Build status: STARTED
[15:43:28.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:46:0)
[15:43:28.130] Build status: STARTED
[15:43:28.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:48:0) after 5m41s
[15:49:09.395] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:48:0)
[15:49:09.455] Build status: STARTED
[15:49:09.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:49:0) after 5m36s
[15:54:45.478] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:49:0)
[15:54:45.579] Build status: STARTED
[15:54:45.579] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:50:0) after 9m19s
[16:04:04.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:50:0)
[16:04:04.636] Build status: STARTED
[16:04:04.636] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:51:0) after 2m41s
[16:06:45.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:51:0)
[16:06:45.675] Build status: STARTED
[16:06:45.675] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:52:0) after 4m22s
[16:11:07.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:52:0)
[16:11:07.839] Build status: STARTED
[16:11:07.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:53:0) after 5m16s
[16:16:24.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:53:0)
[16:16:24.069] Build status: STARTED
[16:16:24.069] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:54:0) after 8m58s
[16:25:22.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:54:0)
[16:25:22.324] Build status: STARTED
[16:25:22.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:55:0) after 5m14s
[16:30:36.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:55:0)
[16:30:36.398] Build status: STARTED
[16:30:36.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:56:0) after 2m3s
[16:32:39.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:56:0)
[16:32:39.478] Build status: STARTED
[16:32:39.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:57:0) after 7m46s
[16:40:25.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:57:0)
[16:40:25.504] Build status: STARTED
[16:40:25.504] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:58:0) after 4m23s
[16:44:48.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:58:0)
[16:44:48.551] Build status: STARTED
[16:44:48.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:59:0) after 2m25s
[16:47:13.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:59:0)
[16:47:13.600] Build status: STARTED
[16:47:13.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:60:0) after 7m53s
[16:55:06.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:60:0)
[16:55:06.644] Build status: STARTED
[16:55:06.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:61:0) after 3m7s
[16:58:13.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:61:0)
[16:58:13.739] Build status: STARTED
[16:58:13.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:62:0) after 6m54s
[17:05:07.808] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:62:0)
[17:05:07.835] Build status: STARTED
[17:05:07.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:63:0) after 5m12s
[17:10:19.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:63:0)
[17:10:19.883] Build status: STARTED
[17:10:19.883] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:64:0) after 4m13s
[17:14:33.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:64:0)
[17:14:33.166] Build status: STARTED
[17:14:33.166] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:65:0) after 3m40s
[17:18:13.282] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:65:0)
[17:18:13.423] Build status: STARTED
[17:18:13.424] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:66:0) after 2m7s
[17:20:20.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:66:0)
[17:20:20.705] Build status: STARTED
[17:20:20.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8924538001392096720:67:0) after 4m53s
[17:20:28.745] Received PubSub notification, asking Buildbucket for the build status
[17:20:28.786] Build:
{
"id": "8759107939430333297",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-01-12T11:59:05.743164952Z",
"startTime": "2024-01-12T15:39:30.117761Z",
"endTime": "2024-01-12T17:20:28.092901Z",
"updateTime": "2024-01-12T17:20:28.092901Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "6761da90f894bdf79809cb494b4a437acc607f68",
"ref": "refs/heads/main"
}
}
}
[17:20:28.786] Invocation finished in 5h21m24.298278364s with status SUCCEEDED