[06:17:24.283] New invocation is queued and will start shortly
[06:17:25.425] Starting the invocation (attempt 1)
[06:17:25.449] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f5bf3948a42aad3fcd69a96bd0256f7c83c62553"
[06:17:25.450] Popped gitiles commit info from properties and tags
[06:17:25.450] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:17:25.451] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:17:25.451] Buildbucket request:
{
"requestId": "8898376974137703648",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f5bf3948a42aad3fcd69a96bd0256f7c83c62553",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898376974137703648"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan(dll)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:17:25.937] Scheduled build:
{
"id": "8732946912192829169",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"number": 23332,
"createdBy": "project:chromium",
"createTime": "2024-10-27T06:17:25.510896156Z",
"updateTime": "2024-10-27T06:17:25.510896156Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f5bf3948a42aad3fcd69a96bd0256f7c83c62553",
"ref": "refs/heads/main"
}
}
}
[06:17:25.937] Task URL: https://cr-buildbucket.appspot.com/build/8732946912192829169
[06:17:25.937] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:2:0) after 4m46s
[06:17:26.907] Received PubSub notification, asking Buildbucket for the build status
[06:17:26.925] Build status: SCHEDULED
[06:19:22.704] Received PubSub notification, asking Buildbucket for the build status
[06:19:22.722] Build status: STARTED
[06:22:12.822] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:2:0)
[06:22:12.840] Build status: STARTED
[06:22:12.840] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:5:0) after 9m8s
[06:31:20.855] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:5:0)
[06:31:20.872] Build status: STARTED
[06:31:20.872] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:6:0) after 8m41s
[06:40:01.892] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:6:0)
[06:40:01.914] Build status: STARTED
[06:40:01.914] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:7:0) after 7m47s
[06:47:48.929] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:7:0)
[06:47:48.949] Build status: STARTED
[06:47:48.949] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:8:0) after 6m40s
[06:54:28.965] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:8:0)
[06:54:29.022] Build status: STARTED
[06:54:29.022] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:9:0) after 8m45s
[07:03:14.049] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:9:0)
[07:03:14.072] Build status: STARTED
[07:03:14.072] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:10:0) after 8m15s
[07:11:29.137] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:10:0)
[07:11:31.177] Build status: STARTED
[07:11:31.177] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:11:0) after 6m56s
[07:18:27.241] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:11:0)
[07:18:27.256] Build status: STARTED
[07:18:27.256] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:12:0) after 9m17s
[07:27:44.370] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:12:0)
[07:27:44.547] Build status: STARTED
[07:27:44.547] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:13:0) after 3m26s
[07:31:10.617] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:13:0)
[07:31:10.638] Build status: STARTED
[07:31:10.639] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:14:0) after 6m54s
[07:38:04.795] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:14:0)
[07:38:04.814] Build status: STARTED
[07:38:04.814] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:15:0) after 4m9s
[07:42:13.930] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:15:0)
[07:42:13.950] Build status: STARTED
[07:42:13.950] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:16:0) after 3m43s
[07:45:56.979] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:16:0)
[07:45:57.012] Build status: STARTED
[07:45:57.012] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:17:0) after 1m35s
[07:47:32.144] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:17:0)
[07:47:32.189] Build status: STARTED
[07:47:32.190] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:18:0) after 4m38s
[07:52:10.253] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:18:0)
[07:52:10.277] Build status: STARTED
[07:52:10.277] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:19:0) after 3m12s
[07:55:22.520] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:19:0)
[07:55:22.542] Build status: STARTED
[07:55:22.542] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:20:0) after 6m58s
[08:02:20.557] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:20:0)
[08:02:20.575] Build status: STARTED
[08:02:20.575] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:21:0) after 5m42s
[08:08:02.752] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:21:0)
[08:08:02.792] Build status: STARTED
[08:08:02.792] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:22:0) after 2m52s
[08:10:54.906] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:22:0)
[08:10:55.119] Build status: STARTED
[08:10:55.119] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:23:0) after 5m50s
[08:16:45.332] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:23:0)
[08:16:45.417] Build status: STARTED
[08:16:45.417] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:24:0) after 7m32s
[08:24:17.612] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:24:0)
[08:24:17.632] Build status: STARTED
[08:24:17.632] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:25:0) after 4m7s
[08:28:24.740] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:25:0)
[08:28:24.758] Build status: STARTED
[08:28:24.758] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:26:0) after 7m16s
[08:35:40.823] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:26:0)
[08:35:40.846] Build status: STARTED
[08:35:40.846] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:27:0) after 6m55s
[08:42:35.861] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:27:0)
[08:42:35.882] Build status: STARTED
[08:42:35.882] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:28:0) after 4m21s
[08:46:56.907] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:28:0)
[08:46:56.923] Build status: STARTED
[08:46:56.923] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:29:0) after 9m48s
[08:56:44.944] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:29:0)
[08:56:44.963] Build status: STARTED
[08:56:44.963] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:30:0) after 1m46s
[08:58:30.980] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:30:0)
[08:58:31.001] Build status: STARTED
[08:58:31.001] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:31:0) after 9m25s
[09:07:56.340] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:31:0)
[09:07:56.445] Build status: STARTED
[09:07:56.445] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:32:0) after 6m13s
[09:14:09.467] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:32:0)
[09:14:09.482] Build status: STARTED
[09:14:09.482] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:33:0) after 8m58s
[09:23:07.861] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:33:0)
[09:23:07.887] Build status: STARTED
[09:23:07.887] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:34:0) after 2m57s
[09:26:05.023] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:34:0)
[09:26:05.040] Build status: STARTED
[09:26:05.040] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:35:0) after 1m13s
[09:27:18.057] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:35:0)
[09:27:18.082] Build status: STARTED
[09:27:18.082] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:36:0) after 1m15s
[09:28:33.214] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:36:0)
[09:28:33.232] Build status: STARTED
[09:28:33.232] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:37:0) after 4m8s
[09:32:41.476] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:37:0)
[09:32:41.512] Build status: STARTED
[09:32:41.512] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:38:0) after 1m54s
[09:34:35.618] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:38:0)
[09:34:35.635] Build status: STARTED
[09:34:35.635] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:39:0) after 5m52s
[09:40:27.640] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:39:0)
[09:40:27.658] Build status: STARTED
[09:40:27.658] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:40:0) after 2m48s
[09:43:15.810] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:40:0)
[09:43:15.833] Build status: STARTED
[09:43:15.833] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:41:0) after 8m5s
[09:51:20.973] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:41:0)
[09:51:21.047] Build status: STARTED
[09:51:21.047] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:42:0) after 4m37s
[09:55:58.284] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:42:0)
[09:55:58.301] Build status: STARTED
[09:55:58.302] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:43:0) after 8m28s
[10:04:26.321] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:43:0)
[10:04:26.346] Build status: STARTED
[10:04:26.346] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:44:0) after 9m14s
[10:13:40.470] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:44:0)
[10:13:40.486] Build status: STARTED
[10:13:40.486] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:45:0) after 1m19s
[10:14:59.545] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:45:0)
[10:14:59.570] Build status: STARTED
[10:14:59.570] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:46:0) after 1m4s
[10:16:03.589] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:46:0)
[10:16:03.605] Build status: STARTED
[10:16:03.605] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:47:0) after 6m51s
[10:22:54.623] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:47:0)
[10:22:54.640] Build status: STARTED
[10:22:54.640] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:48:0) after 1m5s
[10:23:59.657] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:48:0)
[10:23:59.679] Build status: STARTED
[10:23:59.679] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:49:0) after 9m7s
[10:33:06.700] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:49:0)
[10:33:06.720] Build status: STARTED
[10:33:06.720] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:50:0) after 4m59s
[10:38:05.738] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:50:0)
[10:38:05.757] Build status: STARTED
[10:38:05.758] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:51:0) after 1m55s
[10:40:00.923] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:51:0)
[10:40:00.993] Build status: STARTED
[10:40:00.993] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:52:0) after 1m11s
[10:41:12.016] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:52:0)
[10:41:12.034] Build status: STARTED
[10:41:12.034] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:53:0) after 3m1s
[10:44:13.177] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:53:0)
[10:44:13.201] Build status: STARTED
[10:44:13.201] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:54:0) after 7m30s
[10:51:43.332] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:54:0)
[10:51:43.353] Build status: STARTED
[10:51:43.353] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:55:0) after 5m24s
[10:57:07.376] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:55:0)
[10:57:07.414] Build status: STARTED
[10:57:07.414] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:56:0) after 5m15s
[11:02:22.459] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:56:0)
[11:02:22.477] Build status: STARTED
[11:02:22.477] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:57:0) after 4m17s
[11:06:39.496] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:57:0)
[11:06:39.582] Build status: STARTED
[11:06:39.582] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:58:0) after 6m8s
[11:12:47.657] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:58:0)
[11:12:47.686] Build status: STARTED
[11:12:47.686] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:59:0) after 6m50s
[11:19:37.805] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:59:0)
[11:19:37.828] Build status: STARTED
[11:19:37.828] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:60:0) after 1m33s
[11:21:10.845] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:60:0)
[11:21:10.867] Build status: STARTED
[11:21:10.867] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:61:0) after 6m4s
[11:27:15.140] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:61:0)
[11:27:15.161] Build status: STARTED
[11:27:15.161] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:62:0) after 5m16s
[11:32:31.255] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:62:0)
[11:32:31.270] Build status: STARTED
[11:32:31.270] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan(dll):8898376974137703648:63:0) after 9m34s
[11:34:05.208] Received PubSub notification, asking Buildbucket for the build status
[11:34:05.226] Build:
{
"id": "8732946912192829169",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan(dll)"
},
"number": 23332,
"createdBy": "project:chromium",
"createTime": "2024-10-27T06:17:25.510896156Z",
"startTime": "2024-10-27T06:19:22.520439524Z",
"endTime": "2024-10-27T11:34:04.924113520Z",
"updateTime": "2024-10-27T11:34:04.924113520Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f5bf3948a42aad3fcd69a96bd0256f7c83c62553",
"ref": "refs/heads/main"
}
}
}
[11:34:05.226] Invocation finished in 5h16m40.953857966s with status FAILED