[21:09:22.158] New invocation is queued and will start shortly
[21:09:24.048] Starting the invocation (attempt 1)
[21:09:24.086] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c5b37bda194adcbc59ce6867ec8e7080c2113391"
[21:09:24.086] Popped gitiles commit info from properties and tags
[21:09:24.086] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:09:24.086] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:09:24.086] Buildbucket request:
{
"requestId": "8874403257441500576",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c5b37bda194adcbc59ce6867ec8e7080c2113391",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874403257441500576"
},
{
"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"
}
}
[21:09:24.650] Scheduled build:
{
"id": "8708973194669459649",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 33031,
"createdBy": "project:chromium",
"createTime": "2025-07-18T21:09:24.181299354Z",
"updateTime": "2025-07-18T21:09:24.181299354Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c5b37bda194adcbc59ce6867ec8e7080c2113391",
"ref": "refs/heads/main"
}
}
}
[21:09:24.650] Task URL: https://cr-buildbucket.appspot.com/build/8708973194669459649
[21:09:24.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:2:0) after 2m54s
[21:09:24.990] Received PubSub notification, asking Buildbucket for the build status
[21:09:25.015] Build status: SCHEDULED
[21:09:28.359] Received PubSub notification, asking Buildbucket for the build status
[21:09:28.406] Build status: STARTED
[21:12:18.697] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:2:0)
[21:12:18.715] Build status: STARTED
[21:12:18.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:5:0) after 4m6s
[21:16:24.759] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:5:0)
[21:16:24.793] Build status: STARTED
[21:16:24.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:6:0) after 1m48s
[21:18:12.821] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:6:0)
[21:18:12.854] Build status: STARTED
[21:18:12.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:7:0) after 6m39s
[21:24:51.884] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:7:0)
[21:24:51.907] Build status: STARTED
[21:24:51.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:8:0) after 8m12s
[21:33:03.935] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:8:0)
[21:33:03.966] Build status: STARTED
[21:33:03.966] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:9:0) after 5m29s
[21:38:33.044] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:9:0)
[21:38:33.075] Build status: STARTED
[21:38:33.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:10:0) after 6m46s
[21:45:19.121] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:10:0)
[21:45:19.139] Build status: STARTED
[21:45:19.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:11:0) after 1m36s
[21:46:55.159] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:11:0)
[21:46:55.196] Build status: STARTED
[21:46:55.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:12:0) after 2m0s
[21:48:55.223] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:12:0)
[21:48:55.245] Build status: STARTED
[21:48:55.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:13:0) after 3m16s
[21:52:11.323] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:13:0)
[21:52:11.349] Build status: STARTED
[21:52:11.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:14:0) after 2m4s
[21:54:15.420] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:14:0)
[21:54:15.464] Build status: STARTED
[21:54:15.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:15:0) after 9m30s
[22:03:45.571] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:15:0)
[22:03:45.596] Build status: STARTED
[22:03:45.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:16:0) after 3m48s
[22:07:33.681] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:16:0)
[22:07:33.710] Build status: STARTED
[22:07:33.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:17:0) after 8m26s
[22:15:59.737] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:17:0)
[22:15:59.764] Build status: STARTED
[22:15:59.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:18:0) after 9m55s
[22:25:54.790] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:18:0)
[22:25:54.845] Build status: STARTED
[22:25:54.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:19:0) after 3m17s
[22:29:11.931] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:19:0)
[22:29:11.948] Build status: STARTED
[22:29:11.948] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:20:0) after 8m52s
[22:38:03.969] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:20:0)
[22:38:03.989] Build status: STARTED
[22:38:03.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:21:0) after 4m7s
[22:42:11.073] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:21:0)
[22:42:11.095] Build status: STARTED
[22:42:11.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:22:0) after 4m38s
[22:46:49.122] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:22:0)
[22:46:49.148] Build status: STARTED
[22:46:49.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:23:0) after 2m46s
[22:49:35.172] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:23:0)
[22:49:35.189] Build status: STARTED
[22:49:35.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:24:0) after 2m52s
[22:52:27.208] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:24:0)
[22:52:27.225] Build status: STARTED
[22:52:27.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:25:0) after 2m42s
[22:55:09.343] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:25:0)
[22:55:09.374] Build status: STARTED
[22:55:09.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:26:0) after 7m59s
[23:03:08.392] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:26:0)
[23:03:08.425] Build status: STARTED
[23:03:08.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:27:0) after 3m12s
[23:06:20.612] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:27:0)
[23:06:20.680] Build status: STARTED
[23:06:20.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:28:0) after 1m17s
[23:07:37.702] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:28:0)
[23:07:37.719] Build status: STARTED
[23:07:37.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:29:0) after 7m16s
[23:14:53.767] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:29:0)
[23:14:53.783] Build status: STARTED
[23:14:53.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:30:0) after 4m34s
[23:19:27.811] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:30:0)
[23:19:27.937] Build status: STARTED
[23:19:27.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:31:0) after 2m17s
[23:21:45.015] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:31:0)
[23:21:45.032] Build status: STARTED
[23:21:45.032] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:32:0) after 8m3s
[23:29:48.102] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:32:0)
[23:29:48.125] Build status: STARTED
[23:29:48.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:33:0) after 8m34s
[23:38:22.144] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:33:0)
[23:38:22.164] Build status: STARTED
[23:38:22.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:34:0) after 2m51s
[23:41:13.203] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:34:0)
[23:41:13.226] Build status: STARTED
[23:41:13.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:35:0) after 5m26s
[23:46:39.335] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:35:0)
[23:46:39.359] Build status: STARTED
[23:46:39.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:36:0) after 9m19s
[23:55:59.189] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:36:0)
[23:55:59.252] Build status: STARTED
[23:55:59.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:37:0) after 2m7s
[23:58:06.551] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:37:0)
[23:58:06.570] Build status: STARTED
[23:58:06.571] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:38:0) after 8m54s
[00:07:00.651] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:38:0)
[00:07:00.696] Build status: STARTED
[00:07:00.696] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:39:0) after 1m19s
[00:08:19.758] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:39:0)
[00:08:19.961] Build status: STARTED
[00:08:19.961] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:40:0) after 8m8s
[00:16:28.022] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:40:0)
[00:16:28.039] Build status: STARTED
[00:16:28.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:41:0) after 9m20s
[00:25:48.072] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:41:0)
[00:25:48.088] Build status: STARTED
[00:25:48.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:42:0) after 9m58s
[00:35:46.106] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:42:0)
[00:35:46.125] Build status: STARTED
[00:35:46.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:43:0) after 3m17s
[00:39:03.280] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:43:0)
[00:39:03.299] Build status: STARTED
[00:39:03.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:44:0) after 2m15s
[00:41:18.361] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:44:0)
[00:41:18.390] Build status: STARTED
[00:41:18.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:45:0) after 1m51s
[00:43:09.486] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:45:0)
[00:43:09.503] Build status: STARTED
[00:43:09.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:46:0) after 5m46s
[00:48:55.566] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:46:0)
[00:48:55.584] Build status: STARTED
[00:48:55.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:47:0) after 1m56s
[00:50:51.643] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:47:0)
[00:50:51.662] Build status: STARTED
[00:50:51.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:48:0) after 2m41s
[00:53:32.765] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:48:0)
[00:53:32.785] Build status: STARTED
[00:53:32.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:49:0) after 7m5s
[01:00:37.817] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:49:0)
[01:00:37.834] Build status: STARTED
[01:00:37.834] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:50:0) after 5m50s
[01:06:27.871] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:50:0)
[01:06:27.893] Build status: STARTED
[01:06:27.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:51:0) after 9m30s
[01:15:57.919] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:51:0)
[01:15:57.943] Build status: STARTED
[01:15:57.943] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:52:0) after 4m53s
[01:20:51.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:52:0)
[01:20:51.367] Build status: STARTED
[01:20:51.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:53:0) after 1m57s
[01:22:48.483] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:53:0)
[01:22:48.503] Build status: STARTED
[01:22:48.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:54:0) after 3m12s
[01:26:00.525] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:54:0)
[01:26:00.547] Build status: STARTED
[01:26:00.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:55:0) after 2m33s
[01:28:33.568] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:55:0)
[01:28:33.592] Build status: STARTED
[01:28:33.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:56:0) after 1m4s
[01:29:37.699] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:56:0)
[01:29:37.716] Build status: STARTED
[01:29:37.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:57:0) after 4m17s
[01:33:54.978] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:57:0)
[01:33:55.013] Build status: STARTED
[01:33:55.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:58:0) after 5m14s
[01:39:09.042] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:58:0)
[01:39:09.066] Build status: STARTED
[01:39:09.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:59:0) after 6m1s
[01:45:10.305] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:59:0)
[01:45:10.322] Build status: STARTED
[01:45:10.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:60:0) after 9m7s
[01:54:17.408] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:60:0)
[01:54:17.426] Build status: STARTED
[01:54:17.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:61:0) after 9m54s
[02:04:11.424] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:61:0)
[02:04:11.520] Build status: STARTED
[02:04:11.520] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:62:0) after 5m59s
[02:10:10.604] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:62:0)
[02:10:10.627] Build status: STARTED
[02:10:10.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:63:0) after 6m7s
[02:16:17.785] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:63:0)
[02:16:17.814] Build status: STARTED
[02:16:17.814] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:64:0) after 6m24s
[02:22:42.320] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:64:0)
[02:22:42.336] Build status: STARTED
[02:22:42.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:65:0) after 7m50s
[02:30:32.514] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:65:0)
[02:30:32.533] Build status: STARTED
[02:30:32.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:66:0) after 1m29s
[02:32:01.632] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:66:0)
[02:32:01.653] Build status: STARTED
[02:32:01.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874403257441500576:67:0) after 7m30s
[02:38:57.283] Received PubSub notification, asking Buildbucket for the build status
[02:38:57.300] Build:
{
"id": "8708973194669459649",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 33031,
"createdBy": "project:chromium",
"createTime": "2025-07-18T21:09:24.181299354Z",
"startTime": "2025-07-18T21:09:28.137089018Z",
"endTime": "2025-07-19T02:38:56.844368564Z",
"updateTime": "2025-07-19T02:38:56.844368564Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c5b37bda194adcbc59ce6867ec8e7080c2113391",
"ref": "refs/heads/main"
}
}
}
[02:38:57.300] Invocation finished in 5h29m35.146089003s with status SUCCEEDED