[01:53:47.428] New invocation is queued and will start shortly
[01:53:48.867] Starting the invocation (attempt 1)
[01:53:48.900] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d6a992f924ab04b54f5062dedfdefb4d4982d530"
[01:53:48.900] Popped gitiles commit info from properties and tags
[01:53:48.901] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:53:48.901] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:53:48.901] Buildbucket request:
{
"requestId": "8892323562557683376",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8892323562557683376",
"job": "chromium-m131/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8ec9c24d5adc728a36074c152b6b6a170e38f438"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@8ec9c24d5adc728a36074c152b6b6a170e38f438",
"title": "8ec9c24d5adc728a36074c152b6b6a170e38f438",
"url": "https://chromium.googlesource.com/chromium/src/+/8ec9c24d5adc728a36074c152b6b6a170e38f438"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8d3f1773f43f61ef70e5d7f68b584da72e33189d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@8d3f1773f43f61ef70e5d7f68b584da72e33189d",
"title": "8d3f1773f43f61ef70e5d7f68b584da72e33189d",
"url": "https://chromium.googlesource.com/chromium/src/+/8d3f1773f43f61ef70e5d7f68b584da72e33189d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "55116b21df479c1c311443e7c04f69c6444e097a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@55116b21df479c1c311443e7c04f69c6444e097a",
"title": "55116b21df479c1c311443e7c04f69c6444e097a",
"url": "https://chromium.googlesource.com/chromium/src/+/55116b21df479c1c311443e7c04f69c6444e097a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5950daa613845eaeadc02cd099d2eaa408c88b73"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@5950daa613845eaeadc02cd099d2eaa408c88b73",
"title": "5950daa613845eaeadc02cd099d2eaa408c88b73",
"url": "https://chromium.googlesource.com/chromium/src/+/5950daa613845eaeadc02cd099d2eaa408c88b73"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d7a735d44f84097aa0dc0819fe28e0857cd1d3d3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@d7a735d44f84097aa0dc0819fe28e0857cd1d3d3",
"title": "d7a735d44f84097aa0dc0819fe28e0857cd1d3d3",
"url": "https://chromium.googlesource.com/chromium/src/+/d7a735d44f84097aa0dc0819fe28e0857cd1d3d3"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "649968e86aebd6cb1facd019fb9b36d8df673c7d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@649968e86aebd6cb1facd019fb9b36d8df673c7d",
"title": "649968e86aebd6cb1facd019fb9b36d8df673c7d",
"url": "https://chromium.googlesource.com/chromium/src/+/649968e86aebd6cb1facd019fb9b36d8df673c7d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "089bae710292d08744cb4fbf567b2f8f50e9b4c9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@089bae710292d08744cb4fbf567b2f8f50e9b4c9",
"title": "089bae710292d08744cb4fbf567b2f8f50e9b4c9",
"url": "https://chromium.googlesource.com/chromium/src/+/089bae710292d08744cb4fbf567b2f8f50e9b4c9"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "05abc856d5f4271a19550dd520b0018e22b26490"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@05abc856d5f4271a19550dd520b0018e22b26490",
"title": "05abc856d5f4271a19550dd520b0018e22b26490",
"url": "https://chromium.googlesource.com/chromium/src/+/05abc856d5f4271a19550dd520b0018e22b26490"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d6a992f924ab04b54f5062dedfdefb4d4982d530"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@d6a992f924ab04b54f5062dedfdefb4d4982d530",
"title": "d6a992f924ab04b54f5062dedfdefb4d4982d530",
"url": "https://chromium.googlesource.com/chromium/src/+/d6a992f924ab04b54f5062dedfdefb4d4982d530"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6a992f924ab04b54f5062dedfdefb4d4982d530",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892323562557683376"
},
{
"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"
}
}
[01:53:49.367] Scheduled build:
{
"id": "8726893500300672225",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 550,
"createdBy": "project:chromium-m131",
"createTime": "2025-01-02T01:53:48.958581227Z",
"updateTime": "2025-01-02T01:53:48.958581227Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6a992f924ab04b54f5062dedfdefb4d4982d530",
"ref": "refs/branch-heads/6778"
}
}
}
[01:53:49.367] Task URL: https://cr-buildbucket.appspot.com/build/8726893500300672225
[01:53:49.368] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:2:0) after 1m28s
[01:53:49.461] Received PubSub notification, asking Buildbucket for the build status
[01:53:49.480] Build status: SCHEDULED
[01:53:54.699] Received PubSub notification, asking Buildbucket for the build status
[01:53:54.718] Build status: STARTED
[01:55:17.388] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:2:0)
[01:55:17.433] Build status: STARTED
[01:55:17.433] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:5:0) after 6m0s
[02:01:17.455] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:5:0)
[02:01:17.503] Build status: STARTED
[02:01:17.503] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:6:0) after 8m37s
[02:09:54.563] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:6:0)
[02:09:54.610] Build status: STARTED
[02:09:54.610] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:7:0) after 3m19s
[02:13:13.654] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:7:0)
[02:13:13.674] Build status: STARTED
[02:13:13.674] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:8:0) after 2m17s
[02:15:30.775] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:8:0)
[02:15:30.803] Build status: STARTED
[02:15:30.803] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:9:0) after 2m58s
[02:18:28.889] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:9:0)
[02:18:28.920] Build status: STARTED
[02:18:28.920] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:10:0) after 5m58s
[02:24:26.994] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:10:0)
[02:24:27.049] Build status: STARTED
[02:24:27.049] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:11:0) after 3m17s
[02:27:44.076] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:11:0)
[02:27:44.253] Build status: STARTED
[02:27:44.253] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:12:0) after 7m19s
[02:35:03.277] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:12:0)
[02:35:03.318] Build status: STARTED
[02:35:03.318] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:13:0) after 8m34s
[02:43:37.350] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:13:0)
[02:43:37.428] Build status: STARTED
[02:43:37.428] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:14:0) after 4m10s
[02:47:47.450] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:14:0)
[02:47:47.520] Build status: STARTED
[02:47:47.520] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:15:0) after 9m33s
[02:57:20.550] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:15:0)
[02:57:20.583] Build status: STARTED
[02:57:20.583] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:16:0) after 5m27s
[03:02:47.607] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:16:0)
[03:02:47.659] Build status: STARTED
[03:02:47.659] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:17:0) after 6m33s
[03:09:20.689] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:17:0)
[03:09:20.707] Build status: STARTED
[03:09:20.707] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:18:0) after 2m19s
[03:11:39.845] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:18:0)
[03:11:39.917] Build status: STARTED
[03:11:39.917] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:19:0) after 5m6s
[03:16:46.099] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:19:0)
[03:16:46.128] Build status: STARTED
[03:16:46.128] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:20:0) after 3m0s
[03:19:46.201] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:20:0)
[03:19:46.221] Build status: STARTED
[03:19:46.221] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:21:0) after 4m7s
[03:23:53.422] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:21:0)
[03:23:53.441] Build status: STARTED
[03:23:53.441] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:22:0) after 2m15s
[03:26:08.674] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:22:0)
[03:26:08.691] Build status: STARTED
[03:26:08.691] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:23:0) after 4m52s
[03:31:00.707] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:23:0)
[03:31:00.730] Build status: STARTED
[03:31:00.730] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:24:0) after 3m26s
[03:34:27.392] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:24:0)
[03:34:27.417] Build status: STARTED
[03:34:27.417] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:25:0) after 1m5s
[03:35:32.469] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:25:0)
[03:35:32.497] Build status: STARTED
[03:35:32.497] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:26:0) after 2m16s
[03:37:49.077] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:26:0)
[03:37:49.104] Build status: STARTED
[03:37:49.104] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:27:0) after 8m0s
[03:45:49.242] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:27:0)
[03:45:49.275] Build status: STARTED
[03:45:49.275] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:28:0) after 9m8s
[03:54:57.385] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:28:0)
[03:54:57.411] Build status: STARTED
[03:54:57.411] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:29:0) after 6m33s
[04:01:30.430] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:29:0)
[04:01:30.453] Build status: STARTED
[04:01:30.453] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:30:0) after 8m24s
[04:09:54.482] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:30:0)
[04:09:54.511] Build status: STARTED
[04:09:54.511] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:31:0) after 7m4s
[04:16:58.534] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:31:0)
[04:16:58.553] Build status: STARTED
[04:16:58.553] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:32:0) after 5m27s
[04:22:25.626] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:32:0)
[04:22:25.642] Build status: STARTED
[04:22:25.642] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:33:0) after 4m13s
[04:26:38.677] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:33:0)
[04:26:38.701] Build status: STARTED
[04:26:38.701] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:34:0) after 8m46s
[04:35:24.722] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:34:0)
[04:35:24.739] Build status: STARTED
[04:35:24.739] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:35:0) after 1m12s
[04:36:36.950] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:35:0)
[04:36:36.975] Build status: STARTED
[04:36:36.976] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:36:0) after 9m13s
[04:45:50.017] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:36:0)
[04:45:50.033] Build status: STARTED
[04:45:50.033] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:37:0) after 1m6s
[04:46:56.057] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:37:0)
[04:46:56.103] Build status: STARTED
[04:46:56.103] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:38:0) after 9m21s
[04:56:17.247] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:38:0)
[04:56:17.267] Build status: STARTED
[04:56:17.267] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:39:0) after 1m59s
[04:58:16.379] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:39:0)
[04:58:16.396] Build status: STARTED
[04:58:16.396] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:40:0) after 4m45s
[05:03:01.534] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:40:0)
[05:03:01.558] Build status: STARTED
[05:03:01.559] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:41:0) after 6m22s
[05:09:23.581] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:41:0)
[05:09:23.600] Build status: STARTED
[05:09:23.601] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:42:0) after 5m45s
[05:15:08.626] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:42:0)
[05:15:08.656] Build status: STARTED
[05:15:08.656] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:43:0) after 3m55s
[05:19:03.772] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:43:0)
[05:19:03.791] Build status: STARTED
[05:19:03.791] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:44:0) after 7m52s
[05:26:55.815] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:44:0)
[05:26:55.834] Build status: STARTED
[05:26:55.834] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:45:0) after 5m51s
[05:32:46.989] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:45:0)
[05:32:47.004] Build status: STARTED
[05:32:47.004] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8892323562557683376:46:0) after 6m57s
[05:39:28.715] Received PubSub notification, asking Buildbucket for the build status
[05:39:28.764] Build:
{
"id": "8726893500300672225",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 550,
"createdBy": "project:chromium-m131",
"createTime": "2025-01-02T01:53:48.958581227Z",
"startTime": "2025-01-02T01:53:54.522151941Z",
"endTime": "2025-01-02T05:39:16.390493989Z",
"updateTime": "2025-01-02T05:39:16.390493989Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d6a992f924ab04b54f5062dedfdefb4d4982d530",
"ref": "refs/branch-heads/6778"
}
}
}
[05:39:28.765] Invocation finished in 3h45m41.342292235s with status SUCCEEDED