[18:51:40.113] New invocation is queued and will start shortly
[18:51:41.181] Starting the invocation (attempt 1)
[18:51:41.218] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42157bb77e823bd5de68988bed1b7f42c9066d91"
[18:51:41.218] Popped gitiles commit info from properties and tags
[18:51:41.218] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:51:41.218] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:51:41.219] Buildbucket request:
{
"requestId": "8993999916482245248",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "42157bb77e823bd5de68988bed1b7f42c9066d91",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8993999916482245248"
},
{
"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"
}
}
[18:51:42.078] Scheduled build:
{
"id": "8828569854595846145",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1839,
"createdBy": "project:chromium",
"createTime": "2021-12-06T18:51:41.281310550Z",
"updateTime": "2021-12-06T18:51:41.281310550Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "42157bb77e823bd5de68988bed1b7f42c9066d91",
"ref": "refs/heads/main"
}
}
}
[18:51:42.078] Task URL: https://cr-buildbucket.appspot.com/build/8828569854595846145
[18:51:42.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:2:0) after 8m51s
[19:00:33.402] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:2:0)
[19:00:33.446] Build status: SCHEDULED
[19:00:33.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:3:0) after 8m35s
[19:09:08.592] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:3:0)
[19:09:08.625] Build status: SCHEDULED
[19:09:08.625] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:4:0) after 7m22s
[19:16:30.737] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:4:0)
[19:16:30.767] Build status: SCHEDULED
[19:16:30.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:5:0) after 3m34s
[19:20:04.892] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:5:0)
[19:20:04.928] Build status: SCHEDULED
[19:20:04.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:6:0) after 8m6s
[19:28:10.951] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:6:0)
[19:28:10.983] Build status: SCHEDULED
[19:28:10.984] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:7:0) after 3m34s
[19:31:45.009] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:7:0)
[19:31:45.041] Build status: SCHEDULED
[19:31:45.041] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:8:0) after 7m2s
[19:38:47.216] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:8:0)
[19:38:47.346] Build status: SCHEDULED
[19:38:47.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:9:0) after 8m17s
[19:47:04.434] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:9:0)
[19:47:04.510] Build status: SCHEDULED
[19:47:04.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:10:0) after 9m25s
[19:56:29.587] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:10:0)
[19:56:29.620] Build status: SCHEDULED
[19:56:29.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:11:0) after 9m9s
[20:05:38.746] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:11:0)
[20:05:38.779] Build status: SCHEDULED
[20:05:38.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:12:0) after 1m27s
[20:07:02.872] Received PubSub notification, asking Buildbucket for the build status
[20:07:02.944] Build status: STARTED
[20:07:05.802] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:12:0)
[20:07:05.832] Build status: STARTED
[20:07:05.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:14:0) after 3m52s
[20:10:58.194] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:14:0)
[20:10:58.227] Build status: STARTED
[20:10:58.227] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:15:0) after 5m13s
[20:16:11.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:15:0)
[20:16:11.896] Build status: STARTED
[20:16:11.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:16:0) after 6m3s
[20:22:14.920] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:16:0)
[20:22:14.952] Build status: STARTED
[20:22:14.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:17:0) after 6m15s
[20:28:29.974] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:17:0)
[20:28:30.059] Build status: STARTED
[20:28:30.059] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:18:0) after 5m28s
[20:33:58.389] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:18:0)
[20:33:58.418] Build status: STARTED
[20:33:58.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:19:0) after 7m24s
[20:41:22.448] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:19:0)
[20:41:22.492] Build status: STARTED
[20:41:22.492] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:20:0) after 7m57s
[20:49:19.515] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:20:0)
[20:49:19.552] Build status: STARTED
[20:49:19.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:21:0) after 7m11s
[20:56:30.578] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:21:0)
[20:56:30.609] Build status: STARTED
[20:56:30.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:22:0) after 8m0s
[21:04:30.632] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:22:0)
[21:04:30.682] Build status: STARTED
[21:04:30.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:23:0) after 2m50s
[21:07:20.849] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:23:0)
[21:07:20.898] Build status: STARTED
[21:07:20.898] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:24:0) after 5m18s
[21:12:38.926] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:24:0)
[21:12:38.966] Build status: STARTED
[21:12:38.966] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:25:0) after 6m53s
[21:19:32.042] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:25:0)
[21:19:32.076] Build status: STARTED
[21:19:32.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:26:0) after 5m6s
[21:24:38.351] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:26:0)
[21:24:38.470] Build status: STARTED
[21:24:38.470] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:27:0) after 1m13s
[21:25:51.594] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:27:0)
[21:25:51.626] Build status: STARTED
[21:25:51.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:28:0) after 3m36s
[21:29:27.776] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:28:0)
[21:29:27.868] Build status: STARTED
[21:29:27.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:29:0) after 2m33s
[21:32:01.653] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:29:0)
[21:32:01.700] Build status: STARTED
[21:32:01.700] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:30:0) after 9m38s
[21:41:39.734] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:30:0)
[21:41:39.768] Build status: STARTED
[21:41:39.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:31:0) after 6m42s
[21:48:21.753] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:31:0)
[21:48:21.792] Build status: STARTED
[21:48:21.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:32:0) after 5m53s
[21:54:14.901] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:32:0)
[21:54:14.928] Build status: STARTED
[21:54:14.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:33:0) after 2m48s
[21:57:02.971] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:33:0)
[21:57:03.011] Build status: STARTED
[21:57:03.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:34:0) after 5m35s
[22:02:38.042] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:34:0)
[22:02:38.085] Build status: STARTED
[22:02:38.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:35:0) after 4m26s
[22:07:04.108] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:35:0)
[22:07:04.141] Build status: STARTED
[22:07:04.141] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:36:0) after 8m28s
[22:15:32.173] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:36:0)
[22:15:32.235] Build status: STARTED
[22:15:32.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:37:0) after 7m14s
[22:22:46.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:37:0)
[22:22:46.294] Build status: STARTED
[22:22:46.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:38:0) after 7m46s
[22:30:32.336] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:38:0)
[22:30:32.367] Build status: STARTED
[22:30:32.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:39:0) after 1m30s
[22:32:02.398] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:39:0)
[22:32:02.425] Build status: STARTED
[22:32:02.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:40:0) after 8m3s
[22:40:05.481] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:40:0)
[22:40:05.612] Build status: STARTED
[22:40:05.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:41:0) after 3m8s
[22:43:13.637] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:41:0)
[22:43:13.666] Build status: STARTED
[22:43:13.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:42:0) after 4m57s
[22:48:10.773] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:42:0)
[22:48:10.805] Build status: STARTED
[22:48:10.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:43:0) after 2m20s
[22:50:30.829] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:43:0)
[22:50:30.860] Build status: STARTED
[22:50:30.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:44:0) after 4m20s
[22:54:51.035] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:44:0)
[22:54:51.071] Build status: STARTED
[22:54:51.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:45:0) after 8m1s
[23:02:52.296] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:45:0)
[23:02:52.328] Build status: STARTED
[23:02:52.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:46:0) after 1m35s
[23:04:27.472] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:46:0)
[23:04:27.509] Build status: STARTED
[23:04:27.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:47:0) after 5m19s
[23:09:46.916] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:47:0)
[23:09:47.067] Build status: STARTED
[23:09:47.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:48:0) after 5m40s
[23:15:27.173] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:48:0)
[23:15:27.231] Build status: STARTED
[23:15:27.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:49:0) after 9m22s
[23:24:49.580] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:49:0)
[23:24:49.611] Build status: STARTED
[23:24:49.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:50:0) after 4m28s
[23:29:17.923] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:50:0)
[23:29:17.949] Build status: STARTED
[23:29:17.949] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:51:0) after 6m21s
[23:35:38.975] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:51:0)
[23:35:39.007] Build status: STARTED
[23:35:39.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:52:0) after 5m6s
[23:40:45.088] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:52:0)
[23:40:45.136] Build status: STARTED
[23:40:45.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:53:0) after 8m9s
[23:48:54.171] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:53:0)
[23:48:54.211] Build status: STARTED
[23:48:54.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:54:0) after 7m50s
[23:56:44.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:54:0)
[23:56:44.689] Build status: STARTED
[23:56:44.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:55:0) after 7m1s
[00:03:45.930] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:55:0)
[00:03:45.964] Build status: STARTED
[00:03:45.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:56:0) after 3m16s
[00:07:02.135] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:56:0)
[00:07:02.164] Build status: STARTED
[00:07:02.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:57:0) after 5m30s
[00:12:32.967] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:57:0)
[00:12:33.049] Build status: STARTED
[00:12:33.049] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:58:0) after 6m28s
[00:19:01.369] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:58:0)
[00:19:01.597] Build status: STARTED
[00:19:01.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:59:0) after 3m52s
[00:22:53.717] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:59:0)
[00:22:53.748] Build status: STARTED
[00:22:53.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:60:0) after 9m7s
[00:32:00.796] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:60:0)
[00:32:00.844] Build status: STARTED
[00:32:00.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:61:0) after 2m58s
[00:34:58.907] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:61:0)
[00:34:58.948] Build status: STARTED
[00:34:58.948] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:62:0) after 8m30s
[00:43:29.162] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:62:0)
[00:43:29.196] Build status: STARTED
[00:43:29.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:63:0) after 7m31s
[00:51:00.302] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:63:0)
[00:51:00.358] Build status: STARTED
[00:51:00.358] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:64:0) after 2m42s
[00:53:42.389] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:64:0)
[00:53:42.444] Build status: STARTED
[00:53:42.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:65:0) after 4m47s
[00:58:29.507] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:65:0)
[00:58:29.545] Build status: STARTED
[00:58:29.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:66:0) after 1m9s
[00:59:38.631] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:66:0)
[00:59:38.692] Build status: STARTED
[00:59:38.692] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:67:0) after 4m39s
[01:04:17.827] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:67:0)
[01:04:17.878] Build status: STARTED
[01:04:17.878] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:68:0) after 9m15s
[01:13:32.936] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:68:0)
[01:13:32.964] Build status: STARTED
[01:13:32.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:69:0) after 7m49s
[01:21:21.990] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:69:0)
[01:21:22.045] Build status: STARTED
[01:21:22.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:70:0) after 6m2s
[01:27:24.074] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:70:0)
[01:27:24.266] Build status: STARTED
[01:27:24.266] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:71:0) after 5m37s
[01:33:01.295] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:71:0)
[01:33:01.339] Build status: STARTED
[01:33:01.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:72:0) after 3m20s
[01:36:21.518] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:72:0)
[01:36:21.551] Build status: STARTED
[01:36:21.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:73:0) after 3m40s
[01:40:01.582] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:73:0)
[01:40:01.616] Build status: STARTED
[01:40:01.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:74:0) after 2m54s
[01:42:55.745] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:74:0)
[01:42:55.779] Build status: STARTED
[01:42:55.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:75:0) after 4m24s
[01:47:20.098] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:75:0)
[01:47:20.130] Build status: STARTED
[01:47:20.130] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:76:0) after 3m31s
[01:50:51.162] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:76:0)
[01:50:51.193] Build status: STARTED
[01:50:51.193] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:77:0) after 7m31s
[01:58:22.235] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:77:0)
[01:58:22.272] Build status: STARTED
[01:58:22.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:78:0) after 8m10s
[02:06:32.359] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:78:0)
[02:06:32.391] Build status: STARTED
[02:06:32.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:79:0) after 2m7s
[02:08:39.424] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:79:0)
[02:08:39.466] Build status: STARTED
[02:08:39.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:80:0) after 8m53s
[02:17:32.557] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:80:0)
[02:17:32.589] Build status: STARTED
[02:17:32.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:81:0) after 6m56s
[02:24:28.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:81:0)
[02:24:28.902] Build status: STARTED
[02:24:28.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:82:0) after 2m43s
[02:27:12.101] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:82:0)
[02:27:12.132] Build status: STARTED
[02:27:12.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:83:0) after 8m15s
[02:35:27.169] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:83:0)
[02:35:27.204] Build status: STARTED
[02:35:27.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:84:0) after 4m22s
[02:39:49.227] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:84:0)
[02:39:49.258] Build status: STARTED
[02:39:49.258] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:85:0) after 6m18s
[02:46:07.281] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:85:0)
[02:46:07.345] Build status: STARTED
[02:46:07.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:86:0) after 6m49s
[02:52:56.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:86:0)
[02:52:56.400] Build status: STARTED
[02:52:56.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:87:0) after 4m45s
[02:57:41.494] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:87:0)
[02:57:41.527] Build status: STARTED
[02:57:41.527] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:88:0) after 6m41s
[03:04:22.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:88:0)
[03:04:22.626] Build status: STARTED
[03:04:22.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:89:0) after 5m13s
[03:09:35.668] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:89:0)
[03:09:35.701] Build status: STARTED
[03:09:35.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:90:0) after 9m55s
[03:19:30.735] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:90:0)
[03:19:30.768] Build status: STARTED
[03:19:30.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:91:0) after 5m32s
[03:25:02.919] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:91:0)
[03:25:02.952] Build status: STARTED
[03:25:02.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:92:0) after 2m45s
[03:27:48.160] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:92:0)
[03:27:48.194] Build status: STARTED
[03:27:48.194] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:93:0) after 9m14s
[03:37:02.371] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:93:0)
[03:37:02.400] Build status: STARTED
[03:37:02.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:94:0) after 5m56s
[03:42:58.434] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:94:0)
[03:42:58.478] Build status: STARTED
[03:42:58.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8993999916482245248:95:0) after 5m44s
[03:43:09.652] Received PubSub notification, asking Buildbucket for the build status
[03:43:09.684] Build:
{
"id": "8828569854595846145",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 1839,
"createdBy": "project:chromium",
"createTime": "2021-12-06T18:51:41.281310550Z",
"startTime": "2021-12-06T20:07:02.102239Z",
"endTime": "2021-12-07T03:43:09.388445942Z",
"updateTime": "2021-12-07T03:43:09.388445942Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "42157bb77e823bd5de68988bed1b7f42c9066d91",
"ref": "refs/heads/main"
}
}
}
[03:43:09.684] Invocation finished in 8h51m29.585694694s with status FAILED