[17:41:04.904] New invocation is queued and will start shortly
[17:41:06.226] Starting the invocation (attempt 1)
[17:41:06.253] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/411d5a57eb86ede42ebf4f8458e1d372986a10d1"
[17:41:06.253] Popped gitiles commit info from properties and tags
[17:41:06.253] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:41:06.253] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:41:06.254] Buildbucket request:
{
"requestId": "8863907113661227328",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8863907113661227328",
"job": "chromium-m143/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "07992e7ec74da63a0a0072c3ae2e2fbb8996e37c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@07992e7ec74da63a0a0072c3ae2e2fbb8996e37c",
"title": "07992e7ec74da63a0a0072c3ae2e2fbb8996e37c",
"url": "https://chromium.googlesource.com/chromium/src/+/07992e7ec74da63a0a0072c3ae2e2fbb8996e37c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4bbd36c1701e7cdcd7e0641d4f3e833cb7bd7c69"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@4bbd36c1701e7cdcd7e0641d4f3e833cb7bd7c69",
"title": "4bbd36c1701e7cdcd7e0641d4f3e833cb7bd7c69",
"url": "https://chromium.googlesource.com/chromium/src/+/4bbd36c1701e7cdcd7e0641d4f3e833cb7bd7c69"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "446fe8ae3a623cd3834316f6a6a32028c520ffb3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@446fe8ae3a623cd3834316f6a6a32028c520ffb3",
"title": "446fe8ae3a623cd3834316f6a6a32028c520ffb3",
"url": "https://chromium.googlesource.com/chromium/src/+/446fe8ae3a623cd3834316f6a6a32028c520ffb3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "371b27b672fde25f43f6d88d096716bd93689a1f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@371b27b672fde25f43f6d88d096716bd93689a1f",
"title": "371b27b672fde25f43f6d88d096716bd93689a1f",
"url": "https://chromium.googlesource.com/chromium/src/+/371b27b672fde25f43f6d88d096716bd93689a1f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7d5d0b39bbdf4b00912304d53608fade7f5f6ee6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@7d5d0b39bbdf4b00912304d53608fade7f5f6ee6",
"title": "7d5d0b39bbdf4b00912304d53608fade7f5f6ee6",
"url": "https://chromium.googlesource.com/chromium/src/+/7d5d0b39bbdf4b00912304d53608fade7f5f6ee6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b0e2cd210c01d1b63ebf03e3c46f3b7e5fb4d9d0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@b0e2cd210c01d1b63ebf03e3c46f3b7e5fb4d9d0",
"title": "b0e2cd210c01d1b63ebf03e3c46f3b7e5fb4d9d0",
"url": "https://chromium.googlesource.com/chromium/src/+/b0e2cd210c01d1b63ebf03e3c46f3b7e5fb4d9d0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e13d474162ff698ee300f53a80d8ac035d6e8ea4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e13d474162ff698ee300f53a80d8ac035d6e8ea4",
"title": "e13d474162ff698ee300f53a80d8ac035d6e8ea4",
"url": "https://chromium.googlesource.com/chromium/src/+/e13d474162ff698ee300f53a80d8ac035d6e8ea4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "28f011f00df15e2a431f33d7d132ac01afd746e9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@28f011f00df15e2a431f33d7d132ac01afd746e9",
"title": "28f011f00df15e2a431f33d7d132ac01afd746e9",
"url": "https://chromium.googlesource.com/chromium/src/+/28f011f00df15e2a431f33d7d132ac01afd746e9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dd3b3ea4498f4a0b2fa5fc3fc29d38b00f63cee3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@dd3b3ea4498f4a0b2fa5fc3fc29d38b00f63cee3",
"title": "dd3b3ea4498f4a0b2fa5fc3fc29d38b00f63cee3",
"url": "https://chromium.googlesource.com/chromium/src/+/dd3b3ea4498f4a0b2fa5fc3fc29d38b00f63cee3"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bfb6c08d905c742e59c93e7c4835e2dec94b31b0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@bfb6c08d905c742e59c93e7c4835e2dec94b31b0",
"title": "bfb6c08d905c742e59c93e7c4835e2dec94b31b0",
"url": "https://chromium.googlesource.com/chromium/src/+/bfb6c08d905c742e59c93e7c4835e2dec94b31b0"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f355e7e74a634a31a66fd8ffff4c28be480e2d3b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@f355e7e74a634a31a66fd8ffff4c28be480e2d3b",
"title": "f355e7e74a634a31a66fd8ffff4c28be480e2d3b",
"url": "https://chromium.googlesource.com/chromium/src/+/f355e7e74a634a31a66fd8ffff4c28be480e2d3b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "74e2b543e54520c897e3d41ba5e97090db76bed5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@74e2b543e54520c897e3d41ba5e97090db76bed5",
"title": "74e2b543e54520c897e3d41ba5e97090db76bed5",
"url": "https://chromium.googlesource.com/chromium/src/+/74e2b543e54520c897e3d41ba5e97090db76bed5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "54bc1e366c4caef5acfa1cead054d09fe985e558"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@54bc1e366c4caef5acfa1cead054d09fe985e558",
"title": "54bc1e366c4caef5acfa1cead054d09fe985e558",
"url": "https://chromium.googlesource.com/chromium/src/+/54bc1e366c4caef5acfa1cead054d09fe985e558"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bdadcc9f4ee94653fa6156e7174c57b239fe918a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@bdadcc9f4ee94653fa6156e7174c57b239fe918a",
"title": "bdadcc9f4ee94653fa6156e7174c57b239fe918a",
"url": "https://chromium.googlesource.com/chromium/src/+/bdadcc9f4ee94653fa6156e7174c57b239fe918a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "411d5a57eb86ede42ebf4f8458e1d372986a10d1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@411d5a57eb86ede42ebf4f8458e1d372986a10d1",
"title": "411d5a57eb86ede42ebf4f8458e1d372986a10d1",
"url": "https://chromium.googlesource.com/chromium/src/+/411d5a57eb86ede42ebf4f8458e1d372986a10d1"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "411d5a57eb86ede42ebf4f8458e1d372986a10d1",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8863907113661227328"
},
{
"key": "scheduler_job_id",
"value": "chromium-m143/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:41:06.787] Scheduled build:
{
"id": "8698477051523028033",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 146,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-11T17:41:06.321369328Z",
"updateTime": "2025-11-11T17:41:06.321369328Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "411d5a57eb86ede42ebf4f8458e1d372986a10d1",
"ref": "refs/branch-heads/7499"
}
},
"canary": true
}
[17:41:06.787] Task URL: https://cr-buildbucket.appspot.com/build/8698477051523028033
[17:41:06.787] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:2:0) after 2m51s
[17:41:07.726] Received PubSub notification, asking Buildbucket for the build status
[17:41:07.779] Build status: SCHEDULED
[17:42:24.294] Received PubSub notification, asking Buildbucket for the build status
[17:42:24.313] Build status: STARTED
[17:43:57.802] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:2:0)
[17:43:57.842] Build status: STARTED
[17:43:57.842] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:5:0) after 4m41s
[17:48:38.861] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:5:0)
[17:48:38.881] Build status: STARTED
[17:48:38.881] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:6:0) after 1m36s
[17:50:14.896] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:6:0)
[17:50:14.917] Build status: STARTED
[17:50:14.917] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:7:0) after 7m26s
[17:57:40.943] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:7:0)
[17:57:40.976] Build status: STARTED
[17:57:40.976] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:8:0) after 8m20s
[18:06:00.995] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:8:0)
[18:06:01.009] Build status: STARTED
[18:06:01.009] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:9:0) after 8m1s
[18:14:02.022] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:9:0)
[18:14:02.084] Build status: STARTED
[18:14:02.084] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:10:0) after 5m47s
[18:19:49.125] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:10:0)
[18:19:49.145] Build status: STARTED
[18:19:49.145] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:11:0) after 1m29s
[18:21:18.162] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:11:0)
[18:21:18.180] Build status: STARTED
[18:21:18.180] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:12:0) after 6m26s
[18:27:44.240] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:12:0)
[18:27:44.289] Build status: STARTED
[18:27:44.289] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:13:0) after 2m6s
[18:29:50.321] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:13:0)
[18:29:50.337] Build status: STARTED
[18:29:50.337] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:14:0) after 5m39s
[18:35:29.351] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:14:0)
[18:35:29.368] Build status: STARTED
[18:35:29.368] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:15:0) after 4m57s
[18:40:26.348] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:15:0)
[18:40:26.370] Build status: STARTED
[18:40:26.370] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:16:0) after 8m34s
[18:49:00.433] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:16:0)
[18:49:00.449] Build status: STARTED
[18:49:00.449] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:17:0) after 4m8s
[18:53:08.474] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:17:0)
[18:53:08.497] Build status: STARTED
[18:53:08.497] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:18:0) after 3m36s
[18:56:44.481] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:18:0)
[18:56:44.506] Build status: STARTED
[18:56:44.506] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:19:0) after 8m53s
[19:05:37.527] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:19:0)
[19:05:37.543] Build status: STARTED
[19:05:37.543] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:20:0) after 4m54s
[19:10:31.574] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:20:0)
[19:10:31.593] Build status: STARTED
[19:10:31.593] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:21:0) after 3m1s
[19:13:32.609] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:21:0)
[19:13:32.625] Build status: STARTED
[19:13:32.625] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:22:0) after 6m40s
[19:20:12.640] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:22:0)
[19:20:12.660] Build status: STARTED
[19:20:12.660] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:23:0) after 2m5s
[19:22:17.764] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:23:0)
[19:22:17.777] Build status: STARTED
[19:22:17.777] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:24:0) after 9m4s
[19:31:21.818] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:24:0)
[19:31:21.837] Build status: STARTED
[19:31:21.837] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:25:0) after 8m52s
[19:40:13.959] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:25:0)
[19:40:13.975] Build status: STARTED
[19:40:13.975] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:26:0) after 3m10s
[19:43:24.007] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:26:0)
[19:43:24.024] Build status: STARTED
[19:43:24.024] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:27:0) after 5m20s
[19:48:44.039] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:27:0)
[19:48:44.069] Build status: STARTED
[19:48:44.069] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:28:0) after 6m27s
[19:55:11.144] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:28:0)
[19:55:11.165] Build status: STARTED
[19:55:11.165] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:29:0) after 9m58s
[20:05:09.183] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:29:0)
[20:05:09.215] Build status: STARTED
[20:05:09.215] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:30:0) after 2m14s
[20:07:23.253] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:30:0)
[20:07:23.345] Build status: STARTED
[20:07:23.345] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:31:0) after 8m25s
[20:15:48.368] Handling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:31:0)
[20:15:48.388] Build status: STARTED
[20:15:48.388] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-arm64-rel:8863907113661227328:32:0) after 8m58s
[20:20:22.164] Received PubSub notification, asking Buildbucket for the build status
[20:20:22.183] Build:
{
"id": "8698477051523028033",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 146,
"createdBy": "project:chromium-m143",
"createTime": "2025-11-11T17:41:06.321369328Z",
"startTime": "2025-11-11T17:42:23.746894558Z",
"endTime": "2025-11-11T20:20:21.860380771Z",
"updateTime": "2025-11-11T20:20:21.860380771Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "411d5a57eb86ede42ebf4f8458e1d372986a10d1",
"ref": "refs/branch-heads/7499"
}
},
"canary": true
}
[20:20:22.183] Invocation finished in 2h39m17.284990057s with status SUCCEEDED