[11:48:49.562] New invocation is queued and will start shortly
[11:48:50.655] Starting the invocation (attempt 1)
[11:48:50.679] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/43222db4c56fb73b85e0dfbbfcb2b1cb51223591"
[11:48:50.679] Popped gitiles commit info from properties and tags
[11:48:50.679] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:48:50.679] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:48:50.679] Buildbucket request:
{
"requestId": "8878696581204499936",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8878696581204499936",
"job": "chromium-m136/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0537ea04d09b08d5edd053fff1a0a5bd7425fd5d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@0537ea04d09b08d5edd053fff1a0a5bd7425fd5d",
"title": "0537ea04d09b08d5edd053fff1a0a5bd7425fd5d",
"url": "https://chromium.googlesource.com/chromium/src/+/0537ea04d09b08d5edd053fff1a0a5bd7425fd5d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "83518a0c6043c5a5ea43b03733d9cd97dc5bafbf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@83518a0c6043c5a5ea43b03733d9cd97dc5bafbf",
"title": "83518a0c6043c5a5ea43b03733d9cd97dc5bafbf",
"url": "https://chromium.googlesource.com/chromium/src/+/83518a0c6043c5a5ea43b03733d9cd97dc5bafbf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e54c1abc21e39f3b7f467f941e0a1c25841347ee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@e54c1abc21e39f3b7f467f941e0a1c25841347ee",
"title": "e54c1abc21e39f3b7f467f941e0a1c25841347ee",
"url": "https://chromium.googlesource.com/chromium/src/+/e54c1abc21e39f3b7f467f941e0a1c25841347ee"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "77a803cc2fd4aad330ac7cb1f1ff7809a93bba20"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@77a803cc2fd4aad330ac7cb1f1ff7809a93bba20",
"title": "77a803cc2fd4aad330ac7cb1f1ff7809a93bba20",
"url": "https://chromium.googlesource.com/chromium/src/+/77a803cc2fd4aad330ac7cb1f1ff7809a93bba20"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "950bd845f87c63426da323eb13dc1f0c4566af4c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@950bd845f87c63426da323eb13dc1f0c4566af4c",
"title": "950bd845f87c63426da323eb13dc1f0c4566af4c",
"url": "https://chromium.googlesource.com/chromium/src/+/950bd845f87c63426da323eb13dc1f0c4566af4c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b684363dd6cbd176db508c62953a62711ecc87f7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@b684363dd6cbd176db508c62953a62711ecc87f7",
"title": "b684363dd6cbd176db508c62953a62711ecc87f7",
"url": "https://chromium.googlesource.com/chromium/src/+/b684363dd6cbd176db508c62953a62711ecc87f7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "51a4626843ec99bbeb88bd8df693bb7ff275f5f3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@51a4626843ec99bbeb88bd8df693bb7ff275f5f3",
"title": "51a4626843ec99bbeb88bd8df693bb7ff275f5f3",
"url": "https://chromium.googlesource.com/chromium/src/+/51a4626843ec99bbeb88bd8df693bb7ff275f5f3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "43222db4c56fb73b85e0dfbbfcb2b1cb51223591"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@43222db4c56fb73b85e0dfbbfcb2b1cb51223591",
"title": "43222db4c56fb73b85e0dfbbfcb2b1cb51223591",
"url": "https://chromium.googlesource.com/chromium/src/+/43222db4c56fb73b85e0dfbbfcb2b1cb51223591"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "43222db4c56fb73b85e0dfbbfcb2b1cb51223591",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878696581204499936"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:48:51.146] Scheduled build:
{
"id": "8713266519311045777",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 378,
"createdBy": "project:chromium-m136",
"createTime": "2025-06-01T11:48:50.738516875Z",
"updateTime": "2025-06-01T11:48:50.738516875Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "43222db4c56fb73b85e0dfbbfcb2b1cb51223591",
"ref": "refs/branch-heads/7103"
}
}
}
[11:48:51.146] Task URL: https://cr-buildbucket.appspot.com/build/8713266519311045777
[11:48:51.146] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:2:0) after 6m0s
[11:48:52.109] Received PubSub notification, asking Buildbucket for the build status
[11:48:52.147] Build status: SCHEDULED
[11:48:55.417] Received PubSub notification, asking Buildbucket for the build status
[11:48:55.440] Build status: STARTED
[11:54:51.262] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:2:0)
[11:54:51.288] Build status: STARTED
[11:54:51.288] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:5:0) after 5m30s
[12:00:21.355] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:5:0)
[12:00:21.379] Build status: STARTED
[12:00:21.379] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:6:0) after 2m52s
[12:03:13.400] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:6:0)
[12:03:13.426] Build status: STARTED
[12:03:13.427] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:7:0) after 6m59s
[12:10:12.453] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:7:0)
[12:10:12.489] Build status: STARTED
[12:10:12.489] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:8:0) after 7m33s
[12:17:45.575] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:8:0)
[12:17:45.607] Build status: STARTED
[12:17:45.607] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:9:0) after 5m7s
[12:22:52.726] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:9:0)
[12:22:52.744] Build status: STARTED
[12:22:52.744] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:10:0) after 4m2s
[12:26:54.962] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:10:0)
[12:26:54.985] Build status: STARTED
[12:26:54.985] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:11:0) after 1m31s
[12:28:26.017] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:11:0)
[12:28:26.040] Build status: STARTED
[12:28:26.040] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:12:0) after 4m23s
[12:32:49.173] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:12:0)
[12:32:49.245] Build status: STARTED
[12:32:49.245] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:13:0) after 2m55s
[12:35:44.408] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:13:0)
[12:35:44.434] Build status: STARTED
[12:35:44.434] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:14:0) after 2m42s
[12:38:26.455] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:14:0)
[12:38:26.517] Build status: STARTED
[12:38:26.517] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:15:0) after 2m6s
[12:40:32.535] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:15:0)
[12:40:32.555] Build status: STARTED
[12:40:32.555] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:16:0) after 8m35s
[12:49:07.581] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:16:0)
[12:49:07.595] Build status: STARTED
[12:49:07.595] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:17:0) after 2m32s
[12:51:39.663] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:17:0)
[12:51:39.680] Build status: STARTED
[12:51:39.680] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:18:0) after 1m17s
[12:52:56.708] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:18:0)
[12:52:56.848] Build status: STARTED
[12:52:56.848] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:19:0) after 7m12s
[13:00:09.020] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:19:0)
[13:00:09.066] Build status: STARTED
[13:00:09.066] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:20:0) after 4m53s
[13:05:02.301] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:20:0)
[13:05:02.345] Build status: STARTED
[13:05:02.345] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:21:0) after 6m48s
[13:11:50.565] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:21:0)
[13:11:50.614] Build status: STARTED
[13:11:50.614] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:22:0) after 2m20s
[13:14:10.635] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:22:0)
[13:14:10.657] Build status: STARTED
[13:14:10.657] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:23:0) after 1m1s
[13:15:11.671] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:23:0)
[13:15:11.734] Build status: STARTED
[13:15:11.734] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:24:0) after 9m26s
[13:24:37.806] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:24:0)
[13:24:37.830] Build status: STARTED
[13:24:37.830] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:25:0) after 4m1s
[13:28:39.571] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:25:0)
[13:28:39.596] Build status: STARTED
[13:28:39.596] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:26:0) after 4m16s
[13:32:55.790] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:26:0)
[13:32:55.809] Build status: STARTED
[13:32:55.809] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:27:0) after 2m6s
[13:35:01.883] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:27:0)
[13:35:01.899] Build status: STARTED
[13:35:01.899] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:28:0) after 8m56s
[13:43:57.944] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:28:0)
[13:43:57.973] Build status: STARTED
[13:43:57.973] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:29:0) after 7m42s
[13:51:39.991] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:29:0)
[13:51:40.078] Build status: STARTED
[13:51:40.078] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:30:0) after 5m55s
[13:57:35.258] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:30:0)
[13:57:35.273] Build status: STARTED
[13:57:35.273] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:31:0) after 5m38s
[14:03:13.310] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:31:0)
[14:03:13.336] Build status: STARTED
[14:03:13.336] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:32:0) after 2m20s
[14:05:33.363] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:32:0)
[14:05:33.381] Build status: STARTED
[14:05:33.381] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:33:0) after 7m38s
[14:13:11.535] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:33:0)
[14:13:11.640] Build status: STARTED
[14:13:11.640] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:34:0) after 4m43s
[14:17:55.365] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:34:0)
[14:17:55.381] Build status: STARTED
[14:17:55.381] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:35:0) after 5m25s
[14:23:20.395] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:35:0)
[14:23:20.411] Build status: STARTED
[14:23:20.411] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:36:0) after 6m23s
[14:29:43.435] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:36:0)
[14:29:43.459] Build status: STARTED
[14:29:43.459] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:37:0) after 9m49s
[14:39:32.506] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:37:0)
[14:39:32.522] Build status: STARTED
[14:39:32.522] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:38:0) after 1m11s
[14:40:43.554] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:38:0)
[14:40:43.570] Build status: STARTED
[14:40:43.570] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:39:0) after 1m48s
[14:42:31.755] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:39:0)
[14:42:31.771] Build status: STARTED
[14:42:31.771] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:40:0) after 3m4s
[14:45:35.825] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:40:0)
[14:45:35.840] Build status: STARTED
[14:45:35.840] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:41:0) after 3m49s
[14:49:24.854] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:41:0)
[14:49:24.874] Build status: STARTED
[14:49:24.874] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:42:0) after 3m53s
[14:53:17.898] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:42:0)
[14:53:17.948] Build status: STARTED
[14:53:17.949] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:43:0) after 7m30s
[15:00:47.964] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:43:0)
[15:00:47.982] Build status: STARTED
[15:00:47.982] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:44:0) after 5m44s
[15:06:32.075] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:44:0)
[15:06:32.108] Build status: STARTED
[15:06:32.108] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:45:0) after 7m9s
[15:13:41.144] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:45:0)
[15:13:41.263] Build status: STARTED
[15:13:41.263] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:46:0) after 6m43s
[15:20:24.332] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:46:0)
[15:20:24.353] Build status: STARTED
[15:20:24.353] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:47:0) after 8m30s
[15:28:54.455] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:47:0)
[15:28:54.490] Build status: STARTED
[15:28:54.490] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:48:0) after 1m45s
[15:30:39.687] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:48:0)
[15:30:39.705] Build status: STARTED
[15:30:39.705] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:49:0) after 6m8s
[15:36:47.857] Handling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:49:0)
[15:36:47.875] Build status: STARTED
[15:36:47.875] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win32-official:8878696581204499936:50:0) after 4m12s
[15:37:01.209] Received PubSub notification, asking Buildbucket for the build status
[15:37:01.232] Build:
{
"id": "8713266519311045777",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win32-official"
},
"number": 378,
"createdBy": "project:chromium-m136",
"createTime": "2025-06-01T11:48:50.738516875Z",
"startTime": "2025-06-01T11:48:55.190861886Z",
"endTime": "2025-06-01T15:37:00.638470265Z",
"updateTime": "2025-06-01T15:37:00.638470265Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "43222db4c56fb73b85e0dfbbfcb2b1cb51223591",
"ref": "refs/branch-heads/7103"
}
}
}
[15:37:01.232] Invocation finished in 3h48m11.682993797s with status SUCCEEDED