[07:24:30.113] New invocation is queued and will start shortly
[07:24:31.237] Starting the invocation (attempt 1)
[07:24:31.273] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bebded2d86cdbf73f7804cd4529017e43e5094bc"
[07:24:31.273] Popped gitiles commit info from properties and tags
[07:24:31.273] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:24:31.275] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:24:31.275] Buildbucket request:
{
"requestId": "8993590164439621072",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bebded2d86cdbf73f7804cd4529017e43e5094bc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993590164439621072"
},
{
"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"
}
}
[07:24:31.846] Scheduled build:
{
"id": "8828160102495677201",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1868,
"createdBy": "project:chromium",
"createTime": "2021-12-11T07:24:31.335755405Z",
"updateTime": "2021-12-11T07:24:31.335755405Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bebded2d86cdbf73f7804cd4529017e43e5094bc",
"ref": "refs/heads/main"
}
}
}
[07:24:31.846] Task URL: https://cr-buildbucket.appspot.com/build/8828160102495677201
[07:24:31.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:2:0) after 2m38s
[07:27:09.863] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:2:0)
[07:27:09.888] Build status: SCHEDULED
[07:27:09.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:3:0) after 3m43s
[07:30:52.981] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:3:0)
[07:30:53.017] Build status: SCHEDULED
[07:30:53.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:4:0) after 5m38s
[07:36:31.035] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:4:0)
[07:36:31.068] Build status: SCHEDULED
[07:36:31.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:5:0) after 7m46s
[07:44:17.085] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:5:0)
[07:44:17.121] Build status: SCHEDULED
[07:44:17.121] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:6:0) after 8m57s
[07:53:14.141] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:6:0)
[07:53:14.201] Build status: SCHEDULED
[07:53:14.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:7:0) after 4m11s
[07:57:25.272] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:7:0)
[07:57:25.307] Build status: SCHEDULED
[07:57:25.307] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:8:0) after 4m42s
[08:02:07.353] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:8:0)
[08:02:07.373] Build status: SCHEDULED
[08:02:07.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:9:0) after 6m58s
[08:09:05.395] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:9:0)
[08:09:05.416] Build status: SCHEDULED
[08:09:05.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:10:0) after 3m43s
[08:12:48.481] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:10:0)
[08:12:48.503] Build status: SCHEDULED
[08:12:48.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:11:0) after 3m18s
[08:16:06.666] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:11:0)
[08:16:06.695] Build status: SCHEDULED
[08:16:06.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:12:0) after 9m42s
[08:25:48.717] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:12:0)
[08:25:48.737] Build status: SCHEDULED
[08:25:48.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:13:0) after 9m4s
[08:34:52.758] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:13:0)
[08:34:52.776] Build status: SCHEDULED
[08:34:52.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:14:0) after 6m42s
[08:41:34.825] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:14:0)
[08:41:34.852] Build status: SCHEDULED
[08:41:34.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:15:0) after 3m27s
[08:45:01.883] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:15:0)
[08:45:01.919] Build status: SCHEDULED
[08:45:01.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:16:0) after 6m19s
[08:51:20.941] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:16:0)
[08:51:20.975] Build status: SCHEDULED
[08:51:20.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:17:0) after 2m40s
[08:54:01.102] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:17:0)
[08:54:01.154] Build status: SCHEDULED
[08:54:01.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:18:0) after 1m41s
[08:55:22.488] Received PubSub notification, asking Buildbucket for the build status
[08:55:22.508] Build status: STARTED
[08:55:42.178] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:18:0)
[08:55:42.211] Build status: STARTED
[08:55:42.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:20:0) after 7m3s
[09:02:45.378] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:20:0)
[09:02:45.486] Build status: STARTED
[09:02:45.486] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:21:0) after 9m56s
[09:12:41.548] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:21:0)
[09:12:41.577] Build status: STARTED
[09:12:41.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:22:0) after 3m43s
[09:16:24.597] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:22:0)
[09:16:24.629] Build status: STARTED
[09:16:24.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:23:0) after 3m12s
[09:19:36.651] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:23:0)
[09:19:36.669] Build status: STARTED
[09:19:36.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:24:0) after 1m56s
[09:21:32.691] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:24:0)
[09:21:32.710] Build status: STARTED
[09:21:32.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:25:0) after 7m0s
[09:28:32.731] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:25:0)
[09:28:32.752] Build status: STARTED
[09:28:32.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:26:0) after 9m46s
[09:38:18.854] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:26:0)
[09:38:18.888] Build status: STARTED
[09:38:18.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:27:0) after 9m38s
[09:47:57.083] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:27:0)
[09:47:57.106] Build status: STARTED
[09:47:57.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:28:0) after 4m2s
[09:51:59.129] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:28:0)
[09:51:59.150] Build status: STARTED
[09:51:59.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:29:0) after 4m18s
[09:56:17.205] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:29:0)
[09:56:17.225] Build status: STARTED
[09:56:17.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:30:0) after 4m48s
[10:01:05.245] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:30:0)
[10:01:05.267] Build status: STARTED
[10:01:05.267] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:31:0) after 6m20s
[10:07:25.308] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:31:0)
[10:07:25.359] Build status: STARTED
[10:07:25.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:32:0) after 5m32s
[10:12:57.380] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:32:0)
[10:12:57.410] Build status: STARTED
[10:12:57.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:33:0) after 4m42s
[10:17:39.510] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:33:0)
[10:17:39.558] Build status: STARTED
[10:17:39.558] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:34:0) after 3m11s
[10:20:50.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:34:0)
[10:20:50.791] Build status: STARTED
[10:20:50.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:35:0) after 1m33s
[10:22:23.811] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:35:0)
[10:22:23.835] Build status: STARTED
[10:22:23.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:36:0) after 1m1s
[10:23:24.866] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:36:0)
[10:23:24.886] Build status: STARTED
[10:23:24.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:37:0) after 7m14s
[10:30:39.030] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:37:0)
[10:30:39.064] Build status: STARTED
[10:30:39.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:38:0) after 8m55s
[10:39:34.168] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:38:0)
[10:39:34.201] Build status: STARTED
[10:39:34.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:39:0) after 1m54s
[10:41:28.819] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:39:0)
[10:41:28.850] Build status: STARTED
[10:41:28.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:40:0) after 5m37s
[10:47:05.870] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:40:0)
[10:47:05.902] Build status: STARTED
[10:47:05.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:41:0) after 7m29s
[10:54:34.926] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:41:0)
[10:54:34.948] Build status: STARTED
[10:54:34.948] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:42:0) after 1m59s
[10:56:33.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:42:0)
[10:56:34.031] Build status: STARTED
[10:56:34.031] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:43:0) after 7m31s
[11:04:05.052] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:43:0)
[11:04:05.077] Build status: STARTED
[11:04:05.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:44:0) after 8m31s
[11:12:36.842] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:44:0)
[11:12:36.872] Build status: STARTED
[11:12:36.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:45:0) after 1m3s
[11:13:40.399] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:45:0)
[11:13:40.430] Build status: STARTED
[11:13:40.430] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:46:0) after 8m17s
[11:21:57.475] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:46:0)
[11:21:57.497] Build status: STARTED
[11:21:57.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:47:0) after 2m23s
[11:24:20.521] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:47:0)
[11:24:20.544] Build status: STARTED
[11:24:20.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:48:0) after 8m50s
[11:33:10.778] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:48:0)
[11:33:10.809] Build status: STARTED
[11:33:10.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:49:0) after 9m49s
[11:43:00.055] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:49:0)
[11:43:00.090] Build status: STARTED
[11:43:00.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:50:0) after 8m22s
[11:51:22.113] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:50:0)
[11:51:22.147] Build status: STARTED
[11:51:22.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:51:0) after 2m55s
[11:54:17.174] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:51:0)
[11:54:17.206] Build status: STARTED
[11:54:17.206] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:52:0) after 9m14s
[12:03:31.239] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:52:0)
[12:03:31.273] Build status: STARTED
[12:03:31.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:53:0) after 6m22s
[12:09:53.300] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:53:0)
[12:09:53.336] Build status: STARTED
[12:09:53.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:54:0) after 7m44s
[12:17:37.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:54:0)
[12:17:37.945] Build status: STARTED
[12:17:37.945] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:55:0) after 7m6s
[12:24:44.394] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:55:0)
[12:24:44.434] Build status: STARTED
[12:24:44.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:56:0) after 3m57s
[12:28:42.032] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:56:0)
[12:28:42.060] Build status: STARTED
[12:28:42.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:57:0) after 1m34s
[12:30:16.092] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:57:0)
[12:30:16.115] Build status: STARTED
[12:30:16.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:58:0) after 8m50s
[12:39:06.177] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:58:0)
[12:39:06.250] Build status: STARTED
[12:39:06.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:59:0) after 9m52s
[12:48:58.515] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:59:0)
[12:48:58.537] Build status: STARTED
[12:48:58.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:60:0) after 2m2s
[12:51:00.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:60:0)
[12:51:00.799] Build status: STARTED
[12:51:00.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:61:0) after 9m46s
[13:00:46.822] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:61:0)
[13:00:46.862] Build status: STARTED
[13:00:46.862] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:62:0) after 7m6s
[13:07:52.886] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:62:0)
[13:07:52.917] Build status: STARTED
[13:07:52.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:63:0) after 4m35s
[13:12:27.942] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:63:0)
[13:12:27.987] Build status: STARTED
[13:12:27.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:64:0) after 7m36s
[13:20:04.024] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:64:0)
[13:20:04.047] Build status: STARTED
[13:20:04.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:65:0) after 8m46s
[13:28:50.023] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:65:0)
[13:28:50.058] Build status: STARTED
[13:28:50.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:66:0) after 7m39s
[13:36:29.315] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:66:0)
[13:36:29.345] Build status: STARTED
[13:36:29.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:67:0) after 1m8s
[13:37:37.756] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:67:0)
[13:37:37.775] Build status: STARTED
[13:37:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:68:0) after 3m46s
[13:41:24.539] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:68:0)
[13:41:24.600] Build status: STARTED
[13:41:24.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:69:0) after 3m36s
[13:45:00.736] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:69:0)
[13:45:00.765] Build status: STARTED
[13:45:00.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:70:0) after 3m30s
[13:48:30.788] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:70:0)
[13:48:30.810] Build status: STARTED
[13:48:30.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:71:0) after 7m1s
[13:55:31.841] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:71:0)
[13:55:31.881] Build status: STARTED
[13:55:31.881] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:72:0) after 2m49s
[13:58:21.174] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:72:0)
[13:58:21.222] Build status: STARTED
[13:58:21.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:73:0) after 3m23s
[14:01:44.243] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:73:0)
[14:01:44.287] Build status: STARTED
[14:01:44.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:74:0) after 3m25s
[14:05:09.650] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:74:0)
[14:05:09.679] Build status: STARTED
[14:05:09.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:75:0) after 8m5s
[14:13:14.689] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:75:0)
[14:13:14.720] Build status: STARTED
[14:13:14.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:76:0) after 6m29s
[14:19:43.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:76:0)
[14:19:43.903] Build status: STARTED
[14:19:43.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:77:0) after 7m9s
[14:26:53.265] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:77:0)
[14:26:53.339] Build status: STARTED
[14:26:53.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:78:0) after 5m58s
[14:32:51.431] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:78:0)
[14:32:51.465] Build status: STARTED
[14:32:51.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:79:0) after 7m31s
[14:40:22.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:79:0)
[14:40:22.629] Build status: STARTED
[14:40:22.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:80:0) after 6m8s
[14:46:30.722] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:80:0)
[14:46:30.742] Build status: STARTED
[14:46:30.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:81:0) after 4m29s
[14:51:00.119] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:81:0)
[14:51:00.167] Build status: STARTED
[14:51:00.167] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:82:0) after 3m46s
[14:54:46.193] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:82:0)
[14:54:46.224] Build status: STARTED
[14:54:46.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:83:0) after 6m14s
[15:01:00.628] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:83:0)
[15:01:00.654] Build status: STARTED
[15:01:00.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:84:0) after 5m2s
[15:06:02.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:84:0)
[15:06:02.708] Build status: STARTED
[15:06:02.708] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:85:0) after 1m46s
[15:07:48.716] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:85:0)
[15:07:48.759] Build status: STARTED
[15:07:48.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:86:0) after 9m12s
[15:17:00.977] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:86:0)
[15:17:01.001] Build status: STARTED
[15:17:01.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:87:0) after 2m2s
[15:19:03.023] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:87:0)
[15:19:03.056] Build status: STARTED
[15:19:03.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:88:0) after 1m8s
[15:20:11.077] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:88:0)
[15:20:11.098] Build status: STARTED
[15:20:11.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:89:0) after 1m30s
[15:21:41.215] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:89:0)
[15:21:41.242] Build status: STARTED
[15:21:41.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:90:0) after 1m43s
[15:23:24.274] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:90:0)
[15:23:24.299] Build status: STARTED
[15:23:24.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:91:0) after 4m28s
[15:27:52.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:91:0)
[15:27:52.344] Build status: STARTED
[15:27:52.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:92:0) after 6m37s
[15:34:29.366] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:92:0)
[15:34:29.385] Build status: STARTED
[15:34:29.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:93:0) after 9m27s
[15:43:56.668] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:93:0)
[15:43:56.691] Build status: STARTED
[15:43:56.691] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:94:0) after 6m37s
[15:50:33.714] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:94:0)
[15:50:33.738] Build status: STARTED
[15:50:33.738] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:95:0) after 8m44s
[15:59:17.794] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:95:0)
[15:59:17.834] Build status: STARTED
[15:59:17.834] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:96:0) after 7m11s
[16:06:28.924] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:96:0)
[16:06:28.946] Build status: STARTED
[16:06:28.946] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:97:0) after 1m20s
[16:07:49.181] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:97:0)
[16:07:49.209] Build status: STARTED
[16:07:49.209] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:98:0) after 6m46s
[16:14:35.581] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:98:0)
[16:14:35.610] Build status: STARTED
[16:14:35.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:99:0) after 9m24s
[16:23:59.665] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:99:0)
[16:23:59.699] Build status: STARTED
[16:23:59.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:100:0) after 5m42s
[16:29:41.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:100:0)
[16:29:41.946] Build status: STARTED
[16:29:41.946] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:101:0) after 9m21s
[16:39:02.973] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:101:0)
[16:39:03.029] Build status: STARTED
[16:39:03.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:102:0) after 9m5s
[16:48:08.132] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:102:0)
[16:48:08.165] Build status: STARTED
[16:48:08.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:103:0) after 1m37s
[16:49:45.483] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:103:0)
[16:49:45.502] Build status: STARTED
[16:49:45.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:104:0) after 3m5s
[16:52:50.522] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:104:0)
[16:52:50.550] Build status: STARTED
[16:52:50.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:105:0) after 9m37s
[17:02:27.586] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:105:0)
[17:02:27.615] Build status: STARTED
[17:02:27.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:106:0) after 1m34s
[17:04:01.652] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:106:0)
[17:04:01.693] Build status: STARTED
[17:04:01.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:107:0) after 8m38s
[17:12:39.740] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:107:0)
[17:12:39.763] Build status: STARTED
[17:12:39.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:108:0) after 8m27s
[17:21:06.782] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:108:0)
[17:21:06.807] Build status: STARTED
[17:21:06.807] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993590164439621072:109:0) after 8m47s
[17:23:54.324] Received PubSub notification, asking Buildbucket for the build status
[17:23:54.344] Build:
{
"id": "8828160102495677201",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1868,
"createdBy": "project:chromium",
"createTime": "2021-12-11T07:24:31.335755405Z",
"startTime": "2021-12-11T08:55:21.697412Z",
"endTime": "2021-12-11T17:23:53.551485408Z",
"updateTime": "2021-12-11T17:23:53.551485408Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bebded2d86cdbf73f7804cd4529017e43e5094bc",
"ref": "refs/heads/main"
}
}
}
[17:23:54.344] Invocation finished in 9h59m24.246056622s with status FAILED