[17:23:56.925] New invocation is queued and will start shortly
[17:23:58.078] Starting the invocation (attempt 1)
[17:23:58.125] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af7849fe9854abc5969525f57c93ff7ed9b803f2"
[17:23:58.125] Popped gitiles commit info from properties and tags
[17:23:58.125] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:23:58.127] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:23:58.127] Buildbucket request:
{
"requestId": "8993552450511401984",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "af7849fe9854abc5969525f57c93ff7ed9b803f2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993552450511401984"
},
{
"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"
}
}
[17:23:58.854] Scheduled build:
{
"id": "8828122388426548785",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1869,
"createdBy": "project:chromium",
"createTime": "2021-12-11T17:23:58.274786264Z",
"updateTime": "2021-12-11T17:23:58.274786264Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "af7849fe9854abc5969525f57c93ff7ed9b803f2",
"ref": "refs/heads/main"
}
}
}
[17:23:58.854] Task URL: https://cr-buildbucket.appspot.com/build/8828122388426548785
[17:23:58.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:2:0) after 2m52s
[17:26:50.871] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:2:0)
[17:26:51.077] Build status: SCHEDULED
[17:26:51.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:3:0) after 8m10s
[17:35:01.571] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:3:0)
[17:35:01.599] Build status: SCHEDULED
[17:35:01.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:4:0) after 6m21s
[17:41:23.073] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:4:0)
[17:41:23.107] Build status: SCHEDULED
[17:41:23.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:5:0) after 6m53s
[17:48:16.126] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:5:0)
[17:48:16.147] Build status: SCHEDULED
[17:48:16.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:6:0) after 8m54s
[17:57:11.469] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:6:0)
[17:57:11.493] Build status: SCHEDULED
[17:57:11.493] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:7:0) after 2m17s
[17:59:28.514] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:7:0)
[17:59:28.545] Build status: SCHEDULED
[17:59:28.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:8:0) after 1m56s
[18:01:24.608] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:8:0)
[18:01:24.638] Build status: SCHEDULED
[18:01:24.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:9:0) after 2m55s
[18:04:19.657] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:9:0)
[18:04:19.678] Build status: SCHEDULED
[18:04:19.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:10:0) after 3m5s
[18:07:24.758] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:10:0)
[18:07:24.801] Build status: SCHEDULED
[18:07:24.801] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:11:0) after 6m29s
[18:13:54.490] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:11:0)
[18:13:54.509] Build status: SCHEDULED
[18:13:54.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:12:0) after 9m29s
[18:23:23.575] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:12:0)
[18:23:23.593] Build status: SCHEDULED
[18:23:23.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:13:0) after 4m8s
[18:27:31.587] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:13:0)
[18:27:31.621] Build status: SCHEDULED
[18:27:31.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:14:0) after 9m41s
[18:37:12.648] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:14:0)
[18:37:12.683] Build status: SCHEDULED
[18:37:12.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:15:0) after 2m13s
[18:39:25.742] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:15:0)
[18:39:25.775] Build status: SCHEDULED
[18:39:25.775] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:16:0) after 3m44s
[18:43:10.216] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:16:0)
[18:43:10.237] Build status: SCHEDULED
[18:43:10.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:17:0) after 6m17s
[18:49:27.432] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:17:0)
[18:49:27.470] Build status: SCHEDULED
[18:49:27.470] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:18:0) after 3m11s
[18:52:38.488] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:18:0)
[18:52:38.570] Build status: SCHEDULED
[18:52:38.570] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:19:0) after 7m3s
[18:59:41.718] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:19:0)
[18:59:41.738] Build status: SCHEDULED
[18:59:41.738] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:20:0) after 4m30s
[19:04:11.745] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:20:0)
[19:04:11.780] Build status: SCHEDULED
[19:04:11.781] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:21:0) after 5m4s
[19:09:15.921] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:21:0)
[19:09:16.044] Build status: SCHEDULED
[19:09:16.044] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:22:0) after 3m25s
[19:12:41.187] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:22:0)
[19:12:41.262] Build status: SCHEDULED
[19:12:41.262] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:23:0) after 3m52s
[19:16:33.283] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:23:0)
[19:16:33.312] Build status: SCHEDULED
[19:16:33.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:24:0) after 2m23s
[19:18:56.330] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:24:0)
[19:18:56.349] Build status: SCHEDULED
[19:18:56.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:25:0) after 4m20s
[19:23:16.368] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:25:0)
[19:23:16.401] Build status: SCHEDULED
[19:23:16.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:26:0) after 5m1s
[19:28:17.420] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:26:0)
[19:28:17.440] Build status: SCHEDULED
[19:28:17.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:27:0) after 4m11s
[19:32:28.501] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:27:0)
[19:32:28.544] Build status: SCHEDULED
[19:32:28.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:28:0) after 8m14s
[19:40:42.681] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:28:0)
[19:40:42.804] Build status: SCHEDULED
[19:40:42.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:29:0) after 8m17s
[19:46:15.500] Received PubSub notification, asking Buildbucket for the build status
[19:46:15.522] Build status: STARTED
[19:48:59.831] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:29:0)
[19:48:59.854] Build status: STARTED
[19:48:59.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:31:0) after 9m19s
[19:58:18.888] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:31:0)
[19:58:18.909] Build status: STARTED
[19:58:18.909] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:32:0) after 8m43s
[20:07:02.274] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:32:0)
[20:07:02.318] Build status: STARTED
[20:07:02.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:33:0) after 2m18s
[20:09:20.839] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:33:0)
[20:09:20.877] Build status: STARTED
[20:09:20.877] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:34:0) after 3m33s
[20:12:53.904] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:34:0)
[20:12:53.938] Build status: STARTED
[20:12:53.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:35:0) after 3m38s
[20:16:32.152] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:35:0)
[20:16:32.243] Build status: STARTED
[20:16:32.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:36:0) after 6m2s
[20:22:34.270] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:36:0)
[20:22:34.292] Build status: STARTED
[20:22:34.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:37:0) after 2m23s
[20:24:57.293] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:37:0)
[20:24:57.368] Build status: STARTED
[20:24:57.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:38:0) after 5m13s
[20:30:10.392] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:38:0)
[20:30:10.421] Build status: STARTED
[20:30:10.421] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:39:0) after 8m46s
[20:38:57.380] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:39:0)
[20:38:57.405] Build status: STARTED
[20:38:57.405] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:40:0) after 3m49s
[20:42:46.501] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:40:0)
[20:42:46.546] Build status: STARTED
[20:42:46.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:41:0) after 7m46s
[20:50:32.627] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:41:0)
[20:50:32.661] Build status: STARTED
[20:50:32.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:42:0) after 9m6s
[20:59:38.685] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:42:0)
[20:59:38.707] Build status: STARTED
[20:59:38.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:43:0) after 9m36s
[21:09:14.766] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:43:0)
[21:09:14.819] Build status: STARTED
[21:09:14.819] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:44:0) after 1m22s
[21:10:36.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:44:0)
[21:10:36.877] Build status: STARTED
[21:10:36.877] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:45:0) after 5m56s
[21:16:33.031] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:45:0)
[21:16:33.052] Build status: STARTED
[21:16:33.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:46:0) after 7m48s
[21:24:21.140] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:46:0)
[21:24:21.172] Build status: STARTED
[21:24:21.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:47:0) after 9m52s
[21:34:13.415] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:47:0)
[21:34:13.466] Build status: STARTED
[21:34:13.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:48:0) after 3m17s
[21:37:30.525] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:48:0)
[21:37:30.543] Build status: STARTED
[21:37:30.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:49:0) after 7m23s
[21:44:53.894] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:49:0)
[21:44:53.923] Build status: STARTED
[21:44:53.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:50:0) after 4m42s
[21:49:36.182] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:50:0)
[21:49:36.212] Build status: STARTED
[21:49:36.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:51:0) after 8m13s
[21:57:49.258] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:51:0)
[21:57:49.277] Build status: STARTED
[21:57:49.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:52:0) after 9m53s
[22:07:42.300] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:52:0)
[22:07:42.323] Build status: STARTED
[22:07:42.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:53:0) after 3m44s
[22:11:26.582] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:53:0)
[22:11:26.650] Build status: STARTED
[22:11:26.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:54:0) after 3m40s
[22:15:06.764] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:54:0)
[22:15:06.795] Build status: STARTED
[22:15:06.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:55:0) after 9m58s
[22:25:05.308] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:55:0)
[22:25:05.345] Build status: STARTED
[22:25:05.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:56:0) after 8m28s
[22:33:33.334] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:56:0)
[22:33:33.367] Build status: STARTED
[22:33:33.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:57:0) after 9m4s
[22:42:37.561] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:57:0)
[22:42:37.596] Build status: STARTED
[22:42:37.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:58:0) after 1m3s
[22:43:40.740] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:58:0)
[22:43:40.762] Build status: STARTED
[22:43:40.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:59:0) after 3m22s
[22:47:02.924] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:59:0)
[22:47:02.975] Build status: STARTED
[22:47:02.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:60:0) after 2m46s
[22:49:49.284] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:60:0)
[22:49:49.313] Build status: STARTED
[22:49:49.313] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:61:0) after 5m4s
[22:54:53.655] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:61:0)
[22:54:53.699] Build status: STARTED
[22:54:53.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:62:0) after 9m22s
[23:04:16.012] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:62:0)
[23:04:16.033] Build status: STARTED
[23:04:16.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:63:0) after 6m57s
[23:11:13.068] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:63:0)
[23:11:13.147] Build status: STARTED
[23:11:13.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:64:0) after 3m41s
[23:14:54.510] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:64:0)
[23:14:54.538] Build status: STARTED
[23:14:54.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:65:0) after 7m17s
[23:22:11.564] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:65:0)
[23:22:11.585] Build status: STARTED
[23:22:11.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:66:0) after 7m13s
[23:29:24.605] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:66:0)
[23:29:24.638] Build status: STARTED
[23:29:24.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:67:0) after 2m48s
[23:32:12.864] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:67:0)
[23:32:12.906] Build status: STARTED
[23:32:12.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:68:0) after 9m53s
[23:42:06.120] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:68:0)
[23:42:06.192] Build status: STARTED
[23:42:06.192] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:69:0) after 9m8s
[23:51:14.564] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:69:0)
[23:51:14.594] Build status: STARTED
[23:51:14.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:70:0) after 8m12s
[23:59:26.626] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:70:0)
[23:59:26.646] Build status: STARTED
[23:59:26.646] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:71:0) after 2m1s
[00:01:27.669] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:71:0)
[00:01:27.698] Build status: STARTED
[00:01:27.698] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:72:0) after 1m11s
[00:02:38.929] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:72:0)
[00:02:38.950] Build status: STARTED
[00:02:38.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:73:0) after 4m41s
[00:07:20.767] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:73:0)
[00:07:20.799] Build status: STARTED
[00:07:20.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:74:0) after 6m0s
[00:13:21.312] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:74:0)
[00:13:21.332] Build status: STARTED
[00:13:21.332] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:75:0) after 9m32s
[00:22:53.473] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:75:0)
[00:22:53.504] Build status: STARTED
[00:22:53.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:76:0) after 5m35s
[00:28:28.577] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:76:0)
[00:28:28.599] Build status: STARTED
[00:28:28.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:77:0) after 5m9s
[00:33:37.764] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:77:0)
[00:33:37.797] Build status: STARTED
[00:33:37.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:78:0) after 8m51s
[00:42:28.792] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:78:0)
[00:42:28.826] Build status: STARTED
[00:42:28.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:79:0) after 8m25s
[00:50:53.849] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:79:0)
[00:50:53.877] Build status: STARTED
[00:50:53.877] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:80:0) after 6m16s
[00:57:09.902] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:80:0)
[00:57:09.929] Build status: STARTED
[00:57:09.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:81:0) after 1m33s
[00:58:42.941] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:81:0)
[00:58:42.965] Build status: STARTED
[00:58:42.965] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:82:0) after 9m9s
[01:07:53.194] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:82:0)
[01:07:53.226] Build status: STARTED
[01:07:53.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:83:0) after 2m13s
[01:10:06.290] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:83:0)
[01:10:06.460] Build status: STARTED
[01:10:06.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:84:0) after 8m12s
[01:18:18.486] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:84:0)
[01:18:18.521] Build status: STARTED
[01:18:18.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:85:0) after 7m9s
[01:25:27.701] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:85:0)
[01:25:27.732] Build status: STARTED
[01:25:27.732] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:86:0) after 3m0s
[01:28:27.996] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:86:0)
[01:28:28.089] Build status: STARTED
[01:28:28.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:87:0) after 4m13s
[01:32:41.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:87:0)
[01:32:41.195] Build status: STARTED
[01:32:41.195] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:88:0) after 4m10s
[01:36:51.214] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:88:0)
[01:36:51.248] Build status: STARTED
[01:36:51.248] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:89:0) after 7m11s
[01:44:02.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:89:0)
[01:44:02.344] Build status: STARTED
[01:44:02.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:90:0) after 9m57s
[01:53:59.495] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:90:0)
[01:53:59.527] Build status: STARTED
[01:53:59.527] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:91:0) after 8m0s
[02:01:59.703] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:91:0)
[02:01:59.731] Build status: STARTED
[02:01:59.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:92:0) after 9m17s
[02:11:16.764] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:92:0)
[02:11:16.783] Build status: STARTED
[02:11:16.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:93:0) after 9m31s
[02:20:47.812] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:93:0)
[02:20:47.829] Build status: STARTED
[02:20:47.829] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:94:0) after 6m18s
[02:27:06.352] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:94:0)
[02:27:06.392] Build status: STARTED
[02:27:06.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:95:0) after 9m39s
[02:36:45.401] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:95:0)
[02:36:45.437] Build status: STARTED
[02:36:45.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:96:0) after 6m17s
[02:43:02.560] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:96:0)
[02:43:02.592] Build status: STARTED
[02:43:02.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:97:0) after 2m34s
[02:45:36.612] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:97:0)
[02:45:36.653] Build status: STARTED
[02:45:36.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:98:0) after 2m36s
[02:48:12.769] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:98:0)
[02:48:12.795] Build status: STARTED
[02:48:12.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:99:0) after 1m26s
[02:49:39.081] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:99:0)
[02:49:39.106] Build status: STARTED
[02:49:39.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:100:0) after 6m56s
[02:56:35.126] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:100:0)
[02:56:35.162] Build status: STARTED
[02:56:35.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:101:0) after 4m54s
[03:01:29.231] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:101:0)
[03:01:29.267] Build status: STARTED
[03:01:29.267] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:102:0) after 7m39s
[03:09:08.290] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:102:0)
[03:09:08.312] Build status: STARTED
[03:09:08.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:103:0) after 4m48s
[03:13:56.338] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:103:0)
[03:13:56.376] Build status: STARTED
[03:13:56.376] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:104:0) after 3m3s
[03:16:59.456] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:104:0)
[03:16:59.479] Build status: STARTED
[03:16:59.479] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:105:0) after 5m49s
[03:22:48.503] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:105:0)
[03:22:48.524] Build status: STARTED
[03:22:48.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:106:0) after 9m58s
[03:32:46.544] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:106:0)
[03:32:46.579] Build status: STARTED
[03:32:46.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:107:0) after 3m23s
[03:36:10.005] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:107:0)
[03:36:10.100] Build status: STARTED
[03:36:10.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:108:0) after 9m55s
[03:46:05.333] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:108:0)
[03:46:05.358] Build status: STARTED
[03:46:05.358] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:109:0) after 3m40s
[03:49:45.832] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:109:0)
[03:49:45.866] Build status: STARTED
[03:49:45.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:110:0) after 4m16s
[03:54:01.910] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:110:0)
[03:54:01.955] Build status: STARTED
[03:54:01.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:111:0) after 5m21s
[03:59:22.988] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:111:0)
[03:59:23.008] Build status: STARTED
[03:59:23.008] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:112:0) after 4m31s
[04:03:54.186] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:112:0)
[04:03:54.253] Build status: STARTED
[04:03:54.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:113:0) after 5m45s
[04:09:39.277] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:113:0)
[04:09:39.304] Build status: STARTED
[04:09:39.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:114:0) after 4m41s
[04:14:20.324] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:114:0)
[04:14:20.354] Build status: STARTED
[04:14:20.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:115:0) after 5m8s
[04:19:28.445] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:115:0)
[04:19:28.482] Build status: STARTED
[04:19:28.482] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:116:0) after 7m34s
[04:27:02.502] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:116:0)
[04:27:02.535] Build status: STARTED
[04:27:02.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993552450511401984:117:0) after 4m12s
[04:27:28.541] Received PubSub notification, asking Buildbucket for the build status
[04:27:28.589] Build:
{
"id": "8828122388426548785",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1869,
"createdBy": "project:chromium",
"createTime": "2021-12-11T17:23:58.274786264Z",
"startTime": "2021-12-11T19:46:13.364871Z",
"endTime": "2021-12-12T04:27:28.203709434Z",
"updateTime": "2021-12-12T04:27:28.203709434Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "af7849fe9854abc5969525f57c93ff7ed9b803f2",
"ref": "refs/heads/main"
}
}
}
[04:27:28.589] Invocation finished in 11h3m31.686271768s with status FAILED