[04:02:50.378] New invocation is queued and will start shortly
[04:02:51.552] Starting the invocation (attempt 1)
[04:02:51.580] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f80b360db7c763a128e4a02b648fd6c907d1b6fe"
[04:02:51.581] Popped gitiles commit info from properties and tags
[04:02:51.581] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:02:51.581] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:02:51.581] Buildbucket request:
{
"requestId": "8931725123870318368",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f80b360db7c763a128e4a02b648fd6c907d1b6fe",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931725123870318368"
},
{
"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"
}
}
[04:02:59.821] Scheduled build:
{
"id": "8766295061890081265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 13078,
"createdBy": "project:chromium",
"createTime": "2023-10-25T04:02:51.644507796Z",
"updateTime": "2023-10-25T04:02:51.644507796Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f80b360db7c763a128e4a02b648fd6c907d1b6fe",
"ref": "refs/heads/main"
}
}
}
[04:02:59.821] Task URL: https://cr-buildbucket.appspot.com/build/8766295061890081265
[04:02:59.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:2:0) after 1m57s
[04:03:00.186] Received PubSub notification, asking Buildbucket for the build status
[04:03:00.201] Build status: SCHEDULED
[04:04:57.365] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:2:0)
[04:04:57.392] Build status: SCHEDULED
[04:04:57.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:4:0) after 3m23s
[04:08:20.491] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:4:0)
[04:08:20.510] Build status: SCHEDULED
[04:08:20.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:5:0) after 2m45s
[04:09:56.080] Received PubSub notification, asking Buildbucket for the build status
[04:09:56.099] Build status: STARTED
[04:11:05.809] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:5:0)
[04:11:05.825] Build status: STARTED
[04:11:05.825] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:7:0) after 5m2s
[04:16:08.217] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:7:0)
[04:16:08.239] Build status: STARTED
[04:16:08.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:8:0) after 7m33s
[04:23:41.287] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:8:0)
[04:23:41.310] Build status: STARTED
[04:23:41.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:9:0) after 1m53s
[04:25:34.441] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:9:0)
[04:25:34.465] Build status: STARTED
[04:25:34.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:10:0) after 1m39s
[04:27:13.492] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:10:0)
[04:27:13.510] Build status: STARTED
[04:27:13.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:11:0) after 5m35s
[04:32:48.641] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:11:0)
[04:32:48.664] Build status: STARTED
[04:32:48.664] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:12:0) after 3m7s
[04:35:55.682] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:12:0)
[04:35:55.707] Build status: STARTED
[04:35:55.707] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:13:0) after 8m6s
[04:44:01.829] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:13:0)
[04:44:01.856] Build status: STARTED
[04:44:01.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:14:0) after 8m39s
[04:52:41.222] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:14:0)
[04:52:41.247] Build status: STARTED
[04:52:41.247] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:15:0) after 7m48s
[05:00:29.250] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:15:0)
[05:00:29.272] Build status: STARTED
[05:00:29.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:16:0) after 6m17s
[05:06:46.268] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:16:0)
[05:06:46.288] Build status: STARTED
[05:06:46.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:17:0) after 5m40s
[05:12:26.318] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:17:0)
[05:12:26.336] Build status: STARTED
[05:12:26.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:18:0) after 7m22s
[05:19:48.490] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:18:0)
[05:19:48.512] Build status: STARTED
[05:19:48.512] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:19:0) after 7m56s
[05:27:44.613] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:19:0)
[05:27:44.802] Build status: STARTED
[05:27:44.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:20:0) after 1m24s
[05:29:08.819] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:20:0)
[05:29:08.835] Build status: STARTED
[05:29:08.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:21:0) after 4m31s
[05:33:39.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:21:0)
[05:33:39.981] Build status: STARTED
[05:33:39.982] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:22:0) after 1m41s
[05:35:20.986] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:22:0)
[05:35:21.014] Build status: STARTED
[05:35:21.014] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:23:0) after 7m5s
[05:42:26.035] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:23:0)
[05:42:26.051] Build status: STARTED
[05:42:26.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:24:0) after 5m23s
[05:47:49.267] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:24:0)
[05:47:49.291] Build status: STARTED
[05:47:49.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:25:0) after 3m31s
[05:51:20.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:25:0)
[05:51:20.748] Build status: STARTED
[05:51:20.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:26:0) after 6m24s
[05:57:44.868] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:26:0)
[05:57:44.887] Build status: STARTED
[05:57:44.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:27:0) after 2m10s
[05:59:54.948] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:27:0)
[05:59:54.967] Build status: STARTED
[05:59:54.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:28:0) after 5m49s
[06:05:43.990] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:28:0)
[06:05:44.009] Build status: STARTED
[06:05:44.009] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:29:0) after 5m1s
[06:10:45.107] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:29:0)
[06:10:45.139] Build status: STARTED
[06:10:45.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:30:0) after 1m33s
[06:12:18.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:30:0)
[06:12:18.264] Build status: STARTED
[06:12:18.264] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:31:0) after 2m59s
[06:15:17.441] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:31:0)
[06:15:17.460] Build status: STARTED
[06:15:17.460] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:32:0) after 4m7s
[06:19:24.485] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:32:0)
[06:19:24.504] Build status: STARTED
[06:19:24.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:33:0) after 2m6s
[06:21:30.523] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:33:0)
[06:21:30.545] Build status: STARTED
[06:21:30.545] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:34:0) after 2m20s
[06:23:50.838] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:34:0)
[06:23:50.861] Build status: STARTED
[06:23:50.861] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:35:0) after 3m41s
[06:27:31.949] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:35:0)
[06:27:32.111] Build status: STARTED
[06:27:32.111] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:36:0) after 7m35s
[06:35:07.117] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:36:0)
[06:35:07.138] Build status: STARTED
[06:35:07.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:37:0) after 5m13s
[06:40:20.161] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:37:0)
[06:40:20.189] Build status: STARTED
[06:40:20.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:38:0) after 9m21s
[06:49:41.206] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:38:0)
[06:49:41.224] Build status: STARTED
[06:49:41.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:39:0) after 6m42s
[06:56:23.354] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:39:0)
[06:56:23.371] Build status: STARTED
[06:56:23.371] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:40:0) after 1m16s
[06:57:39.638] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:40:0)
[06:57:39.654] Build status: STARTED
[06:57:39.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:41:0) after 2m31s
[07:00:10.687] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:41:0)
[07:00:10.706] Build status: STARTED
[07:00:10.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:42:0) after 3m29s
[07:03:39.775] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:42:0)
[07:03:39.824] Build status: STARTED
[07:03:39.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:43:0) after 5m30s
[07:09:09.889] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:43:0)
[07:09:09.911] Build status: STARTED
[07:09:09.911] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:44:0) after 9m8s
[07:18:18.108] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:44:0)
[07:18:18.143] Build status: STARTED
[07:18:18.143] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:45:0) after 9m4s
[07:27:22.324] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:45:0)
[07:27:22.343] Build status: STARTED
[07:27:22.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:46:0) after 8m16s
[07:35:38.442] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:46:0)
[07:35:38.462] Build status: STARTED
[07:35:38.462] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:47:0) after 2m22s
[07:38:00.651] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:47:0)
[07:38:00.669] Build status: STARTED
[07:38:00.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:48:0) after 9m52s
[07:47:52.750] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:48:0)
[07:47:52.883] Build status: STARTED
[07:47:52.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:49:0) after 8m25s
[07:56:17.903] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:49:0)
[07:56:17.923] Build status: STARTED
[07:56:17.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:50:0) after 9m4s
[08:05:22.860] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:50:0)
[08:05:22.949] Build status: STARTED
[08:05:22.949] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:51:0) after 7m27s
[08:12:50.584] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:51:0)
[08:12:50.614] Build status: STARTED
[08:12:50.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:52:0) after 9m42s
[08:22:32.840] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:52:0)
[08:22:32.871] Build status: STARTED
[08:22:32.871] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:53:0) after 9m29s
[08:32:01.910] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:53:0)
[08:32:01.962] Build status: STARTED
[08:32:01.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:54:0) after 9m7s
[08:41:09.069] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:54:0)
[08:41:09.140] Build status: STARTED
[08:41:09.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:55:0) after 4m5s
[08:45:14.174] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:55:0)
[08:45:14.194] Build status: STARTED
[08:45:14.194] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:56:0) after 6m51s
[08:52:05.183] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:56:0)
[08:52:05.206] Build status: STARTED
[08:52:05.206] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:57:0) after 1m1s
[08:53:06.294] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:57:0)
[08:53:06.311] Build status: STARTED
[08:53:06.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:58:0) after 5m35s
[08:58:41.655] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:58:0)
[08:58:41.680] Build status: STARTED
[08:58:41.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:59:0) after 6m50s
[09:05:31.823] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:59:0)
[09:05:31.841] Build status: STARTED
[09:05:31.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:60:0) after 8m54s
[09:14:25.861] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:60:0)
[09:14:25.881] Build status: STARTED
[09:14:25.881] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:61:0) after 2m23s
[09:16:48.907] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:61:0)
[09:16:48.973] Build status: STARTED
[09:16:48.973] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:62:0) after 3m11s
[09:19:59.996] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:62:0)
[09:20:00.105] Build status: STARTED
[09:20:00.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:63:0) after 9m9s
[09:29:09.381] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:63:0)
[09:29:09.397] Build status: STARTED
[09:29:09.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:64:0) after 3m52s
[09:33:01.422] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:64:0)
[09:33:01.444] Build status: STARTED
[09:33:01.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:65:0) after 8m45s
[09:41:46.462] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:65:0)
[09:41:46.491] Build status: STARTED
[09:41:46.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:66:0) after 7m1s
[09:48:47.541] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:66:0)
[09:48:47.560] Build status: STARTED
[09:48:47.560] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8931725123870318368:67:0) after 7m1s
[09:52:52.515] Received PubSub notification, asking Buildbucket for the build status
[09:52:52.598] Build:
{
"id": "8766295061890081265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 13078,
"createdBy": "project:chromium",
"createTime": "2023-10-25T04:02:51.644507796Z",
"startTime": "2023-10-25T04:09:55.611749Z",
"endTime": "2023-10-25T09:52:52.337544598Z",
"updateTime": "2023-10-25T09:52:52.337544598Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f80b360db7c763a128e4a02b648fd6c907d1b6fe",
"ref": "refs/heads/main"
}
}
}
[09:52:52.599] Invocation finished in 5h50m2.226996277s with status FAILED