[03:54:52.720] New invocation is queued and will start shortly
[03:54:54.111] Starting the invocation (attempt 1)
[03:54:54.136] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/677a4ff53d7e45ebf8588ce9f51be52fb23ebd86"
[03:54:54.136] Popped gitiles commit info from properties and tags
[03:54:54.136] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:54:54.136] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:54:54.136] Buildbucket request:
{
"requestId": "8863143721172874688",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-kubsan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8863143721172874688",
"job": "fuchsia/global.ci-core.x64-kubsan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "da4f90762c7056c4afc3f135f166cc2fd73846c6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@da4f90762c7056c4afc3f135f166cc2fd73846c6",
"title": "da4f90762c7056c4afc3f135f166cc2fd73846c6",
"url": "https://fuchsia.googlesource.com/integration/+/da4f90762c7056c4afc3f135f166cc2fd73846c6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "471c8b0344a183425aa6ce5fbf731d3b5abaf9fc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@471c8b0344a183425aa6ce5fbf731d3b5abaf9fc",
"title": "471c8b0344a183425aa6ce5fbf731d3b5abaf9fc",
"url": "https://fuchsia.googlesource.com/integration/+/471c8b0344a183425aa6ce5fbf731d3b5abaf9fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a5f0b8be7f5c00041ed7616f40c18420e59c356c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a5f0b8be7f5c00041ed7616f40c18420e59c356c",
"title": "a5f0b8be7f5c00041ed7616f40c18420e59c356c",
"url": "https://fuchsia.googlesource.com/integration/+/a5f0b8be7f5c00041ed7616f40c18420e59c356c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cc80dd605bd44434f6d52afe50ece7cc7d1cdbf4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cc80dd605bd44434f6d52afe50ece7cc7d1cdbf4",
"title": "cc80dd605bd44434f6d52afe50ece7cc7d1cdbf4",
"url": "https://fuchsia.googlesource.com/integration/+/cc80dd605bd44434f6d52afe50ece7cc7d1cdbf4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c559770a15925aab72446c68d521cddb1e60f517"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c559770a15925aab72446c68d521cddb1e60f517",
"title": "c559770a15925aab72446c68d521cddb1e60f517",
"url": "https://fuchsia.googlesource.com/integration/+/c559770a15925aab72446c68d521cddb1e60f517"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dfca3a4137ebd2e1befc7b4c719115c589021bbe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dfca3a4137ebd2e1befc7b4c719115c589021bbe",
"title": "dfca3a4137ebd2e1befc7b4c719115c589021bbe",
"url": "https://fuchsia.googlesource.com/integration/+/dfca3a4137ebd2e1befc7b4c719115c589021bbe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ad1c432e210458891122cc971eab7965bf43aa84"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ad1c432e210458891122cc971eab7965bf43aa84",
"title": "ad1c432e210458891122cc971eab7965bf43aa84",
"url": "https://fuchsia.googlesource.com/integration/+/ad1c432e210458891122cc971eab7965bf43aa84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e26c7d25211c3e3bcc9532e2ba111429de4d6338"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e26c7d25211c3e3bcc9532e2ba111429de4d6338",
"title": "e26c7d25211c3e3bcc9532e2ba111429de4d6338",
"url": "https://fuchsia.googlesource.com/integration/+/e26c7d25211c3e3bcc9532e2ba111429de4d6338"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "90288f9309edf8885ee94e35a286b51ad7d5dee1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@90288f9309edf8885ee94e35a286b51ad7d5dee1",
"title": "90288f9309edf8885ee94e35a286b51ad7d5dee1",
"url": "https://fuchsia.googlesource.com/integration/+/90288f9309edf8885ee94e35a286b51ad7d5dee1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "677a4ff53d7e45ebf8588ce9f51be52fb23ebd86"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@677a4ff53d7e45ebf8588ce9f51be52fb23ebd86",
"title": "677a4ff53d7e45ebf8588ce9f51be52fb23ebd86",
"url": "https://fuchsia.googlesource.com/integration/+/677a4ff53d7e45ebf8588ce9f51be52fb23ebd86"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "677a4ff53d7e45ebf8588ce9f51be52fb23ebd86",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8863143721172874688"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.x64-kubsan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:54:54.478] Scheduled build:
{
"id": "8697713658973771681",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-kubsan"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-20T03:54:54.188213351Z",
"updateTime": "2025-11-20T03:54:54.188213351Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "677a4ff53d7e45ebf8588ce9f51be52fb23ebd86",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:54:54.478] Task URL: https://cr-buildbucket.appspot.com/build/8697713658973771681
[03:54:54.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:2:0) after 7m21s
[03:54:54.734] Received PubSub notification, asking Buildbucket for the build status
[03:54:54.780] Build status: SCHEDULED
[03:55:02.840] Received PubSub notification, asking Buildbucket for the build status
[03:55:02.884] Build status: STARTED
[04:02:15.491] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:2:0)
[04:02:15.507] Build status: STARTED
[04:02:15.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:5:0) after 8m13s
[04:10:28.521] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:5:0)
[04:10:28.534] Build status: STARTED
[04:10:28.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:6:0) after 3m6s
[04:13:34.546] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:6:0)
[04:13:34.576] Build status: STARTED
[04:13:34.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:7:0) after 4m42s
[04:18:16.587] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:7:0)
[04:18:16.601] Build status: STARTED
[04:18:16.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:8:0) after 4m49s
[04:23:05.615] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:8:0)
[04:23:05.635] Build status: STARTED
[04:23:05.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:9:0) after 6m34s
[04:29:39.648] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:9:0)
[04:29:39.676] Build status: STARTED
[04:29:39.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:10:0) after 2m31s
[04:32:10.693] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:10:0)
[04:32:10.710] Build status: STARTED
[04:32:10.710] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:11:0) after 5m48s
[04:37:58.726] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:11:0)
[04:37:58.744] Build status: STARTED
[04:37:58.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:12:0) after 2m4s
[04:40:02.795] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:12:0)
[04:40:02.826] Build status: STARTED
[04:40:02.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:13:0) after 5m52s
[04:45:54.815] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:13:0)
[04:45:54.849] Build status: STARTED
[04:45:54.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:14:0) after 2m22s
[04:48:16.902] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:14:0)
[04:48:16.924] Build status: STARTED
[04:48:16.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:15:0) after 8m21s
[04:56:37.938] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:15:0)
[04:56:37.955] Build status: STARTED
[04:56:37.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:16:0) after 4m46s
[05:01:23.995] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:16:0)
[05:01:24.009] Build status: STARTED
[05:01:24.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:17:0) after 1m12s
[05:02:36.024] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:17:0)
[05:02:36.050] Build status: STARTED
[05:02:36.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:18:0) after 5m33s
[05:08:09.090] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:18:0)
[05:08:09.112] Build status: STARTED
[05:08:09.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:19:0) after 1m24s
[05:09:33.118] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:19:0)
[05:09:33.133] Build status: STARTED
[05:09:33.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:20:0) after 3m21s
[05:12:54.179] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:20:0)
[05:12:54.193] Build status: STARTED
[05:12:54.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:21:0) after 6m3s
[05:18:57.227] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:21:0)
[05:18:57.296] Build status: STARTED
[05:18:57.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:22:0) after 4m26s
[05:23:23.319] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:22:0)
[05:23:23.341] Build status: STARTED
[05:23:23.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:23:0) after 5m7s
[05:28:30.405] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:23:0)
[05:28:30.424] Build status: STARTED
[05:28:30.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:24:0) after 4m1s
[05:32:31.489] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:24:0)
[05:32:31.548] Build status: STARTED
[05:32:31.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-kubsan-e5a0ec7f:8863143721172874688:25:0) after 9m17s
[05:32:44.330] Received PubSub notification, asking Buildbucket for the build status
[05:32:44.414] Build:
{
"id": "8697713658973771681",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-kubsan"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-20T03:54:54.188213351Z",
"startTime": "2025-11-20T03:55:02.659952795Z",
"endTime": "2025-11-20T05:32:43.765332320Z",
"updateTime": "2025-11-20T05:32:43.765332320Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "677a4ff53d7e45ebf8588ce9f51be52fb23ebd86",
"ref": "refs/heads/main"
}
},
"canary": true
}
[05:32:44.414] Invocation finished in 1h37m51.70659407s with status FAILED