[01:02:38.378] New invocation is queued and will start shortly
[01:02:39.495] Starting the invocation (attempt 1)
[01:02:39.521] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f0022ae8c213a74eec5b7152f3208179a131e0d9"
[01:02:39.521] Popped gitiles commit info from properties and tags
[01:02:39.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:02:39.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:02:39.521] Buildbucket request:
{
"requestId": "8893595138225882288",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f0022ae8c213a74eec5b7152f3208179a131e0d9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893595138225882288"
},
{
"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"
}
}
[01:02:40.148] Scheduled build:
{
"id": "8728165076309428753",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 30829,
"createdBy": "project:chromium",
"createTime": "2024-12-19T01:02:39.577070934Z",
"updateTime": "2024-12-19T01:02:39.577070934Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f0022ae8c213a74eec5b7152f3208179a131e0d9",
"ref": "refs/heads/main"
}
}
}
[01:02:40.148] Task URL: https://cr-buildbucket.appspot.com/build/8728165076309428753
[01:02:40.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:2:0) after 6m2s
[01:02:41.241] Received PubSub notification, asking Buildbucket for the build status
[01:02:41.276] Build status: SCHEDULED
[01:02:46.998] Received PubSub notification, asking Buildbucket for the build status
[01:02:47.062] Build status: STARTED
[01:08:42.209] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:2:0)
[01:08:42.226] Build status: STARTED
[01:08:42.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:5:0) after 7m25s
[01:16:07.287] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:5:0)
[01:16:07.419] Build status: STARTED
[01:16:07.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:6:0) after 1m15s
[01:17:22.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:6:0)
[01:17:22.480] Build status: STARTED
[01:17:22.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:7:0) after 4m21s
[01:21:43.557] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:7:0)
[01:21:43.579] Build status: STARTED
[01:21:43.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:8:0) after 2m18s
[01:24:01.648] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:8:0)
[01:24:01.676] Build status: STARTED
[01:24:01.676] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:9:0) after 6m37s
[01:30:38.667] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:9:0)
[01:30:38.694] Build status: STARTED
[01:30:38.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:10:0) after 3m59s
[01:34:37.690] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:10:0)
[01:34:37.707] Build status: STARTED
[01:34:37.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:11:0) after 2m31s
[01:37:08.728] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:11:0)
[01:37:08.799] Build status: STARTED
[01:37:08.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:12:0) after 7m49s
[01:44:57.871] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:12:0)
[01:44:57.888] Build status: STARTED
[01:44:57.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:13:0) after 1m56s
[01:46:53.875] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:13:0)
[01:46:53.945] Build status: STARTED
[01:46:53.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:14:0) after 4m18s
[01:51:11.938] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:14:0)
[01:51:11.960] Build status: STARTED
[01:51:11.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:15:0) after 6m17s
[01:57:28.990] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:15:0)
[01:57:29.012] Build status: STARTED
[01:57:29.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:16:0) after 9m38s
[02:07:07.042] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:16:0)
[02:07:07.060] Build status: STARTED
[02:07:07.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:17:0) after 5m22s
[02:12:29.084] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:17:0)
[02:12:29.100] Build status: STARTED
[02:12:29.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:18:0) after 6m6s
[02:18:35.125] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:18:0)
[02:18:35.158] Build status: STARTED
[02:18:35.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:19:0) after 9m24s
[02:27:59.171] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:19:0)
[02:27:59.191] Build status: STARTED
[02:27:59.191] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:20:0) after 6m16s
[02:34:15.225] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:20:0)
[02:34:15.247] Build status: STARTED
[02:34:15.247] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:21:0) after 2m18s
[02:36:33.266] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:21:0)
[02:36:33.289] Build status: STARTED
[02:36:33.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:22:0) after 5m52s
[02:42:25.328] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:22:0)
[02:42:25.354] Build status: STARTED
[02:42:25.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:23:0) after 5m5s
[02:47:30.466] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:23:0)
[02:47:30.485] Build status: STARTED
[02:47:30.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:24:0) after 3m48s
[02:51:18.524] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:24:0)
[02:51:18.590] Build status: STARTED
[02:51:18.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:25:0) after 3m36s
[02:54:54.578] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:25:0)
[02:54:54.592] Build status: STARTED
[02:54:54.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:26:0) after 4m31s
[02:59:25.595] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:26:0)
[02:59:25.610] Build status: STARTED
[02:59:25.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:27:0) after 3m45s
[03:03:10.629] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:27:0)
[03:03:10.654] Build status: STARTED
[03:03:10.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:28:0) after 9m0s
[03:12:10.678] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:28:0)
[03:12:10.695] Build status: STARTED
[03:12:10.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:29:0) after 8m22s
[03:20:32.720] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:29:0)
[03:20:32.790] Build status: STARTED
[03:20:32.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:30:0) after 1m30s
[03:22:02.810] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:30:0)
[03:22:02.826] Build status: STARTED
[03:22:02.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:31:0) after 1m57s
[03:23:59.854] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:31:0)
[03:23:59.872] Build status: STARTED
[03:23:59.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:32:0) after 8m52s
[03:32:51.926] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:32:0)
[03:32:51.947] Build status: STARTED
[03:32:51.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:33:0) after 5m0s
[03:37:52.003] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:33:0)
[03:37:52.025] Build status: STARTED
[03:37:52.025] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:34:0) after 2m22s
[03:40:14.039] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:34:0)
[03:40:14.058] Build status: STARTED
[03:40:14.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:35:0) after 1m36s
[03:41:50.090] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:35:0)
[03:41:50.104] Build status: STARTED
[03:41:50.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:36:0) after 6m5s
[03:47:55.131] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:36:0)
[03:47:55.155] Build status: STARTED
[03:47:55.155] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:37:0) after 4m7s
[03:52:02.157] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:37:0)
[03:52:02.177] Build status: STARTED
[03:52:02.177] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:38:0) after 7m6s
[03:59:08.307] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:38:0)
[03:59:08.331] Build status: STARTED
[03:59:08.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:39:0) after 4m52s
[04:04:00.430] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:39:0)
[04:04:00.464] Build status: STARTED
[04:04:00.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:40:0) after 4m59s
[04:08:59.541] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:40:0)
[04:08:59.558] Build status: STARTED
[04:08:59.558] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:41:0) after 4m49s
[04:13:48.582] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:41:0)
[04:13:48.598] Build status: STARTED
[04:13:48.598] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:42:0) after 8m33s
[04:22:21.632] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:42:0)
[04:22:21.655] Build status: STARTED
[04:22:21.655] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:43:0) after 1m15s
[04:23:36.728] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:43:0)
[04:23:36.902] Build status: STARTED
[04:23:36.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:44:0) after 8m44s
[04:32:20.893] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:44:0)
[04:32:20.910] Build status: STARTED
[04:32:20.910] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:45:0) after 1m57s
[04:34:17.934] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:45:0)
[04:34:17.949] Build status: STARTED
[04:34:17.949] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:46:0) after 7m23s
[04:41:41.009] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:46:0)
[04:41:41.065] Build status: STARTED
[04:41:41.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:47:0) after 7m36s
[04:49:17.105] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:47:0)
[04:49:17.125] Build status: STARTED
[04:49:17.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:48:0) after 8m44s
[04:58:01.163] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:48:0)
[04:58:01.184] Build status: STARTED
[04:58:01.184] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:49:0) after 6m23s
[05:04:24.203] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:49:0)
[05:04:24.221] Build status: STARTED
[05:04:24.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:50:0) after 7m16s
[05:11:40.316] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:50:0)
[05:11:40.336] Build status: STARTED
[05:11:40.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:51:0) after 4m4s
[05:15:44.356] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:51:0)
[05:15:44.381] Build status: STARTED
[05:15:44.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:52:0) after 1m51s
[05:17:35.402] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:52:0)
[05:17:35.417] Build status: STARTED
[05:17:35.417] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:53:0) after 3m48s
[05:21:23.522] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:53:0)
[05:21:23.541] Build status: STARTED
[05:21:23.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:54:0) after 7m20s
[05:28:43.560] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:54:0)
[05:28:43.576] Build status: STARTED
[05:28:43.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:55:0) after 3m32s
[05:32:15.697] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:55:0)
[05:32:15.713] Build status: STARTED
[05:32:15.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:56:0) after 4m37s
[05:36:53.029] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:56:0)
[05:36:53.050] Build status: STARTED
[05:36:53.050] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:57:0) after 3m53s
[05:40:46.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:57:0)
[05:40:46.657] Build status: STARTED
[05:40:46.657] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:58:0) after 4m40s
[05:45:26.780] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:58:0)
[05:45:26.802] Build status: STARTED
[05:45:26.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8893595138225882288:59:0) after 8m26s
[05:48:31.701] Received PubSub notification, asking Buildbucket for the build status
[05:48:31.716] Build:
{
"id": "8728165076309428753",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 30829,
"createdBy": "project:chromium",
"createTime": "2024-12-19T01:02:39.577070934Z",
"startTime": "2024-12-19T01:02:46.808412164Z",
"endTime": "2024-12-19T05:48:30.922583946Z",
"updateTime": "2024-12-19T05:48:30.922583946Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f0022ae8c213a74eec5b7152f3208179a131e0d9",
"ref": "refs/heads/main"
}
}
}
[05:48:31.716] Invocation finished in 4h45m53.350250852s with status SUCCEEDED