[17:57:50.334] New invocation is queued and will start shortly
[17:57:51.420] Starting the invocation (attempt 1)
[17:57:51.444] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/decf7ce83d3246af92a127739d8f4fa9944f96bf"
[17:57:51.444] Popped gitiles commit info from properties and tags
[17:57:51.444] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:57:51.445] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:57:51.445] Buildbucket request:
{
"requestId": "8868707698616774304",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-profile-minimal.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868707698616774304",
"job": "fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1336c04423cb613083329bac6c015340d9f70a51"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1336c04423cb613083329bac6c015340d9f70a51",
"title": "1336c04423cb613083329bac6c015340d9f70a51",
"url": "https://fuchsia.googlesource.com/integration/+/1336c04423cb613083329bac6c015340d9f70a51"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6838532f9208fb587ccf16b04573945a7c02e7ea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6838532f9208fb587ccf16b04573945a7c02e7ea",
"title": "6838532f9208fb587ccf16b04573945a7c02e7ea",
"url": "https://fuchsia.googlesource.com/integration/+/6838532f9208fb587ccf16b04573945a7c02e7ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c351cc1a023c2c5f041801712a19ccb5da6a9c7b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c351cc1a023c2c5f041801712a19ccb5da6a9c7b",
"title": "c351cc1a023c2c5f041801712a19ccb5da6a9c7b",
"url": "https://fuchsia.googlesource.com/integration/+/c351cc1a023c2c5f041801712a19ccb5da6a9c7b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "075cc63bb7e6644d8458f4e3958cec757997353b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@075cc63bb7e6644d8458f4e3958cec757997353b",
"title": "075cc63bb7e6644d8458f4e3958cec757997353b",
"url": "https://fuchsia.googlesource.com/integration/+/075cc63bb7e6644d8458f4e3958cec757997353b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c2267f133ed019b095ec49be45a4934925058dcd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c2267f133ed019b095ec49be45a4934925058dcd",
"title": "c2267f133ed019b095ec49be45a4934925058dcd",
"url": "https://fuchsia.googlesource.com/integration/+/c2267f133ed019b095ec49be45a4934925058dcd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "255bde787571bd96af7e216133ebfa42fc634633"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@255bde787571bd96af7e216133ebfa42fc634633",
"title": "255bde787571bd96af7e216133ebfa42fc634633",
"url": "https://fuchsia.googlesource.com/integration/+/255bde787571bd96af7e216133ebfa42fc634633"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "805a251148fa5cd5a10f618bf8a4610f388f63d0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@805a251148fa5cd5a10f618bf8a4610f388f63d0",
"title": "805a251148fa5cd5a10f618bf8a4610f388f63d0",
"url": "https://fuchsia.googlesource.com/integration/+/805a251148fa5cd5a10f618bf8a4610f388f63d0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d997e9a64523cc5b47f6b3a1a87f4c044cb0394e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d997e9a64523cc5b47f6b3a1a87f4c044cb0394e",
"title": "d997e9a64523cc5b47f6b3a1a87f4c044cb0394e",
"url": "https://fuchsia.googlesource.com/integration/+/d997e9a64523cc5b47f6b3a1a87f4c044cb0394e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a122c536b89a89393162dfb7bbf4a669283470be"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a122c536b89a89393162dfb7bbf4a669283470be",
"title": "a122c536b89a89393162dfb7bbf4a669283470be",
"url": "https://fuchsia.googlesource.com/integration/+/a122c536b89a89393162dfb7bbf4a669283470be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a12da55e85c91873809cb44a983da858f1d79bd8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a12da55e85c91873809cb44a983da858f1d79bd8",
"title": "a12da55e85c91873809cb44a983da858f1d79bd8",
"url": "https://fuchsia.googlesource.com/integration/+/a12da55e85c91873809cb44a983da858f1d79bd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "decf7ce83d3246af92a127739d8f4fa9944f96bf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@decf7ce83d3246af92a127739d8f4fa9944f96bf",
"title": "decf7ce83d3246af92a127739d8f4fa9944f96bf",
"url": "https://fuchsia.googlesource.com/integration/+/decf7ce83d3246af92a127739d8f4fa9944f96bf"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "decf7ce83d3246af92a127739d8f4fa9944f96bf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868707698616774304"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:57:51.801] Scheduled build:
{
"id": "8703277636723398801",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-profile-minimal.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-19T17:57:51.512361264Z",
"updateTime": "2025-09-19T17:57:51.512361264Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "decf7ce83d3246af92a127739d8f4fa9944f96bf",
"ref": "refs/heads/main"
}
}
}
[17:57:51.802] Task URL: https://cr-buildbucket.appspot.com/build/8703277636723398801
[17:57:51.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:2:0) after 4m28s
[17:57:51.900] Received PubSub notification, asking Buildbucket for the build status
[17:57:51.921] Build status: SCHEDULED
[17:57:55.167] Received PubSub notification, asking Buildbucket for the build status
[17:57:55.184] Build status: STARTED
[18:02:19.825] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:2:0)
[18:02:19.846] Build status: STARTED
[18:02:19.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:5:0) after 4m42s
[18:07:01.869] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:5:0)
[18:07:01.971] Build status: STARTED
[18:07:01.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:6:0) after 6m56s
[18:13:57.986] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:6:0)
[18:13:58.006] Build status: STARTED
[18:13:58.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:7:0) after 8m55s
[18:22:53.073] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:7:0)
[18:22:53.091] Build status: STARTED
[18:22:53.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:8:0) after 1m49s
[18:24:42.157] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:8:0)
[18:24:42.187] Build status: STARTED
[18:24:42.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:9:0) after 8m6s
[18:32:48.217] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:9:0)
[18:32:48.240] Build status: STARTED
[18:32:48.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:10:0) after 2m56s
[18:35:44.314] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:10:0)
[18:35:44.342] Build status: STARTED
[18:35:44.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:11:0) after 7m33s
[18:43:17.366] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:11:0)
[18:43:17.393] Build status: STARTED
[18:43:17.393] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:12:0) after 8m34s
[18:51:51.550] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:12:0)
[18:51:51.565] Build status: STARTED
[18:51:51.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:13:0) after 1m9s
[18:53:00.619] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:13:0)
[18:53:00.654] Build status: STARTED
[18:53:00.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:14:0) after 5m3s
[18:58:03.676] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:14:0)
[18:58:03.691] Build status: STARTED
[18:58:03.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:15:0) after 9m31s
[19:07:34.761] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:15:0)
[19:07:34.798] Build status: STARTED
[19:07:34.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:16:0) after 4m10s
[19:11:44.815] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:16:0)
[19:11:44.834] Build status: STARTED
[19:11:44.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:17:0) after 4m3s
[19:15:47.889] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:17:0)
[19:15:47.905] Build status: STARTED
[19:15:47.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:18:0) after 2m47s
[19:18:34.916] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:18:0)
[19:18:35.017] Build status: STARTED
[19:18:35.017] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:19:0) after 5m12s
[19:23:47.035] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:19:0)
[19:23:47.052] Build status: STARTED
[19:23:47.052] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:20:0) after 1m7s
[19:24:54.057] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:20:0)
[19:24:54.119] Build status: STARTED
[19:24:54.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:21:0) after 2m0s
[19:26:54.135] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:21:0)
[19:26:54.153] Build status: STARTED
[19:26:54.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-profile-minimal.x64-release-e5a0ec7f:8868707698616774304:22:0) after 1m50s
[19:27:57.648] Received PubSub notification, asking Buildbucket for the build status
[19:27:57.665] Build:
{
"id": "8703277636723398801",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-profile-minimal.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-19T17:57:51.512361264Z",
"startTime": "2025-09-19T17:57:54.524169507Z",
"endTime": "2025-09-19T19:27:57.399932621Z",
"updateTime": "2025-09-19T19:27:57.399932621Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "decf7ce83d3246af92a127739d8f4fa9944f96bf",
"ref": "refs/heads/main"
}
}
}
[19:27:57.665] Invocation finished in 1h30m7.343023876s with status SUCCEEDED