[01:30:36.265] New invocation is queued and will start shortly
[01:30:37.324] Starting the invocation (attempt 1)
[01:30:37.349] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8f3c21e869ecfad577b9a7816f3a1cf15f228ce1"
[01:30:37.349] Popped gitiles commit info from properties and tags
[01:30:37.349] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:30:37.349] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:30:37.349] Buildbucket request:
{
"requestId": "8850288028887747424",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8850288028887747424",
"job": "chromium-m148/linux-cast-x64-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2dd22779c4889b753f90d4debd6e3e8f85e1f95a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@2dd22779c4889b753f90d4debd6e3e8f85e1f95a",
"title": "2dd22779c4889b753f90d4debd6e3e8f85e1f95a",
"url": "https://chromium.googlesource.com/chromium/src/+/2dd22779c4889b753f90d4debd6e3e8f85e1f95a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0ae48bbd02723737bb94d222c59b26eb3d8ea10b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@0ae48bbd02723737bb94d222c59b26eb3d8ea10b",
"title": "0ae48bbd02723737bb94d222c59b26eb3d8ea10b",
"url": "https://chromium.googlesource.com/chromium/src/+/0ae48bbd02723737bb94d222c59b26eb3d8ea10b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bef9807551962a33c3103356b2b9737a68924d9e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@bef9807551962a33c3103356b2b9737a68924d9e",
"title": "bef9807551962a33c3103356b2b9737a68924d9e",
"url": "https://chromium.googlesource.com/chromium/src/+/bef9807551962a33c3103356b2b9737a68924d9e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb9466897154357bc0dc92be13c8a42f0af14f24"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@cb9466897154357bc0dc92be13c8a42f0af14f24",
"title": "cb9466897154357bc0dc92be13c8a42f0af14f24",
"url": "https://chromium.googlesource.com/chromium/src/+/cb9466897154357bc0dc92be13c8a42f0af14f24"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c6e29a01cd7b370e16926d26ff87a40a39697c8c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@c6e29a01cd7b370e16926d26ff87a40a39697c8c",
"title": "c6e29a01cd7b370e16926d26ff87a40a39697c8c",
"url": "https://chromium.googlesource.com/chromium/src/+/c6e29a01cd7b370e16926d26ff87a40a39697c8c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d569d2e08f305e6794f1d3f30826c2431158c4fa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@d569d2e08f305e6794f1d3f30826c2431158c4fa",
"title": "d569d2e08f305e6794f1d3f30826c2431158c4fa",
"url": "https://chromium.googlesource.com/chromium/src/+/d569d2e08f305e6794f1d3f30826c2431158c4fa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "545327ff392cb7efdf3624eb1d204a185b7bfd2d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@545327ff392cb7efdf3624eb1d204a185b7bfd2d",
"title": "545327ff392cb7efdf3624eb1d204a185b7bfd2d",
"url": "https://chromium.googlesource.com/chromium/src/+/545327ff392cb7efdf3624eb1d204a185b7bfd2d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b14f1b122ee481e8ef5613a10e64c669991d7170"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@b14f1b122ee481e8ef5613a10e64c669991d7170",
"title": "b14f1b122ee481e8ef5613a10e64c669991d7170",
"url": "https://chromium.googlesource.com/chromium/src/+/b14f1b122ee481e8ef5613a10e64c669991d7170"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "53e85c92bfa1750f46f788459d41d140a3dbf9da"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@53e85c92bfa1750f46f788459d41d140a3dbf9da",
"title": "53e85c92bfa1750f46f788459d41d140a3dbf9da",
"url": "https://chromium.googlesource.com/chromium/src/+/53e85c92bfa1750f46f788459d41d140a3dbf9da"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7fc8040ee0c87f6bd42cee031985902c1feeee4c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@7fc8040ee0c87f6bd42cee031985902c1feeee4c",
"title": "7fc8040ee0c87f6bd42cee031985902c1feeee4c",
"url": "https://chromium.googlesource.com/chromium/src/+/7fc8040ee0c87f6bd42cee031985902c1feeee4c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2eafd4d6d613e9e04f2f762061140b0411d7fdde"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@2eafd4d6d613e9e04f2f762061140b0411d7fdde",
"title": "2eafd4d6d613e9e04f2f762061140b0411d7fdde",
"url": "https://chromium.googlesource.com/chromium/src/+/2eafd4d6d613e9e04f2f762061140b0411d7fdde"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "042aa7e5eba3d2db1066546c68c5458a8e525455"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@042aa7e5eba3d2db1066546c68c5458a8e525455",
"title": "042aa7e5eba3d2db1066546c68c5458a8e525455",
"url": "https://chromium.googlesource.com/chromium/src/+/042aa7e5eba3d2db1066546c68c5458a8e525455"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8f3c21e869ecfad577b9a7816f3a1cf15f228ce1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@8f3c21e869ecfad577b9a7816f3a1cf15f228ce1",
"title": "8f3c21e869ecfad577b9a7816f3a1cf15f228ce1",
"url": "https://chromium.googlesource.com/chromium/src/+/8f3c21e869ecfad577b9a7816f3a1cf15f228ce1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8f3c21e869ecfad577b9a7816f3a1cf15f228ce1",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850288028887747424"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/linux-cast-x64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:30:38.136] Scheduled build:
{
"id": "8684857966901088737",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"number": 50,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-11T01:30:37.538962221Z",
"updateTime": "2026-04-11T01:30:37.538962221Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8f3c21e869ecfad577b9a7816f3a1cf15f228ce1",
"ref": "refs/branch-heads/7778"
}
}
}
[01:30:38.136] Task URL: https://cr-buildbucket.appspot.com/build/8684857966901088737
[01:30:38.136] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:2:0) after 8m50s
[01:30:54.511] Received PubSub notification, asking Buildbucket for the build status
[01:30:54.542] Build status: SCHEDULED
[01:31:34.717] Received PubSub notification, asking Buildbucket for the build status
[01:31:34.735] Build status: STARTED
[01:39:28.168] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:2:0)
[01:39:28.189] Build status: STARTED
[01:39:28.189] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:5:0) after 7m53s
[01:47:21.207] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:5:0)
[01:47:21.308] Build status: STARTED
[01:47:21.308] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:6:0) after 1m23s
[01:48:44.366] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:6:0)
[01:48:44.379] Build status: STARTED
[01:48:44.379] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:7:0) after 4m33s
[01:53:17.424] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:7:0)
[01:53:17.452] Build status: STARTED
[01:53:17.452] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:8:0) after 4m25s
[01:57:42.439] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:8:0)
[01:57:42.525] Build status: STARTED
[01:57:42.525] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:9:0) after 5m47s
[02:03:29.659] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:9:0)
[02:03:29.690] Build status: STARTED
[02:03:29.690] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:10:0) after 3m31s
[02:07:00.734] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:10:0)
[02:07:00.751] Build status: STARTED
[02:07:00.751] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:11:0) after 8m28s
[02:15:28.816] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:11:0)
[02:15:28.835] Build status: STARTED
[02:15:28.835] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:12:0) after 3m20s
[02:18:48.871] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:12:0)
[02:18:48.907] Build status: STARTED
[02:18:48.907] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:13:0) after 1m32s
[02:20:20.902] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:13:0)
[02:20:20.930] Build status: STARTED
[02:20:20.930] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:14:0) after 3m57s
[02:24:17.964] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:14:0)
[02:24:17.991] Build status: STARTED
[02:24:17.991] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:15:0) after 2m6s
[02:26:24.004] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:15:0)
[02:26:24.025] Build status: STARTED
[02:26:24.025] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:16:0) after 7m37s
[02:34:01.132] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:16:0)
[02:34:01.152] Build status: STARTED
[02:34:01.152] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:17:0) after 2m20s
[02:36:21.169] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:17:0)
[02:36:21.191] Build status: STARTED
[02:36:21.191] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:18:0) after 5m1s
[02:41:22.260] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:18:0)
[02:41:22.328] Build status: STARTED
[02:41:22.328] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:19:0) after 1m8s
[02:42:30.392] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:19:0)
[02:42:30.416] Build status: STARTED
[02:42:30.416] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:20:0) after 2m23s
[02:44:53.481] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:20:0)
[02:44:53.509] Build status: STARTED
[02:44:53.509] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:21:0) after 3m17s
[02:48:10.527] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:21:0)
[02:48:10.555] Build status: STARTED
[02:48:10.555] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:22:0) after 2m20s
[02:50:30.589] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:22:0)
[02:50:30.603] Build status: STARTED
[02:50:30.603] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:23:0) after 6m8s
[02:56:38.721] Handling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:23:0)
[02:56:38.743] Build status: STARTED
[02:56:38.743] Scheduling timer "check-buildbucket-build-status" (chromium-m148/linux-cast-x64-dbg:8850288028887747424:24:0) after 8m14s
[02:59:42.145] Received PubSub notification, asking Buildbucket for the build status
[02:59:42.167] Build:
{
"id": "8684857966901088737",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "linux-cast-x64-dbg"
},
"number": 50,
"createdBy": "project:chromium-m148",
"createTime": "2026-04-11T01:30:37.538962221Z",
"startTime": "2026-04-11T01:31:24.148231729Z",
"endTime": "2026-04-11T02:59:41.857568420Z",
"updateTime": "2026-04-11T02:59:41.857568420Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8f3c21e869ecfad577b9a7816f3a1cf15f228ce1",
"ref": "refs/branch-heads/7778"
}
}
}
[02:59:42.167] Invocation finished in 1h29m5.906600712s with status SUCCEEDED