[22:27:50.707] New invocation is queued and will start shortly
[22:27:51.991] Starting the invocation (attempt 1)
[22:27:52.016] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a9801d003f273576bc359ed183019841e58d5147"
[22:27:52.016] Popped gitiles commit info from properties and tags
[22:27:52.016] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:27:52.016] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:27:52.017] Buildbucket request:
{
"requestId": "8876482050406342832",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876482050406342832",
"job": "fuchsia/global.ci-kythe-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ec7076861d028df0defe0084af9e6d0deff9c0de"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ec7076861d028df0defe0084af9e6d0deff9c0de",
"title": "ec7076861d028df0defe0084af9e6d0deff9c0de",
"url": "https://fuchsia.googlesource.com/integration/+/ec7076861d028df0defe0084af9e6d0deff9c0de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "253d0e5dc8f80c195fb73b3c0764e095960fd41d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@253d0e5dc8f80c195fb73b3c0764e095960fd41d",
"title": "253d0e5dc8f80c195fb73b3c0764e095960fd41d",
"url": "https://fuchsia.googlesource.com/integration/+/253d0e5dc8f80c195fb73b3c0764e095960fd41d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "06049e08a02fbc20ca0eecebe902501ecd441db8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@06049e08a02fbc20ca0eecebe902501ecd441db8",
"title": "06049e08a02fbc20ca0eecebe902501ecd441db8",
"url": "https://fuchsia.googlesource.com/integration/+/06049e08a02fbc20ca0eecebe902501ecd441db8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d52065e851e255412315edcd77d562fa1927d1bd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d52065e851e255412315edcd77d562fa1927d1bd",
"title": "d52065e851e255412315edcd77d562fa1927d1bd",
"url": "https://fuchsia.googlesource.com/integration/+/d52065e851e255412315edcd77d562fa1927d1bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30c2601fbcc5006b34c849b36ef3365afa16d340"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30c2601fbcc5006b34c849b36ef3365afa16d340",
"title": "30c2601fbcc5006b34c849b36ef3365afa16d340",
"url": "https://fuchsia.googlesource.com/integration/+/30c2601fbcc5006b34c849b36ef3365afa16d340"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "55953f80084f71a2a28c5176d0e5de493bba89db"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@55953f80084f71a2a28c5176d0e5de493bba89db",
"title": "55953f80084f71a2a28c5176d0e5de493bba89db",
"url": "https://fuchsia.googlesource.com/integration/+/55953f80084f71a2a28c5176d0e5de493bba89db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0447c378f56b327724507033e5a80cfd06f39ea3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0447c378f56b327724507033e5a80cfd06f39ea3",
"title": "0447c378f56b327724507033e5a80cfd06f39ea3",
"url": "https://fuchsia.googlesource.com/integration/+/0447c378f56b327724507033e5a80cfd06f39ea3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d13f8ccb9b79bb3bf5c3d740f62417c85ba6b819"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d13f8ccb9b79bb3bf5c3d740f62417c85ba6b819",
"title": "d13f8ccb9b79bb3bf5c3d740f62417c85ba6b819",
"url": "https://fuchsia.googlesource.com/integration/+/d13f8ccb9b79bb3bf5c3d740f62417c85ba6b819"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b776cee63500cfeb7410c20e98d7936fee5e5991"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b776cee63500cfeb7410c20e98d7936fee5e5991",
"title": "b776cee63500cfeb7410c20e98d7936fee5e5991",
"url": "https://fuchsia.googlesource.com/integration/+/b776cee63500cfeb7410c20e98d7936fee5e5991"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d7659f662524d96a62c0e242f4115aca774265b2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d7659f662524d96a62c0e242f4115aca774265b2",
"title": "d7659f662524d96a62c0e242f4115aca774265b2",
"url": "https://fuchsia.googlesource.com/integration/+/d7659f662524d96a62c0e242f4115aca774265b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a397b4a14aac3df47ba162c8a79bda4692f9a809"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a397b4a14aac3df47ba162c8a79bda4692f9a809",
"title": "a397b4a14aac3df47ba162c8a79bda4692f9a809",
"url": "https://fuchsia.googlesource.com/integration/+/a397b4a14aac3df47ba162c8a79bda4692f9a809"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f1ba05ba0aa9c21e83ae4a1bae2e263a4e5f710e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f1ba05ba0aa9c21e83ae4a1bae2e263a4e5f710e",
"title": "f1ba05ba0aa9c21e83ae4a1bae2e263a4e5f710e",
"url": "https://fuchsia.googlesource.com/integration/+/f1ba05ba0aa9c21e83ae4a1bae2e263a4e5f710e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a9801d003f273576bc359ed183019841e58d5147"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a9801d003f273576bc359ed183019841e58d5147",
"title": "a9801d003f273576bc359ed183019841e58d5147",
"url": "https://fuchsia.googlesource.com/integration/+/a9801d003f273576bc359ed183019841e58d5147"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a9801d003f273576bc359ed183019841e58d5147",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876482050406342832"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-kythe-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:27:52.370] Scheduled build:
{
"id": "8711051988308168961",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2025-06-25T22:27:52.078700690Z",
"updateTime": "2025-06-25T22:27:52.078700690Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a9801d003f273576bc359ed183019841e58d5147",
"ref": "refs/heads/main"
}
}
}
[22:27:52.370] Task URL: https://cr-buildbucket.appspot.com/build/8711051988308168961
[22:27:52.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:2:0) after 4m56s
[22:27:53.419] Received PubSub notification, asking Buildbucket for the build status
[22:27:53.556] Build status: SCHEDULED
[22:27:55.540] Received PubSub notification, asking Buildbucket for the build status
[22:27:55.557] Build status: STARTED
[22:32:48.462] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:2:0)
[22:32:48.489] Build status: STARTED
[22:32:48.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:5:0) after 1m21s
[22:34:09.560] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:5:0)
[22:34:09.594] Build status: STARTED
[22:34:09.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:6:0) after 3m39s
[22:37:48.639] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:6:0)
[22:37:48.664] Build status: STARTED
[22:37:48.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:7:0) after 9m7s
[22:46:55.683] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:7:0)
[22:46:55.706] Build status: STARTED
[22:46:55.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:8:0) after 2m23s
[22:49:18.747] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:8:0)
[22:49:18.793] Build status: STARTED
[22:49:18.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:9:0) after 1m2s
[22:50:20.959] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:9:0)
[22:50:20.974] Build status: STARTED
[22:50:20.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:10:0) after 2m26s
[22:52:47.042] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:10:0)
[22:52:47.066] Build status: STARTED
[22:52:47.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:11:0) after 6m26s
[22:59:13.083] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:11:0)
[22:59:13.108] Build status: STARTED
[22:59:13.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:12:0) after 5m50s
[23:05:03.086] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:12:0)
[23:05:03.129] Build status: STARTED
[23:05:03.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:13:0) after 7m44s
[23:12:47.154] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:13:0)
[23:12:47.169] Build status: STARTED
[23:12:47.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:14:0) after 5m50s
[23:18:37.191] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:14:0)
[23:18:37.222] Build status: STARTED
[23:18:37.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:15:0) after 9m29s
[23:28:06.243] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:15:0)
[23:28:06.265] Build status: STARTED
[23:28:06.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:16:0) after 1m0s
[23:29:06.286] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:16:0)
[23:29:06.307] Build status: STARTED
[23:29:06.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:17:0) after 7m28s
[23:36:34.361] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:17:0)
[23:36:34.381] Build status: STARTED
[23:36:34.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:18:0) after 8m7s
[23:44:41.418] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:18:0)
[23:44:41.436] Build status: STARTED
[23:44:41.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:19:0) after 3m54s
[23:48:35.411] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:19:0)
[23:48:35.430] Build status: STARTED
[23:48:35.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:20:0) after 3m30s
[23:52:05.447] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:20:0)
[23:52:05.472] Build status: STARTED
[23:52:05.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:21:0) after 3m22s
[23:55:27.604] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:21:0)
[23:55:27.628] Build status: STARTED
[23:55:27.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:22:0) after 7m0s
[00:02:27.707] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:22:0)
[00:02:27.727] Build status: STARTED
[00:02:27.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-kythe-e5a0ec7f:8876482050406342832:23:0) after 8m36s
[00:06:08.736] Received PubSub notification, asking Buildbucket for the build status
[00:06:08.756] Build:
{
"id": "8711051988308168961",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2025-06-25T22:27:52.078700690Z",
"startTime": "2025-06-25T22:27:55.199174164Z",
"endTime": "2025-06-26T00:06:08.367853256Z",
"updateTime": "2025-06-26T00:06:08.367853256Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a9801d003f273576bc359ed183019841e58d5147",
"ref": "refs/heads/main"
}
}
}
[00:06:08.756] Invocation finished in 1h38m18.062145228s with status SUCCEEDED