[10:36:38.636] New invocation is queued and will start shortly
[10:36:39.693] Starting the invocation (attempt 1)
[10:36:39.716] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b43e7205a049300f26ce51f038ec5ef546a934b"
[10:36:39.716] Popped gitiles commit info from properties and tags
[10:36:39.716] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:36:39.716] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:36:39.717] Buildbucket request:
{
"requestId": "8859494565625828752",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxPGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0b43e7205a049300f26ce51f038ec5ef546a934b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8859494565625828752"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTLinuxPGO"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:36:40.683] Scheduled build:
{
"id": "8694064503792158113",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxPGO"
},
"number": 19009,
"createdBy": "project:chromium",
"createTime": "2025-12-30T10:36:39.762238532Z",
"updateTime": "2025-12-30T10:36:39.762238532Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0b43e7205a049300f26ce51f038ec5ef546a934b",
"ref": "refs/heads/main"
}
}
}
[10:36:40.683] Task URL: https://cr-buildbucket.appspot.com/build/8694064503792158113
[10:36:40.684] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:2:0) after 2m9s
[10:36:41.147] Received PubSub notification, asking Buildbucket for the build status
[10:36:41.166] Build status: SCHEDULED
[10:37:37.957] Received PubSub notification, asking Buildbucket for the build status
[10:37:37.994] Build status: STARTED
[10:38:49.722] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:2:0)
[10:38:49.737] Build status: STARTED
[10:38:49.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:5:0) after 4m50s
[10:43:39.944] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:5:0)
[10:43:39.957] Build status: STARTED
[10:43:39.957] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:6:0) after 4m20s
[10:47:59.983] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:6:0)
[10:48:00.004] Build status: STARTED
[10:48:00.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:7:0) after 3m45s
[10:51:45.302] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:7:0)
[10:51:45.315] Build status: STARTED
[10:51:45.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:8:0) after 8m6s
[10:59:51.351] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:8:0)
[10:59:51.384] Build status: STARTED
[10:59:51.384] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:9:0) after 9m24s
[11:09:15.369] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:9:0)
[11:09:15.447] Build status: STARTED
[11:09:15.447] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:10:0) after 4m37s
[11:13:52.495] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:10:0)
[11:13:52.514] Build status: STARTED
[11:13:52.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:11:0) after 9m2s
[11:22:54.529] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:11:0)
[11:22:54.549] Build status: STARTED
[11:22:54.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:12:0) after 9m44s
[11:32:38.714] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:12:0)
[11:32:38.755] Build status: STARTED
[11:32:38.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:13:0) after 3m53s
[11:36:31.914] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:13:0)
[11:36:31.934] Build status: STARTED
[11:36:31.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:14:0) after 4m49s
[11:41:21.006] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:14:0)
[11:41:21.030] Build status: STARTED
[11:41:21.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:15:0) after 1m56s
[11:43:17.070] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:15:0)
[11:43:17.095] Build status: STARTED
[11:43:17.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:16:0) after 3m16s
[11:46:33.182] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:16:0)
[11:46:33.211] Build status: STARTED
[11:46:33.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:17:0) after 6m16s
[11:52:49.213] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:17:0)
[11:52:49.235] Build status: STARTED
[11:52:49.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:18:0) after 1m31s
[11:54:20.443] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:18:0)
[11:54:20.455] Build status: STARTED
[11:54:20.455] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:19:0) after 1m2s
[11:55:22.517] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:19:0)
[11:55:22.531] Build status: STARTED
[11:55:22.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:20:0) after 2m29s
[11:57:51.702] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:20:0)
[11:57:51.715] Build status: STARTED
[11:57:51.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:21:0) after 5m53s
[12:03:44.732] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:21:0)
[12:03:44.748] Build status: STARTED
[12:03:44.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:22:0) after 2m15s
[12:05:59.788] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:22:0)
[12:05:59.803] Build status: STARTED
[12:05:59.803] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:23:0) after 4m49s
[12:10:48.819] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:23:0)
[12:10:48.838] Build status: STARTED
[12:10:48.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:24:0) after 7m16s
[12:18:05.160] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:24:0)
[12:18:05.228] Build status: STARTED
[12:18:05.228] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:25:0) after 5m18s
[12:23:23.222] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:25:0)
[12:23:23.270] Build status: STARTED
[12:23:23.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:26:0) after 7m2s
[12:30:25.625] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:26:0)
[12:30:25.650] Build status: STARTED
[12:30:25.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:27:0) after 4m4s
[12:34:29.734] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:27:0)
[12:34:29.774] Build status: STARTED
[12:34:29.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:28:0) after 1m31s
[12:36:00.795] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:28:0)
[12:36:00.859] Build status: STARTED
[12:36:00.859] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:29:0) after 9m0s
[12:45:00.877] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:29:0)
[12:45:00.914] Build status: STARTED
[12:45:00.914] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:30:0) after 7m42s
[12:52:42.926] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:30:0)
[12:52:42.940] Build status: STARTED
[12:52:42.940] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:31:0) after 3m28s
[12:56:10.954] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:31:0)
[12:56:11.039] Build status: STARTED
[12:56:11.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:32:0) after 1m40s
[12:57:51.082] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:32:0)
[12:57:51.097] Build status: STARTED
[12:57:51.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:33:0) after 3m22s
[13:01:13.267] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:33:0)
[13:01:13.283] Build status: STARTED
[13:01:13.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:34:0) after 9m13s
[13:10:26.307] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:34:0)
[13:10:26.340] Build status: STARTED
[13:10:26.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:35:0) after 6m57s
[13:17:23.355] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:35:0)
[13:17:23.369] Build status: STARTED
[13:17:23.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:36:0) after 9m39s
[13:27:02.430] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:36:0)
[13:27:02.445] Build status: STARTED
[13:27:02.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:37:0) after 2m37s
[13:29:39.498] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:37:0)
[13:29:39.511] Build status: STARTED
[13:29:39.511] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:38:0) after 2m3s
[13:31:42.582] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:38:0)
[13:31:42.597] Build status: STARTED
[13:31:42.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:39:0) after 8m0s
[13:39:42.935] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:39:0)
[13:39:42.970] Build status: STARTED
[13:39:42.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:40:0) after 8m9s
[13:47:52.035] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:40:0)
[13:47:52.050] Build status: STARTED
[13:47:52.050] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:41:0) after 1m1s
[13:48:53.138] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:41:0)
[13:48:53.164] Build status: STARTED
[13:48:53.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:42:0) after 3m52s
[13:52:45.240] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:42:0)
[13:52:45.252] Build status: STARTED
[13:52:45.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:43:0) after 4m58s
[13:57:43.328] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:43:0)
[13:57:43.343] Build status: STARTED
[13:57:43.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:44:0) after 6m25s
[14:04:08.388] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:44:0)
[14:04:08.400] Build status: STARTED
[14:04:08.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:45:0) after 6m36s
[14:10:44.518] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:45:0)
[14:10:44.555] Build status: STARTED
[14:10:44.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:46:0) after 5m18s
[14:16:02.562] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:46:0)
[14:16:02.627] Build status: STARTED
[14:16:02.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:47:0) after 8m26s
[14:24:28.655] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:47:0)
[14:24:28.668] Build status: STARTED
[14:24:28.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:48:0) after 5m57s
[14:30:25.817] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:48:0)
[14:30:25.833] Build status: STARTED
[14:30:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:49:0) after 4m2s
[14:34:27.848] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:49:0)
[14:34:27.947] Build status: STARTED
[14:34:27.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:50:0) after 3m1s
[14:37:28.995] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:50:0)
[14:37:29.018] Build status: STARTED
[14:37:29.018] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:51:0) after 1m51s
[14:39:20.105] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:51:0)
[14:39:20.120] Build status: STARTED
[14:39:20.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:52:0) after 3m50s
[14:43:10.447] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:52:0)
[14:43:10.462] Build status: STARTED
[14:43:10.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:53:0) after 9m59s
[14:53:09.481] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:53:0)
[14:53:09.499] Build status: STARTED
[14:53:09.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:54:0) after 2m16s
[14:55:25.565] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:54:0)
[14:55:25.757] Build status: STARTED
[14:55:25.757] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:55:0) after 2m25s
[14:57:50.808] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:55:0)
[14:57:50.824] Build status: STARTED
[14:57:50.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:56:0) after 4m10s
[15:02:00.850] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:56:0)
[15:02:00.879] Build status: STARTED
[15:02:00.879] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:57:0) after 5m31s
[15:07:32.460] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:57:0)
[15:07:32.552] Build status: STARTED
[15:07:32.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:58:0) after 6m23s
[15:13:55.569] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:58:0)
[15:13:56.094] Build status: STARTED
[15:13:56.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:59:0) after 2m15s
[15:16:11.110] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:59:0)
[15:16:11.143] Build status: STARTED
[15:16:11.143] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:60:0) after 1m37s
[15:17:48.166] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:60:0)
[15:17:48.235] Build status: STARTED
[15:17:48.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:61:0) after 8m8s
[15:25:56.300] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:61:0)
[15:25:56.313] Build status: STARTED
[15:25:56.313] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:62:0) after 2m59s
[15:28:55.327] Handling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:62:0)
[15:28:55.338] Build status: STARTED
[15:28:55.338] Scheduling timer "check-buildbucket-build-status" (chromium/ToTLinuxPGO:8859494565625828752:63:0) after 4m17s
[15:33:08.000] Received PubSub notification, asking Buildbucket for the build status
[15:33:08.019] Build:
{
"id": "8694064503792158113",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTLinuxPGO"
},
"number": 19009,
"createdBy": "project:chromium",
"createTime": "2025-12-30T10:36:39.762238532Z",
"startTime": "2025-12-30T10:37:37.540798913Z",
"endTime": "2025-12-30T15:33:01.821173545Z",
"updateTime": "2025-12-30T15:33:01.821173545Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0b43e7205a049300f26ce51f038ec5ef546a934b",
"ref": "refs/heads/main"
}
}
}
[15:33:08.019] Invocation finished in 4h56m29.389869162s with status SUCCEEDED