[23:29:39.120] New invocation is queued and will start shortly
[23:29:40.588] Starting the invocation (attempt 1)
[23:29:40.623] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/748242d6047b67d9dc24cd4b9863776b3f2fbe9c"
[23:29:40.623] Popped gitiles commit info from properties and tags
[23:29:40.623] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:29:40.623] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:29:40.624] Buildbucket request:
{
"requestId": "8877565325441399664",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8877565325441399664",
"job": "chromium-m138/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1fee46a25837b87f5daf18539be8b7385f6e04c7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@1fee46a25837b87f5daf18539be8b7385f6e04c7",
"title": "1fee46a25837b87f5daf18539be8b7385f6e04c7",
"url": "https://chromium.googlesource.com/chromium/src/+/1fee46a25837b87f5daf18539be8b7385f6e04c7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ed351f1dd698221aa4062cde0e499c9519f4c6c2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@ed351f1dd698221aa4062cde0e499c9519f4c6c2",
"title": "ed351f1dd698221aa4062cde0e499c9519f4c6c2",
"url": "https://chromium.googlesource.com/chromium/src/+/ed351f1dd698221aa4062cde0e499c9519f4c6c2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6dfb4dc62e5e2fa53fd2977867268499b033ee5a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@6dfb4dc62e5e2fa53fd2977867268499b033ee5a",
"title": "6dfb4dc62e5e2fa53fd2977867268499b033ee5a",
"url": "https://chromium.googlesource.com/chromium/src/+/6dfb4dc62e5e2fa53fd2977867268499b033ee5a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d09b3acf09261fd59134d3abcc17fab4e8859fcc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@d09b3acf09261fd59134d3abcc17fab4e8859fcc",
"title": "d09b3acf09261fd59134d3abcc17fab4e8859fcc",
"url": "https://chromium.googlesource.com/chromium/src/+/d09b3acf09261fd59134d3abcc17fab4e8859fcc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cd1c7bb74ea015d18c062433dcaf45bcf296c31a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@cd1c7bb74ea015d18c062433dcaf45bcf296c31a",
"title": "cd1c7bb74ea015d18c062433dcaf45bcf296c31a",
"url": "https://chromium.googlesource.com/chromium/src/+/cd1c7bb74ea015d18c062433dcaf45bcf296c31a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "93c2d6416ec1356a7a62957ec06fdc5cc8c00cd1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@93c2d6416ec1356a7a62957ec06fdc5cc8c00cd1",
"title": "93c2d6416ec1356a7a62957ec06fdc5cc8c00cd1",
"url": "https://chromium.googlesource.com/chromium/src/+/93c2d6416ec1356a7a62957ec06fdc5cc8c00cd1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c35fd1df36bbba79d195db985c57f264017a25f0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@c35fd1df36bbba79d195db985c57f264017a25f0",
"title": "c35fd1df36bbba79d195db985c57f264017a25f0",
"url": "https://chromium.googlesource.com/chromium/src/+/c35fd1df36bbba79d195db985c57f264017a25f0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "52e843d58d4246de3aa62874051b44330204bd9e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@52e843d58d4246de3aa62874051b44330204bd9e",
"title": "52e843d58d4246de3aa62874051b44330204bd9e",
"url": "https://chromium.googlesource.com/chromium/src/+/52e843d58d4246de3aa62874051b44330204bd9e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9d29874a58ea2189831683499bc6a9befb0ce97a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@9d29874a58ea2189831683499bc6a9befb0ce97a",
"title": "9d29874a58ea2189831683499bc6a9befb0ce97a",
"url": "https://chromium.googlesource.com/chromium/src/+/9d29874a58ea2189831683499bc6a9befb0ce97a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e814a79061d4115f5f74d13d4179fe31fc9f2c05"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@e814a79061d4115f5f74d13d4179fe31fc9f2c05",
"title": "e814a79061d4115f5f74d13d4179fe31fc9f2c05",
"url": "https://chromium.googlesource.com/chromium/src/+/e814a79061d4115f5f74d13d4179fe31fc9f2c05"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2ed57d00fdac23a900141f7d96fd390e3898f516"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@2ed57d00fdac23a900141f7d96fd390e3898f516",
"title": "2ed57d00fdac23a900141f7d96fd390e3898f516",
"url": "https://chromium.googlesource.com/chromium/src/+/2ed57d00fdac23a900141f7d96fd390e3898f516"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "748242d6047b67d9dc24cd4b9863776b3f2fbe9c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@748242d6047b67d9dc24cd4b9863776b3f2fbe9c",
"title": "748242d6047b67d9dc24cd4b9863776b3f2fbe9c",
"url": "https://chromium.googlesource.com/chromium/src/+/748242d6047b67d9dc24cd4b9863776b3f2fbe9c"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "748242d6047b67d9dc24cd4b9863776b3f2fbe9c",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8877565325441399664"
},
{
"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"
}
}
[23:29:41.122] Scheduled build:
{
"id": "8712135263123549441",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 147,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-13T23:29:40.709383087Z",
"updateTime": "2025-06-13T23:29:40.709383087Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "748242d6047b67d9dc24cd4b9863776b3f2fbe9c",
"ref": "refs/branch-heads/7204"
}
}
}
[23:29:41.122] Task URL: https://cr-buildbucket.appspot.com/build/8712135263123549441
[23:29:41.122] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:2:0) after 6m45s
[23:29:42.128] Received PubSub notification, asking Buildbucket for the build status
[23:29:42.154] Build status: SCHEDULED
[23:29:46.153] Received PubSub notification, asking Buildbucket for the build status
[23:29:46.179] Build status: STARTED
[23:36:26.148] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:2:0)
[23:36:26.164] Build status: STARTED
[23:36:26.164] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:5:0) after 2m9s
[23:38:35.181] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:5:0)
[23:38:35.207] Build status: STARTED
[23:38:35.207] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:6:0) after 6m33s
[23:45:08.305] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:6:0)
[23:45:08.333] Build status: STARTED
[23:45:08.333] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:7:0) after 2m42s
[23:47:50.371] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:7:0)
[23:47:50.387] Build status: STARTED
[23:47:50.387] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:8:0) after 4m21s
[23:52:11.413] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:8:0)
[23:52:11.487] Build status: STARTED
[23:52:11.487] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:9:0) after 2m3s
[23:54:14.513] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:9:0)
[23:54:14.578] Build status: STARTED
[23:54:14.578] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:10:0) after 7m34s
[00:01:48.559] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:10:0)
[00:01:48.586] Build status: STARTED
[00:01:48.586] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:11:0) after 3m27s
[00:05:15.712] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:11:0)
[00:05:15.771] Build status: STARTED
[00:05:15.771] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:12:0) after 5m56s
[00:11:11.803] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:12:0)
[00:11:11.822] Build status: STARTED
[00:11:11.822] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:13:0) after 6m30s
[00:17:41.938] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:13:0)
[00:17:41.968] Build status: STARTED
[00:17:41.968] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:14:0) after 5m16s
[00:22:57.993] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:14:0)
[00:22:58.008] Build status: STARTED
[00:22:58.008] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:15:0) after 8m39s
[00:31:37.063] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:15:0)
[00:31:37.085] Build status: STARTED
[00:31:37.085] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:16:0) after 3m18s
[00:34:55.243] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:16:0)
[00:34:55.291] Build status: STARTED
[00:34:55.292] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:17:0) after 1m13s
[00:36:08.306] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:17:0)
[00:36:08.324] Build status: STARTED
[00:36:08.324] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:18:0) after 9m16s
[00:45:24.349] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:18:0)
[00:45:24.380] Build status: STARTED
[00:45:24.380] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:19:0) after 8m28s
[00:53:52.404] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:19:0)
[00:53:52.422] Build status: STARTED
[00:53:52.422] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:20:0) after 1m12s
[00:55:04.474] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:20:0)
[00:55:04.490] Build status: STARTED
[00:55:04.490] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:21:0) after 5m38s
[01:00:42.476] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:21:0)
[01:00:42.493] Build status: STARTED
[01:00:42.493] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:22:0) after 4m20s
[01:05:02.611] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:22:0)
[01:05:02.636] Build status: STARTED
[01:05:02.636] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:23:0) after 1m7s
[01:06:09.919] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:23:0)
[01:06:10.056] Build status: STARTED
[01:06:10.056] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:24:0) after 8m8s
[01:14:18.072] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:24:0)
[01:14:18.091] Build status: STARTED
[01:14:18.091] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:25:0) after 2m2s
[01:16:20.109] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:25:0)
[01:16:20.134] Build status: STARTED
[01:16:20.134] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:26:0) after 1m22s
[01:17:42.154] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:26:0)
[01:17:42.168] Build status: STARTED
[01:17:42.168] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:27:0) after 3m41s
[01:21:23.212] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:27:0)
[01:21:23.232] Build status: STARTED
[01:21:23.232] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:28:0) after 8m53s
[01:30:16.324] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:28:0)
[01:30:16.340] Build status: STARTED
[01:30:16.340] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:29:0) after 2m3s
[01:32:19.357] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:29:0)
[01:32:19.380] Build status: STARTED
[01:32:19.380] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:30:0) after 5m37s
[01:37:56.410] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:30:0)
[01:37:56.431] Build status: STARTED
[01:37:56.432] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:31:0) after 3m9s
[01:41:05.528] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:31:0)
[01:41:05.550] Build status: STARTED
[01:41:05.550] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:32:0) after 2m16s
[01:43:21.564] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:32:0)
[01:43:21.579] Build status: STARTED
[01:43:21.579] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:33:0) after 1m58s
[01:45:19.608] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:33:0)
[01:45:19.653] Build status: STARTED
[01:45:19.653] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:34:0) after 4m58s
[01:50:17.678] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:34:0)
[01:50:17.694] Build status: STARTED
[01:50:17.694] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:35:0) after 6m42s
[01:56:59.741] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:35:0)
[01:56:59.817] Build status: STARTED
[01:56:59.817] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:36:0) after 7m22s
[02:04:21.842] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:36:0)
[02:04:21.859] Build status: STARTED
[02:04:21.859] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:37:0) after 3m26s
[02:07:47.938] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:37:0)
[02:07:47.953] Build status: STARTED
[02:07:47.953] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:38:0) after 7m1s
[02:14:49.000] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:38:0)
[02:14:49.024] Build status: STARTED
[02:14:49.024] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:39:0) after 2m18s
[02:17:07.118] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:39:0)
[02:17:07.137] Build status: STARTED
[02:17:07.137] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8877565325441399664:40:0) after 5m9s
[02:19:30.976] Received PubSub notification, asking Buildbucket for the build status
[02:19:30.996] Build:
{
"id": "8712135263123549441",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 147,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-13T23:29:40.709383087Z",
"startTime": "2025-06-13T23:29:45.947526925Z",
"endTime": "2025-06-14T02:19:30.718728023Z",
"updateTime": "2025-06-14T02:19:30.718728023Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "748242d6047b67d9dc24cd4b9863776b3f2fbe9c",
"ref": "refs/branch-heads/7204"
}
}
}
[02:19:30.996] Invocation finished in 2h49m51.880979283s with status SUCCEEDED