[17:10:06.797] New invocation is queued and will start shortly
[17:10:08.613] Starting the invocation (attempt 1)
[17:10:08.656] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3f719238f4e251d0cbf2f5bb1b16566bc81da5c7"
[17:10:08.656] Popped gitiles commit info from properties and tags
[17:10:08.656] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:10:08.656] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:10:08.656] Buildbucket request:
{
"requestId": "8980869745660592704",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3f719238f4e251d0cbf2f5bb1b16566bc81da5c7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8980869745660592704"
},
{
"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"
}
}
[17:10:09.243] Scheduled build:
{
"id": "8815439682965546305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 2512,
"createdBy": "project:chromium",
"createTime": "2022-04-30T17:10:08.735221208Z",
"updateTime": "2022-04-30T17:10:08.735221208Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3f719238f4e251d0cbf2f5bb1b16566bc81da5c7",
"ref": "refs/heads/main"
}
}
}
[17:10:09.243] Task URL: https://cr-buildbucket.appspot.com/build/8815439682965546305
[17:10:09.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:2:0) after 8m32s
[17:10:35.941] Received PubSub notification, asking Buildbucket for the build status
[17:10:35.959] Build status: STARTED
[17:18:41.272] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:2:0)
[17:18:41.290] Build status: STARTED
[17:18:41.290] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:4:0) after 7m12s
[17:25:53.306] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:4:0)
[17:25:53.361] Build status: STARTED
[17:25:53.361] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:5:0) after 1m22s
[17:27:15.479] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:5:0)
[17:27:15.513] Build status: STARTED
[17:27:15.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:6:0) after 5m0s
[17:32:15.535] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:6:0)
[17:32:15.553] Build status: STARTED
[17:32:15.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:7:0) after 1m51s
[17:34:06.640] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:7:0)
[17:34:06.668] Build status: STARTED
[17:34:06.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:8:0) after 1m3s
[17:35:09.706] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:8:0)
[17:35:09.725] Build status: STARTED
[17:35:09.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:9:0) after 3m14s
[17:38:23.906] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:9:0)
[17:38:23.946] Build status: STARTED
[17:38:23.946] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:10:0) after 7m44s
[17:46:08.271] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:10:0)
[17:46:08.292] Build status: STARTED
[17:46:08.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:11:0) after 9m50s
[17:55:58.312] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:11:0)
[17:55:58.346] Build status: STARTED
[17:55:58.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:12:0) after 5m14s
[18:01:12.379] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:12:0)
[18:01:12.418] Build status: STARTED
[18:01:12.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:13:0) after 8m23s
[18:09:35.483] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:13:0)
[18:09:35.514] Build status: STARTED
[18:09:35.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:14:0) after 1m15s
[18:10:50.544] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:14:0)
[18:10:50.583] Build status: STARTED
[18:10:50.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:15:0) after 3m30s
[18:14:20.609] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:15:0)
[18:14:20.637] Build status: STARTED
[18:14:20.637] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:16:0) after 5m18s
[18:19:38.666] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:16:0)
[18:19:38.729] Build status: STARTED
[18:19:38.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:17:0) after 2m31s
[18:22:09.758] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:17:0)
[18:22:09.781] Build status: STARTED
[18:22:09.781] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:18:0) after 9m5s
[18:31:14.902] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:18:0)
[18:31:15.146] Build status: STARTED
[18:31:15.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:19:0) after 3m17s
[18:34:32.164] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:19:0)
[18:34:32.207] Build status: STARTED
[18:34:32.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:20:0) after 3m16s
[18:37:48.241] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:20:0)
[18:37:48.262] Build status: STARTED
[18:37:48.262] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:21:0) after 6m1s
[18:43:49.353] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:21:0)
[18:43:49.379] Build status: STARTED
[18:43:49.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:22:0) after 4m38s
[18:48:27.399] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:22:0)
[18:48:27.432] Build status: STARTED
[18:48:27.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:23:0) after 6m38s
[18:55:05.637] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:23:0)
[18:55:05.663] Build status: STARTED
[18:55:05.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:24:0) after 3m22s
[18:58:27.713] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:24:0)
[18:58:27.739] Build status: STARTED
[18:58:27.739] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:25:0) after 3m26s
[19:01:53.829] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:25:0)
[19:01:53.852] Build status: STARTED
[19:01:53.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:26:0) after 3m54s
[19:05:47.873] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:26:0)
[19:05:47.954] Build status: STARTED
[19:05:47.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:27:0) after 1m53s
[19:07:40.953] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:27:0)
[19:07:40.974] Build status: STARTED
[19:07:40.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:28:0) after 3m30s
[19:11:11.005] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:28:0)
[19:11:11.029] Build status: STARTED
[19:11:11.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:29:0) after 3m8s
[19:14:19.097] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:29:0)
[19:14:19.119] Build status: STARTED
[19:14:19.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:30:0) after 5m43s
[19:20:02.150] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:30:0)
[19:20:02.207] Build status: STARTED
[19:20:02.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:31:0) after 8m30s
[19:28:32.726] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:31:0)
[19:28:32.763] Build status: STARTED
[19:28:32.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:32:0) after 7m26s
[19:35:58.784] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:32:0)
[19:35:58.826] Build status: STARTED
[19:35:58.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:33:0) after 4m33s
[19:40:31.935] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:33:0)
[19:40:31.992] Build status: STARTED
[19:40:31.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:34:0) after 2m50s
[19:43:22.017] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:34:0)
[19:43:22.051] Build status: STARTED
[19:43:22.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:35:0) after 9m28s
[19:52:50.086] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:35:0)
[19:52:50.106] Build status: STARTED
[19:52:50.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:36:0) after 3m17s
[19:56:07.355] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:36:0)
[19:56:07.390] Build status: STARTED
[19:56:07.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:37:0) after 3m2s
[19:59:09.417] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:37:0)
[19:59:09.445] Build status: STARTED
[19:59:09.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:38:0) after 9m46s
[20:08:55.522] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:38:0)
[20:08:55.555] Build status: STARTED
[20:08:55.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:39:0) after 6m33s
[20:15:28.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:39:0)
[20:15:28.784] Build status: STARTED
[20:15:28.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:40:0) after 3m0s
[20:18:28.971] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:40:0)
[20:18:29.039] Build status: STARTED
[20:18:29.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:41:0) after 7m18s
[20:25:47.228] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:41:0)
[20:25:47.255] Build status: STARTED
[20:25:47.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:42:0) after 6m56s
[20:32:43.279] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:42:0)
[20:32:43.299] Build status: STARTED
[20:32:43.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:43:0) after 1m29s
[20:34:12.318] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:43:0)
[20:34:12.368] Build status: STARTED
[20:34:12.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:44:0) after 7m19s
[20:41:31.399] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:44:0)
[20:41:31.424] Build status: STARTED
[20:41:31.424] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:45:0) after 5m29s
[20:47:00.472] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:45:0)
[20:47:00.525] Build status: STARTED
[20:47:00.525] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:46:0) after 9m1s
[20:56:01.550] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:46:0)
[20:56:01.583] Build status: STARTED
[20:56:01.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:47:0) after 3m16s
[20:59:17.608] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:47:0)
[20:59:17.643] Build status: STARTED
[20:59:17.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:48:0) after 7m59s
[21:07:16.863] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:48:0)
[21:07:16.904] Build status: STARTED
[21:07:16.904] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:49:0) after 5m13s
[21:12:29.946] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:49:0)
[21:12:29.999] Build status: STARTED
[21:12:29.999] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:50:0) after 3m50s
[21:16:20.035] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:50:0)
[21:16:20.074] Build status: STARTED
[21:16:20.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:51:0) after 6m43s
[21:23:03.108] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:51:0)
[21:23:03.138] Build status: STARTED
[21:23:03.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:52:0) after 1m45s
[21:24:48.240] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:52:0)
[21:24:48.262] Build status: STARTED
[21:24:48.262] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:53:0) after 2m25s
[21:27:13.287] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:53:0)
[21:27:13.334] Build status: STARTED
[21:27:13.334] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:54:0) after 7m33s
[21:34:46.361] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:54:0)
[21:34:46.396] Build status: STARTED
[21:34:46.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:55:0) after 8m12s
[21:42:58.518] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:55:0)
[21:42:58.542] Build status: STARTED
[21:42:58.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:56:0) after 8m44s
[21:51:42.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:56:0)
[21:51:42.584] Build status: STARTED
[21:51:42.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:57:0) after 1m16s
[21:52:58.604] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:57:0)
[21:52:58.632] Build status: STARTED
[21:52:58.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:58:0) after 7m27s
[22:00:25.658] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:58:0)
[22:00:25.691] Build status: STARTED
[22:00:25.691] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:59:0) after 8m33s
[22:08:58.696] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:59:0)
[22:08:58.716] Build status: STARTED
[22:08:58.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:60:0) after 7m17s
[22:16:15.863] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:60:0)
[22:16:15.920] Build status: STARTED
[22:16:15.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:61:0) after 8m50s
[22:25:05.942] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:61:0)
[22:25:06.033] Build status: STARTED
[22:25:06.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:62:0) after 9m11s
[22:34:17.015] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:62:0)
[22:34:17.038] Build status: STARTED
[22:34:17.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:63:0) after 4m49s
[22:39:06.211] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:63:0)
[22:39:06.234] Build status: STARTED
[22:39:06.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:64:0) after 2m8s
[22:41:14.331] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:64:0)
[22:41:14.359] Build status: STARTED
[22:41:14.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:65:0) after 7m35s
[22:48:49.379] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:65:0)
[22:48:49.426] Build status: STARTED
[22:48:49.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:66:0) after 8m55s
[22:57:44.552] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:66:0)
[22:57:44.575] Build status: STARTED
[22:57:44.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:67:0) after 3m6s
[23:00:50.781] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:67:0)
[23:00:50.800] Build status: STARTED
[23:00:50.800] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:68:0) after 5m43s
[23:06:34.008] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:68:0)
[23:06:34.030] Build status: STARTED
[23:06:34.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:69:0) after 8m23s
[23:14:57.054] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:69:0)
[23:14:57.093] Build status: STARTED
[23:14:57.093] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:70:0) after 1m47s
[23:16:44.128] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:70:0)
[23:16:44.150] Build status: STARTED
[23:16:44.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:71:0) after 6m2s
[23:22:47.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:71:0)
[23:22:47.209] Build status: STARTED
[23:22:47.209] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:72:0) after 6m26s
[23:29:13.719] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:72:0)
[23:29:13.744] Build status: STARTED
[23:29:13.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:73:0) after 6m6s
[23:35:20.138] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:73:0)
[23:35:20.164] Build status: STARTED
[23:35:20.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:74:0) after 3m5s
[23:38:25.542] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:74:0)
[23:38:25.651] Build status: STARTED
[23:38:25.651] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:75:0) after 9m59s
[23:48:24.950] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:75:0)
[23:48:24.973] Build status: STARTED
[23:48:24.973] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:76:0) after 3m57s
[23:52:21.995] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:76:0)
[23:52:22.017] Build status: STARTED
[23:52:22.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:77:0) after 7m9s
[23:59:31.041] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:77:0)
[23:59:31.062] Build status: STARTED
[23:59:31.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:78:0) after 3m7s
[00:02:38.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:78:0)
[00:02:38.268] Build status: STARTED
[00:02:38.268] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:79:0) after 3m37s
[00:06:15.290] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:79:0)
[00:06:15.321] Build status: STARTED
[00:06:15.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:80:0) after 5m44s
[00:11:59.339] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:80:0)
[00:11:59.383] Build status: STARTED
[00:11:59.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:81:0) after 4m45s
[00:16:44.507] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:81:0)
[00:16:44.531] Build status: STARTED
[00:16:44.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:82:0) after 9m55s
[00:26:39.571] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:82:0)
[00:26:39.594] Build status: STARTED
[00:26:39.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:83:0) after 5m52s
[00:32:31.618] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:83:0)
[00:32:31.654] Build status: STARTED
[00:32:31.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:84:0) after 9m30s
[00:42:01.681] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:84:0)
[00:42:01.703] Build status: STARTED
[00:42:01.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:85:0) after 1m39s
[00:43:40.790] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:85:0)
[00:43:40.824] Build status: STARTED
[00:43:40.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:86:0) after 7m4s
[00:50:45.400] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:86:0)
[00:50:45.433] Build status: STARTED
[00:50:45.433] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:87:0) after 1m23s
[00:52:08.920] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:87:0)
[00:52:08.953] Build status: STARTED
[00:52:08.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:88:0) after 1m43s
[00:53:51.975] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:88:0)
[00:53:51.997] Build status: STARTED
[00:53:51.997] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:89:0) after 9m3s
[01:02:55.017] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:89:0)
[01:02:55.048] Build status: STARTED
[01:02:55.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:90:0) after 4m50s
[01:07:45.692] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:90:0)
[01:07:45.720] Build status: STARTED
[01:07:45.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:91:0) after 5m32s
[01:13:17.846] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:91:0)
[01:13:17.875] Build status: STARTED
[01:13:17.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:92:0) after 1m10s
[01:14:27.938] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:92:0)
[01:14:27.969] Build status: STARTED
[01:14:27.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:93:0) after 4m6s
[01:18:34.704] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:93:0)
[01:18:34.736] Build status: STARTED
[01:18:34.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:94:0) after 6m56s
[01:25:30.759] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:94:0)
[01:25:30.785] Build status: STARTED
[01:25:30.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:95:0) after 3m55s
[01:29:25.808] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:95:0)
[01:29:25.843] Build status: STARTED
[01:29:25.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:96:0) after 4m11s
[01:33:36.867] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:96:0)
[01:33:36.908] Build status: STARTED
[01:33:36.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:97:0) after 6m11s
[01:39:47.914] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:97:0)
[01:39:47.953] Build status: STARTED
[01:39:47.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:98:0) after 3m23s
[01:43:10.980] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:98:0)
[01:43:11.075] Build status: STARTED
[01:43:11.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:99:0) after 5m56s
[01:49:07.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:99:0)
[01:49:07.189] Build status: STARTED
[01:49:07.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:100:0) after 7m40s
[01:56:47.521] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:100:0)
[01:56:47.543] Build status: STARTED
[01:56:47.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:101:0) after 4m35s
[02:01:22.568] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:101:0)
[02:01:22.600] Build status: STARTED
[02:01:22.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:102:0) after 1m45s
[02:03:07.627] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:102:0)
[02:03:07.650] Build status: STARTED
[02:03:07.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:103:0) after 7m35s
[02:10:42.672] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:103:0)
[02:10:42.766] Build status: STARTED
[02:10:42.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:104:0) after 5m56s
[02:16:38.864] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:104:0)
[02:16:38.942] Build status: STARTED
[02:16:38.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:105:0) after 2m7s
[02:18:46.027] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:105:0)
[02:18:46.071] Build status: STARTED
[02:18:46.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:106:0) after 3m19s
[02:22:05.094] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:106:0)
[02:22:05.128] Build status: STARTED
[02:22:05.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8980869745660592704:107:0) after 8m7s
[02:25:37.614] Received PubSub notification, asking Buildbucket for the build status
[02:25:37.644] Build:
{
"id": "8815439682965546305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 2512,
"createdBy": "project:chromium",
"createTime": "2022-04-30T17:10:08.735221208Z",
"startTime": "2022-04-30T17:10:34.573722Z",
"endTime": "2022-05-01T02:25:35.916208239Z",
"updateTime": "2022-05-01T02:25:35.916208239Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3f719238f4e251d0cbf2f5bb1b16566bc81da5c7",
"ref": "refs/heads/main"
}
}
}
[02:25:37.644] Invocation finished in 9h15m30.863301588s with status FAILED