[17:53:36.347] New invocation is queued and will start shortly
[17:53:37.574] Starting the invocation (attempt 1)
[17:53:37.603] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1fd138650d4e0fbcc9e150aaa77675fbab0551cc"
[17:53:37.603] Popped gitiles commit info from properties and tags
[17:53:37.603] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:53:37.603] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:53:37.603] Buildbucket request:
{
"requestId": "8948886280208166064",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1fd138650d4e0fbcc9e150aaa77675fbab0551cc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8948886280208166064"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTWin(dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:53:38.127] Scheduled build:
{
"id": "8783456218073324561",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 11344,
"createdBy": "project:chromium",
"createTime": "2023-04-18T17:53:37.752063606Z",
"updateTime": "2023-04-18T17:53:37.752063606Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1fd138650d4e0fbcc9e150aaa77675fbab0551cc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[17:53:38.127] Task URL: https://cr-buildbucket.appspot.com/build/8783456218073324561
[17:53:38.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:2:0) after 1m21s
[17:54:59.507] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:2:0)
[17:54:59.535] Build status: SCHEDULED
[17:54:59.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:3:0) after 3m39s
[17:56:40.887] Received PubSub notification, asking Buildbucket for the build status
[17:56:40.912] Build status: STARTED
[17:58:39.004] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:3:0)
[17:58:39.075] Build status: STARTED
[17:58:39.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:5:0) after 7m9s
[18:05:48.104] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:5:0)
[18:05:48.128] Build status: STARTED
[18:05:48.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:6:0) after 9m41s
[18:15:29.197] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:6:0)
[18:15:29.255] Build status: STARTED
[18:15:29.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:7:0) after 2m27s
[18:17:56.283] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:7:0)
[18:17:56.316] Build status: STARTED
[18:17:56.316] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:8:0) after 2m31s
[18:20:27.339] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:8:0)
[18:20:27.439] Build status: STARTED
[18:20:27.439] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:9:0) after 5m56s
[18:26:23.663] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:9:0)
[18:26:23.712] Build status: STARTED
[18:26:23.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:10:0) after 6m58s
[18:33:22.009] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:10:0)
[18:33:22.090] Build status: STARTED
[18:33:22.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:11:0) after 4m11s
[18:37:33.116] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:11:0)
[18:37:33.136] Build status: STARTED
[18:37:33.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:12:0) after 7m27s
[18:45:00.203] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:12:0)
[18:45:00.244] Build status: STARTED
[18:45:00.244] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:13:0) after 2m6s
[18:47:06.291] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:13:0)
[18:47:06.312] Build status: STARTED
[18:47:06.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:14:0) after 7m48s
[18:54:54.580] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:14:0)
[18:54:54.600] Build status: STARTED
[18:54:54.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:15:0) after 5m57s
[19:00:51.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:15:0)
[19:00:51.799] Build status: STARTED
[19:00:51.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:16:0) after 5m57s
[19:06:48.926] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:16:0)
[19:06:49.032] Build status: STARTED
[19:06:49.032] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:17:0) after 5m27s
[19:12:16.307] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:17:0)
[19:12:16.352] Build status: STARTED
[19:12:16.352] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:18:0) after 3m22s
[19:15:38.529] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:18:0)
[19:15:38.549] Build status: STARTED
[19:15:38.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:19:0) after 9m58s
[19:25:36.949] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:19:0)
[19:25:36.975] Build status: STARTED
[19:25:36.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:20:0) after 1m40s
[19:27:17.334] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:20:0)
[19:27:17.354] Build status: STARTED
[19:27:17.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:21:0) after 6m41s
[19:33:59.199] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:21:0)
[19:33:59.219] Build status: STARTED
[19:33:59.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:22:0) after 9m9s
[19:43:08.302] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:22:0)
[19:43:08.326] Build status: STARTED
[19:43:08.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:23:0) after 7m37s
[19:50:45.655] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:23:0)
[19:50:45.681] Build status: STARTED
[19:50:45.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:24:0) after 7m11s
[19:57:56.916] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:24:0)
[19:57:56.941] Build status: STARTED
[19:57:56.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:25:0) after 2m54s
[20:00:50.965] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:25:0)
[20:00:50.987] Build status: STARTED
[20:00:50.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:26:0) after 6m13s
[20:07:04.133] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:26:0)
[20:07:04.180] Build status: STARTED
[20:07:04.180] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:27:0) after 9m4s
[20:16:08.200] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:27:0)
[20:16:08.230] Build status: STARTED
[20:16:08.230] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:28:0) after 5m41s
[20:21:49.820] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:28:0)
[20:21:49.841] Build status: STARTED
[20:21:49.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:29:0) after 3m4s
[20:24:53.929] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:29:0)
[20:24:53.952] Build status: STARTED
[20:24:53.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:30:0) after 7m57s
[20:32:51.535] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:30:0)
[20:32:51.554] Build status: STARTED
[20:32:51.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:31:0) after 6m41s
[20:39:33.163] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:31:0)
[20:39:33.206] Build status: STARTED
[20:39:33.206] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:32:0) after 9m31s
[20:49:04.228] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:32:0)
[20:49:04.252] Build status: STARTED
[20:49:04.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:33:0) after 5m53s
[20:54:57.458] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:33:0)
[20:54:57.478] Build status: STARTED
[20:54:57.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:34:0) after 1m9s
[20:56:06.711] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:34:0)
[20:56:06.733] Build status: STARTED
[20:56:06.733] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:35:0) after 5m31s
[21:01:37.801] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:35:0)
[21:01:37.832] Build status: STARTED
[21:01:37.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:36:0) after 5m39s
[21:07:16.930] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:36:0)
[21:07:16.952] Build status: STARTED
[21:07:16.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:37:0) after 7m46s
[21:15:03.198] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:37:0)
[21:15:03.221] Build status: STARTED
[21:15:03.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:38:0) after 4m38s
[21:19:41.325] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:38:0)
[21:19:41.346] Build status: STARTED
[21:19:41.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:39:0) after 6m44s
[21:26:25.445] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:39:0)
[21:26:25.469] Build status: STARTED
[21:26:25.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:40:0) after 7m54s
[21:34:19.488] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:40:0)
[21:34:19.514] Build status: STARTED
[21:34:19.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:41:0) after 6m45s
[21:41:04.561] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:41:0)
[21:41:04.584] Build status: STARTED
[21:41:04.584] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:42:0) after 2m30s
[21:43:34.703] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:42:0)
[21:43:34.722] Build status: STARTED
[21:43:34.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:43:0) after 9m19s
[21:52:53.744] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:43:0)
[21:52:53.767] Build status: STARTED
[21:52:53.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:44:0) after 8m23s
[22:01:16.823] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:44:0)
[22:01:16.846] Build status: STARTED
[22:01:16.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:45:0) after 2m44s
[22:04:00.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:45:0)
[22:04:00.885] Build status: STARTED
[22:04:00.885] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:46:0) after 3m9s
[22:07:10.158] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:46:0)
[22:07:10.182] Build status: STARTED
[22:07:10.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:47:0) after 3m38s
[22:10:48.210] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:47:0)
[22:10:48.231] Build status: STARTED
[22:10:48.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:48:0) after 9m41s
[22:20:29.485] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:48:0)
[22:20:29.508] Build status: STARTED
[22:20:29.508] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:49:0) after 8m59s
[22:29:28.531] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:49:0)
[22:29:28.552] Build status: STARTED
[22:29:28.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:50:0) after 7m50s
[22:37:18.686] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:50:0)
[22:37:18.712] Build status: STARTED
[22:37:18.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:51:0) after 4m20s
[22:41:38.983] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:51:0)
[22:41:39.004] Build status: STARTED
[22:41:39.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:52:0) after 1m26s
[22:43:05.253] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:52:0)
[22:43:05.296] Build status: STARTED
[22:43:05.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:53:0) after 3m34s
[22:46:39.327] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:53:0)
[22:46:39.350] Build status: STARTED
[22:46:39.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:54:0) after 1m37s
[22:48:16.409] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:54:0)
[22:48:16.444] Build status: STARTED
[22:48:16.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:55:0) after 6m57s
[22:55:13.467] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:55:0)
[22:55:13.522] Build status: STARTED
[22:55:13.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:56:0) after 5m58s
[23:01:11.596] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:56:0)
[23:01:11.619] Build status: STARTED
[23:01:11.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:57:0) after 1m3s
[23:02:14.740] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:57:0)
[23:02:14.797] Build status: STARTED
[23:02:14.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:58:0) after 5m4s
[23:07:18.819] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:58:0)
[23:07:18.838] Build status: STARTED
[23:07:18.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:59:0) after 6m22s
[23:13:41.227] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:59:0)
[23:13:41.249] Build status: STARTED
[23:13:41.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:60:0) after 3m4s
[23:16:46.033] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:60:0)
[23:16:46.056] Build status: STARTED
[23:16:46.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:61:0) after 4m6s
[23:20:52.268] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:61:0)
[23:20:52.337] Build status: STARTED
[23:20:52.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:62:0) after 7m28s
[23:28:20.356] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:62:0)
[23:28:20.387] Build status: STARTED
[23:28:20.387] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:63:0) after 7m7s
[23:35:27.413] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:63:0)
[23:35:27.435] Build status: STARTED
[23:35:27.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:64:0) after 9m56s
[23:45:23.642] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:64:0)
[23:45:23.661] Build status: STARTED
[23:45:23.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:65:0) after 4m3s
[23:49:26.700] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:65:0)
[23:49:26.723] Build status: STARTED
[23:49:26.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:66:0) after 7m21s
[23:56:47.774] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:66:0)
[23:56:47.923] Build status: STARTED
[23:56:47.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:67:0) after 1m24s
[23:58:11.985] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:67:0)
[23:58:12.011] Build status: STARTED
[23:58:12.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:68:0) after 2m16s
[00:00:28.173] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:68:0)
[00:00:28.202] Build status: STARTED
[00:00:28.202] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:69:0) after 1m37s
[00:02:05.437] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:69:0)
[00:02:05.469] Build status: STARTED
[00:02:05.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:70:0) after 3m44s
[00:05:49.515] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:70:0)
[00:05:49.539] Build status: STARTED
[00:05:49.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:71:0) after 5m28s
[00:11:17.616] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:71:0)
[00:11:17.640] Build status: STARTED
[00:11:17.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:72:0) after 2m49s
[00:14:06.742] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:72:0)
[00:14:06.773] Build status: STARTED
[00:14:06.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:73:0) after 5m25s
[00:19:32.287] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:73:0)
[00:19:32.311] Build status: STARTED
[00:19:32.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:74:0) after 9m3s
[00:28:35.328] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:74:0)
[00:28:35.350] Build status: STARTED
[00:28:35.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:75:0) after 4m38s
[00:33:13.532] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:75:0)
[00:33:13.609] Build status: STARTED
[00:33:13.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:76:0) after 7m57s
[00:41:10.596] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:76:0)
[00:41:10.620] Build status: STARTED
[00:41:10.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:77:0) after 4m1s
[00:45:11.895] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:77:0)
[00:45:11.916] Build status: STARTED
[00:45:11.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:78:0) after 2m4s
[00:47:16.647] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:78:0)
[00:47:16.674] Build status: STARTED
[00:47:16.674] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:79:0) after 1m15s
[00:48:31.776] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:79:0)
[00:48:31.799] Build status: STARTED
[00:48:31.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:80:0) after 7m12s
[00:55:43.927] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:80:0)
[00:55:43.947] Build status: STARTED
[00:55:43.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:81:0) after 4m15s
[00:59:59.083] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:81:0)
[00:59:59.146] Build status: STARTED
[00:59:59.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:82:0) after 4m39s
[01:04:38.170] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:82:0)
[01:04:38.256] Build status: STARTED
[01:04:38.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:83:0) after 4m22s
[01:09:00.368] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:83:0)
[01:09:00.417] Build status: STARTED
[01:09:00.417] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:84:0) after 3m51s
[01:12:51.443] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:84:0)
[01:12:51.465] Build status: STARTED
[01:12:51.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:85:0) after 4m44s
[01:17:35.819] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:85:0)
[01:17:35.840] Build status: STARTED
[01:17:35.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:86:0) after 2m53s
[01:20:29.134] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:86:0)
[01:20:29.162] Build status: STARTED
[01:20:29.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:87:0) after 9m20s
[01:29:49.201] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:87:0)
[01:29:49.224] Build status: STARTED
[01:29:49.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:88:0) after 2m59s
[01:32:48.373] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:88:0)
[01:32:48.395] Build status: STARTED
[01:32:48.395] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:89:0) after 2m12s
[01:35:00.545] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:89:0)
[01:35:00.568] Build status: STARTED
[01:35:00.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:90:0) after 9m4s
[01:44:04.593] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:90:0)
[01:44:04.667] Build status: STARTED
[01:44:04.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:91:0) after 1m14s
[01:45:18.696] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:91:0)
[01:45:18.720] Build status: STARTED
[01:45:18.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:92:0) after 2m8s
[01:47:27.007] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:92:0)
[01:47:27.026] Build status: STARTED
[01:47:27.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:93:0) after 1m13s
[01:48:40.067] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:93:0)
[01:48:40.085] Build status: STARTED
[01:48:40.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:94:0) after 7m14s
[01:55:54.235] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:94:0)
[01:55:54.262] Build status: STARTED
[01:55:54.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:95:0) after 4m16s
[02:00:10.404] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:95:0)
[02:00:10.957] Build status: STARTED
[02:00:10.957] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:96:0) after 4m33s
[02:04:44.088] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:96:0)
[02:04:44.116] Build status: STARTED
[02:04:44.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:97:0) after 8m57s
[02:13:41.240] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:97:0)
[02:13:41.260] Build status: STARTED
[02:13:41.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:98:0) after 2m38s
[02:16:19.296] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:98:0)
[02:16:19.319] Build status: STARTED
[02:16:19.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:99:0) after 6m41s
[02:23:00.659] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:99:0)
[02:23:00.679] Build status: STARTED
[02:23:00.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:100:0) after 3m6s
[02:26:06.745] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:100:0)
[02:26:06.765] Build status: STARTED
[02:26:06.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:101:0) after 6m49s
[02:32:55.785] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:101:0)
[02:32:55.808] Build status: STARTED
[02:32:55.808] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:102:0) after 6m50s
[02:39:45.848] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:102:0)
[02:39:45.867] Build status: STARTED
[02:39:45.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:103:0) after 5m24s
[02:45:09.964] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:103:0)
[02:45:09.986] Build status: STARTED
[02:45:09.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:104:0) after 5m32s
[02:50:42.241] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:104:0)
[02:50:42.279] Build status: STARTED
[02:50:42.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:105:0) after 1m3s
[02:51:45.309] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:105:0)
[02:51:45.331] Build status: STARTED
[02:51:45.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:106:0) after 5m47s
[02:57:32.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:106:0)
[02:57:32.340] Build status: STARTED
[02:57:32.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:107:0) after 9m46s
[03:07:18.448] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:107:0)
[03:07:18.471] Build status: STARTED
[03:07:18.471] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:108:0) after 9m9s
[03:16:27.517] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:108:0)
[03:16:27.537] Build status: STARTED
[03:16:27.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:109:0) after 8m26s
[03:24:53.678] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:109:0)
[03:24:53.698] Build status: STARTED
[03:24:53.698] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:110:0) after 7m43s
[03:32:36.948] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:110:0)
[03:32:36.969] Build status: STARTED
[03:32:36.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:111:0) after 1m44s
[03:34:21.159] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:111:0)
[03:34:21.189] Build status: STARTED
[03:34:21.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:112:0) after 9m11s
[03:43:32.250] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:112:0)
[03:43:32.280] Build status: STARTED
[03:43:32.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:113:0) after 2m50s
[03:46:22.337] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:113:0)
[03:46:22.359] Build status: STARTED
[03:46:22.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:114:0) after 8m26s
[03:54:48.520] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:114:0)
[03:54:48.541] Build status: STARTED
[03:54:48.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:115:0) after 2m49s
[03:57:37.625] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:115:0)
[03:57:37.644] Build status: STARTED
[03:57:37.645] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:116:0) after 5m18s
[04:02:55.662] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:116:0)
[04:02:55.690] Build status: STARTED
[04:02:55.690] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:117:0) after 7m22s
[04:10:17.711] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:117:0)
[04:10:17.737] Build status: STARTED
[04:10:17.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:118:0) after 2m17s
[04:12:34.913] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:118:0)
[04:12:34.934] Build status: STARTED
[04:12:34.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:119:0) after 3m8s
[04:15:42.960] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:119:0)
[04:15:42.981] Build status: STARTED
[04:15:42.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:120:0) after 3m24s
[04:19:07.007] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:120:0)
[04:19:07.077] Build status: STARTED
[04:19:07.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:121:0) after 4m29s
[04:23:36.098] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:121:0)
[04:23:36.119] Build status: STARTED
[04:23:36.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:122:0) after 6m34s
[04:30:10.167] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:122:0)
[04:30:10.191] Build status: STARTED
[04:30:10.191] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:123:0) after 1m51s
[04:32:01.168] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:123:0)
[04:32:01.186] Build status: STARTED
[04:32:01.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:124:0) after 8m49s
[04:40:50.215] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:124:0)
[04:40:50.235] Build status: STARTED
[04:40:50.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:125:0) after 9m27s
[04:50:17.253] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:125:0)
[04:50:17.326] Build status: STARTED
[04:50:17.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:126:0) after 8m16s
[04:58:33.511] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:126:0)
[04:58:33.538] Build status: STARTED
[04:58:33.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8948886280208166064:127:0) after 7m42s
[05:01:55.558] Received PubSub notification, asking Buildbucket for the build status
[05:01:55.584] Build:
{
"id": "8783456218073324561",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 11344,
"createdBy": "project:chromium",
"createTime": "2023-04-18T17:53:37.752063606Z",
"startTime": "2023-04-18T17:56:39.854802Z",
"endTime": "2023-04-19T05:01:54.903669950Z",
"updateTime": "2023-04-19T05:01:54.903669950Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1fd138650d4e0fbcc9e150aaa77675fbab0551cc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[05:01:55.584] Invocation finished in 11h8m19.251652245s with status FAILED