[18:56:35.380] New invocation is queued and will start shortly
[18:56:36.726] Starting the invocation (attempt 1)
[18:56:36.778] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/559c0abb07f6436ab9efa942a095796dbb803535"
[18:56:36.778] Popped gitiles commit info from properties and tags
[18:56:36.778] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:56:36.811] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:56:36.812] Buildbucket request:
{
"requestId": "8932212475785511056",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.riscv64-profile"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8932212475785511056",
"job": "fuchsia/bringup.riscv64-profile-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "75dadc76847472f60d7ffad91a157f74cca8e1ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@75dadc76847472f60d7ffad91a157f74cca8e1ce",
"title": "75dadc76847472f60d7ffad91a157f74cca8e1ce",
"url": "https://fuchsia.googlesource.com/integration/+/75dadc76847472f60d7ffad91a157f74cca8e1ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d35ae2995ef110fb18167482f9c9c25004f34322"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d35ae2995ef110fb18167482f9c9c25004f34322",
"title": "d35ae2995ef110fb18167482f9c9c25004f34322",
"url": "https://fuchsia.googlesource.com/integration/+/d35ae2995ef110fb18167482f9c9c25004f34322"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "18aaece4cdafad74b820e976cd100c4326ba44ef"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@18aaece4cdafad74b820e976cd100c4326ba44ef",
"title": "18aaece4cdafad74b820e976cd100c4326ba44ef",
"url": "https://fuchsia.googlesource.com/integration/+/18aaece4cdafad74b820e976cd100c4326ba44ef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "310a811cfe98c2853fa4ffbe6643401363612948"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@310a811cfe98c2853fa4ffbe6643401363612948",
"title": "310a811cfe98c2853fa4ffbe6643401363612948",
"url": "https://fuchsia.googlesource.com/integration/+/310a811cfe98c2853fa4ffbe6643401363612948"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c65cfe0881f2606dce51068ec448b25dd14e5751"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c65cfe0881f2606dce51068ec448b25dd14e5751",
"title": "c65cfe0881f2606dce51068ec448b25dd14e5751",
"url": "https://fuchsia.googlesource.com/integration/+/c65cfe0881f2606dce51068ec448b25dd14e5751"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f4e5b8152721e403fb6b30bce1a203586c5daf5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f4e5b8152721e403fb6b30bce1a203586c5daf5",
"title": "4f4e5b8152721e403fb6b30bce1a203586c5daf5",
"url": "https://fuchsia.googlesource.com/integration/+/4f4e5b8152721e403fb6b30bce1a203586c5daf5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a66213e252dc648bcb2060b99a806ebf3a9cc45b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a66213e252dc648bcb2060b99a806ebf3a9cc45b",
"title": "a66213e252dc648bcb2060b99a806ebf3a9cc45b",
"url": "https://fuchsia.googlesource.com/integration/+/a66213e252dc648bcb2060b99a806ebf3a9cc45b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d",
"title": "01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d",
"url": "https://fuchsia.googlesource.com/integration/+/01d2b1f603e770f642d3cc64f6dcb1c7ddc1bf0d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c16918c5a71a0c10d0b62c65c87dcd8f77cffacd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c16918c5a71a0c10d0b62c65c87dcd8f77cffacd",
"title": "c16918c5a71a0c10d0b62c65c87dcd8f77cffacd",
"url": "https://fuchsia.googlesource.com/integration/+/c16918c5a71a0c10d0b62c65c87dcd8f77cffacd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "559c0abb07f6436ab9efa942a095796dbb803535"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@559c0abb07f6436ab9efa942a095796dbb803535",
"title": "559c0abb07f6436ab9efa942a095796dbb803535",
"url": "https://fuchsia.googlesource.com/integration/+/559c0abb07f6436ab9efa942a095796dbb803535"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "559c0abb07f6436ab9efa942a095796dbb803535",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8932212475785511056"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/bringup.riscv64-profile-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:56:37.397] Scheduled build:
{
"id": "8766782413478393633",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.riscv64-profile"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T18:56:36.951269235Z",
"updateTime": "2023-10-19T18:56:36.951269235Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "559c0abb07f6436ab9efa942a095796dbb803535",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:56:37.397] Task URL: https://cr-buildbucket.appspot.com/build/8766782413478393633
[18:56:37.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:2:0) after 9m38s
[18:56:38.389] Received PubSub notification, asking Buildbucket for the build status
[18:56:38.539] Build status: SCHEDULED
[18:58:12.753] Received PubSub notification, asking Buildbucket for the build status
[18:58:12.779] Build status: STARTED
[19:06:15.516] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:2:0)
[19:06:15.543] Build status: STARTED
[19:06:15.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:5:0) after 4m39s
[19:10:54.562] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:5:0)
[19:10:54.610] Build status: STARTED
[19:10:54.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:6:0) after 4m43s
[19:15:37.630] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:6:0)
[19:15:37.658] Build status: STARTED
[19:15:37.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:7:0) after 2m35s
[19:18:12.625] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:7:0)
[19:18:12.644] Build status: STARTED
[19:18:12.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:8:0) after 1m12s
[19:19:24.976] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:8:0)
[19:19:24.992] Build status: STARTED
[19:19:24.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:9:0) after 2m20s
[19:21:45.011] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:9:0)
[19:21:45.027] Build status: STARTED
[19:21:45.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:10:0) after 2m11s
[19:23:56.153] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:10:0)
[19:23:56.192] Build status: STARTED
[19:23:56.192] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:11:0) after 4m21s
[19:28:17.244] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:11:0)
[19:28:17.263] Build status: STARTED
[19:28:17.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:12:0) after 5m53s
[19:34:10.447] Handling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:12:0)
[19:34:10.521] Build status: STARTED
[19:34:10.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/bringup.riscv64-profile-e5a0ec7f:8932212475785511056:13:0) after 7m8s
[19:38:27.474] Received PubSub notification, asking Buildbucket for the build status
[19:38:27.495] Build:
{
"id": "8766782413478393633",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "bringup.riscv64-profile"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T18:56:36.951269235Z",
"startTime": "2023-10-19T18:58:11.486911Z",
"endTime": "2023-10-19T19:38:26.922662999Z",
"updateTime": "2023-10-19T19:38:26.922662999Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "559c0abb07f6436ab9efa942a095796dbb803535",
"ref": "refs/heads/main"
}
},
"canary": true
}
[19:38:27.495] Invocation finished in 41m52.12823199s with status FAILED