[21:15:48.455] New invocation is queued and will start shortly
[21:15:49.636] Starting the invocation (attempt 1)
[21:15:49.663] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/4356863bf1e636968b2037ea82977dc4e7740cc2"
[21:15:49.663] Popped gitiles commit info from properties and tags
[21:15:49.663] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:15:49.665] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:15:49.665] Buildbucket request:
{
"requestId": "8885274488356718480",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8885274488356718480",
"job": "fuchsia/core.x64-release-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "232c9a5cbac0e46614ed209a9c826377585b8905"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@232c9a5cbac0e46614ed209a9c826377585b8905",
"title": "232c9a5cbac0e46614ed209a9c826377585b8905",
"url": "https://fuchsia.googlesource.com/integration/+/232c9a5cbac0e46614ed209a9c826377585b8905"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "439bb2a9954b9114f3e03f36581f032e3a0bccef"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@439bb2a9954b9114f3e03f36581f032e3a0bccef",
"title": "439bb2a9954b9114f3e03f36581f032e3a0bccef",
"url": "https://fuchsia.googlesource.com/integration/+/439bb2a9954b9114f3e03f36581f032e3a0bccef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d38b9764a2a61e8fcb49013f0206cb755b3159fa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d38b9764a2a61e8fcb49013f0206cb755b3159fa",
"title": "d38b9764a2a61e8fcb49013f0206cb755b3159fa",
"url": "https://fuchsia.googlesource.com/integration/+/d38b9764a2a61e8fcb49013f0206cb755b3159fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "20fb2915264a3cab8410e0a8ebec8cac080d6f62"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@20fb2915264a3cab8410e0a8ebec8cac080d6f62",
"title": "20fb2915264a3cab8410e0a8ebec8cac080d6f62",
"url": "https://fuchsia.googlesource.com/integration/+/20fb2915264a3cab8410e0a8ebec8cac080d6f62"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f8e1199fe5694bebc71e5ff5b69d61bc71349f02"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f8e1199fe5694bebc71e5ff5b69d61bc71349f02",
"title": "f8e1199fe5694bebc71e5ff5b69d61bc71349f02",
"url": "https://fuchsia.googlesource.com/integration/+/f8e1199fe5694bebc71e5ff5b69d61bc71349f02"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2d5c3f563a9a7c37f852b4416fd859d705ae6254"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2d5c3f563a9a7c37f852b4416fd859d705ae6254",
"title": "2d5c3f563a9a7c37f852b4416fd859d705ae6254",
"url": "https://fuchsia.googlesource.com/integration/+/2d5c3f563a9a7c37f852b4416fd859d705ae6254"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "20829ac18fcf87a868f9503b7f5346c9f602f14f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@20829ac18fcf87a868f9503b7f5346c9f602f14f",
"title": "20829ac18fcf87a868f9503b7f5346c9f602f14f",
"url": "https://fuchsia.googlesource.com/integration/+/20829ac18fcf87a868f9503b7f5346c9f602f14f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0e702ce8517336d8df7f10717c6e0d9a7bdd23f5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0e702ce8517336d8df7f10717c6e0d9a7bdd23f5",
"title": "0e702ce8517336d8df7f10717c6e0d9a7bdd23f5",
"url": "https://fuchsia.googlesource.com/integration/+/0e702ce8517336d8df7f10717c6e0d9a7bdd23f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8b61da3d7a4a5c17e93ba876335b3e280a960845"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8b61da3d7a4a5c17e93ba876335b3e280a960845",
"title": "8b61da3d7a4a5c17e93ba876335b3e280a960845",
"url": "https://fuchsia.googlesource.com/integration/+/8b61da3d7a4a5c17e93ba876335b3e280a960845"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5306027f8034eaeb395631b8ce5fadb82003f307"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5306027f8034eaeb395631b8ce5fadb82003f307",
"title": "5306027f8034eaeb395631b8ce5fadb82003f307",
"url": "https://fuchsia.googlesource.com/integration/+/5306027f8034eaeb395631b8ce5fadb82003f307"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3d93f88d135a37f0ad6fcedf4d809d6677c8168c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3d93f88d135a37f0ad6fcedf4d809d6677c8168c",
"title": "3d93f88d135a37f0ad6fcedf4d809d6677c8168c",
"url": "https://fuchsia.googlesource.com/integration/+/3d93f88d135a37f0ad6fcedf4d809d6677c8168c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6db62b0c8c4e30b916f0158ad6388429e0b3b163"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6db62b0c8c4e30b916f0158ad6388429e0b3b163",
"title": "6db62b0c8c4e30b916f0158ad6388429e0b3b163",
"url": "https://fuchsia.googlesource.com/integration/+/6db62b0c8c4e30b916f0158ad6388429e0b3b163"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4356863bf1e636968b2037ea82977dc4e7740cc2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4356863bf1e636968b2037ea82977dc4e7740cc2",
"title": "4356863bf1e636968b2037ea82977dc4e7740cc2",
"url": "https://fuchsia.googlesource.com/integration/+/4356863bf1e636968b2037ea82977dc4e7740cc2"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4356863bf1e636968b2037ea82977dc4e7740cc2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8885274488356718480"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:15:50.176] Scheduled build:
{
"id": "8719844426379611809",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-20T21:15:49.711866146Z",
"updateTime": "2025-03-20T21:15:49.711866146Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4356863bf1e636968b2037ea82977dc4e7740cc2",
"ref": "refs/heads/main"
}
}
}
[21:15:50.176] Task URL: https://cr-buildbucket.appspot.com/build/8719844426379611809
[21:15:50.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:2:0) after 4m50s
[21:15:51.171] Received PubSub notification, asking Buildbucket for the build status
[21:15:51.201] Build status: SCHEDULED
[21:15:55.331] Received PubSub notification, asking Buildbucket for the build status
[21:15:55.348] Build status: STARTED
[21:20:40.245] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:2:0)
[21:20:40.260] Build status: STARTED
[21:20:40.260] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:5:0) after 1m24s
[21:22:04.334] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:5:0)
[21:22:04.367] Build status: STARTED
[21:22:04.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:6:0) after 3m10s
[21:25:14.389] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:6:0)
[21:25:14.411] Build status: STARTED
[21:25:14.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:7:0) after 4m47s
[21:30:01.534] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:7:0)
[21:30:01.578] Build status: STARTED
[21:30:01.578] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:8:0) after 9m2s
[21:39:03.633] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:8:0)
[21:39:03.716] Build status: STARTED
[21:39:03.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:9:0) after 9m31s
[21:48:34.727] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:9:0)
[21:48:34.786] Build status: STARTED
[21:48:34.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:10:0) after 5m6s
[21:53:40.826] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:10:0)
[21:53:40.853] Build status: STARTED
[21:53:40.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:11:0) after 6m53s
[22:00:33.881] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:11:0)
[22:00:33.909] Build status: STARTED
[22:00:33.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:12:0) after 2m38s
[22:03:11.925] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:12:0)
[22:03:12.012] Build status: STARTED
[22:03:12.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:13:0) after 2m24s
[22:05:36.033] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:13:0)
[22:05:36.062] Build status: STARTED
[22:05:36.062] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-e5a0ec7f:8885274488356718480:14:0) after 5m10s
[22:09:23.178] Received PubSub notification, asking Buildbucket for the build status
[22:09:23.199] Build:
{
"id": "8719844426379611809",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-03-20T21:15:49.711866146Z",
"startTime": "2025-03-20T21:15:55.113205650Z",
"endTime": "2025-03-20T22:09:22.662123404Z",
"updateTime": "2025-03-20T22:09:22.662123404Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4356863bf1e636968b2037ea82977dc4e7740cc2",
"ref": "refs/heads/main"
}
}
}
[22:09:23.199] Invocation finished in 53m34.756708696s with status SUCCEEDED