[16:38:21.051] New invocation is queued and will start shortly
[16:38:22.923] Starting the invocation (attempt 1)
[16:38:22.954] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b8755a48221453528ac69abb24f02f2be11fc8d1"
[16:38:22.954] Popped gitiles commit info from properties and tags
[16:38:22.954] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:38:22.954] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:38:22.954] Buildbucket request:
{
"requestId": "8886469704984396560",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8886469704984396560",
"job": "chromium-m135/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3decd030e55e1c2450b86ccdc98e720ed8e07b55"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@3decd030e55e1c2450b86ccdc98e720ed8e07b55",
"title": "3decd030e55e1c2450b86ccdc98e720ed8e07b55",
"url": "https://chromium.googlesource.com/chromium/src/+/3decd030e55e1c2450b86ccdc98e720ed8e07b55"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f546a0f5b6b060837e0ca66e9f588408202bc688"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@f546a0f5b6b060837e0ca66e9f588408202bc688",
"title": "f546a0f5b6b060837e0ca66e9f588408202bc688",
"url": "https://chromium.googlesource.com/chromium/src/+/f546a0f5b6b060837e0ca66e9f588408202bc688"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "57ddb9dbb6350868375fec2014c5c27538a97b30"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@57ddb9dbb6350868375fec2014c5c27538a97b30",
"title": "57ddb9dbb6350868375fec2014c5c27538a97b30",
"url": "https://chromium.googlesource.com/chromium/src/+/57ddb9dbb6350868375fec2014c5c27538a97b30"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f6e01fbb630af7bdfce3335c6eb3288c126177aa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@f6e01fbb630af7bdfce3335c6eb3288c126177aa",
"title": "f6e01fbb630af7bdfce3335c6eb3288c126177aa",
"url": "https://chromium.googlesource.com/chromium/src/+/f6e01fbb630af7bdfce3335c6eb3288c126177aa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9cbf864dbab190a2e8082d9bb323db1ec58b6e86"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@9cbf864dbab190a2e8082d9bb323db1ec58b6e86",
"title": "9cbf864dbab190a2e8082d9bb323db1ec58b6e86",
"url": "https://chromium.googlesource.com/chromium/src/+/9cbf864dbab190a2e8082d9bb323db1ec58b6e86"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9c76c0ae014aeb6fd74fd9bd31a06c4d6c2a029b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@9c76c0ae014aeb6fd74fd9bd31a06c4d6c2a029b",
"title": "9c76c0ae014aeb6fd74fd9bd31a06c4d6c2a029b",
"url": "https://chromium.googlesource.com/chromium/src/+/9c76c0ae014aeb6fd74fd9bd31a06c4d6c2a029b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "16198d8d00590f401ce4fd29127c9b18092af42a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@16198d8d00590f401ce4fd29127c9b18092af42a",
"title": "16198d8d00590f401ce4fd29127c9b18092af42a",
"url": "https://chromium.googlesource.com/chromium/src/+/16198d8d00590f401ce4fd29127c9b18092af42a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5180a421583c7db0d1f618d72ddd44fb531d0c57"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@5180a421583c7db0d1f618d72ddd44fb531d0c57",
"title": "5180a421583c7db0d1f618d72ddd44fb531d0c57",
"url": "https://chromium.googlesource.com/chromium/src/+/5180a421583c7db0d1f618d72ddd44fb531d0c57"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bd7323617ed63ebd11629421b29821b8bf5606a2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@bd7323617ed63ebd11629421b29821b8bf5606a2",
"title": "bd7323617ed63ebd11629421b29821b8bf5606a2",
"url": "https://chromium.googlesource.com/chromium/src/+/bd7323617ed63ebd11629421b29821b8bf5606a2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4c7f0084aa89f6ffe9557d24d7c00eb528f7c854"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@4c7f0084aa89f6ffe9557d24d7c00eb528f7c854",
"title": "4c7f0084aa89f6ffe9557d24d7c00eb528f7c854",
"url": "https://chromium.googlesource.com/chromium/src/+/4c7f0084aa89f6ffe9557d24d7c00eb528f7c854"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b8755a48221453528ac69abb24f02f2be11fc8d1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@b8755a48221453528ac69abb24f02f2be11fc8d1",
"title": "b8755a48221453528ac69abb24f02f2be11fc8d1",
"url": "https://chromium.googlesource.com/chromium/src/+/b8755a48221453528ac69abb24f02f2be11fc8d1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8755a48221453528ac69abb24f02f2be11fc8d1",
"ref": "refs/branch-heads/7049"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886469704984396560"
},
{
"key": "scheduler_job_id",
"value": "chromium-m135/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:38:23.479] Scheduled build:
{
"id": "8721039642270651937",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "mac-official"
},
"number": 24,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-07T16:38:23.013776025Z",
"updateTime": "2025-03-07T16:38:23.013776025Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8755a48221453528ac69abb24f02f2be11fc8d1",
"ref": "refs/branch-heads/7049"
}
}
}
[16:38:23.479] Task URL: https://cr-buildbucket.appspot.com/build/8721039642270651937
[16:38:23.479] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:2:0) after 6m43s
[16:38:24.518] Received PubSub notification, asking Buildbucket for the build status
[16:38:24.537] Build status: SCHEDULED
[16:40:15.167] Received PubSub notification, asking Buildbucket for the build status
[16:40:15.205] Build status: STARTED
[16:45:06.535] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:2:0)
[16:45:06.561] Build status: STARTED
[16:45:06.561] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:5:0) after 3m43s
[16:48:49.609] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:5:0)
[16:48:49.635] Build status: STARTED
[16:48:49.635] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:6:0) after 9m26s
[16:58:15.610] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:6:0)
[16:58:16.367] Build status: STARTED
[16:58:16.367] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:7:0) after 7m2s
[17:05:18.360] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:7:0)
[17:05:18.423] Build status: STARTED
[17:05:18.423] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:8:0) after 3m32s
[17:08:50.489] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:8:0)
[17:08:50.512] Build status: STARTED
[17:08:50.512] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:9:0) after 4m17s
[17:13:07.645] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:9:0)
[17:13:07.665] Build status: STARTED
[17:13:07.666] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:10:0) after 3m10s
[17:16:17.679] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:10:0)
[17:16:17.695] Build status: STARTED
[17:16:17.695] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:11:0) after 4m46s
[17:21:03.741] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:11:0)
[17:21:03.761] Build status: STARTED
[17:21:03.761] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:12:0) after 7m13s
[17:28:16.778] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:12:0)
[17:28:16.797] Build status: STARTED
[17:28:16.797] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:13:0) after 8m16s
[17:36:32.905] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:13:0)
[17:36:32.949] Build status: STARTED
[17:36:32.949] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:14:0) after 7m37s
[17:44:09.966] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:14:0)
[17:44:09.982] Build status: STARTED
[17:44:09.982] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:15:0) after 8m1s
[17:52:11.055] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:15:0)
[17:52:11.072] Build status: STARTED
[17:52:11.072] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:16:0) after 5m50s
[17:58:01.097] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:16:0)
[17:58:01.119] Build status: STARTED
[17:58:01.119] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:17:0) after 6m27s
[18:04:28.234] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:17:0)
[18:04:28.265] Build status: STARTED
[18:04:28.265] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:18:0) after 1m57s
[18:06:25.295] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:18:0)
[18:06:25.322] Build status: STARTED
[18:06:25.322] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:19:0) after 1m27s
[18:07:52.341] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:19:0)
[18:07:52.355] Build status: STARTED
[18:07:52.355] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:20:0) after 7m13s
[18:15:05.420] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:20:0)
[18:15:05.445] Build status: STARTED
[18:15:05.445] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:21:0) after 1m43s
[18:16:48.552] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:21:0)
[18:16:48.572] Build status: STARTED
[18:16:48.572] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:22:0) after 8m26s
[18:25:14.588] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:22:0)
[18:25:14.608] Build status: STARTED
[18:25:14.608] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:23:0) after 7m46s
[18:33:00.675] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:23:0)
[18:33:00.851] Build status: STARTED
[18:33:00.851] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:24:0) after 2m48s
[18:35:48.867] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:24:0)
[18:35:48.893] Build status: STARTED
[18:35:48.893] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:25:0) after 8m32s
[18:44:21.029] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:25:0)
[18:44:21.046] Build status: STARTED
[18:44:21.046] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:26:0) after 6m53s
[18:51:14.046] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:26:0)
[18:51:14.099] Build status: STARTED
[18:51:14.099] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:27:0) after 9m9s
[19:00:23.165] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:27:0)
[19:00:23.186] Build status: STARTED
[19:00:23.186] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:28:0) after 9m10s
[19:09:33.204] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:28:0)
[19:09:33.228] Build status: STARTED
[19:09:33.228] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:29:0) after 8m30s
[19:18:03.315] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:29:0)
[19:18:03.332] Build status: STARTED
[19:18:03.332] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:30:0) after 2m35s
[19:20:38.430] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:30:0)
[19:20:38.543] Build status: STARTED
[19:20:38.543] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:31:0) after 6m40s
[19:27:18.576] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:31:0)
[19:27:18.606] Build status: STARTED
[19:27:18.606] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:32:0) after 1m0s
[19:28:18.750] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:32:0)
[19:28:18.770] Build status: STARTED
[19:28:18.770] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:33:0) after 2m48s
[19:31:06.795] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:33:0)
[19:31:06.817] Build status: STARTED
[19:31:06.817] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:34:0) after 7m46s
[19:38:52.835] Handling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:34:0)
[19:38:52.851] Build status: STARTED
[19:38:52.851] Scheduling timer "check-buildbucket-build-status" (chromium-m135/mac-official:8886469704984396560:35:0) after 8m41s
[19:40:41.195] Received PubSub notification, asking Buildbucket for the build status
[19:40:41.384] Build:
{
"id": "8721039642270651937",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "mac-official"
},
"number": 24,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-07T16:38:23.013776025Z",
"startTime": "2025-03-07T16:40:14.755185186Z",
"endTime": "2025-03-07T19:40:40.488859194Z",
"updateTime": "2025-03-07T19:40:40.488859194Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b8755a48221453528ac69abb24f02f2be11fc8d1",
"ref": "refs/branch-heads/7049"
}
}
}
[19:40:41.384] Invocation finished in 3h2m20.342070917s with status SUCCEEDED