[16:10:23.485] New invocation is queued and will start shortly
[16:10:25.788] Starting the invocation (attempt 1)
[16:10:25.816] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92"
[16:10:25.816] Popped gitiles commit info from properties and tags
[16:10:25.816] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:10:25.817] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:10:25.817] Buildbucket request:
{
"requestId": "8873787888748955184",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873787888748955184",
"job": "v8/V8 Blink Win",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8e2906164324e7816ce769a9edaa29e12571f7d8"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8e2906164324e7816ce769a9edaa29e12571f7d8",
"title": "8e2906164324e7816ce769a9edaa29e12571f7d8",
"url": "https://chromium.googlesource.com/v8/v8/+/8e2906164324e7816ce769a9edaa29e12571f7d8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c211a63a80b19634456393d45474ed17d5f58bcf"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@c211a63a80b19634456393d45474ed17d5f58bcf",
"title": "c211a63a80b19634456393d45474ed17d5f58bcf",
"url": "https://chromium.googlesource.com/v8/v8/+/c211a63a80b19634456393d45474ed17d5f58bcf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "4ea793124a35b6fc136a14e84ecfbd05934b1c32"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@4ea793124a35b6fc136a14e84ecfbd05934b1c32",
"title": "4ea793124a35b6fc136a14e84ecfbd05934b1c32",
"url": "https://chromium.googlesource.com/v8/v8/+/4ea793124a35b6fc136a14e84ecfbd05934b1c32"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "549ac3ee50b8b7d722f83318edc06c5cad6fd075"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@549ac3ee50b8b7d722f83318edc06c5cad6fd075",
"title": "549ac3ee50b8b7d722f83318edc06c5cad6fd075",
"url": "https://chromium.googlesource.com/v8/v8/+/549ac3ee50b8b7d722f83318edc06c5cad6fd075"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "e56255801e308410458b0d5908b542a845509e3d"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@e56255801e308410458b0d5908b542a845509e3d",
"title": "e56255801e308410458b0d5908b542a845509e3d",
"url": "https://chromium.googlesource.com/v8/v8/+/e56255801e308410458b0d5908b542a845509e3d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "30242fa056e62433c5190be80991e8abfa8959ae"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@30242fa056e62433c5190be80991e8abfa8959ae",
"title": "30242fa056e62433c5190be80991e8abfa8959ae",
"url": "https://chromium.googlesource.com/v8/v8/+/30242fa056e62433c5190be80991e8abfa8959ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92",
"title": "1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92",
"url": "https://chromium.googlesource.com/v8/v8/+/1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873787888748955184"
},
{
"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:10:26.439] Scheduled build:
{
"id": "8708357825580489889",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 16042,
"createdBy": "project:v8",
"createTime": "2025-07-25T16:10:25.876404066Z",
"updateTime": "2025-07-25T16:10:25.876404066Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92",
"ref": "refs/heads/main"
}
}
}
[16:10:26.439] Task URL: https://cr-buildbucket.appspot.com/build/8708357825580489889
[16:10:26.439] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:2:0) after 8m9s
[16:10:26.523] Received PubSub notification, asking Buildbucket for the build status
[16:10:26.551] Build status: SCHEDULED
[16:10:34.214] Received PubSub notification, asking Buildbucket for the build status
[16:10:34.241] Build status: STARTED
[16:18:35.496] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:2:0)
[16:18:35.516] Build status: STARTED
[16:18:35.516] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:5:0) after 2m3s
[16:20:38.758] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:5:0)
[16:20:38.837] Build status: STARTED
[16:20:38.837] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:6:0) after 9m10s
[16:29:48.857] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:6:0)
[16:29:48.886] Build status: STARTED
[16:29:48.886] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:7:0) after 6m45s
[16:36:33.911] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:7:0)
[16:36:33.933] Build status: STARTED
[16:36:33.933] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:8:0) after 9m47s
[16:46:21.087] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:8:0)
[16:46:21.102] Build status: STARTED
[16:46:21.102] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:9:0) after 1m7s
[16:47:28.156] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:9:0)
[16:47:28.176] Build status: STARTED
[16:47:28.176] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:10:0) after 6m14s
[16:53:42.205] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:10:0)
[16:53:42.227] Build status: STARTED
[16:53:42.228] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:11:0) after 4m39s
[16:58:21.314] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:11:0)
[16:58:21.342] Build status: STARTED
[16:58:21.342] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:12:0) after 5m48s
[17:04:09.365] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:12:0)
[17:04:09.379] Build status: STARTED
[17:04:09.380] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:13:0) after 1m3s
[17:05:12.556] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:13:0)
[17:05:12.577] Build status: STARTED
[17:05:12.578] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:14:0) after 9m15s
[17:14:27.594] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:14:0)
[17:14:28.783] Build status: STARTED
[17:14:28.783] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:15:0) after 5m58s
[17:20:26.862] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:15:0)
[17:20:26.889] Build status: STARTED
[17:20:26.889] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:16:0) after 1m28s
[17:21:54.910] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:16:0)
[17:21:54.941] Build status: STARTED
[17:21:54.941] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:17:0) after 9m4s
[17:30:58.955] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:17:0)
[17:30:58.972] Build status: STARTED
[17:30:58.973] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:18:0) after 2m56s
[17:33:54.997] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:18:0)
[17:33:55.018] Build status: STARTED
[17:33:55.018] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:19:0) after 2m7s
[17:36:02.122] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:19:0)
[17:36:02.140] Build status: STARTED
[17:36:02.140] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:20:0) after 1m54s
[17:37:56.164] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:20:0)
[17:37:56.195] Build status: STARTED
[17:37:56.195] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:21:0) after 8m34s
[17:46:30.207] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:21:0)
[17:46:30.235] Build status: STARTED
[17:46:30.236] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:22:0) after 8m42s
[17:55:12.273] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:22:0)
[17:55:12.290] Build status: STARTED
[17:55:12.290] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:23:0) after 9m16s
[18:04:28.328] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:23:0)
[18:04:28.354] Build status: STARTED
[18:04:28.354] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:24:0) after 7m22s
[18:11:50.435] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:24:0)
[18:11:50.465] Build status: STARTED
[18:11:50.465] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:25:0) after 8m19s
[18:20:09.470] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:25:0)
[18:20:09.505] Build status: STARTED
[18:20:09.505] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:26:0) after 1m35s
[18:21:44.525] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:26:0)
[18:21:44.557] Build status: STARTED
[18:21:44.557] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:27:0) after 5m8s
[18:26:52.631] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:27:0)
[18:26:52.651] Build status: STARTED
[18:26:52.651] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:28:0) after 1m47s
[18:28:39.751] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:28:0)
[18:28:39.767] Build status: STARTED
[18:28:39.767] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:29:0) after 7m28s
[18:36:07.791] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:29:0)
[18:36:07.875] Build status: STARTED
[18:36:07.876] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:30:0) after 9m57s
[18:46:04.900] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:30:0)
[18:46:04.926] Build status: STARTED
[18:46:04.927] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:31:0) after 9m21s
[18:55:25.981] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:31:0)
[18:55:25.999] Build status: STARTED
[18:55:26.000] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:32:0) after 2m48s
[18:58:14.030] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:32:0)
[18:58:14.084] Build status: STARTED
[18:58:14.085] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:33:0) after 6m54s
[19:05:08.106] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:33:0)
[19:05:08.150] Build status: STARTED
[19:05:08.150] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:34:0) after 8m35s
[19:13:43.172] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:34:0)
[19:13:43.233] Build status: STARTED
[19:13:43.233] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:35:0) after 7m29s
[19:21:12.283] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:35:0)
[19:21:12.311] Build status: STARTED
[19:21:12.311] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:36:0) after 8m45s
[19:29:57.332] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:36:0)
[19:29:57.363] Build status: STARTED
[19:29:57.363] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:37:0) after 3m38s
[19:33:35.394] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:37:0)
[19:33:35.410] Build status: STARTED
[19:33:35.410] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:38:0) after 8m23s
[19:41:58.436] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:38:0)
[19:41:58.452] Build status: STARTED
[19:41:58.452] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:39:0) after 1m30s
[19:43:28.512] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:39:0)
[19:43:28.610] Build status: STARTED
[19:43:28.610] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:40:0) after 3m35s
[19:47:03.805] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:40:0)
[19:47:03.833] Build status: STARTED
[19:47:03.833] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:41:0) after 5m23s
[19:52:26.817] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:41:0)
[19:52:26.897] Build status: STARTED
[19:52:26.897] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:42:0) after 7m41s
[20:00:07.920] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:42:0)
[20:00:07.937] Build status: STARTED
[20:00:07.937] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:43:0) after 4m12s
[20:04:20.021] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:43:0)
[20:04:20.054] Build status: STARTED
[20:04:20.055] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:44:0) after 8m47s
[20:13:07.226] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:44:0)
[20:13:07.260] Build status: STARTED
[20:13:07.260] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:45:0) after 3m58s
[20:17:05.289] Handling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:45:0)
[20:17:05.363] Build status: STARTED
[20:17:05.363] Scheduling timer "check-buildbucket-build-status" (v8/V8 Blink Win:8873787888748955184:46:0) after 6m5s
[20:17:51.009] Received PubSub notification, asking Buildbucket for the build status
[20:17:51.026] Build:
{
"id": "8708357825580489889",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Blink Win"
},
"number": 16042,
"createdBy": "project:v8",
"createTime": "2025-07-25T16:10:25.876404066Z",
"startTime": "2025-07-25T16:10:33.975203565Z",
"endTime": "2025-07-25T20:17:50.637789375Z",
"updateTime": "2025-07-25T20:17:50.637789375Z",
"status": "INFRA_FAILURE",
"statusDetails": {
"timeout": {
}
},
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1eda9300e26ec1aad95f98b6cb8e0d3ca6fb1e92",
"ref": "refs/heads/main"
}
}
}
[20:17:51.026] Invocation finished in 4h7m27.5550555s with status FAILED