[23:46:17.465] New invocation is queued and will start shortly
[23:46:18.736] Starting the invocation (attempt 1)
[23:46:18.771] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d9d85466a19ed0955ea2f675ec88e6265e5e763"
[23:46:18.771] Popped gitiles commit info from properties and tags
[23:46:18.771] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:46:18.771] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:46:18.771] Buildbucket request:
{
"requestId": "8966892887477611232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d9d85466a19ed0955ea2f675ec88e6265e5e763",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966892887477611232"
},
{
"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"
}
}
[23:46:24.529] Scheduled build:
{
"id": "8801462825362905601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 3830,
"createdBy": "project:chromium",
"createTime": "2022-10-01T23:46:18.855644594Z",
"updateTime": "2022-10-01T23:46:18.855644594Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d9d85466a19ed0955ea2f675ec88e6265e5e763",
"ref": "refs/heads/main"
}
}
}
[23:46:24.529] Task URL: https://cr-buildbucket.appspot.com/build/8801462825362905601
[23:46:24.529] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:2:0) after 3m11s
[23:47:13.565] Received PubSub notification, asking Buildbucket for the build status
[23:47:13.607] Build status: STARTED
[23:49:35.556] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:2:0)
[23:49:35.584] Build status: STARTED
[23:49:35.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:4:0) after 9m4s
[23:58:39.657] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:4:0)
[23:58:39.693] Build status: STARTED
[23:58:39.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:5:0) after 6m32s
[00:05:11.734] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:5:0)
[00:05:11.759] Build status: STARTED
[00:05:11.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:6:0) after 3m48s
[00:08:59.783] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:6:0)
[00:08:59.811] Build status: STARTED
[00:08:59.811] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:7:0) after 1m57s
[00:10:57.363] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:7:0)
[00:10:57.394] Build status: STARTED
[00:10:57.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:8:0) after 8m51s
[00:19:48.422] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:8:0)
[00:19:48.448] Build status: STARTED
[00:19:48.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:9:0) after 5m51s
[00:25:39.821] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:9:0)
[00:25:39.844] Build status: STARTED
[00:25:39.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:10:0) after 4m22s
[00:30:01.994] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:10:0)
[00:30:02.024] Build status: STARTED
[00:30:02.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:11:0) after 3m14s
[00:33:16.335] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:11:0)
[00:33:16.375] Build status: STARTED
[00:33:16.375] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:12:0) after 9m49s
[00:43:05.445] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:12:0)
[00:43:05.469] Build status: STARTED
[00:43:05.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:13:0) after 3m5s
[00:46:10.576] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:13:0)
[00:46:10.707] Build status: STARTED
[00:46:10.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:14:0) after 7m58s
[00:54:08.761] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:14:0)
[00:54:08.785] Build status: STARTED
[00:54:08.785] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:15:0) after 9m3s
[01:03:12.417] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:15:0)
[01:03:12.443] Build status: STARTED
[01:03:12.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:16:0) after 1m10s
[01:04:22.896] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:16:0)
[01:04:22.925] Build status: STARTED
[01:04:22.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:17:0) after 8m57s
[01:13:19.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:17:0)
[01:13:19.992] Build status: STARTED
[01:13:19.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:18:0) after 7m25s
[01:20:45.103] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:18:0)
[01:20:45.127] Build status: STARTED
[01:20:45.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:19:0) after 6m58s
[01:27:43.166] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:19:0)
[01:27:43.190] Build status: STARTED
[01:27:43.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:20:0) after 1m30s
[01:29:13.184] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:20:0)
[01:29:13.222] Build status: STARTED
[01:29:13.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:21:0) after 1m42s
[01:30:55.297] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:21:0)
[01:30:55.322] Build status: STARTED
[01:30:55.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:22:0) after 4m0s
[01:34:55.548] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:22:0)
[01:34:55.584] Build status: STARTED
[01:34:55.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:23:0) after 5m12s
[01:40:07.629] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:23:0)
[01:40:07.661] Build status: STARTED
[01:40:07.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:24:0) after 2m18s
[01:42:25.688] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:24:0)
[01:42:25.733] Build status: STARTED
[01:42:25.733] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:25:0) after 5m59s
[01:48:24.761] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:25:0)
[01:48:24.789] Build status: STARTED
[01:48:24.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:26:0) after 2m30s
[01:50:54.839] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:26:0)
[01:50:54.866] Build status: STARTED
[01:50:54.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:27:0) after 1m34s
[01:52:28.897] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:27:0)
[01:52:28.929] Build status: STARTED
[01:52:28.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:28:0) after 8m39s
[02:01:08.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:28:0)
[02:01:08.120] Build status: STARTED
[02:01:08.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:29:0) after 4m28s
[02:05:36.194] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:29:0)
[02:05:36.221] Build status: STARTED
[02:05:36.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:30:0) after 6m5s
[02:11:41.245] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:30:0)
[02:11:41.266] Build status: STARTED
[02:11:41.266] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:31:0) after 8m24s
[02:20:05.400] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:31:0)
[02:20:05.437] Build status: STARTED
[02:20:05.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:32:0) after 1m33s
[02:21:38.470] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:32:0)
[02:21:38.502] Build status: STARTED
[02:21:38.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:33:0) after 5m52s
[02:27:30.543] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:33:0)
[02:27:30.566] Build status: STARTED
[02:27:30.566] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:34:0) after 8m13s
[02:35:43.593] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:34:0)
[02:35:43.657] Build status: STARTED
[02:35:43.657] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:35:0) after 6m55s
[02:42:38.685] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:35:0)
[02:42:38.713] Build status: STARTED
[02:42:38.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:36:0) after 1m19s
[02:43:57.744] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:36:0)
[02:43:57.772] Build status: STARTED
[02:43:57.772] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:37:0) after 2m1s
[02:45:58.799] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:37:0)
[02:45:58.824] Build status: STARTED
[02:45:58.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:38:0) after 3m32s
[02:49:31.011] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:38:0)
[02:49:31.033] Build status: STARTED
[02:49:31.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:39:0) after 7m5s
[02:56:36.064] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:39:0)
[02:56:36.086] Build status: STARTED
[02:56:36.086] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:40:0) after 9m26s
[03:06:02.371] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:40:0)
[03:06:02.397] Build status: STARTED
[03:06:02.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:41:0) after 9m12s
[03:15:14.427] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:41:0)
[03:15:14.454] Build status: STARTED
[03:15:14.454] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:42:0) after 9m14s
[03:24:28.484] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:42:0)
[03:24:28.513] Build status: STARTED
[03:24:28.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:43:0) after 4m31s
[03:28:59.540] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:43:0)
[03:28:59.573] Build status: STARTED
[03:28:59.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:44:0) after 6m36s
[03:35:35.657] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:44:0)
[03:35:35.680] Build status: STARTED
[03:35:35.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:45:0) after 3m43s
[03:39:19.187] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:45:0)
[03:39:19.210] Build status: STARTED
[03:39:19.210] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:46:0) after 7m42s
[03:47:01.658] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:46:0)
[03:47:01.692] Build status: STARTED
[03:47:01.692] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:47:0) after 5m49s
[03:52:50.721] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:47:0)
[03:52:50.743] Build status: STARTED
[03:52:50.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:48:0) after 7m14s
[04:00:04.935] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:48:0)
[04:00:04.966] Build status: STARTED
[04:00:04.966] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:49:0) after 5m16s
[04:05:20.995] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:49:0)
[04:05:21.027] Build status: STARTED
[04:05:21.027] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:50:0) after 3m33s
[04:08:54.054] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:50:0)
[04:08:54.076] Build status: STARTED
[04:08:54.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:51:0) after 1m20s
[04:10:14.143] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:51:0)
[04:10:14.190] Build status: STARTED
[04:10:14.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:52:0) after 3m52s
[04:14:06.326] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:52:0)
[04:14:06.357] Build status: STARTED
[04:14:06.357] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:53:0) after 6m46s
[04:20:52.384] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:53:0)
[04:20:52.409] Build status: STARTED
[04:20:52.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:54:0) after 1m51s
[04:22:43.460] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:54:0)
[04:22:43.487] Build status: STARTED
[04:22:43.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:55:0) after 9m5s
[04:31:48.520] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:55:0)
[04:31:48.541] Build status: STARTED
[04:31:48.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:56:0) after 6m37s
[04:38:25.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:56:0)
[04:38:25.597] Build status: STARTED
[04:38:25.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:57:0) after 3m36s
[04:42:01.714] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:57:0)
[04:42:01.740] Build status: STARTED
[04:42:01.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:58:0) after 9m27s
[04:51:28.770] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:58:0)
[04:51:28.795] Build status: STARTED
[04:51:28.796] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:59:0) after 9m29s
[05:00:57.905] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:59:0)
[05:00:57.926] Build status: STARTED
[05:00:57.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:60:0) after 3m0s
[05:03:58.100] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:60:0)
[05:03:58.130] Build status: STARTED
[05:03:58.130] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:61:0) after 8m53s
[05:12:51.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:61:0)
[05:12:51.229] Build status: STARTED
[05:12:51.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:62:0) after 3m4s
[05:15:55.584] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:62:0)
[05:15:55.612] Build status: STARTED
[05:15:55.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:63:0) after 4m55s
[05:20:51.374] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:63:0)
[05:20:51.466] Build status: STARTED
[05:20:51.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:64:0) after 4m58s
[05:25:50.368] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:64:0)
[05:25:50.391] Build status: STARTED
[05:25:50.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:65:0) after 1m28s
[05:27:18.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:65:0)
[05:27:18.543] Build status: STARTED
[05:27:18.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:66:0) after 7m0s
[05:34:18.749] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:66:0)
[05:34:18.821] Build status: STARTED
[05:34:18.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:67:0) after 1m47s
[05:36:05.856] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:67:0)
[05:36:05.889] Build status: STARTED
[05:36:05.889] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:68:0) after 6m17s
[05:42:22.891] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:68:0)
[05:42:22.918] Build status: STARTED
[05:42:22.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:69:0) after 1m50s
[05:44:13.092] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:69:0)
[05:44:13.115] Build status: STARTED
[05:44:13.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:70:0) after 4m13s
[05:48:26.449] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:70:0)
[05:48:26.492] Build status: STARTED
[05:48:26.492] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:71:0) after 2m0s
[05:50:26.608] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:71:0)
[05:50:26.636] Build status: STARTED
[05:50:26.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:72:0) after 6m0s
[05:56:27.081] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:72:0)
[05:56:27.107] Build status: STARTED
[05:56:27.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:73:0) after 3m28s
[05:59:55.817] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:73:0)
[05:59:55.846] Build status: STARTED
[05:59:55.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:74:0) after 3m42s
[06:03:38.742] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:74:0)
[06:03:38.766] Build status: STARTED
[06:03:38.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:75:0) after 2m17s
[06:05:55.964] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:75:0)
[06:05:55.990] Build status: STARTED
[06:05:55.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:76:0) after 3m49s
[06:09:45.376] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:76:0)
[06:09:45.408] Build status: STARTED
[06:09:45.408] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:77:0) after 5m58s
[06:15:45.077] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:77:0)
[06:15:45.116] Build status: STARTED
[06:15:45.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:78:0) after 8m34s
[06:24:20.651] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:78:0)
[06:24:20.673] Build status: STARTED
[06:24:20.673] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:79:0) after 3m44s
[06:28:04.695] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:79:0)
[06:28:04.719] Build status: STARTED
[06:28:04.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:80:0) after 6m45s
[06:34:50.345] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:80:0)
[06:34:50.383] Build status: STARTED
[06:34:50.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:81:0) after 5m16s
[06:40:06.946] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:81:0)
[06:40:06.967] Build status: STARTED
[06:40:06.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:82:0) after 1m30s
[06:41:37.001] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:82:0)
[06:41:37.026] Build status: STARTED
[06:41:37.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:83:0) after 7m14s
[06:48:51.495] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:83:0)
[06:48:51.519] Build status: STARTED
[06:48:51.519] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:84:0) after 2m57s
[06:51:48.543] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:84:0)
[06:51:48.565] Build status: STARTED
[06:51:48.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:85:0) after 8m49s
[07:00:37.591] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:85:0)
[07:00:37.614] Build status: STARTED
[07:00:37.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:86:0) after 3m2s
[07:03:39.648] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:86:0)
[07:03:39.670] Build status: STARTED
[07:03:39.670] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:87:0) after 4m14s
[07:07:53.796] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:87:0)
[07:07:53.818] Build status: STARTED
[07:07:53.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:88:0) after 6m46s
[07:14:39.861] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:88:0)
[07:14:39.937] Build status: STARTED
[07:14:39.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:89:0) after 7m17s
[07:21:56.962] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:89:0)
[07:21:56.986] Build status: STARTED
[07:21:56.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:90:0) after 3m56s
[07:25:53.559] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:90:0)
[07:25:53.581] Build status: STARTED
[07:25:53.581] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:91:0) after 6m35s
[07:32:29.076] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:91:0)
[07:32:29.101] Build status: STARTED
[07:32:29.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:92:0) after 8m43s
[07:41:12.128] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:92:0)
[07:41:12.197] Build status: STARTED
[07:41:12.197] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:93:0) after 8m14s
[07:49:26.254] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:93:0)
[07:49:26.282] Build status: STARTED
[07:49:26.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:94:0) after 4m51s
[07:54:17.315] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:94:0)
[07:54:17.345] Build status: STARTED
[07:54:17.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:95:0) after 4m49s
[07:59:06.378] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:95:0)
[07:59:06.418] Build status: STARTED
[07:59:06.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:96:0) after 8m6s
[08:07:12.765] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:96:0)
[08:07:12.787] Build status: STARTED
[08:07:12.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:97:0) after 2m51s
[08:10:03.992] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:97:0)
[08:10:04.024] Build status: STARTED
[08:10:04.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:98:0) after 4m16s
[08:14:20.528] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:98:0)
[08:14:20.692] Build status: STARTED
[08:14:20.692] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:99:0) after 7m38s
[08:21:58.738] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:99:0)
[08:21:58.761] Build status: STARTED
[08:21:58.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:100:0) after 5m15s
[08:27:14.669] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:100:0)
[08:27:14.693] Build status: STARTED
[08:27:14.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:101:0) after 9m54s
[08:37:08.759] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:101:0)
[08:37:08.823] Build status: STARTED
[08:37:08.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8966892887477611232:102:0) after 8m17s
[08:40:54.144] Received PubSub notification, asking Buildbucket for the build status
[08:40:54.204] Build:
{
"id": "8801462825362905601",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 3830,
"createdBy": "project:chromium",
"createTime": "2022-10-01T23:46:18.855644594Z",
"startTime": "2022-10-01T23:47:13.017532Z",
"endTime": "2022-10-02T08:40:53.867258811Z",
"updateTime": "2022-10-02T08:40:53.867258811Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d9d85466a19ed0955ea2f675ec88e6265e5e763",
"ref": "refs/heads/main"
}
}
}
[08:40:54.204] Invocation finished in 8h54m36.750200199s with status FAILED