[03:31:21.148] New invocation is queued and will start shortly
[03:31:22.246] Starting the invocation (attempt 1)
[03:31:22.283] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/33358eda4740b4184f08e170de6bf523f6d5c331"
[03:31:22.283] Popped gitiles commit info from properties and tags
[03:31:22.283] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:31:22.283] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:31:22.284] Buildbucket request:
{
"requestId": "8894763542589058272",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8894763542589058272",
"job": "chromium-m131/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9b74ea11f8bdf3800de2a36a6348b47da21cc7dd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@9b74ea11f8bdf3800de2a36a6348b47da21cc7dd",
"title": "9b74ea11f8bdf3800de2a36a6348b47da21cc7dd",
"url": "https://chromium.googlesource.com/chromium/src/+/9b74ea11f8bdf3800de2a36a6348b47da21cc7dd"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f4ce1a66b19273670241eba7f00dbc6733c559cd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@f4ce1a66b19273670241eba7f00dbc6733c559cd",
"title": "f4ce1a66b19273670241eba7f00dbc6733c559cd",
"url": "https://chromium.googlesource.com/chromium/src/+/f4ce1a66b19273670241eba7f00dbc6733c559cd"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5d35d2b968c6c97ecb06f39c48c8eeaa45c5c09e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@5d35d2b968c6c97ecb06f39c48c8eeaa45c5c09e",
"title": "5d35d2b968c6c97ecb06f39c48c8eeaa45c5c09e",
"url": "https://chromium.googlesource.com/chromium/src/+/5d35d2b968c6c97ecb06f39c48c8eeaa45c5c09e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c7878cd9291ccca756944b347f28e2acc0c314b4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@c7878cd9291ccca756944b347f28e2acc0c314b4",
"title": "c7878cd9291ccca756944b347f28e2acc0c314b4",
"url": "https://chromium.googlesource.com/chromium/src/+/c7878cd9291ccca756944b347f28e2acc0c314b4"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "fff03ba8c25219675498eb7ef500e990d1bdfd00"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@fff03ba8c25219675498eb7ef500e990d1bdfd00",
"title": "fff03ba8c25219675498eb7ef500e990d1bdfd00",
"url": "https://chromium.googlesource.com/chromium/src/+/fff03ba8c25219675498eb7ef500e990d1bdfd00"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c81690c94d122c2079c2a00e7da0ec72f7073295"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@c81690c94d122c2079c2a00e7da0ec72f7073295",
"title": "c81690c94d122c2079c2a00e7da0ec72f7073295",
"url": "https://chromium.googlesource.com/chromium/src/+/c81690c94d122c2079c2a00e7da0ec72f7073295"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "56437638e39f07ca3c6c55745f59e643c4c5c747"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@56437638e39f07ca3c6c55745f59e643c4c5c747",
"title": "56437638e39f07ca3c6c55745f59e643c4c5c747",
"url": "https://chromium.googlesource.com/chromium/src/+/56437638e39f07ca3c6c55745f59e643c4c5c747"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3e3dd0d0e5b504fe6cc3334ec2622846b528956b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@3e3dd0d0e5b504fe6cc3334ec2622846b528956b",
"title": "3e3dd0d0e5b504fe6cc3334ec2622846b528956b",
"url": "https://chromium.googlesource.com/chromium/src/+/3e3dd0d0e5b504fe6cc3334ec2622846b528956b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "33358eda4740b4184f08e170de6bf523f6d5c331"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@33358eda4740b4184f08e170de6bf523f6d5c331",
"title": "33358eda4740b4184f08e170de6bf523f6d5c331",
"url": "https://chromium.googlesource.com/chromium/src/+/33358eda4740b4184f08e170de6bf523f6d5c331"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "33358eda4740b4184f08e170de6bf523f6d5c331",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894763542589058272"
},
{
"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"
}
}
[03:31:22.992] Scheduled build:
{
"id": "8729333480676656945",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 378,
"createdBy": "project:chromium-m131",
"createTime": "2024-12-06T03:31:22.341089611Z",
"updateTime": "2024-12-06T03:31:22.341089611Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "33358eda4740b4184f08e170de6bf523f6d5c331",
"ref": "refs/branch-heads/6778"
}
}
}
[03:31:22.992] Task URL: https://cr-buildbucket.appspot.com/build/8729333480676656945
[03:31:22.992] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:2:0) after 4m41s
[03:32:46.212] Received PubSub notification, asking Buildbucket for the build status
[03:32:46.262] Build status: SCHEDULED
[03:33:44.148] Received PubSub notification, asking Buildbucket for the build status
[03:33:44.167] Build status: STARTED
[03:36:04.068] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:2:0)
[03:36:04.090] Build status: STARTED
[03:36:04.090] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:5:0) after 3m36s
[03:39:40.208] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:5:0)
[03:39:40.234] Build status: STARTED
[03:39:40.234] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:6:0) after 4m18s
[03:43:58.313] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:6:0)
[03:43:58.330] Build status: STARTED
[03:43:58.330] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:7:0) after 9m15s
[03:53:13.480] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:7:0)
[03:53:13.512] Build status: STARTED
[03:53:13.512] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:8:0) after 6m21s
[03:59:34.576] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:8:0)
[03:59:34.595] Build status: STARTED
[03:59:34.595] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:9:0) after 5m20s
[04:04:54.610] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:9:0)
[04:04:54.676] Build status: STARTED
[04:04:54.677] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:10:0) after 9m6s
[04:14:00.926] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:10:0)
[04:14:00.945] Build status: STARTED
[04:14:00.945] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:11:0) after 3m34s
[04:17:34.979] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:11:0)
[04:17:34.998] Build status: STARTED
[04:17:34.998] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:12:0) after 5m16s
[04:22:51.048] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:12:0)
[04:22:51.067] Build status: STARTED
[04:22:51.067] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:13:0) after 2m48s
[04:25:39.292] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:13:0)
[04:25:39.313] Build status: STARTED
[04:25:39.313] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:14:0) after 4m49s
[04:30:28.752] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:14:0)
[04:30:28.967] Build status: STARTED
[04:30:28.967] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:15:0) after 9m34s
[04:40:03.465] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:15:0)
[04:40:03.488] Build status: STARTED
[04:40:03.488] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:16:0) after 3m3s
[04:43:06.512] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:16:0)
[04:43:06.545] Build status: STARTED
[04:43:06.545] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:17:0) after 2m31s
[04:45:37.560] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:17:0)
[04:45:37.583] Build status: STARTED
[04:45:37.583] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:18:0) after 1m23s
[04:47:00.671] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:18:0)
[04:47:00.691] Build status: STARTED
[04:47:00.691] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:19:0) after 7m36s
[04:54:36.708] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:19:0)
[04:54:36.732] Build status: STARTED
[04:54:36.732] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:20:0) after 7m25s
[05:02:02.365] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:20:0)
[05:02:02.383] Build status: STARTED
[05:02:02.383] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:21:0) after 6m22s
[05:08:24.557] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:21:0)
[05:08:24.573] Build status: STARTED
[05:08:24.573] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:22:0) after 3m33s
[05:11:57.594] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:22:0)
[05:11:57.618] Build status: STARTED
[05:11:57.618] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:23:0) after 6m43s
[05:18:40.638] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:23:0)
[05:18:40.658] Build status: STARTED
[05:18:40.658] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:24:0) after 1m46s
[05:20:26.653] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:24:0)
[05:20:26.671] Build status: STARTED
[05:20:26.671] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:25:0) after 8m3s
[05:28:29.686] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:25:0)
[05:28:29.703] Build status: STARTED
[05:28:29.703] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:26:0) after 8m7s
[05:36:36.719] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:26:0)
[05:36:36.736] Build status: STARTED
[05:36:36.736] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:27:0) after 1m16s
[05:37:52.870] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:27:0)
[05:37:52.929] Build status: STARTED
[05:37:52.929] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:28:0) after 4m40s
[05:42:32.945] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:28:0)
[05:42:32.966] Build status: STARTED
[05:42:32.966] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:29:0) after 5m14s
[05:47:47.115] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:29:0)
[05:47:47.277] Build status: STARTED
[05:47:47.277] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:30:0) after 9m53s
[05:57:40.498] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:30:0)
[05:57:40.521] Build status: STARTED
[05:57:40.521] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:31:0) after 1m10s
[05:58:50.537] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:31:0)
[05:58:50.578] Build status: STARTED
[05:58:50.578] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:32:0) after 2m12s
[06:01:02.914] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:32:0)
[06:01:02.932] Build status: STARTED
[06:01:02.932] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:33:0) after 7m21s
[06:08:23.983] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:33:0)
[06:08:24.004] Build status: STARTED
[06:08:24.004] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:34:0) after 2m59s
[06:11:23.094] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:34:0)
[06:11:23.135] Build status: STARTED
[06:11:23.135] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:35:0) after 3m28s
[06:14:51.159] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:35:0)
[06:14:51.181] Build status: STARTED
[06:14:51.181] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:36:0) after 7m35s
[06:22:26.197] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:36:0)
[06:22:26.220] Build status: STARTED
[06:22:26.220] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:37:0) after 5m27s
[06:27:53.500] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:37:0)
[06:27:53.517] Build status: STARTED
[06:27:53.517] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:38:0) after 3m23s
[06:31:16.532] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:38:0)
[06:31:16.570] Build status: STARTED
[06:31:16.570] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:39:0) after 1m49s
[06:33:05.635] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:39:0)
[06:33:05.668] Build status: STARTED
[06:33:05.668] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:40:0) after 5m31s
[06:38:36.711] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:40:0)
[06:38:36.807] Build status: STARTED
[06:38:36.807] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:41:0) after 9m50s
[06:48:26.927] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:41:0)
[06:48:26.952] Build status: STARTED
[06:48:26.953] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:42:0) after 5m54s
[06:54:21.319] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:42:0)
[06:54:21.906] Build status: STARTED
[06:54:21.906] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:43:0) after 4m45s
[06:59:06.941] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:43:0)
[06:59:06.969] Build status: STARTED
[06:59:06.969] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:44:0) after 1m22s
[07:00:29.005] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:44:0)
[07:00:29.034] Build status: STARTED
[07:00:29.034] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8894763542589058272:45:0) after 7m56s
[07:05:46.738] Received PubSub notification, asking Buildbucket for the build status
[07:05:46.764] Build:
{
"id": "8729333480676656945",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 378,
"createdBy": "project:chromium-m131",
"createTime": "2024-12-06T03:31:22.341089611Z",
"startTime": "2024-12-06T03:32:57.652024541Z",
"endTime": "2024-12-06T07:05:44.750861789Z",
"updateTime": "2024-12-06T07:05:44.750861789Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "33358eda4740b4184f08e170de6bf523f6d5c331",
"ref": "refs/branch-heads/6778"
}
}
}
[07:05:46.764] Invocation finished in 3h34m25.630336717s with status SUCCEEDED