[11:25:05.065] New invocation is queued and will start shortly
[11:25:06.339] Starting the invocation (attempt 1)
[11:25:06.371] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8"
[11:25:06.371] Popped gitiles commit info from properties and tags
[11:25:06.371] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:25:06.371] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:25:06.372] Buildbucket request:
{
"requestId": "8898810601460728304",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898810601460728304",
"job": "chromium-m131/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b9a1807d7102913d23ffceb4546909c510b16e31"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@b9a1807d7102913d23ffceb4546909c510b16e31",
"title": "b9a1807d7102913d23ffceb4546909c510b16e31",
"url": "https://chromium.googlesource.com/chromium/src/+/b9a1807d7102913d23ffceb4546909c510b16e31"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "70d2d921d9ea0c80dfcd099d0c9cbfab7562d59e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@70d2d921d9ea0c80dfcd099d0c9cbfab7562d59e",
"title": "70d2d921d9ea0c80dfcd099d0c9cbfab7562d59e",
"url": "https://chromium.googlesource.com/chromium/src/+/70d2d921d9ea0c80dfcd099d0c9cbfab7562d59e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b8a82cd7bc2b074cd1ec98382030b9128b12ed4e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@b8a82cd7bc2b074cd1ec98382030b9128b12ed4e",
"title": "b8a82cd7bc2b074cd1ec98382030b9128b12ed4e",
"url": "https://chromium.googlesource.com/chromium/src/+/b8a82cd7bc2b074cd1ec98382030b9128b12ed4e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f214d6ab8e8470bf8ec891e119588d8784a2f8af"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@f214d6ab8e8470bf8ec891e119588d8784a2f8af",
"title": "f214d6ab8e8470bf8ec891e119588d8784a2f8af",
"url": "https://chromium.googlesource.com/chromium/src/+/f214d6ab8e8470bf8ec891e119588d8784a2f8af"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ec132209ae9c8b2108155e69fd631799abdcae30"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@ec132209ae9c8b2108155e69fd631799abdcae30",
"title": "ec132209ae9c8b2108155e69fd631799abdcae30",
"url": "https://chromium.googlesource.com/chromium/src/+/ec132209ae9c8b2108155e69fd631799abdcae30"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d0702dbe48074fb5e2a3b77785ca682e72bc7f44"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@d0702dbe48074fb5e2a3b77785ca682e72bc7f44",
"title": "d0702dbe48074fb5e2a3b77785ca682e72bc7f44",
"url": "https://chromium.googlesource.com/chromium/src/+/d0702dbe48074fb5e2a3b77785ca682e72bc7f44"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "41d8e27d3160ded6a4f2513c87da8995183dcbe6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@41d8e27d3160ded6a4f2513c87da8995183dcbe6",
"title": "41d8e27d3160ded6a4f2513c87da8995183dcbe6",
"url": "https://chromium.googlesource.com/chromium/src/+/41d8e27d3160ded6a4f2513c87da8995183dcbe6"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e1a935318de7538a9862b31b2c1baccb0fadcaa1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@e1a935318de7538a9862b31b2c1baccb0fadcaa1",
"title": "e1a935318de7538a9862b31b2c1baccb0fadcaa1",
"url": "https://chromium.googlesource.com/chromium/src/+/e1a935318de7538a9862b31b2c1baccb0fadcaa1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "99a4d6052f7624aee9c4c2e7f71692c377709b1f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@99a4d6052f7624aee9c4c2e7f71692c377709b1f",
"title": "99a4d6052f7624aee9c4c2e7f71692c377709b1f",
"url": "https://chromium.googlesource.com/chromium/src/+/99a4d6052f7624aee9c4c2e7f71692c377709b1f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "625e5fdbb9325fae1284233f04e7d872b9552712"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@625e5fdbb9325fae1284233f04e7d872b9552712",
"title": "625e5fdbb9325fae1284233f04e7d872b9552712",
"url": "https://chromium.googlesource.com/chromium/src/+/625e5fdbb9325fae1284233f04e7d872b9552712"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d2a55690cd511763fc721f2c8a0a4bd96513fe08"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@d2a55690cd511763fc721f2c8a0a4bd96513fe08",
"title": "d2a55690cd511763fc721f2c8a0a4bd96513fe08",
"url": "https://chromium.googlesource.com/chromium/src/+/d2a55690cd511763fc721f2c8a0a4bd96513fe08"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "819d724c45606c172f18be8f08faf27bd0f02a8a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@819d724c45606c172f18be8f08faf27bd0f02a8a",
"title": "819d724c45606c172f18be8f08faf27bd0f02a8a",
"url": "https://chromium.googlesource.com/chromium/src/+/819d724c45606c172f18be8f08faf27bd0f02a8a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9956fc0e0ebc1dcce5aefcf184359c98fdb77137"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@9956fc0e0ebc1dcce5aefcf184359c98fdb77137",
"title": "9956fc0e0ebc1dcce5aefcf184359c98fdb77137",
"url": "https://chromium.googlesource.com/chromium/src/+/9956fc0e0ebc1dcce5aefcf184359c98fdb77137"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "15717c6b6a95a7051f97734db04dee620acbac3a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@15717c6b6a95a7051f97734db04dee620acbac3a",
"title": "15717c6b6a95a7051f97734db04dee620acbac3a",
"url": "https://chromium.googlesource.com/chromium/src/+/15717c6b6a95a7051f97734db04dee620acbac3a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f65304aa63656c40a667c436cff845014ffc657e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@f65304aa63656c40a667c436cff845014ffc657e",
"title": "f65304aa63656c40a667c436cff845014ffc657e",
"url": "https://chromium.googlesource.com/chromium/src/+/f65304aa63656c40a667c436cff845014ffc657e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1e1b79319ab1fe8c7e88e96395d2e4074ebed215"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@1e1b79319ab1fe8c7e88e96395d2e4074ebed215",
"title": "1e1b79319ab1fe8c7e88e96395d2e4074ebed215",
"url": "https://chromium.googlesource.com/chromium/src/+/1e1b79319ab1fe8c7e88e96395d2e4074ebed215"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dfb6318291af34f7211db658d9474387835134c1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@dfb6318291af34f7211db658d9474387835134c1",
"title": "dfb6318291af34f7211db658d9474387835134c1",
"url": "https://chromium.googlesource.com/chromium/src/+/dfb6318291af34f7211db658d9474387835134c1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8",
"title": "6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8",
"url": "https://chromium.googlesource.com/chromium/src/+/6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898810601460728304"
},
{
"key": "scheduler_job_id",
"value": "chromium-m131/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:25:07.212] Scheduled build:
{
"id": "8733380539279921345",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 64,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-22T11:25:06.494275737Z",
"updateTime": "2024-10-22T11:25:06.494275737Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8",
"ref": "refs/branch-heads/6778"
}
},
"canary": true
}
[11:25:07.212] Task URL: https://cr-buildbucket.appspot.com/build/8733380539279921345
[11:25:07.212] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:2:0) after 5m53s
[11:25:07.358] Received PubSub notification, asking Buildbucket for the build status
[11:25:07.414] Build status: SCHEDULED
[11:29:19.766] Received PubSub notification, asking Buildbucket for the build status
[11:29:19.834] Build status: STARTED
[11:31:00.226] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:2:0)
[11:31:00.253] Build status: STARTED
[11:31:00.253] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:5:0) after 7m4s
[11:38:04.364] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:5:0)
[11:38:04.388] Build status: STARTED
[11:38:04.388] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:6:0) after 7m11s
[11:45:15.424] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:6:0)
[11:45:15.440] Build status: STARTED
[11:45:15.440] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:7:0) after 2m1s
[11:47:16.446] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:7:0)
[11:47:16.469] Build status: STARTED
[11:47:16.469] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:8:0) after 3m52s
[11:51:08.562] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:8:0)
[11:51:08.584] Build status: STARTED
[11:51:08.584] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:9:0) after 7m47s
[11:58:55.650] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:9:0)
[11:58:55.664] Build status: STARTED
[11:58:55.664] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:10:0) after 5m50s
[12:04:45.683] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:10:0)
[12:04:45.699] Build status: STARTED
[12:04:45.699] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:11:0) after 2m58s
[12:07:43.717] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:11:0)
[12:07:43.755] Build status: STARTED
[12:07:43.755] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:12:0) after 1m38s
[12:09:21.773] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:12:0)
[12:09:21.796] Build status: STARTED
[12:09:21.796] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:13:0) after 4m23s
[12:13:44.878] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:13:0)
[12:13:44.909] Build status: STARTED
[12:13:44.910] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:14:0) after 6m37s
[12:20:21.976] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:14:0)
[12:20:21.997] Build status: STARTED
[12:20:21.997] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:15:0) after 9m38s
[12:29:59.976] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:15:0)
[12:29:59.993] Build status: STARTED
[12:29:59.993] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:16:0) after 4m14s
[12:34:14.047] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:16:0)
[12:34:14.063] Build status: STARTED
[12:34:14.063] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:17:0) after 5m25s
[12:39:39.049] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:17:0)
[12:39:39.075] Build status: STARTED
[12:39:39.075] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:18:0) after 6m9s
[12:45:48.204] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:18:0)
[12:45:48.221] Build status: STARTED
[12:45:48.221] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:19:0) after 6m58s
[12:52:46.240] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:19:0)
[12:52:46.265] Build status: STARTED
[12:52:46.266] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:20:0) after 7m16s
[13:00:02.258] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:20:0)
[13:00:02.283] Build status: STARTED
[13:00:02.283] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:21:0) after 9m44s
[13:09:46.370] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:21:0)
[13:09:46.388] Build status: STARTED
[13:09:46.388] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:22:0) after 1m1s
[13:10:47.409] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:22:0)
[13:10:47.432] Build status: STARTED
[13:10:47.432] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:23:0) after 6m28s
[13:17:15.450] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:23:0)
[13:17:15.474] Build status: STARTED
[13:17:15.475] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:24:0) after 5m7s
[13:22:22.542] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:24:0)
[13:22:22.600] Build status: STARTED
[13:22:22.600] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:25:0) after 8m30s
[13:30:52.592] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:25:0)
[13:30:52.617] Build status: STARTED
[13:30:52.617] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:26:0) after 7m9s
[13:38:01.640] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:26:0)
[13:38:01.664] Build status: STARTED
[13:38:01.664] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:27:0) after 9m51s
[13:47:52.732] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:27:0)
[13:47:52.748] Build status: STARTED
[13:47:52.748] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:28:0) after 5m7s
[13:52:59.770] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:28:0)
[13:52:59.786] Build status: STARTED
[13:52:59.786] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:29:0) after 9m3s
[14:02:02.954] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:29:0)
[14:02:02.980] Build status: STARTED
[14:02:02.980] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:30:0) after 5m0s
[14:07:03.032] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:30:0)
[14:07:03.054] Build status: STARTED
[14:07:03.054] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:31:0) after 6m55s
[14:13:58.085] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:31:0)
[14:13:58.116] Build status: STARTED
[14:13:58.116] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:32:0) after 2m40s
[14:16:38.125] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:32:0)
[14:16:38.149] Build status: STARTED
[14:16:38.149] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:33:0) after 2m44s
[14:19:22.171] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:33:0)
[14:19:22.189] Build status: STARTED
[14:19:22.189] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:34:0) after 3m33s
[14:22:55.238] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:34:0)
[14:22:55.254] Build status: STARTED
[14:22:55.254] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898810601460728304:35:0) after 6m51s
[14:27:21.502] Received PubSub notification, asking Buildbucket for the build status
[14:27:21.520] Build:
{
"id": "8733380539279921345",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 64,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-22T11:25:06.494275737Z",
"startTime": "2024-10-22T11:29:19.597000484Z",
"endTime": "2024-10-22T14:27:20.634915600Z",
"updateTime": "2024-10-22T14:27:20.634915600Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6cc0c00f35c1ec4fc3c3de9771d59ec6bbe14df8",
"ref": "refs/branch-heads/6778"
}
},
"canary": true
}
[14:27:21.520] Invocation finished in 3h2m16.488076016s with status SUCCEEDED