[20:57:58.346] New invocation is queued and will start shortly
[20:57:59.413] Starting the invocation (attempt 1)
[20:57:59.441] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/f1b776a195c8bd885dc92c1185a1d083acc734a6"
[20:57:59.441] Popped gitiles commit info from properties and tags
[20:57:59.441] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:57:59.441] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:57:59.442] Buildbucket request:
{
"requestId": "8890349040562754528",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8890349040562754528",
"job": "fuchsia/fuchsia-profile-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "65440907d3f0e87f3c3b394ad82133ae37f207c8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@65440907d3f0e87f3c3b394ad82133ae37f207c8",
"title": "65440907d3f0e87f3c3b394ad82133ae37f207c8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/65440907d3f0e87f3c3b394ad82133ae37f207c8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1fa4b2163dd917ad6c2353f0afd1cafcff35ab79"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1fa4b2163dd917ad6c2353f0afd1cafcff35ab79",
"title": "1fa4b2163dd917ad6c2353f0afd1cafcff35ab79",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1fa4b2163dd917ad6c2353f0afd1cafcff35ab79"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "23661fdde5c392d43918cdb4378990680aea128d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@23661fdde5c392d43918cdb4378990680aea128d",
"title": "23661fdde5c392d43918cdb4378990680aea128d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/23661fdde5c392d43918cdb4378990680aea128d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8b14068e5eb93f1a6e7e92e266b7a2ee8a4ef21f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8b14068e5eb93f1a6e7e92e266b7a2ee8a4ef21f",
"title": "8b14068e5eb93f1a6e7e92e266b7a2ee8a4ef21f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8b14068e5eb93f1a6e7e92e266b7a2ee8a4ef21f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "45c1e201c7d5294672b49232240644fcc08221e6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@45c1e201c7d5294672b49232240644fcc08221e6",
"title": "45c1e201c7d5294672b49232240644fcc08221e6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/45c1e201c7d5294672b49232240644fcc08221e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c665fd9893610b5e3d291ae6471deb31ab137b83"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c665fd9893610b5e3d291ae6471deb31ab137b83",
"title": "c665fd9893610b5e3d291ae6471deb31ab137b83",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c665fd9893610b5e3d291ae6471deb31ab137b83"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "017c28f9deb20d6a8d783ed66333759a310efeb9"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@017c28f9deb20d6a8d783ed66333759a310efeb9",
"title": "017c28f9deb20d6a8d783ed66333759a310efeb9",
"url": "https://fuchsia.googlesource.com/fuchsia/+/017c28f9deb20d6a8d783ed66333759a310efeb9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "09f95c009a3da2a262c296e55f24f9d15010918c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@09f95c009a3da2a262c296e55f24f9d15010918c",
"title": "09f95c009a3da2a262c296e55f24f9d15010918c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/09f95c009a3da2a262c296e55f24f9d15010918c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a4385729226b1e65906152829f4409d6046fc46e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a4385729226b1e65906152829f4409d6046fc46e",
"title": "a4385729226b1e65906152829f4409d6046fc46e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a4385729226b1e65906152829f4409d6046fc46e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3f2b3082b603abaf5555ce45614605e67bab55ed"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3f2b3082b603abaf5555ce45614605e67bab55ed",
"title": "3f2b3082b603abaf5555ce45614605e67bab55ed",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3f2b3082b603abaf5555ce45614605e67bab55ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dd65e44a997a9a21660aeee53ff72294391a32fa"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dd65e44a997a9a21660aeee53ff72294391a32fa",
"title": "dd65e44a997a9a21660aeee53ff72294391a32fa",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dd65e44a997a9a21660aeee53ff72294391a32fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a6fb4e7c5441e90d1d0b92f76591caa6646f1037"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a6fb4e7c5441e90d1d0b92f76591caa6646f1037",
"title": "a6fb4e7c5441e90d1d0b92f76591caa6646f1037",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a6fb4e7c5441e90d1d0b92f76591caa6646f1037"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e517834c8d2dafc2e8885c129b10e9f52191ccbc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e517834c8d2dafc2e8885c129b10e9f52191ccbc",
"title": "e517834c8d2dafc2e8885c129b10e9f52191ccbc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e517834c8d2dafc2e8885c129b10e9f52191ccbc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "41cbfd0b440f50f13a6c1114f8db0b4ca334050a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@41cbfd0b440f50f13a6c1114f8db0b4ca334050a",
"title": "41cbfd0b440f50f13a6c1114f8db0b4ca334050a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/41cbfd0b440f50f13a6c1114f8db0b4ca334050a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f0dec92227fc3cba0b5f611f257621f736a418f6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f0dec92227fc3cba0b5f611f257621f736a418f6",
"title": "f0dec92227fc3cba0b5f611f257621f736a418f6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f0dec92227fc3cba0b5f611f257621f736a418f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1c90af568a88ac9620049f482154db74a8cc4674"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1c90af568a88ac9620049f482154db74a8cc4674",
"title": "1c90af568a88ac9620049f482154db74a8cc4674",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1c90af568a88ac9620049f482154db74a8cc4674"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "38b662a181cc822744c2527c6b096530b2d1b3dd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@38b662a181cc822744c2527c6b096530b2d1b3dd",
"title": "38b662a181cc822744c2527c6b096530b2d1b3dd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/38b662a181cc822744c2527c6b096530b2d1b3dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d6ce26445c97ce34ea8f23d33a3082922a0e7c93"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d6ce26445c97ce34ea8f23d33a3082922a0e7c93",
"title": "d6ce26445c97ce34ea8f23d33a3082922a0e7c93",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d6ce26445c97ce34ea8f23d33a3082922a0e7c93"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "86c3a2190cb22cf1c43c589e4fe1d43c9e9d3cd8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@86c3a2190cb22cf1c43c589e4fe1d43c9e9d3cd8",
"title": "86c3a2190cb22cf1c43c589e4fe1d43c9e9d3cd8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/86c3a2190cb22cf1c43c589e4fe1d43c9e9d3cd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f1b776a195c8bd885dc92c1185a1d083acc734a6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f1b776a195c8bd885dc92c1185a1d083acc734a6",
"title": "f1b776a195c8bd885dc92c1185a1d083acc734a6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f1b776a195c8bd885dc92c1185a1d083acc734a6"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "f1b776a195c8bd885dc92c1185a1d083acc734a6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8890349040562754528"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-profile-core.x64-release-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:57:59.821] Scheduled build:
{
"id": "8724918978691559297",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-23T20:57:59.504265312Z",
"updateTime": "2025-01-23T20:57:59.504265312Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "f1b776a195c8bd885dc92c1185a1d083acc734a6",
"ref": "refs/heads/main"
}
}
}
[20:57:59.821] Task URL: https://cr-buildbucket.appspot.com/build/8724918978691559297
[20:57:59.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:2:0) after 8m44s
[20:58:00.867] Received PubSub notification, asking Buildbucket for the build status
[20:58:00.920] Build status: SCHEDULED
[20:58:04.389] Received PubSub notification, asking Buildbucket for the build status
[20:58:04.411] Build status: STARTED
[21:06:43.925] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:2:0)
[21:06:43.952] Build status: STARTED
[21:06:43.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:5:0) after 6m57s
[21:13:40.968] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:5:0)
[21:13:40.994] Build status: STARTED
[21:13:40.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:6:0) after 8m36s
[21:22:17.026] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:6:0)
[21:22:17.203] Build status: STARTED
[21:22:17.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:7:0) after 8m3s
[21:30:20.271] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:7:0)
[21:30:20.347] Build status: STARTED
[21:30:20.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:8:0) after 6m11s
[21:36:31.368] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:8:0)
[21:36:31.397] Build status: STARTED
[21:36:31.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:9:0) after 5m46s
[21:42:17.415] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:9:0)
[21:42:17.432] Build status: STARTED
[21:42:17.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:10:0) after 8m8s
[21:50:25.414] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:10:0)
[21:50:25.449] Build status: STARTED
[21:50:25.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:11:0) after 8m59s
[21:59:24.522] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:11:0)
[21:59:24.543] Build status: STARTED
[21:59:24.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:12:0) after 9m34s
[22:08:58.574] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:12:0)
[22:08:58.597] Build status: STARTED
[22:08:58.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:13:0) after 3m31s
[22:12:29.626] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:13:0)
[22:12:29.797] Build status: STARTED
[22:12:29.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:14:0) after 5m0s
[22:17:29.795] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:14:0)
[22:17:29.814] Build status: STARTED
[22:17:29.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:15:0) after 3m52s
[22:21:21.829] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:15:0)
[22:21:21.847] Build status: STARTED
[22:21:21.847] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:16:0) after 1m25s
[22:22:46.875] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:16:0)
[22:22:46.903] Build status: STARTED
[22:22:46.903] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:17:0) after 5m2s
[22:27:49.002] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:17:0)
[22:27:49.041] Build status: STARTED
[22:27:49.041] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:18:0) after 8m41s
[22:36:30.066] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:18:0)
[22:36:30.086] Build status: STARTED
[22:36:30.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:19:0) after 2m36s
[22:39:06.315] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:19:0)
[22:39:06.336] Build status: STARTED
[22:39:06.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:20:0) after 1m44s
[22:40:50.322] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:20:0)
[22:40:50.339] Build status: STARTED
[22:40:50.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:21:0) after 5m7s
[22:45:57.417] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:21:0)
[22:45:57.460] Build status: STARTED
[22:45:57.460] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:22:0) after 6m5s
[22:52:02.497] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:22:0)
[22:52:02.527] Build status: STARTED
[22:52:02.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:23:0) after 2m14s
[22:54:16.554] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:23:0)
[22:54:16.570] Build status: STARTED
[22:54:16.571] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:24:0) after 9m56s
[23:04:12.652] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:24:0)
[23:04:12.671] Build status: STARTED
[23:04:12.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8890349040562754528:25:0) after 2m19s
[23:04:14.997] Received PubSub notification, asking Buildbucket for the build status
[23:04:15.034] Build:
{
"id": "8724918978691559297",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-23T20:57:59.504265312Z",
"startTime": "2025-01-23T20:58:04.115810500Z",
"endTime": "2025-01-23T23:04:14.602268481Z",
"updateTime": "2025-01-23T23:04:14.602268481Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "f1b776a195c8bd885dc92c1185a1d083acc734a6",
"ref": "refs/heads/main"
}
}
}
[23:04:15.034] Invocation finished in 2h6m16.697446717s with status SUCCEEDED