[17:05:22.076] New invocation is queued and will start shortly
[17:05:23.146] Starting the invocation (attempt 1)
[17:05:23.179] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/f48e05a057d1ef8bb3d671912496aef6d3c1c91e"
[17:05:23.179] Popped gitiles commit info from properties and tags
[17:05:23.179] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[17:05:23.179] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[17:05:23.179] Buildbucket request:
{
"requestId": "8947530360570869744",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8947530360570869744",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "5d80441adab137aec6b47c960c913c822787046b"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5d80441adab137aec6b47c960c913c822787046b",
"title": "5d80441adab137aec6b47c960c913c822787046b",
"url": "https://chromium.googlesource.com/v8/v8/+/5d80441adab137aec6b47c960c913c822787046b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "fd9c00081c16647b0fb64dcd125d4825615358f9"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@fd9c00081c16647b0fb64dcd125d4825615358f9",
"title": "fd9c00081c16647b0fb64dcd125d4825615358f9",
"url": "https://chromium.googlesource.com/v8/v8/+/fd9c00081c16647b0fb64dcd125d4825615358f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f652dadb4c70b013a9c0d57cc5ec4f0036bf1970"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f652dadb4c70b013a9c0d57cc5ec4f0036bf1970",
"title": "f652dadb4c70b013a9c0d57cc5ec4f0036bf1970",
"url": "https://chromium.googlesource.com/v8/v8/+/f652dadb4c70b013a9c0d57cc5ec4f0036bf1970"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "335ac4bd86320aca910e9036cb2aba6dca85d97d"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@335ac4bd86320aca910e9036cb2aba6dca85d97d",
"title": "335ac4bd86320aca910e9036cb2aba6dca85d97d",
"url": "https://chromium.googlesource.com/v8/v8/+/335ac4bd86320aca910e9036cb2aba6dca85d97d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7622afda36fe569079b001a0e164659aa32d3427"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@7622afda36fe569079b001a0e164659aa32d3427",
"title": "7622afda36fe569079b001a0e164659aa32d3427",
"url": "https://chromium.googlesource.com/v8/v8/+/7622afda36fe569079b001a0e164659aa32d3427"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8d2ad7e6fc51f35a67b59eb5192be90ea33a7603"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8d2ad7e6fc51f35a67b59eb5192be90ea33a7603",
"title": "8d2ad7e6fc51f35a67b59eb5192be90ea33a7603",
"url": "https://chromium.googlesource.com/v8/v8/+/8d2ad7e6fc51f35a67b59eb5192be90ea33a7603"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "cb69f03f23eb07c3e5f30fb75164799db3e0cdc8"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cb69f03f23eb07c3e5f30fb75164799db3e0cdc8",
"title": "cb69f03f23eb07c3e5f30fb75164799db3e0cdc8",
"url": "https://chromium.googlesource.com/v8/v8/+/cb69f03f23eb07c3e5f30fb75164799db3e0cdc8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7396bc26b3c469605a19833f1558545c3aa7715a"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@7396bc26b3c469605a19833f1558545c3aa7715a",
"title": "7396bc26b3c469605a19833f1558545c3aa7715a",
"url": "https://chromium.googlesource.com/v8/v8/+/7396bc26b3c469605a19833f1558545c3aa7715a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "cca97efd514aaf038cd90c2b2927c5e1b8b5d16b"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@cca97efd514aaf038cd90c2b2927c5e1b8b5d16b",
"title": "cca97efd514aaf038cd90c2b2927c5e1b8b5d16b",
"url": "https://chromium.googlesource.com/v8/v8/+/cca97efd514aaf038cd90c2b2927c5e1b8b5d16b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f48e05a057d1ef8bb3d671912496aef6d3c1c91e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f48e05a057d1ef8bb3d671912496aef6d3c1c91e",
"title": "f48e05a057d1ef8bb3d671912496aef6d3c1c91e",
"url": "https://chromium.googlesource.com/v8/v8/+/f48e05a057d1ef8bb3d671912496aef6d3c1c91e"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f48e05a057d1ef8bb3d671912496aef6d3c1c91e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8947530360570869744"
},
{
"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"
}
}
[17:05:23.729] Scheduled build:
{
"id": "8782100298683049233",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 7265,
"createdBy": "project:v8",
"createTime": "2023-05-03T17:05:23.250420344Z",
"updateTime": "2023-05-03T17:05:23.250420344Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f48e05a057d1ef8bb3d671912496aef6d3c1c91e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[17:05:23.729] Task URL: https://cr-buildbucket-dev.appspot.com/build/8782100298683049233
[17:05:23.729] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:2:0) after 5m14s
[17:10:37.744] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:2:0)
[17:10:37.777] Build status: SCHEDULED
[17:10:37.778] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:3:0) after 8m40s
[17:19:17.795] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:3:0)
[17:19:17.852] Build status: SCHEDULED
[17:19:17.852] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:4:0) after 7m8s
[17:26:25.869] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:4:0)
[17:26:25.908] Build status: SCHEDULED
[17:26:25.908] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:5:0) after 8m18s
[17:34:43.927] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:5:0)
[17:34:43.946] Build status: SCHEDULED
[17:34:43.946] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:6:0) after 1m11s
[17:35:54.967] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:6:0)
[17:35:55.001] Build status: SCHEDULED
[17:35:55.001] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:7:0) after 8m57s
[17:44:52.024] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:7:0)
[17:44:52.079] Build status: SCHEDULED
[17:44:52.079] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:8:0) after 9m13s
[17:54:05.097] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:8:0)
[17:54:05.130] Build status: SCHEDULED
[17:54:05.131] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:9:0) after 7m53s
[18:01:58.149] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:9:0)
[18:01:58.178] Build status: SCHEDULED
[18:01:58.178] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:10:0) after 1m38s
[18:03:36.197] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:10:0)
[18:03:36.256] Build status: SCHEDULED
[18:03:36.256] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:11:0) after 8m42s
[18:12:18.274] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:11:0)
[18:12:18.318] Build status: SCHEDULED
[18:12:18.318] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:12:0) after 7m4s
[18:19:22.336] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:12:0)
[18:19:22.368] Build status: SCHEDULED
[18:19:22.368] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:13:0) after 5m51s
[18:25:13.388] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:13:0)
[18:25:13.433] Build status: SCHEDULED
[18:25:13.433] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:14:0) after 2m51s
[18:28:04.453] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:14:0)
[18:28:04.510] Build status: SCHEDULED
[18:28:04.510] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:15:0) after 8m21s
[18:36:25.527] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:15:0)
[18:36:25.551] Build status: SCHEDULED
[18:36:25.551] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:16:0) after 8m23s
[18:44:48.570] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:16:0)
[18:44:48.600] Build status: SCHEDULED
[18:44:48.600] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:17:0) after 5m9s
[18:49:57.618] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:17:0)
[18:49:57.675] Build status: SCHEDULED
[18:49:57.675] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:18:0) after 9m17s
[18:59:14.702] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:18:0)
[18:59:14.728] Build status: SCHEDULED
[18:59:14.728] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:19:0) after 7m18s
[19:06:32.751] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:19:0)
[19:06:33.443] Build status: SCHEDULED
[19:06:33.443] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:20:0) after 5m7s
[19:11:40.463] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:20:0)
[19:11:40.493] Build status: SCHEDULED
[19:11:40.493] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:21:0) after 2m3s
[19:13:43.513] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:21:0)
[19:13:43.547] Build status: SCHEDULED
[19:13:43.547] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:22:0) after 5m3s
[19:18:46.564] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:22:0)
[19:18:46.584] Build status: SCHEDULED
[19:18:46.584] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:23:0) after 7m14s
[19:26:00.613] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:23:0)
[19:26:00.670] Build status: SCHEDULED
[19:26:00.670] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:24:0) after 2m44s
[19:28:44.688] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:24:0)
[19:28:44.727] Build status: SCHEDULED
[19:28:44.727] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:25:0) after 3m38s
[19:32:22.755] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:25:0)
[19:32:22.840] Build status: SCHEDULED
[19:32:22.840] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:26:0) after 3m7s
[19:35:29.856] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:26:0)
[19:35:29.899] Build status: SCHEDULED
[19:35:29.899] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:27:0) after 1m34s
[19:37:03.923] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:27:0)
[19:37:03.951] Build status: SCHEDULED
[19:37:03.951] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:28:0) after 4m28s
[19:41:31.967] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:28:0)
[19:41:32.002] Build status: SCHEDULED
[19:41:32.002] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:29:0) after 3m2s
[19:44:33.985] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:29:0)
[19:44:34.034] Build status: SCHEDULED
[19:44:34.034] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:30:0) after 9m32s
[19:54:06.057] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:30:0)
[19:54:06.105] Build status: SCHEDULED
[19:54:06.105] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:31:0) after 7m10s
[20:01:16.131] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:31:0)
[20:01:16.191] Build status: SCHEDULED
[20:01:16.191] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:32:0) after 1m38s
[20:02:54.224] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:32:0)
[20:02:54.255] Build status: SCHEDULED
[20:02:54.255] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:33:0) after 6m18s
[20:09:12.276] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:33:0)
[20:09:12.302] Build status: SCHEDULED
[20:09:12.302] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:34:0) after 5m53s
[20:15:05.323] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:34:0)
[20:15:05.367] Build status: SCHEDULED
[20:15:05.367] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:35:0) after 4m4s
[20:19:09.391] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:35:0)
[20:19:09.431] Build status: SCHEDULED
[20:19:09.431] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:36:0) after 1m25s
[20:20:34.453] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:36:0)
[20:20:34.487] Build status: SCHEDULED
[20:20:34.487] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:37:0) after 8m27s
[20:29:01.510] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:37:0)
[20:29:01.556] Build status: SCHEDULED
[20:29:01.557] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:38:0) after 3m16s
[20:32:17.576] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:38:0)
[20:32:17.595] Build status: SCHEDULED
[20:32:17.595] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:39:0) after 4m11s
[20:36:28.617] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:39:0)
[20:36:28.672] Build status: SCHEDULED
[20:36:28.672] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:40:0) after 8m19s
[20:41:41.593] Received PubSub notification, asking Buildbucket for the build status
[20:41:41.642] Build status: STARTED
[20:44:47.693] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:40:0)
[20:44:47.734] Build status: STARTED
[20:44:47.734] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8947530360570869744:42:0) after 9m53s
[20:44:59.784] Received PubSub notification, asking Buildbucket for the build status
[20:44:59.841] Build:
{
"id": "8782100298683049233",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 7265,
"createdBy": "project:v8",
"createTime": "2023-05-03T17:05:23.250420344Z",
"startTime": "2023-05-03T20:41:40.817157Z",
"endTime": "2023-05-03T20:44:57.375047072Z",
"updateTime": "2023-05-03T20:44:57.375047072Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "f48e05a057d1ef8bb3d671912496aef6d3c1c91e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:44:59.841] Invocation finished in 3h39m37.774708961s with status FAILED