[01:26:47.581] New invocation is queued and will start shortly
[01:26:48.644] Starting the invocation (attempt 1)
[01:26:48.670] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/aa59ed4b510b292d4b81e03ad98859afcfd335b0"
[01:26:48.670] Popped gitiles commit info from properties and tags
[01:26:48.670] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:26:48.670] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:26:48.671] Buildbucket request:
{
"requestId": "8927658077995130112",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8927658077995130112",
"job": "emscripten-releases/win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "aa59ed4b510b292d4b81e03ad98859afcfd335b0"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@aa59ed4b510b292d4b81e03ad98859afcfd335b0",
"title": "aa59ed4b510b292d4b81e03ad98859afcfd335b0",
"url": "https://chromium.googlesource.com/emscripten-releases/+/aa59ed4b510b292d4b81e03ad98859afcfd335b0"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "aa59ed4b510b292d4b81e03ad98859afcfd335b0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8927658077995130112"
},
{
"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"
}
}
[01:26:49.119] Scheduled build:
{
"id": "8762228016123454465",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-12-09T01:26:48.735715791Z",
"updateTime": "2023-12-09T01:26:48.735715791Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "aa59ed4b510b292d4b81e03ad98859afcfd335b0",
"ref": "refs/heads/main"
}
}
}
[01:26:49.119] Task URL: https://cr-buildbucket.appspot.com/build/8762228016123454465
[01:26:49.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:2:0) after 7m17s
[01:26:50.067] Received PubSub notification, asking Buildbucket for the build status
[01:26:50.159] Build status: SCHEDULED
[01:34:06.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:2:0)
[01:34:06.182] Build status: SCHEDULED
[01:34:06.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:4:0) after 5m50s
[01:39:56.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:4:0)
[01:39:56.639] Build status: SCHEDULED
[01:39:56.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:5:0) after 2m37s
[01:42:33.773] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:5:0)
[01:42:33.806] Build status: SCHEDULED
[01:42:33.806] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:6:0) after 6m44s
[01:49:17.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:6:0)
[01:49:17.863] Build status: SCHEDULED
[01:49:17.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:7:0) after 6m38s
[01:55:56.140] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:7:0)
[01:55:56.309] Build status: SCHEDULED
[01:55:56.309] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:8:0) after 2m21s
[01:58:17.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:8:0)
[01:58:17.474] Build status: SCHEDULED
[01:58:17.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:9:0) after 5m34s
[02:03:51.693] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:9:0)
[02:03:51.718] Build status: SCHEDULED
[02:03:51.718] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:10:0) after 7m43s
[02:11:34.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:10:0)
[02:11:34.815] Build status: SCHEDULED
[02:11:34.816] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:11:0) after 1m45s
[02:13:19.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:11:0)
[02:13:19.938] Build status: SCHEDULED
[02:13:19.938] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:12:0) after 2m10s
[02:15:30.073] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:12:0)
[02:15:30.091] Build status: SCHEDULED
[02:15:30.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:13:0) after 3m36s
[02:19:06.370] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:13:0)
[02:19:06.392] Build status: SCHEDULED
[02:19:06.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:14:0) after 7m32s
[02:26:38.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:14:0)
[02:26:38.486] Build status: SCHEDULED
[02:26:38.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:15:0) after 1m41s
[02:28:19.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:15:0)
[02:28:19.575] Build status: SCHEDULED
[02:28:19.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:16:0) after 5m16s
[02:33:35.634] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:16:0)
[02:33:35.677] Build status: SCHEDULED
[02:33:35.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:17:0) after 5m33s
[02:39:08.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:17:0)
[02:39:08.720] Build status: SCHEDULED
[02:39:08.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:18:0) after 5m13s
[02:44:21.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:18:0)
[02:44:21.766] Build status: SCHEDULED
[02:44:21.766] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:19:0) after 7m0s
[02:47:03.235] Received PubSub notification, asking Buildbucket for the build status
[02:47:03.270] Build status: STARTED
[02:51:21.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:19:0)
[02:51:21.994] Build status: STARTED
[02:51:21.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:21:0) after 2m28s
[02:53:50.162] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:21:0)
[02:53:50.178] Build status: STARTED
[02:53:50.178] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:22:0) after 7m26s
[03:01:16.319] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:22:0)
[03:01:16.362] Build status: STARTED
[03:01:16.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:23:0) after 7m35s
[03:08:51.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:23:0)
[03:08:51.420] Build status: STARTED
[03:08:51.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:24:0) after 6m7s
[03:14:58.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:24:0)
[03:14:58.767] Build status: STARTED
[03:14:58.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:25:0) after 4m20s
[03:19:18.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:25:0)
[03:19:18.841] Build status: STARTED
[03:19:18.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:26:0) after 4m49s
[03:24:07.845] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:26:0)
[03:24:08.017] Build status: STARTED
[03:24:08.017] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:27:0) after 2m3s
[03:26:11.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:27:0)
[03:26:11.086] Build status: STARTED
[03:26:11.086] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:28:0) after 5m10s
[03:31:21.340] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:28:0)
[03:31:21.369] Build status: STARTED
[03:31:21.369] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:29:0) after 1m23s
[03:32:44.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:29:0)
[03:32:44.741] Build status: STARTED
[03:32:44.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:30:0) after 9m37s
[03:42:21.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:30:0)
[03:42:21.933] Build status: STARTED
[03:42:21.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:31:0) after 5m30s
[03:47:52.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:31:0)
[03:47:52.173] Build status: STARTED
[03:47:52.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:32:0) after 1m43s
[03:49:36.389] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:32:0)
[03:49:36.407] Build status: STARTED
[03:49:36.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:33:0) after 3m29s
[03:53:05.950] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:33:0)
[03:53:05.972] Build status: STARTED
[03:53:05.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:34:0) after 4m30s
[03:57:35.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:34:0)
[03:57:36.080] Build status: STARTED
[03:57:36.080] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:35:0) after 6m34s
[04:04:10.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:35:0)
[04:04:10.425] Build status: STARTED
[04:04:10.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:36:0) after 4m5s
[04:08:15.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:36:0)
[04:08:15.843] Build status: STARTED
[04:08:15.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:37:0) after 4m7s
[04:12:22.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:37:0)
[04:12:22.888] Build status: STARTED
[04:12:22.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:38:0) after 6m1s
[04:18:23.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:38:0)
[04:18:23.962] Build status: STARTED
[04:18:23.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:39:0) after 2m12s
[04:20:35.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:39:0)
[04:20:36.060] Build status: STARTED
[04:20:36.060] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:40:0) after 6m14s
[04:26:50.081] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:40:0)
[04:26:50.102] Build status: STARTED
[04:26:50.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:41:0) after 7m48s
[04:34:38.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:41:0)
[04:34:38.160] Build status: STARTED
[04:34:38.160] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8927658077995130112:42:0) after 3m1s
[04:35:14.572] Received PubSub notification, asking Buildbucket for the build status
[04:35:14.643] Build:
{
"id": "8762228016123454465",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-12-09T01:26:48.735715791Z",
"startTime": "2023-12-09T02:47:02.381149Z",
"endTime": "2023-12-09T04:35:13.581750Z",
"updateTime": "2023-12-09T04:35:13.581750Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "aa59ed4b510b292d4b81e03ad98859afcfd335b0",
"ref": "refs/heads/main"
}
}
}
[04:35:14.643] Invocation finished in 3h8m27.075720474s with status SUCCEEDED