[23:34:42.821] New invocation is queued and will start shortly
[23:34:44.107] Starting the invocation (attempt 1)
[23:34:44.156] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/f5ce37c48a7b549b305cce760270e67e19d955ae"
[23:34:44.156] Popped gitiles commit info from properties and tags
[23:34:44.156] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:34:44.156] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:34:44.156] Buildbucket request:
{
"requestId": "8924131847724722864",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8924131847724722864",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "02e08bb6b80b057197e52231aa32612bebdbef19"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@02e08bb6b80b057197e52231aa32612bebdbef19",
"title": "02e08bb6b80b057197e52231aa32612bebdbef19",
"url": "https://fuchsia.googlesource.com/integration/+/02e08bb6b80b057197e52231aa32612bebdbef19"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bc98c5821b3704083979b74ac1c3370033babf61"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bc98c5821b3704083979b74ac1c3370033babf61",
"title": "bc98c5821b3704083979b74ac1c3370033babf61",
"url": "https://fuchsia.googlesource.com/integration/+/bc98c5821b3704083979b74ac1c3370033babf61"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "498e6da7631f4ef5b78c62b02860da4a940bb4c2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@498e6da7631f4ef5b78c62b02860da4a940bb4c2",
"title": "498e6da7631f4ef5b78c62b02860da4a940bb4c2",
"url": "https://fuchsia.googlesource.com/integration/+/498e6da7631f4ef5b78c62b02860da4a940bb4c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e9347db32933701bb6e55b3b90aafecb2511d5c4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e9347db32933701bb6e55b3b90aafecb2511d5c4",
"title": "e9347db32933701bb6e55b3b90aafecb2511d5c4",
"url": "https://fuchsia.googlesource.com/integration/+/e9347db32933701bb6e55b3b90aafecb2511d5c4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a14b2dc83e362f6a11b857cd5561ed8fd0be6ea7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a14b2dc83e362f6a11b857cd5561ed8fd0be6ea7",
"title": "a14b2dc83e362f6a11b857cd5561ed8fd0be6ea7",
"url": "https://fuchsia.googlesource.com/integration/+/a14b2dc83e362f6a11b857cd5561ed8fd0be6ea7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ab2a0c08cf5bcc14b7ef23944d0bf984f114ae62"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ab2a0c08cf5bcc14b7ef23944d0bf984f114ae62",
"title": "ab2a0c08cf5bcc14b7ef23944d0bf984f114ae62",
"url": "https://fuchsia.googlesource.com/integration/+/ab2a0c08cf5bcc14b7ef23944d0bf984f114ae62"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c698cf9608e235d42be7016e49d74841abb0c39"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c698cf9608e235d42be7016e49d74841abb0c39",
"title": "7c698cf9608e235d42be7016e49d74841abb0c39",
"url": "https://fuchsia.googlesource.com/integration/+/7c698cf9608e235d42be7016e49d74841abb0c39"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "54322e0a4827ac4eaf5444531393d6dd9e3631cf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@54322e0a4827ac4eaf5444531393d6dd9e3631cf",
"title": "54322e0a4827ac4eaf5444531393d6dd9e3631cf",
"url": "https://fuchsia.googlesource.com/integration/+/54322e0a4827ac4eaf5444531393d6dd9e3631cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c830a2db49b5fd035ea44b40f17705d69748254"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c830a2db49b5fd035ea44b40f17705d69748254",
"title": "7c830a2db49b5fd035ea44b40f17705d69748254",
"url": "https://fuchsia.googlesource.com/integration/+/7c830a2db49b5fd035ea44b40f17705d69748254"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f40e7ec35725901100ec973f60f5abc1a13c1380"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f40e7ec35725901100ec973f60f5abc1a13c1380",
"title": "f40e7ec35725901100ec973f60f5abc1a13c1380",
"url": "https://fuchsia.googlesource.com/integration/+/f40e7ec35725901100ec973f60f5abc1a13c1380"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2205f2a2b827ad570869d50c927f6b70c7e8d208"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2205f2a2b827ad570869d50c927f6b70c7e8d208",
"title": "2205f2a2b827ad570869d50c927f6b70c7e8d208",
"url": "https://fuchsia.googlesource.com/integration/+/2205f2a2b827ad570869d50c927f6b70c7e8d208"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6ae3ccaf0b6bfa9ccaa70d6ad47aeb1c60174a4d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6ae3ccaf0b6bfa9ccaa70d6ad47aeb1c60174a4d",
"title": "6ae3ccaf0b6bfa9ccaa70d6ad47aeb1c60174a4d",
"url": "https://fuchsia.googlesource.com/integration/+/6ae3ccaf0b6bfa9ccaa70d6ad47aeb1c60174a4d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9094ec7e68991fbef73a70d7c08059009263f9c4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9094ec7e68991fbef73a70d7c08059009263f9c4",
"title": "9094ec7e68991fbef73a70d7c08059009263f9c4",
"url": "https://fuchsia.googlesource.com/integration/+/9094ec7e68991fbef73a70d7c08059009263f9c4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bafba22d753f4c21244de77408839e48bcae91d6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bafba22d753f4c21244de77408839e48bcae91d6",
"title": "bafba22d753f4c21244de77408839e48bcae91d6",
"url": "https://fuchsia.googlesource.com/integration/+/bafba22d753f4c21244de77408839e48bcae91d6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "65dc01344c59c7db82fbdacb3993c5d52c92e294"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@65dc01344c59c7db82fbdacb3993c5d52c92e294",
"title": "65dc01344c59c7db82fbdacb3993c5d52c92e294",
"url": "https://fuchsia.googlesource.com/integration/+/65dc01344c59c7db82fbdacb3993c5d52c92e294"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1fdf6a32e072ee56ddc5f5831ceb472a1d738423"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1fdf6a32e072ee56ddc5f5831ceb472a1d738423",
"title": "1fdf6a32e072ee56ddc5f5831ceb472a1d738423",
"url": "https://fuchsia.googlesource.com/integration/+/1fdf6a32e072ee56ddc5f5831ceb472a1d738423"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a16a02f52d39c2a6abcfcd587357f44898be0695"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a16a02f52d39c2a6abcfcd587357f44898be0695",
"title": "a16a02f52d39c2a6abcfcd587357f44898be0695",
"url": "https://fuchsia.googlesource.com/integration/+/a16a02f52d39c2a6abcfcd587357f44898be0695"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "115c02b886661992756e11c6f8690d7ad8ffaf28"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@115c02b886661992756e11c6f8690d7ad8ffaf28",
"title": "115c02b886661992756e11c6f8690d7ad8ffaf28",
"url": "https://fuchsia.googlesource.com/integration/+/115c02b886661992756e11c6f8690d7ad8ffaf28"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e59b8a50f938f8090914d884f9a9bdb7888ff272"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e59b8a50f938f8090914d884f9a9bdb7888ff272",
"title": "e59b8a50f938f8090914d884f9a9bdb7888ff272",
"url": "https://fuchsia.googlesource.com/integration/+/e59b8a50f938f8090914d884f9a9bdb7888ff272"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5b3f5996811902b38262e6245414d620f875d3e3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5b3f5996811902b38262e6245414d620f875d3e3",
"title": "5b3f5996811902b38262e6245414d620f875d3e3",
"url": "https://fuchsia.googlesource.com/integration/+/5b3f5996811902b38262e6245414d620f875d3e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1ca76180b0b25d9beb3c4c4186971f5c12deb40e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1ca76180b0b25d9beb3c4c4186971f5c12deb40e",
"title": "1ca76180b0b25d9beb3c4c4186971f5c12deb40e",
"url": "https://fuchsia.googlesource.com/integration/+/1ca76180b0b25d9beb3c4c4186971f5c12deb40e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "58489fd5d06184622c956a1ec6eff9f23e1bad59"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@58489fd5d06184622c956a1ec6eff9f23e1bad59",
"title": "58489fd5d06184622c956a1ec6eff9f23e1bad59",
"url": "https://fuchsia.googlesource.com/integration/+/58489fd5d06184622c956a1ec6eff9f23e1bad59"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f5ce37c48a7b549b305cce760270e67e19d955ae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f5ce37c48a7b549b305cce760270e67e19d955ae",
"title": "f5ce37c48a7b549b305cce760270e67e19d955ae",
"url": "https://fuchsia.googlesource.com/integration/+/f5ce37c48a7b549b305cce760270e67e19d955ae"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f5ce37c48a7b549b305cce760270e67e19d955ae",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8924131847724722864"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-asan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:34:44.594] Scheduled build:
{
"id": "8758701785593747089",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-16T23:34:44.225822381Z",
"updateTime": "2024-01-16T23:34:44.225822381Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f5ce37c48a7b549b305cce760270e67e19d955ae",
"ref": "refs/heads/main"
}
},
"canary": true
}
[23:34:44.594] Task URL: https://cr-buildbucket.appspot.com/build/8758701785593747089
[23:34:44.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:2:0) after 3m51s
[23:35:07.458] Received PubSub notification, asking Buildbucket for the build status
[23:35:07.526] Build status: SCHEDULED
[23:36:10.343] Received PubSub notification, asking Buildbucket for the build status
[23:36:10.405] Build status: STARTED
[23:38:36.057] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:2:0)
[23:38:36.073] Build status: STARTED
[23:38:36.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:5:0) after 9m14s
[23:47:50.179] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:5:0)
[23:47:50.202] Build status: STARTED
[23:47:50.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:6:0) after 4m37s
[23:52:27.294] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:6:0)
[23:52:27.312] Build status: STARTED
[23:52:27.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:7:0) after 3m48s
[23:56:15.404] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:7:0)
[23:56:15.418] Build status: STARTED
[23:56:15.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:8:0) after 9m24s
[00:05:39.498] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:8:0)
[00:05:39.513] Build status: STARTED
[00:05:39.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:9:0) after 6m13s
[00:11:52.903] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:9:0)
[00:11:52.922] Build status: STARTED
[00:11:52.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:10:0) after 5m8s
[00:17:00.940] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:10:0)
[00:17:00.956] Build status: STARTED
[00:17:00.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:11:0) after 6m49s
[00:23:49.989] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:11:0)
[00:23:50.005] Build status: STARTED
[00:23:50.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:12:0) after 1m35s
[00:25:25.034] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:12:0)
[00:25:25.050] Build status: STARTED
[00:25:25.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:13:0) after 3m2s
[00:28:27.655] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:13:0)
[00:28:27.686] Build status: STARTED
[00:28:27.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:14:0) after 1m28s
[00:29:55.702] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:14:0)
[00:29:55.734] Build status: STARTED
[00:29:55.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:15:0) after 4m7s
[00:34:02.962] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:15:0)
[00:34:02.986] Build status: STARTED
[00:34:02.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:16:0) after 9m47s
[00:43:50.619] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:16:0)
[00:43:50.669] Build status: STARTED
[00:43:50.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:17:0) after 8m37s
[00:52:27.879] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:17:0)
[00:52:27.908] Build status: STARTED
[00:52:27.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:18:0) after 3m17s
[00:55:45.140] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:18:0)
[00:55:45.235] Build status: STARTED
[00:55:45.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8924131847724722864:19:0) after 6m24s
[01:02:02.266] Received PubSub notification, asking Buildbucket for the build status
[01:02:02.370] Build:
{
"id": "8758701785593747089",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-16T23:34:44.225822381Z",
"startTime": "2024-01-16T23:35:55.364951Z",
"endTime": "2024-01-17T01:02:01.524689Z",
"updateTime": "2024-01-17T01:02:01.524689Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f5ce37c48a7b549b305cce760270e67e19d955ae",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:02:02.370] Invocation finished in 1h27m19.560917034s with status SUCCEEDED