[18:29:51.165] New invocation is queued and will start shortly
[18:29:55.379] Starting the invocation (attempt 1)
[18:29:55.406] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45058e714ddfc2388e677f11665b87deb67f45de"
[18:29:55.406] Popped gitiles commit info from properties and tags
[18:29:55.406] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:29:55.406] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:29:55.407] Buildbucket request:
{
"requestId": "8915000734309430144",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45058e714ddfc2388e677f11665b87deb67f45de",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915000734309430144"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:29:55.988] Scheduled build:
{
"id": "8749570669125185489",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 8919,
"createdBy": "project:chromium",
"createTime": "2024-04-26T18:29:55.481672128Z",
"updateTime": "2024-04-26T18:29:55.481672128Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45058e714ddfc2388e677f11665b87deb67f45de",
"ref": "refs/heads/main"
}
}
}
[18:29:55.988] Task URL: https://cr-buildbucket.appspot.com/build/8749570669125185489
[18:29:55.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:2:0) after 7m13s
[18:29:56.929] Received PubSub notification, asking Buildbucket for the build status
[18:29:56.954] Build status: SCHEDULED
[18:30:03.029] Received PubSub notification, asking Buildbucket for the build status
[18:30:03.079] Build status: STARTED
[18:37:09.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:2:0)
[18:37:09.041] Build status: STARTED
[18:37:09.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:5:0) after 7m37s
[18:44:46.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:5:0)
[18:44:46.124] Build status: STARTED
[18:44:46.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:6:0) after 2m29s
[18:47:15.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:6:0)
[18:47:15.198] Build status: STARTED
[18:47:15.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:7:0) after 3m42s
[18:50:57.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:7:0)
[18:50:57.286] Build status: STARTED
[18:50:57.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:8:0) after 4m32s
[18:55:29.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:8:0)
[18:55:29.521] Build status: STARTED
[18:55:29.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:9:0) after 4m41s
[19:00:10.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:9:0)
[19:00:10.766] Build status: STARTED
[19:00:10.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:10:0) after 3m36s
[19:03:46.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:10:0)
[19:03:46.829] Build status: STARTED
[19:03:46.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:11:0) after 2m50s
[19:06:37.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:11:0)
[19:06:37.055] Build status: STARTED
[19:06:37.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:12:0) after 1m52s
[19:08:29.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:12:0)
[19:08:29.138] Build status: STARTED
[19:08:29.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:13:0) after 1m15s
[19:09:44.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:13:0)
[19:09:44.193] Build status: STARTED
[19:09:44.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:14:0) after 6m2s
[19:15:46.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:14:0)
[19:15:46.299] Build status: STARTED
[19:15:46.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:15:0) after 9m24s
[19:25:10.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:15:0)
[19:25:10.420] Build status: STARTED
[19:25:10.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:16:0) after 2m33s
[19:27:43.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:16:0)
[19:27:43.544] Build status: STARTED
[19:27:43.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:17:0) after 1m14s
[19:28:57.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:17:0)
[19:28:57.642] Build status: STARTED
[19:28:57.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:18:0) after 5m35s
[19:34:32.714] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:18:0)
[19:34:32.735] Build status: STARTED
[19:34:32.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:19:0) after 6m35s
[19:41:07.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:19:0)
[19:41:07.887] Build status: STARTED
[19:41:07.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:20:0) after 8m15s
[19:49:22.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:20:0)
[19:49:22.995] Build status: STARTED
[19:49:22.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:21:0) after 3m8s
[19:52:31.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:21:0)
[19:52:31.049] Build status: STARTED
[19:52:31.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:22:0) after 4m12s
[19:56:43.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:22:0)
[19:56:43.087] Build status: STARTED
[19:56:43.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:23:0) after 4m55s
[20:01:38.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:23:0)
[20:01:38.156] Build status: STARTED
[20:01:38.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:24:0) after 8m31s
[20:10:09.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:24:0)
[20:10:09.335] Build status: STARTED
[20:10:09.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:25:0) after 7m29s
[20:17:38.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:25:0)
[20:17:38.436] Build status: STARTED
[20:17:38.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:26:0) after 4m59s
[20:22:37.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:26:0)
[20:22:37.525] Build status: STARTED
[20:22:37.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:27:0) after 4m42s
[20:27:19.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:27:0)
[20:27:19.681] Build status: STARTED
[20:27:19.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:28:0) after 4m44s
[20:32:03.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:28:0)
[20:32:03.759] Build status: STARTED
[20:32:03.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:29:0) after 1m40s
[20:33:43.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:29:0)
[20:33:43.811] Build status: STARTED
[20:33:43.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:30:0) after 4m17s
[20:38:00.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:30:0)
[20:38:00.871] Build status: STARTED
[20:38:00.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:31:0) after 1m32s
[20:39:32.936] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:31:0)
[20:39:32.956] Build status: STARTED
[20:39:32.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:32:0) after 3m56s
[20:43:29.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:32:0)
[20:43:29.158] Build status: STARTED
[20:43:29.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:33:0) after 6m46s
[20:50:15.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:33:0)
[20:50:15.287] Build status: STARTED
[20:50:15.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:34:0) after 4m10s
[20:54:25.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:34:0)
[20:54:25.391] Build status: STARTED
[20:54:25.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:35:0) after 1m25s
[20:55:50.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:35:0)
[20:55:50.476] Build status: STARTED
[20:55:50.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:36:0) after 3m8s
[20:58:58.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:36:0)
[20:58:58.506] Build status: STARTED
[20:58:58.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:37:0) after 5m44s
[21:04:42.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:37:0)
[21:04:42.584] Build status: STARTED
[21:04:42.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8915000734309430144:38:0) after 1m36s
[21:05:27.233] Received PubSub notification, asking Buildbucket for the build status
[21:05:27.523] Build:
{
"id": "8749570669125185489",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 8919,
"createdBy": "project:chromium",
"createTime": "2024-04-26T18:29:55.481672128Z",
"startTime": "2024-04-26T18:30:02.423594867Z",
"endTime": "2024-04-26T21:05:25.679639281Z",
"updateTime": "2024-04-26T21:05:25.679639281Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45058e714ddfc2388e677f11665b87deb67f45de",
"ref": "refs/heads/main"
}
}
}
[21:05:27.523] Invocation finished in 2h35m36.36961396s with status SUCCEEDED