[18:55:27.023] New invocation is queued and will start shortly
[18:55:28.282] Starting the invocation (attempt 1)
[18:55:28.309] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/13f2bdd84a4e3255abb0a5ddbf54e96abf66367d"
[18:55:28.309] Popped gitiles commit info from properties and tags
[18:55:28.310] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:55:28.310] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:55:28.310] Buildbucket request:
{
"requestId": "8884105558301629440",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884105558301629440",
"job": "fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9db0f9b80f2d04aab8cc35bd91ca77c37cc9c506"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9db0f9b80f2d04aab8cc35bd91ca77c37cc9c506",
"title": "9db0f9b80f2d04aab8cc35bd91ca77c37cc9c506",
"url": "https://fuchsia.googlesource.com/integration/+/9db0f9b80f2d04aab8cc35bd91ca77c37cc9c506"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f7f048f7276933449e344cce380c5bb66b23aa6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f7f048f7276933449e344cce380c5bb66b23aa6",
"title": "4f7f048f7276933449e344cce380c5bb66b23aa6",
"url": "https://fuchsia.googlesource.com/integration/+/4f7f048f7276933449e344cce380c5bb66b23aa6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fdb7b6f57e84cf1b67c313b0eb1f87f154434184"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fdb7b6f57e84cf1b67c313b0eb1f87f154434184",
"title": "fdb7b6f57e84cf1b67c313b0eb1f87f154434184",
"url": "https://fuchsia.googlesource.com/integration/+/fdb7b6f57e84cf1b67c313b0eb1f87f154434184"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "03923d8c97e6d26c9c941d78440219f8d16a50e5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@03923d8c97e6d26c9c941d78440219f8d16a50e5",
"title": "03923d8c97e6d26c9c941d78440219f8d16a50e5",
"url": "https://fuchsia.googlesource.com/integration/+/03923d8c97e6d26c9c941d78440219f8d16a50e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cfc1780e74e98c06e9ffd2c2e39d42d00adbcbf6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cfc1780e74e98c06e9ffd2c2e39d42d00adbcbf6",
"title": "cfc1780e74e98c06e9ffd2c2e39d42d00adbcbf6",
"url": "https://fuchsia.googlesource.com/integration/+/cfc1780e74e98c06e9ffd2c2e39d42d00adbcbf6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a2b5ed530515c81202ccbd9ec1985847b8ca76ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a2b5ed530515c81202ccbd9ec1985847b8ca76ce",
"title": "a2b5ed530515c81202ccbd9ec1985847b8ca76ce",
"url": "https://fuchsia.googlesource.com/integration/+/a2b5ed530515c81202ccbd9ec1985847b8ca76ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "369b625c49e90028fbb6cf86518a897adf87f406"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@369b625c49e90028fbb6cf86518a897adf87f406",
"title": "369b625c49e90028fbb6cf86518a897adf87f406",
"url": "https://fuchsia.googlesource.com/integration/+/369b625c49e90028fbb6cf86518a897adf87f406"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5a4b5fe6db93380c0e72f0769282bfe5fa7ab420"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5a4b5fe6db93380c0e72f0769282bfe5fa7ab420",
"title": "5a4b5fe6db93380c0e72f0769282bfe5fa7ab420",
"url": "https://fuchsia.googlesource.com/integration/+/5a4b5fe6db93380c0e72f0769282bfe5fa7ab420"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "13f2bdd84a4e3255abb0a5ddbf54e96abf66367d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@13f2bdd84a4e3255abb0a5ddbf54e96abf66367d",
"title": "13f2bdd84a4e3255abb0a5ddbf54e96abf66367d",
"url": "https://fuchsia.googlesource.com/integration/+/13f2bdd84a4e3255abb0a5ddbf54e96abf66367d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "13f2bdd84a4e3255abb0a5ddbf54e96abf66367d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884105558301629440"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:55:28.678] Scheduled build:
{
"id": "8718675496239090433",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-02T18:55:28.364675218Z",
"updateTime": "2025-04-02T18:55:28.364675218Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "13f2bdd84a4e3255abb0a5ddbf54e96abf66367d",
"ref": "refs/heads/main"
}
}
}
[18:55:28.678] Task URL: https://cr-buildbucket.appspot.com/build/8718675496239090433
[18:55:28.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:2:0) after 4m51s
[18:55:29.835] Received PubSub notification, asking Buildbucket for the build status
[18:55:29.855] Build status: SCHEDULED
[18:55:35.300] Received PubSub notification, asking Buildbucket for the build status
[18:55:35.323] Build status: STARTED
[19:00:19.751] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:2:0)
[19:00:19.773] Build status: STARTED
[19:00:19.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:5:0) after 2m44s
[19:03:03.913] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:5:0)
[19:03:03.935] Build status: STARTED
[19:03:03.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:6:0) after 9m4s
[19:12:07.968] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:6:0)
[19:12:08.049] Build status: STARTED
[19:12:08.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:7:0) after 4m24s
[19:16:32.184] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:7:0)
[19:16:32.208] Build status: STARTED
[19:16:32.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:8:0) after 1m46s
[19:18:18.227] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:8:0)
[19:18:18.307] Build status: STARTED
[19:18:18.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:9:0) after 1m23s
[19:19:41.347] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:9:0)
[19:19:41.383] Build status: STARTED
[19:19:41.383] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:10:0) after 1m37s
[19:21:18.382] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:10:0)
[19:21:18.407] Build status: STARTED
[19:21:18.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:11:0) after 4m33s
[19:25:51.430] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:11:0)
[19:25:51.461] Build status: STARTED
[19:25:51.461] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:12:0) after 8m6s
[19:33:57.562] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:12:0)
[19:33:57.575] Build status: STARTED
[19:33:57.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:13:0) after 6m48s
[19:40:45.664] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:13:0)
[19:40:45.700] Build status: STARTED
[19:40:45.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:14:0) after 4m45s
[19:45:30.774] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:14:0)
[19:45:30.788] Build status: STARTED
[19:45:30.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:15:0) after 5m30s
[19:51:01.066] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:15:0)
[19:51:01.085] Build status: STARTED
[19:51:01.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:16:0) after 1m54s
[19:52:55.113] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:16:0)
[19:52:55.142] Build status: STARTED
[19:52:55.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:17:0) after 6m15s
[19:59:10.168] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:17:0)
[19:59:10.186] Build status: STARTED
[19:59:10.186] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:18:0) after 5m24s
[20:04:34.203] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:18:0)
[20:04:34.231] Build status: STARTED
[20:04:34.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:19:0) after 6m14s
[20:10:48.295] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:19:0)
[20:10:48.313] Build status: STARTED
[20:10:48.313] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:20:0) after 6m0s
[20:16:48.284] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:20:0)
[20:16:48.301] Build status: STARTED
[20:16:48.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:21:0) after 1m49s
[20:18:37.328] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:21:0)
[20:18:37.346] Build status: STARTED
[20:18:37.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.arm64-thinlto-e5a0ec7f:8884105558301629440:22:0) after 7m7s
[20:23:08.299] Received PubSub notification, asking Buildbucket for the build status
[20:23:08.320] Build:
{
"id": "8718675496239090433",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"createdBy": "project:fuchsia",
"createTime": "2025-04-02T18:55:28.364675218Z",
"startTime": "2025-04-02T18:55:34.637466064Z",
"endTime": "2025-04-02T20:23:07.700469956Z",
"updateTime": "2025-04-02T20:23:07.700469956Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "13f2bdd84a4e3255abb0a5ddbf54e96abf66367d",
"ref": "refs/heads/main"
}
}
}
[20:23:08.320] Invocation finished in 1h27m41.307282018s with status SUCCEEDED