[09:40:41.299] New invocation is queued and will start shortly
[09:40:42.549] Starting the invocation (attempt 1)
[09:40:42.588] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/ff9242b7f6711b5a28b49b8a1fae30918fc3acfa"
[09:40:42.588] Popped gitiles commit info from properties and tags
[09:40:42.588] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:40:42.588] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:40:42.589] Buildbucket request:
{
"requestId": "8871456885611120064",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8871456885611120064",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "5ecbcc9a9c73bde0a8d19d17bf6a9d00b2ddb968"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5ecbcc9a9c73bde0a8d19d17bf6a9d00b2ddb968",
"title": "5ecbcc9a9c73bde0a8d19d17bf6a9d00b2ddb968",
"url": "https://chromium.googlesource.com/v8/v8/+/5ecbcc9a9c73bde0a8d19d17bf6a9d00b2ddb968"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "b5bbc4cd23c8e58cbfb27500f8ef8853a34d585f"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b5bbc4cd23c8e58cbfb27500f8ef8853a34d585f",
"title": "b5bbc4cd23c8e58cbfb27500f8ef8853a34d585f",
"url": "https://chromium.googlesource.com/v8/v8/+/b5bbc4cd23c8e58cbfb27500f8ef8853a34d585f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1c319210895ab650d0b02b03f3e8358d76b1d2a1"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@1c319210895ab650d0b02b03f3e8358d76b1d2a1",
"title": "1c319210895ab650d0b02b03f3e8358d76b1d2a1",
"url": "https://chromium.googlesource.com/v8/v8/+/1c319210895ab650d0b02b03f3e8358d76b1d2a1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "cc0b1a9039baceec586203622633629671616643"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cc0b1a9039baceec586203622633629671616643",
"title": "cc0b1a9039baceec586203622633629671616643",
"url": "https://chromium.googlesource.com/v8/v8/+/cc0b1a9039baceec586203622633629671616643"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "ff9242b7f6711b5a28b49b8a1fae30918fc3acfa"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@ff9242b7f6711b5a28b49b8a1fae30918fc3acfa",
"title": "ff9242b7f6711b5a28b49b8a1fae30918fc3acfa",
"url": "https://chromium.googlesource.com/v8/v8/+/ff9242b7f6711b5a28b49b8a1fae30918fc3acfa"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "ff9242b7f6711b5a28b49b8a1fae30918fc3acfa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871456885611120064"
},
{
"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"
}
}
[09:40:43.219] Scheduled build:
{
"id": "8706026823485314177",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 16139,
"createdBy": "project:v8",
"createTime": "2025-08-20T09:40:42.706693825Z",
"updateTime": "2025-08-20T09:40:42.706693825Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "ff9242b7f6711b5a28b49b8a1fae30918fc3acfa",
"ref": "refs/heads/main"
}
}
}
[09:40:43.219] Task URL: https://cr-buildbucket.appspot.com/build/8706026823485314177
[09:40:43.219] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:2:0) after 2m2s
[09:40:44.263] Received PubSub notification, asking Buildbucket for the build status
[09:40:44.292] Build status: SCHEDULED
[09:42:20.028] Received PubSub notification, asking Buildbucket for the build status
[09:42:20.051] Build status: STARTED
[09:42:45.832] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:2:0)
[09:42:45.865] Build status: STARTED
[09:42:45.866] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:5:0) after 8m54s
[09:51:40.102] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:5:0)
[09:51:40.152] Build status: STARTED
[09:51:40.153] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:6:0) after 4m14s
[09:55:54.170] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:6:0)
[09:55:54.190] Build status: STARTED
[09:55:54.190] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:7:0) after 3m9s
[09:59:03.241] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:7:0)
[09:59:03.260] Build status: STARTED
[09:59:03.260] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:8:0) after 5m0s
[10:04:03.524] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:8:0)
[10:04:03.546] Build status: STARTED
[10:04:03.546] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:9:0) after 9m30s
[10:13:34.186] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:9:0)
[10:13:34.242] Build status: STARTED
[10:13:34.242] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:10:0) after 6m8s
[10:19:42.256] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:10:0)
[10:19:42.328] Build status: STARTED
[10:19:42.328] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:11:0) after 4m29s
[10:24:11.654] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:11:0)
[10:24:11.680] Build status: STARTED
[10:24:11.680] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:12:0) after 1m38s
[10:25:49.784] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:12:0)
[10:25:49.807] Build status: STARTED
[10:25:49.807] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:13:0) after 7m32s
[10:33:21.850] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:13:0)
[10:33:21.871] Build status: STARTED
[10:33:21.871] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:14:0) after 5m49s
[10:39:10.941] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:14:0)
[10:39:10.956] Build status: STARTED
[10:39:10.956] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:15:0) after 4m3s
[10:43:13.977] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:15:0)
[10:43:13.997] Build status: STARTED
[10:43:13.997] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:16:0) after 2m44s
[10:45:58.062] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:16:0)
[10:45:58.081] Build status: STARTED
[10:45:58.082] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:17:0) after 7m49s
[10:53:47.149] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:17:0)
[10:53:47.165] Build status: STARTED
[10:53:47.165] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:18:0) after 1m10s
[10:54:57.192] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:18:0)
[10:54:57.255] Build status: STARTED
[10:54:57.255] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:19:0) after 9m50s
[11:04:47.278] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:19:0)
[11:04:47.307] Build status: STARTED
[11:04:47.307] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:20:0) after 4m3s
[11:08:50.374] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:20:0)
[11:08:50.441] Build status: STARTED
[11:08:50.441] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:21:0) after 2m33s
[11:11:23.457] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:21:0)
[11:11:23.475] Build status: STARTED
[11:11:23.475] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:22:0) after 5m27s
[11:16:50.491] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:22:0)
[11:16:50.510] Build status: STARTED
[11:16:50.510] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:23:0) after 5m26s
[11:22:16.533] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:23:0)
[11:22:16.551] Build status: STARTED
[11:22:16.551] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:24:0) after 7m46s
[11:30:02.567] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:24:0)
[11:30:02.590] Build status: STARTED
[11:30:02.590] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:25:0) after 7m53s
[11:37:55.693] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:25:0)
[11:37:55.719] Build status: STARTED
[11:37:55.719] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:26:0) after 2m37s
[11:40:32.809] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:26:0)
[11:40:32.825] Build status: STARTED
[11:40:32.825] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:27:0) after 6m15s
[11:46:48.906] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:27:0)
[11:46:48.923] Build status: STARTED
[11:46:48.923] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:28:0) after 3m42s
[11:50:30.926] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:28:0)
[11:50:30.947] Build status: STARTED
[11:50:30.947] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:29:0) after 3m41s
[11:54:12.066] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:29:0)
[11:54:12.086] Build status: STARTED
[11:54:12.086] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:30:0) after 2m41s
[11:56:53.107] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:30:0)
[11:56:53.126] Build status: STARTED
[11:56:53.126] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:31:0) after 8m5s
[12:04:58.152] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:31:0)
[12:04:58.188] Build status: STARTED
[12:04:58.188] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:32:0) after 3m49s
[12:08:47.270] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:32:0)
[12:08:47.325] Build status: STARTED
[12:08:47.325] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:33:0) after 4m18s
[12:13:05.336] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:33:0)
[12:13:05.357] Build status: STARTED
[12:13:05.357] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:34:0) after 1m10s
[12:14:15.465] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:34:0)
[12:14:15.493] Build status: STARTED
[12:14:15.493] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:35:0) after 2m22s
[12:16:37.584] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:35:0)
[12:16:37.608] Build status: STARTED
[12:16:37.608] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:36:0) after 3m35s
[12:20:12.659] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:36:0)
[12:20:12.676] Build status: STARTED
[12:20:12.676] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:37:0) after 9m34s
[12:29:46.691] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:37:0)
[12:29:46.706] Build status: STARTED
[12:29:46.706] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:38:0) after 8m57s
[12:38:43.925] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:38:0)
[12:38:43.944] Build status: STARTED
[12:38:43.944] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:39:0) after 8m37s
[12:47:21.178] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8871456885611120064:39:0)
[12:47:21.220] Build:
{
"id": "8706026823485314177",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 16139,
"createdBy": "project:v8",
"createTime": "2025-08-20T09:40:42.706693825Z",
"startTime": "2025-08-20T09:42:18.249717179Z",
"endTime": "2025-08-20T12:46:50.820671829Z",
"updateTime": "2025-08-20T12:46:50.820671829Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "ff9242b7f6711b5a28b49b8a1fae30918fc3acfa",
"ref": "refs/heads/main"
}
}
}
[12:47:21.220] Invocation finished in 3h6m39.924762955s with status SUCCEEDED