[00:19:13.044] New invocation is queued and will start shortly
[00:19:14.875] Starting the invocation (attempt 1)
[00:19:14.915] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b795f5cbf1a407b6ab0235fe88fee11b4b4fffa2"
[00:19:14.915] Popped gitiles commit info from properties and tags
[00:19:14.915] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:19:14.915] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:19:14.915] Buildbucket request:
{
"requestId": "8993163936193885216",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b795f5cbf1a407b6ab0235fe88fee11b4b4fffa2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993163936193885216"
},
{
"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"
}
}
[00:19:15.524] Scheduled build:
{
"id": "8827733873456109713",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1879,
"createdBy": "project:chromium",
"createTime": "2021-12-16T00:19:15.023915877Z",
"updateTime": "2021-12-16T00:19:15.023915877Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b795f5cbf1a407b6ab0235fe88fee11b4b4fffa2",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:19:15.524] Task URL: https://cr-buildbucket.appspot.com/build/8827733873456109713
[00:19:15.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:2:0) after 9m24s
[00:28:39.603] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:2:0)
[00:28:39.623] Build status: SCHEDULED
[00:28:39.623] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:3:0) after 8m38s
[00:37:17.783] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:3:0)
[00:37:17.810] Build status: SCHEDULED
[00:37:17.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:4:0) after 6m52s
[00:44:09.835] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:4:0)
[00:44:09.869] Build status: SCHEDULED
[00:44:09.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:5:0) after 4m43s
[00:48:52.931] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:5:0)
[00:48:52.949] Build status: SCHEDULED
[00:48:52.949] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:6:0) after 1m44s
[00:50:36.990] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:6:0)
[00:50:37.029] Build status: SCHEDULED
[00:50:37.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:7:0) after 9m18s
[00:59:55.158] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:7:0)
[00:59:55.185] Build status: SCHEDULED
[00:59:55.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:8:0) after 5m19s
[01:05:14.330] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:8:0)
[01:05:14.349] Build status: SCHEDULED
[01:05:14.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:9:0) after 6m17s
[01:11:31.374] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:9:0)
[01:11:31.406] Build status: SCHEDULED
[01:11:31.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:10:0) after 5m52s
[01:17:23.574] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:10:0)
[01:17:23.605] Build status: SCHEDULED
[01:17:23.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:11:0) after 7m53s
[01:25:16.798] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:11:0)
[01:25:16.826] Build status: SCHEDULED
[01:25:16.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:12:0) after 6m19s
[01:31:35.829] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:12:0)
[01:31:35.862] Build status: SCHEDULED
[01:31:35.862] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:13:0) after 6m34s
[01:38:09.858] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:13:0)
[01:38:09.901] Build status: SCHEDULED
[01:38:09.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:14:0) after 5m54s
[01:44:04.033] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:14:0)
[01:44:04.057] Build status: SCHEDULED
[01:44:04.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:15:0) after 8m21s
[01:50:11.862] Received PubSub notification, asking Buildbucket for the build status
[01:50:11.882] Build status: STARTED
[01:52:25.081] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:15:0)
[01:52:25.105] Build status: STARTED
[01:52:25.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:17:0) after 6m1s
[01:58:26.164] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:17:0)
[01:58:26.188] Build status: STARTED
[01:58:26.188] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:18:0) after 6m27s
[02:04:53.439] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:18:0)
[02:04:53.460] Build status: STARTED
[02:04:53.460] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:19:0) after 5m49s
[02:10:42.588] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:19:0)
[02:10:42.628] Build status: STARTED
[02:10:42.628] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:20:0) after 7m17s
[02:17:59.779] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:20:0)
[02:17:59.801] Build status: STARTED
[02:17:59.801] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:21:0) after 3m21s
[02:21:20.983] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:21:0)
[02:21:21.006] Build status: STARTED
[02:21:21.006] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:22:0) after 5m12s
[02:26:33.163] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:22:0)
[02:26:33.183] Build status: STARTED
[02:26:33.183] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:23:0) after 3m56s
[02:30:29.276] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:23:0)
[02:30:29.305] Build status: STARTED
[02:30:29.305] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:24:0) after 3m4s
[02:33:33.329] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:24:0)
[02:33:33.352] Build status: STARTED
[02:33:33.352] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:25:0) after 6m23s
[02:39:56.477] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:25:0)
[02:39:56.497] Build status: STARTED
[02:39:56.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:26:0) after 7m10s
[02:47:06.564] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:26:0)
[02:47:06.613] Build status: STARTED
[02:47:06.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:27:0) after 5m19s
[02:52:25.694] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:27:0)
[02:52:25.728] Build status: STARTED
[02:52:25.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:28:0) after 8m46s
[03:01:11.863] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:28:0)
[03:01:11.885] Build status: STARTED
[03:01:11.885] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:29:0) after 6m1s
[03:07:13.204] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:29:0)
[03:07:13.250] Build status: STARTED
[03:07:13.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:30:0) after 7m17s
[03:14:30.339] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:30:0)
[03:14:30.373] Build status: STARTED
[03:14:30.373] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:31:0) after 1m19s
[03:15:49.711] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:31:0)
[03:15:49.745] Build status: STARTED
[03:15:49.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:32:0) after 2m38s
[03:18:27.876] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:32:0)
[03:18:27.900] Build status: STARTED
[03:18:27.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:33:0) after 8m25s
[03:26:52.996] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:33:0)
[03:26:53.017] Build status: STARTED
[03:26:53.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:34:0) after 6m57s
[03:33:50.207] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:34:0)
[03:33:50.268] Build status: STARTED
[03:33:50.268] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:35:0) after 6m25s
[03:40:15.361] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:35:0)
[03:40:15.382] Build status: STARTED
[03:40:15.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:36:0) after 4m31s
[03:44:46.419] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:36:0)
[03:44:46.440] Build status: STARTED
[03:44:46.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:37:0) after 3m26s
[03:48:12.464] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:37:0)
[03:48:12.495] Build status: STARTED
[03:48:12.495] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:38:0) after 8m28s
[03:56:40.530] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:38:0)
[03:56:40.577] Build status: STARTED
[03:56:40.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:39:0) after 4m38s
[04:01:18.633] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:39:0)
[04:01:18.659] Build status: STARTED
[04:01:18.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:40:0) after 6m12s
[04:07:30.687] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:40:0)
[04:07:30.720] Build status: STARTED
[04:07:30.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:41:0) after 2m31s
[04:10:01.715] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:41:0)
[04:10:01.743] Build status: STARTED
[04:10:01.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:42:0) after 5m25s
[04:15:26.892] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:42:0)
[04:15:26.914] Build status: STARTED
[04:15:26.914] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:43:0) after 7m27s
[04:22:54.006] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:43:0)
[04:22:54.087] Build status: STARTED
[04:22:54.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:44:0) after 6m59s
[04:29:53.265] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:44:0)
[04:29:53.344] Build status: STARTED
[04:29:53.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:45:0) after 3m13s
[04:33:06.337] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:45:0)
[04:33:06.360] Build status: STARTED
[04:33:06.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:46:0) after 8m37s
[04:41:43.393] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:46:0)
[04:41:43.447] Build status: STARTED
[04:41:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:47:0) after 3m57s
[04:45:40.677] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:47:0)
[04:45:40.704] Build status: STARTED
[04:45:40.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:48:0) after 8m35s
[04:54:15.731] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:48:0)
[04:54:15.752] Build status: STARTED
[04:54:15.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:49:0) after 3m28s
[04:57:43.793] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:49:0)
[04:57:43.813] Build status: STARTED
[04:57:43.813] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:50:0) after 8m44s
[05:06:27.851] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:50:0)
[05:06:27.872] Build status: STARTED
[05:06:27.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:51:0) after 3m39s
[05:10:06.997] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:51:0)
[05:10:07.028] Build status: STARTED
[05:10:07.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:52:0) after 6m24s
[05:16:31.054] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:52:0)
[05:16:31.074] Build status: STARTED
[05:16:31.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:53:0) after 8m51s
[05:25:22.387] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:53:0)
[05:25:22.407] Build status: STARTED
[05:25:22.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:54:0) after 8m6s
[05:33:28.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:54:0)
[05:33:28.637] Build status: STARTED
[05:33:28.637] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:55:0) after 1m55s
[05:35:23.661] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:55:0)
[05:35:23.682] Build status: STARTED
[05:35:23.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:56:0) after 6m40s
[05:42:03.775] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:56:0)
[05:42:03.798] Build status: STARTED
[05:42:03.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:57:0) after 9m38s
[05:51:41.851] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:57:0)
[05:51:41.930] Build status: STARTED
[05:51:41.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:58:0) after 4m32s
[05:56:13.980] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:58:0)
[05:56:14.005] Build status: STARTED
[05:56:14.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:59:0) after 9m25s
[06:05:39.305] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:59:0)
[06:05:39.324] Build status: STARTED
[06:05:39.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:60:0) after 6m15s
[06:11:54.354] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:60:0)
[06:11:54.376] Build status: STARTED
[06:11:54.376] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:61:0) after 7m20s
[06:19:14.405] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:61:0)
[06:19:14.427] Build status: STARTED
[06:19:14.427] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:62:0) after 2m27s
[06:21:41.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:62:0)
[06:21:41.576] Build status: STARTED
[06:21:41.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:63:0) after 8m30s
[06:30:11.604] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:63:0)
[06:30:11.635] Build status: STARTED
[06:30:11.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:64:0) after 3m36s
[06:33:47.659] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:64:0)
[06:33:47.679] Build status: STARTED
[06:33:47.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:65:0) after 3m10s
[06:36:57.849] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:65:0)
[06:36:57.868] Build status: STARTED
[06:36:57.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:66:0) after 6m59s
[06:43:56.917] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:66:0)
[06:43:56.944] Build status: STARTED
[06:43:56.944] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:67:0) after 4m25s
[06:48:21.970] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:67:0)
[06:48:21.990] Build status: STARTED
[06:48:21.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:68:0) after 6m7s
[06:54:29.019] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:68:0)
[06:54:29.068] Build status: STARTED
[06:54:29.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:69:0) after 5m57s
[07:00:26.096] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:69:0)
[07:00:26.116] Build status: STARTED
[07:00:26.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:70:0) after 7m14s
[07:07:40.145] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:70:0)
[07:07:40.172] Build status: STARTED
[07:07:40.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:71:0) after 5m43s
[07:13:23.212] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:71:0)
[07:13:23.246] Build status: STARTED
[07:13:23.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:72:0) after 5m40s
[07:19:03.285] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:72:0)
[07:19:03.305] Build status: STARTED
[07:19:03.305] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:73:0) after 8m56s
[07:27:59.321] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:73:0)
[07:27:59.450] Build status: STARTED
[07:27:59.450] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:74:0) after 3m17s
[07:31:16.493] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:74:0)
[07:31:16.532] Build status: STARTED
[07:31:16.532] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:75:0) after 3m1s
[07:34:17.565] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:75:0)
[07:34:17.592] Build status: STARTED
[07:34:17.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:76:0) after 2m26s
[07:36:43.639] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:76:0)
[07:36:43.658] Build status: STARTED
[07:36:43.658] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:77:0) after 2m25s
[07:39:08.704] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:77:0)
[07:39:08.728] Build status: STARTED
[07:39:08.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:78:0) after 9m7s
[07:48:16.097] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:78:0)
[07:48:16.118] Build status: STARTED
[07:48:16.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:79:0) after 2m19s
[07:50:35.252] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:79:0)
[07:50:35.291] Build status: STARTED
[07:50:35.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:80:0) after 4m13s
[07:54:48.318] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:80:0)
[07:54:48.359] Build status: STARTED
[07:54:48.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:81:0) after 8m9s
[08:02:57.485] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:81:0)
[08:02:57.517] Build status: STARTED
[08:02:57.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:82:0) after 2m54s
[08:05:51.545] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:82:0)
[08:05:51.568] Build status: STARTED
[08:05:51.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:83:0) after 2m27s
[08:08:18.696] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:83:0)
[08:08:18.715] Build status: STARTED
[08:08:18.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:84:0) after 5m15s
[08:13:33.705] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:84:0)
[08:13:33.724] Build status: STARTED
[08:13:33.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:85:0) after 6m1s
[08:19:35.184] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:85:0)
[08:19:35.204] Build status: STARTED
[08:19:35.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:86:0) after 3m21s
[08:22:56.270] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:86:0)
[08:22:56.292] Build status: STARTED
[08:22:56.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:87:0) after 8m25s
[08:31:21.588] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:87:0)
[08:31:21.638] Build status: STARTED
[08:31:21.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:88:0) after 7m9s
[08:38:30.643] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:88:0)
[08:38:30.663] Build status: STARTED
[08:38:30.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:89:0) after 7m57s
[08:46:28.012] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:89:0)
[08:46:28.045] Build status: STARTED
[08:46:28.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:90:0) after 9m56s
[08:56:24.174] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:90:0)
[08:56:24.200] Build status: STARTED
[08:56:24.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:91:0) after 3m29s
[08:59:53.235] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:91:0)
[08:59:53.257] Build status: STARTED
[08:59:53.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:92:0) after 4m13s
[09:04:06.282] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:92:0)
[09:04:06.303] Build status: STARTED
[09:04:06.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:93:0) after 8m10s
[09:12:16.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:93:0)
[09:12:16.427] Build status: STARTED
[09:12:16.427] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:94:0) after 4m44s
[09:17:00.797] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:94:0)
[09:17:00.832] Build status: STARTED
[09:17:00.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:95:0) after 5m51s
[09:22:51.858] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:95:0)
[09:22:51.908] Build status: STARTED
[09:22:51.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:96:0) after 1m12s
[09:24:03.937] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:96:0)
[09:24:03.958] Build status: STARTED
[09:24:03.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:97:0) after 8m2s
[09:32:06.060] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:97:0)
[09:32:06.150] Build status: STARTED
[09:32:06.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:98:0) after 9m54s
[09:42:00.172] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:98:0)
[09:42:00.208] Build status: STARTED
[09:42:00.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:99:0) after 9m27s
[09:51:27.371] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:99:0)
[09:51:27.394] Build status: STARTED
[09:51:27.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:100:0) after 2m22s
[09:53:49.599] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:100:0)
[09:53:49.631] Build status: STARTED
[09:53:49.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:101:0) after 1m9s
[09:54:58.705] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:101:0)
[09:54:58.736] Build status: STARTED
[09:54:58.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:102:0) after 4m56s
[09:59:54.869] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:102:0)
[09:59:54.891] Build status: STARTED
[09:59:54.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:103:0) after 4m57s
[10:04:51.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:103:0)
[10:04:51.971] Build status: STARTED
[10:04:51.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:104:0) after 7m45s
[10:12:37.149] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:104:0)
[10:12:37.176] Build status: STARTED
[10:12:37.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993163936193885216:105:0) after 9m48s
[10:16:43.473] Received PubSub notification, asking Buildbucket for the build status
[10:16:43.503] Build:
{
"id": "8827733873456109713",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1879,
"createdBy": "project:chromium",
"createTime": "2021-12-16T00:19:15.023915877Z",
"startTime": "2021-12-16T01:50:10.531698Z",
"endTime": "2021-12-16T10:16:43.004019571Z",
"updateTime": "2021-12-16T10:16:43.004019571Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b795f5cbf1a407b6ab0235fe88fee11b4b4fffa2",
"ref": "refs/heads/main"
}
},
"canary": true
}
[10:16:43.503] Invocation finished in 9h57m30.474432223s with status FAILED