[07:02:30.923] New invocation is queued and will start shortly
[07:02:32.151] Starting the invocation (attempt 1)
[07:02:32.179] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c1c385f8655056a5e11961050dd9d54d229b072"
[07:02:32.179] Popped gitiles commit info from properties and tags
[07:02:32.179] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:02:32.179] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:02:32.179] Buildbucket request:
{
"requestId": "8875271909584240000",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8875271909584240000",
"job": "chromium-m137/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f1f59861586ad6b8029f3a904ab2bb8c8333bf7e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@f1f59861586ad6b8029f3a904ab2bb8c8333bf7e",
"title": "f1f59861586ad6b8029f3a904ab2bb8c8333bf7e",
"url": "https://chromium.googlesource.com/chromium/src/+/f1f59861586ad6b8029f3a904ab2bb8c8333bf7e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "25dd3a7eefddc8cb2a58a0815c06846fc9cb5744"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@25dd3a7eefddc8cb2a58a0815c06846fc9cb5744",
"title": "25dd3a7eefddc8cb2a58a0815c06846fc9cb5744",
"url": "https://chromium.googlesource.com/chromium/src/+/25dd3a7eefddc8cb2a58a0815c06846fc9cb5744"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "25dca187f9a24e6f0f6c7b4900998c9fb8c2da19"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@25dca187f9a24e6f0f6c7b4900998c9fb8c2da19",
"title": "25dca187f9a24e6f0f6c7b4900998c9fb8c2da19",
"url": "https://chromium.googlesource.com/chromium/src/+/25dca187f9a24e6f0f6c7b4900998c9fb8c2da19"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4c1c385f8655056a5e11961050dd9d54d229b072"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@4c1c385f8655056a5e11961050dd9d54d229b072",
"title": "4c1c385f8655056a5e11961050dd9d54d229b072",
"url": "https://chromium.googlesource.com/chromium/src/+/4c1c385f8655056a5e11961050dd9d54d229b072"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4c1c385f8655056a5e11961050dd9d54d229b072",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8875271909584240000"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/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:02:32.753] Scheduled build:
{
"id": "8709841847518249729",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 719,
"createdBy": "project:chromium-m137",
"createTime": "2025-07-09T07:02:32.269898641Z",
"updateTime": "2025-07-09T07:02:32.269898641Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4c1c385f8655056a5e11961050dd9d54d229b072",
"ref": "refs/branch-heads/7151"
}
}
}
[07:02:32.753] Task URL: https://cr-buildbucket.appspot.com/build/8709841847518249729
[07:02:32.753] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:2:0) after 2m6s
[07:02:33.746] Received PubSub notification, asking Buildbucket for the build status
[07:02:33.776] Build status: SCHEDULED
[07:04:38.778] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:2:0)
[07:04:38.813] Build status: SCHEDULED
[07:04:38.813] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:4:0) after 6m52s
[07:11:30.838] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:4:0)
[07:11:30.998] Build status: SCHEDULED
[07:11:30.998] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:5:0) after 1m22s
[07:12:53.019] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:5:0)
[07:12:53.047] Build status: SCHEDULED
[07:12:53.047] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:6:0) after 4m47s
[07:17:40.169] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:6:0)
[07:17:40.199] Build status: SCHEDULED
[07:17:40.199] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:7:0) after 6m49s
[07:24:29.335] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:7:0)
[07:24:29.416] Build status: SCHEDULED
[07:24:29.416] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:8:0) after 1m58s
[07:26:27.536] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:8:0)
[07:26:27.569] Build status: SCHEDULED
[07:26:27.569] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:9:0) after 8m23s
[07:26:31.079] Received PubSub notification, asking Buildbucket for the build status
[07:26:31.109] Build status: STARTED
[07:34:50.586] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:9:0)
[07:34:50.621] Build status: STARTED
[07:34:50.621] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:11:0) after 4m42s
[07:39:32.649] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:11:0)
[07:39:32.681] Build status: STARTED
[07:39:32.681] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:12:0) after 4m51s
[07:44:24.093] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:12:0)
[07:44:24.123] Build status: STARTED
[07:44:24.123] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:13:0) after 6m14s
[07:50:38.163] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:13:0)
[07:50:38.189] Build status: STARTED
[07:50:38.189] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:14:0) after 2m39s
[07:53:17.411] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:14:0)
[07:53:17.431] Build status: STARTED
[07:53:17.431] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:15:0) after 1m36s
[07:54:53.561] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:15:0)
[07:54:53.586] Build status: STARTED
[07:54:53.586] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:16:0) after 6m52s
[08:01:45.893] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:16:0)
[08:01:45.987] Build status: STARTED
[08:01:45.987] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:17:0) after 6m46s
[08:08:32.036] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:17:0)
[08:08:32.057] Build status: STARTED
[08:08:32.057] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:18:0) after 1m8s
[08:09:40.085] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:18:0)
[08:09:40.104] Build status: STARTED
[08:09:40.104] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:19:0) after 5m45s
[08:15:25.122] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:19:0)
[08:15:25.170] Build status: STARTED
[08:15:25.170] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:20:0) after 7m43s
[08:23:08.195] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:20:0)
[08:23:08.215] Build status: STARTED
[08:23:08.215] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:21:0) after 1m16s
[08:24:24.470] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:21:0)
[08:24:24.495] Build status: STARTED
[08:24:24.495] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:22:0) after 8m34s
[08:32:58.477] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:22:0)
[08:32:58.506] Build status: STARTED
[08:32:58.506] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:23:0) after 9m36s
[08:42:34.554] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:23:0)
[08:42:34.585] Build status: STARTED
[08:42:34.585] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:24:0) after 4m46s
[08:47:20.659] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:24:0)
[08:47:20.685] Build status: STARTED
[08:47:20.685] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:25:0) after 4m5s
[08:51:25.718] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:25:0)
[08:51:25.751] Build status: STARTED
[08:51:25.751] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:26:0) after 6m25s
[08:57:50.764] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:26:0)
[08:57:50.782] Build status: STARTED
[08:57:50.782] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:27:0) after 1m20s
[08:59:10.802] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:27:0)
[08:59:10.818] Build status: STARTED
[08:59:10.818] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8875271909584240000:28:0) after 4m21s
[09:01:52.898] Received PubSub notification, asking Buildbucket for the build status
[09:01:52.961] Build:
{
"id": "8709841847518249729",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 719,
"createdBy": "project:chromium-m137",
"createTime": "2025-07-09T07:02:32.269898641Z",
"startTime": "2025-07-09T07:26:30.883861601Z",
"endTime": "2025-07-09T09:01:52.116008118Z",
"updateTime": "2025-07-09T09:01:52.116008118Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4c1c385f8655056a5e11961050dd9d54d229b072",
"ref": "refs/branch-heads/7151"
}
}
}
[09:01:52.961] Invocation finished in 1h59m22.045497s with status SUCCEEDED