[11:23:42.745] New invocation is queued and will start shortly
[11:23:43.873] Starting the invocation (attempt 1)
[11:23:43.896] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/34975ef81563b79fe1a33928d488c8954cb8523e"
[11:23:43.896] Popped gitiles commit info from properties and tags
[11:23:43.896] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:23:43.897] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:23:43.897] Buildbucket request:
{
"requestId": "8853512204548581968",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8853512204548581968",
"job": "chromium-m145/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "45afe09ef4ad1be83ef556438d47c09994db500b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@45afe09ef4ad1be83ef556438d47c09994db500b",
"title": "45afe09ef4ad1be83ef556438d47c09994db500b",
"url": "https://chromium.googlesource.com/chromium/src/+/45afe09ef4ad1be83ef556438d47c09994db500b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "91307ef64d0e89604ac988637553e45357bc3a6f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@91307ef64d0e89604ac988637553e45357bc3a6f",
"title": "91307ef64d0e89604ac988637553e45357bc3a6f",
"url": "https://chromium.googlesource.com/chromium/src/+/91307ef64d0e89604ac988637553e45357bc3a6f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "68085eb71c97dc0452b7a654d0eb860fbd496af2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@68085eb71c97dc0452b7a654d0eb860fbd496af2",
"title": "68085eb71c97dc0452b7a654d0eb860fbd496af2",
"url": "https://chromium.googlesource.com/chromium/src/+/68085eb71c97dc0452b7a654d0eb860fbd496af2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "eb186f2e5a9a83c9c98dc0ad1acf6e5581325534"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@eb186f2e5a9a83c9c98dc0ad1acf6e5581325534",
"title": "eb186f2e5a9a83c9c98dc0ad1acf6e5581325534",
"url": "https://chromium.googlesource.com/chromium/src/+/eb186f2e5a9a83c9c98dc0ad1acf6e5581325534"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f48bf0522af7728093f0244e991f38b4d9973b7b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@f48bf0522af7728093f0244e991f38b4d9973b7b",
"title": "f48bf0522af7728093f0244e991f38b4d9973b7b",
"url": "https://chromium.googlesource.com/chromium/src/+/f48bf0522af7728093f0244e991f38b4d9973b7b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fa6afa250f06c1bb1ced4ff257b1910eba260b35"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@fa6afa250f06c1bb1ced4ff257b1910eba260b35",
"title": "fa6afa250f06c1bb1ced4ff257b1910eba260b35",
"url": "https://chromium.googlesource.com/chromium/src/+/fa6afa250f06c1bb1ced4ff257b1910eba260b35"
},
{
"gitiles": {
"ref": "refs/branch-heads/7632",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34975ef81563b79fe1a33928d488c8954cb8523e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7632@34975ef81563b79fe1a33928d488c8954cb8523e",
"title": "34975ef81563b79fe1a33928d488c8954cb8523e",
"url": "https://chromium.googlesource.com/chromium/src/+/34975ef81563b79fe1a33928d488c8954cb8523e"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34975ef81563b79fe1a33928d488c8954cb8523e",
"ref": "refs/branch-heads/7632"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8853512204548581968"
},
{
"key": "scheduler_job_id",
"value": "chromium-m145/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:23:44.570] Scheduled build:
{
"id": "8688082142619770273",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 297,
"createdBy": "project:chromium-m145",
"createTime": "2026-03-06T11:23:43.963483649Z",
"updateTime": "2026-03-06T11:23:43.963483649Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34975ef81563b79fe1a33928d488c8954cb8523e",
"ref": "refs/branch-heads/7632"
}
}
}
[11:23:44.570] Task URL: https://cr-buildbucket.appspot.com/build/8688082142619770273
[11:23:44.570] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:2:0) after 9m34s
[11:23:45.226] Received PubSub notification, asking Buildbucket for the build status
[11:23:45.249] Build status: SCHEDULED
[11:25:16.439] Received PubSub notification, asking Buildbucket for the build status
[11:25:16.460] Build status: STARTED
[11:33:18.639] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:2:0)
[11:33:18.681] Build status: STARTED
[11:33:18.681] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:5:0) after 2m25s
[11:35:43.666] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:5:0)
[11:35:43.688] Build status: STARTED
[11:35:43.688] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:6:0) after 1m16s
[11:36:59.731] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:6:0)
[11:36:59.753] Build status: STARTED
[11:36:59.753] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:7:0) after 8m37s
[11:45:36.771] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:7:0)
[11:45:36.796] Build status: STARTED
[11:45:36.796] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:8:0) after 3m48s
[11:49:24.770] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:8:0)
[11:49:24.786] Build status: STARTED
[11:49:24.786] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:9:0) after 3m40s
[11:53:04.852] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:9:0)
[11:53:04.944] Build status: STARTED
[11:53:04.944] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:10:0) after 1m6s
[11:54:11.118] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:10:0)
[11:54:11.136] Build status: STARTED
[11:54:11.136] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:11:0) after 5m32s
[11:59:43.197] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:11:0)
[11:59:43.213] Build status: STARTED
[11:59:43.213] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:12:0) after 1m37s
[12:01:20.255] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:12:0)
[12:01:20.292] Build status: STARTED
[12:01:20.292] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:13:0) after 2m36s
[12:03:56.405] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:13:0)
[12:03:56.506] Build status: STARTED
[12:03:56.506] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:14:0) after 6m33s
[12:10:29.578] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:14:0)
[12:10:29.600] Build status: STARTED
[12:10:29.600] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:15:0) after 7m1s
[12:17:30.584] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:15:0)
[12:17:30.675] Build status: STARTED
[12:17:30.675] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:16:0) after 8m36s
[12:26:06.729] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:16:0)
[12:26:06.766] Build status: STARTED
[12:26:06.766] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:17:0) after 5m16s
[12:31:22.832] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:17:0)
[12:31:22.849] Build status: STARTED
[12:31:22.849] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:18:0) after 5m42s
[12:37:04.844] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:18:0)
[12:37:04.866] Build status: STARTED
[12:37:04.866] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:19:0) after 1m43s
[12:38:47.925] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:19:0)
[12:38:47.942] Build status: STARTED
[12:38:47.942] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:20:0) after 1m49s
[12:40:37.003] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:20:0)
[12:40:37.027] Build status: STARTED
[12:40:37.027] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:21:0) after 3m26s
[12:44:03.044] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:21:0)
[12:44:03.083] Build status: STARTED
[12:44:03.083] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:22:0) after 9m14s
[12:53:17.101] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:22:0)
[12:53:17.126] Build status: STARTED
[12:53:17.126] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:23:0) after 1m8s
[12:54:25.095] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:23:0)
[12:54:25.112] Build status: STARTED
[12:54:25.112] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:24:0) after 1m3s
[12:55:28.227] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:24:0)
[12:55:28.267] Build status: STARTED
[12:55:28.267] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:25:0) after 1m5s
[12:56:33.308] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:25:0)
[12:56:33.324] Build status: STARTED
[12:56:33.324] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:26:0) after 9m10s
[13:05:43.370] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:26:0)
[13:05:43.394] Build status: STARTED
[13:05:43.394] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:27:0) after 5m9s
[13:10:52.409] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:27:0)
[13:10:52.485] Build status: STARTED
[13:10:52.486] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:28:0) after 9m25s
[13:20:17.503] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:28:0)
[13:20:17.566] Build status: STARTED
[13:20:17.566] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:29:0) after 6m30s
[13:26:47.607] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:29:0)
[13:26:47.624] Build status: STARTED
[13:26:47.624] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:30:0) after 1m30s
[13:28:17.628] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:30:0)
[13:28:17.644] Build status: STARTED
[13:28:17.644] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:31:0) after 4m49s
[13:33:06.615] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:31:0)
[13:33:06.648] Build status: STARTED
[13:33:06.648] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:32:0) after 6m43s
[13:39:49.700] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:32:0)
[13:39:49.722] Build status: STARTED
[13:39:49.722] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:33:0) after 4m50s
[13:44:39.836] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:33:0)
[13:44:39.855] Build status: STARTED
[13:44:39.855] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:34:0) after 1m19s
[13:45:58.961] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:34:0)
[13:45:59.008] Build status: STARTED
[13:45:59.008] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:35:0) after 2m27s
[13:48:26.063] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:35:0)
[13:48:26.088] Build status: STARTED
[13:48:26.088] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:36:0) after 3m41s
[13:52:07.201] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:36:0)
[13:52:07.222] Build status: STARTED
[13:52:07.222] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:37:0) after 2m54s
[13:55:01.235] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:37:0)
[13:55:01.253] Build status: STARTED
[13:55:01.253] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:38:0) after 8m58s
[14:03:59.358] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:38:0)
[14:03:59.380] Build status: STARTED
[14:03:59.380] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:39:0) after 1m59s
[14:05:58.397] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:39:0)
[14:05:58.419] Build status: STARTED
[14:05:58.419] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:40:0) after 5m56s
[14:11:54.438] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:40:0)
[14:11:54.475] Build status: STARTED
[14:11:54.475] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:41:0) after 6m7s
[14:18:01.490] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:41:0)
[14:18:01.511] Build status: STARTED
[14:18:01.511] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:42:0) after 3m3s
[14:21:04.528] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:42:0)
[14:21:04.574] Build status: STARTED
[14:21:04.574] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:43:0) after 9m53s
[14:30:57.629] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:43:0)
[14:30:57.644] Build status: STARTED
[14:30:57.644] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:44:0) after 1m42s
[14:32:39.661] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:44:0)
[14:32:39.739] Build status: STARTED
[14:32:39.739] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:45:0) after 7m15s
[14:39:54.756] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:45:0)
[14:39:54.773] Build status: STARTED
[14:39:54.773] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:46:0) after 8m31s
[14:48:25.880] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:46:0)
[14:48:25.925] Build status: STARTED
[14:48:25.927] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:47:0) after 2m22s
[14:50:47.943] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:47:0)
[14:50:47.963] Build status: STARTED
[14:50:47.963] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:48:0) after 1m59s
[14:52:46.973] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:48:0)
[14:52:46.987] Build status: STARTED
[14:52:46.987] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:49:0) after 6m21s
[14:59:08.117] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:49:0)
[14:59:08.138] Build status: STARTED
[14:59:08.138] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:50:0) after 4m50s
[15:03:58.118] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:50:0)
[15:03:58.159] Build status: STARTED
[15:03:58.159] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:51:0) after 1m40s
[15:05:38.180] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:51:0)
[15:05:38.221] Build status: STARTED
[15:05:38.222] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:52:0) after 4m35s
[15:10:13.250] Handling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:52:0)
[15:10:13.272] Build status: STARTED
[15:10:13.273] Scheduling timer "check-buildbucket-build-status" (chromium-m145/win32-official:8853512204548581968:53:0) after 9m32s
[15:11:42.751] Received PubSub notification, asking Buildbucket for the build status
[15:11:42.769] Build:
{
"id": "8688082142619770273",
"builder": {
"project": "chromium-m145",
"bucket": "ci",
"builder": "win32-official"
},
"number": 297,
"createdBy": "project:chromium-m145",
"createTime": "2026-03-06T11:23:43.963483649Z",
"startTime": "2026-03-06T11:25:14.232429125Z",
"endTime": "2026-03-06T15:11:40.246119164Z",
"updateTime": "2026-03-06T15:11:40.246119164Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "34975ef81563b79fe1a33928d488c8954cb8523e",
"ref": "refs/branch-heads/7632"
}
}
}
[15:11:42.769] Invocation finished in 3h48m0.028996638s with status SUCCEEDED