[03:15:12.495] New invocation is queued and will start shortly
[03:15:13.582] Starting the invocation (attempt 1)
[03:15:13.616] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e76ead1cb376cd556059de5403c95b9e34e5d646"
[03:15:13.616] Popped gitiles commit info from properties and tags
[03:15:13.616] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:15:13.616] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:15:13.617] Buildbucket request:
{
"requestId": "8869397407698352992",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8869397407698352992",
"job": "chromium-m141/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "063cfd7bc07d56f1dd833f938d3230d654b7c816"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@063cfd7bc07d56f1dd833f938d3230d654b7c816",
"title": "063cfd7bc07d56f1dd833f938d3230d654b7c816",
"url": "https://chromium.googlesource.com/chromium/src/+/063cfd7bc07d56f1dd833f938d3230d654b7c816"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "44bba6b0bfd23f2ced9cca8d24b64a427ccc3b54"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@44bba6b0bfd23f2ced9cca8d24b64a427ccc3b54",
"title": "44bba6b0bfd23f2ced9cca8d24b64a427ccc3b54",
"url": "https://chromium.googlesource.com/chromium/src/+/44bba6b0bfd23f2ced9cca8d24b64a427ccc3b54"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "10dbc50662492e9c9ebf659caaf674df9a909d51"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@10dbc50662492e9c9ebf659caaf674df9a909d51",
"title": "10dbc50662492e9c9ebf659caaf674df9a909d51",
"url": "https://chromium.googlesource.com/chromium/src/+/10dbc50662492e9c9ebf659caaf674df9a909d51"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e8ad7a65b5633b4bf70a52dd4a536bbb2de3e74"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@2e8ad7a65b5633b4bf70a52dd4a536bbb2de3e74",
"title": "2e8ad7a65b5633b4bf70a52dd4a536bbb2de3e74",
"url": "https://chromium.googlesource.com/chromium/src/+/2e8ad7a65b5633b4bf70a52dd4a536bbb2de3e74"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c1fac70afa9da99f34f7aca21b5b3fb4041645eb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@c1fac70afa9da99f34f7aca21b5b3fb4041645eb",
"title": "c1fac70afa9da99f34f7aca21b5b3fb4041645eb",
"url": "https://chromium.googlesource.com/chromium/src/+/c1fac70afa9da99f34f7aca21b5b3fb4041645eb"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "219355efedae0a26dcde16913f68da6ad16349ce"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@219355efedae0a26dcde16913f68da6ad16349ce",
"title": "219355efedae0a26dcde16913f68da6ad16349ce",
"url": "https://chromium.googlesource.com/chromium/src/+/219355efedae0a26dcde16913f68da6ad16349ce"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "be0020dc35ee5039b5a0feeef920905bd0f58ee8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@be0020dc35ee5039b5a0feeef920905bd0f58ee8",
"title": "be0020dc35ee5039b5a0feeef920905bd0f58ee8",
"url": "https://chromium.googlesource.com/chromium/src/+/be0020dc35ee5039b5a0feeef920905bd0f58ee8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eedbadeb0d1918ba14b61ad871738b315d384d79"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@eedbadeb0d1918ba14b61ad871738b315d384d79",
"title": "eedbadeb0d1918ba14b61ad871738b315d384d79",
"url": "https://chromium.googlesource.com/chromium/src/+/eedbadeb0d1918ba14b61ad871738b315d384d79"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b46872dc6e9ec72738d4a249a88ca59c6dedf155"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@b46872dc6e9ec72738d4a249a88ca59c6dedf155",
"title": "b46872dc6e9ec72738d4a249a88ca59c6dedf155",
"url": "https://chromium.googlesource.com/chromium/src/+/b46872dc6e9ec72738d4a249a88ca59c6dedf155"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "046c28d50a83a37a3dbb260d509670d5cfc40b7f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@046c28d50a83a37a3dbb260d509670d5cfc40b7f",
"title": "046c28d50a83a37a3dbb260d509670d5cfc40b7f",
"url": "https://chromium.googlesource.com/chromium/src/+/046c28d50a83a37a3dbb260d509670d5cfc40b7f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "61c102a3105a76a3d9f63dc492828684236adee4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@61c102a3105a76a3d9f63dc492828684236adee4",
"title": "61c102a3105a76a3d9f63dc492828684236adee4",
"url": "https://chromium.googlesource.com/chromium/src/+/61c102a3105a76a3d9f63dc492828684236adee4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e3f6e02a1449e50f299cccbfe3d5c961bf9c8a5f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@e3f6e02a1449e50f299cccbfe3d5c961bf9c8a5f",
"title": "e3f6e02a1449e50f299cccbfe3d5c961bf9c8a5f",
"url": "https://chromium.googlesource.com/chromium/src/+/e3f6e02a1449e50f299cccbfe3d5c961bf9c8a5f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0137646b533beed3d290db8963d505ec36598be0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@0137646b533beed3d290db8963d505ec36598be0",
"title": "0137646b533beed3d290db8963d505ec36598be0",
"url": "https://chromium.googlesource.com/chromium/src/+/0137646b533beed3d290db8963d505ec36598be0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7390",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e76ead1cb376cd556059de5403c95b9e34e5d646"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7390@e76ead1cb376cd556059de5403c95b9e34e5d646",
"title": "e76ead1cb376cd556059de5403c95b9e34e5d646",
"url": "https://chromium.googlesource.com/chromium/src/+/e76ead1cb376cd556059de5403c95b9e34e5d646"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e76ead1cb376cd556059de5403c95b9e34e5d646",
"ref": "refs/branch-heads/7390"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8869397407698352992"
},
{
"key": "scheduler_job_id",
"value": "chromium-m141/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:15:14.212] Scheduled build:
{
"id": "8703967345800046385",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 80,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-12T03:15:13.679663316Z",
"updateTime": "2025-09-12T03:15:13.679663316Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e76ead1cb376cd556059de5403c95b9e34e5d646",
"ref": "refs/branch-heads/7390"
}
}
}
[03:15:14.212] Task URL: https://cr-buildbucket.appspot.com/build/8703967345800046385
[03:15:14.212] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:2:0) after 4m4s
[03:15:15.156] Received PubSub notification, asking Buildbucket for the build status
[03:15:15.195] Build status: SCHEDULED
[03:19:18.328] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:2:0)
[03:19:18.342] Build status: SCHEDULED
[03:19:18.342] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:4:0) after 3m48s
[03:23:06.394] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:4:0)
[03:23:06.428] Build status: SCHEDULED
[03:23:06.428] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:5:0) after 3m39s
[03:24:32.476] Received PubSub notification, asking Buildbucket for the build status
[03:24:32.495] Build status: STARTED
[03:26:45.454] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:5:0)
[03:26:45.534] Build status: STARTED
[03:26:45.534] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:7:0) after 2m43s
[03:29:28.549] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:7:0)
[03:29:28.570] Build status: STARTED
[03:29:28.570] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:8:0) after 2m40s
[03:32:08.634] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:8:0)
[03:32:08.695] Build status: STARTED
[03:32:08.695] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:9:0) after 6m46s
[03:38:54.740] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:9:0)
[03:38:54.757] Build status: STARTED
[03:38:54.757] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:10:0) after 7m43s
[03:46:37.770] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:10:0)
[03:46:37.862] Build status: STARTED
[03:46:37.862] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:11:0) after 8m45s
[03:55:22.836] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:11:0)
[03:55:22.852] Build status: STARTED
[03:55:22.853] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:12:0) after 4m4s
[03:59:26.868] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:12:0)
[03:59:26.933] Build status: STARTED
[03:59:26.933] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:13:0) after 2m45s
[04:02:12.014] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:13:0)
[04:02:12.033] Build status: STARTED
[04:02:12.033] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:14:0) after 8m39s
[04:10:51.057] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:14:0)
[04:10:51.135] Build status: STARTED
[04:10:51.136] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:15:0) after 5m54s
[04:16:45.156] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:15:0)
[04:16:45.175] Build status: STARTED
[04:16:45.175] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:16:0) after 1m35s
[04:18:20.210] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:16:0)
[04:18:20.231] Build status: STARTED
[04:18:20.231] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:17:0) after 9m24s
[04:27:44.248] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:17:0)
[04:27:44.268] Build status: STARTED
[04:27:44.268] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:18:0) after 6m34s
[04:34:18.371] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:18:0)
[04:34:18.391] Build status: STARTED
[04:34:18.391] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:19:0) after 1m43s
[04:36:01.407] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:19:0)
[04:36:01.429] Build status: STARTED
[04:36:01.429] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:20:0) after 5m35s
[04:41:36.483] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:20:0)
[04:41:36.555] Build status: STARTED
[04:41:36.555] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:21:0) after 8m10s
[04:49:47.062] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:21:0)
[04:49:47.082] Build status: STARTED
[04:49:47.082] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:22:0) after 5m41s
[04:55:28.129] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:22:0)
[04:55:28.147] Build status: STARTED
[04:55:28.147] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:23:0) after 5m45s
[05:01:13.210] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:23:0)
[05:01:13.225] Build status: STARTED
[05:01:13.225] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:24:0) after 8m13s
[05:09:26.318] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:24:0)
[05:09:26.335] Build status: STARTED
[05:09:26.335] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:25:0) after 1m36s
[05:11:02.435] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:25:0)
[05:11:02.473] Build status: STARTED
[05:11:02.473] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:26:0) after 6m22s
[05:17:24.467] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:26:0)
[05:17:24.486] Build status: STARTED
[05:17:24.486] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:27:0) after 2m45s
[05:20:09.551] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:27:0)
[05:20:09.565] Build status: STARTED
[05:20:09.565] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:28:0) after 6m50s
[05:26:59.634] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:28:0)
[05:26:59.656] Build status: STARTED
[05:26:59.656] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:29:0) after 7m53s
[05:34:52.778] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:29:0)
[05:34:52.799] Build status: STARTED
[05:34:52.800] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:30:0) after 1m35s
[05:36:28.137] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:30:0)
[05:36:28.188] Build status: STARTED
[05:36:28.188] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:31:0) after 9m58s
[05:46:26.603] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:31:0)
[05:46:26.637] Build status: STARTED
[05:46:26.637] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:32:0) after 8m13s
[05:54:39.654] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:32:0)
[05:54:39.715] Build status: STARTED
[05:54:39.715] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:33:0) after 3m29s
[05:58:08.785] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:33:0)
[05:58:08.808] Build status: STARTED
[05:58:08.809] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:34:0) after 5m8s
[06:03:16.818] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:34:0)
[06:03:16.846] Build status: STARTED
[06:03:16.846] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:35:0) after 9m55s
[06:13:11.986] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:35:0)
[06:13:12.073] Build status: STARTED
[06:13:12.073] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:36:0) after 5m13s
[06:18:25.250] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:36:0)
[06:18:25.302] Build status: STARTED
[06:18:25.302] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:37:0) after 3m10s
[06:21:35.338] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:37:0)
[06:21:35.397] Build status: STARTED
[06:21:35.397] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:38:0) after 1m12s
[06:22:47.730] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:38:0)
[06:22:47.747] Build status: STARTED
[06:22:47.747] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:39:0) after 9m4s
[06:31:51.770] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:39:0)
[06:31:51.789] Build status: STARTED
[06:31:51.789] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:40:0) after 9m52s
[06:41:44.175] Handling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:40:0)
[06:41:44.244] Build status: STARTED
[06:41:44.244] Scheduling timer "check-buildbucket-build-status" (chromium-m141/win-official:8869397407698352992:41:0) after 7m55s
[06:42:16.194] Received PubSub notification, asking Buildbucket for the build status
[06:42:16.209] Build:
{
"id": "8703967345800046385",
"builder": {
"project": "chromium-m141",
"bucket": "ci",
"builder": "win-official"
},
"number": 80,
"createdBy": "project:chromium-m141",
"createTime": "2025-09-12T03:15:13.679663316Z",
"startTime": "2025-09-12T03:24:32.293466561Z",
"endTime": "2025-09-12T06:42:15.324862988Z",
"updateTime": "2025-09-12T06:42:15.324862988Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e76ead1cb376cd556059de5403c95b9e34e5d646",
"ref": "refs/branch-heads/7390"
}
}
}
[06:42:16.209] Invocation finished in 3h27m3.723991851s with status SUCCEEDED