[19:44:36.438] New invocation is queued and will start shortly
[19:44:38.954] Starting the invocation (attempt 1)
[19:44:38.983] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/043c80a87889064a299f4dd0f3ee5441659f516d"
[19:44:38.983] Popped gitiles commit info from properties and tags
[19:44:38.983] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:44:38.984] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:44:38.986] Buildbucket request:
{
"requestId": "8876945305273436864",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876945305273436864",
"job": "chromium-m137/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bf8314f5faa1a9483f45ba32bdd2643f0ce2577c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@bf8314f5faa1a9483f45ba32bdd2643f0ce2577c",
"title": "bf8314f5faa1a9483f45ba32bdd2643f0ce2577c",
"url": "https://chromium.googlesource.com/chromium/src/+/bf8314f5faa1a9483f45ba32bdd2643f0ce2577c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa5434931b1bc61e581a62a5d34c91cf2cb61a98"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@aa5434931b1bc61e581a62a5d34c91cf2cb61a98",
"title": "aa5434931b1bc61e581a62a5d34c91cf2cb61a98",
"url": "https://chromium.googlesource.com/chromium/src/+/aa5434931b1bc61e581a62a5d34c91cf2cb61a98"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1cdebf4644d0c6adb20da0e17868c0427b3a31c1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@1cdebf4644d0c6adb20da0e17868c0427b3a31c1",
"title": "1cdebf4644d0c6adb20da0e17868c0427b3a31c1",
"url": "https://chromium.googlesource.com/chromium/src/+/1cdebf4644d0c6adb20da0e17868c0427b3a31c1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ed7aa9c22cfcf011169613e1c299f496c5c1866b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@ed7aa9c22cfcf011169613e1c299f496c5c1866b",
"title": "ed7aa9c22cfcf011169613e1c299f496c5c1866b",
"url": "https://chromium.googlesource.com/chromium/src/+/ed7aa9c22cfcf011169613e1c299f496c5c1866b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "779386888e9bc9793c4b8a06333de602c2675228"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@779386888e9bc9793c4b8a06333de602c2675228",
"title": "779386888e9bc9793c4b8a06333de602c2675228",
"url": "https://chromium.googlesource.com/chromium/src/+/779386888e9bc9793c4b8a06333de602c2675228"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f57ee97a3dc7945c8616723da7d56480dee61ceb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@f57ee97a3dc7945c8616723da7d56480dee61ceb",
"title": "f57ee97a3dc7945c8616723da7d56480dee61ceb",
"url": "https://chromium.googlesource.com/chromium/src/+/f57ee97a3dc7945c8616723da7d56480dee61ceb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "043c80a87889064a299f4dd0f3ee5441659f516d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@043c80a87889064a299f4dd0f3ee5441659f516d",
"title": "043c80a87889064a299f4dd0f3ee5441659f516d",
"url": "https://chromium.googlesource.com/chromium/src/+/043c80a87889064a299f4dd0f3ee5441659f516d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "043c80a87889064a299f4dd0f3ee5441659f516d",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876945305273436864"
},
{
"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"
}
}
[19:44:39.579] Scheduled build:
{
"id": "8711515241850369825",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 518,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-20T19:44:39.073145189Z",
"updateTime": "2025-06-20T19:44:39.073145189Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "043c80a87889064a299f4dd0f3ee5441659f516d",
"ref": "refs/branch-heads/7151"
}
}
}
[19:44:39.579] Task URL: https://cr-buildbucket.appspot.com/build/8711515241850369825
[19:44:39.579] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:2:0) after 3m23s
[19:44:40.602] Received PubSub notification, asking Buildbucket for the build status
[19:44:40.629] Build status: SCHEDULED
[19:48:02.608] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:2:0)
[19:48:02.636] Build status: SCHEDULED
[19:48:02.637] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:4:0) after 4m10s
[19:52:12.706] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:4:0)
[19:52:12.805] Build status: SCHEDULED
[19:52:12.805] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:5:0) after 1m8s
[19:53:20.824] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:5:0)
[19:53:20.852] Build status: SCHEDULED
[19:53:20.852] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:6:0) after 5m0s
[19:58:20.906] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:6:0)
[19:58:20.924] Build status: SCHEDULED
[19:58:20.924] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:7:0) after 8m48s
[20:01:14.437] Received PubSub notification, asking Buildbucket for the build status
[20:01:14.467] Build status: STARTED
[20:07:09.040] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:7:0)
[20:07:09.071] Build status: STARTED
[20:07:09.071] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:9:0) after 1m50s
[20:08:59.087] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:9:0)
[20:08:59.112] Build status: STARTED
[20:08:59.112] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:10:0) after 1m32s
[20:10:31.228] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:10:0)
[20:10:31.245] Build status: STARTED
[20:10:31.245] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:11:0) after 4m6s
[20:14:37.263] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:11:0)
[20:14:37.288] Build status: STARTED
[20:14:37.288] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:12:0) after 6m53s
[20:21:30.308] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:12:0)
[20:21:30.334] Build status: STARTED
[20:21:30.334] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:13:0) after 8m8s
[20:29:38.358] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:13:0)
[20:29:38.383] Build status: STARTED
[20:29:38.383] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:14:0) after 2m23s
[20:32:01.388] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:14:0)
[20:32:01.423] Build status: STARTED
[20:32:01.423] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:15:0) after 7m34s
[20:39:35.404] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:15:0)
[20:39:35.440] Build status: STARTED
[20:39:35.440] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:16:0) after 6m46s
[20:46:21.494] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:16:0)
[20:46:21.534] Build status: STARTED
[20:46:21.534] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:17:0) after 8m44s
[20:55:05.612] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:17:0)
[20:55:05.631] Build status: STARTED
[20:55:05.631] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:18:0) after 1m20s
[20:56:25.633] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:18:0)
[20:56:25.657] Build status: STARTED
[20:56:25.657] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:19:0) after 3m58s
[21:00:23.711] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:19:0)
[21:00:23.743] Build status: STARTED
[21:00:23.743] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:20:0) after 8m6s
[21:08:29.758] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:20:0)
[21:08:29.783] Build status: STARTED
[21:08:29.783] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:21:0) after 7m18s
[21:15:47.949] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:21:0)
[21:15:47.979] Build status: STARTED
[21:15:47.979] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:22:0) after 2m2s
[21:17:50.105] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:22:0)
[21:17:50.121] Build status: STARTED
[21:17:50.121] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:23:0) after 6m3s
[21:23:53.229] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:23:0)
[21:23:53.251] Build status: STARTED
[21:23:53.251] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:24:0) after 5m54s
[21:29:47.265] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:24:0)
[21:29:47.286] Build status: STARTED
[21:29:47.286] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:25:0) after 6m0s
[21:35:47.369] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:25:0)
[21:35:47.390] Build status: STARTED
[21:35:47.390] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:26:0) after 3m44s
[21:39:31.392] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:26:0)
[21:39:31.428] Build status: STARTED
[21:39:31.428] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:27:0) after 5m26s
[21:44:57.516] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:27:0)
[21:44:57.532] Build status: STARTED
[21:44:57.533] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:28:0) after 2m48s
[21:47:45.606] Handling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:28:0)
[21:47:45.628] Build status: STARTED
[21:47:45.628] Scheduling timer "check-buildbucket-build-status" (chromium-m137/fuchsia-x64-cast-receiver-dbg:8876945305273436864:29:0) after 3m5s
[21:48:26.965] Received PubSub notification, asking Buildbucket for the build status
[21:48:27.032] Build:
{
"id": "8711515241850369825",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 518,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-20T19:44:39.073145189Z",
"startTime": "2025-06-20T20:01:08.307554248Z",
"endTime": "2025-06-20T21:48:26.338565606Z",
"updateTime": "2025-06-20T21:48:26.338565606Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "043c80a87889064a299f4dd0f3ee5441659f516d",
"ref": "refs/branch-heads/7151"
}
}
}
[21:48:27.032] Invocation finished in 2h3m50.607156976s with status SUCCEEDED