[18:14:45.062] New invocation is queued and will start shortly
[18:14:46.148] Starting the invocation (attempt 1)
[18:14:46.186] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/204c48ef81f2ebe39d47d87678113f91b83e6b3e"
[18:14:46.186] Popped gitiles commit info from properties and tags
[18:14:46.186] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:14:46.188] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:14:46.188] Buildbucket request:
{
"requestId": "8934661226135801696",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "204c48ef81f2ebe39d47d87678113f91b83e6b3e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934661226135801696"
},
{
"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"
}
}
[18:14:46.782] Scheduled build:
{
"id": "8769231164199220177",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 6314,
"createdBy": "project:chromium",
"createTime": "2023-09-22T18:14:46.281594432Z",
"updateTime": "2023-09-22T18:14:46.281594432Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "204c48ef81f2ebe39d47d87678113f91b83e6b3e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:14:46.782] Task URL: https://cr-buildbucket.appspot.com/build/8769231164199220177
[18:14:46.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:2:0) after 9m39s
[18:14:47.705] Received PubSub notification, asking Buildbucket for the build status
[18:14:47.737] Build status: SCHEDULED
[18:15:12.045] Received PubSub notification, asking Buildbucket for the build status
[18:15:12.087] Build status: STARTED
[18:24:25.811] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:2:0)
[18:24:25.832] Build status: STARTED
[18:24:25.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:5:0) after 3m40s
[18:28:05.900] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:5:0)
[18:28:05.985] Build status: STARTED
[18:28:05.985] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:6:0) after 3m10s
[18:31:16.151] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:6:0)
[18:31:16.181] Build status: STARTED
[18:31:16.181] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:7:0) after 4m12s
[18:35:28.209] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:7:0)
[18:35:28.240] Build status: STARTED
[18:35:28.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:8:0) after 7m59s
[18:43:27.351] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:8:0)
[18:43:27.369] Build status: STARTED
[18:43:27.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:9:0) after 4m24s
[18:47:51.391] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:9:0)
[18:47:51.446] Build status: STARTED
[18:47:51.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:10:0) after 5m29s
[18:53:20.461] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:10:0)
[18:53:20.482] Build status: STARTED
[18:53:20.482] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:11:0) after 9m46s
[19:03:06.494] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:11:0)
[19:03:06.530] Build status: STARTED
[19:03:06.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:12:0) after 2m55s
[19:06:02.036] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:12:0)
[19:06:02.066] Build status: STARTED
[19:06:02.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:13:0) after 3m1s
[19:09:03.176] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:13:0)
[19:09:03.193] Build status: STARTED
[19:09:03.193] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:14:0) after 6m1s
[19:15:04.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:14:0)
[19:15:04.426] Build status: STARTED
[19:15:04.427] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:15:0) after 1m43s
[19:16:47.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:15:0)
[19:16:47.850] Build status: STARTED
[19:16:47.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:16:0) after 7m34s
[19:24:21.873] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:16:0)
[19:24:22.054] Build status: STARTED
[19:24:22.054] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:17:0) after 9m55s
[19:34:17.078] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:17:0)
[19:34:17.099] Build status: STARTED
[19:34:17.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:18:0) after 2m6s
[19:36:23.159] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:18:0)
[19:36:23.186] Build status: STARTED
[19:36:23.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:19:0) after 8m15s
[19:44:38.204] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:19:0)
[19:44:38.234] Build status: STARTED
[19:44:38.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:20:0) after 5m27s
[19:50:05.290] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:20:0)
[19:50:05.322] Build status: STARTED
[19:50:05.322] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:21:0) after 1m58s
[19:52:03.339] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:21:0)
[19:52:03.357] Build status: STARTED
[19:52:03.357] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:22:0) after 2m25s
[19:54:28.355] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:22:0)
[19:54:28.410] Build status: STARTED
[19:54:28.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:23:0) after 2m31s
[19:56:59.460] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:23:0)
[19:56:59.480] Build status: STARTED
[19:56:59.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:24:0) after 4m16s
[20:01:15.623] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:24:0)
[20:01:15.715] Build status: STARTED
[20:01:15.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:25:0) after 7m39s
[20:08:54.805] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:25:0)
[20:08:54.840] Build status: STARTED
[20:08:54.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:26:0) after 7m10s
[20:16:04.873] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:26:0)
[20:16:04.895] Build status: STARTED
[20:16:04.895] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:27:0) after 5m41s
[20:21:45.940] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:27:0)
[20:21:46.009] Build status: STARTED
[20:21:46.009] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:28:0) after 5m13s
[20:26:59.157] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:28:0)
[20:26:59.219] Build status: STARTED
[20:26:59.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:29:0) after 7m22s
[20:34:21.256] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:29:0)
[20:34:21.286] Build status: STARTED
[20:34:21.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:30:0) after 3m42s
[20:38:03.505] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:30:0)
[20:38:03.522] Build status: STARTED
[20:38:03.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:31:0) after 7m58s
[20:46:01.614] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:31:0)
[20:46:01.638] Build status: STARTED
[20:46:01.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:32:0) after 3m46s
[20:49:47.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:32:0)
[20:49:47.792] Build status: STARTED
[20:49:47.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:33:0) after 5m49s
[20:55:36.847] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:33:0)
[20:55:36.884] Build status: STARTED
[20:55:36.884] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:34:0) after 2m29s
[20:58:06.028] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:34:0)
[20:58:06.073] Build status: STARTED
[20:58:06.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:35:0) after 5m13s
[21:03:19.287] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:35:0)
[21:03:19.316] Build status: STARTED
[21:03:19.317] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:36:0) after 4m33s
[21:07:52.334] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:36:0)
[21:07:52.357] Build status: STARTED
[21:07:52.357] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:37:0) after 5m56s
[21:13:48.375] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:37:0)
[21:13:48.410] Build status: STARTED
[21:13:48.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:38:0) after 3m1s
[21:16:49.493] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:38:0)
[21:16:49.516] Build status: STARTED
[21:16:49.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:39:0) after 6m56s
[21:23:45.532] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:39:0)
[21:23:45.550] Build status: STARTED
[21:23:45.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:40:0) after 2m34s
[21:26:19.754] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:40:0)
[21:26:19.770] Build status: STARTED
[21:26:19.770] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:41:0) after 7m3s
[21:33:22.964] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:41:0)
[21:33:23.081] Build status: STARTED
[21:33:23.081] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:42:0) after 3m30s
[21:36:53.264] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:42:0)
[21:36:53.289] Build status: STARTED
[21:36:53.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:43:0) after 6m40s
[21:43:33.409] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:43:0)
[21:43:33.436] Build status: STARTED
[21:43:33.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:44:0) after 5m40s
[21:49:13.462] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:44:0)
[21:49:13.489] Build status: STARTED
[21:49:13.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:45:0) after 9m0s
[21:58:13.556] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:45:0)
[21:58:13.575] Build status: STARTED
[21:58:13.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:46:0) after 8m36s
[22:06:49.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:46:0)
[22:06:49.615] Build status: STARTED
[22:06:49.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:47:0) after 2m38s
[22:09:27.644] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:47:0)
[22:09:27.665] Build status: STARTED
[22:09:27.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:48:0) after 4m12s
[22:13:39.681] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:48:0)
[22:13:39.705] Build status: STARTED
[22:13:39.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:49:0) after 7m39s
[22:21:18.881] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:49:0)
[22:21:18.973] Build status: STARTED
[22:21:18.973] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:50:0) after 5m57s
[22:27:16.077] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:50:0)
[22:27:16.131] Build status: STARTED
[22:27:16.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:51:0) after 1m28s
[22:28:44.147] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:51:0)
[22:28:44.238] Build status: STARTED
[22:28:44.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:52:0) after 4m33s
[22:33:17.353] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:52:0)
[22:33:17.423] Build status: STARTED
[22:33:17.423] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:53:0) after 4m51s
[22:38:08.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:53:0)
[22:38:08.939] Build status: STARTED
[22:38:08.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:54:0) after 9m4s
[22:47:12.961] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:54:0)
[22:47:12.979] Build status: STARTED
[22:47:12.979] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:55:0) after 3m56s
[22:51:09.011] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:55:0)
[22:51:09.033] Build status: STARTED
[22:51:09.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:56:0) after 2m54s
[22:54:03.056] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:56:0)
[22:54:03.076] Build status: STARTED
[22:54:03.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:57:0) after 6m42s
[23:00:45.464] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:57:0)
[23:00:45.499] Build status: STARTED
[23:00:45.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:58:0) after 9m33s
[23:10:18.928] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:58:0)
[23:10:18.945] Build status: STARTED
[23:10:18.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:59:0) after 8m57s
[23:19:16.094] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:59:0)
[23:19:16.135] Build status: STARTED
[23:19:16.135] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:60:0) after 5m16s
[23:24:32.251] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:60:0)
[23:24:32.463] Build status: STARTED
[23:24:32.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:61:0) after 7m33s
[23:32:05.665] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:61:0)
[23:32:05.691] Build status: STARTED
[23:32:05.691] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:62:0) after 9m55s
[23:42:00.706] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:62:0)
[23:42:00.782] Build status: STARTED
[23:42:00.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:63:0) after 1m8s
[23:43:09.138] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:63:0)
[23:43:09.156] Build status: STARTED
[23:43:09.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:64:0) after 6m28s
[23:49:37.187] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:64:0)
[23:49:37.204] Build status: STARTED
[23:49:37.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:65:0) after 8m34s
[23:58:11.309] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:65:0)
[23:58:11.337] Build status: STARTED
[23:58:11.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:66:0) after 2m17s
[00:00:28.354] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:66:0)
[00:00:28.377] Build status: STARTED
[00:00:28.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:67:0) after 2m44s
[00:03:12.404] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:67:0)
[00:03:12.434] Build status: STARTED
[00:03:12.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:68:0) after 8m17s
[00:11:29.460] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:68:0)
[00:11:29.482] Build status: STARTED
[00:11:29.482] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:69:0) after 9m21s
[00:20:50.716] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:69:0)
[00:20:50.734] Build status: STARTED
[00:20:50.734] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:70:0) after 5m14s
[00:26:04.711] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:70:0)
[00:26:04.730] Build status: STARTED
[00:26:04.730] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:71:0) after 7m16s
[00:33:20.840] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:71:0)
[00:33:20.893] Build status: STARTED
[00:33:20.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:72:0) after 2m39s
[00:35:59.979] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:72:0)
[00:36:00.001] Build status: STARTED
[00:36:00.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:73:0) after 4m38s
[00:40:38.036] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:73:0)
[00:40:38.054] Build status: STARTED
[00:40:38.054] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:74:0) after 8m59s
[00:49:37.178] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:74:0)
[00:49:37.255] Build status: STARTED
[00:49:37.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:75:0) after 9m4s
[00:58:41.512] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:75:0)
[00:58:41.531] Build status: STARTED
[00:58:41.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:76:0) after 2m49s
[01:01:30.524] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:76:0)
[01:01:30.569] Build status: STARTED
[01:01:30.569] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:77:0) after 3m46s
[01:05:16.629] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:77:0)
[01:05:16.652] Build status: STARTED
[01:05:16.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:78:0) after 7m36s
[01:12:52.711] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:78:0)
[01:12:52.728] Build status: STARTED
[01:12:52.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:79:0) after 7m47s
[01:20:39.889] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:79:0)
[01:20:39.922] Build status: STARTED
[01:20:39.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:80:0) after 3m37s
[01:24:17.115] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:80:0)
[01:24:17.142] Build status: STARTED
[01:24:17.142] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:81:0) after 2m57s
[01:27:14.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:81:0)
[01:27:14.296] Build status: STARTED
[01:27:14.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:82:0) after 8m23s
[01:35:37.411] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:82:0)
[01:35:37.428] Build status: STARTED
[01:35:37.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:83:0) after 9m43s
[01:45:21.221] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:83:0)
[01:45:21.258] Build status: STARTED
[01:45:21.258] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:84:0) after 6m12s
[01:51:33.342] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:84:0)
[01:51:33.381] Build status: STARTED
[01:51:33.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:85:0) after 9m15s
[02:00:48.837] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:85:0)
[02:00:48.859] Build status: STARTED
[02:00:48.859] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:86:0) after 2m48s
[02:03:36.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:86:0)
[02:03:36.847] Build status: STARTED
[02:03:36.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:87:0) after 1m2s
[02:04:38.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:87:0)
[02:04:38.859] Build status: STARTED
[02:04:38.859] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:88:0) after 1m51s
[02:06:30.127] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:88:0)
[02:06:30.151] Build status: STARTED
[02:06:30.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:89:0) after 2m26s
[02:08:56.171] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:89:0)
[02:08:56.187] Build status: STARTED
[02:08:56.187] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:90:0) after 3m16s
[02:12:12.212] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:90:0)
[02:12:12.233] Build status: STARTED
[02:12:12.233] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:91:0) after 2m51s
[02:15:03.518] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:91:0)
[02:15:03.691] Build status: STARTED
[02:15:03.692] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:92:0) after 6m48s
[02:21:51.912] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:92:0)
[02:21:51.932] Build status: STARTED
[02:21:51.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:93:0) after 1m54s
[02:23:46.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:93:0)
[02:23:46.277] Build status: STARTED
[02:23:46.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:94:0) after 2m43s
[02:26:29.294] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:94:0)
[02:26:29.312] Build status: STARTED
[02:26:29.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:95:0) after 9m37s
[02:36:06.331] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:95:0)
[02:36:06.399] Build status: STARTED
[02:36:06.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:96:0) after 4m7s
[02:40:13.586] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:96:0)
[02:40:13.631] Build status: STARTED
[02:40:13.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:97:0) after 7m9s
[02:47:22.646] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:97:0)
[02:47:24.051] Build status: STARTED
[02:47:24.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:98:0) after 9m18s
[02:56:42.190] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:98:0)
[02:56:42.208] Build status: STARTED
[02:56:42.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:99:0) after 3m34s
[03:00:16.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:99:0)
[03:00:17.016] Build status: STARTED
[03:00:17.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:100:0) after 3m38s
[03:03:55.147] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:100:0)
[03:03:55.176] Build status: STARTED
[03:03:55.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:101:0) after 5m38s
[03:09:33.653] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:101:0)
[03:09:33.672] Build status: STARTED
[03:09:33.673] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:102:0) after 1m37s
[03:11:10.815] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:102:0)
[03:11:10.838] Build status: STARTED
[03:11:10.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:103:0) after 9m5s
[03:20:15.895] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:103:0)
[03:20:15.918] Build status: STARTED
[03:20:15.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:104:0) after 7m59s
[03:28:14.967] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:104:0)
[03:28:14.988] Build status: STARTED
[03:28:14.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:105:0) after 2m22s
[03:30:37.228] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:105:0)
[03:30:37.246] Build status: STARTED
[03:30:37.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:106:0) after 5m57s
[03:36:34.593] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:106:0)
[03:36:34.610] Build status: STARTED
[03:36:34.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:107:0) after 1m18s
[03:37:52.632] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:107:0)
[03:37:52.651] Build status: STARTED
[03:37:52.651] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:108:0) after 9m7s
[03:46:59.757] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:108:0)
[03:46:59.777] Build status: STARTED
[03:46:59.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:109:0) after 3m36s
[03:50:36.041] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:109:0)
[03:50:36.125] Build status: STARTED
[03:50:36.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:110:0) after 6m17s
[03:56:53.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:110:0)
[03:56:53.264] Build status: STARTED
[03:56:53.264] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:111:0) after 8m39s
[04:05:32.326] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:111:0)
[04:05:32.373] Build status: STARTED
[04:05:32.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:112:0) after 4m15s
[04:09:47.493] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:112:0)
[04:09:47.510] Build status: STARTED
[04:09:47.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:113:0) after 5m53s
[04:15:40.728] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:113:0)
[04:15:40.752] Build status: STARTED
[04:15:40.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:114:0) after 2m11s
[04:17:51.767] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:114:0)
[04:17:51.788] Build status: STARTED
[04:17:51.788] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8934661226135801696:115:0) after 6m10s
[04:20:12.382] Received PubSub notification, asking Buildbucket for the build status
[04:20:12.400] Build:
{
"id": "8769231164199220177",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 6314,
"createdBy": "project:chromium",
"createTime": "2023-09-22T18:14:46.281594432Z",
"startTime": "2023-09-22T18:15:11.163192Z",
"endTime": "2023-09-23T04:20:12.128625756Z",
"updateTime": "2023-09-23T04:20:12.128625756Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "204c48ef81f2ebe39d47d87678113f91b83e6b3e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:20:12.400] Invocation finished in 10h5m27.34955929s with status FAILED