[22:32:08.210] New invocation is queued and will start shortly
[22:32:09.318] Starting the invocation (attempt 1)
[22:32:09.347] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/48386ac92cf16978f5a878af24f795258ba9ddb2"
[22:32:09.347] Popped gitiles commit info from properties and tags
[22:32:09.347] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:32:09.347] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:32:09.348] Buildbucket request:
{
"requestId": "8896956694793213456",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896956694793213456",
"job": "fuchsia/core.x64-release-profile-kernel-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a104ec3a179cd3abe676c9a7705a09a10ad181e8"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a104ec3a179cd3abe676c9a7705a09a10ad181e8",
"title": "a104ec3a179cd3abe676c9a7705a09a10ad181e8",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a104ec3a179cd3abe676c9a7705a09a10ad181e8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "cc48c272a335b9f7ca776df4f5d616e26991b9f1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@cc48c272a335b9f7ca776df4f5d616e26991b9f1",
"title": "cc48c272a335b9f7ca776df4f5d616e26991b9f1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/cc48c272a335b9f7ca776df4f5d616e26991b9f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9f4c4f7a6bc3f3a5ee9a066e749cc11d38a55c19"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9f4c4f7a6bc3f3a5ee9a066e749cc11d38a55c19",
"title": "9f4c4f7a6bc3f3a5ee9a066e749cc11d38a55c19",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9f4c4f7a6bc3f3a5ee9a066e749cc11d38a55c19"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a40da5b0fc11328449feb15521121632c6efdda6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a40da5b0fc11328449feb15521121632c6efdda6",
"title": "a40da5b0fc11328449feb15521121632c6efdda6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a40da5b0fc11328449feb15521121632c6efdda6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5f3d2ebbccc78167e2ab874c29d5ef5eba735415"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5f3d2ebbccc78167e2ab874c29d5ef5eba735415",
"title": "5f3d2ebbccc78167e2ab874c29d5ef5eba735415",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5f3d2ebbccc78167e2ab874c29d5ef5eba735415"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9d562ba4d5ebebe4b440bc0e0aef1f5e75ecd533"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9d562ba4d5ebebe4b440bc0e0aef1f5e75ecd533",
"title": "9d562ba4d5ebebe4b440bc0e0aef1f5e75ecd533",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9d562ba4d5ebebe4b440bc0e0aef1f5e75ecd533"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fb8a2073578af5e384fcf82f511a09f0c4e7bbf4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fb8a2073578af5e384fcf82f511a09f0c4e7bbf4",
"title": "fb8a2073578af5e384fcf82f511a09f0c4e7bbf4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fb8a2073578af5e384fcf82f511a09f0c4e7bbf4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "993ca21f4ccc3799cda64adf421c8a7ea87139bd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@993ca21f4ccc3799cda64adf421c8a7ea87139bd",
"title": "993ca21f4ccc3799cda64adf421c8a7ea87139bd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/993ca21f4ccc3799cda64adf421c8a7ea87139bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3086cc9b1de729a186450445fe77eba4f28c8c97"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3086cc9b1de729a186450445fe77eba4f28c8c97",
"title": "3086cc9b1de729a186450445fe77eba4f28c8c97",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3086cc9b1de729a186450445fe77eba4f28c8c97"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "07b6e22a36a9a19f8b2bedbdca1668dc57a8a56f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@07b6e22a36a9a19f8b2bedbdca1668dc57a8a56f",
"title": "07b6e22a36a9a19f8b2bedbdca1668dc57a8a56f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/07b6e22a36a9a19f8b2bedbdca1668dc57a8a56f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e7431166f7a5db61185fbf5cdabed38ea4c25939"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e7431166f7a5db61185fbf5cdabed38ea4c25939",
"title": "e7431166f7a5db61185fbf5cdabed38ea4c25939",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e7431166f7a5db61185fbf5cdabed38ea4c25939"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2aaab06caae2e92a300b847096ba59229adc0745"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2aaab06caae2e92a300b847096ba59229adc0745",
"title": "2aaab06caae2e92a300b847096ba59229adc0745",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2aaab06caae2e92a300b847096ba59229adc0745"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "de3a73e20627f945f0c33875aa9b3b95fd976f5f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@de3a73e20627f945f0c33875aa9b3b95fd976f5f",
"title": "de3a73e20627f945f0c33875aa9b3b95fd976f5f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/de3a73e20627f945f0c33875aa9b3b95fd976f5f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6b255e596e867e752afd876070142a4463d6ebf7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6b255e596e867e752afd876070142a4463d6ebf7",
"title": "6b255e596e867e752afd876070142a4463d6ebf7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6b255e596e867e752afd876070142a4463d6ebf7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "48386ac92cf16978f5a878af24f795258ba9ddb2"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@48386ac92cf16978f5a878af24f795258ba9ddb2",
"title": "48386ac92cf16978f5a878af24f795258ba9ddb2",
"url": "https://fuchsia.googlesource.com/fuchsia/+/48386ac92cf16978f5a878af24f795258ba9ddb2"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "48386ac92cf16978f5a878af24f795258ba9ddb2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896956694793213456"
},
{
"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:32:09.753] Scheduled build:
{
"id": "8731526632872734001",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-11T22:32:09.420062955Z",
"updateTime": "2024-11-11T22:32:09.420062955Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "48386ac92cf16978f5a878af24f795258ba9ddb2",
"ref": "refs/heads/main"
}
}
}
[22:32:09.753] Task URL: https://cr-buildbucket.appspot.com/build/8731526632872734001
[22:32:09.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:2:0) after 9m36s
[22:32:10.756] Received PubSub notification, asking Buildbucket for the build status
[22:32:10.775] Build status: SCHEDULED
[22:32:23.082] Received PubSub notification, asking Buildbucket for the build status
[22:32:23.106] Build status: STARTED
[22:41:45.821] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:2:0)
[22:41:45.845] Build status: STARTED
[22:41:45.845] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:5:0) after 7m19s
[22:49:04.898] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:5:0)
[22:49:04.935] Build status: STARTED
[22:49:04.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:6:0) after 2m12s
[22:51:16.951] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:6:0)
[22:51:16.976] Build status: STARTED
[22:51:16.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:7:0) after 8m19s
[22:59:35.995] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:7:0)
[22:59:36.013] Build status: STARTED
[22:59:36.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:8:0) after 7m27s
[23:07:03.069] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:8:0)
[23:07:03.147] Build status: STARTED
[23:07:03.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:9:0) after 8m7s
[23:15:10.203] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:9:0)
[23:15:10.228] Build status: STARTED
[23:15:10.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:10:0) after 8m20s
[23:23:30.244] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:10:0)
[23:23:30.272] Build status: STARTED
[23:23:30.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:11:0) after 7m22s
[23:30:52.295] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:11:0)
[23:30:52.338] Build status: STARTED
[23:30:52.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:12:0) after 8m15s
[23:39:07.352] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:12:0)
[23:39:07.442] Build status: STARTED
[23:39:07.442] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:13:0) after 1m52s
[23:40:59.484] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:13:0)
[23:40:59.501] Build status: STARTED
[23:40:59.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:14:0) after 8m29s
[23:49:28.516] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:14:0)
[23:49:28.532] Build status: STARTED
[23:49:28.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:15:0) after 3m16s
[23:52:44.589] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:15:0)
[23:52:44.603] Build status: STARTED
[23:52:44.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:16:0) after 7m57s
[00:00:41.624] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:16:0)
[00:00:41.660] Build status: STARTED
[00:00:41.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-profile-kernel-af244ac6:8896956694793213456:17:0) after 9m48s
[00:02:39.439] Received PubSub notification, asking Buildbucket for the build status
[00:02:39.585] Build:
{
"id": "8731526632872734001",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.x64-release-profile-kernel"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-11T22:32:09.420062955Z",
"startTime": "2024-11-11T22:32:22.832328129Z",
"endTime": "2024-11-12T00:02:39.125060584Z",
"updateTime": "2024-11-12T00:02:39.125060584Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "48386ac92cf16978f5a878af24f795258ba9ddb2",
"ref": "refs/heads/main"
}
}
}
[00:02:39.585] Invocation finished in 1h30m31.380606634s with status SUCCEEDED