[06:13:56.777] New invocation is queued and will start shortly
[06:13:58.016] Starting the invocation (attempt 1)
[06:13:58.044] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edb9e20526b11b115443fbe849a6d92cc23a62d6"
[06:13:58.044] Popped gitiles commit info from properties and tags
[06:13:58.044] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:13:58.044] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:13:58.044] Buildbucket request:
{
"requestId": "8950017462572984256",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "edb9e20526b11b115443fbe849a6d92cc23a62d6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8950017462572984256"
},
{
"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"
}
}
[06:13:58.596] Scheduled build:
{
"id": "8784587400511159521",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4973,
"createdBy": "project:chromium",
"createTime": "2023-04-06T06:13:58.114735464Z",
"updateTime": "2023-04-06T06:13:58.114735464Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "edb9e20526b11b115443fbe849a6d92cc23a62d6",
"ref": "refs/heads/main"
}
}
}
[06:13:58.596] Task URL: https://cr-buildbucket.appspot.com/build/8784587400511159521
[06:13:58.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:2:0) after 2m6s
[06:16:04.618] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:2:0)
[06:16:04.639] Build status: SCHEDULED
[06:16:04.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:3:0) after 1m1s
[06:17:05.977] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:3:0)
[06:17:06.004] Build status: SCHEDULED
[06:17:06.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:4:0) after 5m37s
[06:17:36.022] Received PubSub notification, asking Buildbucket for the build status
[06:17:36.054] Build status: STARTED
[06:22:43.020] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:4:0)
[06:22:43.047] Build status: STARTED
[06:22:43.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:6:0) after 2m11s
[06:24:54.063] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:6:0)
[06:24:54.090] Build status: STARTED
[06:24:54.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:7:0) after 9m37s
[06:34:31.107] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:7:0)
[06:34:31.150] Build status: STARTED
[06:34:31.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:8:0) after 5m15s
[06:39:46.166] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:8:0)
[06:39:46.192] Build status: STARTED
[06:39:46.192] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:9:0) after 3m22s
[06:43:08.207] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:9:0)
[06:43:08.236] Build status: STARTED
[06:43:08.236] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:10:0) after 4m37s
[06:47:45.380] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:10:0)
[06:47:45.400] Build status: STARTED
[06:47:45.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:11:0) after 4m13s
[06:51:58.737] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:11:0)
[06:51:58.759] Build status: STARTED
[06:51:58.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:12:0) after 7m24s
[06:59:22.902] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:12:0)
[06:59:22.927] Build status: STARTED
[06:59:22.927] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:13:0) after 2m20s
[07:01:43.221] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:13:0)
[07:01:43.245] Build status: STARTED
[07:01:43.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:14:0) after 5m56s
[07:07:39.235] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:14:0)
[07:07:39.258] Build status: STARTED
[07:07:39.258] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:15:0) after 5m12s
[07:12:51.405] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:15:0)
[07:12:51.448] Build status: STARTED
[07:12:51.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:16:0) after 7m50s
[07:20:41.534] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:16:0)
[07:20:41.555] Build status: STARTED
[07:20:41.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:17:0) after 4m29s
[07:25:10.570] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:17:0)
[07:25:10.594] Build status: STARTED
[07:25:10.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:18:0) after 5m47s
[07:30:57.673] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:18:0)
[07:30:57.723] Build status: STARTED
[07:30:57.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:19:0) after 3m10s
[07:34:07.741] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:19:0)
[07:34:07.768] Build status: STARTED
[07:34:07.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:20:0) after 7m6s
[07:41:13.885] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:20:0)
[07:41:13.923] Build status: STARTED
[07:41:13.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:21:0) after 9m22s
[07:50:35.943] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:21:0)
[07:50:35.983] Build status: STARTED
[07:50:35.984] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:22:0) after 7m38s
[07:58:14.000] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:22:0)
[07:58:14.021] Build status: STARTED
[07:58:14.021] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:23:0) after 4m35s
[08:02:49.062] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:23:0)
[08:02:49.084] Build status: STARTED
[08:02:49.084] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:24:0) after 1m57s
[08:04:46.099] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:24:0)
[08:04:46.122] Build status: STARTED
[08:04:46.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:25:0) after 9m22s
[08:14:08.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:25:0)
[08:14:08.643] Build status: STARTED
[08:14:08.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:26:0) after 2m21s
[08:16:29.743] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:26:0)
[08:16:29.766] Build status: STARTED
[08:16:29.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:27:0) after 7m38s
[08:24:07.898] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:27:0)
[08:24:07.942] Build status: STARTED
[08:24:07.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:28:0) after 7m17s
[08:31:25.089] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:28:0)
[08:31:25.110] Build status: STARTED
[08:31:25.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:29:0) after 5m6s
[08:36:31.128] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:29:0)
[08:36:31.148] Build status: STARTED
[08:36:31.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:30:0) after 8m12s
[08:44:43.232] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:30:0)
[08:44:43.257] Build status: STARTED
[08:44:43.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:31:0) after 2m6s
[08:46:49.273] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:31:0)
[08:46:49.298] Build status: STARTED
[08:46:49.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:32:0) after 8m59s
[08:55:48.409] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:32:0)
[08:55:48.430] Build status: STARTED
[08:55:48.430] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:33:0) after 5m16s
[09:01:04.449] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:33:0)
[09:01:04.472] Build status: STARTED
[09:01:04.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:34:0) after 5m20s
[09:06:24.493] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:34:0)
[09:06:24.512] Build status: STARTED
[09:06:24.512] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:35:0) after 7m47s
[09:14:11.531] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:35:0)
[09:14:11.642] Build status: STARTED
[09:14:11.642] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:36:0) after 5m21s
[09:19:32.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:36:0)
[09:19:32.792] Build status: STARTED
[09:19:32.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:37:0) after 8m46s
[09:28:18.829] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:37:0)
[09:28:18.918] Build status: STARTED
[09:28:18.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:38:0) after 1m23s
[09:29:42.218] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:38:0)
[09:29:42.310] Build status: STARTED
[09:29:42.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:39:0) after 1m48s
[09:31:30.526] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:39:0)
[09:31:30.563] Build status: STARTED
[09:31:30.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:40:0) after 3m3s
[09:34:33.679] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:40:0)
[09:34:33.705] Build status: STARTED
[09:34:33.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:41:0) after 7m15s
[09:41:48.877] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:41:0)
[09:41:48.898] Build status: STARTED
[09:41:48.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:42:0) after 7m3s
[09:48:52.131] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:42:0)
[09:48:52.154] Build status: STARTED
[09:48:52.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:43:0) after 7m44s
[09:56:36.175] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:43:0)
[09:56:36.196] Build status: STARTED
[09:56:36.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:44:0) after 5m54s
[10:02:30.223] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:44:0)
[10:02:30.259] Build status: STARTED
[10:02:30.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:45:0) after 3m28s
[10:05:58.322] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:45:0)
[10:05:58.350] Build status: STARTED
[10:05:58.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:46:0) after 4m1s
[10:09:59.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:46:0)
[10:09:59.398] Build status: STARTED
[10:09:59.398] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:47:0) after 5m52s
[10:15:51.616] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:47:0)
[10:15:51.637] Build status: STARTED
[10:15:51.637] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:48:0) after 6m49s
[10:22:40.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:48:0)
[10:22:40.742] Build status: STARTED
[10:22:40.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:49:0) after 1m18s
[10:23:58.758] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:49:0)
[10:23:58.798] Build status: STARTED
[10:23:58.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:50:0) after 3m51s
[10:27:49.818] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:50:0)
[10:27:49.838] Build status: STARTED
[10:27:49.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:51:0) after 4m49s
[10:32:39.106] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:51:0)
[10:32:39.133] Build status: STARTED
[10:32:39.133] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:52:0) after 2m19s
[10:34:58.509] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:52:0)
[10:34:58.540] Build status: STARTED
[10:34:58.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:53:0) after 5m56s
[10:40:54.758] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:53:0)
[10:40:54.812] Build status: STARTED
[10:40:54.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:54:0) after 2m33s
[10:43:28.042] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:54:0)
[10:43:28.065] Build status: STARTED
[10:43:28.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:55:0) after 9m53s
[10:53:21.093] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:55:0)
[10:53:21.119] Build status: STARTED
[10:53:21.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:56:0) after 3m31s
[10:56:52.295] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:56:0)
[10:56:52.337] Build status: STARTED
[10:56:52.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:57:0) after 6m23s
[11:03:15.419] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:57:0)
[11:03:15.447] Build status: STARTED
[11:03:15.447] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:58:0) after 7m39s
[11:10:54.464] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:58:0)
[11:10:54.503] Build status: STARTED
[11:10:54.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:59:0) after 8m4s
[11:18:58.589] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:59:0)
[11:18:58.613] Build status: STARTED
[11:18:58.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:60:0) after 5m9s
[11:24:07.626] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:60:0)
[11:24:07.645] Build status: STARTED
[11:24:07.645] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:61:0) after 7m2s
[11:31:10.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:61:0)
[11:31:10.119] Build status: STARTED
[11:31:10.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:62:0) after 1m51s
[11:33:01.136] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:62:0)
[11:33:01.161] Build status: STARTED
[11:33:01.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:63:0) after 4m41s
[11:37:42.328] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:63:0)
[11:37:42.347] Build status: STARTED
[11:37:42.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:64:0) after 4m23s
[11:42:05.437] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:64:0)
[11:42:05.464] Build status: STARTED
[11:42:05.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:65:0) after 4m33s
[11:46:38.602] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:65:0)
[11:46:38.638] Build status: STARTED
[11:46:38.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:66:0) after 4m9s
[11:50:47.660] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:66:0)
[11:50:47.680] Build status: STARTED
[11:50:47.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:67:0) after 8m43s
[11:59:30.699] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:67:0)
[11:59:30.719] Build status: STARTED
[11:59:30.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:68:0) after 1m34s
[12:01:04.840] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:68:0)
[12:01:04.861] Build status: STARTED
[12:01:04.861] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:69:0) after 7m59s
[12:09:04.158] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:69:0)
[12:09:04.182] Build status: STARTED
[12:09:04.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:70:0) after 7m11s
[12:16:15.258] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:70:0)
[12:16:15.303] Build status: STARTED
[12:16:15.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:71:0) after 9m26s
[12:25:41.326] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:71:0)
[12:25:41.347] Build status: STARTED
[12:25:41.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:72:0) after 5m16s
[12:30:57.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:72:0)
[12:30:57.393] Build status: STARTED
[12:30:57.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:73:0) after 4m31s
[12:35:28.572] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:73:0)
[12:35:28.593] Build status: STARTED
[12:35:28.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:74:0) after 5m14s
[12:40:42.795] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:74:0)
[12:40:42.818] Build status: STARTED
[12:40:42.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:75:0) after 1m54s
[12:42:36.842] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:75:0)
[12:42:36.864] Build status: STARTED
[12:42:36.864] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:76:0) after 3m39s
[12:46:16.071] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:76:0)
[12:46:16.097] Build status: STARTED
[12:46:16.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:77:0) after 9m11s
[12:55:27.120] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:77:0)
[12:55:27.139] Build status: STARTED
[12:55:27.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:78:0) after 5m53s
[13:01:20.259] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:78:0)
[13:01:20.286] Build status: STARTED
[13:01:20.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:79:0) after 4m37s
[13:05:57.523] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:79:0)
[13:05:57.557] Build status: STARTED
[13:05:57.557] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:80:0) after 1m34s
[13:07:31.675] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:80:0)
[13:07:31.701] Build status: STARTED
[13:07:31.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:81:0) after 7m22s
[13:14:53.721] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:81:0)
[13:14:53.752] Build status: STARTED
[13:14:53.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:82:0) after 6m9s
[13:21:02.981] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:82:0)
[13:21:03.007] Build status: STARTED
[13:21:03.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:83:0) after 5m55s
[13:26:58.004] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:83:0)
[13:26:58.029] Build status: STARTED
[13:26:58.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:84:0) after 2m40s
[13:29:38.182] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:84:0)
[13:29:38.203] Build status: STARTED
[13:29:38.203] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:85:0) after 7m43s
[13:37:21.426] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:85:0)
[13:37:21.450] Build status: STARTED
[13:37:21.450] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:86:0) after 2m51s
[13:40:12.573] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:86:0)
[13:40:12.600] Build status: STARTED
[13:40:12.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:87:0) after 9m6s
[13:49:18.850] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:87:0)
[13:49:18.877] Build status: STARTED
[13:49:18.877] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:88:0) after 2m40s
[13:51:59.119] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:88:0)
[13:51:59.144] Build status: STARTED
[13:51:59.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:89:0) after 1m39s
[13:53:38.176] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:89:0)
[13:53:38.201] Build status: STARTED
[13:53:38.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:90:0) after 3m29s
[13:57:07.331] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:90:0)
[13:57:07.351] Build status: STARTED
[13:57:07.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:91:0) after 2m45s
[13:59:52.429] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:91:0)
[13:59:52.451] Build status: STARTED
[13:59:52.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:92:0) after 2m9s
[14:02:01.525] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:92:0)
[14:02:01.559] Build status: STARTED
[14:02:01.559] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:93:0) after 9m27s
[14:11:28.583] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:93:0)
[14:11:28.606] Build status: STARTED
[14:11:28.606] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:94:0) after 2m31s
[14:13:59.761] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:94:0)
[14:13:59.788] Build status: STARTED
[14:13:59.788] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:95:0) after 1m22s
[14:15:21.899] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:95:0)
[14:15:21.921] Build status: STARTED
[14:15:21.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:96:0) after 3m9s
[14:18:31.078] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:96:0)
[14:18:31.102] Build status: STARTED
[14:18:31.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:97:0) after 4m3s
[14:22:34.122] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:97:0)
[14:22:34.144] Build status: STARTED
[14:22:34.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:98:0) after 1m13s
[14:23:47.258] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:98:0)
[14:23:47.282] Build status: STARTED
[14:23:47.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:99:0) after 2m51s
[14:26:39.154] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:99:0)
[14:26:39.177] Build status: STARTED
[14:26:39.177] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:100:0) after 1m35s
[14:28:14.411] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:100:0)
[14:28:14.439] Build status: STARTED
[14:28:14.439] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:101:0) after 3m49s
[14:32:03.601] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:101:0)
[14:32:03.625] Build status: STARTED
[14:32:03.625] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:102:0) after 6m37s
[14:38:40.742] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:102:0)
[14:38:40.760] Build status: STARTED
[14:38:40.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:103:0) after 1m34s
[14:40:14.860] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:103:0)
[14:40:14.886] Build status: STARTED
[14:40:14.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:104:0) after 5m37s
[14:45:52.026] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:104:0)
[14:45:52.047] Build status: STARTED
[14:45:52.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:105:0) after 3m33s
[14:49:25.178] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:105:0)
[14:49:25.201] Build status: STARTED
[14:49:25.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:106:0) after 1m24s
[14:50:49.324] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:106:0)
[14:50:49.346] Build status: STARTED
[14:50:49.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:107:0) after 3m25s
[14:54:14.693] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:107:0)
[14:54:14.716] Build status: STARTED
[14:54:14.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:108:0) after 1m18s
[14:55:32.931] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:108:0)
[14:55:32.952] Build status: STARTED
[14:55:32.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:109:0) after 2m3s
[14:57:36.033] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:109:0)
[14:57:36.057] Build status: STARTED
[14:57:36.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:110:0) after 5m6s
[15:02:42.190] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:110:0)
[15:02:42.218] Build status: STARTED
[15:02:42.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:111:0) after 3m14s
[15:05:56.211] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:111:0)
[15:05:56.235] Build status: STARTED
[15:05:56.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:112:0) after 7m25s
[15:13:21.334] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:112:0)
[15:13:21.354] Build status: STARTED
[15:13:21.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:113:0) after 2m25s
[15:15:46.471] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:113:0)
[15:15:46.491] Build status: STARTED
[15:15:46.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:114:0) after 5m27s
[15:21:13.605] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:114:0)
[15:21:13.633] Build status: STARTED
[15:21:13.633] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:115:0) after 4m28s
[15:25:42.243] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:115:0)
[15:25:42.268] Build status: STARTED
[15:25:42.268] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:116:0) after 5m14s
[15:30:56.579] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:116:0)
[15:30:56.635] Build status: STARTED
[15:30:56.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:117:0) after 9m20s
[15:40:16.788] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:117:0)
[15:40:16.809] Build status: STARTED
[15:40:16.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:118:0) after 5m6s
[15:45:22.957] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:118:0)
[15:45:22.987] Build status: STARTED
[15:45:22.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:119:0) after 4m54s
[15:50:17.000] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:119:0)
[15:50:17.138] Build status: STARTED
[15:50:17.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:120:0) after 6m57s
[15:57:14.173] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:120:0)
[15:57:14.197] Build status: STARTED
[15:57:14.197] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:121:0) after 8m47s
[16:06:01.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:121:0)
[16:06:01.702] Build status: STARTED
[16:06:01.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:122:0) after 5m33s
[16:11:34.722] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:122:0)
[16:11:34.748] Build status: STARTED
[16:11:34.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:123:0) after 2m59s
[16:14:33.783] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:123:0)
[16:14:33.864] Build status: STARTED
[16:14:33.864] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:124:0) after 9m45s
[16:24:18.885] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:124:0)
[16:24:18.921] Build status: STARTED
[16:24:18.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8950017462572984256:125:0) after 5m36s
[16:25:25.888] Received PubSub notification, asking Buildbucket for the build status
[16:25:25.910] Build:
{
"id": "8784587400511159521",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4973,
"createdBy": "project:chromium",
"createTime": "2023-04-06T06:13:58.114735464Z",
"startTime": "2023-04-06T06:17:35.426440Z",
"endTime": "2023-04-06T16:25:25.572494289Z",
"updateTime": "2023-04-06T16:25:25.572494289Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "edb9e20526b11b115443fbe849a6d92cc23a62d6",
"ref": "refs/heads/main"
}
}
}
[16:25:25.910] Invocation finished in 10h11m29.145794825s with status FAILED