[08:05:01.294] New invocation is queued and will start shortly
[08:05:02.378] Starting the invocation (attempt 1)
[08:05:02.414] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/61e6cf52c2e566e8bab53049e48a089a9777af75"
[08:05:02.414] Popped gitiles commit info from properties and tags
[08:05:02.414] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:05:02.414] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:05:02.415] Buildbucket request:
{
"requestId": "8953090771172821408",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "61e6cf52c2e566e8bab53049e48a089a9777af75",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8953090771172821408"
},
{
"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"
}
}
[08:05:03.242] Scheduled build:
{
"id": "8787660709135534193",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4878,
"createdBy": "project:chromium",
"createTime": "2023-03-03T08:05:02.613000796Z",
"updateTime": "2023-03-03T08:05:02.613000796Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "61e6cf52c2e566e8bab53049e48a089a9777af75",
"ref": "refs/heads/main"
}
}
}
[08:05:03.242] Task URL: https://cr-buildbucket.appspot.com/build/8787660709135534193
[08:05:03.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:2:0) after 1m23s
[08:05:06.275] Received PubSub notification, asking Buildbucket for the build status
[08:05:06.342] Build status: STARTED
[08:06:26.458] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:2:0)
[08:06:26.521] Build status: STARTED
[08:06:26.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:4:0) after 8m51s
[08:15:17.547] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:4:0)
[08:15:17.587] Build status: STARTED
[08:15:17.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:5:0) after 8m50s
[08:24:07.660] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:5:0)
[08:24:07.704] Build status: STARTED
[08:24:07.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:6:0) after 8m44s
[08:32:51.733] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:6:0)
[08:32:51.758] Build status: STARTED
[08:32:51.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:7:0) after 5m45s
[08:38:37.069] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:7:0)
[08:38:37.102] Build status: STARTED
[08:38:37.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:8:0) after 9m59s
[08:48:36.279] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:8:0)
[08:48:36.318] Build status: STARTED
[08:48:36.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:9:0) after 2m17s
[08:50:53.423] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:9:0)
[08:50:53.457] Build status: STARTED
[08:50:53.457] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:10:0) after 3m19s
[08:54:12.486] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:10:0)
[08:54:12.506] Build status: STARTED
[08:54:12.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:11:0) after 4m18s
[08:58:30.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:11:0)
[08:58:30.723] Build status: STARTED
[08:58:30.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:12:0) after 9m35s
[09:08:05.752] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:12:0)
[09:08:05.776] Build status: STARTED
[09:08:05.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:13:0) after 6m11s
[09:14:16.893] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:13:0)
[09:14:16.914] Build status: STARTED
[09:14:16.914] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:14:0) after 4m5s
[09:18:21.956] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:14:0)
[09:18:21.977] Build status: STARTED
[09:18:21.978] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:15:0) after 6m34s
[09:24:56.017] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:15:0)
[09:24:56.036] Build status: STARTED
[09:24:56.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:16:0) after 7m28s
[09:32:24.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:16:0)
[09:32:24.144] Build status: STARTED
[09:32:24.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:17:0) after 1m35s
[09:33:59.238] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:17:0)
[09:33:59.256] Build status: STARTED
[09:33:59.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:18:0) after 7m46s
[09:41:45.308] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:18:0)
[09:41:45.339] Build status: STARTED
[09:41:45.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:19:0) after 7m27s
[09:49:12.442] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:19:0)
[09:49:12.480] Build status: STARTED
[09:49:12.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:20:0) after 7m18s
[09:56:30.503] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:20:0)
[09:56:30.563] Build status: STARTED
[09:56:30.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:21:0) after 2m16s
[09:58:46.604] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:21:0)
[09:58:46.625] Build status: STARTED
[09:58:46.625] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:22:0) after 2m58s
[10:01:44.692] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:22:0)
[10:01:44.750] Build status: STARTED
[10:01:44.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:23:0) after 7m41s
[10:09:25.905] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:23:0)
[10:09:25.933] Build status: STARTED
[10:09:25.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:24:0) after 4m42s
[10:14:07.967] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:24:0)
[10:14:07.986] Build status: STARTED
[10:14:07.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:25:0) after 4m16s
[10:18:24.012] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:25:0)
[10:18:24.036] Build status: STARTED
[10:18:24.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:26:0) after 1m36s
[10:20:00.089] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:26:0)
[10:20:00.108] Build status: STARTED
[10:20:00.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:27:0) after 3m36s
[10:23:36.178] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:27:0)
[10:23:36.222] Build status: STARTED
[10:23:36.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:28:0) after 1m40s
[10:25:16.391] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:28:0)
[10:25:16.428] Build status: STARTED
[10:25:16.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:29:0) after 1m3s
[10:26:19.464] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:29:0)
[10:26:19.487] Build status: STARTED
[10:26:19.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:30:0) after 8m31s
[10:34:50.576] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:30:0)
[10:34:50.595] Build status: STARTED
[10:34:50.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:31:0) after 4m47s
[10:39:37.625] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:31:0)
[10:39:37.647] Build status: STARTED
[10:39:37.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:32:0) after 2m36s
[10:42:13.669] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:32:0)
[10:42:13.688] Build status: STARTED
[10:42:13.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:33:0) after 7m48s
[10:50:01.793] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:33:0)
[10:50:01.824] Build status: STARTED
[10:50:01.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:34:0) after 2m47s
[10:52:48.943] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:34:0)
[10:52:48.967] Build status: STARTED
[10:52:48.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:35:0) after 2m1s
[10:54:49.991] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:35:0)
[10:54:50.014] Build status: STARTED
[10:54:50.014] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:36:0) after 6m40s
[11:01:30.352] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:36:0)
[11:01:30.377] Build status: STARTED
[11:01:30.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:37:0) after 7m15s
[11:08:45.407] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:37:0)
[11:08:45.429] Build status: STARTED
[11:08:45.429] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:38:0) after 5m36s
[11:14:21.574] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:38:0)
[11:14:21.596] Build status: STARTED
[11:14:21.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:39:0) after 2m51s
[11:17:12.634] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:39:0)
[11:17:12.658] Build status: STARTED
[11:17:12.658] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:40:0) after 7m53s
[11:25:05.798] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:40:0)
[11:25:05.827] Build status: STARTED
[11:25:05.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:41:0) after 3m57s
[11:29:03.217] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:41:0)
[11:29:03.279] Build status: STARTED
[11:29:03.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:42:0) after 9m34s
[11:38:37.374] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:42:0)
[11:38:37.414] Build status: STARTED
[11:38:37.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:43:0) after 7m4s
[11:45:41.461] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:43:0)
[11:45:41.518] Build status: STARTED
[11:45:41.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:44:0) after 2m50s
[11:48:31.642] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:44:0)
[11:48:31.714] Build status: STARTED
[11:48:31.714] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:45:0) after 4m37s
[11:53:09.097] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:45:0)
[11:53:09.123] Build status: STARTED
[11:53:09.123] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:46:0) after 4m39s
[11:57:48.562] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:46:0)
[11:57:48.583] Build status: STARTED
[11:57:48.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:47:0) after 7m11s
[12:04:59.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:47:0)
[12:04:59.783] Build status: STARTED
[12:04:59.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:48:0) after 6m47s
[12:11:46.816] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:48:0)
[12:11:46.842] Build status: STARTED
[12:11:46.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:49:0) after 9m54s
[12:21:41.077] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:49:0)
[12:21:41.098] Build status: STARTED
[12:21:41.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:50:0) after 8m28s
[12:30:09.441] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:50:0)
[12:30:09.515] Build status: STARTED
[12:30:09.515] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:51:0) after 3m40s
[12:33:50.064] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:51:0)
[12:33:50.085] Build status: STARTED
[12:33:50.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:52:0) after 2m53s
[12:36:43.153] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:52:0)
[12:36:43.173] Build status: STARTED
[12:36:43.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:53:0) after 5m48s
[12:42:31.351] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:53:0)
[12:42:31.385] Build status: STARTED
[12:42:31.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:54:0) after 4m24s
[12:46:55.441] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:54:0)
[12:46:55.461] Build status: STARTED
[12:46:55.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:55:0) after 9m25s
[12:56:20.466] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:55:0)
[12:56:20.488] Build status: STARTED
[12:56:20.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:56:0) after 9m47s
[13:06:07.517] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:56:0)
[13:06:07.541] Build status: STARTED
[13:06:07.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:57:0) after 2m2s
[13:08:09.583] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:57:0)
[13:08:09.624] Build status: STARTED
[13:08:09.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:58:0) after 6m54s
[13:15:03.888] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:58:0)
[13:15:04.088] Build status: STARTED
[13:15:04.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:59:0) after 1m23s
[13:16:27.527] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:59:0)
[13:16:27.557] Build status: STARTED
[13:16:27.557] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:60:0) after 8m17s
[13:24:44.590] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:60:0)
[13:24:44.613] Build status: STARTED
[13:24:44.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:61:0) after 7m38s
[13:32:22.636] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:61:0)
[13:32:22.661] Build status: STARTED
[13:32:22.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:62:0) after 8m38s
[13:41:00.702] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:62:0)
[13:41:00.724] Build status: STARTED
[13:41:00.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:63:0) after 7m28s
[13:48:28.994] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:63:0)
[13:48:29.023] Build status: STARTED
[13:48:29.023] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:64:0) after 1m48s
[13:50:17.070] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:64:0)
[13:50:17.095] Build status: STARTED
[13:50:17.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:65:0) after 5m47s
[13:56:04.252] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:65:0)
[13:56:04.299] Build status: STARTED
[13:56:04.299] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:66:0) after 4m35s
[14:00:39.355] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:66:0)
[14:00:39.381] Build status: STARTED
[14:00:39.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:67:0) after 9m32s
[14:10:11.560] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:67:0)
[14:10:11.578] Build status: STARTED
[14:10:11.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:68:0) after 6m29s
[14:16:40.689] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:68:0)
[14:16:40.743] Build status: STARTED
[14:16:40.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:69:0) after 7m38s
[14:24:18.783] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:69:0)
[14:24:18.825] Build status: STARTED
[14:24:18.825] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:70:0) after 5m20s
[14:29:38.842] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:70:0)
[14:29:38.865] Build status: STARTED
[14:29:38.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:71:0) after 4m24s
[14:34:02.955] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:71:0)
[14:34:03.029] Build status: STARTED
[14:34:03.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:72:0) after 5m5s
[14:39:08.165] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:72:0)
[14:39:08.194] Build status: STARTED
[14:39:08.194] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:73:0) after 7m28s
[14:46:36.319] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:73:0)
[14:46:36.344] Build status: STARTED
[14:46:36.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:74:0) after 2m19s
[14:48:55.434] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:74:0)
[14:48:55.467] Build status: STARTED
[14:48:55.467] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:75:0) after 1m33s
[14:50:28.500] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:75:0)
[14:50:28.523] Build status: STARTED
[14:50:28.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:76:0) after 7m3s
[14:57:31.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:76:0)
[14:57:31.601] Build status: STARTED
[14:57:31.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:77:0) after 5m26s
[15:02:57.636] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:77:0)
[15:02:57.665] Build status: STARTED
[15:02:57.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:78:0) after 4m20s
[15:07:17.699] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:78:0)
[15:07:17.727] Build status: STARTED
[15:07:17.727] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:79:0) after 3m0s
[15:10:17.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:79:0)
[15:10:17.883] Build status: STARTED
[15:10:17.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:80:0) after 2m20s
[15:12:37.997] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:80:0)
[15:12:38.058] Build status: STARTED
[15:12:38.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:81:0) after 9m7s
[15:21:45.133] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:81:0)
[15:21:45.154] Build status: STARTED
[15:21:45.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:82:0) after 7m37s
[15:29:22.273] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:82:0)
[15:29:22.304] Build status: STARTED
[15:29:22.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:83:0) after 6m49s
[15:36:11.294] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:83:0)
[15:36:11.320] Build status: STARTED
[15:36:11.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:84:0) after 2m20s
[15:38:31.374] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:84:0)
[15:38:31.422] Build status: STARTED
[15:38:31.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:85:0) after 6m55s
[15:45:26.472] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:85:0)
[15:45:28.106] Build status: STARTED
[15:45:28.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:86:0) after 4m31s
[15:49:59.935] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:86:0)
[15:49:59.990] Build status: STARTED
[15:49:59.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:87:0) after 7m2s
[15:57:02.208] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:87:0)
[15:57:02.235] Build status: STARTED
[15:57:02.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:88:0) after 1m47s
[15:58:49.617] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:88:0)
[15:58:49.688] Build status: STARTED
[15:58:49.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:89:0) after 5m9s
[16:03:58.713] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:89:0)
[16:03:58.736] Build status: STARTED
[16:03:58.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:90:0) after 6m18s
[16:10:16.783] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:90:0)
[16:10:16.805] Build status: STARTED
[16:10:16.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:91:0) after 3m8s
[16:13:24.864] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:91:0)
[16:13:24.888] Build status: STARTED
[16:13:24.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:92:0) after 7m22s
[16:20:46.913] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:92:0)
[16:20:46.943] Build status: STARTED
[16:20:46.943] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:93:0) after 4m54s
[16:25:41.213] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:93:0)
[16:25:41.234] Build status: STARTED
[16:25:41.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:94:0) after 8m5s
[16:33:46.279] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:94:0)
[16:33:46.303] Build status: STARTED
[16:33:46.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:95:0) after 9m43s
[16:43:29.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:95:0)
[16:43:29.388] Build status: STARTED
[16:43:29.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:96:0) after 1m46s
[16:45:16.037] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:96:0)
[16:45:16.061] Build status: STARTED
[16:45:16.061] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:97:0) after 6m2s
[16:51:18.096] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:97:0)
[16:51:18.125] Build status: STARTED
[16:51:18.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:98:0) after 3m24s
[16:54:42.265] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:98:0)
[16:54:42.287] Build status: STARTED
[16:54:42.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:99:0) after 2m46s
[16:57:28.332] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:99:0)
[16:57:28.356] Build status: STARTED
[16:57:28.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:100:0) after 7m47s
[17:05:15.385] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:100:0)
[17:05:15.410] Build status: STARTED
[17:05:15.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:101:0) after 8m26s
[17:13:41.475] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:101:0)
[17:13:41.498] Build status: STARTED
[17:13:41.498] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:102:0) after 9m40s
[17:23:21.535] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:102:0)
[17:23:21.556] Build status: STARTED
[17:23:21.556] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:103:0) after 8m4s
[17:31:25.712] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:103:0)
[17:31:25.741] Build status: STARTED
[17:31:25.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:104:0) after 9m10s
[17:40:36.023] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:104:0)
[17:40:36.045] Build status: STARTED
[17:40:36.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:105:0) after 6m56s
[17:47:32.181] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:105:0)
[17:47:32.204] Build status: STARTED
[17:47:32.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:106:0) after 2m32s
[17:50:04.524] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:106:0)
[17:50:04.555] Build status: STARTED
[17:50:04.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:107:0) after 3m34s
[17:53:38.848] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:107:0)
[17:53:38.868] Build status: STARTED
[17:53:38.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:108:0) after 8m23s
[18:02:02.038] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:108:0)
[18:02:02.066] Build status: STARTED
[18:02:02.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:109:0) after 9m48s
[18:11:50.092] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:109:0)
[18:11:50.162] Build status: STARTED
[18:11:50.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:110:0) after 2m1s
[18:13:51.200] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:110:0)
[18:13:51.231] Build status: STARTED
[18:13:51.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:111:0) after 1m8s
[18:14:59.313] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:111:0)
[18:14:59.341] Build status: STARTED
[18:14:59.341] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:112:0) after 2m29s
[18:17:28.393] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:112:0)
[18:17:28.422] Build status: STARTED
[18:17:28.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:113:0) after 3m27s
[18:20:55.974] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:113:0)
[18:20:55.996] Build status: STARTED
[18:20:55.996] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:114:0) after 2m36s
[18:23:32.039] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:114:0)
[18:23:32.064] Build status: STARTED
[18:23:32.064] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:115:0) after 9m43s
[18:33:15.364] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:115:0)
[18:33:15.431] Build status: STARTED
[18:33:15.431] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:116:0) after 9m37s
[18:42:52.473] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:116:0)
[18:42:52.491] Build status: STARTED
[18:42:52.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:117:0) after 8m17s
[18:51:09.491] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:117:0)
[18:51:09.513] Build status: STARTED
[18:51:09.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:118:0) after 1m1s
[18:52:10.590] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:118:0)
[18:52:10.613] Build status: STARTED
[18:52:10.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:119:0) after 9m48s
[19:01:58.996] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:119:0)
[19:01:59.038] Build status: STARTED
[19:01:59.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:120:0) after 1m28s
[19:03:27.079] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:120:0)
[19:03:27.201] Build status: STARTED
[19:03:27.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:121:0) after 1m30s
[19:04:57.365] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:121:0)
[19:04:57.439] Build status: STARTED
[19:04:57.439] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:122:0) after 1m24s
[19:06:21.507] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:122:0)
[19:06:21.528] Build status: STARTED
[19:06:21.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:123:0) after 8m26s
[19:14:47.778] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:123:0)
[19:14:47.809] Build status: STARTED
[19:14:47.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:124:0) after 8m45s
[19:23:32.963] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:124:0)
[19:23:32.986] Build status: STARTED
[19:23:32.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:125:0) after 7m44s
[19:31:17.024] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:125:0)
[19:31:17.088] Build status: STARTED
[19:31:17.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:126:0) after 1m50s
[19:33:07.113] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:126:0)
[19:33:07.143] Build status: STARTED
[19:33:07.143] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8953090771172821408:127:0) after 8m18s
[19:36:15.794] Received PubSub notification, asking Buildbucket for the build status
[19:36:15.819] Build:
{
"id": "8787660709135534193",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4878,
"createdBy": "project:chromium",
"createTime": "2023-03-03T08:05:02.613000796Z",
"startTime": "2023-03-03T08:05:05.341574Z",
"endTime": "2023-03-03T19:36:15.459416987Z",
"updateTime": "2023-03-03T19:36:15.459416987Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "61e6cf52c2e566e8bab53049e48a089a9777af75",
"ref": "refs/heads/main"
}
}
}
[19:36:15.820] Invocation finished in 11h31m14.53380166s with status FAILED