[04:02:06.771] New invocation is queued and will start shortly
[04:02:08.160] Starting the invocation (attempt 1)
[04:02:08.187] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/260f34381e685b6ce673838a5929aacf57ceb7c4"
[04:02:08.187] Popped gitiles commit info from properties and tags
[04:02:08.187] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:02:08.187] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:02:08.187] Buildbucket request:
{
"requestId": "8952924859744704336",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "260f34381e685b6ce673838a5929aacf57ceb7c4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952924859744704336"
},
{
"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:08.667] Scheduled build:
{
"id": "8787494797463303281",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 11238,
"createdBy": "project:chromium",
"createTime": "2023-03-05T04:02:08.313907256Z",
"updateTime": "2023-03-05T04:02:08.313907256Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "260f34381e685b6ce673838a5929aacf57ceb7c4",
"ref": "refs/heads/main"
}
}
}
[04:02:08.667] Task URL: https://cr-buildbucket.appspot.com/build/8787494797463303281
[04:02:08.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:2:0) after 1m34s
[04:03:43.436] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:2:0)
[04:03:43.470] Build status: SCHEDULED
[04:03:43.470] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:3:0) after 7m22s
[04:03:50.550] Received PubSub notification, asking Buildbucket for the build status
[04:03:50.570] Build status: STARTED
[04:11:05.524] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:3:0)
[04:11:05.562] Build status: STARTED
[04:11:05.562] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:5:0) after 3m47s
[04:14:52.584] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:5:0)
[04:14:52.615] Build status: STARTED
[04:14:52.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:6:0) after 9m52s
[04:24:44.612] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:6:0)
[04:24:44.636] Build status: STARTED
[04:24:44.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:7:0) after 5m5s
[04:29:49.677] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:7:0)
[04:29:49.699] Build status: STARTED
[04:29:49.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:8:0) after 1m11s
[04:31:00.748] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:8:0)
[04:31:00.784] Build status: STARTED
[04:31:00.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:9:0) after 2m31s
[04:33:32.309] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:9:0)
[04:33:32.334] Build status: STARTED
[04:33:32.334] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:10:0) after 1m33s
[04:35:05.533] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:10:0)
[04:35:05.563] Build status: STARTED
[04:35:05.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:11:0) after 8m50s
[04:43:55.578] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:11:0)
[04:43:55.597] Build status: STARTED
[04:43:55.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:12:0) after 8m16s
[04:52:11.611] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:12:0)
[04:52:11.693] Build status: STARTED
[04:52:11.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:13:0) after 7m11s
[04:59:22.712] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:13:0)
[04:59:22.749] Build status: STARTED
[04:59:22.749] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:14:0) after 6m41s
[05:06:03.763] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:14:0)
[05:06:03.779] Build status: STARTED
[05:06:03.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:15:0) after 5m44s
[05:11:47.882] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:15:0)
[05:11:47.906] Build status: STARTED
[05:11:47.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:16:0) after 3m21s
[05:15:09.078] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:16:0)
[05:15:09.104] Build status: STARTED
[05:15:09.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:17:0) after 5m48s
[05:20:57.153] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:17:0)
[05:20:57.172] Build status: STARTED
[05:20:57.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:18:0) after 5m42s
[05:26:39.757] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:18:0)
[05:26:39.816] Build status: STARTED
[05:26:39.816] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:19:0) after 2m12s
[05:28:52.688] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:19:0)
[05:28:52.709] Build status: STARTED
[05:28:52.709] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:20:0) after 5m46s
[05:34:38.753] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:20:0)
[05:34:38.774] Build status: STARTED
[05:34:38.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:21:0) after 2m11s
[05:36:51.080] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:21:0)
[05:36:51.102] Build status: STARTED
[05:36:51.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:22:0) after 5m7s
[05:41:58.331] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:22:0)
[05:41:58.363] Build status: STARTED
[05:41:58.363] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:23:0) after 8m40s
[05:50:38.490] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:23:0)
[05:50:38.513] Build status: STARTED
[05:50:38.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:24:0) after 4m18s
[05:54:56.529] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:24:0)
[05:54:56.552] Build status: STARTED
[05:54:56.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:25:0) after 2m0s
[05:56:56.559] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:25:0)
[05:56:56.608] Build status: STARTED
[05:56:56.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:26:0) after 7m55s
[06:04:51.821] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:26:0)
[06:04:51.843] Build status: STARTED
[06:04:51.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:27:0) after 4m1s
[06:08:53.288] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:27:0)
[06:08:53.399] Build status: STARTED
[06:08:53.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:28:0) after 9m28s
[06:18:21.419] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:28:0)
[06:18:21.449] Build status: STARTED
[06:18:21.450] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:29:0) after 4m26s
[06:22:47.578] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:29:0)
[06:22:47.599] Build status: STARTED
[06:22:47.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:30:0) after 2m34s
[06:25:21.615] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:30:0)
[06:25:21.660] Build status: STARTED
[06:25:21.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:31:0) after 2m39s
[06:28:00.876] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:31:0)
[06:28:00.897] Build status: STARTED
[06:28:00.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:32:0) after 2m11s
[06:30:11.915] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:32:0)
[06:30:11.939] Build status: STARTED
[06:30:11.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:33:0) after 6m54s
[06:37:06.227] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:33:0)
[06:37:06.253] Build status: STARTED
[06:37:06.253] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:34:0) after 9m2s
[06:46:08.276] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:34:0)
[06:46:08.304] Build status: STARTED
[06:46:08.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:35:0) after 8m30s
[06:54:38.370] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:35:0)
[06:54:38.393] Build status: STARTED
[06:54:38.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:36:0) after 2m49s
[06:57:27.409] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:36:0)
[06:57:27.437] Build status: STARTED
[06:57:27.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:37:0) after 5m13s
[07:02:40.455] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:37:0)
[07:02:40.485] Build status: STARTED
[07:02:40.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:38:0) after 3m28s
[07:06:08.579] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:38:0)
[07:06:08.621] Build status: STARTED
[07:06:08.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:39:0) after 1m25s
[07:07:33.644] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:39:0)
[07:07:33.667] Build status: STARTED
[07:07:33.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:40:0) after 7m49s
[07:15:22.739] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:40:0)
[07:15:22.761] Build status: STARTED
[07:15:22.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:41:0) after 5m4s
[07:20:26.957] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:41:0)
[07:20:26.993] Build status: STARTED
[07:20:26.993] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:42:0) after 6m0s
[07:26:27.268] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:42:0)
[07:26:27.291] Build status: STARTED
[07:26:27.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:43:0) after 5m17s
[07:31:44.318] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:43:0)
[07:31:44.343] Build status: STARTED
[07:31:44.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:44:0) after 5m31s
[07:37:15.363] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:44:0)
[07:37:15.383] Build status: STARTED
[07:37:15.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:45:0) after 6m16s
[07:43:31.623] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:45:0)
[07:43:31.646] Build status: STARTED
[07:43:31.646] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:46:0) after 6m14s
[07:49:45.664] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:46:0)
[07:49:45.688] Build status: STARTED
[07:49:45.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:47:0) after 1m22s
[07:51:07.714] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:47:0)
[07:51:07.736] Build status: STARTED
[07:51:07.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:48:0) after 1m26s
[07:52:33.751] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:48:0)
[07:52:33.798] Build status: STARTED
[07:52:33.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:49:0) after 9m56s
[08:02:30.020] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:49:0)
[08:02:30.044] Build status: STARTED
[08:02:30.044] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:50:0) after 8m11s
[08:10:41.063] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:50:0)
[08:10:41.089] Build status: STARTED
[08:10:41.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:51:0) after 9m58s
[08:20:39.110] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:51:0)
[08:20:39.136] Build status: STARTED
[08:20:39.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:52:0) after 4m38s
[08:25:17.351] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:52:0)
[08:25:17.384] Build status: STARTED
[08:25:17.384] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:53:0) after 9m33s
[08:34:50.404] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:53:0)
[08:34:50.437] Build status: STARTED
[08:34:50.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:54:0) after 6m10s
[08:41:00.457] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:54:0)
[08:41:00.480] Build status: STARTED
[08:41:00.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:55:0) after 6m3s
[08:47:03.499] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:55:0)
[08:47:03.524] Build status: STARTED
[08:47:03.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:56:0) after 4m33s
[08:51:36.552] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:56:0)
[08:51:36.577] Build status: STARTED
[08:51:36.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:57:0) after 5m49s
[08:57:25.596] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:57:0)
[08:57:25.617] Build status: STARTED
[08:57:25.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:58:0) after 9m58s
[09:07:23.641] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:58:0)
[09:07:23.666] Build status: STARTED
[09:07:23.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:59:0) after 3m29s
[09:10:52.696] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:59:0)
[09:10:52.719] Build status: STARTED
[09:10:52.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:60:0) after 7m15s
[09:18:07.741] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:60:0)
[09:18:07.765] Build status: STARTED
[09:18:07.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:61:0) after 3m9s
[09:21:16.785] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:61:0)
[09:21:16.811] Build status: STARTED
[09:21:16.811] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:62:0) after 9m0s
[09:30:16.830] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:62:0)
[09:30:16.853] Build status: STARTED
[09:30:16.853] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:63:0) after 5m42s
[09:35:58.876] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:63:0)
[09:35:58.908] Build status: STARTED
[09:35:58.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:64:0) after 2m31s
[09:38:29.982] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:64:0)
[09:38:30.004] Build status: STARTED
[09:38:30.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:65:0) after 9m26s
[09:47:56.024] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:65:0)
[09:47:56.048] Build status: STARTED
[09:47:56.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:66:0) after 3m38s
[09:51:34.076] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:66:0)
[09:51:34.101] Build status: STARTED
[09:51:34.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:67:0) after 5m24s
[09:56:58.120] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:67:0)
[09:56:58.155] Build status: STARTED
[09:56:58.155] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:68:0) after 9m35s
[10:06:33.450] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:68:0)
[10:06:33.490] Build status: STARTED
[10:06:33.490] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:69:0) after 7m54s
[10:14:27.547] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:69:0)
[10:14:27.585] Build status: STARTED
[10:14:27.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:70:0) after 6m10s
[10:20:37.602] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:70:0)
[10:20:37.622] Build status: STARTED
[10:20:37.622] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:71:0) after 4m3s
[10:24:40.828] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:71:0)
[10:24:40.850] Build status: STARTED
[10:24:40.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:72:0) after 3m56s
[10:28:36.897] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:72:0)
[10:28:36.919] Build status: STARTED
[10:28:36.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:73:0) after 8m44s
[10:37:20.950] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:73:0)
[10:37:20.975] Build status: STARTED
[10:37:20.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:74:0) after 2m57s
[10:40:17.995] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:74:0)
[10:40:18.017] Build status: STARTED
[10:40:18.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:75:0) after 1m1s
[10:41:19.076] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:75:0)
[10:41:19.148] Build status: STARTED
[10:41:19.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:76:0) after 8m13s
[10:49:32.199] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:76:0)
[10:49:32.234] Build status: STARTED
[10:49:32.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:77:0) after 7m41s
[10:57:13.297] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:77:0)
[10:57:13.334] Build status: STARTED
[10:57:13.334] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:78:0) after 5m3s
[11:02:16.323] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:78:0)
[11:02:16.347] Build status: STARTED
[11:02:16.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:79:0) after 1m1s
[11:03:17.392] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:79:0)
[11:03:17.416] Build status: STARTED
[11:03:17.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:80:0) after 9m54s
[11:13:11.455] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:80:0)
[11:13:11.488] Build status: STARTED
[11:13:11.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:81:0) after 9m6s
[11:22:17.516] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:81:0)
[11:22:17.539] Build status: STARTED
[11:22:17.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:82:0) after 9m1s
[11:31:18.557] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:82:0)
[11:31:18.579] Build status: STARTED
[11:31:18.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:83:0) after 6m55s
[11:38:13.597] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:83:0)
[11:38:13.628] Build status: STARTED
[11:38:13.628] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:84:0) after 8m9s
[11:46:22.661] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:84:0)
[11:46:22.683] Build status: STARTED
[11:46:22.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:85:0) after 6m46s
[11:53:08.706] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:85:0)
[11:53:08.724] Build status: STARTED
[11:53:08.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:86:0) after 8m44s
[12:01:52.720] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:86:0)
[12:01:52.748] Build status: STARTED
[12:01:52.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:87:0) after 9m20s
[12:11:12.911] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:87:0)
[12:11:12.937] Build status: STARTED
[12:11:12.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:88:0) after 7m52s
[12:19:04.954] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:88:0)
[12:19:04.976] Build status: STARTED
[12:19:04.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:89:0) after 5m12s
[12:24:16.997] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:89:0)
[12:24:17.020] Build status: STARTED
[12:24:17.020] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:90:0) after 1m9s
[12:25:26.037] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:90:0)
[12:25:26.056] Build status: STARTED
[12:25:26.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:91:0) after 7m16s
[12:32:42.075] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:91:0)
[12:32:42.115] Build status: STARTED
[12:32:42.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:92:0) after 9m34s
[12:42:16.129] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:92:0)
[12:42:16.170] Build status: STARTED
[12:42:16.170] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:93:0) after 2m40s
[12:44:56.203] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:93:0)
[12:44:56.225] Build status: STARTED
[12:44:56.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:94:0) after 3m32s
[12:48:28.641] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:94:0)
[12:48:28.665] Build status: STARTED
[12:48:28.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:95:0) after 4m32s
[12:53:00.700] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:95:0)
[12:53:00.724] Build status: STARTED
[12:53:00.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:96:0) after 5m11s
[12:58:11.744] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:96:0)
[12:58:11.782] Build status: STARTED
[12:58:11.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:97:0) after 7m9s
[13:05:20.811] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:97:0)
[13:05:20.841] Build status: STARTED
[13:05:20.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:98:0) after 2m52s
[13:08:12.860] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:98:0)
[13:08:12.887] Build status: STARTED
[13:08:12.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:99:0) after 7m6s
[13:15:18.910] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:99:0)
[13:15:18.937] Build status: STARTED
[13:15:18.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:100:0) after 8m11s
[13:23:29.964] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:100:0)
[13:23:29.989] Build status: STARTED
[13:23:29.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:101:0) after 4m17s
[13:27:47.009] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:101:0)
[13:27:47.034] Build status: STARTED
[13:27:47.035] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:102:0) after 8m52s
[13:36:39.043] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:102:0)
[13:36:39.067] Build status: STARTED
[13:36:39.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:103:0) after 1m21s
[13:38:00.388] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:103:0)
[13:38:00.416] Build status: STARTED
[13:38:00.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:104:0) after 2m20s
[13:40:20.701] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:104:0)
[13:40:20.754] Build status: STARTED
[13:40:20.754] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:105:0) after 6m53s
[13:47:13.776] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:105:0)
[13:47:13.796] Build status: STARTED
[13:47:13.796] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:106:0) after 5m59s
[13:53:12.834] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:106:0)
[13:53:12.854] Build status: STARTED
[13:53:12.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:107:0) after 2m33s
[13:55:45.904] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:107:0)
[13:55:45.926] Build status: STARTED
[13:55:45.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:108:0) after 6m12s
[14:01:58.213] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:108:0)
[14:01:58.234] Build status: STARTED
[14:01:58.234] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:109:0) after 9m15s
[14:11:13.291] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:109:0)
[14:11:13.327] Build status: STARTED
[14:11:13.327] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:110:0) after 5m17s
[14:16:30.345] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:110:0)
[14:16:30.366] Build status: STARTED
[14:16:30.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:111:0) after 6m41s
[14:23:11.721] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:111:0)
[14:23:11.745] Build status: STARTED
[14:23:11.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:112:0) after 1m45s
[14:24:56.762] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:112:0)
[14:24:56.783] Build status: STARTED
[14:24:56.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:113:0) after 9m38s
[14:34:34.807] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:113:0)
[14:34:34.828] Build status: STARTED
[14:34:34.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:114:0) after 1m32s
[14:36:06.872] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:114:0)
[14:36:06.916] Build status: STARTED
[14:36:06.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:115:0) after 1m32s
[14:37:39.051] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:115:0)
[14:37:39.076] Build status: STARTED
[14:37:39.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:116:0) after 9m20s
[14:46:59.249] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:116:0)
[14:46:59.275] Build status: STARTED
[14:46:59.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:117:0) after 6m58s
[14:53:57.293] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:117:0)
[14:53:57.313] Build status: STARTED
[14:53:57.313] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:118:0) after 1m43s
[14:55:40.337] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:118:0)
[14:55:40.380] Build status: STARTED
[14:55:40.380] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:119:0) after 3m27s
[14:59:07.455] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:119:0)
[14:59:07.481] Build status: STARTED
[14:59:07.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:120:0) after 6m0s
[15:05:07.504] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:120:0)
[15:05:07.533] Build status: STARTED
[15:05:07.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:121:0) after 1m1s
[15:06:08.563] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:121:0)
[15:06:08.593] Build status: STARTED
[15:06:08.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:122:0) after 1m26s
[15:07:34.671] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:122:0)
[15:07:34.701] Build status: STARTED
[15:07:34.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:123:0) after 9m21s
[15:16:55.873] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:123:0)
[15:16:55.894] Build status: STARTED
[15:16:55.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:124:0) after 6m45s
[15:23:40.932] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:124:0)
[15:23:40.952] Build status: STARTED
[15:23:40.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:125:0) after 2m57s
[15:26:38.175] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:125:0)
[15:26:38.196] Build status: STARTED
[15:26:38.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:126:0) after 3m1s
[15:29:39.242] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:126:0)
[15:29:39.260] Build status: STARTED
[15:29:39.260] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:127:0) after 6m59s
[15:36:38.284] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:127:0)
[15:36:38.305] Build status: STARTED
[15:36:38.305] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:128:0) after 5m32s
[15:42:10.401] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:128:0)
[15:42:10.530] Build status: STARTED
[15:42:10.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:129:0) after 9m2s
[15:51:12.546] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:129:0)
[15:51:12.568] Build status: STARTED
[15:51:12.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:130:0) after 1m58s
[15:53:10.586] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:130:0)
[15:53:10.611] Build status: STARTED
[15:53:10.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:131:0) after 6m27s
[15:59:37.630] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:131:0)
[15:59:37.701] Build status: STARTED
[15:59:37.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:132:0) after 2m41s
[16:02:19.529] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:132:0)
[16:02:19.553] Build status: STARTED
[16:02:19.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:133:0) after 8m50s
[16:11:09.560] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:133:0)
[16:11:09.587] Build status: STARTED
[16:11:09.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:134:0) after 4m44s
[16:15:53.703] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:134:0)
[16:15:53.726] Build status: STARTED
[16:15:53.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:135:0) after 4m27s
[16:20:20.765] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:135:0)
[16:20:20.795] Build status: STARTED
[16:20:20.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:136:0) after 5m12s
[16:25:33.605] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:136:0)
[16:25:33.659] Build status: STARTED
[16:25:33.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:137:0) after 3m50s
[16:29:24.268] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:137:0)
[16:29:24.291] Build status: STARTED
[16:29:24.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:138:0) after 7m15s
[16:36:39.474] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:138:0)
[16:36:39.496] Build status: STARTED
[16:36:39.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:139:0) after 6m22s
[16:43:01.716] Handling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:139:0)
[16:43:01.869] Build status: STARTED
[16:43:01.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTWin(dbg):8952924859744704336:140:0) after 9m49s
[16:43:44.646] Received PubSub notification, asking Buildbucket for the build status
[16:43:44.677] Build:
{
"id": "8787494797463303281",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTWin(dbg)"
},
"number": 11238,
"createdBy": "project:chromium",
"createTime": "2023-03-05T04:02:08.313907256Z",
"startTime": "2023-03-05T04:03:49.722010Z",
"endTime": "2023-03-05T16:43:44.271175704Z",
"updateTime": "2023-03-05T16:43:44.271175704Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "260f34381e685b6ce673838a5929aacf57ceb7c4",
"ref": "refs/heads/main"
}
}
}
[16:43:44.677] Invocation finished in 12h41m37.923300642s with status FAILED