[14:14:55.403] New invocation is queued and will start shortly
[14:14:57.364] Starting the invocation (attempt 1)
[14:14:57.391] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/73b97ef37215b0d2683e7f439f5ca87ba02a102e"
[14:14:57.392] Popped gitiles commit info from properties and tags
[14:14:57.392] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:14:57.392] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:14:57.392] Buildbucket request:
{
"requestId": "8915832145770579328",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "73b97ef37215b0d2683e7f439f5ca87ba02a102e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915832145770579328"
},
{
"key": "scheduler_job_id",
"value": "chromium/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:14:58.174] Scheduled build:
{
"id": "8750402082894911121",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"number": 7525,
"createdBy": "project:chromium",
"createTime": "2024-04-17T14:14:57.523487188Z",
"updateTime": "2024-04-17T14:14:57.523487188Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "73b97ef37215b0d2683e7f439f5ca87ba02a102e",
"ref": "refs/heads/main"
}
}
}
[14:14:58.174] Task URL: https://cr-buildbucket.appspot.com/build/8750402082894911121
[14:14:58.174] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:2:0) after 3m15s
[14:14:59.158] Received PubSub notification, asking Buildbucket for the build status
[14:14:59.238] Build status: SCHEDULED
[14:16:27.451] Received PubSub notification, asking Buildbucket for the build status
[14:16:27.479] Build status: STARTED
[14:18:13.238] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:2:0)
[14:18:13.278] Build status: STARTED
[14:18:13.278] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:5:0) after 8m58s
[14:27:11.353] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:5:0)
[14:27:11.388] Build status: STARTED
[14:27:11.388] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:6:0) after 9m55s
[14:37:06.666] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:6:0)
[14:37:06.713] Build status: STARTED
[14:37:06.713] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:7:0) after 8m49s
[14:45:55.947] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:7:0)
[14:45:55.985] Build status: STARTED
[14:45:55.985] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:8:0) after 7m46s
[14:53:42.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:8:0)
[14:53:42.191] Build status: STARTED
[14:53:42.191] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:9:0) after 2m35s
[14:56:17.304] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:9:0)
[14:56:17.348] Build status: STARTED
[14:56:17.348] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:10:0) after 7m40s
[15:03:57.383] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:10:0)
[15:03:57.416] Build status: STARTED
[15:03:57.416] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:11:0) after 9m56s
[15:13:53.461] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:11:0)
[15:13:53.538] Build status: STARTED
[15:13:53.538] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:12:0) after 3m59s
[15:17:53.461] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:12:0)
[15:17:53.508] Build status: STARTED
[15:17:53.508] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:13:0) after 2m37s
[15:20:30.528] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:13:0)
[15:20:30.564] Build status: STARTED
[15:20:30.564] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:14:0) after 5m45s
[15:26:15.540] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:14:0)
[15:26:15.573] Build status: STARTED
[15:26:15.573] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:15:0) after 6m5s
[15:32:20.611] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:15:0)
[15:32:20.695] Build status: STARTED
[15:32:20.695] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:16:0) after 9m12s
[15:41:32.721] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:16:0)
[15:41:32.775] Build status: STARTED
[15:41:32.775] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:17:0) after 6m15s
[15:47:47.868] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:17:0)
[15:47:47.890] Build status: STARTED
[15:47:47.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:18:0) after 3m21s
[15:51:08.912] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:18:0)
[15:51:08.954] Build status: STARTED
[15:51:08.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:19:0) after 7m45s
[15:58:54.861] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:19:0)
[15:58:54.879] Build status: STARTED
[15:58:54.879] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:20:0) after 2m2s
[16:00:57.300] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:20:0)
[16:00:57.324] Build status: STARTED
[16:00:57.325] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:21:0) after 4m45s
[16:05:42.565] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:21:0)
[16:05:42.729] Build status: STARTED
[16:05:42.729] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:22:0) after 8m41s
[16:14:23.894] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:22:0)
[16:14:23.939] Build status: STARTED
[16:14:23.940] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:23:0) after 6m22s
[16:20:46.078] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:23:0)
[16:20:46.110] Build status: STARTED
[16:20:46.110] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:24:0) after 8m9s
[16:28:55.141] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:24:0)
[16:28:55.212] Build status: STARTED
[16:28:55.212] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:25:0) after 8m9s
[16:37:04.249] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:25:0)
[16:37:04.275] Build status: STARTED
[16:37:04.275] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:26:0) after 6m40s
[16:43:44.353] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:26:0)
[16:43:44.370] Build status: STARTED
[16:43:44.370] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:27:0) after 9m49s
[16:53:33.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:27:0)
[16:53:33.474] Build status: STARTED
[16:53:33.474] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:28:0) after 9m33s
[17:03:06.524] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:28:0)
[17:03:06.587] Build status: STARTED
[17:03:06.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:29:0) after 5m55s
[17:09:01.642] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:29:0)
[17:09:01.714] Build status: STARTED
[17:09:01.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:30:0) after 6m20s
[17:15:21.786] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:30:0)
[17:15:21.812] Build status: STARTED
[17:15:21.812] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:31:0) after 1m45s
[17:17:06.888] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:31:0)
[17:17:06.908] Build status: STARTED
[17:17:06.908] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:32:0) after 4m17s
[17:21:23.942] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:32:0)
[17:21:24.014] Build status: STARTED
[17:21:24.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:33:0) after 6m25s
[17:27:49.100] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:33:0)
[17:27:49.233] Build status: STARTED
[17:27:49.233] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:34:0) after 1m56s
[17:29:45.466] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:34:0)
[17:29:45.511] Build status: STARTED
[17:29:45.511] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:35:0) after 9m50s
[17:39:35.532] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:35:0)
[17:39:35.555] Build status: STARTED
[17:39:35.555] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:36:0) after 1m18s
[17:40:53.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:36:0)
[17:40:53.593] Build status: STARTED
[17:40:53.593] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:37:0) after 7m42s
[17:48:35.611] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:37:0)
[17:48:35.685] Build status: STARTED
[17:48:35.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:38:0) after 3m51s
[17:52:26.765] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:38:0)
[17:52:26.794] Build status: STARTED
[17:52:26.794] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:39:0) after 6m19s
[17:58:45.942] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:39:0)
[17:58:45.963] Build status: STARTED
[17:58:45.963] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:40:0) after 2m25s
[18:01:11.063] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:40:0)
[18:01:11.097] Build status: STARTED
[18:01:11.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:41:0) after 6m6s
[18:07:17.148] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:41:0)
[18:07:17.340] Build status: STARTED
[18:07:17.340] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:42:0) after 2m44s
[18:10:01.372] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:42:0)
[18:10:01.400] Build status: STARTED
[18:10:01.400] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:43:0) after 7m53s
[18:17:54.452] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:43:0)
[18:17:54.472] Build status: STARTED
[18:17:54.472] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:44:0) after 7m16s
[18:25:10.554] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:44:0)
[18:25:10.577] Build status: STARTED
[18:25:10.577] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:45:0) after 9m17s
[18:34:27.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:45:0)
[18:34:27.646] Build status: STARTED
[18:34:27.646] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:46:0) after 2m5s
[18:36:32.667] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:46:0)
[18:36:32.691] Build status: STARTED
[18:36:32.691] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:47:0) after 6m18s
[18:42:50.721] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:47:0)
[18:42:50.750] Build status: STARTED
[18:42:50.750] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:48:0) after 6m4s
[18:48:54.784] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:48:0)
[18:48:54.812] Build status: STARTED
[18:48:54.812] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:49:0) after 6m17s
[18:55:11.851] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:49:0)
[18:55:11.872] Build status: STARTED
[18:55:11.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:50:0) after 9m31s
[19:04:42.893] Handling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:50:0)
[19:04:42.937] Build status: STARTED
[19:04:42.937] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:8915832145770579328:51:0) after 5m54s
[19:04:47.016] Received PubSub notification, asking Buildbucket for the build status
[19:04:47.045] Build:
{
"id": "8750402082894911121",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win32-official"
},
"number": 7525,
"createdBy": "project:chromium",
"createTime": "2024-04-17T14:14:57.523487188Z",
"startTime": "2024-04-17T14:16:27.177010104Z",
"endTime": "2024-04-17T19:04:46.642618406Z",
"updateTime": "2024-04-17T19:04:46.642618406Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "73b97ef37215b0d2683e7f439f5ca87ba02a102e",
"ref": "refs/heads/main"
}
}
}
[19:04:47.045] Invocation finished in 4h49m51.647240134s with status SUCCEEDED