[12:00:55.379] New invocation is queued and will start shortly
[12:00:56.910] Starting the invocation (attempt 1)
[12:00:56.941] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/2fafafd3a077a2882225874a3d5c90833487c82e"
[12:00:56.941] Popped gitiles commit info from properties and tags
[12:00:56.941] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:00:56.941] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:00:56.942] Buildbucket request:
{
"requestId": "8897358795206137840",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan-cxx_determinism-build_only"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8897358795206137840",
"job": "fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7ee4427f5cd5828df5f3556ad77eafea7b786950"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7ee4427f5cd5828df5f3556ad77eafea7b786950",
"title": "7ee4427f5cd5828df5f3556ad77eafea7b786950",
"url": "https://fuchsia.googlesource.com/integration/+/7ee4427f5cd5828df5f3556ad77eafea7b786950"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a901736e0857f32ab6761accefa9de413af07d0b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a901736e0857f32ab6761accefa9de413af07d0b",
"title": "a901736e0857f32ab6761accefa9de413af07d0b",
"url": "https://fuchsia.googlesource.com/integration/+/a901736e0857f32ab6761accefa9de413af07d0b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d04192e227145ee90f342d9f95427b4f76ebb233"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d04192e227145ee90f342d9f95427b4f76ebb233",
"title": "d04192e227145ee90f342d9f95427b4f76ebb233",
"url": "https://fuchsia.googlesource.com/integration/+/d04192e227145ee90f342d9f95427b4f76ebb233"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "67919a6519088b0991b30aa1379ac1ad31d40a05"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@67919a6519088b0991b30aa1379ac1ad31d40a05",
"title": "67919a6519088b0991b30aa1379ac1ad31d40a05",
"url": "https://fuchsia.googlesource.com/integration/+/67919a6519088b0991b30aa1379ac1ad31d40a05"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "76dee8b23b76a3fc39b298388a9f47e6ec06c706"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@76dee8b23b76a3fc39b298388a9f47e6ec06c706",
"title": "76dee8b23b76a3fc39b298388a9f47e6ec06c706",
"url": "https://fuchsia.googlesource.com/integration/+/76dee8b23b76a3fc39b298388a9f47e6ec06c706"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fcdff8b670a691f5eb905bb0ccfb0a4da2f09e63"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fcdff8b670a691f5eb905bb0ccfb0a4da2f09e63",
"title": "fcdff8b670a691f5eb905bb0ccfb0a4da2f09e63",
"url": "https://fuchsia.googlesource.com/integration/+/fcdff8b670a691f5eb905bb0ccfb0a4da2f09e63"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8aa7f90219b120a9951295e5ccc34f912410c548"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8aa7f90219b120a9951295e5ccc34f912410c548",
"title": "8aa7f90219b120a9951295e5ccc34f912410c548",
"url": "https://fuchsia.googlesource.com/integration/+/8aa7f90219b120a9951295e5ccc34f912410c548"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f04807b36c5c423777b3de8a99ec00f03ad0c24b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f04807b36c5c423777b3de8a99ec00f03ad0c24b",
"title": "f04807b36c5c423777b3de8a99ec00f03ad0c24b",
"url": "https://fuchsia.googlesource.com/integration/+/f04807b36c5c423777b3de8a99ec00f03ad0c24b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2fafafd3a077a2882225874a3d5c90833487c82e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2fafafd3a077a2882225874a3d5c90833487c82e",
"title": "2fafafd3a077a2882225874a3d5c90833487c82e",
"url": "https://fuchsia.googlesource.com/integration/+/2fafafd3a077a2882225874a3d5c90833487c82e"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2fafafd3a077a2882225874a3d5c90833487c82e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897358795206137840"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:00:57.382] Scheduled build:
{
"id": "8731928732808957361",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan-cxx_determinism-build_only"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-07T12:00:57.038506946Z",
"updateTime": "2024-11-07T12:00:57.038506946Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2fafafd3a077a2882225874a3d5c90833487c82e",
"ref": "refs/heads/main"
}
}
}
[12:00:57.382] Task URL: https://cr-buildbucket.appspot.com/build/8731928732808957361
[12:00:57.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:2:0) after 4m47s
[12:00:58.368] Received PubSub notification, asking Buildbucket for the build status
[12:00:58.385] Build status: SCHEDULED
[12:01:02.095] Received PubSub notification, asking Buildbucket for the build status
[12:01:02.135] Build status: STARTED
[12:05:44.363] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:2:0)
[12:05:44.384] Build status: STARTED
[12:05:44.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:5:0) after 8m31s
[12:14:15.497] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:5:0)
[12:14:15.528] Build status: STARTED
[12:14:15.528] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:6:0) after 9m37s
[12:23:52.546] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:6:0)
[12:23:52.573] Build status: STARTED
[12:23:52.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:7:0) after 2m55s
[12:26:47.569] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:7:0)
[12:26:47.613] Build status: STARTED
[12:26:47.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:8:0) after 5m8s
[12:31:55.633] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:8:0)
[12:31:55.652] Build status: STARTED
[12:31:55.652] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:9:0) after 5m46s
[12:37:41.718] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:9:0)
[12:37:41.751] Build status: STARTED
[12:37:41.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:10:0) after 3m59s
[12:41:40.766] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:10:0)
[12:41:40.782] Build status: STARTED
[12:41:40.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:11:0) after 2m1s
[12:43:41.799] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:11:0)
[12:43:41.831] Build status: STARTED
[12:43:41.831] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:12:0) after 1m23s
[12:45:04.850] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:12:0)
[12:45:04.875] Build status: STARTED
[12:45:04.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:13:0) after 7m57s
[12:53:01.896] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:13:0)
[12:53:01.918] Build status: STARTED
[12:53:01.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:14:0) after 5m26s
[12:58:27.956] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:14:0)
[12:58:27.974] Build status: STARTED
[12:58:27.974] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:15:0) after 4m4s
[13:02:32.213] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:15:0)
[13:02:32.232] Build status: STARTED
[13:02:32.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:16:0) after 4m57s
[13:07:29.300] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:16:0)
[13:07:29.321] Build status: STARTED
[13:07:29.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:17:0) after 5m9s
[13:12:38.337] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:17:0)
[13:12:38.355] Build status: STARTED
[13:12:38.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:18:0) after 2m55s
[13:15:33.480] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:18:0)
[13:15:33.497] Build status: STARTED
[13:15:33.497] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:19:0) after 9m14s
[13:24:47.725] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:19:0)
[13:24:47.741] Build status: STARTED
[13:24:47.741] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:20:0) after 4m46s
[13:29:34.151] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:20:0)
[13:29:34.171] Build status: STARTED
[13:29:34.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:21:0) after 6m52s
[13:36:26.188] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:21:0)
[13:36:26.208] Build status: STARTED
[13:36:26.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:22:0) after 9m39s
[13:46:05.291] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:22:0)
[13:46:05.357] Build status: STARTED
[13:46:05.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:23:0) after 9m33s
[13:55:39.083] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:23:0)
[13:55:39.101] Build status: STARTED
[13:55:39.101] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:24:0) after 2m14s
[13:57:53.167] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:24:0)
[13:57:53.190] Build status: STARTED
[13:57:53.190] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:25:0) after 9m6s
[14:06:59.258] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:25:0)
[14:06:59.327] Build status: STARTED
[14:06:59.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:26:0) after 9m9s
[14:16:08.709] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:26:0)
[14:16:08.737] Build status: STARTED
[14:16:08.737] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:27:0) after 6m10s
[14:22:18.857] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:27:0)
[14:22:18.877] Build status: STARTED
[14:22:18.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:28:0) after 7m6s
[14:29:24.959] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:28:0)
[14:29:24.978] Build status: STARTED
[14:29:24.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:29:0) after 7m39s
[14:37:04.293] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:29:0)
[14:37:04.314] Build status: STARTED
[14:37:04.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:30:0) after 7m47s
[14:44:51.375] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:30:0)
[14:44:51.400] Build status: STARTED
[14:44:51.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:31:0) after 9m14s
[14:54:05.421] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:31:0)
[14:54:05.448] Build status: STARTED
[14:54:05.448] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:32:0) after 6m16s
[15:00:21.469] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:32:0)
[15:00:21.495] Build status: STARTED
[15:00:21.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:33:0) after 6m43s
[15:07:04.654] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:33:0)
[15:07:04.734] Build status: STARTED
[15:07:04.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:34:0) after 7m42s
[15:14:46.750] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:34:0)
[15:14:46.765] Build status: STARTED
[15:14:46.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:35:0) after 5m57s
[15:20:43.839] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:35:0)
[15:20:43.859] Build status: STARTED
[15:20:43.859] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:36:0) after 1m52s
[15:22:35.879] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:36:0)
[15:22:35.899] Build status: STARTED
[15:22:35.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:37:0) after 7m24s
[15:29:59.932] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:37:0)
[15:29:59.958] Build status: STARTED
[15:29:59.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8897358795206137840:38:0) after 7m10s
[15:35:38.459] Received PubSub notification, asking Buildbucket for the build status
[15:35:38.502] Build:
{
"id": "8731928732808957361",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan-cxx_determinism-build_only"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-07T12:00:57.038506946Z",
"startTime": "2024-11-07T12:01:01.567670092Z",
"endTime": "2024-11-07T15:35:38.117021923Z",
"updateTime": "2024-11-07T15:35:38.117021923Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "2fafafd3a077a2882225874a3d5c90833487c82e",
"ref": "refs/heads/main"
}
}
}
[15:35:38.502] Invocation finished in 3h34m43.133804291s with status SUCCEEDED