[20:05:18.637] New invocation is queued and will start shortly
[20:05:19.706] Starting the invocation (attempt 1)
[20:05:19.737] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/599fb054aefbf9dcf3912ffb43c9f3972e46316e"
[20:05:19.737] Popped gitiles commit info from properties and tags
[20:05:19.737] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:05:19.737] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:05:19.737] Buildbucket request:
{
"requestId": "8993451701524667584",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "599fb054aefbf9dcf3912ffb43c9f3972e46316e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993451701524667584"
},
{
"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"
}
}
[20:05:20.294] Scheduled build:
{
"id": "8828021639658391505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1871,
"createdBy": "project:chromium",
"createTime": "2021-12-12T20:05:19.787962784Z",
"updateTime": "2021-12-12T20:05:19.787962784Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "599fb054aefbf9dcf3912ffb43c9f3972e46316e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:05:20.294] Task URL: https://cr-buildbucket.appspot.com/build/8828021639658391505
[20:05:20.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:2:0) after 6m6s
[20:11:26.378] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:2:0)
[20:11:26.410] Build status: SCHEDULED
[20:11:26.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:3:0) after 6m31s
[20:17:57.510] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:3:0)
[20:17:57.525] Build status: SCHEDULED
[20:17:57.525] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:4:0) after 8m56s
[20:26:53.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:4:0)
[20:26:53.585] Build status: SCHEDULED
[20:26:53.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:5:0) after 4m3s
[20:30:57.013] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:5:0)
[20:30:57.048] Build status: SCHEDULED
[20:30:57.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:6:0) after 4m22s
[20:35:19.238] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:6:0)
[20:35:19.274] Build status: SCHEDULED
[20:35:19.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:7:0) after 6m16s
[20:41:35.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:7:0)
[20:41:35.528] Build status: SCHEDULED
[20:41:35.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:8:0) after 2m49s
[20:44:24.948] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:8:0)
[20:44:24.968] Build status: SCHEDULED
[20:44:24.968] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:9:0) after 7m12s
[20:51:37.000] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:9:0)
[20:51:37.020] Build status: SCHEDULED
[20:51:37.020] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:10:0) after 4m45s
[20:56:22.373] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:10:0)
[20:56:22.415] Build status: SCHEDULED
[20:56:22.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:11:0) after 8m27s
[21:04:49.436] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:11:0)
[21:04:49.466] Build status: SCHEDULED
[21:04:49.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:12:0) after 7m25s
[21:12:14.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:12:0)
[21:12:14.541] Build status: SCHEDULED
[21:12:14.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:13:0) after 4m16s
[21:16:30.545] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:13:0)
[21:16:30.617] Build status: SCHEDULED
[21:16:30.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:14:0) after 6m33s
[21:23:03.734] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:14:0)
[21:23:03.755] Build status: SCHEDULED
[21:23:03.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:15:0) after 4m27s
[21:25:00.320] Received PubSub notification, asking Buildbucket for the build status
[21:25:00.341] Build status: STARTED
[21:27:30.774] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:15:0)
[21:27:30.802] Build status: STARTED
[21:27:30.803] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:17:0) after 3m44s
[21:31:14.876] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:17:0)
[21:31:14.903] Build status: STARTED
[21:31:14.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:18:0) after 8m33s
[21:39:47.924] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:18:0)
[21:39:47.946] Build status: STARTED
[21:39:47.946] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:19:0) after 8m57s
[21:48:44.967] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:19:0)
[21:48:44.988] Build status: STARTED
[21:48:44.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:20:0) after 4m18s
[21:53:03.009] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:20:0)
[21:53:03.040] Build status: STARTED
[21:53:03.041] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:21:0) after 7m26s
[22:00:29.119] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:21:0)
[22:00:29.138] Build status: STARTED
[22:00:29.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:22:0) after 2m29s
[22:02:58.182] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:22:0)
[22:02:58.330] Build status: STARTED
[22:02:58.330] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:23:0) after 7m52s
[22:10:50.452] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:23:0)
[22:10:50.480] Build status: STARTED
[22:10:50.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:24:0) after 9m39s
[22:20:29.505] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:24:0)
[22:20:29.549] Build status: STARTED
[22:20:29.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:25:0) after 6m40s
[22:27:09.572] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:25:0)
[22:27:09.603] Build status: STARTED
[22:27:09.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:26:0) after 4m31s
[22:31:40.875] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:26:0)
[22:31:40.909] Build status: STARTED
[22:31:40.909] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:27:0) after 5m30s
[22:37:11.120] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:27:0)
[22:37:11.163] Build status: STARTED
[22:37:11.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:28:0) after 2m33s
[22:39:44.182] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:28:0)
[22:39:44.214] Build status: STARTED
[22:39:44.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:29:0) after 4m3s
[22:43:47.237] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:29:0)
[22:43:47.265] Build status: STARTED
[22:43:47.265] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:30:0) after 6m28s
[22:50:15.286] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:30:0)
[22:50:15.321] Build status: STARTED
[22:50:15.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:31:0) after 3m2s
[22:53:17.340] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:31:0)
[22:53:17.394] Build status: STARTED
[22:53:17.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:32:0) after 7m34s
[23:00:51.743] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:32:0)
[23:00:51.764] Build status: STARTED
[23:00:51.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:33:0) after 3m50s
[23:04:41.786] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:33:0)
[23:04:41.812] Build status: STARTED
[23:04:41.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:34:0) after 3m25s
[23:08:07.165] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:34:0)
[23:08:07.195] Build status: STARTED
[23:08:07.195] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:35:0) after 1m59s
[23:10:06.217] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:35:0)
[23:10:06.309] Build status: STARTED
[23:10:06.309] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:36:0) after 9m11s
[23:19:17.324] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:36:0)
[23:19:17.344] Build status: STARTED
[23:19:17.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:37:0) after 8m50s
[23:28:07.368] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:37:0)
[23:28:07.388] Build status: STARTED
[23:28:07.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:38:0) after 4m45s
[23:32:52.599] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:38:0)
[23:32:52.626] Build status: STARTED
[23:32:52.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:39:0) after 8m40s
[23:41:32.896] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:39:0)
[23:41:32.927] Build status: STARTED
[23:41:32.927] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:40:0) after 2m49s
[23:44:21.971] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:40:0)
[23:44:21.992] Build status: STARTED
[23:44:21.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:41:0) after 8m28s
[23:52:50.122] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:41:0)
[23:52:50.152] Build status: STARTED
[23:52:50.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:42:0) after 4m42s
[23:57:32.639] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:42:0)
[23:57:32.662] Build status: STARTED
[23:57:32.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:43:0) after 6m44s
[00:04:16.683] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:43:0)
[00:04:16.702] Build status: STARTED
[00:04:16.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:44:0) after 6m51s
[00:11:07.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:44:0)
[00:11:07.853] Build status: STARTED
[00:11:07.853] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:45:0) after 3m45s
[00:14:52.987] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:45:0)
[00:14:53.010] Build status: STARTED
[00:14:53.010] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:46:0) after 8m36s
[00:23:29.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:46:0)
[00:23:29.343] Build status: STARTED
[00:23:29.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:47:0) after 7m17s
[00:30:46.366] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:47:0)
[00:30:46.386] Build status: STARTED
[00:30:46.386] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:48:0) after 4m4s
[00:34:50.490] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:48:0)
[00:34:50.516] Build status: STARTED
[00:34:50.516] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:49:0) after 5m25s
[00:40:15.539] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:49:0)
[00:40:15.573] Build status: STARTED
[00:40:15.574] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:50:0) after 2m10s
[00:42:25.670] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:50:0)
[00:42:25.705] Build status: STARTED
[00:42:25.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:51:0) after 2m28s
[00:44:53.770] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:51:0)
[00:44:53.832] Build status: STARTED
[00:44:53.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:52:0) after 7m42s
[00:52:35.856] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:52:0)
[00:52:35.886] Build status: STARTED
[00:52:35.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:53:0) after 6m16s
[00:58:52.094] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:53:0)
[00:58:52.116] Build status: STARTED
[00:58:52.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:54:0) after 5m39s
[01:04:31.221] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:54:0)
[01:04:31.257] Build status: STARTED
[01:04:31.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:55:0) after 9m51s
[01:14:22.365] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:55:0)
[01:14:22.383] Build status: STARTED
[01:14:22.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:56:0) after 1m53s
[01:16:15.402] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:56:0)
[01:16:15.453] Build status: STARTED
[01:16:15.454] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:57:0) after 1m58s
[01:18:13.431] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:57:0)
[01:18:13.572] Build status: STARTED
[01:18:13.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:58:0) after 9m48s
[01:28:01.602] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:58:0)
[01:28:01.638] Build status: STARTED
[01:28:01.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:59:0) after 4m24s
[01:32:25.775] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:59:0)
[01:32:25.808] Build status: STARTED
[01:32:25.808] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:60:0) after 4m0s
[01:36:25.874] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:60:0)
[01:36:25.903] Build status: STARTED
[01:36:25.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:61:0) after 9m7s
[01:45:32.927] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:61:0)
[01:45:32.955] Build status: STARTED
[01:45:32.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:62:0) after 3m58s
[01:49:30.977] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:62:0)
[01:49:30.996] Build status: STARTED
[01:49:30.996] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:63:0) after 1m43s
[01:51:14.020] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:63:0)
[01:51:14.039] Build status: STARTED
[01:51:14.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:64:0) after 4m23s
[01:55:37.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:64:0)
[01:55:37.186] Build status: STARTED
[01:55:37.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:65:0) after 3m23s
[01:59:00.218] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:65:0)
[01:59:00.246] Build status: STARTED
[01:59:00.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:66:0) after 2m27s
[02:01:27.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:66:0)
[02:01:27.272] Build status: STARTED
[02:01:27.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:67:0) after 6m45s
[02:08:12.301] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:67:0)
[02:08:12.325] Build status: STARTED
[02:08:12.325] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:68:0) after 4m58s
[02:13:10.350] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:68:0)
[02:13:10.371] Build status: STARTED
[02:13:10.371] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:69:0) after 5m44s
[02:18:54.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:69:0)
[02:18:54.420] Build status: STARTED
[02:18:54.420] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:70:0) after 1m19s
[02:20:13.579] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:70:0)
[02:20:13.600] Build status: STARTED
[02:20:13.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:71:0) after 1m1s
[02:21:14.685] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:71:0)
[02:21:14.718] Build status: STARTED
[02:21:14.718] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:72:0) after 6m37s
[02:27:51.769] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:72:0)
[02:27:51.799] Build status: STARTED
[02:27:51.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:73:0) after 4m59s
[02:32:50.847] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:73:0)
[02:32:50.868] Build status: STARTED
[02:32:50.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:74:0) after 3m16s
[02:36:06.998] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:74:0)
[02:36:07.022] Build status: STARTED
[02:36:07.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:75:0) after 1m51s
[02:37:58.258] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:75:0)
[02:37:58.291] Build status: STARTED
[02:37:58.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:76:0) after 9m36s
[02:47:34.315] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:76:0)
[02:47:34.356] Build status: STARTED
[02:47:34.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:77:0) after 7m21s
[02:54:55.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:77:0)
[02:54:55.531] Build status: STARTED
[02:54:55.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:78:0) after 1m51s
[02:56:46.566] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:78:0)
[02:56:46.587] Build status: STARTED
[02:56:46.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:79:0) after 4m0s
[03:00:46.710] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:79:0)
[03:00:46.741] Build status: STARTED
[03:00:46.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:80:0) after 3m32s
[03:04:18.767] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:80:0)
[03:04:18.797] Build status: STARTED
[03:04:18.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:81:0) after 5m22s
[03:09:40.849] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:81:0)
[03:09:40.885] Build status: STARTED
[03:09:40.885] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:82:0) after 8m56s
[03:18:36.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:82:0)
[03:18:36.973] Build status: STARTED
[03:18:36.973] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:83:0) after 7m21s
[03:25:57.997] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:83:0)
[03:25:58.028] Build status: STARTED
[03:25:58.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:84:0) after 2m31s
[03:28:29.048] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:84:0)
[03:28:29.074] Build status: STARTED
[03:28:29.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:85:0) after 7m14s
[03:35:43.802] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:85:0)
[03:35:43.901] Build status: STARTED
[03:35:43.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:86:0) after 4m9s
[03:39:52.946] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:86:0)
[03:39:52.966] Build status: STARTED
[03:39:52.966] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:87:0) after 8m59s
[03:48:51.990] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:87:0)
[03:48:52.011] Build status: STARTED
[03:48:52.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:88:0) after 8m24s
[03:57:16.033] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:88:0)
[03:57:16.056] Build status: STARTED
[03:57:16.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:89:0) after 7m6s
[04:04:22.898] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:89:0)
[04:04:22.925] Build status: STARTED
[04:04:22.925] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:90:0) after 9m54s
[04:14:17.027] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:90:0)
[04:14:17.088] Build status: STARTED
[04:14:17.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:91:0) after 1m10s
[04:15:27.148] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:91:0)
[04:15:27.174] Build status: STARTED
[04:15:27.174] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:92:0) after 4m21s
[04:19:48.198] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:92:0)
[04:19:48.252] Build status: STARTED
[04:19:48.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:93:0) after 6m10s
[04:25:58.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:93:0)
[04:25:58.407] Build status: STARTED
[04:25:58.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:94:0) after 9m20s
[04:35:18.455] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:94:0)
[04:35:18.496] Build status: STARTED
[04:35:18.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:95:0) after 1m37s
[04:36:55.518] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:95:0)
[04:36:55.538] Build status: STARTED
[04:36:55.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:96:0) after 4m26s
[04:41:22.028] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:96:0)
[04:41:22.051] Build status: STARTED
[04:41:22.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:97:0) after 4m8s
[04:45:30.074] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:97:0)
[04:45:30.117] Build status: STARTED
[04:45:30.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:98:0) after 3m51s
[04:49:21.141] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:98:0)
[04:49:21.162] Build status: STARTED
[04:49:21.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:99:0) after 4m59s
[04:54:20.317] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:99:0)
[04:54:20.350] Build status: STARTED
[04:54:20.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:100:0) after 7m40s
[05:02:00.835] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:100:0)
[05:02:00.860] Build status: STARTED
[05:02:00.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:101:0) after 6m44s
[05:08:45.571] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:101:0)
[05:08:45.608] Build status: STARTED
[05:08:45.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:102:0) after 1m6s
[05:09:51.804] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:102:0)
[05:09:51.823] Build status: STARTED
[05:09:51.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:103:0) after 4m0s
[05:13:52.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:103:0)
[05:13:52.281] Build status: STARTED
[05:13:52.281] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:104:0) after 3m10s
[05:17:02.357] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:104:0)
[05:17:02.380] Build status: STARTED
[05:17:02.380] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:105:0) after 3m47s
[05:20:49.424] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:105:0)
[05:20:49.445] Build status: STARTED
[05:20:49.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:106:0) after 9m7s
[05:29:56.548] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:106:0)
[05:29:56.576] Build status: STARTED
[05:29:56.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:107:0) after 5m0s
[05:34:56.812] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:107:0)
[05:34:56.842] Build status: STARTED
[05:34:56.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:108:0) after 9m18s
[05:44:14.822] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:108:0)
[05:44:14.839] Build status: STARTED
[05:44:14.839] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:109:0) after 4m27s
[05:48:41.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:109:0)
[05:48:41.884] Build status: STARTED
[05:48:41.884] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:110:0) after 5m11s
[05:53:52.908] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:110:0)
[05:53:52.928] Build status: STARTED
[05:53:52.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:111:0) after 4m26s
[05:58:19.154] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:111:0)
[05:58:19.189] Build status: STARTED
[05:58:19.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:112:0) after 9m54s
[06:08:13.651] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:112:0)
[06:08:13.686] Build status: STARTED
[06:08:13.686] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:113:0) after 5m35s
[06:13:48.726] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:113:0)
[06:13:48.743] Build status: STARTED
[06:13:48.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993451701524667584:114:0) after 3m48s
[06:15:35.269] Received PubSub notification, asking Buildbucket for the build status
[06:15:35.292] Build:
{
"id": "8828021639658391505",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1871,
"createdBy": "project:chromium",
"createTime": "2021-12-12T20:05:19.787962784Z",
"startTime": "2021-12-12T21:24:59.209499Z",
"endTime": "2021-12-13T06:15:34.953783726Z",
"updateTime": "2021-12-13T06:15:34.953783726Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "599fb054aefbf9dcf3912ffb43c9f3972e46316e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[06:15:35.292] Invocation finished in 10h10m16.66939162s with status FAILED