[21:00:54.856] New invocation is queued and will start shortly
[21:00:55.928] Starting the invocation (attempt 1)
[21:00:55.955] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d524e9d570b210e53fb42366a4276e064580c87"
[21:00:55.955] Popped gitiles commit info from properties and tags
[21:00:55.955] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:00:55.956] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:00:55.956] Buildbucket request:
{
"requestId": "8859998871735397168",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8859998871735397168",
"job": "chromium-m144/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c0935679a9b154429eab19545d277fd8f2546c22"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@c0935679a9b154429eab19545d277fd8f2546c22",
"title": "c0935679a9b154429eab19545d277fd8f2546c22",
"url": "https://chromium.googlesource.com/chromium/src/+/c0935679a9b154429eab19545d277fd8f2546c22"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "964127c0ad9e85e8f19e6700fbd6f27670f5ec88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@964127c0ad9e85e8f19e6700fbd6f27670f5ec88",
"title": "964127c0ad9e85e8f19e6700fbd6f27670f5ec88",
"url": "https://chromium.googlesource.com/chromium/src/+/964127c0ad9e85e8f19e6700fbd6f27670f5ec88"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "465cda104bb67898672604d0de1fedcc4ba25980"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@465cda104bb67898672604d0de1fedcc4ba25980",
"title": "465cda104bb67898672604d0de1fedcc4ba25980",
"url": "https://chromium.googlesource.com/chromium/src/+/465cda104bb67898672604d0de1fedcc4ba25980"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1fc1204cda720fa82aa4b994d6b9442d43f53716"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@1fc1204cda720fa82aa4b994d6b9442d43f53716",
"title": "1fc1204cda720fa82aa4b994d6b9442d43f53716",
"url": "https://chromium.googlesource.com/chromium/src/+/1fc1204cda720fa82aa4b994d6b9442d43f53716"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0d66d08bf7f52471f6ee8291f5eb861c1c487758"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@0d66d08bf7f52471f6ee8291f5eb861c1c487758",
"title": "0d66d08bf7f52471f6ee8291f5eb861c1c487758",
"url": "https://chromium.googlesource.com/chromium/src/+/0d66d08bf7f52471f6ee8291f5eb861c1c487758"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d27338d5883baa5aa99faee3d7d8d615ed10f3a4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@d27338d5883baa5aa99faee3d7d8d615ed10f3a4",
"title": "d27338d5883baa5aa99faee3d7d8d615ed10f3a4",
"url": "https://chromium.googlesource.com/chromium/src/+/d27338d5883baa5aa99faee3d7d8d615ed10f3a4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7a7172807568668fef92aefb2f6dcb5f77591b19"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7a7172807568668fef92aefb2f6dcb5f77591b19",
"title": "7a7172807568668fef92aefb2f6dcb5f77591b19",
"url": "https://chromium.googlesource.com/chromium/src/+/7a7172807568668fef92aefb2f6dcb5f77591b19"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7fc08434cae5ee8373a9204d3580ed70fa896a34"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7fc08434cae5ee8373a9204d3580ed70fa896a34",
"title": "7fc08434cae5ee8373a9204d3580ed70fa896a34",
"url": "https://chromium.googlesource.com/chromium/src/+/7fc08434cae5ee8373a9204d3580ed70fa896a34"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ba9585f162e0ea194783196f20d4d9d0f0163007"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@ba9585f162e0ea194783196f20d4d9d0f0163007",
"title": "ba9585f162e0ea194783196f20d4d9d0f0163007",
"url": "https://chromium.googlesource.com/chromium/src/+/ba9585f162e0ea194783196f20d4d9d0f0163007"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "908d32b24e5ba29358f721758ff2e999c78c7ebc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@908d32b24e5ba29358f721758ff2e999c78c7ebc",
"title": "908d32b24e5ba29358f721758ff2e999c78c7ebc",
"url": "https://chromium.googlesource.com/chromium/src/+/908d32b24e5ba29358f721758ff2e999c78c7ebc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aa4c66651620cb0e1be04bede3391727c9e935d5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@aa4c66651620cb0e1be04bede3391727c9e935d5",
"title": "aa4c66651620cb0e1be04bede3391727c9e935d5",
"url": "https://chromium.googlesource.com/chromium/src/+/aa4c66651620cb0e1be04bede3391727c9e935d5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cdd4c10d06fb42adb5403f894161a175e2abcef1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@cdd4c10d06fb42adb5403f894161a175e2abcef1",
"title": "cdd4c10d06fb42adb5403f894161a175e2abcef1",
"url": "https://chromium.googlesource.com/chromium/src/+/cdd4c10d06fb42adb5403f894161a175e2abcef1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7186149aed9eaeab5ed585026c017cea08391baf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7186149aed9eaeab5ed585026c017cea08391baf",
"title": "7186149aed9eaeab5ed585026c017cea08391baf",
"url": "https://chromium.googlesource.com/chromium/src/+/7186149aed9eaeab5ed585026c017cea08391baf"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5cc7073682a5d21eb9702d31ab051647e55ee98f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@5cc7073682a5d21eb9702d31ab051647e55ee98f",
"title": "5cc7073682a5d21eb9702d31ab051647e55ee98f",
"url": "https://chromium.googlesource.com/chromium/src/+/5cc7073682a5d21eb9702d31ab051647e55ee98f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1d524e9d570b210e53fb42366a4276e064580c87"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@1d524e9d570b210e53fb42366a4276e064580c87",
"title": "1d524e9d570b210e53fb42366a4276e064580c87",
"url": "https://chromium.googlesource.com/chromium/src/+/1d524e9d570b210e53fb42366a4276e064580c87"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d524e9d570b210e53fb42366a4276e064580c87",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8859998871735397168"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:00:57.125] Scheduled build:
{
"id": "8694568809695721489",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 150,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-24T21:00:56.174896592Z",
"updateTime": "2025-12-24T21:00:56.174896592Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d524e9d570b210e53fb42366a4276e064580c87",
"ref": "refs/branch-heads/7559"
}
},
"canary": true
}
[21:00:57.125] Task URL: https://cr-buildbucket.appspot.com/build/8694568809695721489
[21:00:57.125] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:2:0) after 8m49s
[21:01:05.971] Received PubSub notification, asking Buildbucket for the build status
[21:01:05.997] Build status: SCHEDULED
[21:01:47.922] Received PubSub notification, asking Buildbucket for the build status
[21:01:47.938] Build status: STARTED
[21:09:46.130] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:2:0)
[21:09:46.146] Build status: STARTED
[21:09:46.146] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:5:0) after 6m39s
[21:16:25.259] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:5:0)
[21:16:25.278] Build status: STARTED
[21:16:25.278] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:6:0) after 4m34s
[21:20:59.317] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:6:0)
[21:20:59.339] Build status: STARTED
[21:20:59.339] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:7:0) after 9m32s
[21:30:31.565] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:7:0)
[21:30:31.588] Build status: STARTED
[21:30:31.589] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:8:0) after 4m4s
[21:34:35.803] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:8:0)
[21:34:35.831] Build status: STARTED
[21:34:35.831] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:9:0) after 6m50s
[21:41:25.846] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:9:0)
[21:41:25.860] Build status: STARTED
[21:41:25.860] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:10:0) after 1m18s
[21:42:43.976] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:10:0)
[21:42:44.002] Build status: STARTED
[21:42:44.002] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:11:0) after 9m41s
[21:52:25.109] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:11:0)
[21:52:25.156] Build status: STARTED
[21:52:25.156] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:12:0) after 4m41s
[21:57:06.200] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:12:0)
[21:57:06.216] Build status: STARTED
[21:57:06.216] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:13:0) after 4m5s
[22:01:11.280] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:13:0)
[22:01:11.316] Build status: STARTED
[22:01:11.316] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:14:0) after 1m8s
[22:02:19.330] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:14:0)
[22:02:19.344] Build status: STARTED
[22:02:19.345] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:15:0) after 4m45s
[22:07:04.394] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:15:0)
[22:07:04.423] Build status: STARTED
[22:07:04.423] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:16:0) after 8m36s
[22:15:40.438] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:16:0)
[22:15:40.455] Build status: STARTED
[22:15:40.455] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:17:0) after 7m53s
[22:23:33.667] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:17:0)
[22:23:33.684] Build status: STARTED
[22:23:33.684] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:18:0) after 1m59s
[22:25:32.713] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:18:0)
[22:25:32.780] Build status: STARTED
[22:25:32.780] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:19:0) after 7m5s
[22:32:37.795] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:19:0)
[22:32:37.811] Build status: STARTED
[22:32:37.811] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:20:0) after 9m41s
[22:42:18.864] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:20:0)
[22:42:18.888] Build status: STARTED
[22:42:18.888] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:21:0) after 4m4s
[22:46:23.055] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:21:0)
[22:46:23.094] Build status: STARTED
[22:46:23.094] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:22:0) after 3m14s
[22:49:37.107] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:22:0)
[22:49:37.130] Build status: STARTED
[22:49:37.130] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:23:0) after 1m51s
[22:51:28.149] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:23:0)
[22:51:28.319] Build status: STARTED
[22:51:28.319] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:24:0) after 7m13s
[22:58:41.335] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:24:0)
[22:58:41.379] Build status: STARTED
[22:58:41.379] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:25:0) after 9m23s
[23:08:04.397] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:25:0)
[23:08:04.444] Build status: STARTED
[23:08:04.444] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:26:0) after 9m19s
[23:17:23.660] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:26:0)
[23:17:23.700] Build status: STARTED
[23:17:23.700] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:27:0) after 7m16s
[23:24:39.720] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:27:0)
[23:24:39.753] Build status: STARTED
[23:24:39.753] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:28:0) after 9m1s
[23:33:40.814] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:28:0)
[23:33:40.871] Build status: STARTED
[23:33:40.871] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:29:0) after 3m33s
[23:37:13.885] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:29:0)
[23:37:13.917] Build status: STARTED
[23:37:13.917] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:30:0) after 4m26s
[23:41:40.050] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:30:0)
[23:41:40.064] Build status: STARTED
[23:41:40.065] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:31:0) after 3m27s
[23:45:07.087] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:31:0)
[23:45:07.114] Build status: STARTED
[23:45:07.114] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:32:0) after 3m14s
[23:48:21.246] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:32:0)
[23:48:21.268] Build status: STARTED
[23:48:21.268] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:33:0) after 5m21s
[23:53:42.282] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:33:0)
[23:53:42.322] Build status: STARTED
[23:53:42.322] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:34:0) after 6m12s
[23:59:54.342] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:34:0)
[23:59:54.357] Build status: STARTED
[23:59:54.357] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:35:0) after 2m30s
[00:02:24.552] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:35:0)
[00:02:24.573] Build status: STARTED
[00:02:24.573] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:36:0) after 9m0s
[00:11:24.679] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:36:0)
[00:11:24.694] Build status: STARTED
[00:11:24.694] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:37:0) after 8m18s
[00:19:42.710] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:37:0)
[00:19:42.724] Build status: STARTED
[00:19:42.724] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:38:0) after 1m15s
[00:20:57.945] Handling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:38:0)
[00:20:57.963] Build status: STARTED
[00:20:57.963] Scheduling timer "check-buildbucket-build-status" (chromium-m144/mac-official:8859998871735397168:39:0) after 9m37s
[00:24:28.284] Received PubSub notification, asking Buildbucket for the build status
[00:24:28.296] Build:
{
"id": "8694568809695721489",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "mac-official"
},
"number": 150,
"createdBy": "project:chromium-m144",
"createTime": "2025-12-24T21:00:56.174896592Z",
"startTime": "2025-12-24T21:01:47.748318373Z",
"endTime": "2025-12-25T00:24:27.942500643Z",
"updateTime": "2025-12-25T00:24:27.942500643Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d524e9d570b210e53fb42366a4276e064580c87",
"ref": "refs/branch-heads/7559"
}
},
"canary": true
}
[00:24:28.296] Invocation finished in 3h23m33.45140223s with status SUCCEEDED