[01:24:39.375] New invocation is queued and will start shortly
[01:24:40.501] Starting the invocation (attempt 1)
[01:24:40.534] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/05b56e08e2621421d621d00370cbd22977b4f5f9"
[01:24:40.534] Popped gitiles commit info from properties and tags
[01:24:40.534] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:24:40.535] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:24:40.535] Buildbucket request:
{
"requestId": "8861703620882620432",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8861703620882620432",
"job": "chromium-m144/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "956644f949bbb97280ced8c94dd3c51d91959f7a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@956644f949bbb97280ced8c94dd3c51d91959f7a",
"title": "956644f949bbb97280ced8c94dd3c51d91959f7a",
"url": "https://chromium.googlesource.com/chromium/src/+/956644f949bbb97280ced8c94dd3c51d91959f7a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "579e7465263a80dcae2cb8b84b6e5ccb68555263"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@579e7465263a80dcae2cb8b84b6e5ccb68555263",
"title": "579e7465263a80dcae2cb8b84b6e5ccb68555263",
"url": "https://chromium.googlesource.com/chromium/src/+/579e7465263a80dcae2cb8b84b6e5ccb68555263"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5358900f6789e9aaf17a7fdd1eefc9230c3f30b4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5358900f6789e9aaf17a7fdd1eefc9230c3f30b4",
"title": "5358900f6789e9aaf17a7fdd1eefc9230c3f30b4",
"url": "https://chromium.googlesource.com/chromium/src/+/5358900f6789e9aaf17a7fdd1eefc9230c3f30b4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ba1c3bcd4ec36270808a87c1eb214a0d05683ca9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ba1c3bcd4ec36270808a87c1eb214a0d05683ca9",
"title": "ba1c3bcd4ec36270808a87c1eb214a0d05683ca9",
"url": "https://chromium.googlesource.com/chromium/src/+/ba1c3bcd4ec36270808a87c1eb214a0d05683ca9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "05b56e08e2621421d621d00370cbd22977b4f5f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@05b56e08e2621421d621d00370cbd22977b4f5f9",
"title": "05b56e08e2621421d621d00370cbd22977b4f5f9",
"url": "https://chromium.googlesource.com/chromium/src/+/05b56e08e2621421d621d00370cbd22977b4f5f9"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "05b56e08e2621421d621d00370cbd22977b4f5f9",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861703620882620432"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/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:24:41.090] Scheduled build:
{
"id": "8696273558941588353",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 45,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-06T01:24:40.602390305Z",
"updateTime": "2025-12-06T01:24:40.602390305Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "05b56e08e2621421d621d00370cbd22977b4f5f9",
"ref": "refs/branch-heads/7559"
}
}
}
[01:24:41.090] Task URL: https://cr-buildbucket.appspot.com/build/8696273558941588353
[01:24:41.090] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:2:0) after 7m59s
[01:24:44.280] Received PubSub notification, asking Buildbucket for the build status
[01:24:44.305] Build status: SCHEDULED
[01:32:40.104] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:2:0)
[01:32:40.166] Build status: SCHEDULED
[01:32:40.166] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:4:0) after 5m30s
[01:38:10.204] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:4:0)
[01:38:10.275] Build status: SCHEDULED
[01:38:10.275] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:5:0) after 1m56s
[01:40:06.338] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:5:0)
[01:40:06.364] Build status: SCHEDULED
[01:40:06.364] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:6:0) after 1m29s
[01:41:35.432] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:6:0)
[01:41:35.460] Build status: SCHEDULED
[01:41:35.461] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:7:0) after 5m47s
[01:46:55.850] Received PubSub notification, asking Buildbucket for the build status
[01:46:55.870] Build status: STARTED
[01:47:22.562] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:7:0)
[01:47:22.586] Build status: STARTED
[01:47:22.586] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:9:0) after 6m11s
[01:53:33.598] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:9:0)
[01:53:33.615] Build status: STARTED
[01:53:33.615] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:10:0) after 8m35s
[02:02:08.736] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:10:0)
[02:02:08.784] Build status: STARTED
[02:02:08.785] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:11:0) after 4m19s
[02:06:27.805] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:11:0)
[02:06:27.834] Build status: STARTED
[02:06:27.834] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:12:0) after 7m30s
[02:13:57.848] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:12:0)
[02:13:57.861] Build status: STARTED
[02:13:57.862] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:13:0) after 4m52s
[02:18:49.893] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:13:0)
[02:18:49.919] Build status: STARTED
[02:18:49.919] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:14:0) after 6m15s
[02:25:04.930] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:14:0)
[02:25:05.015] Build status: STARTED
[02:25:05.015] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:15:0) after 5m59s
[02:31:04.114] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:15:0)
[02:31:04.152] Build status: STARTED
[02:31:04.152] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:16:0) after 7m23s
[02:38:27.230] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:16:0)
[02:38:27.248] Build status: STARTED
[02:38:27.248] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:17:0) after 9m13s
[02:47:40.252] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:17:0)
[02:47:40.280] Build status: STARTED
[02:47:40.281] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:18:0) after 8m44s
[02:56:24.332] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:18:0)
[02:56:24.351] Build status: STARTED
[02:56:24.352] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:19:0) after 7m59s
[03:04:23.365] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:19:0)
[03:04:23.387] Build status: STARTED
[03:04:23.388] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:20:0) after 4m6s
[03:08:29.452] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:20:0)
[03:08:29.466] Build status: STARTED
[03:08:29.466] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:21:0) after 9m20s
[03:17:49.488] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:21:0)
[03:17:49.505] Build status: STARTED
[03:17:49.505] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:22:0) after 6m12s
[03:24:01.517] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:22:0)
[03:24:01.540] Build status: STARTED
[03:24:01.540] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:23:0) after 9m15s
[03:33:16.604] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:23:0)
[03:33:16.622] Build status: STARTED
[03:33:16.622] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:24:0) after 6m24s
[03:39:40.667] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:24:0)
[03:39:40.690] Build status: STARTED
[03:39:40.690] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:25:0) after 4m13s
[03:43:53.708] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:25:0)
[03:43:53.727] Build status: STARTED
[03:43:53.727] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:26:0) after 4m19s
[03:48:12.792] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:26:0)
[03:48:12.870] Build status: STARTED
[03:48:12.870] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:27:0) after 1m9s
[03:49:21.911] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:27:0)
[03:49:21.929] Build status: STARTED
[03:49:21.929] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:28:0) after 8m14s
[03:57:35.943] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:28:0)
[03:57:36.261] Build status: STARTED
[03:57:36.261] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:29:0) after 4m37s
[04:02:13.288] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:29:0)
[04:02:13.306] Build status: STARTED
[04:02:13.306] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:30:0) after 2m40s
[04:04:53.385] Handling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:30:0)
[04:04:53.411] Build status: STARTED
[04:04:53.411] Scheduling timer "check-buildbucket-build-status" (chromium-m144/fuchsia-x64-cast-receiver-dbg:8861703620882620432:31:0) after 9m20s
[04:12:39.758] Received PubSub notification, asking Buildbucket for the build status
[04:12:39.780] Build:
{
"id": "8696273558941588353",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 45,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-06T01:24:40.602390305Z",
"startTime": "2025-12-06T01:46:12.200449276Z",
"endTime": "2025-12-06T04:12:39.115808610Z",
"updateTime": "2025-12-06T04:12:39.115808610Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "05b56e08e2621421d621d00370cbd22977b4f5f9",
"ref": "refs/branch-heads/7559"
}
}
}
[04:12:39.780] Invocation finished in 2h48m0.412566528s with status SUCCEEDED