[02:11:10.840] New invocation is queued and will start shortly
[02:11:11.947] Starting the invocation (attempt 1)
[02:11:11.983] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3232e2a0de856cd8f5dac3c1a9a61af843e62708"
[02:11:11.983] Popped gitiles commit info from properties and tags
[02:11:11.983] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:11:11.983] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:11:11.984] Buildbucket request:
{
"requestId": "8873478299458023184",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873478299458023184",
"job": "chromium-m139/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3b1322c35305419327f6e9288401eca5e0c96cd6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@3b1322c35305419327f6e9288401eca5e0c96cd6",
"title": "3b1322c35305419327f6e9288401eca5e0c96cd6",
"url": "https://chromium.googlesource.com/chromium/src/+/3b1322c35305419327f6e9288401eca5e0c96cd6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9b9eb8b4df7ba30b29cd95d1cf40e28461844838"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@9b9eb8b4df7ba30b29cd95d1cf40e28461844838",
"title": "9b9eb8b4df7ba30b29cd95d1cf40e28461844838",
"url": "https://chromium.googlesource.com/chromium/src/+/9b9eb8b4df7ba30b29cd95d1cf40e28461844838"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "89a602ce61c4059b46409b145b44a087d8d8a09b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@89a602ce61c4059b46409b145b44a087d8d8a09b",
"title": "89a602ce61c4059b46409b145b44a087d8d8a09b",
"url": "https://chromium.googlesource.com/chromium/src/+/89a602ce61c4059b46409b145b44a087d8d8a09b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e3550fbdad4f36f7ede0815e2be4d13352f7fd43"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@e3550fbdad4f36f7ede0815e2be4d13352f7fd43",
"title": "e3550fbdad4f36f7ede0815e2be4d13352f7fd43",
"url": "https://chromium.googlesource.com/chromium/src/+/e3550fbdad4f36f7ede0815e2be4d13352f7fd43"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c3a59f637de4da46b0d61e6dcb0aab7e161da722"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@c3a59f637de4da46b0d61e6dcb0aab7e161da722",
"title": "c3a59f637de4da46b0d61e6dcb0aab7e161da722",
"url": "https://chromium.googlesource.com/chromium/src/+/c3a59f637de4da46b0d61e6dcb0aab7e161da722"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "34abad0708865002282b716dff315f67883cbc94"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@34abad0708865002282b716dff315f67883cbc94",
"title": "34abad0708865002282b716dff315f67883cbc94",
"url": "https://chromium.googlesource.com/chromium/src/+/34abad0708865002282b716dff315f67883cbc94"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a3e3c8213a62b29012940f72aa6765a5533697c6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@a3e3c8213a62b29012940f72aa6765a5533697c6",
"title": "a3e3c8213a62b29012940f72aa6765a5533697c6",
"url": "https://chromium.googlesource.com/chromium/src/+/a3e3c8213a62b29012940f72aa6765a5533697c6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d9fa6125a0deb1a487b67a9b83fb51bcc51828a4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@d9fa6125a0deb1a487b67a9b83fb51bcc51828a4",
"title": "d9fa6125a0deb1a487b67a9b83fb51bcc51828a4",
"url": "https://chromium.googlesource.com/chromium/src/+/d9fa6125a0deb1a487b67a9b83fb51bcc51828a4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "23a00f7d2c97f9047b37b4e852e1d4bb2079122f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@23a00f7d2c97f9047b37b4e852e1d4bb2079122f",
"title": "23a00f7d2c97f9047b37b4e852e1d4bb2079122f",
"url": "https://chromium.googlesource.com/chromium/src/+/23a00f7d2c97f9047b37b4e852e1d4bb2079122f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "98d52545704334179c6de3dab6c69ee12b2e6a7a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@98d52545704334179c6de3dab6c69ee12b2e6a7a",
"title": "98d52545704334179c6de3dab6c69ee12b2e6a7a",
"url": "https://chromium.googlesource.com/chromium/src/+/98d52545704334179c6de3dab6c69ee12b2e6a7a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7258",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3232e2a0de856cd8f5dac3c1a9a61af843e62708"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7258@3232e2a0de856cd8f5dac3c1a9a61af843e62708",
"title": "3232e2a0de856cd8f5dac3c1a9a61af843e62708",
"url": "https://chromium.googlesource.com/chromium/src/+/3232e2a0de856cd8f5dac3c1a9a61af843e62708"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3232e2a0de856cd8f5dac3c1a9a61af843e62708",
"ref": "refs/branch-heads/7258"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873478299458023184"
},
{
"key": "scheduler_job_id",
"value": "chromium-m139/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:11:12.612] Scheduled build:
{
"id": "8708048237531515633",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win-official"
},
"number": 302,
"createdBy": "project:chromium-m139",
"createTime": "2025-07-29T02:11:12.047642340Z",
"updateTime": "2025-07-29T02:11:12.047642340Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3232e2a0de856cd8f5dac3c1a9a61af843e62708",
"ref": "refs/branch-heads/7258"
}
}
}
[02:11:12.612] Task URL: https://cr-buildbucket.appspot.com/build/8708048237531515633
[02:11:12.613] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:2:0) after 4m59s
[02:11:13.353] Received PubSub notification, asking Buildbucket for the build status
[02:11:13.385] Build status: SCHEDULED
[02:11:17.821] Received PubSub notification, asking Buildbucket for the build status
[02:11:17.846] Build status: STARTED
[02:16:11.632] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:2:0)
[02:16:11.654] Build status: STARTED
[02:16:11.654] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:5:0) after 9m58s
[02:26:09.725] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:5:0)
[02:26:09.761] Build status: STARTED
[02:26:09.761] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:6:0) after 6m9s
[02:32:18.780] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:6:0)
[02:32:18.804] Build status: STARTED
[02:32:18.804] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:7:0) after 5m2s
[02:37:20.828] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:7:0)
[02:37:20.854] Build status: STARTED
[02:37:20.855] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:8:0) after 2m27s
[02:39:47.992] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:8:0)
[02:39:48.051] Build status: STARTED
[02:39:48.051] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:9:0) after 2m19s
[02:42:07.099] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:9:0)
[02:42:07.116] Build status: STARTED
[02:42:07.116] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:10:0) after 1m37s
[02:43:44.143] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:10:0)
[02:43:44.166] Build status: STARTED
[02:43:44.166] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:11:0) after 4m48s
[02:48:32.229] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:11:0)
[02:48:32.254] Build status: STARTED
[02:48:32.254] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:12:0) after 6m49s
[02:55:21.273] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:12:0)
[02:55:21.292] Build status: STARTED
[02:55:21.292] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:13:0) after 2m29s
[02:57:50.317] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:13:0)
[02:57:50.394] Build status: STARTED
[02:57:50.394] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:14:0) after 7m3s
[03:04:53.480] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:14:0)
[03:04:53.532] Build status: STARTED
[03:04:53.532] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:15:0) after 5m28s
[03:10:21.573] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:15:0)
[03:10:21.597] Build status: STARTED
[03:10:21.597] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:16:0) after 2m28s
[03:12:49.645] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:16:0)
[03:12:49.671] Build status: STARTED
[03:12:49.671] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:17:0) after 4m37s
[03:17:26.778] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:17:0)
[03:17:26.796] Build status: STARTED
[03:17:26.796] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:18:0) after 3m56s
[03:21:22.819] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:18:0)
[03:21:22.892] Build status: STARTED
[03:21:22.892] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:19:0) after 7m29s
[03:28:51.906] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:19:0)
[03:28:51.929] Build status: STARTED
[03:28:51.929] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:20:0) after 9m26s
[03:38:17.946] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:20:0)
[03:38:18.014] Build status: STARTED
[03:38:18.014] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:21:0) after 8m3s
[03:46:21.024] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:21:0)
[03:46:21.042] Build status: STARTED
[03:46:21.042] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:22:0) after 9m59s
[03:56:20.077] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:22:0)
[03:56:20.102] Build status: STARTED
[03:56:20.102] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:23:0) after 5m5s
[04:01:25.125] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:23:0)
[04:01:25.155] Build status: STARTED
[04:01:25.155] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:24:0) after 7m30s
[04:08:55.183] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:24:0)
[04:08:55.210] Build status: STARTED
[04:08:55.210] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:25:0) after 8m38s
[04:17:33.288] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:25:0)
[04:17:33.308] Build status: STARTED
[04:17:33.308] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:26:0) after 2m38s
[04:20:11.293] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:26:0)
[04:20:11.343] Build status: STARTED
[04:20:11.344] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:27:0) after 1m43s
[04:21:54.413] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:27:0)
[04:21:54.438] Build status: STARTED
[04:21:54.439] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:28:0) after 7m50s
[04:29:44.536] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:28:0)
[04:29:44.554] Build status: STARTED
[04:29:44.554] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:29:0) after 4m43s
[04:34:27.558] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:29:0)
[04:34:27.583] Build status: STARTED
[04:34:27.583] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:30:0) after 1m50s
[04:36:17.621] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:30:0)
[04:36:17.699] Build status: STARTED
[04:36:17.699] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:31:0) after 7m59s
[04:44:16.766] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:31:0)
[04:44:16.791] Build status: STARTED
[04:44:16.791] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:32:0) after 7m17s
[04:51:33.904] Handling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:32:0)
[04:51:34.002] Build status: STARTED
[04:51:34.002] Scheduling timer "check-buildbucket-build-status" (chromium-m139/win-official:8873478299458023184:33:0) after 6m16s
[04:55:16.720] Received PubSub notification, asking Buildbucket for the build status
[04:55:16.818] Build:
{
"id": "8708048237531515633",
"builder": {
"project": "chromium-m139",
"bucket": "ci",
"builder": "win-official"
},
"number": 302,
"createdBy": "project:chromium-m139",
"createTime": "2025-07-29T02:11:12.047642340Z",
"startTime": "2025-07-29T02:11:16.449076941Z",
"endTime": "2025-07-29T04:55:16.392010414Z",
"updateTime": "2025-07-29T04:55:16.392010414Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3232e2a0de856cd8f5dac3c1a9a61af843e62708",
"ref": "refs/branch-heads/7258"
}
}
}
[04:55:16.818] Invocation finished in 2h44m5.9915122s with status SUCCEEDED