[19:02:59.267] New invocation is queued and will start shortly
[19:03:00.936] Starting the invocation (attempt 1)
[19:03:00.977] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76c83e16549b25e61d6702a477c798fb767946f1"
[19:03:00.977] Popped gitiles commit info from properties and tags
[19:03:00.977] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:03:00.977] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:03:00.977] Buildbucket request:
{
"requestId": "8978688317017582752",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "76c83e16549b25e61d6702a477c798fb767946f1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8978688317017582752"
},
{
"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"
}
}
[19:03:01.870] Scheduled build:
{
"id": "8813258254491785201",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 2607,
"createdBy": "project:chromium",
"createTime": "2022-05-24T19:03:01.043259288Z",
"updateTime": "2022-05-24T19:03:01.043259288Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "76c83e16549b25e61d6702a477c798fb767946f1",
"ref": "refs/heads/main"
}
},
"canary": true
}
[19:03:01.870] Task URL: https://cr-buildbucket.appspot.com/build/8813258254491785201
[19:03:01.870] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:2:0) after 1m17s
[19:03:58.425] Received PubSub notification, asking Buildbucket for the build status
[19:03:58.456] Build status: STARTED
[19:04:19.917] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:2:0)
[19:04:19.937] Build status: STARTED
[19:04:19.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:4:0) after 5m41s
[19:10:00.975] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:4:0)
[19:10:01.007] Build status: STARTED
[19:10:01.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:5:0) after 4m12s
[19:14:13.025] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:5:0)
[19:14:13.056] Build status: STARTED
[19:14:13.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:6:0) after 8m48s
[19:23:01.074] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:6:0)
[19:23:01.118] Build status: STARTED
[19:23:01.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:7:0) after 1m22s
[19:24:23.141] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:7:0)
[19:24:23.171] Build status: STARTED
[19:24:23.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:8:0) after 4m43s
[19:29:06.278] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:8:0)
[19:29:06.302] Build status: STARTED
[19:29:06.302] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:9:0) after 4m0s
[19:33:06.333] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:9:0)
[19:33:06.392] Build status: STARTED
[19:33:06.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:10:0) after 8m53s
[19:41:59.438] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:10:0)
[19:41:59.463] Build status: STARTED
[19:41:59.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:11:0) after 7m10s
[19:49:09.489] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:11:0)
[19:49:09.543] Build status: STARTED
[19:49:09.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:12:0) after 5m7s
[19:54:16.666] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:12:0)
[19:54:16.688] Build status: STARTED
[19:54:16.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:13:0) after 9m14s
[20:03:30.870] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:13:0)
[20:03:30.891] Build status: STARTED
[20:03:30.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:14:0) after 1m45s
[20:05:15.939] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:14:0)
[20:05:15.961] Build status: STARTED
[20:05:15.961] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:15:0) after 8m22s
[20:13:37.987] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:15:0)
[20:13:38.044] Build status: STARTED
[20:13:38.044] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:16:0) after 6m29s
[20:20:07.159] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:16:0)
[20:20:07.187] Build status: STARTED
[20:20:07.187] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:17:0) after 3m9s
[20:23:16.728] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:17:0)
[20:23:16.767] Build status: STARTED
[20:23:16.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:18:0) after 2m55s
[20:26:11.788] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:18:0)
[20:26:11.830] Build status: STARTED
[20:26:11.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:19:0) after 4m40s
[20:30:52.012] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:19:0)
[20:30:52.101] Build status: STARTED
[20:30:52.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:20:0) after 1m46s
[20:32:38.220] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:20:0)
[20:32:38.240] Build status: STARTED
[20:32:38.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:21:0) after 9m3s
[20:41:41.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:21:0)
[20:41:41.303] Build status: STARTED
[20:41:41.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:22:0) after 3m41s
[20:45:22.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:22:0)
[20:45:22.344] Build status: STARTED
[20:45:22.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:23:0) after 1m4s
[20:46:26.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:23:0)
[20:46:26.397] Build status: STARTED
[20:46:26.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:24:0) after 7m49s
[20:54:15.499] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:24:0)
[20:54:15.528] Build status: STARTED
[20:54:15.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:25:0) after 4m54s
[20:59:10.003] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:25:0)
[20:59:10.034] Build status: STARTED
[20:59:10.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:26:0) after 8m40s
[21:07:50.213] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:26:0)
[21:07:50.239] Build status: STARTED
[21:07:50.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:27:0) after 4m16s
[21:12:06.353] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:27:0)
[21:12:06.388] Build status: STARTED
[21:12:06.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:28:0) after 2m31s
[21:14:37.454] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:28:0)
[21:14:37.492] Build status: STARTED
[21:14:37.492] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:29:0) after 9m51s
[21:24:28.666] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:29:0)
[21:24:28.698] Build status: STARTED
[21:24:28.698] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:30:0) after 2m6s
[21:26:35.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:30:0)
[21:26:35.151] Build status: STARTED
[21:26:35.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:31:0) after 1m55s
[21:28:30.175] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:31:0)
[21:28:30.198] Build status: STARTED
[21:28:30.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:32:0) after 1m25s
[21:29:55.278] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:32:0)
[21:29:55.298] Build status: STARTED
[21:29:55.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:33:0) after 7m7s
[21:37:02.366] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:33:0)
[21:37:02.437] Build status: STARTED
[21:37:02.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:34:0) after 5m20s
[21:42:22.950] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:34:0)
[21:42:22.969] Build status: STARTED
[21:42:22.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:35:0) after 7m22s
[21:49:45.032] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:35:0)
[21:49:45.056] Build status: STARTED
[21:49:45.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:36:0) after 3m14s
[21:52:59.371] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:36:0)
[21:52:59.445] Build status: STARTED
[21:52:59.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:37:0) after 3m24s
[21:56:23.906] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:37:0)
[21:56:23.954] Build status: STARTED
[21:56:23.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:38:0) after 8m12s
[22:04:36.078] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:38:0)
[22:04:36.101] Build status: STARTED
[22:04:36.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:39:0) after 8m7s
[22:12:43.326] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:39:0)
[22:12:43.366] Build status: STARTED
[22:12:43.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:40:0) after 1m58s
[22:14:41.388] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:40:0)
[22:14:41.409] Build status: STARTED
[22:14:41.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:41:0) after 2m34s
[22:17:15.888] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:41:0)
[22:17:15.919] Build status: STARTED
[22:17:15.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:42:0) after 8m6s
[22:25:21.941] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:42:0)
[22:25:21.962] Build status: STARTED
[22:25:21.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:43:0) after 8m11s
[22:33:33.115] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:43:0)
[22:33:33.142] Build status: STARTED
[22:33:33.142] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:44:0) after 7m24s
[22:40:57.170] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:44:0)
[22:40:57.217] Build status: STARTED
[22:40:57.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:45:0) after 1m1s
[22:41:58.285] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:45:0)
[22:41:58.306] Build status: STARTED
[22:41:58.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:46:0) after 3m13s
[22:45:11.660] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:46:0)
[22:45:11.766] Build status: STARTED
[22:45:11.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:47:0) after 8m25s
[22:53:36.850] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:47:0)
[22:53:36.884] Build status: STARTED
[22:53:36.884] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:48:0) after 6m29s
[23:00:06.053] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:48:0)
[23:00:06.097] Build status: STARTED
[23:00:06.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:49:0) after 9m58s
[23:10:04.456] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:49:0)
[23:10:04.494] Build status: STARTED
[23:10:04.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:50:0) after 2m24s
[23:12:28.570] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:50:0)
[23:12:28.608] Build status: STARTED
[23:12:28.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:51:0) after 3m37s
[23:16:05.632] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:51:0)
[23:16:05.678] Build status: STARTED
[23:16:05.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:52:0) after 8m0s
[23:24:05.703] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:52:0)
[23:24:05.724] Build status: STARTED
[23:24:05.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:53:0) after 9m15s
[23:33:20.812] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:53:0)
[23:33:20.836] Build status: STARTED
[23:33:20.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:54:0) after 5m46s
[23:39:06.896] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:54:0)
[23:39:06.919] Build status: STARTED
[23:39:06.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:55:0) after 3m59s
[23:43:06.083] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:55:0)
[23:43:06.108] Build status: STARTED
[23:43:06.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:56:0) after 9m33s
[23:52:39.301] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:56:0)
[23:52:39.346] Build status: STARTED
[23:52:39.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:57:0) after 6m39s
[23:59:18.942] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:57:0)
[23:59:18.992] Build status: STARTED
[23:59:18.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:58:0) after 2m45s
[00:02:04.017] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:58:0)
[00:02:04.036] Build status: STARTED
[00:02:04.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:59:0) after 1m3s
[00:03:07.342] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:59:0)
[00:03:07.369] Build status: STARTED
[00:03:07.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:60:0) after 3m14s
[00:06:21.591] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:60:0)
[00:06:21.613] Build status: STARTED
[00:06:21.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:61:0) after 6m46s
[00:13:07.636] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:61:0)
[00:13:07.675] Build status: STARTED
[00:13:07.675] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:62:0) after 6m35s
[00:19:42.835] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:62:0)
[00:19:42.868] Build status: STARTED
[00:19:42.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:63:0) after 1m13s
[00:20:55.950] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:63:0)
[00:20:55.980] Build status: STARTED
[00:20:55.980] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:64:0) after 3m54s
[00:24:50.195] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:64:0)
[00:24:50.218] Build status: STARTED
[00:24:50.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:65:0) after 7m26s
[00:32:16.291] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:65:0)
[00:32:16.356] Build status: STARTED
[00:32:16.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:66:0) after 1m5s
[00:33:21.530] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:66:0)
[00:33:21.553] Build status: STARTED
[00:33:21.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:67:0) after 9m24s
[00:42:45.579] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:67:0)
[00:42:45.605] Build status: STARTED
[00:42:45.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:68:0) after 3m53s
[00:46:38.818] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:68:0)
[00:46:38.858] Build status: STARTED
[00:46:38.858] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:69:0) after 7m44s
[00:54:22.948] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:69:0)
[00:54:22.985] Build status: STARTED
[00:54:22.985] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:70:0) after 3m17s
[00:57:40.329] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:70:0)
[00:57:40.354] Build status: STARTED
[00:57:40.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:71:0) after 1m47s
[00:59:27.568] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:71:0)
[00:59:27.588] Build status: STARTED
[00:59:27.588] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:72:0) after 9m57s
[01:09:24.650] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:72:0)
[01:09:24.671] Build status: STARTED
[01:09:24.671] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:73:0) after 9m57s
[01:19:21.741] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:73:0)
[01:19:21.804] Build status: STARTED
[01:19:21.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:74:0) after 4m39s
[01:24:00.991] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:74:0)
[01:24:01.011] Build status: STARTED
[01:24:01.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:75:0) after 3m30s
[01:27:31.286] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:75:0)
[01:27:31.351] Build status: STARTED
[01:27:31.351] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:76:0) after 4m0s
[01:31:31.579] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:76:0)
[01:31:31.619] Build status: STARTED
[01:31:31.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:77:0) after 6m49s
[01:38:20.725] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:77:0)
[01:38:20.747] Build status: STARTED
[01:38:20.747] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:78:0) after 7m58s
[01:46:18.772] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:78:0)
[01:46:18.794] Build status: STARTED
[01:46:18.794] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:79:0) after 3m59s
[01:50:17.818] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:79:0)
[01:50:17.854] Build status: STARTED
[01:50:17.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:80:0) after 3m12s
[01:53:30.010] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:80:0)
[01:53:30.042] Build status: STARTED
[01:53:30.042] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:81:0) after 6m48s
[02:00:18.152] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:81:0)
[02:00:18.179] Build status: STARTED
[02:00:18.179] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:82:0) after 2m8s
[02:02:26.506] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:82:0)
[02:02:26.535] Build status: STARTED
[02:02:26.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:83:0) after 6m15s
[02:08:41.629] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:83:0)
[02:08:41.651] Build status: STARTED
[02:08:41.651] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:84:0) after 2m31s
[02:11:12.843] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:84:0)
[02:11:12.868] Build status: STARTED
[02:11:12.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:85:0) after 9m21s
[02:20:33.915] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:85:0)
[02:20:33.935] Build status: STARTED
[02:20:33.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:86:0) after 3m57s
[02:24:30.958] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:86:0)
[02:24:30.979] Build status: STARTED
[02:24:30.979] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:87:0) after 1m39s
[02:26:10.055] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:87:0)
[02:26:10.088] Build status: STARTED
[02:26:10.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:88:0) after 1m50s
[02:28:00.112] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:88:0)
[02:28:00.134] Build status: STARTED
[02:28:00.134] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:89:0) after 7m54s
[02:35:54.223] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:89:0)
[02:35:54.246] Build status: STARTED
[02:35:54.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:90:0) after 7m31s
[02:43:25.373] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:90:0)
[02:43:25.396] Build status: STARTED
[02:43:25.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:91:0) after 1m38s
[02:45:03.424] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:91:0)
[02:45:03.455] Build status: STARTED
[02:45:03.455] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:92:0) after 9m14s
[02:54:17.501] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:92:0)
[02:54:17.531] Build status: STARTED
[02:54:17.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:93:0) after 3m19s
[02:57:36.641] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:93:0)
[02:57:36.680] Build status: STARTED
[02:57:36.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:94:0) after 4m38s
[03:02:14.745] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:94:0)
[03:02:14.767] Build status: STARTED
[03:02:14.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:95:0) after 2m3s
[03:04:17.890] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:95:0)
[03:04:17.921] Build status: STARTED
[03:04:17.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:96:0) after 3m52s
[03:08:10.302] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:96:0)
[03:08:10.323] Build status: STARTED
[03:08:10.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:97:0) after 3m11s
[03:11:21.673] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:97:0)
[03:11:21.694] Build status: STARTED
[03:11:21.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:98:0) after 3m47s
[03:15:08.764] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:98:0)
[03:15:08.785] Build status: STARTED
[03:15:08.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8978688317017582752:99:0) after 5m44s
[03:16:34.212] Received PubSub notification, asking Buildbucket for the build status
[03:16:34.233] Build:
{
"id": "8813258254491785201",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 2607,
"createdBy": "project:chromium",
"createTime": "2022-05-24T19:03:01.043259288Z",
"startTime": "2022-05-24T19:03:57.122492Z",
"endTime": "2022-05-25T03:16:33.157562039Z",
"updateTime": "2022-05-25T03:16:33.157562039Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "76c83e16549b25e61d6702a477c798fb767946f1",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:16:34.233] Invocation finished in 8h13m34.982328943s with status FAILED