[16:59:30.673] New invocation is queued and will start shortly
[16:59:31.953] Starting the invocation (attempt 1)
[16:59:31.979] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/e480f54d580226536599f5862901f163df5e237f"
[16:59:31.979] Popped gitiles commit info from properties and tags
[16:59:31.979] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:59:31.979] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:59:31.979] Buildbucket request:
{
"requestId": "8884747031329660656",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-kernel-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884747031329660656",
"job": "fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ee980b42cb0e1fe5a0e270d3b90c5afcd2bb66ae"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ee980b42cb0e1fe5a0e270d3b90c5afcd2bb66ae",
"title": "ee980b42cb0e1fe5a0e270d3b90c5afcd2bb66ae",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ee980b42cb0e1fe5a0e270d3b90c5afcd2bb66ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "785faaca9105d2eb68ae4337d45cde2426991635"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@785faaca9105d2eb68ae4337d45cde2426991635",
"title": "785faaca9105d2eb68ae4337d45cde2426991635",
"url": "https://fuchsia.googlesource.com/fuchsia/+/785faaca9105d2eb68ae4337d45cde2426991635"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e314cec8a9a4c8e09a0cb06da78db1db46230781"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e314cec8a9a4c8e09a0cb06da78db1db46230781",
"title": "e314cec8a9a4c8e09a0cb06da78db1db46230781",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e314cec8a9a4c8e09a0cb06da78db1db46230781"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9186402829faf58613747a68c1bf87e27c9b3e54"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9186402829faf58613747a68c1bf87e27c9b3e54",
"title": "9186402829faf58613747a68c1bf87e27c9b3e54",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9186402829faf58613747a68c1bf87e27c9b3e54"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "cd9cfca0e24a1d6e53b045c4e3cdad7a63cf5840"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@cd9cfca0e24a1d6e53b045c4e3cdad7a63cf5840",
"title": "cd9cfca0e24a1d6e53b045c4e3cdad7a63cf5840",
"url": "https://fuchsia.googlesource.com/fuchsia/+/cd9cfca0e24a1d6e53b045c4e3cdad7a63cf5840"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "06c5a3b5f169c7cf66ba5cdf1a70712c2a475ab7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@06c5a3b5f169c7cf66ba5cdf1a70712c2a475ab7",
"title": "06c5a3b5f169c7cf66ba5cdf1a70712c2a475ab7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/06c5a3b5f169c7cf66ba5cdf1a70712c2a475ab7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8fe5f7a1054697790be12bb5b4984d3b5897863e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8fe5f7a1054697790be12bb5b4984d3b5897863e",
"title": "8fe5f7a1054697790be12bb5b4984d3b5897863e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8fe5f7a1054697790be12bb5b4984d3b5897863e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "56b13133957bbb4f56afec4c61091b7b50bfad8f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@56b13133957bbb4f56afec4c61091b7b50bfad8f",
"title": "56b13133957bbb4f56afec4c61091b7b50bfad8f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/56b13133957bbb4f56afec4c61091b7b50bfad8f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e480f54d580226536599f5862901f163df5e237f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e480f54d580226536599f5862901f163df5e237f",
"title": "e480f54d580226536599f5862901f163df5e237f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e480f54d580226536599f5862901f163df5e237f"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e480f54d580226536599f5862901f163df5e237f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884747031329660656"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:59:32.348] Scheduled build:
{
"id": "8719316969252994097",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-kernel-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-26T16:59:32.026407542Z",
"updateTime": "2025-03-26T16:59:32.026407542Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e480f54d580226536599f5862901f163df5e237f",
"ref": "refs/heads/main"
}
},
"canary": true
}
[16:59:32.348] Task URL: https://cr-buildbucket.appspot.com/build/8719316969252994097
[16:59:32.348] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:2:0) after 7m50s
[16:59:33.322] Received PubSub notification, asking Buildbucket for the build status
[16:59:33.352] Build status: SCHEDULED
[16:59:37.768] Received PubSub notification, asking Buildbucket for the build status
[16:59:37.784] Build status: STARTED
[17:07:22.406] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:2:0)
[17:07:22.437] Build status: STARTED
[17:07:22.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:5:0) after 7m36s
[17:14:58.461] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:5:0)
[17:14:58.487] Build status: STARTED
[17:14:58.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:6:0) after 8m59s
[17:23:57.502] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:6:0)
[17:23:57.526] Build status: STARTED
[17:23:57.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:7:0) after 5m47s
[17:29:44.641] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:7:0)
[17:29:44.667] Build status: STARTED
[17:29:44.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:8:0) after 5m32s
[17:35:16.804] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:8:0)
[17:35:16.829] Build status: STARTED
[17:35:16.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:9:0) after 1m56s
[17:37:12.875] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:9:0)
[17:37:13.048] Build status: STARTED
[17:37:13.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:10:0) after 5m1s
[17:42:14.154] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:10:0)
[17:42:14.181] Build status: STARTED
[17:42:14.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:11:0) after 2m20s
[17:44:34.255] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:11:0)
[17:44:34.281] Build status: STARTED
[17:44:34.281] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:12:0) after 6m56s
[17:51:30.443] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:12:0)
[17:51:30.465] Build status: STARTED
[17:51:30.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:13:0) after 4m18s
[17:55:48.633] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:13:0)
[17:55:48.652] Build status: STARTED
[17:55:48.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:14:0) after 1m49s
[17:57:37.815] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:14:0)
[17:57:37.841] Build status: STARTED
[17:57:37.841] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:15:0) after 1m31s
[17:59:08.872] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:15:0)
[17:59:08.902] Build status: STARTED
[17:59:08.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:16:0) after 4m7s
[18:03:15.914] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:16:0)
[18:03:15.930] Build status: STARTED
[18:03:15.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:17:0) after 8m42s
[18:11:58.098] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:17:0)
[18:11:58.147] Build status: STARTED
[18:11:58.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:18:0) after 8m55s
[18:20:53.258] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:18:0)
[18:20:53.287] Build status: STARTED
[18:20:53.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884747031329660656:19:0) after 1m19s
[18:21:06.236] Received PubSub notification, asking Buildbucket for the build status
[18:21:06.254] Build:
{
"id": "8719316969252994097",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-kernel-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-26T16:59:32.026407542Z",
"startTime": "2025-03-26T16:59:37.591734908Z",
"endTime": "2025-03-26T18:21:05.835264462Z",
"updateTime": "2025-03-26T18:21:05.835264462Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e480f54d580226536599f5862901f163df5e237f",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:21:06.254] Invocation finished in 1h21m35.591693943s with status SUCCEEDED