[05:38:38.463] New invocation is queued and will start shortly
[05:38:39.624] Starting the invocation (attempt 1)
[05:38:39.652] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ff31b627a66aee8fe58f07d07d85a4590cc89e7f"
[05:38:39.652] Popped gitiles commit info from properties and tags
[05:38:39.652] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:38:39.652] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:38:39.652] Buildbucket request:
{
"requestId": "8905717767310724608",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ff31b627a66aee8fe58f07d07d85a4590cc89e7f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8905717767310724608"
},
{
"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"
}
}
[05:38:40.253] Scheduled build:
{
"id": "8740287705240026513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 29094,
"createdBy": "project:chromium",
"createTime": "2024-08-07T05:38:39.719642731Z",
"updateTime": "2024-08-07T05:38:39.719642731Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ff31b627a66aee8fe58f07d07d85a4590cc89e7f",
"ref": "refs/heads/main"
}
}
}
[05:38:40.253] Task URL: https://cr-buildbucket.appspot.com/build/8740287705240026513
[05:38:40.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:2:0) after 7m11s
[05:38:41.158] Received PubSub notification, asking Buildbucket for the build status
[05:38:41.186] Build status: SCHEDULED
[05:39:26.316] Received PubSub notification, asking Buildbucket for the build status
[05:39:26.331] Build status: STARTED
[05:45:51.270] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:2:0)
[05:45:51.286] Build status: STARTED
[05:45:51.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:5:0) after 2m17s
[05:48:08.401] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:5:0)
[05:48:08.426] Build status: STARTED
[05:48:08.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:6:0) after 4m23s
[05:52:31.458] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:6:0)
[05:52:31.476] Build status: STARTED
[05:52:31.476] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:7:0) after 6m40s
[05:59:11.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:7:0)
[05:59:11.919] Build status: STARTED
[05:59:11.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:8:0) after 5m38s
[06:04:49.983] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:8:0)
[06:04:50.015] Build status: STARTED
[06:04:50.015] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:9:0) after 5m54s
[06:10:44.088] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:9:0)
[06:10:44.122] Build status: STARTED
[06:10:44.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:10:0) after 4m53s
[06:15:37.143] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:10:0)
[06:15:37.203] Build status: STARTED
[06:15:37.203] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:11:0) after 8m50s
[06:24:27.364] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:11:0)
[06:24:27.382] Build status: STARTED
[06:24:27.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:12:0) after 4m11s
[06:28:38.522] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:12:0)
[06:28:38.538] Build status: STARTED
[06:28:38.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:13:0) after 9m10s
[06:37:48.634] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:13:0)
[06:37:48.650] Build status: STARTED
[06:37:48.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:14:0) after 7m35s
[06:45:23.812] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:14:0)
[06:45:23.831] Build status: STARTED
[06:45:23.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:15:0) after 8m47s
[06:54:11.058] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:15:0)
[06:54:11.075] Build status: STARTED
[06:54:11.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:16:0) after 9m35s
[07:03:46.097] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:16:0)
[07:03:46.116] Build status: STARTED
[07:03:46.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:17:0) after 2m3s
[07:05:49.150] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:17:0)
[07:05:49.170] Build status: STARTED
[07:05:49.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:18:0) after 9m58s
[07:15:47.189] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:18:0)
[07:15:47.273] Build status: STARTED
[07:15:47.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:19:0) after 7m12s
[07:22:59.294] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:19:0)
[07:22:59.323] Build status: STARTED
[07:22:59.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:20:0) after 7m54s
[07:30:53.333] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:20:0)
[07:30:53.351] Build status: STARTED
[07:30:53.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:21:0) after 9m44s
[07:40:37.373] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:21:0)
[07:40:37.415] Build status: STARTED
[07:40:37.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:22:0) after 7m11s
[07:47:48.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:22:0)
[07:47:48.553] Build status: STARTED
[07:47:48.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:23:0) after 9m30s
[07:57:18.575] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:23:0)
[07:57:18.590] Build status: STARTED
[07:57:18.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:24:0) after 9m37s
[08:06:55.666] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:24:0)
[08:06:55.683] Build status: STARTED
[08:06:55.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:25:0) after 1m14s
[08:08:09.700] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:25:0)
[08:08:09.755] Build status: STARTED
[08:08:09.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:26:0) after 2m43s
[08:10:52.824] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:26:0)
[08:10:52.844] Build status: STARTED
[08:10:52.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:27:0) after 6m31s
[08:17:23.871] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:27:0)
[08:17:23.898] Build status: STARTED
[08:17:23.898] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:28:0) after 5m50s
[08:23:13.914] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:28:0)
[08:23:13.937] Build status: STARTED
[08:23:13.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:29:0) after 8m33s
[08:31:47.061] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:29:0)
[08:31:47.109] Build status: STARTED
[08:31:47.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:30:0) after 9m7s
[08:40:54.128] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:30:0)
[08:40:54.144] Build status: STARTED
[08:40:54.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:31:0) after 2m29s
[08:43:23.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:31:0)
[08:43:23.212] Build status: STARTED
[08:43:23.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:32:0) after 4m13s
[08:47:36.598] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:32:0)
[08:47:36.614] Build status: STARTED
[08:47:36.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:33:0) after 2m53s
[08:50:29.772] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:33:0)
[08:50:29.790] Build status: STARTED
[08:50:29.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:34:0) after 9m37s
[09:00:06.945] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:34:0)
[09:00:06.962] Build status: STARTED
[09:00:06.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:35:0) after 7m18s
[09:07:24.994] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:35:0)
[09:07:25.022] Build status: STARTED
[09:07:25.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:36:0) after 4m28s
[09:11:53.129] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:36:0)
[09:11:53.150] Build status: STARTED
[09:11:53.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:37:0) after 6m52s
[09:18:45.171] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:37:0)
[09:18:45.188] Build status: STARTED
[09:18:45.188] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:38:0) after 1m28s
[09:20:13.383] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:38:0)
[09:20:13.421] Build status: STARTED
[09:20:13.421] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:39:0) after 6m2s
[09:26:15.527] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:39:0)
[09:26:15.568] Build status: STARTED
[09:26:15.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:40:0) after 9m39s
[09:35:54.621] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:40:0)
[09:35:54.697] Build status: STARTED
[09:35:54.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:41:0) after 5m53s
[09:41:47.719] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:41:0)
[09:41:47.761] Build status: STARTED
[09:41:47.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8905717767310724608:42:0) after 9m25s
[09:42:56.619] Received PubSub notification, asking Buildbucket for the build status
[09:42:56.642] Build:
{
"id": "8740287705240026513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 29094,
"createdBy": "project:chromium",
"createTime": "2024-08-07T05:38:39.719642731Z",
"startTime": "2024-08-07T05:39:25.686227377Z",
"endTime": "2024-08-07T09:42:53.479847634Z",
"updateTime": "2024-08-07T09:42:53.479847634Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ff31b627a66aee8fe58f07d07d85a4590cc89e7f",
"ref": "refs/heads/main"
}
}
}
[09:42:56.642] Invocation finished in 4h4m18.281222361s with status SUCCEEDED