[21:56:18.095] New invocation is queued and will start shortly
[21:56:19.124] Starting the invocation (attempt 1)
[21:56:19.163] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fcfcfb97bb8b1c3835b805afc56ca1cb1feb1249"
[21:56:19.164] Popped gitiles commit info from properties and tags
[21:56:19.164] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:56:19.164] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:56:19.164] Buildbucket request:
{
"requestId": "8988824273292450880",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fcfcfb97bb8b1c3835b805afc56ca1cb1feb1249",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8988824273292450880"
},
{
"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"
}
}
[21:56:19.867] Scheduled build:
{
"id": "8823394211440173073",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 8651,
"createdBy": "project:chromium",
"createTime": "2022-02-01T21:56:19.229157337Z",
"updateTime": "2022-02-01T21:56:19.229157337Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fcfcfb97bb8b1c3835b805afc56ca1cb1feb1249",
"ref": "refs/heads/main"
}
}
}
[21:56:19.867] Task URL: https://cr-buildbucket.appspot.com/build/8823394211440173073
[21:56:19.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:2:0) after 6m19s
[22:02:38.891] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:2:0)
[22:02:38.970] Build status: SCHEDULED
[22:02:38.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:3:0) after 8m15s
[22:02:44.833] Received PubSub notification, asking Buildbucket for the build status
[22:02:44.861] Build status: STARTED
[22:10:54.053] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:3:0)
[22:10:54.099] Build status: STARTED
[22:10:54.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:5:0) after 7m38s
[22:18:32.331] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:5:0)
[22:18:32.357] Build status: STARTED
[22:18:32.358] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:6:0) after 1m15s
[22:19:47.436] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:6:0)
[22:19:47.459] Build status: STARTED
[22:19:47.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:7:0) after 1m24s
[22:21:11.525] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:7:0)
[22:21:11.544] Build status: STARTED
[22:21:11.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:8:0) after 7m52s
[22:29:03.578] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:8:0)
[22:29:03.621] Build status: STARTED
[22:29:03.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:9:0) after 5m59s
[22:35:02.756] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:9:0)
[22:35:02.782] Build status: STARTED
[22:35:02.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:10:0) after 7m56s
[22:42:58.775] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:10:0)
[22:42:58.803] Build status: STARTED
[22:42:58.803] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:11:0) after 8m6s
[22:51:04.829] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:11:0)
[22:51:04.852] Build status: STARTED
[22:51:04.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:12:0) after 7m2s
[22:58:06.977] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:12:0)
[22:58:06.996] Build status: STARTED
[22:58:06.996] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:13:0) after 5m30s
[23:03:37.254] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:13:0)
[23:03:37.275] Build status: STARTED
[23:03:37.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:14:0) after 6m42s
[23:10:19.301] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:14:0)
[23:10:19.320] Build status: STARTED
[23:10:19.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:15:0) after 1m54s
[23:12:13.579] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:15:0)
[23:12:13.609] Build status: STARTED
[23:12:13.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:16:0) after 3m24s
[23:15:37.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:16:0)
[23:15:37.889] Build status: STARTED
[23:15:37.889] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:17:0) after 4m24s
[23:20:02.153] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:17:0)
[23:20:02.178] Build status: STARTED
[23:20:02.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:18:0) after 3m34s
[23:23:36.387] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:18:0)
[23:23:36.475] Build status: STARTED
[23:23:36.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:19:0) after 2m40s
[23:26:16.500] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:19:0)
[23:26:16.750] Build status: STARTED
[23:26:16.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:20:0) after 7m14s
[23:33:30.866] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:20:0)
[23:33:30.887] Build status: STARTED
[23:33:30.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:21:0) after 8m36s
[23:42:06.927] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:21:0)
[23:42:06.981] Build status: STARTED
[23:42:06.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:22:0) after 5m26s
[23:47:33.109] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:22:0)
[23:47:33.127] Build status: STARTED
[23:47:33.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:23:0) after 5m36s
[23:53:09.367] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:23:0)
[23:53:09.406] Build status: STARTED
[23:53:09.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:24:0) after 4m49s
[23:57:58.497] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:24:0)
[23:57:58.530] Build status: STARTED
[23:57:58.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:25:0) after 2m38s
[00:00:36.565] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:25:0)
[00:00:36.601] Build status: STARTED
[00:00:36.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:26:0) after 7m58s
[00:08:34.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:26:0)
[00:08:34.745] Build status: STARTED
[00:08:34.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:27:0) after 2m53s
[00:11:27.817] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:27:0)
[00:11:27.837] Build status: STARTED
[00:11:27.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:28:0) after 3m18s
[00:14:46.026] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:28:0)
[00:14:46.051] Build status: STARTED
[00:14:46.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:29:0) after 3m33s
[00:18:19.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:29:0)
[00:18:19.117] Build status: STARTED
[00:18:19.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:30:0) after 5m59s
[00:24:18.278] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:30:0)
[00:24:18.298] Build status: STARTED
[00:24:18.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:31:0) after 9m52s
[00:34:10.378] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:31:0)
[00:34:10.400] Build status: STARTED
[00:34:10.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:32:0) after 2m8s
[00:36:18.532] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:32:0)
[00:36:18.554] Build status: STARTED
[00:36:18.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:33:0) after 3m46s
[00:40:04.707] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:33:0)
[00:40:04.746] Build status: STARTED
[00:40:04.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:34:0) after 7m49s
[00:47:53.945] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:34:0)
[00:47:53.972] Build status: STARTED
[00:47:53.972] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:35:0) after 7m11s
[00:55:05.103] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:35:0)
[00:55:05.164] Build status: STARTED
[00:55:05.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:36:0) after 9m47s
[01:04:52.194] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:36:0)
[01:04:52.239] Build status: STARTED
[01:04:52.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:37:0) after 3m42s
[01:08:34.350] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:37:0)
[01:08:34.409] Build status: STARTED
[01:08:34.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:38:0) after 6m0s
[01:14:34.437] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:38:0)
[01:14:34.506] Build status: STARTED
[01:14:34.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:39:0) after 6m47s
[01:21:21.532] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:39:0)
[01:21:21.552] Build status: STARTED
[01:21:21.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:40:0) after 6m27s
[01:27:48.586] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:40:0)
[01:27:48.619] Build status: STARTED
[01:27:48.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:41:0) after 1m43s
[01:29:31.734] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:41:0)
[01:29:31.767] Build status: STARTED
[01:29:31.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:42:0) after 1m38s
[01:31:09.796] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:42:0)
[01:31:09.830] Build status: STARTED
[01:31:09.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:43:0) after 8m16s
[01:39:25.917] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:43:0)
[01:39:25.951] Build status: STARTED
[01:39:25.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:44:0) after 1m15s
[01:40:40.980] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:44:0)
[01:40:40.999] Build status: STARTED
[01:40:40.999] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:45:0) after 6m36s
[01:47:17.104] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:45:0)
[01:47:17.136] Build status: STARTED
[01:47:17.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:46:0) after 6m1s
[01:53:18.162] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:46:0)
[01:53:18.181] Build status: STARTED
[01:53:18.181] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:47:0) after 9m47s
[02:03:05.233] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:47:0)
[02:03:05.253] Build status: STARTED
[02:03:05.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:48:0) after 1m48s
[02:04:53.365] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:48:0)
[02:04:53.388] Build status: STARTED
[02:04:53.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:49:0) after 3m27s
[02:08:20.414] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:49:0)
[02:08:20.434] Build status: STARTED
[02:08:20.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:50:0) after 8m13s
[02:16:33.612] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:50:0)
[02:16:33.632] Build status: STARTED
[02:16:33.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:51:0) after 2m54s
[02:19:27.668] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:51:0)
[02:19:27.693] Build status: STARTED
[02:19:27.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:52:0) after 6m21s
[02:25:49.162] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:52:0)
[02:25:49.200] Build status: STARTED
[02:25:49.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:53:0) after 8m40s
[02:34:29.281] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:53:0)
[02:34:29.302] Build status: STARTED
[02:34:29.302] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:54:0) after 7m42s
[02:42:11.581] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:54:0)
[02:42:11.608] Build status: STARTED
[02:42:11.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:55:0) after 3m7s
[02:45:18.845] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:55:0)
[02:45:18.885] Build status: STARTED
[02:45:18.885] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:56:0) after 9m59s
[02:55:18.004] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:56:0)
[02:55:18.053] Build status: STARTED
[02:55:18.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:57:0) after 2m44s
[02:58:02.125] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:57:0)
[02:58:02.161] Build status: STARTED
[02:58:02.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:58:0) after 8m28s
[03:06:30.198] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:58:0)
[03:06:30.291] Build status: STARTED
[03:06:30.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:59:0) after 3m57s
[03:10:27.595] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:59:0)
[03:10:27.679] Build status: STARTED
[03:10:27.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:60:0) after 7m7s
[03:17:34.754] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:60:0)
[03:17:34.776] Build status: STARTED
[03:17:34.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:61:0) after 9m12s
[03:26:46.804] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:61:0)
[03:26:46.824] Build status: STARTED
[03:26:46.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:62:0) after 6m25s
[03:33:11.871] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:62:0)
[03:33:11.891] Build status: STARTED
[03:33:11.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:63:0) after 6m54s
[03:40:06.220] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:63:0)
[03:40:06.242] Build status: STARTED
[03:40:06.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:64:0) after 4m6s
[03:44:12.522] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:64:0)
[03:44:12.566] Build status: STARTED
[03:44:12.566] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:65:0) after 6m33s
[03:50:45.694] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:65:0)
[03:50:45.718] Build status: STARTED
[03:50:45.718] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:66:0) after 8m56s
[03:59:42.015] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:66:0)
[03:59:42.063] Build status: STARTED
[03:59:42.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:67:0) after 6m46s
[04:06:28.116] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:67:0)
[04:06:28.136] Build status: STARTED
[04:06:28.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:68:0) after 1m29s
[04:07:57.234] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:68:0)
[04:07:57.293] Build status: STARTED
[04:07:57.293] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:69:0) after 6m47s
[04:14:44.353] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:69:0)
[04:14:44.454] Build status: STARTED
[04:14:44.454] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:70:0) after 9m41s
[04:24:25.514] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:70:0)
[04:24:25.539] Build status: STARTED
[04:24:25.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:71:0) after 3m43s
[04:28:08.604] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:71:0)
[04:28:08.625] Build status: STARTED
[04:28:08.625] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:72:0) after 3m9s
[04:31:17.653] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:72:0)
[04:31:17.696] Build status: STARTED
[04:31:17.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:73:0) after 6m39s
[04:37:56.776] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:73:0)
[04:37:56.810] Build status: STARTED
[04:37:56.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:74:0) after 5m13s
[04:43:09.890] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:74:0)
[04:43:09.925] Build status: STARTED
[04:43:09.925] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:75:0) after 7m4s
[04:50:14.123] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:75:0)
[04:50:14.153] Build status: STARTED
[04:50:14.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:76:0) after 8m52s
[04:59:06.179] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:76:0)
[04:59:06.211] Build status: STARTED
[04:59:06.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:77:0) after 2m33s
[05:01:39.230] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:77:0)
[05:01:39.251] Build status: STARTED
[05:01:39.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:78:0) after 4m14s
[05:05:53.347] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:78:0)
[05:05:53.370] Build status: STARTED
[05:05:53.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:79:0) after 3m5s
[05:08:58.430] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:79:0)
[05:08:58.461] Build status: STARTED
[05:08:58.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:80:0) after 1m20s
[05:10:18.557] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:80:0)
[05:10:18.588] Build status: STARTED
[05:10:18.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:81:0) after 6m59s
[05:17:17.639] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:81:0)
[05:17:17.660] Build status: STARTED
[05:17:17.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:82:0) after 6m13s
[05:23:30.712] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:82:0)
[05:23:30.856] Build status: STARTED
[05:23:30.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:83:0) after 6m20s
[05:29:50.894] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:83:0)
[05:29:50.917] Build status: STARTED
[05:29:50.917] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:84:0) after 1m40s
[05:31:31.144] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:84:0)
[05:31:31.165] Build status: STARTED
[05:31:31.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:85:0) after 1m14s
[05:32:45.214] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:85:0)
[05:32:45.254] Build status: STARTED
[05:32:45.254] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:86:0) after 4m49s
[05:37:34.233] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:86:0)
[05:37:34.255] Build status: STARTED
[05:37:34.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:87:0) after 6m57s
[05:44:31.293] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:87:0)
[05:44:31.376] Build status: STARTED
[05:44:31.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:88:0) after 2m41s
[05:47:12.464] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:88:0)
[05:47:12.484] Build status: STARTED
[05:47:12.484] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:89:0) after 4m20s
[05:51:32.560] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:89:0)
[05:51:32.590] Build status: STARTED
[05:51:32.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:90:0) after 6m49s
[05:58:21.618] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:90:0)
[05:58:21.637] Build status: STARTED
[05:58:21.637] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:91:0) after 5m5s
[06:03:26.957] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:91:0)
[06:03:27.004] Build status: STARTED
[06:03:27.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:92:0) after 6m30s
[06:09:57.071] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:92:0)
[06:09:57.100] Build status: STARTED
[06:09:57.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:93:0) after 8m32s
[06:18:29.336] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:93:0)
[06:18:29.381] Build status: STARTED
[06:18:29.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:94:0) after 8m9s
[06:26:38.717] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:94:0)
[06:26:38.753] Build status: STARTED
[06:26:38.753] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:95:0) after 1m46s
[06:28:24.985] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:95:0)
[06:28:25.011] Build status: STARTED
[06:28:25.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:96:0) after 5m39s
[06:34:04.045] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:96:0)
[06:34:04.065] Build status: STARTED
[06:34:04.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:97:0) after 3m1s
[06:37:05.145] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:97:0)
[06:37:05.169] Build status: STARTED
[06:37:05.169] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:98:0) after 7m41s
[06:44:46.197] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:98:0)
[06:44:46.231] Build status: STARTED
[06:44:46.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:99:0) after 1m31s
[06:46:17.381] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:99:0)
[06:46:17.409] Build status: STARTED
[06:46:17.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:100:0) after 6m6s
[06:52:23.662] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:100:0)
[06:52:23.683] Build status: STARTED
[06:52:23.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:101:0) after 6m39s
[06:59:02.709] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:101:0)
[06:59:02.731] Build status: STARTED
[06:59:02.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:102:0) after 1m41s
[07:00:43.873] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:102:0)
[07:00:43.894] Build status: STARTED
[07:00:43.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:103:0) after 3m5s
[07:03:48.962] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:103:0)
[07:03:48.984] Build status: STARTED
[07:03:48.984] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:104:0) after 9m48s
[07:13:37.060] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:104:0)
[07:13:37.100] Build status: STARTED
[07:13:37.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:105:0) after 8m11s
[07:21:48.345] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:105:0)
[07:21:48.369] Build status: STARTED
[07:21:48.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:106:0) after 2m33s
[07:24:21.409] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:106:0)
[07:24:21.437] Build status: STARTED
[07:24:21.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:107:0) after 4m43s
[07:29:04.578] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:107:0)
[07:29:04.601] Build status: STARTED
[07:29:04.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:108:0) after 2m54s
[07:31:58.859] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:108:0)
[07:31:58.882] Build status: STARTED
[07:31:58.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:109:0) after 4m2s
[07:36:00.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:109:0)
[07:36:00.977] Build status: STARTED
[07:36:00.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8988824273292450880:110:0) after 5m33s
[07:36:35.993] Received PubSub notification, asking Buildbucket for the build status
[07:36:36.023] Build:
{
"id": "8823394211440173073",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 8651,
"createdBy": "project:chromium",
"createTime": "2022-02-01T21:56:19.229157337Z",
"startTime": "2022-02-01T22:02:44.155243Z",
"endTime": "2022-02-02T07:36:34.613951489Z",
"updateTime": "2022-02-02T07:36:34.613951489Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "fcfcfb97bb8b1c3835b805afc56ca1cb1feb1249",
"ref": "refs/heads/main"
}
}
}
[07:36:36.023] Invocation finished in 9h40m17.944173612s with status FAILED