[02:48:10.443] New invocation is queued and will start shortly
[02:48:11.571] Starting the invocation (attempt 1)
[02:48:11.595] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b22e71bd91cf933b293139adc052e191288a9bcc"
[02:48:11.595] Popped gitiles commit info from properties and tags
[02:48:11.595] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:48:11.595] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:48:11.595] Buildbucket request:
{
"requestId": "8894585065206519728",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b22e71bd91cf933b293139adc052e191288a9bcc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894585065206519728"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:48:12.102] Scheduled build:
{
"id": "8729155003260658897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17884,
"createdBy": "project:chromium",
"createTime": "2024-12-08T02:48:11.669315566Z",
"updateTime": "2024-12-08T02:48:11.669315566Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b22e71bd91cf933b293139adc052e191288a9bcc",
"ref": "refs/heads/main"
}
}
}
[02:48:12.102] Task URL: https://cr-buildbucket.appspot.com/build/8729155003260658897
[02:48:12.102] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:2:0) after 6m35s
[02:48:48.696] Received PubSub notification, asking Buildbucket for the build status
[02:48:48.719] Build status: SCHEDULED
[02:54:47.100] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:2:0)
[02:54:47.126] Build status: SCHEDULED
[02:54:47.126] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:4:0) after 4m19s
[02:59:06.220] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:4:0)
[02:59:06.268] Build status: SCHEDULED
[02:59:06.268] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:5:0) after 6m39s
[03:05:45.289] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:5:0)
[03:05:45.314] Build status: SCHEDULED
[03:05:45.314] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:6:0) after 6m0s
[03:11:45.338] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:6:0)
[03:11:45.366] Build status: SCHEDULED
[03:11:45.366] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:7:0) after 9m53s
[03:21:38.416] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:7:0)
[03:21:38.433] Build status: SCHEDULED
[03:21:38.433] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:8:0) after 5m24s
[03:27:02.551] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:8:0)
[03:27:02.567] Build status: SCHEDULED
[03:27:02.567] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:9:0) after 7m23s
[03:33:17.001] Received PubSub notification, asking Buildbucket for the build status
[03:33:17.022] Build status: STARTED
[03:34:25.549] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:9:0)
[03:34:25.573] Build status: STARTED
[03:34:25.573] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:11:0) after 4m19s
[03:38:44.697] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:11:0)
[03:38:44.720] Build status: STARTED
[03:38:44.720] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:12:0) after 2m58s
[03:41:42.736] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:12:0)
[03:41:42.756] Build status: STARTED
[03:41:42.756] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:13:0) after 4m14s
[03:45:57.074] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:13:0)
[03:45:57.125] Build status: STARTED
[03:45:57.125] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:14:0) after 7m56s
[03:53:53.158] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:14:0)
[03:53:53.188] Build status: STARTED
[03:53:53.188] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:15:0) after 5m42s
[03:59:35.405] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:15:0)
[03:59:35.495] Build status: STARTED
[03:59:35.495] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:16:0) after 1m8s
[04:00:43.512] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:16:0)
[04:00:43.529] Build status: STARTED
[04:00:43.529] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:17:0) after 2m55s
[04:03:38.593] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:17:0)
[04:03:38.615] Build status: STARTED
[04:03:38.615] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:18:0) after 7m31s
[04:11:09.635] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:18:0)
[04:11:09.653] Build status: STARTED
[04:11:09.653] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:19:0) after 4m35s
[04:15:44.808] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:19:0)
[04:15:44.834] Build status: STARTED
[04:15:44.834] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:20:0) after 1m48s
[04:17:32.856] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:20:0)
[04:17:32.872] Build status: STARTED
[04:17:32.872] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:21:0) after 2m19s
[04:19:51.989] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:21:0)
[04:19:52.011] Build status: STARTED
[04:19:52.011] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:22:0) after 7m59s
[04:27:51.045] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:22:0)
[04:27:51.061] Build status: STARTED
[04:27:51.061] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:23:0) after 7m16s
[04:35:07.078] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:23:0)
[04:35:07.107] Build status: STARTED
[04:35:07.107] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:24:0) after 8m40s
[04:43:47.280] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:24:0)
[04:43:47.296] Build status: STARTED
[04:43:47.296] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:25:0) after 5m25s
[04:49:12.295] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:25:0)
[04:49:12.357] Build status: STARTED
[04:49:12.357] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:26:0) after 4m2s
[04:53:14.376] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:26:0)
[04:53:14.394] Build status: STARTED
[04:53:14.394] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:27:0) after 2m49s
[04:56:03.521] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:27:0)
[04:56:03.547] Build status: STARTED
[04:56:03.547] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:28:0) after 3m14s
[04:59:17.561] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:28:0)
[04:59:17.582] Build status: STARTED
[04:59:17.582] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:29:0) after 6m30s
[05:05:47.599] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:29:0)
[05:05:47.626] Build status: STARTED
[05:05:47.626] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:30:0) after 8m15s
[05:14:02.692] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:30:0)
[05:14:02.709] Build status: STARTED
[05:14:02.709] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:31:0) after 2m28s
[05:16:30.726] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:31:0)
[05:16:30.743] Build status: STARTED
[05:16:30.743] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:32:0) after 2m10s
[05:18:40.764] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:32:0)
[05:18:40.781] Build status: STARTED
[05:18:40.781] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:33:0) after 4m29s
[05:23:09.836] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:33:0)
[05:23:09.862] Build status: STARTED
[05:23:09.862] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:34:0) after 2m53s
[05:26:02.841] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:34:0)
[05:26:02.859] Build status: STARTED
[05:26:02.859] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:35:0) after 1m56s
[05:27:58.965] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:35:0)
[05:27:58.982] Build status: STARTED
[05:27:58.982] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:36:0) after 1m58s
[05:29:56.998] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:36:0)
[05:29:57.066] Build status: STARTED
[05:29:57.066] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:37:0) after 7m17s
[05:37:14.046] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:37:0)
[05:37:14.121] Build status: STARTED
[05:37:14.121] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:38:0) after 4m13s
[05:41:27.221] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:38:0)
[05:41:29.634] Build status: STARTED
[05:41:29.634] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:39:0) after 5m30s
[05:46:59.653] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:39:0)
[05:46:59.712] Build status: STARTED
[05:46:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:40:0) after 5m19s
[05:52:18.731] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:40:0)
[05:52:18.751] Build status: STARTED
[05:52:18.751] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:41:0) after 9m45s
[06:02:03.770] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:41:0)
[06:02:03.803] Build status: STARTED
[06:02:03.803] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:42:0) after 6m5s
[06:08:08.838] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:42:0)
[06:08:08.855] Build status: STARTED
[06:08:08.855] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:43:0) after 2m13s
[06:10:22.005] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:43:0)
[06:10:22.025] Build status: STARTED
[06:10:22.025] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:44:0) after 6m8s
[06:16:30.042] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:44:0)
[06:16:30.061] Build status: STARTED
[06:16:30.061] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:45:0) after 4m57s
[06:21:27.185] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:45:0)
[06:21:27.202] Build status: STARTED
[06:21:27.202] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:46:0) after 5m36s
[06:27:03.252] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:46:0)
[06:27:03.268] Build status: STARTED
[06:27:03.268] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:47:0) after 7m0s
[06:34:03.434] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:47:0)
[06:34:03.452] Build status: STARTED
[06:34:03.452] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:48:0) after 8m44s
[06:42:47.520] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:48:0)
[06:42:47.595] Build status: STARTED
[06:42:47.595] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:49:0) after 6m34s
[06:49:21.712] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:49:0)
[06:49:21.733] Build status: STARTED
[06:49:21.733] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:50:0) after 1m54s
[06:51:15.750] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:50:0)
[06:51:15.768] Build status: STARTED
[06:51:15.768] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:51:0) after 4m0s
[06:55:15.785] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:51:0)
[06:55:15.800] Build status: STARTED
[06:55:15.800] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:52:0) after 6m4s
[07:01:19.856] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:52:0)
[07:01:19.874] Build status: STARTED
[07:01:19.874] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:53:0) after 9m38s
[07:10:57.891] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:53:0)
[07:10:57.923] Build status: STARTED
[07:10:57.923] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:54:0) after 5m19s
[07:16:17.275] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:54:0)
[07:16:17.292] Build status: STARTED
[07:16:17.292] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:55:0) after 4m18s
[07:20:35.313] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:55:0)
[07:20:35.329] Build status: STARTED
[07:20:35.329] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:56:0) after 8m50s
[07:29:25.575] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:56:0)
[07:29:25.587] Build status: STARTED
[07:29:25.587] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:57:0) after 6m17s
[07:35:42.670] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:57:0)
[07:35:42.699] Build status: STARTED
[07:35:42.699] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:58:0) after 4m41s
[07:40:23.776] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:58:0)
[07:40:23.793] Build status: STARTED
[07:40:23.793] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:59:0) after 4m50s
[07:45:13.808] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:59:0)
[07:45:13.829] Build status: STARTED
[07:45:13.829] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:60:0) after 5m18s
[07:50:31.846] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:60:0)
[07:50:31.867] Build status: STARTED
[07:50:31.867] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:61:0) after 2m46s
[07:53:18.025] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:61:0)
[07:53:18.042] Build status: STARTED
[07:53:18.042] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:62:0) after 2m33s
[07:55:51.253] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:62:0)
[07:55:51.267] Build status: STARTED
[07:55:51.267] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:63:0) after 5m50s
[08:01:41.320] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:63:0)
[08:01:41.352] Build status: STARTED
[08:01:41.352] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:64:0) after 3m2s
[08:04:43.575] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:64:0)
[08:04:43.595] Build status: STARTED
[08:04:43.595] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:65:0) after 9m21s
[08:14:04.595] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:65:0)
[08:14:04.613] Build status: STARTED
[08:14:04.613] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:66:0) after 9m44s
[08:23:48.701] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:66:0)
[08:23:48.741] Build status: STARTED
[08:23:48.741] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:67:0) after 5m58s
[08:29:46.863] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:67:0)
[08:29:46.882] Build status: STARTED
[08:29:46.882] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:68:0) after 9m47s
[08:39:33.896] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:68:0)
[08:39:33.913] Build status: STARTED
[08:39:33.913] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:69:0) after 3m59s
[08:43:32.894] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:69:0)
[08:43:32.916] Build status: STARTED
[08:43:32.916] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:70:0) after 4m47s
[08:48:19.915] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:70:0)
[08:48:19.931] Build status: STARTED
[08:48:19.931] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:71:0) after 2m56s
[08:51:16.032] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:71:0)
[08:51:16.049] Build status: STARTED
[08:51:16.049] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:72:0) after 9m12s
[09:00:28.066] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:72:0)
[09:00:28.090] Build status: STARTED
[09:00:28.090] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:73:0) after 9m14s
[09:09:42.133] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:73:0)
[09:09:42.159] Build status: STARTED
[09:09:42.159] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:74:0) after 2m10s
[09:11:52.289] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:74:0)
[09:11:52.309] Build status: STARTED
[09:11:52.309] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:75:0) after 5m15s
[09:17:07.416] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:75:0)
[09:17:07.619] Build status: STARTED
[09:17:07.619] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:76:0) after 9m35s
[09:26:42.637] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:76:0)
[09:26:42.660] Build status: STARTED
[09:26:42.660] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:77:0) after 8m8s
[09:34:50.911] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:77:0)
[09:34:50.934] Build status: STARTED
[09:34:50.934] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:78:0) after 2m40s
[09:37:31.152] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:78:0)
[09:37:31.172] Build status: STARTED
[09:37:31.172] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:79:0) after 7m25s
[09:44:56.314] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:79:0)
[09:44:56.340] Build status: STARTED
[09:44:56.340] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:80:0) after 3m12s
[09:48:08.411] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:80:0)
[09:48:08.426] Build status: STARTED
[09:48:08.426] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:81:0) after 5m17s
[09:53:25.494] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:81:0)
[09:53:25.514] Build status: STARTED
[09:53:25.514] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:82:0) after 7m22s
[10:00:47.700] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:82:0)
[10:00:47.717] Build status: STARTED
[10:00:47.717] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:83:0) after 6m5s
[10:06:52.739] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:83:0)
[10:06:52.778] Build status: STARTED
[10:06:52.778] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:84:0) after 2m7s
[10:08:59.896] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:84:0)
[10:08:59.912] Build status: STARTED
[10:08:59.912] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:85:0) after 7m34s
[10:16:33.946] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:85:0)
[10:16:33.964] Build status: STARTED
[10:16:33.964] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:86:0) after 5m20s
[10:21:54.157] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:86:0)
[10:21:54.175] Build status: STARTED
[10:21:54.175] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:87:0) after 9m33s
[10:31:27.192] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:87:0)
[10:31:27.219] Build status: STARTED
[10:31:27.219] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:88:0) after 1m37s
[10:33:04.237] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:88:0)
[10:33:04.257] Build status: STARTED
[10:33:04.257] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:89:0) after 8m34s
[10:41:38.278] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:89:0)
[10:41:38.307] Build status: STARTED
[10:41:38.307] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:90:0) after 4m5s
[10:45:43.329] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:90:0)
[10:45:43.343] Build status: STARTED
[10:45:43.343] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:91:0) after 2m21s
[10:48:04.360] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:91:0)
[10:48:04.376] Build status: STARTED
[10:48:04.376] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:92:0) after 6m35s
[10:54:39.399] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:92:0)
[10:54:39.417] Build status: STARTED
[10:54:39.417] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:93:0) after 8m34s
[11:03:13.437] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:93:0)
[11:03:13.519] Build status: STARTED
[11:03:13.519] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:94:0) after 7m34s
[11:10:47.643] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:94:0)
[11:10:47.707] Build status: STARTED
[11:10:47.707] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:95:0) after 8m49s
[11:19:36.755] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:95:0)
[11:19:36.774] Build status: STARTED
[11:19:36.774] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:96:0) after 4m39s
[11:24:15.832] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:96:0)
[11:24:15.847] Build status: STARTED
[11:24:15.847] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:97:0) after 4m24s
[11:28:39.914] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:97:0)
[11:28:39.931] Build status: STARTED
[11:28:39.931] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:98:0) after 6m39s
[11:35:18.947] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:98:0)
[11:35:18.970] Build status: STARTED
[11:35:18.970] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:99:0) after 9m49s
[11:45:07.988] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:99:0)
[11:45:08.004] Build status: STARTED
[11:45:08.004] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:100:0) after 1m14s
[11:46:21.991] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:100:0)
[11:46:22.008] Build status: STARTED
[11:46:22.008] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:101:0) after 5m35s
[11:51:57.026] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:101:0)
[11:51:57.050] Build status: STARTED
[11:51:57.050] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:102:0) after 6m30s
[11:58:27.068] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:102:0)
[11:58:27.083] Build status: STARTED
[11:58:27.083] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:103:0) after 2m27s
[12:00:54.103] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:103:0)
[12:00:54.174] Build status: STARTED
[12:00:54.174] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:104:0) after 5m41s
[12:06:35.193] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:104:0)
[12:06:35.255] Build status: STARTED
[12:06:35.255] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:105:0) after 2m56s
[12:09:31.281] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:105:0)
[12:09:31.307] Build status: STARTED
[12:09:31.307] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:106:0) after 5m58s
[12:15:29.370] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:106:0)
[12:15:29.390] Build status: STARTED
[12:15:29.390] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:107:0) after 7m57s
[12:23:26.543] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:107:0)
[12:23:26.644] Build status: STARTED
[12:23:26.644] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:108:0) after 2m21s
[12:25:47.661] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:108:0)
[12:25:47.680] Build status: STARTED
[12:25:47.680] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:109:0) after 4m4s
[12:29:51.697] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:109:0)
[12:29:51.712] Build status: STARTED
[12:29:51.712] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:110:0) after 7m33s
[12:37:24.763] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:110:0)
[12:37:24.784] Build status: STARTED
[12:37:24.785] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:111:0) after 4m31s
[12:41:55.803] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:111:0)
[12:41:55.847] Build status: STARTED
[12:41:55.847] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:112:0) after 3m14s
[12:45:09.864] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:112:0)
[12:45:09.885] Build status: STARTED
[12:45:09.885] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:113:0) after 8m43s
[12:53:52.989] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:113:0)
[12:53:53.008] Build status: STARTED
[12:53:53.009] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:114:0) after 3m47s
[12:57:40.079] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:114:0)
[12:57:40.105] Build status: STARTED
[12:57:40.105] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:115:0) after 4m44s
[13:02:24.179] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:115:0)
[13:02:24.195] Build status: STARTED
[13:02:24.195] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:116:0) after 3m30s
[13:05:54.314] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:116:0)
[13:05:54.331] Build status: STARTED
[13:05:54.331] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:117:0) after 8m6s
[13:14:00.348] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:117:0)
[13:14:00.366] Build status: STARTED
[13:14:00.366] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:118:0) after 6m14s
[13:20:14.382] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:118:0)
[13:20:14.424] Build status: STARTED
[13:20:14.424] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:119:0) after 1m44s
[13:21:58.445] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:119:0)
[13:21:58.462] Build status: STARTED
[13:21:58.462] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:120:0) after 7m37s
[13:29:35.488] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:120:0)
[13:29:35.507] Build status: STARTED
[13:29:35.507] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:121:0) after 6m42s
[13:36:17.529] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:121:0)
[13:36:17.546] Build status: STARTED
[13:36:17.547] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:122:0) after 2m31s
[13:38:48.894] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:122:0)
[13:38:48.913] Build status: STARTED
[13:38:48.913] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:123:0) after 8m13s
[13:47:02.204] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:123:0)
[13:47:02.222] Build status: STARTED
[13:47:02.222] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:124:0) after 6m40s
[13:53:42.374] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:124:0)
[13:53:42.430] Build status: STARTED
[13:53:42.430] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:125:0) after 5m4s
[13:58:46.481] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:125:0)
[13:58:46.498] Build status: STARTED
[13:58:46.498] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:126:0) after 2m7s
[14:00:53.620] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:126:0)
[14:00:53.654] Build status: STARTED
[14:00:53.654] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:127:0) after 5m33s
[14:06:26.734] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:127:0)
[14:06:26.750] Build status: STARTED
[14:06:26.750] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:128:0) after 3m24s
[14:09:51.083] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:128:0)
[14:09:51.192] Build status: STARTED
[14:09:51.192] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:129:0) after 8m20s
[14:18:11.316] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:129:0)
[14:18:11.337] Build status: STARTED
[14:18:11.337] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:130:0) after 2m20s
[14:20:31.597] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:130:0)
[14:20:31.617] Build status: STARTED
[14:20:31.617] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:131:0) after 5m32s
[14:26:03.801] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:131:0)
[14:26:03.821] Build status: STARTED
[14:26:03.821] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:132:0) after 9m50s
[14:35:53.880] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:132:0)
[14:35:53.901] Build status: STARTED
[14:35:53.901] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:133:0) after 9m32s
[14:45:25.921] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:133:0)
[14:45:25.961] Build status: STARTED
[14:45:25.961] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:134:0) after 8m44s
[14:54:09.982] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:134:0)
[14:54:09.998] Build status: STARTED
[14:54:09.998] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:135:0) after 7m2s
[15:01:12.276] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:135:0)
[15:01:12.320] Build status: STARTED
[15:01:12.320] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:136:0) after 7m42s
[15:08:54.446] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:136:0)
[15:08:54.467] Build status: STARTED
[15:08:54.467] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:137:0) after 1m5s
[15:09:59.487] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:137:0)
[15:09:59.513] Build status: STARTED
[15:09:59.513] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:138:0) after 6m33s
[15:16:32.896] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:138:0)
[15:16:32.914] Build status: STARTED
[15:16:32.914] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:139:0) after 8m11s
[15:24:43.987] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:139:0)
[15:24:44.008] Build status: STARTED
[15:24:44.008] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:140:0) after 7m54s
[15:32:38.025] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:140:0)
[15:32:38.116] Build status: STARTED
[15:32:38.116] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:141:0) after 4m25s
[15:37:03.206] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:141:0)
[15:37:03.222] Build status: STARTED
[15:37:03.222] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:142:0) after 8m20s
[15:45:23.296] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:142:0)
[15:45:23.313] Build status: STARTED
[15:45:23.313] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:143:0) after 7m44s
[15:53:07.313] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:143:0)
[15:53:07.328] Build status: STARTED
[15:53:07.328] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:144:0) after 6m31s
[15:59:38.349] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:144:0)
[15:59:38.364] Build status: STARTED
[15:59:38.364] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:145:0) after 6m44s
[16:06:22.399] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:145:0)
[16:06:22.418] Build status: STARTED
[16:06:22.418] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:146:0) after 4m19s
[16:10:41.578] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:146:0)
[16:10:41.596] Build status: STARTED
[16:10:41.596] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:147:0) after 1m1s
[16:11:42.857] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:147:0)
[16:11:42.884] Build status: STARTED
[16:11:42.884] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:148:0) after 3m51s
[16:15:33.943] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:148:0)
[16:15:34.014] Build status: STARTED
[16:15:34.014] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:149:0) after 7m31s
[16:23:05.343] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:149:0)
[16:23:05.363] Build status: STARTED
[16:23:05.363] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:150:0) after 2m0s
[16:25:05.344] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:150:0)
[16:25:05.361] Build status: STARTED
[16:25:05.361] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:151:0) after 1m34s
[16:26:39.410] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:151:0)
[16:26:39.493] Build status: STARTED
[16:26:39.493] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:152:0) after 3m44s
[16:30:23.524] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:152:0)
[16:30:23.543] Build status: STARTED
[16:30:23.543] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:153:0) after 8m51s
[16:39:14.583] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:153:0)
[16:39:14.606] Build status: STARTED
[16:39:14.607] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:154:0) after 2m56s
[16:42:10.771] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:154:0)
[16:42:10.869] Build status: STARTED
[16:42:10.869] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:155:0) after 7m52s
[16:50:02.899] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:155:0)
[16:50:02.919] Build status: STARTED
[16:50:02.919] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:156:0) after 8m47s
[16:58:50.326] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:156:0)
[16:58:50.367] Build status: STARTED
[16:58:50.367] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:157:0) after 5m48s
[17:04:38.629] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:157:0)
[17:04:38.646] Build status: STARTED
[17:04:38.646] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:158:0) after 9m43s
[17:14:21.705] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:158:0)
[17:14:21.726] Build status: STARTED
[17:14:21.727] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:159:0) after 3m20s
[17:17:41.750] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:159:0)
[17:17:41.774] Build status: STARTED
[17:17:41.774] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:160:0) after 2m54s
[17:20:35.924] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:160:0)
[17:20:35.942] Build status: STARTED
[17:20:35.942] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:161:0) after 2m8s
[17:22:43.962] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:161:0)
[17:22:43.981] Build status: STARTED
[17:22:43.981] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:162:0) after 4m18s
[17:27:02.044] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:162:0)
[17:27:02.062] Build status: STARTED
[17:27:02.062] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:163:0) after 8m26s
[17:35:28.081] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:163:0)
[17:35:28.100] Build status: STARTED
[17:35:28.100] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:164:0) after 3m25s
[17:38:53.130] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:164:0)
[17:38:53.211] Build status: STARTED
[17:38:53.211] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:165:0) after 2m17s
[17:41:10.410] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:165:0)
[17:41:10.472] Build status: STARTED
[17:41:10.472] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:166:0) after 8m24s
[17:49:34.492] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:166:0)
[17:49:34.508] Build status: STARTED
[17:49:34.508] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:167:0) after 7m16s
[17:56:50.576] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:167:0)
[17:56:50.621] Build status: STARTED
[17:56:50.621] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:168:0) after 8m10s
[18:05:00.648] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:168:0)
[18:05:00.670] Build status: STARTED
[18:05:00.670] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:169:0) after 2m51s
[18:07:51.689] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:169:0)
[18:07:51.704] Build status: STARTED
[18:07:51.704] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:170:0) after 1m30s
[18:09:21.723] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:170:0)
[18:09:21.741] Build status: STARTED
[18:09:21.741] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8894585065206519728:171:0) after 5m4s
[18:10:36.989] Received PubSub notification, asking Buildbucket for the build status
[18:10:37.014] Build:
{
"id": "8729155003260658897",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17884,
"createdBy": "project:chromium",
"createTime": "2024-12-08T02:48:11.669315566Z",
"startTime": "2024-12-08T03:32:47.865147388Z",
"endTime": "2024-12-08T18:10:36.582081261Z",
"updateTime": "2024-12-08T18:10:36.582081261Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b22e71bd91cf933b293139adc052e191288a9bcc",
"ref": "refs/heads/main"
}
}
}
[18:10:37.014] Invocation finished in 15h22m26.584552433s with status FAILED