[16:05:34.253] New invocation is queued and will start shortly
[16:05:35.390] Starting the invocation (attempt 1)
[16:05:35.436] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2597880fca4c1eed94f3580dd72a457b91b21e0b"
[16:05:35.436] Popped gitiles commit info from properties and tags
[16:05:35.436] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:05:35.436] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:05:35.437] Buildbucket request:
{
"requestId": "8872701028434311088",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2597880fca4c1eed94f3580dd72a457b91b21e0b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872701028434311088"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTMacASan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:05:36.044] Scheduled build:
{
"id": "8707270966419258193",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 36188,
"createdBy": "project:chromium",
"createTime": "2025-08-06T16:05:35.544929738Z",
"updateTime": "2025-08-06T16:05:35.544929738Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2597880fca4c1eed94f3580dd72a457b91b21e0b",
"ref": "refs/heads/main"
}
}
}
[16:05:36.044] Task URL: https://cr-buildbucket.appspot.com/build/8707270966419258193
[16:05:36.044] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:2:0) after 2m29s
[16:05:36.248] Received PubSub notification, asking Buildbucket for the build status
[16:05:36.264] Build status: SCHEDULED
[16:05:43.555] Received PubSub notification, asking Buildbucket for the build status
[16:05:43.582] Build status: STARTED
[16:08:05.102] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:2:0)
[16:08:05.129] Build status: STARTED
[16:08:05.129] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:5:0) after 2m46s
[16:10:51.142] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:5:0)
[16:10:51.175] Build status: STARTED
[16:10:51.175] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:6:0) after 7m9s
[16:18:00.205] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:6:0)
[16:18:00.232] Build status: STARTED
[16:18:00.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:7:0) after 6m49s
[16:24:49.246] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:7:0)
[16:24:49.320] Build status: STARTED
[16:24:49.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:8:0) after 7m29s
[16:32:18.415] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:8:0)
[16:32:18.443] Build status: STARTED
[16:32:18.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:9:0) after 3m36s
[16:35:54.488] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:9:0)
[16:35:54.556] Build status: STARTED
[16:35:54.557] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:10:0) after 3m56s
[16:39:50.573] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:10:0)
[16:39:50.593] Build status: STARTED
[16:39:50.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:11:0) after 4m20s
[16:44:10.616] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:11:0)
[16:44:10.644] Build status: STARTED
[16:44:10.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:12:0) after 5m50s
[16:50:00.665] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:12:0)
[16:50:00.713] Build status: STARTED
[16:50:00.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:13:0) after 2m6s
[16:52:06.742] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:13:0)
[16:52:06.771] Build status: STARTED
[16:52:06.772] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:14:0) after 3m57s
[16:56:03.956] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:14:0)
[16:56:03.974] Build status: STARTED
[16:56:03.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:15:0) after 8m23s
[17:04:26.992] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:15:0)
[17:04:27.017] Build status: STARTED
[17:04:27.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:16:0) after 5m3s
[17:09:30.094] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:16:0)
[17:09:30.110] Build status: STARTED
[17:09:30.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:17:0) after 2m0s
[17:11:30.129] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:17:0)
[17:11:30.147] Build status: STARTED
[17:11:30.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:18:0) after 5m38s
[17:17:08.186] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:18:0)
[17:17:08.208] Build status: STARTED
[17:17:08.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:19:0) after 4m58s
[17:22:06.232] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:19:0)
[17:22:06.252] Build status: STARTED
[17:22:06.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:20:0) after 8m7s
[17:30:13.326] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:20:0)
[17:30:13.345] Build status: STARTED
[17:30:13.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:21:0) after 1m15s
[17:31:28.372] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:21:0)
[17:31:28.391] Build status: STARTED
[17:31:28.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:22:0) after 8m3s
[17:39:31.413] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:22:0)
[17:39:31.428] Build status: STARTED
[17:39:31.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:23:0) after 5m24s
[17:44:55.498] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:23:0)
[17:44:55.518] Build status: STARTED
[17:44:55.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:24:0) after 6m49s
[17:51:44.591] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:24:0)
[17:51:44.606] Build status: STARTED
[17:51:44.606] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:25:0) after 2m1s
[17:53:45.663] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:25:0)
[17:53:45.680] Build status: STARTED
[17:53:45.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:26:0) after 2m54s
[17:56:39.846] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:26:0)
[17:56:39.875] Build status: STARTED
[17:56:39.876] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:27:0) after 9m29s
[18:06:08.902] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:27:0)
[18:06:08.923] Build status: STARTED
[18:06:08.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:28:0) after 8m33s
[18:14:41.984] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:28:0)
[18:14:42.044] Build status: STARTED
[18:14:42.044] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:29:0) after 8m55s
[18:23:37.077] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:29:0)
[18:23:37.099] Build status: STARTED
[18:23:37.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:30:0) after 3m54s
[18:27:31.103] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:30:0)
[18:27:31.132] Build status: STARTED
[18:27:31.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:31:0) after 1m37s
[18:29:08.186] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:31:0)
[18:29:08.205] Build status: STARTED
[18:29:08.205] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:32:0) after 7m27s
[18:36:35.248] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:32:0)
[18:36:35.320] Build status: STARTED
[18:36:35.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:33:0) after 7m24s
[18:43:59.503] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:33:0)
[18:43:59.545] Build status: STARTED
[18:43:59.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:34:0) after 9m2s
[18:53:01.642] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:34:0)
[18:53:01.673] Build status: STARTED
[18:53:01.673] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:35:0) after 7m41s
[19:00:42.926] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:35:0)
[19:00:42.940] Build status: STARTED
[19:00:42.940] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:36:0) after 7m15s
[19:07:58.009] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:36:0)
[19:07:58.026] Build status: STARTED
[19:07:58.027] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:37:0) after 3m55s
[19:11:53.131] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:37:0)
[19:11:53.163] Build status: STARTED
[19:11:53.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:38:0) after 6m44s
[19:18:37.685] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:38:0)
[19:18:37.700] Build status: STARTED
[19:18:37.700] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:39:0) after 4m32s
[19:23:09.758] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:39:0)
[19:23:09.803] Build status: STARTED
[19:23:09.803] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:40:0) after 4m22s
[19:27:31.853] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:40:0)
[19:27:31.875] Build status: STARTED
[19:27:31.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:41:0) after 7m45s
[19:35:16.937] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:41:0)
[19:35:16.956] Build status: STARTED
[19:35:16.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:42:0) after 6m56s
[19:42:13.037] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:42:0)
[19:42:13.059] Build status: STARTED
[19:42:13.059] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:43:0) after 7m31s
[19:49:44.426] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:43:0)
[19:49:44.446] Build status: STARTED
[19:49:44.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:44:0) after 9m45s
[19:59:29.694] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:44:0)
[19:59:29.714] Build status: STARTED
[19:59:29.714] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:45:0) after 8m53s
[20:08:22.789] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:45:0)
[20:08:22.812] Build status: STARTED
[20:08:22.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:46:0) after 2m25s
[20:10:48.196] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:46:0)
[20:10:48.213] Build status: STARTED
[20:10:48.213] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:47:0) after 7m37s
[20:18:25.273] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:47:0)
[20:18:25.293] Build status: STARTED
[20:18:25.293] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8872701028434311088:48:0) after 8m8s
[20:21:47.033] Received PubSub notification, asking Buildbucket for the build status
[20:21:47.051] Build:
{
"id": "8707270966419258193",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 36188,
"createdBy": "project:chromium",
"createTime": "2025-08-06T16:05:35.544929738Z",
"startTime": "2025-08-06T16:05:42.192999308Z",
"endTime": "2025-08-06T20:21:46.681714793Z",
"updateTime": "2025-08-06T20:21:46.681714793Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2597880fca4c1eed94f3580dd72a457b91b21e0b",
"ref": "refs/heads/main"
}
}
}
[20:21:47.051] Invocation finished in 4h16m12.813138662s with status SUCCEEDED