[01:45:28.425] New invocation is queued and will start shortly
[01:45:29.617] Starting the invocation (attempt 1)
[01:45:29.646] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1533e7925c52d3d147228a048e0ebdfcada7620d"
[01:45:29.646] Popped gitiles commit info from properties and tags
[01:45:29.646] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:45:29.646] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:45:29.646] Buildbucket request:
{
"requestId": "8898394082552625904",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1533e7925c52d3d147228a048e0ebdfcada7620d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898394082552625904"
},
{
"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:45:30.515] Scheduled build:
{
"id": "8732964020540425377",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17277,
"createdBy": "project:chromium",
"createTime": "2024-10-27T01:45:29.718585755Z",
"updateTime": "2024-10-27T01:45:29.718585755Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1533e7925c52d3d147228a048e0ebdfcada7620d",
"ref": "refs/heads/main"
}
}
}
[01:45:30.515] Task URL: https://cr-buildbucket.appspot.com/build/8732964020540425377
[01:45:30.515] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:2:0) after 6m36s
[01:45:30.747] Received PubSub notification, asking Buildbucket for the build status
[01:45:30.807] Build status: SCHEDULED
[01:52:06.536] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:2:0)
[01:52:06.554] Build status: SCHEDULED
[01:52:06.554] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:4:0) after 4m52s
[01:56:58.574] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:4:0)
[01:56:58.594] Build status: SCHEDULED
[01:56:58.594] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:5:0) after 7m18s
[02:00:48.037] Received PubSub notification, asking Buildbucket for the build status
[02:00:48.078] Build status: STARTED
[02:04:16.809] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:5:0)
[02:04:16.825] Build status: STARTED
[02:04:16.825] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:7:0) after 5m4s
[02:09:21.064] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:7:0)
[02:09:21.140] Build status: STARTED
[02:09:21.140] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:8:0) after 2m17s
[02:11:38.502] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:8:0)
[02:11:38.529] Build status: STARTED
[02:11:38.529] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:9:0) after 9m15s
[02:20:53.868] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:9:0)
[02:20:53.889] Build status: STARTED
[02:20:53.889] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:10:0) after 6m49s
[02:27:43.004] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:10:0)
[02:27:43.023] Build status: STARTED
[02:27:43.023] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:11:0) after 3m11s
[02:30:54.067] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:11:0)
[02:30:54.135] Build status: STARTED
[02:30:54.135] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:12:0) after 5m16s
[02:36:10.275] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:12:0)
[02:36:10.289] Build status: STARTED
[02:36:10.289] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:13:0) after 3m22s
[02:39:32.372] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:13:0)
[02:39:32.392] Build status: STARTED
[02:39:32.392] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:14:0) after 6m13s
[02:45:45.497] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:14:0)
[02:45:45.523] Build status: STARTED
[02:45:45.523] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:15:0) after 6m45s
[02:52:30.539] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:15:0)
[02:52:30.559] Build status: STARTED
[02:52:30.559] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:16:0) after 1m29s
[02:53:59.574] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:16:0)
[02:53:59.588] Build status: STARTED
[02:53:59.588] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:17:0) after 7m9s
[03:01:08.763] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:17:0)
[03:01:08.780] Build status: STARTED
[03:01:08.780] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:18:0) after 6m33s
[03:07:41.798] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:18:0)
[03:07:41.838] Build status: STARTED
[03:07:41.838] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:19:0) after 7m3s
[03:14:44.861] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:19:0)
[03:14:44.877] Build status: STARTED
[03:14:44.877] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:20:0) after 2m35s
[03:17:19.996] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:20:0)
[03:17:20.014] Build status: STARTED
[03:17:20.014] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:21:0) after 3m2s
[03:20:22.029] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:21:0)
[03:20:22.053] Build status: STARTED
[03:20:22.053] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:22:0) after 4m52s
[03:25:14.358] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:22:0)
[03:25:14.376] Build status: STARTED
[03:25:14.376] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:23:0) after 7m38s
[03:32:52.380] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:23:0)
[03:32:52.397] Build status: STARTED
[03:32:52.398] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:24:0) after 1m18s
[03:34:10.423] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:24:0)
[03:34:10.440] Build status: STARTED
[03:34:10.440] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:25:0) after 4m15s
[03:38:25.458] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:25:0)
[03:38:25.478] Build status: STARTED
[03:38:25.478] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:26:0) after 8m27s
[03:46:52.494] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:26:0)
[03:46:52.516] Build status: STARTED
[03:46:52.516] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:27:0) after 3m6s
[03:49:58.510] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:27:0)
[03:49:58.531] Build status: STARTED
[03:49:58.531] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:28:0) after 2m20s
[03:52:18.549] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:28:0)
[03:52:18.571] Build status: STARTED
[03:52:18.571] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:29:0) after 7m27s
[03:59:45.602] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:29:0)
[03:59:45.637] Build status: STARTED
[03:59:45.637] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:30:0) after 1m33s
[04:01:19.168] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:30:0)
[04:01:19.190] Build status: STARTED
[04:01:19.190] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:31:0) after 9m45s
[04:11:04.208] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:31:0)
[04:11:04.223] Build status: STARTED
[04:11:04.223] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:32:0) after 4m22s
[04:15:26.238] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:32:0)
[04:15:26.256] Build status: STARTED
[04:15:26.256] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:33:0) after 1m15s
[04:16:41.376] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:33:0)
[04:16:41.392] Build status: STARTED
[04:16:41.392] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:34:0) after 3m24s
[04:20:05.565] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:34:0)
[04:20:05.585] Build status: STARTED
[04:20:05.585] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:35:0) after 9m3s
[04:29:08.603] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:35:0)
[04:29:08.621] Build status: STARTED
[04:29:08.621] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:36:0) after 7m59s
[04:37:07.748] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:36:0)
[04:37:07.825] Build status: STARTED
[04:37:07.825] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:37:0) after 8m28s
[04:45:35.845] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:37:0)
[04:45:35.864] Build status: STARTED
[04:45:35.864] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:38:0) after 8m14s
[04:53:49.882] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:38:0)
[04:53:49.901] Build status: STARTED
[04:53:49.901] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:39:0) after 8m13s
[05:02:02.932] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:39:0)
[05:02:02.951] Build status: STARTED
[05:02:02.951] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:40:0) after 3m31s
[05:05:34.157] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:40:0)
[05:05:34.199] Build status: STARTED
[05:05:34.199] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:41:0) after 8m59s
[05:14:33.219] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:41:0)
[05:14:33.249] Build status: STARTED
[05:14:33.249] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:42:0) after 9m38s
[05:24:11.309] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:42:0)
[05:24:11.375] Build status: STARTED
[05:24:11.375] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:43:0) after 8m26s
[05:32:37.397] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:43:0)
[05:32:37.413] Build status: STARTED
[05:32:37.413] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:44:0) after 9m22s
[05:41:59.431] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:44:0)
[05:41:59.447] Build status: STARTED
[05:41:59.447] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:45:0) after 5m57s
[05:47:56.465] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:45:0)
[05:47:56.487] Build status: STARTED
[05:47:56.487] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:46:0) after 9m27s
[05:57:23.506] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:46:0)
[05:57:23.522] Build status: STARTED
[05:57:23.522] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:47:0) after 6m5s
[06:03:28.597] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:47:0)
[06:03:28.616] Build status: STARTED
[06:03:28.616] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:48:0) after 2m35s
[06:06:03.713] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:48:0)
[06:06:03.731] Build status: STARTED
[06:06:03.731] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:49:0) after 1m44s
[06:07:47.746] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:49:0)
[06:07:47.769] Build status: STARTED
[06:07:47.769] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:50:0) after 9m25s
[06:17:12.785] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:50:0)
[06:17:12.802] Build status: STARTED
[06:17:12.802] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:51:0) after 8m55s
[06:26:07.820] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:51:0)
[06:26:07.840] Build status: STARTED
[06:26:07.841] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:52:0) after 1m47s
[06:27:55.518] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:52:0)
[06:27:55.534] Build status: STARTED
[06:27:55.534] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:53:0) after 8m42s
[06:36:37.584] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:53:0)
[06:36:37.603] Build status: STARTED
[06:36:37.603] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:54:0) after 4m13s
[06:40:50.723] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:54:0)
[06:40:50.739] Build status: STARTED
[06:40:50.739] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:55:0) after 8m19s
[06:49:10.021] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:55:0)
[06:49:10.058] Build status: STARTED
[06:49:10.058] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:56:0) after 3m55s
[06:53:05.109] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:56:0)
[06:53:05.125] Build status: STARTED
[06:53:05.125] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:57:0) after 5m56s
[06:59:01.240] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:57:0)
[06:59:01.258] Build status: STARTED
[06:59:01.258] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:58:0) after 1m19s
[07:00:20.282] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:58:0)
[07:00:20.300] Build status: STARTED
[07:00:20.301] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:59:0) after 3m48s
[07:04:08.351] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:59:0)
[07:04:08.377] Build status: STARTED
[07:04:08.377] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:60:0) after 5m4s
[07:09:12.482] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:60:0)
[07:09:12.498] Build status: STARTED
[07:09:12.498] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:61:0) after 6m19s
[07:15:31.681] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:61:0)
[07:15:31.703] Build status: STARTED
[07:15:31.703] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8898394082552625904:62:0) after 7m6s
[07:19:49.313] Received PubSub notification, asking Buildbucket for the build status
[07:19:49.333] Build:
{
"id": "8732964020540425377",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 17277,
"createdBy": "project:chromium",
"createTime": "2024-10-27T01:45:29.718585755Z",
"startTime": "2024-10-27T02:00:47.749965805Z",
"endTime": "2024-10-27T07:19:49.014238019Z",
"updateTime": "2024-10-27T07:19:49.014238019Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1533e7925c52d3d147228a048e0ebdfcada7620d",
"ref": "refs/heads/main"
}
}
}
[07:19:49.333] Invocation finished in 5h34m20.917629693s with status SUCCEEDED