[16:19:45.264] New invocation is queued and will start shortly
[16:19:46.372] Starting the invocation (attempt 1)
[16:19:46.394] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/07b4712a78f01548e26ca932958d9bb72e6a510f"
[16:19:46.394] Popped gitiles commit info from properties and tags
[16:19:46.394] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:19:46.394] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:19:46.395] Buildbucket request:
{
"requestId": "8876142820802769824",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876142820802769824",
"job": "chromium-m139/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b2c0f8fe2b1796bcd9b7856b09ba8b857c2ef941"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@b2c0f8fe2b1796bcd9b7856b09ba8b857c2ef941",
"title": "b2c0f8fe2b1796bcd9b7856b09ba8b857c2ef941",
"url": "https://chromium.googlesource.com/chromium/src/+/b2c0f8fe2b1796bcd9b7856b09ba8b857c2ef941"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9e4080bbd335dff2efa73f8bed7836192dc11704"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@9e4080bbd335dff2efa73f8bed7836192dc11704",
"title": "9e4080bbd335dff2efa73f8bed7836192dc11704",
"url": "https://chromium.googlesource.com/chromium/src/+/9e4080bbd335dff2efa73f8bed7836192dc11704"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "20e08aa8093b231709942d4e3d8523db9503f6f8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@20e08aa8093b231709942d4e3d8523db9503f6f8",
"title": "20e08aa8093b231709942d4e3d8523db9503f6f8",
"url": "https://chromium.googlesource.com/chromium/src/+/20e08aa8093b231709942d4e3d8523db9503f6f8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "74497b908a871e5536df0af858668b6b5f065f25"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@74497b908a871e5536df0af858668b6b5f065f25",
"title": "74497b908a871e5536df0af858668b6b5f065f25",
"url": "https://chromium.googlesource.com/chromium/src/+/74497b908a871e5536df0af858668b6b5f065f25"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5dc83ad2e40b6c1b6373dcd9d8de3d11b54bf9c4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@5dc83ad2e40b6c1b6373dcd9d8de3d11b54bf9c4",
"title": "5dc83ad2e40b6c1b6373dcd9d8de3d11b54bf9c4",
"url": "https://chromium.googlesource.com/chromium/src/+/5dc83ad2e40b6c1b6373dcd9d8de3d11b54bf9c4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "333ff913c2f9055fb52404376c8fd11685985528"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@333ff913c2f9055fb52404376c8fd11685985528",
"title": "333ff913c2f9055fb52404376c8fd11685985528",
"url": "https://chromium.googlesource.com/chromium/src/+/333ff913c2f9055fb52404376c8fd11685985528"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cf66e1c35efc0d147a48a346a409f5306eff385e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@cf66e1c35efc0d147a48a346a409f5306eff385e",
"title": "cf66e1c35efc0d147a48a346a409f5306eff385e",
"url": "https://chromium.googlesource.com/chromium/src/+/cf66e1c35efc0d147a48a346a409f5306eff385e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "652938afa0f673298ae2d20cf4d6c853d4a747fc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@652938afa0f673298ae2d20cf4d6c853d4a747fc",
"title": "652938afa0f673298ae2d20cf4d6c853d4a747fc",
"url": "https://chromium.googlesource.com/chromium/src/+/652938afa0f673298ae2d20cf4d6c853d4a747fc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "07b4712a78f01548e26ca932958d9bb72e6a510f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@07b4712a78f01548e26ca932958d9bb72e6a510f",
"title": "07b4712a78f01548e26ca932958d9bb72e6a510f",
"url": "https://chromium.googlesource.com/chromium/src/+/07b4712a78f01548e26ca932958d9bb72e6a510f"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07b4712a78f01548e26ca932958d9bb72e6a510f",
"ref": "refs/branch-heads/7258"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876142820802769824"
},
{
"key": "scheduler_job_id",
"value": "chromium-m139/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:19:47.184] Scheduled build:
{
"id": "8710712758898210257",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"number": 36,
"createdBy": "project:chromium-m139",
"createTime": "2025-06-29T16:19:46.454884332Z",
"updateTime": "2025-06-29T16:19:46.454884332Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07b4712a78f01548e26ca932958d9bb72e6a510f",
"ref": "refs/branch-heads/7258"
}
}
}
[16:19:47.184] Task URL: https://cr-buildbucket.appspot.com/build/8710712758898210257
[16:19:47.184] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:2:0) after 1m58s
[16:19:48.104] Received PubSub notification, asking Buildbucket for the build status
[16:19:48.134] Build status: SCHEDULED
[16:21:10.494] Received PubSub notification, asking Buildbucket for the build status
[16:21:10.519] Build status: STARTED
[16:21:45.322] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:2:0)
[16:21:45.349] Build status: STARTED
[16:21:45.349] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:5:0) after 8m56s
[16:30:41.371] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:5:0)
[16:30:41.490] Build status: STARTED
[16:30:41.490] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:6:0) after 5m59s
[16:36:40.652] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:6:0)
[16:36:40.698] Build status: STARTED
[16:36:40.698] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:7:0) after 2m49s
[16:39:29.751] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:7:0)
[16:39:29.805] Build status: STARTED
[16:39:29.805] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:8:0) after 5m46s
[16:45:16.145] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:8:0)
[16:45:16.217] Build status: STARTED
[16:45:16.217] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:9:0) after 2m51s
[16:48:07.196] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:9:0)
[16:48:07.223] Build status: STARTED
[16:48:07.223] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:10:0) after 9m16s
[16:57:23.485] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:10:0)
[16:57:23.511] Build status: STARTED
[16:57:23.511] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:11:0) after 2m30s
[16:59:53.671] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:11:0)
[16:59:53.687] Build status: STARTED
[16:59:53.687] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:12:0) after 4m39s
[17:04:32.972] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:12:0)
[17:04:33.001] Build status: STARTED
[17:04:33.001] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:13:0) after 5m59s
[17:10:32.027] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:13:0)
[17:10:32.047] Build status: STARTED
[17:10:32.047] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:14:0) after 1m41s
[17:12:13.141] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:14:0)
[17:12:13.166] Build status: STARTED
[17:12:13.166] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:15:0) after 7m14s
[17:19:27.305] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:15:0)
[17:19:27.328] Build status: STARTED
[17:19:27.328] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:16:0) after 1m52s
[17:21:19.352] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:16:0)
[17:21:19.366] Build status: STARTED
[17:21:19.367] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:17:0) after 3m42s
[17:25:01.451] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:17:0)
[17:25:01.525] Build status: STARTED
[17:25:01.525] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:18:0) after 8m35s
[17:33:36.646] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:18:0)
[17:33:36.915] Build status: STARTED
[17:33:36.915] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:19:0) after 3m41s
[17:37:18.176] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:19:0)
[17:37:18.223] Build status: STARTED
[17:37:18.223] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:20:0) after 5m30s
[17:42:48.351] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:20:0)
[17:42:48.375] Build status: STARTED
[17:42:48.375] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:21:0) after 6m44s
[17:49:32.379] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:21:0)
[17:49:32.397] Build status: STARTED
[17:49:32.398] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:22:0) after 6m30s
[17:56:02.562] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:22:0)
[17:56:02.598] Build status: STARTED
[17:56:02.598] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:23:0) after 1m18s
[17:57:20.730] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:23:0)
[17:57:20.754] Build status: STARTED
[17:57:20.754] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:24:0) after 8m43s
[18:06:03.956] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:24:0)
[18:06:03.982] Build status: STARTED
[18:06:03.982] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:25:0) after 4m26s
[18:10:29.987] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:25:0)
[18:10:30.034] Build status: STARTED
[18:10:30.034] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:26:0) after 7m0s
[18:17:30.054] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:26:0)
[18:17:30.073] Build status: STARTED
[18:17:30.073] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:27:0) after 8m37s
[18:26:07.098] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:27:0)
[18:26:07.115] Build status: STARTED
[18:26:07.115] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:28:0) after 7m39s
[18:33:46.256] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:28:0)
[18:33:46.272] Build status: STARTED
[18:33:46.272] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:29:0) after 4m49s
[18:38:35.305] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:29:0)
[18:38:35.323] Build status: STARTED
[18:38:35.323] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:30:0) after 3m59s
[18:42:34.458] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:30:0)
[18:42:34.491] Build status: STARTED
[18:42:34.491] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:31:0) after 4m20s
[18:46:54.605] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:31:0)
[18:46:54.648] Build status: STARTED
[18:46:54.648] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:32:0) after 6m18s
[18:53:12.769] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:32:0)
[18:53:12.790] Build status: STARTED
[18:53:12.790] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:33:0) after 8m33s
[19:01:46.121] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:33:0)
[19:01:46.137] Build status: STARTED
[19:01:46.137] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:34:0) after 7m19s
[19:09:05.299] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:34:0)
[19:09:05.322] Build status: STARTED
[19:09:05.322] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:35:0) after 8m28s
[19:17:33.445] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:35:0)
[19:17:33.491] Build status: STARTED
[19:17:33.491] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:36:0) after 2m56s
[19:20:29.474] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:36:0)
[19:20:29.507] Build status: STARTED
[19:20:29.507] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:37:0) after 7m53s
[19:28:22.530] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:37:0)
[19:28:22.552] Build status: STARTED
[19:28:22.552] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:38:0) after 6m31s
[19:34:53.673] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:38:0)
[19:34:53.699] Build status: STARTED
[19:34:53.699] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8876142820802769824:39:0) after 3m9s
[19:35:53.767] Received PubSub notification, asking Buildbucket for the build status
[19:35:53.787] Build:
{
"id": "8710712758898210257",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"number": 36,
"createdBy": "project:chromium-m139",
"createTime": "2025-06-29T16:19:46.454884332Z",
"startTime": "2025-06-29T16:21:10.280224151Z",
"endTime": "2025-06-29T19:35:30.825079381Z",
"updateTime": "2025-06-29T19:35:30.825079381Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "07b4712a78f01548e26ca932958d9bb72e6a510f",
"ref": "refs/branch-heads/7258"
}
}
}
[19:35:53.787] Invocation finished in 3h16m8.533069281s with status SUCCEEDED