[06:15:36.178] New invocation is queued and will start shortly
[06:15:37.255] Starting the invocation (attempt 1)
[06:15:37.300] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f7154df70eb9c4d18c4e7a83dcc4c5573c928255"
[06:15:37.301] Popped gitiles commit info from properties and tags
[06:15:37.301] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:15:37.301] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:15:37.301] Buildbucket request:
{
"requestId": "8993413305257670400",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f7154df70eb9c4d18c4e7a83dcc4c5573c928255",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993413305257670400"
},
{
"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:15:37.791] Scheduled build:
{
"id": "8827983243352896689",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1872,
"createdBy": "project:chromium",
"createTime": "2021-12-13T06:15:37.357182144Z",
"updateTime": "2021-12-13T06:15:37.357182144Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f7154df70eb9c4d18c4e7a83dcc4c5573c928255",
"ref": "refs/heads/main"
}
}
}
[06:15:37.791] Task URL: https://cr-buildbucket.appspot.com/build/8827983243352896689
[06:15:37.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:2:0) after 9m22s
[06:24:59.809] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:2:0)
[06:24:59.826] Build status: SCHEDULED
[06:24:59.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:3:0) after 4m10s
[06:29:09.864] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:3:0)
[06:29:09.934] Build status: SCHEDULED
[06:29:09.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:4:0) after 7m31s
[06:36:41.054] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:4:0)
[06:36:41.071] Build status: SCHEDULED
[06:36:41.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:5:0) after 5m31s
[06:42:12.097] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:5:0)
[06:42:12.179] Build status: SCHEDULED
[06:42:12.179] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:6:0) after 2m30s
[06:44:42.305] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:6:0)
[06:44:42.325] Build status: SCHEDULED
[06:44:42.325] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:7:0) after 5m6s
[06:49:48.360] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:7:0)
[06:49:48.392] Build status: SCHEDULED
[06:49:48.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:8:0) after 4m5s
[06:53:53.412] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:8:0)
[06:53:53.436] Build status: SCHEDULED
[06:53:53.436] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:9:0) after 2m0s
[06:55:53.457] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:9:0)
[06:55:53.475] Build status: SCHEDULED
[06:55:53.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:10:0) after 5m23s
[07:01:16.791] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:10:0)
[07:01:16.867] Build status: SCHEDULED
[07:01:16.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:11:0) after 7m11s
[07:08:27.886] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:11:0)
[07:08:27.918] Build status: SCHEDULED
[07:08:27.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:12:0) after 5m29s
[07:13:56.896] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:12:0)
[07:13:56.915] Build status: SCHEDULED
[07:13:56.915] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:13:0) after 3m35s
[07:17:31.433] Received PubSub notification, asking Buildbucket for the build status
[07:17:31.453] Build status: STARTED
[07:17:31.939] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:13:0)
[07:17:31.962] Build status: STARTED
[07:17:31.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:15:0) after 1m19s
[07:18:51.088] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:15:0)
[07:18:51.108] Build status: STARTED
[07:18:51.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:16:0) after 2m26s
[07:21:17.128] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:16:0)
[07:21:17.164] Build status: STARTED
[07:21:17.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:17:0) after 5m39s
[07:26:56.357] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:17:0)
[07:26:56.390] Build status: STARTED
[07:26:56.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:18:0) after 3m51s
[07:30:47.429] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:18:0)
[07:30:47.569] Build status: STARTED
[07:30:47.569] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:19:0) after 8m33s
[07:39:20.625] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:19:0)
[07:39:20.643] Build status: STARTED
[07:39:20.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:20:0) after 2m9s
[07:41:29.663] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:20:0)
[07:41:29.717] Build status: STARTED
[07:41:29.717] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:21:0) after 1m31s
[07:43:00.770] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:21:0)
[07:43:00.808] Build status: STARTED
[07:43:00.808] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:22:0) after 5m26s
[07:48:26.832] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:22:0)
[07:48:26.854] Build status: STARTED
[07:48:26.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:23:0) after 1m43s
[07:50:09.875] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:23:0)
[07:50:09.899] Build status: STARTED
[07:50:09.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:24:0) after 1m0s
[07:51:09.922] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:24:0)
[07:51:09.962] Build status: STARTED
[07:51:09.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:25:0) after 9m3s
[08:00:12.993] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:25:0)
[08:00:13.063] Build status: STARTED
[08:00:13.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:26:0) after 3m14s
[08:03:27.088] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:26:0)
[08:03:27.105] Build status: STARTED
[08:03:27.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:27:0) after 6m46s
[08:10:13.298] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:27:0)
[08:10:13.321] Build status: STARTED
[08:10:13.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:28:0) after 5m24s
[08:15:37.472] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:28:0)
[08:15:37.492] Build status: STARTED
[08:15:37.492] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:29:0) after 6m32s
[08:22:09.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:29:0)
[08:22:09.587] Build status: STARTED
[08:22:09.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:30:0) after 4m16s
[08:26:25.618] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:30:0)
[08:26:25.745] Build status: STARTED
[08:26:25.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:31:0) after 2m13s
[08:28:38.772] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:31:0)
[08:28:38.793] Build status: STARTED
[08:28:38.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:32:0) after 7m27s
[08:36:05.814] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:32:0)
[08:36:05.888] Build status: STARTED
[08:36:05.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:33:0) after 3m31s
[08:39:37.008] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:33:0)
[08:39:37.052] Build status: STARTED
[08:39:37.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:34:0) after 6m5s
[08:45:42.072] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:34:0)
[08:45:42.106] Build status: STARTED
[08:45:42.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:35:0) after 7m58s
[08:53:40.264] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:35:0)
[08:53:40.306] Build status: STARTED
[08:53:40.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:36:0) after 4m29s
[08:58:09.325] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:36:0)
[08:58:09.366] Build status: STARTED
[08:58:09.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:37:0) after 1m36s
[08:59:45.599] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:37:0)
[08:59:45.620] Build status: STARTED
[08:59:45.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:38:0) after 3m1s
[09:02:46.640] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:38:0)
[09:02:46.663] Build status: STARTED
[09:02:46.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:39:0) after 6m59s
[09:09:45.715] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:39:0)
[09:09:45.738] Build status: STARTED
[09:09:45.738] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:40:0) after 9m28s
[09:19:13.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:40:0)
[09:19:13.791] Build status: STARTED
[09:19:13.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:41:0) after 2m22s
[09:21:35.946] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:41:0)
[09:21:35.968] Build status: STARTED
[09:21:35.968] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:42:0) after 8m18s
[09:29:54.066] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:42:0)
[09:29:54.098] Build status: STARTED
[09:29:54.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:43:0) after 2m0s
[09:31:54.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:43:0)
[09:31:54.145] Build status: STARTED
[09:31:54.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:44:0) after 7m6s
[09:39:00.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:44:0)
[09:39:00.208] Build status: STARTED
[09:39:00.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:45:0) after 3m48s
[09:42:48.274] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:45:0)
[09:42:48.320] Build status: STARTED
[09:42:48.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:46:0) after 1m26s
[09:44:14.346] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:46:0)
[09:44:14.382] Build status: STARTED
[09:44:14.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:47:0) after 2m41s
[09:46:55.721] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:47:0)
[09:46:55.757] Build status: STARTED
[09:46:55.757] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:48:0) after 3m23s
[09:50:18.783] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:48:0)
[09:50:18.813] Build status: STARTED
[09:50:18.813] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:49:0) after 8m5s
[09:58:23.907] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:49:0)
[09:58:23.935] Build status: STARTED
[09:58:23.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:50:0) after 8m0s
[10:06:24.063] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:50:0)
[10:06:24.090] Build status: STARTED
[10:06:24.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:51:0) after 2m22s
[10:08:46.178] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:51:0)
[10:08:46.198] Build status: STARTED
[10:08:46.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:52:0) after 2m48s
[10:11:34.307] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:52:0)
[10:11:34.334] Build status: STARTED
[10:11:34.334] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:53:0) after 2m51s
[10:14:25.357] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:53:0)
[10:14:25.379] Build status: STARTED
[10:14:25.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:54:0) after 4m25s
[10:18:50.413] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:54:0)
[10:18:50.446] Build status: STARTED
[10:18:50.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:55:0) after 6m0s
[10:24:50.911] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:55:0)
[10:24:50.945] Build status: STARTED
[10:24:50.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:56:0) after 9m28s
[10:34:19.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:56:0)
[10:34:19.142] Build status: STARTED
[10:34:19.142] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:57:0) after 8m59s
[10:43:18.169] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:57:0)
[10:43:18.189] Build status: STARTED
[10:43:18.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:58:0) after 9m19s
[10:52:37.223] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:58:0)
[10:52:37.261] Build status: STARTED
[10:52:37.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:59:0) after 7m51s
[11:00:28.286] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:59:0)
[11:00:28.341] Build status: STARTED
[11:00:28.341] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:60:0) after 4m43s
[11:05:11.368] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:60:0)
[11:05:11.469] Build status: STARTED
[11:05:11.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:61:0) after 3m52s
[11:09:03.492] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:61:0)
[11:09:03.543] Build status: STARTED
[11:09:03.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:62:0) after 6m32s
[11:15:35.566] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:62:0)
[11:15:35.591] Build status: STARTED
[11:15:35.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:63:0) after 4m46s
[11:20:21.632] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:63:0)
[11:20:21.680] Build status: STARTED
[11:20:21.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:64:0) after 9m1s
[11:29:22.703] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:64:0)
[11:29:22.726] Build status: STARTED
[11:29:22.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:65:0) after 6m18s
[11:35:40.750] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:65:0)
[11:35:40.789] Build status: STARTED
[11:35:40.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:66:0) after 8m9s
[11:43:49.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:66:0)
[11:43:49.978] Build status: STARTED
[11:43:49.978] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:67:0) after 9m33s
[11:53:23.049] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:67:0)
[11:53:23.083] Build status: STARTED
[11:53:23.083] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:68:0) after 8m27s
[12:01:50.421] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:68:0)
[12:01:50.476] Build status: STARTED
[12:01:50.476] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:69:0) after 8m49s
[12:10:39.507] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:69:0)
[12:10:39.547] Build status: STARTED
[12:10:39.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:70:0) after 8m54s
[12:19:33.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:70:0)
[12:19:33.601] Build status: STARTED
[12:19:33.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:71:0) after 4m14s
[12:23:47.632] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:71:0)
[12:23:47.655] Build status: STARTED
[12:23:47.655] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:72:0) after 5m11s
[12:28:58.679] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:72:0)
[12:28:58.712] Build status: STARTED
[12:28:58.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:73:0) after 2m0s
[12:30:58.788] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:73:0)
[12:30:58.842] Build status: STARTED
[12:30:58.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:74:0) after 1m2s
[12:32:01.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:74:0)
[12:32:01.548] Build status: STARTED
[12:32:01.548] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:75:0) after 8m11s
[12:40:12.657] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:75:0)
[12:40:12.691] Build status: STARTED
[12:40:12.691] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:76:0) after 5m0s
[12:45:12.816] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:76:0)
[12:45:12.845] Build status: STARTED
[12:45:12.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:77:0) after 1m27s
[12:46:39.862] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:77:0)
[12:46:39.921] Build status: STARTED
[12:46:39.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:78:0) after 1m38s
[12:48:17.960] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:78:0)
[12:48:17.978] Build status: STARTED
[12:48:17.978] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:79:0) after 5m29s
[12:53:47.328] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:79:0)
[12:53:47.648] Build status: STARTED
[12:53:47.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:80:0) after 6m18s
[13:00:05.665] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:80:0)
[13:00:05.779] Build status: STARTED
[13:00:05.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:81:0) after 7m52s
[13:07:57.823] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:81:0)
[13:07:57.846] Build status: STARTED
[13:07:57.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:82:0) after 8m17s
[13:16:14.867] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:82:0)
[13:16:14.886] Build status: STARTED
[13:16:14.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:83:0) after 1m21s
[13:17:35.907] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:83:0)
[13:17:35.942] Build status: STARTED
[13:17:35.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:84:0) after 6m5s
[13:23:40.977] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:84:0)
[13:23:41.003] Build status: STARTED
[13:23:41.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:85:0) after 9m27s
[13:33:08.089] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:85:0)
[13:33:08.195] Build status: STARTED
[13:33:08.195] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:86:0) after 5m50s
[13:38:58.575] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:86:0)
[13:38:58.594] Build status: STARTED
[13:38:58.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:87:0) after 6m39s
[13:45:37.631] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:87:0)
[13:45:37.651] Build status: STARTED
[13:45:37.651] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:88:0) after 4m9s
[13:49:46.872] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:88:0)
[13:49:46.899] Build status: STARTED
[13:49:46.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:89:0) after 3m47s
[13:53:33.940] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:89:0)
[13:53:33.959] Build status: STARTED
[13:53:33.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:90:0) after 2m58s
[13:56:32.085] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:90:0)
[13:56:32.112] Build status: STARTED
[13:56:32.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:91:0) after 1m22s
[13:57:54.130] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:91:0)
[13:57:54.151] Build status: STARTED
[13:57:54.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:92:0) after 7m21s
[14:05:15.485] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:92:0)
[14:05:15.504] Build status: STARTED
[14:05:15.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:93:0) after 5m52s
[14:11:07.529] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:93:0)
[14:11:07.559] Build status: STARTED
[14:11:07.559] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:94:0) after 8m6s
[14:19:13.633] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:94:0)
[14:19:13.662] Build status: STARTED
[14:19:13.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:95:0) after 7m52s
[14:27:05.661] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:95:0)
[14:27:05.685] Build status: STARTED
[14:27:05.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:96:0) after 1m3s
[14:28:08.730] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:96:0)
[14:28:08.750] Build status: STARTED
[14:28:08.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:97:0) after 1m3s
[14:29:11.774] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:97:0)
[14:29:11.843] Build status: STARTED
[14:29:11.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:98:0) after 3m21s
[14:32:32.903] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:98:0)
[14:32:32.930] Build status: STARTED
[14:32:32.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:99:0) after 5m41s
[14:38:14.041] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:99:0)
[14:38:14.073] Build status: STARTED
[14:38:14.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:100:0) after 8m8s
[14:46:22.093] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:100:0)
[14:46:22.126] Build status: STARTED
[14:46:22.126] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:101:0) after 4m8s
[14:50:30.112] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:101:0)
[14:50:30.131] Build status: STARTED
[14:50:30.131] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:102:0) after 2m20s
[14:52:50.154] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:102:0)
[14:52:50.197] Build status: STARTED
[14:52:50.197] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:103:0) after 1m30s
[14:54:20.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:103:0)
[14:54:20.344] Build status: STARTED
[14:54:20.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:104:0) after 8m42s
[15:03:02.384] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:104:0)
[15:03:02.454] Build status: STARTED
[15:03:02.454] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:105:0) after 7m11s
[15:10:13.482] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:105:0)
[15:10:13.506] Build status: STARTED
[15:10:13.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:106:0) after 8m54s
[15:19:07.585] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:106:0)
[15:19:07.605] Build status: STARTED
[15:19:07.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:107:0) after 6m32s
[15:25:39.631] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:107:0)
[15:25:39.659] Build status: STARTED
[15:25:39.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:108:0) after 1m30s
[15:27:09.760] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:108:0)
[15:27:09.967] Build status: STARTED
[15:27:09.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:109:0) after 8m30s
[15:35:40.215] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:109:0)
[15:35:40.237] Build status: STARTED
[15:35:40.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:110:0) after 2m56s
[15:38:36.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:110:0)
[15:38:36.361] Build status: STARTED
[15:38:36.361] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993413305257670400:111:0) after 4m44s
[15:39:15.860] Received PubSub notification, asking Buildbucket for the build status
[15:39:15.880] Build:
{
"id": "8827983243352896689",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1872,
"createdBy": "project:chromium",
"createTime": "2021-12-13T06:15:37.357182144Z",
"startTime": "2021-12-13T07:17:29.504431Z",
"endTime": "2021-12-13T15:39:15.569821819Z",
"updateTime": "2021-12-13T15:39:15.569821819Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f7154df70eb9c4d18c4e7a83dcc4c5573c928255",
"ref": "refs/heads/main"
}
}
}
[15:39:15.880] Invocation finished in 9h23m39.722528722s with status FAILED