[03:59:24.112] New invocation is queued and will start shortly
[03:59:25.259] Starting the invocation (attempt 1)
[03:59:25.287] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/14996af23520b5e7ee3c8dfd3d7eeda938829c0d"
[03:59:25.287] Popped gitiles commit info from properties and tags
[03:59:25.287] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:59:25.287] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:59:25.287] Buildbucket request:
{
"requestId": "8909438486422800656",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "14996af23520b5e7ee3c8dfd3d7eeda938829c0d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8909438486422800656"
},
{
"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"
}
}
[03:59:26.471] Scheduled build:
{
"id": "8744008424332577265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 15860,
"createdBy": "project:chromium",
"createTime": "2024-06-27T03:59:25.482229188Z",
"updateTime": "2024-06-27T03:59:25.482229188Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "14996af23520b5e7ee3c8dfd3d7eeda938829c0d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:59:26.471] Task URL: https://cr-buildbucket.appspot.com/build/8744008424332577265
[03:59:26.471] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:2:0) after 4m59s
[03:59:27.269] Received PubSub notification, asking Buildbucket for the build status
[03:59:27.296] Build status: SCHEDULED
[04:04:25.638] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:2:0)
[04:04:25.658] Build status: SCHEDULED
[04:04:25.658] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:4:0) after 3m3s
[04:07:28.683] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:4:0)
[04:07:28.700] Build status: SCHEDULED
[04:07:28.700] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:5:0) after 8m26s
[04:07:56.511] Received PubSub notification, asking Buildbucket for the build status
[04:07:56.536] Build status: STARTED
[04:15:54.942] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:5:0)
[04:15:54.960] Build status: STARTED
[04:15:54.960] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:7:0) after 9m36s
[04:25:30.955] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:7:0)
[04:25:31.033] Build status: STARTED
[04:25:31.033] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:8:0) after 6m55s
[04:32:26.071] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:8:0)
[04:32:26.089] Build status: STARTED
[04:32:26.090] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:9:0) after 9m44s
[04:42:10.109] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:9:0)
[04:42:10.132] Build status: STARTED
[04:42:10.133] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:10:0) after 5m9s
[04:47:19.358] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:10:0)
[04:47:19.377] Build status: STARTED
[04:47:19.377] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:11:0) after 4m4s
[04:51:23.721] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:11:0)
[04:51:23.739] Build status: STARTED
[04:51:23.739] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:12:0) after 1m47s
[04:53:11.136] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:12:0)
[04:53:11.152] Build status: STARTED
[04:53:11.152] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:13:0) after 5m31s
[04:58:42.215] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:13:0)
[04:58:42.236] Build status: STARTED
[04:58:42.236] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:14:0) after 6m53s
[05:05:35.258] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:14:0)
[05:05:35.277] Build status: STARTED
[05:05:35.277] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:15:0) after 3m40s
[05:09:15.329] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:15:0)
[05:09:15.419] Build status: STARTED
[05:09:15.420] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:16:0) after 6m28s
[05:15:43.549] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:16:0)
[05:15:43.571] Build status: STARTED
[05:15:43.571] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:17:0) after 1m25s
[05:17:08.908] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:17:0)
[05:17:08.925] Build status: STARTED
[05:17:08.925] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:18:0) after 7m56s
[05:25:05.535] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:18:0)
[05:25:05.629] Build status: STARTED
[05:25:05.629] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:19:0) after 8m28s
[05:33:33.713] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:19:0)
[05:33:33.731] Build status: STARTED
[05:33:33.731] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:20:0) after 6m4s
[05:39:37.753] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:20:0)
[05:39:37.775] Build status: STARTED
[05:39:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:21:0) after 5m58s
[05:45:35.792] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:21:0)
[05:45:35.812] Build status: STARTED
[05:45:35.812] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:22:0) after 2m51s
[05:48:26.943] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:22:0)
[05:48:26.979] Build status: STARTED
[05:48:26.979] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:23:0) after 7m59s
[05:56:26.179] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:23:0)
[05:56:26.218] Build status: STARTED
[05:56:26.218] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:24:0) after 9m47s
[06:06:13.241] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:24:0)
[06:06:13.295] Build status: STARTED
[06:06:13.295] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:25:0) after 3m59s
[06:10:12.335] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:25:0)
[06:10:12.354] Build status: STARTED
[06:10:12.354] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:26:0) after 6m37s
[06:16:49.936] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:26:0)
[06:16:49.953] Build status: STARTED
[06:16:49.953] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:27:0) after 5m6s
[06:21:55.977] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:27:0)
[06:21:55.997] Build status: STARTED
[06:21:55.997] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:28:0) after 6m21s
[06:28:17.017] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:28:0)
[06:28:17.034] Build status: STARTED
[06:28:17.034] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:29:0) after 7m3s
[06:35:20.061] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:29:0)
[06:35:20.082] Build status: STARTED
[06:35:20.082] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:30:0) after 5m24s
[06:40:44.212] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:30:0)
[06:40:44.229] Build status: STARTED
[06:40:44.229] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:31:0) after 5m15s
[06:45:59.259] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:31:0)
[06:45:59.278] Build status: STARTED
[06:45:59.278] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:32:0) after 3m21s
[06:49:20.538] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:32:0)
[06:49:20.656] Build status: STARTED
[06:49:20.656] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:33:0) after 4m57s
[06:54:17.679] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:33:0)
[06:54:17.724] Build status: STARTED
[06:54:17.724] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:34:0) after 1m2s
[06:55:19.744] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:34:0)
[06:55:19.772] Build status: STARTED
[06:55:19.772] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:35:0) after 3m8s
[06:58:27.822] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:35:0)
[06:58:27.840] Build status: STARTED
[06:58:27.840] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:36:0) after 8m39s
[07:07:06.938] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:36:0)
[07:07:06.958] Build status: STARTED
[07:07:06.958] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:37:0) after 1m43s
[07:08:50.007] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:37:0)
[07:08:50.025] Build status: STARTED
[07:08:50.025] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:38:0) after 6m57s
[07:15:47.209] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:38:0)
[07:15:47.227] Build status: STARTED
[07:15:47.227] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:39:0) after 7m39s
[07:23:26.247] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:39:0)
[07:23:26.266] Build status: STARTED
[07:23:26.267] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:40:0) after 6m46s
[07:30:12.292] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:40:0)
[07:30:12.310] Build status: STARTED
[07:30:12.311] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:41:0) after 6m4s
[07:36:16.342] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:41:0)
[07:36:16.360] Build status: STARTED
[07:36:16.360] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:42:0) after 5m18s
[07:41:34.413] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:42:0)
[07:41:34.428] Build status: STARTED
[07:41:34.428] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:43:0) after 4m45s
[07:46:19.480] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:43:0)
[07:46:19.547] Build status: STARTED
[07:46:19.547] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:44:0) after 9m20s
[07:55:39.562] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:44:0)
[07:55:39.580] Build status: STARTED
[07:55:39.580] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:45:0) after 1m1s
[07:56:40.928] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:45:0)
[07:56:40.970] Build status: STARTED
[07:56:40.970] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:46:0) after 3m59s
[08:00:40.217] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:46:0)
[08:00:40.239] Build status: STARTED
[08:00:40.239] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:47:0) after 8m48s
[08:09:28.264] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:47:0)
[08:09:28.278] Build status: STARTED
[08:09:28.278] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:48:0) after 8m26s
[08:17:54.336] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:48:0)
[08:17:54.352] Build status: STARTED
[08:17:54.352] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:49:0) after 7m5s
[08:24:59.372] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:49:0)
[08:24:59.396] Build status: STARTED
[08:24:59.396] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:50:0) after 1m29s
[08:26:28.427] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:50:0)
[08:26:28.454] Build status: STARTED
[08:26:28.455] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:51:0) after 4m27s
[08:30:55.664] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:51:0)
[08:30:55.732] Build status: STARTED
[08:30:55.732] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:52:0) after 9m54s
[08:40:49.750] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:52:0)
[08:40:49.774] Build status: STARTED
[08:40:49.774] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:53:0) after 7m32s
[08:48:21.803] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:53:0)
[08:48:21.826] Build status: STARTED
[08:48:21.826] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:54:0) after 5m16s
[08:53:38.034] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:54:0)
[08:53:38.051] Build status: STARTED
[08:53:38.051] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:55:0) after 3m39s
[08:57:17.229] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:55:0)
[08:57:17.255] Build status: STARTED
[08:57:17.255] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:56:0) after 4m25s
[09:01:42.485] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:56:0)
[09:01:42.503] Build status: STARTED
[09:01:42.503] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8909438486422800656:57:0) after 7m27s
[09:07:14.231] Received PubSub notification, asking Buildbucket for the build status
[09:07:14.251] Build:
{
"id": "8744008424332577265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 15860,
"createdBy": "project:chromium",
"createTime": "2024-06-27T03:59:25.482229188Z",
"startTime": "2024-06-27T04:07:46.037876738Z",
"endTime": "2024-06-27T09:07:11.416247691Z",
"updateTime": "2024-06-27T09:07:11.416247691Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "14996af23520b5e7ee3c8dfd3d7eeda938829c0d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[09:07:14.251] Invocation finished in 5h7m50.14616062s with status SUCCEEDED