[18:57:38.569] New invocation is queued and will start shortly
[18:57:39.674] Starting the invocation (attempt 1)
[18:57:39.713] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ec2c13adf8928b4214abeab9ebed419414a399e"
[18:57:39.713] Popped gitiles commit info from properties and tags
[18:57:39.713] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:57:39.713] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:57:39.713] Buildbucket request:
{
"requestId": "8960206872145507920",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ec2c13adf8928b4214abeab9ebed419414a399e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8960206872145507920"
},
{
"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:57:40.157] Scheduled build:
{
"id": "8794776810208342065",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4298,
"createdBy": "project:chromium",
"createTime": "2022-12-14T18:57:39.787907066Z",
"updateTime": "2022-12-14T18:57:39.787907066Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ec2c13adf8928b4214abeab9ebed419414a399e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:57:40.157] Task URL: https://cr-buildbucket.appspot.com/build/8794776810208342065
[18:57:40.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:2:0) after 9m11s
[19:02:08.227] Received PubSub notification, asking Buildbucket for the build status
[19:02:08.250] Build status: STARTED
[19:06:51.238] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:2:0)
[19:06:51.274] Build status: STARTED
[19:06:51.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:4:0) after 8m46s
[19:15:37.521] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:4:0)
[19:15:37.545] Build status: STARTED
[19:15:37.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:5:0) after 8m42s
[19:24:19.664] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:5:0)
[19:24:19.685] Build status: STARTED
[19:24:19.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:6:0) after 6m8s
[19:30:27.724] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:6:0)
[19:30:27.778] Build status: STARTED
[19:30:27.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:7:0) after 4m3s
[19:34:30.883] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:7:0)
[19:34:30.910] Build status: STARTED
[19:34:30.910] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:8:0) after 9m27s
[19:43:57.958] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:8:0)
[19:43:57.980] Build status: STARTED
[19:43:57.980] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:9:0) after 3m30s
[19:47:28.014] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:9:0)
[19:47:28.035] Build status: STARTED
[19:47:28.035] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:10:0) after 3m48s
[19:51:16.063] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:10:0)
[19:51:16.087] Build status: STARTED
[19:51:16.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:11:0) after 6m54s
[19:58:10.129] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:11:0)
[19:58:10.154] Build status: STARTED
[19:58:10.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:12:0) after 6m7s
[20:04:17.180] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:12:0)
[20:04:17.200] Build status: STARTED
[20:04:17.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:13:0) after 3m28s
[20:07:45.224] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:13:0)
[20:07:45.250] Build status: STARTED
[20:07:45.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:14:0) after 3m34s
[20:11:19.301] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:14:0)
[20:11:19.325] Build status: STARTED
[20:11:19.325] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:15:0) after 3m29s
[20:14:48.389] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:15:0)
[20:14:48.412] Build status: STARTED
[20:14:48.412] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:16:0) after 8m15s
[20:23:03.478] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:16:0)
[20:23:03.500] Build status: STARTED
[20:23:03.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:17:0) after 7m31s
[20:30:34.855] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:17:0)
[20:30:34.882] Build status: STARTED
[20:30:34.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:18:0) after 9m20s
[20:39:54.926] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:18:0)
[20:39:54.976] Build status: STARTED
[20:39:54.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:19:0) after 7m21s
[20:47:16.458] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:19:0)
[20:47:16.492] Build status: STARTED
[20:47:16.492] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:20:0) after 5m9s
[20:52:25.556] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:20:0)
[20:52:25.580] Build status: STARTED
[20:52:25.580] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:21:0) after 1m22s
[20:53:47.793] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:21:0)
[20:53:47.816] Build status: STARTED
[20:53:47.816] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:22:0) after 7m7s
[21:00:54.813] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:22:0)
[21:00:54.848] Build status: STARTED
[21:00:54.848] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:23:0) after 5m27s
[21:06:21.878] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:23:0)
[21:06:21.967] Build status: STARTED
[21:06:21.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:24:0) after 3m58s
[21:10:19.966] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:24:0)
[21:10:19.991] Build status: STARTED
[21:10:19.991] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:25:0) after 8m32s
[21:18:52.112] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:25:0)
[21:18:52.162] Build status: STARTED
[21:18:52.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:26:0) after 1m46s
[21:20:38.180] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:26:0)
[21:20:38.207] Build status: STARTED
[21:20:38.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:27:0) after 9m7s
[21:29:45.235] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:27:0)
[21:29:45.261] Build status: STARTED
[21:29:45.262] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:28:0) after 6m17s
[21:36:02.353] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:28:0)
[21:36:02.377] Build status: STARTED
[21:36:02.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:29:0) after 6m38s
[21:42:40.498] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:29:0)
[21:42:40.520] Build status: STARTED
[21:42:40.520] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:30:0) after 8m52s
[21:51:32.556] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:30:0)
[21:51:32.588] Build status: STARTED
[21:51:32.588] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:31:0) after 3m18s
[21:54:50.614] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:31:0)
[21:54:50.638] Build status: STARTED
[21:54:50.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:32:0) after 8m0s
[22:02:50.674] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:32:0)
[22:02:50.696] Build status: STARTED
[22:02:50.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:33:0) after 8m32s
[22:11:22.947] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:33:0)
[22:11:22.972] Build status: STARTED
[22:11:22.972] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:34:0) after 4m4s
[22:15:27.060] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:34:0)
[22:15:27.087] Build status: STARTED
[22:15:27.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:35:0) after 9m19s
[22:24:46.407] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:35:0)
[22:24:46.430] Build status: STARTED
[22:24:46.430] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:36:0) after 6m25s
[22:31:11.556] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:36:0)
[22:31:11.585] Build status: STARTED
[22:31:11.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:37:0) after 5m45s
[22:36:56.624] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:37:0)
[22:36:56.658] Build status: STARTED
[22:36:56.658] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:38:0) after 6m0s
[22:42:56.798] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:38:0)
[22:42:56.820] Build status: STARTED
[22:42:56.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:39:0) after 3m27s
[22:46:23.896] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:39:0)
[22:46:23.922] Build status: STARTED
[22:46:23.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:40:0) after 4m43s
[22:51:08.086] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:40:0)
[22:51:08.109] Build status: STARTED
[22:51:08.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:41:0) after 1m48s
[22:52:56.214] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:41:0)
[22:52:56.237] Build status: STARTED
[22:52:56.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:42:0) after 8m48s
[23:01:44.272] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:42:0)
[23:01:44.302] Build status: STARTED
[23:01:44.302] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:43:0) after 2m11s
[23:03:55.495] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:43:0)
[23:03:55.517] Build status: STARTED
[23:03:55.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:44:0) after 6m16s
[23:10:11.601] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:44:0)
[23:10:11.653] Build status: STARTED
[23:10:11.653] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:45:0) after 2m9s
[23:12:21.117] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:45:0)
[23:12:21.139] Build status: STARTED
[23:12:21.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:46:0) after 4m43s
[23:17:04.409] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:46:0)
[23:17:04.434] Build status: STARTED
[23:17:04.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:47:0) after 8m50s
[23:25:54.633] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:47:0)
[23:25:54.662] Build status: STARTED
[23:25:54.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:48:0) after 3m28s
[23:29:22.758] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:48:0)
[23:29:22.779] Build status: STARTED
[23:29:22.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:49:0) after 3m15s
[23:32:38.013] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:49:0)
[23:32:38.111] Build status: STARTED
[23:32:38.111] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:50:0) after 4m40s
[23:37:18.322] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:50:0)
[23:37:18.345] Build status: STARTED
[23:37:18.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:51:0) after 4m38s
[23:41:56.794] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:51:0)
[23:41:56.818] Build status: STARTED
[23:41:56.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:52:0) after 2m39s
[23:44:35.943] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:52:0)
[23:44:35.963] Build status: STARTED
[23:44:35.963] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:53:0) after 7m15s
[23:51:51.487] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:53:0)
[23:51:51.506] Build status: STARTED
[23:51:51.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:54:0) after 3m24s
[23:55:15.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:54:0)
[23:55:15.619] Build status: STARTED
[23:55:15.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:55:0) after 4m58s
[00:00:13.939] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:55:0)
[00:00:13.961] Build status: STARTED
[00:00:13.961] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:56:0) after 5m3s
[00:05:16.988] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:56:0)
[00:05:17.010] Build status: STARTED
[00:05:17.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:57:0) after 9m52s
[00:15:09.038] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:57:0)
[00:15:09.069] Build status: STARTED
[00:15:09.069] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:58:0) after 5m20s
[00:20:29.202] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:58:0)
[00:20:29.336] Build status: STARTED
[00:20:29.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:59:0) after 9m20s
[00:29:49.387] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:59:0)
[00:29:49.413] Build status: STARTED
[00:29:49.413] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:60:0) after 7m32s
[00:37:21.673] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:60:0)
[00:37:21.730] Build status: STARTED
[00:37:21.730] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:61:0) after 3m54s
[00:41:15.736] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:61:0)
[00:41:15.758] Build status: STARTED
[00:41:15.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:62:0) after 3m31s
[00:44:46.899] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:62:0)
[00:44:46.921] Build status: STARTED
[00:44:46.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:63:0) after 6m10s
[00:50:56.946] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:63:0)
[00:50:56.970] Build status: STARTED
[00:50:56.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:64:0) after 8m36s
[00:59:33.053] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:64:0)
[00:59:33.076] Build status: STARTED
[00:59:33.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:65:0) after 3m33s
[01:03:06.122] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:65:0)
[01:03:06.152] Build status: STARTED
[01:03:06.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:66:0) after 6m50s
[01:09:56.180] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:66:0)
[01:09:56.207] Build status: STARTED
[01:09:56.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:67:0) after 3m43s
[01:13:39.246] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:67:0)
[01:13:39.275] Build status: STARTED
[01:13:39.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:68:0) after 3m24s
[01:17:03.306] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:68:0)
[01:17:03.330] Build status: STARTED
[01:17:03.330] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:69:0) after 9m2s
[01:26:05.403] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:69:0)
[01:26:05.426] Build status: STARTED
[01:26:05.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:70:0) after 7m21s
[01:33:26.457] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:70:0)
[01:33:26.485] Build status: STARTED
[01:33:26.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:71:0) after 7m30s
[01:40:56.513] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:71:0)
[01:40:56.540] Build status: STARTED
[01:40:56.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:72:0) after 5m19s
[01:46:15.550] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:72:0)
[01:46:15.572] Build status: STARTED
[01:46:15.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:73:0) after 9m11s
[01:55:26.775] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:73:0)
[01:55:26.806] Build status: STARTED
[01:55:26.806] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:74:0) after 6m56s
[02:02:24.043] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:74:0)
[02:02:24.097] Build status: STARTED
[02:02:24.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:75:0) after 1m1s
[02:03:25.227] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:75:0)
[02:03:25.412] Build status: STARTED
[02:03:25.412] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:76:0) after 3m52s
[02:07:17.578] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:76:0)
[02:07:17.604] Build status: STARTED
[02:07:17.604] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:77:0) after 6m27s
[02:13:44.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:77:0)
[02:13:44.741] Build status: STARTED
[02:13:44.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:78:0) after 6m44s
[02:20:28.845] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:78:0)
[02:20:28.868] Build status: STARTED
[02:20:28.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:79:0) after 1m58s
[02:22:26.899] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:79:0)
[02:22:26.926] Build status: STARTED
[02:22:26.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:80:0) after 1m36s
[02:24:03.084] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:80:0)
[02:24:03.110] Build status: STARTED
[02:24:03.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:81:0) after 6m28s
[02:30:31.310] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:81:0)
[02:30:31.333] Build status: STARTED
[02:30:31.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:82:0) after 3m29s
[02:34:00.718] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:82:0)
[02:34:00.751] Build status: STARTED
[02:34:00.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:83:0) after 3m43s
[02:37:43.916] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:83:0)
[02:37:43.939] Build status: STARTED
[02:37:43.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:84:0) after 6m17s
[02:44:01.069] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:84:0)
[02:44:01.110] Build status: STARTED
[02:44:01.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:85:0) after 9m9s
[02:53:10.484] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:85:0)
[02:53:10.507] Build status: STARTED
[02:53:10.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:86:0) after 8m40s
[03:01:51.024] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:86:0)
[03:01:51.050] Build status: STARTED
[03:01:51.050] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:87:0) after 2m18s
[03:04:09.076] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:87:0)
[03:04:09.097] Build status: STARTED
[03:04:09.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:88:0) after 7m29s
[03:11:38.206] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:88:0)
[03:11:38.228] Build status: STARTED
[03:11:38.228] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:89:0) after 5m50s
[03:17:28.782] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:89:0)
[03:17:28.806] Build status: STARTED
[03:17:28.806] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:90:0) after 9m15s
[03:26:43.879] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:90:0)
[03:26:43.907] Build status: STARTED
[03:26:43.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:91:0) after 7m18s
[03:34:01.959] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:91:0)
[03:34:01.984] Build status: STARTED
[03:34:01.984] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:92:0) after 5m22s
[03:39:24.031] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:92:0)
[03:39:24.061] Build status: STARTED
[03:39:24.061] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:93:0) after 9m39s
[03:49:03.273] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:93:0)
[03:49:03.388] Build status: STARTED
[03:49:03.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:94:0) after 1m34s
[03:50:37.860] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:94:0)
[03:50:37.897] Build status: STARTED
[03:50:37.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:95:0) after 2m7s
[03:52:45.124] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:95:0)
[03:52:45.143] Build status: STARTED
[03:52:45.143] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:96:0) after 3m13s
[03:55:58.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:96:0)
[03:55:58.261] Build status: STARTED
[03:55:58.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:97:0) after 5m45s
[04:01:43.289] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:97:0)
[04:01:43.311] Build status: STARTED
[04:01:43.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:98:0) after 2m5s
[04:03:48.343] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:98:0)
[04:03:48.366] Build status: STARTED
[04:03:48.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:99:0) after 5m7s
[04:08:55.393] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:99:0)
[04:08:55.419] Build status: STARTED
[04:08:55.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:100:0) after 3m26s
[04:12:21.544] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:100:0)
[04:12:21.566] Build status: STARTED
[04:12:21.566] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:101:0) after 5m33s
[04:17:54.921] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:101:0)
[04:17:54.942] Build status: STARTED
[04:17:54.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:102:0) after 5m58s
[04:23:53.045] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:102:0)
[04:23:53.072] Build status: STARTED
[04:23:53.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:103:0) after 5m45s
[04:29:38.698] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:103:0)
[04:29:38.723] Build status: STARTED
[04:29:38.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:104:0) after 7m8s
[04:36:46.742] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:104:0)
[04:36:46.763] Build status: STARTED
[04:36:46.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:105:0) after 8m50s
[04:45:36.864] Handling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:105:0)
[04:45:36.887] Build status: STARTED
[04:45:36.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin64PGO:8960206872145507920:106:0) after 7m7s
[04:51:24.524] Received PubSub notification, asking Buildbucket for the build status
[04:51:24.618] Build:
{
"id": "8794776810208342065",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin64PGO"
},
"number": 4298,
"createdBy": "project:chromium",
"createTime": "2022-12-14T18:57:39.787907066Z",
"startTime": "2022-12-14T19:02:07.646493Z",
"endTime": "2022-12-15T04:51:24.273563860Z",
"updateTime": "2022-12-15T04:51:24.273563860Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4ec2c13adf8928b4214abeab9ebed419414a399e",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:51:24.618] Invocation finished in 9h53m46.062039726s with status FAILED