[16:35:19.314] New invocation is queued and will start shortly
[16:35:21.048] Starting the invocation (attempt 1)
[16:35:21.153] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6539226ea2df6ee2d8babbff079b313f7615789d"
[16:35:21.153] Popped gitiles commit info from properties and tags
[16:35:21.153] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:35:21.153] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:35:21.154] Buildbucket request:
{
"requestId": "8843526933486115408",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8843526933486115408",
"job": "chromium-m150/win-arm64-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "51a58bd8f4a6adc40422efdf29573e45a33dba39"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@51a58bd8f4a6adc40422efdf29573e45a33dba39",
"title": "51a58bd8f4a6adc40422efdf29573e45a33dba39",
"url": "https://chromium.googlesource.com/chromium/src/+/51a58bd8f4a6adc40422efdf29573e45a33dba39"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a474ad4a849ea2d50cdda39bc2446823f9473559"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@a474ad4a849ea2d50cdda39bc2446823f9473559",
"title": "a474ad4a849ea2d50cdda39bc2446823f9473559",
"url": "https://chromium.googlesource.com/chromium/src/+/a474ad4a849ea2d50cdda39bc2446823f9473559"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6212191adb979c566554eee1bd220dc185ed55e6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@6212191adb979c566554eee1bd220dc185ed55e6",
"title": "6212191adb979c566554eee1bd220dc185ed55e6",
"url": "https://chromium.googlesource.com/chromium/src/+/6212191adb979c566554eee1bd220dc185ed55e6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cdf4e3e7c64eea17eee46fdcf14af89c18081cc0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@cdf4e3e7c64eea17eee46fdcf14af89c18081cc0",
"title": "cdf4e3e7c64eea17eee46fdcf14af89c18081cc0",
"url": "https://chromium.googlesource.com/chromium/src/+/cdf4e3e7c64eea17eee46fdcf14af89c18081cc0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "503fd028faa3439e4b83d97b1c6d938cb0c5d5ff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@503fd028faa3439e4b83d97b1c6d938cb0c5d5ff",
"title": "503fd028faa3439e4b83d97b1c6d938cb0c5d5ff",
"url": "https://chromium.googlesource.com/chromium/src/+/503fd028faa3439e4b83d97b1c6d938cb0c5d5ff"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "24287a958620395ce760ec871f10db014ceafe05"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@24287a958620395ce760ec871f10db014ceafe05",
"title": "24287a958620395ce760ec871f10db014ceafe05",
"url": "https://chromium.googlesource.com/chromium/src/+/24287a958620395ce760ec871f10db014ceafe05"
},
{
"gitiles": {
"ref": "refs/branch-heads/7871",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6539226ea2df6ee2d8babbff079b313f7615789d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7871@6539226ea2df6ee2d8babbff079b313f7615789d",
"title": "6539226ea2df6ee2d8babbff079b313f7615789d",
"url": "https://chromium.googlesource.com/chromium/src/+/6539226ea2df6ee2d8babbff079b313f7615789d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6539226ea2df6ee2d8babbff079b313f7615789d",
"ref": "refs/branch-heads/7871"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8843526933486115408"
},
{
"key": "scheduler_job_id",
"value": "chromium-m150/win-arm64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:35:21.714] Scheduled build:
{
"id": "8678096870801129841",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 272,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-24T16:35:21.239869631Z",
"updateTime": "2026-06-24T16:35:21.239869631Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6539226ea2df6ee2d8babbff079b313f7615789d",
"ref": "refs/branch-heads/7871"
}
}
}
[16:35:21.714] Task URL: https://cr-buildbucket.appspot.com/build/8678096870801129841
[16:35:21.714] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:2:0) after 6m29s
[16:35:22.554] Received PubSub notification, asking Buildbucket for the build status
[16:35:22.571] Build status: SCHEDULED
[16:35:29.210] Received PubSub notification, asking Buildbucket for the build status
[16:35:29.232] Build status: STARTED
[16:41:50.854] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:2:0)
[16:41:50.883] Build status: STARTED
[16:41:50.883] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:5:0) after 6m21s
[16:48:11.899] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:5:0)
[16:48:11.918] Build status: STARTED
[16:48:11.919] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:6:0) after 3m13s
[16:51:24.927] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:6:0)
[16:51:24.951] Build status: STARTED
[16:51:24.951] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:7:0) after 9m44s
[17:01:09.016] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:7:0)
[17:01:09.035] Build status: STARTED
[17:01:09.035] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:8:0) after 9m42s
[17:10:51.005] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:8:0)
[17:10:51.022] Build status: STARTED
[17:10:51.022] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:9:0) after 3m55s
[17:14:46.042] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:9:0)
[17:14:46.066] Build status: STARTED
[17:14:46.066] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:10:0) after 9m36s
[17:24:22.180] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:10:0)
[17:24:22.236] Build status: STARTED
[17:24:22.236] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:11:0) after 2m35s
[17:26:57.257] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:11:0)
[17:26:57.299] Build status: STARTED
[17:26:57.299] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:12:0) after 1m36s
[17:28:33.322] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:12:0)
[17:28:33.348] Build status: STARTED
[17:28:33.348] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:13:0) after 7m20s
[17:35:53.363] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:13:0)
[17:35:53.396] Build status: STARTED
[17:35:53.396] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:14:0) after 6m25s
[17:42:18.385] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:14:0)
[17:42:18.454] Build status: STARTED
[17:42:18.454] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:15:0) after 1m20s
[17:43:38.468] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:15:0)
[17:43:38.533] Build status: STARTED
[17:43:38.533] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:16:0) after 1m37s
[17:45:15.533] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:16:0)
[17:45:15.556] Build status: STARTED
[17:45:15.556] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:17:0) after 3m25s
[17:48:40.553] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:17:0)
[17:48:40.574] Build status: STARTED
[17:48:40.574] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:18:0) after 2m35s
[17:51:15.640] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:18:0)
[17:51:15.658] Build status: STARTED
[17:51:15.658] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:19:0) after 6m27s
[17:57:42.712] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:19:0)
[17:57:42.728] Build status: STARTED
[17:57:42.728] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:20:0) after 1m6s
[17:58:48.733] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:20:0)
[17:58:48.750] Build status: STARTED
[17:58:48.750] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:21:0) after 8m14s
[18:07:02.804] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:21:0)
[18:07:02.832] Build status: STARTED
[18:07:02.832] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:22:0) after 8m19s
[18:15:21.972] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:22:0)
[18:15:21.990] Build status: STARTED
[18:15:21.990] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:23:0) after 1m40s
[18:17:02.218] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:23:0)
[18:17:02.239] Build status: STARTED
[18:17:02.239] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:24:0) after 8m53s
[18:25:55.297] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:24:0)
[18:25:55.323] Build status: STARTED
[18:25:55.323] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:25:0) after 2m35s
[18:28:30.345] Handling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:25:0)
[18:28:30.363] Build status: STARTED
[18:28:30.363] Scheduling timer "check-buildbucket-build-status" (chromium-m150/win-arm64-dbg:8843526933486115408:26:0) after 7m17s
[18:34:52.266] Received PubSub notification, asking Buildbucket for the build status
[18:34:52.298] Build:
{
"id": "8678096870801129841",
"builder": {
"project": "chromium-m150",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 272,
"createdBy": "project:chromium-m150",
"createTime": "2026-06-24T16:35:21.239869631Z",
"startTime": "2026-06-24T16:35:28.894050473Z",
"endTime": "2026-06-24T18:34:51.895027956Z",
"updateTime": "2026-06-24T18:34:51.895027956Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6539226ea2df6ee2d8babbff079b313f7615789d",
"ref": "refs/branch-heads/7871"
}
}
}
[18:34:52.299] Invocation finished in 1h59m33.003931449s with status SUCCEEDED