[14:41:16.588] New invocation is queued and will start shortly
[14:41:17.686] Starting the invocation (attempt 1)
[14:41:17.716] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6d2148534be0d7ae0349ac4a698354066a6961d"
[14:41:17.716] Popped gitiles commit info from properties and tags
[14:41:17.716] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:41:17.716] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:41:17.716] Buildbucket request:
{
"requestId": "8868629468284652864",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6d2148534be0d7ae0349ac4a698354066a6961d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868629468284652864"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTiOS"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:41:18.231] Scheduled build:
{
"id": "8703199406379783729",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 33784,
"createdBy": "project:chromium",
"createTime": "2025-09-20T14:41:17.781291730Z",
"updateTime": "2025-09-20T14:41:17.781291730Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6d2148534be0d7ae0349ac4a698354066a6961d",
"ref": "refs/heads/main"
}
}
}
[14:41:18.231] Task URL: https://cr-buildbucket.appspot.com/build/8703199406379783729
[14:41:18.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:2:0) after 3m11s
[14:41:19.186] Received PubSub notification, asking Buildbucket for the build status
[14:41:19.217] Build status: SCHEDULED
[14:41:23.259] Received PubSub notification, asking Buildbucket for the build status
[14:41:23.281] Build status: STARTED
[14:44:29.320] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:2:0)
[14:44:29.336] Build status: STARTED
[14:44:29.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:5:0) after 7m37s
[14:52:06.354] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:5:0)
[14:52:06.377] Build status: STARTED
[14:52:06.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:6:0) after 6m55s
[14:59:01.492] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:6:0)
[14:59:01.507] Build status: STARTED
[14:59:01.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:7:0) after 5m9s
[15:04:10.613] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:7:0)
[15:04:10.632] Build status: STARTED
[15:04:10.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:8:0) after 3m36s
[15:07:46.648] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:8:0)
[15:07:46.665] Build status: STARTED
[15:07:46.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:9:0) after 6m12s
[15:13:58.778] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:9:0)
[15:13:58.853] Build status: STARTED
[15:13:58.853] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:10:0) after 3m52s
[15:17:50.866] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:10:0)
[15:17:50.887] Build status: STARTED
[15:17:50.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:11:0) after 8m42s
[15:26:33.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:11:0)
[15:26:33.229] Build status: STARTED
[15:26:33.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:12:0) after 7m29s
[15:34:02.328] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:12:0)
[15:34:02.362] Build status: STARTED
[15:34:02.362] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:13:0) after 1m34s
[15:35:36.392] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:13:0)
[15:35:36.414] Build status: STARTED
[15:35:36.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:14:0) after 3m34s
[15:39:11.195] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:14:0)
[15:39:11.218] Build status: STARTED
[15:39:11.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:15:0) after 7m19s
[15:46:30.493] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:15:0)
[15:46:30.546] Build status: STARTED
[15:46:30.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:16:0) after 4m37s
[15:51:07.584] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:16:0)
[15:51:07.608] Build status: STARTED
[15:51:07.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:17:0) after 2m31s
[15:53:38.825] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:17:0)
[15:53:38.842] Build status: STARTED
[15:53:38.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:18:0) after 9m4s
[16:02:42.833] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:18:0)
[16:02:42.860] Build status: STARTED
[16:02:42.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:19:0) after 2m9s
[16:04:51.980] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:19:0)
[16:04:52.008] Build status: STARTED
[16:04:52.008] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:20:0) after 1m30s
[16:06:22.046] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:20:0)
[16:06:22.077] Build status: STARTED
[16:06:22.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:21:0) after 4m48s
[16:11:10.096] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:21:0)
[16:11:10.117] Build status: STARTED
[16:11:10.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:22:0) after 1m5s
[16:12:15.132] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:22:0)
[16:12:15.147] Build status: STARTED
[16:12:15.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:23:0) after 6m2s
[16:18:17.204] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:23:0)
[16:18:17.237] Build status: STARTED
[16:18:17.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:24:0) after 8m20s
[16:26:37.256] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:24:0)
[16:26:37.272] Build status: STARTED
[16:26:37.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:25:0) after 5m52s
[16:32:29.286] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:25:0)
[16:32:29.324] Build status: STARTED
[16:32:29.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:26:0) after 2m9s
[16:34:38.479] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:26:0)
[16:34:38.499] Build status: STARTED
[16:34:38.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:27:0) after 9m2s
[16:43:40.515] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:27:0)
[16:43:40.533] Build status: STARTED
[16:43:40.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:28:0) after 3m6s
[16:46:46.663] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:28:0)
[16:46:46.677] Build status: STARTED
[16:46:46.677] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:29:0) after 3m53s
[16:50:39.693] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:29:0)
[16:50:39.710] Build status: STARTED
[16:50:39.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:30:0) after 4m21s
[16:55:00.823] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:30:0)
[16:55:00.843] Build status: STARTED
[16:55:00.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:31:0) after 7m10s
[17:02:10.856] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:31:0)
[17:02:11.014] Build status: STARTED
[17:02:11.014] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:32:0) after 4m50s
[17:07:01.095] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:32:0)
[17:07:01.116] Build status: STARTED
[17:07:01.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:33:0) after 3m40s
[17:10:41.130] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:33:0)
[17:10:41.163] Build status: STARTED
[17:10:41.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:34:0) after 7m26s
[17:18:07.276] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:34:0)
[17:18:07.340] Build status: STARTED
[17:18:07.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:35:0) after 2m35s
[17:20:42.364] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:35:0)
[17:20:42.387] Build status: STARTED
[17:20:42.387] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:36:0) after 1m34s
[17:22:16.411] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:36:0)
[17:22:16.432] Build status: STARTED
[17:22:16.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:37:0) after 7m4s
[17:29:20.837] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:37:0)
[17:29:20.852] Build status: STARTED
[17:29:20.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:38:0) after 5m4s
[17:34:24.894] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:38:0)
[17:34:24.910] Build status: STARTED
[17:34:24.910] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:39:0) after 7m8s
[17:41:32.939] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:39:0)
[17:41:33.028] Build status: STARTED
[17:41:33.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:40:0) after 8m12s
[17:49:45.047] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:40:0)
[17:49:45.063] Build status: STARTED
[17:49:45.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:41:0) after 9m16s
[17:59:01.080] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:41:0)
[17:59:01.168] Build status: STARTED
[17:59:01.168] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:42:0) after 2m4s
[18:01:05.238] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:42:0)
[18:01:05.257] Build status: STARTED
[18:01:05.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:43:0) after 7m14s
[18:08:19.282] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:43:0)
[18:08:19.365] Build status: STARTED
[18:08:19.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:44:0) after 3m3s
[18:11:22.589] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:44:0)
[18:11:22.603] Build status: STARTED
[18:11:22.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:45:0) after 2m28s
[18:13:50.728] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:45:0)
[18:13:50.745] Build status: STARTED
[18:13:50.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:46:0) after 8m57s
[18:22:47.769] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:46:0)
[18:22:47.786] Build status: STARTED
[18:22:47.786] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:47:0) after 2m7s
[18:24:54.817] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:47:0)
[18:24:54.830] Build status: STARTED
[18:24:54.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:48:0) after 8m57s
[18:33:51.942] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:48:0)
[18:33:51.957] Build status: STARTED
[18:33:51.957] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:49:0) after 6m59s
[18:40:50.980] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:49:0)
[18:40:51.049] Build status: STARTED
[18:40:51.049] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:50:0) after 6m58s
[18:47:49.070] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:50:0)
[18:47:49.117] Build status: STARTED
[18:47:49.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:51:0) after 6m55s
[18:54:44.222] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:51:0)
[18:54:44.252] Build status: STARTED
[18:54:44.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:52:0) after 5m35s
[19:00:19.274] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:52:0)
[19:00:19.305] Build status: STARTED
[19:00:19.305] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:53:0) after 8m36s
[19:08:55.365] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:53:0)
[19:08:55.863] Build status: STARTED
[19:08:55.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:54:0) after 4m35s
[19:13:31.153] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:54:0)
[19:13:31.168] Build status: STARTED
[19:13:31.168] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8868629468284652864:55:0) after 8m36s
[19:16:13.914] Received PubSub notification, asking Buildbucket for the build status
[19:16:13.930] Build:
{
"id": "8703199406379783729",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 33784,
"createdBy": "project:chromium",
"createTime": "2025-09-20T14:41:17.781291730Z",
"startTime": "2025-09-20T14:41:22.184065280Z",
"endTime": "2025-09-20T19:16:13.556430400Z",
"updateTime": "2025-09-20T19:16:13.556430400Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6d2148534be0d7ae0349ac4a698354066a6961d",
"ref": "refs/heads/main"
}
}
}
[19:16:13.930] Invocation finished in 4h34m57.349176954s with status SUCCEEDED