[21:32:38.587] New invocation is queued and will start shortly
[21:32:39.648] Starting the invocation (attempt 1)
[21:32:39.675] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72e51c3f73ab65b519102bad7023e474e5491592"
[21:32:39.675] Popped gitiles commit info from properties and tags
[21:32:39.675] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:32:39.677] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:32:39.677] Buildbucket request:
{
"requestId": "8891524619831292496",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "72e51c3f73ab65b519102bad7023e474e5491592",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8891524619831292496"
},
{
"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"
}
}
[21:32:40.579] Scheduled build:
{
"id": "8726094557953962273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 10983,
"createdBy": "project:chromium",
"createTime": "2025-01-10T21:32:39.754199486Z",
"updateTime": "2025-01-10T21:32:39.754199486Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "72e51c3f73ab65b519102bad7023e474e5491592",
"ref": "refs/heads/main"
}
}
}
[21:32:40.579] Task URL: https://cr-buildbucket.appspot.com/build/8726094557953962273
[21:32:40.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:2:0) after 4m44s
[21:32:41.480] Received PubSub notification, asking Buildbucket for the build status
[21:32:41.510] Build status: SCHEDULED
[21:32:46.202] Received PubSub notification, asking Buildbucket for the build status
[21:32:46.224] Build status: STARTED
[21:37:24.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:2:0)
[21:37:24.707] Build status: STARTED
[21:37:24.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:5:0) after 8m31s
[21:45:55.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:5:0)
[21:45:55.763] Build status: STARTED
[21:45:55.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:6:0) after 8m46s
[21:54:41.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:6:0)
[21:54:41.819] Build status: STARTED
[21:54:41.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:7:0) after 5m6s
[21:59:47.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:7:0)
[21:59:47.868] Build status: STARTED
[21:59:47.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:8:0) after 4m39s
[22:04:26.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:8:0)
[22:04:26.921] Build status: STARTED
[22:04:26.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:9:0) after 4m30s
[22:08:56.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:9:0)
[22:08:56.988] Build status: STARTED
[22:08:56.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:10:0) after 6m14s
[22:15:11.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:10:0)
[22:15:11.050] Build status: STARTED
[22:15:11.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:11:0) after 9m50s
[22:25:01.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:11:0)
[22:25:01.178] Build status: STARTED
[22:25:01.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:12:0) after 3m16s
[22:28:17.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:12:0)
[22:28:17.223] Build status: STARTED
[22:28:17.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:13:0) after 7m14s
[22:35:31.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:13:0)
[22:35:31.355] Build status: STARTED
[22:35:31.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:14:0) after 2m47s
[22:38:18.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:14:0)
[22:38:18.468] Build status: STARTED
[22:38:18.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:15:0) after 8m40s
[22:46:58.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:15:0)
[22:46:58.510] Build status: STARTED
[22:46:58.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:16:0) after 3m8s
[22:50:06.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:16:0)
[22:50:06.558] Build status: STARTED
[22:50:06.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:17:0) after 7m52s
[22:57:58.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:17:0)
[22:57:58.609] Build status: STARTED
[22:57:58.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:18:0) after 8m33s
[23:06:31.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:18:0)
[23:06:31.843] Build status: STARTED
[23:06:31.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:19:0) after 5m9s
[23:11:40.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:19:0)
[23:11:40.883] Build status: STARTED
[23:11:40.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:20:0) after 9m23s
[23:21:04.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:20:0)
[23:21:04.039] Build status: STARTED
[23:21:04.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:21:0) after 2m39s
[23:23:43.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:21:0)
[23:23:43.140] Build status: STARTED
[23:23:43.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:22:0) after 2m48s
[23:26:31.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:22:0)
[23:26:31.210] Build status: STARTED
[23:26:31.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:23:0) after 5m57s
[23:32:28.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:23:0)
[23:32:28.247] Build status: STARTED
[23:32:28.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:24:0) after 8m29s
[23:40:57.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:24:0)
[23:40:57.375] Build status: STARTED
[23:40:57.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:25:0) after 9m45s
[23:50:42.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:25:0)
[23:50:42.504] Build status: STARTED
[23:50:42.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:26:0) after 7m39s
[23:58:21.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:26:0)
[23:58:21.546] Build status: STARTED
[23:58:21.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:27:0) after 2m18s
[00:00:39.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:27:0)
[00:00:39.603] Build status: STARTED
[00:00:39.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:28:0) after 1m49s
[00:02:28.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:28:0)
[00:02:28.664] Build status: STARTED
[00:02:28.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:29:0) after 8m57s
[00:11:25.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:29:0)
[00:11:25.725] Build status: STARTED
[00:11:25.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:30:0) after 4m44s
[00:16:09.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:30:0)
[00:16:09.781] Build status: STARTED
[00:16:09.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:31:0) after 2m31s
[00:18:40.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:31:0)
[00:18:40.822] Build status: STARTED
[00:18:40.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:32:0) after 5m33s
[00:24:13.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:32:0)
[00:24:13.877] Build status: STARTED
[00:24:13.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:33:0) after 6m13s
[00:30:26.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:33:0)
[00:30:27.020] Build status: STARTED
[00:30:27.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:34:0) after 9m41s
[00:40:08.050] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:34:0)
[00:40:08.083] Build status: STARTED
[00:40:08.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:35:0) after 3m26s
[00:43:34.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:35:0)
[00:43:34.270] Build status: STARTED
[00:43:34.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:36:0) after 2m47s
[00:46:21.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:36:0)
[00:46:21.311] Build status: STARTED
[00:46:21.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:37:0) after 3m51s
[00:50:12.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:37:0)
[00:50:12.363] Build status: STARTED
[00:50:12.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:38:0) after 7m41s
[00:57:53.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:38:0)
[00:57:53.408] Build status: STARTED
[00:57:53.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:39:0) after 3m31s
[01:01:24.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:39:0)
[01:01:24.474] Build status: STARTED
[01:01:24.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:40:0) after 3m49s
[01:05:13.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:40:0)
[01:05:13.515] Build status: STARTED
[01:05:13.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:41:0) after 9m37s
[01:14:50.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:41:0)
[01:14:50.638] Build status: STARTED
[01:14:50.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:42:0) after 7m7s
[01:21:57.722] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:42:0)
[01:21:57.738] Build status: STARTED
[01:21:57.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:43:0) after 5m3s
[01:27:00.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:43:0)
[01:27:01.007] Build status: STARTED
[01:27:01.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:44:0) after 3m15s
[01:30:16.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:44:0)
[01:30:16.058] Build status: STARTED
[01:30:16.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:45:0) after 1m51s
[01:32:07.197] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:45:0)
[01:32:07.320] Build status: STARTED
[01:32:07.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:46:0) after 7m4s
[01:39:11.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:46:0)
[01:39:11.422] Build status: STARTED
[01:39:11.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:47:0) after 9m54s
[01:49:05.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:47:0)
[01:49:05.524] Build status: STARTED
[01:49:05.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:48:0) after 4m11s
[01:53:16.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:48:0)
[01:53:16.574] Build status: STARTED
[01:53:16.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:49:0) after 8m22s
[02:01:38.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:49:0)
[02:01:38.620] Build status: STARTED
[02:01:38.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:50:0) after 2m40s
[02:04:18.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:50:0)
[02:04:18.683] Build status: STARTED
[02:04:18.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:8891524619831292496:51:0) after 7m46s
[02:09:49.010] Received PubSub notification, asking Buildbucket for the build status
[02:09:49.101] Build:
{
"id": "8726094557953962273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "win-official"
},
"number": 10983,
"createdBy": "project:chromium",
"createTime": "2025-01-10T21:32:39.754199486Z",
"startTime": "2025-01-10T21:32:46.007589848Z",
"endTime": "2025-01-11T02:09:45.527877072Z",
"updateTime": "2025-01-11T02:09:45.527877072Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "72e51c3f73ab65b519102bad7023e474e5491592",
"ref": "refs/heads/main"
}
}
}
[02:09:49.101] Invocation finished in 4h37m10.520450226s with status SUCCEEDED