[18:41:56.642] New invocation is queued and will start shortly
[18:41:57.690] Starting the invocation (attempt 1)
[18:41:57.717] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/82601221c16b35defcb8d48320dc5688a17adba4"
[18:41:57.717] Popped gitiles commit info from properties and tags
[18:41:57.717] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:41:57.717] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:41:57.718] Buildbucket request:
{
"requestId": "8884831183774180672",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884831183774180672",
"job": "chromium-m134/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e4fe319ac7cb7e7433e2c6b4fb707626deff463f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@e4fe319ac7cb7e7433e2c6b4fb707626deff463f",
"title": "e4fe319ac7cb7e7433e2c6b4fb707626deff463f",
"url": "https://chromium.googlesource.com/chromium/src/+/e4fe319ac7cb7e7433e2c6b4fb707626deff463f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c899e20cfb76f2adba3d1a790b56accdf730e919"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@c899e20cfb76f2adba3d1a790b56accdf730e919",
"title": "c899e20cfb76f2adba3d1a790b56accdf730e919",
"url": "https://chromium.googlesource.com/chromium/src/+/c899e20cfb76f2adba3d1a790b56accdf730e919"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "66903444331338c1ec6403d22cbef7fc8a1593b9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@66903444331338c1ec6403d22cbef7fc8a1593b9",
"title": "66903444331338c1ec6403d22cbef7fc8a1593b9",
"url": "https://chromium.googlesource.com/chromium/src/+/66903444331338c1ec6403d22cbef7fc8a1593b9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1f47af722c8984bc8445ab3600567b4d644606eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@1f47af722c8984bc8445ab3600567b4d644606eb",
"title": "1f47af722c8984bc8445ab3600567b4d644606eb",
"url": "https://chromium.googlesource.com/chromium/src/+/1f47af722c8984bc8445ab3600567b4d644606eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ba14393fc692198467f0a347956232edd1ab290"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@1ba14393fc692198467f0a347956232edd1ab290",
"title": "1ba14393fc692198467f0a347956232edd1ab290",
"url": "https://chromium.googlesource.com/chromium/src/+/1ba14393fc692198467f0a347956232edd1ab290"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e2e631024ce00cce4a2a52bf1c6494c8501a428e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@e2e631024ce00cce4a2a52bf1c6494c8501a428e",
"title": "e2e631024ce00cce4a2a52bf1c6494c8501a428e",
"url": "https://chromium.googlesource.com/chromium/src/+/e2e631024ce00cce4a2a52bf1c6494c8501a428e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2d5fa862078b4fb6f2d1b3964057b315fd92ab4a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@2d5fa862078b4fb6f2d1b3964057b315fd92ab4a",
"title": "2d5fa862078b4fb6f2d1b3964057b315fd92ab4a",
"url": "https://chromium.googlesource.com/chromium/src/+/2d5fa862078b4fb6f2d1b3964057b315fd92ab4a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "91871241dff1f15d695dba39b88213f4823206d4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@91871241dff1f15d695dba39b88213f4823206d4",
"title": "91871241dff1f15d695dba39b88213f4823206d4",
"url": "https://chromium.googlesource.com/chromium/src/+/91871241dff1f15d695dba39b88213f4823206d4"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3c781af245f3896b699ffda595a4e1e544f10eda"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@3c781af245f3896b699ffda595a4e1e544f10eda",
"title": "3c781af245f3896b699ffda595a4e1e544f10eda",
"url": "https://chromium.googlesource.com/chromium/src/+/3c781af245f3896b699ffda595a4e1e544f10eda"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "82601221c16b35defcb8d48320dc5688a17adba4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@82601221c16b35defcb8d48320dc5688a17adba4",
"title": "82601221c16b35defcb8d48320dc5688a17adba4",
"url": "https://chromium.googlesource.com/chromium/src/+/82601221c16b35defcb8d48320dc5688a17adba4"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "82601221c16b35defcb8d48320dc5688a17adba4",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884831183774180672"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:41:58.442] Scheduled build:
{
"id": "8719401121906088689",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 318,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-25T18:41:57.802310912Z",
"updateTime": "2025-03-25T18:41:57.802310912Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "82601221c16b35defcb8d48320dc5688a17adba4",
"ref": "refs/branch-heads/6998"
}
}
}
[18:41:58.443] Task URL: https://cr-buildbucket.appspot.com/build/8719401121906088689
[18:41:58.443] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:2:0) after 3m15s
[18:42:19.634] Received PubSub notification, asking Buildbucket for the build status
[18:42:19.670] Build status: STARTED
[18:42:25.746] Received PubSub notification, asking Buildbucket for the build status
[18:42:25.792] Build status: STARTED
[18:45:13.456] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:2:0)
[18:45:13.480] Build status: STARTED
[18:45:13.480] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:5:0) after 2m9s
[18:47:22.496] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:5:0)
[18:47:22.521] Build status: STARTED
[18:47:22.521] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:6:0) after 5m51s
[18:53:13.539] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:6:0)
[18:53:13.560] Build status: STARTED
[18:53:13.560] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:7:0) after 9m47s
[19:03:00.579] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:7:0)
[19:03:00.647] Build status: STARTED
[19:03:00.647] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:8:0) after 2m37s
[19:05:37.710] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:8:0)
[19:05:37.735] Build status: STARTED
[19:05:37.735] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:9:0) after 9m13s
[19:14:50.763] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:9:0)
[19:14:50.792] Build status: STARTED
[19:14:50.792] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:10:0) after 3m41s
[19:18:31.942] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:10:0)
[19:18:31.979] Build status: STARTED
[19:18:31.979] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:11:0) after 7m50s
[19:26:22.057] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:11:0)
[19:26:22.143] Build status: STARTED
[19:26:22.143] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:12:0) after 1m2s
[19:27:24.307] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:12:0)
[19:27:24.322] Build status: STARTED
[19:27:24.322] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:13:0) after 5m39s
[19:33:03.340] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:13:0)
[19:33:03.359] Build status: STARTED
[19:33:03.359] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:14:0) after 7m14s
[19:40:17.601] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:14:0)
[19:40:17.624] Build status: STARTED
[19:40:17.624] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:15:0) after 8m47s
[19:49:04.650] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:15:0)
[19:49:04.667] Build status: STARTED
[19:49:04.667] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:16:0) after 5m44s
[19:54:48.677] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:16:0)
[19:54:48.694] Build status: STARTED
[19:54:48.694] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:17:0) after 7m43s
[20:02:31.805] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:17:0)
[20:02:31.824] Build status: STARTED
[20:02:31.824] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:18:0) after 4m39s
[20:07:10.987] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:18:0)
[20:07:11.006] Build status: STARTED
[20:07:11.006] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:19:0) after 6m58s
[20:14:09.414] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:19:0)
[20:14:09.443] Build status: STARTED
[20:14:09.443] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:20:0) after 4m47s
[20:18:56.559] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:20:0)
[20:18:56.576] Build status: STARTED
[20:18:56.576] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:21:0) after 2m35s
[20:21:31.641] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:21:0)
[20:21:31.659] Build status: STARTED
[20:21:31.659] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:22:0) after 2m50s
[20:24:21.685] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:22:0)
[20:24:21.714] Build status: STARTED
[20:24:21.714] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:23:0) after 2m33s
[20:26:54.845] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:23:0)
[20:26:54.896] Build status: STARTED
[20:26:54.896] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:24:0) after 4m39s
[20:31:33.940] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:24:0)
[20:31:33.961] Build status: STARTED
[20:31:33.961] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:25:0) after 1m46s
[20:33:20.192] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:25:0)
[20:33:20.207] Build status: STARTED
[20:33:20.207] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:26:0) after 8m0s
[20:41:20.410] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:26:0)
[20:41:20.429] Build status: STARTED
[20:41:20.429] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:27:0) after 7m10s
[20:48:30.537] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:27:0)
[20:48:30.564] Build status: STARTED
[20:48:30.564] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:28:0) after 5m59s
[20:54:29.590] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:28:0)
[20:54:29.612] Build status: STARTED
[20:54:29.612] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:29:0) after 8m27s
[21:02:56.695] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:29:0)
[21:02:56.714] Build status: STARTED
[21:02:56.714] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:30:0) after 6m17s
[21:09:13.764] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:30:0)
[21:09:13.781] Build status: STARTED
[21:09:13.781] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:31:0) after 9m5s
[21:18:18.895] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:31:0)
[21:18:18.913] Build status: STARTED
[21:18:18.913] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:32:0) after 4m24s
[21:22:43.037] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:32:0)
[21:22:43.063] Build status: STARTED
[21:22:43.063] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:33:0) after 1m17s
[21:24:00.130] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:33:0)
[21:24:00.149] Build status: STARTED
[21:24:00.149] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:34:0) after 9m38s
[21:33:38.263] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:34:0)
[21:33:38.281] Build status: STARTED
[21:33:38.281] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:35:0) after 8m52s
[21:42:30.382] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:35:0)
[21:42:30.398] Build status: STARTED
[21:42:30.398] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:36:0) after 6m11s
[21:48:41.435] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:36:0)
[21:48:41.462] Build status: STARTED
[21:48:41.462] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:37:0) after 4m31s
[21:53:12.558] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:37:0)
[21:53:12.575] Build status: STARTED
[21:53:12.575] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:38:0) after 7m32s
[22:00:44.699] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:38:0)
[22:00:44.894] Build status: STARTED
[22:00:44.894] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:39:0) after 5m52s
[22:06:36.915] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:39:0)
[22:06:37.000] Build status: STARTED
[22:06:37.000] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:40:0) after 4m27s
[22:11:04.040] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:40:0)
[22:11:04.065] Build status: STARTED
[22:11:04.065] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:41:0) after 8m30s
[22:19:34.136] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:41:0)
[22:19:34.169] Build status: STARTED
[22:19:34.169] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:42:0) after 5m24s
[22:24:58.205] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:42:0)
[22:24:58.275] Build status: STARTED
[22:24:58.275] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:43:0) after 7m47s
[22:32:45.438] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:43:0)
[22:32:45.458] Build status: STARTED
[22:32:45.458] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8884831183774180672:44:0) after 7m36s
[22:38:15.582] Received PubSub notification, asking Buildbucket for the build status
[22:38:15.605] Build:
{
"id": "8719401121906088689",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 318,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-25T18:41:57.802310912Z",
"startTime": "2025-03-25T18:42:04.315449999Z",
"endTime": "2025-03-25T22:38:15.113053421Z",
"updateTime": "2025-03-25T22:38:15.113053421Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "82601221c16b35defcb8d48320dc5688a17adba4",
"ref": "refs/branch-heads/6998"
}
}
}
[22:38:15.605] Invocation finished in 3h56m18.967535641s with status SUCCEEDED