[18:20:28.486] New invocation is queued and will start shortly
[18:20:29.777] Starting the invocation (attempt 1)
[18:20:29.808] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ffedc45b73e2e4ce410cd29972a41c4d60bbdc46"
[18:20:29.808] Popped gitiles commit info from properties and tags
[18:20:29.808] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:20:29.809] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:20:29.809] Buildbucket request:
{
"requestId": "8900777600598147776",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8900777600598147776",
"job": "chromium-m130/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3073ba0225f7cd858816acbe379ff1ace678be24"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@3073ba0225f7cd858816acbe379ff1ace678be24",
"title": "3073ba0225f7cd858816acbe379ff1ace678be24",
"url": "https://chromium.googlesource.com/chromium/src/+/3073ba0225f7cd858816acbe379ff1ace678be24"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "09245ea1cd99f2e661a8a0936d7d75248d3e7d96"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@09245ea1cd99f2e661a8a0936d7d75248d3e7d96",
"title": "09245ea1cd99f2e661a8a0936d7d75248d3e7d96",
"url": "https://chromium.googlesource.com/chromium/src/+/09245ea1cd99f2e661a8a0936d7d75248d3e7d96"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6f76bcf0df4acda46467611b17667ee6c110fe24"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@6f76bcf0df4acda46467611b17667ee6c110fe24",
"title": "6f76bcf0df4acda46467611b17667ee6c110fe24",
"url": "https://chromium.googlesource.com/chromium/src/+/6f76bcf0df4acda46467611b17667ee6c110fe24"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "728740113a16d821207767bec0c67807827faa19"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@728740113a16d821207767bec0c67807827faa19",
"title": "728740113a16d821207767bec0c67807827faa19",
"url": "https://chromium.googlesource.com/chromium/src/+/728740113a16d821207767bec0c67807827faa19"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8d3b2062b97e664f339fa9ef94d5dadc20ff2f88"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@8d3b2062b97e664f339fa9ef94d5dadc20ff2f88",
"title": "8d3b2062b97e664f339fa9ef94d5dadc20ff2f88",
"url": "https://chromium.googlesource.com/chromium/src/+/8d3b2062b97e664f339fa9ef94d5dadc20ff2f88"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "09cb01b6f477a9996e171087e20893d46af15ea4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@09cb01b6f477a9996e171087e20893d46af15ea4",
"title": "09cb01b6f477a9996e171087e20893d46af15ea4",
"url": "https://chromium.googlesource.com/chromium/src/+/09cb01b6f477a9996e171087e20893d46af15ea4"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "483c73d7f5326b9afd8be600b6554dbe8e37c4d4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@483c73d7f5326b9afd8be600b6554dbe8e37c4d4",
"title": "483c73d7f5326b9afd8be600b6554dbe8e37c4d4",
"url": "https://chromium.googlesource.com/chromium/src/+/483c73d7f5326b9afd8be600b6554dbe8e37c4d4"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1553dbb1863d1db4cafaf4149eb42772708bfe59"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@1553dbb1863d1db4cafaf4149eb42772708bfe59",
"title": "1553dbb1863d1db4cafaf4149eb42772708bfe59",
"url": "https://chromium.googlesource.com/chromium/src/+/1553dbb1863d1db4cafaf4149eb42772708bfe59"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "73b8e7efbd72eb6c0fe2bc2db23d9c7a41380dab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@73b8e7efbd72eb6c0fe2bc2db23d9c7a41380dab",
"title": "73b8e7efbd72eb6c0fe2bc2db23d9c7a41380dab",
"url": "https://chromium.googlesource.com/chromium/src/+/73b8e7efbd72eb6c0fe2bc2db23d9c7a41380dab"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8141b96556abb3f7a22af407aae1a5dcbb847846"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@8141b96556abb3f7a22af407aae1a5dcbb847846",
"title": "8141b96556abb3f7a22af407aae1a5dcbb847846",
"url": "https://chromium.googlesource.com/chromium/src/+/8141b96556abb3f7a22af407aae1a5dcbb847846"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "acd496f9d66871267cfd81495cadd7176051fc38"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@acd496f9d66871267cfd81495cadd7176051fc38",
"title": "acd496f9d66871267cfd81495cadd7176051fc38",
"url": "https://chromium.googlesource.com/chromium/src/+/acd496f9d66871267cfd81495cadd7176051fc38"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6a50c327f57652266339dfdcbcdab0badf54d489"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@6a50c327f57652266339dfdcbcdab0badf54d489",
"title": "6a50c327f57652266339dfdcbcdab0badf54d489",
"url": "https://chromium.googlesource.com/chromium/src/+/6a50c327f57652266339dfdcbcdab0badf54d489"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ffedc45b73e2e4ce410cd29972a41c4d60bbdc46"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@ffedc45b73e2e4ce410cd29972a41c4d60bbdc46",
"title": "ffedc45b73e2e4ce410cd29972a41c4d60bbdc46",
"url": "https://chromium.googlesource.com/chromium/src/+/ffedc45b73e2e4ce410cd29972a41c4d60bbdc46"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ffedc45b73e2e4ce410cd29972a41c4d60bbdc46",
"ref": "refs/branch-heads/6723"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900777600598147776"
},
{
"key": "scheduler_job_id",
"value": "chromium-m130/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:20:30.493] Scheduled build:
{
"id": "8735347538481158849",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "win-official"
},
"number": 132,
"createdBy": "project:chromium-m130",
"createTime": "2024-09-30T18:20:29.875963109Z",
"updateTime": "2024-09-30T18:20:29.875963109Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ffedc45b73e2e4ce410cd29972a41c4d60bbdc46",
"ref": "refs/branch-heads/6723"
}
}
}
[18:20:30.494] Task URL: https://cr-buildbucket.appspot.com/build/8735347538481158849
[18:20:30.494] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:2:0) after 5m2s
[18:20:31.325] Received PubSub notification, asking Buildbucket for the build status
[18:20:31.354] Build status: SCHEDULED
[18:21:50.772] Received PubSub notification, asking Buildbucket for the build status
[18:21:50.799] Build status: STARTED
[18:25:32.530] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:2:0)
[18:25:32.557] Build status: STARTED
[18:25:32.557] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:5:0) after 2m3s
[18:27:35.531] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:5:0)
[18:27:35.548] Build status: STARTED
[18:27:35.548] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:6:0) after 5m27s
[18:33:02.614] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:6:0)
[18:33:02.633] Build status: STARTED
[18:33:02.633] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:7:0) after 2m22s
[18:35:24.650] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:7:0)
[18:35:24.666] Build status: STARTED
[18:35:24.666] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:8:0) after 2m18s
[18:37:42.721] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:8:0)
[18:37:42.744] Build status: STARTED
[18:37:42.744] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:9:0) after 5m6s
[18:42:48.762] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:9:0)
[18:42:48.785] Build status: STARTED
[18:42:48.785] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:10:0) after 4m4s
[18:46:52.880] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:10:0)
[18:46:52.975] Build status: STARTED
[18:46:52.976] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:11:0) after 4m9s
[18:51:02.023] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:11:0)
[18:51:02.044] Build status: STARTED
[18:51:02.044] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:12:0) after 6m34s
[18:57:36.060] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:12:0)
[18:57:36.135] Build status: STARTED
[18:57:36.135] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:13:0) after 3m5s
[19:00:41.263] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:13:0)
[19:00:41.284] Build status: STARTED
[19:00:41.284] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:14:0) after 3m37s
[19:04:18.350] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:14:0)
[19:04:18.370] Build status: STARTED
[19:04:18.370] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:15:0) after 6m53s
[19:11:11.436] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:15:0)
[19:11:11.465] Build status: STARTED
[19:11:11.465] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:16:0) after 6m33s
[19:17:44.660] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:16:0)
[19:17:44.734] Build status: STARTED
[19:17:44.734] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:17:0) after 9m27s
[19:27:11.780] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:17:0)
[19:27:11.800] Build status: STARTED
[19:27:11.800] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:18:0) after 3m17s
[19:30:28.820] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:18:0)
[19:30:28.892] Build status: STARTED
[19:30:28.892] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:19:0) after 6m41s
[19:37:09.884] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:19:0)
[19:37:09.906] Build status: STARTED
[19:37:09.906] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:20:0) after 2m36s
[19:39:45.983] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:20:0)
[19:39:45.999] Build status: STARTED
[19:39:45.999] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:21:0) after 1m44s
[19:41:30.067] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:21:0)
[19:41:30.114] Build status: STARTED
[19:41:30.114] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:22:0) after 7m37s
[19:49:07.172] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:22:0)
[19:49:07.195] Build status: STARTED
[19:49:07.195] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:23:0) after 5m42s
[19:54:49.222] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:23:0)
[19:54:49.239] Build status: STARTED
[19:54:49.239] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:24:0) after 2m20s
[19:57:09.328] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:24:0)
[19:57:09.355] Build status: STARTED
[19:57:09.355] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:25:0) after 3m7s
[20:00:16.414] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:25:0)
[20:00:16.432] Build status: STARTED
[20:00:16.432] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:26:0) after 8m20s
[20:08:36.451] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:26:0)
[20:08:36.475] Build status: STARTED
[20:08:36.475] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:27:0) after 9m32s
[20:18:08.517] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:27:0)
[20:18:08.535] Build status: STARTED
[20:18:08.535] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:28:0) after 5m57s
[20:24:05.534] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:28:0)
[20:24:05.551] Build status: STARTED
[20:24:05.551] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:29:0) after 7m26s
[20:31:31.611] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:29:0)
[20:31:31.650] Build status: STARTED
[20:31:31.650] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:30:0) after 7m21s
[20:38:52.652] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:30:0)
[20:38:52.669] Build status: STARTED
[20:38:52.669] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:31:0) after 3m42s
[20:42:34.656] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:31:0)
[20:42:34.673] Build status: STARTED
[20:42:34.673] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:32:0) after 4m8s
[20:46:42.743] Handling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:32:0)
[20:46:42.771] Build status: STARTED
[20:46:42.771] Scheduling timer "check-buildbucket-build-status" (chromium-m130/win-official:8900777600598147776:33:0) after 9m24s
[20:46:44.853] Received PubSub notification, asking Buildbucket for the build status
[20:46:44.874] Build:
{
"id": "8735347538481158849",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "win-official"
},
"number": 132,
"createdBy": "project:chromium-m130",
"createTime": "2024-09-30T18:20:29.875963109Z",
"startTime": "2024-09-30T18:21:50.586892946Z",
"endTime": "2024-09-30T20:46:43.783192479Z",
"updateTime": "2024-09-30T20:46:43.783192479Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ffedc45b73e2e4ce410cd29972a41c4d60bbdc46",
"ref": "refs/branch-heads/6723"
}
}
}
[20:46:44.874] Invocation finished in 2h26m16.400608706s with status SUCCEEDED