[16:13:48.159] New invocation is queued and will start shortly
[16:13:49.245] Starting the invocation (attempt 1)
[16:13:49.271] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2516835433dc9297dfd1968057295bdbafc618cc"
[16:13:49.271] Popped gitiles commit info from properties and tags
[16:13:49.271] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:13:49.271] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:13:49.271] Buildbucket request:
{
"requestId": "8895712140000039296",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8895712140000039296",
"job": "chromium-m132/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e32f63a2e21ebaa308dce79494908ddc11d96cf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@8e32f63a2e21ebaa308dce79494908ddc11d96cf",
"title": "8e32f63a2e21ebaa308dce79494908ddc11d96cf",
"url": "https://chromium.googlesource.com/chromium/src/+/8e32f63a2e21ebaa308dce79494908ddc11d96cf"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "223c6029db62ee6103661d36aa7704c188ebfd4e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@223c6029db62ee6103661d36aa7704c188ebfd4e",
"title": "223c6029db62ee6103661d36aa7704c188ebfd4e",
"url": "https://chromium.googlesource.com/chromium/src/+/223c6029db62ee6103661d36aa7704c188ebfd4e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f56cd92e48ea5e55a4af16b2823be0b73c62954c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@f56cd92e48ea5e55a4af16b2823be0b73c62954c",
"title": "f56cd92e48ea5e55a4af16b2823be0b73c62954c",
"url": "https://chromium.googlesource.com/chromium/src/+/f56cd92e48ea5e55a4af16b2823be0b73c62954c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "318c62b75db8b9a5ad5940e03855ca666590d09c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@318c62b75db8b9a5ad5940e03855ca666590d09c",
"title": "318c62b75db8b9a5ad5940e03855ca666590d09c",
"url": "https://chromium.googlesource.com/chromium/src/+/318c62b75db8b9a5ad5940e03855ca666590d09c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e96c90b1da902b327b03e54785c86473d3a1b73d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@e96c90b1da902b327b03e54785c86473d3a1b73d",
"title": "e96c90b1da902b327b03e54785c86473d3a1b73d",
"url": "https://chromium.googlesource.com/chromium/src/+/e96c90b1da902b327b03e54785c86473d3a1b73d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "133299e1d24624532119643da4bfc6c894b0603a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@133299e1d24624532119643da4bfc6c894b0603a",
"title": "133299e1d24624532119643da4bfc6c894b0603a",
"url": "https://chromium.googlesource.com/chromium/src/+/133299e1d24624532119643da4bfc6c894b0603a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d48ecce4298a0b1e3d003d29f9197a9297337bcf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@d48ecce4298a0b1e3d003d29f9197a9297337bcf",
"title": "d48ecce4298a0b1e3d003d29f9197a9297337bcf",
"url": "https://chromium.googlesource.com/chromium/src/+/d48ecce4298a0b1e3d003d29f9197a9297337bcf"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c9842d0173eda2c6f338c6c37004f153ac32cc7e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@c9842d0173eda2c6f338c6c37004f153ac32cc7e",
"title": "c9842d0173eda2c6f338c6c37004f153ac32cc7e",
"url": "https://chromium.googlesource.com/chromium/src/+/c9842d0173eda2c6f338c6c37004f153ac32cc7e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7722b5e6bdb1cd50e895531862e11f77365c7461"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@7722b5e6bdb1cd50e895531862e11f77365c7461",
"title": "7722b5e6bdb1cd50e895531862e11f77365c7461",
"url": "https://chromium.googlesource.com/chromium/src/+/7722b5e6bdb1cd50e895531862e11f77365c7461"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7b3062103f27e662b4288e3cda240ad90b00bc63"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@7b3062103f27e662b4288e3cda240ad90b00bc63",
"title": "7b3062103f27e662b4288e3cda240ad90b00bc63",
"url": "https://chromium.googlesource.com/chromium/src/+/7b3062103f27e662b4288e3cda240ad90b00bc63"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "948cdc4596dc7d448d5173b17027c6fff9fae76e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@948cdc4596dc7d448d5173b17027c6fff9fae76e",
"title": "948cdc4596dc7d448d5173b17027c6fff9fae76e",
"url": "https://chromium.googlesource.com/chromium/src/+/948cdc4596dc7d448d5173b17027c6fff9fae76e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4b9ddda8f8da6c3c6702b2a8f39c25a8773d9479"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@4b9ddda8f8da6c3c6702b2a8f39c25a8773d9479",
"title": "4b9ddda8f8da6c3c6702b2a8f39c25a8773d9479",
"url": "https://chromium.googlesource.com/chromium/src/+/4b9ddda8f8da6c3c6702b2a8f39c25a8773d9479"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2516835433dc9297dfd1968057295bdbafc618cc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@2516835433dc9297dfd1968057295bdbafc618cc",
"title": "2516835433dc9297dfd1968057295bdbafc618cc",
"url": "https://chromium.googlesource.com/chromium/src/+/2516835433dc9297dfd1968057295bdbafc618cc"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2516835433dc9297dfd1968057295bdbafc618cc",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895712140000039296"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:13:49.849] Scheduled build:
{
"id": "8730282078113521217",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "mac-official"
},
"number": 121,
"createdBy": "project:chromium-m132",
"createTime": "2024-11-25T16:13:49.329637706Z",
"updateTime": "2024-11-25T16:13:49.329637706Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2516835433dc9297dfd1968057295bdbafc618cc",
"ref": "refs/branch-heads/6834"
}
}
}
[16:13:49.849] Task URL: https://cr-buildbucket.appspot.com/build/8730282078113521217
[16:13:49.849] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:2:0) after 2m46s
[16:13:50.847] Received PubSub notification, asking Buildbucket for the build status
[16:13:50.879] Build status: SCHEDULED
[16:13:55.701] Received PubSub notification, asking Buildbucket for the build status
[16:13:55.729] Build status: STARTED
[16:16:35.947] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:2:0)
[16:16:35.970] Build status: STARTED
[16:16:35.970] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:5:0) after 7m48s
[16:24:24.026] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:5:0)
[16:24:24.048] Build status: STARTED
[16:24:24.048] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:6:0) after 5m22s
[16:29:46.065] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:6:0)
[16:29:46.272] Build status: STARTED
[16:29:46.272] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:7:0) after 8m26s
[16:38:12.331] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:7:0)
[16:38:12.347] Build status: STARTED
[16:38:12.347] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:8:0) after 2m13s
[16:40:26.270] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:8:0)
[16:40:26.321] Build status: STARTED
[16:40:26.321] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:9:0) after 6m31s
[16:46:57.436] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:9:0)
[16:46:57.519] Build status: STARTED
[16:46:57.519] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:10:0) after 5m57s
[16:52:54.536] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:10:0)
[16:52:54.555] Build status: STARTED
[16:52:54.555] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:11:0) after 4m54s
[16:57:48.712] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:11:0)
[16:57:48.733] Build status: STARTED
[16:57:48.733] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:12:0) after 9m30s
[17:07:18.770] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:12:0)
[17:07:18.791] Build status: STARTED
[17:07:18.791] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:13:0) after 6m54s
[17:14:12.809] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:13:0)
[17:14:12.824] Build status: STARTED
[17:14:12.824] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:14:0) after 4m43s
[17:18:55.839] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:14:0)
[17:18:55.859] Build status: STARTED
[17:18:55.859] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:15:0) after 1m6s
[17:20:01.899] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:15:0)
[17:20:01.935] Build status: STARTED
[17:20:01.935] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:16:0) after 9m11s
[17:29:12.964] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:16:0)
[17:29:12.983] Build status: STARTED
[17:29:12.983] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:17:0) after 9m7s
[17:38:20.002] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:17:0)
[17:38:20.035] Build status: STARTED
[17:38:20.035] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:18:0) after 3m26s
[17:41:46.031] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:18:0)
[17:41:46.051] Build status: STARTED
[17:41:46.051] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:19:0) after 6m11s
[17:47:57.069] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:19:0)
[17:47:57.088] Build status: STARTED
[17:47:57.088] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:20:0) after 4m16s
[17:52:13.167] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:20:0)
[17:52:13.190] Build status: STARTED
[17:52:13.190] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:21:0) after 4m19s
[17:56:32.330] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:21:0)
[17:56:32.349] Build status: STARTED
[17:56:32.349] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:22:0) after 9m38s
[18:06:10.377] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:22:0)
[18:06:10.421] Build status: STARTED
[18:06:10.421] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:23:0) after 7m50s
[18:14:00.438] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:23:0)
[18:14:00.457] Build status: STARTED
[18:14:00.457] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:24:0) after 1m57s
[18:15:57.479] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:24:0)
[18:15:57.501] Build status: STARTED
[18:15:57.501] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:25:0) after 7m7s
[18:23:04.510] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:25:0)
[18:23:04.532] Build status: STARTED
[18:23:04.532] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:26:0) after 4m5s
[18:27:09.891] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:26:0)
[18:27:09.904] Build status: STARTED
[18:27:09.904] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:27:0) after 4m17s
[18:31:26.925] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:27:0)
[18:31:26.952] Build status: STARTED
[18:31:26.952] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:28:0) after 9m43s
[18:41:10.168] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:28:0)
[18:41:10.243] Build status: STARTED
[18:41:10.243] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:29:0) after 1m17s
[18:42:27.351] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:29:0)
[18:42:27.423] Build status: STARTED
[18:42:27.423] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:30:0) after 6m19s
[18:48:46.440] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:30:0)
[18:48:46.460] Build status: STARTED
[18:48:46.460] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:31:0) after 3m24s
[18:52:10.527] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:31:0)
[18:52:10.544] Build status: STARTED
[18:52:10.544] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:32:0) after 7m31s
[18:59:41.611] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:32:0)
[18:59:41.659] Build status: STARTED
[18:59:41.659] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:33:0) after 8m21s
[19:08:02.679] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:33:0)
[19:08:02.697] Build status: STARTED
[19:08:02.697] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:34:0) after 4m27s
[19:12:29.730] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:34:0)
[19:12:29.766] Build status: STARTED
[19:12:29.766] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:35:0) after 8m45s
[19:21:14.784] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:35:0)
[19:21:14.809] Build status: STARTED
[19:21:14.809] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:36:0) after 1m57s
[19:23:11.877] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:36:0)
[19:23:11.949] Build status: STARTED
[19:23:11.949] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:37:0) after 9m33s
[19:32:44.968] Handling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:37:0)
[19:32:45.033] Build status: STARTED
[19:32:45.033] Scheduling timer "check-buildbucket-build-status" (chromium-m132/mac-official:8895712140000039296:38:0) after 5m21s
[19:36:18.845] Received PubSub notification, asking Buildbucket for the build status
[19:36:19.010] Build:
{
"id": "8730282078113521217",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "mac-official"
},
"number": 121,
"createdBy": "project:chromium-m132",
"createTime": "2024-11-25T16:13:49.329637706Z",
"startTime": "2024-11-25T16:13:55.491081740Z",
"endTime": "2024-11-25T19:36:18.503756956Z",
"updateTime": "2024-11-25T19:36:18.503756956Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "2516835433dc9297dfd1968057295bdbafc618cc",
"ref": "refs/branch-heads/6834"
}
}
}
[19:36:19.010] Invocation finished in 3h22m30.864661366s with status SUCCEEDED