[20:23:33.176] New invocation is queued and will start shortly
[20:23:34.258] Starting the invocation (attempt 1)
[20:23:34.282] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c35d5e7500decb0a3d01939bc6f6622f7910326a"
[20:23:34.282] Popped gitiles commit info from properties and tags
[20:23:34.282] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:23:34.282] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:23:34.283] Buildbucket request:
{
"requestId": "8893612696836310496",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893612696836310496",
"job": "chromium-m132/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c585c7c0b4c684881e89028ee106b05d8f6d4a45"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@c585c7c0b4c684881e89028ee106b05d8f6d4a45",
"title": "c585c7c0b4c684881e89028ee106b05d8f6d4a45",
"url": "https://chromium.googlesource.com/chromium/src/+/c585c7c0b4c684881e89028ee106b05d8f6d4a45"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d186cf86f01777bb2264d44197349d4e6c8f3e19"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@d186cf86f01777bb2264d44197349d4e6c8f3e19",
"title": "d186cf86f01777bb2264d44197349d4e6c8f3e19",
"url": "https://chromium.googlesource.com/chromium/src/+/d186cf86f01777bb2264d44197349d4e6c8f3e19"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "aacd79200cec6c262cbb4aec8bee0cc75de0390d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@aacd79200cec6c262cbb4aec8bee0cc75de0390d",
"title": "aacd79200cec6c262cbb4aec8bee0cc75de0390d",
"url": "https://chromium.googlesource.com/chromium/src/+/aacd79200cec6c262cbb4aec8bee0cc75de0390d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9e95199d45d80ffb704831975e4fbb8b39b77049"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@9e95199d45d80ffb704831975e4fbb8b39b77049",
"title": "9e95199d45d80ffb704831975e4fbb8b39b77049",
"url": "https://chromium.googlesource.com/chromium/src/+/9e95199d45d80ffb704831975e4fbb8b39b77049"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34bbfcdcdb5402a7fb29a908e8c1fe400f7fff16"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@34bbfcdcdb5402a7fb29a908e8c1fe400f7fff16",
"title": "34bbfcdcdb5402a7fb29a908e8c1fe400f7fff16",
"url": "https://chromium.googlesource.com/chromium/src/+/34bbfcdcdb5402a7fb29a908e8c1fe400f7fff16"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cc984128ab87441f60431b9aa6b9e0c1573ea002"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@cc984128ab87441f60431b9aa6b9e0c1573ea002",
"title": "cc984128ab87441f60431b9aa6b9e0c1573ea002",
"url": "https://chromium.googlesource.com/chromium/src/+/cc984128ab87441f60431b9aa6b9e0c1573ea002"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8ece54735a88c47402bcc2995f088cd25492c3dd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@8ece54735a88c47402bcc2995f088cd25492c3dd",
"title": "8ece54735a88c47402bcc2995f088cd25492c3dd",
"url": "https://chromium.googlesource.com/chromium/src/+/8ece54735a88c47402bcc2995f088cd25492c3dd"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a046a9d133b2e885c35e6b4e542c3a2d3491e27f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@a046a9d133b2e885c35e6b4e542c3a2d3491e27f",
"title": "a046a9d133b2e885c35e6b4e542c3a2d3491e27f",
"url": "https://chromium.googlesource.com/chromium/src/+/a046a9d133b2e885c35e6b4e542c3a2d3491e27f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ab8e0b95472cfdc7c80c05c9f4ce1d482a96eb06"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@ab8e0b95472cfdc7c80c05c9f4ce1d482a96eb06",
"title": "ab8e0b95472cfdc7c80c05c9f4ce1d482a96eb06",
"url": "https://chromium.googlesource.com/chromium/src/+/ab8e0b95472cfdc7c80c05c9f4ce1d482a96eb06"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "12596890bb07a3e31f845b98d8019d169b67f259"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@12596890bb07a3e31f845b98d8019d169b67f259",
"title": "12596890bb07a3e31f845b98d8019d169b67f259",
"url": "https://chromium.googlesource.com/chromium/src/+/12596890bb07a3e31f845b98d8019d169b67f259"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c35d5e7500decb0a3d01939bc6f6622f7910326a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@c35d5e7500decb0a3d01939bc6f6622f7910326a",
"title": "c35d5e7500decb0a3d01939bc6f6622f7910326a",
"url": "https://chromium.googlesource.com/chromium/src/+/c35d5e7500decb0a3d01939bc6f6622f7910326a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c35d5e7500decb0a3d01939bc6f6622f7910326a",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893612696836310496"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:23:34.948] Scheduled build:
{
"id": "8728182634966420561",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"number": 234,
"createdBy": "project:chromium-m132",
"createTime": "2024-12-18T20:23:34.337843248Z",
"updateTime": "2024-12-18T20:23:34.337843248Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c35d5e7500decb0a3d01939bc6f6622f7910326a",
"ref": "refs/branch-heads/6834"
}
}
}
[20:23:34.948] Task URL: https://cr-buildbucket.appspot.com/build/8728182634966420561
[20:23:34.948] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:2:0) after 5m17s
[20:23:35.824] Received PubSub notification, asking Buildbucket for the build status
[20:23:35.845] Build status: SCHEDULED
[20:23:40.356] Received PubSub notification, asking Buildbucket for the build status
[20:23:40.380] Build status: STARTED
[20:28:52.163] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:2:0)
[20:28:52.266] Build status: STARTED
[20:28:52.266] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:5:0) after 3m27s
[20:32:19.340] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:5:0)
[20:32:19.359] Build status: STARTED
[20:32:19.360] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:6:0) after 2m7s
[20:34:26.388] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:6:0)
[20:34:26.455] Build status: STARTED
[20:34:26.455] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:7:0) after 1m9s
[20:35:35.470] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:7:0)
[20:35:35.492] Build status: STARTED
[20:35:35.492] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:8:0) after 7m7s
[20:42:42.507] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:8:0)
[20:42:42.526] Build status: STARTED
[20:42:42.526] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:9:0) after 7m38s
[20:50:20.543] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:9:0)
[20:50:20.559] Build status: STARTED
[20:50:20.559] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:10:0) after 3m28s
[20:53:48.664] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:10:0)
[20:53:48.680] Build status: STARTED
[20:53:48.680] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:11:0) after 4m28s
[20:58:17.184] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:11:0)
[20:58:17.202] Build status: STARTED
[20:58:17.202] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:12:0) after 3m44s
[21:02:01.362] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:12:0)
[21:02:01.383] Build status: STARTED
[21:02:01.383] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:13:0) after 5m51s
[21:07:53.607] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:13:0)
[21:07:53.649] Build status: STARTED
[21:07:53.649] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:14:0) after 1m46s
[21:09:39.906] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:14:0)
[21:09:39.924] Build status: STARTED
[21:09:39.924] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:15:0) after 2m35s
[21:12:15.773] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:15:0)
[21:12:15.789] Build status: STARTED
[21:12:15.789] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:16:0) after 9m15s
[21:21:30.801] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:16:0)
[21:21:30.823] Build status: STARTED
[21:21:30.824] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:17:0) after 4m22s
[21:25:52.856] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:17:0)
[21:25:52.878] Build status: STARTED
[21:25:52.878] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:18:0) after 2m14s
[21:28:06.896] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:18:0)
[21:28:06.933] Build status: STARTED
[21:28:06.933] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:19:0) after 1m32s
[21:29:38.950] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:19:0)
[21:29:38.969] Build status: STARTED
[21:29:38.969] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:20:0) after 7m36s
[21:37:15.406] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:20:0)
[21:37:15.433] Build status: STARTED
[21:37:15.433] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:21:0) after 4m42s
[21:41:57.452] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:21:0)
[21:41:57.469] Build status: STARTED
[21:41:57.469] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:22:0) after 4m54s
[21:46:51.494] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:22:0)
[21:46:51.514] Build status: STARTED
[21:46:51.515] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:23:0) after 3m59s
[21:50:50.806] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:23:0)
[21:50:50.823] Build status: STARTED
[21:50:50.823] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:24:0) after 4m49s
[21:55:39.821] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:24:0)
[21:55:39.843] Build status: STARTED
[21:55:39.843] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:25:0) after 3m48s
[21:59:28.190] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:25:0)
[21:59:28.211] Build status: STARTED
[21:59:28.212] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:26:0) after 9m38s
[22:09:06.693] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:26:0)
[22:09:06.714] Build status: STARTED
[22:09:06.714] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:27:0) after 8m36s
[22:17:42.868] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:27:0)
[22:17:42.894] Build status: STARTED
[22:17:42.894] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:28:0) after 3m57s
[22:21:40.032] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:28:0)
[22:21:40.116] Build status: STARTED
[22:21:40.116] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:29:0) after 2m54s
[22:24:34.171] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:29:0)
[22:24:34.192] Build status: STARTED
[22:24:34.192] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:30:0) after 3m57s
[22:28:31.639] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:30:0)
[22:28:31.661] Build status: STARTED
[22:28:31.661] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:31:0) after 3m4s
[22:31:35.750] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:31:0)
[22:31:35.766] Build status: STARTED
[22:31:35.766] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:32:0) after 6m43s
[22:38:18.779] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:32:0)
[22:38:18.845] Build status: STARTED
[22:38:18.845] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:33:0) after 7m55s
[22:46:13.993] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:33:0)
[22:46:14.011] Build status: STARTED
[22:46:14.011] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:34:0) after 6m36s
[22:52:50.097] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:34:0)
[22:52:50.114] Build status: STARTED
[22:52:50.114] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:35:0) after 7m46s
[23:00:36.144] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:35:0)
[23:00:36.165] Build status: STARTED
[23:00:36.165] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:36:0) after 6m31s
[23:07:07.181] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:36:0)
[23:07:07.219] Build status: STARTED
[23:07:07.219] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:37:0) after 5m5s
[23:12:12.479] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:37:0)
[23:12:12.680] Build status: STARTED
[23:12:12.680] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:38:0) after 7m28s
[23:19:40.748] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:38:0)
[23:19:40.770] Build status: STARTED
[23:19:40.770] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:39:0) after 9m36s
[23:29:16.913] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:39:0)
[23:29:16.934] Build status: STARTED
[23:29:16.934] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:40:0) after 8m3s
[23:37:19.931] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:40:0)
[23:37:19.948] Build status: STARTED
[23:37:19.948] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:41:0) after 6m35s
[23:43:54.964] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:41:0)
[23:43:54.981] Build status: STARTED
[23:43:54.981] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:42:0) after 6m51s
[23:50:46.005] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:42:0)
[23:50:46.023] Build status: STARTED
[23:50:46.023] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:43:0) after 7m31s
[23:58:17.064] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:43:0)
[23:58:17.132] Build status: STARTED
[23:58:17.132] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:44:0) after 8m1s
[00:06:18.206] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:44:0)
[00:06:18.224] Build status: STARTED
[00:06:18.224] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:45:0) after 1m50s
[00:08:08.234] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:45:0)
[00:08:08.257] Build status: STARTED
[00:08:08.257] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8893612696836310496:46:0) after 6m44s
[00:10:24.267] Received PubSub notification, asking Buildbucket for the build status
[00:10:24.288] Build:
{
"id": "8728182634966420561",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"number": 234,
"createdBy": "project:chromium-m132",
"createTime": "2024-12-18T20:23:34.337843248Z",
"startTime": "2024-12-18T20:23:40.146467333Z",
"endTime": "2024-12-19T00:10:23.995758384Z",
"updateTime": "2024-12-19T00:10:23.995758384Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c35d5e7500decb0a3d01939bc6f6622f7910326a",
"ref": "refs/branch-heads/6834"
}
}
}
[00:10:24.288] Invocation finished in 3h46m51.118224081s with status SUCCEEDED