[16:04:44.533] New invocation is queued and will start shortly
[16:04:45.696] Starting the invocation (attempt 1)
[16:04:45.728] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ab28c082a9c3af7fc4c31f87c2d661ac80be3dee"
[16:04:45.728] Popped gitiles commit info from properties and tags
[16:04:45.728] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:04:45.728] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:04:45.729] Buildbucket request:
{
"requestId": "8896256291833619488",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8896256291833619488",
"job": "chromium-m132/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cb7cb0e3bacca3e6dd09d912b72df2831fe3359b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@cb7cb0e3bacca3e6dd09d912b72df2831fe3359b",
"title": "cb7cb0e3bacca3e6dd09d912b72df2831fe3359b",
"url": "https://chromium.googlesource.com/chromium/src/+/cb7cb0e3bacca3e6dd09d912b72df2831fe3359b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "939b8381070229a7a8b779b39b12b95d570b45b0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@939b8381070229a7a8b779b39b12b95d570b45b0",
"title": "939b8381070229a7a8b779b39b12b95d570b45b0",
"url": "https://chromium.googlesource.com/chromium/src/+/939b8381070229a7a8b779b39b12b95d570b45b0"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "561dc30528d5b2bd09603ec074c183fa64adbe01"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@561dc30528d5b2bd09603ec074c183fa64adbe01",
"title": "561dc30528d5b2bd09603ec074c183fa64adbe01",
"url": "https://chromium.googlesource.com/chromium/src/+/561dc30528d5b2bd09603ec074c183fa64adbe01"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "05afb213b0950a899993dd5feb6eb006856fdc7e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@05afb213b0950a899993dd5feb6eb006856fdc7e",
"title": "05afb213b0950a899993dd5feb6eb006856fdc7e",
"url": "https://chromium.googlesource.com/chromium/src/+/05afb213b0950a899993dd5feb6eb006856fdc7e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6fa360f8b388e42deebe3ef0f5288569ea4cf735"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@6fa360f8b388e42deebe3ef0f5288569ea4cf735",
"title": "6fa360f8b388e42deebe3ef0f5288569ea4cf735",
"url": "https://chromium.googlesource.com/chromium/src/+/6fa360f8b388e42deebe3ef0f5288569ea4cf735"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1d19924f9bc3b68c0a16eef0451a6e587e71793c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@1d19924f9bc3b68c0a16eef0451a6e587e71793c",
"title": "1d19924f9bc3b68c0a16eef0451a6e587e71793c",
"url": "https://chromium.googlesource.com/chromium/src/+/1d19924f9bc3b68c0a16eef0451a6e587e71793c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7a30ec1fc0e344c59d0fde363569addd9c92242d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@7a30ec1fc0e344c59d0fde363569addd9c92242d",
"title": "7a30ec1fc0e344c59d0fde363569addd9c92242d",
"url": "https://chromium.googlesource.com/chromium/src/+/7a30ec1fc0e344c59d0fde363569addd9c92242d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8fd55bb61a98248be8919447b2acff80968de1a0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@8fd55bb61a98248be8919447b2acff80968de1a0",
"title": "8fd55bb61a98248be8919447b2acff80968de1a0",
"url": "https://chromium.googlesource.com/chromium/src/+/8fd55bb61a98248be8919447b2acff80968de1a0"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6b42d7e268de913ebaa4cb1d54fde7744384bf34"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@6b42d7e268de913ebaa4cb1d54fde7744384bf34",
"title": "6b42d7e268de913ebaa4cb1d54fde7744384bf34",
"url": "https://chromium.googlesource.com/chromium/src/+/6b42d7e268de913ebaa4cb1d54fde7744384bf34"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9cfd0f81904e2f27d60f430c3ec927d64b9cc523"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@9cfd0f81904e2f27d60f430c3ec927d64b9cc523",
"title": "9cfd0f81904e2f27d60f430c3ec927d64b9cc523",
"url": "https://chromium.googlesource.com/chromium/src/+/9cfd0f81904e2f27d60f430c3ec927d64b9cc523"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5266aea38f3b699fa98418705ea4b7c533db5c23"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@5266aea38f3b699fa98418705ea4b7c533db5c23",
"title": "5266aea38f3b699fa98418705ea4b7c533db5c23",
"url": "https://chromium.googlesource.com/chromium/src/+/5266aea38f3b699fa98418705ea4b7c533db5c23"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "65c2f5a8496bb61ab2611c4d3506f864ebb0557c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@65c2f5a8496bb61ab2611c4d3506f864ebb0557c",
"title": "65c2f5a8496bb61ab2611c4d3506f864ebb0557c",
"url": "https://chromium.googlesource.com/chromium/src/+/65c2f5a8496bb61ab2611c4d3506f864ebb0557c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0711921a859be65d72cd169df9498a638fa90afe"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@0711921a859be65d72cd169df9498a638fa90afe",
"title": "0711921a859be65d72cd169df9498a638fa90afe",
"url": "https://chromium.googlesource.com/chromium/src/+/0711921a859be65d72cd169df9498a638fa90afe"
},
{
"gitiles": {
"ref": "refs/branch-heads/6834",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ab28c082a9c3af7fc4c31f87c2d661ac80be3dee"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6834@ab28c082a9c3af7fc4c31f87c2d661ac80be3dee",
"title": "ab28c082a9c3af7fc4c31f87c2d661ac80be3dee",
"url": "https://chromium.googlesource.com/chromium/src/+/ab28c082a9c3af7fc4c31f87c2d661ac80be3dee"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ab28c082a9c3af7fc4c31f87c2d661ac80be3dee",
"ref": "refs/branch-heads/6834"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896256291833619488"
},
{
"key": "scheduler_job_id",
"value": "chromium-m132/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:04:46.287] Scheduled build:
{
"id": "8730826229853784417",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"number": 56,
"createdBy": "project:chromium-m132",
"createTime": "2024-11-19T16:04:45.790445993Z",
"updateTime": "2024-11-19T16:04:45.790445993Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ab28c082a9c3af7fc4c31f87c2d661ac80be3dee",
"ref": "refs/branch-heads/6834"
}
}
}
[16:04:46.288] Task URL: https://cr-buildbucket.appspot.com/build/8730826229853784417
[16:04:46.288] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:2:0) after 3m27s
[16:04:47.222] Received PubSub notification, asking Buildbucket for the build status
[16:04:47.243] Build status: SCHEDULED
[16:06:28.873] Received PubSub notification, asking Buildbucket for the build status
[16:06:28.888] Build status: STARTED
[16:08:13.293] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:2:0)
[16:08:13.324] Build status: STARTED
[16:08:13.324] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:5:0) after 7m38s
[16:15:51.355] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:5:0)
[16:15:51.374] Build status: STARTED
[16:15:51.374] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:6:0) after 1m29s
[16:17:20.408] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:6:0)
[16:17:20.439] Build status: STARTED
[16:17:20.439] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:7:0) after 1m7s
[16:18:27.473] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:7:0)
[16:18:27.512] Build status: STARTED
[16:18:27.512] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:8:0) after 4m58s
[16:23:25.514] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:8:0)
[16:23:25.544] Build status: STARTED
[16:23:25.544] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:9:0) after 3m40s
[16:27:05.648] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:9:0)
[16:27:05.680] Build status: STARTED
[16:27:05.680] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:10:0) after 3m29s
[16:30:34.804] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:10:0)
[16:30:34.836] Build status: STARTED
[16:30:34.836] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:11:0) after 9m9s
[16:39:43.903] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:11:0)
[16:39:44.027] Build status: STARTED
[16:39:44.027] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:12:0) after 1m58s
[16:41:42.093] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:12:0)
[16:41:42.124] Build status: STARTED
[16:41:42.124] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:13:0) after 9m46s
[16:51:28.277] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:13:0)
[16:51:28.310] Build status: STARTED
[16:51:28.310] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:14:0) after 3m31s
[16:54:59.357] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:14:0)
[16:54:59.392] Build status: STARTED
[16:54:59.392] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:15:0) after 5m10s
[17:00:09.542] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:15:0)
[17:00:09.564] Build status: STARTED
[17:00:09.564] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:16:0) after 3m5s
[17:03:14.689] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:16:0)
[17:03:14.710] Build status: STARTED
[17:03:14.710] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:17:0) after 3m54s
[17:07:08.777] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:17:0)
[17:07:08.805] Build status: STARTED
[17:07:08.805] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:18:0) after 3m50s
[17:10:58.866] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:18:0)
[17:10:58.891] Build status: STARTED
[17:10:58.891] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:19:0) after 1m44s
[17:12:42.960] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:19:0)
[17:12:42.988] Build status: STARTED
[17:12:42.988] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:20:0) after 3m25s
[17:16:08.035] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:20:0)
[17:16:08.054] Build status: STARTED
[17:16:08.054] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:21:0) after 2m49s
[17:18:57.084] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:21:0)
[17:18:57.147] Build status: STARTED
[17:18:57.147] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:22:0) after 7m34s
[17:26:31.198] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:22:0)
[17:26:31.215] Build status: STARTED
[17:26:31.215] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:23:0) after 1m37s
[17:28:08.238] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:23:0)
[17:28:08.261] Build status: STARTED
[17:28:08.261] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:24:0) after 5m49s
[17:33:57.278] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:24:0)
[17:33:57.340] Build status: STARTED
[17:33:57.340] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:25:0) after 4m50s
[17:38:47.399] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:25:0)
[17:38:47.415] Build status: STARTED
[17:38:47.415] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:26:0) after 2m10s
[17:40:57.496] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:26:0)
[17:40:57.514] Build status: STARTED
[17:40:57.514] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:27:0) after 3m0s
[17:43:57.662] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:27:0)
[17:43:57.683] Build status: STARTED
[17:43:57.683] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:28:0) after 5m22s
[17:49:19.730] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:28:0)
[17:49:19.751] Build status: STARTED
[17:49:19.751] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:29:0) after 3m21s
[17:52:40.767] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:29:0)
[17:52:40.786] Build status: STARTED
[17:52:40.786] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:30:0) after 7m39s
[18:00:19.876] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:30:0)
[18:00:19.896] Build status: STARTED
[18:00:19.897] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:31:0) after 1m24s
[18:01:43.931] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:31:0)
[18:01:43.949] Build status: STARTED
[18:01:43.949] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:32:0) after 8m7s
[18:09:51.078] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:32:0)
[18:09:51.100] Build status: STARTED
[18:09:51.100] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:33:0) after 9m58s
[18:19:49.258] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:33:0)
[18:19:49.276] Build status: STARTED
[18:19:49.276] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:34:0) after 7m1s
[18:26:50.322] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:34:0)
[18:26:50.359] Build status: STARTED
[18:26:50.359] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:35:0) after 2m12s
[18:29:02.376] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:35:0)
[18:29:02.414] Build status: STARTED
[18:29:02.414] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:36:0) after 8m55s
[18:37:57.601] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:36:0)
[18:37:57.666] Build status: STARTED
[18:37:57.666] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:37:0) after 2m7s
[18:40:04.733] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:37:0)
[18:40:04.803] Build status: STARTED
[18:40:04.803] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:38:0) after 6m10s
[18:46:14.825] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:38:0)
[18:46:14.846] Build status: STARTED
[18:46:14.846] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:39:0) after 7m37s
[18:53:51.933] Handling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:39:0)
[18:53:52.102] Build status: STARTED
[18:53:52.102] Scheduling timer "check-buildbucket-build-status" (chromium-m132/win-official:8896256291833619488:40:0) after 7m26s
[18:59:37.680] Received PubSub notification, asking Buildbucket for the build status
[18:59:37.877] Build:
{
"id": "8730826229853784417",
"builder": {
"project": "chromium-m132",
"bucket": "ci",
"builder": "win-official"
},
"number": 56,
"createdBy": "project:chromium-m132",
"createTime": "2024-11-19T16:04:45.790445993Z",
"startTime": "2024-11-19T16:06:28.336532546Z",
"endTime": "2024-11-19T18:59:37.271719916Z",
"updateTime": "2024-11-19T18:59:37.271719916Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ab28c082a9c3af7fc4c31f87c2d661ac80be3dee",
"ref": "refs/branch-heads/6834"
}
}
}
[18:59:37.877] Invocation finished in 2h54m53.358857965s with status SUCCEEDED