[18:04:18.779] New invocation is queued and will start shortly
[18:04:19.801] Starting the invocation (attempt 1)
[18:04:19.828] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/563d305e5a49e9f5b48ec281d9af86d89a8729cd"
[18:04:19.828] Popped gitiles commit info from properties and tags
[18:04:19.828] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:04:19.829] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:04:19.829] Buildbucket request:
{
"requestId": "8897788917517386496",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8897788917517386496",
"job": "chromium-m131/win-arm64-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "08f72eba2a6809dd3a4b3830c58aef710becb464"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@08f72eba2a6809dd3a4b3830c58aef710becb464",
"title": "08f72eba2a6809dd3a4b3830c58aef710becb464",
"url": "https://chromium.googlesource.com/chromium/src/+/08f72eba2a6809dd3a4b3830c58aef710becb464"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "63aec6df4bdbbe078bf46415afbced319470fc07"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@63aec6df4bdbbe078bf46415afbced319470fc07",
"title": "63aec6df4bdbbe078bf46415afbced319470fc07",
"url": "https://chromium.googlesource.com/chromium/src/+/63aec6df4bdbbe078bf46415afbced319470fc07"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "858b0d1ccd621fcc7bdea4190a9b99cc4faa9797"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@858b0d1ccd621fcc7bdea4190a9b99cc4faa9797",
"title": "858b0d1ccd621fcc7bdea4190a9b99cc4faa9797",
"url": "https://chromium.googlesource.com/chromium/src/+/858b0d1ccd621fcc7bdea4190a9b99cc4faa9797"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e8ac8204c2a79ebbe1323d1155c67c8d3a6da92f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@e8ac8204c2a79ebbe1323d1155c67c8d3a6da92f",
"title": "e8ac8204c2a79ebbe1323d1155c67c8d3a6da92f",
"url": "https://chromium.googlesource.com/chromium/src/+/e8ac8204c2a79ebbe1323d1155c67c8d3a6da92f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b921dd4bbc988a7e54a15a1f707949911d473344"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@b921dd4bbc988a7e54a15a1f707949911d473344",
"title": "b921dd4bbc988a7e54a15a1f707949911d473344",
"url": "https://chromium.googlesource.com/chromium/src/+/b921dd4bbc988a7e54a15a1f707949911d473344"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e738986a03987fa23d127a938a5dde78f7459ee2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@e738986a03987fa23d127a938a5dde78f7459ee2",
"title": "e738986a03987fa23d127a938a5dde78f7459ee2",
"url": "https://chromium.googlesource.com/chromium/src/+/e738986a03987fa23d127a938a5dde78f7459ee2"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6a2bc56f70fdfe9aa8a2ae6a956cacadd8e90a2f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@6a2bc56f70fdfe9aa8a2ae6a956cacadd8e90a2f",
"title": "6a2bc56f70fdfe9aa8a2ae6a956cacadd8e90a2f",
"url": "https://chromium.googlesource.com/chromium/src/+/6a2bc56f70fdfe9aa8a2ae6a956cacadd8e90a2f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cdafd124f755532bcb5edcec2ef5e9e45565f84f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@cdafd124f755532bcb5edcec2ef5e9e45565f84f",
"title": "cdafd124f755532bcb5edcec2ef5e9e45565f84f",
"url": "https://chromium.googlesource.com/chromium/src/+/cdafd124f755532bcb5edcec2ef5e9e45565f84f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bdb0bbe5a11cf4d5a4d968a4d3adc30d2b659741"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@bdb0bbe5a11cf4d5a4d968a4d3adc30d2b659741",
"title": "bdb0bbe5a11cf4d5a4d968a4d3adc30d2b659741",
"url": "https://chromium.googlesource.com/chromium/src/+/bdb0bbe5a11cf4d5a4d968a4d3adc30d2b659741"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cc0036da1553ceb5d672d09c76cfda010ba847d0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@cc0036da1553ceb5d672d09c76cfda010ba847d0",
"title": "cc0036da1553ceb5d672d09c76cfda010ba847d0",
"url": "https://chromium.googlesource.com/chromium/src/+/cc0036da1553ceb5d672d09c76cfda010ba847d0"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "62d043086dcab7c732c80e1fa45f992a9fd03a01"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@62d043086dcab7c732c80e1fa45f992a9fd03a01",
"title": "62d043086dcab7c732c80e1fa45f992a9fd03a01",
"url": "https://chromium.googlesource.com/chromium/src/+/62d043086dcab7c732c80e1fa45f992a9fd03a01"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1a4f34ffb307231223a78dddd1e901f20b481f1c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@1a4f34ffb307231223a78dddd1e901f20b481f1c",
"title": "1a4f34ffb307231223a78dddd1e901f20b481f1c",
"url": "https://chromium.googlesource.com/chromium/src/+/1a4f34ffb307231223a78dddd1e901f20b481f1c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e798b54b95cccdbe99939925d3e9c68df0c91ca8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@e798b54b95cccdbe99939925d3e9c68df0c91ca8",
"title": "e798b54b95cccdbe99939925d3e9c68df0c91ca8",
"url": "https://chromium.googlesource.com/chromium/src/+/e798b54b95cccdbe99939925d3e9c68df0c91ca8"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "90baa6239392f5280471c0108d5d04a507fc25e7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@90baa6239392f5280471c0108d5d04a507fc25e7",
"title": "90baa6239392f5280471c0108d5d04a507fc25e7",
"url": "https://chromium.googlesource.com/chromium/src/+/90baa6239392f5280471c0108d5d04a507fc25e7"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f59ced97cc750e1d97591ef32a41b2b69a8f2965"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@f59ced97cc750e1d97591ef32a41b2b69a8f2965",
"title": "f59ced97cc750e1d97591ef32a41b2b69a8f2965",
"url": "https://chromium.googlesource.com/chromium/src/+/f59ced97cc750e1d97591ef32a41b2b69a8f2965"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5acd57112f0ed4710cf9f356c706fd6296bbd193"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@5acd57112f0ed4710cf9f356c706fd6296bbd193",
"title": "5acd57112f0ed4710cf9f356c706fd6296bbd193",
"url": "https://chromium.googlesource.com/chromium/src/+/5acd57112f0ed4710cf9f356c706fd6296bbd193"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "563d305e5a49e9f5b48ec281d9af86d89a8729cd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@563d305e5a49e9f5b48ec281d9af86d89a8729cd",
"title": "563d305e5a49e9f5b48ec281d9af86d89a8729cd",
"url": "https://chromium.googlesource.com/chromium/src/+/563d305e5a49e9f5b48ec281d9af86d89a8729cd"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "563d305e5a49e9f5b48ec281d9af86d89a8729cd",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897788917517386496"
},
{
"key": "scheduler_job_id",
"value": "chromium-m131/win-arm64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:04:20.377] Scheduled build:
{
"id": "8732358855692715345",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 161,
"createdBy": "project:chromium-m131",
"createTime": "2024-11-02T18:04:19.891953454Z",
"updateTime": "2024-11-02T18:04:19.891953454Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "563d305e5a49e9f5b48ec281d9af86d89a8729cd",
"ref": "refs/branch-heads/6778"
}
},
"canary": true
}
[18:04:20.377] Task URL: https://cr-buildbucket.appspot.com/build/8732358855692715345
[18:04:20.377] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:2:0) after 3m21s
[18:04:21.315] Received PubSub notification, asking Buildbucket for the build status
[18:04:21.336] Build status: SCHEDULED
[18:07:41.417] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:2:0)
[18:07:41.525] Build status: SCHEDULED
[18:07:41.525] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:4:0) after 5m43s
[18:13:24.595] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:4:0)
[18:13:24.719] Build status: SCHEDULED
[18:13:24.719] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:5:0) after 3m40s
[18:17:04.740] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:5:0)
[18:17:04.757] Build status: SCHEDULED
[18:17:04.757] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:6:0) after 2m43s
[18:19:47.786] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:6:0)
[18:19:47.804] Build status: SCHEDULED
[18:19:47.804] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:7:0) after 4m56s
[18:24:44.670] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:7:0)
[18:24:44.691] Build status: SCHEDULED
[18:24:44.691] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:8:0) after 9m5s
[18:33:49.719] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:8:0)
[18:33:49.740] Build status: SCHEDULED
[18:33:49.740] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:9:0) after 8m41s
[18:42:30.757] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:9:0)
[18:42:30.773] Build status: SCHEDULED
[18:42:30.773] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:10:0) after 2m23s
[18:44:53.791] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:10:0)
[18:44:53.814] Build status: SCHEDULED
[18:44:53.814] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:11:0) after 8m26s
[18:53:20.151] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:11:0)
[18:53:20.171] Build status: SCHEDULED
[18:53:20.171] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:12:0) after 9m5s
[18:58:36.262] Received PubSub notification, asking Buildbucket for the build status
[18:58:36.284] Build status: STARTED
[19:02:25.257] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:12:0)
[19:02:25.274] Build status: STARTED
[19:02:25.274] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:14:0) after 7m48s
[19:10:13.307] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:14:0)
[19:10:13.327] Build status: STARTED
[19:10:13.327] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:15:0) after 6m13s
[19:16:26.348] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:15:0)
[19:16:26.377] Build status: STARTED
[19:16:26.377] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:16:0) after 1m56s
[19:18:22.421] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:16:0)
[19:18:22.441] Build status: STARTED
[19:18:22.441] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:17:0) after 8m36s
[19:26:58.434] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:17:0)
[19:26:58.463] Build status: STARTED
[19:26:58.463] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:18:0) after 7m14s
[19:34:12.484] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:18:0)
[19:34:12.501] Build status: STARTED
[19:34:12.501] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:19:0) after 4m34s
[19:38:46.516] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:19:0)
[19:38:46.531] Build status: STARTED
[19:38:46.531] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:20:0) after 3m20s
[19:42:06.552] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:20:0)
[19:42:06.568] Build status: STARTED
[19:42:06.568] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:21:0) after 9m49s
[19:51:55.588] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:21:0)
[19:51:55.605] Build status: STARTED
[19:51:55.605] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:22:0) after 2m47s
[19:54:42.623] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:22:0)
[19:54:42.640] Build status: STARTED
[19:54:42.640] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:23:0) after 8m53s
[20:03:35.765] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:23:0)
[20:03:35.786] Build status: STARTED
[20:03:35.786] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:24:0) after 7m36s
[20:11:11.806] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:24:0)
[20:11:11.822] Build status: STARTED
[20:11:11.822] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:25:0) after 8m17s
[20:19:29.037] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:25:0)
[20:19:29.061] Build status: STARTED
[20:19:29.061] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:26:0) after 1m25s
[20:20:54.250] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:26:0)
[20:20:54.440] Build status: STARTED
[20:20:54.440] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:27:0) after 3m0s
[20:23:54.464] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:27:0)
[20:23:54.479] Build status: STARTED
[20:23:54.479] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:28:0) after 9m38s
[20:33:32.806] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:28:0)
[20:33:32.826] Build status: STARTED
[20:33:32.826] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:29:0) after 5m52s
[20:39:24.857] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:29:0)
[20:39:24.875] Build status: STARTED
[20:39:24.875] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:30:0) after 7m3s
[20:46:28.099] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:30:0)
[20:46:28.132] Build status: STARTED
[20:46:28.132] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:31:0) after 9m38s
[20:56:06.155] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:31:0)
[20:56:06.173] Build status: STARTED
[20:56:06.173] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:32:0) after 3m26s
[20:59:32.203] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:32:0)
[20:59:32.224] Build status: STARTED
[20:59:32.224] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:33:0) after 3m23s
[21:02:55.377] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:33:0)
[21:02:55.398] Build status: STARTED
[21:02:55.398] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8897788917517386496:34:0) after 8m53s
[21:07:23.205] Received PubSub notification, asking Buildbucket for the build status
[21:07:23.222] Build:
{
"id": "8732358855692715345",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 161,
"createdBy": "project:chromium-m131",
"createTime": "2024-11-02T18:04:19.891953454Z",
"startTime": "2024-11-02T18:58:36.041134545Z",
"endTime": "2024-11-02T21:07:09.302499318Z",
"updateTime": "2024-11-02T21:07:09.302499318Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "563d305e5a49e9f5b48ec281d9af86d89a8729cd",
"ref": "refs/branch-heads/6778"
}
},
"canary": true
}
[21:07:23.222] Invocation finished in 3h3m4.45474686s with status SUCCEEDED