[09:41:20.739] New invocation is queued and will start shortly
[09:41:21.850] Starting the invocation (attempt 1)
[09:41:21.877] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c7941c39312e2ae86d0cf070cf362775a90baf49"
[09:41:21.877] Popped gitiles commit info from properties and tags
[09:41:21.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:41:21.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:41:21.877] Buildbucket request:
{
"requestId": "8883687434719143872",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8883687434719143872",
"job": "chromium-m136/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "77bbe51ffdc4a52972cbac8a0a56992a7b419e08"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@77bbe51ffdc4a52972cbac8a0a56992a7b419e08",
"title": "77bbe51ffdc4a52972cbac8a0a56992a7b419e08",
"url": "https://chromium.googlesource.com/chromium/src/+/77bbe51ffdc4a52972cbac8a0a56992a7b419e08"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ea29a59191c08c57a3867a8aabf437abc259f0ac"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@ea29a59191c08c57a3867a8aabf437abc259f0ac",
"title": "ea29a59191c08c57a3867a8aabf437abc259f0ac",
"url": "https://chromium.googlesource.com/chromium/src/+/ea29a59191c08c57a3867a8aabf437abc259f0ac"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2bb115fc4b2e65cad00e06c1d494fd9b0b48ec85"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@2bb115fc4b2e65cad00e06c1d494fd9b0b48ec85",
"title": "2bb115fc4b2e65cad00e06c1d494fd9b0b48ec85",
"url": "https://chromium.googlesource.com/chromium/src/+/2bb115fc4b2e65cad00e06c1d494fd9b0b48ec85"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dd04788b9d88495c7c037d851bf37bd6bda94a5d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@dd04788b9d88495c7c037d851bf37bd6bda94a5d",
"title": "dd04788b9d88495c7c037d851bf37bd6bda94a5d",
"url": "https://chromium.googlesource.com/chromium/src/+/dd04788b9d88495c7c037d851bf37bd6bda94a5d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "42c4089c9a5a344d1a247d317e649406e7fffcb4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@42c4089c9a5a344d1a247d317e649406e7fffcb4",
"title": "42c4089c9a5a344d1a247d317e649406e7fffcb4",
"url": "https://chromium.googlesource.com/chromium/src/+/42c4089c9a5a344d1a247d317e649406e7fffcb4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "18e9d19d91fd1573cd7b4bffb86cfca4ac929978"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@18e9d19d91fd1573cd7b4bffb86cfca4ac929978",
"title": "18e9d19d91fd1573cd7b4bffb86cfca4ac929978",
"url": "https://chromium.googlesource.com/chromium/src/+/18e9d19d91fd1573cd7b4bffb86cfca4ac929978"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c6bd863688c6b6c14098556fd8e06ea6c70d85dd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@c6bd863688c6b6c14098556fd8e06ea6c70d85dd",
"title": "c6bd863688c6b6c14098556fd8e06ea6c70d85dd",
"url": "https://chromium.googlesource.com/chromium/src/+/c6bd863688c6b6c14098556fd8e06ea6c70d85dd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "41a77d186823f0c6d5697864ddb7e025eaf0dc5e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@41a77d186823f0c6d5697864ddb7e025eaf0dc5e",
"title": "41a77d186823f0c6d5697864ddb7e025eaf0dc5e",
"url": "https://chromium.googlesource.com/chromium/src/+/41a77d186823f0c6d5697864ddb7e025eaf0dc5e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c7941c39312e2ae86d0cf070cf362775a90baf49"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@c7941c39312e2ae86d0cf070cf362775a90baf49",
"title": "c7941c39312e2ae86d0cf070cf362775a90baf49",
"url": "https://chromium.googlesource.com/chromium/src/+/c7941c39312e2ae86d0cf070cf362775a90baf49"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c7941c39312e2ae86d0cf070cf362775a90baf49",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8883687434719143872"
},
{
"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"
}
}
[09:41:22.372] Scheduled build:
{
"id": "8718257372789136289",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 68,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-07T09:41:21.959547701Z",
"updateTime": "2025-04-07T09:41:21.959547701Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c7941c39312e2ae86d0cf070cf362775a90baf49",
"ref": "refs/branch-heads/7103"
}
},
"canary": true
}
[09:41:22.372] Task URL: https://cr-buildbucket.appspot.com/build/8718257372789136289
[09:41:22.372] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:2:0) after 8m50s
[09:41:28.239] Received PubSub notification, asking Buildbucket for the build status
[09:41:28.265] Build status: SCHEDULED
[09:41:41.025] Received PubSub notification, asking Buildbucket for the build status
[09:41:41.049] Build status: STARTED
[09:50:12.508] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:2:0)
[09:50:12.541] Build status: STARTED
[09:50:12.541] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:5:0) after 7m25s
[09:57:37.961] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:5:0)
[09:57:37.989] Build status: STARTED
[09:57:37.989] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:6:0) after 2m13s
[09:59:51.051] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:6:0)
[09:59:51.085] Build status: STARTED
[09:59:51.085] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:7:0) after 6m34s
[10:06:26.184] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:7:0)
[10:06:26.260] Build status: STARTED
[10:06:26.260] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:8:0) after 2m1s
[10:08:27.276] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:8:0)
[10:08:27.298] Build status: STARTED
[10:08:27.298] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:9:0) after 7m20s
[10:15:47.458] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:9:0)
[10:15:47.474] Build status: STARTED
[10:15:47.474] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:10:0) after 5m59s
[10:21:46.467] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:10:0)
[10:21:46.585] Build status: STARTED
[10:21:46.585] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:11:0) after 2m28s
[10:24:14.756] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:11:0)
[10:24:14.822] Build status: STARTED
[10:24:14.822] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:12:0) after 4m10s
[10:28:24.845] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:12:0)
[10:28:24.879] Build status: STARTED
[10:28:24.879] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:13:0) after 1m48s
[10:30:12.902] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:13:0)
[10:30:12.919] Build status: STARTED
[10:30:12.919] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:14:0) after 8m4s
[10:38:17.054] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:14:0)
[10:38:17.074] Build status: STARTED
[10:38:17.074] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:15:0) after 3m59s
[10:42:16.246] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:15:0)
[10:42:16.263] Build status: STARTED
[10:42:16.263] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:16:0) after 2m29s
[10:44:45.360] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:16:0)
[10:44:45.437] Build status: STARTED
[10:44:45.437] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:17:0) after 6m23s
[10:51:08.444] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:17:0)
[10:51:08.487] Build status: STARTED
[10:51:08.487] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:18:0) after 4m25s
[10:55:33.507] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:18:0)
[10:55:33.523] Build status: STARTED
[10:55:33.523] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:19:0) after 3m30s
[10:59:03.565] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:19:0)
[10:59:03.593] Build status: STARTED
[10:59:03.593] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:20:0) after 8m23s
[11:07:26.612] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:20:0)
[11:07:26.633] Build status: STARTED
[11:07:26.633] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:21:0) after 1m2s
[11:08:28.836] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:21:0)
[11:08:28.854] Build status: STARTED
[11:08:28.854] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:22:0) after 3m5s
[11:11:33.867] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:22:0)
[11:11:33.891] Build status: STARTED
[11:11:33.891] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:23:0) after 3m14s
[11:14:48.064] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:23:0)
[11:14:48.081] Build status: STARTED
[11:14:48.081] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:24:0) after 9m49s
[11:24:37.517] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:24:0)
[11:24:37.588] Build status: STARTED
[11:24:37.588] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:25:0) after 2m7s
[11:26:44.612] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:25:0)
[11:26:44.633] Build status: STARTED
[11:26:44.633] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:26:0) after 2m50s
[11:29:34.686] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:26:0)
[11:29:34.708] Build status: STARTED
[11:29:34.708] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:27:0) after 9m16s
[11:38:50.734] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:27:0)
[11:38:50.758] Build status: STARTED
[11:38:50.758] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:28:0) after 4m20s
[11:43:10.783] Handling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:28:0)
[11:43:10.798] Build status: STARTED
[11:43:10.798] Scheduling timer "check-buildbucket-build-status" (chromium-m136/win-arm64-rel:8883687434719143872:29:0) after 6m19s
[11:46:50.847] Received PubSub notification, asking Buildbucket for the build status
[11:46:50.862] Build:
{
"id": "8718257372789136289",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 68,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-07T09:41:21.959547701Z",
"startTime": "2025-04-07T09:41:31.709353293Z",
"endTime": "2025-04-07T11:46:50.560219258Z",
"updateTime": "2025-04-07T11:46:50.560219258Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c7941c39312e2ae86d0cf070cf362775a90baf49",
"ref": "refs/branch-heads/7103"
}
},
"canary": true
}
[11:46:50.862] Invocation finished in 2h5m30.127219546s with status SUCCEEDED