[23:42:55.192] New invocation is queued and will start shortly
[23:42:56.760] Starting the invocation (attempt 1)
[23:42:56.819] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c4c5704e839ac4c624a790d432d29b17ada562c4"
[23:42:56.819] Popped gitiles commit info from properties and tags
[23:42:56.819] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:42:56.819] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:42:56.820] Buildbucket request:
{
"requestId": "8855549427865895536",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8855549427865895536",
"job": "chromium-m145/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "17270587d1e0393b669420d5d5dcdd6526ead316"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@17270587d1e0393b669420d5d5dcdd6526ead316",
"title": "17270587d1e0393b669420d5d5dcdd6526ead316",
"url": "https://chromium.googlesource.com/chromium/src/+/17270587d1e0393b669420d5d5dcdd6526ead316"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "12e46de5c8e1312cde5141af3d1d66b267806dd4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@12e46de5c8e1312cde5141af3d1d66b267806dd4",
"title": "12e46de5c8e1312cde5141af3d1d66b267806dd4",
"url": "https://chromium.googlesource.com/chromium/src/+/12e46de5c8e1312cde5141af3d1d66b267806dd4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "383509739b07ef09878f62c8fdec6b7abd7c3e78"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@383509739b07ef09878f62c8fdec6b7abd7c3e78",
"title": "383509739b07ef09878f62c8fdec6b7abd7c3e78",
"url": "https://chromium.googlesource.com/chromium/src/+/383509739b07ef09878f62c8fdec6b7abd7c3e78"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2d8ff91a0505c82b03c7780da000c0017a054be6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@2d8ff91a0505c82b03c7780da000c0017a054be6",
"title": "2d8ff91a0505c82b03c7780da000c0017a054be6",
"url": "https://chromium.googlesource.com/chromium/src/+/2d8ff91a0505c82b03c7780da000c0017a054be6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "600e07f1f6a93b282ada0c594652d87b403c3722"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@600e07f1f6a93b282ada0c594652d87b403c3722",
"title": "600e07f1f6a93b282ada0c594652d87b403c3722",
"url": "https://chromium.googlesource.com/chromium/src/+/600e07f1f6a93b282ada0c594652d87b403c3722"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d10e38302625329ea29fb9d24c859ff9aa47169a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@d10e38302625329ea29fb9d24c859ff9aa47169a",
"title": "d10e38302625329ea29fb9d24c859ff9aa47169a",
"url": "https://chromium.googlesource.com/chromium/src/+/d10e38302625329ea29fb9d24c859ff9aa47169a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1b9a5bfe27cdbcfef9d466a8a6d052a9493fca47"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@1b9a5bfe27cdbcfef9d466a8a6d052a9493fca47",
"title": "1b9a5bfe27cdbcfef9d466a8a6d052a9493fca47",
"url": "https://chromium.googlesource.com/chromium/src/+/1b9a5bfe27cdbcfef9d466a8a6d052a9493fca47"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "31d92142efda96440af963fa8b9ab10945fba2b1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@31d92142efda96440af963fa8b9ab10945fba2b1",
"title": "31d92142efda96440af963fa8b9ab10945fba2b1",
"url": "https://chromium.googlesource.com/chromium/src/+/31d92142efda96440af963fa8b9ab10945fba2b1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6104da30717be99ce5817f98b741356c97fd1c07"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@6104da30717be99ce5817f98b741356c97fd1c07",
"title": "6104da30717be99ce5817f98b741356c97fd1c07",
"url": "https://chromium.googlesource.com/chromium/src/+/6104da30717be99ce5817f98b741356c97fd1c07"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "39ab0548726c811e91bc26c3270c6a62a2d7aa2d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@39ab0548726c811e91bc26c3270c6a62a2d7aa2d",
"title": "39ab0548726c811e91bc26c3270c6a62a2d7aa2d",
"url": "https://chromium.googlesource.com/chromium/src/+/39ab0548726c811e91bc26c3270c6a62a2d7aa2d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5a68e4da07695804e4cd6db7fc8453f242ba71ca"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@5a68e4da07695804e4cd6db7fc8453f242ba71ca",
"title": "5a68e4da07695804e4cd6db7fc8453f242ba71ca",
"url": "https://chromium.googlesource.com/chromium/src/+/5a68e4da07695804e4cd6db7fc8453f242ba71ca"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5fa850a331ea45aa1f524a4485d052707a3c7f0d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@5fa850a331ea45aa1f524a4485d052707a3c7f0d",
"title": "5fa850a331ea45aa1f524a4485d052707a3c7f0d",
"url": "https://chromium.googlesource.com/chromium/src/+/5fa850a331ea45aa1f524a4485d052707a3c7f0d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4eb28946eae9710fb11785ae89057847dafc4e7a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@4eb28946eae9710fb11785ae89057847dafc4e7a",
"title": "4eb28946eae9710fb11785ae89057847dafc4e7a",
"url": "https://chromium.googlesource.com/chromium/src/+/4eb28946eae9710fb11785ae89057847dafc4e7a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b8f65e3201dabb32c09536955edce0d10bfdf90d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@b8f65e3201dabb32c09536955edce0d10bfdf90d",
"title": "b8f65e3201dabb32c09536955edce0d10bfdf90d",
"url": "https://chromium.googlesource.com/chromium/src/+/b8f65e3201dabb32c09536955edce0d10bfdf90d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8ba88061a4c261bce9db4e321065729eec2a4a88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@8ba88061a4c261bce9db4e321065729eec2a4a88",
"title": "8ba88061a4c261bce9db4e321065729eec2a4a88",
"url": "https://chromium.googlesource.com/chromium/src/+/8ba88061a4c261bce9db4e321065729eec2a4a88"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7db3b3cdbf4912a756f1a71ff0ebc59ee373f471"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@7db3b3cdbf4912a756f1a71ff0ebc59ee373f471",
"title": "7db3b3cdbf4912a756f1a71ff0ebc59ee373f471",
"url": "https://chromium.googlesource.com/chromium/src/+/7db3b3cdbf4912a756f1a71ff0ebc59ee373f471"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7afacaf2203aed2c44428412a2c2bf6ec8774359"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@7afacaf2203aed2c44428412a2c2bf6ec8774359",
"title": "7afacaf2203aed2c44428412a2c2bf6ec8774359",
"url": "https://chromium.googlesource.com/chromium/src/+/7afacaf2203aed2c44428412a2c2bf6ec8774359"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "419788e158ff272ea1b9cf607786bd16bbe1f022"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@419788e158ff272ea1b9cf607786bd16bbe1f022",
"title": "419788e158ff272ea1b9cf607786bd16bbe1f022",
"url": "https://chromium.googlesource.com/chromium/src/+/419788e158ff272ea1b9cf607786bd16bbe1f022"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c4c5704e839ac4c624a790d432d29b17ada562c4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@c4c5704e839ac4c624a790d432d29b17ada562c4",
"title": "c4c5704e839ac4c624a790d432d29b17ada562c4",
"url": "https://chromium.googlesource.com/chromium/src/+/c4c5704e839ac4c624a790d432d29b17ada562c4"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c4c5704e839ac4c624a790d432d29b17ada562c4",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8855549427865895536"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:42:57.363] Scheduled build:
{
"id": "8690119365444264113",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-official"
},
"number": 226,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-11T23:42:56.879794723Z",
"updateTime": "2026-02-11T23:42:56.879794723Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c4c5704e839ac4c624a790d432d29b17ada562c4",
"ref": "refs/branch-heads/7632"
}
}
}
[23:42:57.363] Task URL: https://cr-buildbucket.appspot.com/build/8690119365444264113
[23:42:57.363] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:2:0) after 4m15s
[23:42:57.514] Received PubSub notification, asking Buildbucket for the build status
[23:42:57.535] Build status: SCHEDULED
[23:44:22.726] Received PubSub notification, asking Buildbucket for the build status
[23:44:22.755] Build status: STARTED
[23:47:12.455] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:2:0)
[23:47:12.473] Build status: STARTED
[23:47:12.473] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:5:0) after 4m34s
[23:51:46.602] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:5:0)
[23:51:46.618] Build status: STARTED
[23:51:46.618] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:6:0) after 6m5s
[23:57:51.641] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:6:0)
[23:57:51.662] Build status: STARTED
[23:57:51.662] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:7:0) after 1m29s
[23:59:20.677] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:7:0)
[23:59:20.698] Build status: STARTED
[23:59:20.698] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:8:0) after 9m3s
[00:08:23.712] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:8:0)
[00:08:23.738] Build status: STARTED
[00:08:23.738] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:9:0) after 1m15s
[00:09:38.797] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:9:0)
[00:09:38.813] Build status: STARTED
[00:09:38.813] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:10:0) after 3m33s
[00:13:11.830] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:10:0)
[00:13:11.859] Build status: STARTED
[00:13:11.859] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:11:0) after 5m0s
[00:18:11.920] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:11:0)
[00:18:11.948] Build status: STARTED
[00:18:11.948] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:12:0) after 8m46s
[00:26:57.964] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:12:0)
[00:26:58.022] Build status: STARTED
[00:26:58.023] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:13:0) after 7m29s
[00:34:27.086] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:13:0)
[00:34:27.108] Build status: STARTED
[00:34:27.108] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:14:0) after 1m32s
[00:35:59.130] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:14:0)
[00:35:59.146] Build status: STARTED
[00:35:59.146] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:15:0) after 9m13s
[00:45:12.165] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:15:0)
[00:45:12.187] Build status: STARTED
[00:45:12.187] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:16:0) after 8m44s
[00:53:56.270] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:16:0)
[00:53:56.287] Build status: STARTED
[00:53:56.287] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:17:0) after 9m49s
[01:03:45.314] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:17:0)
[01:03:45.351] Build status: STARTED
[01:03:45.351] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:18:0) after 4m10s
[01:07:55.367] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:18:0)
[01:07:55.385] Build status: STARTED
[01:07:55.385] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:19:0) after 4m1s
[01:11:56.479] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:19:0)
[01:11:56.551] Build status: STARTED
[01:11:56.551] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:20:0) after 1m37s
[01:13:33.614] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:20:0)
[01:13:33.690] Build status: STARTED
[01:13:33.690] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:21:0) after 9m43s
[01:23:16.806] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:21:0)
[01:23:16.829] Build status: STARTED
[01:23:16.829] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:22:0) after 1m39s
[01:24:55.798] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:22:0)
[01:24:55.838] Build status: STARTED
[01:24:55.838] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:23:0) after 2m39s
[01:27:34.904] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:23:0)
[01:27:34.933] Build status: STARTED
[01:27:34.933] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:24:0) after 3m25s
[01:30:59.949] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:24:0)
[01:30:59.966] Build status: STARTED
[01:30:59.966] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:25:0) after 5m52s
[01:36:51.987] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:25:0)
[01:36:52.009] Build status: STARTED
[01:36:52.009] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:26:0) after 2m37s
[01:39:29.064] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:26:0)
[01:39:29.080] Build status: STARTED
[01:39:29.080] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:27:0) after 4m3s
[01:43:32.097] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:27:0)
[01:43:32.113] Build status: STARTED
[01:43:32.113] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:28:0) after 8m33s
[01:52:05.143] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:28:0)
[01:52:05.164] Build status: STARTED
[01:52:05.164] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:29:0) after 6m8s
[01:58:13.198] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:29:0)
[01:58:13.211] Build status: STARTED
[01:58:13.211] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:30:0) after 2m7s
[02:00:20.227] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:30:0)
[02:00:20.272] Build status: STARTED
[02:00:20.272] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:31:0) after 9m50s
[02:10:10.312] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:31:0)
[02:10:10.338] Build status: STARTED
[02:10:10.338] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:32:0) after 2m0s
[02:12:10.369] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:32:0)
[02:12:10.385] Build status: STARTED
[02:12:10.385] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:33:0) after 5m4s
[02:17:14.455] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:33:0)
[02:17:14.482] Build status: STARTED
[02:17:14.482] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:34:0) after 4m8s
[02:21:22.485] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:34:0)
[02:21:22.615] Build status: STARTED
[02:21:22.615] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:35:0) after 1m4s
[02:22:26.635] Handling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:35:0)
[02:22:26.656] Build status: STARTED
[02:22:26.657] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win-official:8855549427865895536:36:0) after 9m40s
[02:27:26.912] Received PubSub notification, asking Buildbucket for the build status
[02:27:26.926] Build:
{
"id": "8690119365444264113",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win-official"
},
"number": 226,
"createdBy": "project:chromium-m145",
"createTime": "2026-02-11T23:42:56.879794723Z",
"startTime": "2026-02-11T23:44:19.149705937Z",
"endTime": "2026-02-12T02:27:26.653515982Z",
"updateTime": "2026-02-12T02:27:26.653515982Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c4c5704e839ac4c624a790d432d29b17ada562c4",
"ref": "refs/branch-heads/7632"
}
}
}
[02:27:26.926] Invocation finished in 2h44m31.740089637s with status SUCCEEDED