[18:06:02.325] New invocation is queued and will start shortly
[18:06:03.482] Starting the invocation (attempt 1)
[18:06:03.503] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/62e30fd41f344a414d4ffc7ddf61b187c85dcd2c"
[18:06:03.503] Popped gitiles commit info from properties and tags
[18:06:03.503] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:06:03.503] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:06:03.503] Buildbucket request:
{
"requestId": "8844970777522540944",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8844970777522540944",
"job": "chromium-m150/linux-cast-x64-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "964474592af70f1d6aef310ca2f63819d7aa3286"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@964474592af70f1d6aef310ca2f63819d7aa3286",
"title": "964474592af70f1d6aef310ca2f63819d7aa3286",
"url": "https://chromium.googlesource.com/chromium/src/+/964474592af70f1d6aef310ca2f63819d7aa3286"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "818f14ceb51c4fb2c764a1b10d83514a9fb74e65"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@818f14ceb51c4fb2c764a1b10d83514a9fb74e65",
"title": "818f14ceb51c4fb2c764a1b10d83514a9fb74e65",
"url": "https://chromium.googlesource.com/chromium/src/+/818f14ceb51c4fb2c764a1b10d83514a9fb74e65"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ea5756545b3ca55f93cc00efdda42c63d6e31d88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@ea5756545b3ca55f93cc00efdda42c63d6e31d88",
"title": "ea5756545b3ca55f93cc00efdda42c63d6e31d88",
"url": "https://chromium.googlesource.com/chromium/src/+/ea5756545b3ca55f93cc00efdda42c63d6e31d88"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2b079eafc8f2018ecd811e8f5340923bfb9ab31e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@2b079eafc8f2018ecd811e8f5340923bfb9ab31e",
"title": "2b079eafc8f2018ecd811e8f5340923bfb9ab31e",
"url": "https://chromium.googlesource.com/chromium/src/+/2b079eafc8f2018ecd811e8f5340923bfb9ab31e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0af0652a8565e49f1e216ba548f7fff31474d373"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@0af0652a8565e49f1e216ba548f7fff31474d373",
"title": "0af0652a8565e49f1e216ba548f7fff31474d373",
"url": "https://chromium.googlesource.com/chromium/src/+/0af0652a8565e49f1e216ba548f7fff31474d373"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ec37bfb8205024f758f67255b85fe820be3c9a43"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@ec37bfb8205024f758f67255b85fe820be3c9a43",
"title": "ec37bfb8205024f758f67255b85fe820be3c9a43",
"url": "https://chromium.googlesource.com/chromium/src/+/ec37bfb8205024f758f67255b85fe820be3c9a43"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "01fd2706c1c32c27f4c56c04aa4f17fa38a8d54d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@01fd2706c1c32c27f4c56c04aa4f17fa38a8d54d",
"title": "01fd2706c1c32c27f4c56c04aa4f17fa38a8d54d",
"url": "https://chromium.googlesource.com/chromium/src/+/01fd2706c1c32c27f4c56c04aa4f17fa38a8d54d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6fcf91540949355ad239358bb0f4e04c250dae21"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@6fcf91540949355ad239358bb0f4e04c250dae21",
"title": "6fcf91540949355ad239358bb0f4e04c250dae21",
"url": "https://chromium.googlesource.com/chromium/src/+/6fcf91540949355ad239358bb0f4e04c250dae21"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "62e30fd41f344a414d4ffc7ddf61b187c85dcd2c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@62e30fd41f344a414d4ffc7ddf61b187c85dcd2c",
"title": "62e30fd41f344a414d4ffc7ddf61b187c85dcd2c",
"url": "https://chromium.googlesource.com/chromium/src/+/62e30fd41f344a414d4ffc7ddf61b187c85dcd2c"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "62e30fd41f344a414d4ffc7ddf61b187c85dcd2c",
"ref": "refs/branch-heads/7871"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8844970777522540944"
},
{
"key": "scheduler_job_id",
"value": "chromium-m150/linux-cast-x64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:06:05.835] Scheduled build:
{
"id": "8679540715523892385",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"number": 96,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-08T18:06:03.610735246Z",
"updateTime": "2026-06-08T18:06:03.610735246Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "62e30fd41f344a414d4ffc7ddf61b187c85dcd2c",
"ref": "refs/branch-heads/7871"
}
}
}
[18:06:05.835] Task URL: https://cr-buildbucket.appspot.com/build/8679540715523892385
[18:06:05.835] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:2:0) after 1m35s
[18:06:06.618] Received PubSub notification, asking Buildbucket for the build status
[18:06:06.874] Build status: SCHEDULED
[18:06:14.042] Received PubSub notification, asking Buildbucket for the build status
[18:06:14.065] Build status: STARTED
[18:07:40.894] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:2:0)
[18:07:40.929] Build status: STARTED
[18:07:40.929] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:5:0) after 2m39s
[18:10:19.914] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:5:0)
[18:10:19.933] Build status: STARTED
[18:10:19.933] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:6:0) after 9m43s
[18:20:02.999] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:6:0)
[18:20:03.020] Build status: STARTED
[18:20:03.020] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:7:0) after 2m38s
[18:22:41.175] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:7:0)
[18:22:41.294] Build status: STARTED
[18:22:41.294] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:8:0) after 1m11s
[18:23:52.393] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:8:0)
[18:23:52.435] Build status: STARTED
[18:23:52.435] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:9:0) after 2m29s
[18:26:21.519] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:9:0)
[18:26:21.535] Build status: STARTED
[18:26:21.535] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:10:0) after 1m3s
[18:27:24.702] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:10:0)
[18:27:24.728] Build status: STARTED
[18:27:24.728] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:11:0) after 2m26s
[18:29:50.781] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:11:0)
[18:29:50.812] Build status: STARTED
[18:29:50.812] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:12:0) after 3m57s
[18:33:47.838] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:12:0)
[18:33:47.870] Build status: STARTED
[18:33:47.870] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:13:0) after 6m40s
[18:40:27.940] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:13:0)
[18:40:27.957] Build status: STARTED
[18:40:27.957] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:14:0) after 5m53s
[18:46:21.019] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:14:0)
[18:46:21.042] Build status: STARTED
[18:46:21.042] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:15:0) after 9m6s
[18:55:27.075] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:15:0)
[18:55:27.099] Build status: STARTED
[18:55:27.099] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:16:0) after 1m37s
[18:57:04.150] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:16:0)
[18:57:04.166] Build status: STARTED
[18:57:04.166] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:17:0) after 4m40s
[19:01:44.471] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:17:0)
[19:01:44.489] Build status: STARTED
[19:01:44.489] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:18:0) after 4m22s
[19:06:06.527] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:18:0)
[19:06:06.547] Build status: STARTED
[19:06:06.547] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:19:0) after 3m41s
[19:09:47.587] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:19:0)
[19:09:47.604] Build status: STARTED
[19:09:47.604] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:20:0) after 1m26s
[19:11:13.646] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:20:0)
[19:11:13.703] Build status: STARTED
[19:11:13.703] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:21:0) after 2m13s
[19:13:26.755] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:21:0)
[19:13:26.769] Build status: STARTED
[19:13:26.769] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:22:0) after 2m28s
[19:15:54.856] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:22:0)
[19:15:55.023] Build status: STARTED
[19:15:55.023] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:23:0) after 5m27s
[19:21:22.047] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:23:0)
[19:21:22.101] Build status: STARTED
[19:21:22.101] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:24:0) after 2m20s
[19:23:42.279] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:24:0)
[19:23:42.308] Build status: STARTED
[19:23:42.308] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:25:0) after 2m2s
[19:25:44.441] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:25:0)
[19:25:44.457] Build status: STARTED
[19:25:44.457] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:26:0) after 7m14s
[19:32:58.680] Handling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:26:0)
[19:32:58.770] Build status: STARTED
[19:32:58.770] Scheduling timer "check-buildbucket-build-status" (chromium-m150/linux-cast-x64-dbg:8844970777522540944:27:0) after 9m27s
[19:37:00.901] Received PubSub notification, asking Buildbucket for the build status
[19:37:00.922] Build:
{
"id": "8679540715523892385",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"number": 96,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-08T18:06:03.610735246Z",
"startTime": "2026-06-08T18:06:13.541344916Z",
"endTime": "2026-06-08T19:37:00.271257549Z",
"updateTime": "2026-06-08T19:37:00.271257549Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "62e30fd41f344a414d4ffc7ddf61b187c85dcd2c",
"ref": "refs/branch-heads/7871"
}
}
}
[19:37:00.922] Invocation finished in 1h30m58.601136438s with status SUCCEEDED