[19:16:50.462] New invocation is queued and will start shortly
[19:16:51.561] Starting the invocation (attempt 1)
[19:16:51.594] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/e3061f299b144acf335b3deafb444d7a2d7f1bf9"
[19:16:51.594] Popped gitiles commit info from properties and tags
[19:16:51.594] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:16:51.594] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:16:51.595] Buildbucket request:
{
"requestId": "8889630627468006896",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889630627468006896",
"job": "fuchsia/core.x64-full_determinism-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3534f9d710292555a5b77b1903a667c64625d5df"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3534f9d710292555a5b77b1903a667c64625d5df",
"title": "3534f9d710292555a5b77b1903a667c64625d5df",
"url": "https://fuchsia.googlesource.com/integration/+/3534f9d710292555a5b77b1903a667c64625d5df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0380191b8c412e508619fea6078354088b781108"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0380191b8c412e508619fea6078354088b781108",
"title": "0380191b8c412e508619fea6078354088b781108",
"url": "https://fuchsia.googlesource.com/integration/+/0380191b8c412e508619fea6078354088b781108"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6ad807a56e1eed853b285eac154b7071f36bfb3f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6ad807a56e1eed853b285eac154b7071f36bfb3f",
"title": "6ad807a56e1eed853b285eac154b7071f36bfb3f",
"url": "https://fuchsia.googlesource.com/integration/+/6ad807a56e1eed853b285eac154b7071f36bfb3f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7b6e862d3c45f49d43e36ba85cce5f9d9e2d61ca"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7b6e862d3c45f49d43e36ba85cce5f9d9e2d61ca",
"title": "7b6e862d3c45f49d43e36ba85cce5f9d9e2d61ca",
"url": "https://fuchsia.googlesource.com/integration/+/7b6e862d3c45f49d43e36ba85cce5f9d9e2d61ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aef07dbfbe8178c067d9b728d2378887dcb2cd1e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aef07dbfbe8178c067d9b728d2378887dcb2cd1e",
"title": "aef07dbfbe8178c067d9b728d2378887dcb2cd1e",
"url": "https://fuchsia.googlesource.com/integration/+/aef07dbfbe8178c067d9b728d2378887dcb2cd1e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1db5fea68559f39ca59086876cad31ad3a49fea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1db5fea68559f39ca59086876cad31ad3a49fea",
"title": "d1db5fea68559f39ca59086876cad31ad3a49fea",
"url": "https://fuchsia.googlesource.com/integration/+/d1db5fea68559f39ca59086876cad31ad3a49fea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "870068199c6cc7afb1532b59fa2f16a1a07d51a8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@870068199c6cc7afb1532b59fa2f16a1a07d51a8",
"title": "870068199c6cc7afb1532b59fa2f16a1a07d51a8",
"url": "https://fuchsia.googlesource.com/integration/+/870068199c6cc7afb1532b59fa2f16a1a07d51a8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8ebd1fde14ddab8884bafaaafde1212c34c8d001"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8ebd1fde14ddab8884bafaaafde1212c34c8d001",
"title": "8ebd1fde14ddab8884bafaaafde1212c34c8d001",
"url": "https://fuchsia.googlesource.com/integration/+/8ebd1fde14ddab8884bafaaafde1212c34c8d001"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a140a8a33c89b733381540297a27316857635be9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a140a8a33c89b733381540297a27316857635be9",
"title": "a140a8a33c89b733381540297a27316857635be9",
"url": "https://fuchsia.googlesource.com/integration/+/a140a8a33c89b733381540297a27316857635be9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "57c2edbc282c8716c6947d4aff46a62e2ae5ccaf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@57c2edbc282c8716c6947d4aff46a62e2ae5ccaf",
"title": "57c2edbc282c8716c6947d4aff46a62e2ae5ccaf",
"url": "https://fuchsia.googlesource.com/integration/+/57c2edbc282c8716c6947d4aff46a62e2ae5ccaf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c56f08e055a2cf0a4cb5aad23524df13000249f6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c56f08e055a2cf0a4cb5aad23524df13000249f6",
"title": "c56f08e055a2cf0a4cb5aad23524df13000249f6",
"url": "https://fuchsia.googlesource.com/integration/+/c56f08e055a2cf0a4cb5aad23524df13000249f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "34d4c180a904d57d9a7cc7a3e40d9bb4160b631d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@34d4c180a904d57d9a7cc7a3e40d9bb4160b631d",
"title": "34d4c180a904d57d9a7cc7a3e40d9bb4160b631d",
"url": "https://fuchsia.googlesource.com/integration/+/34d4c180a904d57d9a7cc7a3e40d9bb4160b631d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "16fd55768627fdab64817a9147669d0f541ebbf7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@16fd55768627fdab64817a9147669d0f541ebbf7",
"title": "16fd55768627fdab64817a9147669d0f541ebbf7",
"url": "https://fuchsia.googlesource.com/integration/+/16fd55768627fdab64817a9147669d0f541ebbf7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "587c378d7739a7f11256ebe9028e0053f985313c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@587c378d7739a7f11256ebe9028e0053f985313c",
"title": "587c378d7739a7f11256ebe9028e0053f985313c",
"url": "https://fuchsia.googlesource.com/integration/+/587c378d7739a7f11256ebe9028e0053f985313c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d504e91963d55f3a82ad9b275254863775ec6773"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d504e91963d55f3a82ad9b275254863775ec6773",
"title": "d504e91963d55f3a82ad9b275254863775ec6773",
"url": "https://fuchsia.googlesource.com/integration/+/d504e91963d55f3a82ad9b275254863775ec6773"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e3061f299b144acf335b3deafb444d7a2d7f1bf9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e3061f299b144acf335b3deafb444d7a2d7f1bf9",
"title": "e3061f299b144acf335b3deafb444d7a2d7f1bf9",
"url": "https://fuchsia.googlesource.com/integration/+/e3061f299b144acf335b3deafb444d7a2d7f1bf9"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e3061f299b144acf335b3deafb444d7a2d7f1bf9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889630627468006896"
},
{
"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"
}
}
[19:16:52.069] Scheduled build:
{
"id": "8724200565557923409",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-31T19:16:51.658742004Z",
"updateTime": "2025-01-31T19:16:51.658742004Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e3061f299b144acf335b3deafb444d7a2d7f1bf9",
"ref": "refs/heads/main"
}
}
}
[19:16:52.069] Task URL: https://cr-buildbucket.appspot.com/build/8724200565557923409
[19:16:52.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:2:0) after 8m45s
[19:16:52.700] Received PubSub notification, asking Buildbucket for the build status
[19:16:52.727] Build status: SCHEDULED
[19:17:06.129] Received PubSub notification, asking Buildbucket for the build status
[19:17:06.154] Build status: STARTED
[19:25:37.092] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:2:0)
[19:25:37.135] Build status: STARTED
[19:25:37.135] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:5:0) after 4m49s
[19:30:26.210] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:5:0)
[19:30:26.237] Build status: STARTED
[19:30:26.237] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:6:0) after 6m57s
[19:37:23.210] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:6:0)
[19:37:23.271] Build status: STARTED
[19:37:23.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:7:0) after 5m46s
[19:43:09.365] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:7:0)
[19:43:09.381] Build status: STARTED
[19:43:09.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:8:0) after 4m36s
[19:47:45.502] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:8:0)
[19:47:45.520] Build status: STARTED
[19:47:45.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:9:0) after 9m46s
[19:57:31.593] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:9:0)
[19:57:31.613] Build status: STARTED
[19:57:31.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:10:0) after 8m38s
[20:06:09.710] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:10:0)
[20:06:09.732] Build status: STARTED
[20:06:09.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:11:0) after 7m56s
[20:14:05.760] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:11:0)
[20:14:05.776] Build status: STARTED
[20:14:05.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:12:0) after 6m59s
[20:21:04.948] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:12:0)
[20:21:05.002] Build status: STARTED
[20:21:05.002] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:13:0) after 2m21s
[20:23:26.029] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:13:0)
[20:23:26.048] Build status: STARTED
[20:23:26.048] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:14:0) after 3m20s
[20:26:46.110] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:14:0)
[20:26:46.127] Build status: STARTED
[20:26:46.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:15:0) after 3m39s
[20:30:25.202] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:15:0)
[20:30:25.230] Build status: STARTED
[20:30:25.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:16:0) after 3m50s
[20:34:15.270] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:16:0)
[20:34:15.284] Build status: STARTED
[20:34:15.284] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:17:0) after 7m39s
[20:41:54.310] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:17:0)
[20:41:54.330] Build status: STARTED
[20:41:54.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:18:0) after 5m52s
[20:47:46.401] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:18:0)
[20:47:46.415] Build status: STARTED
[20:47:46.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:19:0) after 4m11s
[20:51:57.456] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:19:0)
[20:51:57.479] Build status: STARTED
[20:51:57.479] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:20:0) after 9m57s
[21:01:54.500] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:20:0)
[21:01:54.516] Build status: STARTED
[21:01:54.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:21:0) after 3m35s
[21:05:29.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:21:0)
[21:05:29.635] Build status: STARTED
[21:05:29.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:22:0) after 8m18s
[21:13:47.660] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:22:0)
[21:13:47.676] Build status: STARTED
[21:13:47.676] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:23:0) after 7m6s
[21:20:53.766] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:23:0)
[21:20:53.781] Build status: STARTED
[21:20:53.781] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:24:0) after 7m23s
[21:28:16.873] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:24:0)
[21:28:16.890] Build status: STARTED
[21:28:16.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:25:0) after 5m26s
[21:33:42.990] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:25:0)
[21:33:43.006] Build status: STARTED
[21:33:43.006] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:26:0) after 6m42s
[21:40:25.036] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:26:0)
[21:40:25.050] Build status: STARTED
[21:40:25.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:27:0) after 9m1s
[21:49:26.135] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:27:0)
[21:49:26.158] Build status: STARTED
[21:49:26.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:28:0) after 6m46s
[21:56:12.185] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:28:0)
[21:56:12.213] Build status: STARTED
[21:56:12.214] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:29:0) after 8m15s
[22:04:27.259] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:29:0)
[22:04:27.285] Build status: STARTED
[22:04:27.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:30:0) after 6m13s
[22:10:40.352] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:30:0)
[22:10:40.373] Build status: STARTED
[22:10:40.373] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:31:0) after 9m1s
[22:19:41.409] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:31:0)
[22:19:41.430] Build status: STARTED
[22:19:41.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:32:0) after 5m46s
[22:25:27.457] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:32:0)
[22:25:27.473] Build status: STARTED
[22:25:27.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:33:0) after 6m19s
[22:31:46.622] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:33:0)
[22:31:46.640] Build status: STARTED
[22:31:46.641] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-full_determinism-e5a0ec7f:8889630627468006896:34:0) after 6m40s
[22:34:48.786] Received PubSub notification, asking Buildbucket for the build status
[22:34:48.877] Build:
{
"id": "8724200565557923409",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-full_determinism"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-31T19:16:51.658742004Z",
"startTime": "2025-01-31T19:17:05.831376127Z",
"endTime": "2025-01-31T22:34:48.302638424Z",
"updateTime": "2025-01-31T22:34:48.302638424Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e3061f299b144acf335b3deafb444d7a2d7f1bf9",
"ref": "refs/heads/main"
}
}
}
[22:34:48.877] Invocation finished in 3h17m58.424539922s with status FAILED