[18:21:08.582] New invocation is queued and will start shortly
[18:21:09.995] Starting the invocation (attempt 1)
[18:21:10.023] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/e3df9054f14d5247a478e9a3f09130b6641082bf"
[18:21:10.023] Popped gitiles commit info from properties and tags
[18:21:10.023] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:21:10.023] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:21:10.023] Buildbucket request:
{
"requestId": "8884741895499027120",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-kernel-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884741895499027120",
"job": "fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "50ccc230f2c38197b04204219e819a02ae091507"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@50ccc230f2c38197b04204219e819a02ae091507",
"title": "50ccc230f2c38197b04204219e819a02ae091507",
"url": "https://fuchsia.googlesource.com/fuchsia/+/50ccc230f2c38197b04204219e819a02ae091507"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "09f84ba92fe7532b016d41fdc83c3aa1ff4c8cff"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@09f84ba92fe7532b016d41fdc83c3aa1ff4c8cff",
"title": "09f84ba92fe7532b016d41fdc83c3aa1ff4c8cff",
"url": "https://fuchsia.googlesource.com/fuchsia/+/09f84ba92fe7532b016d41fdc83c3aa1ff4c8cff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3246bea1a39e8fda7a25c3c32a2cef682f941e2b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3246bea1a39e8fda7a25c3c32a2cef682f941e2b",
"title": "3246bea1a39e8fda7a25c3c32a2cef682f941e2b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3246bea1a39e8fda7a25c3c32a2cef682f941e2b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "275bcfd114b6f49cd938493dbd9bc444aff35848"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@275bcfd114b6f49cd938493dbd9bc444aff35848",
"title": "275bcfd114b6f49cd938493dbd9bc444aff35848",
"url": "https://fuchsia.googlesource.com/fuchsia/+/275bcfd114b6f49cd938493dbd9bc444aff35848"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "40f729a3d5edd16cdb0607a8996e6d062c14a2da"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@40f729a3d5edd16cdb0607a8996e6d062c14a2da",
"title": "40f729a3d5edd16cdb0607a8996e6d062c14a2da",
"url": "https://fuchsia.googlesource.com/fuchsia/+/40f729a3d5edd16cdb0607a8996e6d062c14a2da"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0dd817eef5a3d96f6d7aa8829ded00bb9c88b890"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0dd817eef5a3d96f6d7aa8829ded00bb9c88b890",
"title": "0dd817eef5a3d96f6d7aa8829ded00bb9c88b890",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0dd817eef5a3d96f6d7aa8829ded00bb9c88b890"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a8741f9d2136149adc9ee102401b704d3c2c563b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a8741f9d2136149adc9ee102401b704d3c2c563b",
"title": "a8741f9d2136149adc9ee102401b704d3c2c563b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a8741f9d2136149adc9ee102401b704d3c2c563b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "203d527e8117eb39ff2f4352abc1af56cf031b6a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@203d527e8117eb39ff2f4352abc1af56cf031b6a",
"title": "203d527e8117eb39ff2f4352abc1af56cf031b6a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/203d527e8117eb39ff2f4352abc1af56cf031b6a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6c3054bcb4564852d1aa16404f6c9836d18cb29e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6c3054bcb4564852d1aa16404f6c9836d18cb29e",
"title": "6c3054bcb4564852d1aa16404f6c9836d18cb29e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6c3054bcb4564852d1aa16404f6c9836d18cb29e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "88d2f67d64483037e06d221cb61bdce9650c0697"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@88d2f67d64483037e06d221cb61bdce9650c0697",
"title": "88d2f67d64483037e06d221cb61bdce9650c0697",
"url": "https://fuchsia.googlesource.com/fuchsia/+/88d2f67d64483037e06d221cb61bdce9650c0697"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b2ccdc7d648538ca38969c58b9b0fd9c685b1580"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b2ccdc7d648538ca38969c58b9b0fd9c685b1580",
"title": "b2ccdc7d648538ca38969c58b9b0fd9c685b1580",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b2ccdc7d648538ca38969c58b9b0fd9c685b1580"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c09eae1e0ca78694afb6821d0f3bb59bf9a9a945"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c09eae1e0ca78694afb6821d0f3bb59bf9a9a945",
"title": "c09eae1e0ca78694afb6821d0f3bb59bf9a9a945",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c09eae1e0ca78694afb6821d0f3bb59bf9a9a945"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "48cf4d50e4d0c2f1d8c89c79162250a4295ea0a3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@48cf4d50e4d0c2f1d8c89c79162250a4295ea0a3",
"title": "48cf4d50e4d0c2f1d8c89c79162250a4295ea0a3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/48cf4d50e4d0c2f1d8c89c79162250a4295ea0a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5989e6935be997e2410dc3613121520920d9717e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5989e6935be997e2410dc3613121520920d9717e",
"title": "5989e6935be997e2410dc3613121520920d9717e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5989e6935be997e2410dc3613121520920d9717e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8fdb991c00baf5cfbdd894d07f36a9904f8e6767"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8fdb991c00baf5cfbdd894d07f36a9904f8e6767",
"title": "8fdb991c00baf5cfbdd894d07f36a9904f8e6767",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8fdb991c00baf5cfbdd894d07f36a9904f8e6767"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5562b7e436d4982f978ef691d9828137477aac8a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5562b7e436d4982f978ef691d9828137477aac8a",
"title": "5562b7e436d4982f978ef691d9828137477aac8a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5562b7e436d4982f978ef691d9828137477aac8a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f368d59118aa95c3abe0048ed6231955afad988e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f368d59118aa95c3abe0048ed6231955afad988e",
"title": "f368d59118aa95c3abe0048ed6231955afad988e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f368d59118aa95c3abe0048ed6231955afad988e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3abbc509ec120bbb17769d32886d31b1b27f9bc0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3abbc509ec120bbb17769d32886d31b1b27f9bc0",
"title": "3abbc509ec120bbb17769d32886d31b1b27f9bc0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3abbc509ec120bbb17769d32886d31b1b27f9bc0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6811fe984fdd6757c5786b5b6fd5f1e1c446c77c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6811fe984fdd6757c5786b5b6fd5f1e1c446c77c",
"title": "6811fe984fdd6757c5786b5b6fd5f1e1c446c77c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6811fe984fdd6757c5786b5b6fd5f1e1c446c77c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e3df9054f14d5247a478e9a3f09130b6641082bf"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e3df9054f14d5247a478e9a3f09130b6641082bf",
"title": "e3df9054f14d5247a478e9a3f09130b6641082bf",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e3df9054f14d5247a478e9a3f09130b6641082bf"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e3df9054f14d5247a478e9a3f09130b6641082bf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884741895499027120"
},
{
"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"
}
}
[18:21:10.430] Scheduled build:
{
"id": "8719311833269802193",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-kernel-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-26T18:21:10.081259756Z",
"updateTime": "2025-03-26T18:21:10.081259756Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e3df9054f14d5247a478e9a3f09130b6641082bf",
"ref": "refs/heads/main"
}
}
}
[18:21:10.430] Task URL: https://cr-buildbucket.appspot.com/build/8719311833269802193
[18:21:10.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:2:0) after 5m2s
[18:21:11.410] Received PubSub notification, asking Buildbucket for the build status
[18:21:11.433] Build status: SCHEDULED
[18:21:15.151] Received PubSub notification, asking Buildbucket for the build status
[18:21:15.242] Build status: STARTED
[18:26:12.509] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:2:0)
[18:26:12.535] Build status: STARTED
[18:26:12.535] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:5:0) after 4m19s
[18:30:31.762] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:5:0)
[18:30:31.782] Build status: STARTED
[18:30:31.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:6:0) after 8m52s
[18:39:23.809] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:6:0)
[18:39:23.827] Build status: STARTED
[18:39:23.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:7:0) after 6m41s
[18:46:04.849] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:7:0)
[18:46:04.878] Build status: STARTED
[18:46:04.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:8:0) after 5m58s
[18:52:03.018] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:8:0)
[18:52:03.044] Build status: STARTED
[18:52:03.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:9:0) after 6m29s
[18:58:32.120] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:9:0)
[18:58:32.206] Build status: STARTED
[18:58:32.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:10:0) after 5m39s
[19:04:11.383] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:10:0)
[19:04:11.408] Build status: STARTED
[19:04:11.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:11:0) after 6m32s
[19:10:43.433] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:11:0)
[19:10:43.446] Build status: STARTED
[19:10:43.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:12:0) after 1m45s
[19:12:28.472] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:12:0)
[19:12:28.495] Build status: STARTED
[19:12:28.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:13:0) after 1m21s
[19:13:49.531] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:13:0)
[19:13:49.547] Build status: STARTED
[19:13:49.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:14:0) after 2m44s
[19:16:33.656] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:14:0)
[19:16:33.673] Build status: STARTED
[19:16:33.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:15:0) after 1m32s
[19:18:05.698] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:15:0)
[19:18:05.725] Build status: STARTED
[19:18:05.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:16:0) after 9m13s
[19:27:18.791] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:16:0)
[19:27:18.860] Build status: STARTED
[19:27:18.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:17:0) after 2m22s
[19:29:40.879] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:17:0)
[19:29:40.896] Build status: STARTED
[19:29:40.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:18:0) after 1m0s
[19:30:40.914] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:18:0)
[19:30:40.988] Build status: STARTED
[19:30:40.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:19:0) after 5m3s
[19:35:44.012] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:19:0)
[19:35:44.054] Build status: STARTED
[19:35:44.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:20:0) after 5m18s
[19:41:02.066] Handling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:20:0)
[19:41:02.092] Build status: STARTED
[19:41:02.092] Scheduling timer "check-buildbucket-build-status" (fuchsia/ci-fuchsia-profile-kernel-core.x64-release-af244ac6:8884741895499027120:21:0) after 6m17s
[19:42:03.543] Received PubSub notification, asking Buildbucket for the build status
[19:42:03.567] Build:
{
"id": "8719311833269802193",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-kernel-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-26T18:21:10.081259756Z",
"startTime": "2025-03-26T18:21:14.935514246Z",
"endTime": "2025-03-26T19:42:02.882255946Z",
"updateTime": "2025-03-26T19:42:02.882255946Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e3df9054f14d5247a478e9a3f09130b6641082bf",
"ref": "refs/heads/main"
}
}
}
[19:42:03.567] Invocation finished in 1h20m54.9963447s with status SUCCEEDED