[20:01:26.118] New invocation is queued and will start shortly
[20:01:27.185] Starting the invocation (attempt 1)
[20:01:27.211] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/50d9141449c462b1aba4b6fb5874d28187210158"
[20:01:27.211] Popped gitiles commit info from properties and tags
[20:01:27.211] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:01:27.211] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:01:27.212] Buildbucket request:
{
"requestId": "8911914675577601344",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8911914675577601344",
"job": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c7f4db3b0e2ac334d92f4673d1b91ee18a91e29e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c7f4db3b0e2ac334d92f4673d1b91ee18a91e29e",
"title": "c7f4db3b0e2ac334d92f4673d1b91ee18a91e29e",
"url": "https://fuchsia.googlesource.com/integration/+/c7f4db3b0e2ac334d92f4673d1b91ee18a91e29e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "04501f257886d00696a68e41cad3fd9c82ee08f4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@04501f257886d00696a68e41cad3fd9c82ee08f4",
"title": "04501f257886d00696a68e41cad3fd9c82ee08f4",
"url": "https://fuchsia.googlesource.com/integration/+/04501f257886d00696a68e41cad3fd9c82ee08f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b4f8a5486956a2ce03dcbf7be4a41ed5cd2994a6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b4f8a5486956a2ce03dcbf7be4a41ed5cd2994a6",
"title": "b4f8a5486956a2ce03dcbf7be4a41ed5cd2994a6",
"url": "https://fuchsia.googlesource.com/integration/+/b4f8a5486956a2ce03dcbf7be4a41ed5cd2994a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50fedf52bb711da16beb9a0a8c318cbb4e7bd525"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50fedf52bb711da16beb9a0a8c318cbb4e7bd525",
"title": "50fedf52bb711da16beb9a0a8c318cbb4e7bd525",
"url": "https://fuchsia.googlesource.com/integration/+/50fedf52bb711da16beb9a0a8c318cbb4e7bd525"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "55abcf50788e1a1dd53b937ec2a94f67dfaa995f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@55abcf50788e1a1dd53b937ec2a94f67dfaa995f",
"title": "55abcf50788e1a1dd53b937ec2a94f67dfaa995f",
"url": "https://fuchsia.googlesource.com/integration/+/55abcf50788e1a1dd53b937ec2a94f67dfaa995f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3665547833d76605e20adf42f6cdc743acf130a5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3665547833d76605e20adf42f6cdc743acf130a5",
"title": "3665547833d76605e20adf42f6cdc743acf130a5",
"url": "https://fuchsia.googlesource.com/integration/+/3665547833d76605e20adf42f6cdc743acf130a5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "76321bbf087f9277f82d58b3f3ea1af078ce349e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@76321bbf087f9277f82d58b3f3ea1af078ce349e",
"title": "76321bbf087f9277f82d58b3f3ea1af078ce349e",
"url": "https://fuchsia.googlesource.com/integration/+/76321bbf087f9277f82d58b3f3ea1af078ce349e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "823a1647e6cf90e318d229f1949dba6c970be3c1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@823a1647e6cf90e318d229f1949dba6c970be3c1",
"title": "823a1647e6cf90e318d229f1949dba6c970be3c1",
"url": "https://fuchsia.googlesource.com/integration/+/823a1647e6cf90e318d229f1949dba6c970be3c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2b4facfadfc777df455a286b99e8a88d2d53cbb9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2b4facfadfc777df455a286b99e8a88d2d53cbb9",
"title": "2b4facfadfc777df455a286b99e8a88d2d53cbb9",
"url": "https://fuchsia.googlesource.com/integration/+/2b4facfadfc777df455a286b99e8a88d2d53cbb9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "81bfaa576dc3b109ce021231f0c787c4a87e5549"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@81bfaa576dc3b109ce021231f0c787c4a87e5549",
"title": "81bfaa576dc3b109ce021231f0c787c4a87e5549",
"url": "https://fuchsia.googlesource.com/integration/+/81bfaa576dc3b109ce021231f0c787c4a87e5549"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9f83d8e27a3b34374151980edbc7927f70e9f96d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9f83d8e27a3b34374151980edbc7927f70e9f96d",
"title": "9f83d8e27a3b34374151980edbc7927f70e9f96d",
"url": "https://fuchsia.googlesource.com/integration/+/9f83d8e27a3b34374151980edbc7927f70e9f96d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "617e8ba7c497d3eff932e1798455f2c3313872ad"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@617e8ba7c497d3eff932e1798455f2c3313872ad",
"title": "617e8ba7c497d3eff932e1798455f2c3313872ad",
"url": "https://fuchsia.googlesource.com/integration/+/617e8ba7c497d3eff932e1798455f2c3313872ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "88f4b0ae9b6488b117a46154efe5438747a00c9c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@88f4b0ae9b6488b117a46154efe5438747a00c9c",
"title": "88f4b0ae9b6488b117a46154efe5438747a00c9c",
"url": "https://fuchsia.googlesource.com/integration/+/88f4b0ae9b6488b117a46154efe5438747a00c9c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7a5c595ebd0603edddf8be5dc1c1c48de60eec0e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7a5c595ebd0603edddf8be5dc1c1c48de60eec0e",
"title": "7a5c595ebd0603edddf8be5dc1c1c48de60eec0e",
"url": "https://fuchsia.googlesource.com/integration/+/7a5c595ebd0603edddf8be5dc1c1c48de60eec0e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cea8b0a882e70e3a97a1d088c9c9280f4a314f69"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cea8b0a882e70e3a97a1d088c9c9280f4a314f69",
"title": "cea8b0a882e70e3a97a1d088c9c9280f4a314f69",
"url": "https://fuchsia.googlesource.com/integration/+/cea8b0a882e70e3a97a1d088c9c9280f4a314f69"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f421c716aaf6c15b49a6319da27534eef5697ecb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f421c716aaf6c15b49a6319da27534eef5697ecb",
"title": "f421c716aaf6c15b49a6319da27534eef5697ecb",
"url": "https://fuchsia.googlesource.com/integration/+/f421c716aaf6c15b49a6319da27534eef5697ecb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "955ee6e9c37984e84b2a034d15843171da0f0524"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@955ee6e9c37984e84b2a034d15843171da0f0524",
"title": "955ee6e9c37984e84b2a034d15843171da0f0524",
"url": "https://fuchsia.googlesource.com/integration/+/955ee6e9c37984e84b2a034d15843171da0f0524"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "12ef696723370a7cc4d4bf52345e5a6ffce62d8b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@12ef696723370a7cc4d4bf52345e5a6ffce62d8b",
"title": "12ef696723370a7cc4d4bf52345e5a6ffce62d8b",
"url": "https://fuchsia.googlesource.com/integration/+/12ef696723370a7cc4d4bf52345e5a6ffce62d8b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50d9141449c462b1aba4b6fb5874d28187210158"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50d9141449c462b1aba4b6fb5874d28187210158",
"title": "50d9141449c462b1aba4b6fb5874d28187210158",
"url": "https://fuchsia.googlesource.com/integration/+/50d9141449c462b1aba4b6fb5874d28187210158"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "50d9141449c462b1aba4b6fb5874d28187210158",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8911914675577601344"
},
{
"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"
}
}
[20:01:27.787] Scheduled build:
{
"id": "8746484613714110705",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-30T20:01:27.266361330Z",
"updateTime": "2024-05-30T20:01:27.266361330Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "50d9141449c462b1aba4b6fb5874d28187210158",
"ref": "refs/heads/main"
}
}
}
[20:01:27.787] Task URL: https://cr-buildbucket.appspot.com/build/8746484613714110705
[20:01:27.787] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:2:0) after 7m7s
[20:01:28.586] Received PubSub notification, asking Buildbucket for the build status
[20:01:28.666] Build status: SCHEDULED
[20:03:27.325] Received PubSub notification, asking Buildbucket for the build status
[20:03:27.342] Build status: STARTED
[20:08:34.804] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:2:0)
[20:08:34.833] Build status: STARTED
[20:08:34.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:5:0) after 8m42s
[20:17:16.877] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:5:0)
[20:17:16.930] Build status: STARTED
[20:17:16.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:6:0) after 9m48s
[20:27:05.007] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:6:0)
[20:27:05.023] Build status: STARTED
[20:27:05.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:7:0) after 4m15s
[20:31:20.045] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:7:0)
[20:31:20.066] Build status: STARTED
[20:31:20.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:8:0) after 3m16s
[20:34:36.084] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:8:0)
[20:34:36.114] Build status: STARTED
[20:34:36.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:9:0) after 7m37s
[20:42:13.140] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:9:0)
[20:42:13.159] Build status: STARTED
[20:42:13.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:10:0) after 3m35s
[20:45:48.289] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:10:0)
[20:45:48.315] Build status: STARTED
[20:45:48.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:11:0) after 2m32s
[20:48:20.430] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:11:0)
[20:48:20.450] Build status: STARTED
[20:48:20.450] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:12:0) after 8m8s
[20:56:28.479] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:12:0)
[20:56:28.497] Build status: STARTED
[20:56:28.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:13:0) after 8m14s
[21:04:42.538] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:13:0)
[21:04:42.559] Build status: STARTED
[21:04:42.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:14:0) after 4m38s
[21:09:20.685] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:14:0)
[21:09:20.713] Build status: STARTED
[21:09:20.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:15:0) after 2m21s
[21:11:41.710] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:15:0)
[21:11:41.727] Build status: STARTED
[21:11:41.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:16:0) after 3m18s
[21:14:59.780] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:16:0)
[21:14:59.803] Build status: STARTED
[21:14:59.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:17:0) after 5m59s
[21:20:58.868] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:17:0)
[21:20:58.887] Build status: STARTED
[21:20:58.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:18:0) after 4m44s
[21:25:42.907] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:18:0)
[21:25:42.928] Build status: STARTED
[21:25:42.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:19:0) after 1m33s
[21:27:15.946] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:19:0)
[21:27:15.963] Build status: STARTED
[21:27:15.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:20:0) after 7m41s
[21:34:57.062] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:20:0)
[21:34:57.204] Build status: STARTED
[21:34:57.204] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:21:0) after 2m4s
[21:37:01.324] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:21:0)
[21:37:01.346] Build status: STARTED
[21:37:01.346] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:22:0) after 1m36s
[21:38:37.433] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:22:0)
[21:38:37.449] Build status: STARTED
[21:38:37.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:23:0) after 5m24s
[21:44:01.511] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:23:0)
[21:44:01.527] Build status: STARTED
[21:44:01.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:24:0) after 7m8s
[21:51:09.530] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:24:0)
[21:51:09.547] Build status: STARTED
[21:51:09.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:25:0) after 2m26s
[21:53:35.580] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:25:0)
[21:53:35.596] Build status: STARTED
[21:53:35.596] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8911914675577601344:26:0) after 5m28s
[21:56:22.992] Received PubSub notification, asking Buildbucket for the build status
[21:56:23.008] Build:
{
"id": "8746484613714110705",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-30T20:01:27.266361330Z",
"startTime": "2024-05-30T20:03:20.964012368Z",
"endTime": "2024-05-30T21:56:22.601905669Z",
"updateTime": "2024-05-30T21:56:22.601905669Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "50d9141449c462b1aba4b6fb5874d28187210158",
"ref": "refs/heads/main"
}
}
}
[21:56:23.008] Invocation finished in 1h54m56.903186787s with status SUCCEEDED