[01:27:10.148] New invocation is queued and will start shortly
[01:27:11.257] Starting the invocation (attempt 1)
[01:27:11.287] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dcea4d2173b2a80f35de2bbfa3a7ed612fb682a"
[01:27:11.287] Popped gitiles commit info from properties and tags
[01:27:11.287] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:27:11.287] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:27:11.287] Buildbucket request:
{
"requestId": "8878735692470543280",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5dcea4d2173b2a80f35de2bbfa3a7ed612fb682a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878735692470543280"
},
{
"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"
}
}
[01:27:11.803] Scheduled build:
{
"id": "8713305630551650817",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 35543,
"createdBy": "project:chromium",
"createTime": "2025-06-01T01:27:11.352541680Z",
"updateTime": "2025-06-01T01:27:11.352541680Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5dcea4d2173b2a80f35de2bbfa3a7ed612fb682a",
"ref": "refs/heads/main"
}
}
}
[01:27:11.803] Task URL: https://cr-buildbucket.appspot.com/build/8713305630551650817
[01:27:11.803] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:2:0) after 4m35s
[01:27:18.725] Received PubSub notification, asking Buildbucket for the build status
[01:27:18.748] Build status: SCHEDULED
[01:28:02.361] Received PubSub notification, asking Buildbucket for the build status
[01:28:02.448] Build status: STARTED
[01:31:46.815] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:2:0)
[01:31:46.840] Build status: STARTED
[01:31:46.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:5:0) after 9m44s
[01:41:30.860] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:5:0)
[01:41:30.953] Build status: STARTED
[01:41:30.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:6:0) after 2m48s
[01:44:19.124] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:6:0)
[01:44:19.150] Build status: STARTED
[01:44:19.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:7:0) after 9m8s
[01:53:27.172] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:7:0)
[01:53:27.196] Build status: STARTED
[01:53:27.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:8:0) after 5m10s
[01:58:37.311] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:8:0)
[01:58:37.334] Build status: STARTED
[01:58:37.334] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:9:0) after 7m59s
[02:06:36.357] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:9:0)
[02:06:36.415] Build status: STARTED
[02:06:36.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:10:0) after 6m39s
[02:13:15.427] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:10:0)
[02:13:15.441] Build status: STARTED
[02:13:15.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:11:0) after 8m4s
[02:21:19.873] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:11:0)
[02:21:19.890] Build status: STARTED
[02:21:19.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:12:0) after 5m9s
[02:26:28.902] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:12:0)
[02:26:28.920] Build status: STARTED
[02:26:28.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:13:0) after 9m42s
[02:36:10.964] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:13:0)
[02:36:10.980] Build status: STARTED
[02:36:10.980] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:14:0) after 1m37s
[02:37:48.063] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:14:0)
[02:37:48.088] Build status: STARTED
[02:37:48.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:15:0) after 8m18s
[02:46:06.489] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:15:0)
[02:46:06.514] Build status: STARTED
[02:46:06.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:16:0) after 4m50s
[02:50:56.537] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:16:0)
[02:50:56.607] Build status: STARTED
[02:50:56.607] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:17:0) after 3m24s
[02:54:20.621] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:17:0)
[02:54:20.641] Build status: STARTED
[02:54:20.641] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:18:0) after 2m47s
[02:57:07.899] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:18:0)
[02:57:07.928] Build status: STARTED
[02:57:07.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:19:0) after 9m32s
[03:06:40.082] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:19:0)
[03:06:40.103] Build status: STARTED
[03:06:40.103] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:20:0) after 9m51s
[03:16:31.330] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:20:0)
[03:16:31.347] Build status: STARTED
[03:16:31.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:21:0) after 6m12s
[03:22:43.382] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:21:0)
[03:22:43.409] Build status: STARTED
[03:22:43.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:22:0) after 8m11s
[03:30:54.429] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:22:0)
[03:30:54.451] Build status: STARTED
[03:30:54.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:23:0) after 6m7s
[03:37:01.634] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:23:0)
[03:37:01.652] Build status: STARTED
[03:37:01.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:24:0) after 7m45s
[03:44:46.674] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:24:0)
[03:44:46.722] Build status: STARTED
[03:44:46.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:25:0) after 4m49s
[03:49:36.282] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:25:0)
[03:49:36.348] Build status: STARTED
[03:49:36.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:26:0) after 7m24s
[03:57:00.577] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:26:0)
[03:57:00.616] Build status: STARTED
[03:57:00.616] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:27:0) after 6m18s
[04:03:18.631] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:27:0)
[04:03:18.655] Build status: STARTED
[04:03:18.655] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:28:0) after 7m59s
[04:11:17.956] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:28:0)
[04:11:17.976] Build status: STARTED
[04:11:17.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:29:0) after 5m0s
[04:16:18.014] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:29:0)
[04:16:18.030] Build status: STARTED
[04:16:18.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:30:0) after 8m48s
[04:25:06.515] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:30:0)
[04:25:06.533] Build status: STARTED
[04:25:06.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:31:0) after 7m42s
[04:32:48.631] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:31:0)
[04:32:48.650] Build status: STARTED
[04:32:48.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:32:0) after 5m28s
[04:38:16.686] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:32:0)
[04:38:16.705] Build status: STARTED
[04:38:16.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:33:0) after 8m16s
[04:46:33.250] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:33:0)
[04:46:33.296] Build status: STARTED
[04:46:33.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:34:0) after 9m6s
[04:55:39.504] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:34:0)
[04:55:39.596] Build status: STARTED
[04:55:39.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:35:0) after 5m45s
[05:01:24.581] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:35:0)
[05:01:24.610] Build status: STARTED
[05:01:24.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:36:0) after 7m45s
[05:09:09.770] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:36:0)
[05:09:09.787] Build status: STARTED
[05:09:09.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:37:0) after 9m17s
[05:18:26.809] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:37:0)
[05:18:26.827] Build status: STARTED
[05:18:26.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8878735692470543280:38:0) after 7m35s
[05:19:30.509] Received PubSub notification, asking Buildbucket for the build status
[05:19:30.529] Build:
{
"id": "8713305630551650817",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 35543,
"createdBy": "project:chromium",
"createTime": "2025-06-01T01:27:11.352541680Z",
"startTime": "2025-06-01T01:27:38.039054501Z",
"endTime": "2025-06-01T05:19:30.256125208Z",
"updateTime": "2025-06-01T05:19:30.256125208Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5dcea4d2173b2a80f35de2bbfa3a7ed612fb682a",
"ref": "refs/heads/main"
}
}
}
[05:19:30.529] Invocation finished in 3h52m20.38986727s with status FAILED