[04:25:34.165] New invocation is queued and will start shortly
[04:25:36.508] Starting the invocation (attempt 1)
[04:25:36.538] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0065c0815101c861388810fed92a21177d76caf"
[04:25:36.538] Popped gitiles commit info from properties and tags
[04:25:36.539] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:25:36.539] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:25:36.539] Buildbucket request:
{
"requestId": "8845928366657326944",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8845928366657326944",
"job": "chromium-m148/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c8b0257dbce5b068b2f4fe4845798ba41ba8e399"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@c8b0257dbce5b068b2f4fe4845798ba41ba8e399",
"title": "c8b0257dbce5b068b2f4fe4845798ba41ba8e399",
"url": "https://chromium.googlesource.com/chromium/src/+/c8b0257dbce5b068b2f4fe4845798ba41ba8e399"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "20545884554be0fbb046745e48e5b209f8ff8624"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@20545884554be0fbb046745e48e5b209f8ff8624",
"title": "20545884554be0fbb046745e48e5b209f8ff8624",
"url": "https://chromium.googlesource.com/chromium/src/+/20545884554be0fbb046745e48e5b209f8ff8624"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dabd0689f68e02e5133ba096ef4b9048148a0466"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@dabd0689f68e02e5133ba096ef4b9048148a0466",
"title": "dabd0689f68e02e5133ba096ef4b9048148a0466",
"url": "https://chromium.googlesource.com/chromium/src/+/dabd0689f68e02e5133ba096ef4b9048148a0466"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3d3db33aa1ef0b09a9c88b487ab56462497d2579"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@3d3db33aa1ef0b09a9c88b487ab56462497d2579",
"title": "3d3db33aa1ef0b09a9c88b487ab56462497d2579",
"url": "https://chromium.googlesource.com/chromium/src/+/3d3db33aa1ef0b09a9c88b487ab56462497d2579"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7b9e13873a9b9168facdbc6fb838d3e970792c21"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@7b9e13873a9b9168facdbc6fb838d3e970792c21",
"title": "7b9e13873a9b9168facdbc6fb838d3e970792c21",
"url": "https://chromium.googlesource.com/chromium/src/+/7b9e13873a9b9168facdbc6fb838d3e970792c21"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "670ffff80c8a2c658a144751066d21985972b576"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@670ffff80c8a2c658a144751066d21985972b576",
"title": "670ffff80c8a2c658a144751066d21985972b576",
"url": "https://chromium.googlesource.com/chromium/src/+/670ffff80c8a2c658a144751066d21985972b576"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dec4f589f6fe1e91c32d1e5dc089a928ce0089bc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@dec4f589f6fe1e91c32d1e5dc089a928ce0089bc",
"title": "dec4f589f6fe1e91c32d1e5dc089a928ce0089bc",
"url": "https://chromium.googlesource.com/chromium/src/+/dec4f589f6fe1e91c32d1e5dc089a928ce0089bc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c35b24cbb76845e472f9d6efc12b8d5c1d6c960c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@c35b24cbb76845e472f9d6efc12b8d5c1d6c960c",
"title": "c35b24cbb76845e472f9d6efc12b8d5c1d6c960c",
"url": "https://chromium.googlesource.com/chromium/src/+/c35b24cbb76845e472f9d6efc12b8d5c1d6c960c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f4a45fedf044f5ef85a7af71a70d40e8ef2c99bc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@f4a45fedf044f5ef85a7af71a70d40e8ef2c99bc",
"title": "f4a45fedf044f5ef85a7af71a70d40e8ef2c99bc",
"url": "https://chromium.googlesource.com/chromium/src/+/f4a45fedf044f5ef85a7af71a70d40e8ef2c99bc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "64f4d6a2a534e912c9d5d27c970b8209b7c9f499"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@64f4d6a2a534e912c9d5d27c970b8209b7c9f499",
"title": "64f4d6a2a534e912c9d5d27c970b8209b7c9f499",
"url": "https://chromium.googlesource.com/chromium/src/+/64f4d6a2a534e912c9d5d27c970b8209b7c9f499"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "58b9e861c97f04fb36671f95caa08545b8c699d7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@58b9e861c97f04fb36671f95caa08545b8c699d7",
"title": "58b9e861c97f04fb36671f95caa08545b8c699d7",
"url": "https://chromium.googlesource.com/chromium/src/+/58b9e861c97f04fb36671f95caa08545b8c699d7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "83e70e4ebc7b39c96f0a347a6309dc4e7da6a4e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@83e70e4ebc7b39c96f0a347a6309dc4e7da6a4e7",
"title": "83e70e4ebc7b39c96f0a347a6309dc4e7da6a4e7",
"url": "https://chromium.googlesource.com/chromium/src/+/83e70e4ebc7b39c96f0a347a6309dc4e7da6a4e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7336c313ae4bb6ae959647aadcb1e97f0ffb1c88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@7336c313ae4bb6ae959647aadcb1e97f0ffb1c88",
"title": "7336c313ae4bb6ae959647aadcb1e97f0ffb1c88",
"url": "https://chromium.googlesource.com/chromium/src/+/7336c313ae4bb6ae959647aadcb1e97f0ffb1c88"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34c8eb781e1258906b73cd695170aeeb84feea0b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@34c8eb781e1258906b73cd695170aeeb84feea0b",
"title": "34c8eb781e1258906b73cd695170aeeb84feea0b",
"url": "https://chromium.googlesource.com/chromium/src/+/34c8eb781e1258906b73cd695170aeeb84feea0b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e0065c0815101c861388810fed92a21177d76caf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7778@e0065c0815101c861388810fed92a21177d76caf",
"title": "e0065c0815101c861388810fed92a21177d76caf",
"url": "https://chromium.googlesource.com/chromium/src/+/e0065c0815101c861388810fed92a21177d76caf"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e0065c0815101c861388810fed92a21177d76caf",
"ref": "refs/branch-heads/7778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8845928366657326944"
},
{
"key": "scheduler_job_id",
"value": "chromium-m148/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:25:37.042] Scheduled build:
{
"id": "8680498303447952641",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win-official"
},
"number": 374,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-29T04:25:36.601603419Z",
"updateTime": "2026-05-29T04:25:36.601603419Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e0065c0815101c861388810fed92a21177d76caf",
"ref": "refs/branch-heads/7778"
}
}
}
[04:25:37.042] Task URL: https://cr-buildbucket.appspot.com/build/8680498303447952641
[04:25:37.042] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:2:0) after 2m17s
[04:25:38.157] Received PubSub notification, asking Buildbucket for the build status
[04:25:38.175] Build status: SCHEDULED
[04:25:41.591] Received PubSub notification, asking Buildbucket for the build status
[04:25:41.675] Build status: STARTED
[04:27:54.070] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:2:0)
[04:27:54.087] Build status: STARTED
[04:27:54.087] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:5:0) after 4m25s
[04:32:19.145] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:5:0)
[04:32:19.167] Build status: STARTED
[04:32:19.167] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:6:0) after 4m8s
[04:36:27.422] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:6:0)
[04:36:27.447] Build status: STARTED
[04:36:27.448] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:7:0) after 3m33s
[04:40:00.465] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:7:0)
[04:40:00.596] Build status: STARTED
[04:40:00.596] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:8:0) after 7m53s
[04:47:53.632] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:8:0)
[04:47:53.648] Build status: STARTED
[04:47:53.648] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:9:0) after 3m37s
[04:51:30.662] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:9:0)
[04:51:30.679] Build status: STARTED
[04:51:30.679] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:10:0) after 7m28s
[04:58:58.747] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:10:0)
[04:58:58.767] Build status: STARTED
[04:58:58.767] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:11:0) after 3m22s
[05:02:20.780] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:11:0)
[05:02:20.794] Build status: STARTED
[05:02:20.794] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:12:0) after 9m16s
[05:11:36.809] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:12:0)
[05:11:37.109] Build status: STARTED
[05:11:37.109] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:13:0) after 3m17s
[05:14:54.126] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:13:0)
[05:14:54.144] Build status: STARTED
[05:14:54.144] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:14:0) after 5m24s
[05:20:18.247] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:14:0)
[05:20:18.397] Build status: STARTED
[05:20:18.397] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:15:0) after 5m20s
[05:25:38.513] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:15:0)
[05:25:38.703] Build status: STARTED
[05:25:38.703] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:16:0) after 9m3s
[05:34:41.780] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:16:0)
[05:34:41.797] Build status: STARTED
[05:34:41.797] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:17:0) after 4m46s
[05:39:27.813] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:17:0)
[05:39:27.830] Build status: STARTED
[05:39:27.830] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:18:0) after 7m7s
[05:46:34.835] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:18:0)
[05:46:34.888] Build status: STARTED
[05:46:34.888] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:19:0) after 7m42s
[05:54:17.028] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:19:0)
[05:54:17.048] Build status: STARTED
[05:54:17.048] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:20:0) after 5m28s
[05:59:45.067] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:20:0)
[05:59:45.084] Build status: STARTED
[05:59:45.084] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:21:0) after 7m24s
[06:07:09.086] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:21:0)
[06:07:09.193] Build status: STARTED
[06:07:09.193] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:22:0) after 1m38s
[06:08:47.246] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:22:0)
[06:08:47.269] Build status: STARTED
[06:08:47.269] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:23:0) after 3m24s
[06:12:11.452] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:23:0)
[06:12:11.499] Build status: STARTED
[06:12:11.499] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:24:0) after 3m39s
[06:15:50.606] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:24:0)
[06:15:50.622] Build status: STARTED
[06:15:50.622] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:25:0) after 7m34s
[06:23:24.689] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:25:0)
[06:23:24.803] Build status: STARTED
[06:23:24.803] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:26:0) after 1m20s
[06:24:44.818] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:26:0)
[06:24:44.837] Build status: STARTED
[06:24:44.837] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:27:0) after 2m28s
[06:27:12.825] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:27:0)
[06:27:12.859] Build status: STARTED
[06:27:12.859] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:28:0) after 9m21s
[06:36:33.846] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:28:0)
[06:36:33.862] Build status: STARTED
[06:36:33.862] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:29:0) after 8m21s
[06:44:54.939] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:29:0)
[06:44:54.966] Build status: STARTED
[06:44:54.966] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:30:0) after 2m7s
[06:47:01.942] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:30:0)
[06:47:01.982] Build status: STARTED
[06:47:01.982] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:31:0) after 9m20s
[06:56:22.035] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:31:0)
[06:56:22.064] Build status: STARTED
[06:56:22.064] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:32:0) after 5m50s
[07:02:12.181] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:32:0)
[07:02:12.224] Build status: STARTED
[07:02:12.224] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:33:0) after 9m35s
[07:11:47.290] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:33:0)
[07:11:47.351] Build status: STARTED
[07:11:47.351] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:34:0) after 2m34s
[07:14:21.417] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:34:0)
[07:14:21.444] Build status: STARTED
[07:14:21.445] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:35:0) after 6m25s
[07:20:46.462] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:35:0)
[07:20:46.491] Build status: STARTED
[07:20:46.491] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:36:0) after 2m10s
[07:22:56.547] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:36:0)
[07:22:56.563] Build status: STARTED
[07:22:56.563] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:37:0) after 2m52s
[07:25:48.577] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:37:0)
[07:25:48.595] Build status: STARTED
[07:25:48.595] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:38:0) after 9m46s
[07:35:34.613] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:38:0)
[07:35:34.630] Build status: STARTED
[07:35:34.630] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:39:0) after 9m7s
[07:44:41.699] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:39:0)
[07:44:41.722] Build status: STARTED
[07:44:41.722] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:40:0) after 9m4s
[07:53:45.787] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:40:0)
[07:53:45.804] Build status: STARTED
[07:53:45.804] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:41:0) after 3m42s
[07:57:27.946] Handling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:41:0)
[07:57:27.961] Build status: STARTED
[07:57:27.961] Scheduling timer "check-buildbucket-build-status" (chromium-m148/win-official:8845928366657326944:42:0) after 8m11s
[08:01:06.746] Received PubSub notification, asking Buildbucket for the build status
[08:01:06.765] Build:
{
"id": "8680498303447952641",
"builder": {
"project": "chromium-m148",
"bucket": "ci",
"builder": "win-official"
},
"number": 374,
"createdBy": "project:chromium-m148",
"createTime": "2026-05-29T04:25:36.601603419Z",
"startTime": "2026-05-29T04:25:41.424060778Z",
"endTime": "2026-05-29T08:01:06.020614703Z",
"updateTime": "2026-05-29T08:01:06.020614703Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e0065c0815101c861388810fed92a21177d76caf",
"ref": "refs/branch-heads/7778"
}
}
}
[08:01:06.765] Invocation finished in 3h35m32.608373083s with status SUCCEEDED