[10:31:46.105] New invocation is queued and will start shortly
[10:31:47.214] Starting the invocation (attempt 1)
[10:31:47.266] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/833647b476390f171f6a71300568d55b44166734"
[10:31:47.266] Popped gitiles commit info from properties and tags
[10:31:47.266] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:31:47.266] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:31:47.267] Buildbucket request:
{
"requestId": "8966580486659461312",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8966580486659461312",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "cc40beb19a2be3cf1840576b75be5ebbd818c66d"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cc40beb19a2be3cf1840576b75be5ebbd818c66d",
"title": "cc40beb19a2be3cf1840576b75be5ebbd818c66d",
"url": "https://chromium.googlesource.com/v8/v8/+/cc40beb19a2be3cf1840576b75be5ebbd818c66d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1a9b13fc73d092240f2411b3ece9279fcb3014bb"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@1a9b13fc73d092240f2411b3ece9279fcb3014bb",
"title": "1a9b13fc73d092240f2411b3ece9279fcb3014bb",
"url": "https://chromium.googlesource.com/v8/v8/+/1a9b13fc73d092240f2411b3ece9279fcb3014bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c3dffe6e2bdab79bb5bfb94be9fcc6a16adf6cf5"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@c3dffe6e2bdab79bb5bfb94be9fcc6a16adf6cf5",
"title": "c3dffe6e2bdab79bb5bfb94be9fcc6a16adf6cf5",
"url": "https://chromium.googlesource.com/v8/v8/+/c3dffe6e2bdab79bb5bfb94be9fcc6a16adf6cf5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "572f55a4adbefcebf77ff731f7b3516d8c899616"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@572f55a4adbefcebf77ff731f7b3516d8c899616",
"title": "572f55a4adbefcebf77ff731f7b3516d8c899616",
"url": "https://chromium.googlesource.com/v8/v8/+/572f55a4adbefcebf77ff731f7b3516d8c899616"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2cd18db11ffc243c26fd6ea1b2ea1ce62edb50cc"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@2cd18db11ffc243c26fd6ea1b2ea1ce62edb50cc",
"title": "2cd18db11ffc243c26fd6ea1b2ea1ce62edb50cc",
"url": "https://chromium.googlesource.com/v8/v8/+/2cd18db11ffc243c26fd6ea1b2ea1ce62edb50cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "39975b4f33e4e9704c2463b7e196c8f1cc8c13e0"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@39975b4f33e4e9704c2463b7e196c8f1cc8c13e0",
"title": "39975b4f33e4e9704c2463b7e196c8f1cc8c13e0",
"url": "https://chromium.googlesource.com/v8/v8/+/39975b4f33e4e9704c2463b7e196c8f1cc8c13e0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "4a095336eea1e7e09a5153f9bb0587a0e59c23ae"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@4a095336eea1e7e09a5153f9bb0587a0e59c23ae",
"title": "4a095336eea1e7e09a5153f9bb0587a0e59c23ae",
"url": "https://chromium.googlesource.com/v8/v8/+/4a095336eea1e7e09a5153f9bb0587a0e59c23ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "ff7fd115ae1fcedcd13c088736c02e14771179e9"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@ff7fd115ae1fcedcd13c088736c02e14771179e9",
"title": "ff7fd115ae1fcedcd13c088736c02e14771179e9",
"url": "https://chromium.googlesource.com/v8/v8/+/ff7fd115ae1fcedcd13c088736c02e14771179e9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "833647b476390f171f6a71300568d55b44166734"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@833647b476390f171f6a71300568d55b44166734",
"title": "833647b476390f171f6a71300568d55b44166734",
"url": "https://chromium.googlesource.com/v8/v8/+/833647b476390f171f6a71300568d55b44166734"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "833647b476390f171f6a71300568d55b44166734",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966580486659461312"
},
{
"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"
}
}
[10:31:47.981] Scheduled build:
{
"id": "8801150424693691345",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9718,
"createdBy": "project:v8",
"createTime": "2022-10-05T10:31:47.349885045Z",
"updateTime": "2022-10-05T10:31:47.349885045Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "833647b476390f171f6a71300568d55b44166734",
"ref": "refs/heads/main"
}
}
}
[10:31:47.981] Task URL: https://cr-buildbucket.appspot.com/build/8801150424693691345
[10:31:47.981] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:2:0) after 8m44s
[10:35:37.560] Received PubSub notification, asking Buildbucket for the build status
[10:35:37.596] Build status: STARTED
[10:40:32.094] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:2:0)
[10:40:32.120] Build status: STARTED
[10:40:32.121] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:4:0) after 7m30s
[10:48:02.267] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:4:0)
[10:48:02.291] Build status: STARTED
[10:48:02.291] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:5:0) after 2m28s
[10:50:30.441] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:5:0)
[10:50:30.477] Build status: STARTED
[10:50:30.477] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:6:0) after 6m13s
[10:56:43.923] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:6:0)
[10:56:43.965] Build status: STARTED
[10:56:43.965] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:7:0) after 3m13s
[10:59:57.144] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:7:0)
[10:59:57.195] Build status: STARTED
[10:59:57.195] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:8:0) after 6m52s
[11:06:49.320] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:8:0)
[11:06:49.346] Build status: STARTED
[11:06:49.346] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:9:0) after 7m28s
[11:14:17.427] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:9:0)
[11:14:17.453] Build status: STARTED
[11:14:17.453] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:10:0) after 9m40s
[11:23:57.570] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:10:0)
[11:23:57.599] Build status: STARTED
[11:23:57.599] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:11:0) after 7m51s
[11:31:48.623] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:11:0)
[11:31:48.649] Build status: STARTED
[11:31:48.649] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:12:0) after 9m58s
[11:41:46.687] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:12:0)
[11:41:46.709] Build status: STARTED
[11:41:46.709] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:13:0) after 4m49s
[11:46:35.714] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:13:0)
[11:46:35.739] Build status: STARTED
[11:46:35.739] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:14:0) after 3m12s
[11:49:47.904] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:14:0)
[11:49:47.928] Build status: STARTED
[11:49:47.928] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:15:0) after 4m23s
[11:54:11.114] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:15:0)
[11:54:11.143] Build status: STARTED
[11:54:11.143] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:16:0) after 1m36s
[11:55:47.320] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:16:0)
[11:55:47.345] Build status: STARTED
[11:55:47.345] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:17:0) after 3m10s
[11:58:57.623] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:17:0)
[11:58:57.677] Build status: STARTED
[11:58:57.677] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:18:0) after 4m30s
[12:03:27.735] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:18:0)
[12:03:27.759] Build status: STARTED
[12:03:27.759] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:19:0) after 9m7s
[12:12:34.877] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:19:0)
[12:12:34.912] Build status: STARTED
[12:12:34.912] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:20:0) after 9m22s
[12:21:57.156] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:20:0)
[12:21:57.185] Build status: STARTED
[12:21:57.185] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:21:0) after 7m2s
[12:28:59.580] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:21:0)
[12:28:59.670] Build status: STARTED
[12:28:59.670] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:22:0) after 5m1s
[12:34:00.733] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:22:0)
[12:34:00.756] Build status: STARTED
[12:34:00.756] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:23:0) after 8m5s
[12:42:05.983] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:23:0)
[12:42:06.006] Build status: STARTED
[12:42:06.007] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:24:0) after 5m28s
[12:47:34.226] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:24:0)
[12:47:34.255] Build status: STARTED
[12:47:34.255] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:25:0) after 7m36s
[12:55:10.404] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:25:0)
[12:55:10.425] Build status: STARTED
[12:55:10.425] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:26:0) after 2m34s
[12:57:44.547] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:26:0)
[12:57:44.685] Build status: STARTED
[12:57:44.686] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:27:0) after 7m54s
[13:05:38.838] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:27:0)
[13:05:38.864] Build status: STARTED
[13:05:38.864] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:28:0) after 2m25s
[13:08:03.910] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:28:0)
[13:08:03.931] Build status: STARTED
[13:08:03.931] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:29:0) after 1m0s
[13:09:04.010] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:29:0)
[13:09:04.035] Build status: STARTED
[13:09:04.035] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:30:0) after 1m36s
[13:10:40.203] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:30:0)
[13:10:40.359] Build status: STARTED
[13:10:40.359] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:31:0) after 4m16s
[13:14:56.494] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:31:0)
[13:14:56.534] Build status: STARTED
[13:14:56.534] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8966580486659461312:32:0) after 8m44s
[13:20:59.276] Received PubSub notification, asking Buildbucket for the build status
[13:20:59.362] Build:
{
"id": "8801150424693691345",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 9718,
"createdBy": "project:v8",
"createTime": "2022-10-05T10:31:47.349885045Z",
"startTime": "2022-10-05T10:35:37.069907Z",
"endTime": "2022-10-05T13:20:59.054578545Z",
"updateTime": "2022-10-05T13:20:59.054578545Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "833647b476390f171f6a71300568d55b44166734",
"ref": "refs/heads/main"
}
}
}
[13:20:59.362] Invocation finished in 2h49m13.270566567s with status SUCCEEDED