[01:47:04.543] New invocation is queued and will start shortly
[01:47:06.272] Starting the invocation (attempt 1)
[01:47:06.295] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/d7e79001191b0444935c79c7765e0eb565e727aa"
[01:47:06.295] Popped gitiles commit info from properties and tags
[01:47:06.295] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:47:06.295] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:47:06.296] Buildbucket request:
{
"requestId": "8836425656744055072",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8836425656744055072",
"job": "fuchsia/global.ci-core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e026470dbcf00d65ed3f14f9205183ca79ac44ad"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e026470dbcf00d65ed3f14f9205183ca79ac44ad",
"title": "e026470dbcf00d65ed3f14f9205183ca79ac44ad",
"url": "https://fuchsia.googlesource.com/integration/+/e026470dbcf00d65ed3f14f9205183ca79ac44ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9970d1f24dba7d26a633554d6f38e886250502c2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9970d1f24dba7d26a633554d6f38e886250502c2",
"title": "9970d1f24dba7d26a633554d6f38e886250502c2",
"url": "https://fuchsia.googlesource.com/integration/+/9970d1f24dba7d26a633554d6f38e886250502c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "227f75bdfce0a72bb3b8c88cb332a7e62dd6ef15"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@227f75bdfce0a72bb3b8c88cb332a7e62dd6ef15",
"title": "227f75bdfce0a72bb3b8c88cb332a7e62dd6ef15",
"url": "https://fuchsia.googlesource.com/integration/+/227f75bdfce0a72bb3b8c88cb332a7e62dd6ef15"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ba59d1d084749a8a9159ce1933fc61c3cc53341c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ba59d1d084749a8a9159ce1933fc61c3cc53341c",
"title": "ba59d1d084749a8a9159ce1933fc61c3cc53341c",
"url": "https://fuchsia.googlesource.com/integration/+/ba59d1d084749a8a9159ce1933fc61c3cc53341c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b0a26c540685c5610f19c68f55bda9c56a21b960"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b0a26c540685c5610f19c68f55bda9c56a21b960",
"title": "b0a26c540685c5610f19c68f55bda9c56a21b960",
"url": "https://fuchsia.googlesource.com/integration/+/b0a26c540685c5610f19c68f55bda9c56a21b960"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "445663513d3a5fc02bf65c9e9e90a8bbb06967ed"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@445663513d3a5fc02bf65c9e9e90a8bbb06967ed",
"title": "445663513d3a5fc02bf65c9e9e90a8bbb06967ed",
"url": "https://fuchsia.googlesource.com/integration/+/445663513d3a5fc02bf65c9e9e90a8bbb06967ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "33913ac6fcf0df9680b5212189f5fa92dacbb30a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@33913ac6fcf0df9680b5212189f5fa92dacbb30a",
"title": "33913ac6fcf0df9680b5212189f5fa92dacbb30a",
"url": "https://fuchsia.googlesource.com/integration/+/33913ac6fcf0df9680b5212189f5fa92dacbb30a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "393a8de9bc0739eb393401ac2a4c79469db7ad2b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@393a8de9bc0739eb393401ac2a4c79469db7ad2b",
"title": "393a8de9bc0739eb393401ac2a4c79469db7ad2b",
"url": "https://fuchsia.googlesource.com/integration/+/393a8de9bc0739eb393401ac2a4c79469db7ad2b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3658e263b6c789c9806be607c1547431df2f53c2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3658e263b6c789c9806be607c1547431df2f53c2",
"title": "3658e263b6c789c9806be607c1547431df2f53c2",
"url": "https://fuchsia.googlesource.com/integration/+/3658e263b6c789c9806be607c1547431df2f53c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d7e79001191b0444935c79c7765e0eb565e727aa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d7e79001191b0444935c79c7765e0eb565e727aa",
"title": "d7e79001191b0444935c79c7765e0eb565e727aa",
"url": "https://fuchsia.googlesource.com/integration/+/d7e79001191b0444935c79c7765e0eb565e727aa"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d7e79001191b0444935c79c7765e0eb565e727aa",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8836425656744055072"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:47:06.785] Scheduled build:
{
"id": "8670995594166170145",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2026-09-11T01:47:06.379197141Z",
"updateTime": "2026-09-11T01:47:06.379197141Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d7e79001191b0444935c79c7765e0eb565e727aa",
"ref": "refs/heads/main"
}
}
}
[01:47:06.785] Task URL: https://cr-buildbucket.appspot.com/build/8670995594166170145
[01:47:06.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:2:0) after 7m16s
[01:47:23.822] Received PubSub notification, asking Buildbucket for the build status
[01:47:23.841] Build status: STARTED
[01:47:27.734] Received PubSub notification, asking Buildbucket for the build status
[01:47:27.821] Build status: STARTED
[01:54:22.813] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:2:0)
[01:54:22.834] Build status: STARTED
[01:54:22.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:5:0) after 6m23s
[02:00:46.026] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:5:0)
[02:00:46.051] Build status: STARTED
[02:00:46.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:6:0) after 5m24s
[02:06:10.267] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:6:0)
[02:06:10.289] Build status: STARTED
[02:06:10.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:7:0) after 1m18s
[02:07:28.869] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:7:0)
[02:07:28.951] Build status: STARTED
[02:07:28.951] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:8:0) after 5m20s
[02:12:48.999] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:8:0)
[02:12:49.022] Build status: STARTED
[02:12:49.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:9:0) after 5m16s
[02:18:05.051] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:9:0)
[02:18:05.091] Build status: STARTED
[02:18:05.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:10:0) after 4m46s
[02:22:51.181] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:10:0)
[02:22:51.224] Build status: STARTED
[02:22:51.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:11:0) after 1m29s
[02:24:20.268] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:11:0)
[02:24:20.287] Build status: STARTED
[02:24:20.287] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:12:0) after 9m42s
[02:34:02.364] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:12:0)
[02:34:02.467] Build status: STARTED
[02:34:02.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:13:0) after 4m53s
[02:38:55.611] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:13:0)
[02:38:55.635] Build status: STARTED
[02:38:55.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:14:0) after 5m22s
[02:44:17.690] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:14:0)
[02:44:17.719] Build status: STARTED
[02:44:17.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:15:0) after 2m49s
[02:47:06.733] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:15:0)
[02:47:06.754] Build status: STARTED
[02:47:06.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:16:0) after 2m50s
[02:49:56.772] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:16:0)
[02:49:56.812] Build status: STARTED
[02:49:56.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:17:0) after 2m59s
[02:52:55.887] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:17:0)
[02:52:55.907] Build status: STARTED
[02:52:55.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:18:0) after 4m32s
[02:57:27.953] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:18:0)
[02:57:27.973] Build status: STARTED
[02:57:27.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:19:0) after 6m40s
[03:04:08.215] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:19:0)
[03:04:08.235] Build status: STARTED
[03:04:08.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:20:0) after 1m46s
[03:05:54.208] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:20:0)
[03:05:54.227] Build status: STARTED
[03:05:54.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:21:0) after 5m10s
[03:11:04.343] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:21:0)
[03:11:04.362] Build status: STARTED
[03:11:04.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:22:0) after 2m7s
[03:13:11.354] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:22:0)
[03:13:11.376] Build status: STARTED
[03:13:11.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-core.x64-debug-e5a0ec7f:8836425656744055072:23:0) after 6m58s
[03:18:18.426] Received PubSub notification, asking Buildbucket for the build status
[03:18:18.448] Build:
{
"id": "8670995594166170145",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2026-09-11T01:47:06.379197141Z",
"startTime": "2026-09-11T01:47:12.957281371Z",
"endTime": "2026-09-11T03:18:17.855279337Z",
"updateTime": "2026-09-11T03:18:17.855279337Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "d7e79001191b0444935c79c7765e0eb565e727aa",
"ref": "refs/heads/main"
}
}
}
[03:18:18.448] Invocation finished in 1h31m13.9111961s with status SUCCEEDED