[20:34:42.977] New invocation is queued and will start shortly
[20:34:44.130] Starting the invocation (attempt 1)
[20:34:44.162] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561"
[20:34:44.162] Popped gitiles commit info from properties and tags
[20:34:44.162] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:34:44.162] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:34:44.163] Buildbucket request:
{
"requestId": "8877848122349337712",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8877848122349337712",
"job": "chromium-m138/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "27896d29522bce199b87aea9c957b8be9ad50e4f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@27896d29522bce199b87aea9c957b8be9ad50e4f",
"title": "27896d29522bce199b87aea9c957b8be9ad50e4f",
"url": "https://chromium.googlesource.com/chromium/src/+/27896d29522bce199b87aea9c957b8be9ad50e4f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4d9435cadce3b5f396642efc6649b92ee39414e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@4d9435cadce3b5f396642efc6649b92ee39414e5",
"title": "4d9435cadce3b5f396642efc6649b92ee39414e5",
"url": "https://chromium.googlesource.com/chromium/src/+/4d9435cadce3b5f396642efc6649b92ee39414e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "97a764f41d90365985bb58dd1c0dd72e64903c93"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@97a764f41d90365985bb58dd1c0dd72e64903c93",
"title": "97a764f41d90365985bb58dd1c0dd72e64903c93",
"url": "https://chromium.googlesource.com/chromium/src/+/97a764f41d90365985bb58dd1c0dd72e64903c93"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "522d9ecb723d51ba2b9feba7a62fc41f8eaaf201"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@522d9ecb723d51ba2b9feba7a62fc41f8eaaf201",
"title": "522d9ecb723d51ba2b9feba7a62fc41f8eaaf201",
"url": "https://chromium.googlesource.com/chromium/src/+/522d9ecb723d51ba2b9feba7a62fc41f8eaaf201"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fa00ab25d6715f665051129a8616198c45abfe83"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@fa00ab25d6715f665051129a8616198c45abfe83",
"title": "fa00ab25d6715f665051129a8616198c45abfe83",
"url": "https://chromium.googlesource.com/chromium/src/+/fa00ab25d6715f665051129a8616198c45abfe83"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f9365f7f4c92dc766477ec07fd957a821e00978c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@f9365f7f4c92dc766477ec07fd957a821e00978c",
"title": "f9365f7f4c92dc766477ec07fd957a821e00978c",
"url": "https://chromium.googlesource.com/chromium/src/+/f9365f7f4c92dc766477ec07fd957a821e00978c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f97be7272ae69c37a575a05b727fd57e61ce0c45"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@f97be7272ae69c37a575a05b727fd57e61ce0c45",
"title": "f97be7272ae69c37a575a05b727fd57e61ce0c45",
"url": "https://chromium.googlesource.com/chromium/src/+/f97be7272ae69c37a575a05b727fd57e61ce0c45"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9e9cb74a9552d906b817c75d1e6af258d0167aa9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@9e9cb74a9552d906b817c75d1e6af258d0167aa9",
"title": "9e9cb74a9552d906b817c75d1e6af258d0167aa9",
"url": "https://chromium.googlesource.com/chromium/src/+/9e9cb74a9552d906b817c75d1e6af258d0167aa9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c049e2963303789eb91b9d75e02d2efee4637b96"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@c049e2963303789eb91b9d75e02d2efee4637b96",
"title": "c049e2963303789eb91b9d75e02d2efee4637b96",
"url": "https://chromium.googlesource.com/chromium/src/+/c049e2963303789eb91b9d75e02d2efee4637b96"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "50850c97e78eea5def932f885023e402260fdaff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@50850c97e78eea5def932f885023e402260fdaff",
"title": "50850c97e78eea5def932f885023e402260fdaff",
"url": "https://chromium.googlesource.com/chromium/src/+/50850c97e78eea5def932f885023e402260fdaff"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b2585a2dd192e114a4952cb7e572b94429e20f45"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@b2585a2dd192e114a4952cb7e572b94429e20f45",
"title": "b2585a2dd192e114a4952cb7e572b94429e20f45",
"url": "https://chromium.googlesource.com/chromium/src/+/b2585a2dd192e114a4952cb7e572b94429e20f45"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7e43ae07b4a1d8e36734e99966ecd85f6d8893f3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@7e43ae07b4a1d8e36734e99966ecd85f6d8893f3",
"title": "7e43ae07b4a1d8e36734e99966ecd85f6d8893f3",
"url": "https://chromium.googlesource.com/chromium/src/+/7e43ae07b4a1d8e36734e99966ecd85f6d8893f3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "63867d6546e421611fb7b4cd53e90787cbe67990"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@63867d6546e421611fb7b4cd53e90787cbe67990",
"title": "63867d6546e421611fb7b4cd53e90787cbe67990",
"url": "https://chromium.googlesource.com/chromium/src/+/63867d6546e421611fb7b4cd53e90787cbe67990"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "960c913519d40846e9e51c5813cfc0406097fa09"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@960c913519d40846e9e51c5813cfc0406097fa09",
"title": "960c913519d40846e9e51c5813cfc0406097fa09",
"url": "https://chromium.googlesource.com/chromium/src/+/960c913519d40846e9e51c5813cfc0406097fa09"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "da8d613cf9d18993a8e7426692e677916ec59d60"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@da8d613cf9d18993a8e7426692e677916ec59d60",
"title": "da8d613cf9d18993a8e7426692e677916ec59d60",
"url": "https://chromium.googlesource.com/chromium/src/+/da8d613cf9d18993a8e7426692e677916ec59d60"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1ce208052a23c883b518caf3a6aa8749a2fec7bc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@1ce208052a23c883b518caf3a6aa8749a2fec7bc",
"title": "1ce208052a23c883b518caf3a6aa8749a2fec7bc",
"url": "https://chromium.googlesource.com/chromium/src/+/1ce208052a23c883b518caf3a6aa8749a2fec7bc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561",
"title": "4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561",
"url": "https://chromium.googlesource.com/chromium/src/+/4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8877848122349337712"
},
{
"key": "scheduler_job_id",
"value": "chromium-m138/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:34:44.639] Scheduled build:
{
"id": "8712418060384946753",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 122,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-10T20:34:44.224085078Z",
"updateTime": "2025-06-10T20:34:44.224085078Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561",
"ref": "refs/branch-heads/7204"
}
}
}
[20:34:44.639] Task URL: https://cr-buildbucket.appspot.com/build/8712418060384946753
[20:34:44.639] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:2:0) after 2m58s
[20:34:45.641] Received PubSub notification, asking Buildbucket for the build status
[20:34:45.698] Build status: SCHEDULED
[20:34:58.825] Received PubSub notification, asking Buildbucket for the build status
[20:34:58.849] Build status: STARTED
[20:37:42.702] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:2:0)
[20:37:42.729] Build status: STARTED
[20:37:42.729] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:5:0) after 5m25s
[20:43:07.783] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:5:0)
[20:43:07.818] Build status: STARTED
[20:43:07.818] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:6:0) after 4m21s
[20:47:28.912] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:6:0)
[20:47:28.932] Build status: STARTED
[20:47:28.932] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:7:0) after 7m34s
[20:55:02.942] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:7:0)
[20:55:02.970] Build status: STARTED
[20:55:02.970] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:8:0) after 2m31s
[20:57:33.992] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:8:0)
[20:57:34.016] Build status: STARTED
[20:57:34.016] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:9:0) after 4m15s
[21:01:49.034] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:9:0)
[21:01:49.051] Build status: STARTED
[21:01:49.051] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:10:0) after 4m4s
[21:05:53.123] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:10:0)
[21:05:53.148] Build status: STARTED
[21:05:53.148] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:11:0) after 2m3s
[21:07:56.173] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:11:0)
[21:07:56.217] Build status: STARTED
[21:07:56.217] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:12:0) after 6m23s
[21:14:19.415] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:12:0)
[21:14:19.434] Build status: STARTED
[21:14:19.434] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:13:0) after 8m54s
[21:23:13.457] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:13:0)
[21:23:13.604] Build status: STARTED
[21:23:13.604] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:14:0) after 8m3s
[21:31:16.661] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:14:0)
[21:31:16.680] Build status: STARTED
[21:31:16.680] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:15:0) after 9m15s
[21:40:31.699] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:15:0)
[21:40:31.716] Build status: STARTED
[21:40:31.716] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:16:0) after 1m23s
[21:41:54.732] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:16:0)
[21:41:54.748] Build status: STARTED
[21:41:54.748] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:17:0) after 7m57s
[21:49:51.767] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:17:0)
[21:49:51.795] Build status: STARTED
[21:49:51.795] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:18:0) after 5m52s
[21:55:43.822] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:18:0)
[21:55:43.843] Build status: STARTED
[21:55:43.843] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:19:0) after 4m10s
[21:59:53.903] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:19:0)
[21:59:53.925] Build status: STARTED
[21:59:53.925] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:20:0) after 8m14s
[22:08:08.029] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:20:0)
[22:08:08.098] Build status: STARTED
[22:08:08.098] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:21:0) after 7m56s
[22:16:04.121] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:21:0)
[22:16:04.142] Build status: STARTED
[22:16:04.142] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:22:0) after 1m7s
[22:17:11.328] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:22:0)
[22:17:11.346] Build status: STARTED
[22:17:11.346] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:23:0) after 9m18s
[22:26:29.369] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:23:0)
[22:26:29.392] Build status: STARTED
[22:26:29.392] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:24:0) after 2m29s
[22:28:58.411] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:24:0)
[22:28:58.434] Build status: STARTED
[22:28:58.434] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:25:0) after 1m50s
[22:30:48.503] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:25:0)
[22:30:48.521] Build status: STARTED
[22:30:48.521] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:26:0) after 5m11s
[22:35:59.546] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:26:0)
[22:35:59.562] Build status: STARTED
[22:35:59.562] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:27:0) after 1m22s
[22:37:21.582] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:27:0)
[22:37:21.674] Build status: STARTED
[22:37:21.674] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:28:0) after 3m52s
[22:41:13.673] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:28:0)
[22:41:14.819] Build status: STARTED
[22:41:14.819] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:29:0) after 6m42s
[22:47:56.902] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:29:0)
[22:47:56.918] Build status: STARTED
[22:47:56.919] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:30:0) after 2m43s
[22:50:39.918] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:30:0)
[22:50:39.936] Build status: STARTED
[22:50:39.937] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:31:0) after 2m4s
[22:52:44.008] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:31:0)
[22:52:44.028] Build status: STARTED
[22:52:44.028] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:32:0) after 6m14s
[22:58:58.010] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:32:0)
[22:58:58.029] Build status: STARTED
[22:58:58.029] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:33:0) after 9m50s
[23:08:48.259] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:33:0)
[23:08:48.281] Build status: STARTED
[23:08:48.281] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:34:0) after 1m3s
[23:09:51.345] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:34:0)
[23:09:51.363] Build status: STARTED
[23:09:51.363] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:35:0) after 4m17s
[23:14:08.436] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:35:0)
[23:14:08.458] Build status: STARTED
[23:14:08.458] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877848122349337712:36:0) after 9m43s
[23:16:21.025] Received PubSub notification, asking Buildbucket for the build status
[23:16:21.044] Build:
{
"id": "8712418060384946753",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 122,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-10T20:34:44.224085078Z",
"startTime": "2025-06-10T20:34:56.152071440Z",
"endTime": "2025-06-10T23:16:20.801106261Z",
"updateTime": "2025-06-10T23:16:20.801106261Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4762a9b9c3aa8bcf38ccb9cba8ea520248c8f561",
"ref": "refs/branch-heads/7204"
}
}
}
[23:16:21.044] Invocation finished in 2h41m38.077825995s with status SUCCEEDED