[07:36:28.308] New invocation is queued and will start shortly
[07:36:30.033] Starting the invocation (attempt 1)
[07:36:30.056] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/76a8218a4eade14c42d93124b7da31ca73b59c95"
[07:36:30.056] Popped gitiles commit info from properties and tags
[07:36:30.056] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:36:30.058] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:36:30.058] Buildbucket request:
{
"requestId": "8876628727730549472",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876628727730549472",
"job": "chromium-m138/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cf6d22a50bc3d88d0e715f031a4057820253456e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@cf6d22a50bc3d88d0e715f031a4057820253456e",
"title": "cf6d22a50bc3d88d0e715f031a4057820253456e",
"url": "https://chromium.googlesource.com/chromium/src/+/cf6d22a50bc3d88d0e715f031a4057820253456e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d80ebc0d6fc222a73aabaf05362d437b3856d50c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@d80ebc0d6fc222a73aabaf05362d437b3856d50c",
"title": "d80ebc0d6fc222a73aabaf05362d437b3856d50c",
"url": "https://chromium.googlesource.com/chromium/src/+/d80ebc0d6fc222a73aabaf05362d437b3856d50c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fb782ea01c669c57d00eb71d6f9cb3b2b4a7f557"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@fb782ea01c669c57d00eb71d6f9cb3b2b4a7f557",
"title": "fb782ea01c669c57d00eb71d6f9cb3b2b4a7f557",
"url": "https://chromium.googlesource.com/chromium/src/+/fb782ea01c669c57d00eb71d6f9cb3b2b4a7f557"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7e64bdf9f079176a43fff41e42af32d3079b2304"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@7e64bdf9f079176a43fff41e42af32d3079b2304",
"title": "7e64bdf9f079176a43fff41e42af32d3079b2304",
"url": "https://chromium.googlesource.com/chromium/src/+/7e64bdf9f079176a43fff41e42af32d3079b2304"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "efdf3ac471ac6587fec2aa994c2d831c593c5d29"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@efdf3ac471ac6587fec2aa994c2d831c593c5d29",
"title": "efdf3ac471ac6587fec2aa994c2d831c593c5d29",
"url": "https://chromium.googlesource.com/chromium/src/+/efdf3ac471ac6587fec2aa994c2d831c593c5d29"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6efccf655963721fc025a7a3a3c3a4e806d2c43c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@6efccf655963721fc025a7a3a3c3a4e806d2c43c",
"title": "6efccf655963721fc025a7a3a3c3a4e806d2c43c",
"url": "https://chromium.googlesource.com/chromium/src/+/6efccf655963721fc025a7a3a3c3a4e806d2c43c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "72642a86a9f223ad8f68e9a3ec1d3ff66b85d154"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@72642a86a9f223ad8f68e9a3ec1d3ff66b85d154",
"title": "72642a86a9f223ad8f68e9a3ec1d3ff66b85d154",
"url": "https://chromium.googlesource.com/chromium/src/+/72642a86a9f223ad8f68e9a3ec1d3ff66b85d154"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "76a8218a4eade14c42d93124b7da31ca73b59c95"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@76a8218a4eade14c42d93124b7da31ca73b59c95",
"title": "76a8218a4eade14c42d93124b7da31ca73b59c95",
"url": "https://chromium.googlesource.com/chromium/src/+/76a8218a4eade14c42d93124b7da31ca73b59c95"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "76a8218a4eade14c42d93124b7da31ca73b59c95",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876628727730549472"
},
{
"key": "scheduler_job_id",
"value": "chromium-m138/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:36:30.669] Scheduled build:
{
"id": "8711198665153154913",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"number": 187,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-24T07:36:30.139528862Z",
"updateTime": "2025-06-24T07:36:30.139528862Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "76a8218a4eade14c42d93124b7da31ca73b59c95",
"ref": "refs/branch-heads/7204"
}
},
"canary": true
}
[07:36:30.669] Task URL: https://cr-buildbucket.appspot.com/build/8711198665153154913
[07:36:30.669] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:2:0) after 9m28s
[07:36:31.704] Received PubSub notification, asking Buildbucket for the build status
[07:36:31.725] Build status: SCHEDULED
[07:37:46.856] Received PubSub notification, asking Buildbucket for the build status
[07:37:46.880] Build status: STARTED
[07:45:58.689] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:2:0)
[07:45:58.716] Build status: STARTED
[07:45:58.716] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:5:0) after 7m17s
[07:53:15.765] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:5:0)
[07:53:15.783] Build status: STARTED
[07:53:15.783] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:6:0) after 7m6s
[08:00:21.798] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:6:0)
[08:00:21.939] Build status: STARTED
[08:00:21.939] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:7:0) after 2m43s
[08:03:04.960] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:7:0)
[08:03:04.978] Build status: STARTED
[08:03:04.978] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:8:0) after 6m7s
[08:09:11.997] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:8:0)
[08:09:12.023] Build status: STARTED
[08:09:12.023] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:9:0) after 7m7s
[08:16:19.110] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:9:0)
[08:16:19.128] Build status: STARTED
[08:16:19.128] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:10:0) after 9m25s
[08:25:44.362] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:10:0)
[08:25:44.382] Build status: STARTED
[08:25:44.383] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:11:0) after 7m42s
[08:33:26.405] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:11:0)
[08:33:26.424] Build status: STARTED
[08:33:26.424] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:12:0) after 2m29s
[08:35:55.660] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:12:0)
[08:35:55.685] Build status: STARTED
[08:35:55.685] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:13:0) after 9m42s
[08:45:37.801] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:13:0)
[08:45:37.827] Build status: STARTED
[08:45:37.827] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:14:0) after 3m22s
[08:48:59.894] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:14:0)
[08:48:59.910] Build status: STARTED
[08:48:59.910] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:15:0) after 7m36s
[08:56:35.934] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:15:0)
[08:56:36.207] Build status: STARTED
[08:56:36.207] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:16:0) after 9m38s
[09:06:14.235] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:16:0)
[09:06:14.251] Build status: STARTED
[09:06:14.251] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:17:0) after 5m13s
[09:11:27.326] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:17:0)
[09:11:27.345] Build status: STARTED
[09:11:27.345] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:18:0) after 5m49s
[09:17:16.419] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:18:0)
[09:17:16.445] Build status: STARTED
[09:17:16.445] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:19:0) after 8m58s
[09:26:14.428] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:19:0)
[09:26:14.445] Build status: STARTED
[09:26:14.445] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:20:0) after 3m53s
[09:30:07.504] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:20:0)
[09:30:07.590] Build status: STARTED
[09:30:07.590] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:21:0) after 4m56s
[09:35:03.782] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:21:0)
[09:35:03.818] Build status: STARTED
[09:35:03.818] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:22:0) after 9m4s
[09:44:07.852] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:22:0)
[09:44:07.878] Build status: STARTED
[09:44:07.878] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:23:0) after 3m22s
[09:47:29.874] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:23:0)
[09:47:29.892] Build status: STARTED
[09:47:29.892] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:24:0) after 5m15s
[09:52:44.915] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:24:0)
[09:52:44.943] Build status: STARTED
[09:52:44.943] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:25:0) after 5m45s
[09:58:30.108] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:25:0)
[09:58:30.129] Build status: STARTED
[09:58:30.129] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:26:0) after 5m2s
[10:03:32.152] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:26:0)
[10:03:32.184] Build status: STARTED
[10:03:32.184] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:27:0) after 5m55s
[10:09:27.304] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:27:0)
[10:09:27.331] Build status: STARTED
[10:09:27.331] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:28:0) after 1m38s
[10:11:05.378] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:28:0)
[10:11:05.404] Build status: STARTED
[10:11:05.404] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:29:0) after 6m40s
[10:17:45.431] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:29:0)
[10:17:45.449] Build status: STARTED
[10:17:45.450] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:30:0) after 6m11s
[10:23:56.476] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:30:0)
[10:23:56.502] Build status: STARTED
[10:23:56.502] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:31:0) after 4m58s
[10:28:54.607] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:31:0)
[10:28:54.631] Build status: STARTED
[10:28:54.631] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:32:0) after 7m51s
[10:36:45.727] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:32:0)
[10:36:45.747] Build status: STARTED
[10:36:45.747] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:33:0) after 4m17s
[10:41:02.885] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:33:0)
[10:41:02.911] Build status: STARTED
[10:41:02.911] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:34:0) after 9m12s
[10:50:14.944] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:34:0)
[10:50:14.960] Build status: STARTED
[10:50:14.960] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:35:0) after 7m37s
[10:57:51.992] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:35:0)
[10:57:52.014] Build status: STARTED
[10:57:52.014] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:36:0) after 5m52s
[11:03:44.075] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:36:0)
[11:03:44.092] Build status: STARTED
[11:03:44.092] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:37:0) after 4m0s
[11:07:44.135] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:37:0)
[11:07:44.158] Build status: STARTED
[11:07:44.158] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:38:0) after 6m26s
[11:14:10.204] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:38:0)
[11:14:10.219] Build status: STARTED
[11:14:10.220] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:39:0) after 3m30s
[11:17:40.258] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:39:0)
[11:17:40.292] Build status: STARTED
[11:17:40.292] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8876628727730549472:40:0) after 8m3s
[11:24:28.328] Received PubSub notification, asking Buildbucket for the build status
[11:24:28.356] Build:
{
"id": "8711198665153154913",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"number": 187,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-24T07:36:30.139528862Z",
"startTime": "2025-06-24T07:37:46.627758088Z",
"endTime": "2025-06-24T11:24:27.998744018Z",
"updateTime": "2025-06-24T11:24:27.998744018Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "76a8218a4eade14c42d93124b7da31ca73b59c95",
"ref": "refs/branch-heads/7204"
}
},
"canary": true
}
[11:24:28.356] Invocation finished in 3h48m0.058994853s with status SUCCEEDED