[16:00:12.112] New invocation is queued and will start shortly
[16:00:13.244] Starting the invocation (attempt 1)
[16:00:13.313] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/a83a306479fb5cc6d1036000640e2acdb68b3a32"
[16:00:13.313] Popped gitiles commit info from properties and tags
[16:00:13.313] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[16:00:13.313] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[16:00:13.313] Buildbucket request:
{
"requestId": "8963298332629838800",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8963298332629838800",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2d9d35c4e96fc115a332aba335911ad529669cf5"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@2d9d35c4e96fc115a332aba335911ad529669cf5",
"title": "2d9d35c4e96fc115a332aba335911ad529669cf5",
"url": "https://chromium.googlesource.com/v8/v8/+/2d9d35c4e96fc115a332aba335911ad529669cf5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "00139375ffb064d0d0aafb2bfdf10d6a68375ce4"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@00139375ffb064d0d0aafb2bfdf10d6a68375ce4",
"title": "00139375ffb064d0d0aafb2bfdf10d6a68375ce4",
"url": "https://chromium.googlesource.com/v8/v8/+/00139375ffb064d0d0aafb2bfdf10d6a68375ce4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c8e875de7094f054cb5ec168fa825208a4de442e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@c8e875de7094f054cb5ec168fa825208a4de442e",
"title": "c8e875de7094f054cb5ec168fa825208a4de442e",
"url": "https://chromium.googlesource.com/v8/v8/+/c8e875de7094f054cb5ec168fa825208a4de442e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "356260cb8595820d0fbb26f358d505d3dc783b41"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@356260cb8595820d0fbb26f358d505d3dc783b41",
"title": "356260cb8595820d0fbb26f358d505d3dc783b41",
"url": "https://chromium.googlesource.com/v8/v8/+/356260cb8595820d0fbb26f358d505d3dc783b41"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "f9eee456f5e08091017fc630107d9a1154602d18"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@f9eee456f5e08091017fc630107d9a1154602d18",
"title": "f9eee456f5e08091017fc630107d9a1154602d18",
"url": "https://chromium.googlesource.com/v8/v8/+/f9eee456f5e08091017fc630107d9a1154602d18"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "df2078c5a3703c840de249648a28dd752508c427"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@df2078c5a3703c840de249648a28dd752508c427",
"title": "df2078c5a3703c840de249648a28dd752508c427",
"url": "https://chromium.googlesource.com/v8/v8/+/df2078c5a3703c840de249648a28dd752508c427"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "11998500993c9f01c6aa37e75ca131b0c045211f"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@11998500993c9f01c6aa37e75ca131b0c045211f",
"title": "11998500993c9f01c6aa37e75ca131b0c045211f",
"url": "https://chromium.googlesource.com/v8/v8/+/11998500993c9f01c6aa37e75ca131b0c045211f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "246d04486cf6962fabb4309407428123a299b57e"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@246d04486cf6962fabb4309407428123a299b57e",
"title": "246d04486cf6962fabb4309407428123a299b57e",
"url": "https://chromium.googlesource.com/v8/v8/+/246d04486cf6962fabb4309407428123a299b57e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "2adc620152425bd9beb8e74408dd034c571528f4"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@2adc620152425bd9beb8e74408dd034c571528f4",
"title": "2adc620152425bd9beb8e74408dd034c571528f4",
"url": "https://chromium.googlesource.com/v8/v8/+/2adc620152425bd9beb8e74408dd034c571528f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "d691642d23dc038f91a379ae28545178680a3eeb"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@d691642d23dc038f91a379ae28545178680a3eeb",
"title": "d691642d23dc038f91a379ae28545178680a3eeb",
"url": "https://chromium.googlesource.com/v8/v8/+/d691642d23dc038f91a379ae28545178680a3eeb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7758a26f1cd9357ad29cd4335e8c7acc277fef04"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@7758a26f1cd9357ad29cd4335e8c7acc277fef04",
"title": "7758a26f1cd9357ad29cd4335e8c7acc277fef04",
"url": "https://chromium.googlesource.com/v8/v8/+/7758a26f1cd9357ad29cd4335e8c7acc277fef04"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "dbc6e5658055cc230db1485926fee77338cc72b8"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@dbc6e5658055cc230db1485926fee77338cc72b8",
"title": "dbc6e5658055cc230db1485926fee77338cc72b8",
"url": "https://chromium.googlesource.com/v8/v8/+/dbc6e5658055cc230db1485926fee77338cc72b8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "3c7e371eabdb21d5092826f89e2348d40f3b9568"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@3c7e371eabdb21d5092826f89e2348d40f3b9568",
"title": "3c7e371eabdb21d5092826f89e2348d40f3b9568",
"url": "https://chromium.googlesource.com/v8/v8/+/3c7e371eabdb21d5092826f89e2348d40f3b9568"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "a83a306479fb5cc6d1036000640e2acdb68b3a32"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@a83a306479fb5cc6d1036000640e2acdb68b3a32",
"title": "a83a306479fb5cc6d1036000640e2acdb68b3a32",
"url": "https://chromium.googlesource.com/v8/v8/+/a83a306479fb5cc6d1036000640e2acdb68b3a32"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a83a306479fb5cc6d1036000640e2acdb68b3a32",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8963298332629838800"
},
{
"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"
}
}
[16:00:14.719] Scheduled build:
{
"id": "8797868270418505265",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 6053,
"createdBy": "project:v8",
"createTime": "2022-11-10T16:00:13.584139049Z",
"updateTime": "2022-11-10T16:00:13.584139049Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a83a306479fb5cc6d1036000640e2acdb68b3a32",
"ref": "refs/heads/main"
}
}
}
[16:00:14.719] Task URL: https://cr-buildbucket-dev.appspot.com/build/8797868270418505265
[16:00:14.719] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:2:0) after 1m8s
[16:01:22.747] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:2:0)
[16:01:22.833] Build status: SCHEDULED
[16:01:22.833] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:3:0) after 7m3s
[16:08:25.858] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:3:0)
[16:08:25.897] Build status: SCHEDULED
[16:08:25.897] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:4:0) after 8m44s
[16:17:09.930] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:4:0)
[16:17:10.233] Build status: SCHEDULED
[16:17:10.233] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:5:0) after 9m35s
[16:26:45.260] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:5:0)
[16:26:45.323] Build status: SCHEDULED
[16:26:45.323] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:6:0) after 7m43s
[16:34:28.352] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:6:0)
[16:34:28.407] Build status: SCHEDULED
[16:34:28.407] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:7:0) after 2m1s
[16:36:29.432] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:7:0)
[16:36:29.475] Build status: SCHEDULED
[16:36:29.475] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:8:0) after 5m51s
[16:42:20.508] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:8:0)
[16:42:20.559] Build status: SCHEDULED
[16:42:20.559] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:9:0) after 5m36s
[16:47:56.582] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:9:0)
[16:47:56.622] Build status: SCHEDULED
[16:47:56.622] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:10:0) after 5m30s
[16:53:26.649] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:10:0)
[16:53:26.681] Build status: SCHEDULED
[16:53:26.681] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:11:0) after 7m18s
[17:00:44.714] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:11:0)
[17:00:44.778] Build status: SCHEDULED
[17:00:44.778] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:12:0) after 8m3s
[17:08:47.806] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:12:0)
[17:08:47.840] Build status: SCHEDULED
[17:08:47.840] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:13:0) after 5m9s
[17:13:56.872] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:13:0)
[17:13:56.965] Build status: SCHEDULED
[17:13:56.965] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:14:0) after 5m39s
[17:19:36.001] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:14:0)
[17:19:36.068] Build status: SCHEDULED
[17:19:36.068] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:15:0) after 2m0s
[17:21:36.097] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:15:0)
[17:21:36.205] Build status: SCHEDULED
[17:21:36.205] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:16:0) after 3m46s
[17:25:22.268] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:16:0)
[17:25:22.338] Build status: SCHEDULED
[17:25:22.338] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:17:0) after 1m58s
[17:27:20.378] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:17:0)
[17:27:20.419] Build status: SCHEDULED
[17:27:20.419] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:18:0) after 1m0s
[17:28:20.445] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:18:0)
[17:28:20.473] Build status: SCHEDULED
[17:28:20.473] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:19:0) after 4m12s
[17:32:32.497] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:19:0)
[17:32:32.612] Build status: SCHEDULED
[17:32:32.612] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:20:0) after 1m39s
[17:34:11.643] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:20:0)
[17:34:11.711] Build status: SCHEDULED
[17:34:11.711] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:21:0) after 6m41s
[17:40:52.741] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:21:0)
[17:40:52.830] Build status: SCHEDULED
[17:40:52.830] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:22:0) after 6m23s
[17:47:15.870] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:22:0)
[17:47:15.948] Build status: SCHEDULED
[17:47:15.948] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:23:0) after 7m10s
[17:54:25.990] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:23:0)
[17:54:26.046] Build status: SCHEDULED
[17:54:26.046] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:24:0) after 6m25s
[18:00:51.079] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:24:0)
[18:00:51.117] Build status: SCHEDULED
[18:00:51.117] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:25:0) after 1m18s
[18:02:09.149] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:25:0)
[18:02:09.199] Build status: SCHEDULED
[18:02:09.199] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:26:0) after 4m29s
[18:06:38.236] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:26:0)
[18:06:38.270] Build status: SCHEDULED
[18:06:38.270] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:27:0) after 4m58s
[18:11:36.300] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:27:0)
[18:11:36.345] Build status: SCHEDULED
[18:11:36.345] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:28:0) after 4m49s
[18:16:25.372] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:28:0)
[18:16:25.417] Build status: SCHEDULED
[18:16:25.417] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:29:0) after 5m58s
[18:20:42.851] Received PubSub notification, asking Buildbucket for the build status
[18:20:42.895] Build status: STARTED
[18:22:23.455] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:29:0)
[18:22:23.499] Build status: STARTED
[18:22:23.499] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:31:0) after 1m16s
[18:23:39.540] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:31:0)
[18:23:39.568] Build status: STARTED
[18:23:39.568] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8963298332629838800:32:0) after 9m6s
[18:25:57.097] Received PubSub notification, asking Buildbucket for the build status
[18:25:57.142] Build:
{
"id": "8797868270418505265",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 6053,
"createdBy": "project:v8",
"createTime": "2022-11-10T16:00:13.584139049Z",
"startTime": "2022-11-10T18:20:41.141602Z",
"endTime": "2022-11-10T18:25:56.551272830Z",
"updateTime": "2022-11-10T18:25:56.551272830Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "a83a306479fb5cc6d1036000640e2acdb68b3a32",
"ref": "refs/heads/main"
}
}
}
[18:25:57.142] Invocation finished in 2h25m45.050640595s with status FAILED