[20:56:10.054] New invocation is queued and will start shortly
[20:56:11.180] Starting the invocation (attempt 1)
[20:56:11.214] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4"
[20:56:11.214] Popped gitiles commit info from properties and tags
[20:56:11.214] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:56:11.214] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:56:11.214] Buildbucket request:
{
"requestId": "8879839906010376368",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8879839906010376368",
"job": "chromium-m136/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d2f8277f06894d43ed15992a5e56544991ad525f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@d2f8277f06894d43ed15992a5e56544991ad525f",
"title": "d2f8277f06894d43ed15992a5e56544991ad525f",
"url": "https://chromium.googlesource.com/chromium/src/+/d2f8277f06894d43ed15992a5e56544991ad525f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4726f657cc204546dec3009efbd00081bea3a3e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@4726f657cc204546dec3009efbd00081bea3a3e7",
"title": "4726f657cc204546dec3009efbd00081bea3a3e7",
"url": "https://chromium.googlesource.com/chromium/src/+/4726f657cc204546dec3009efbd00081bea3a3e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a17c660f18256188a00c73e8208c33d83607bf12"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@a17c660f18256188a00c73e8208c33d83607bf12",
"title": "a17c660f18256188a00c73e8208c33d83607bf12",
"url": "https://chromium.googlesource.com/chromium/src/+/a17c660f18256188a00c73e8208c33d83607bf12"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0841bd41e3d433c8c85aa8ad1186ce9eb38dd7b6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@0841bd41e3d433c8c85aa8ad1186ce9eb38dd7b6",
"title": "0841bd41e3d433c8c85aa8ad1186ce9eb38dd7b6",
"url": "https://chromium.googlesource.com/chromium/src/+/0841bd41e3d433c8c85aa8ad1186ce9eb38dd7b6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4",
"title": "91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4",
"url": "https://chromium.googlesource.com/chromium/src/+/91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8879839906010376368"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:56:11.911] Scheduled build:
{
"id": "8714409843976699761",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 490,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-19T20:56:11.369799753Z",
"updateTime": "2025-05-19T20:56:11.369799753Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4",
"ref": "refs/branch-heads/7103"
}
}
}
[20:56:11.911] Task URL: https://cr-buildbucket.appspot.com/build/8714409843976699761
[20:56:11.911] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:2:0) after 8m37s
[20:56:12.963] Received PubSub notification, asking Buildbucket for the build status
[20:56:13.042] Build status: SCHEDULED
[20:57:27.447] Received PubSub notification, asking Buildbucket for the build status
[20:57:27.471] Build status: STARTED
[21:04:48.964] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:2:0)
[21:04:48.988] Build status: STARTED
[21:04:48.988] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:5:0) after 9m31s
[21:14:20.107] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:5:0)
[21:14:20.141] Build status: STARTED
[21:14:20.141] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:6:0) after 6m29s
[21:20:49.234] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:6:0)
[21:20:49.258] Build status: STARTED
[21:20:49.258] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:7:0) after 2m23s
[21:23:12.320] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:7:0)
[21:23:12.387] Build status: STARTED
[21:23:12.387] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:8:0) after 6m15s
[21:29:27.403] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:8:0)
[21:29:27.434] Build status: STARTED
[21:29:27.434] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:9:0) after 3m18s
[21:32:45.451] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:9:0)
[21:32:45.524] Build status: STARTED
[21:32:45.524] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:10:0) after 4m41s
[21:37:26.658] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:10:0)
[21:37:26.682] Build status: STARTED
[21:37:26.682] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:11:0) after 7m20s
[21:44:46.790] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:11:0)
[21:44:46.818] Build status: STARTED
[21:44:46.818] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:12:0) after 8m33s
[21:53:19.920] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:12:0)
[21:53:19.992] Build status: STARTED
[21:53:19.992] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:13:0) after 5m25s
[21:58:45.070] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:13:0)
[21:58:45.107] Build status: STARTED
[21:58:45.107] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:14:0) after 5m40s
[22:04:25.198] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:14:0)
[22:04:25.261] Build status: STARTED
[22:04:25.261] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:15:0) after 2m25s
[22:06:50.354] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:15:0)
[22:06:50.420] Build status: STARTED
[22:06:50.420] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:16:0) after 5m29s
[22:12:19.498] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:16:0)
[22:12:19.516] Build status: STARTED
[22:12:19.516] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:17:0) after 3m32s
[22:15:51.729] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:17:0)
[22:15:51.781] Build status: STARTED
[22:15:51.781] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:18:0) after 5m46s
[22:21:37.806] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:18:0)
[22:21:37.836] Build status: STARTED
[22:21:37.836] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:19:0) after 3m42s
[22:25:19.850] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:19:0)
[22:25:19.867] Build status: STARTED
[22:25:19.867] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:20:0) after 8m34s
[22:33:53.934] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:20:0)
[22:33:54.021] Build status: STARTED
[22:33:54.021] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:21:0) after 1m28s
[22:35:22.042] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:21:0)
[22:35:22.064] Build status: STARTED
[22:35:22.064] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:22:0) after 1m30s
[22:36:52.052] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:22:0)
[22:36:52.074] Build status: STARTED
[22:36:52.074] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:23:0) after 7m25s
[22:44:17.141] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:23:0)
[22:44:17.156] Build status: STARTED
[22:44:17.156] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:24:0) after 5m59s
[22:50:16.271] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:24:0)
[22:50:16.292] Build status: STARTED
[22:50:16.292] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:25:0) after 2m24s
[22:52:40.312] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:25:0)
[22:52:40.338] Build status: STARTED
[22:52:40.339] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:26:0) after 4m11s
[22:56:51.360] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:26:0)
[22:56:51.383] Build status: STARTED
[22:56:51.383] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8879839906010376368:27:0) after 1m17s
[22:58:04.448] Received PubSub notification, asking Buildbucket for the build status
[22:58:04.491] Build:
{
"id": "8714409843976699761",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 490,
"createdBy": "project:chromium-m136",
"createTime": "2025-05-19T20:56:11.369799753Z",
"startTime": "2025-05-19T20:57:27.248696782Z",
"endTime": "2025-05-19T22:58:03.993024036Z",
"updateTime": "2025-05-19T22:58:03.993024036Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "91e50e6d9c7518cbd831bbf8f2d5aff43e1248c4",
"ref": "refs/branch-heads/7103"
}
}
}
[22:58:04.491] Invocation finished in 2h1m54.444931117s with status SUCCEEDED