[01:41:36.973] New invocation is queued and will start shortly
[01:41:38.334] Starting the invocation (attempt 1)
[01:41:38.358] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3a2a37a8ab276310e18b1a7383a6429900941c86"
[01:41:38.358] Popped gitiles commit info from properties and tags
[01:41:38.358] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:41:38.358] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:41:38.358] Buildbucket request:
{
"requestId": "8857806884303478352",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857806884303478352",
"job": "chromium-m144/chromeos-jacuzzi-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "80be69ef794ba862ff256b0b23f051cbbc32e1ed"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@80be69ef794ba862ff256b0b23f051cbbc32e1ed",
"title": "80be69ef794ba862ff256b0b23f051cbbc32e1ed",
"url": "https://chromium.googlesource.com/chromium/src/+/80be69ef794ba862ff256b0b23f051cbbc32e1ed"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e9005764e56bf52be782b82b379a009e21007b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@2e9005764e56bf52be782b82b379a009e21007b7",
"title": "2e9005764e56bf52be782b82b379a009e21007b7",
"url": "https://chromium.googlesource.com/chromium/src/+/2e9005764e56bf52be782b82b379a009e21007b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "da10a6e39a68df912e9be9c12befd30ff5ecde5e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@da10a6e39a68df912e9be9c12befd30ff5ecde5e",
"title": "da10a6e39a68df912e9be9c12befd30ff5ecde5e",
"url": "https://chromium.googlesource.com/chromium/src/+/da10a6e39a68df912e9be9c12befd30ff5ecde5e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4d704cba739f0def4e3096d3890eb9c737494710"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@4d704cba739f0def4e3096d3890eb9c737494710",
"title": "4d704cba739f0def4e3096d3890eb9c737494710",
"url": "https://chromium.googlesource.com/chromium/src/+/4d704cba739f0def4e3096d3890eb9c737494710"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7db3b5e763257b08227e386a5fb79429537655b5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@7db3b5e763257b08227e386a5fb79429537655b5",
"title": "7db3b5e763257b08227e386a5fb79429537655b5",
"url": "https://chromium.googlesource.com/chromium/src/+/7db3b5e763257b08227e386a5fb79429537655b5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c9b734d8f730669e4f563856a561c2c3867691a7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@c9b734d8f730669e4f563856a561c2c3867691a7",
"title": "c9b734d8f730669e4f563856a561c2c3867691a7",
"url": "https://chromium.googlesource.com/chromium/src/+/c9b734d8f730669e4f563856a561c2c3867691a7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "612f6be010edf55941d58fc0901dbf6ca9ccbb2c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@612f6be010edf55941d58fc0901dbf6ca9ccbb2c",
"title": "612f6be010edf55941d58fc0901dbf6ca9ccbb2c",
"url": "https://chromium.googlesource.com/chromium/src/+/612f6be010edf55941d58fc0901dbf6ca9ccbb2c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7559",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3a2a37a8ab276310e18b1a7383a6429900941c86"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7559@3a2a37a8ab276310e18b1a7383a6429900941c86",
"title": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"url": "https://chromium.googlesource.com/chromium/src/+/3a2a37a8ab276310e18b1a7383a6429900941c86"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"ref": "refs/branch-heads/7559"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857806884303478352"
},
{
"key": "scheduler_job_id",
"value": "chromium-m144/chromeos-jacuzzi-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:41:38.796] Scheduled build:
{
"id": "8692376822143573057",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"number": 677,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-18T01:41:38.404297266Z",
"updateTime": "2026-01-18T01:41:38.404297266Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"ref": "refs/branch-heads/7559"
}
},
"canary": true
}
[01:41:38.796] Task URL: https://cr-buildbucket.appspot.com/build/8692376822143573057
[01:41:38.796] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:2:0) after 7m39s
[01:41:39.840] Received PubSub notification, asking Buildbucket for the build status
[01:41:39.854] Build status: SCHEDULED
[01:41:43.244] Received PubSub notification, asking Buildbucket for the build status
[01:41:43.332] Build status: STARTED
[01:49:17.957] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:2:0)
[01:49:17.979] Build status: STARTED
[01:49:17.979] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:5:0) after 6m19s
[01:55:37.075] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:5:0)
[01:55:37.087] Build status: STARTED
[01:55:37.087] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:6:0) after 1m36s
[01:57:13.167] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:6:0)
[01:57:13.191] Build status: STARTED
[01:57:13.191] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:7:0) after 2m9s
[01:59:22.209] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:7:0)
[01:59:22.238] Build status: STARTED
[01:59:22.238] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:8:0) after 4m32s
[02:03:54.252] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:8:0)
[02:03:54.281] Build status: STARTED
[02:03:54.281] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:9:0) after 3m43s
[02:07:37.425] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:9:0)
[02:07:37.500] Build status: STARTED
[02:07:37.500] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:10:0) after 1m46s
[02:09:23.516] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:10:0)
[02:09:23.600] Build status: STARTED
[02:09:23.600] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:11:0) after 4m20s
[02:13:43.812] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:11:0)
[02:13:43.855] Build status: STARTED
[02:13:43.855] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:12:0) after 6m12s
[02:19:55.881] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:12:0)
[02:19:55.902] Build status: STARTED
[02:19:55.902] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:13:0) after 8m29s
[02:28:24.917] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:13:0)
[02:28:24.932] Build status: STARTED
[02:28:24.932] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:14:0) after 5m18s
[02:33:43.209] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:14:0)
[02:33:43.233] Build status: STARTED
[02:33:43.233] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:15:0) after 2m40s
[02:36:23.264] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:15:0)
[02:36:23.285] Build status: STARTED
[02:36:23.285] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:16:0) after 5m47s
[02:42:10.437] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:16:0)
[02:42:10.465] Build status: STARTED
[02:42:10.466] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:17:0) after 4m54s
[02:47:04.771] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:17:0)
[02:47:04.827] Build status: STARTED
[02:47:04.827] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:18:0) after 9m25s
[02:56:29.887] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:18:0)
[02:56:29.899] Build status: STARTED
[02:56:29.899] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:19:0) after 3m40s
[03:00:10.016] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:19:0)
[03:00:10.043] Build status: STARTED
[03:00:10.043] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:20:0) after 6m5s
[03:06:15.056] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:20:0)
[03:06:15.073] Build status: STARTED
[03:06:15.073] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:21:0) after 2m48s
[03:09:03.175] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:21:0)
[03:09:03.191] Build status: STARTED
[03:09:03.191] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:22:0) after 6m32s
[03:15:35.404] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:22:0)
[03:15:35.421] Build status: STARTED
[03:15:35.421] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:23:0) after 7m4s
[03:22:39.521] Handling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:23:0)
[03:22:39.543] Build status: STARTED
[03:22:39.543] Scheduling timer "check-buildbucket-build-status" (chromium-m144/chromeos-jacuzzi-rel:8857806884303478352:24:0) after 7m12s
[03:29:01.546] Received PubSub notification, asking Buildbucket for the build status
[03:29:01.569] Build:
{
"id": "8692376822143573057",
"builder": {
"project": "chromium-m144",
"bucket": "ci",
"builder": "chromeos-jacuzzi-rel"
},
"number": 677,
"createdBy": "project:chromium-m144",
"createTime": "2026-01-18T01:41:38.404297266Z",
"startTime": "2026-01-18T01:41:43.117847240Z",
"endTime": "2026-01-18T03:29:00.722440491Z",
"updateTime": "2026-01-18T03:29:00.722440491Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3a2a37a8ab276310e18b1a7383a6429900941c86",
"ref": "refs/branch-heads/7559"
}
},
"canary": true
}
[03:29:01.569] Invocation finished in 1h47m24.607727294s with status SUCCEEDED