[22:40:36.499] New invocation is queued and will start shortly
[22:40:37.647] Starting the invocation (attempt 1)
[22:40:37.678] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2a590b5d5c6ecad199e2e5aca1c317140672e453"
[22:40:37.678] Popped gitiles commit info from properties and tags
[22:40:37.678] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:40:37.678] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:40:37.679] Buildbucket request:
{
"requestId": "8931745396915371168",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8931745396915371168",
"job": "emscripten-releases/win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/emscripten-releases",
"revision": "2a590b5d5c6ecad199e2e5aca1c317140672e453"
},
"id": "https://chromium.googlesource.com/emscripten-releases/+/refs/heads/main@2a590b5d5c6ecad199e2e5aca1c317140672e453",
"title": "2a590b5d5c6ecad199e2e5aca1c317140672e453",
"url": "https://chromium.googlesource.com/emscripten-releases/+/2a590b5d5c6ecad199e2e5aca1c317140672e453"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "2a590b5d5c6ecad199e2e5aca1c317140672e453",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931745396915371168"
},
{
"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"
}
}
[22:40:38.350] Scheduled build:
{
"id": "8766315334863229569",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-10-24T22:40:37.830358313Z",
"updateTime": "2023-10-24T22:40:37.830358313Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "2a590b5d5c6ecad199e2e5aca1c317140672e453",
"ref": "refs/heads/main"
}
}
}
[22:40:38.350] Task URL: https://cr-buildbucket.appspot.com/build/8766315334863229569
[22:40:38.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:2:0) after 7m9s
[22:40:39.412] Received PubSub notification, asking Buildbucket for the build status
[22:40:39.438] Build status: SCHEDULED
[22:47:47.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:2:0)
[22:47:47.399] Build status: SCHEDULED
[22:47:47.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:4:0) after 9m27s
[22:57:14.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:4:0)
[22:57:14.434] Build status: SCHEDULED
[22:57:14.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:5:0) after 6m36s
[23:03:51.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:5:0)
[23:03:51.114] Build status: SCHEDULED
[23:03:51.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:6:0) after 8m39s
[23:12:30.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:6:0)
[23:12:30.326] Build status: SCHEDULED
[23:12:30.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:7:0) after 4m50s
[23:17:20.931] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:7:0)
[23:17:20.948] Build status: SCHEDULED
[23:17:20.948] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:8:0) after 6m10s
[23:23:31.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:8:0)
[23:23:31.259] Build status: SCHEDULED
[23:23:31.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:9:0) after 7m6s
[23:30:37.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:9:0)
[23:30:37.852] Build status: SCHEDULED
[23:30:37.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:10:0) after 5m29s
[23:36:07.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:10:0)
[23:36:07.242] Build status: SCHEDULED
[23:36:07.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:11:0) after 5m45s
[23:41:52.491] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:11:0)
[23:41:52.552] Build status: SCHEDULED
[23:41:52.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:12:0) after 9m4s
[23:50:56.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:12:0)
[23:50:56.804] Build status: SCHEDULED
[23:50:56.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:13:0) after 3m1s
[23:53:57.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:13:0)
[23:53:57.949] Build status: SCHEDULED
[23:53:57.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:14:0) after 8m55s
[00:02:53.262] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:14:0)
[00:02:53.286] Build status: SCHEDULED
[00:02:53.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:15:0) after 9m21s
[00:12:14.356] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:15:0)
[00:12:14.388] Build status: SCHEDULED
[00:12:14.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:16:0) after 4m18s
[00:16:32.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:16:0)
[00:16:32.440] Build status: SCHEDULED
[00:16:32.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:17:0) after 9m44s
[00:26:16.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:17:0)
[00:26:16.807] Build status: SCHEDULED
[00:26:16.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:18:0) after 5m39s
[00:31:55.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:18:0)
[00:31:56.019] Build status: SCHEDULED
[00:31:56.019] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:19:0) after 4m46s
[00:34:00.359] Received PubSub notification, asking Buildbucket for the build status
[00:34:00.393] Build status: STARTED
[00:36:42.032] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:19:0)
[00:36:42.110] Build status: STARTED
[00:36:42.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:21:0) after 1m10s
[00:37:52.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:21:0)
[00:37:52.310] Build status: STARTED
[00:37:52.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:22:0) after 5m12s
[00:43:04.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:22:0)
[00:43:04.371] Build status: STARTED
[00:43:04.371] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:23:0) after 1m53s
[00:44:57.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:23:0)
[00:44:57.431] Build status: STARTED
[00:44:57.431] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:24:0) after 7m36s
[00:52:33.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:24:0)
[00:52:33.569] Build status: STARTED
[00:52:33.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:25:0) after 3m56s
[00:56:29.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:25:0)
[00:56:29.859] Build status: STARTED
[00:56:29.859] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:26:0) after 3m2s
[00:59:31.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:26:0)
[00:59:31.905] Build status: STARTED
[00:59:31.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:27:0) after 5m25s
[01:04:56.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:27:0)
[01:04:57.019] Build status: STARTED
[01:04:57.019] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:28:0) after 4m36s
[01:09:33.054] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:28:0)
[01:09:33.077] Build status: STARTED
[01:09:33.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:29:0) after 7m3s
[01:16:36.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:29:0)
[01:16:36.226] Build status: STARTED
[01:16:36.226] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:30:0) after 9m36s
[01:26:12.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:30:0)
[01:26:12.267] Build status: STARTED
[01:26:12.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:31:0) after 9m29s
[01:35:41.478] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:31:0)
[01:35:41.504] Build status: STARTED
[01:35:41.504] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:32:0) after 6m27s
[01:42:08.708] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:32:0)
[01:42:08.755] Build status: STARTED
[01:42:08.755] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:33:0) after 9m2s
[01:51:10.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:33:0)
[01:51:10.833] Build status: STARTED
[01:51:10.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:34:0) after 9m13s
[02:00:23.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:34:0)
[02:00:23.905] Build status: STARTED
[02:00:23.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:8931745396915371168:35:0) after 9m24s
[02:07:32.164] Received PubSub notification, asking Buildbucket for the build status
[02:07:32.234] Build:
{
"id": "8766315334863229569",
"builder": {
"project": "emscripten-releases",
"bucket": "ci",
"builder": "win"
},
"createdBy": "project:emscripten-releases",
"createTime": "2023-10-24T22:40:37.830358313Z",
"startTime": "2023-10-25T00:33:59.891917Z",
"endTime": "2023-10-25T02:07:31.923067240Z",
"updateTime": "2023-10-25T02:07:31.923067240Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "emscripten-releases",
"id": "2a590b5d5c6ecad199e2e5aca1c317140672e453",
"ref": "refs/heads/main"
}
}
}
[02:07:32.234] Invocation finished in 3h26m55.74438632s with status SUCCEEDED