[19:44:41.151] New invocation is queued and will start shortly
[19:44:42.291] Starting the invocation (attempt 1)
[19:44:42.376] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/33f7913c984133c50bee5755961b76bb8d2238fa"
[19:44:42.377] Popped gitiles commit info from properties and tags
[19:44:42.377] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[19:44:42.377] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[19:44:42.377] Buildbucket request:
{
"requestId": "8964008985054869344",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8964008985054869344",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "28f63891a836970705b673727960eb317a4dec7e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@28f63891a836970705b673727960eb317a4dec7e",
"title": "28f63891a836970705b673727960eb317a4dec7e",
"url": "https://chromium.googlesource.com/v8/v8/+/28f63891a836970705b673727960eb317a4dec7e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "bcf8d26426609f379606efb65d0e9884647ba901"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@bcf8d26426609f379606efb65d0e9884647ba901",
"title": "bcf8d26426609f379606efb65d0e9884647ba901",
"url": "https://chromium.googlesource.com/v8/v8/+/bcf8d26426609f379606efb65d0e9884647ba901"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "067ede0ad4333f4b01e1e3df4f421335912c3452"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@067ede0ad4333f4b01e1e3df4f421335912c3452",
"title": "067ede0ad4333f4b01e1e3df4f421335912c3452",
"url": "https://chromium.googlesource.com/v8/v8/+/067ede0ad4333f4b01e1e3df4f421335912c3452"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "103c34991b591b5b02e92241445be3c62f0c9fd8"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@103c34991b591b5b02e92241445be3c62f0c9fd8",
"title": "103c34991b591b5b02e92241445be3c62f0c9fd8",
"url": "https://chromium.googlesource.com/v8/v8/+/103c34991b591b5b02e92241445be3c62f0c9fd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "381b8f55e9f5f8c987766cb6f438aaec8febeb03"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@381b8f55e9f5f8c987766cb6f438aaec8febeb03",
"title": "381b8f55e9f5f8c987766cb6f438aaec8febeb03",
"url": "https://chromium.googlesource.com/v8/v8/+/381b8f55e9f5f8c987766cb6f438aaec8febeb03"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8d7f885ff179e27d43080cf6e57ab7df83e1a0d9"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8d7f885ff179e27d43080cf6e57ab7df83e1a0d9",
"title": "8d7f885ff179e27d43080cf6e57ab7df83e1a0d9",
"url": "https://chromium.googlesource.com/v8/v8/+/8d7f885ff179e27d43080cf6e57ab7df83e1a0d9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "33f7913c984133c50bee5755961b76bb8d2238fa"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@33f7913c984133c50bee5755961b76bb8d2238fa",
"title": "33f7913c984133c50bee5755961b76bb8d2238fa",
"url": "https://chromium.googlesource.com/v8/v8/+/33f7913c984133c50bee5755961b76bb8d2238fa"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "33f7913c984133c50bee5755961b76bb8d2238fa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8964008985054869344"
},
{
"key": "scheduler_job_id",
"value": "v8/V8 Win64 - dev image"
},
{
"key": "user_agent",
"value": "luci-scheduler-dev"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
}
}
[19:44:43.154] Scheduled build:
{
"id": "8798578922898259105",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 5997,
"createdBy": "project:v8",
"createTime": "2022-11-02T19:44:42.566319944Z",
"updateTime": "2022-11-02T19:44:42.566319944Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "33f7913c984133c50bee5755961b76bb8d2238fa",
"ref": "refs/heads/main"
}
}
}
[19:44:43.154] Task URL: https://cr-buildbucket-dev.appspot.com/build/8798578922898259105
[19:44:43.154] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:2:0) after 8m20s
[19:53:03.185] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:2:0)
[19:53:03.325] Build status: SCHEDULED
[19:53:03.325] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:3:0) after 2m50s
[19:55:53.351] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:3:0)
[19:55:53.397] Build status: SCHEDULED
[19:55:53.397] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:4:0) after 1m58s
[19:57:51.435] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:4:0)
[19:57:51.510] Build status: SCHEDULED
[19:57:51.510] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:5:0) after 4m8s
[20:01:59.555] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:5:0)
[20:01:59.601] Build status: SCHEDULED
[20:01:59.601] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:6:0) after 3m31s
[20:05:30.634] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:6:0)
[20:05:30.680] Build status: SCHEDULED
[20:05:30.681] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:7:0) after 7m30s
[20:13:00.758] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:7:0)
[20:13:00.816] Build status: SCHEDULED
[20:13:00.817] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:8:0) after 5m5s
[20:18:05.847] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:8:0)
[20:18:05.891] Build status: SCHEDULED
[20:18:05.891] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:9:0) after 5m8s
[20:23:13.913] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:9:0)
[20:23:13.978] Build status: SCHEDULED
[20:23:13.978] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:10:0) after 7m32s
[20:30:46.007] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:10:0)
[20:30:46.059] Build status: SCHEDULED
[20:30:46.059] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:11:0) after 8m55s
[20:39:41.089] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:11:0)
[20:39:41.121] Build status: SCHEDULED
[20:39:41.121] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:12:0) after 9m47s
[20:49:28.150] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:12:0)
[20:49:28.228] Build status: SCHEDULED
[20:49:28.228] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:13:0) after 9m0s
[20:58:28.261] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:13:0)
[20:58:28.606] Build status: SCHEDULED
[20:58:28.606] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:14:0) after 3m38s
[21:02:06.638] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:14:0)
[21:02:06.775] Build status: SCHEDULED
[21:02:06.775] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:15:0) after 6m26s
[21:08:32.801] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:15:0)
[21:08:32.877] Build status: SCHEDULED
[21:08:32.877] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:16:0) after 2m12s
[21:10:44.922] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:16:0)
[21:10:45.081] Build status: SCHEDULED
[21:10:45.081] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:17:0) after 7m36s
[21:18:21.106] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:17:0)
[21:18:21.166] Build status: SCHEDULED
[21:18:21.166] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:18:0) after 1m58s
[21:20:19.193] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:18:0)
[21:20:19.235] Build status: SCHEDULED
[21:20:19.235] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:19:0) after 2m49s
[21:23:08.262] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:19:0)
[21:23:08.302] Build status: SCHEDULED
[21:23:08.302] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:20:0) after 5m44s
[21:28:52.331] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:20:0)
[21:28:52.358] Build status: SCHEDULED
[21:28:52.358] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:21:0) after 4m51s
[21:33:43.394] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:21:0)
[21:33:43.463] Build status: SCHEDULED
[21:33:43.463] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:22:0) after 7m5s
[21:40:48.491] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:22:0)
[21:40:48.534] Build status: SCHEDULED
[21:40:48.534] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:23:0) after 4m19s
[21:45:07.564] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:23:0)
[21:45:07.596] Build status: SCHEDULED
[21:45:07.596] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:24:0) after 3m21s
[21:48:28.645] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:24:0)
[21:48:28.705] Build status: SCHEDULED
[21:48:28.705] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:25:0) after 2m13s
[21:50:41.752] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:25:0)
[21:50:41.782] Build status: SCHEDULED
[21:50:41.782] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:26:0) after 8m1s
[21:58:42.831] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:26:0)
[21:58:42.908] Build status: SCHEDULED
[21:58:42.908] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:27:0) after 9m40s
[22:08:22.935] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:27:0)
[22:08:22.997] Build status: SCHEDULED
[22:08:22.997] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:28:0) after 5m36s
[22:13:59.043] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:28:0)
[22:13:59.099] Build status: SCHEDULED
[22:13:59.100] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:29:0) after 1m53s
[22:15:52.826] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:29:0)
[22:15:52.859] Build status: SCHEDULED
[22:15:52.859] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:30:0) after 2m56s
[22:18:48.906] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:30:0)
[22:18:48.964] Build status: SCHEDULED
[22:18:48.964] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:31:0) after 8m32s
[22:27:20.996] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:31:0)
[22:27:21.057] Build status: SCHEDULED
[22:27:21.057] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:32:0) after 3m33s
[22:30:48.359] Received PubSub notification, asking Buildbucket for the build status
[22:30:48.390] Build status: STARTED
[22:30:54.085] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:32:0)
[22:30:54.130] Build status: STARTED
[22:30:54.130] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:34:0) after 4m17s
[22:35:11.162] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:34:0)
[22:35:11.201] Build status: STARTED
[22:35:11.201] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8964008985054869344:35:0) after 1m14s
[22:36:18.908] Received PubSub notification, asking Buildbucket for the build status
[22:36:18.954] Build:
{
"id": "8798578922898259105",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 5997,
"createdBy": "project:v8",
"createTime": "2022-11-02T19:44:42.566319944Z",
"startTime": "2022-11-02T22:30:47.517927Z",
"endTime": "2022-11-02T22:36:18.612013390Z",
"updateTime": "2022-11-02T22:36:18.612013390Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "33f7913c984133c50bee5755961b76bb8d2238fa",
"ref": "refs/heads/main"
}
}
}
[22:36:18.954] Invocation finished in 2h51m37.828481381s with status FAILED