[14:50:02.113] New invocation is queued and will start shortly
[14:50:03.229] Starting the invocation (attempt 1)
[14:50:03.284] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/1135c0fca450231f151069d6ed1a8b6e1857de61"
[14:50:03.284] Popped gitiles commit info from properties and tags
[14:50:03.284] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[14:50:03.284] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[14:50:03.284] Buildbucket request:
{
"requestId": "8968285580289798288",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8968285580289798288",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "25c6d74ed7592fc6601a85999a2d57536da3cf82"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@25c6d74ed7592fc6601a85999a2d57536da3cf82",
"title": "25c6d74ed7592fc6601a85999a2d57536da3cf82",
"url": "https://chromium.googlesource.com/v8/v8/+/25c6d74ed7592fc6601a85999a2d57536da3cf82"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "488b0ff1f578ca0dba6019a227554574bfcacc4d"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@488b0ff1f578ca0dba6019a227554574bfcacc4d",
"title": "488b0ff1f578ca0dba6019a227554574bfcacc4d",
"url": "https://chromium.googlesource.com/v8/v8/+/488b0ff1f578ca0dba6019a227554574bfcacc4d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "498cc55cd4f00468ac75c6238f2bc2f4e14c723c"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@498cc55cd4f00468ac75c6238f2bc2f4e14c723c",
"title": "498cc55cd4f00468ac75c6238f2bc2f4e14c723c",
"url": "https://chromium.googlesource.com/v8/v8/+/498cc55cd4f00468ac75c6238f2bc2f4e14c723c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "0b8d71264a157a3592ea42fe42e82683edd8b5af"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@0b8d71264a157a3592ea42fe42e82683edd8b5af",
"title": "0b8d71264a157a3592ea42fe42e82683edd8b5af",
"url": "https://chromium.googlesource.com/v8/v8/+/0b8d71264a157a3592ea42fe42e82683edd8b5af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "aabcb150c3f7bbe9bb210bbf5473725c759f64ec"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@aabcb150c3f7bbe9bb210bbf5473725c759f64ec",
"title": "aabcb150c3f7bbe9bb210bbf5473725c759f64ec",
"url": "https://chromium.googlesource.com/v8/v8/+/aabcb150c3f7bbe9bb210bbf5473725c759f64ec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "08cbf4e4e570a5431ab8cbe12d48056b66dec647"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@08cbf4e4e570a5431ab8cbe12d48056b66dec647",
"title": "08cbf4e4e570a5431ab8cbe12d48056b66dec647",
"url": "https://chromium.googlesource.com/v8/v8/+/08cbf4e4e570a5431ab8cbe12d48056b66dec647"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1135c0fca450231f151069d6ed1a8b6e1857de61"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@1135c0fca450231f151069d6ed1a8b6e1857de61",
"title": "1135c0fca450231f151069d6ed1a8b6e1857de61",
"url": "https://chromium.googlesource.com/v8/v8/+/1135c0fca450231f151069d6ed1a8b6e1857de61"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1135c0fca450231f151069d6ed1a8b6e1857de61",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8968285580289798288"
},
{
"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"
}
}
[14:50:03.995] Scheduled build:
{
"id": "8802855518278403121",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 5714,
"createdBy": "project:v8",
"createTime": "2022-09-16T14:50:03.390299039Z",
"updateTime": "2022-09-16T14:50:03.390299039Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1135c0fca450231f151069d6ed1a8b6e1857de61",
"ref": "refs/heads/main"
}
}
}
[14:50:03.995] Task URL: https://cr-buildbucket-dev.appspot.com/build/8802855518278403121
[14:50:03.995] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:2:0) after 3m41s
[14:53:45.027] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:2:0)
[14:53:45.128] Build status: SCHEDULED
[14:53:45.128] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:3:0) after 9m27s
[15:03:12.153] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:3:0)
[15:03:12.200] Build status: SCHEDULED
[15:03:12.201] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:4:0) after 6m1s
[15:09:13.229] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:4:0)
[15:09:13.260] Build status: SCHEDULED
[15:09:13.260] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:5:0) after 7m6s
[15:16:19.306] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:5:0)
[15:16:19.336] Build status: SCHEDULED
[15:16:19.336] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:6:0) after 1m37s
[15:17:56.358] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:6:0)
[15:17:56.414] Build status: SCHEDULED
[15:17:56.414] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:7:0) after 5m1s
[15:22:57.435] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:7:0)
[15:22:57.464] Build status: SCHEDULED
[15:22:57.464] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:8:0) after 3m16s
[15:26:13.487] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:8:0)
[15:26:13.515] Build status: SCHEDULED
[15:26:13.515] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:9:0) after 9m14s
[15:35:27.540] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:9:0)
[15:35:27.596] Build status: SCHEDULED
[15:35:27.596] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:10:0) after 5m8s
[15:40:35.620] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:10:0)
[15:40:35.649] Build status: SCHEDULED
[15:40:35.649] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:11:0) after 4m29s
[15:45:04.673] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:11:0)
[15:45:04.727] Build status: SCHEDULED
[15:45:04.727] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:12:0) after 2m51s
[15:47:55.762] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:12:0)
[15:47:55.811] Build status: SCHEDULED
[15:47:55.811] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:13:0) after 9m37s
[15:57:32.843] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:13:0)
[15:57:32.888] Build status: SCHEDULED
[15:57:32.888] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:14:0) after 1m5s
[15:58:37.934] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:14:0)
[15:58:37.984] Build status: SCHEDULED
[15:58:37.984] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:15:0) after 2m2s
[16:00:40.009] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:15:0)
[16:00:40.040] Build status: SCHEDULED
[16:00:40.040] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:16:0) after 1m46s
[16:02:26.067] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:16:0)
[16:02:26.117] Build status: SCHEDULED
[16:02:26.117] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:17:0) after 5m49s
[16:08:15.145] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:17:0)
[16:08:15.187] Build status: SCHEDULED
[16:08:15.187] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:18:0) after 7m58s
[16:16:13.221] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:18:0)
[16:16:13.290] Build status: SCHEDULED
[16:16:13.290] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:19:0) after 5m19s
[16:21:32.315] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:19:0)
[16:21:32.503] Build status: SCHEDULED
[16:21:32.503] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:20:0) after 5m54s
[16:27:26.546] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:20:0)
[16:27:26.575] Build status: SCHEDULED
[16:27:26.575] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:21:0) after 6m41s
[16:34:07.599] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:21:0)
[16:34:07.636] Build status: SCHEDULED
[16:34:07.636] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:22:0) after 2m48s
[16:36:55.661] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:22:0)
[16:36:55.703] Build status: SCHEDULED
[16:36:55.703] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:23:0) after 5m15s
[16:40:51.198] Received PubSub notification, asking Buildbucket for the build status
[16:40:51.334] Build status: STARTED
[16:42:10.745] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:23:0)
[16:42:10.790] Build status: STARTED
[16:42:10.790] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:25:0) after 3m37s
[16:45:47.843] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:25:0)
[16:45:47.881] Build status: STARTED
[16:45:47.881] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8968285580289798288:26:0) after 4m15s
[16:46:28.765] Received PubSub notification, asking Buildbucket for the build status
[16:46:28.818] Build:
{
"id": "8802855518278403121",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 5714,
"createdBy": "project:v8",
"createTime": "2022-09-16T14:50:03.390299039Z",
"startTime": "2022-09-16T16:40:50.886588319Z",
"endTime": "2022-09-16T16:46:28.321094442Z",
"updateTime": "2022-09-16T16:46:28.321094442Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1135c0fca450231f151069d6ed1a8b6e1857de61",
"ref": "refs/heads/main"
}
}
}
[16:46:28.818] Invocation finished in 1h56m26.729106098s with status FAILED