[14:41:53.219] New invocation is queued and will start shortly
[14:41:54.329] Starting the invocation (attempt 1)
[14:41:54.360] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38d2158fc0e6cd4edb5ed46cbb6cc2757dc2b538"
[14:41:54.360] Popped gitiles commit info from properties and tags
[14:41:54.361] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:41:54.361] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:41:54.361] Buildbucket request:
{
"requestId": "8958501620556103760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "38d2158fc0e6cd4edb5ed46cbb6cc2757dc2b538",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8958501620556103760"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin64PGO"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:41:54.844] Scheduled build:
{
"id": "8793071558596805377",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4443,
"createdBy": "project:chromium",
"createTime": "2023-01-02T14:41:54.451078123Z",
"updateTime": "2023-01-02T14:41:54.451078123Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "38d2158fc0e6cd4edb5ed46cbb6cc2757dc2b538",
"ref": "refs/heads/main"
}
}
}
[14:41:54.844] Task URL: https://cr-buildbucket.appspot.com/build/8793071558596805377
[14:41:54.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:2:0) after 8m29s
[14:45:44.180] Received PubSub notification, asking Buildbucket for the build status
[14:45:44.219] Build status: STARTED
[14:50:23.909] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:2:0)
[14:50:23.932] Build status: STARTED
[14:50:23.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:4:0) after 1m30s
[14:51:54.029] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:4:0)
[14:51:54.067] Build status: STARTED
[14:51:54.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:5:0) after 8m37s
[15:00:31.145] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:5:0)
[15:00:31.172] Build status: STARTED
[15:00:31.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:6:0) after 5m46s
[15:06:17.360] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:6:0)
[15:06:17.392] Build status: STARTED
[15:06:17.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:7:0) after 5m7s
[15:11:24.416] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:7:0)
[15:11:24.438] Build status: STARTED
[15:11:24.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:8:0) after 1m43s
[15:13:08.141] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:8:0)
[15:13:08.164] Build status: STARTED
[15:13:08.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:9:0) after 4m36s
[15:17:44.525] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:9:0)
[15:17:44.547] Build status: STARTED
[15:17:44.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:10:0) after 3m18s
[15:21:02.625] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:10:0)
[15:21:02.645] Build status: STARTED
[15:21:02.645] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:11:0) after 6m47s
[15:27:49.831] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:11:0)
[15:27:49.872] Build status: STARTED
[15:27:49.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:12:0) after 5m24s
[15:33:13.871] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:12:0)
[15:33:13.893] Build status: STARTED
[15:33:13.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:13:0) after 9m41s
[15:42:55.031] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:13:0)
[15:42:55.057] Build status: STARTED
[15:42:55.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:14:0) after 4m22s
[15:47:17.114] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:14:0)
[15:47:17.232] Build status: STARTED
[15:47:17.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:15:0) after 1m58s
[15:49:15.408] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:15:0)
[15:49:15.428] Build status: STARTED
[15:49:15.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:16:0) after 5m12s
[15:54:27.806] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:16:0)
[15:54:27.849] Build status: STARTED
[15:54:27.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:17:0) after 6m25s
[16:00:53.107] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:17:0)
[16:00:53.151] Build status: STARTED
[16:00:53.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:18:0) after 1m56s
[16:02:49.401] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:18:0)
[16:02:49.425] Build status: STARTED
[16:02:49.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:19:0) after 6m15s
[16:09:04.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:19:0)
[16:09:04.577] Build status: STARTED
[16:09:04.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:20:0) after 4m9s
[16:13:13.600] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:20:0)
[16:13:13.658] Build status: STARTED
[16:13:13.658] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:21:0) after 6m50s
[16:20:03.767] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:21:0)
[16:20:03.798] Build status: STARTED
[16:20:03.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:22:0) after 5m26s
[16:25:29.828] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:22:0)
[16:25:29.850] Build status: STARTED
[16:25:29.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:23:0) after 5m28s
[16:30:57.895] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:23:0)
[16:30:57.920] Build status: STARTED
[16:30:57.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:24:0) after 3m10s
[16:34:07.940] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:24:0)
[16:34:07.962] Build status: STARTED
[16:34:07.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:25:0) after 2m8s
[16:36:16.039] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:25:0)
[16:36:16.064] Build status: STARTED
[16:36:16.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:26:0) after 1m39s
[16:37:55.360] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:26:0)
[16:37:55.462] Build status: STARTED
[16:37:55.462] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:27:0) after 8m1s
[16:45:57.197] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:27:0)
[16:45:57.220] Build status: STARTED
[16:45:57.220] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:28:0) after 8m45s
[16:54:42.338] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:28:0)
[16:54:42.360] Build status: STARTED
[16:54:42.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:29:0) after 2m29s
[16:57:11.631] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:29:0)
[16:57:11.653] Build status: STARTED
[16:57:11.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:30:0) after 2m10s
[16:59:21.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:30:0)
[16:59:21.697] Build status: STARTED
[16:59:21.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:31:0) after 2m58s
[17:02:19.824] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:31:0)
[17:02:19.851] Build status: STARTED
[17:02:19.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:32:0) after 5m53s
[17:08:12.871] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:32:0)
[17:08:12.914] Build status: STARTED
[17:08:12.914] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:33:0) after 2m37s
[17:10:50.044] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:33:0)
[17:10:50.148] Build status: STARTED
[17:10:50.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:34:0) after 7m2s
[17:17:52.361] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:34:0)
[17:17:52.383] Build status: STARTED
[17:17:52.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:35:0) after 8m52s
[17:26:44.450] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:35:0)
[17:26:44.479] Build status: STARTED
[17:26:44.479] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:36:0) after 2m8s
[17:28:52.506] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:36:0)
[17:28:52.571] Build status: STARTED
[17:28:52.571] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:37:0) after 2m14s
[17:31:06.740] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:37:0)
[17:31:06.778] Build status: STARTED
[17:31:06.778] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:38:0) after 1m20s
[17:32:26.906] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:38:0)
[17:32:26.938] Build status: STARTED
[17:32:26.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:39:0) after 3m29s
[17:35:55.965] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:39:0)
[17:35:56.005] Build status: STARTED
[17:35:56.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:40:0) after 7m49s
[17:43:45.061] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:40:0)
[17:43:45.112] Build status: STARTED
[17:43:45.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:41:0) after 3m36s
[17:47:21.133] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:41:0)
[17:47:21.154] Build status: STARTED
[17:47:21.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:42:0) after 7m4s
[17:54:25.212] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:42:0)
[17:54:25.234] Build status: STARTED
[17:54:25.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:43:0) after 6m40s
[18:01:05.338] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:43:0)
[18:01:05.405] Build status: STARTED
[18:01:05.405] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:44:0) after 9m34s
[18:10:39.435] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:44:0)
[18:10:39.598] Build status: STARTED
[18:10:39.598] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:45:0) after 3m7s
[18:13:46.766] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:45:0)
[18:13:46.789] Build status: STARTED
[18:13:46.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:46:0) after 7m36s
[18:21:22.843] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:46:0)
[18:21:22.867] Build status: STARTED
[18:21:22.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:47:0) after 8m8s
[18:29:31.029] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:47:0)
[18:29:31.062] Build status: STARTED
[18:29:31.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:48:0) after 1m22s
[18:30:53.208] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:48:0)
[18:30:53.239] Build status: STARTED
[18:30:53.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:49:0) after 8m59s
[18:39:52.413] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:49:0)
[18:39:52.438] Build status: STARTED
[18:39:52.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:50:0) after 4m0s
[18:43:52.452] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:50:0)
[18:43:52.483] Build status: STARTED
[18:43:52.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:51:0) after 7m29s
[18:51:21.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:51:0)
[18:51:21.664] Build status: STARTED
[18:51:21.664] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:52:0) after 3m1s
[18:54:22.774] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:52:0)
[18:54:22.847] Build status: STARTED
[18:54:22.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:53:0) after 9m48s
[19:04:10.869] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:53:0)
[19:04:10.897] Build status: STARTED
[19:04:10.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:54:0) after 1m11s
[19:05:21.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:54:0)
[19:05:21.941] Build status: STARTED
[19:05:21.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:55:0) after 3m33s
[19:08:54.969] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:55:0)
[19:08:54.989] Build status: STARTED
[19:08:54.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:56:0) after 6m42s
[19:15:37.277] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:56:0)
[19:15:37.298] Build status: STARTED
[19:15:37.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:57:0) after 3m7s
[19:18:44.304] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:57:0)
[19:18:44.326] Build status: STARTED
[19:18:44.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:58:0) after 5m48s
[19:24:32.324] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:58:0)
[19:24:32.371] Build status: STARTED
[19:24:32.371] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:59:0) after 9m40s
[19:34:12.433] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:59:0)
[19:34:12.460] Build status: STARTED
[19:34:12.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:60:0) after 9m25s
[19:43:37.510] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:60:0)
[19:43:37.535] Build status: STARTED
[19:43:37.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:61:0) after 8m43s
[19:52:20.590] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:61:0)
[19:52:20.621] Build status: STARTED
[19:52:20.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:62:0) after 9m8s
[20:01:28.645] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:62:0)
[20:01:28.707] Build status: STARTED
[20:01:28.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:63:0) after 3m53s
[20:05:21.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:63:0)
[20:05:21.798] Build status: STARTED
[20:05:21.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:64:0) after 3m0s
[20:08:21.821] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:64:0)
[20:08:21.842] Build status: STARTED
[20:08:21.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:65:0) after 4m18s
[20:12:40.526] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:65:0)
[20:12:40.611] Build status: STARTED
[20:12:40.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:66:0) after 1m33s
[20:14:13.634] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:66:0)
[20:14:13.654] Build status: STARTED
[20:14:13.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:67:0) after 4m8s
[20:18:21.680] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:67:0)
[20:18:21.711] Build status: STARTED
[20:18:21.711] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:68:0) after 7m45s
[20:26:06.766] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:68:0)
[20:26:06.789] Build status: STARTED
[20:26:06.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:69:0) after 5m47s
[20:31:53.815] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:69:0)
[20:31:53.851] Build status: STARTED
[20:31:53.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:70:0) after 8m5s
[20:39:59.012] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:70:0)
[20:39:59.037] Build status: STARTED
[20:39:59.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:71:0) after 1m27s
[20:41:26.058] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:71:0)
[20:41:26.087] Build status: STARTED
[20:41:26.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:72:0) after 3m8s
[20:44:34.106] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:72:0)
[20:44:34.127] Build status: STARTED
[20:44:34.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:73:0) after 6m11s
[20:50:45.239] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:73:0)
[20:50:45.299] Build status: STARTED
[20:50:45.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:74:0) after 2m49s
[20:53:34.311] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:74:0)
[20:53:34.333] Build status: STARTED
[20:53:34.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:75:0) after 8m51s
[21:02:25.415] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:75:0)
[21:02:25.441] Build status: STARTED
[21:02:25.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:76:0) after 7m48s
[21:10:13.474] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:76:0)
[21:10:13.500] Build status: STARTED
[21:10:13.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:77:0) after 6m53s
[21:17:06.524] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:77:0)
[21:17:06.640] Build status: STARTED
[21:17:06.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:78:0) after 5m59s
[21:23:05.686] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:78:0)
[21:23:05.709] Build status: STARTED
[21:23:05.709] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:79:0) after 4m13s
[21:27:19.013] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:79:0)
[21:27:19.037] Build status: STARTED
[21:27:19.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:80:0) after 8m2s
[21:35:21.069] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:80:0)
[21:35:21.098] Build status: STARTED
[21:35:21.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:81:0) after 5m0s
[21:40:21.135] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:81:0)
[21:40:21.157] Build status: STARTED
[21:40:21.157] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:82:0) after 2m2s
[21:42:23.196] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:82:0)
[21:42:23.216] Build status: STARTED
[21:42:23.216] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:83:0) after 6m45s
[21:49:08.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:83:0)
[21:49:08.445] Build status: STARTED
[21:49:08.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:84:0) after 5m8s
[21:54:16.572] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:84:0)
[21:54:16.594] Build status: STARTED
[21:54:16.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:85:0) after 5m59s
[22:00:15.628] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:85:0)
[22:00:15.650] Build status: STARTED
[22:00:15.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:86:0) after 9m56s
[22:10:11.708] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:86:0)
[22:10:11.731] Build status: STARTED
[22:10:11.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:87:0) after 2m34s
[22:12:45.750] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:87:0)
[22:12:45.781] Build status: STARTED
[22:12:45.781] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:88:0) after 4m9s
[22:16:54.877] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:88:0)
[22:16:54.906] Build status: STARTED
[22:16:54.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:89:0) after 2m22s
[22:19:16.972] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:89:0)
[22:19:17.011] Build status: STARTED
[22:19:17.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:90:0) after 9m12s
[22:28:29.033] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:90:0)
[22:28:29.066] Build status: STARTED
[22:28:29.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:91:0) after 2m23s
[22:30:52.207] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:91:0)
[22:30:52.235] Build status: STARTED
[22:30:52.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:92:0) after 9m27s
[22:40:19.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:92:0)
[22:40:19.285] Build status: STARTED
[22:40:19.285] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:93:0) after 2m24s
[22:42:43.278] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:93:0)
[22:42:43.299] Build status: STARTED
[22:42:43.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:94:0) after 2m45s
[22:45:28.385] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:94:0)
[22:45:28.405] Build status: STARTED
[22:45:28.405] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:95:0) after 7m25s
[22:52:53.425] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:95:0)
[22:52:53.458] Build status: STARTED
[22:52:53.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:96:0) after 1m5s
[22:53:58.576] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:96:0)
[22:53:58.600] Build status: STARTED
[22:53:58.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:97:0) after 1m25s
[22:55:23.637] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:97:0)
[22:55:23.656] Build status: STARTED
[22:55:23.656] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:98:0) after 6m20s
[23:01:43.795] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:98:0)
[23:01:43.882] Build status: STARTED
[23:01:43.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:99:0) after 8m27s
[23:10:10.897] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:99:0)
[23:10:10.932] Build status: STARTED
[23:10:10.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:100:0) after 9m52s
[23:20:03.067] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:100:0)
[23:20:03.092] Build status: STARTED
[23:20:03.092] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:101:0) after 7m0s
[23:27:03.196] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:101:0)
[23:27:03.223] Build status: STARTED
[23:27:03.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:102:0) after 3m21s
[23:30:24.481] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:102:0)
[23:30:24.508] Build status: STARTED
[23:30:24.508] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:103:0) after 1m9s
[23:31:33.550] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:103:0)
[23:31:33.762] Build status: STARTED
[23:31:33.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8958501620556103760:104:0) after 7m4s
[23:36:44.609] Received PubSub notification, asking Buildbucket for the build status
[23:36:44.631] Build:
{
"id": "8793071558596805377",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4443,
"createdBy": "project:chromium",
"createTime": "2023-01-02T14:41:54.451078123Z",
"startTime": "2023-01-02T14:45:43.684727Z",
"endTime": "2023-01-02T23:36:43.843618944Z",
"updateTime": "2023-01-02T23:36:43.843618944Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "38d2158fc0e6cd4edb5ed46cbb6cc2757dc2b538",
"ref": "refs/heads/main"
}
}
}
[23:36:44.631] Invocation finished in 8h54m51.432772735s with status FAILED