[01:57:57.190] New invocation is queued and will start shortly
[01:57:58.431] Starting the invocation (attempt 1)
[01:57:58.461] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f77ee002403d0e1689574141cc3a46566c92475"
[01:57:58.461] Popped gitiles commit info from properties and tags
[01:57:58.461] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:57:58.461] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:57:58.461] Buildbucket request:
{
"requestId": "8898755685276610896",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f77ee002403d0e1689574141cc3a46566c92475",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898755685276610896"
},
{
"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"
}
}
[01:57:59.006] Scheduled build:
{
"id": "8733325623225313841",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17261,
"createdBy": "project:chromium",
"createTime": "2024-10-23T01:57:58.525291877Z",
"updateTime": "2024-10-23T01:57:58.525291877Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f77ee002403d0e1689574141cc3a46566c92475",
"ref": "refs/heads/main"
}
}
}
[01:57:59.006] Task URL: https://cr-buildbucket.appspot.com/build/8733325623225313841
[01:57:59.006] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:2:0) after 1m49s
[01:58:00.014] Received PubSub notification, asking Buildbucket for the build status
[01:58:00.044] Build status: SCHEDULED
[01:59:48.038] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:2:0)
[01:59:48.067] Build status: SCHEDULED
[01:59:48.067] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:4:0) after 2m49s
[02:02:37.083] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:4:0)
[02:02:37.109] Build status: SCHEDULED
[02:02:37.109] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:5:0) after 8m32s
[02:11:09.554] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:5:0)
[02:11:09.588] Build status: SCHEDULED
[02:11:09.588] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:6:0) after 4m59s
[02:16:08.603] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:6:0)
[02:16:08.622] Build status: SCHEDULED
[02:16:08.622] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:7:0) after 8m6s
[02:24:14.800] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:7:0)
[02:24:14.820] Build status: SCHEDULED
[02:24:14.820] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:8:0) after 8m0s
[02:32:15.072] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:8:0)
[02:32:15.102] Build status: SCHEDULED
[02:32:15.102] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:9:0) after 6m7s
[02:34:01.290] Received PubSub notification, asking Buildbucket for the build status
[02:34:01.313] Build status: STARTED
[02:38:22.128] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:9:0)
[02:38:22.154] Build status: STARTED
[02:38:22.155] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:11:0) after 3m7s
[02:41:29.174] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:11:0)
[02:41:29.239] Build status: STARTED
[02:41:29.239] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:12:0) after 3m43s
[02:45:12.348] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:12:0)
[02:45:12.367] Build status: STARTED
[02:45:12.367] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:13:0) after 5m5s
[02:50:17.511] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:13:0)
[02:50:17.534] Build status: STARTED
[02:50:17.534] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:14:0) after 8m47s
[02:59:04.553] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:14:0)
[02:59:04.605] Build status: STARTED
[02:59:04.605] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:15:0) after 7m23s
[03:06:27.734] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:15:0)
[03:06:27.856] Build status: STARTED
[03:06:27.857] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:16:0) after 2m24s
[03:08:51.973] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:16:0)
[03:08:51.993] Build status: STARTED
[03:08:51.993] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:17:0) after 7m47s
[03:16:39.092] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:17:0)
[03:16:39.110] Build status: STARTED
[03:16:39.110] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:18:0) after 6m56s
[03:23:35.252] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:18:0)
[03:23:35.277] Build status: STARTED
[03:23:35.277] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:19:0) after 2m9s
[03:25:44.359] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:19:0)
[03:25:44.399] Build status: STARTED
[03:25:44.399] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:20:0) after 6m16s
[03:32:00.418] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:20:0)
[03:32:00.438] Build status: STARTED
[03:32:00.438] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:21:0) after 6m1s
[03:38:01.992] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:21:0)
[03:38:02.010] Build status: STARTED
[03:38:02.010] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:22:0) after 2m32s
[03:40:34.359] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:22:0)
[03:40:34.381] Build status: STARTED
[03:40:34.381] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:23:0) after 8m12s
[03:48:46.486] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:23:0)
[03:48:46.507] Build status: STARTED
[03:48:46.507] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:24:0) after 3m27s
[03:52:13.528] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:24:0)
[03:52:13.613] Build status: STARTED
[03:52:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:25:0) after 9m16s
[04:01:29.635] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:25:0)
[04:01:29.714] Build status: STARTED
[04:01:29.714] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:26:0) after 3m37s
[04:05:06.785] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:26:0)
[04:05:06.800] Build status: STARTED
[04:05:06.800] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:27:0) after 8m28s
[04:13:35.174] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:27:0)
[04:13:35.263] Build status: STARTED
[04:13:35.263] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:28:0) after 4m17s
[04:17:52.381] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:28:0)
[04:17:52.458] Build status: STARTED
[04:17:52.458] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:29:0) after 5m26s
[04:23:18.449] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:29:0)
[04:23:18.466] Build status: STARTED
[04:23:18.466] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:30:0) after 3m19s
[04:26:37.605] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:30:0)
[04:26:37.633] Build status: STARTED
[04:26:37.633] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:31:0) after 2m44s
[04:29:21.674] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:31:0)
[04:29:21.693] Build status: STARTED
[04:29:21.693] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:32:0) after 8m27s
[04:37:48.840] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:32:0)
[04:37:48.857] Build status: STARTED
[04:37:48.857] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:33:0) after 7m48s
[04:45:36.935] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:33:0)
[04:45:36.974] Build status: STARTED
[04:45:36.974] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:34:0) after 5m23s
[04:50:59.998] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:34:0)
[04:51:00.071] Build status: STARTED
[04:51:00.071] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:35:0) after 8m9s
[04:59:09.056] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:35:0)
[04:59:09.073] Build status: STARTED
[04:59:09.073] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:36:0) after 8m11s
[05:07:20.049] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:36:0)
[05:07:20.084] Build status: STARTED
[05:07:20.084] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:37:0) after 8m27s
[05:15:47.406] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:37:0)
[05:15:47.430] Build status: STARTED
[05:15:47.430] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:38:0) after 3m33s
[05:19:20.448] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:38:0)
[05:19:20.467] Build status: STARTED
[05:19:20.467] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:39:0) after 4m16s
[05:23:36.701] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:39:0)
[05:23:36.719] Build status: STARTED
[05:23:36.719] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:40:0) after 9m14s
[05:32:50.738] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:40:0)
[05:32:50.754] Build status: STARTED
[05:32:50.754] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:41:0) after 6m24s
[05:39:14.788] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:41:0)
[05:39:14.809] Build status: STARTED
[05:39:14.809] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:42:0) after 5m9s
[05:44:23.850] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:42:0)
[05:44:23.870] Build status: STARTED
[05:44:23.870] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:43:0) after 1m17s
[05:45:40.891] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:43:0)
[05:45:40.912] Build status: STARTED
[05:45:40.912] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:44:0) after 4m4s
[05:49:44.956] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:44:0)
[05:49:44.975] Build status: STARTED
[05:49:44.975] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:45:0) after 2m26s
[05:52:11.000] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:45:0)
[05:52:11.019] Build status: STARTED
[05:52:11.019] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:46:0) after 7m18s
[05:59:29.185] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:46:0)
[05:59:29.202] Build status: STARTED
[05:59:29.202] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:47:0) after 5m24s
[06:04:53.184] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:47:0)
[06:04:53.234] Build status: STARTED
[06:04:53.234] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:48:0) after 1m28s
[06:06:21.225] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:48:0)
[06:06:21.246] Build status: STARTED
[06:06:21.246] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:49:0) after 6m34s
[06:12:55.272] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:49:0)
[06:12:55.294] Build status: STARTED
[06:12:55.294] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:50:0) after 3m49s
[06:16:44.389] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:50:0)
[06:16:44.404] Build status: STARTED
[06:16:44.404] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:51:0) after 1m56s
[06:18:40.420] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:51:0)
[06:18:40.436] Build status: STARTED
[06:18:40.436] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:52:0) after 8m23s
[06:27:03.858] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:52:0)
[06:27:03.878] Build status: STARTED
[06:27:03.878] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:53:0) after 4m44s
[06:31:47.903] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:53:0)
[06:31:47.922] Build status: STARTED
[06:31:47.922] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:54:0) after 5m54s
[06:37:42.133] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:54:0)
[06:37:42.161] Build status: STARTED
[06:37:42.161] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:55:0) after 5m27s
[06:43:09.633] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:55:0)
[06:43:09.666] Build status: STARTED
[06:43:09.666] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:56:0) after 5m55s
[06:49:04.845] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:56:0)
[06:49:04.861] Build status: STARTED
[06:49:04.861] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:57:0) after 8m24s
[06:57:28.892] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:57:0)
[06:57:28.918] Build status: STARTED
[06:57:28.918] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:58:0) after 3m23s
[07:00:51.947] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:58:0)
[07:00:51.985] Build status: STARTED
[07:00:51.986] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:59:0) after 6m8s
[07:07:00.005] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:59:0)
[07:07:00.061] Build status: STARTED
[07:07:00.061] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:60:0) after 9m27s
[07:16:27.071] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:60:0)
[07:16:27.093] Build status: STARTED
[07:16:27.093] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:61:0) after 3m14s
[07:19:41.259] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:61:0)
[07:19:41.299] Build status: STARTED
[07:19:41.299] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:62:0) after 8m48s
[07:28:29.304] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:62:0)
[07:28:29.321] Build status: STARTED
[07:28:29.321] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:63:0) after 7m22s
[07:35:51.344] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:63:0)
[07:35:51.363] Build status: STARTED
[07:35:51.363] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:64:0) after 3m3s
[07:38:54.608] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:64:0)
[07:38:54.634] Build status: STARTED
[07:38:54.634] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:65:0) after 4m38s
[07:43:32.662] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:65:0)
[07:43:32.683] Build status: STARTED
[07:43:32.683] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:66:0) after 7m3s
[07:50:35.799] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:66:0)
[07:50:35.816] Build status: STARTED
[07:50:35.816] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:67:0) after 5m37s
[07:56:13.043] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:67:0)
[07:56:13.059] Build status: STARTED
[07:56:13.059] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:68:0) after 3m29s
[07:59:42.080] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:68:0)
[07:59:42.178] Build status: STARTED
[07:59:42.178] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898755685276610896:69:0) after 9m11s
[08:01:32.958] Received PubSub notification, asking Buildbucket for the build status
[08:01:32.977] Build:
{
"id": "8733325623225313841",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17261,
"createdBy": "project:chromium",
"createTime": "2024-10-23T01:57:58.525291877Z",
"startTime": "2024-10-23T02:34:01.030307591Z",
"endTime": "2024-10-23T08:01:32.698282548Z",
"updateTime": "2024-10-23T08:01:32.698282548Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1f77ee002403d0e1689574141cc3a46566c92475",
"ref": "refs/heads/main"
}
}
}
[08:01:32.977] Invocation finished in 6h3m35.791404927s with status SUCCEEDED