[16:46:53.404] New invocation is queued and will start shortly
[16:46:54.801] Starting the invocation (attempt 1)
[16:46:54.827] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/edc542f405fcb6d19b4f1cae00ba35c1929b8684"
[16:46:54.827] Popped gitiles commit info from properties and tags
[16:46:54.828] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:46:54.828] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:46:54.828] Buildbucket request:
{
"requestId": "8898790355171600176",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898790355171600176",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "751593590f9a3cd53cc62c04dfd62621ba2cb110"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@751593590f9a3cd53cc62c04dfd62621ba2cb110",
"title": "751593590f9a3cd53cc62c04dfd62621ba2cb110",
"url": "https://chromium.googlesource.com/v8/v8/+/751593590f9a3cd53cc62c04dfd62621ba2cb110"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "46eaf4d11ea2229667a25b3cb441a305bf111885"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@46eaf4d11ea2229667a25b3cb441a305bf111885",
"title": "46eaf4d11ea2229667a25b3cb441a305bf111885",
"url": "https://chromium.googlesource.com/v8/v8/+/46eaf4d11ea2229667a25b3cb441a305bf111885"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "e0b210e1a696164016c3b230abac6aaa095028e6"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@e0b210e1a696164016c3b230abac6aaa095028e6",
"title": "e0b210e1a696164016c3b230abac6aaa095028e6",
"url": "https://chromium.googlesource.com/v8/v8/+/e0b210e1a696164016c3b230abac6aaa095028e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c1c4bd89633a6f5e0a840bdb3a6c1e45af2267dc"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@c1c4bd89633a6f5e0a840bdb3a6c1e45af2267dc",
"title": "c1c4bd89633a6f5e0a840bdb3a6c1e45af2267dc",
"url": "https://chromium.googlesource.com/v8/v8/+/c1c4bd89633a6f5e0a840bdb3a6c1e45af2267dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "5a9f02a91b2b114af6eebd81dac9d8967f9b807f"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5a9f02a91b2b114af6eebd81dac9d8967f9b807f",
"title": "5a9f02a91b2b114af6eebd81dac9d8967f9b807f",
"url": "https://chromium.googlesource.com/v8/v8/+/5a9f02a91b2b114af6eebd81dac9d8967f9b807f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "cbc2598000c0163994ad9da6a2bab2ca513a2504"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cbc2598000c0163994ad9da6a2bab2ca513a2504",
"title": "cbc2598000c0163994ad9da6a2bab2ca513a2504",
"url": "https://chromium.googlesource.com/v8/v8/+/cbc2598000c0163994ad9da6a2bab2ca513a2504"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7e66d3039ccb300e44f65c102416cd078982e755"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@7e66d3039ccb300e44f65c102416cd078982e755",
"title": "7e66d3039ccb300e44f65c102416cd078982e755",
"url": "https://chromium.googlesource.com/v8/v8/+/7e66d3039ccb300e44f65c102416cd078982e755"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "33656c5e949bfcf4b2e3e2bffa06f123a23ba06e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@33656c5e949bfcf4b2e3e2bffa06f123a23ba06e",
"title": "33656c5e949bfcf4b2e3e2bffa06f123a23ba06e",
"url": "https://chromium.googlesource.com/v8/v8/+/33656c5e949bfcf4b2e3e2bffa06f123a23ba06e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "edc542f405fcb6d19b4f1cae00ba35c1929b8684"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@edc542f405fcb6d19b4f1cae00ba35c1929b8684",
"title": "edc542f405fcb6d19b4f1cae00ba35c1929b8684",
"url": "https://chromium.googlesource.com/v8/v8/+/edc542f405fcb6d19b4f1cae00ba35c1929b8684"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "edc542f405fcb6d19b4f1cae00ba35c1929b8684",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898790355171600176"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Blink Win"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:46:55.380] Scheduled build:
{
"id": "8733360292933308593",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 14763,
"createdBy": "project:v8",
"createTime": "2024-10-22T16:46:54.915865063Z",
"updateTime": "2024-10-22T16:46:54.915865063Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "edc542f405fcb6d19b4f1cae00ba35c1929b8684",
"ref": "refs/heads/main"
}
}
}
[16:46:55.380] Task URL: https://cr-buildbucket.appspot.com/build/8733360292933308593
[16:46:55.380] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:2:0) after 3m7s
[16:46:56.388] Received PubSub notification, asking Buildbucket for the build status
[16:46:56.406] Build status: SCHEDULED
[16:47:04.917] Received PubSub notification, asking Buildbucket for the build status
[16:47:04.966] Build status: STARTED
[16:50:02.416] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:2:0)
[16:50:02.474] Build status: STARTED
[16:50:02.474] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:5:0) after 4m19s
[16:54:21.500] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:5:0)
[16:54:21.525] Build status: STARTED
[16:54:21.525] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:6:0) after 1m50s
[16:56:11.594] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:6:0)
[16:56:11.614] Build status: STARTED
[16:56:11.614] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:7:0) after 3m5s
[16:59:16.634] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:7:0)
[16:59:16.651] Build status: STARTED
[16:59:16.651] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:8:0) after 6m23s
[17:05:39.894] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:8:0)
[17:05:39.922] Build status: STARTED
[17:05:39.922] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:9:0) after 7m37s
[17:13:16.940] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:9:0)
[17:13:16.959] Build status: STARTED
[17:13:16.959] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:10:0) after 7m27s
[17:20:43.997] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:10:0)
[17:20:44.015] Build status: STARTED
[17:20:44.015] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:11:0) after 8m54s
[17:29:38.150] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:11:0)
[17:29:38.167] Build status: STARTED
[17:29:38.167] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:12:0) after 1m9s
[17:30:47.177] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:12:0)
[17:30:47.198] Build status: STARTED
[17:30:47.198] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:13:0) after 7m15s
[17:38:02.178] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:13:0)
[17:38:02.196] Build status: STARTED
[17:38:02.196] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:14:0) after 4m42s
[17:42:44.212] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:14:0)
[17:42:44.244] Build status: STARTED
[17:42:44.244] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:15:0) after 3m18s
[17:46:02.321] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:15:0)
[17:46:02.342] Build status: STARTED
[17:46:02.342] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:16:0) after 1m4s
[17:47:06.457] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:16:0)
[17:47:06.475] Build status: STARTED
[17:47:06.475] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:17:0) after 4m49s
[17:51:55.552] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:17:0)
[17:51:55.576] Build status: STARTED
[17:51:55.577] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:18:0) after 5m30s
[17:57:25.591] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:18:0)
[17:57:25.620] Build status: STARTED
[17:57:25.620] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:19:0) after 5m34s
[18:02:59.712] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:19:0)
[18:02:59.731] Build status: STARTED
[18:02:59.731] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:20:0) after 1m15s
[18:04:14.839] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:20:0)
[18:04:14.858] Build status: STARTED
[18:04:14.858] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:21:0) after 3m30s
[18:07:44.953] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:21:0)
[18:07:44.971] Build status: STARTED
[18:07:44.971] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:22:0) after 3m42s
[18:11:27.021] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:22:0)
[18:11:27.043] Build status: STARTED
[18:11:27.043] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:23:0) after 9m3s
[18:20:30.059] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:23:0)
[18:20:30.079] Build status: STARTED
[18:20:30.079] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:24:0) after 3m50s
[18:24:20.135] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:24:0)
[18:24:20.166] Build status: STARTED
[18:24:20.166] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:25:0) after 3m6s
[18:27:26.184] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:25:0)
[18:27:26.212] Build status: STARTED
[18:27:26.212] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:26:0) after 6m39s
[18:34:05.251] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:26:0)
[18:34:05.281] Build status: STARTED
[18:34:05.281] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:27:0) after 2m21s
[18:36:26.313] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:27:0)
[18:36:26.347] Build status: STARTED
[18:36:26.347] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:28:0) after 2m3s
[18:38:29.418] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:28:0)
[18:38:29.469] Build status: STARTED
[18:38:29.469] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:29:0) after 3m45s
[18:42:14.519] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:29:0)
[18:42:14.546] Build status: STARTED
[18:42:14.546] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:30:0) after 2m17s
[18:44:31.535] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:30:0)
[18:44:31.555] Build status: STARTED
[18:44:31.555] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:31:0) after 6m35s
[18:51:06.569] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:31:0)
[18:51:06.594] Build status: STARTED
[18:51:06.594] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:32:0) after 8m16s
[18:59:22.656] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:32:0)
[18:59:22.676] Build status: STARTED
[18:59:22.676] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:33:0) after 5m30s
[19:04:52.711] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:33:0)
[19:04:52.728] Build status: STARTED
[19:04:52.728] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:34:0) after 1m8s
[19:06:00.750] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:34:0)
[19:06:00.769] Build status: STARTED
[19:06:00.769] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:35:0) after 3m11s
[19:09:11.836] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:35:0)
[19:09:11.852] Build status: STARTED
[19:09:11.852] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:36:0) after 6m12s
[19:15:23.880] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:36:0)
[19:15:23.898] Build status: STARTED
[19:15:23.898] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:37:0) after 1m30s
[19:16:54.013] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:37:0)
[19:16:54.046] Build status: STARTED
[19:16:54.046] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:38:0) after 2m12s
[19:19:06.068] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:38:0)
[19:19:06.088] Build status: STARTED
[19:19:06.088] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:39:0) after 4m14s
[19:23:20.107] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:39:0)
[19:23:20.139] Build status: STARTED
[19:23:20.139] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8898790355171600176:40:0) after 9m31s
[19:23:55.843] Received PubSub notification, asking Buildbucket for the build status
[19:23:55.860] Build:
{
"id": "8733360292933308593",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 14763,
"createdBy": "project:v8",
"createTime": "2024-10-22T16:46:54.915865063Z",
"startTime": "2024-10-22T16:47:04.636659470Z",
"endTime": "2024-10-22T19:23:55.534145495Z",
"updateTime": "2024-10-22T19:23:55.534145495Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "edc542f405fcb6d19b4f1cae00ba35c1929b8684",
"ref": "refs/heads/main"
}
}
}
[19:23:55.860] Invocation finished in 2h37m2.463299825s with status SUCCEEDED