[23:32:44.190] New invocation is queued and will start shortly
[23:32:45.308] Starting the invocation (attempt 1)
[23:32:45.338] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/351fd401bfccdb1450d6b5f4dc762c712461eccd"
[23:32:45.338] Popped gitiles commit info from properties and tags
[23:32:45.340] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:32:45.341] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:32:45.341] Buildbucket request:
{
"requestId": "8914438096708574496",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8914438096708574496",
"job": "emscripten-releases/win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "351fd401bfccdb1450d6b5f4dc762c712461eccd"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@351fd401bfccdb1450d6b5f4dc762c712461eccd",
"title": "351fd401bfccdb1450d6b5f4dc762c712461eccd",
"url": "https://chromium.googlesource.com/emscripten-releases/+/351fd401bfccdb1450d6b5f4dc762c712461eccd"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "351fd401bfccdb1450d6b5f4dc762c712461eccd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8914438096708574496"
},
{
"key": "scheduler_job_id",
"value": "emscripten-releases/win"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:32:45.947] Scheduled build:
{
"id": "8749008034771969073",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-05-02T23:32:45.414200199Z",
"updateTime": "2024-05-02T23:32:45.414200199Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "351fd401bfccdb1450d6b5f4dc762c712461eccd",
"ref": "refs/heads/main"
}
}
}
[23:32:45.947] Task URL: https://cr-buildbucket.appspot.com/build/8749008034771969073
[23:32:45.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:2:0) after 7m35s
[23:32:46.806] Received PubSub notification, asking Buildbucket for the build status
[23:32:46.841] Build status: SCHEDULED
[23:40:20.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:2:0)
[23:40:20.992] Build status: SCHEDULED
[23:40:20.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:4:0) after 8m48s
[23:49:09.030] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:4:0)
[23:49:09.076] Build status: SCHEDULED
[23:49:09.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:5:0) after 5m38s
[23:54:47.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:5:0)
[23:54:47.320] Build status: SCHEDULED
[23:54:47.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:6:0) after 4m57s
[23:59:44.343] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:6:0)
[23:59:44.365] Build status: SCHEDULED
[23:59:44.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:7:0) after 4m9s
[00:03:53.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:7:0)
[00:03:53.698] Build status: SCHEDULED
[00:03:53.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:8:0) after 8m21s
[00:12:14.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:8:0)
[00:12:14.751] Build status: SCHEDULED
[00:12:14.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:9:0) after 1m1s
[00:13:15.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:9:0)
[00:13:15.794] Build status: SCHEDULED
[00:13:15.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:10:0) after 1m18s
[00:14:33.814] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:10:0)
[00:14:33.849] Build status: SCHEDULED
[00:14:33.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:11:0) after 5m50s
[00:20:23.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:11:0)
[00:20:23.991] Build status: SCHEDULED
[00:20:23.991] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:12:0) after 8m42s
[00:29:06.047] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:12:0)
[00:29:06.119] Build status: SCHEDULED
[00:29:06.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:13:0) after 6m4s
[00:35:10.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:13:0)
[00:35:10.541] Build status: SCHEDULED
[00:35:10.541] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:14:0) after 3m26s
[00:38:36.558] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:14:0)
[00:38:36.648] Build status: SCHEDULED
[00:38:36.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:15:0) after 2m19s
[00:40:44.040] Received PubSub notification, asking Buildbucket for the build status
[00:40:44.091] Build status: STARTED
[00:40:55.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:15:0)
[00:40:55.692] Build status: STARTED
[00:40:55.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:17:0) after 2m58s
[00:43:53.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:17:0)
[00:43:53.733] Build status: STARTED
[00:43:53.733] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:18:0) after 6m18s
[00:50:11.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:18:0)
[00:50:11.831] Build status: STARTED
[00:50:11.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:19:0) after 6m6s
[00:56:18.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:19:0)
[00:56:18.222] Build status: STARTED
[00:56:18.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:20:0) after 7m42s
[01:04:00.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:20:0)
[01:04:00.496] Build status: STARTED
[01:04:00.496] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:21:0) after 8m36s
[01:12:36.513] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:21:0)
[01:12:36.548] Build status: STARTED
[01:12:36.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:22:0) after 9m42s
[01:22:18.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:22:0)
[01:22:18.593] Build status: STARTED
[01:22:18.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:23:0) after 8m20s
[01:30:38.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:23:0)
[01:30:38.634] Build status: STARTED
[01:30:38.634] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:24:0) after 2m32s
[01:33:10.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:24:0)
[01:33:10.761] Build status: STARTED
[01:33:10.761] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:25:0) after 4m22s
[01:37:32.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:25:0)
[01:37:32.822] Build status: STARTED
[01:37:32.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:26:0) after 5m31s
[01:43:04.159] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:26:0)
[01:43:04.207] Build status: STARTED
[01:43:04.207] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:27:0) after 6m39s
[01:49:43.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:27:0)
[01:49:43.325] Build status: STARTED
[01:49:43.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:28:0) after 1m24s
[01:51:07.478] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:28:0)
[01:51:07.718] Build status: STARTED
[01:51:07.718] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:29:0) after 1m33s
[01:52:40.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:29:0)
[01:52:40.786] Build status: STARTED
[01:52:40.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:30:0) after 6m11s
[01:58:51.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:30:0)
[01:58:51.939] Build status: STARTED
[01:58:51.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:31:0) after 4m46s
[02:03:38.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:31:0)
[02:03:38.038] Build status: STARTED
[02:03:38.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:32:0) after 3m11s
[02:06:49.096] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:32:0)
[02:06:49.141] Build status: STARTED
[02:06:49.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:33:0) after 4m5s
[02:10:54.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:33:0)
[02:10:54.300] Build status: STARTED
[02:10:54.300] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:34:0) after 2m41s
[02:13:35.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:34:0)
[02:13:35.471] Build status: STARTED
[02:13:35.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8914438096708574496:35:0) after 8m6s
[02:15:18.392] Received PubSub notification, asking Buildbucket for the build status
[02:15:18.434] Build:
{
"id": "8749008034771969073",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2024-05-02T23:32:45.414200199Z",
"startTime": "2024-05-03T00:40:43.825374527Z",
"endTime": "2024-05-03T02:15:18.038287435Z",
"updateTime": "2024-05-03T02:15:18.038287435Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "351fd401bfccdb1450d6b5f4dc762c712461eccd",
"ref": "refs/heads/main"
}
}
}
[02:15:18.434] Invocation finished in 2h42m34.250818806s with status SUCCEEDED