[16:13:08.109] New invocation is queued and will start shortly
[16:13:09.174] Starting the invocation (attempt 1)
[16:13:09.201] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c8c04f5b123165f5d3692f158c5999a1c31c112"
[16:13:09.201] Popped gitiles commit info from properties and tags
[16:13:09.201] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:13:09.202] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:13:09.202] Buildbucket request:
{
"requestId": "8861466528696034720",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4c8c04f5b123165f5d3692f158c5999a1c31c112",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8861466528696034720"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-x64-cast-receiver-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:13:09.689] Scheduled build:
{
"id": "8696036466841275505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-rel"
},
"number": 27656,
"createdBy": "project:chromium",
"createTime": "2025-12-08T16:13:09.249808274Z",
"updateTime": "2025-12-08T16:13:09.249808274Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4c8c04f5b123165f5d3692f158c5999a1c31c112",
"ref": "refs/heads/main"
}
}
}
[16:13:09.689] Task URL: https://cr-buildbucket.appspot.com/build/8696036466841275505
[16:13:09.689] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:2:0) after 2m36s
[16:13:10.756] Received PubSub notification, asking Buildbucket for the build status
[16:13:10.772] Build status: SCHEDULED
[16:13:14.637] Received PubSub notification, asking Buildbucket for the build status
[16:13:14.680] Build status: STARTED
[16:15:45.672] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:2:0)
[16:15:45.686] Build status: STARTED
[16:15:45.686] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:5:0) after 9m17s
[16:25:02.750] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:5:0)
[16:25:02.834] Build status: STARTED
[16:25:02.834] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:6:0) after 2m51s
[16:27:53.854] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:6:0)
[16:27:53.913] Build status: STARTED
[16:27:53.913] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:7:0) after 8m39s
[16:36:32.902] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:7:0)
[16:36:32.917] Build status: STARTED
[16:36:32.918] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:8:0) after 6m19s
[16:42:51.909] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:8:0)
[16:42:51.924] Build status: STARTED
[16:42:51.924] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:9:0) after 2m12s
[16:45:03.941] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:9:0)
[16:45:03.987] Build status: STARTED
[16:45:03.987] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:10:0) after 4m57s
[16:50:01.012] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:10:0)
[16:50:01.102] Build status: STARTED
[16:50:01.102] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:11:0) after 7m9s
[16:57:10.166] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:11:0)
[16:57:10.178] Build status: STARTED
[16:57:10.178] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:12:0) after 4m49s
[17:01:59.315] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:12:0)
[17:01:59.386] Build status: STARTED
[17:01:59.386] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:13:0) after 4m49s
[17:06:48.453] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:13:0)
[17:06:48.467] Build status: STARTED
[17:06:48.467] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:14:0) after 7m19s
[17:14:07.520] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:14:0)
[17:14:07.648] Build status: STARTED
[17:14:07.648] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:15:0) after 7m49s
[17:21:56.667] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:15:0)
[17:21:56.695] Build status: STARTED
[17:21:56.695] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:16:0) after 6m5s
[17:28:01.794] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:16:0)
[17:28:01.812] Build status: STARTED
[17:28:01.812] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:17:0) after 9m41s
[17:37:42.865] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:17:0)
[17:37:42.950] Build status: STARTED
[17:37:42.950] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:18:0) after 9m59s
[17:47:42.016] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:18:0)
[17:47:42.057] Build status: STARTED
[17:47:42.057] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:19:0) after 2m49s
[17:50:31.080] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:19:0)
[17:50:31.100] Build status: STARTED
[17:50:31.100] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:20:0) after 1m46s
[17:52:17.300] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:20:0)
[17:52:17.369] Build status: STARTED
[17:52:17.370] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:21:0) after 2m3s
[17:54:20.392] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:21:0)
[17:54:20.409] Build status: STARTED
[17:54:20.409] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:22:0) after 5m10s
[17:59:30.475] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:22:0)
[17:59:30.493] Build status: STARTED
[17:59:30.493] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:23:0) after 2m29s
[18:01:59.609] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:23:0)
[18:01:59.638] Build status: STARTED
[18:01:59.638] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:24:0) after 8m34s
[18:10:33.648] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:24:0)
[18:10:33.665] Build status: STARTED
[18:10:33.665] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:25:0) after 1m9s
[18:11:42.719] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:25:0)
[18:11:42.742] Build status: STARTED
[18:11:42.742] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:26:0) after 3m15s
[18:14:57.716] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:26:0)
[18:14:57.740] Build status: STARTED
[18:14:57.740] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-rel:8861466528696034720:27:0) after 8m12s
[18:17:39.003] Received PubSub notification, asking Buildbucket for the build status
[18:17:39.022] Build:
{
"id": "8696036466841275505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-rel"
},
"number": 27656,
"createdBy": "project:chromium",
"createTime": "2025-12-08T16:13:09.249808274Z",
"startTime": "2025-12-08T16:13:14.258703867Z",
"endTime": "2025-12-08T18:17:38.385658538Z",
"updateTime": "2025-12-08T18:17:38.385658538Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4c8c04f5b123165f5d3692f158c5999a1c31c112",
"ref": "refs/heads/main"
}
}
}
[18:17:39.022] Invocation finished in 2h4m30.926238741s with status SUCCEEDED