[05:57:52.313] New invocation is queued and will start shortly
[05:57:53.459] Starting the invocation (attempt 1)
[05:57:53.481] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1c8366964efa473a15815784e38f0c48c9b4d8c7"
[05:57:53.481] Popped gitiles commit info from properties and tags
[05:57:53.481] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:57:53.481] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:57:53.481] Buildbucket request:
{
"requestId": "8868118816260696208",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868118816260696208",
"job": "chromium-m140/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a1f0a8f07dc8f5e7f1a182fafda91275b8e7ea47"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@a1f0a8f07dc8f5e7f1a182fafda91275b8e7ea47",
"title": "a1f0a8f07dc8f5e7f1a182fafda91275b8e7ea47",
"url": "https://chromium.googlesource.com/chromium/src/+/a1f0a8f07dc8f5e7f1a182fafda91275b8e7ea47"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4058670b11078469ac3f540f356ded00f8a299a1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@4058670b11078469ac3f540f356ded00f8a299a1",
"title": "4058670b11078469ac3f540f356ded00f8a299a1",
"url": "https://chromium.googlesource.com/chromium/src/+/4058670b11078469ac3f540f356ded00f8a299a1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b2325c0b431e5775a235a3514db431e7640ce203"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@b2325c0b431e5775a235a3514db431e7640ce203",
"title": "b2325c0b431e5775a235a3514db431e7640ce203",
"url": "https://chromium.googlesource.com/chromium/src/+/b2325c0b431e5775a235a3514db431e7640ce203"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dc92534055dc8b77d4da256aebce5963f9684c1c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@dc92534055dc8b77d4da256aebce5963f9684c1c",
"title": "dc92534055dc8b77d4da256aebce5963f9684c1c",
"url": "https://chromium.googlesource.com/chromium/src/+/dc92534055dc8b77d4da256aebce5963f9684c1c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "46019ecb4e053a32a98d6a96a68ceaecb15d230f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@46019ecb4e053a32a98d6a96a68ceaecb15d230f",
"title": "46019ecb4e053a32a98d6a96a68ceaecb15d230f",
"url": "https://chromium.googlesource.com/chromium/src/+/46019ecb4e053a32a98d6a96a68ceaecb15d230f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dea039eb6d61e7f47d82a51b5de4dd06c0e9721f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@dea039eb6d61e7f47d82a51b5de4dd06c0e9721f",
"title": "dea039eb6d61e7f47d82a51b5de4dd06c0e9721f",
"url": "https://chromium.googlesource.com/chromium/src/+/dea039eb6d61e7f47d82a51b5de4dd06c0e9721f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "feb5775197ca4fe2e3de3518d870d9db5ee59790"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@feb5775197ca4fe2e3de3518d870d9db5ee59790",
"title": "feb5775197ca4fe2e3de3518d870d9db5ee59790",
"url": "https://chromium.googlesource.com/chromium/src/+/feb5775197ca4fe2e3de3518d870d9db5ee59790"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1c8366964efa473a15815784e38f0c48c9b4d8c7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@1c8366964efa473a15815784e38f0c48c9b4d8c7",
"title": "1c8366964efa473a15815784e38f0c48c9b4d8c7",
"url": "https://chromium.googlesource.com/chromium/src/+/1c8366964efa473a15815784e38f0c48c9b4d8c7"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1c8366964efa473a15815784e38f0c48c9b4d8c7",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868118816260696208"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:57:54.102] Scheduled build:
{
"id": "8702688754321783201",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 538,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-26T05:57:53.533685975Z",
"updateTime": "2025-09-26T05:57:53.533685975Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1c8366964efa473a15815784e38f0c48c9b4d8c7",
"ref": "refs/branch-heads/7339"
}
}
}
[05:57:54.102] Task URL: https://cr-buildbucket.appspot.com/build/8702688754321783201
[05:57:54.102] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:2:0) after 7m34s
[05:57:54.905] Received PubSub notification, asking Buildbucket for the build status
[05:57:54.930] Build status: SCHEDULED
[05:58:07.949] Received PubSub notification, asking Buildbucket for the build status
[05:58:07.975] Build status: STARTED
[06:05:28.117] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:2:0)
[06:05:28.162] Build status: STARTED
[06:05:28.162] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:5:0) after 2m24s
[06:07:52.176] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:5:0)
[06:07:52.205] Build status: STARTED
[06:07:52.205] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:6:0) after 3m10s
[06:11:02.267] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:6:0)
[06:11:02.285] Build status: STARTED
[06:11:02.285] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:7:0) after 7m25s
[06:18:27.292] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:7:0)
[06:18:27.316] Build status: STARTED
[06:18:27.316] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:8:0) after 8m57s
[06:27:24.331] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:8:0)
[06:27:24.356] Build status: STARTED
[06:27:24.356] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:9:0) after 4m27s
[06:31:51.492] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:9:0)
[06:31:51.510] Build status: STARTED
[06:31:51.510] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:10:0) after 1m21s
[06:33:12.868] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:10:0)
[06:33:12.900] Build status: STARTED
[06:33:12.900] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:11:0) after 4m52s
[06:38:04.917] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:11:0)
[06:38:04.940] Build status: STARTED
[06:38:04.940] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:12:0) after 8m10s
[06:46:14.956] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:12:0)
[06:46:14.993] Build status: STARTED
[06:46:14.993] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:13:0) after 4m19s
[06:50:34.036] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:13:0)
[06:50:34.050] Build status: STARTED
[06:50:34.050] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:14:0) after 4m53s
[06:55:27.081] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:14:0)
[06:55:27.096] Build status: STARTED
[06:55:27.096] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:15:0) after 9m12s
[07:04:39.199] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:15:0)
[07:04:39.226] Build status: STARTED
[07:04:39.227] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:16:0) after 9m26s
[07:14:05.244] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:16:0)
[07:14:05.258] Build status: STARTED
[07:14:05.258] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:17:0) after 4m20s
[07:18:25.350] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:17:0)
[07:18:25.370] Build status: STARTED
[07:18:25.370] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:18:0) after 8m15s
[07:26:40.421] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:18:0)
[07:26:40.442] Build status: STARTED
[07:26:40.442] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:19:0) after 6m17s
[07:32:57.457] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:19:0)
[07:32:57.487] Build status: STARTED
[07:32:57.487] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:20:0) after 4m34s
[07:37:31.638] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:20:0)
[07:37:31.656] Build status: STARTED
[07:37:31.656] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:21:0) after 6m55s
[07:44:26.672] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:21:0)
[07:44:26.698] Build status: STARTED
[07:44:26.698] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:22:0) after 9m34s
[07:54:00.692] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:22:0)
[07:54:00.745] Build status: STARTED
[07:54:00.745] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:23:0) after 1m49s
[07:55:49.759] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:23:0)
[07:55:49.800] Build status: STARTED
[07:55:49.800] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:24:0) after 4m26s
[08:00:15.826] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:24:0)
[08:00:15.850] Build status: STARTED
[08:00:15.850] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:25:0) after 3m45s
[08:04:00.865] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:25:0)
[08:04:00.925] Build status: STARTED
[08:04:00.925] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:26:0) after 3m49s
[08:07:49.940] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:26:0)
[08:07:49.971] Build status: STARTED
[08:07:49.971] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:27:0) after 7m54s
[08:15:44.046] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:27:0)
[08:15:44.115] Build status: STARTED
[08:15:44.115] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:28:0) after 7m11s
[08:22:55.160] Handling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:28:0)
[08:22:55.225] Build status: STARTED
[08:22:55.225] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-arm64-rel:8868118816260696208:29:0) after 2m35s
[08:23:14.519] Received PubSub notification, asking Buildbucket for the build status
[08:23:14.638] Build:
{
"id": "8702688754321783201",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 538,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-26T05:57:53.533685975Z",
"startTime": "2025-09-26T05:58:07.721144388Z",
"endTime": "2025-09-26T08:23:14.193171598Z",
"updateTime": "2025-09-26T08:23:14.193171598Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1c8366964efa473a15815784e38f0c48c9b4d8c7",
"ref": "refs/branch-heads/7339"
}
}
}
[08:23:14.638] Invocation finished in 2h25m22.337692973s with status SUCCEEDED