[07:19:14.468] New invocation is queued and will start shortly
[07:19:15.524] Starting the invocation (attempt 1)
[07:19:15.550] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c8ffe3a370721bd48f52ca3c5647b512f03bedd1"
[07:19:15.550] Popped gitiles commit info from properties and tags
[07:19:15.550] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:19:15.550] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:19:15.550] Buildbucket request:
{
"requestId": "8865486384924616512",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865486384924616512",
"job": "chromium-m142/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "87859233af1566a4f414b7f1412c476dbe8a69b6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@87859233af1566a4f414b7f1412c476dbe8a69b6",
"title": "87859233af1566a4f414b7f1412c476dbe8a69b6",
"url": "https://chromium.googlesource.com/chromium/src/+/87859233af1566a4f414b7f1412c476dbe8a69b6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "42f9fc217932ff7af27e87cbe062e91f775be35f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@42f9fc217932ff7af27e87cbe062e91f775be35f",
"title": "42f9fc217932ff7af27e87cbe062e91f775be35f",
"url": "https://chromium.googlesource.com/chromium/src/+/42f9fc217932ff7af27e87cbe062e91f775be35f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e6a2e5ec7cc5c98738056d8cc1fa39c745a944b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@8e6a2e5ec7cc5c98738056d8cc1fa39c745a944b",
"title": "8e6a2e5ec7cc5c98738056d8cc1fa39c745a944b",
"url": "https://chromium.googlesource.com/chromium/src/+/8e6a2e5ec7cc5c98738056d8cc1fa39c745a944b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1bd5dcea6219db28781f3d5ebcd9af8ae2d82a8f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@1bd5dcea6219db28781f3d5ebcd9af8ae2d82a8f",
"title": "1bd5dcea6219db28781f3d5ebcd9af8ae2d82a8f",
"url": "https://chromium.googlesource.com/chromium/src/+/1bd5dcea6219db28781f3d5ebcd9af8ae2d82a8f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e1f650d838d55626aff6f87ba7628f0ca2f09bd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@8e1f650d838d55626aff6f87ba7628f0ca2f09bd",
"title": "8e1f650d838d55626aff6f87ba7628f0ca2f09bd",
"url": "https://chromium.googlesource.com/chromium/src/+/8e1f650d838d55626aff6f87ba7628f0ca2f09bd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c8ffe3a370721bd48f52ca3c5647b512f03bedd1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@c8ffe3a370721bd48f52ca3c5647b512f03bedd1",
"title": "c8ffe3a370721bd48f52ca3c5647b512f03bedd1",
"url": "https://chromium.googlesource.com/chromium/src/+/c8ffe3a370721bd48f52ca3c5647b512f03bedd1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c8ffe3a370721bd48f52ca3c5647b512f03bedd1",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865486384924616512"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:19:16.425] Scheduled build:
{
"id": "8700056322876599233",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 252,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-25T07:19:15.792831213Z",
"updateTime": "2025-10-25T07:19:15.792831213Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c8ffe3a370721bd48f52ca3c5647b512f03bedd1",
"ref": "refs/branch-heads/7444"
}
}
}
[07:19:16.425] Task URL: https://cr-buildbucket.appspot.com/build/8700056322876599233
[07:19:16.425] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:2:0) after 6m29s
[07:19:17.373] Received PubSub notification, asking Buildbucket for the build status
[07:19:17.420] Build status: SCHEDULED
[07:25:45.438] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:2:0)
[07:25:45.470] Build status: SCHEDULED
[07:25:45.470] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:4:0) after 9m40s
[07:35:25.581] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:4:0)
[07:35:25.597] Build status: SCHEDULED
[07:35:25.597] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:5:0) after 7m58s
[07:43:23.677] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:5:0)
[07:43:23.692] Build status: SCHEDULED
[07:43:23.692] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:6:0) after 6m49s
[07:50:12.915] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:6:0)
[07:50:12.938] Build status: SCHEDULED
[07:50:12.938] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:7:0) after 2m28s
[07:52:41.062] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:7:0)
[07:52:41.078] Build status: SCHEDULED
[07:52:41.078] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:8:0) after 8m53s
[07:53:36.349] Received PubSub notification, asking Buildbucket for the build status
[07:53:36.383] Build status: STARTED
[08:01:34.093] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:8:0)
[08:01:34.112] Build status: STARTED
[08:01:34.112] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:10:0) after 9m27s
[08:11:01.129] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:10:0)
[08:11:01.149] Build status: STARTED
[08:11:01.149] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:11:0) after 7m26s
[08:18:27.820] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:11:0)
[08:18:27.839] Build status: STARTED
[08:18:27.839] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:12:0) after 6m51s
[08:25:18.853] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:12:0)
[08:25:18.875] Build status: STARTED
[08:25:18.875] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:13:0) after 4m23s
[08:29:41.861] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:13:0)
[08:29:41.876] Build status: STARTED
[08:29:41.876] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:14:0) after 3m56s
[08:33:37.891] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:14:0)
[08:33:37.924] Build status: STARTED
[08:33:37.924] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:15:0) after 3m35s
[08:37:12.989] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:15:0)
[08:37:13.006] Build status: STARTED
[08:37:13.006] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:16:0) after 4m40s
[08:41:53.466] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:16:0)
[08:41:53.480] Build status: STARTED
[08:41:53.480] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:17:0) after 1m21s
[08:43:14.709] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:17:0)
[08:43:14.742] Build status: STARTED
[08:43:14.742] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:18:0) after 5m1s
[08:48:15.924] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:18:0)
[08:48:15.940] Build status: STARTED
[08:48:15.940] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:19:0) after 2m53s
[08:51:09.015] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:19:0)
[08:51:09.085] Build status: STARTED
[08:51:09.085] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:20:0) after 5m12s
[08:56:21.099] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:20:0)
[08:56:21.117] Build status: STARTED
[08:56:21.117] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:21:0) after 9m37s
[09:05:58.132] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:21:0)
[09:05:58.147] Build status: STARTED
[09:05:58.147] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:22:0) after 7m33s
[09:13:31.202] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:22:0)
[09:13:31.220] Build status: STARTED
[09:13:31.220] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:23:0) after 2m23s
[09:15:54.234] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:23:0)
[09:15:54.318] Build status: STARTED
[09:15:54.318] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:24:0) after 4m19s
[09:20:13.372] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:24:0)
[09:20:13.465] Build status: STARTED
[09:20:13.465] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:25:0) after 6m40s
[09:26:53.706] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:25:0)
[09:26:53.723] Build status: STARTED
[09:26:53.723] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:26:0) after 8m15s
[09:35:09.057] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:26:0)
[09:35:09.099] Build status: STARTED
[09:35:09.099] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:27:0) after 4m1s
[09:39:10.210] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:27:0)
[09:39:10.223] Build status: STARTED
[09:39:10.223] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:28:0) after 6m2s
[09:45:12.236] Handling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:28:0)
[09:45:12.257] Build status: STARTED
[09:45:12.257] Scheduling timer "check-buildbucket-build-status" (chromium-m142/fuchsia-x64-cast-receiver-dbg:8865486384924616512:29:0) after 4m1s
[09:46:00.478] Received PubSub notification, asking Buildbucket for the build status
[09:46:00.496] Build:
{
"id": "8700056322876599233",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 252,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-25T07:19:15.792831213Z",
"startTime": "2025-10-25T07:53:36.171797403Z",
"endTime": "2025-10-25T09:46:00.068558556Z",
"updateTime": "2025-10-25T09:46:00.068558556Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c8ffe3a370721bd48f52ca3c5647b512f03bedd1",
"ref": "refs/branch-heads/7444"
}
}
}
[09:46:00.496] Invocation finished in 2h26m46.039548032s with status SUCCEEDED