[13:05:20.045] New invocation is queued and will start shortly
[13:05:21.167] Starting the invocation (attempt 1)
[13:05:21.197] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6fbce2178807823f4bca84dabf2ca7e272aae74b"
[13:05:21.197] Popped gitiles commit info from properties and tags
[13:05:21.197] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:05:21.197] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:05:21.198] Buildbucket request:
{
"requestId": "8856133123091587296",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8856133123091587296",
"job": "chromium-m145/linux-chromeos-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b5e4306b08059dcc1e2bd38ed49182ee2ffb23ae"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@b5e4306b08059dcc1e2bd38ed49182ee2ffb23ae",
"title": "b5e4306b08059dcc1e2bd38ed49182ee2ffb23ae",
"url": "https://chromium.googlesource.com/chromium/src/+/b5e4306b08059dcc1e2bd38ed49182ee2ffb23ae"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "06918fe93f8a66e55f733f2fa0f0cfbe3096e592"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@06918fe93f8a66e55f733f2fa0f0cfbe3096e592",
"title": "06918fe93f8a66e55f733f2fa0f0cfbe3096e592",
"url": "https://chromium.googlesource.com/chromium/src/+/06918fe93f8a66e55f733f2fa0f0cfbe3096e592"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "39747520b24d4d94d576ee242bd82c44c08c2b27"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@39747520b24d4d94d576ee242bd82c44c08c2b27",
"title": "39747520b24d4d94d576ee242bd82c44c08c2b27",
"url": "https://chromium.googlesource.com/chromium/src/+/39747520b24d4d94d576ee242bd82c44c08c2b27"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "92e92bbde91f22eb909ea2074077971718a9eb31"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@92e92bbde91f22eb909ea2074077971718a9eb31",
"title": "92e92bbde91f22eb909ea2074077971718a9eb31",
"url": "https://chromium.googlesource.com/chromium/src/+/92e92bbde91f22eb909ea2074077971718a9eb31"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3cae34b5562d00fd71f687af2afaacfd1109a62a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@3cae34b5562d00fd71f687af2afaacfd1109a62a",
"title": "3cae34b5562d00fd71f687af2afaacfd1109a62a",
"url": "https://chromium.googlesource.com/chromium/src/+/3cae34b5562d00fd71f687af2afaacfd1109a62a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6fbce2178807823f4bca84dabf2ca7e272aae74b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@6fbce2178807823f4bca84dabf2ca7e272aae74b",
"title": "6fbce2178807823f4bca84dabf2ca7e272aae74b",
"url": "https://chromium.googlesource.com/chromium/src/+/6fbce2178807823f4bca84dabf2ca7e272aae74b"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6fbce2178807823f4bca84dabf2ca7e272aae74b",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8856133123091587296"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:05:21.773] Scheduled build:
{
"id": "8690703061139916433",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 283,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-05T13:05:21.285116528Z",
"updateTime": "2026-02-05T13:05:21.285116528Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6fbce2178807823f4bca84dabf2ca7e272aae74b",
"ref": "refs/branch-heads/7632"
}
}
}
[13:05:21.773] Task URL: https://cr-buildbucket.appspot.com/build/8690703061139916433
[13:05:21.773] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:2:0) after 7m36s
[13:05:23.682] Received PubSub notification, asking Buildbucket for the build status
[13:05:23.697] Build status: SCHEDULED
[13:06:10.134] Received PubSub notification, asking Buildbucket for the build status
[13:06:10.172] Build status: STARTED
[13:12:57.930] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:2:0)
[13:12:57.997] Build status: STARTED
[13:12:57.997] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:5:0) after 8m15s
[13:21:13.022] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:5:0)
[13:21:13.039] Build status: STARTED
[13:21:13.039] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:6:0) after 6m19s
[13:27:32.052] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:6:0)
[13:27:32.068] Build status: STARTED
[13:27:32.068] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:7:0) after 8m27s
[13:35:59.182] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:7:0)
[13:35:59.397] Build status: STARTED
[13:35:59.397] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:8:0) after 1m9s
[13:37:08.457] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:8:0)
[13:37:08.489] Build status: STARTED
[13:37:08.489] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:9:0) after 7m54s
[13:45:02.544] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:9:0)
[13:45:02.581] Build status: STARTED
[13:45:02.581] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:10:0) after 8m24s
[13:53:26.605] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:10:0)
[13:53:26.621] Build status: STARTED
[13:53:26.621] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:11:0) after 3m35s
[13:57:01.645] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:11:0)
[13:57:01.661] Build status: STARTED
[13:57:01.661] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:12:0) after 2m41s
[13:59:42.726] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:12:0)
[13:59:42.808] Build status: STARTED
[13:59:42.808] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:13:0) after 7m9s
[14:06:51.821] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:13:0)
[14:06:52.051] Build status: STARTED
[14:06:52.051] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:14:0) after 7m22s
[14:14:14.184] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:14:0)
[14:14:14.200] Build status: STARTED
[14:14:14.201] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:15:0) after 8m7s
[14:22:21.215] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:15:0)
[14:22:21.237] Build status: STARTED
[14:22:21.237] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:16:0) after 7m27s
[14:29:48.256] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:16:0)
[14:29:48.310] Build status: STARTED
[14:29:48.310] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:17:0) after 3m53s
[14:33:41.367] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:17:0)
[14:33:41.382] Build status: STARTED
[14:33:41.382] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:18:0) after 9m3s
[14:42:44.400] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:18:0)
[14:42:44.418] Build status: STARTED
[14:42:44.418] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:19:0) after 8m47s
[14:51:31.517] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:19:0)
[14:51:31.605] Build status: STARTED
[14:51:31.605] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:20:0) after 3m28s
[14:54:59.734] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:20:0)
[14:54:59.756] Build status: STARTED
[14:54:59.757] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:21:0) after 7m55s
[15:02:54.915] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:21:0)
[15:02:54.937] Build status: STARTED
[15:02:54.938] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:22:0) after 8m38s
[15:11:32.956] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:22:0)
[15:11:32.986] Build status: STARTED
[15:11:32.986] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:23:0) after 1m33s
[15:13:06.005] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:23:0)
[15:13:06.020] Build status: STARTED
[15:13:06.021] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:24:0) after 7m35s
[15:20:41.060] Handling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:24:0)
[15:20:41.082] Build status: STARTED
[15:20:41.082] Scheduling timer "check-buildbucket-build-status" (chromium-m145/linux-chromeos-dbg:8856133123091587296:25:0) after 7m16s
[15:23:28.785] Received PubSub notification, asking Buildbucket for the build status
[15:23:28.804] Build:
{
"id": "8690703061139916433",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 283,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-05T13:05:21.285116528Z",
"startTime": "2026-02-05T13:06:09.969142475Z",
"endTime": "2026-02-05T15:23:28.467628332Z",
"updateTime": "2026-02-05T15:23:28.467628332Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6fbce2178807823f4bca84dabf2ca7e272aae74b",
"ref": "refs/branch-heads/7632"
}
}
}
[15:23:28.805] Invocation finished in 2h18m8.76405814s with status SUCCEEDED