[18:37:05.134] New invocation is queued and will start shortly
[18:37:06.187] Starting the invocation (attempt 1)
[18:37:06.214] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/c21081f05e4fcde1008cb2a3968ac8950613b8d3"
[18:37:06.215] Popped gitiles commit info from properties and tags
[18:37:06.215] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:37:06.215] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:37:06.215] Buildbucket request:
{
"requestId": "8885465668207008512",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8885465668207008512",
"job": "fuchsia/core.x64-release-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "891f9ef7234e4d03b4a4d3a8c3aae770948d1784"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@891f9ef7234e4d03b4a4d3a8c3aae770948d1784",
"title": "891f9ef7234e4d03b4a4d3a8c3aae770948d1784",
"url": "https://fuchsia.googlesource.com/integration/+/891f9ef7234e4d03b4a4d3a8c3aae770948d1784"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0b94ce9b3c1b764a244d21491430e11453b4690b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0b94ce9b3c1b764a244d21491430e11453b4690b",
"title": "0b94ce9b3c1b764a244d21491430e11453b4690b",
"url": "https://fuchsia.googlesource.com/integration/+/0b94ce9b3c1b764a244d21491430e11453b4690b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "169a89bcc771e5df3c2cd14f5e5bb4b1af9c32a0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@169a89bcc771e5df3c2cd14f5e5bb4b1af9c32a0",
"title": "169a89bcc771e5df3c2cd14f5e5bb4b1af9c32a0",
"url": "https://fuchsia.googlesource.com/integration/+/169a89bcc771e5df3c2cd14f5e5bb4b1af9c32a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c21081f05e4fcde1008cb2a3968ac8950613b8d3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c21081f05e4fcde1008cb2a3968ac8950613b8d3",
"title": "c21081f05e4fcde1008cb2a3968ac8950613b8d3",
"url": "https://fuchsia.googlesource.com/integration/+/c21081f05e4fcde1008cb2a3968ac8950613b8d3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c21081f05e4fcde1008cb2a3968ac8950613b8d3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8885465668207008512"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:37:06.666] Scheduled build:
{
"id": "8720035606297997793",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-18T18:37:06.333511375Z",
"updateTime": "2025-03-18T18:37:06.333511375Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c21081f05e4fcde1008cb2a3968ac8950613b8d3",
"ref": "refs/heads/main"
}
}
}
[18:37:06.666] Task URL: https://cr-buildbucket.appspot.com/build/8720035606297997793
[18:37:06.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:2:0) after 3m16s
[18:37:06.752] Received PubSub notification, asking Buildbucket for the build status
[18:37:06.775] Build status: SCHEDULED
[18:37:14.894] Received PubSub notification, asking Buildbucket for the build status
[18:37:14.919] Build status: STARTED
[18:40:22.690] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:2:0)
[18:40:22.716] Build status: STARTED
[18:40:22.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:5:0) after 5m13s
[18:45:35.783] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:5:0)
[18:45:35.860] Build status: STARTED
[18:45:35.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:6:0) after 4m2s
[18:49:37.846] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:6:0)
[18:49:37.870] Build status: STARTED
[18:49:37.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:7:0) after 8m50s
[18:58:27.891] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:7:0)
[18:58:27.919] Build status: STARTED
[18:58:27.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:8:0) after 8m24s
[19:06:51.936] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:8:0)
[19:06:51.964] Build status: STARTED
[19:06:51.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:9:0) after 5m3s
[19:11:54.980] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:9:0)
[19:11:55.007] Build status: STARTED
[19:11:55.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:10:0) after 9m3s
[19:20:58.024] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:10:0)
[19:20:58.047] Build status: STARTED
[19:20:58.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:11:0) after 1m19s
[19:22:17.060] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:11:0)
[19:22:17.078] Build status: STARTED
[19:22:17.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:12:0) after 8m1s
[19:30:18.100] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:12:0)
[19:30:18.134] Build status: STARTED
[19:30:18.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:13:0) after 1m36s
[19:31:54.154] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:13:0)
[19:31:54.181] Build status: STARTED
[19:31:54.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:14:0) after 5m35s
[19:37:29.467] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:14:0)
[19:37:29.489] Build status: STARTED
[19:37:29.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885465668207008512:15:0) after 7m46s
[19:42:06.108] Received PubSub notification, asking Buildbucket for the build status
[19:42:06.126] Build:
{
"id": "8720035606297997793",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-18T18:37:06.333511375Z",
"startTime": "2025-03-18T18:37:14.695781110Z",
"endTime": "2025-03-18T19:42:05.614256308Z",
"updateTime": "2025-03-18T19:42:05.614256308Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c21081f05e4fcde1008cb2a3968ac8950613b8d3",
"ref": "refs/heads/main"
}
}
}
[19:42:06.126] Invocation finished in 1h5m0.997422017s with status SUCCEEDED