[00:49:28.578] New invocation is queued and will start shortly
[00:49:29.764] Starting the invocation (attempt 1)
[00:49:29.794] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/35d55b36085a9884e6a23598bf4e7d7d19eb286f"
[00:49:29.794] Popped gitiles commit info from properties and tags
[00:49:29.794] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:49:29.794] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:49:29.794] Buildbucket request:
{
"requestId": "8876654333674556320",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8876654333674556320",
"job": "chromium-m137/win-arm64-rel",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "601062f4045da23177ed4517a8968f2e8b12af65"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@601062f4045da23177ed4517a8968f2e8b12af65",
"title": "601062f4045da23177ed4517a8968f2e8b12af65",
"url": "https://chromium.googlesource.com/chromium/src/+/601062f4045da23177ed4517a8968f2e8b12af65"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e733a60e2782e5e537744a7b8123bb157e46571c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@e733a60e2782e5e537744a7b8123bb157e46571c",
"title": "e733a60e2782e5e537744a7b8123bb157e46571c",
"url": "https://chromium.googlesource.com/chromium/src/+/e733a60e2782e5e537744a7b8123bb157e46571c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "22650b657e880ac32a30d12fdc4845f395cdc2ae"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@22650b657e880ac32a30d12fdc4845f395cdc2ae",
"title": "22650b657e880ac32a30d12fdc4845f395cdc2ae",
"url": "https://chromium.googlesource.com/chromium/src/+/22650b657e880ac32a30d12fdc4845f395cdc2ae"
},
{
"gitiles": {
"ref": "refs/branch-heads/7151",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "35d55b36085a9884e6a23598bf4e7d7d19eb286f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7151@35d55b36085a9884e6a23598bf4e7d7d19eb286f",
"title": "35d55b36085a9884e6a23598bf4e7d7d19eb286f",
"url": "https://chromium.googlesource.com/chromium/src/+/35d55b36085a9884e6a23598bf4e7d7d19eb286f"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "35d55b36085a9884e6a23598bf4e7d7d19eb286f",
"ref": "refs/branch-heads/7151"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8876654333674556320"
},
{
"key": "scheduler_job_id",
"value": "chromium-m137/win-arm64-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:49:30.352] Scheduled build:
{
"id": "8711224271681925889",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 583,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-24T00:49:29.850987826Z",
"updateTime": "2025-06-24T00:49:29.850987826Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "35d55b36085a9884e6a23598bf4e7d7d19eb286f",
"ref": "refs/branch-heads/7151"
}
}
}
[00:49:30.352] Task URL: https://cr-buildbucket.appspot.com/build/8711224271681925889
[00:49:30.352] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:2:0) after 1m29s
[00:49:30.724] Received PubSub notification, asking Buildbucket for the build status
[00:49:30.781] Build status: SCHEDULED
[00:50:59.394] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:2:0)
[00:50:59.418] Build status: SCHEDULED
[00:50:59.419] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:4:0) after 8m58s
[00:59:57.438] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:4:0)
[00:59:57.462] Build status: SCHEDULED
[00:59:57.462] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:5:0) after 9m7s
[01:06:28.358] Received PubSub notification, asking Buildbucket for the build status
[01:06:28.385] Build status: STARTED
[01:09:04.521] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:5:0)
[01:09:04.586] Build status: STARTED
[01:09:04.586] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:7:0) after 2m26s
[01:11:30.610] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:7:0)
[01:11:30.626] Build status: STARTED
[01:11:30.626] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:8:0) after 2m34s
[01:14:04.686] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:8:0)
[01:14:04.756] Build status: STARTED
[01:14:04.756] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:9:0) after 8m44s
[01:22:48.849] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:9:0)
[01:22:48.924] Build status: STARTED
[01:22:48.924] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:10:0) after 5m4s
[01:27:52.944] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:10:0)
[01:27:52.968] Build status: STARTED
[01:27:52.968] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:11:0) after 2m40s
[01:30:33.002] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:11:0)
[01:30:33.024] Build status: STARTED
[01:30:33.024] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:12:0) after 4m59s
[01:35:32.238] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:12:0)
[01:35:32.262] Build status: STARTED
[01:35:32.262] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:13:0) after 1m34s
[01:37:06.286] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:13:0)
[01:37:06.308] Build status: STARTED
[01:37:06.308] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:14:0) after 9m33s
[01:46:39.329] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:14:0)
[01:46:39.355] Build status: STARTED
[01:46:39.355] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:15:0) after 6m17s
[01:52:56.415] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:15:0)
[01:52:56.449] Build status: STARTED
[01:52:56.449] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:16:0) after 1m58s
[01:54:54.496] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:16:0)
[01:54:54.520] Build status: STARTED
[01:54:54.520] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:17:0) after 8m34s
[02:03:28.558] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:17:0)
[02:03:28.588] Build status: STARTED
[02:03:28.588] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:18:0) after 4m7s
[02:07:35.606] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:18:0)
[02:07:35.629] Build status: STARTED
[02:07:35.629] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:19:0) after 6m24s
[02:13:59.696] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:19:0)
[02:13:59.766] Build status: STARTED
[02:13:59.766] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:20:0) after 5m48s
[02:19:47.941] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:20:0)
[02:19:47.959] Build status: STARTED
[02:19:47.959] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:21:0) after 2m0s
[02:21:47.974] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:21:0)
[02:21:47.993] Build status: STARTED
[02:21:47.993] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:22:0) after 7m21s
[02:29:09.069] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:22:0)
[02:29:09.110] Build status: STARTED
[02:29:09.110] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:23:0) after 2m7s
[02:31:16.127] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:23:0)
[02:31:16.155] Build status: STARTED
[02:31:16.155] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:24:0) after 6m33s
[02:37:49.180] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:24:0)
[02:37:49.201] Build status: STARTED
[02:37:49.202] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:25:0) after 3m18s
[02:41:07.266] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:25:0)
[02:41:07.283] Build status: STARTED
[02:41:07.283] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:26:0) after 4m16s
[02:45:23.388] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:26:0)
[02:45:23.410] Build status: STARTED
[02:45:23.410] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:27:0) after 8m5s
[02:53:28.620] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:27:0)
[02:53:28.859] Build status: STARTED
[02:53:28.859] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:28:0) after 6m51s
[03:00:19.878] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:28:0)
[03:00:19.896] Build status: STARTED
[03:00:19.896] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:29:0) after 2m10s
[03:02:30.003] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:29:0)
[03:02:30.028] Build status: STARTED
[03:02:30.029] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:30:0) after 7m0s
[03:09:30.098] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:30:0)
[03:09:30.116] Build status: STARTED
[03:09:30.116] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:31:0) after 6m39s
[03:16:09.134] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:31:0)
[03:16:09.148] Build status: STARTED
[03:16:09.148] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:32:0) after 4m8s
[03:20:17.186] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:32:0)
[03:20:17.206] Build status: STARTED
[03:20:17.206] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:33:0) after 5m55s
[03:26:12.202] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:33:0)
[03:26:12.221] Build status: STARTED
[03:26:12.221] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:34:0) after 5m55s
[03:32:07.248] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:34:0)
[03:32:07.269] Build status: STARTED
[03:32:07.269] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:35:0) after 5m5s
[03:37:12.397] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:35:0)
[03:37:12.413] Build status: STARTED
[03:37:12.413] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:36:0) after 5m39s
[03:42:51.671] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:36:0)
[03:42:51.705] Build status: STARTED
[03:42:51.705] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:37:0) after 2m53s
[03:45:44.878] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:37:0)
[03:45:44.976] Build status: STARTED
[03:45:44.976] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:38:0) after 7m6s
[03:52:51.092] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:38:0)
[03:52:51.136] Build status: STARTED
[03:52:51.137] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:39:0) after 1m1s
[03:53:52.463] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:39:0)
[03:53:52.489] Build status: STARTED
[03:53:52.489] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:40:0) after 3m23s
[03:57:15.513] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:40:0)
[03:57:15.533] Build status: STARTED
[03:57:15.533] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:41:0) after 6m7s
[04:03:22.570] Handling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:41:0)
[04:03:22.590] Build status: STARTED
[04:03:22.590] Scheduling timer "check-buildbucket-build-status" (chromium-m137/win-arm64-rel:8876654333674556320:42:0) after 5m3s
[04:04:32.886] Received PubSub notification, asking Buildbucket for the build status
[04:04:32.905] Build:
{
"id": "8711224271681925889",
"builder": {
"project": "chromium-m137",
"bucket": "ci",
"builder": "win-arm64-rel"
},
"number": 583,
"createdBy": "project:chromium-m137",
"createTime": "2025-06-24T00:49:29.850987826Z",
"startTime": "2025-06-24T01:06:28.039819460Z",
"endTime": "2025-06-24T04:04:32.454092928Z",
"updateTime": "2025-06-24T04:04:32.454092928Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "35d55b36085a9884e6a23598bf4e7d7d19eb286f",
"ref": "refs/branch-heads/7151"
}
}
}
[04:04:32.905] Invocation finished in 3h15m4.338986451s with status SUCCEEDED