[15:06:42.877] New invocation is queued and will start shortly
[15:06:44.177] Starting the invocation (attempt 1)
[15:06:44.205] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b48f906cd332a5d51f9a957ac0b858b2a3a17052"
[15:06:44.205] Popped gitiles commit info from properties and tags
[15:06:44.205] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:06:44.205] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:06:44.205] Buildbucket request:
{
"requestId": "8889102782265492256",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b48f906cd332a5d51f9a957ac0b858b2a3a17052",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889102782265492256"
},
{
"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"
}
}
[15:06:44.755] Scheduled build:
{
"id": "8723672720153114433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 33423,
"createdBy": "project:chromium",
"createTime": "2025-02-06T15:06:44.264467873Z",
"updateTime": "2025-02-06T15:06:44.264467873Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b48f906cd332a5d51f9a957ac0b858b2a3a17052",
"ref": "refs/heads/main"
}
}
}
[15:06:44.755] Task URL: https://cr-buildbucket.appspot.com/build/8723672720153114433
[15:06:44.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:2:0) after 8m13s
[15:06:45.768] Received PubSub notification, asking Buildbucket for the build status
[15:06:45.785] Build status: SCHEDULED
[15:06:48.179] Received PubSub notification, asking Buildbucket for the build status
[15:06:48.248] Build status: STARTED
[15:14:57.791] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:2:0)
[15:14:57.817] Build status: STARTED
[15:14:57.817] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:5:0) after 7m2s
[15:21:59.951] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:5:0)
[15:21:59.974] Build status: STARTED
[15:21:59.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:6:0) after 7m18s
[15:29:18.104] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:6:0)
[15:29:18.136] Build status: STARTED
[15:29:18.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:7:0) after 6m17s
[15:35:35.157] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:7:0)
[15:35:35.182] Build status: STARTED
[15:35:35.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:8:0) after 6m33s
[15:42:08.209] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:8:0)
[15:42:08.235] Build status: STARTED
[15:42:08.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:9:0) after 4m36s
[15:46:44.258] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:9:0)
[15:46:44.283] Build status: STARTED
[15:46:44.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:10:0) after 6m58s
[15:53:42.302] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:10:0)
[15:53:42.383] Build status: STARTED
[15:53:42.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:11:0) after 1m19s
[15:55:01.446] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:11:0)
[15:55:01.528] Build status: STARTED
[15:55:01.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:12:0) after 6m50s
[16:01:51.595] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:12:0)
[16:01:51.663] Build status: STARTED
[16:01:51.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:13:0) after 6m50s
[16:08:41.832] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:13:0)
[16:08:41.854] Build status: STARTED
[16:08:41.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:14:0) after 6m27s
[16:15:08.931] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:14:0)
[16:15:08.960] Build status: STARTED
[16:15:08.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:15:0) after 4m27s
[16:19:36.094] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:15:0)
[16:19:36.372] Build status: STARTED
[16:19:36.372] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:16:0) after 5m28s
[16:25:04.544] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:16:0)
[16:25:04.563] Build status: STARTED
[16:25:04.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:17:0) after 1m34s
[16:26:38.591] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:17:0)
[16:26:38.611] Build status: STARTED
[16:26:38.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:18:0) after 3m59s
[16:30:37.638] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:18:0)
[16:30:37.652] Build status: STARTED
[16:30:37.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:19:0) after 6m40s
[16:37:17.726] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:19:0)
[16:37:17.743] Build status: STARTED
[16:37:17.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:20:0) after 4m53s
[16:42:10.863] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:20:0)
[16:42:10.887] Build status: STARTED
[16:42:10.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:21:0) after 8m52s
[16:51:02.918] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:21:0)
[16:51:02.942] Build status: STARTED
[16:51:02.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:22:0) after 9m55s
[17:00:57.983] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:22:0)
[17:00:58.002] Build status: STARTED
[17:00:58.002] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:23:0) after 3m15s
[17:04:13.330] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:23:0)
[17:04:13.415] Build status: STARTED
[17:04:13.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:24:0) after 6m24s
[17:10:37.528] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:24:0)
[17:10:37.615] Build status: STARTED
[17:10:37.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:25:0) after 9m49s
[17:20:26.722] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:25:0)
[17:20:26.739] Build status: STARTED
[17:20:26.739] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:26:0) after 6m51s
[17:27:17.885] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:26:0)
[17:27:17.902] Build status: STARTED
[17:27:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:27:0) after 7m39s
[17:34:56.961] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:27:0)
[17:34:56.984] Build status: STARTED
[17:34:56.984] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:28:0) after 7m37s
[17:42:34.092] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:28:0)
[17:42:34.106] Build status: STARTED
[17:42:34.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:29:0) after 4m13s
[17:46:47.160] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:29:0)
[17:46:47.176] Build status: STARTED
[17:46:47.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:30:0) after 6m57s
[17:53:44.207] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:30:0)
[17:53:44.224] Build status: STARTED
[17:53:44.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:31:0) after 8m6s
[18:01:50.270] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:31:0)
[18:01:50.289] Build status: STARTED
[18:01:50.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:32:0) after 4m16s
[18:06:06.317] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:32:0)
[18:06:06.341] Build status: STARTED
[18:06:06.341] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:33:0) after 3m34s
[18:09:40.511] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:33:0)
[18:09:40.529] Build status: STARTED
[18:09:40.529] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:34:0) after 8m40s
[18:18:20.550] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:34:0)
[18:18:20.565] Build status: STARTED
[18:18:20.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:35:0) after 2m47s
[18:21:07.669] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:35:0)
[18:21:07.685] Build status: STARTED
[18:21:07.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:36:0) after 9m54s
[18:31:01.709] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:36:0)
[18:31:01.726] Build status: STARTED
[18:31:01.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:37:0) after 6m50s
[18:37:51.745] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:37:0)
[18:37:51.764] Build status: STARTED
[18:37:51.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:38:0) after 2m25s
[18:40:16.785] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:38:0)
[18:40:16.809] Build status: STARTED
[18:40:16.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:39:0) after 9m4s
[18:49:20.836] Handling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:39:0)
[18:49:20.857] Build status: STARTED
[18:49:20.857] Scheduling timer "check-buildbucket-build-status" (chromium/ToTMacASan:8889102782265492256:40:0) after 5m58s
[18:53:49.616] Received PubSub notification, asking Buildbucket for the build status
[18:53:49.635] Build:
{
"id": "8723672720153114433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTMacASan"
},
"number": 33423,
"createdBy": "project:chromium",
"createTime": "2025-02-06T15:06:44.264467873Z",
"startTime": "2025-02-06T15:06:47.968615895Z",
"endTime": "2025-02-06T18:53:49.084446186Z",
"updateTime": "2025-02-06T18:53:49.084446186Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b48f906cd332a5d51f9a957ac0b858b2a3a17052",
"ref": "refs/heads/main"
}
}
}
[18:53:49.635] Invocation finished in 3h47m6.769172579s with status SUCCEEDED