[01:15:04.499] New invocation is queued and will start shortly
[01:15:05.905] Starting the invocation (attempt 1)
[01:15:05.949] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7229ed041aab80ec9d683d7f4a1aa376193b60b6"
[01:15:05.949] Popped gitiles commit info from properties and tags
[01:15:05.949] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:15:05.950] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:15:05.950] Buildbucket request:
{
"requestId": "8992163855306384528",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7229ed041aab80ec9d683d7f4a1aa376193b60b6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8992163855306384528"
},
{
"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"
}
}
[01:15:06.534] Scheduled build:
{
"id": "8826733793068293985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 8458,
"createdBy": "project:chromium",
"createTime": "2021-12-27T01:15:06.003437458Z",
"updateTime": "2021-12-27T01:15:06.003437458Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7229ed041aab80ec9d683d7f4a1aa376193b60b6",
"ref": "refs/heads/main"
}
}
}
[01:15:06.534] Task URL: https://cr-buildbucket.appspot.com/build/8826733793068293985
[01:15:06.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:2:0) after 9m56s
[01:15:43.960] Received PubSub notification, asking Buildbucket for the build status
[01:15:43.976] Build status: STARTED
[01:25:03.196] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:2:0)
[01:25:03.224] Build status: STARTED
[01:25:03.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:4:0) after 7m23s
[01:32:26.365] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:4:0)
[01:32:26.421] Build status: STARTED
[01:32:26.421] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:5:0) after 2m42s
[01:35:08.458] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:5:0)
[01:35:08.487] Build status: STARTED
[01:35:08.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:6:0) after 9m52s
[01:45:00.802] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:6:0)
[01:45:00.820] Build status: STARTED
[01:45:00.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:7:0) after 1m39s
[01:46:39.835] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:7:0)
[01:46:39.853] Build status: STARTED
[01:46:39.853] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:8:0) after 1m57s
[01:48:37.853] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:8:0)
[01:48:37.871] Build status: STARTED
[01:48:37.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:9:0) after 8m9s
[01:56:46.906] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:9:0)
[01:56:46.936] Build status: STARTED
[01:56:46.936] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:10:0) after 8m43s
[02:05:29.973] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:10:0)
[02:05:30.036] Build status: STARTED
[02:05:30.036] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:11:0) after 9m40s
[02:15:10.062] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:11:0)
[02:15:10.092] Build status: STARTED
[02:15:10.092] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:12:0) after 2m5s
[02:17:15.419] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:12:0)
[02:17:15.446] Build status: STARTED
[02:17:15.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:13:0) after 9m44s
[02:26:59.524] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:13:0)
[02:26:59.563] Build status: STARTED
[02:26:59.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:14:0) after 6m48s
[02:33:47.576] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:14:0)
[02:33:47.599] Build status: STARTED
[02:33:47.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:15:0) after 1m7s
[02:34:55.732] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:15:0)
[02:34:55.751] Build status: STARTED
[02:34:55.751] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:16:0) after 9m53s
[02:44:48.769] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:16:0)
[02:44:48.804] Build status: STARTED
[02:44:48.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:17:0) after 7m5s
[02:51:54.046] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:17:0)
[02:51:54.079] Build status: STARTED
[02:51:54.079] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:18:0) after 4m30s
[02:56:24.223] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:18:0)
[02:56:24.252] Build status: STARTED
[02:56:24.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:19:0) after 5m27s
[03:01:51.467] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:19:0)
[03:01:51.503] Build status: STARTED
[03:01:51.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:20:0) after 7m14s
[03:09:05.717] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:20:0)
[03:09:05.743] Build status: STARTED
[03:09:05.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:21:0) after 8m16s
[03:17:22.233] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:21:0)
[03:17:22.255] Build status: STARTED
[03:17:22.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:22:0) after 1m0s
[03:18:22.269] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:22:0)
[03:18:22.300] Build status: STARTED
[03:18:22.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:23:0) after 8m13s
[03:26:35.432] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:23:0)
[03:26:35.455] Build status: STARTED
[03:26:35.455] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:24:0) after 3m24s
[03:29:59.455] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:24:0)
[03:29:59.482] Build status: STARTED
[03:29:59.482] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:25:0) after 4m34s
[03:34:33.569] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:25:0)
[03:34:33.590] Build status: STARTED
[03:34:33.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:26:0) after 2m45s
[03:37:18.606] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:26:0)
[03:37:18.648] Build status: STARTED
[03:37:18.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:27:0) after 2m43s
[03:40:01.716] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:27:0)
[03:40:01.737] Build status: STARTED
[03:40:01.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:28:0) after 5m40s
[03:45:41.752] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:28:0)
[03:45:41.790] Build status: STARTED
[03:45:41.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:29:0) after 5m30s
[03:51:12.007] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:29:0)
[03:51:12.078] Build status: STARTED
[03:51:12.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:30:0) after 2m44s
[03:53:56.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:30:0)
[03:53:56.117] Build status: STARTED
[03:53:56.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:31:0) after 5m40s
[03:59:36.424] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:31:0)
[03:59:36.443] Build status: STARTED
[03:59:36.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:32:0) after 1m52s
[04:01:28.661] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:32:0)
[04:01:28.690] Build status: STARTED
[04:01:28.690] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:33:0) after 4m39s
[04:06:07.786] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:33:0)
[04:06:07.808] Build status: STARTED
[04:06:07.808] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:34:0) after 2m42s
[04:08:49.823] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:34:0)
[04:08:49.867] Build status: STARTED
[04:08:49.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:35:0) after 3m35s
[04:12:24.969] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:35:0)
[04:12:24.994] Build status: STARTED
[04:12:24.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:36:0) after 4m26s
[04:16:51.097] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:36:0)
[04:16:51.116] Build status: STARTED
[04:16:51.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:37:0) after 1m4s
[04:17:55.498] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:37:0)
[04:17:55.523] Build status: STARTED
[04:17:55.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:38:0) after 8m50s
[04:26:45.540] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:38:0)
[04:26:45.567] Build status: STARTED
[04:26:45.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:39:0) after 8m32s
[04:35:17.616] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:39:0)
[04:35:17.681] Build status: STARTED
[04:35:17.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:40:0) after 7m40s
[04:42:57.992] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:40:0)
[04:42:58.009] Build status: STARTED
[04:42:58.009] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:41:0) after 2m11s
[04:45:09.034] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:41:0)
[04:45:09.071] Build status: STARTED
[04:45:09.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:42:0) after 3m8s
[04:48:17.089] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:42:0)
[04:48:17.115] Build status: STARTED
[04:48:17.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:43:0) after 3m40s
[04:51:57.134] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:43:0)
[04:51:57.156] Build status: STARTED
[04:51:57.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:44:0) after 1m30s
[04:53:27.374] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:44:0)
[04:53:27.416] Build status: STARTED
[04:53:27.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:45:0) after 6m45s
[05:00:12.568] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:45:0)
[05:00:12.594] Build status: STARTED
[05:00:12.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:46:0) after 2m0s
[05:02:12.613] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:46:0)
[05:02:12.630] Build status: STARTED
[05:02:12.630] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:47:0) after 3m33s
[05:05:45.956] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:47:0)
[05:05:45.975] Build status: STARTED
[05:05:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:48:0) after 4m11s
[05:09:56.992] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:48:0)
[05:09:57.019] Build status: STARTED
[05:09:57.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:49:0) after 4m16s
[05:14:13.179] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:49:0)
[05:14:13.199] Build status: STARTED
[05:14:13.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:50:0) after 7m30s
[05:21:43.475] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:50:0)
[05:21:43.492] Build status: STARTED
[05:21:43.492] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:51:0) after 4m4s
[05:25:47.509] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:51:0)
[05:25:47.527] Build status: STARTED
[05:25:47.527] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:52:0) after 9m2s
[05:34:49.560] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:52:0)
[05:34:49.585] Build status: STARTED
[05:34:49.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:53:0) after 7m12s
[05:42:01.602] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:53:0)
[05:42:01.620] Build status: STARTED
[05:42:01.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:54:0) after 8m16s
[05:50:17.660] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:54:0)
[05:50:17.678] Build status: STARTED
[05:50:17.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:55:0) after 2m13s
[05:52:30.672] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:55:0)
[05:52:30.700] Build status: STARTED
[05:52:30.700] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:56:0) after 8m25s
[06:00:55.887] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:56:0)
[06:00:55.906] Build status: STARTED
[06:00:55.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:57:0) after 8m42s
[06:09:37.982] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:57:0)
[06:09:38.005] Build status: STARTED
[06:09:38.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:58:0) after 8m50s
[06:18:28.004] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:58:0)
[06:18:28.024] Build status: STARTED
[06:18:28.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:59:0) after 8m8s
[06:26:36.042] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:59:0)
[06:26:36.136] Build status: STARTED
[06:26:36.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:60:0) after 1m7s
[06:27:43.308] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:60:0)
[06:27:43.330] Build status: STARTED
[06:27:43.330] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:61:0) after 5m51s
[06:33:34.378] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:61:0)
[06:33:34.399] Build status: STARTED
[06:33:34.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:62:0) after 1m40s
[06:35:14.489] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:62:0)
[06:35:14.514] Build status: STARTED
[06:35:14.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:63:0) after 4m45s
[06:39:59.732] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:63:0)
[06:39:59.758] Build status: STARTED
[06:39:59.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:64:0) after 6m41s
[06:46:40.774] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:64:0)
[06:46:40.791] Build status: STARTED
[06:46:40.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:65:0) after 7m14s
[06:53:54.908] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:65:0)
[06:53:54.926] Build status: STARTED
[06:53:54.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:66:0) after 9m39s
[07:03:33.950] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:66:0)
[07:03:33.970] Build status: STARTED
[07:03:33.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:67:0) after 2m39s
[07:06:13.114] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:67:0)
[07:06:13.186] Build status: STARTED
[07:06:13.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:68:0) after 4m2s
[07:10:15.204] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:68:0)
[07:10:15.223] Build status: STARTED
[07:10:15.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:69:0) after 7m2s
[07:17:17.499] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:69:0)
[07:17:17.533] Build status: STARTED
[07:17:17.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:70:0) after 7m48s
[07:25:06.193] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:70:0)
[07:25:06.240] Build status: STARTED
[07:25:06.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:71:0) after 3m28s
[07:28:34.380] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:71:0)
[07:28:34.446] Build status: STARTED
[07:28:34.446] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:72:0) after 1m26s
[07:30:00.463] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:72:0)
[07:30:00.481] Build status: STARTED
[07:30:00.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:73:0) after 1m44s
[07:31:45.062] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:73:0)
[07:31:45.147] Build status: STARTED
[07:31:45.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:74:0) after 6m49s
[07:38:34.169] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:74:0)
[07:38:34.193] Build status: STARTED
[07:38:34.193] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:75:0) after 2m10s
[07:40:44.273] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:75:0)
[07:40:44.304] Build status: STARTED
[07:40:44.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:76:0) after 6m21s
[07:47:05.453] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:76:0)
[07:47:05.471] Build status: STARTED
[07:47:05.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:77:0) after 9m31s
[07:56:36.809] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:77:0)
[07:56:36.842] Build status: STARTED
[07:56:36.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:78:0) after 4m2s
[08:00:38.818] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:78:0)
[08:00:38.855] Build status: STARTED
[08:00:38.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:79:0) after 8m49s
[08:09:27.880] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:79:0)
[08:09:27.910] Build status: STARTED
[08:09:27.910] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:80:0) after 8m38s
[08:18:05.928] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:80:0)
[08:18:05.959] Build status: STARTED
[08:18:05.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:81:0) after 3m53s
[08:21:58.977] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:81:0)
[08:21:59.010] Build status: STARTED
[08:21:59.010] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:82:0) after 1m56s
[08:23:55.034] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:82:0)
[08:23:55.066] Build status: STARTED
[08:23:55.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:83:0) after 5m58s
[08:29:53.084] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:83:0)
[08:29:53.115] Build status: STARTED
[08:29:53.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:84:0) after 9m46s
[08:39:39.134] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:84:0)
[08:39:39.155] Build status: STARTED
[08:39:39.155] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:85:0) after 6m49s
[08:46:28.289] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:85:0)
[08:46:28.308] Build status: STARTED
[08:46:28.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:86:0) after 4m46s
[08:51:14.384] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:86:0)
[08:51:14.420] Build status: STARTED
[08:51:14.420] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:87:0) after 7m40s
[08:58:54.438] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:87:0)
[08:58:54.458] Build status: STARTED
[08:58:54.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:88:0) after 9m35s
[09:08:29.590] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:88:0)
[09:08:29.624] Build status: STARTED
[09:08:29.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:89:0) after 3m12s
[09:11:41.658] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:89:0)
[09:11:41.688] Build status: STARTED
[09:11:41.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:90:0) after 6m11s
[09:17:52.708] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:90:0)
[09:17:52.736] Build status: STARTED
[09:17:52.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:91:0) after 9m59s
[09:27:51.955] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:91:0)
[09:27:51.989] Build status: STARTED
[09:27:51.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:92:0) after 7m10s
[09:35:02.095] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:92:0)
[09:35:02.113] Build status: STARTED
[09:35:02.113] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:93:0) after 9m15s
[09:44:17.120] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:93:0)
[09:44:17.159] Build status: STARTED
[09:44:17.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:94:0) after 1m14s
[09:45:31.178] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:94:0)
[09:45:31.200] Build status: STARTED
[09:45:31.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:95:0) after 7m4s
[09:52:35.402] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:95:0)
[09:52:35.436] Build status: STARTED
[09:52:35.436] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:96:0) after 1m32s
[09:54:07.453] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:96:0)
[09:54:07.494] Build status: STARTED
[09:54:07.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:97:0) after 8m57s
[10:03:04.518] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:97:0)
[10:03:04.556] Build status: STARTED
[10:03:04.556] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:98:0) after 5m41s
[10:08:45.693] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:98:0)
[10:08:45.726] Build status: STARTED
[10:08:45.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:99:0) after 8m21s
[10:17:07.029] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:99:0)
[10:17:07.063] Build status: STARTED
[10:17:07.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:100:0) after 9m13s
[10:26:20.079] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:100:0)
[10:26:20.120] Build status: STARTED
[10:26:20.120] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:101:0) after 9m46s
[10:36:06.086] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:101:0)
[10:36:06.108] Build status: STARTED
[10:36:06.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:102:0) after 2m14s
[10:38:20.122] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:102:0)
[10:38:20.139] Build status: STARTED
[10:38:20.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:103:0) after 4m8s
[10:42:28.192] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:103:0)
[10:42:28.254] Build status: STARTED
[10:42:28.254] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:104:0) after 4m15s
[10:46:43.273] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:104:0)
[10:46:43.310] Build status: STARTED
[10:46:43.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:105:0) after 2m20s
[10:49:03.336] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:105:0)
[10:49:03.374] Build status: STARTED
[10:49:03.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:106:0) after 3m10s
[10:52:13.396] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:106:0)
[10:52:13.771] Build status: STARTED
[10:52:13.771] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:107:0) after 8m15s
[11:00:28.797] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:107:0)
[11:00:28.816] Build status: STARTED
[11:00:28.816] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:108:0) after 5m3s
[11:05:31.838] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:108:0)
[11:05:31.861] Build status: STARTED
[11:05:31.861] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:109:0) after 6m33s
[11:12:04.880] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:109:0)
[11:12:04.908] Build status: STARTED
[11:12:04.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:110:0) after 9m36s
[11:21:41.091] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:110:0)
[11:21:41.112] Build status: STARTED
[11:21:41.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:111:0) after 4m8s
[11:25:49.130] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:111:0)
[11:25:49.152] Build status: STARTED
[11:25:49.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:112:0) after 6m27s
[11:32:16.177] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:112:0)
[11:32:16.264] Build status: STARTED
[11:32:16.264] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:113:0) after 7m40s
[11:39:56.319] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:113:0)
[11:39:56.339] Build status: STARTED
[11:39:56.339] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:114:0) after 2m24s
[11:42:20.359] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:114:0)
[11:42:20.381] Build status: STARTED
[11:42:20.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:115:0) after 9m36s
[11:51:56.413] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:115:0)
[11:51:56.445] Build status: STARTED
[11:51:56.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:116:0) after 4m53s
[11:56:50.064] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:116:0)
[11:56:50.104] Build status: STARTED
[11:56:50.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:117:0) after 6m33s
[12:03:23.121] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:117:0)
[12:03:23.139] Build status: STARTED
[12:03:23.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:118:0) after 1m20s
[12:04:44.309] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:118:0)
[12:04:44.340] Build status: STARTED
[12:04:44.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:119:0) after 9m43s
[12:14:27.358] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:119:0)
[12:14:27.382] Build status: STARTED
[12:14:27.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:120:0) after 6m10s
[12:20:37.402] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:120:0)
[12:20:37.432] Build status: STARTED
[12:20:37.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:121:0) after 3m4s
[12:23:41.508] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:121:0)
[12:23:41.543] Build status: STARTED
[12:23:41.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:122:0) after 8m56s
[12:32:37.558] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:122:0)
[12:32:37.607] Build status: STARTED
[12:32:37.607] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:123:0) after 2m15s
[12:34:52.737] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:123:0)
[12:34:52.764] Build status: STARTED
[12:34:52.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:124:0) after 1m7s
[12:35:59.781] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:124:0)
[12:35:59.798] Build status: STARTED
[12:35:59.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:125:0) after 9m56s
[12:45:55.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:125:0)
[12:45:55.862] Build status: STARTED
[12:45:55.862] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:126:0) after 4m21s
[12:50:16.885] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:126:0)
[12:50:16.935] Build status: STARTED
[12:50:16.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:127:0) after 4m56s
[12:55:13.124] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:127:0)
[12:55:13.151] Build status: STARTED
[12:55:13.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:128:0) after 3m15s
[12:58:28.447] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:128:0)
[12:58:28.467] Build status: STARTED
[12:58:28.467] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8992163855306384528:129:0) after 6m18s
[13:01:04.781] Received PubSub notification, asking Buildbucket for the build status
[13:01:04.808] Build:
{
"id": "8826733793068293985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 8458,
"createdBy": "project:chromium",
"createTime": "2021-12-27T01:15:06.003437458Z",
"startTime": "2021-12-27T01:15:41.164005Z",
"endTime": "2021-12-27T13:01:04.310926319Z",
"updateTime": "2021-12-27T13:01:04.310926319Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "7229ed041aab80ec9d683d7f4a1aa376193b60b6",
"ref": "refs/heads/main"
}
}
}
[13:01:04.808] Invocation finished in 11h46m0.323493511s with status FAILED