[11:59:12.081] New invocation is queued and will start shortly
[11:59:13.161] Starting the invocation (attempt 1)
[11:59:13.202] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/1c94e7a94c9d49ec7475c6433115c052de5a94c6"
[11:59:13.202] Popped gitiles commit info from properties and tags
[11:59:13.202] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[11:59:13.202] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[11:59:13.202] Buildbucket request:
{
"requestId": "8979983336883292320",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8979983336883292320",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "b086aa700c310c8f8b8018f1d81d64a526e5f150"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b086aa700c310c8f8b8018f1d81d64a526e5f150",
"title": "b086aa700c310c8f8b8018f1d81d64a526e5f150",
"url": "https://chromium.googlesource.com/v8/v8/+/b086aa700c310c8f8b8018f1d81d64a526e5f150"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "395350c064c3a8cef01e61b12053e51f7b8d0078"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@395350c064c3a8cef01e61b12053e51f7b8d0078",
"title": "395350c064c3a8cef01e61b12053e51f7b8d0078",
"url": "https://chromium.googlesource.com/v8/v8/+/395350c064c3a8cef01e61b12053e51f7b8d0078"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "7e233997b05077e7858e2ad9d30d72d8cffdc9ff"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@7e233997b05077e7858e2ad9d30d72d8cffdc9ff",
"title": "7e233997b05077e7858e2ad9d30d72d8cffdc9ff",
"url": "https://chromium.googlesource.com/v8/v8/+/7e233997b05077e7858e2ad9d30d72d8cffdc9ff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "d5245542c933c8165ece1e3859daa6fadd7d774c"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@d5245542c933c8165ece1e3859daa6fadd7d774c",
"title": "d5245542c933c8165ece1e3859daa6fadd7d774c",
"url": "https://chromium.googlesource.com/v8/v8/+/d5245542c933c8165ece1e3859daa6fadd7d774c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1c94e7a94c9d49ec7475c6433115c052de5a94c6"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@1c94e7a94c9d49ec7475c6433115c052de5a94c6",
"title": "1c94e7a94c9d49ec7475c6433115c052de5a94c6",
"url": "https://chromium.googlesource.com/v8/v8/+/1c94e7a94c9d49ec7475c6433115c052de5a94c6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1c94e7a94c9d49ec7475c6433115c052de5a94c6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8979983336883292320"
},
{
"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"
}
}
[11:59:14.100] Scheduled build:
{
"id": "8814553274947030929",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 4588,
"createdBy": "project:v8",
"createTime": "2022-05-10T11:59:13.296199048Z",
"updateTime": "2022-05-10T11:59:13.296199048Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1c94e7a94c9d49ec7475c6433115c052de5a94c6",
"ref": "refs/heads/main"
}
}
}
[11:59:14.100] Task URL: https://cr-buildbucket-dev.appspot.com/build/8814553274947030929
[11:59:14.100] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:2:0) after 4m44s
[12:03:58.110] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:2:0)
[12:03:58.176] Build status: SCHEDULED
[12:03:58.177] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:3:0) after 1m46s
[12:05:44.221] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:3:0)
[12:05:44.303] Build status: SCHEDULED
[12:05:44.303] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:4:0) after 8m48s
[12:14:32.323] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:4:0)
[12:14:32.367] Build status: SCHEDULED
[12:14:32.367] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:5:0) after 5m56s
[12:20:28.386] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:5:0)
[12:20:28.494] Build status: SCHEDULED
[12:20:28.494] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:6:0) after 9m14s
[12:29:42.513] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:6:0)
[12:29:42.553] Build status: SCHEDULED
[12:29:42.553] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:7:0) after 3m22s
[12:33:04.666] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:7:0)
[12:33:04.718] Build status: SCHEDULED
[12:33:04.718] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:8:0) after 7m18s
[12:40:22.738] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:8:0)
[12:40:22.788] Build status: SCHEDULED
[12:40:22.788] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:9:0) after 4m15s
[12:44:37.808] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:9:0)
[12:44:37.881] Build status: SCHEDULED
[12:44:37.881] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:10:0) after 6m23s
[12:51:00.905] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:10:0)
[12:51:00.947] Build status: SCHEDULED
[12:51:00.947] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:11:0) after 4m26s
[12:55:26.972] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:11:0)
[12:55:27.039] Build status: SCHEDULED
[12:55:27.040] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:12:0) after 9m49s
[13:05:16.059] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:12:0)
[13:05:16.130] Build status: SCHEDULED
[13:05:16.130] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:13:0) after 7m22s
[13:12:38.497] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:13:0)
[13:12:38.546] Build status: SCHEDULED
[13:12:38.546] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:14:0) after 2m1s
[13:14:39.569] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:14:0)
[13:14:39.625] Build status: SCHEDULED
[13:14:39.625] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:15:0) after 3m57s
[13:18:36.646] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:15:0)
[13:18:36.748] Build status: SCHEDULED
[13:18:36.748] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:16:0) after 7m23s
[13:25:59.802] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:16:0)
[13:25:59.831] Build status: SCHEDULED
[13:25:59.831] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:17:0) after 6m4s
[13:32:03.854] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:17:0)
[13:32:03.893] Build status: SCHEDULED
[13:32:03.893] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:18:0) after 7m10s
[13:39:13.919] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:18:0)
[13:39:13.944] Build status: SCHEDULED
[13:39:13.944] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:19:0) after 9m59s
[13:49:12.964] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:19:0)
[13:49:13.072] Build status: SCHEDULED
[13:49:13.072] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:20:0) after 9m10s
[13:58:23.095] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:20:0)
[13:58:23.165] Build status: SCHEDULED
[13:58:23.165] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:21:0) after 5m13s
[14:03:36.186] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:21:0)
[14:03:36.267] Build status: SCHEDULED
[14:03:36.267] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:22:0) after 2m48s
[14:06:24.286] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:22:0)
[14:06:24.381] Build status: SCHEDULED
[14:06:24.381] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8979983336883292320:23:0) after 5m37s
[14:07:41.834] Received PubSub notification, asking Buildbucket for the build status
[14:07:41.901] Build status: STARTED
[14:10:44.032] Received PubSub notification, asking Buildbucket for the build status
[14:10:44.115] Build:
{
"id": "8814553274947030929",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 4588,
"createdBy": "project:v8",
"createTime": "2022-05-10T11:59:13.296199048Z",
"startTime": "2022-05-10T14:07:40.039254Z",
"endTime": "2022-05-10T14:10:43.638600241Z",
"updateTime": "2022-05-10T14:10:43.638600241Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "1c94e7a94c9d49ec7475c6433115c052de5a94c6",
"ref": "refs/heads/main"
}
}
}
[14:10:44.115] Invocation finished in 2h11m32.04979309s with status SUCCEEDED