[01:59:54.396] New invocation is queued and will start shortly
[01:59:55.524] Starting the invocation (attempt 1)
[01:59:55.545] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/26d85379cd8e4586aee59083e499f999d8968bec"
[01:59:55.545] Popped gitiles commit info from properties and tags
[01:59:55.545] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:59:55.546] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:59:55.546] Buildbucket request:
{
"requestId": "8865053490885666080",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865053490885666080",
"job": "chromium-m143/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "50f13343ecd2d968f5304249b82792aa3e045dcd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@50f13343ecd2d968f5304249b82792aa3e045dcd",
"title": "50f13343ecd2d968f5304249b82792aa3e045dcd",
"url": "https://chromium.googlesource.com/chromium/src/+/50f13343ecd2d968f5304249b82792aa3e045dcd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6a93f1d6db15c73a7b58f3042b0f96d2f71c1772"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@6a93f1d6db15c73a7b58f3042b0f96d2f71c1772",
"title": "6a93f1d6db15c73a7b58f3042b0f96d2f71c1772",
"url": "https://chromium.googlesource.com/chromium/src/+/6a93f1d6db15c73a7b58f3042b0f96d2f71c1772"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7d7943e7b7d0a1d52d909fa3dace82818d7683f3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@7d7943e7b7d0a1d52d909fa3dace82818d7683f3",
"title": "7d7943e7b7d0a1d52d909fa3dace82818d7683f3",
"url": "https://chromium.googlesource.com/chromium/src/+/7d7943e7b7d0a1d52d909fa3dace82818d7683f3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c232248d823a8292bfe64cd3c22380a3206a37eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@c232248d823a8292bfe64cd3c22380a3206a37eb",
"title": "c232248d823a8292bfe64cd3c22380a3206a37eb",
"url": "https://chromium.googlesource.com/chromium/src/+/c232248d823a8292bfe64cd3c22380a3206a37eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "36da782cbf2cb6ba56d4ca26f052c6d2b8e8b1f1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@36da782cbf2cb6ba56d4ca26f052c6d2b8e8b1f1",
"title": "36da782cbf2cb6ba56d4ca26f052c6d2b8e8b1f1",
"url": "https://chromium.googlesource.com/chromium/src/+/36da782cbf2cb6ba56d4ca26f052c6d2b8e8b1f1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e6f34c552647e867543af7bf172ca8d0aed9087"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@2e6f34c552647e867543af7bf172ca8d0aed9087",
"title": "2e6f34c552647e867543af7bf172ca8d0aed9087",
"url": "https://chromium.googlesource.com/chromium/src/+/2e6f34c552647e867543af7bf172ca8d0aed9087"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "50cd106eb16447790c4101a1bba9c599985c884d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@50cd106eb16447790c4101a1bba9c599985c884d",
"title": "50cd106eb16447790c4101a1bba9c599985c884d",
"url": "https://chromium.googlesource.com/chromium/src/+/50cd106eb16447790c4101a1bba9c599985c884d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa08d8f40386dc529e42fb6bf14fb42b948e2ddf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@aa08d8f40386dc529e42fb6bf14fb42b948e2ddf",
"title": "aa08d8f40386dc529e42fb6bf14fb42b948e2ddf",
"url": "https://chromium.googlesource.com/chromium/src/+/aa08d8f40386dc529e42fb6bf14fb42b948e2ddf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c07626d5f55e2ced47dc29d1951c04674ff91ed5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@c07626d5f55e2ced47dc29d1951c04674ff91ed5",
"title": "c07626d5f55e2ced47dc29d1951c04674ff91ed5",
"url": "https://chromium.googlesource.com/chromium/src/+/c07626d5f55e2ced47dc29d1951c04674ff91ed5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "809912f83456d4f00801d48e32ab1cf9e3351310"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@809912f83456d4f00801d48e32ab1cf9e3351310",
"title": "809912f83456d4f00801d48e32ab1cf9e3351310",
"url": "https://chromium.googlesource.com/chromium/src/+/809912f83456d4f00801d48e32ab1cf9e3351310"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6d39d94b2affbe7056c4d751be582f36021b95ee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@6d39d94b2affbe7056c4d751be582f36021b95ee",
"title": "6d39d94b2affbe7056c4d751be582f36021b95ee",
"url": "https://chromium.googlesource.com/chromium/src/+/6d39d94b2affbe7056c4d751be582f36021b95ee"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e45ad5ff09bf8f2a6d9637a752e66ca140fd7599"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e45ad5ff09bf8f2a6d9637a752e66ca140fd7599",
"title": "e45ad5ff09bf8f2a6d9637a752e66ca140fd7599",
"url": "https://chromium.googlesource.com/chromium/src/+/e45ad5ff09bf8f2a6d9637a752e66ca140fd7599"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "58075fae76b68195d4a210875f05df72dec77ec3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@58075fae76b68195d4a210875f05df72dec77ec3",
"title": "58075fae76b68195d4a210875f05df72dec77ec3",
"url": "https://chromium.googlesource.com/chromium/src/+/58075fae76b68195d4a210875f05df72dec77ec3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "26d85379cd8e4586aee59083e499f999d8968bec"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@26d85379cd8e4586aee59083e499f999d8968bec",
"title": "26d85379cd8e4586aee59083e499f999d8968bec",
"url": "https://chromium.googlesource.com/chromium/src/+/26d85379cd8e4586aee59083e499f999d8968bec"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "26d85379cd8e4586aee59083e499f999d8968bec",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865053490885666080"
},
{
"key": "scheduler_job_id",
"value": "chromium-m143/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:59:56.000] Scheduled build:
{
"id": "8699623428957721713",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 15,
"createdBy": "project:chromium-m143",
"createTime": "2025-10-30T01:59:55.604723726Z",
"updateTime": "2025-10-30T01:59:55.604723726Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "26d85379cd8e4586aee59083e499f999d8968bec",
"ref": "refs/branch-heads/7499"
}
}
}
[01:59:56.000] Task URL: https://cr-buildbucket.appspot.com/build/8699623428957721713
[01:59:56.000] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:2:0) after 7m46s
[01:59:56.952] Received PubSub notification, asking Buildbucket for the build status
[01:59:57.001] Build status: SCHEDULED
[02:07:42.142] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:2:0)
[02:07:42.167] Build status: SCHEDULED
[02:07:42.167] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:4:0) after 4m36s
[02:12:18.280] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:4:0)
[02:12:18.310] Build status: SCHEDULED
[02:12:18.310] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:5:0) after 1m9s
[02:13:27.520] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:5:0)
[02:13:27.538] Build status: SCHEDULED
[02:13:27.538] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:6:0) after 6m0s
[02:19:27.603] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:6:0)
[02:19:27.626] Build status: SCHEDULED
[02:19:27.626] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:7:0) after 9m6s
[02:28:33.663] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:7:0)
[02:28:33.677] Build status: SCHEDULED
[02:28:33.677] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:8:0) after 4m0s
[02:32:33.665] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:8:0)
[02:32:33.717] Build status: SCHEDULED
[02:32:33.717] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:9:0) after 5m38s
[02:38:11.772] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:9:0)
[02:38:11.797] Build status: SCHEDULED
[02:38:11.797] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:10:0) after 4m50s
[02:41:16.712] Received PubSub notification, asking Buildbucket for the build status
[02:41:16.764] Build status: STARTED
[02:43:01.831] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:10:0)
[02:43:01.857] Build status: STARTED
[02:43:01.857] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:12:0) after 6m0s
[02:49:01.870] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:12:0)
[02:49:01.885] Build status: STARTED
[02:49:01.885] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:13:0) after 4m3s
[02:53:04.954] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:13:0)
[02:53:05.092] Build status: STARTED
[02:53:05.092] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:14:0) after 4m11s
[02:57:16.116] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:14:0)
[02:57:16.170] Build status: STARTED
[02:57:16.170] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:15:0) after 3m41s
[03:00:57.188] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:15:0)
[03:00:57.203] Build status: STARTED
[03:00:57.203] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:16:0) after 1m39s
[03:02:36.318] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:16:0)
[03:02:36.333] Build status: STARTED
[03:02:36.334] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:17:0) after 3m33s
[03:06:09.511] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:17:0)
[03:06:09.527] Build status: STARTED
[03:06:09.527] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:18:0) after 1m9s
[03:07:18.549] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:18:0)
[03:07:18.722] Build status: STARTED
[03:07:18.722] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:19:0) after 1m28s
[03:08:46.786] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:19:0)
[03:08:46.804] Build status: STARTED
[03:08:46.804] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:20:0) after 3m39s
[03:12:25.828] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:20:0)
[03:12:25.862] Build status: STARTED
[03:12:25.862] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:21:0) after 3m46s
[03:16:11.892] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:21:0)
[03:16:11.908] Build status: STARTED
[03:16:11.908] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:22:0) after 6m20s
[03:22:31.932] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:22:0)
[03:22:31.953] Build status: STARTED
[03:22:31.953] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:23:0) after 4m36s
[03:27:07.974] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:23:0)
[03:27:07.993] Build status: STARTED
[03:27:07.993] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:24:0) after 6m41s
[03:33:49.010] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:24:0)
[03:33:49.030] Build status: STARTED
[03:33:49.030] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:25:0) after 5m41s
[03:39:30.048] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:25:0)
[03:39:30.070] Build status: STARTED
[03:39:30.070] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:26:0) after 2m50s
[03:42:20.075] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:26:0)
[03:42:20.102] Build status: STARTED
[03:42:20.103] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:27:0) after 5m40s
[03:48:00.138] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:27:0)
[03:48:00.164] Build status: STARTED
[03:48:00.165] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:28:0) after 8m15s
[03:56:15.222] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:28:0)
[03:56:15.236] Build status: STARTED
[03:56:15.236] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:29:0) after 4m14s
[04:00:29.303] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:29:0)
[04:00:29.321] Build status: STARTED
[04:00:29.321] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:30:0) after 4m22s
[04:04:51.337] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:30:0)
[04:04:51.352] Build status: STARTED
[04:04:51.352] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:31:0) after 3m33s
[04:08:24.476] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:31:0)
[04:08:24.495] Build status: STARTED
[04:08:24.495] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:32:0) after 5m13s
[04:13:37.521] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:32:0)
[04:13:37.535] Build status: STARTED
[04:13:37.535] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:33:0) after 9m30s
[04:23:07.554] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:33:0)
[04:23:07.568] Build status: STARTED
[04:23:07.568] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:34:0) after 8m56s
[04:32:03.685] Handling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:34:0)
[04:32:03.735] Build status: STARTED
[04:32:03.735] Scheduling timer "check-buildbucket-build-status" (chromium-m143/fuchsia-x64-cast-receiver-dbg:8865053490885666080:35:0) after 6m51s
[04:33:53.619] Received PubSub notification, asking Buildbucket for the build status
[04:33:53.636] Build:
{
"id": "8699623428957721713",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 15,
"createdBy": "project:chromium-m143",
"createTime": "2025-10-30T01:59:55.604723726Z",
"startTime": "2025-10-30T02:41:16.442056860Z",
"endTime": "2025-10-30T04:33:34.695989514Z",
"updateTime": "2025-10-30T04:33:34.695989514Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "26d85379cd8e4586aee59083e499f999d8968bec",
"ref": "refs/branch-heads/7499"
}
}
}
[04:33:53.636] Invocation finished in 2h33m59.254030851s with status SUCCEEDED