[17:01:12.361] New invocation is queued and will start shortly
[17:01:13.553] Starting the invocation (attempt 1)
[17:01:13.577] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/f5de0bc8445fa7b74de0f16cbfda3208ab60644b"
[17:01:13.577] Popped gitiles commit info from properties and tags
[17:01:13.577] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:01:13.578] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:01:13.578] Buildbucket request:
{
"requestId": "8863366040623716016",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8863366040623716016",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "024139f3211c9f24b9538d7cb240791cd16b063a"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@024139f3211c9f24b9538d7cb240791cd16b063a",
"title": "024139f3211c9f24b9538d7cb240791cd16b063a",
"url": "https://chromium.googlesource.com/v8/v8/+/024139f3211c9f24b9538d7cb240791cd16b063a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "380c6165edc8f8f52450bc0c6b7496386f65b5a4"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@380c6165edc8f8f52450bc0c6b7496386f65b5a4",
"title": "380c6165edc8f8f52450bc0c6b7496386f65b5a4",
"url": "https://chromium.googlesource.com/v8/v8/+/380c6165edc8f8f52450bc0c6b7496386f65b5a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "5c63f5680cb14eea38c63ba6c11f6e9c1fcf2f18"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5c63f5680cb14eea38c63ba6c11f6e9c1fcf2f18",
"title": "5c63f5680cb14eea38c63ba6c11f6e9c1fcf2f18",
"url": "https://chromium.googlesource.com/v8/v8/+/5c63f5680cb14eea38c63ba6c11f6e9c1fcf2f18"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "d7f988d5f0f6cf4a97604e38a7b386477688ba1d"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@d7f988d5f0f6cf4a97604e38a7b386477688ba1d",
"title": "d7f988d5f0f6cf4a97604e38a7b386477688ba1d",
"url": "https://chromium.googlesource.com/v8/v8/+/d7f988d5f0f6cf4a97604e38a7b386477688ba1d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "647d53e3e1da7d3c12e1fb1819c78a164e0b1720"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@647d53e3e1da7d3c12e1fb1819c78a164e0b1720",
"title": "647d53e3e1da7d3c12e1fb1819c78a164e0b1720",
"url": "https://chromium.googlesource.com/v8/v8/+/647d53e3e1da7d3c12e1fb1819c78a164e0b1720"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "e471736ad13ffd80081a51c767b196d923f7bcfa"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@e471736ad13ffd80081a51c767b196d923f7bcfa",
"title": "e471736ad13ffd80081a51c767b196d923f7bcfa",
"url": "https://chromium.googlesource.com/v8/v8/+/e471736ad13ffd80081a51c767b196d923f7bcfa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "b25cd62c7ba2a9c1fa76906d135832ae09d0a674"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b25cd62c7ba2a9c1fa76906d135832ae09d0a674",
"title": "b25cd62c7ba2a9c1fa76906d135832ae09d0a674",
"url": "https://chromium.googlesource.com/v8/v8/+/b25cd62c7ba2a9c1fa76906d135832ae09d0a674"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "cd2484a43762f5d731fe0b87c1727697085b6ed1"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cd2484a43762f5d731fe0b87c1727697085b6ed1",
"title": "cd2484a43762f5d731fe0b87c1727697085b6ed1",
"url": "https://chromium.googlesource.com/v8/v8/+/cd2484a43762f5d731fe0b87c1727697085b6ed1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2d9bb826bc8a5e028d29acda998be673a176e7c7"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@2d9bb826bc8a5e028d29acda998be673a176e7c7",
"title": "2d9bb826bc8a5e028d29acda998be673a176e7c7",
"url": "https://chromium.googlesource.com/v8/v8/+/2d9bb826bc8a5e028d29acda998be673a176e7c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "56670bd5452ada44e1542fb6051e046431c40b68"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@56670bd5452ada44e1542fb6051e046431c40b68",
"title": "56670bd5452ada44e1542fb6051e046431c40b68",
"url": "https://chromium.googlesource.com/v8/v8/+/56670bd5452ada44e1542fb6051e046431c40b68"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8ab8f91ed2d2757ae49b4b03b41dfa60d9b31ad8"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8ab8f91ed2d2757ae49b4b03b41dfa60d9b31ad8",
"title": "8ab8f91ed2d2757ae49b4b03b41dfa60d9b31ad8",
"url": "https://chromium.googlesource.com/v8/v8/+/8ab8f91ed2d2757ae49b4b03b41dfa60d9b31ad8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f5de0bc8445fa7b74de0f16cbfda3208ab60644b"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f5de0bc8445fa7b74de0f16cbfda3208ab60644b",
"title": "f5de0bc8445fa7b74de0f16cbfda3208ab60644b",
"url": "https://chromium.googlesource.com/v8/v8/+/f5de0bc8445fa7b74de0f16cbfda3208ab60644b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f5de0bc8445fa7b74de0f16cbfda3208ab60644b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8863366040623716016"
},
{
"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"
}
}
[17:01:14.107] Scheduled build:
{
"id": "8697935978630206993",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 16509,
"createdBy": "project:v8",
"createTime": "2025-11-17T17:01:13.642552350Z",
"updateTime": "2025-11-17T17:01:13.642552350Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f5de0bc8445fa7b74de0f16cbfda3208ab60644b",
"ref": "refs/heads/main"
}
}
}
[17:01:14.107] Task URL: https://cr-buildbucket.appspot.com/build/8697935978630206993
[17:01:14.107] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:2:0) after 7m7s
[17:01:15.083] Received PubSub notification, asking Buildbucket for the build status
[17:01:15.104] Build status: SCHEDULED
[17:01:23.894] Received PubSub notification, asking Buildbucket for the build status
[17:01:23.915] Build status: STARTED
[17:08:21.133] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:2:0)
[17:08:21.154] Build status: STARTED
[17:08:21.154] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:5:0) after 4m42s
[17:13:03.130] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:5:0)
[17:13:03.155] Build status: STARTED
[17:13:03.155] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:6:0) after 3m21s
[17:16:24.226] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:6:0)
[17:16:24.298] Build status: STARTED
[17:16:24.298] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:7:0) after 1m3s
[17:17:27.363] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:7:0)
[17:17:27.385] Build status: STARTED
[17:17:27.385] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:8:0) after 9m53s
[17:27:20.439] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:8:0)
[17:27:20.455] Build status: STARTED
[17:27:20.455] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:9:0) after 5m51s
[17:33:11.517] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:9:0)
[17:33:11.558] Build status: STARTED
[17:33:11.558] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:10:0) after 1m58s
[17:35:09.576] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:10:0)
[17:35:09.654] Build status: STARTED
[17:35:09.654] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:11:0) after 8m58s
[17:44:07.753] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:11:0)
[17:44:07.776] Build status: STARTED
[17:44:07.776] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:12:0) after 8m39s
[17:52:46.773] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:12:0)
[17:52:46.789] Build status: STARTED
[17:52:46.790] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:13:0) after 4m33s
[17:57:19.855] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:13:0)
[17:57:20.069] Build status: STARTED
[17:57:20.069] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:14:0) after 6m15s
[18:03:35.081] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:14:0)
[18:03:35.118] Build status: STARTED
[18:03:35.118] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:15:0) after 7m34s
[18:11:09.124] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:15:0)
[18:11:09.139] Build status: STARTED
[18:11:09.139] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:16:0) after 3m58s
[18:15:07.310] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:16:0)
[18:15:07.331] Build status: STARTED
[18:15:07.331] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:17:0) after 6m51s
[18:21:58.350] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:17:0)
[18:21:58.374] Build status: STARTED
[18:21:58.374] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:18:0) after 9m19s
[18:31:17.424] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:18:0)
[18:31:17.445] Build status: STARTED
[18:31:17.445] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:19:0) after 5m53s
[18:37:10.534] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:19:0)
[18:37:10.548] Build status: STARTED
[18:37:10.548] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:20:0) after 5m17s
[18:42:27.616] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:20:0)
[18:42:27.633] Build status: STARTED
[18:42:27.634] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:21:0) after 3m31s
[18:45:58.673] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:21:0)
[18:45:58.715] Build status: STARTED
[18:45:58.715] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:22:0) after 7m10s
[18:53:08.727] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:22:0)
[18:53:08.779] Build status: STARTED
[18:53:08.779] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:23:0) after 7m59s
[19:01:07.793] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:23:0)
[19:01:07.859] Build status: STARTED
[19:01:07.859] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:24:0) after 6m54s
[19:08:01.855] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:24:0)
[19:08:01.925] Build status: STARTED
[19:08:01.925] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:25:0) after 2m12s
[19:10:13.939] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:25:0)
[19:10:13.956] Build status: STARTED
[19:10:13.956] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:26:0) after 3m55s
[19:14:08.957] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:26:0)
[19:14:08.993] Build status: STARTED
[19:14:08.993] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:27:0) after 5m23s
[19:19:32.007] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:27:0)
[19:19:32.021] Build status: STARTED
[19:19:32.021] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:28:0) after 3m52s
[19:23:24.037] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:28:0)
[19:23:24.071] Build status: STARTED
[19:23:24.071] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:29:0) after 4m45s
[19:28:09.079] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:29:0)
[19:28:09.097] Build status: STARTED
[19:28:09.097] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:30:0) after 6m53s
[19:35:02.111] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:30:0)
[19:35:02.129] Build status: STARTED
[19:35:02.129] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:31:0) after 1m14s
[19:36:16.234] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:31:0)
[19:36:16.340] Build status: STARTED
[19:36:16.340] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:32:0) after 2m48s
[19:39:04.357] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:32:0)
[19:39:04.377] Build status: STARTED
[19:39:04.377] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:33:0) after 2m38s
[19:41:42.450] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:33:0)
[19:41:42.509] Build status: STARTED
[19:41:42.509] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:34:0) after 5m50s
[19:47:32.528] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:34:0)
[19:47:32.543] Build status: STARTED
[19:47:32.543] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:35:0) after 7m4s
[19:54:36.603] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:35:0)
[19:54:36.621] Build status: STARTED
[19:54:36.621] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:36:0) after 5m3s
[19:59:39.636] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:36:0)
[19:59:39.654] Build status: STARTED
[19:59:39.654] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:37:0) after 6m33s
[20:06:12.803] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:37:0)
[20:06:12.821] Build status: STARTED
[20:06:12.821] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:38:0) after 7m35s
[20:13:47.866] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:38:0)
[20:13:47.883] Build status: STARTED
[20:13:47.883] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:39:0) after 4m57s
[20:18:44.904] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:39:0)
[20:18:44.919] Build status: STARTED
[20:18:44.919] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:40:0) after 5m30s
[20:24:15.034] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:40:0)
[20:24:15.050] Build status: STARTED
[20:24:15.050] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:41:0) after 4m56s
[20:29:11.076] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:41:0)
[20:29:11.288] Build status: STARTED
[20:29:11.288] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:42:0) after 9m39s
[20:38:50.376] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:42:0)
[20:38:50.403] Build status: STARTED
[20:38:50.403] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8863366040623716016:43:0) after 7m41s
[20:42:05.089] Received PubSub notification, asking Buildbucket for the build status
[20:42:05.107] Build:
{
"id": "8697935978630206993",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 16509,
"createdBy": "project:v8",
"createTime": "2025-11-17T17:01:13.642552350Z",
"startTime": "2025-11-17T17:01:23.506545659Z",
"endTime": "2025-11-17T20:42:04.755414131Z",
"updateTime": "2025-11-17T20:42:04.755414131Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f5de0bc8445fa7b74de0f16cbfda3208ab60644b",
"ref": "refs/heads/main"
}
}
}
[20:42:05.107] Invocation finished in 3h40m52.750420728s with status SUCCEEDED