[01:59:00.152] New invocation is queued and will start shortly
[01:59:01.225] Starting the invocation (attempt 1)
[01:59:01.262] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/5a282826c1546293c7e3b63723e8fdd9b9922b83"
[01:59:01.262] Popped gitiles commit info from properties and tags
[01:59:01.262] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:59:01.262] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:59:01.263] Buildbucket request:
{
"requestId": "8894044577025459840",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-cxx20"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8894044577025459840",
"job": "fuchsia/core.x64-cxx20-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb2751084e9aac7da710a7d3cc4f340523a664a6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb2751084e9aac7da710a7d3cc4f340523a664a6",
"title": "fb2751084e9aac7da710a7d3cc4f340523a664a6",
"url": "https://fuchsia.googlesource.com/integration/+/fb2751084e9aac7da710a7d3cc4f340523a664a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cc7802b453a1c9dc3c3d3ba589c02d65a371e6f4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cc7802b453a1c9dc3c3d3ba589c02d65a371e6f4",
"title": "cc7802b453a1c9dc3c3d3ba589c02d65a371e6f4",
"url": "https://fuchsia.googlesource.com/integration/+/cc7802b453a1c9dc3c3d3ba589c02d65a371e6f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "16deb59c965e8ac41ec96f2681fbe726405420f9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@16deb59c965e8ac41ec96f2681fbe726405420f9",
"title": "16deb59c965e8ac41ec96f2681fbe726405420f9",
"url": "https://fuchsia.googlesource.com/integration/+/16deb59c965e8ac41ec96f2681fbe726405420f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "abda9cbda51a631bde7a5df8c1d4ddf5624d709a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@abda9cbda51a631bde7a5df8c1d4ddf5624d709a",
"title": "abda9cbda51a631bde7a5df8c1d4ddf5624d709a",
"url": "https://fuchsia.googlesource.com/integration/+/abda9cbda51a631bde7a5df8c1d4ddf5624d709a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e63a3b06497a1d642c39b2bec738036595be44af"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e63a3b06497a1d642c39b2bec738036595be44af",
"title": "e63a3b06497a1d642c39b2bec738036595be44af",
"url": "https://fuchsia.googlesource.com/integration/+/e63a3b06497a1d642c39b2bec738036595be44af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "77d2dd7bdbb4e1cd7c85779887643fbaf0d4a136"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@77d2dd7bdbb4e1cd7c85779887643fbaf0d4a136",
"title": "77d2dd7bdbb4e1cd7c85779887643fbaf0d4a136",
"url": "https://fuchsia.googlesource.com/integration/+/77d2dd7bdbb4e1cd7c85779887643fbaf0d4a136"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6aa42f106723a24427b42fcba4db726e50e560a6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6aa42f106723a24427b42fcba4db726e50e560a6",
"title": "6aa42f106723a24427b42fcba4db726e50e560a6",
"url": "https://fuchsia.googlesource.com/integration/+/6aa42f106723a24427b42fcba4db726e50e560a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0caf0bcea718713d083b97b0043a99abfa974abb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0caf0bcea718713d083b97b0043a99abfa974abb",
"title": "0caf0bcea718713d083b97b0043a99abfa974abb",
"url": "https://fuchsia.googlesource.com/integration/+/0caf0bcea718713d083b97b0043a99abfa974abb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "770e556524268245009ea1821915159e68252f27"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@770e556524268245009ea1821915159e68252f27",
"title": "770e556524268245009ea1821915159e68252f27",
"url": "https://fuchsia.googlesource.com/integration/+/770e556524268245009ea1821915159e68252f27"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bd21d2e517c81e68d2c4d48304471837dca58435"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bd21d2e517c81e68d2c4d48304471837dca58435",
"title": "bd21d2e517c81e68d2c4d48304471837dca58435",
"url": "https://fuchsia.googlesource.com/integration/+/bd21d2e517c81e68d2c4d48304471837dca58435"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fdf142ff3d0d6eda439ca0b3297bd18653316085"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fdf142ff3d0d6eda439ca0b3297bd18653316085",
"title": "fdf142ff3d0d6eda439ca0b3297bd18653316085",
"url": "https://fuchsia.googlesource.com/integration/+/fdf142ff3d0d6eda439ca0b3297bd18653316085"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "31e42ab740ae10207750f7cea3cba293d2ef1747"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@31e42ab740ae10207750f7cea3cba293d2ef1747",
"title": "31e42ab740ae10207750f7cea3cba293d2ef1747",
"url": "https://fuchsia.googlesource.com/integration/+/31e42ab740ae10207750f7cea3cba293d2ef1747"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fcc82b8487b2ef8bd9f725f369b39c2d7b3e417f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fcc82b8487b2ef8bd9f725f369b39c2d7b3e417f",
"title": "fcc82b8487b2ef8bd9f725f369b39c2d7b3e417f",
"url": "https://fuchsia.googlesource.com/integration/+/fcc82b8487b2ef8bd9f725f369b39c2d7b3e417f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f09ea4c47ed05e8026e24aadac5818d4a59ceb9a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f09ea4c47ed05e8026e24aadac5818d4a59ceb9a",
"title": "f09ea4c47ed05e8026e24aadac5818d4a59ceb9a",
"url": "https://fuchsia.googlesource.com/integration/+/f09ea4c47ed05e8026e24aadac5818d4a59ceb9a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dab6c829adbbd6b458004432fe6364dedb13f3be"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dab6c829adbbd6b458004432fe6364dedb13f3be",
"title": "dab6c829adbbd6b458004432fe6364dedb13f3be",
"url": "https://fuchsia.googlesource.com/integration/+/dab6c829adbbd6b458004432fe6364dedb13f3be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "de79ea4576e22d610891e65277213f950b30c7a2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@de79ea4576e22d610891e65277213f950b30c7a2",
"title": "de79ea4576e22d610891e65277213f950b30c7a2",
"url": "https://fuchsia.googlesource.com/integration/+/de79ea4576e22d610891e65277213f950b30c7a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "16522b274d5d74900815cca427233474058e7ece"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@16522b274d5d74900815cca427233474058e7ece",
"title": "16522b274d5d74900815cca427233474058e7ece",
"url": "https://fuchsia.googlesource.com/integration/+/16522b274d5d74900815cca427233474058e7ece"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7fc59738b392cbf0dfcfaf58809b58d554ec374c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7fc59738b392cbf0dfcfaf58809b58d554ec374c",
"title": "7fc59738b392cbf0dfcfaf58809b58d554ec374c",
"url": "https://fuchsia.googlesource.com/integration/+/7fc59738b392cbf0dfcfaf58809b58d554ec374c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "71c7975ec0f10ef25b70c26804b2694086f41d45"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@71c7975ec0f10ef25b70c26804b2694086f41d45",
"title": "71c7975ec0f10ef25b70c26804b2694086f41d45",
"url": "https://fuchsia.googlesource.com/integration/+/71c7975ec0f10ef25b70c26804b2694086f41d45"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5a282826c1546293c7e3b63723e8fdd9b9922b83"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5a282826c1546293c7e3b63723e8fdd9b9922b83",
"title": "5a282826c1546293c7e3b63723e8fdd9b9922b83",
"url": "https://fuchsia.googlesource.com/integration/+/5a282826c1546293c7e3b63723e8fdd9b9922b83"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5a282826c1546293c7e3b63723e8fdd9b9922b83",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894044577025459840"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-cxx20-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:59:01.688] Scheduled build:
{
"id": "8728614515103332321",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-cxx20"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-14T01:59:01.343340100Z",
"updateTime": "2024-12-14T01:59:01.343340100Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5a282826c1546293c7e3b63723e8fdd9b9922b83",
"ref": "refs/heads/main"
}
}
}
[01:59:01.689] Task URL: https://cr-buildbucket.appspot.com/build/8728614515103332321
[01:59:01.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:2:0) after 7m38s
[01:59:02.644] Received PubSub notification, asking Buildbucket for the build status
[01:59:02.703] Build status: SCHEDULED
[01:59:07.002] Received PubSub notification, asking Buildbucket for the build status
[01:59:07.073] Build status: STARTED
[02:06:39.709] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:2:0)
[02:06:39.739] Build status: STARTED
[02:06:39.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:5:0) after 4m18s
[02:10:57.943] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:5:0)
[02:10:57.968] Build status: STARTED
[02:10:57.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:6:0) after 4m45s
[02:15:42.987] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:6:0)
[02:15:43.006] Build status: STARTED
[02:15:43.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:7:0) after 5m26s
[02:21:09.023] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:7:0)
[02:21:09.051] Build status: STARTED
[02:21:09.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:8:0) after 3m47s
[02:24:56.072] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:8:0)
[02:24:56.104] Build status: STARTED
[02:24:56.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:9:0) after 8m41s
[02:33:37.120] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:9:0)
[02:33:37.189] Build status: STARTED
[02:33:37.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:10:0) after 3m23s
[02:37:00.208] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:10:0)
[02:37:00.241] Build status: STARTED
[02:37:00.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:11:0) after 8m55s
[02:45:55.267] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:11:0)
[02:45:55.286] Build status: STARTED
[02:45:55.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:12:0) after 4m0s
[02:49:55.309] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:12:0)
[02:49:55.329] Build status: STARTED
[02:49:55.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:13:0) after 6m20s
[02:56:15.347] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:13:0)
[02:56:15.366] Build status: STARTED
[02:56:15.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:14:0) after 1m8s
[02:57:23.388] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:14:0)
[02:57:23.405] Build status: STARTED
[02:57:23.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:15:0) after 7m51s
[03:05:14.424] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:15:0)
[03:05:14.444] Build status: STARTED
[03:05:14.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:16:0) after 6m21s
[03:11:35.435] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:16:0)
[03:11:35.454] Build status: STARTED
[03:11:35.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:17:0) after 7m3s
[03:18:38.561] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:17:0)
[03:18:38.579] Build status: STARTED
[03:18:38.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:18:0) after 2m1s
[03:20:39.646] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:18:0)
[03:20:39.670] Build status: STARTED
[03:20:39.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:19:0) after 8m45s
[03:29:24.786] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:19:0)
[03:29:24.802] Build status: STARTED
[03:29:24.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:20:0) after 2m37s
[03:32:01.818] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:20:0)
[03:32:01.835] Build status: STARTED
[03:32:01.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8894044577025459840:21:0) after 5m29s
[03:32:53.170] Received PubSub notification, asking Buildbucket for the build status
[03:32:53.195] Build:
{
"id": "8728614515103332321",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-cxx20"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-14T01:59:01.343340100Z",
"startTime": "2024-12-14T01:59:06.798020095Z",
"endTime": "2024-12-14T03:32:20.143001977Z",
"updateTime": "2024-12-14T03:32:20.143001977Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5a282826c1546293c7e3b63723e8fdd9b9922b83",
"ref": "refs/heads/main"
}
}
}
[03:32:53.195] Invocation finished in 1h33m53.068587797s with status SUCCEEDED