[16:50:38.512] New invocation is queued and will start shortly
[16:50:39.595] Starting the invocation (attempt 1)
[16:50:39.619] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b76bf38789065da4764d7480bd8c05809fef553"
[16:50:39.619] Popped gitiles commit info from properties and tags
[16:50:39.619] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:50:39.619] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:50:39.619] Buildbucket request:
{
"requestId": "8909842949036688096",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9b76bf38789065da4764d7480bd8c05809fef553",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8909842949036688096"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin64PGO"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:50:40.373] Scheduled build:
{
"id": "8744412887150379665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 9308,
"createdBy": "project:chromium",
"createTime": "2024-06-22T16:50:39.680460974Z",
"updateTime": "2024-06-22T16:50:39.680460974Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9b76bf38789065da4764d7480bd8c05809fef553",
"ref": "refs/heads/main"
}
}
}
[16:50:40.373] Task URL: https://cr-buildbucket.appspot.com/build/8744412887150379665
[16:50:40.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:2:0) after 7m0s
[16:50:41.232] Received PubSub notification, asking Buildbucket for the build status
[16:50:41.263] Build status: SCHEDULED
[16:52:30.214] Received PubSub notification, asking Buildbucket for the build status
[16:52:30.240] Build status: STARTED
[16:57:40.363] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:2:0)
[16:57:40.383] Build status: STARTED
[16:57:40.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:5:0) after 4m38s
[17:02:18.505] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:5:0)
[17:02:18.536] Build status: STARTED
[17:02:18.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:6:0) after 1m34s
[17:03:52.575] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:6:0)
[17:03:52.594] Build status: STARTED
[17:03:52.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:7:0) after 1m24s
[17:05:16.616] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:7:0)
[17:05:16.631] Build status: STARTED
[17:05:16.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:8:0) after 2m0s
[17:07:16.665] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:8:0)
[17:07:16.687] Build status: STARTED
[17:07:16.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:9:0) after 9m19s
[17:16:35.774] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:9:0)
[17:16:35.802] Build status: STARTED
[17:16:35.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:10:0) after 3m2s
[17:19:38.090] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:10:0)
[17:19:38.158] Build status: STARTED
[17:19:38.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:11:0) after 2m8s
[17:21:46.837] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:11:0)
[17:21:46.867] Build status: STARTED
[17:21:46.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:12:0) after 2m21s
[17:24:07.886] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:12:0)
[17:24:07.944] Build status: STARTED
[17:24:07.944] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:13:0) after 8m57s
[17:33:04.969] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:13:0)
[17:33:04.986] Build status: STARTED
[17:33:04.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:14:0) after 4m35s
[17:37:39.997] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:14:0)
[17:37:40.042] Build status: STARTED
[17:37:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:15:0) after 6m53s
[17:44:33.067] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:15:0)
[17:44:33.091] Build status: STARTED
[17:44:33.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:16:0) after 8m26s
[17:52:59.117] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:16:0)
[17:52:59.181] Build status: STARTED
[17:52:59.181] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:17:0) after 9m30s
[18:02:29.220] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:17:0)
[18:02:29.238] Build status: STARTED
[18:02:29.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:18:0) after 2m17s
[18:04:46.526] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:18:0)
[18:04:46.546] Build status: STARTED
[18:04:46.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:19:0) after 3m8s
[18:07:55.864] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:19:0)
[18:07:55.884] Build status: STARTED
[18:07:55.884] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:20:0) after 4m45s
[18:12:40.934] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:20:0)
[18:12:40.956] Build status: STARTED
[18:12:40.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:21:0) after 7m6s
[18:19:46.985] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:21:0)
[18:19:47.004] Build status: STARTED
[18:19:47.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:22:0) after 7m53s
[18:27:40.252] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:22:0)
[18:27:40.269] Build status: STARTED
[18:27:40.269] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:23:0) after 6m42s
[18:34:22.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:23:0)
[18:34:22.587] Build status: STARTED
[18:34:22.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:24:0) after 9m33s
[18:43:55.610] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:24:0)
[18:43:55.629] Build status: STARTED
[18:43:55.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:25:0) after 5m0s
[18:48:55.649] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:25:0)
[18:48:55.666] Build status: STARTED
[18:48:55.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:26:0) after 1m12s
[18:50:07.745] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:26:0)
[18:50:07.818] Build status: STARTED
[18:50:07.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:27:0) after 8m50s
[18:58:57.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:27:0)
[18:58:57.860] Build status: STARTED
[18:58:57.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:28:0) after 4m49s
[19:03:46.888] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:28:0)
[19:03:46.907] Build status: STARTED
[19:03:46.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:29:0) after 1m23s
[19:05:09.943] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:29:0)
[19:05:09.967] Build status: STARTED
[19:05:09.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:30:0) after 1m29s
[19:06:39.064] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:30:0)
[19:06:39.082] Build status: STARTED
[19:06:39.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:31:0) after 8m37s
[19:15:16.101] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:31:0)
[19:15:16.144] Build status: STARTED
[19:15:16.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:32:0) after 9m7s
[19:24:23.168] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:32:0)
[19:24:23.186] Build status: STARTED
[19:24:23.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:33:0) after 1m41s
[19:26:04.533] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:33:0)
[19:26:04.565] Build status: STARTED
[19:26:04.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:34:0) after 4m48s
[19:30:53.065] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:34:0)
[19:30:53.082] Build status: STARTED
[19:30:53.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:35:0) after 6m43s
[19:37:36.110] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:35:0)
[19:37:36.139] Build status: STARTED
[19:37:36.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:36:0) after 8m55s
[19:46:31.302] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:36:0)
[19:46:31.321] Build status: STARTED
[19:46:31.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:37:0) after 3m34s
[19:50:05.339] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:37:0)
[19:50:05.377] Build status: STARTED
[19:50:05.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:38:0) after 7m15s
[19:57:20.500] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:38:0)
[19:57:20.526] Build status: STARTED
[19:57:20.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:39:0) after 9m25s
[20:06:45.655] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:39:0)
[20:06:45.678] Build status: STARTED
[20:06:45.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:40:0) after 9m0s
[20:15:45.763] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:40:0)
[20:15:45.785] Build status: STARTED
[20:15:45.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:41:0) after 3m20s
[20:19:05.805] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:41:0)
[20:19:05.888] Build status: STARTED
[20:19:05.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:42:0) after 6m18s
[20:25:23.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:42:0)
[20:25:23.936] Build status: STARTED
[20:25:23.936] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:43:0) after 5m38s
[20:31:02.054] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:43:0)
[20:31:02.109] Build status: STARTED
[20:31:02.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:44:0) after 1m13s
[20:32:15.221] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:44:0)
[20:32:15.238] Build status: STARTED
[20:32:15.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:45:0) after 3m8s
[20:35:23.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:45:0)
[20:35:23.291] Build status: STARTED
[20:35:23.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:46:0) after 8m18s
[20:43:41.426] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:46:0)
[20:43:41.443] Build status: STARTED
[20:43:41.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:47:0) after 4m19s
[20:48:00.601] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:47:0)
[20:48:00.744] Build status: STARTED
[20:48:00.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:48:0) after 5m18s
[20:53:19.031] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:48:0)
[20:53:19.058] Build status: STARTED
[20:53:19.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:49:0) after 4m1s
[20:57:20.076] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:49:0)
[20:57:20.140] Build status: STARTED
[20:57:20.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:50:0) after 8m58s
[21:06:18.275] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:50:0)
[21:06:18.299] Build status: STARTED
[21:06:18.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:51:0) after 9m42s
[21:16:00.330] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:51:0)
[21:16:00.351] Build status: STARTED
[21:16:00.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:52:0) after 9m59s
[21:25:59.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:52:0)
[21:25:59.390] Build status: STARTED
[21:25:59.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:53:0) after 1m24s
[21:27:23.419] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:53:0)
[21:27:23.485] Build status: STARTED
[21:27:23.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:54:0) after 5m6s
[21:32:29.526] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:54:0)
[21:32:29.542] Build status: STARTED
[21:32:29.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:55:0) after 3m27s
[21:35:56.558] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:55:0)
[21:35:56.574] Build status: STARTED
[21:35:56.574] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:56:0) after 4m3s
[21:39:59.597] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:56:0)
[21:39:59.621] Build status: STARTED
[21:39:59.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:57:0) after 8m9s
[21:48:08.658] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:57:0)
[21:48:08.678] Build status: STARTED
[21:48:08.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:58:0) after 9m14s
[21:57:22.700] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:58:0)
[21:57:22.728] Build status: STARTED
[21:57:22.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:59:0) after 6m41s
[22:04:03.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:59:0)
[22:04:03.784] Build status: STARTED
[22:04:03.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:60:0) after 9m20s
[22:13:23.857] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:60:0)
[22:13:23.882] Build status: STARTED
[22:13:23.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:61:0) after 7m36s
[22:20:59.916] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:61:0)
[22:20:59.935] Build status: STARTED
[22:20:59.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:62:0) after 8m0s
[22:28:59.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:62:0)
[22:28:59.977] Build status: STARTED
[22:28:59.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:63:0) after 9m40s
[22:38:40.000] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:63:0)
[22:38:40.031] Build status: STARTED
[22:38:40.031] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:64:0) after 9m58s
[22:48:38.305] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:64:0)
[22:48:38.324] Build status: STARTED
[22:48:38.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:65:0) after 5m53s
[22:54:31.808] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:65:0)
[22:54:31.827] Build status: STARTED
[22:54:31.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:66:0) after 1m7s
[22:55:39.546] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:66:0)
[22:55:39.565] Build status: STARTED
[22:55:39.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:67:0) after 3m19s
[22:58:58.552] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:67:0)
[22:58:58.571] Build status: STARTED
[22:58:58.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:68:0) after 9m53s
[23:08:51.640] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:68:0)
[23:08:51.783] Build status: STARTED
[23:08:51.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:69:0) after 4m24s
[23:13:15.816] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:69:0)
[23:13:15.833] Build status: STARTED
[23:13:15.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:70:0) after 5m17s
[23:18:32.937] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:70:0)
[23:18:33.117] Build status: STARTED
[23:18:33.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:71:0) after 5m41s
[23:24:14.148] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:71:0)
[23:24:14.171] Build status: STARTED
[23:24:14.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:72:0) after 4m35s
[23:28:49.199] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:72:0)
[23:28:49.233] Build status: STARTED
[23:28:49.233] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:73:0) after 3m37s
[23:32:26.251] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:73:0)
[23:32:26.272] Build status: STARTED
[23:32:26.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:74:0) after 5m54s
[23:38:20.291] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:74:0)
[23:38:20.308] Build status: STARTED
[23:38:20.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:75:0) after 8m28s
[23:46:48.354] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:75:0)
[23:46:48.381] Build status: STARTED
[23:46:48.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:76:0) after 1m45s
[23:48:33.591] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:76:0)
[23:48:33.617] Build status: STARTED
[23:48:33.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:77:0) after 1m15s
[23:49:48.745] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:77:0)
[23:49:48.769] Build status: STARTED
[23:49:48.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:78:0) after 7m57s
[23:57:46.425] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:78:0)
[23:57:46.507] Build status: STARTED
[23:57:46.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:79:0) after 4m29s
[00:02:15.507] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:79:0)
[00:02:15.531] Build status: STARTED
[00:02:15.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:80:0) after 6m46s
[00:09:02.052] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:80:0)
[00:09:02.072] Build status: STARTED
[00:09:02.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:81:0) after 4m47s
[00:13:49.042] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:81:0)
[00:13:49.068] Build status: STARTED
[00:13:49.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:82:0) after 1m20s
[00:15:09.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:82:0)
[00:15:09.157] Build status: STARTED
[00:15:09.157] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:83:0) after 9m29s
[00:24:38.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:83:0)
[00:24:38.260] Build status: STARTED
[00:24:38.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:84:0) after 2m55s
[00:27:33.291] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:84:0)
[00:27:33.312] Build status: STARTED
[00:27:33.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:85:0) after 8m20s
[00:35:53.910] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:85:0)
[00:35:53.933] Build status: STARTED
[00:35:53.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:86:0) after 8m46s
[00:44:39.952] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:86:0)
[00:44:40.016] Build status: STARTED
[00:44:40.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:87:0) after 5m29s
[00:50:09.039] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:87:0)
[00:50:09.064] Build status: STARTED
[00:50:09.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:88:0) after 2m52s
[00:53:01.084] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:88:0)
[00:53:01.104] Build status: STARTED
[00:53:01.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:89:0) after 3m43s
[00:56:44.135] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:89:0)
[00:56:44.152] Build status: STARTED
[00:56:44.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:90:0) after 1m12s
[00:57:56.180] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:90:0)
[00:57:56.200] Build status: STARTED
[00:57:56.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:91:0) after 2m45s
[01:00:41.298] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:91:0)
[01:00:41.377] Build status: STARTED
[01:00:41.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:92:0) after 9m15s
[01:09:56.403] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:92:0)
[01:09:56.426] Build status: STARTED
[01:09:56.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:93:0) after 4m20s
[01:14:16.483] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:93:0)
[01:14:16.504] Build status: STARTED
[01:14:16.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:94:0) after 2m37s
[01:16:54.080] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:94:0)
[01:16:54.195] Build status: STARTED
[01:16:54.195] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:95:0) after 2m57s
[01:19:51.414] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:95:0)
[01:19:51.432] Build status: STARTED
[01:19:51.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:96:0) after 6m35s
[01:26:26.462] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:96:0)
[01:26:26.478] Build status: STARTED
[01:26:26.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:97:0) after 3m30s
[01:29:56.495] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:97:0)
[01:29:56.516] Build status: STARTED
[01:29:56.516] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:98:0) after 1m32s
[01:31:28.540] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:98:0)
[01:31:28.565] Build status: STARTED
[01:31:28.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:99:0) after 1m6s
[01:32:34.585] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:99:0)
[01:32:34.608] Build status: STARTED
[01:32:34.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:100:0) after 3m30s
[01:36:04.627] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:100:0)
[01:36:04.643] Build status: STARTED
[01:36:04.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:101:0) after 2m5s
[01:38:09.779] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:101:0)
[01:38:09.795] Build status: STARTED
[01:38:09.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:102:0) after 8m3s
[01:46:13.202] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:102:0)
[01:46:13.224] Build status: STARTED
[01:46:13.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:103:0) after 6m8s
[01:52:21.246] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:103:0)
[01:52:21.265] Build status: STARTED
[01:52:21.265] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:104:0) after 9m27s
[02:01:48.375] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:104:0)
[02:01:48.394] Build status: STARTED
[02:01:48.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:105:0) after 2m23s
[02:04:11.417] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:105:0)
[02:04:11.436] Build status: STARTED
[02:04:11.436] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:106:0) after 2m31s
[02:06:42.630] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:106:0)
[02:06:42.655] Build status: STARTED
[02:06:42.655] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:107:0) after 7m41s
[02:14:24.251] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:107:0)
[02:14:24.283] Build status: STARTED
[02:14:24.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:108:0) after 5m56s
[02:20:21.499] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:108:0)
[02:20:21.522] Build status: STARTED
[02:20:21.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:109:0) after 3m11s
[02:23:32.639] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:109:0)
[02:23:32.715] Build status: STARTED
[02:23:32.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:110:0) after 4m33s
[02:28:05.843] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:110:0)
[02:28:05.886] Build status: STARTED
[02:28:05.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:111:0) after 6m25s
[02:34:31.320] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:111:0)
[02:34:31.363] Build status: STARTED
[02:34:31.363] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:112:0) after 1m34s
[02:36:05.795] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:112:0)
[02:36:06.171] Build status: STARTED
[02:36:06.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:113:0) after 9m44s
[02:45:50.300] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:113:0)
[02:45:50.318] Build status: STARTED
[02:45:50.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:114:0) after 4m12s
[02:50:03.721] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:114:0)
[02:50:03.771] Build status: STARTED
[02:50:03.771] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:115:0) after 9m27s
[02:59:31.311] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:115:0)
[02:59:31.338] Build status: STARTED
[02:59:31.338] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:116:0) after 7m40s
[03:07:11.426] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:116:0)
[03:07:11.445] Build status: STARTED
[03:07:11.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:117:0) after 7m22s
[03:14:33.476] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:117:0)
[03:14:33.494] Build status: STARTED
[03:14:33.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:118:0) after 8m14s
[03:22:47.514] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:118:0)
[03:22:47.536] Build status: STARTED
[03:22:47.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:119:0) after 3m52s
[03:26:39.581] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:119:0)
[03:26:39.605] Build status: STARTED
[03:26:39.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:120:0) after 8m23s
[03:35:02.660] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:120:0)
[03:35:02.706] Build status: STARTED
[03:35:02.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:121:0) after 7m38s
[03:42:40.735] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:121:0)
[03:42:40.795] Build status: STARTED
[03:42:40.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:122:0) after 4m50s
[03:47:30.825] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:122:0)
[03:47:30.845] Build status: STARTED
[03:47:30.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:123:0) after 4m11s
[03:51:42.059] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:123:0)
[03:51:42.097] Build status: STARTED
[03:51:42.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:124:0) after 1m5s
[03:52:47.203] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:124:0)
[03:52:47.229] Build status: STARTED
[03:52:47.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:125:0) after 8m56s
[04:01:43.256] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:125:0)
[04:01:43.282] Build status: STARTED
[04:01:43.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:126:0) after 4m1s
[04:05:44.315] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:126:0)
[04:05:44.337] Build status: STARTED
[04:05:44.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:127:0) after 3m5s
[04:08:49.520] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:127:0)
[04:08:49.601] Build status: STARTED
[04:08:49.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:128:0) after 5m30s
[04:14:19.627] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:128:0)
[04:14:19.676] Build status: STARTED
[04:14:19.676] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:129:0) after 2m41s
[04:17:00.719] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:129:0)
[04:17:00.742] Build status: STARTED
[04:17:00.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:130:0) after 6m9s
[04:23:09.769] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:130:0)
[04:23:09.785] Build status: STARTED
[04:23:09.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:131:0) after 2m53s
[04:26:02.856] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:131:0)
[04:26:02.883] Build status: STARTED
[04:26:02.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:132:0) after 6m54s
[04:32:56.916] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:132:0)
[04:32:56.940] Build status: STARTED
[04:32:56.940] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:133:0) after 1m59s
[04:34:55.936] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:133:0)
[04:34:55.955] Build status: STARTED
[04:34:55.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:134:0) after 6m46s
[04:41:42.380] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:134:0)
[04:41:42.400] Build status: STARTED
[04:41:42.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:135:0) after 4m15s
[04:45:57.907] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:135:0)
[04:45:57.923] Build status: STARTED
[04:45:57.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:136:0) after 7m19s
[04:53:16.901] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:136:0)
[04:53:16.921] Build status: STARTED
[04:53:16.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8909842949036688096:137:0) after 3m26s
[04:54:25.205] Received PubSub notification, asking Buildbucket for the build status
[04:54:25.380] Build:
{
"id": "8744412887150379665",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 9308,
"createdBy": "project:chromium",
"createTime": "2024-06-22T16:50:39.680460974Z",
"startTime": "2024-06-22T16:52:30.014312299Z",
"endTime": "2024-06-23T04:54:24.947482078Z",
"updateTime": "2024-06-23T04:54:24.947482078Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9b76bf38789065da4764d7480bd8c05809fef553",
"ref": "refs/heads/main"
}
}
}
[04:54:25.380] Invocation finished in 12h3m46.88214411s with status FAILED