[00:59:49.516] New invocation is queued and will start shortly
[00:59:50.843] Starting the invocation (attempt 1)
[00:59:50.874] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b1db5b61e19842f435dcf76dd9ce50df4c8b68bd"
[00:59:50.874] Popped gitiles commit info from properties and tags
[00:59:50.874] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:59:50.875] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:59:50.875] Buildbucket request:
{
"requestId": "8891149197190640768",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8891149197190640768",
"job": "chromium-m132/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e2d87ff1e8ffc3f51af67fc0d3895ecfe0780466"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@e2d87ff1e8ffc3f51af67fc0d3895ecfe0780466",
"title": "e2d87ff1e8ffc3f51af67fc0d3895ecfe0780466",
"url": "https://chromium.googlesource.com/chromium/src/+/e2d87ff1e8ffc3f51af67fc0d3895ecfe0780466"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "69e50dbf342d7867cf0a3ecc931c07cdee8ae147"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@69e50dbf342d7867cf0a3ecc931c07cdee8ae147",
"title": "69e50dbf342d7867cf0a3ecc931c07cdee8ae147",
"url": "https://chromium.googlesource.com/chromium/src/+/69e50dbf342d7867cf0a3ecc931c07cdee8ae147"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d514d18e55002e540b44986f887b0dd65727378a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@d514d18e55002e540b44986f887b0dd65727378a",
"title": "d514d18e55002e540b44986f887b0dd65727378a",
"url": "https://chromium.googlesource.com/chromium/src/+/d514d18e55002e540b44986f887b0dd65727378a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5ca56c999e734701db9a8ad4f57e8959afba977e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@5ca56c999e734701db9a8ad4f57e8959afba977e",
"title": "5ca56c999e734701db9a8ad4f57e8959afba977e",
"url": "https://chromium.googlesource.com/chromium/src/+/5ca56c999e734701db9a8ad4f57e8959afba977e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e1050bc9c123d003a0c7b2d9a138c10f005e673"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@8e1050bc9c123d003a0c7b2d9a138c10f005e673",
"title": "8e1050bc9c123d003a0c7b2d9a138c10f005e673",
"url": "https://chromium.googlesource.com/chromium/src/+/8e1050bc9c123d003a0c7b2d9a138c10f005e673"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d4fca8f7005c7911df91db232a4f24665ab4e6ca"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@d4fca8f7005c7911df91db232a4f24665ab4e6ca",
"title": "d4fca8f7005c7911df91db232a4f24665ab4e6ca",
"url": "https://chromium.googlesource.com/chromium/src/+/d4fca8f7005c7911df91db232a4f24665ab4e6ca"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b1db5b61e19842f435dcf76dd9ce50df4c8b68bd"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@b1db5b61e19842f435dcf76dd9ce50df4c8b68bd",
"title": "b1db5b61e19842f435dcf76dd9ce50df4c8b68bd",
"url": "https://chromium.googlesource.com/chromium/src/+/b1db5b61e19842f435dcf76dd9ce50df4c8b68bd"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b1db5b61e19842f435dcf76dd9ce50df4c8b68bd",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8891149197190640768"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:59:51.498] Scheduled build:
{
"id": "8725719135046040577",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win32-official"
},
"number": 314,
"createdBy": "project:chromium-m132",
"createTime": "2025-01-15T00:59:50.940418782Z",
"updateTime": "2025-01-15T00:59:50.940418782Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b1db5b61e19842f435dcf76dd9ce50df4c8b68bd",
"ref": "refs/branch-heads/6834"
}
}
}
[00:59:51.498] Task URL: https://cr-buildbucket.appspot.com/build/8725719135046040577
[00:59:51.498] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:2:0) after 6m22s
[00:59:52.399] Received PubSub notification, asking Buildbucket for the build status
[00:59:52.430] Build status: SCHEDULED
[01:00:01.100] Received PubSub notification, asking Buildbucket for the build status
[01:00:01.125] Build status: STARTED
[01:06:13.526] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:2:0)
[01:06:13.688] Build status: STARTED
[01:06:13.688] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:5:0) after 5m16s
[01:11:29.697] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:5:0)
[01:11:29.722] Build status: STARTED
[01:11:29.722] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:6:0) after 6m12s
[01:17:41.769] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:6:0)
[01:17:41.825] Build status: STARTED
[01:17:41.825] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:7:0) after 6m43s
[01:24:24.847] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:7:0)
[01:24:24.870] Build status: STARTED
[01:24:24.870] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:8:0) after 2m9s
[01:26:33.904] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:8:0)
[01:26:33.944] Build status: STARTED
[01:26:33.944] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:9:0) after 1m59s
[01:28:32.969] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:9:0)
[01:28:32.993] Build status: STARTED
[01:28:32.993] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:10:0) after 5m0s
[01:33:33.008] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:10:0)
[01:33:33.034] Build status: STARTED
[01:33:33.034] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:11:0) after 3m25s
[01:36:58.053] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:11:0)
[01:36:58.079] Build status: STARTED
[01:36:58.079] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:12:0) after 7m50s
[01:44:48.130] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:12:0)
[01:44:48.158] Build status: STARTED
[01:44:48.158] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:13:0) after 5m25s
[01:50:13.196] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:13:0)
[01:50:13.221] Build status: STARTED
[01:50:13.221] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:14:0) after 2m50s
[01:53:03.241] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:14:0)
[01:53:03.377] Build status: STARTED
[01:53:03.377] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:15:0) after 1m40s
[01:54:43.401] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:15:0)
[01:54:43.432] Build status: STARTED
[01:54:43.432] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:16:0) after 6m19s
[02:01:02.496] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:16:0)
[02:01:02.537] Build status: STARTED
[02:01:02.538] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:17:0) after 4m0s
[02:05:02.567] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:17:0)
[02:05:02.615] Build status: STARTED
[02:05:02.615] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:18:0) after 8m7s
[02:13:09.763] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:18:0)
[02:13:09.784] Build status: STARTED
[02:13:09.784] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:19:0) after 7m2s
[02:20:11.897] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:19:0)
[02:20:12.062] Build status: STARTED
[02:20:12.062] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:20:0) after 8m39s
[02:28:51.118] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:20:0)
[02:28:51.138] Build status: STARTED
[02:28:51.138] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:21:0) after 7m24s
[02:36:15.235] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:21:0)
[02:36:15.253] Build status: STARTED
[02:36:15.253] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:22:0) after 1m28s
[02:37:43.343] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:22:0)
[02:37:43.365] Build status: STARTED
[02:37:43.365] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:23:0) after 3m17s
[02:41:00.505] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:23:0)
[02:41:00.525] Build status: STARTED
[02:41:00.525] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:24:0) after 3m24s
[02:44:24.589] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:24:0)
[02:44:24.666] Build status: STARTED
[02:44:24.667] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:25:0) after 7m43s
[02:52:07.735] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:25:0)
[02:52:07.759] Build status: STARTED
[02:52:07.759] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:26:0) after 7m5s
[02:59:12.783] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:26:0)
[02:59:12.802] Build status: STARTED
[02:59:12.802] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:27:0) after 7m29s
[03:06:41.819] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:27:0)
[03:06:41.839] Build status: STARTED
[03:06:41.839] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:28:0) after 2m23s
[03:09:04.856] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:28:0)
[03:09:04.875] Build status: STARTED
[03:09:04.875] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:29:0) after 2m33s
[03:11:37.891] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:29:0)
[03:11:37.906] Build status: STARTED
[03:11:37.906] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:30:0) after 7m12s
[03:18:49.929] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:30:0)
[03:18:50.057] Build status: STARTED
[03:18:50.057] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:31:0) after 4m30s
[03:23:20.081] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:31:0)
[03:23:20.099] Build status: STARTED
[03:23:20.099] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:32:0) after 1m17s
[03:24:37.173] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:32:0)
[03:24:37.193] Build status: STARTED
[03:24:37.193] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:33:0) after 9m4s
[03:33:41.218] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:33:0)
[03:33:41.244] Build status: STARTED
[03:33:41.244] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:34:0) after 9m46s
[03:43:27.269] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:34:0)
[03:43:27.301] Build status: STARTED
[03:43:27.301] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:35:0) after 6m42s
[03:50:09.325] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:35:0)
[03:50:09.343] Build status: STARTED
[03:50:09.343] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:36:0) after 8m19s
[03:58:28.364] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:36:0)
[03:58:28.439] Build status: STARTED
[03:58:28.439] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:37:0) after 6m33s
[04:05:01.502] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:37:0)
[04:05:01.530] Build status: STARTED
[04:05:01.530] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:38:0) after 1m33s
[04:06:34.658] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:38:0)
[04:06:34.685] Build status: STARTED
[04:06:34.685] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:39:0) after 9m57s
[04:16:31.708] Handling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:39:0)
[04:16:31.726] Build status: STARTED
[04:16:31.726] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win32-official:8891149197190640768:40:0) after 4m26s
[04:20:45.299] Received PubSub notification, asking Buildbucket for the build status
[04:20:45.322] Build:
{
"id": "8725719135046040577",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win32-official"
},
"number": 314,
"createdBy": "project:chromium-m132",
"createTime": "2025-01-15T00:59:50.940418782Z",
"startTime": "2025-01-15T01:00:00.881915992Z",
"endTime": "2025-01-15T04:20:44.900873480Z",
"updateTime": "2025-01-15T04:20:44.900873480Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b1db5b61e19842f435dcf76dd9ce50df4c8b68bd",
"ref": "refs/branch-heads/6834"
}
}
}
[04:20:45.322] Invocation finished in 3h20m55.810766486s with status SUCCEEDED