[21:29:06.771] New invocation is queued and will start shortly
[21:29:08.139] Starting the invocation (attempt 1)
[21:29:08.167] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0"
[21:29:08.167] Popped gitiles commit info from properties and tags
[21:29:08.167] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:29:08.167] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:29:08.168] Buildbucket request:
{
"requestId": "8898229017455944160",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898229017455944160",
"job": "chromium-m131/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "17a2b642b09a83aab350dac83a59a4b3df0e43be"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@17a2b642b09a83aab350dac83a59a4b3df0e43be",
"title": "17a2b642b09a83aab350dac83a59a4b3df0e43be",
"url": "https://chromium.googlesource.com/chromium/src/+/17a2b642b09a83aab350dac83a59a4b3df0e43be"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ab111aaa86a231a7bf319bab248fbf0438afb325"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@ab111aaa86a231a7bf319bab248fbf0438afb325",
"title": "ab111aaa86a231a7bf319bab248fbf0438afb325",
"url": "https://chromium.googlesource.com/chromium/src/+/ab111aaa86a231a7bf319bab248fbf0438afb325"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d44c7daa02be6fec53d32ce1396859cc4ae2f151"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@d44c7daa02be6fec53d32ce1396859cc4ae2f151",
"title": "d44c7daa02be6fec53d32ce1396859cc4ae2f151",
"url": "https://chromium.googlesource.com/chromium/src/+/d44c7daa02be6fec53d32ce1396859cc4ae2f151"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "73ee977fc3c1eb648bb0c1d251f18143ce4b21c5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@73ee977fc3c1eb648bb0c1d251f18143ce4b21c5",
"title": "73ee977fc3c1eb648bb0c1d251f18143ce4b21c5",
"url": "https://chromium.googlesource.com/chromium/src/+/73ee977fc3c1eb648bb0c1d251f18143ce4b21c5"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9f14ff617ece339eb3970128c4cc27a44545970d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@9f14ff617ece339eb3970128c4cc27a44545970d",
"title": "9f14ff617ece339eb3970128c4cc27a44545970d",
"url": "https://chromium.googlesource.com/chromium/src/+/9f14ff617ece339eb3970128c4cc27a44545970d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b20faa7ecdfae8644dac6ebc42b2dde2db0c437"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@8b20faa7ecdfae8644dac6ebc42b2dde2db0c437",
"title": "8b20faa7ecdfae8644dac6ebc42b2dde2db0c437",
"url": "https://chromium.googlesource.com/chromium/src/+/8b20faa7ecdfae8644dac6ebc42b2dde2db0c437"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8f445a6d120ce451abddf18b6527020d001d47d0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@8f445a6d120ce451abddf18b6527020d001d47d0",
"title": "8f445a6d120ce451abddf18b6527020d001d47d0",
"url": "https://chromium.googlesource.com/chromium/src/+/8f445a6d120ce451abddf18b6527020d001d47d0"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9d59aeed8145f56c449e3a4040730054afb8b5b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@9d59aeed8145f56c449e3a4040730054afb8b5b7",
"title": "9d59aeed8145f56c449e3a4040730054afb8b5b7",
"url": "https://chromium.googlesource.com/chromium/src/+/9d59aeed8145f56c449e3a4040730054afb8b5b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "47554fb479c4787e37421fe670868738e2aa4e7a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@47554fb479c4787e37421fe670868738e2aa4e7a",
"title": "47554fb479c4787e37421fe670868738e2aa4e7a",
"url": "https://chromium.googlesource.com/chromium/src/+/47554fb479c4787e37421fe670868738e2aa4e7a"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7a842522d9aa9e842fc69d0211fa4ea63953a314"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@7a842522d9aa9e842fc69d0211fa4ea63953a314",
"title": "7a842522d9aa9e842fc69d0211fa4ea63953a314",
"url": "https://chromium.googlesource.com/chromium/src/+/7a842522d9aa9e842fc69d0211fa4ea63953a314"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bb6909988b8b2b39dcc91b813e295b0324c3ed8e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@bb6909988b8b2b39dcc91b813e295b0324c3ed8e",
"title": "bb6909988b8b2b39dcc91b813e295b0324c3ed8e",
"url": "https://chromium.googlesource.com/chromium/src/+/bb6909988b8b2b39dcc91b813e295b0324c3ed8e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "022cd86e1c7e6b93f752ea3caf33ce34367af47f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@022cd86e1c7e6b93f752ea3caf33ce34367af47f",
"title": "022cd86e1c7e6b93f752ea3caf33ce34367af47f",
"url": "https://chromium.googlesource.com/chromium/src/+/022cd86e1c7e6b93f752ea3caf33ce34367af47f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0",
"title": "109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0",
"url": "https://chromium.googlesource.com/chromium/src/+/109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898229017455944160"
},
{
"key": "scheduler_job_id",
"value": "chromium-m131/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:29:08.746] Scheduled build:
{
"id": "8732798955265660097",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 121,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-28T21:29:08.231317427Z",
"updateTime": "2024-10-28T21:29:08.231317427Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0",
"ref": "refs/branch-heads/6778"
}
}
}
[21:29:08.746] Task URL: https://cr-buildbucket.appspot.com/build/8732798955265660097
[21:29:08.746] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:2:0) after 3m13s
[21:29:12.321] Received PubSub notification, asking Buildbucket for the build status
[21:29:12.342] Build status: SCHEDULED
[21:29:19.005] Received PubSub notification, asking Buildbucket for the build status
[21:29:19.229] Build status: STARTED
[21:32:21.889] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:2:0)
[21:32:21.904] Build status: STARTED
[21:32:21.904] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:5:0) after 8m53s
[21:41:15.012] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:5:0)
[21:41:15.039] Build status: STARTED
[21:41:15.039] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:6:0) after 5m50s
[21:47:05.110] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:6:0)
[21:47:05.149] Build status: STARTED
[21:47:05.149] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:7:0) after 8m34s
[21:55:39.168] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:7:0)
[21:55:39.200] Build status: STARTED
[21:55:39.200] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:8:0) after 9m59s
[22:05:38.268] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:8:0)
[22:05:38.290] Build status: STARTED
[22:05:38.290] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:9:0) after 5m21s
[22:10:59.533] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:9:0)
[22:10:59.553] Build status: STARTED
[22:10:59.553] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:10:0) after 7m45s
[22:18:44.764] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:10:0)
[22:18:44.789] Build status: STARTED
[22:18:44.789] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:11:0) after 3m26s
[22:22:10.829] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:11:0)
[22:22:10.851] Build status: STARTED
[22:22:10.851] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:12:0) after 8m51s
[22:31:01.903] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:12:0)
[22:31:01.929] Build status: STARTED
[22:31:01.929] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:13:0) after 3m14s
[22:34:16.017] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:13:0)
[22:34:16.096] Build status: STARTED
[22:34:16.096] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:14:0) after 3m51s
[22:38:07.115] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:14:0)
[22:38:07.135] Build status: STARTED
[22:38:07.135] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:15:0) after 3m25s
[22:41:32.162] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:15:0)
[22:41:32.191] Build status: STARTED
[22:41:32.191] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:16:0) after 7m34s
[22:49:06.218] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:16:0)
[22:49:06.236] Build status: STARTED
[22:49:06.236] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:17:0) after 7m42s
[22:56:48.252] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:17:0)
[22:56:48.271] Build status: STARTED
[22:56:48.272] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:18:0) after 7m5s
[23:03:53.270] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:18:0)
[23:03:53.314] Build status: STARTED
[23:03:53.314] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:19:0) after 5m28s
[23:09:21.338] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:19:0)
[23:09:21.358] Build status: STARTED
[23:09:21.358] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:20:0) after 6m53s
[23:16:14.376] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:20:0)
[23:16:14.403] Build status: STARTED
[23:16:14.403] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:21:0) after 9m46s
[23:26:00.421] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:21:0)
[23:26:00.441] Build status: STARTED
[23:26:00.441] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:22:0) after 3m50s
[23:29:50.459] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:22:0)
[23:29:50.476] Build status: STARTED
[23:29:50.476] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:23:0) after 3m20s
[23:33:10.498] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:23:0)
[23:33:10.514] Build status: STARTED
[23:33:10.515] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:24:0) after 4m47s
[23:37:57.590] Handling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:24:0)
[23:37:57.626] Build status: STARTED
[23:37:57.626] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-official:8898229017455944160:25:0) after 6m30s
[23:41:42.278] Received PubSub notification, asking Buildbucket for the build status
[23:41:42.306] Build:
{
"id": "8732798955265660097",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-official"
},
"number": 121,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-28T21:29:08.231317427Z",
"startTime": "2024-10-28T21:29:15.521725403Z",
"endTime": "2024-10-28T23:41:41.689347585Z",
"updateTime": "2024-10-28T23:41:41.689347585Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "109147bdbbacf17dd1fc9b8264ed0cdfb0bb47b0",
"ref": "refs/branch-heads/6778"
}
}
}
[23:41:42.306] Invocation finished in 2h12m35.547167716s with status SUCCEEDED