[23:13:18.425] New invocation is queued and will start shortly
[23:13:19.595] Starting the invocation (attempt 1)
[23:13:19.619] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/58e591c4389c23bc7c7c5d929f7195f5d065714c"
[23:13:19.619] Popped gitiles commit info from properties and tags
[23:13:19.619] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:13:19.620] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:13:19.620] Buildbucket request:
{
"requestId": "8911540216176855792",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8911540216176855792",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ffe2b7333f6b64d9f9846a5b4ec0b7435fdd5a4c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ffe2b7333f6b64d9f9846a5b4ec0b7435fdd5a4c",
"title": "ffe2b7333f6b64d9f9846a5b4ec0b7435fdd5a4c",
"url": "https://fuchsia.googlesource.com/integration/+/ffe2b7333f6b64d9f9846a5b4ec0b7435fdd5a4c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6c83e3d2f8133ddd130ed66d4580551868d3e816"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6c83e3d2f8133ddd130ed66d4580551868d3e816",
"title": "6c83e3d2f8133ddd130ed66d4580551868d3e816",
"url": "https://fuchsia.googlesource.com/integration/+/6c83e3d2f8133ddd130ed66d4580551868d3e816"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "12036aab68598da73eb602e8b764b2d51ba2bb92"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@12036aab68598da73eb602e8b764b2d51ba2bb92",
"title": "12036aab68598da73eb602e8b764b2d51ba2bb92",
"url": "https://fuchsia.googlesource.com/integration/+/12036aab68598da73eb602e8b764b2d51ba2bb92"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "335f65d24877b98a9ccba9dd626515ceb4da3655"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@335f65d24877b98a9ccba9dd626515ceb4da3655",
"title": "335f65d24877b98a9ccba9dd626515ceb4da3655",
"url": "https://fuchsia.googlesource.com/integration/+/335f65d24877b98a9ccba9dd626515ceb4da3655"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "47c7a56e1d406d2e9e90f61788b592ff6592d1e7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@47c7a56e1d406d2e9e90f61788b592ff6592d1e7",
"title": "47c7a56e1d406d2e9e90f61788b592ff6592d1e7",
"url": "https://fuchsia.googlesource.com/integration/+/47c7a56e1d406d2e9e90f61788b592ff6592d1e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eabb66374f8893191bda85703fbcb5767bbcc440"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eabb66374f8893191bda85703fbcb5767bbcc440",
"title": "eabb66374f8893191bda85703fbcb5767bbcc440",
"url": "https://fuchsia.googlesource.com/integration/+/eabb66374f8893191bda85703fbcb5767bbcc440"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "380a165c570b3a08eee4620592f2f04124ae7110"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@380a165c570b3a08eee4620592f2f04124ae7110",
"title": "380a165c570b3a08eee4620592f2f04124ae7110",
"url": "https://fuchsia.googlesource.com/integration/+/380a165c570b3a08eee4620592f2f04124ae7110"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fde8560685b2d2dbe76ad8416ac09a0fca73992f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fde8560685b2d2dbe76ad8416ac09a0fca73992f",
"title": "fde8560685b2d2dbe76ad8416ac09a0fca73992f",
"url": "https://fuchsia.googlesource.com/integration/+/fde8560685b2d2dbe76ad8416ac09a0fca73992f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "32642af3243d99c915e6a285cb65b6381202d058"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@32642af3243d99c915e6a285cb65b6381202d058",
"title": "32642af3243d99c915e6a285cb65b6381202d058",
"url": "https://fuchsia.googlesource.com/integration/+/32642af3243d99c915e6a285cb65b6381202d058"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "58e591c4389c23bc7c7c5d929f7195f5d065714c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@58e591c4389c23bc7c7c5d929f7195f5d065714c",
"title": "58e591c4389c23bc7c7c5d929f7195f5d065714c",
"url": "https://fuchsia.googlesource.com/integration/+/58e591c4389c23bc7c7c5d929f7195f5d065714c"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "58e591c4389c23bc7c7c5d929f7195f5d065714c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8911540216176855792"
},
{
"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:13:20.037] Scheduled build:
{
"id": "8746110154211444433",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-03T23:13:19.676617050Z",
"updateTime": "2024-06-03T23:13:19.676617050Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "58e591c4389c23bc7c7c5d929f7195f5d065714c",
"ref": "refs/heads/main"
}
}
}
[23:13:20.037] Task URL: https://cr-buildbucket.appspot.com/build/8746110154211444433
[23:13:20.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:2:0) after 9m26s
[23:15:05.209] Received PubSub notification, asking Buildbucket for the build status
[23:15:05.225] Build status: SCHEDULED
[23:15:34.205] Received PubSub notification, asking Buildbucket for the build status
[23:15:34.265] Build status: STARTED
[23:22:46.054] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:2:0)
[23:22:46.076] Build status: STARTED
[23:22:46.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:5:0) after 3m0s
[23:25:46.091] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:5:0)
[23:25:46.111] Build status: STARTED
[23:25:46.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:6:0) after 7m0s
[23:32:46.150] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:6:0)
[23:32:46.167] Build status: STARTED
[23:32:46.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:7:0) after 4m2s
[23:36:48.267] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:7:0)
[23:36:48.282] Build status: STARTED
[23:36:48.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:8:0) after 9m57s
[23:46:45.448] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:8:0)
[23:46:45.471] Build status: STARTED
[23:46:45.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:9:0) after 3m6s
[23:49:51.479] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:9:0)
[23:49:51.496] Build status: STARTED
[23:49:51.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:10:0) after 9m52s
[23:59:43.515] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:10:0)
[23:59:43.718] Build status: STARTED
[23:59:43.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:11:0) after 8m1s
[00:07:44.726] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:11:0)
[00:07:44.756] Build status: STARTED
[00:07:44.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:12:0) after 3m22s
[00:11:06.790] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:12:0)
[00:11:06.809] Build status: STARTED
[00:11:06.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:13:0) after 8m6s
[00:19:13.466] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:13:0)
[00:19:13.489] Build status: STARTED
[00:19:13.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:14:0) after 6m34s
[00:25:47.555] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:14:0)
[00:25:47.572] Build status: STARTED
[00:25:47.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:15:0) after 4m18s
[00:30:05.671] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:15:0)
[00:30:05.694] Build status: STARTED
[00:30:05.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:16:0) after 6m56s
[00:37:01.714] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:16:0)
[00:37:01.735] Build status: STARTED
[00:37:01.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8911540216176855792:17:0) after 5m7s
[00:41:32.699] Received PubSub notification, asking Buildbucket for the build status
[00:41:32.726] Build:
{
"id": "8746110154211444433",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-03T23:13:19.676617050Z",
"startTime": "2024-06-03T23:15:33.995966454Z",
"endTime": "2024-06-04T00:41:31.962597110Z",
"updateTime": "2024-06-04T00:41:31.962597110Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "58e591c4389c23bc7c7c5d929f7195f5d065714c",
"ref": "refs/heads/main"
}
}
}
[00:41:32.726] Invocation finished in 1h28m14.308284762s with status SUCCEEDED