[23:38:25.548] New invocation is queued and will start shortly
[23:38:26.628] Starting the invocation (attempt 1)
[23:38:26.657] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/6429a3b2a1dab6165d93a72055ccf2a4d6113b89"
[23:38:26.657] Popped gitiles commit info from properties and tags
[23:38:26.657] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:38:26.657] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:38:26.658] Buildbucket request:
{
"requestId": "8917789826530727152",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8917789826530727152",
"job": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fe832cafd6296d5b7585c06c66e55dbb94ce40f1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fe832cafd6296d5b7585c06c66e55dbb94ce40f1",
"title": "fe832cafd6296d5b7585c06c66e55dbb94ce40f1",
"url": "https://fuchsia.googlesource.com/integration/+/fe832cafd6296d5b7585c06c66e55dbb94ce40f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3c1a7b0e17a7e02157abec057269e8b7ffc5a7a3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3c1a7b0e17a7e02157abec057269e8b7ffc5a7a3",
"title": "3c1a7b0e17a7e02157abec057269e8b7ffc5a7a3",
"url": "https://fuchsia.googlesource.com/integration/+/3c1a7b0e17a7e02157abec057269e8b7ffc5a7a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5a56e2d450d6626af653ee3175e4da34795f8b58"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5a56e2d450d6626af653ee3175e4da34795f8b58",
"title": "5a56e2d450d6626af653ee3175e4da34795f8b58",
"url": "https://fuchsia.googlesource.com/integration/+/5a56e2d450d6626af653ee3175e4da34795f8b58"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7f0074351167e4e05dd6821707ee9e65c0ee129"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7f0074351167e4e05dd6821707ee9e65c0ee129",
"title": "e7f0074351167e4e05dd6821707ee9e65c0ee129",
"url": "https://fuchsia.googlesource.com/integration/+/e7f0074351167e4e05dd6821707ee9e65c0ee129"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "15a9e89e909c1eb877c1ab401a428f08c1c0e353"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@15a9e89e909c1eb877c1ab401a428f08c1c0e353",
"title": "15a9e89e909c1eb877c1ab401a428f08c1c0e353",
"url": "https://fuchsia.googlesource.com/integration/+/15a9e89e909c1eb877c1ab401a428f08c1c0e353"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "405a9fa35fdad574bac3228fe13d1506e8d57501"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@405a9fa35fdad574bac3228fe13d1506e8d57501",
"title": "405a9fa35fdad574bac3228fe13d1506e8d57501",
"url": "https://fuchsia.googlesource.com/integration/+/405a9fa35fdad574bac3228fe13d1506e8d57501"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cb70111cce2ea8112ee551c484cb93f585dc1fc4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cb70111cce2ea8112ee551c484cb93f585dc1fc4",
"title": "cb70111cce2ea8112ee551c484cb93f585dc1fc4",
"url": "https://fuchsia.googlesource.com/integration/+/cb70111cce2ea8112ee551c484cb93f585dc1fc4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b54a98d83ded724755fa47ab43ae7371daa1f68d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b54a98d83ded724755fa47ab43ae7371daa1f68d",
"title": "b54a98d83ded724755fa47ab43ae7371daa1f68d",
"url": "https://fuchsia.googlesource.com/integration/+/b54a98d83ded724755fa47ab43ae7371daa1f68d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dba7f9543508ce07167f1df850e7ea70f7b048e2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dba7f9543508ce07167f1df850e7ea70f7b048e2",
"title": "dba7f9543508ce07167f1df850e7ea70f7b048e2",
"url": "https://fuchsia.googlesource.com/integration/+/dba7f9543508ce07167f1df850e7ea70f7b048e2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aec0d9b51854c44037f24a60814cedbd8571ada4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aec0d9b51854c44037f24a60814cedbd8571ada4",
"title": "aec0d9b51854c44037f24a60814cedbd8571ada4",
"url": "https://fuchsia.googlesource.com/integration/+/aec0d9b51854c44037f24a60814cedbd8571ada4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "58ea8ee6e7c322fe7b2325e1902fb5de36a1969f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@58ea8ee6e7c322fe7b2325e1902fb5de36a1969f",
"title": "58ea8ee6e7c322fe7b2325e1902fb5de36a1969f",
"url": "https://fuchsia.googlesource.com/integration/+/58ea8ee6e7c322fe7b2325e1902fb5de36a1969f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d79bce8d9b02f4c210b5d50dd181e7bc6e5d24b9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d79bce8d9b02f4c210b5d50dd181e7bc6e5d24b9",
"title": "d79bce8d9b02f4c210b5d50dd181e7bc6e5d24b9",
"url": "https://fuchsia.googlesource.com/integration/+/d79bce8d9b02f4c210b5d50dd181e7bc6e5d24b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "222a3f05bb5a21bc646c789171a51e3a179d8886"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@222a3f05bb5a21bc646c789171a51e3a179d8886",
"title": "222a3f05bb5a21bc646c789171a51e3a179d8886",
"url": "https://fuchsia.googlesource.com/integration/+/222a3f05bb5a21bc646c789171a51e3a179d8886"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c3d86fe7e711c3f110b3d8fd5b4823a7b0c626bc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c3d86fe7e711c3f110b3d8fd5b4823a7b0c626bc",
"title": "c3d86fe7e711c3f110b3d8fd5b4823a7b0c626bc",
"url": "https://fuchsia.googlesource.com/integration/+/c3d86fe7e711c3f110b3d8fd5b4823a7b0c626bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7d02f79af00ba6a21e504f6418b5076741ecb740"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7d02f79af00ba6a21e504f6418b5076741ecb740",
"title": "7d02f79af00ba6a21e504f6418b5076741ecb740",
"url": "https://fuchsia.googlesource.com/integration/+/7d02f79af00ba6a21e504f6418b5076741ecb740"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "897b7a6e96b11da7f92436af105a7ac98611fee5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@897b7a6e96b11da7f92436af105a7ac98611fee5",
"title": "897b7a6e96b11da7f92436af105a7ac98611fee5",
"url": "https://fuchsia.googlesource.com/integration/+/897b7a6e96b11da7f92436af105a7ac98611fee5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6a93abfca9b25e29457dfad9a746b8e9c2d3b0b6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6a93abfca9b25e29457dfad9a746b8e9c2d3b0b6",
"title": "6a93abfca9b25e29457dfad9a746b8e9c2d3b0b6",
"url": "https://fuchsia.googlesource.com/integration/+/6a93abfca9b25e29457dfad9a746b8e9c2d3b0b6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0d487cb7081c18b08add1b77c9923fbe0af5893a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0d487cb7081c18b08add1b77c9923fbe0af5893a",
"title": "0d487cb7081c18b08add1b77c9923fbe0af5893a",
"url": "https://fuchsia.googlesource.com/integration/+/0d487cb7081c18b08add1b77c9923fbe0af5893a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "297b6f7ed91e5ea0950700e02b24596c0a601091"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@297b6f7ed91e5ea0950700e02b24596c0a601091",
"title": "297b6f7ed91e5ea0950700e02b24596c0a601091",
"url": "https://fuchsia.googlesource.com/integration/+/297b6f7ed91e5ea0950700e02b24596c0a601091"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "846c3bc7af44b1d70521749150433139eb18d64a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@846c3bc7af44b1d70521749150433139eb18d64a",
"title": "846c3bc7af44b1d70521749150433139eb18d64a",
"url": "https://fuchsia.googlesource.com/integration/+/846c3bc7af44b1d70521749150433139eb18d64a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "10c32d1b8fd6377816100feae58f15442166375d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@10c32d1b8fd6377816100feae58f15442166375d",
"title": "10c32d1b8fd6377816100feae58f15442166375d",
"url": "https://fuchsia.googlesource.com/integration/+/10c32d1b8fd6377816100feae58f15442166375d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6429a3b2a1dab6165d93a72055ccf2a4d6113b89"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6429a3b2a1dab6165d93a72055ccf2a4d6113b89",
"title": "6429a3b2a1dab6165d93a72055ccf2a4d6113b89",
"url": "https://fuchsia.googlesource.com/integration/+/6429a3b2a1dab6165d93a72055ccf2a4d6113b89"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6429a3b2a1dab6165d93a72055ccf2a4d6113b89",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917789826530727152"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:38:27.274] Scheduled build:
{
"id": "8752359764617497441",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-26T23:38:26.744732865Z",
"updateTime": "2024-03-26T23:38:26.744732865Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6429a3b2a1dab6165d93a72055ccf2a4d6113b89",
"ref": "refs/heads/main"
}
}
}
[23:38:27.274] Task URL: https://cr-buildbucket.appspot.com/build/8752359764617497441
[23:38:27.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:2:0) after 2m59s
[23:38:27.451] Received PubSub notification, asking Buildbucket for the build status
[23:38:27.520] Build status: SCHEDULED
[23:38:40.448] Received PubSub notification, asking Buildbucket for the build status
[23:38:40.482] Build status: STARTED
[23:41:26.292] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:2:0)
[23:41:26.324] Build status: STARTED
[23:41:26.324] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:5:0) after 9m35s
[23:51:01.412] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:5:0)
[23:51:01.438] Build status: STARTED
[23:51:01.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:6:0) after 2m51s
[23:53:52.479] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:6:0)
[23:53:52.519] Build status: STARTED
[23:53:52.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:7:0) after 5m41s
[23:59:33.541] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:7:0)
[23:59:33.561] Build status: STARTED
[23:59:33.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:8:0) after 4m12s
[00:03:45.624] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:8:0)
[00:03:45.650] Build status: STARTED
[00:03:45.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:9:0) after 7m35s
[00:11:20.735] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:9:0)
[00:11:20.763] Build status: STARTED
[00:11:20.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:10:0) after 5m10s
[00:16:30.814] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:10:0)
[00:16:30.850] Build status: STARTED
[00:16:30.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:11:0) after 2m51s
[00:19:21.895] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:11:0)
[00:19:21.929] Build status: STARTED
[00:19:21.929] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:12:0) after 7m11s
[00:26:32.963] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:12:0)
[00:26:32.983] Build status: STARTED
[00:26:32.983] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:13:0) after 1m22s
[00:27:54.981] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:13:0)
[00:27:54.998] Build status: STARTED
[00:27:54.998] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:14:0) after 4m32s
[00:32:27.050] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:14:0)
[00:32:27.076] Build status: STARTED
[00:32:27.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:15:0) after 7m5s
[00:39:32.169] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:15:0)
[00:39:32.200] Build status: STARTED
[00:39:32.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:16:0) after 2m13s
[00:41:45.321] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:16:0)
[00:41:45.353] Build status: STARTED
[00:41:45.353] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:17:0) after 5m36s
[00:47:21.395] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:17:0)
[00:47:21.438] Build status: STARTED
[00:47:21.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:18:0) after 5m48s
[00:53:09.472] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:18:0)
[00:53:09.497] Build status: STARTED
[00:53:09.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:19:0) after 7m0s
[01:00:09.532] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:19:0)
[01:00:09.575] Build status: STARTED
[01:00:09.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:20:0) after 3m34s
[01:03:43.594] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:20:0)
[01:03:43.620] Build status: STARTED
[01:03:43.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:21:0) after 1m26s
[01:05:09.854] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:21:0)
[01:05:09.880] Build status: STARTED
[01:05:09.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:22:0) after 9m29s
[01:14:39.098] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:22:0)
[01:14:39.139] Build status: STARTED
[01:14:39.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8917789826530727152:23:0) after 7m59s
[01:18:23.210] Received PubSub notification, asking Buildbucket for the build status
[01:18:23.231] Build:
{
"id": "8752359764617497441",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-26T23:38:26.744732865Z",
"startTime": "2024-03-26T23:38:39.966498965Z",
"endTime": "2024-03-27T01:18:21.778926Z",
"updateTime": "2024-03-27T01:18:21.778926Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "6429a3b2a1dab6165d93a72055ccf2a4d6113b89",
"ref": "refs/heads/main"
}
}
}
[01:18:23.231] Invocation finished in 1h39m57.695758651s with status SUCCEEDED