[05:49:01.059] New invocation is queued and will start shortly
[05:49:02.227] Starting the invocation (attempt 1)
[05:49:02.255] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ed879ff310879295c274c6e8533187ae364b9df"
[05:49:02.255] Popped gitiles commit info from properties and tags
[05:49:02.255] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:49:02.255] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:49:02.255] Buildbucket request:
{
"requestId": "8874008176133808720",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ed879ff310879295c274c6e8533187ae364b9df",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874008176133808720"
},
{
"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:49:02.883] Scheduled build:
{
"id": "8708578114161275665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 33052,
"createdBy": "project:chromium",
"createTime": "2025-07-23T05:49:02.314268694Z",
"updateTime": "2025-07-23T05:49:02.314268694Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ed879ff310879295c274c6e8533187ae364b9df",
"ref": "refs/heads/main"
}
}
}
[05:49:02.883] Task URL: https://cr-buildbucket.appspot.com/build/8708578114161275665
[05:49:02.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:2:0) after 8m49s
[05:49:03.820] Received PubSub notification, asking Buildbucket for the build status
[05:49:03.840] Build status: SCHEDULED
[05:49:10.784] Received PubSub notification, asking Buildbucket for the build status
[05:49:10.807] Build status: STARTED
[05:57:52.007] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:2:0)
[05:57:52.037] Build status: STARTED
[05:57:52.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:5:0) after 1m42s
[05:59:34.097] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:5:0)
[05:59:34.190] Build status: STARTED
[05:59:34.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:6:0) after 4m29s
[06:04:03.363] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:6:0)
[06:04:03.390] Build status: STARTED
[06:04:03.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:7:0) after 2m37s
[06:06:40.666] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:7:0)
[06:06:40.695] Build status: STARTED
[06:06:40.696] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:8:0) after 3m34s
[06:10:14.720] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:8:0)
[06:10:14.745] Build status: STARTED
[06:10:14.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:9:0) after 3m43s
[06:13:57.934] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:9:0)
[06:13:57.960] Build status: STARTED
[06:13:57.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:10:0) after 8m29s
[06:22:27.147] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:10:0)
[06:22:27.216] Build status: STARTED
[06:22:27.216] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:11:0) after 4m52s
[06:27:19.518] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:11:0)
[06:27:19.588] Build status: STARTED
[06:27:19.588] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:12:0) after 7m56s
[06:35:15.769] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:12:0)
[06:35:15.796] Build status: STARTED
[06:35:15.796] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:13:0) after 2m3s
[06:37:19.310] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:13:0)
[06:37:19.461] Build status: STARTED
[06:37:19.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:14:0) after 9m17s
[06:46:36.634] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:14:0)
[06:46:36.674] Build status: STARTED
[06:46:36.674] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:15:0) after 3m12s
[06:49:49.086] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:15:0)
[06:49:49.120] Build status: STARTED
[06:49:49.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:16:0) after 1m50s
[06:51:39.241] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:16:0)
[06:51:39.292] Build status: STARTED
[06:51:39.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:17:0) after 4m52s
[06:56:31.280] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:17:0)
[06:56:31.333] Build status: STARTED
[06:56:31.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:18:0) after 6m58s
[07:03:29.468] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:18:0)
[07:03:29.493] Build status: STARTED
[07:03:29.493] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:19:0) after 8m28s
[07:11:57.648] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:19:0)
[07:11:57.669] Build status: STARTED
[07:11:57.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:20:0) after 3m9s
[07:15:06.720] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:20:0)
[07:15:06.793] Build status: STARTED
[07:15:06.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:21:0) after 2m17s
[07:17:23.855] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:21:0)
[07:17:23.880] Build status: STARTED
[07:17:23.880] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:22:0) after 8m8s
[07:25:31.914] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:22:0)
[07:25:31.935] Build status: STARTED
[07:25:31.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:23:0) after 5m53s
[07:31:25.059] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:23:0)
[07:31:25.078] Build status: STARTED
[07:31:25.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:24:0) after 4m32s
[07:35:57.101] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:24:0)
[07:35:57.116] Build status: STARTED
[07:35:57.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:25:0) after 9m28s
[07:45:25.116] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:25:0)
[07:45:25.133] Build status: STARTED
[07:45:25.133] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:26:0) after 6m34s
[07:51:59.262] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:26:0)
[07:51:59.347] Build status: STARTED
[07:51:59.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:27:0) after 4m30s
[07:56:29.372] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:27:0)
[07:56:29.396] Build status: STARTED
[07:56:29.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:28:0) after 9m59s
[08:06:28.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:28:0)
[08:06:28.459] Build status: STARTED
[08:06:28.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:29:0) after 3m39s
[08:10:07.479] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:29:0)
[08:10:07.577] Build status: STARTED
[08:10:07.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:30:0) after 2m19s
[08:12:26.555] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:30:0)
[08:12:26.593] Build status: STARTED
[08:12:26.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:31:0) after 8m6s
[08:20:33.399] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:31:0)
[08:20:33.417] Build status: STARTED
[08:20:33.417] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:32:0) after 2m51s
[08:23:24.441] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:32:0)
[08:23:24.463] Build status: STARTED
[08:23:24.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:33:0) after 7m6s
[08:30:30.522] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:33:0)
[08:30:30.547] Build status: STARTED
[08:30:30.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:34:0) after 5m30s
[08:36:00.756] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:34:0)
[08:36:00.777] Build status: STARTED
[08:36:00.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:35:0) after 2m51s
[08:38:51.938] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:35:0)
[08:38:51.954] Build status: STARTED
[08:38:51.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:36:0) after 3m28s
[08:42:20.014] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:36:0)
[08:42:20.039] Build status: STARTED
[08:42:20.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:37:0) after 7m51s
[08:50:11.464] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:37:0)
[08:50:11.490] Build status: STARTED
[08:50:11.490] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:38:0) after 1m55s
[08:52:07.001] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:38:0)
[08:52:07.022] Build status: STARTED
[08:52:07.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:39:0) after 9m33s
[09:01:40.048] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:39:0)
[09:01:40.065] Build status: STARTED
[09:01:40.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:40:0) after 7m18s
[09:08:58.086] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:40:0)
[09:08:58.111] Build status: STARTED
[09:08:58.111] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:41:0) after 7m19s
[09:16:17.190] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:41:0)
[09:16:17.208] Build status: STARTED
[09:16:17.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:42:0) after 5m0s
[09:21:17.221] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:42:0)
[09:21:17.244] Build status: STARTED
[09:21:17.244] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:43:0) after 3m31s
[09:24:48.265] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:43:0)
[09:24:48.327] Build status: STARTED
[09:24:48.327] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:44:0) after 8m51s
[09:33:39.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:44:0)
[09:33:39.366] Build status: STARTED
[09:33:39.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:45:0) after 5m51s
[09:39:30.390] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:45:0)
[09:39:30.415] Build status: STARTED
[09:39:30.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:46:0) after 4m10s
[09:43:40.443] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:46:0)
[09:43:40.465] Build status: STARTED
[09:43:40.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:47:0) after 2m27s
[09:46:07.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:47:0)
[09:46:07.556] Build status: STARTED
[09:46:07.556] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:48:0) after 7m40s
[09:53:47.615] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:48:0)
[09:53:47.690] Build status: STARTED
[09:53:47.690] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:49:0) after 3m35s
[09:57:22.805] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:49:0)
[09:57:22.938] Build status: STARTED
[09:57:22.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:50:0) after 1m37s
[09:59:00.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:50:0)
[09:59:00.369] Build status: STARTED
[09:59:00.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:51:0) after 5m41s
[10:04:41.393] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:51:0)
[10:04:41.419] Build status: STARTED
[10:04:41.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:52:0) after 6m35s
[10:11:16.629] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:52:0)
[10:11:16.644] Build status: STARTED
[10:11:16.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:53:0) after 9m13s
[10:20:29.708] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:53:0)
[10:20:29.769] Build status: STARTED
[10:20:29.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:54:0) after 6m31s
[10:27:00.900] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:54:0)
[10:27:00.935] Build status: STARTED
[10:27:00.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:55:0) after 3m35s
[10:30:36.074] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:55:0)
[10:30:36.132] Build status: STARTED
[10:30:36.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:56:0) after 3m0s
[10:33:36.399] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:56:0)
[10:33:36.421] Build status: STARTED
[10:33:36.421] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:57:0) after 1m30s
[10:35:06.448] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:57:0)
[10:35:06.489] Build status: STARTED
[10:35:06.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:58:0) after 1m51s
[10:36:57.520] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:58:0)
[10:36:57.535] Build status: STARTED
[10:36:57.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:59:0) after 8m17s
[10:45:14.588] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:59:0)
[10:45:14.610] Build status: STARTED
[10:45:14.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:60:0) after 8m17s
[10:53:31.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:60:0)
[10:53:31.744] Build status: STARTED
[10:53:31.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:61:0) after 6m29s
[11:00:00.948] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:61:0)
[11:00:00.969] Build status: STARTED
[11:00:00.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8874008176133808720:62:0) after 8m19s
[11:03:03.188] Received PubSub notification, asking Buildbucket for the build status
[11:03:03.204] Build:
{
"id": "8708578114161275665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 33052,
"createdBy": "project:chromium",
"createTime": "2025-07-23T05:49:02.314268694Z",
"startTime": "2025-07-23T05:49:10.551486480Z",
"endTime": "2025-07-23T11:03:02.768153822Z",
"updateTime": "2025-07-23T11:03:02.768153822Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ed879ff310879295c274c6e8533187ae364b9df",
"ref": "refs/heads/main"
}
}
}
[11:03:03.204] Invocation finished in 5h14m2.154401503s with status SUCCEEDED