[11:23:00.766] New invocation is queued and will start shortly
[11:23:01.910] Starting the invocation (attempt 1)
[11:23:01.938] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873"
[11:23:01.938] Popped gitiles commit info from properties and tags
[11:23:01.938] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:23:01.938] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:23:01.938] Buildbucket request:
{
"requestId": "8870182090395240752",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870182090395240752",
"job": "chromium-m141/chromeos-jacuzzi-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "78fa2f09497dbc6ea38ab0b9cce290c8e99833ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@78fa2f09497dbc6ea38ab0b9cce290c8e99833ad",
"title": "78fa2f09497dbc6ea38ab0b9cce290c8e99833ad",
"url": "https://chromium.googlesource.com/chromium/src/+/78fa2f09497dbc6ea38ab0b9cce290c8e99833ad"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3750e4368c6b4b55fa38f408633ac5ea581753e2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@3750e4368c6b4b55fa38f408633ac5ea581753e2",
"title": "3750e4368c6b4b55fa38f408633ac5ea581753e2",
"url": "https://chromium.googlesource.com/chromium/src/+/3750e4368c6b4b55fa38f408633ac5ea581753e2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7cfc80e06f0d2c75036ca4917bf173c28a166a02"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@7cfc80e06f0d2c75036ca4917bf173c28a166a02",
"title": "7cfc80e06f0d2c75036ca4917bf173c28a166a02",
"url": "https://chromium.googlesource.com/chromium/src/+/7cfc80e06f0d2c75036ca4917bf173c28a166a02"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0902a75ecef5737b2c073a79f990bbc77ab85df5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@0902a75ecef5737b2c073a79f990bbc77ab85df5",
"title": "0902a75ecef5737b2c073a79f990bbc77ab85df5",
"url": "https://chromium.googlesource.com/chromium/src/+/0902a75ecef5737b2c073a79f990bbc77ab85df5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0982314138cb457bee4922cba5bb70cd830bbfc6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@0982314138cb457bee4922cba5bb70cd830bbfc6",
"title": "0982314138cb457bee4922cba5bb70cd830bbfc6",
"url": "https://chromium.googlesource.com/chromium/src/+/0982314138cb457bee4922cba5bb70cd830bbfc6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873",
"title": "b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873",
"url": "https://chromium.googlesource.com/chromium/src/+/b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873",
"ref": "refs/branch-heads/7390"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870182090395240752"
},
{
"key": "scheduler_job_id",
"value": "chromium-m141/chromeos-jacuzzi-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:23:04.817] Scheduled build:
{
"id": "8704752028421121841",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"number": 12,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-03T11:23:02.017187134Z",
"updateTime": "2025-09-03T11:23:02.017187134Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873",
"ref": "refs/branch-heads/7390"
}
}
}
[11:23:04.817] Task URL: https://cr-buildbucket.appspot.com/build/8704752028421121841
[11:23:04.817] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:2:0) after 7m52s
[11:23:05.385] Received PubSub notification, asking Buildbucket for the build status
[11:23:05.412] Build status: SCHEDULED
[11:23:09.307] Received PubSub notification, asking Buildbucket for the build status
[11:23:09.325] Build status: STARTED
[11:30:56.831] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:2:0)
[11:30:56.851] Build status: STARTED
[11:30:56.851] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:5:0) after 1m24s
[11:32:20.862] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:5:0)
[11:32:20.883] Build status: STARTED
[11:32:20.883] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:6:0) after 3m45s
[11:36:05.928] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:6:0)
[11:36:05.954] Build status: STARTED
[11:36:05.954] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:7:0) after 1m32s
[11:37:38.016] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:7:0)
[11:37:38.038] Build status: STARTED
[11:37:38.038] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:8:0) after 7m9s
[11:44:47.084] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:8:0)
[11:44:47.147] Build status: STARTED
[11:44:47.147] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:9:0) after 6m41s
[11:51:28.160] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:9:0)
[11:51:28.178] Build status: STARTED
[11:51:28.178] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:10:0) after 3m10s
[11:54:38.192] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:10:0)
[11:54:38.210] Build status: STARTED
[11:54:38.210] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:11:0) after 3m0s
[11:57:38.222] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:11:0)
[11:57:38.240] Build status: STARTED
[11:57:38.240] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:12:0) after 6m1s
[12:03:39.283] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:12:0)
[12:03:39.318] Build status: STARTED
[12:03:39.318] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:13:0) after 9m14s
[12:12:53.330] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:13:0)
[12:12:53.386] Build status: STARTED
[12:12:53.386] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:14:0) after 2m11s
[12:15:04.401] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:14:0)
[12:15:04.462] Build status: STARTED
[12:15:04.462] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:15:0) after 4m7s
[12:19:11.476] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:15:0)
[12:19:11.497] Build status: STARTED
[12:19:11.497] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:16:0) after 5m52s
[12:25:03.540] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:16:0)
[12:25:03.573] Build status: STARTED
[12:25:03.573] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:17:0) after 2m24s
[12:27:27.643] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:17:0)
[12:27:27.661] Build status: STARTED
[12:27:27.661] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:18:0) after 8m44s
[12:36:11.702] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:18:0)
[12:36:11.724] Build status: STARTED
[12:36:11.724] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:19:0) after 1m56s
[12:38:07.739] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:19:0)
[12:38:07.764] Build status: STARTED
[12:38:07.764] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:20:0) after 8m12s
[12:46:19.829] Handling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:20:0)
[12:46:19.842] Build status: STARTED
[12:46:19.842] Scheduling timer "check-buildbucket-build-status" (chromium-m141/chromeos-jacuzzi-rel:8870182090395240752:21:0) after 9m2s
[12:48:06.211] Received PubSub notification, asking Buildbucket for the build status
[12:48:06.284] Build:
{
"id": "8704752028421121841",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"number": 12,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-03T11:23:02.017187134Z",
"startTime": "2025-09-03T11:23:09.092381667Z",
"endTime": "2025-09-03T12:48:05.931514902Z",
"updateTime": "2025-09-03T12:48:05.931514902Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b90eb63a6a4471b1be8dc3573e3fdddb4e2e7873",
"ref": "refs/branch-heads/7390"
}
}
}
[12:48:06.284] Invocation finished in 1h25m5.532462105s with status SUCCEEDED