[20:43:20.772] New invocation is queued and will start shortly
[20:43:21.878] Starting the invocation (attempt 1)
[20:43:21.913] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/0e1408ef472401bcde73204ae34859635253e272"
[20:43:21.913] Popped gitiles commit info from properties and tags
[20:43:21.913] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:43:21.913] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:43:21.914] Buildbucket request:
{
"requestId": "8899228463473589968",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899228463473589968",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8505d4d7abd2b5c1c69715df260e81c60c2f5e90"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8505d4d7abd2b5c1c69715df260e81c60c2f5e90",
"title": "8505d4d7abd2b5c1c69715df260e81c60c2f5e90",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8505d4d7abd2b5c1c69715df260e81c60c2f5e90"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c71d6458a9a72dab9035c193d8f0eefb20356724"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c71d6458a9a72dab9035c193d8f0eefb20356724",
"title": "c71d6458a9a72dab9035c193d8f0eefb20356724",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c71d6458a9a72dab9035c193d8f0eefb20356724"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8f1ac4cba256fa531d611617a42bacc76b603149"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8f1ac4cba256fa531d611617a42bacc76b603149",
"title": "8f1ac4cba256fa531d611617a42bacc76b603149",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8f1ac4cba256fa531d611617a42bacc76b603149"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "62c4fe559a6eb1e1b9e5fa4edf2a9a9a56c3757b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@62c4fe559a6eb1e1b9e5fa4edf2a9a9a56c3757b",
"title": "62c4fe559a6eb1e1b9e5fa4edf2a9a9a56c3757b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/62c4fe559a6eb1e1b9e5fa4edf2a9a9a56c3757b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5012a244fa053751af6d839ffb8ad8a08f730e9f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5012a244fa053751af6d839ffb8ad8a08f730e9f",
"title": "5012a244fa053751af6d839ffb8ad8a08f730e9f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5012a244fa053751af6d839ffb8ad8a08f730e9f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ae9486759e1547da05e18496ee3aaa8b1efffd00"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ae9486759e1547da05e18496ee3aaa8b1efffd00",
"title": "ae9486759e1547da05e18496ee3aaa8b1efffd00",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ae9486759e1547da05e18496ee3aaa8b1efffd00"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "74dc87e3df4531e04355d71a54c80ddb479737f2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@74dc87e3df4531e04355d71a54c80ddb479737f2",
"title": "74dc87e3df4531e04355d71a54c80ddb479737f2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/74dc87e3df4531e04355d71a54c80ddb479737f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "54fff17901b554f211835a451ccf37af3c942384"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@54fff17901b554f211835a451ccf37af3c942384",
"title": "54fff17901b554f211835a451ccf37af3c942384",
"url": "https://fuchsia.googlesource.com/fuchsia/+/54fff17901b554f211835a451ccf37af3c942384"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "86f51306ae47017562b0911e166ef9b4c6c5ae50"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@86f51306ae47017562b0911e166ef9b4c6c5ae50",
"title": "86f51306ae47017562b0911e166ef9b4c6c5ae50",
"url": "https://fuchsia.googlesource.com/fuchsia/+/86f51306ae47017562b0911e166ef9b4c6c5ae50"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0e1408ef472401bcde73204ae34859635253e272"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0e1408ef472401bcde73204ae34859635253e272",
"title": "0e1408ef472401bcde73204ae34859635253e272",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0e1408ef472401bcde73204ae34859635253e272"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "0e1408ef472401bcde73204ae34859635253e272",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899228463473589968"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:43:22.622] Scheduled build:
{
"id": "8733798401543171553",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-17T20:43:21.986342896Z",
"updateTime": "2024-10-17T20:43:21.986342896Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "0e1408ef472401bcde73204ae34859635253e272",
"ref": "refs/heads/main"
}
}
}
[20:43:22.622] Task URL: https://cr-buildbucket.appspot.com/build/8733798401543171553
[20:43:22.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:2:0) after 6m32s
[20:43:22.782] Received PubSub notification, asking Buildbucket for the build status
[20:43:22.809] Build status: SCHEDULED
[20:43:33.103] Received PubSub notification, asking Buildbucket for the build status
[20:43:33.131] Build status: STARTED
[20:49:54.639] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:2:0)
[20:49:54.658] Build status: STARTED
[20:49:54.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:5:0) after 3m45s
[20:53:39.676] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:5:0)
[20:53:39.712] Build status: STARTED
[20:53:39.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:6:0) after 2m39s
[20:56:18.741] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:6:0)
[20:56:18.764] Build status: STARTED
[20:56:18.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:7:0) after 9m19s
[21:05:37.792] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:7:0)
[21:05:37.815] Build status: STARTED
[21:05:37.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:8:0) after 9m15s
[21:14:52.881] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:8:0)
[21:14:52.904] Build status: STARTED
[21:14:52.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:9:0) after 3m38s
[21:18:30.924] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:9:0)
[21:18:30.941] Build status: STARTED
[21:18:30.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:10:0) after 8m23s
[21:26:53.996] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:10:0)
[21:26:54.020] Build status: STARTED
[21:26:54.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:11:0) after 7m56s
[21:34:50.051] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:11:0)
[21:34:50.070] Build status: STARTED
[21:34:50.070] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:12:0) after 3m20s
[21:38:10.097] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:12:0)
[21:38:10.137] Build status: STARTED
[21:38:10.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:13:0) after 2m7s
[21:40:17.140] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:13:0)
[21:40:17.214] Build status: STARTED
[21:40:17.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8899228463473589968:14:0) after 9m35s
[21:42:21.504] Received PubSub notification, asking Buildbucket for the build status
[21:42:21.526] Build:
{
"id": "8733798401543171553",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-17T20:43:21.986342896Z",
"startTime": "2024-10-17T20:43:32.670126596Z",
"endTime": "2024-10-17T21:42:21.182382515Z",
"updateTime": "2024-10-17T21:42:21.182382515Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "0e1408ef472401bcde73204ae34859635253e272",
"ref": "refs/heads/main"
}
}
}
[21:42:21.526] Invocation finished in 59m0.764920062s with status SUCCEEDED