[17:12:46.451] New invocation is queued and will start shortly
[17:12:48.058] Starting the invocation (attempt 1)
[17:12:48.110] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/third_party/rust/+/336209eef13882bd1e211b24779584cb7ef911eb"
[17:12:48.110] Popped gitiles commit info from properties and tags
[17:12:48.110] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:12:48.110] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:12:48.110] Buildbucket request:
{
"requestId": "8891540969373524656",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8891540969373524656",
"job": "fuchsia/rust_test-x64-linux-49def413",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "a2d78f77f48058fc58c3e56309b870ebeae90859"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@a2d78f77f48058fc58c3e56309b870ebeae90859",
"title": "a2d78f77f48058fc58c3e56309b870ebeae90859",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/a2d78f77f48058fc58c3e56309b870ebeae90859"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "adf0f419832a0b337c592d13eff0362f0757272b"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@adf0f419832a0b337c592d13eff0362f0757272b",
"title": "adf0f419832a0b337c592d13eff0362f0757272b",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/adf0f419832a0b337c592d13eff0362f0757272b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "a28b98650ffc7681aa388011055761c64eb5f3c9"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@a28b98650ffc7681aa388011055761c64eb5f3c9",
"title": "a28b98650ffc7681aa388011055761c64eb5f3c9",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/a28b98650ffc7681aa388011055761c64eb5f3c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "0335dc131fb35f710668f6e079d39eebbc31edac"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@0335dc131fb35f710668f6e079d39eebbc31edac",
"title": "0335dc131fb35f710668f6e079d39eebbc31edac",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/0335dc131fb35f710668f6e079d39eebbc31edac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "3998964879e709cfc1728edb3e596c3ec0a7696e"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@3998964879e709cfc1728edb3e596c3ec0a7696e",
"title": "3998964879e709cfc1728edb3e596c3ec0a7696e",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/3998964879e709cfc1728edb3e596c3ec0a7696e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "916a0b322536821ea210905a7dd8294d10d0271b"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@916a0b322536821ea210905a7dd8294d10d0271b",
"title": "916a0b322536821ea210905a7dd8294d10d0271b",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/916a0b322536821ea210905a7dd8294d10d0271b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "0e8e9b38878f1f30f53a7445a752783c7de38583"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@0e8e9b38878f1f30f53a7445a752783c7de38583",
"title": "0e8e9b38878f1f30f53a7445a752783c7de38583",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/0e8e9b38878f1f30f53a7445a752783c7de38583"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "517118e1600899a08a997364efe209229c410c03"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@517118e1600899a08a997364efe209229c410c03",
"title": "517118e1600899a08a997364efe209229c410c03",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/517118e1600899a08a997364efe209229c410c03"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "f6d58eb790a4b7e3e1fccb8caa4f684cb46af7bf"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@f6d58eb790a4b7e3e1fccb8caa4f684cb46af7bf",
"title": "f6d58eb790a4b7e3e1fccb8caa4f684cb46af7bf",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/f6d58eb790a4b7e3e1fccb8caa4f684cb46af7bf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "c74c817fb7da588c236d7368ccac41b47fe1fad8"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@c74c817fb7da588c236d7368ccac41b47fe1fad8",
"title": "c74c817fb7da588c236d7368ccac41b47fe1fad8",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/c74c817fb7da588c236d7368ccac41b47fe1fad8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "ec96e028a79a39fe743ba97aafdb20bdda7df1b5"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@ec96e028a79a39fe743ba97aafdb20bdda7df1b5",
"title": "ec96e028a79a39fe743ba97aafdb20bdda7df1b5",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/ec96e028a79a39fe743ba97aafdb20bdda7df1b5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "8fd8b2d63c25138619dad163131d29c432e295ba"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@8fd8b2d63c25138619dad163131d29c432e295ba",
"title": "8fd8b2d63c25138619dad163131d29c432e295ba",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/8fd8b2d63c25138619dad163131d29c432e295ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "147ac7ad5dd30a68a23e7abc23abe5691b7897b9"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@147ac7ad5dd30a68a23e7abc23abe5691b7897b9",
"title": "147ac7ad5dd30a68a23e7abc23abe5691b7897b9",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/147ac7ad5dd30a68a23e7abc23abe5691b7897b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "169793591c3c1a32a8d4dddc138adb57aacdfcb8"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@169793591c3c1a32a8d4dddc138adb57aacdfcb8",
"title": "169793591c3c1a32a8d4dddc138adb57aacdfcb8",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/169793591c3c1a32a8d4dddc138adb57aacdfcb8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "e39eacd2d415803ef82de3b6a314e4f2d0fbc4dc"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@e39eacd2d415803ef82de3b6a314e4f2d0fbc4dc",
"title": "e39eacd2d415803ef82de3b6a314e4f2d0fbc4dc",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/e39eacd2d415803ef82de3b6a314e4f2d0fbc4dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "92a664e111ef7c75ce0b95fc831d5d700f027acf"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@92a664e111ef7c75ce0b95fc831d5d700f027acf",
"title": "92a664e111ef7c75ce0b95fc831d5d700f027acf",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/92a664e111ef7c75ce0b95fc831d5d700f027acf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/third_party/rust",
"revision": "336209eef13882bd1e211b24779584cb7ef911eb"
},
"id": "https://fuchsia.googlesource.com/third_party/rust/+/refs/heads/main@336209eef13882bd1e211b24779584cb7ef911eb",
"title": "336209eef13882bd1e211b24779584cb7ef911eb",
"url": "https://fuchsia.googlesource.com/third_party/rust/+/336209eef13882bd1e211b24779584cb7ef911eb"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "336209eef13882bd1e211b24779584cb7ef911eb",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8891540969373524656"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/rust_test-x64-linux-49def413"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:12:48.646] Scheduled build:
{
"id": "8726110906902515553",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-10T17:12:48.182508754Z",
"updateTime": "2025-01-10T17:12:48.182508754Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "336209eef13882bd1e211b24779584cb7ef911eb",
"ref": "refs/heads/main"
}
}
}
[17:12:48.646] Task URL: https://cr-buildbucket.appspot.com/build/8726110906902515553
[17:12:48.646] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:2:0) after 8m27s
[17:12:49.550] Received PubSub notification, asking Buildbucket for the build status
[17:12:49.574] Build status: SCHEDULED
[17:12:52.682] Received PubSub notification, asking Buildbucket for the build status
[17:12:52.701] Build status: STARTED
[17:21:15.808] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:2:0)
[17:21:15.846] Build status: STARTED
[17:21:15.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:5:0) after 3m20s
[17:24:35.868] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:5:0)
[17:24:35.900] Build status: STARTED
[17:24:35.900] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:6:0) after 1m21s
[17:25:57.036] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:6:0)
[17:25:57.065] Build status: STARTED
[17:25:57.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:7:0) after 6m20s
[17:32:17.084] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:7:0)
[17:32:17.107] Build status: STARTED
[17:32:17.107] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:8:0) after 7m25s
[17:39:42.186] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:8:0)
[17:39:42.216] Build status: STARTED
[17:39:42.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:9:0) after 7m38s
[17:47:20.325] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:9:0)
[17:47:20.349] Build status: STARTED
[17:47:20.349] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:10:0) after 3m54s
[17:51:14.443] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:10:0)
[17:51:14.474] Build status: STARTED
[17:51:14.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:11:0) after 2m49s
[17:54:03.562] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:11:0)
[17:54:03.599] Build status: STARTED
[17:54:03.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:12:0) after 8m44s
[18:02:47.591] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:12:0)
[18:02:47.609] Build status: STARTED
[18:02:47.609] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:13:0) after 7m0s
[18:09:47.680] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:13:0)
[18:09:47.698] Build status: STARTED
[18:09:47.698] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:14:0) after 9m7s
[18:18:54.710] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:14:0)
[18:18:54.732] Build status: STARTED
[18:18:54.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:15:0) after 4m8s
[18:23:02.760] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:15:0)
[18:23:02.779] Build status: STARTED
[18:23:02.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:16:0) after 1m50s
[18:24:52.828] Handling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:16:0)
[18:24:52.852] Build status: STARTED
[18:24:52.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/rust_test-x64-linux-49def413:8891540969373524656:17:0) after 5m10s
[18:28:29.356] Received PubSub notification, asking Buildbucket for the build status
[18:28:29.374] Build:
{
"id": "8726110906902515553",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "rust_test-x64-linux"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-10T17:12:48.182508754Z",
"startTime": "2025-01-10T17:12:52.471046874Z",
"endTime": "2025-01-10T18:28:28.962162846Z",
"updateTime": "2025-01-10T18:28:28.962162846Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "third_party/rust",
"id": "336209eef13882bd1e211b24779584cb7ef911eb",
"ref": "refs/heads/main"
}
}
}
[18:28:29.374] Invocation finished in 1h15m42.931893697s with status FAILED