[19:43:37.070] New invocation is queued and will start shortly
[19:43:38.401] Starting the invocation (attempt 1)
[19:43:38.427] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a4e9b639c7f56cd278db13fad3d17087b75118e7"
[19:43:38.427] Popped gitiles commit info from properties and tags
[19:43:38.427] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:43:38.428] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:43:38.428] Buildbucket request:
{
"requestId": "8870059998081603568",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a4e9b639c7f56cd278db13fad3d17087b75118e7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870059998081603568"
},
{
"key": "scheduler_job_id",
"value": "chromium/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:43:38.956] Scheduled build:
{
"id": "8704629935920690385",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 5563,
"createdBy": "project:chromium",
"createTime": "2025-09-04T19:43:38.499115568Z",
"updateTime": "2025-09-04T19:43:38.499115568Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a4e9b639c7f56cd278db13fad3d17087b75118e7",
"ref": "refs/heads/main"
}
}
}
[19:43:38.956] Task URL: https://cr-buildbucket.appspot.com/build/8704629935920690385
[19:43:38.956] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:2:0) after 1m2s
[19:43:41.562] Received PubSub notification, asking Buildbucket for the build status
[19:43:41.596] Build status: SCHEDULED
[19:44:41.005] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:2:0)
[19:44:41.047] Build status: SCHEDULED
[19:44:41.047] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:4:0) after 2m39s
[19:47:20.065] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:4:0)
[19:47:20.083] Build status: SCHEDULED
[19:47:20.083] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:5:0) after 3m7s
[19:50:27.099] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:5:0)
[19:50:27.117] Build status: SCHEDULED
[19:50:27.117] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:6:0) after 1m31s
[19:51:58.201] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:6:0)
[19:51:58.252] Build status: SCHEDULED
[19:51:58.253] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:7:0) after 6m40s
[19:58:38.254] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:7:0)
[19:58:38.297] Build status: SCHEDULED
[19:58:38.297] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:8:0) after 3m3s
[20:01:41.314] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:8:0)
[20:01:41.330] Build status: SCHEDULED
[20:01:41.331] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:9:0) after 8m7s
[20:09:48.355] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:9:0)
[20:09:48.388] Build status: SCHEDULED
[20:09:48.388] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:10:0) after 7m57s
[20:17:45.414] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:10:0)
[20:17:45.431] Build status: SCHEDULED
[20:17:45.431] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:11:0) after 9m11s
[20:26:56.548] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:11:0)
[20:26:56.564] Build status: SCHEDULED
[20:26:56.564] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:12:0) after 4m51s
[20:31:47.580] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:12:0)
[20:31:47.599] Build status: SCHEDULED
[20:31:47.599] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:13:0) after 4m49s
[20:33:45.328] Received PubSub notification, asking Buildbucket for the build status
[20:33:45.349] Build status: STARTED
[20:36:36.615] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:13:0)
[20:36:36.638] Build status: STARTED
[20:36:36.638] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:15:0) after 4m19s
[20:40:55.656] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:15:0)
[20:40:55.674] Build status: STARTED
[20:40:55.674] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:16:0) after 5m36s
[20:46:31.992] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:16:0)
[20:46:32.010] Build status: STARTED
[20:46:32.010] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:17:0) after 4m33s
[20:51:05.060] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:17:0)
[20:51:05.105] Build status: STARTED
[20:51:05.105] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:18:0) after 1m8s
[20:52:13.175] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:18:0)
[20:52:13.192] Build status: STARTED
[20:52:13.192] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:19:0) after 1m29s
[20:53:42.210] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:19:0)
[20:53:42.223] Build status: STARTED
[20:53:42.223] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:20:0) after 9m59s
[21:03:41.377] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:20:0)
[21:03:41.405] Build status: STARTED
[21:03:41.406] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:21:0) after 5m13s
[21:08:54.426] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:21:0)
[21:08:54.445] Build status: STARTED
[21:08:54.445] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:22:0) after 1m51s
[21:10:45.514] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:22:0)
[21:10:45.551] Build status: STARTED
[21:10:45.551] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:23:0) after 7m57s
[21:18:42.614] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:23:0)
[21:18:42.808] Build status: STARTED
[21:18:42.808] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:24:0) after 9m43s
[21:28:25.876] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:24:0)
[21:28:25.895] Build status: STARTED
[21:28:25.895] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:25:0) after 3m3s
[21:31:28.894] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:25:0)
[21:31:28.916] Build status: STARTED
[21:31:28.916] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:26:0) after 2m27s
[21:33:55.985] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:26:0)
[21:33:56.010] Build status: STARTED
[21:33:56.010] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:27:0) after 1m13s
[21:35:08.983] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:27:0)
[21:35:09.042] Build status: STARTED
[21:35:09.042] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:28:0) after 1m19s
[21:36:28.024] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:28:0)
[21:36:28.166] Build status: STARTED
[21:36:28.166] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:29:0) after 3m21s
[21:39:49.306] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:29:0)
[21:39:49.323] Build status: STARTED
[21:39:49.323] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:30:0) after 4m54s
[21:44:43.311] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:30:0)
[21:44:43.352] Build status: STARTED
[21:44:43.352] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:31:0) after 1m47s
[21:46:30.367] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:31:0)
[21:46:30.402] Build status: STARTED
[21:46:30.402] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:32:0) after 6m53s
[21:53:23.468] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:32:0)
[21:53:23.484] Build status: STARTED
[21:53:23.484] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:33:0) after 9m26s
[22:02:49.560] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:33:0)
[22:02:49.579] Build status: STARTED
[22:02:49.579] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:34:0) after 2m55s
[22:05:44.558] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:34:0)
[22:05:44.576] Build status: STARTED
[22:05:44.576] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:35:0) after 4m13s
[22:09:57.593] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:35:0)
[22:09:57.614] Build status: STARTED
[22:09:57.615] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:36:0) after 4m32s
[22:14:29.693] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:36:0)
[22:14:29.713] Build status: STARTED
[22:14:29.713] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:37:0) after 2m10s
[22:16:39.701] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:37:0)
[22:16:39.723] Build status: STARTED
[22:16:39.723] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:38:0) after 6m29s
[22:23:08.729] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:38:0)
[22:23:08.763] Build status: STARTED
[22:23:08.763] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:39:0) after 6m32s
[22:29:40.787] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:39:0)
[22:29:40.802] Build status: STARTED
[22:29:40.802] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8870059998081603568:40:0) after 8m25s
[22:32:28.274] Received PubSub notification, asking Buildbucket for the build status
[22:32:28.302] Build:
{
"id": "8704629935920690385",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 5563,
"createdBy": "project:chromium",
"createTime": "2025-09-04T19:43:38.499115568Z",
"startTime": "2025-09-04T20:33:29.043735948Z",
"endTime": "2025-09-04T22:32:26.134691672Z",
"updateTime": "2025-09-04T22:32:26.134691672Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a4e9b639c7f56cd278db13fad3d17087b75118e7",
"ref": "refs/heads/main"
}
}
}
[22:32:28.302] Invocation finished in 2h48m51.246853585s with status SUCCEEDED