[17:39:40.916] New invocation is queued and will start shortly
[17:39:42.853] Starting the invocation (attempt 1)
[17:39:42.880] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e7f411ca93789845d878355ebdf9a78e5833d67e"
[17:39:42.880] Popped gitiles commit info from properties and tags
[17:39:42.880] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:39:42.880] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:39:42.880] Buildbucket request:
{
"requestId": "8889908531091763696",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889908531091763696",
"job": "chromium-m132/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6dad90d4017b8ac27f47fe86c8dff857f594a038"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@6dad90d4017b8ac27f47fe86c8dff857f594a038",
"title": "6dad90d4017b8ac27f47fe86c8dff857f594a038",
"url": "https://chromium.googlesource.com/chromium/src/+/6dad90d4017b8ac27f47fe86c8dff857f594a038"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b0a08fd2c82a3eb7dfada45c437d8a762ca3d635"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@b0a08fd2c82a3eb7dfada45c437d8a762ca3d635",
"title": "b0a08fd2c82a3eb7dfada45c437d8a762ca3d635",
"url": "https://chromium.googlesource.com/chromium/src/+/b0a08fd2c82a3eb7dfada45c437d8a762ca3d635"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1dbbca6e8f9ea73a3369057593056323c369944f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@1dbbca6e8f9ea73a3369057593056323c369944f",
"title": "1dbbca6e8f9ea73a3369057593056323c369944f",
"url": "https://chromium.googlesource.com/chromium/src/+/1dbbca6e8f9ea73a3369057593056323c369944f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8916f4a221541594107aa77113f38da343176335"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@8916f4a221541594107aa77113f38da343176335",
"title": "8916f4a221541594107aa77113f38da343176335",
"url": "https://chromium.googlesource.com/chromium/src/+/8916f4a221541594107aa77113f38da343176335"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2e55c9b199ac0ded9c19da05b6b18951ea2d6a92"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@2e55c9b199ac0ded9c19da05b6b18951ea2d6a92",
"title": "2e55c9b199ac0ded9c19da05b6b18951ea2d6a92",
"url": "https://chromium.googlesource.com/chromium/src/+/2e55c9b199ac0ded9c19da05b6b18951ea2d6a92"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "92134c9af62b9a4e256ba2a56b52d4884a3fd45d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@92134c9af62b9a4e256ba2a56b52d4884a3fd45d",
"title": "92134c9af62b9a4e256ba2a56b52d4884a3fd45d",
"url": "https://chromium.googlesource.com/chromium/src/+/92134c9af62b9a4e256ba2a56b52d4884a3fd45d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ff61fe188877b874b603811e28e0ebbef54b5b3a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@ff61fe188877b874b603811e28e0ebbef54b5b3a",
"title": "ff61fe188877b874b603811e28e0ebbef54b5b3a",
"url": "https://chromium.googlesource.com/chromium/src/+/ff61fe188877b874b603811e28e0ebbef54b5b3a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0a0e4e1b59dfb95b95cceb4896ccb5f6b717fbdf"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@0a0e4e1b59dfb95b95cceb4896ccb5f6b717fbdf",
"title": "0a0e4e1b59dfb95b95cceb4896ccb5f6b717fbdf",
"url": "https://chromium.googlesource.com/chromium/src/+/0a0e4e1b59dfb95b95cceb4896ccb5f6b717fbdf"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fad90600255bf994ed54e9c017d6fbc8f2feca55"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@fad90600255bf994ed54e9c017d6fbc8f2feca55",
"title": "fad90600255bf994ed54e9c017d6fbc8f2feca55",
"url": "https://chromium.googlesource.com/chromium/src/+/fad90600255bf994ed54e9c017d6fbc8f2feca55"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e7f411ca93789845d878355ebdf9a78e5833d67e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@e7f411ca93789845d878355ebdf9a78e5833d67e",
"title": "e7f411ca93789845d878355ebdf9a78e5833d67e",
"url": "https://chromium.googlesource.com/chromium/src/+/e7f411ca93789845d878355ebdf9a78e5833d67e"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e7f411ca93789845d878355ebdf9a78e5833d67e",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889908531091763696"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:39:43.676] Scheduled build:
{
"id": "8724478468162300241",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win32-official"
},
"number": 416,
"createdBy": "project:chromium-m132",
"createTime": "2025-01-28T17:39:43.082960491Z",
"updateTime": "2025-01-28T17:39:43.082960491Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e7f411ca93789845d878355ebdf9a78e5833d67e",
"ref": "refs/branch-heads/6834"
}
}
}
[17:39:43.676] Task URL: https://cr-buildbucket.appspot.com/build/8724478468162300241
[17:39:43.676] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:2:0) after 1m59s
[17:39:56.465] Received PubSub notification, asking Buildbucket for the build status
[17:39:56.515] Build status: SCHEDULED
[17:41:18.577] Received PubSub notification, asking Buildbucket for the build status
[17:41:18.608] Build status: STARTED
[17:41:42.807] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:2:0)
[17:41:42.875] Build status: STARTED
[17:41:42.875] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:5:0) after 2m45s
[17:44:27.872] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:5:0)
[17:44:27.896] Build status: STARTED
[17:44:27.896] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:6:0) after 4m41s
[17:49:09.365] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:6:0)
[17:49:09.395] Build status: STARTED
[17:49:09.395] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:7:0) after 3m31s
[17:52:40.661] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:7:0)
[17:52:40.768] Build status: STARTED
[17:52:40.768] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:8:0) after 1m21s
[17:54:01.783] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:8:0)
[17:54:01.811] Build status: STARTED
[17:54:01.811] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:9:0) after 9m16s
[18:03:17.831] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:9:0)
[18:03:17.929] Build status: STARTED
[18:03:17.929] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:10:0) after 9m51s
[18:13:08.950] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:10:0)
[18:13:08.967] Build status: STARTED
[18:13:08.967] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:11:0) after 9m16s
[18:22:25.016] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:11:0)
[18:22:25.032] Build status: STARTED
[18:22:25.032] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:12:0) after 7m7s
[18:29:32.119] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:12:0)
[18:29:32.140] Build status: STARTED
[18:29:32.140] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:13:0) after 4m57s
[18:34:29.175] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:13:0)
[18:34:29.191] Build status: STARTED
[18:34:29.191] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:14:0) after 1m42s
[18:36:11.193] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:14:0)
[18:36:11.211] Build status: STARTED
[18:36:11.211] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:15:0) after 8m47s
[18:44:58.347] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:15:0)
[18:44:58.375] Build status: STARTED
[18:44:58.375] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:16:0) after 7m39s
[18:52:37.397] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:16:0)
[18:52:37.416] Build status: STARTED
[18:52:37.416] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:17:0) after 7m16s
[18:59:53.491] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:17:0)
[18:59:53.514] Build status: STARTED
[18:59:53.514] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:18:0) after 2m53s
[19:02:46.603] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:18:0)
[19:02:46.630] Build status: STARTED
[19:02:46.630] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:19:0) after 4m8s
[19:06:54.778] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:19:0)
[19:06:54.797] Build status: STARTED
[19:06:54.797] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:20:0) after 1m39s
[19:08:33.825] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:20:0)
[19:08:33.842] Build status: STARTED
[19:08:33.842] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:21:0) after 7m3s
[19:15:36.854] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:21:0)
[19:15:36.873] Build status: STARTED
[19:15:36.873] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:22:0) after 5m45s
[19:21:21.984] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:22:0)
[19:21:22.004] Build status: STARTED
[19:21:22.004] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:23:0) after 4m17s
[19:25:39.072] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:23:0)
[19:25:39.101] Build status: STARTED
[19:25:39.101] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:24:0) after 9m24s
[19:35:03.166] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:24:0)
[19:35:03.187] Build status: STARTED
[19:35:03.187] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:25:0) after 4m8s
[19:39:11.212] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:25:0)
[19:39:11.235] Build status: STARTED
[19:39:11.235] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:26:0) after 6m11s
[19:45:22.310] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:26:0)
[19:45:22.328] Build status: STARTED
[19:45:22.329] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:27:0) after 8m1s
[19:53:23.414] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:27:0)
[19:53:23.493] Build status: STARTED
[19:53:23.493] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:28:0) after 3m41s
[19:57:04.499] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:28:0)
[19:57:04.550] Build status: STARTED
[19:57:04.550] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:29:0) after 9m56s
[20:07:00.554] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:29:0)
[20:07:00.575] Build status: STARTED
[20:07:00.575] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:30:0) after 9m34s
[20:16:34.650] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:30:0)
[20:16:34.678] Build status: STARTED
[20:16:34.678] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:31:0) after 8m52s
[20:25:26.728] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:31:0)
[20:25:26.745] Build status: STARTED
[20:25:26.745] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:32:0) after 1m22s
[20:26:48.777] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:32:0)
[20:26:48.801] Build status: STARTED
[20:26:48.801] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:33:0) after 6m41s
[20:33:29.822] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:33:0)
[20:33:29.841] Build status: STARTED
[20:33:29.841] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:34:0) after 6m6s
[20:39:35.859] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:34:0)
[20:39:35.893] Build status: STARTED
[20:39:35.893] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:35:0) after 6m18s
[20:45:53.907] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:35:0)
[20:45:53.925] Build status: STARTED
[20:45:53.926] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:36:0) after 9m56s
[20:55:49.962] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:36:0)
[20:55:49.985] Build status: STARTED
[20:55:49.985] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8889908531091763696:37:0) after 6m27s
[20:57:22.122] Received PubSub notification, asking Buildbucket for the build status
[20:57:22.148] Build:
{
"id": "8724478468162300241",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win32-official"
},
"number": 416,
"createdBy": "project:chromium-m132",
"createTime": "2025-01-28T17:39:43.082960491Z",
"startTime": "2025-01-28T17:41:03.175005010Z",
"endTime": "2025-01-28T20:57:21.350355603Z",
"updateTime": "2025-01-28T20:57:21.350355603Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e7f411ca93789845d878355ebdf9a78e5833d67e",
"ref": "refs/branch-heads/6834"
}
}
}
[20:57:22.148] Invocation finished in 3h17m41.243279885s with status SUCCEEDED