[02:19:24.102] New invocation is queued and will start shortly
[02:19:25.197] Starting the invocation (attempt 1)
[02:19:25.222] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b45e14cce387dcf74f057f521deb04c09c185b2a"
[02:19:25.222] Popped gitiles commit info from properties and tags
[02:19:25.222] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:19:25.222] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:19:25.222] Buildbucket request:
{
"requestId": "8873205991337017616",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873205991337017616",
"job": "chromium-m139/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "106732645bab48ce19fc730c6714b72281b7b50d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@106732645bab48ce19fc730c6714b72281b7b50d",
"title": "106732645bab48ce19fc730c6714b72281b7b50d",
"url": "https://chromium.googlesource.com/chromium/src/+/106732645bab48ce19fc730c6714b72281b7b50d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0a03bf0e19067b4dc67856996ae300153d615afd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@0a03bf0e19067b4dc67856996ae300153d615afd",
"title": "0a03bf0e19067b4dc67856996ae300153d615afd",
"url": "https://chromium.googlesource.com/chromium/src/+/0a03bf0e19067b4dc67856996ae300153d615afd"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "07716f4de29c799f65d51ff406acfd44c8a46e89"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@07716f4de29c799f65d51ff406acfd44c8a46e89",
"title": "07716f4de29c799f65d51ff406acfd44c8a46e89",
"url": "https://chromium.googlesource.com/chromium/src/+/07716f4de29c799f65d51ff406acfd44c8a46e89"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "85ef66682db4afee99cc16e18ae21e7bf10a6229"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@85ef66682db4afee99cc16e18ae21e7bf10a6229",
"title": "85ef66682db4afee99cc16e18ae21e7bf10a6229",
"url": "https://chromium.googlesource.com/chromium/src/+/85ef66682db4afee99cc16e18ae21e7bf10a6229"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b45e14cce387dcf74f057f521deb04c09c185b2a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@b45e14cce387dcf74f057f521deb04c09c185b2a",
"title": "b45e14cce387dcf74f057f521deb04c09c185b2a",
"url": "https://chromium.googlesource.com/chromium/src/+/b45e14cce387dcf74f057f521deb04c09c185b2a"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b45e14cce387dcf74f057f521deb04c09c185b2a",
"ref": "refs/branch-heads/7258"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873205991337017616"
},
{
"key": "scheduler_job_id",
"value": "chromium-m139/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:19:25.714] Scheduled build:
{
"id": "8707775929425867761",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"number": 256,
"createdBy": "project:chromium-m139",
"createTime": "2025-08-01T02:19:25.293603604Z",
"updateTime": "2025-08-01T02:19:25.293603604Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b45e14cce387dcf74f057f521deb04c09c185b2a",
"ref": "refs/branch-heads/7258"
}
}
}
[02:19:25.714] Task URL: https://cr-buildbucket.appspot.com/build/8707775929425867761
[02:19:25.714] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:2:0) after 8m9s
[02:19:26.185] Received PubSub notification, asking Buildbucket for the build status
[02:19:26.208] Build status: SCHEDULED
[02:19:29.907] Received PubSub notification, asking Buildbucket for the build status
[02:19:29.992] Build status: STARTED
[02:27:34.742] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:2:0)
[02:27:34.758] Build status: STARTED
[02:27:34.758] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:5:0) after 7m0s
[02:34:34.777] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:5:0)
[02:34:34.888] Build status: STARTED
[02:34:34.888] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:6:0) after 4m0s
[02:38:34.918] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:6:0)
[02:38:34.936] Build status: STARTED
[02:38:34.936] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:7:0) after 4m33s
[02:43:07.961] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:7:0)
[02:43:07.988] Build status: STARTED
[02:43:07.988] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:8:0) after 7m59s
[02:51:07.248] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:8:0)
[02:51:07.273] Build status: STARTED
[02:51:07.273] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:9:0) after 8m7s
[02:59:14.334] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:9:0)
[02:59:14.490] Build status: STARTED
[02:59:14.490] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:10:0) after 3m58s
[03:03:12.509] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:10:0)
[03:03:12.540] Build status: STARTED
[03:03:12.540] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:11:0) after 2m2s
[03:05:14.592] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:11:0)
[03:05:14.617] Build status: STARTED
[03:05:14.617] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:12:0) after 1m0s
[03:06:14.739] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:12:0)
[03:06:14.777] Build status: STARTED
[03:06:14.777] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:13:0) after 4m45s
[03:10:59.794] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:13:0)
[03:10:59.916] Build status: STARTED
[03:10:59.916] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:14:0) after 4m5s
[03:15:04.948] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:14:0)
[03:15:04.976] Build status: STARTED
[03:15:04.976] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:15:0) after 9m26s
[03:24:31.104] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:15:0)
[03:24:31.131] Build status: STARTED
[03:24:31.132] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:16:0) after 1m50s
[03:26:21.153] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:16:0)
[03:26:21.178] Build status: STARTED
[03:26:21.178] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:17:0) after 1m46s
[03:28:07.226] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:17:0)
[03:28:07.262] Build status: STARTED
[03:28:07.262] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:18:0) after 7m36s
[03:35:43.287] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:18:0)
[03:35:43.399] Build status: STARTED
[03:35:43.399] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:19:0) after 9m19s
[03:45:02.457] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:19:0)
[03:45:02.530] Build status: STARTED
[03:45:02.530] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:20:0) after 7m56s
[03:52:58.655] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:20:0)
[03:52:58.676] Build status: STARTED
[03:52:58.676] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:21:0) after 3m6s
[03:56:04.741] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:21:0)
[03:56:04.771] Build status: STARTED
[03:56:04.771] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:22:0) after 4m40s
[04:00:44.845] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:22:0)
[04:00:44.936] Build status: STARTED
[04:00:44.936] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:23:0) after 8m9s
[04:08:53.972] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:23:0)
[04:08:53.991] Build status: STARTED
[04:08:53.991] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:24:0) after 8m47s
[04:17:41.282] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:24:0)
[04:17:41.363] Build status: STARTED
[04:17:41.363] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:25:0) after 7m8s
[04:24:49.380] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:25:0)
[04:24:49.412] Build status: STARTED
[04:24:49.412] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:26:0) after 2m6s
[04:26:55.467] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:26:0)
[04:26:55.502] Build status: STARTED
[04:26:55.502] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:27:0) after 7m53s
[04:34:48.525] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:27:0)
[04:34:48.582] Build status: STARTED
[04:34:48.582] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:28:0) after 5m30s
[04:40:18.590] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:28:0)
[04:40:18.611] Build status: STARTED
[04:40:18.611] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:29:0) after 3m56s
[04:44:14.592] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:29:0)
[04:44:14.611] Build status: STARTED
[04:44:14.611] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:30:0) after 5m40s
[04:49:54.631] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:30:0)
[04:49:54.649] Build status: STARTED
[04:49:54.649] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:31:0) after 9m40s
[04:59:34.794] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:31:0)
[04:59:34.812] Build status: STARTED
[04:59:34.812] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:32:0) after 9m37s
[05:09:11.828] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:32:0)
[05:09:11.846] Build status: STARTED
[05:09:11.846] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:33:0) after 8m13s
[05:17:24.872] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:33:0)
[05:17:24.892] Build status: STARTED
[05:17:24.892] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:34:0) after 7m21s
[05:24:45.918] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:34:0)
[05:24:46.014] Build status: STARTED
[05:24:46.014] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:35:0) after 5m14s
[05:30:00.324] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:35:0)
[05:30:00.348] Build status: STARTED
[05:30:00.348] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:36:0) after 8m31s
[05:38:31.527] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:36:0)
[05:38:31.550] Build status: STARTED
[05:38:31.550] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:37:0) after 3m6s
[05:41:37.548] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:37:0)
[05:41:37.585] Build status: STARTED
[05:41:37.585] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:38:0) after 5m34s
[05:47:11.730] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:38:0)
[05:47:11.794] Build status: STARTED
[05:47:11.794] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:39:0) after 8m59s
[05:56:10.886] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:39:0)
[05:56:10.958] Build status: STARTED
[05:56:10.958] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:40:0) after 2m1s
[05:58:11.945] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:40:0)
[05:58:11.977] Build status: STARTED
[05:58:11.977] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:41:0) after 9m23s
[06:07:35.001] Handling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:41:0)
[06:07:35.025] Build status: STARTED
[06:07:35.025] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win32-official:8873205991337017616:42:0) after 8m49s
[06:12:38.110] Received PubSub notification, asking Buildbucket for the build status
[06:12:38.129] Build:
{
"id": "8707775929425867761",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win32-official"
},
"number": 256,
"createdBy": "project:chromium-m139",
"createTime": "2025-08-01T02:19:25.293603604Z",
"startTime": "2025-08-01T02:19:29.591698551Z",
"endTime": "2025-08-01T06:12:37.780636178Z",
"updateTime": "2025-08-01T06:12:37.780636178Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b45e14cce387dcf74f057f521deb04c09c185b2a",
"ref": "refs/branch-heads/7258"
}
}
}
[06:12:38.129] Invocation finished in 3h53m14.041565677s with status SUCCEEDED