[17:11:50.455] New invocation is queued and will start shortly
[17:11:51.572] Starting the invocation (attempt 1)
[17:11:51.601] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/93cc2a508ec46d6d5d7e7735284996420a25e809"
[17:11:51.601] Popped gitiles commit info from properties and tags
[17:11:51.601] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:11:51.601] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:11:51.602] Buildbucket request:
{
"requestId": "8878404467964969728",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8878404467964969728",
"job": "chromium-m138/chromeos-jacuzzi-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6cec9cbdf53c7390d6dcc072e89c448a4300163e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@6cec9cbdf53c7390d6dcc072e89c448a4300163e",
"title": "6cec9cbdf53c7390d6dcc072e89c448a4300163e",
"url": "https://chromium.googlesource.com/chromium/src/+/6cec9cbdf53c7390d6dcc072e89c448a4300163e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e81c0ead79c8710b011b28f7b058861c807d44f7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@e81c0ead79c8710b011b28f7b058861c807d44f7",
"title": "e81c0ead79c8710b011b28f7b058861c807d44f7",
"url": "https://chromium.googlesource.com/chromium/src/+/e81c0ead79c8710b011b28f7b058861c807d44f7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "53f53cbd56bc7e455273dbb45fe4f19ac9740bd7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@53f53cbd56bc7e455273dbb45fe4f19ac9740bd7",
"title": "53f53cbd56bc7e455273dbb45fe4f19ac9740bd7",
"url": "https://chromium.googlesource.com/chromium/src/+/53f53cbd56bc7e455273dbb45fe4f19ac9740bd7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6b8af4604950c0e11cc4b7b6708dfab41ea2789d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@6b8af4604950c0e11cc4b7b6708dfab41ea2789d",
"title": "6b8af4604950c0e11cc4b7b6708dfab41ea2789d",
"url": "https://chromium.googlesource.com/chromium/src/+/6b8af4604950c0e11cc4b7b6708dfab41ea2789d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bfa326d9a63eb5063a993daf3e89f118fd83ed3c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@bfa326d9a63eb5063a993daf3e89f118fd83ed3c",
"title": "bfa326d9a63eb5063a993daf3e89f118fd83ed3c",
"url": "https://chromium.googlesource.com/chromium/src/+/bfa326d9a63eb5063a993daf3e89f118fd83ed3c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f48b653464bf6a42c92497f02b693fb5c2b55022"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@f48b653464bf6a42c92497f02b693fb5c2b55022",
"title": "f48b653464bf6a42c92497f02b693fb5c2b55022",
"url": "https://chromium.googlesource.com/chromium/src/+/f48b653464bf6a42c92497f02b693fb5c2b55022"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0e0be047a89feecc43d2c760f44e078e283972f2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@0e0be047a89feecc43d2c760f44e078e283972f2",
"title": "0e0be047a89feecc43d2c760f44e078e283972f2",
"url": "https://chromium.googlesource.com/chromium/src/+/0e0be047a89feecc43d2c760f44e078e283972f2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c7d79a3961a9d1a41dd5925c036f5853149c4f4e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@c7d79a3961a9d1a41dd5925c036f5853149c4f4e",
"title": "c7d79a3961a9d1a41dd5925c036f5853149c4f4e",
"url": "https://chromium.googlesource.com/chromium/src/+/c7d79a3961a9d1a41dd5925c036f5853149c4f4e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "93cc2a508ec46d6d5d7e7735284996420a25e809"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@93cc2a508ec46d6d5d7e7735284996420a25e809",
"title": "93cc2a508ec46d6d5d7e7735284996420a25e809",
"url": "https://chromium.googlesource.com/chromium/src/+/93cc2a508ec46d6d5d7e7735284996420a25e809"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "93cc2a508ec46d6d5d7e7735284996420a25e809",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878404467964969728"
},
{
"key": "scheduler_job_id",
"value": "chromium-m138/chromeos-jacuzzi-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:11:52.674] Scheduled build:
{
"id": "8712974406037133857",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"number": 111,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-04T17:11:51.665641417Z",
"updateTime": "2025-06-04T17:11:51.665641417Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "93cc2a508ec46d6d5d7e7735284996420a25e809",
"ref": "refs/branch-heads/7204"
}
}
}
[17:11:52.674] Task URL: https://cr-buildbucket.appspot.com/build/8712974406037133857
[17:11:52.674] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:2:0) after 5m12s
[17:11:53.286] Received PubSub notification, asking Buildbucket for the build status
[17:11:53.310] Build status: SCHEDULED
[17:11:58.770] Received PubSub notification, asking Buildbucket for the build status
[17:11:58.838] Build status: STARTED
[17:17:04.725] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:2:0)
[17:17:04.751] Build status: STARTED
[17:17:04.751] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:5:0) after 8m8s
[17:25:12.833] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:5:0)
[17:25:12.859] Build status: STARTED
[17:25:12.859] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:6:0) after 2m37s
[17:27:49.898] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:6:0)
[17:27:49.924] Build status: STARTED
[17:27:49.924] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:7:0) after 4m3s
[17:31:52.992] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:7:0)
[17:31:53.016] Build status: STARTED
[17:31:53.016] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:8:0) after 4m20s
[17:36:13.038] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:8:0)
[17:36:13.204] Build status: STARTED
[17:36:13.204] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:9:0) after 3m15s
[17:39:28.230] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:9:0)
[17:39:28.259] Build status: STARTED
[17:39:28.259] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:10:0) after 1m1s
[17:40:29.320] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:10:0)
[17:40:29.344] Build status: STARTED
[17:40:29.344] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:11:0) after 6m12s
[17:46:41.343] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:11:0)
[17:46:41.379] Build status: STARTED
[17:46:41.379] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:12:0) after 3m19s
[17:50:00.517] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:12:0)
[17:50:00.542] Build status: STARTED
[17:50:00.542] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:13:0) after 8m41s
[17:58:41.577] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:13:0)
[17:58:41.655] Build status: STARTED
[17:58:41.655] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:14:0) after 9m4s
[18:07:45.682] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:14:0)
[18:07:45.702] Build status: STARTED
[18:07:45.702] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:15:0) after 9m51s
[18:17:36.741] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:15:0)
[18:17:36.770] Build status: STARTED
[18:17:36.770] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:16:0) after 1m3s
[18:18:39.818] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:16:0)
[18:18:39.843] Build status: STARTED
[18:18:39.843] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:17:0) after 7m4s
[18:25:43.859] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:17:0)
[18:25:43.882] Build status: STARTED
[18:25:43.882] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:18:0) after 8m47s
[18:34:30.910] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:18:0)
[18:34:30.938] Build status: STARTED
[18:34:30.938] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:19:0) after 3m59s
[18:38:30.010] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:19:0)
[18:38:30.034] Build status: STARTED
[18:38:30.034] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:20:0) after 9m59s
[18:48:29.056] Handling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:20:0)
[18:48:29.100] Build status: STARTED
[18:48:29.100] Scheduling timer "check-buildbucket-build-status" (chromium-m138/chromeos-jacuzzi-rel:8878404467964969728:21:0) after 9m30s
[18:50:50.978] Received PubSub notification, asking Buildbucket for the build status
[18:50:50.997] Build:
{
"id": "8712974406037133857",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"number": 111,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-04T17:11:51.665641417Z",
"startTime": "2025-06-04T17:11:57.295786227Z",
"endTime": "2025-06-04T18:50:50.662526040Z",
"updateTime": "2025-06-04T18:50:50.662526040Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "93cc2a508ec46d6d5d7e7735284996420a25e809",
"ref": "refs/branch-heads/7204"
}
}
}
[18:50:50.997] Invocation finished in 1h39m0.553827292s with status SUCCEEDED