[23:08:16.844] New invocation is queued and will start shortly
[23:08:18.734] Starting the invocation (attempt 1)
[23:08:18.771] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f46fb8b1fced0420cc2c8f153d505dda3ac3bb80"
[23:08:18.771] Popped gitiles commit info from properties and tags
[23:08:18.772] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:08:18.772] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:08:18.772] Buildbucket request:
{
"requestId": "8831362217141849248",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f46fb8b1fced0420cc2c8f153d505dda3ac3bb80",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8831362217141849248"
},
{
"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"
}
}
[23:08:19.458] Scheduled build:
{
"id": "8665932154381165601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 38431,
"createdBy": "project:chromium",
"createTime": "2026-11-05T23:08:18.855283610Z",
"updateTime": "2026-11-05T23:08:18.855283610Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f46fb8b1fced0420cc2c8f153d505dda3ac3bb80",
"ref": "refs/heads/main"
}
}
}
[23:08:19.458] Task URL: https://cr-buildbucket.appspot.com/build/8665932154381165601
[23:08:19.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:2:0) after 4m47s
[23:08:20.338] Received PubSub notification, asking Buildbucket for the build status
[23:08:20.357] Build status: SCHEDULED
[23:08:24.268] Received PubSub notification, asking Buildbucket for the build status
[23:08:24.322] Build status: STARTED
[23:13:06.777] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:2:0)
[23:13:06.820] Build status: STARTED
[23:13:06.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:5:0) after 5m5s
[23:18:11.951] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:5:0)
[23:18:11.977] Build status: STARTED
[23:18:11.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:6:0) after 5m14s
[23:23:26.025] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:6:0)
[23:23:26.065] Build status: STARTED
[23:23:26.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:7:0) after 1m52s
[23:25:18.148] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:7:0)
[23:25:18.191] Build status: STARTED
[23:25:18.191] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:8:0) after 6m21s
[23:31:39.468] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:8:0)
[23:31:39.552] Build status: STARTED
[23:31:39.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:9:0) after 9m3s
[23:40:42.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:9:0)
[23:40:42.669] Build status: STARTED
[23:40:42.670] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:10:0) after 8m6s
[23:48:48.699] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:10:0)
[23:48:48.728] Build status: STARTED
[23:48:48.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:11:0) after 1m5s
[23:49:53.905] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:11:0)
[23:49:53.931] Build status: STARTED
[23:49:53.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:12:0) after 8m34s
[23:58:27.983] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:12:0)
[23:58:28.006] Build status: STARTED
[23:58:28.006] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:13:0) after 3m16s
[00:01:44.056] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:13:0)
[00:01:44.076] Build status: STARTED
[00:01:44.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:14:0) after 9m11s
[00:10:55.254] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:14:0)
[00:10:55.278] Build status: STARTED
[00:10:55.278] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:15:0) after 6m1s
[00:16:56.352] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:15:0)
[00:16:56.373] Build status: STARTED
[00:16:56.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:16:0) after 4m30s
[00:21:26.511] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:16:0)
[00:21:26.610] Build status: STARTED
[00:21:26.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:17:0) after 6m22s
[00:27:48.635] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:17:0)
[00:27:48.689] Build status: STARTED
[00:27:48.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:18:0) after 6m36s
[00:34:24.742] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:18:0)
[00:34:24.760] Build status: STARTED
[00:34:24.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:19:0) after 1m12s
[00:35:37.044] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:19:0)
[00:35:37.116] Build status: STARTED
[00:35:37.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:20:0) after 5m11s
[00:40:48.207] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:20:0)
[00:40:48.228] Build status: STARTED
[00:40:48.228] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:21:0) after 7m52s
[00:48:40.275] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:21:0)
[00:48:40.323] Build status: STARTED
[00:48:40.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:22:0) after 4m37s
[00:53:17.363] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:22:0)
[00:53:17.464] Build status: STARTED
[00:53:17.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:23:0) after 4m0s
[00:57:17.580] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:23:0)
[00:57:17.597] Build status: STARTED
[00:57:17.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:24:0) after 5m30s
[01:02:47.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:24:0)
[01:02:47.768] Build status: STARTED
[01:02:47.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:25:0) after 7m20s
[01:10:07.912] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:25:0)
[01:10:07.939] Build status: STARTED
[01:10:07.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:26:0) after 6m18s
[01:16:25.971] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:26:0)
[01:16:26.001] Build status: STARTED
[01:16:26.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:27:0) after 6m15s
[01:22:41.025] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:27:0)
[01:22:41.050] Build status: STARTED
[01:22:41.050] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:28:0) after 1m54s
[01:24:35.124] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:28:0)
[01:24:35.656] Build status: STARTED
[01:24:35.656] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:29:0) after 4m16s
[01:28:51.661] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:29:0)
[01:28:51.682] Build status: STARTED
[01:28:51.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:30:0) after 8m2s
[01:36:53.724] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:30:0)
[01:36:53.750] Build status: STARTED
[01:36:53.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:31:0) after 8m59s
[01:45:52.807] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:31:0)
[01:45:52.828] Build status: STARTED
[01:45:52.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:32:0) after 2m50s
[01:48:42.980] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:32:0)
[01:48:43.003] Build status: STARTED
[01:48:43.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:33:0) after 4m40s
[01:53:23.081] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:33:0)
[01:53:23.103] Build status: STARTED
[01:53:23.103] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:34:0) after 1m32s
[01:54:55.227] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:34:0)
[01:54:55.249] Build status: STARTED
[01:54:55.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:35:0) after 6m8s
[02:01:03.290] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:35:0)
[02:01:03.400] Build status: STARTED
[02:01:03.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:36:0) after 1m47s
[02:02:50.550] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:36:0)
[02:02:50.586] Build status: STARTED
[02:02:50.586] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:37:0) after 3m47s
[02:06:37.654] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:37:0)
[02:06:37.680] Build status: STARTED
[02:06:37.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:38:0) after 9m53s
[02:16:30.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:38:0)
[02:16:30.765] Build status: STARTED
[02:16:30.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:39:0) after 4m14s
[02:20:44.844] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:39:0)
[02:20:44.870] Build status: STARTED
[02:20:44.870] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:40:0) after 6m54s
[02:27:38.967] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:40:0)
[02:27:38.983] Build status: STARTED
[02:27:38.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:41:0) after 5m51s
[02:33:30.086] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:41:0)
[02:33:30.108] Build status: STARTED
[02:33:30.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:42:0) after 8m6s
[02:41:36.301] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:42:0)
[02:41:36.328] Build status: STARTED
[02:41:36.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:43:0) after 7m3s
[02:48:39.311] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:43:0)
[02:48:39.337] Build status: STARTED
[02:48:39.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:44:0) after 8m47s
[02:57:26.358] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:44:0)
[02:57:26.413] Build status: STARTED
[02:57:26.413] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:45:0) after 9m56s
[03:07:22.444] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:45:0)
[03:07:22.466] Build status: STARTED
[03:07:22.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:46:0) after 8m21s
[03:15:43.529] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:46:0)
[03:15:43.551] Build status: STARTED
[03:15:43.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:47:0) after 5m13s
[03:20:56.581] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:47:0)
[03:20:56.610] Build status: STARTED
[03:20:56.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:48:0) after 1m55s
[03:22:51.668] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:48:0)
[03:22:51.752] Build status: STARTED
[03:22:51.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:49:0) after 8m53s
[03:31:44.790] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:49:0)
[03:31:44.810] Build status: STARTED
[03:31:44.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:50:0) after 3m44s
[03:35:28.890] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:50:0)
[03:35:28.907] Build status: STARTED
[03:35:28.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:51:0) after 8m25s
[03:43:54.049] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:51:0)
[03:43:54.072] Build status: STARTED
[03:43:54.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:52:0) after 8m43s
[03:52:37.098] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:52:0)
[03:52:37.201] Build status: STARTED
[03:52:37.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:53:0) after 6m38s
[03:59:15.400] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:53:0)
[03:59:15.428] Build status: STARTED
[03:59:15.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:54:0) after 1m40s
[04:00:55.501] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:54:0)
[04:00:55.517] Build status: STARTED
[04:00:55.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8831362217141849248:55:0) after 7m37s
[04:05:40.563] Received PubSub notification, asking Buildbucket for the build status
[04:05:40.586] Build:
{
"id": "8665932154381165601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 38431,
"createdBy": "project:chromium",
"createTime": "2026-11-05T23:08:18.855283610Z",
"startTime": "2026-11-05T23:08:23.118954690Z",
"endTime": "2026-11-06T04:05:40.265738226Z",
"updateTime": "2026-11-06T04:05:40.265738226Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f46fb8b1fced0420cc2c8f153d505dda3ac3bb80",
"ref": "refs/heads/main"
}
}
}
[04:05:40.586] Invocation finished in 4h57m23.747377497s with status SUCCEEDED