[06:26:36.436] New invocation is queued and will start shortly
[06:26:38.001] Starting the invocation (attempt 1)
[06:26:38.039] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45facba7582a4f5fb1152de2b9e65a4123773f82"
[06:26:38.039] Popped gitiles commit info from properties and tags
[06:26:38.039] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:26:38.039] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:26:38.039] Buildbucket request:
{
"requestId": "8918307727777245760",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45facba7582a4f5fb1152de2b9e65a4123773f82",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8918307727777245760"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-build-perf-developer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:26:39.647] Scheduled build:
{
"id": "8752877665352603457",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 673,
"createdBy": "project:chromium",
"createTime": "2024-03-21T06:26:38.115635885Z",
"updateTime": "2024-03-21T06:26:38.115635885Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45facba7582a4f5fb1152de2b9e65a4123773f82",
"ref": "refs/heads/main"
}
}
}
[06:26:39.647] Task URL: https://cr-buildbucket.appspot.com/build/8752877665352603457
[06:26:39.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:2:0) after 9m36s
[06:26:40.190] Received PubSub notification, asking Buildbucket for the build status
[06:26:40.208] Build status: SCHEDULED
[06:28:06.487] Received PubSub notification, asking Buildbucket for the build status
[06:28:06.515] Build status: STARTED
[06:36:15.697] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:2:0)
[06:36:15.739] Build status: STARTED
[06:36:15.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:5:0) after 5m40s
[06:41:55.761] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:5:0)
[06:41:55.780] Build status: STARTED
[06:41:55.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:6:0) after 9m20s
[06:51:15.834] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:6:0)
[06:51:15.867] Build status: STARTED
[06:51:15.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:7:0) after 9m44s
[07:00:59.907] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:7:0)
[07:00:59.982] Build status: STARTED
[07:00:59.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:8:0) after 8m33s
[07:09:33.130] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:8:0)
[07:09:33.217] Build status: STARTED
[07:09:33.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:9:0) after 9m25s
[07:18:58.247] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:9:0)
[07:18:58.266] Build status: STARTED
[07:18:58.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:10:0) after 5m14s
[07:24:12.289] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:10:0)
[07:24:12.327] Build status: STARTED
[07:24:12.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:11:0) after 3m13s
[07:27:25.546] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:11:0)
[07:27:25.589] Build status: STARTED
[07:27:25.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:12:0) after 6m40s
[07:34:05.624] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:12:0)
[07:34:05.734] Build status: STARTED
[07:34:05.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:13:0) after 6m8s
[07:40:14.086] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:13:0)
[07:40:14.110] Build status: STARTED
[07:40:14.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:14:0) after 3m51s
[07:44:05.283] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:14:0)
[07:44:05.342] Build status: STARTED
[07:44:05.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:15:0) after 3m14s
[07:47:19.531] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:15:0)
[07:47:19.564] Build status: STARTED
[07:47:19.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:16:0) after 4m36s
[07:51:55.552] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:16:0)
[07:51:55.581] Build status: STARTED
[07:51:55.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:17:0) after 6m19s
[07:58:15.246] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:17:0)
[07:58:15.264] Build status: STARTED
[07:58:15.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:18:0) after 1m49s
[08:00:04.516] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:18:0)
[08:00:04.603] Build status: STARTED
[08:00:04.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:19:0) after 6m12s
[08:06:16.948] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:19:0)
[08:06:16.978] Build status: STARTED
[08:06:16.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:20:0) after 4m29s
[08:10:46.039] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:20:0)
[08:10:46.085] Build status: STARTED
[08:10:46.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:21:0) after 4m4s
[08:14:50.212] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:21:0)
[08:14:50.231] Build status: STARTED
[08:14:50.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:22:0) after 2m58s
[08:17:48.318] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:22:0)
[08:17:48.341] Build status: STARTED
[08:17:48.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:23:0) after 5m23s
[08:23:11.389] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:23:0)
[08:23:11.428] Build status: STARTED
[08:23:11.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:24:0) after 8m30s
[08:31:41.448] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:24:0)
[08:31:41.464] Build status: STARTED
[08:31:41.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:25:0) after 6m25s
[08:38:06.437] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:25:0)
[08:38:06.459] Build status: STARTED
[08:38:06.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:26:0) after 6m19s
[08:44:25.583] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:26:0)
[08:44:25.603] Build status: STARTED
[08:44:25.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:27:0) after 7m1s
[08:51:26.639] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:27:0)
[08:51:26.671] Build status: STARTED
[08:51:26.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:28:0) after 7m42s
[08:59:08.805] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:28:0)
[08:59:08.844] Build status: STARTED
[08:59:08.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:29:0) after 8m12s
[09:07:21.315] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:29:0)
[09:07:21.333] Build status: STARTED
[09:07:21.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:30:0) after 7m15s
[09:14:36.355] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:30:0)
[09:14:36.392] Build status: STARTED
[09:14:36.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:31:0) after 1m50s
[09:16:26.704] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:31:0)
[09:16:26.790] Build status: STARTED
[09:16:26.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:32:0) after 9m9s
[09:25:35.821] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:32:0)
[09:25:35.842] Build status: STARTED
[09:25:35.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:33:0) after 3m57s
[09:29:32.928] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:33:0)
[09:29:32.951] Build status: STARTED
[09:29:32.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:34:0) after 3m36s
[09:33:09.049] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:34:0)
[09:33:09.074] Build status: STARTED
[09:33:09.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:35:0) after 2m40s
[09:35:49.125] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:35:0)
[09:35:49.393] Build status: STARTED
[09:35:49.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:36:0) after 9m41s
[09:45:30.905] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:36:0)
[09:45:30.938] Build status: STARTED
[09:45:30.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:37:0) after 8m29s
[09:53:59.966] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:37:0)
[09:54:00.011] Build status: STARTED
[09:54:00.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:38:0) after 9m4s
[10:03:04.191] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:38:0)
[10:03:04.212] Build status: STARTED
[10:03:04.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:39:0) after 7m3s
[10:10:07.262] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:39:0)
[10:10:07.295] Build status: STARTED
[10:10:07.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:40:0) after 7m37s
[10:17:44.422] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:40:0)
[10:17:44.442] Build status: STARTED
[10:17:44.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:41:0) after 8m10s
[10:25:54.652] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:41:0)
[10:25:54.745] Build status: STARTED
[10:25:54.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:42:0) after 9m14s
[10:35:08.873] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:42:0)
[10:35:08.897] Build status: STARTED
[10:35:08.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:43:0) after 6m54s
[10:42:02.948] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:43:0)
[10:42:03.043] Build status: STARTED
[10:42:03.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:44:0) after 6m34s
[10:48:37.374] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:44:0)
[10:48:37.407] Build status: STARTED
[10:48:37.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:45:0) after 5m2s
[10:53:39.509] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:45:0)
[10:53:39.531] Build status: STARTED
[10:53:39.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:46:0) after 8m54s
[11:02:33.551] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:46:0)
[11:02:33.575] Build status: STARTED
[11:02:33.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:47:0) after 6m11s
[11:08:44.952] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:47:0)
[11:08:44.981] Build status: STARTED
[11:08:44.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:48:0) after 8m32s
[11:17:17.076] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:48:0)
[11:17:17.113] Build status: STARTED
[11:17:17.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:49:0) after 5m55s
[11:23:12.118] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:49:0)
[11:23:12.144] Build status: STARTED
[11:23:12.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:50:0) after 7m3s
[11:30:15.162] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:50:0)
[11:30:15.222] Build status: STARTED
[11:30:15.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:51:0) after 1m49s
[11:32:04.242] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:51:0)
[11:32:04.324] Build status: STARTED
[11:32:04.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:52:0) after 2m55s
[11:34:59.414] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:52:0)
[11:34:59.433] Build status: STARTED
[11:34:59.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:53:0) after 9m43s
[11:44:42.532] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:53:0)
[11:44:42.565] Build status: STARTED
[11:44:42.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:54:0) after 7m39s
[11:52:21.810] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:54:0)
[11:52:21.838] Build status: STARTED
[11:52:21.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:55:0) after 8m31s
[12:00:52.933] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:55:0)
[12:00:52.962] Build status: STARTED
[12:00:52.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:56:0) after 2m24s
[12:03:17.056] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:56:0)
[12:03:17.178] Build status: STARTED
[12:03:17.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:57:0) after 9m49s
[12:13:07.134] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:57:0)
[12:13:07.178] Build status: STARTED
[12:13:07.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:58:0) after 1m43s
[12:14:50.232] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:58:0)
[12:14:50.298] Build status: STARTED
[12:14:50.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:59:0) after 1m43s
[12:16:33.319] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:59:0)
[12:16:33.343] Build status: STARTED
[12:16:33.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8918307727777245760:60:0) after 7m30s
[12:18:22.467] Received PubSub notification, asking Buildbucket for the build status
[12:18:22.495] Build:
{
"id": "8752877665352603457",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 673,
"createdBy": "project:chromium",
"createTime": "2024-03-21T06:26:38.115635885Z",
"startTime": "2024-03-21T06:28:05.773072Z",
"endTime": "2024-03-21T12:18:19.830724Z",
"updateTime": "2024-03-21T12:18:19.830724Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45facba7582a4f5fb1152de2b9e65a4123773f82",
"ref": "refs/heads/main"
}
}
}
[12:18:22.495] Invocation finished in 5h51m46.076686213s with status SUCCEEDED