[22:35:31.684] New invocation is queued and will start shortly
[22:35:33.847] Starting the invocation (attempt 1)
[22:35:33.876] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/98a0b13af36a771a27b05981383e97dd2a6a2b45"
[22:35:33.876] Popped gitiles commit info from properties and tags
[22:35:33.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:35:33.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:35:33.877] Buildbucket request:
{
"requestId": "8896141108743221328",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896141108743221328",
"job": "fuchsia/core.x64-release-profile-kernel-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b116b6ebd699cd096b617f14d1da1379968ae7d1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b116b6ebd699cd096b617f14d1da1379968ae7d1",
"title": "b116b6ebd699cd096b617f14d1da1379968ae7d1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b116b6ebd699cd096b617f14d1da1379968ae7d1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "57a3977adcf018718c25fec2c0e0244fb778a8aa"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@57a3977adcf018718c25fec2c0e0244fb778a8aa",
"title": "57a3977adcf018718c25fec2c0e0244fb778a8aa",
"url": "https://fuchsia.googlesource.com/fuchsia/+/57a3977adcf018718c25fec2c0e0244fb778a8aa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "173fcb28b66274c409b297911089daaef64d54ab"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@173fcb28b66274c409b297911089daaef64d54ab",
"title": "173fcb28b66274c409b297911089daaef64d54ab",
"url": "https://fuchsia.googlesource.com/fuchsia/+/173fcb28b66274c409b297911089daaef64d54ab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d886ceb413b002ab95b230692faba7b6c3beab86"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d886ceb413b002ab95b230692faba7b6c3beab86",
"title": "d886ceb413b002ab95b230692faba7b6c3beab86",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d886ceb413b002ab95b230692faba7b6c3beab86"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "abff7686963972f88109ab856b28244a1f0b7327"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@abff7686963972f88109ab856b28244a1f0b7327",
"title": "abff7686963972f88109ab856b28244a1f0b7327",
"url": "https://fuchsia.googlesource.com/fuchsia/+/abff7686963972f88109ab856b28244a1f0b7327"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0f4bf2623113a143dcfe9aac59966aa8e3167c5e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0f4bf2623113a143dcfe9aac59966aa8e3167c5e",
"title": "0f4bf2623113a143dcfe9aac59966aa8e3167c5e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0f4bf2623113a143dcfe9aac59966aa8e3167c5e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6151a08967287dc8fccc69cd7b8deb49dcc4adb0"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6151a08967287dc8fccc69cd7b8deb49dcc4adb0",
"title": "6151a08967287dc8fccc69cd7b8deb49dcc4adb0",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6151a08967287dc8fccc69cd7b8deb49dcc4adb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "dc8837306d385b135c7e32bc135fb97a5c84b0bf"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@dc8837306d385b135c7e32bc135fb97a5c84b0bf",
"title": "dc8837306d385b135c7e32bc135fb97a5c84b0bf",
"url": "https://fuchsia.googlesource.com/fuchsia/+/dc8837306d385b135c7e32bc135fb97a5c84b0bf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9a1dd4469d16b85debc2778da3b30c8bb6ad8975"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9a1dd4469d16b85debc2778da3b30c8bb6ad8975",
"title": "9a1dd4469d16b85debc2778da3b30c8bb6ad8975",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9a1dd4469d16b85debc2778da3b30c8bb6ad8975"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fbd33ea8759dd29eb6054c78022eb27e8ce042b3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fbd33ea8759dd29eb6054c78022eb27e8ce042b3",
"title": "fbd33ea8759dd29eb6054c78022eb27e8ce042b3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fbd33ea8759dd29eb6054c78022eb27e8ce042b3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "98a0b13af36a771a27b05981383e97dd2a6a2b45"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@98a0b13af36a771a27b05981383e97dd2a6a2b45",
"title": "98a0b13af36a771a27b05981383e97dd2a6a2b45",
"url": "https://fuchsia.googlesource.com/fuchsia/+/98a0b13af36a771a27b05981383e97dd2a6a2b45"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "98a0b13af36a771a27b05981383e97dd2a6a2b45",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896141108743221328"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-profile-kernel-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:35:34.331] Scheduled build:
{
"id": "8730711045722337185",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-20T22:35:33.938257873Z",
"updateTime": "2024-11-20T22:35:33.938257873Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "98a0b13af36a771a27b05981383e97dd2a6a2b45",
"ref": "refs/heads/main"
}
}
}
[22:35:34.331] Task URL: https://cr-buildbucket.appspot.com/build/8730711045722337185
[22:35:34.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:2:0) after 5m12s
[22:35:35.280] Received PubSub notification, asking Buildbucket for the build status
[22:35:35.297] Build status: SCHEDULED
[22:38:40.267] Received PubSub notification, asking Buildbucket for the build status
[22:38:40.289] Build status: STARTED
[22:40:46.428] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:2:0)
[22:40:46.446] Build status: STARTED
[22:40:46.446] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:5:0) after 4m6s
[22:44:52.511] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:5:0)
[22:44:52.536] Build status: STARTED
[22:44:52.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:6:0) after 5m42s
[22:50:34.554] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:6:0)
[22:50:34.582] Build status: STARTED
[22:50:34.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:7:0) after 7m19s
[22:57:53.602] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:7:0)
[22:57:53.646] Build status: STARTED
[22:57:53.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:8:0) after 5m34s
[23:03:27.662] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:8:0)
[23:03:27.677] Build status: STARTED
[23:03:27.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:9:0) after 6m41s
[23:10:08.724] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:9:0)
[23:10:08.748] Build status: STARTED
[23:10:08.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:10:0) after 7m15s
[23:17:23.783] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:10:0)
[23:17:23.802] Build status: STARTED
[23:17:23.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:11:0) after 8m4s
[23:25:27.887] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:11:0)
[23:25:27.908] Build status: STARTED
[23:25:27.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:12:0) after 9m38s
[23:35:05.971] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:12:0)
[23:35:05.995] Build status: STARTED
[23:35:05.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:13:0) after 7m4s
[23:42:10.014] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:13:0)
[23:42:10.032] Build status: STARTED
[23:42:10.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:14:0) after 9m33s
[23:51:43.092] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:14:0)
[23:51:43.110] Build status: STARTED
[23:51:43.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:15:0) after 3m27s
[23:55:10.127] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:15:0)
[23:55:10.147] Build status: STARTED
[23:55:10.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896141108743221328:16:0) after 1m51s
[23:56:53.499] Received PubSub notification, asking Buildbucket for the build status
[23:56:53.535] Build:
{
"id": "8730711045722337185",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-20T22:35:33.938257873Z",
"startTime": "2024-11-20T22:38:26.203550544Z",
"endTime": "2024-11-20T23:56:53.075997218Z",
"updateTime": "2024-11-20T23:56:53.075997218Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "98a0b13af36a771a27b05981383e97dd2a6a2b45",
"ref": "refs/heads/main"
}
}
}
[23:56:53.535] Invocation finished in 1h21m21.861678946s with status SUCCEEDED