[22:15:35.572] New invocation is queued and will start shortly
[22:15:36.863] Starting the invocation (attempt 1)
[22:15:36.891] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a872fae168eced803a24524454220d0751131dce"
[22:15:36.891] Popped gitiles commit info from properties and tags
[22:15:36.891] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:15:36.895] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:15:36.896] Buildbucket request:
{
"requestId": "8886448487552029472",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8886448487552029472",
"job": "chromium-m134/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0e6c9fe5f17fe2757bc360731a0992d091a40c67"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@0e6c9fe5f17fe2757bc360731a0992d091a40c67",
"title": "0e6c9fe5f17fe2757bc360731a0992d091a40c67",
"url": "https://chromium.googlesource.com/chromium/src/+/0e6c9fe5f17fe2757bc360731a0992d091a40c67"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5c0565f6c993d83023cdafa340b8dd8d59a075eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@5c0565f6c993d83023cdafa340b8dd8d59a075eb",
"title": "5c0565f6c993d83023cdafa340b8dd8d59a075eb",
"url": "https://chromium.googlesource.com/chromium/src/+/5c0565f6c993d83023cdafa340b8dd8d59a075eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fd54da84f56c041529a7223222c509ee39ccca04"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@fd54da84f56c041529a7223222c509ee39ccca04",
"title": "fd54da84f56c041529a7223222c509ee39ccca04",
"url": "https://chromium.googlesource.com/chromium/src/+/fd54da84f56c041529a7223222c509ee39ccca04"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6fa48e58915ff5abacb6b56f1cd036df6aaab4a9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@6fa48e58915ff5abacb6b56f1cd036df6aaab4a9",
"title": "6fa48e58915ff5abacb6b56f1cd036df6aaab4a9",
"url": "https://chromium.googlesource.com/chromium/src/+/6fa48e58915ff5abacb6b56f1cd036df6aaab4a9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "908dd9c4b9d9eea0c59b7f1dc2a0dec494f8f473"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@908dd9c4b9d9eea0c59b7f1dc2a0dec494f8f473",
"title": "908dd9c4b9d9eea0c59b7f1dc2a0dec494f8f473",
"url": "https://chromium.googlesource.com/chromium/src/+/908dd9c4b9d9eea0c59b7f1dc2a0dec494f8f473"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5d836499aee266412b4967898e117ff1b16c83ec"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@5d836499aee266412b4967898e117ff1b16c83ec",
"title": "5d836499aee266412b4967898e117ff1b16c83ec",
"url": "https://chromium.googlesource.com/chromium/src/+/5d836499aee266412b4967898e117ff1b16c83ec"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a22941a97966a72af6eb02002d8b1e200d9dc4eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@a22941a97966a72af6eb02002d8b1e200d9dc4eb",
"title": "a22941a97966a72af6eb02002d8b1e200d9dc4eb",
"url": "https://chromium.googlesource.com/chromium/src/+/a22941a97966a72af6eb02002d8b1e200d9dc4eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0",
"title": "7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0",
"url": "https://chromium.googlesource.com/chromium/src/+/7e3d5c978c6d3a6eda25692cfac7f893a2b20dd0"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b623489a994ecb8df7381fcceb54cfd3aafc9c3c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@b623489a994ecb8df7381fcceb54cfd3aafc9c3c",
"title": "b623489a994ecb8df7381fcceb54cfd3aafc9c3c",
"url": "https://chromium.googlesource.com/chromium/src/+/b623489a994ecb8df7381fcceb54cfd3aafc9c3c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "219ee74681fc640b1ee1af1c99df3729a0ec25a8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@219ee74681fc640b1ee1af1c99df3729a0ec25a8",
"title": "219ee74681fc640b1ee1af1c99df3729a0ec25a8",
"url": "https://chromium.googlesource.com/chromium/src/+/219ee74681fc640b1ee1af1c99df3729a0ec25a8"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0785feea94bd68072d21ebfb4426cfeabf88520f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@0785feea94bd68072d21ebfb4426cfeabf88520f",
"title": "0785feea94bd68072d21ebfb4426cfeabf88520f",
"url": "https://chromium.googlesource.com/chromium/src/+/0785feea94bd68072d21ebfb4426cfeabf88520f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0662a0c3f20d324f2776a621cba03ba6bedc85da"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@0662a0c3f20d324f2776a621cba03ba6bedc85da",
"title": "0662a0c3f20d324f2776a621cba03ba6bedc85da",
"url": "https://chromium.googlesource.com/chromium/src/+/0662a0c3f20d324f2776a621cba03ba6bedc85da"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a872fae168eced803a24524454220d0751131dce"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@a872fae168eced803a24524454220d0751131dce",
"title": "a872fae168eced803a24524454220d0751131dce",
"url": "https://chromium.googlesource.com/chromium/src/+/a872fae168eced803a24524454220d0751131dce"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a872fae168eced803a24524454220d0751131dce",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886448487552029472"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:15:37.633] Scheduled build:
{
"id": "8721018425434707793",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 341,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-07T22:15:36.965107893Z",
"updateTime": "2025-03-07T22:15:36.965107893Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a872fae168eced803a24524454220d0751131dce",
"ref": "refs/branch-heads/6998"
}
}
}
[22:15:37.633] Task URL: https://cr-buildbucket.appspot.com/build/8721018425434707793
[22:15:37.633] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:2:0) after 6m45s
[22:15:38.652] Received PubSub notification, asking Buildbucket for the build status
[22:15:38.687] Build status: SCHEDULED
[22:22:22.700] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:2:0)
[22:22:22.763] Build status: SCHEDULED
[22:22:22.763] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:4:0) after 9m27s
[22:31:49.793] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:4:0)
[22:31:49.884] Build status: SCHEDULED
[22:31:49.884] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:5:0) after 7m27s
[22:39:16.943] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:5:0)
[22:39:16.967] Build status: SCHEDULED
[22:39:16.967] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:6:0) after 8m54s
[22:48:11.089] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:6:0)
[22:48:11.147] Build status: SCHEDULED
[22:48:11.147] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:7:0) after 3m12s
[22:51:23.239] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:7:0)
[22:51:23.261] Build status: SCHEDULED
[22:51:23.261] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:8:0) after 1m32s
[22:52:55.283] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:8:0)
[22:52:55.309] Build status: SCHEDULED
[22:52:55.309] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:9:0) after 8m44s
[23:01:39.287] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:9:0)
[23:01:39.311] Build status: SCHEDULED
[23:01:39.311] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:10:0) after 9m51s
[23:11:30.361] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:10:0)
[23:11:30.452] Build status: SCHEDULED
[23:11:30.452] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:11:0) after 2m9s
[23:13:39.513] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:11:0)
[23:13:39.538] Build status: SCHEDULED
[23:13:39.538] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:12:0) after 1m47s
[23:15:26.536] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:12:0)
[23:15:26.554] Build status: SCHEDULED
[23:15:26.554] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:13:0) after 7m6s
[23:17:13.932] Received PubSub notification, asking Buildbucket for the build status
[23:17:14.015] Build status: STARTED
[23:22:32.580] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:13:0)
[23:22:32.602] Build status: STARTED
[23:22:32.602] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:15:0) after 9m4s
[23:31:36.668] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:15:0)
[23:31:36.682] Build status: STARTED
[23:31:36.682] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:16:0) after 2m48s
[23:34:24.790] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:16:0)
[23:34:24.819] Build status: STARTED
[23:34:24.820] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:17:0) after 6m22s
[23:40:46.835] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:17:0)
[23:40:46.856] Build status: STARTED
[23:40:46.856] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:18:0) after 6m5s
[23:46:51.930] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:18:0)
[23:46:51.951] Build status: STARTED
[23:46:51.951] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:19:0) after 8m8s
[23:55:00.006] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:19:0)
[23:55:00.025] Build status: STARTED
[23:55:00.025] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:20:0) after 5m16s
[00:00:16.049] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:20:0)
[00:00:16.105] Build status: STARTED
[00:00:16.106] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:21:0) after 1m54s
[00:02:10.126] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:21:0)
[00:02:10.168] Build status: STARTED
[00:02:10.168] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:22:0) after 7m43s
[00:09:53.196] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:22:0)
[00:09:53.213] Build status: STARTED
[00:09:53.213] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:23:0) after 4m49s
[00:14:42.239] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:23:0)
[00:14:42.267] Build status: STARTED
[00:14:42.267] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:24:0) after 6m36s
[00:21:18.366] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:24:0)
[00:21:18.385] Build status: STARTED
[00:21:18.385] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:25:0) after 6m20s
[00:27:38.407] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:25:0)
[00:27:38.427] Build status: STARTED
[00:27:38.427] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:26:0) after 2m16s
[00:29:54.450] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:26:0)
[00:29:54.473] Build status: STARTED
[00:29:54.473] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:27:0) after 8m10s
[00:38:04.487] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:27:0)
[00:38:04.565] Build status: STARTED
[00:38:04.565] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:28:0) after 2m25s
[00:40:29.653] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:28:0)
[00:40:29.672] Build status: STARTED
[00:40:29.672] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:29:0) after 1m21s
[00:41:50.690] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:29:0)
[00:41:50.714] Build status: STARTED
[00:41:50.714] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:30:0) after 4m54s
[00:46:44.729] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:30:0)
[00:46:44.864] Build status: STARTED
[00:46:44.864] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:31:0) after 1m5s
[00:47:49.927] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:31:0)
[00:47:50.200] Build status: STARTED
[00:47:50.200] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:32:0) after 5m29s
[00:53:19.229] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:32:0)
[00:53:19.243] Build status: STARTED
[00:53:19.243] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:33:0) after 8m45s
[01:02:04.335] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:33:0)
[01:02:04.366] Build status: STARTED
[01:02:04.366] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:34:0) after 7m15s
[01:09:19.365] Handling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:34:0)
[01:09:19.381] Build status: STARTED
[01:09:19.381] Scheduling timer "check-buildbucket-build-status" (chromium-m134/fuchsia-x64-cast-receiver-dbg:8886448487552029472:35:0) after 9m43s
[01:09:32.499] Received PubSub notification, asking Buildbucket for the build status
[01:09:32.517] Build:
{
"id": "8721018425434707793",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 341,
"createdBy": "project:chromium-m134",
"createTime": "2025-03-07T22:15:36.965107893Z",
"startTime": "2025-03-07T23:17:13.687480967Z",
"endTime": "2025-03-08T01:09:31.373447855Z",
"updateTime": "2025-03-08T01:09:31.373447855Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a872fae168eced803a24524454220d0751131dce",
"ref": "refs/branch-heads/6998"
}
}
}
[01:09:32.517] Invocation finished in 2h53m56.954916455s with status SUCCEEDED