[00:21:43.249] New invocation is queued and will start shortly
[00:21:44.722] Starting the invocation (attempt 1)
[00:21:44.747] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/0c26995a523c66804a0b11d74d47265c46d01277"
[00:21:44.747] Popped gitiles commit info from properties and tags
[00:21:44.748] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:21:44.748] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:21:44.748] Buildbucket request:
{
"requestId": "8901389051724588304",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901389051724588304",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1bbe5b60ac22c264571e66e8118d46477d7917ac"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1bbe5b60ac22c264571e66e8118d46477d7917ac",
"title": "1bbe5b60ac22c264571e66e8118d46477d7917ac",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1bbe5b60ac22c264571e66e8118d46477d7917ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8c32fce0cb9d2ffdc25ef8a6e3ccc2688fcc1335"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8c32fce0cb9d2ffdc25ef8a6e3ccc2688fcc1335",
"title": "8c32fce0cb9d2ffdc25ef8a6e3ccc2688fcc1335",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8c32fce0cb9d2ffdc25ef8a6e3ccc2688fcc1335"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2bd47bc64d4a005f21a0bb4d07d452698cd120dc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2bd47bc64d4a005f21a0bb4d07d452698cd120dc",
"title": "2bd47bc64d4a005f21a0bb4d07d452698cd120dc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2bd47bc64d4a005f21a0bb4d07d452698cd120dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "93e8d007a484bdbea1a060d197669831a93c32a7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@93e8d007a484bdbea1a060d197669831a93c32a7",
"title": "93e8d007a484bdbea1a060d197669831a93c32a7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/93e8d007a484bdbea1a060d197669831a93c32a7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4d5142891005ab6ac2d2816e4ec1835cfb43242b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4d5142891005ab6ac2d2816e4ec1835cfb43242b",
"title": "4d5142891005ab6ac2d2816e4ec1835cfb43242b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4d5142891005ab6ac2d2816e4ec1835cfb43242b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bc02cded32342bb480b91be65dfea6e11ab82cb3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bc02cded32342bb480b91be65dfea6e11ab82cb3",
"title": "bc02cded32342bb480b91be65dfea6e11ab82cb3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bc02cded32342bb480b91be65dfea6e11ab82cb3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "364fa025cc9a46950de72c1c5cef04a0c849c5d2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@364fa025cc9a46950de72c1c5cef04a0c849c5d2",
"title": "364fa025cc9a46950de72c1c5cef04a0c849c5d2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/364fa025cc9a46950de72c1c5cef04a0c849c5d2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9b4990857120299e2abdfa60ab9a8d2d3ae5b6be"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9b4990857120299e2abdfa60ab9a8d2d3ae5b6be",
"title": "9b4990857120299e2abdfa60ab9a8d2d3ae5b6be",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9b4990857120299e2abdfa60ab9a8d2d3ae5b6be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2c047be47f421377dd111314d231aa288853ab15"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2c047be47f421377dd111314d231aa288853ab15",
"title": "2c047be47f421377dd111314d231aa288853ab15",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2c047be47f421377dd111314d231aa288853ab15"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bb75a2e43d1e959de78d8bce36fc41b256831d3a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bb75a2e43d1e959de78d8bce36fc41b256831d3a",
"title": "bb75a2e43d1e959de78d8bce36fc41b256831d3a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bb75a2e43d1e959de78d8bce36fc41b256831d3a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e0402507ff0a791a76fcd628d20377767e9611b1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e0402507ff0a791a76fcd628d20377767e9611b1",
"title": "e0402507ff0a791a76fcd628d20377767e9611b1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e0402507ff0a791a76fcd628d20377767e9611b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5fd1c9d6b0f0773cbe394cb3d18bbdc4cb36b194"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5fd1c9d6b0f0773cbe394cb3d18bbdc4cb36b194",
"title": "5fd1c9d6b0f0773cbe394cb3d18bbdc4cb36b194",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5fd1c9d6b0f0773cbe394cb3d18bbdc4cb36b194"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b25a20886741234bc32a6cf035b169ca29e6dad2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b25a20886741234bc32a6cf035b169ca29e6dad2",
"title": "b25a20886741234bc32a6cf035b169ca29e6dad2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b25a20886741234bc32a6cf035b169ca29e6dad2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c353f295ac70c463195620b51dc237519307afc9"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c353f295ac70c463195620b51dc237519307afc9",
"title": "c353f295ac70c463195620b51dc237519307afc9",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c353f295ac70c463195620b51dc237519307afc9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3a9932f2f69b5fbad8e181dce6ebae73a61dca10"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3a9932f2f69b5fbad8e181dce6ebae73a61dca10",
"title": "3a9932f2f69b5fbad8e181dce6ebae73a61dca10",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3a9932f2f69b5fbad8e181dce6ebae73a61dca10"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "377d29937bf0d21dd132fdb1f7f9b1b8a4751128"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@377d29937bf0d21dd132fdb1f7f9b1b8a4751128",
"title": "377d29937bf0d21dd132fdb1f7f9b1b8a4751128",
"url": "https://fuchsia.googlesource.com/fuchsia/+/377d29937bf0d21dd132fdb1f7f9b1b8a4751128"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dcc95ee7c235770c74704aa47468e08f74e3b095"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dcc95ee7c235770c74704aa47468e08f74e3b095",
"title": "dcc95ee7c235770c74704aa47468e08f74e3b095",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dcc95ee7c235770c74704aa47468e08f74e3b095"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0c26995a523c66804a0b11d74d47265c46d01277"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0c26995a523c66804a0b11d74d47265c46d01277",
"title": "0c26995a523c66804a0b11d74d47265c46d01277",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0c26995a523c66804a0b11d74d47265c46d01277"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "0c26995a523c66804a0b11d74d47265c46d01277",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901389051724588304"
},
{
"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"
}
}
[00:21:45.161] Scheduled build:
{
"id": "8735958989438685825",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-24T00:21:44.802149243Z",
"updateTime": "2024-09-24T00:21:44.802149243Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "0c26995a523c66804a0b11d74d47265c46d01277",
"ref": "refs/heads/main"
}
}
}
[00:21:45.162] Task URL: https://cr-buildbucket.appspot.com/build/8735958989438685825
[00:21:45.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:2:0) after 2m6s
[00:21:46.064] Received PubSub notification, asking Buildbucket for the build status
[00:21:46.100] Build status: SCHEDULED
[00:21:50.884] Received PubSub notification, asking Buildbucket for the build status
[00:21:50.915] Build status: STARTED
[00:23:51.179] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:2:0)
[00:23:51.203] Build status: STARTED
[00:23:51.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:5:0) after 3m39s
[00:27:30.280] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:5:0)
[00:27:30.308] Build status: STARTED
[00:27:30.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:6:0) after 1m35s
[00:29:05.278] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:6:0)
[00:29:05.367] Build status: STARTED
[00:29:05.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:7:0) after 8m48s
[00:37:53.394] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:7:0)
[00:37:53.415] Build status: STARTED
[00:37:53.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:8:0) after 3m10s
[00:41:03.443] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:8:0)
[00:41:03.466] Build status: STARTED
[00:41:03.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:9:0) after 5m4s
[00:46:07.561] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:9:0)
[00:46:07.578] Build status: STARTED
[00:46:07.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:10:0) after 7m1s
[00:53:08.594] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:10:0)
[00:53:08.612] Build status: STARTED
[00:53:08.612] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:11:0) after 5m59s
[00:59:07.631] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:11:0)
[00:59:07.657] Build status: STARTED
[00:59:07.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:12:0) after 7m4s
[01:06:11.675] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:12:0)
[01:06:11.787] Build status: STARTED
[01:06:11.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:13:0) after 8m16s
[01:14:27.834] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:13:0)
[01:14:27.854] Build status: STARTED
[01:14:27.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:14:0) after 6m16s
[01:20:43.933] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:14:0)
[01:20:43.955] Build status: STARTED
[01:20:43.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:15:0) after 5m28s
[01:26:11.977] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:15:0)
[01:26:11.998] Build status: STARTED
[01:26:11.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:16:0) after 1m55s
[01:28:07.025] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:16:0)
[01:28:07.061] Build status: STARTED
[01:28:07.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901389051724588304:17:0) after 8m58s
[01:36:40.671] Received PubSub notification, asking Buildbucket for the build status
[01:36:40.690] Build:
{
"id": "8735958989438685825",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-24T00:21:44.802149243Z",
"startTime": "2024-09-24T00:21:50.686856369Z",
"endTime": "2024-09-24T01:36:39.962469856Z",
"updateTime": "2024-09-24T01:36:39.962469856Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "0c26995a523c66804a0b11d74d47265c46d01277",
"ref": "refs/heads/main"
}
}
}
[01:36:40.690] Invocation finished in 1h14m57.451483092s with status SUCCEEDED