[14:28:04.908] New invocation is queued and will start shortly
[14:28:06.169] Starting the invocation (attempt 1)
[14:28:06.200] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9c44f6fd16691dbf336c20e600998a33dfd78bc3"
[14:28:06.200] Popped gitiles commit info from properties and tags
[14:28:06.200] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:28:06.200] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:28:06.200] Buildbucket request:
{
"requestId": "8889739391593533232",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889739391593533232",
"job": "chromium-m133/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "255d83f806353c18f8a505f67efe89f480b7d4c1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@255d83f806353c18f8a505f67efe89f480b7d4c1",
"title": "255d83f806353c18f8a505f67efe89f480b7d4c1",
"url": "https://chromium.googlesource.com/chromium/src/+/255d83f806353c18f8a505f67efe89f480b7d4c1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3b94ea4b8e929171486ba76e6051c8f09155aeda"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@3b94ea4b8e929171486ba76e6051c8f09155aeda",
"title": "3b94ea4b8e929171486ba76e6051c8f09155aeda",
"url": "https://chromium.googlesource.com/chromium/src/+/3b94ea4b8e929171486ba76e6051c8f09155aeda"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "536a22eba9b147647248eb240d2bb01478b0fc68"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@536a22eba9b147647248eb240d2bb01478b0fc68",
"title": "536a22eba9b147647248eb240d2bb01478b0fc68",
"url": "https://chromium.googlesource.com/chromium/src/+/536a22eba9b147647248eb240d2bb01478b0fc68"
},
{
"gitiles": {
"ref": "refs/branch-heads/6943",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9c44f6fd16691dbf336c20e600998a33dfd78bc3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6943@9c44f6fd16691dbf336c20e600998a33dfd78bc3",
"title": "9c44f6fd16691dbf336c20e600998a33dfd78bc3",
"url": "https://chromium.googlesource.com/chromium/src/+/9c44f6fd16691dbf336c20e600998a33dfd78bc3"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9c44f6fd16691dbf336c20e600998a33dfd78bc3",
"ref": "refs/branch-heads/6943"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889739391593533232"
},
{
"key": "scheduler_job_id",
"value": "chromium-m133/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:28:06.674] Scheduled build:
{
"id": "8724309329522070753",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 261,
"createdBy": "project:chromium-m133",
"createTime": "2025-01-30T14:28:06.259476285Z",
"updateTime": "2025-01-30T14:28:06.259476285Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9c44f6fd16691dbf336c20e600998a33dfd78bc3",
"ref": "refs/branch-heads/6943"
}
}
}
[14:28:06.674] Task URL: https://cr-buildbucket.appspot.com/build/8724309329522070753
[14:28:06.674] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:2:0) after 5m28s
[14:28:07.630] Received PubSub notification, asking Buildbucket for the build status
[14:28:07.656] Build status: SCHEDULED
[14:28:14.558] Received PubSub notification, asking Buildbucket for the build status
[14:28:14.612] Build status: STARTED
[14:33:34.779] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:2:0)
[14:33:34.804] Build status: STARTED
[14:33:34.805] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:5:0) after 4m58s
[14:38:32.827] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:5:0)
[14:38:32.866] Build status: STARTED
[14:38:32.866] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:6:0) after 1m11s
[14:39:43.883] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:6:0)
[14:39:43.908] Build status: STARTED
[14:39:43.908] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:7:0) after 8m27s
[14:48:10.924] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:7:0)
[14:48:10.944] Build status: STARTED
[14:48:10.944] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:8:0) after 8m53s
[14:57:04.015] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:8:0)
[14:57:04.043] Build status: STARTED
[14:57:04.043] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:9:0) after 2m33s
[14:59:37.045] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:9:0)
[14:59:37.073] Build status: STARTED
[14:59:37.073] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:10:0) after 6m6s
[15:05:43.091] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:10:0)
[15:05:43.116] Build status: STARTED
[15:05:43.116] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:11:0) after 1m33s
[15:07:16.178] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:11:0)
[15:07:16.198] Build status: STARTED
[15:07:16.198] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:12:0) after 6m42s
[15:13:58.640] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:12:0)
[15:13:58.723] Build status: STARTED
[15:13:58.723] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:13:0) after 7m28s
[15:21:26.910] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:13:0)
[15:21:26.938] Build status: STARTED
[15:21:26.938] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:14:0) after 5m49s
[15:27:15.957] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:14:0)
[15:27:15.993] Build status: STARTED
[15:27:15.993] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:15:0) after 9m28s
[15:36:43.981] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:15:0)
[15:36:44.005] Build status: STARTED
[15:36:44.005] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:16:0) after 4m0s
[15:40:44.024] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:16:0)
[15:40:44.054] Build status: STARTED
[15:40:44.054] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:17:0) after 5m29s
[15:46:13.131] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:17:0)
[15:46:13.166] Build status: STARTED
[15:46:13.167] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:18:0) after 1m57s
[15:48:10.193] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:18:0)
[15:48:10.236] Build status: STARTED
[15:48:10.236] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:19:0) after 2m21s
[15:50:31.218] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:19:0)
[15:50:31.252] Build status: STARTED
[15:50:31.253] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:20:0) after 4m34s
[15:55:05.307] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:20:0)
[15:55:05.323] Build status: STARTED
[15:55:05.324] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:21:0) after 8m22s
[16:03:27.417] Handling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:21:0)
[16:03:27.434] Build status: STARTED
[16:03:27.434] Scheduling timer "check-buildbucket-build-status" (chromium-m133/win-arm64-rel:8889739391593533232:22:0) after 6m50s
[16:06:28.440] Received PubSub notification, asking Buildbucket for the build status
[16:06:28.474] Build:
{
"id": "8724309329522070753",
"builder": {
"project": "chromium-m133",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 261,
"createdBy": "project:chromium-m133",
"createTime": "2025-01-30T14:28:06.259476285Z",
"startTime": "2025-01-30T14:28:14.184685422Z",
"endTime": "2025-01-30T16:06:28.045095617Z",
"updateTime": "2025-01-30T16:06:28.045095617Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9c44f6fd16691dbf336c20e600998a33dfd78bc3",
"ref": "refs/branch-heads/6943"
}
}
}
[16:06:28.474] Invocation finished in 1h38m23.573746902s with status SUCCEEDED