[13:14:58.654] New invocation is queued and will start shortly
[13:14:59.710] Starting the invocation (attempt 1)
[13:14:59.740] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/db1cae895064ec0c555f2d9b8c6ca9b9e95490ba"
[13:14:59.740] Popped gitiles commit info from properties and tags
[13:14:59.740] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:14:59.741] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:14:59.742] Buildbucket request:
{
"requestId": "8886210709231291584",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8886210709231291584",
"job": "chromium-m135/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "77602fa050d63ebbddb44b88d2f8e17cb9dfa6fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@77602fa050d63ebbddb44b88d2f8e17cb9dfa6fb",
"title": "77602fa050d63ebbddb44b88d2f8e17cb9dfa6fb",
"url": "https://chromium.googlesource.com/chromium/src/+/77602fa050d63ebbddb44b88d2f8e17cb9dfa6fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3175c8a380b963fc770fcabbaac652adfba722fb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@3175c8a380b963fc770fcabbaac652adfba722fb",
"title": "3175c8a380b963fc770fcabbaac652adfba722fb",
"url": "https://chromium.googlesource.com/chromium/src/+/3175c8a380b963fc770fcabbaac652adfba722fb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d3de7e49cee26824ea8c1107bbe1f47b9c9c4af2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@d3de7e49cee26824ea8c1107bbe1f47b9c9c4af2",
"title": "d3de7e49cee26824ea8c1107bbe1f47b9c9c4af2",
"url": "https://chromium.googlesource.com/chromium/src/+/d3de7e49cee26824ea8c1107bbe1f47b9c9c4af2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "87342da1bdb69d72fcaf5b37bbc722fedb9a45d4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@87342da1bdb69d72fcaf5b37bbc722fedb9a45d4",
"title": "87342da1bdb69d72fcaf5b37bbc722fedb9a45d4",
"url": "https://chromium.googlesource.com/chromium/src/+/87342da1bdb69d72fcaf5b37bbc722fedb9a45d4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "42cc9f57ee53a830d587650b260816e560770f94"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@42cc9f57ee53a830d587650b260816e560770f94",
"title": "42cc9f57ee53a830d587650b260816e560770f94",
"url": "https://chromium.googlesource.com/chromium/src/+/42cc9f57ee53a830d587650b260816e560770f94"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e13b084de9d5f1288cc99a1a0da0b6d2882eee80"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@e13b084de9d5f1288cc99a1a0da0b6d2882eee80",
"title": "e13b084de9d5f1288cc99a1a0da0b6d2882eee80",
"url": "https://chromium.googlesource.com/chromium/src/+/e13b084de9d5f1288cc99a1a0da0b6d2882eee80"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6bcb018b3bb82d4ecc8fd074371fdaecfdded766"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@6bcb018b3bb82d4ecc8fd074371fdaecfdded766",
"title": "6bcb018b3bb82d4ecc8fd074371fdaecfdded766",
"url": "https://chromium.googlesource.com/chromium/src/+/6bcb018b3bb82d4ecc8fd074371fdaecfdded766"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8c9ea5a4db3cd09ba604c4a7d6aa84f692afbd03"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@8c9ea5a4db3cd09ba604c4a7d6aa84f692afbd03",
"title": "8c9ea5a4db3cd09ba604c4a7d6aa84f692afbd03",
"url": "https://chromium.googlesource.com/chromium/src/+/8c9ea5a4db3cd09ba604c4a7d6aa84f692afbd03"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "977f2e6dc93bd6476d642ce82367b3dd57cd832b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@977f2e6dc93bd6476d642ce82367b3dd57cd832b",
"title": "977f2e6dc93bd6476d642ce82367b3dd57cd832b",
"url": "https://chromium.googlesource.com/chromium/src/+/977f2e6dc93bd6476d642ce82367b3dd57cd832b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "844583e9ea631aafe7efcf1ccb7d2bf7d000c719"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@844583e9ea631aafe7efcf1ccb7d2bf7d000c719",
"title": "844583e9ea631aafe7efcf1ccb7d2bf7d000c719",
"url": "https://chromium.googlesource.com/chromium/src/+/844583e9ea631aafe7efcf1ccb7d2bf7d000c719"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c9ce2de35a54e3739d000475a910d0e692d93a8c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@c9ce2de35a54e3739d000475a910d0e692d93a8c",
"title": "c9ce2de35a54e3739d000475a910d0e692d93a8c",
"url": "https://chromium.googlesource.com/chromium/src/+/c9ce2de35a54e3739d000475a910d0e692d93a8c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9abf13b044fb221ddbf9f9ad90a2ace728647a27"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@9abf13b044fb221ddbf9f9ad90a2ace728647a27",
"title": "9abf13b044fb221ddbf9f9ad90a2ace728647a27",
"url": "https://chromium.googlesource.com/chromium/src/+/9abf13b044fb221ddbf9f9ad90a2ace728647a27"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "db1cae895064ec0c555f2d9b8c6ca9b9e95490ba"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@db1cae895064ec0c555f2d9b8c6ca9b9e95490ba",
"title": "db1cae895064ec0c555f2d9b8c6ca9b9e95490ba",
"url": "https://chromium.googlesource.com/chromium/src/+/db1cae895064ec0c555f2d9b8c6ca9b9e95490ba"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "db1cae895064ec0c555f2d9b8c6ca9b9e95490ba",
"ref": "refs/branch-heads/7049"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886210709231291584"
},
{
"key": "scheduler_job_id",
"value": "chromium-m135/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:15:00.479] Scheduled build:
{
"id": "8720780647316464257",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"number": 50,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-10T13:14:59.849535262Z",
"updateTime": "2025-03-10T13:14:59.849535262Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "db1cae895064ec0c555f2d9b8c6ca9b9e95490ba",
"ref": "refs/branch-heads/7049"
}
}
}
[13:15:00.479] Task URL: https://cr-buildbucket.appspot.com/build/8720780647316464257
[13:15:00.479] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:2:0) after 4m5s
[13:15:00.728] Received PubSub notification, asking Buildbucket for the build status
[13:15:00.746] Build status: SCHEDULED
[13:15:05.993] Received PubSub notification, asking Buildbucket for the build status
[13:15:06.079] Build status: STARTED
[13:19:05.583] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:2:0)
[13:19:05.607] Build status: STARTED
[13:19:05.607] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:5:0) after 9m26s
[13:28:31.699] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:5:0)
[13:28:31.725] Build status: STARTED
[13:28:31.725] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:6:0) after 2m13s
[13:30:44.925] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:6:0)
[13:30:44.958] Build status: STARTED
[13:30:44.958] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:7:0) after 2m52s
[13:33:36.976] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:7:0)
[13:33:36.996] Build status: STARTED
[13:33:36.996] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:8:0) after 7m25s
[13:41:02.019] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:8:0)
[13:41:02.037] Build status: STARTED
[13:41:02.037] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:9:0) after 2m23s
[13:43:25.050] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:9:0)
[13:43:25.075] Build status: STARTED
[13:43:25.075] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:10:0) after 9m53s
[13:53:18.146] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:10:0)
[13:53:18.168] Build status: STARTED
[13:53:18.168] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:11:0) after 8m26s
[14:01:44.200] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:11:0)
[14:01:44.236] Build status: STARTED
[14:01:44.236] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:12:0) after 5m25s
[14:07:09.323] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:12:0)
[14:07:09.352] Build status: STARTED
[14:07:09.352] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:13:0) after 9m42s
[14:16:51.510] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:13:0)
[14:16:51.532] Build status: STARTED
[14:16:51.532] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:14:0) after 5m0s
[14:21:51.741] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:14:0)
[14:21:51.759] Build status: STARTED
[14:21:51.759] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:15:0) after 5m40s
[14:27:31.780] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:15:0)
[14:27:31.840] Build status: STARTED
[14:27:31.840] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:16:0) after 5m26s
[14:32:57.873] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:16:0)
[14:32:57.938] Build status: STARTED
[14:32:57.939] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:17:0) after 6m29s
[14:39:27.191] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:17:0)
[14:39:27.210] Build status: STARTED
[14:39:27.210] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:18:0) after 5m53s
[14:45:20.447] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:18:0)
[14:45:20.463] Build status: STARTED
[14:45:20.463] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:19:0) after 6m6s
[14:51:26.527] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:19:0)
[14:51:26.543] Build status: STARTED
[14:51:26.543] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:20:0) after 1m21s
[14:52:47.943] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:20:0)
[14:52:48.048] Build status: STARTED
[14:52:48.048] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:21:0) after 9m55s
[15:02:43.067] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:21:0)
[15:02:43.106] Build status: STARTED
[15:02:43.106] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:22:0) after 9m44s
[15:12:27.239] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:22:0)
[15:12:27.279] Build status: STARTED
[15:12:27.279] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:23:0) after 1m15s
[15:13:43.217] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:23:0)
[15:13:43.257] Build status: STARTED
[15:13:43.257] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:24:0) after 8m0s
[15:21:43.291] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:24:0)
[15:21:43.323] Build status: STARTED
[15:21:43.323] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:25:0) after 5m50s
[15:27:33.374] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:25:0)
[15:27:33.393] Build status: STARTED
[15:27:33.393] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:26:0) after 1m21s
[15:28:54.466] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:26:0)
[15:28:54.520] Build status: STARTED
[15:28:54.520] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:27:0) after 6m27s
[15:35:21.571] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:27:0)
[15:35:21.593] Build status: STARTED
[15:35:21.593] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:28:0) after 7m18s
[15:42:39.735] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:28:0)
[15:42:39.752] Build status: STARTED
[15:42:39.753] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:29:0) after 6m6s
[15:48:45.770] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:29:0)
[15:48:45.789] Build status: STARTED
[15:48:45.789] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:30:0) after 5m34s
[15:54:19.810] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:30:0)
[15:54:19.904] Build status: STARTED
[15:54:19.904] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:31:0) after 3m29s
[15:57:48.897] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:31:0)
[15:57:48.964] Build status: STARTED
[15:57:48.964] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:32:0) after 6m21s
[16:04:09.994] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:32:0)
[16:04:10.012] Build status: STARTED
[16:04:10.012] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:33:0) after 3m29s
[16:07:39.029] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:33:0)
[16:07:39.090] Build status: STARTED
[16:07:39.090] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886210709231291584:34:0) after 9m9s
[16:09:05.737] Received PubSub notification, asking Buildbucket for the build status
[16:09:05.773] Build:
{
"id": "8720780647316464257",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"number": 50,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-10T13:14:59.849535262Z",
"startTime": "2025-03-10T13:15:05.743439581Z",
"endTime": "2025-03-10T16:09:05.228169808Z",
"updateTime": "2025-03-10T16:09:05.228169808Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "db1cae895064ec0c555f2d9b8c6ca9b9e95490ba",
"ref": "refs/branch-heads/7049"
}
}
}
[16:09:05.774] Invocation finished in 2h54m7.134398676s with status SUCCEEDED