[04:21:05.291] New invocation is queued and will start shortly
[04:21:05.291] Triggered by user:tools-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[04:21:06.476] Starting the invocation (attempt 1)
[04:21:06.500] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad"
[04:21:06.500] Reconstructed gitiles commit from tags
[04:21:06.500] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:21:06.500] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:21:06.501] Buildbucket request:
{
"requestId": "8916684879347343040",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "ca9272de2b74c6e2bf4945b5d28a012a56be8646",
"project": "integration"
},
"rust_toolchain": {
"cas_digest": "68006ca31311087017fe2cbf9d8bf9110720a9932ebd211039fc6be57ac886a9/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8916684879347343040",
"job": "fuchsia/rust_toolchain.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "ca9272de2b74c6e2bf4945b5d28a012a56be8646",
"project": "integration"
},
"rust_toolchain": {
"cas_digest": "0205c2381527cfaa86b057565bd9962318985394e837746ce04672f37c14037a/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux",
"user_agent:recipe"
]
},
"id": "bad8abc8-8c24-4b19-8ce7-20dc75747f05",
"title": "rust-x64-linux/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "ca9272de2b74c6e2bf4945b5d28a012a56be8646",
"project": "integration"
},
"rust_toolchain": {
"cas_digest": "68006ca31311087017fe2cbf9d8bf9110720a9932ebd211039fc6be57ac886a9/394",
"cas_instance": "projects/chromium-swarm/instances/default_instance"
}
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad"
}
},
"tags": [
"buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad",
"gitiles_ref:refs/heads/main",
"parent_buildername:rust-x64-linux-bootstrap",
"user_agent:recipe"
]
},
"id": "b9fc79a0-fd9e-4529-bc81-22a9f02704ca",
"title": "rust-x64-linux-bootstrap/0"
}
]
},
"rust_toolchain": {
"git_repository": "https://fuchsia.googlesource.com/third_party/rust",
"git_revision": "a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad"
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916684879347343040"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust_toolchain.core.x64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "rust-x64-linux-bootstrap"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:21:06.975] Scheduled build:
{
"id": "8751254817368817969",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-08T04:21:06.556886134Z",
"updateTime": "2024-04-08T04:21:06.556886134Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad",
"ref": "refs/heads/main"
}
}
}
[04:21:06.975] Task URL: https://cr-buildbucket.appspot.com/build/8751254817368817969
[04:21:06.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:2:0) after 8m13s
[04:21:07.895] Received PubSub notification, asking Buildbucket for the build status
[04:21:07.950] Build status: SCHEDULED
[04:21:12.449] Received PubSub notification, asking Buildbucket for the build status
[04:21:12.557] Build status: STARTED
[04:29:20.469] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:2:0)
[04:29:20.500] Build status: STARTED
[04:29:20.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:5:0) after 5m25s
[04:34:45.563] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:5:0)
[04:34:45.600] Build status: STARTED
[04:34:45.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:6:0) after 3m42s
[04:38:27.620] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:6:0)
[04:38:27.641] Build status: STARTED
[04:38:27.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:7:0) after 3m20s
[04:41:47.886] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:7:0)
[04:41:47.915] Build status: STARTED
[04:41:47.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:8:0) after 5m38s
[04:47:25.934] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:8:0)
[04:47:25.974] Build status: STARTED
[04:47:25.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:9:0) after 4m42s
[04:52:07.991] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:9:0)
[04:52:08.048] Build status: STARTED
[04:52:08.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:10:0) after 1m2s
[04:53:10.065] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:10:0)
[04:53:10.087] Build status: STARTED
[04:53:10.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:11:0) after 3m33s
[04:56:43.116] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:11:0)
[04:56:43.171] Build status: STARTED
[04:56:43.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:12:0) after 5m46s
[05:02:29.189] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:12:0)
[05:02:29.208] Build status: STARTED
[05:02:29.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:13:0) after 5m7s
[05:07:36.226] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:13:0)
[05:07:36.285] Build status: STARTED
[05:07:36.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:14:0) after 4m11s
[05:11:47.563] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:14:0)
[05:11:47.583] Build status: STARTED
[05:11:47.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:15:0) after 9m29s
[05:21:16.837] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:15:0)
[05:21:16.883] Build status: STARTED
[05:21:16.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:16:0) after 7m15s
[05:28:31.899] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:16:0)
[05:28:31.922] Build status: STARTED
[05:28:31.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:17:0) after 1m14s
[05:29:47.149] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:17:0)
[05:29:47.171] Build status: STARTED
[05:29:47.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:18:0) after 8m29s
[05:38:16.195] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:18:0)
[05:38:16.229] Build status: STARTED
[05:38:16.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:19:0) after 1m27s
[05:39:43.253] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:19:0)
[05:39:43.270] Build status: STARTED
[05:39:43.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:20:0) after 1m38s
[05:41:21.339] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:20:0)
[05:41:21.358] Build status: STARTED
[05:41:21.358] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:21:0) after 8m24s
[05:49:45.377] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:21:0)
[05:49:45.400] Build status: STARTED
[05:49:45.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:22:0) after 2m6s
[05:51:51.432] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:22:0)
[05:51:51.455] Build status: STARTED
[05:51:51.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:23:0) after 2m2s
[05:53:53.478] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:23:0)
[05:53:53.648] Build status: STARTED
[05:53:53.648] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:24:0) after 3m29s
[05:57:22.664] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:24:0)
[05:57:22.684] Build status: STARTED
[05:57:22.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:25:0) after 6m44s
[06:04:07.146] Handling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:25:0)
[06:04:07.276] Build status: STARTED
[06:04:07.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_toolchain.core.x64-debug:8916684879347343040:26:0) after 8m57s
[06:06:19.781] Received PubSub notification, asking Buildbucket for the build status
[06:06:19.883] Build:
{
"id": "8751254817368817969",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_toolchain.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-08T04:21:06.556886134Z",
"startTime": "2024-04-08T04:21:12.047610912Z",
"endTime": "2024-04-08T06:06:15.016651373Z",
"updateTime": "2024-04-08T06:06:15.016651373Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "a2c72ce59414d689d3d9f7e6a6a97455d2ac6cad",
"ref": "refs/heads/main"
}
}
}
[06:06:19.883] Invocation finished in 1h45m14.597161588s with status SUCCEEDED