[14:08:10.311] New invocation is queued and will start shortly
[14:08:11.384] Starting the invocation (attempt 1)
[14:08:11.416] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c7a19e2eb75677e0b5b4fc6d507722767846107"
[14:08:11.416] Popped gitiles commit info from properties and tags
[14:08:11.416] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:08:11.420] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:08:11.420] Buildbucket request:
{
"requestId": "8947360314848775008",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c7a19e2eb75677e0b5b4fc6d507722767846107",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8947360314848775008"
},
{
"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"
}
}
[14:08:11.851] Scheduled build:
{
"id": "8781930252960929505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 5237,
"createdBy": "project:chromium",
"createTime": "2023-05-05T14:08:11.488612613Z",
"updateTime": "2023-05-05T14:08:11.488612613Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c7a19e2eb75677e0b5b4fc6d507722767846107",
"ref": "refs/heads/main"
}
}
}
[14:08:11.851] Task URL: https://cr-buildbucket.appspot.com/build/8781930252960929505
[14:08:11.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:2:0) after 7m1s
[14:08:25.714] Received PubSub notification, asking Buildbucket for the build status
[14:08:25.888] Build status: STARTED
[14:15:13.081] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:2:0)
[14:15:13.129] Build status: STARTED
[14:15:13.129] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:4:0) after 6m6s
[14:21:19.250] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:4:0)
[14:21:19.270] Build status: STARTED
[14:21:19.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:5:0) after 4m36s
[14:25:55.301] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:5:0)
[14:25:55.333] Build status: STARTED
[14:25:55.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:6:0) after 4m20s
[14:30:15.904] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:6:0)
[14:30:15.940] Build status: STARTED
[14:30:15.940] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:7:0) after 2m36s
[14:32:52.074] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:7:0)
[14:32:52.103] Build status: STARTED
[14:32:52.103] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:8:0) after 9m34s
[14:42:26.316] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:8:0)
[14:42:26.344] Build status: STARTED
[14:42:26.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:9:0) after 3m34s
[14:46:00.428] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:9:0)
[14:46:00.466] Build status: STARTED
[14:46:00.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:10:0) after 8m7s
[14:54:07.558] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:10:0)
[14:54:07.584] Build status: STARTED
[14:54:07.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:11:0) after 7m27s
[15:01:34.638] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:11:0)
[15:01:34.730] Build status: STARTED
[15:01:34.730] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:12:0) after 3m21s
[15:04:55.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:12:0)
[15:04:55.862] Build status: STARTED
[15:04:55.862] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:13:0) after 1m3s
[15:05:58.899] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:13:0)
[15:05:58.955] Build status: STARTED
[15:05:58.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:14:0) after 2m53s
[15:08:52.012] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:14:0)
[15:08:52.031] Build status: STARTED
[15:08:52.032] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:15:0) after 5m30s
[15:14:22.536] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:15:0)
[15:14:22.556] Build status: STARTED
[15:14:22.556] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:16:0) after 8m37s
[15:22:59.902] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:16:0)
[15:23:00.029] Build status: STARTED
[15:23:00.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:17:0) after 3m35s
[15:26:35.150] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:17:0)
[15:26:35.178] Build status: STARTED
[15:26:35.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:18:0) after 7m24s
[15:33:59.224] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:18:0)
[15:33:59.263] Build status: STARTED
[15:33:59.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:19:0) after 8m24s
[15:42:23.591] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:19:0)
[15:42:23.613] Build status: STARTED
[15:42:23.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:20:0) after 8m19s
[15:50:42.647] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:20:0)
[15:50:42.671] Build status: STARTED
[15:50:42.671] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:21:0) after 7m9s
[15:57:52.059] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:21:0)
[15:57:52.087] Build status: STARTED
[15:57:52.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:22:0) after 1m3s
[15:58:55.624] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:22:0)
[15:58:55.644] Build status: STARTED
[15:58:55.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:23:0) after 1m15s
[16:00:10.783] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:23:0)
[16:00:10.836] Build status: STARTED
[16:00:10.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:24:0) after 5m52s
[16:06:02.872] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:24:0)
[16:06:02.900] Build status: STARTED
[16:06:02.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:25:0) after 9m13s
[16:15:15.945] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:25:0)
[16:15:15.966] Build status: STARTED
[16:15:15.966] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:26:0) after 4m19s
[16:19:34.989] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:26:0)
[16:19:35.010] Build status: STARTED
[16:19:35.010] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:27:0) after 2m36s
[16:22:11.698] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:27:0)
[16:22:11.719] Build status: STARTED
[16:22:11.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:28:0) after 6m43s
[16:28:54.810] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:28:0)
[16:28:54.831] Build status: STARTED
[16:28:54.831] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:29:0) after 2m44s
[16:31:39.112] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:29:0)
[16:31:39.157] Build status: STARTED
[16:31:39.157] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:30:0) after 4m29s
[16:36:08.216] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:30:0)
[16:36:08.239] Build status: STARTED
[16:36:08.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:31:0) after 8m45s
[16:44:53.637] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:31:0)
[16:44:53.715] Build status: STARTED
[16:44:53.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:32:0) after 5m43s
[16:50:36.843] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:32:0)
[16:50:36.866] Build status: STARTED
[16:50:36.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:33:0) after 3m35s
[16:54:11.890] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:33:0)
[16:54:11.929] Build status: STARTED
[16:54:11.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:34:0) after 1m45s
[16:55:56.957] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:34:0)
[16:55:56.975] Build status: STARTED
[16:55:56.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:35:0) after 8m40s
[17:04:37.148] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:35:0)
[17:04:37.245] Build status: STARTED
[17:04:37.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:36:0) after 9m27s
[17:14:04.613] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:36:0)
[17:14:04.653] Build status: STARTED
[17:14:04.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:37:0) after 5m57s
[17:20:01.694] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:37:0)
[17:20:01.723] Build status: STARTED
[17:20:01.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:38:0) after 8m12s
[17:28:13.801] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:38:0)
[17:28:13.824] Build status: STARTED
[17:28:13.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:39:0) after 7m12s
[17:35:25.850] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:39:0)
[17:35:25.868] Build status: STARTED
[17:35:25.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:40:0) after 9m50s
[17:45:16.265] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:40:0)
[17:45:16.299] Build status: STARTED
[17:45:16.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:41:0) after 2m57s
[17:48:13.338] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:41:0)
[17:48:13.452] Build status: STARTED
[17:48:13.452] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:42:0) after 1m18s
[17:49:31.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:42:0)
[17:49:31.614] Build status: STARTED
[17:49:31.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:43:0) after 7m35s
[17:57:06.909] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:43:0)
[17:57:06.930] Build status: STARTED
[17:57:06.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:44:0) after 7m57s
[18:05:03.945] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:44:0)
[18:05:03.971] Build status: STARTED
[18:05:03.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:45:0) after 2m3s
[18:07:07.063] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:45:0)
[18:07:07.084] Build status: STARTED
[18:07:07.084] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:46:0) after 9m2s
[18:16:09.132] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:46:0)
[18:16:09.158] Build status: STARTED
[18:16:09.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:47:0) after 2m2s
[18:18:11.182] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:47:0)
[18:18:11.218] Build status: STARTED
[18:18:11.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:48:0) after 9m26s
[18:27:37.260] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:48:0)
[18:27:37.298] Build status: STARTED
[18:27:37.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:49:0) after 1m21s
[18:28:58.373] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:49:0)
[18:28:58.401] Build status: STARTED
[18:28:58.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:50:0) after 6m12s
[18:35:10.888] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:50:0)
[18:35:10.933] Build status: STARTED
[18:35:10.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:51:0) after 7m56s
[18:43:07.079] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:51:0)
[18:43:07.122] Build status: STARTED
[18:43:07.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:52:0) after 2m5s
[18:45:12.199] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:52:0)
[18:45:12.225] Build status: STARTED
[18:45:12.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:53:0) after 6m30s
[18:51:42.258] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:53:0)
[18:51:42.274] Build status: STARTED
[18:51:42.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:54:0) after 2m4s
[18:53:46.403] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:54:0)
[18:53:46.450] Build status: STARTED
[18:53:46.450] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:55:0) after 2m58s
[18:56:44.472] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:55:0)
[18:56:44.493] Build status: STARTED
[18:56:44.493] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:56:0) after 7m47s
[19:04:31.625] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:56:0)
[19:04:31.656] Build status: STARTED
[19:04:31.656] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:57:0) after 2m36s
[19:07:07.732] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:57:0)
[19:07:07.752] Build status: STARTED
[19:07:07.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:58:0) after 3m23s
[19:10:30.801] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:58:0)
[19:10:30.827] Build status: STARTED
[19:10:30.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:59:0) after 6m32s
[19:17:02.981] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:59:0)
[19:17:03.001] Build status: STARTED
[19:17:03.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:60:0) after 6m19s
[19:23:22.203] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:60:0)
[19:23:22.222] Build status: STARTED
[19:23:22.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:61:0) after 6m36s
[19:29:58.309] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:61:0)
[19:29:58.328] Build status: STARTED
[19:29:58.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:62:0) after 1m28s
[19:31:26.374] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:62:0)
[19:31:26.393] Build status: STARTED
[19:31:26.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:63:0) after 9m12s
[19:40:38.558] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:63:0)
[19:40:38.712] Build status: STARTED
[19:40:38.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:64:0) after 6m32s
[19:47:10.846] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:64:0)
[19:47:10.877] Build status: STARTED
[19:47:10.877] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:65:0) after 3m23s
[19:50:33.852] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:65:0)
[19:50:33.873] Build status: STARTED
[19:50:33.874] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:66:0) after 3m24s
[19:53:57.900] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:66:0)
[19:53:57.927] Build status: STARTED
[19:53:57.927] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:67:0) after 4m53s
[19:58:50.933] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:67:0)
[19:58:50.956] Build status: STARTED
[19:58:50.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:68:0) after 8m2s
[20:06:53.155] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:68:0)
[20:06:53.206] Build status: STARTED
[20:06:53.206] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:69:0) after 7m3s
[20:13:56.618] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:69:0)
[20:13:56.668] Build status: STARTED
[20:13:56.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:70:0) after 4m15s
[20:18:12.153] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:70:0)
[20:18:12.184] Build status: STARTED
[20:18:12.184] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:71:0) after 1m14s
[20:19:26.317] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:71:0)
[20:19:26.340] Build status: STARTED
[20:19:26.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:72:0) after 4m58s
[20:24:24.366] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:72:0)
[20:24:24.391] Build status: STARTED
[20:24:24.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:73:0) after 5m44s
[20:30:08.453] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:73:0)
[20:30:08.533] Build status: STARTED
[20:30:08.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:74:0) after 9m36s
[20:39:44.557] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:74:0)
[20:39:44.576] Build status: STARTED
[20:39:44.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:75:0) after 8m19s
[20:48:03.719] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:75:0)
[20:48:03.741] Build status: STARTED
[20:48:03.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:76:0) after 8m40s
[20:56:44.343] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:76:0)
[20:56:44.367] Build status: STARTED
[20:56:44.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:77:0) after 7m59s
[21:04:43.392] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:77:0)
[21:04:43.415] Build status: STARTED
[21:04:43.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:78:0) after 7m10s
[21:11:53.521] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:78:0)
[21:11:53.542] Build status: STARTED
[21:11:53.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:79:0) after 7m26s
[21:19:19.567] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:79:0)
[21:19:19.590] Build status: STARTED
[21:19:19.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:80:0) after 9m4s
[21:28:23.630] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:80:0)
[21:28:23.657] Build status: STARTED
[21:28:23.657] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:81:0) after 6m8s
[21:34:33.384] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:81:0)
[21:34:33.405] Build status: STARTED
[21:34:33.405] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:82:0) after 7m31s
[21:42:04.707] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:82:0)
[21:42:04.727] Build status: STARTED
[21:42:04.727] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:83:0) after 7m25s
[21:49:29.752] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:83:0)
[21:49:29.771] Build status: STARTED
[21:49:29.771] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:84:0) after 8m28s
[21:57:57.885] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:84:0)
[21:57:57.908] Build status: STARTED
[21:57:57.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:85:0) after 2m35s
[22:00:32.964] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:85:0)
[22:00:32.986] Build status: STARTED
[22:00:32.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:86:0) after 9m34s
[22:10:07.107] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:86:0)
[22:10:07.172] Build status: STARTED
[22:10:07.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:87:0) after 2m11s
[22:12:18.458] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:87:0)
[22:12:18.477] Build status: STARTED
[22:12:18.477] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:88:0) after 1m54s
[22:14:12.765] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:88:0)
[22:14:12.798] Build status: STARTED
[22:14:12.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:89:0) after 6m10s
[22:20:23.039] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:89:0)
[22:20:23.060] Build status: STARTED
[22:20:23.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:90:0) after 7m22s
[22:27:45.139] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:90:0)
[22:27:45.192] Build status: STARTED
[22:27:45.192] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:91:0) after 1m20s
[22:29:05.219] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:91:0)
[22:29:05.238] Build status: STARTED
[22:29:05.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:92:0) after 8m26s
[22:37:31.467] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:92:0)
[22:37:31.486] Build status: STARTED
[22:37:31.486] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:93:0) after 9m46s
[22:47:17.516] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:93:0)
[22:47:17.544] Build status: STARTED
[22:47:17.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:94:0) after 9m10s
[22:56:28.160] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:94:0)
[22:56:28.180] Build status: STARTED
[22:56:28.180] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:95:0) after 3m17s
[22:59:45.209] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:95:0)
[22:59:45.253] Build status: STARTED
[22:59:45.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:96:0) after 9m45s
[23:09:30.336] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:96:0)
[23:09:30.428] Build status: STARTED
[23:09:30.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:97:0) after 9m45s
[23:19:15.710] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:97:0)
[23:19:15.734] Build status: STARTED
[23:19:15.735] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:98:0) after 8m43s
[23:27:58.948] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:98:0)
[23:27:58.972] Build status: STARTED
[23:27:58.972] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:99:0) after 7m5s
[23:35:04.122] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:99:0)
[23:35:04.141] Build status: STARTED
[23:35:04.141] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:100:0) after 3m48s
[23:38:52.281] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:100:0)
[23:38:52.304] Build status: STARTED
[23:38:52.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:101:0) after 2m35s
[23:41:27.747] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:101:0)
[23:41:27.767] Build status: STARTED
[23:41:27.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:102:0) after 1m55s
[23:43:23.027] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:102:0)
[23:43:23.047] Build status: STARTED
[23:43:23.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:103:0) after 1m39s
[23:45:02.219] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:103:0)
[23:45:02.242] Build status: STARTED
[23:45:02.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:104:0) after 3m0s
[23:48:02.271] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:104:0)
[23:48:02.291] Build status: STARTED
[23:48:02.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:105:0) after 8m46s
[23:56:48.677] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:105:0)
[23:56:48.697] Build status: STARTED
[23:56:48.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:106:0) after 3m55s
[00:00:43.735] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:106:0)
[00:00:43.759] Build status: STARTED
[00:00:43.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:107:0) after 2m12s
[00:02:55.784] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:107:0)
[00:02:55.801] Build status: STARTED
[00:02:55.801] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:108:0) after 1m12s
[00:04:07.827] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:108:0)
[00:04:07.848] Build status: STARTED
[00:04:07.848] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:109:0) after 7m15s
[00:11:23.298] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:109:0)
[00:11:23.318] Build status: STARTED
[00:11:23.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:110:0) after 7m36s
[00:18:59.953] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:110:0)
[00:18:59.974] Build status: STARTED
[00:18:59.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:111:0) after 5m22s
[00:24:22.312] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:111:0)
[00:24:22.395] Build status: STARTED
[00:24:22.395] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:112:0) after 5m7s
[00:29:29.433] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:112:0)
[00:29:29.455] Build status: STARTED
[00:29:29.455] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:113:0) after 5m10s
[00:34:39.487] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:113:0)
[00:34:39.510] Build status: STARTED
[00:34:39.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:114:0) after 3m29s
[00:38:08.770] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:114:0)
[00:38:08.787] Build status: STARTED
[00:38:08.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:115:0) after 4m1s
[00:42:10.148] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:115:0)
[00:42:10.169] Build status: STARTED
[00:42:10.169] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:116:0) after 8m54s
[00:51:04.974] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:116:0)
[00:51:05.003] Build status: STARTED
[00:51:05.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:117:0) after 1m58s
[00:53:03.056] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:117:0)
[00:53:03.083] Build status: STARTED
[00:53:03.083] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:118:0) after 4m30s
[00:57:33.147] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:118:0)
[00:57:33.184] Build status: STARTED
[00:57:33.184] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:119:0) after 7m40s
[01:05:13.996] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:119:0)
[01:05:14.028] Build status: STARTED
[01:05:14.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:120:0) after 1m55s
[01:07:09.070] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:120:0)
[01:07:09.094] Build status: STARTED
[01:07:09.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8947360314848775008:121:0) after 9m46s
[01:11:23.786] Received PubSub notification, asking Buildbucket for the build status
[01:11:23.809] Build:
{
"id": "8781930252960929505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 5237,
"createdBy": "project:chromium",
"createTime": "2023-05-05T14:08:11.488612613Z",
"startTime": "2023-05-05T14:08:25.072092Z",
"endTime": "2023-05-06T01:11:23.447193925Z",
"updateTime": "2023-05-06T01:11:23.447193925Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c7a19e2eb75677e0b5b4fc6d507722767846107",
"ref": "refs/heads/main"
}
}
}
[01:11:23.809] Invocation finished in 11h3m13.504838287s with status FAILED