[21:49:09.300] New invocation is queued and will start shortly
[21:49:10.850] Starting the invocation (attempt 1)
[21:49:10.880] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/4760f197c13e5516f071fda028123c71900837d4"
[21:49:10.880] Popped gitiles commit info from properties and tags
[21:49:10.880] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:49:10.880] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:49:10.882] Buildbucket request:
{
"requestId": "8936187885434316096",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8936187885434316096",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f9b00f4fcf2de21252cb64956ad7d65cb6a33f3c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f9b00f4fcf2de21252cb64956ad7d65cb6a33f3c",
"title": "f9b00f4fcf2de21252cb64956ad7d65cb6a33f3c",
"url": "https://fuchsia.googlesource.com/integration/+/f9b00f4fcf2de21252cb64956ad7d65cb6a33f3c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d4d208db122a61eb3596fc2f86c3f0e87c191e34"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d4d208db122a61eb3596fc2f86c3f0e87c191e34",
"title": "d4d208db122a61eb3596fc2f86c3f0e87c191e34",
"url": "https://fuchsia.googlesource.com/integration/+/d4d208db122a61eb3596fc2f86c3f0e87c191e34"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "06bb2c6d2f4297e63b4b46172bba75632b591bc1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@06bb2c6d2f4297e63b4b46172bba75632b591bc1",
"title": "06bb2c6d2f4297e63b4b46172bba75632b591bc1",
"url": "https://fuchsia.googlesource.com/integration/+/06bb2c6d2f4297e63b4b46172bba75632b591bc1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c30ec2835f32a380b37bc72fe3b24f7a051b7a6f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c30ec2835f32a380b37bc72fe3b24f7a051b7a6f",
"title": "c30ec2835f32a380b37bc72fe3b24f7a051b7a6f",
"url": "https://fuchsia.googlesource.com/integration/+/c30ec2835f32a380b37bc72fe3b24f7a051b7a6f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b17296051a0d4b7b94b4656086cea4ad620973d7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b17296051a0d4b7b94b4656086cea4ad620973d7",
"title": "b17296051a0d4b7b94b4656086cea4ad620973d7",
"url": "https://fuchsia.googlesource.com/integration/+/b17296051a0d4b7b94b4656086cea4ad620973d7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "efb7a1adc282139400b110408319e4e0beb62517"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@efb7a1adc282139400b110408319e4e0beb62517",
"title": "efb7a1adc282139400b110408319e4e0beb62517",
"url": "https://fuchsia.googlesource.com/integration/+/efb7a1adc282139400b110408319e4e0beb62517"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7a8a940e90d1a262e393332ee07508a9815208b6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7a8a940e90d1a262e393332ee07508a9815208b6",
"title": "7a8a940e90d1a262e393332ee07508a9815208b6",
"url": "https://fuchsia.googlesource.com/integration/+/7a8a940e90d1a262e393332ee07508a9815208b6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3e3d3151c489657ab464fcbb43cb579180216289"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3e3d3151c489657ab464fcbb43cb579180216289",
"title": "3e3d3151c489657ab464fcbb43cb579180216289",
"url": "https://fuchsia.googlesource.com/integration/+/3e3d3151c489657ab464fcbb43cb579180216289"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3d267b47468e953ac05f025da71df2de12c3c289"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3d267b47468e953ac05f025da71df2de12c3c289",
"title": "3d267b47468e953ac05f025da71df2de12c3c289",
"url": "https://fuchsia.googlesource.com/integration/+/3d267b47468e953ac05f025da71df2de12c3c289"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2bf91df11d194f62bdc370e3d4612560807f14bb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2bf91df11d194f62bdc370e3d4612560807f14bb",
"title": "2bf91df11d194f62bdc370e3d4612560807f14bb",
"url": "https://fuchsia.googlesource.com/integration/+/2bf91df11d194f62bdc370e3d4612560807f14bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e523d2056a3536c476b2bafa7b87bd89997629bb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e523d2056a3536c476b2bafa7b87bd89997629bb",
"title": "e523d2056a3536c476b2bafa7b87bd89997629bb",
"url": "https://fuchsia.googlesource.com/integration/+/e523d2056a3536c476b2bafa7b87bd89997629bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "529ab4b8e5a5d4c174cca24e3746881939841dee"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@529ab4b8e5a5d4c174cca24e3746881939841dee",
"title": "529ab4b8e5a5d4c174cca24e3746881939841dee",
"url": "https://fuchsia.googlesource.com/integration/+/529ab4b8e5a5d4c174cca24e3746881939841dee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "79827a338f5c5227c675a7484f8ca244528e7dae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@79827a338f5c5227c675a7484f8ca244528e7dae",
"title": "79827a338f5c5227c675a7484f8ca244528e7dae",
"url": "https://fuchsia.googlesource.com/integration/+/79827a338f5c5227c675a7484f8ca244528e7dae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3e914275ca03eea9259ce621ecee88b7f4e3f4d6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3e914275ca03eea9259ce621ecee88b7f4e3f4d6",
"title": "3e914275ca03eea9259ce621ecee88b7f4e3f4d6",
"url": "https://fuchsia.googlesource.com/integration/+/3e914275ca03eea9259ce621ecee88b7f4e3f4d6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "819465e5428944eb37fd754f9c6c95bbfc75ec9e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@819465e5428944eb37fd754f9c6c95bbfc75ec9e",
"title": "819465e5428944eb37fd754f9c6c95bbfc75ec9e",
"url": "https://fuchsia.googlesource.com/integration/+/819465e5428944eb37fd754f9c6c95bbfc75ec9e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4760f197c13e5516f071fda028123c71900837d4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4760f197c13e5516f071fda028123c71900837d4",
"title": "4760f197c13e5516f071fda028123c71900837d4",
"url": "https://fuchsia.googlesource.com/integration/+/4760f197c13e5516f071fda028123c71900837d4"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4760f197c13e5516f071fda028123c71900837d4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8936187885434316096"
},
{
"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"
}
}
[21:49:11.538] Scheduled build:
{
"id": "8770757823014111601",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-05T21:49:10.980262997Z",
"updateTime": "2023-09-05T21:49:10.980262997Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4760f197c13e5516f071fda028123c71900837d4",
"ref": "refs/heads/main"
}
}
}
[21:49:11.538] Task URL: https://cr-buildbucket.appspot.com/build/8770757823014111601
[21:49:11.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:2:0) after 9m37s
[21:49:12.414] Received PubSub notification, asking Buildbucket for the build status
[21:49:12.444] Build status: SCHEDULED
[21:51:51.875] Received PubSub notification, asking Buildbucket for the build status
[21:51:51.908] Build status: STARTED
[21:58:48.736] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:2:0)
[21:58:48.756] Build status: STARTED
[21:58:48.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:5:0) after 7m12s
[22:06:00.814] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:5:0)
[22:06:00.850] Build status: STARTED
[22:06:00.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:6:0) after 6m39s
[22:12:39.866] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:6:0)
[22:12:39.916] Build status: STARTED
[22:12:39.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:7:0) after 6m13s
[22:18:53.000] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:7:0)
[22:18:53.074] Build status: STARTED
[22:18:53.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:8:0) after 7m59s
[22:26:52.172] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:8:0)
[22:26:52.196] Build status: STARTED
[22:26:52.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:9:0) after 6m26s
[22:33:18.489] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:9:0)
[22:33:18.509] Build status: STARTED
[22:33:18.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:10:0) after 7m20s
[22:40:38.542] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:10:0)
[22:40:38.561] Build status: STARTED
[22:40:38.561] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:11:0) after 9m41s
[22:50:19.638] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:11:0)
[22:50:19.655] Build status: STARTED
[22:50:19.655] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:12:0) after 2m0s
[22:52:19.978] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:12:0)
[22:52:19.995] Build status: STARTED
[22:52:19.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:13:0) after 5m27s
[22:57:47.112] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:13:0)
[22:57:47.132] Build status: STARTED
[22:57:47.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:14:0) after 2m40s
[23:00:27.158] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:14:0)
[23:00:27.187] Build status: STARTED
[23:00:27.187] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:15:0) after 1m31s
[23:01:58.597] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:15:0)
[23:01:58.619] Build status: STARTED
[23:01:58.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:16:0) after 1m0s
[23:02:58.673] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:16:0)
[23:02:58.735] Build status: STARTED
[23:02:58.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936187885434316096:17:0) after 8m43s
[23:03:43.629] Received PubSub notification, asking Buildbucket for the build status
[23:03:43.649] Build:
{
"id": "8770757823014111601",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-05T21:49:10.980262997Z",
"startTime": "2023-09-05T21:51:51.007908Z",
"endTime": "2023-09-05T23:03:43.033675603Z",
"updateTime": "2023-09-05T23:03:43.033675603Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4760f197c13e5516f071fda028123c71900837d4",
"ref": "refs/heads/main"
}
}
}
[23:03:43.649] Invocation finished in 1h14m34.360630377s with status SUCCEEDED