[11:31:36.450] New invocation is queued and will start shortly
[11:31:37.642] Starting the invocation (attempt 1)
[11:31:37.684] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/886f977973e3e20761797469fd13a131be77e245"
[11:31:37.684] Popped gitiles commit info from properties and tags
[11:31:37.684] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:31:37.684] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:31:37.685] Buildbucket request:
{
"requestId": "8915389435893613664",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8915389435893613664",
"job": "fuchsia/core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cce4aa9aa727ea1f3be5396eaa1928e4c53385a6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cce4aa9aa727ea1f3be5396eaa1928e4c53385a6",
"title": "cce4aa9aa727ea1f3be5396eaa1928e4c53385a6",
"url": "https://fuchsia.googlesource.com/integration/+/cce4aa9aa727ea1f3be5396eaa1928e4c53385a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "65a908e4bdbee27f8c65352d33c08397510ae51d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@65a908e4bdbee27f8c65352d33c08397510ae51d",
"title": "65a908e4bdbee27f8c65352d33c08397510ae51d",
"url": "https://fuchsia.googlesource.com/integration/+/65a908e4bdbee27f8c65352d33c08397510ae51d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "886f977973e3e20761797469fd13a131be77e245"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@886f977973e3e20761797469fd13a131be77e245",
"title": "886f977973e3e20761797469fd13a131be77e245",
"url": "https://fuchsia.googlesource.com/integration/+/886f977973e3e20761797469fd13a131be77e245"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "886f977973e3e20761797469fd13a131be77e245",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915389435893613664"
},
{
"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"
}
}
[11:31:38.182] Scheduled build:
{
"id": "8749959373877292417",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-22T11:31:37.743793244Z",
"updateTime": "2024-04-22T11:31:37.743793244Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "886f977973e3e20761797469fd13a131be77e245",
"ref": "refs/heads/main"
}
}
}
[11:31:38.182] Task URL: https://cr-buildbucket.appspot.com/build/8749959373877292417
[11:31:38.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:2:0) after 2m2s
[11:31:38.727] Received PubSub notification, asking Buildbucket for the build status
[11:31:38.759] Build status: SCHEDULED
[11:31:43.987] Received PubSub notification, asking Buildbucket for the build status
[11:31:44.037] Build status: STARTED
[11:33:40.208] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:2:0)
[11:33:40.331] Build status: STARTED
[11:33:40.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:5:0) after 9m34s
[11:43:14.348] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:5:0)
[11:43:14.367] Build status: STARTED
[11:43:14.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:6:0) after 7m29s
[11:50:43.384] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:6:0)
[11:50:43.425] Build status: STARTED
[11:50:43.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:7:0) after 9m18s
[12:00:01.525] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:7:0)
[12:00:01.575] Build status: STARTED
[12:00:01.575] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:8:0) after 1m59s
[12:02:00.665] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:8:0)
[12:02:00.686] Build status: STARTED
[12:02:00.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:9:0) after 4m38s
[12:06:38.711] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:9:0)
[12:06:38.741] Build status: STARTED
[12:06:38.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:10:0) after 1m2s
[12:07:40.800] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:10:0)
[12:07:40.840] Build status: STARTED
[12:07:40.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:11:0) after 2m10s
[12:09:50.977] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:11:0)
[12:09:51.000] Build status: STARTED
[12:09:51.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:12:0) after 1m45s
[12:11:36.204] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:12:0)
[12:11:36.230] Build status: STARTED
[12:11:36.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:13:0) after 2m21s
[12:13:57.779] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:13:0)
[12:13:57.811] Build status: STARTED
[12:13:57.811] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:14:0) after 9m42s
[12:23:39.817] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:14:0)
[12:23:39.838] Build status: STARTED
[12:23:39.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:15:0) after 1m5s
[12:24:44.947] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:15:0)
[12:24:44.968] Build status: STARTED
[12:24:44.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:16:0) after 6m2s
[12:30:47.452] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:16:0)
[12:30:47.472] Build status: STARTED
[12:30:47.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:17:0) after 7m32s
[12:38:19.492] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:17:0)
[12:38:19.510] Build status: STARTED
[12:38:19.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:18:0) after 3m12s
[12:41:31.544] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:18:0)
[12:41:31.560] Build status: STARTED
[12:41:31.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:19:0) after 6m46s
[12:48:17.605] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:19:0)
[12:48:17.629] Build status: STARTED
[12:48:17.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:20:0) after 3m42s
[12:51:59.660] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:20:0)
[12:51:59.679] Build status: STARTED
[12:51:59.679] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:21:0) after 2m14s
[12:54:13.714] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:21:0)
[12:54:13.732] Build status: STARTED
[12:54:13.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:22:0) after 5m58s
[13:00:12.004] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:22:0)
[13:00:12.084] Build status: STARTED
[13:00:12.084] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:23:0) after 4m37s
[13:04:49.143] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:23:0)
[13:04:49.189] Build status: STARTED
[13:04:49.189] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:24:0) after 3m51s
[13:08:40.213] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:24:0)
[13:08:40.249] Build status: STARTED
[13:08:40.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:25:0) after 7m26s
[13:16:06.295] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:25:0)
[13:16:06.337] Build status: STARTED
[13:16:06.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:26:0) after 1m45s
[13:17:51.389] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:26:0)
[13:17:51.501] Build status: STARTED
[13:17:51.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:27:0) after 1m52s
[13:19:44.080] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:27:0)
[13:19:44.124] Build status: STARTED
[13:19:44.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:28:0) after 7m29s
[13:27:13.275] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:28:0)
[13:27:13.303] Build status: STARTED
[13:27:13.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:29:0) after 4m55s
[13:32:09.362] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:29:0)
[13:32:09.400] Build status: STARTED
[13:32:09.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:30:0) after 9m22s
[13:41:31.451] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:30:0)
[13:41:31.468] Build status: STARTED
[13:41:31.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:31:0) after 8m42s
[13:50:13.567] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:31:0)
[13:50:13.628] Build status: STARTED
[13:50:13.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:32:0) after 8m17s
[13:58:30.921] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:32:0)
[13:58:30.940] Build status: STARTED
[13:58:30.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:33:0) after 9m28s
[14:07:58.958] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:33:0)
[14:07:59.144] Build status: STARTED
[14:07:59.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:34:0) after 2m29s
[14:10:28.355] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:34:0)
[14:10:28.404] Build status: STARTED
[14:10:28.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:35:0) after 5m56s
[14:16:24.672] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:35:0)
[14:16:24.724] Build status: STARTED
[14:16:24.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:36:0) after 5m44s
[14:22:08.842] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:36:0)
[14:22:08.859] Build status: STARTED
[14:22:08.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:37:0) after 5m12s
[14:27:21.023] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:37:0)
[14:27:21.056] Build status: STARTED
[14:27:21.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:38:0) after 2m59s
[14:30:20.195] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:38:0)
[14:30:20.653] Build status: STARTED
[14:30:20.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:39:0) after 9m10s
[14:39:30.776] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:39:0)
[14:39:30.798] Build status: STARTED
[14:39:30.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:40:0) after 8m33s
[14:48:03.842] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:40:0)
[14:48:03.867] Build status: STARTED
[14:48:03.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:41:0) after 5m1s
[14:53:04.884] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:41:0)
[14:53:04.917] Build status: STARTED
[14:53:04.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:42:0) after 9m38s
[15:02:42.964] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:42:0)
[15:02:42.982] Build status: STARTED
[15:02:42.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8915389435893613664:43:0) after 5m50s
[15:06:33.759] Received PubSub notification, asking Buildbucket for the build status
[15:06:33.821] Build:
{
"id": "8749959373877292417",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-22T11:31:37.743793244Z",
"startTime": "2024-04-22T11:31:43.703850144Z",
"endTime": "2024-04-22T15:06:33.094159367Z",
"updateTime": "2024-04-22T15:06:33.094159367Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "886f977973e3e20761797469fd13a131be77e245",
"ref": "refs/heads/main"
}
}
}
[15:06:33.822] Invocation finished in 3h34m57.385274072s with status FAILED