[14:20:16.607] New invocation is queued and will start shortly
[14:20:17.821] Starting the invocation (attempt 1)
[14:20:17.845] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/e3bcb68a17a3c94f317a84760b4b2bd2667892f1"
[14:20:17.845] Popped gitiles commit info from properties and tags
[14:20:17.845] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:20:17.846] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:20:17.846] Buildbucket request:
{
"requestId": "8917915539202593152",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8917915539202593152",
"job": "emscripten-releases/linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "e3bcb68a17a3c94f317a84760b4b2bd2667892f1"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@e3bcb68a17a3c94f317a84760b4b2bd2667892f1",
"title": "e3bcb68a17a3c94f317a84760b4b2bd2667892f1",
"url": "https://chromium.googlesource.com/emscripten-releases/+/e3bcb68a17a3c94f317a84760b4b2bd2667892f1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "e3bcb68a17a3c94f317a84760b4b2bd2667892f1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917915539202593152"
},
{
"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"
}
}
[14:20:18.333] Scheduled build:
{
"id": "8752485477174801985",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-03-25T14:20:17.907281446Z",
"updateTime": "2024-03-25T14:20:17.907281446Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "e3bcb68a17a3c94f317a84760b4b2bd2667892f1",
"ref": "refs/heads/main"
}
}
}
[14:20:18.333] Task URL: https://cr-buildbucket.appspot.com/build/8752485477174801985
[14:20:18.333] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:2:0) after 3m3s
[14:20:19.382] Received PubSub notification, asking Buildbucket for the build status
[14:20:19.580] Build status: SCHEDULED
[14:23:21.546] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:2:0)
[14:23:21.618] Build status: SCHEDULED
[14:23:21.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:4:0) after 7m45s
[14:31:07.160] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:4:0)
[14:31:07.214] Build status: SCHEDULED
[14:31:07.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:5:0) after 8m11s
[14:39:18.233] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:5:0)
[14:39:18.260] Build status: SCHEDULED
[14:39:18.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:6:0) after 1m24s
[14:40:42.780] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:6:0)
[14:40:42.806] Build status: SCHEDULED
[14:40:42.806] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:7:0) after 2m4s
[14:42:46.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:7:0)
[14:42:46.959] Build status: SCHEDULED
[14:42:46.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:8:0) after 4m10s
[14:46:57.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:8:0)
[14:46:57.206] Build status: SCHEDULED
[14:46:57.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:9:0) after 1m5s
[14:48:02.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:9:0)
[14:48:02.403] Build status: SCHEDULED
[14:48:02.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:10:0) after 4m39s
[14:52:41.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:10:0)
[14:52:41.429] Build status: SCHEDULED
[14:52:41.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:11:0) after 6m56s
[14:59:37.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:11:0)
[14:59:37.527] Build status: SCHEDULED
[14:59:37.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:12:0) after 2m10s
[15:01:47.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:12:0)
[15:01:47.741] Build status: SCHEDULED
[15:01:47.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:13:0) after 1m39s
[15:03:26.817] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:13:0)
[15:03:26.846] Build status: SCHEDULED
[15:03:26.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:14:0) after 2m33s
[15:05:59.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:14:0)
[15:05:59.882] Build status: SCHEDULED
[15:05:59.882] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:15:0) after 5m51s
[15:11:50.936] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:15:0)
[15:11:50.979] Build status: SCHEDULED
[15:11:50.980] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:16:0) after 1m43s
[15:13:34.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:16:0)
[15:13:34.118] Build status: SCHEDULED
[15:13:34.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:17:0) after 2m9s
[15:15:43.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:17:0)
[15:15:43.211] Build status: SCHEDULED
[15:15:43.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:18:0) after 4m59s
[15:20:42.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:18:0)
[15:20:42.286] Build status: SCHEDULED
[15:20:42.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:19:0) after 8m24s
[15:29:06.333] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:19:0)
[15:29:06.379] Build status: SCHEDULED
[15:29:06.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:20:0) after 8m48s
[15:37:54.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:20:0)
[15:37:54.544] Build status: SCHEDULED
[15:37:54.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:21:0) after 9m19s
[15:47:13.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:21:0)
[15:47:13.812] Build status: SCHEDULED
[15:47:13.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:22:0) after 2m37s
[15:49:50.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:22:0)
[15:49:51.148] Build status: SCHEDULED
[15:49:51.148] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:23:0) after 4m44s
[15:54:35.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:23:0)
[15:54:35.252] Build status: SCHEDULED
[15:54:35.252] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:24:0) after 2m48s
[15:57:23.276] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:24:0)
[15:57:23.294] Build status: SCHEDULED
[15:57:23.294] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:25:0) after 6m4s
[16:03:27.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:25:0)
[16:03:27.395] Build status: SCHEDULED
[16:03:27.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:26:0) after 2m14s
[16:05:41.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:26:0)
[16:05:41.450] Build status: SCHEDULED
[16:05:41.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:27:0) after 6m6s
[16:09:03.020] Received PubSub notification, asking Buildbucket for the build status
[16:09:03.076] Build status: STARTED
[16:11:47.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:27:0)
[16:11:47.522] Build status: STARTED
[16:11:47.522] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:29:0) after 4m7s
[16:15:54.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:29:0)
[16:15:54.557] Build status: STARTED
[16:15:54.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:30:0) after 5m36s
[16:21:30.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:30:0)
[16:21:30.660] Build status: STARTED
[16:21:30.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:31:0) after 4m17s
[16:25:47.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:31:0)
[16:25:47.747] Build status: STARTED
[16:25:47.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:32:0) after 7m57s
[16:33:44.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:32:0)
[16:33:44.813] Build status: STARTED
[16:33:44.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:33:0) after 3m21s
[16:37:05.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:33:0)
[16:37:05.908] Build status: STARTED
[16:37:05.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:34:0) after 4m49s
[16:41:54.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:34:0)
[16:41:54.945] Build status: STARTED
[16:41:54.946] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:35:0) after 9m57s
[16:51:51.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:35:0)
[16:51:52.008] Build status: STARTED
[16:51:52.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:36:0) after 8m12s
[17:00:04.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:36:0)
[17:00:04.143] Build status: STARTED
[17:00:04.144] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:37:0) after 6m1s
[17:06:05.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:37:0)
[17:06:05.289] Build status: STARTED
[17:06:05.289] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:38:0) after 6m27s
[17:12:32.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:38:0)
[17:12:32.398] Build status: STARTED
[17:12:32.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:39:0) after 6m59s
[17:19:31.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:39:0)
[17:19:31.462] Build status: STARTED
[17:19:31.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:40:0) after 9m48s
[17:29:19.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:40:0)
[17:29:19.617] Build status: STARTED
[17:29:19.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:41:0) after 2m40s
[17:31:59.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:41:0)
[17:31:59.673] Build status: STARTED
[17:31:59.673] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:42:0) after 9m38s
[17:41:37.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:42:0)
[17:41:37.745] Build status: STARTED
[17:41:37.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:43:0) after 6m16s
[17:47:53.816] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:43:0)
[17:47:53.855] Build status: STARTED
[17:47:53.856] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:44:0) after 4m7s
[17:52:00.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:44:0)
[17:52:01.026] Build status: STARTED
[17:52:01.027] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:8917915539202593152:45:0) after 7m39s
[17:58:37.332] Received PubSub notification, asking Buildbucket for the build status
[17:58:37.360] Build:
{
"id": "8752485477174801985",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "linux"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-03-25T14:20:17.907281446Z",
"startTime": "2024-03-25T16:09:02.001339Z",
"endTime": "2024-03-25T17:58:24.823634Z",
"updateTime": "2024-03-25T17:58:24.823634Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "e3bcb68a17a3c94f317a84760b4b2bd2667892f1",
"ref": "refs/heads/main"
}
}
}
[17:58:37.360] Invocation finished in 3h38m20.769472085s with status SUCCEEDED