[10:58:06.786] New invocation is queued and will start shortly
[10:58:08.042] Starting the invocation (attempt 1)
[10:58:08.067] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/4d8e866da48938e4f0c21e3fd970c50d9561dfa4"
[10:58:08.068] Popped gitiles commit info from properties and tags
[10:58:08.068] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:58:08.069] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:58:08.069] Buildbucket request:
{
"requestId": "8921008555094872352",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8921008555094872352",
"job": "fuchsia/core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "df2b3912612bc1c861176f0f0ec0620befbe7b75"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@df2b3912612bc1c861176f0f0ec0620befbe7b75",
"title": "df2b3912612bc1c861176f0f0ec0620befbe7b75",
"url": "https://fuchsia.googlesource.com/integration/+/df2b3912612bc1c861176f0f0ec0620befbe7b75"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "536ea47ae3a6c2fe2af6ba190989e5c862fe3fa4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@536ea47ae3a6c2fe2af6ba190989e5c862fe3fa4",
"title": "536ea47ae3a6c2fe2af6ba190989e5c862fe3fa4",
"url": "https://fuchsia.googlesource.com/integration/+/536ea47ae3a6c2fe2af6ba190989e5c862fe3fa4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4d8e866da48938e4f0c21e3fd970c50d9561dfa4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4d8e866da48938e4f0c21e3fd970c50d9561dfa4",
"title": "4d8e866da48938e4f0c21e3fd970c50d9561dfa4",
"url": "https://fuchsia.googlesource.com/integration/+/4d8e866da48938e4f0c21e3fd970c50d9561dfa4"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4d8e866da48938e4f0c21e3fd970c50d9561dfa4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8921008555094872352"
},
{
"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"
}
}
[10:58:08.543] Scheduled build:
{
"id": "8755578493028775377",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-20T10:58:08.127918130Z",
"updateTime": "2024-02-20T10:58:08.127918130Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4d8e866da48938e4f0c21e3fd970c50d9561dfa4",
"ref": "refs/heads/main"
}
}
}
[10:58:08.543] Task URL: https://cr-buildbucket.appspot.com/build/8755578493028775377
[10:58:08.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:2:0) after 1m7s
[10:58:09.430] Received PubSub notification, asking Buildbucket for the build status
[10:58:09.463] Build status: STARTED
[10:58:09.849] Received PubSub notification, asking Buildbucket for the build status
[10:58:09.873] Build status: STARTED
[10:59:15.559] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:2:0)
[10:59:15.589] Build status: STARTED
[10:59:15.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:5:0) after 3m48s
[11:03:03.692] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:5:0)
[11:03:03.717] Build status: STARTED
[11:03:03.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:6:0) after 5m11s
[11:08:14.742] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:6:0)
[11:08:14.781] Build status: STARTED
[11:08:14.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:7:0) after 2m33s
[11:10:47.907] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:7:0)
[11:10:47.942] Build status: STARTED
[11:10:47.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:8:0) after 5m48s
[11:16:36.069] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:8:0)
[11:16:36.101] Build status: STARTED
[11:16:36.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:9:0) after 8m23s
[11:24:59.220] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:9:0)
[11:24:59.245] Build status: STARTED
[11:24:59.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:10:0) after 5m43s
[11:30:42.505] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:10:0)
[11:30:42.525] Build status: STARTED
[11:30:42.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:11:0) after 8m58s
[11:39:40.640] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:11:0)
[11:39:40.773] Build status: STARTED
[11:39:40.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:12:0) after 7m41s
[11:47:21.905] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:12:0)
[11:47:21.938] Build status: STARTED
[11:47:21.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:13:0) after 6m3s
[11:53:24.973] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:13:0)
[11:53:24.995] Build status: STARTED
[11:53:24.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:14:0) after 9m49s
[12:03:14.105] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:14:0)
[12:03:14.131] Build status: STARTED
[12:03:14.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:15:0) after 9m25s
[12:12:39.150] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:15:0)
[12:12:39.169] Build status: STARTED
[12:12:39.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:16:0) after 3m10s
[12:15:49.185] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:16:0)
[12:15:49.205] Build status: STARTED
[12:15:49.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:17:0) after 8m56s
[12:24:45.819] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:17:0)
[12:24:45.839] Build status: STARTED
[12:24:45.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:18:0) after 5m0s
[12:29:45.997] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:18:0)
[12:29:46.032] Build status: STARTED
[12:29:46.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:19:0) after 1m34s
[12:31:20.184] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:19:0)
[12:31:20.218] Build status: STARTED
[12:31:20.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:20:0) after 9m24s
[12:40:44.251] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:20:0)
[12:40:44.277] Build status: STARTED
[12:40:44.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:21:0) after 4m43s
[12:45:27.643] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:21:0)
[12:45:27.665] Build status: STARTED
[12:45:27.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:22:0) after 8m29s
[12:53:56.849] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:22:0)
[12:53:56.867] Build status: STARTED
[12:53:56.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:23:0) after 4m38s
[12:58:34.924] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:23:0)
[12:58:34.946] Build status: STARTED
[12:58:34.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:24:0) after 2m10s
[13:00:45.090] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:24:0)
[13:00:45.154] Build status: STARTED
[13:00:45.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:25:0) after 4m39s
[13:05:24.171] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:25:0)
[13:05:24.205] Build status: STARTED
[13:05:24.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:26:0) after 2m33s
[13:07:57.586] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:26:0)
[13:07:57.604] Build status: STARTED
[13:07:57.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:27:0) after 9m28s
[13:17:25.754] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:27:0)
[13:17:25.804] Build status: STARTED
[13:17:25.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:28:0) after 5m24s
[13:22:49.827] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:28:0)
[13:22:49.846] Build status: STARTED
[13:22:49.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:29:0) after 1m2s
[13:23:52.021] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:29:0)
[13:23:52.047] Build status: STARTED
[13:23:52.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:30:0) after 8m12s
[13:32:04.693] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:30:0)
[13:32:04.717] Build status: STARTED
[13:32:04.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:31:0) after 5m56s
[13:38:00.735] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:31:0)
[13:38:00.773] Build status: STARTED
[13:38:00.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:32:0) after 9m12s
[13:47:12.790] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:32:0)
[13:47:12.809] Build status: STARTED
[13:47:12.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:33:0) after 4m20s
[13:51:32.837] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:33:0)
[13:51:32.878] Build status: STARTED
[13:51:32.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:34:0) after 6m10s
[13:57:42.903] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:34:0)
[13:57:43.011] Build status: STARTED
[13:57:43.011] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:35:0) after 5m3s
[14:02:46.518] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:35:0)
[14:02:46.587] Build status: STARTED
[14:02:46.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8921008555094872352:36:0) after 3m29s
[14:05:41.767] Received PubSub notification, asking Buildbucket for the build status
[14:05:41.790] Build:
{
"id": "8755578493028775377",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-20T10:58:08.127918130Z",
"startTime": "2024-02-20T10:58:08.912865Z",
"endTime": "2024-02-20T14:05:40.970837Z",
"updateTime": "2024-02-20T14:05:40.970837Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "4d8e866da48938e4f0c21e3fd970c50d9561dfa4",
"ref": "refs/heads/main"
}
}
}
[14:05:41.790] Invocation finished in 3h7m35.01758378s with status FAILED