[22:39:08.257] New invocation is queued and will start shortly
[22:39:09.471] Starting the invocation (attempt 1)
[22:39:09.497] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a3ea8e397706a9addc38c7946fdf12dcc2856d4e"
[22:39:09.497] Popped gitiles commit info from properties and tags
[22:39:09.498] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:39:09.498] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:39:09.498] Buildbucket request:
{
"requestId": "8929661759215097648",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8929661759215097648",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "54f49391bfe60b91803db5598f28210a62f28927"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@54f49391bfe60b91803db5598f28210a62f28927",
"title": "54f49391bfe60b91803db5598f28210a62f28927",
"url": "https://fuchsia.googlesource.com/integration/+/54f49391bfe60b91803db5598f28210a62f28927"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "87eebc618ee70e268dea6c0d80599f6a46c87dda"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@87eebc618ee70e268dea6c0d80599f6a46c87dda",
"title": "87eebc618ee70e268dea6c0d80599f6a46c87dda",
"url": "https://fuchsia.googlesource.com/integration/+/87eebc618ee70e268dea6c0d80599f6a46c87dda"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "606a5b28ae97c552b590060cc75f9d8e24f96be1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@606a5b28ae97c552b590060cc75f9d8e24f96be1",
"title": "606a5b28ae97c552b590060cc75f9d8e24f96be1",
"url": "https://fuchsia.googlesource.com/integration/+/606a5b28ae97c552b590060cc75f9d8e24f96be1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8bbfdfc2d13af5fcd7567ae4ff716005c0355078"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8bbfdfc2d13af5fcd7567ae4ff716005c0355078",
"title": "8bbfdfc2d13af5fcd7567ae4ff716005c0355078",
"url": "https://fuchsia.googlesource.com/integration/+/8bbfdfc2d13af5fcd7567ae4ff716005c0355078"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2021c688166105c590730a43709f0b1c7e6c7780"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2021c688166105c590730a43709f0b1c7e6c7780",
"title": "2021c688166105c590730a43709f0b1c7e6c7780",
"url": "https://fuchsia.googlesource.com/integration/+/2021c688166105c590730a43709f0b1c7e6c7780"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e01a0d4d9519638527feea754e31fd23b87cf2f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e01a0d4d9519638527feea754e31fd23b87cf2f",
"title": "9e01a0d4d9519638527feea754e31fd23b87cf2f",
"url": "https://fuchsia.googlesource.com/integration/+/9e01a0d4d9519638527feea754e31fd23b87cf2f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c7e4cdc3410c7bc1677221491677c5fc53ec8823"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c7e4cdc3410c7bc1677221491677c5fc53ec8823",
"title": "c7e4cdc3410c7bc1677221491677c5fc53ec8823",
"url": "https://fuchsia.googlesource.com/integration/+/c7e4cdc3410c7bc1677221491677c5fc53ec8823"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "395dbdcffc1abe3c901261868fee4fa6c0a8853d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@395dbdcffc1abe3c901261868fee4fa6c0a8853d",
"title": "395dbdcffc1abe3c901261868fee4fa6c0a8853d",
"url": "https://fuchsia.googlesource.com/integration/+/395dbdcffc1abe3c901261868fee4fa6c0a8853d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30e5277f89a284a8dec175b0307e6f59978c8837"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30e5277f89a284a8dec175b0307e6f59978c8837",
"title": "30e5277f89a284a8dec175b0307e6f59978c8837",
"url": "https://fuchsia.googlesource.com/integration/+/30e5277f89a284a8dec175b0307e6f59978c8837"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "497bf159ebbe25a4766ffcbdebc73a93521f3beb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@497bf159ebbe25a4766ffcbdebc73a93521f3beb",
"title": "497bf159ebbe25a4766ffcbdebc73a93521f3beb",
"url": "https://fuchsia.googlesource.com/integration/+/497bf159ebbe25a4766ffcbdebc73a93521f3beb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0bf96e8d82a481b3675906fa38b7b2630b3e98fe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0bf96e8d82a481b3675906fa38b7b2630b3e98fe",
"title": "0bf96e8d82a481b3675906fa38b7b2630b3e98fe",
"url": "https://fuchsia.googlesource.com/integration/+/0bf96e8d82a481b3675906fa38b7b2630b3e98fe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "35fdc9c9792103eabf1665447252e98bba4b2935"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@35fdc9c9792103eabf1665447252e98bba4b2935",
"title": "35fdc9c9792103eabf1665447252e98bba4b2935",
"url": "https://fuchsia.googlesource.com/integration/+/35fdc9c9792103eabf1665447252e98bba4b2935"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0c658132021ecfd0456ba0a9de2528e7410e7e71"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0c658132021ecfd0456ba0a9de2528e7410e7e71",
"title": "0c658132021ecfd0456ba0a9de2528e7410e7e71",
"url": "https://fuchsia.googlesource.com/integration/+/0c658132021ecfd0456ba0a9de2528e7410e7e71"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e341b202e4a90b7b0b3a8906bf2a3dd4e0d20639"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e341b202e4a90b7b0b3a8906bf2a3dd4e0d20639",
"title": "e341b202e4a90b7b0b3a8906bf2a3dd4e0d20639",
"url": "https://fuchsia.googlesource.com/integration/+/e341b202e4a90b7b0b3a8906bf2a3dd4e0d20639"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "26804b1129fd8ef0408f69ddc15040b059638c93"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@26804b1129fd8ef0408f69ddc15040b059638c93",
"title": "26804b1129fd8ef0408f69ddc15040b059638c93",
"url": "https://fuchsia.googlesource.com/integration/+/26804b1129fd8ef0408f69ddc15040b059638c93"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8f51bb4b7fe32c1c95e977e1de9beca71cb99958"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8f51bb4b7fe32c1c95e977e1de9beca71cb99958",
"title": "8f51bb4b7fe32c1c95e977e1de9beca71cb99958",
"url": "https://fuchsia.googlesource.com/integration/+/8f51bb4b7fe32c1c95e977e1de9beca71cb99958"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7f1dee10f4848ff6a5cf7a101358b4f533249969"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7f1dee10f4848ff6a5cf7a101358b4f533249969",
"title": "7f1dee10f4848ff6a5cf7a101358b4f533249969",
"url": "https://fuchsia.googlesource.com/integration/+/7f1dee10f4848ff6a5cf7a101358b4f533249969"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d81efb701a75180f03638cdb876ba24e8a6894bd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d81efb701a75180f03638cdb876ba24e8a6894bd",
"title": "d81efb701a75180f03638cdb876ba24e8a6894bd",
"url": "https://fuchsia.googlesource.com/integration/+/d81efb701a75180f03638cdb876ba24e8a6894bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a3ea8e397706a9addc38c7946fdf12dcc2856d4e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a3ea8e397706a9addc38c7946fdf12dcc2856d4e",
"title": "a3ea8e397706a9addc38c7946fdf12dcc2856d4e",
"url": "https://fuchsia.googlesource.com/integration/+/a3ea8e397706a9addc38c7946fdf12dcc2856d4e"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a3ea8e397706a9addc38c7946fdf12dcc2856d4e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8929661759215097648"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:39:10.008] Scheduled build:
{
"id": "8764231697188437681",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-16T22:39:09.565769051Z",
"updateTime": "2023-11-16T22:39:09.565769051Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a3ea8e397706a9addc38c7946fdf12dcc2856d4e",
"ref": "refs/heads/main"
}
}
}
[22:39:10.008] Task URL: https://cr-buildbucket.appspot.com/build/8764231697188437681
[22:39:10.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:2:0) after 4m26s
[22:39:10.246] Received PubSub notification, asking Buildbucket for the build status
[22:39:10.268] Build status: SCHEDULED
[22:39:11.643] Received PubSub notification, asking Buildbucket for the build status
[22:39:11.702] Build status: STARTED
[22:43:36.037] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:2:0)
[22:43:36.067] Build status: STARTED
[22:43:36.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:5:0) after 3m39s
[22:47:15.141] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:5:0)
[22:47:15.241] Build status: STARTED
[22:47:15.241] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:6:0) after 1m23s
[22:48:38.447] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:6:0)
[22:48:38.473] Build status: STARTED
[22:48:38.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:7:0) after 6m45s
[22:55:23.784] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:7:0)
[22:55:23.813] Build status: STARTED
[22:55:23.813] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:8:0) after 8m33s
[23:03:57.056] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:8:0)
[23:03:57.074] Build status: STARTED
[23:03:57.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:9:0) after 4m41s
[23:08:38.266] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:9:0)
[23:08:38.295] Build status: STARTED
[23:08:38.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:10:0) after 2m56s
[23:11:34.318] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:10:0)
[23:11:34.410] Build status: STARTED
[23:11:34.410] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:11:0) after 2m1s
[23:13:35.432] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:11:0)
[23:13:35.519] Build status: STARTED
[23:13:35.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:12:0) after 9m34s
[23:23:09.543] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:12:0)
[23:23:09.603] Build status: STARTED
[23:23:09.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:13:0) after 8m59s
[23:32:08.617] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:13:0)
[23:32:08.707] Build status: STARTED
[23:32:08.707] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:14:0) after 2m48s
[23:34:56.961] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:14:0)
[23:34:56.982] Build status: STARTED
[23:34:56.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:15:0) after 8m25s
[23:43:22.010] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:15:0)
[23:43:22.028] Build status: STARTED
[23:43:22.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:16:0) after 2m14s
[23:45:36.095] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:16:0)
[23:45:36.119] Build status: STARTED
[23:45:36.119] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8929661759215097648:17:0) after 9m37s
[23:52:45.481] Received PubSub notification, asking Buildbucket for the build status
[23:52:45.500] Build:
{
"id": "8764231697188437681",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-16T22:39:09.565769051Z",
"startTime": "2023-11-16T22:39:10.415705Z",
"endTime": "2023-11-16T23:52:44.803073Z",
"updateTime": "2023-11-16T23:52:44.803073Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a3ea8e397706a9addc38c7946fdf12dcc2856d4e",
"ref": "refs/heads/main"
}
}
}
[23:52:45.500] Invocation finished in 1h13m37.251540645s with status SUCCEEDED