[07:53:54.097] New invocation is queued and will start shortly
[07:53:55.170] Starting the invocation (attempt 1)
[07:53:55.197] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/f509c65e16b1c70df2e2482d26de4457bef6aac0"
[07:53:55.197] Popped gitiles commit info from properties and tags
[07:53:55.197] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:53:55.197] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:53:55.198] Buildbucket request:
{
"requestId": "8917033878150175872",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8917033878150175872",
"job": "fuchsia/core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "de4e50915ca7fe915f662e8e0e5b0586521fa09f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@de4e50915ca7fe915f662e8e0e5b0586521fa09f",
"title": "de4e50915ca7fe915f662e8e0e5b0586521fa09f",
"url": "https://fuchsia.googlesource.com/integration/+/de4e50915ca7fe915f662e8e0e5b0586521fa09f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f4d2bb2f2b6bebba42363ca2b7ddab0301ecaea2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f4d2bb2f2b6bebba42363ca2b7ddab0301ecaea2",
"title": "f4d2bb2f2b6bebba42363ca2b7ddab0301ecaea2",
"url": "https://fuchsia.googlesource.com/integration/+/f4d2bb2f2b6bebba42363ca2b7ddab0301ecaea2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5590ffdc229bc1e5bc5c4e909a94c0d395942421"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5590ffdc229bc1e5bc5c4e909a94c0d395942421",
"title": "5590ffdc229bc1e5bc5c4e909a94c0d395942421",
"url": "https://fuchsia.googlesource.com/integration/+/5590ffdc229bc1e5bc5c4e909a94c0d395942421"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "885be5417807afee4c5c3e5059c4079ba953b6ae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@885be5417807afee4c5c3e5059c4079ba953b6ae",
"title": "885be5417807afee4c5c3e5059c4079ba953b6ae",
"url": "https://fuchsia.googlesource.com/integration/+/885be5417807afee4c5c3e5059c4079ba953b6ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "85e531a03a6ef4eb52e6a7c279741dffc9cbddea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@85e531a03a6ef4eb52e6a7c279741dffc9cbddea",
"title": "85e531a03a6ef4eb52e6a7c279741dffc9cbddea",
"url": "https://fuchsia.googlesource.com/integration/+/85e531a03a6ef4eb52e6a7c279741dffc9cbddea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c00ac3600b8a51a765bd075a2e392f386a1b2a1c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c00ac3600b8a51a765bd075a2e392f386a1b2a1c",
"title": "c00ac3600b8a51a765bd075a2e392f386a1b2a1c",
"url": "https://fuchsia.googlesource.com/integration/+/c00ac3600b8a51a765bd075a2e392f386a1b2a1c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8d5149d2e03bec48a470d563957c9a96e8e11b2a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8d5149d2e03bec48a470d563957c9a96e8e11b2a",
"title": "8d5149d2e03bec48a470d563957c9a96e8e11b2a",
"url": "https://fuchsia.googlesource.com/integration/+/8d5149d2e03bec48a470d563957c9a96e8e11b2a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "22c114cbde7dd93c7f12d1cb266d9c947a4f5d25"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@22c114cbde7dd93c7f12d1cb266d9c947a4f5d25",
"title": "22c114cbde7dd93c7f12d1cb266d9c947a4f5d25",
"url": "https://fuchsia.googlesource.com/integration/+/22c114cbde7dd93c7f12d1cb266d9c947a4f5d25"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5fc0405f0c115020911a596c06f50d0d80d495e7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5fc0405f0c115020911a596c06f50d0d80d495e7",
"title": "5fc0405f0c115020911a596c06f50d0d80d495e7",
"url": "https://fuchsia.googlesource.com/integration/+/5fc0405f0c115020911a596c06f50d0d80d495e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f509c65e16b1c70df2e2482d26de4457bef6aac0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f509c65e16b1c70df2e2482d26de4457bef6aac0",
"title": "f509c65e16b1c70df2e2482d26de4457bef6aac0",
"url": "https://fuchsia.googlesource.com/integration/+/f509c65e16b1c70df2e2482d26de4457bef6aac0"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f509c65e16b1c70df2e2482d26de4457bef6aac0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917033878150175872"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-full_determinism-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:53:55.685] Scheduled build:
{
"id": "8751603816275797713",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-04T07:53:55.262814925Z",
"updateTime": "2024-04-04T07:53:55.262814925Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f509c65e16b1c70df2e2482d26de4457bef6aac0",
"ref": "refs/heads/main"
}
}
}
[07:53:55.685] Task URL: https://cr-buildbucket.appspot.com/build/8751603816275797713
[07:53:55.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:2:0) after 5m0s
[07:53:56.634] Received PubSub notification, asking Buildbucket for the build status
[07:53:56.697] Build status: SCHEDULED
[07:54:00.068] Received PubSub notification, asking Buildbucket for the build status
[07:54:00.105] Build status: STARTED
[07:58:55.813] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:2:0)
[07:58:55.838] Build status: STARTED
[07:58:55.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:5:0) after 9m9s
[08:08:04.896] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:5:0)
[08:08:04.963] Build status: STARTED
[08:08:04.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:6:0) after 6m10s
[08:14:15.062] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:6:0)
[08:14:15.078] Build status: STARTED
[08:14:15.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:7:0) after 5m38s
[08:19:53.104] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:7:0)
[08:19:53.128] Build status: STARTED
[08:19:53.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:8:0) after 3m5s
[08:22:58.357] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:8:0)
[08:22:58.379] Build status: STARTED
[08:22:58.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:9:0) after 2m5s
[08:25:03.512] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:9:0)
[08:25:03.590] Build status: STARTED
[08:25:03.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:10:0) after 5m26s
[08:30:29.743] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:10:0)
[08:30:29.770] Build status: STARTED
[08:30:29.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:11:0) after 6m27s
[08:36:56.898] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:11:0)
[08:36:56.915] Build status: STARTED
[08:36:56.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:12:0) after 4m24s
[08:41:21.107] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:12:0)
[08:41:21.127] Build status: STARTED
[08:41:21.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:13:0) after 9m36s
[08:50:57.245] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:13:0)
[08:50:57.272] Build status: STARTED
[08:50:57.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:14:0) after 3m46s
[08:54:43.294] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:14:0)
[08:54:43.317] Build status: STARTED
[08:54:43.317] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:15:0) after 9m33s
[09:04:16.351] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:15:0)
[09:04:16.376] Build status: STARTED
[09:04:16.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:16:0) after 7m51s
[09:12:07.576] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:16:0)
[09:12:07.598] Build status: STARTED
[09:12:07.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:17:0) after 2m12s
[09:14:19.830] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:17:0)
[09:14:19.851] Build status: STARTED
[09:14:19.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:18:0) after 3m52s
[09:18:12.125] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:18:0)
[09:18:12.144] Build status: STARTED
[09:18:12.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:19:0) after 7m28s
[09:25:40.167] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:19:0)
[09:25:40.197] Build status: STARTED
[09:25:40.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:20:0) after 4m48s
[09:30:28.197] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:20:0)
[09:30:28.215] Build status: STARTED
[09:30:28.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:21:0) after 3m17s
[09:33:45.226] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:21:0)
[09:33:45.246] Build status: STARTED
[09:33:45.246] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:22:0) after 6m38s
[09:40:23.263] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:22:0)
[09:40:23.288] Build status: STARTED
[09:40:23.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:23:0) after 8m26s
[09:48:49.305] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:23:0)
[09:48:49.326] Build status: STARTED
[09:48:49.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:24:0) after 6m45s
[09:55:35.157] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:24:0)
[09:55:35.328] Build status: STARTED
[09:55:35.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:25:0) after 5m2s
[10:00:37.408] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:25:0)
[10:00:37.431] Build status: STARTED
[10:00:37.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:26:0) after 1m0s
[10:01:37.510] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:26:0)
[10:01:37.529] Build status: STARTED
[10:01:37.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:27:0) after 2m7s
[10:03:44.547] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:27:0)
[10:03:44.570] Build status: STARTED
[10:03:44.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:28:0) after 2m19s
[10:06:03.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:28:0)
[10:06:03.639] Build status: STARTED
[10:06:03.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:29:0) after 4m1s
[10:10:04.676] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:29:0)
[10:10:04.696] Build status: STARTED
[10:10:04.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:30:0) after 8m27s
[10:18:31.820] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:30:0)
[10:18:31.840] Build status: STARTED
[10:18:31.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:31:0) after 5m39s
[10:24:11.271] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:31:0)
[10:24:11.288] Build status: STARTED
[10:24:11.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:32:0) after 7m52s
[10:32:03.753] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:32:0)
[10:32:03.775] Build status: STARTED
[10:32:03.775] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:33:0) after 3m45s
[10:35:48.945] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:33:0)
[10:35:48.965] Build status: STARTED
[10:35:48.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:34:0) after 6m51s
[10:42:40.087] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:34:0)
[10:42:40.109] Build status: STARTED
[10:42:40.109] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:35:0) after 2m43s
[10:45:23.376] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:35:0)
[10:45:23.425] Build status: STARTED
[10:45:23.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:36:0) after 8m15s
[10:53:38.501] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:36:0)
[10:53:38.522] Build status: STARTED
[10:53:38.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:37:0) after 3m58s
[10:57:36.625] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:37:0)
[10:57:36.647] Build status: STARTED
[10:57:36.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:38:0) after 3m48s
[11:01:24.664] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:38:0)
[11:01:24.687] Build status: STARTED
[11:01:24.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:39:0) after 1m8s
[11:02:32.705] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:39:0)
[11:02:32.725] Build status: STARTED
[11:02:32.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:40:0) after 4m36s
[11:07:08.884] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:40:0)
[11:07:08.908] Build status: STARTED
[11:07:08.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:41:0) after 8m27s
[11:15:35.939] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:41:0)
[11:15:36.027] Build status: STARTED
[11:15:36.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:42:0) after 2m46s
[11:18:22.154] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:42:0)
[11:18:22.179] Build status: STARTED
[11:18:22.179] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8917033878150175872:43:0) after 3m46s
[11:18:46.628] Received PubSub notification, asking Buildbucket for the build status
[11:18:46.668] Build:
{
"id": "8751603816275797713",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-04T07:53:55.262814925Z",
"startTime": "2024-04-04T07:53:59.876412523Z",
"endTime": "2024-04-04T11:18:46.393999735Z",
"updateTime": "2024-04-04T11:18:46.393999735Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "f509c65e16b1c70df2e2482d26de4457bef6aac0",
"ref": "refs/heads/main"
}
}
}
[11:18:46.668] Invocation finished in 3h24m52.577257731s with status FAILED