[09:40:34.311] New invocation is queued and will start shortly
[09:40:35.369] Starting the invocation (attempt 1)
[09:40:35.396] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/75eefb9d952ac95ba0fac77955288185ad75752a"
[09:40:35.397] Popped gitiles commit info from properties and tags
[09:40:35.397] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:40:35.397] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:40:35.397] Buildbucket request:
{
"requestId": "8888398525667551680",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888398525667551680",
"job": "fuchsia/fuchsia-profile-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ce9edf3b6d3c648f247251a46226cfabc9b64fcd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ce9edf3b6d3c648f247251a46226cfabc9b64fcd",
"title": "ce9edf3b6d3c648f247251a46226cfabc9b64fcd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ce9edf3b6d3c648f247251a46226cfabc9b64fcd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4e66570ada5599d4b5705d88d4c795b21b27e8b5"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4e66570ada5599d4b5705d88d4c795b21b27e8b5",
"title": "4e66570ada5599d4b5705d88d4c795b21b27e8b5",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4e66570ada5599d4b5705d88d4c795b21b27e8b5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9d3617244f225c4705cb6bd72ac7f52181e5e3b2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9d3617244f225c4705cb6bd72ac7f52181e5e3b2",
"title": "9d3617244f225c4705cb6bd72ac7f52181e5e3b2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9d3617244f225c4705cb6bd72ac7f52181e5e3b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "575ae54ed7ea544d007a7e58c3f2afac8331056f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@575ae54ed7ea544d007a7e58c3f2afac8331056f",
"title": "575ae54ed7ea544d007a7e58c3f2afac8331056f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/575ae54ed7ea544d007a7e58c3f2afac8331056f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bd9f8ce942f7b8a75584c6a15628e09b48724a1b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bd9f8ce942f7b8a75584c6a15628e09b48724a1b",
"title": "bd9f8ce942f7b8a75584c6a15628e09b48724a1b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bd9f8ce942f7b8a75584c6a15628e09b48724a1b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "75eefb9d952ac95ba0fac77955288185ad75752a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@75eefb9d952ac95ba0fac77955288185ad75752a",
"title": "75eefb9d952ac95ba0fac77955288185ad75752a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/75eefb9d952ac95ba0fac77955288185ad75752a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "75eefb9d952ac95ba0fac77955288185ad75752a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888398525667551680"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-profile-core.x64-release-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:40:35.773] Scheduled build:
{
"id": "8722968463811690465",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-14T09:40:35.449190607Z",
"updateTime": "2025-02-14T09:40:35.449190607Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "75eefb9d952ac95ba0fac77955288185ad75752a",
"ref": "refs/heads/main"
}
}
}
[09:40:35.773] Task URL: https://cr-buildbucket.appspot.com/build/8722968463811690465
[09:40:35.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:2:0) after 4m36s
[09:40:36.784] Received PubSub notification, asking Buildbucket for the build status
[09:40:36.815] Build status: SCHEDULED
[09:40:43.344] Received PubSub notification, asking Buildbucket for the build status
[09:40:43.368] Build status: STARTED
[09:45:11.791] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:2:0)
[09:45:11.867] Build status: STARTED
[09:45:11.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:5:0) after 6m36s
[09:51:47.860] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:5:0)
[09:51:47.881] Build status: STARTED
[09:51:47.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:6:0) after 7m38s
[09:59:26.103] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:6:0)
[09:59:26.126] Build status: STARTED
[09:59:26.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:7:0) after 5m29s
[10:04:55.157] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:7:0)
[10:04:55.185] Build status: STARTED
[10:04:55.185] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:8:0) after 4m57s
[10:09:52.210] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:8:0)
[10:09:52.279] Build status: STARTED
[10:09:52.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:9:0) after 5m17s
[10:15:09.312] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:9:0)
[10:15:09.337] Build status: STARTED
[10:15:09.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:10:0) after 8m48s
[10:23:57.385] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:10:0)
[10:23:57.404] Build status: STARTED
[10:23:57.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:11:0) after 8m18s
[10:32:15.430] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:11:0)
[10:32:15.450] Build status: STARTED
[10:32:15.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:12:0) after 2m12s
[10:34:27.922] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:12:0)
[10:34:27.951] Build status: STARTED
[10:34:27.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:13:0) after 5m58s
[10:40:26.142] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:13:0)
[10:40:26.170] Build status: STARTED
[10:40:26.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:14:0) after 9m48s
[10:50:14.190] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:14:0)
[10:50:14.206] Build status: STARTED
[10:50:14.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:15:0) after 1m27s
[10:51:41.375] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:15:0)
[10:51:41.398] Build status: STARTED
[10:51:41.398] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:16:0) after 4m11s
[10:55:52.459] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:16:0)
[10:55:52.489] Build status: STARTED
[10:55:52.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:17:0) after 7m21s
[11:03:13.508] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:17:0)
[11:03:13.532] Build status: STARTED
[11:03:13.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:18:0) after 2m31s
[11:05:44.581] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:18:0)
[11:05:44.669] Build status: STARTED
[11:05:44.669] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:19:0) after 9m54s
[11:15:38.691] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:19:0)
[11:15:38.747] Build status: STARTED
[11:15:38.747] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:20:0) after 2m37s
[11:18:15.791] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:20:0)
[11:18:15.818] Build status: STARTED
[11:18:15.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:21:0) after 2m51s
[11:21:07.262] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:21:0)
[11:21:07.285] Build status: STARTED
[11:21:07.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:22:0) after 4m0s
[11:25:07.260] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:22:0)
[11:25:07.306] Build status: STARTED
[11:25:07.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:23:0) after 1m58s
[11:27:05.468] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:23:0)
[11:27:05.570] Build status: STARTED
[11:27:05.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:24:0) after 6m14s
[11:33:19.651] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:24:0)
[11:33:19.678] Build status: STARTED
[11:33:19.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:25:0) after 2m21s
[11:35:40.892] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:25:0)
[11:35:40.914] Build status: STARTED
[11:35:40.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:26:0) after 2m32s
[11:38:13.071] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:26:0)
[11:38:13.089] Build status: STARTED
[11:38:13.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:27:0) after 2m46s
[11:40:59.105] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:27:0)
[11:40:59.129] Build status: STARTED
[11:40:59.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:28:0) after 1m47s
[11:42:46.414] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:28:0)
[11:42:46.434] Build status: STARTED
[11:42:46.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:29:0) after 9m7s
[11:51:53.646] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:29:0)
[11:51:53.672] Build status: STARTED
[11:51:53.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:30:0) after 8m30s
[12:00:23.695] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:30:0)
[12:00:23.713] Build status: STARTED
[12:00:23.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:31:0) after 8m51s
[12:09:14.962] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:31:0)
[12:09:14.994] Build status: STARTED
[12:09:14.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:32:0) after 2m6s
[12:11:21.177] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:32:0)
[12:11:21.193] Build status: STARTED
[12:11:21.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:33:0) after 2m34s
[12:13:55.252] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:33:0)
[12:13:55.268] Build status: STARTED
[12:13:55.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888398525667551680:34:0) after 8m51s
[12:18:09.946] Received PubSub notification, asking Buildbucket for the build status
[12:18:09.969] Build:
{
"id": "8722968463811690465",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-14T09:40:35.449190607Z",
"startTime": "2025-02-14T09:40:43.182593772Z",
"endTime": "2025-02-14T12:18:08.941048508Z",
"updateTime": "2025-02-14T12:18:08.941048508Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "75eefb9d952ac95ba0fac77955288185ad75752a",
"ref": "refs/heads/main"
}
}
}
[12:18:09.969] Invocation finished in 2h37m35.673512928s with status SUCCEEDED