[14:30:24.223] New invocation is queued and will start shortly
[14:30:25.353] Starting the invocation (attempt 1)
[14:30:25.388] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4232d45d0fa24d40ec964991b840ced3e2f8903"
[14:30:25.388] Popped gitiles commit info from properties and tags
[14:30:25.388] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:30:25.388] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:30:25.389] Buildbucket request:
{
"requestId": "8872072837069692704",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872072837069692704",
"job": "chromium-m140/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "17cc4834e232a7af18156788564660a42075bbb4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@17cc4834e232a7af18156788564660a42075bbb4",
"title": "17cc4834e232a7af18156788564660a42075bbb4",
"url": "https://chromium.googlesource.com/chromium/src/+/17cc4834e232a7af18156788564660a42075bbb4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "97a914daf9715ab44155a9e6e7236c2896fb06d2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@97a914daf9715ab44155a9e6e7236c2896fb06d2",
"title": "97a914daf9715ab44155a9e6e7236c2896fb06d2",
"url": "https://chromium.googlesource.com/chromium/src/+/97a914daf9715ab44155a9e6e7236c2896fb06d2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8503538afd108aae1114da408fd990b7f856b242"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@8503538afd108aae1114da408fd990b7f856b242",
"title": "8503538afd108aae1114da408fd990b7f856b242",
"url": "https://chromium.googlesource.com/chromium/src/+/8503538afd108aae1114da408fd990b7f856b242"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb3cc35aa7ebea5c0e2b3972a8579e7c8a23a30f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@cb3cc35aa7ebea5c0e2b3972a8579e7c8a23a30f",
"title": "cb3cc35aa7ebea5c0e2b3972a8579e7c8a23a30f",
"url": "https://chromium.googlesource.com/chromium/src/+/cb3cc35aa7ebea5c0e2b3972a8579e7c8a23a30f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0435c17a04dec9b18ff3ee601a0f3084ff7676d0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@0435c17a04dec9b18ff3ee601a0f3084ff7676d0",
"title": "0435c17a04dec9b18ff3ee601a0f3084ff7676d0",
"url": "https://chromium.googlesource.com/chromium/src/+/0435c17a04dec9b18ff3ee601a0f3084ff7676d0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "53418a6be1aaf95c3d10249fad84089d7d0669b4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@53418a6be1aaf95c3d10249fad84089d7d0669b4",
"title": "53418a6be1aaf95c3d10249fad84089d7d0669b4",
"url": "https://chromium.googlesource.com/chromium/src/+/53418a6be1aaf95c3d10249fad84089d7d0669b4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5c9de8b4d69b1432d6969a6e57fec4140c1b03d9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@5c9de8b4d69b1432d6969a6e57fec4140c1b03d9",
"title": "5c9de8b4d69b1432d6969a6e57fec4140c1b03d9",
"url": "https://chromium.googlesource.com/chromium/src/+/5c9de8b4d69b1432d6969a6e57fec4140c1b03d9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a42d58e4dc127054520f7bf8fda32aed91588e42"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@a42d58e4dc127054520f7bf8fda32aed91588e42",
"title": "a42d58e4dc127054520f7bf8fda32aed91588e42",
"url": "https://chromium.googlesource.com/chromium/src/+/a42d58e4dc127054520f7bf8fda32aed91588e42"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d4232d45d0fa24d40ec964991b840ced3e2f8903"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@d4232d45d0fa24d40ec964991b840ced3e2f8903",
"title": "d4232d45d0fa24d40ec964991b840ced3e2f8903",
"url": "https://chromium.googlesource.com/chromium/src/+/d4232d45d0fa24d40ec964991b840ced3e2f8903"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d4232d45d0fa24d40ec964991b840ced3e2f8903",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872072837069692704"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:30:26.002] Scheduled build:
{
"id": "8706642775069759569",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 77,
"createdBy": "project:chromium-m140",
"createTime": "2025-08-13T14:30:25.499167656Z",
"updateTime": "2025-08-13T14:30:25.499167656Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d4232d45d0fa24d40ec964991b840ced3e2f8903",
"ref": "refs/branch-heads/7339"
}
}
}
[14:30:26.002] Task URL: https://cr-buildbucket.appspot.com/build/8706642775069759569
[14:30:26.002] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:2:0) after 2m29s
[14:30:26.981] Received PubSub notification, asking Buildbucket for the build status
[14:30:27.023] Build status: SCHEDULED
[14:32:55.172] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:2:0)
[14:32:55.191] Build status: SCHEDULED
[14:32:55.191] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:4:0) after 5m5s
[14:38:00.203] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:4:0)
[14:38:00.230] Build status: SCHEDULED
[14:38:00.230] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:5:0) after 5m20s
[14:43:20.234] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:5:0)
[14:43:20.259] Build status: SCHEDULED
[14:43:20.259] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:6:0) after 9m57s
[14:53:17.277] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:6:0)
[14:53:17.328] Build status: SCHEDULED
[14:53:17.328] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:7:0) after 7m54s
[15:01:11.598] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:7:0)
[15:01:11.654] Build status: SCHEDULED
[15:01:11.654] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:8:0) after 6m56s
[15:08:07.730] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:8:0)
[15:08:07.745] Build status: SCHEDULED
[15:08:07.745] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:9:0) after 6m59s
[15:15:06.834] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:9:0)
[15:15:06.854] Build status: SCHEDULED
[15:15:06.854] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:10:0) after 9m6s
[15:24:12.885] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:10:0)
[15:24:12.911] Build status: SCHEDULED
[15:24:12.911] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:11:0) after 7m17s
[15:30:16.239] Received PubSub notification, asking Buildbucket for the build status
[15:30:16.518] Build status: STARTED
[15:31:29.883] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:11:0)
[15:31:29.921] Build status: STARTED
[15:31:29.921] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:13:0) after 2m34s
[15:34:04.052] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:13:0)
[15:34:04.070] Build status: STARTED
[15:34:04.070] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:14:0) after 4m23s
[15:38:27.097] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:14:0)
[15:38:27.117] Build status: STARTED
[15:38:27.117] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:15:0) after 7m28s
[15:45:55.160] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:15:0)
[15:45:55.179] Build status: STARTED
[15:45:55.179] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:16:0) after 5m25s
[15:51:20.259] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:16:0)
[15:51:20.279] Build status: STARTED
[15:51:20.280] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:17:0) after 3m34s
[15:54:54.269] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:17:0)
[15:54:54.286] Build status: STARTED
[15:54:54.286] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:18:0) after 5m22s
[16:00:16.283] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:18:0)
[16:00:16.374] Build status: STARTED
[16:00:16.374] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:19:0) after 4m45s
[16:05:01.396] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:19:0)
[16:05:01.417] Build status: STARTED
[16:05:01.417] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:20:0) after 3m33s
[16:08:34.392] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:20:0)
[16:08:34.416] Build status: STARTED
[16:08:34.416] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:21:0) after 6m34s
[16:15:08.468] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:21:0)
[16:15:08.505] Build status: STARTED
[16:15:08.505] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:22:0) after 6m21s
[16:21:29.578] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:22:0)
[16:21:29.595] Build status: STARTED
[16:21:29.595] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:23:0) after 1m5s
[16:22:34.864] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:23:0)
[16:22:34.926] Build status: STARTED
[16:22:34.926] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:24:0) after 8m38s
[16:31:12.964] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:24:0)
[16:31:13.041] Build status: STARTED
[16:31:13.041] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:25:0) after 1m25s
[16:32:38.131] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:25:0)
[16:32:38.220] Build status: STARTED
[16:32:38.220] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:26:0) after 4m16s
[16:36:54.244] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:26:0)
[16:36:54.286] Build status: STARTED
[16:36:54.287] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:27:0) after 1m52s
[16:38:46.301] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:27:0)
[16:38:46.343] Build status: STARTED
[16:38:46.343] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:28:0) after 4m48s
[16:43:34.388] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:28:0)
[16:43:34.416] Build status: STARTED
[16:43:34.416] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:29:0) after 7m54s
[16:51:28.574] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:29:0)
[16:51:28.593] Build status: STARTED
[16:51:28.593] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:30:0) after 4m27s
[16:55:55.639] Handling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:30:0)
[16:55:55.670] Build status: STARTED
[16:55:55.670] Scheduling timer "check-buildbucket-build-status" (chromium-m140/fuchsia-x64-cast-receiver-dbg:8872072837069692704:31:0) after 4m55s
[16:59:08.714] Received PubSub notification, asking Buildbucket for the build status
[16:59:08.749] Build:
{
"id": "8706642775069759569",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 77,
"createdBy": "project:chromium-m140",
"createTime": "2025-08-13T14:30:25.499167656Z",
"startTime": "2025-08-13T15:30:15.979773542Z",
"endTime": "2025-08-13T16:59:07.920802634Z",
"updateTime": "2025-08-13T16:59:07.920802634Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d4232d45d0fa24d40ec964991b840ced3e2f8903",
"ref": "refs/branch-heads/7339"
}
}
}
[16:59:08.749] Invocation finished in 2h28m44.540022118s with status SUCCEEDED