[20:18:06.783] New invocation is queued and will start shortly
[20:18:07.890] Starting the invocation (attempt 1)
[20:18:07.921] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc41a98978bb937773a12b63042661dd73202b9f"
[20:18:07.921] Popped gitiles commit info from properties and tags
[20:18:07.921] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:18:07.922] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:18:07.922] Buildbucket request:
{
"requestId": "8970801654457750432",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dc41a98978bb937773a12b63042661dd73202b9f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8970801654457750432"
},
{
"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"
}
}
[20:18:08.472] Scheduled build:
{
"id": "8805371592557823681",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 9893,
"createdBy": "project:chromium",
"createTime": "2022-08-19T20:18:07.972962781Z",
"updateTime": "2022-08-19T20:18:07.972962781Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dc41a98978bb937773a12b63042661dd73202b9f",
"ref": "refs/heads/main"
}
}
}
[20:18:08.472] Task URL: https://cr-buildbucket.appspot.com/build/8805371592557823681
[20:18:08.472] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:2:0) after 6m30s
[20:23:14.801] Received PubSub notification, asking Buildbucket for the build status
[20:23:14.819] Build status: STARTED
[20:24:38.669] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:2:0)
[20:24:38.690] Build status: STARTED
[20:24:38.690] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:4:0) after 1m10s
[20:25:48.709] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:4:0)
[20:25:48.732] Build status: STARTED
[20:25:48.732] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:5:0) after 4m58s
[20:30:47.398] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:5:0)
[20:30:47.418] Build status: STARTED
[20:30:47.418] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:6:0) after 2m55s
[20:33:42.462] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:6:0)
[20:33:42.483] Build status: STARTED
[20:33:42.483] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:7:0) after 5m6s
[20:38:48.502] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:7:0)
[20:38:48.530] Build status: STARTED
[20:38:48.530] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:8:0) after 5m43s
[20:44:31.804] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:8:0)
[20:44:31.824] Build status: STARTED
[20:44:31.824] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:9:0) after 2m25s
[20:46:56.954] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:9:0)
[20:46:56.972] Build status: STARTED
[20:46:56.972] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:10:0) after 6m18s
[20:53:15.041] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:10:0)
[20:53:15.061] Build status: STARTED
[20:53:15.061] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:11:0) after 3m7s
[20:56:22.379] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:11:0)
[20:56:22.399] Build status: STARTED
[20:56:22.400] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:12:0) after 2m21s
[20:58:43.952] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:12:0)
[20:58:43.975] Build status: STARTED
[20:58:43.975] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:13:0) after 2m55s
[21:01:39.000] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:13:0)
[21:01:39.022] Build status: STARTED
[21:01:39.022] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:14:0) after 5m6s
[21:06:45.281] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:14:0)
[21:06:45.301] Build status: STARTED
[21:06:45.302] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:15:0) after 3m44s
[21:10:29.776] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:15:0)
[21:10:29.797] Build status: STARTED
[21:10:29.797] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:16:0) after 2m14s
[21:12:43.862] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:16:0)
[21:12:43.884] Build status: STARTED
[21:12:43.884] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:17:0) after 4m27s
[21:17:11.216] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:17:0)
[21:17:11.239] Build status: STARTED
[21:17:11.239] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:18:0) after 4m14s
[21:21:25.262] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:18:0)
[21:21:25.318] Build status: STARTED
[21:21:25.318] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:19:0) after 4m3s
[21:25:28.332] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:19:0)
[21:25:28.353] Build status: STARTED
[21:25:28.353] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:20:0) after 5m3s
[21:30:31.394] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:20:0)
[21:30:31.416] Build status: STARTED
[21:30:31.416] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:21:0) after 3m35s
[21:34:06.587] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:21:0)
[21:34:06.631] Build status: STARTED
[21:34:06.631] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:22:0) after 1m34s
[21:35:41.034] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:22:0)
[21:35:41.076] Build status: STARTED
[21:35:41.076] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:23:0) after 5m36s
[21:41:17.590] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:23:0)
[21:41:17.625] Build status: STARTED
[21:41:17.625] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:24:0) after 2m45s
[21:44:02.701] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:24:0)
[21:44:02.735] Build status: STARTED
[21:44:02.735] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:25:0) after 7m3s
[21:51:05.819] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:25:0)
[21:51:05.844] Build status: STARTED
[21:51:05.844] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:26:0) after 4m51s
[21:55:57.206] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:26:0)
[21:55:57.229] Build status: STARTED
[21:55:57.229] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:27:0) after 7m37s
[22:03:34.250] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:27:0)
[22:03:34.307] Build status: STARTED
[22:03:34.307] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:28:0) after 2m27s
[22:06:01.613] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:28:0)
[22:06:01.637] Build status: STARTED
[22:06:01.637] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:29:0) after 2m2s
[22:08:03.841] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:29:0)
[22:08:03.872] Build status: STARTED
[22:08:03.872] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:30:0) after 6m13s
[22:14:16.975] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:30:0)
[22:14:16.995] Build status: STARTED
[22:14:16.995] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:31:0) after 4m2s
[22:18:19.032] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:31:0)
[22:18:19.067] Build status: STARTED
[22:18:19.067] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:32:0) after 9m13s
[22:27:32.361] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:32:0)
[22:27:32.383] Build status: STARTED
[22:27:32.383] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:33:0) after 1m52s
[22:29:24.609] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:33:0)
[22:29:24.642] Build status: STARTED
[22:29:24.642] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:34:0) after 1m47s
[22:31:11.780] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:34:0)
[22:31:11.821] Build status: STARTED
[22:31:11.821] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:35:0) after 8m24s
[22:39:35.991] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:35:0)
[22:39:36.017] Build status: STARTED
[22:39:36.017] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:36:0) after 3m33s
[22:43:09.040] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:36:0)
[22:43:09.062] Build status: STARTED
[22:43:09.062] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:37:0) after 1m23s
[22:44:32.387] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:37:0)
[22:44:32.409] Build status: STARTED
[22:44:32.409] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:38:0) after 7m44s
[22:52:16.447] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:38:0)
[22:52:16.471] Build status: STARTED
[22:52:16.471] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:39:0) after 3m2s
[22:55:18.542] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:39:0)
[22:55:18.562] Build status: STARTED
[22:55:18.562] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:40:0) after 1m32s
[22:56:50.662] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:40:0)
[22:56:50.688] Build status: STARTED
[22:56:50.688] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:41:0) after 7m14s
[23:04:04.855] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:41:0)
[23:04:04.885] Build status: STARTED
[23:04:04.885] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:42:0) after 3m5s
[23:07:09.939] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:42:0)
[23:07:09.960] Build status: STARTED
[23:07:09.960] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:43:0) after 7m13s
[23:14:23.077] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:43:0)
[23:14:23.103] Build status: STARTED
[23:14:23.103] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:44:0) after 3m17s
[23:17:40.139] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:44:0)
[23:17:40.161] Build status: STARTED
[23:17:40.161] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:45:0) after 4m54s
[23:22:34.357] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:45:0)
[23:22:34.380] Build status: STARTED
[23:22:34.380] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:46:0) after 9m52s
[23:32:26.372] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:46:0)
[23:32:26.395] Build status: STARTED
[23:32:26.395] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:47:0) after 3m36s
[23:36:02.517] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:47:0)
[23:36:02.544] Build status: STARTED
[23:36:02.544] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:48:0) after 7m56s
[23:43:58.715] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:48:0)
[23:43:58.735] Build status: STARTED
[23:43:58.735] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:49:0) after 8m6s
[23:52:04.986] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:49:0)
[23:52:05.049] Build status: STARTED
[23:52:05.049] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:50:0) after 3m28s
[23:55:33.193] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:50:0)
[23:55:33.212] Build status: STARTED
[23:55:33.212] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:51:0) after 9m53s
[00:05:26.350] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:51:0)
[00:05:26.374] Build status: STARTED
[00:05:26.374] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:52:0) after 5m22s
[00:10:48.556] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:52:0)
[00:10:48.644] Build status: STARTED
[00:10:48.645] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:53:0) after 5m6s
[00:15:54.913] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:53:0)
[00:15:54.942] Build status: STARTED
[00:15:54.942] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:54:0) after 9m45s
[00:25:40.114] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:54:0)
[00:25:40.139] Build status: STARTED
[00:25:40.139] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:55:0) after 4m5s
[00:29:45.821] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:55:0)
[00:29:45.854] Build status: STARTED
[00:29:45.854] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:56:0) after 9m13s
[00:38:59.014] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:56:0)
[00:38:59.042] Build status: STARTED
[00:38:59.042] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:57:0) after 8m49s
[00:47:48.201] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:57:0)
[00:47:48.227] Build status: STARTED
[00:47:48.227] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:58:0) after 1m28s
[00:49:16.391] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:58:0)
[00:49:16.412] Build status: STARTED
[00:49:16.412] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:59:0) after 8m23s
[00:57:39.532] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:59:0)
[00:57:39.555] Build status: STARTED
[00:57:39.555] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:60:0) after 9m37s
[01:07:16.590] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:60:0)
[01:07:16.614] Build status: STARTED
[01:07:16.614] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:61:0) after 3m37s
[01:10:53.745] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:61:0)
[01:10:53.775] Build status: STARTED
[01:10:53.775] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:62:0) after 1m18s
[01:12:11.793] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:62:0)
[01:12:11.818] Build status: STARTED
[01:12:11.818] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:63:0) after 5m25s
[01:17:36.843] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:63:0)
[01:17:36.879] Build status: STARTED
[01:17:36.879] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:64:0) after 1m58s
[01:19:35.262] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:64:0)
[01:19:35.289] Build status: STARTED
[01:19:35.289] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:65:0) after 1m6s
[01:20:41.371] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:65:0)
[01:20:41.403] Build status: STARTED
[01:20:41.403] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:66:0) after 4m16s
[01:24:58.153] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:66:0)
[01:24:58.179] Build status: STARTED
[01:24:58.179] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:67:0) after 7m7s
[01:32:05.406] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:67:0)
[01:32:05.432] Build status: STARTED
[01:32:05.432] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:68:0) after 9m44s
[01:41:49.464] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:68:0)
[01:41:49.490] Build status: STARTED
[01:41:49.490] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:69:0) after 3m46s
[01:45:35.524] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:69:0)
[01:45:35.556] Build status: STARTED
[01:45:35.556] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:70:0) after 8m3s
[01:53:38.588] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:70:0)
[01:53:38.626] Build status: STARTED
[01:53:38.626] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:71:0) after 4m6s
[01:57:44.770] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:71:0)
[01:57:44.805] Build status: STARTED
[01:57:44.805] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:72:0) after 7m52s
[02:05:36.850] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:72:0)
[02:05:36.873] Build status: STARTED
[02:05:36.874] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:73:0) after 6m29s
[02:12:06.137] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:73:0)
[02:12:06.163] Build status: STARTED
[02:12:06.163] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:74:0) after 3m52s
[02:15:58.287] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:74:0)
[02:15:58.315] Build status: STARTED
[02:15:58.315] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:75:0) after 5m13s
[02:21:11.428] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:75:0)
[02:21:11.455] Build status: STARTED
[02:21:11.455] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:76:0) after 6m1s
[02:27:12.490] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:76:0)
[02:27:12.511] Build status: STARTED
[02:27:12.511] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:77:0) after 5m7s
[02:32:19.538] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:77:0)
[02:32:19.560] Build status: STARTED
[02:32:19.560] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:78:0) after 7m54s
[02:40:13.684] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:78:0)
[02:40:13.707] Build status: STARTED
[02:40:13.707] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:79:0) after 3m27s
[02:43:40.902] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:79:0)
[02:43:40.992] Build status: STARTED
[02:43:40.992] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:80:0) after 7m25s
[02:51:06.117] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:80:0)
[02:51:06.146] Build status: STARTED
[02:51:06.146] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:81:0) after 4m31s
[02:55:37.395] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:81:0)
[02:55:37.422] Build status: STARTED
[02:55:37.422] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:82:0) after 2m11s
[02:57:48.736] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:82:0)
[02:57:48.757] Build status: STARTED
[02:57:48.757] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:83:0) after 7m19s
[03:05:07.870] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:83:0)
[03:05:07.952] Build status: STARTED
[03:05:07.953] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:84:0) after 9m28s
[03:14:36.091] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:84:0)
[03:14:36.112] Build status: STARTED
[03:14:36.112] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:85:0) after 9m15s
[03:23:51.234] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:85:0)
[03:23:51.285] Build status: STARTED
[03:23:51.285] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:86:0) after 1m35s
[03:25:26.693] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:86:0)
[03:25:26.714] Build status: STARTED
[03:25:26.714] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:87:0) after 1m27s
[03:26:53.770] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:87:0)
[03:26:53.842] Build status: STARTED
[03:26:53.842] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:88:0) after 1m0s
[03:27:53.873] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:88:0)
[03:27:53.899] Build status: STARTED
[03:27:53.899] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:89:0) after 5m40s
[03:33:34.116] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:89:0)
[03:33:34.138] Build status: STARTED
[03:33:34.138] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:90:0) after 6m43s
[03:40:17.169] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:90:0)
[03:40:17.194] Build status: STARTED
[03:40:17.194] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:91:0) after 9m58s
[03:50:15.215] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:91:0)
[03:50:15.236] Build status: STARTED
[03:50:15.236] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:92:0) after 8m5s
[03:58:20.273] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:92:0)
[03:58:20.297] Build status: STARTED
[03:58:20.297] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:93:0) after 2m22s
[04:00:42.293] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:93:0)
[04:00:42.314] Build status: STARTED
[04:00:42.314] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:94:0) after 3m57s
[04:04:39.369] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:94:0)
[04:04:39.393] Build status: STARTED
[04:04:39.393] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:95:0) after 4m48s
[04:09:27.417] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:95:0)
[04:09:27.441] Build status: STARTED
[04:09:27.441] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:96:0) after 9m16s
[04:18:43.733] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:96:0)
[04:18:43.762] Build status: STARTED
[04:18:43.762] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:97:0) after 2m1s
[04:20:44.851] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:97:0)
[04:20:44.880] Build status: STARTED
[04:20:44.880] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:98:0) after 4m19s
[04:25:03.925] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:98:0)
[04:25:03.948] Build status: STARTED
[04:25:03.948] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:99:0) after 4m11s
[04:29:15.076] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:99:0)
[04:29:15.101] Build status: STARTED
[04:29:15.101] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:100:0) after 6m15s
[04:35:30.194] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:100:0)
[04:35:30.216] Build status: STARTED
[04:35:30.216] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:101:0) after 7m48s
[04:43:18.240] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:101:0)
[04:43:18.262] Build status: STARTED
[04:43:18.262] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:102:0) after 6m31s
[04:49:49.295] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:102:0)
[04:49:49.319] Build status: STARTED
[04:49:49.319] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:103:0) after 1m38s
[04:51:27.465] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:103:0)
[04:51:27.498] Build status: STARTED
[04:51:27.498] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:104:0) after 3m7s
[04:54:34.534] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:104:0)
[04:54:34.560] Build status: STARTED
[04:54:34.560] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:105:0) after 3m11s
[04:57:45.667] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:105:0)
[04:57:45.690] Build status: STARTED
[04:57:45.690] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:106:0) after 3m52s
[05:01:37.720] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:106:0)
[05:01:37.750] Build status: STARTED
[05:01:37.750] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:107:0) after 7m17s
[05:08:54.796] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:107:0)
[05:08:54.870] Build status: STARTED
[05:08:54.870] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:108:0) after 5m0s
[05:13:54.958] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:108:0)
[05:13:54.985] Build status: STARTED
[05:13:54.985] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:109:0) after 2m49s
[05:16:44.016] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:109:0)
[05:16:44.038] Build status: STARTED
[05:16:44.038] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:110:0) after 9m9s
[05:25:53.271] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:110:0)
[05:25:53.296] Build status: STARTED
[05:25:53.296] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:111:0) after 5m1s
[05:30:54.665] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:111:0)
[05:30:54.687] Build status: STARTED
[05:30:54.687] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:112:0) after 1m28s
[05:32:22.717] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:112:0)
[05:32:22.740] Build status: STARTED
[05:32:22.740] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:113:0) after 2m56s
[05:35:18.765] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:113:0)
[05:35:18.787] Build status: STARTED
[05:35:18.787] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:114:0) after 1m12s
[05:36:30.943] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:114:0)
[05:36:31.022] Build status: STARTED
[05:36:31.022] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:115:0) after 4m29s
[05:41:00.059] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:115:0)
[05:41:00.099] Build status: STARTED
[05:41:00.099] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:116:0) after 5m51s
[05:46:51.124] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:116:0)
[05:46:51.150] Build status: STARTED
[05:46:51.150] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:117:0) after 9m50s
[05:56:41.191] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:117:0)
[05:56:41.213] Build status: STARTED
[05:56:41.213] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:118:0) after 4m34s
[06:01:15.253] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:118:0)
[06:01:15.278] Build status: STARTED
[06:01:15.278] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:119:0) after 5m47s
[06:07:02.302] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:119:0)
[06:07:02.327] Build status: STARTED
[06:07:02.327] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8970801654457750432:120:0) after 4m17s
[06:10:12.128] Received PubSub notification, asking Buildbucket for the build status
[06:10:12.157] Build:
{
"id": "8805371592557823681",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 9893,
"createdBy": "project:chromium",
"createTime": "2022-08-19T20:18:07.972962781Z",
"startTime": "2022-08-19T20:23:12.569139Z",
"endTime": "2022-08-20T06:10:10.874322145Z",
"updateTime": "2022-08-20T06:10:10.874322145Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dc41a98978bb937773a12b63042661dd73202b9f",
"ref": "refs/heads/main"
}
}
}
[06:10:12.158] Invocation finished in 9h52m5.381352245s with status FAILED