[17:04:29.263] New invocation is queued and will start shortly
[17:04:30.442] Starting the invocation (attempt 1)
[17:04:30.474] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4759c502b84545020661a4f137361ad6ce1127cd"
[17:04:30.474] Popped gitiles commit info from properties and tags
[17:04:30.474] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:04:30.475] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:04:30.475] Buildbucket request:
{
"requestId": "8916455656475091696",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-cft"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4759c502b84545020661a4f137361ad6ce1127cd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916455656475091696"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-rel-cft"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:04:31.298] Scheduled build:
{
"id": "8751025594488540529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-cft"
},
"number": 4685,
"createdBy": "project:chromium",
"createTime": "2024-04-10T17:04:30.530630646Z",
"updateTime": "2024-04-10T17:04:30.530630646Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4759c502b84545020661a4f137361ad6ce1127cd",
"ref": "refs/heads/main"
}
}
}
[17:04:31.298] Task URL: https://cr-buildbucket.appspot.com/build/8751025594488540529
[17:04:31.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:2:0) after 5m33s
[17:04:32.202] Received PubSub notification, asking Buildbucket for the build status
[17:04:32.306] Build status: SCHEDULED
[17:06:16.306] Received PubSub notification, asking Buildbucket for the build status
[17:06:16.342] Build status: STARTED
[17:10:04.383] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:2:0)
[17:10:04.403] Build status: STARTED
[17:10:04.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:5:0) after 1m55s
[17:11:59.520] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:5:0)
[17:11:59.540] Build status: STARTED
[17:11:59.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:6:0) after 3m54s
[17:15:53.631] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:6:0)
[17:15:53.650] Build status: STARTED
[17:15:53.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:7:0) after 6m36s
[17:22:29.703] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:7:0)
[17:22:29.810] Build status: STARTED
[17:22:29.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:8:0) after 6m26s
[17:28:55.869] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:8:0)
[17:28:55.886] Build status: STARTED
[17:28:55.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:9:0) after 2m50s
[17:31:45.957] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:9:0)
[17:31:45.992] Build status: STARTED
[17:31:45.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:10:0) after 3m49s
[17:35:35.161] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:10:0)
[17:35:35.211] Build status: STARTED
[17:35:35.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:11:0) after 1m22s
[17:36:57.264] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:11:0)
[17:36:57.294] Build status: STARTED
[17:36:57.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:12:0) after 8m58s
[17:45:55.317] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:12:0)
[17:45:55.344] Build status: STARTED
[17:45:55.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:13:0) after 2m3s
[17:47:58.404] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:13:0)
[17:47:58.425] Build status: STARTED
[17:47:58.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:14:0) after 2m0s
[17:49:58.508] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:14:0)
[17:49:58.537] Build status: STARTED
[17:49:58.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:15:0) after 6m20s
[17:56:18.596] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:15:0)
[17:56:18.684] Build status: STARTED
[17:56:18.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:16:0) after 4m23s
[18:00:41.831] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:16:0)
[18:00:41.932] Build status: STARTED
[18:00:41.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:17:0) after 3m41s
[18:04:22.954] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:17:0)
[18:04:22.976] Build status: STARTED
[18:04:22.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:18:0) after 5m56s
[18:10:19.072] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:18:0)
[18:10:19.121] Build status: STARTED
[18:10:19.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:19:0) after 8m49s
[18:19:08.273] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:19:0)
[18:19:08.320] Build status: STARTED
[18:19:08.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:20:0) after 8m1s
[18:27:09.485] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:20:0)
[18:27:09.524] Build status: STARTED
[18:27:09.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:21:0) after 4m43s
[18:31:52.654] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:21:0)
[18:31:52.683] Build status: STARTED
[18:31:52.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:22:0) after 2m55s
[18:34:47.710] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:22:0)
[18:34:47.729] Build status: STARTED
[18:34:47.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:23:0) after 6m19s
[18:41:06.748] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:23:0)
[18:41:06.766] Build status: STARTED
[18:41:06.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:24:0) after 7m49s
[18:48:55.789] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:24:0)
[18:48:55.830] Build status: STARTED
[18:48:55.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:25:0) after 6m31s
[18:55:26.908] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:25:0)
[18:55:26.928] Build status: STARTED
[18:55:26.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:26:0) after 2m13s
[18:57:39.993] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:26:0)
[18:57:40.032] Build status: STARTED
[18:57:40.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:27:0) after 1m58s
[18:59:38.188] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:27:0)
[18:59:38.296] Build status: STARTED
[18:59:38.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:28:0) after 3m27s
[19:03:05.378] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:28:0)
[19:03:05.401] Build status: STARTED
[19:03:05.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:29:0) after 5m58s
[19:09:03.423] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:29:0)
[19:09:03.445] Build status: STARTED
[19:09:03.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:30:0) after 6m3s
[19:15:06.577] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:30:0)
[19:15:06.658] Build status: STARTED
[19:15:06.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:31:0) after 4m45s
[19:19:51.687] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:31:0)
[19:19:51.721] Build status: STARTED
[19:19:51.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:32:0) after 6m22s
[19:26:13.742] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:32:0)
[19:26:13.771] Build status: STARTED
[19:26:13.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:33:0) after 5m19s
[19:31:32.837] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:33:0)
[19:31:32.918] Build status: STARTED
[19:31:32.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:34:0) after 2m13s
[19:33:45.975] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:34:0)
[19:33:45.992] Build status: STARTED
[19:33:45.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:35:0) after 7m22s
[19:41:08.035] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:35:0)
[19:41:08.056] Build status: STARTED
[19:41:08.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:36:0) after 1m55s
[19:43:03.099] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:36:0)
[19:43:03.125] Build status: STARTED
[19:43:03.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:37:0) after 3m41s
[19:46:44.358] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:37:0)
[19:46:44.436] Build status: STARTED
[19:46:44.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:38:0) after 7m4s
[19:53:48.467] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:38:0)
[19:53:48.501] Build status: STARTED
[19:53:48.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:39:0) after 4m35s
[19:58:23.515] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:39:0)
[19:58:23.583] Build status: STARTED
[19:58:23.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:40:0) after 3m21s
[20:01:44.605] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:40:0)
[20:01:45.093] Build status: STARTED
[20:01:45.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:41:0) after 2m30s
[20:04:15.155] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:41:0)
[20:04:15.809] Build status: STARTED
[20:04:15.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:42:0) after 8m9s
[20:12:24.894] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:42:0)
[20:12:24.932] Build status: STARTED
[20:12:24.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:43:0) after 8m42s
[20:21:06.952] Handling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:43:0)
[20:21:07.032] Build status: STARTED
[20:21:07.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-rel-cft:8916455656475091696:44:0) after 3m36s
[20:21:27.144] Received PubSub notification, asking Buildbucket for the build status
[20:21:27.169] Build:
{
"id": "8751025594488540529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-rel-cft"
},
"number": 4685,
"createdBy": "project:chromium",
"createTime": "2024-04-10T17:04:30.530630646Z",
"startTime": "2024-04-10T17:06:16.107598337Z",
"endTime": "2024-04-10T20:21:25.783299859Z",
"updateTime": "2024-04-10T20:21:25.783299859Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4759c502b84545020661a4f137361ad6ce1127cd",
"ref": "refs/heads/main"
}
}
}
[20:21:27.169] Invocation finished in 3h16m57.916701989s with status SUCCEEDED