[20:03:46.355] New invocation is queued and will start shortly
[20:03:47.490] Starting the invocation (attempt 1)
[20:03:47.521] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/fd6ea459cecdd0590749014130db6535427bbb8e"
[20:03:47.521] Popped gitiles commit info from properties and tags
[20:03:47.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:03:47.522] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:03:47.522] Buildbucket request:
{
"requestId": "8885369617375634048",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8885369617375634048",
"job": "fuchsia/core.x64-release-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8603a766b070a107250ebef5e3318a7dc2661f17"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8603a766b070a107250ebef5e3318a7dc2661f17",
"title": "8603a766b070a107250ebef5e3318a7dc2661f17",
"url": "https://fuchsia.googlesource.com/integration/+/8603a766b070a107250ebef5e3318a7dc2661f17"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "764a4008fc0b84368ecd00300ef515eff4e2a320"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@764a4008fc0b84368ecd00300ef515eff4e2a320",
"title": "764a4008fc0b84368ecd00300ef515eff4e2a320",
"url": "https://fuchsia.googlesource.com/integration/+/764a4008fc0b84368ecd00300ef515eff4e2a320"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "387c856415f0e0623889cd6271cba53df14ea289"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@387c856415f0e0623889cd6271cba53df14ea289",
"title": "387c856415f0e0623889cd6271cba53df14ea289",
"url": "https://fuchsia.googlesource.com/integration/+/387c856415f0e0623889cd6271cba53df14ea289"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1ab05a164fcede28d6250ebaf32a56edc1968eda"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1ab05a164fcede28d6250ebaf32a56edc1968eda",
"title": "1ab05a164fcede28d6250ebaf32a56edc1968eda",
"url": "https://fuchsia.googlesource.com/integration/+/1ab05a164fcede28d6250ebaf32a56edc1968eda"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "28b0114e5a7d30403bfadb64358f7b66d9ee7bf5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@28b0114e5a7d30403bfadb64358f7b66d9ee7bf5",
"title": "28b0114e5a7d30403bfadb64358f7b66d9ee7bf5",
"url": "https://fuchsia.googlesource.com/integration/+/28b0114e5a7d30403bfadb64358f7b66d9ee7bf5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9cb1553de362057d67cc0797cf370b5064ff6bc1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9cb1553de362057d67cc0797cf370b5064ff6bc1",
"title": "9cb1553de362057d67cc0797cf370b5064ff6bc1",
"url": "https://fuchsia.googlesource.com/integration/+/9cb1553de362057d67cc0797cf370b5064ff6bc1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "003e611eb0bf7e8b59d850aa9698c5cfe8c59bd9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@003e611eb0bf7e8b59d850aa9698c5cfe8c59bd9",
"title": "003e611eb0bf7e8b59d850aa9698c5cfe8c59bd9",
"url": "https://fuchsia.googlesource.com/integration/+/003e611eb0bf7e8b59d850aa9698c5cfe8c59bd9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1fda930453baea16338e920fcb8df11d4cb11fa8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1fda930453baea16338e920fcb8df11d4cb11fa8",
"title": "1fda930453baea16338e920fcb8df11d4cb11fa8",
"url": "https://fuchsia.googlesource.com/integration/+/1fda930453baea16338e920fcb8df11d4cb11fa8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3a25a95290ee4746098c7687370825d1e605809e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3a25a95290ee4746098c7687370825d1e605809e",
"title": "3a25a95290ee4746098c7687370825d1e605809e",
"url": "https://fuchsia.googlesource.com/integration/+/3a25a95290ee4746098c7687370825d1e605809e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ccb985233898ab0f4a009d5e3d342aed129bd4a6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ccb985233898ab0f4a009d5e3d342aed129bd4a6",
"title": "ccb985233898ab0f4a009d5e3d342aed129bd4a6",
"url": "https://fuchsia.googlesource.com/integration/+/ccb985233898ab0f4a009d5e3d342aed129bd4a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "41566759b16aae33d01eaa35c60fee04a371e036"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@41566759b16aae33d01eaa35c60fee04a371e036",
"title": "41566759b16aae33d01eaa35c60fee04a371e036",
"url": "https://fuchsia.googlesource.com/integration/+/41566759b16aae33d01eaa35c60fee04a371e036"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "83933536c28dcae5fcf93043daf4fb2769dc8103"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@83933536c28dcae5fcf93043daf4fb2769dc8103",
"title": "83933536c28dcae5fcf93043daf4fb2769dc8103",
"url": "https://fuchsia.googlesource.com/integration/+/83933536c28dcae5fcf93043daf4fb2769dc8103"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8a0d173e425b923f3d16cedb50c12057d2b2b021"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8a0d173e425b923f3d16cedb50c12057d2b2b021",
"title": "8a0d173e425b923f3d16cedb50c12057d2b2b021",
"url": "https://fuchsia.googlesource.com/integration/+/8a0d173e425b923f3d16cedb50c12057d2b2b021"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b38ad3079be887cb1f88240a8260cb71ae965e67"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b38ad3079be887cb1f88240a8260cb71ae965e67",
"title": "b38ad3079be887cb1f88240a8260cb71ae965e67",
"url": "https://fuchsia.googlesource.com/integration/+/b38ad3079be887cb1f88240a8260cb71ae965e67"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a4a73caf8d1ebbdd073e4d368694bb07a15ff096"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a4a73caf8d1ebbdd073e4d368694bb07a15ff096",
"title": "a4a73caf8d1ebbdd073e4d368694bb07a15ff096",
"url": "https://fuchsia.googlesource.com/integration/+/a4a73caf8d1ebbdd073e4d368694bb07a15ff096"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "960c433d097999100fa099c7d6d4793bada824cf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@960c433d097999100fa099c7d6d4793bada824cf",
"title": "960c433d097999100fa099c7d6d4793bada824cf",
"url": "https://fuchsia.googlesource.com/integration/+/960c433d097999100fa099c7d6d4793bada824cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9c2fc18262a950a97179b1675dbf17de36d5789d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9c2fc18262a950a97179b1675dbf17de36d5789d",
"title": "9c2fc18262a950a97179b1675dbf17de36d5789d",
"url": "https://fuchsia.googlesource.com/integration/+/9c2fc18262a950a97179b1675dbf17de36d5789d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fd6ea459cecdd0590749014130db6535427bbb8e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fd6ea459cecdd0590749014130db6535427bbb8e",
"title": "fd6ea459cecdd0590749014130db6535427bbb8e",
"url": "https://fuchsia.googlesource.com/integration/+/fd6ea459cecdd0590749014130db6535427bbb8e"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "fd6ea459cecdd0590749014130db6535427bbb8e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8885369617375634048"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:03:48.040] Scheduled build:
{
"id": "8719939555343224081",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-19T20:03:47.662935252Z",
"updateTime": "2025-03-19T20:03:47.662935252Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "fd6ea459cecdd0590749014130db6535427bbb8e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:03:48.040] Task URL: https://cr-buildbucket.appspot.com/build/8719939555343224081
[20:03:48.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:2:0) after 6m25s
[20:03:48.153] Received PubSub notification, asking Buildbucket for the build status
[20:03:48.200] Build status: SCHEDULED
[20:04:05.971] Received PubSub notification, asking Buildbucket for the build status
[20:04:05.996] Build status: STARTED
[20:10:13.114] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:2:0)
[20:10:13.136] Build status: STARTED
[20:10:13.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:5:0) after 5m3s
[20:15:16.148] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:5:0)
[20:15:16.222] Build status: STARTED
[20:15:16.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:6:0) after 3m55s
[20:19:11.249] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:6:0)
[20:19:11.276] Build status: STARTED
[20:19:11.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:7:0) after 4m31s
[20:23:42.305] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:7:0)
[20:23:42.359] Build status: STARTED
[20:23:42.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:8:0) after 4m53s
[20:28:35.412] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:8:0)
[20:28:35.440] Build status: STARTED
[20:28:35.440] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:9:0) after 5m14s
[20:33:49.460] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:9:0)
[20:33:49.484] Build status: STARTED
[20:33:49.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:10:0) after 6m15s
[20:40:04.501] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:10:0)
[20:40:04.519] Build status: STARTED
[20:40:04.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:11:0) after 3m24s
[20:43:28.522] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:11:0)
[20:43:28.560] Build status: STARTED
[20:43:28.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:12:0) after 5m40s
[20:49:08.660] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:12:0)
[20:49:08.677] Build status: STARTED
[20:49:08.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:13:0) after 3m47s
[20:52:55.697] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:13:0)
[20:52:55.768] Build status: STARTED
[20:52:55.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:14:0) after 8m38s
[21:01:33.840] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:14:0)
[21:01:33.862] Build status: STARTED
[21:01:33.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:15:0) after 4m9s
[21:05:42.936] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:15:0)
[21:05:42.959] Build status: STARTED
[21:05:42.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885369617375634048:16:0) after 8m8s
[21:12:39.935] Received PubSub notification, asking Buildbucket for the build status
[21:12:39.948] Build:
{
"id": "8719939555343224081",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-19T20:03:47.662935252Z",
"startTime": "2025-03-19T20:04:05.440088179Z",
"endTime": "2025-03-19T21:12:39.495461242Z",
"updateTime": "2025-03-19T21:12:39.495461242Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "fd6ea459cecdd0590749014130db6535427bbb8e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:12:39.948] Invocation finished in 1h8m53.607835111s with status SUCCEEDED