[14:40:19.364] New invocation is queued and will start shortly
[14:40:20.508] Starting the invocation (attempt 1)
[14:40:20.538] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9d33895e11140546073a723c06d47c0f4e985f8c"
[14:40:20.538] Popped gitiles commit info from properties and tags
[14:40:20.538] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:40:20.540] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:40:20.540] Buildbucket request:
{
"requestId": "8934855910728808528",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9d33895e11140546073a723c06d47c0f4e985f8c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934855910728808528"
},
{
"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"
}
}
[14:40:21.270] Scheduled build:
{
"id": "8769425848768217345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 12541,
"createdBy": "project:chromium",
"createTime": "2023-09-20T14:40:20.608370996Z",
"updateTime": "2023-09-20T14:40:20.608370996Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9d33895e11140546073a723c06d47c0f4e985f8c",
"ref": "refs/heads/main"
}
}
}
[14:40:21.270] Task URL: https://cr-buildbucket.appspot.com/build/8769425848768217345
[14:40:21.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:2:0) after 6m29s
[14:40:22.145] Received PubSub notification, asking Buildbucket for the build status
[14:40:22.164] Build status: SCHEDULED
[14:46:50.373] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:2:0)
[14:46:50.393] Build status: SCHEDULED
[14:46:50.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:4:0) after 6m3s
[14:52:35.363] Received PubSub notification, asking Buildbucket for the build status
[14:52:35.401] Build status: STARTED
[14:52:53.515] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:4:0)
[14:52:53.551] Build status: STARTED
[14:52:53.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:6:0) after 3m53s
[14:56:46.568] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:6:0)
[14:56:46.681] Build status: STARTED
[14:56:46.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:7:0) after 1m36s
[14:58:22.767] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:7:0)
[14:58:22.817] Build status: STARTED
[14:58:22.817] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:8:0) after 1m5s
[14:59:27.832] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:8:0)
[14:59:27.902] Build status: STARTED
[14:59:27.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:9:0) after 9m7s
[15:08:34.942] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:9:0)
[15:08:34.984] Build status: STARTED
[15:08:34.985] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:10:0) after 1m54s
[15:10:29.049] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:10:0)
[15:10:29.076] Build status: STARTED
[15:10:29.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:11:0) after 6m34s
[15:17:03.157] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:11:0)
[15:17:03.178] Build status: STARTED
[15:17:03.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:12:0) after 2m16s
[15:19:19.389] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:12:0)
[15:19:19.410] Build status: STARTED
[15:19:19.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:13:0) after 5m12s
[15:24:31.523] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:13:0)
[15:24:31.551] Build status: STARTED
[15:24:31.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:14:0) after 2m24s
[15:26:55.900] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:14:0)
[15:26:55.938] Build status: STARTED
[15:26:55.938] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:15:0) after 1m11s
[15:28:06.963] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:15:0)
[15:28:06.992] Build status: STARTED
[15:28:06.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:16:0) after 7m14s
[15:35:21.257] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:16:0)
[15:35:21.273] Build status: STARTED
[15:35:21.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:17:0) after 8m8s
[15:43:29.296] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:17:0)
[15:43:29.392] Build status: STARTED
[15:43:29.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:18:0) after 6m47s
[15:50:16.647] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:18:0)
[15:50:16.667] Build status: STARTED
[15:50:16.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:19:0) after 4m41s
[15:54:57.688] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:19:0)
[15:54:57.712] Build status: STARTED
[15:54:57.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:20:0) after 6m40s
[16:01:37.739] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:20:0)
[16:01:37.787] Build status: STARTED
[16:01:37.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:21:0) after 9m25s
[16:11:02.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:21:0)
[16:11:02.936] Build status: STARTED
[16:11:02.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:22:0) after 1m6s
[16:12:08.990] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:22:0)
[16:12:09.038] Build status: STARTED
[16:12:09.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:23:0) after 7m49s
[16:19:58.118] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:23:0)
[16:19:58.148] Build status: STARTED
[16:19:58.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:24:0) after 6m33s
[16:26:31.246] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:24:0)
[16:26:31.270] Build status: STARTED
[16:26:31.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:25:0) after 3m56s
[16:30:27.325] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:25:0)
[16:30:27.348] Build status: STARTED
[16:30:27.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:26:0) after 9m42s
[16:40:09.464] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:26:0)
[16:40:09.502] Build status: STARTED
[16:40:09.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:27:0) after 8m11s
[16:48:20.517] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:27:0)
[16:48:20.534] Build status: STARTED
[16:48:20.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:28:0) after 6m16s
[16:54:36.662] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:28:0)
[16:54:36.679] Build status: STARTED
[16:54:36.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:29:0) after 4m14s
[16:58:50.943] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:29:0)
[16:58:50.962] Build status: STARTED
[16:58:50.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:30:0) after 1m3s
[16:59:53.974] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:30:0)
[16:59:53.989] Build status: STARTED
[16:59:53.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:31:0) after 9m0s
[17:08:54.035] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:31:0)
[17:08:54.104] Build status: STARTED
[17:08:54.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:32:0) after 4m10s
[17:13:04.125] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:32:0)
[17:13:04.148] Build status: STARTED
[17:13:04.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:33:0) after 5m54s
[17:18:58.172] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:33:0)
[17:18:58.257] Build status: STARTED
[17:18:58.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:34:0) after 5m59s
[17:24:57.285] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:34:0)
[17:24:57.303] Build status: STARTED
[17:24:57.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:35:0) after 3m30s
[17:28:27.344] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:35:0)
[17:28:27.379] Build status: STARTED
[17:28:27.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:36:0) after 5m23s
[17:33:50.734] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:36:0)
[17:33:50.788] Build status: STARTED
[17:33:50.788] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:37:0) after 4m46s
[17:38:36.822] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:37:0)
[17:38:36.843] Build status: STARTED
[17:38:36.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:38:0) after 3m40s
[17:42:16.958] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:38:0)
[17:42:16.974] Build status: STARTED
[17:42:16.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:39:0) after 2m27s
[17:44:44.165] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:39:0)
[17:44:44.184] Build status: STARTED
[17:44:44.184] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:40:0) after 6m54s
[17:51:38.387] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:40:0)
[17:51:38.406] Build status: STARTED
[17:51:38.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:41:0) after 4m36s
[17:56:14.773] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:41:0)
[17:56:14.804] Build status: STARTED
[17:56:14.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:42:0) after 5m48s
[18:02:02.982] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:42:0)
[18:02:04.366] Build status: STARTED
[18:02:04.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:43:0) after 1m2s
[18:03:06.511] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:43:0)
[18:03:06.531] Build status: STARTED
[18:03:06.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:44:0) after 2m30s
[18:05:36.559] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:44:0)
[18:05:36.582] Build status: STARTED
[18:05:36.582] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:45:0) after 9m6s
[18:14:42.559] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:45:0)
[18:14:42.576] Build status: STARTED
[18:14:42.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:46:0) after 6m14s
[18:20:56.602] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:46:0)
[18:20:56.659] Build status: STARTED
[18:20:56.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:47:0) after 3m15s
[18:24:11.950] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:47:0)
[18:24:11.971] Build status: STARTED
[18:24:11.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:48:0) after 7m12s
[18:31:23.949] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:48:0)
[18:31:23.967] Build status: STARTED
[18:31:23.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:49:0) after 1m31s
[18:32:55.001] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:49:0)
[18:32:55.094] Build status: STARTED
[18:32:55.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:50:0) after 5m46s
[18:38:41.148] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:50:0)
[18:38:41.173] Build status: STARTED
[18:38:41.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:51:0) after 4m10s
[18:42:51.165] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:51:0)
[18:42:51.358] Build status: STARTED
[18:42:51.358] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:52:0) after 2m21s
[18:45:12.512] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:52:0)
[18:45:12.527] Build status: STARTED
[18:45:12.527] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:53:0) after 8m54s
[18:54:07.585] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:53:0)
[18:54:07.606] Build status: STARTED
[18:54:07.606] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:54:0) after 4m52s
[18:58:59.729] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:54:0)
[18:58:59.755] Build status: STARTED
[18:58:59.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:55:0) after 4m59s
[19:03:58.877] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:55:0)
[19:03:58.897] Build status: STARTED
[19:03:58.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:56:0) after 6m48s
[19:10:47.007] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:56:0)
[19:10:47.030] Build status: STARTED
[19:10:47.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:57:0) after 6m30s
[19:17:17.297] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:57:0)
[19:17:17.320] Build status: STARTED
[19:17:17.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:58:0) after 2m18s
[19:19:35.373] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:58:0)
[19:19:35.399] Build status: STARTED
[19:19:35.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:59:0) after 8m29s
[19:28:04.416] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:59:0)
[19:28:04.434] Build status: STARTED
[19:28:04.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:60:0) after 8m40s
[19:36:44.492] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:60:0)
[19:36:44.532] Build status: STARTED
[19:36:44.532] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:61:0) after 4m40s
[19:41:24.755] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:61:0)
[19:41:24.784] Build status: STARTED
[19:41:24.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:62:0) after 7m42s
[19:49:06.809] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:62:0)
[19:49:06.843] Build status: STARTED
[19:49:06.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:63:0) after 9m1s
[19:58:08.026] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:63:0)
[19:58:08.052] Build status: STARTED
[19:58:08.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:64:0) after 9m7s
[20:07:15.217] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:64:0)
[20:07:15.236] Build status: STARTED
[20:07:15.236] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:65:0) after 1m4s
[20:08:19.261] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:65:0)
[20:08:19.376] Build status: STARTED
[20:08:19.376] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:66:0) after 6m3s
[20:14:22.400] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:66:0)
[20:14:22.442] Build status: STARTED
[20:14:22.442] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:67:0) after 2m53s
[20:17:15.642] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:67:0)
[20:17:15.660] Build status: STARTED
[20:17:15.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:68:0) after 1m42s
[20:18:57.876] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:68:0)
[20:18:57.890] Build status: STARTED
[20:18:57.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:69:0) after 3m50s
[20:22:48.139] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:69:0)
[20:22:48.166] Build status: STARTED
[20:22:48.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:70:0) after 6m0s
[20:28:48.183] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:70:0)
[20:28:48.207] Build status: STARTED
[20:28:48.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:71:0) after 8m37s
[20:37:25.228] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:71:0)
[20:37:25.250] Build status: STARTED
[20:37:25.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:72:0) after 9m49s
[20:47:14.321] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:72:0)
[20:47:14.364] Build status: STARTED
[20:47:14.364] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:73:0) after 7m39s
[20:54:53.558] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:73:0)
[20:54:53.735] Build status: STARTED
[20:54:53.735] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:74:0) after 5m41s
[21:00:34.752] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:74:0)
[21:00:34.806] Build status: STARTED
[21:00:34.806] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:75:0) after 5m4s
[21:05:38.871] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:75:0)
[21:05:38.934] Build status: STARTED
[21:05:38.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:76:0) after 2m53s
[21:08:32.254] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:76:0)
[21:08:32.330] Build status: STARTED
[21:08:32.330] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:77:0) after 9m38s
[21:18:10.479] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:77:0)
[21:18:10.506] Build status: STARTED
[21:18:10.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:78:0) after 7m27s
[21:25:37.532] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:78:0)
[21:25:37.553] Build status: STARTED
[21:25:37.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:79:0) after 9m21s
[21:34:58.646] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:79:0)
[21:34:58.669] Build status: STARTED
[21:34:58.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:80:0) after 7m39s
[21:42:37.685] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:80:0)
[21:42:37.703] Build status: STARTED
[21:42:37.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:81:0) after 4m39s
[21:47:17.297] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:81:0)
[21:47:17.337] Build status: STARTED
[21:47:17.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:82:0) after 2m8s
[21:49:25.356] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:82:0)
[21:49:25.380] Build status: STARTED
[21:49:25.380] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:83:0) after 8m3s
[21:57:28.435] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:83:0)
[21:57:28.459] Build status: STARTED
[21:57:28.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:84:0) after 2m56s
[22:00:24.457] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:84:0)
[22:00:24.480] Build status: STARTED
[22:00:24.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:85:0) after 5m39s
[22:06:03.768] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:85:0)
[22:06:03.789] Build status: STARTED
[22:06:03.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:86:0) after 6m58s
[22:13:01.855] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:86:0)
[22:13:01.879] Build status: STARTED
[22:13:01.879] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:87:0) after 1m10s
[22:14:11.936] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:87:0)
[22:14:11.956] Build status: STARTED
[22:14:11.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:88:0) after 9m56s
[22:24:07.960] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:88:0)
[22:24:07.980] Build status: STARTED
[22:24:07.980] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:89:0) after 3m27s
[22:27:35.081] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:89:0)
[22:27:35.098] Build status: STARTED
[22:27:35.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:90:0) after 2m57s
[22:30:32.117] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:90:0)
[22:30:32.140] Build status: STARTED
[22:30:32.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:91:0) after 1m11s
[22:31:43.183] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:91:0)
[22:31:43.231] Build status: STARTED
[22:31:43.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:92:0) after 4m21s
[22:36:04.766] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:92:0)
[22:36:04.791] Build status: STARTED
[22:36:04.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:93:0) after 8m12s
[22:44:16.824] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:93:0)
[22:44:16.974] Build status: STARTED
[22:44:16.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:94:0) after 9m43s
[22:54:00.037] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:94:0)
[22:54:00.056] Build status: STARTED
[22:54:00.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:95:0) after 2m19s
[22:56:19.243] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:95:0)
[22:56:19.280] Build status: STARTED
[22:56:19.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:96:0) after 8m44s
[23:05:03.296] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:96:0)
[23:05:03.315] Build status: STARTED
[23:05:03.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:97:0) after 9m13s
[23:14:16.509] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:97:0)
[23:14:16.582] Build status: STARTED
[23:14:16.582] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:98:0) after 2m40s
[23:16:56.865] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:98:0)
[23:16:56.882] Build status: STARTED
[23:16:56.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:99:0) after 6m41s
[23:23:37.908] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:99:0)
[23:23:37.928] Build status: STARTED
[23:23:37.928] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:100:0) after 5m42s
[23:29:20.125] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:100:0)
[23:29:20.153] Build status: STARTED
[23:29:20.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:101:0) after 7m40s
[23:37:00.451] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:101:0)
[23:37:00.526] Build status: STARTED
[23:37:00.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:102:0) after 7m22s
[23:44:22.665] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:102:0)
[23:44:22.689] Build status: STARTED
[23:44:22.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:103:0) after 8m4s
[23:52:27.077] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:103:0)
[23:52:27.098] Build status: STARTED
[23:52:27.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:104:0) after 4m12s
[23:56:39.434] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:104:0)
[23:56:39.451] Build status: STARTED
[23:56:39.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:105:0) after 7m55s
[00:04:34.482] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:105:0)
[00:04:34.508] Build status: STARTED
[00:04:34.508] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:106:0) after 3m40s
[00:08:14.527] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:106:0)
[00:08:14.552] Build status: STARTED
[00:08:14.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:107:0) after 7m50s
[00:16:04.597] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:107:0)
[00:16:04.623] Build status: STARTED
[00:16:04.623] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:108:0) after 3m44s
[00:19:48.620] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:108:0)
[00:19:48.640] Build status: STARTED
[00:19:48.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:109:0) after 1m35s
[00:21:23.668] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:109:0)
[00:21:23.694] Build status: STARTED
[00:21:23.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:110:0) after 1m28s
[00:22:51.759] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:110:0)
[00:22:51.776] Build status: STARTED
[00:22:51.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:111:0) after 2m40s
[00:25:32.186] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:111:0)
[00:25:32.224] Build status: STARTED
[00:25:32.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:112:0) after 1m0s
[00:26:32.589] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:112:0)
[00:26:32.609] Build status: STARTED
[00:26:32.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:113:0) after 4m27s
[00:31:00.045] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:113:0)
[00:31:00.069] Build status: STARTED
[00:31:00.069] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:114:0) after 4m37s
[00:35:37.086] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:114:0)
[00:35:37.106] Build status: STARTED
[00:35:37.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:115:0) after 9m9s
[00:44:46.126] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:115:0)
[00:44:46.162] Build status: STARTED
[00:44:46.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:116:0) after 7m6s
[00:51:52.412] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:116:0)
[00:51:52.432] Build status: STARTED
[00:51:52.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:117:0) after 9m0s
[01:00:52.452] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:117:0)
[01:00:52.495] Build status: STARTED
[01:00:52.495] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:118:0) after 9m33s
[01:10:25.771] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:118:0)
[01:10:25.789] Build status: STARTED
[01:10:25.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:119:0) after 4m51s
[01:15:16.878] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:119:0)
[01:15:16.897] Build status: STARTED
[01:15:16.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:120:0) after 6m3s
[01:21:19.906] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:120:0)
[01:21:19.951] Build status: STARTED
[01:21:19.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:121:0) after 8m42s
[01:30:01.971] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:121:0)
[01:30:02.148] Build status: STARTED
[01:30:02.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:122:0) after 4m32s
[01:34:34.166] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:122:0)
[01:34:34.186] Build status: STARTED
[01:34:34.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:123:0) after 4m2s
[01:38:36.241] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:123:0)
[01:38:36.268] Build status: STARTED
[01:38:36.268] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:124:0) after 1m11s
[01:39:47.666] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:124:0)
[01:39:47.686] Build status: STARTED
[01:39:47.686] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:125:0) after 6m25s
[01:46:13.009] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:125:0)
[01:46:13.038] Build status: STARTED
[01:46:13.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:126:0) after 8m12s
[01:54:25.272] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:126:0)
[01:54:25.297] Build status: STARTED
[01:54:25.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:127:0) after 5m55s
[02:00:20.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:127:0)
[02:00:20.369] Build status: STARTED
[02:00:20.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:128:0) after 1m8s
[02:01:28.467] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:128:0)
[02:01:28.491] Build status: STARTED
[02:01:28.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:129:0) after 7m21s
[02:08:49.517] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:129:0)
[02:08:49.605] Build status: STARTED
[02:08:49.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:130:0) after 8m22s
[02:17:11.624] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:130:0)
[02:17:11.662] Build status: STARTED
[02:17:11.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:131:0) after 3m38s
[02:20:49.918] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:131:0)
[02:20:49.935] Build status: STARTED
[02:20:49.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:132:0) after 8m6s
[02:28:56.041] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:132:0)
[02:28:56.060] Build status: STARTED
[02:28:56.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:133:0) after 2m34s
[02:31:30.229] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:133:0)
[02:31:30.245] Build status: STARTED
[02:31:30.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:134:0) after 4m46s
[02:36:16.566] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:134:0)
[02:36:16.587] Build status: STARTED
[02:36:16.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:135:0) after 3m54s
[02:40:10.850] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:135:0)
[02:40:10.890] Build status: STARTED
[02:40:10.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:136:0) after 2m32s
[02:42:43.176] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:136:0)
[02:42:43.199] Build status: STARTED
[02:42:43.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8934855910728808528:137:0) after 8m54s
[02:45:31.270] Received PubSub notification, asking Buildbucket for the build status
[02:45:31.285] Build:
{
"id": "8769425848768217345",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 12541,
"createdBy": "project:chromium",
"createTime": "2023-09-20T14:40:20.608370996Z",
"startTime": "2023-09-20T14:52:33.688454Z",
"endTime": "2023-09-21T02:45:30.896098304Z",
"updateTime": "2023-09-21T02:45:30.896098304Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9d33895e11140546073a723c06d47c0f4e985f8c",
"ref": "refs/heads/main"
}
}
}
[02:45:31.285] Invocation finished in 12h5m11.929877898s with status SUCCEEDED