[10:04:55.064] New invocation is queued and will start shortly
[10:04:56.133] Starting the invocation (attempt 1)
[10:04:56.160] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/v8/v8/+/b04b184405b8f1989f15844105cfacf0500817a8"
[10:04:56.160] Popped gitiles commit info from properties and tags
[10:04:56.160] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[10:04:56.163] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[10:04:56.163] Buildbucket request:
{
"requestId": "8941305622326563136",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler-dev.appspot.com",
"invocation": "8941305622326563136",
"job": "v8/V8 Win64 - dev image",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "c2dc92b6252c69fb8205e529dd5179fc1fdd18fc"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@c2dc92b6252c69fb8205e529dd5179fc1fdd18fc",
"title": "c2dc92b6252c69fb8205e529dd5179fc1fdd18fc",
"url": "https://chromium.googlesource.com/v8/v8/+/c2dc92b6252c69fb8205e529dd5179fc1fdd18fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "80b0061bb1aa7dee84b26b6aec1afa6ca2920508"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@80b0061bb1aa7dee84b26b6aec1afa6ca2920508",
"title": "80b0061bb1aa7dee84b26b6aec1afa6ca2920508",
"url": "https://chromium.googlesource.com/v8/v8/+/80b0061bb1aa7dee84b26b6aec1afa6ca2920508"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "af438df5abe74068811c41baef85fcde09e552db"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@af438df5abe74068811c41baef85fcde09e552db",
"title": "af438df5abe74068811c41baef85fcde09e552db",
"url": "https://chromium.googlesource.com/v8/v8/+/af438df5abe74068811c41baef85fcde09e552db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "5c72350b26d87b3e3d373999ba75fe801b4e8838"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5c72350b26d87b3e3d373999ba75fe801b4e8838",
"title": "5c72350b26d87b3e3d373999ba75fe801b4e8838",
"url": "https://chromium.googlesource.com/v8/v8/+/5c72350b26d87b3e3d373999ba75fe801b4e8838"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "44f4b5daff4650273b6f5909d8836f55e9f64df8"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@44f4b5daff4650273b6f5909d8836f55e9f64df8",
"title": "44f4b5daff4650273b6f5909d8836f55e9f64df8",
"url": "https://chromium.googlesource.com/v8/v8/+/44f4b5daff4650273b6f5909d8836f55e9f64df8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "5ee5c77044390164880373ddfea006cd774690c0"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@5ee5c77044390164880373ddfea006cd774690c0",
"title": "5ee5c77044390164880373ddfea006cd774690c0",
"url": "https://chromium.googlesource.com/v8/v8/+/5ee5c77044390164880373ddfea006cd774690c0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "79541914ca666c9d434e27d25cce53217f6e6751"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@79541914ca666c9d434e27d25cce53217f6e6751",
"title": "79541914ca666c9d434e27d25cce53217f6e6751",
"url": "https://chromium.googlesource.com/v8/v8/+/79541914ca666c9d434e27d25cce53217f6e6751"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "1ff5f472658640f580434028029c6294d36d225a"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@1ff5f472658640f580434028029c6294d36d225a",
"title": "1ff5f472658640f580434028029c6294d36d225a",
"url": "https://chromium.googlesource.com/v8/v8/+/1ff5f472658640f580434028029c6294d36d225a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "72e216c3785c13dbd4355e32ae8831846f96ac35"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@72e216c3785c13dbd4355e32ae8831846f96ac35",
"title": "72e216c3785c13dbd4355e32ae8831846f96ac35",
"url": "https://chromium.googlesource.com/v8/v8/+/72e216c3785c13dbd4355e32ae8831846f96ac35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "a8d014deeff5a52ec36f93354b3857e271c46cf2"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@a8d014deeff5a52ec36f93354b3857e271c46cf2",
"title": "a8d014deeff5a52ec36f93354b3857e271c46cf2",
"url": "https://chromium.googlesource.com/v8/v8/+/a8d014deeff5a52ec36f93354b3857e271c46cf2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "fd2b1f3ee7ec38bb854c1df82fde402aad767d58"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@fd2b1f3ee7ec38bb854c1df82fde402aad767d58",
"title": "fd2b1f3ee7ec38bb854c1df82fde402aad767d58",
"url": "https://chromium.googlesource.com/v8/v8/+/fd2b1f3ee7ec38bb854c1df82fde402aad767d58"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "660bd863818d41cfdcca672c5c6966ba18556d96"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@660bd863818d41cfdcca672c5c6966ba18556d96",
"title": "660bd863818d41cfdcca672c5c6966ba18556d96",
"url": "https://chromium.googlesource.com/v8/v8/+/660bd863818d41cfdcca672c5c6966ba18556d96"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8f76604a6d943c1dfb7d6382d5c9d98c41ecfacd"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8f76604a6d943c1dfb7d6382d5c9d98c41ecfacd",
"title": "8f76604a6d943c1dfb7d6382d5c9d98c41ecfacd",
"url": "https://chromium.googlesource.com/v8/v8/+/8f76604a6d943c1dfb7d6382d5c9d98c41ecfacd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "8a5f4392291c89a03607a9dd2fee636a463aaba1"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@8a5f4392291c89a03607a9dd2fee636a463aaba1",
"title": "8a5f4392291c89a03607a9dd2fee636a463aaba1",
"url": "https://chromium.googlesource.com/v8/v8/+/8a5f4392291c89a03607a9dd2fee636a463aaba1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "ba22c088522664d0379c8710db311fc606530cee"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@ba22c088522664d0379c8710db311fc606530cee",
"title": "ba22c088522664d0379c8710db311fc606530cee",
"url": "https://chromium.googlesource.com/v8/v8/+/ba22c088522664d0379c8710db311fc606530cee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/v8/v8",
"revision": "b04b184405b8f1989f15844105cfacf0500817a8"
},
"id": "https://chromium.googlesource.com/v8/v8/+/refs/heads/main@b04b184405b8f1989f15844105cfacf0500817a8",
"title": "b04b184405b8f1989f15844105cfacf0500817a8",
"url": "https://chromium.googlesource.com/v8/v8/+/b04b184405b8f1989f15844105cfacf0500817a8"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "b04b184405b8f1989f15844105cfacf0500817a8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8941305622326563136"
},
{
"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"
}
}
[10:04:56.816] Scheduled build:
{
"id": "8775875560427585681",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 7619,
"createdBy": "project:v8",
"createTime": "2023-07-11T10:04:56.250248703Z",
"updateTime": "2023-07-11T10:04:56.250248703Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "b04b184405b8f1989f15844105cfacf0500817a8",
"ref": "refs/heads/main"
}
}
}
[10:04:56.816] Task URL: https://cr-buildbucket-dev.appspot.com/build/8775875560427585681
[10:04:56.816] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:2:0) after 5m59s
[10:05:05.766] Received PubSub notification, asking Buildbucket for the build status
[10:05:05.797] Build status: SCHEDULED
[10:10:55.836] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:2:0)
[10:10:55.864] Build status: SCHEDULED
[10:10:55.864] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:4:0) after 3m16s
[10:14:11.887] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:4:0)
[10:14:11.923] Build status: SCHEDULED
[10:14:11.923] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:5:0) after 1m32s
[10:15:43.944] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:5:0)
[10:15:43.970] Build status: SCHEDULED
[10:15:43.970] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:6:0) after 7m29s
[10:23:12.988] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:6:0)
[10:23:13.037] Build status: SCHEDULED
[10:23:13.037] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:7:0) after 9m50s
[10:33:03.055] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:7:0)
[10:33:03.079] Build status: SCHEDULED
[10:33:03.079] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:8:0) after 6m15s
[10:39:18.102] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:8:0)
[10:39:18.142] Build status: SCHEDULED
[10:39:18.142] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:9:0) after 3m42s
[10:43:00.162] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:9:0)
[10:43:00.361] Build status: SCHEDULED
[10:43:00.361] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:10:0) after 6m9s
[10:49:09.379] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:10:0)
[10:49:09.408] Build status: SCHEDULED
[10:49:09.408] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:11:0) after 7m40s
[10:56:49.428] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:11:0)
[10:56:49.471] Build status: SCHEDULED
[10:56:49.471] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:12:0) after 1m4s
[10:57:53.490] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:12:0)
[10:57:53.536] Build status: SCHEDULED
[10:57:53.536] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:13:0) after 8m0s
[11:05:53.553] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:13:0)
[11:05:53.629] Build status: SCHEDULED
[11:05:53.629] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:14:0) after 1m56s
[11:07:49.647] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:14:0)
[11:07:49.672] Build status: SCHEDULED
[11:07:49.672] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:15:0) after 8m49s
[11:16:38.710] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:15:0)
[11:16:39.810] Build status: SCHEDULED
[11:16:39.810] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:16:0) after 1m4s
[11:17:43.860] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:16:0)
[11:17:43.934] Build status: SCHEDULED
[11:17:43.934] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:17:0) after 5m10s
[11:22:53.954] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:17:0)
[11:22:53.975] Build status: SCHEDULED
[11:22:53.975] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:18:0) after 7m13s
[11:30:06.994] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:18:0)
[11:30:07.021] Build status: SCHEDULED
[11:30:07.021] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:19:0) after 1m55s
[11:32:02.038] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:19:0)
[11:32:02.059] Build status: SCHEDULED
[11:32:02.059] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:20:0) after 2m45s
[11:34:47.077] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:20:0)
[11:34:47.144] Build status: SCHEDULED
[11:34:47.144] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:21:0) after 5m37s
[11:40:24.160] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:21:0)
[11:40:24.288] Build status: SCHEDULED
[11:40:24.288] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:22:0) after 6m12s
[11:46:36.324] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:22:0)
[11:46:36.363] Build status: SCHEDULED
[11:46:36.363] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:23:0) after 1m30s
[11:48:06.385] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:23:0)
[11:48:06.420] Build status: SCHEDULED
[11:48:06.420] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:24:0) after 2m37s
[11:50:43.437] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:24:0)
[11:50:43.472] Build status: SCHEDULED
[11:50:43.472] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:25:0) after 5m51s
[11:56:34.493] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:25:0)
[11:56:34.533] Build status: SCHEDULED
[11:56:34.533] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:26:0) after 6m12s
[12:02:12.835] Received PubSub notification, asking Buildbucket for the build status
[12:02:12.856] Build status: STARTED
[12:02:46.551] Handling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:26:0)
[12:02:46.576] Build status: STARTED
[12:02:46.576] Scheduling timer "check-buildbucket-build-status" (v8/V8 Win64 - dev image:8941305622326563136:28:0) after 7m20s
[12:06:19.471] Received PubSub notification, asking Buildbucket for the build status
[12:06:19.491] Build:
{
"id": "8775875560427585681",
"builder": {
"project": "v8",
"bucket": "ci",
"builder": "V8 Win64 - dev image"
},
"number": 7619,
"createdBy": "project:v8",
"createTime": "2023-07-11T10:04:56.250248703Z",
"startTime": "2023-07-11T12:02:12.161200Z",
"endTime": "2023-07-11T12:06:17.753676379Z",
"updateTime": "2023-07-11T12:06:17.753676379Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "v8/v8",
"id": "b04b184405b8f1989f15844105cfacf0500817a8",
"ref": "refs/heads/main"
}
}
}
[12:06:19.491] Invocation finished in 2h1m24.434951292s with status SUCCEEDED