[18:06:16.840] New invocation is queued and will start shortly
[18:06:18.022] Starting the invocation (attempt 1)
[18:06:18.055] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3e378c626d11bca038cf91d655bc00b5f373188c"
[18:06:18.055] Popped gitiles commit info from properties and tags
[18:06:18.056] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:06:18.057] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:06:18.057] Buildbucket request:
{
"requestId": "8877495073248466032",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8877495073248466032",
"job": "chromium-m138/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "daa6464e418a83e93735fb957f9eeca6bede89a9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@daa6464e418a83e93735fb957f9eeca6bede89a9",
"title": "daa6464e418a83e93735fb957f9eeca6bede89a9",
"url": "https://chromium.googlesource.com/chromium/src/+/daa6464e418a83e93735fb957f9eeca6bede89a9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f5abdfe6a54d7e9bae224a45cce9982e37634d4f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@f5abdfe6a54d7e9bae224a45cce9982e37634d4f",
"title": "f5abdfe6a54d7e9bae224a45cce9982e37634d4f",
"url": "https://chromium.googlesource.com/chromium/src/+/f5abdfe6a54d7e9bae224a45cce9982e37634d4f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d19add1ff6a904cd5c9d4e15d28b4c5a9496796b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@d19add1ff6a904cd5c9d4e15d28b4c5a9496796b",
"title": "d19add1ff6a904cd5c9d4e15d28b4c5a9496796b",
"url": "https://chromium.googlesource.com/chromium/src/+/d19add1ff6a904cd5c9d4e15d28b4c5a9496796b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c4bccca64fa851173597d642694a8f519a3bdaa2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@c4bccca64fa851173597d642694a8f519a3bdaa2",
"title": "c4bccca64fa851173597d642694a8f519a3bdaa2",
"url": "https://chromium.googlesource.com/chromium/src/+/c4bccca64fa851173597d642694a8f519a3bdaa2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6d85ca5a8e6fde085ec89e48c3816ef66525f148"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@6d85ca5a8e6fde085ec89e48c3816ef66525f148",
"title": "6d85ca5a8e6fde085ec89e48c3816ef66525f148",
"url": "https://chromium.googlesource.com/chromium/src/+/6d85ca5a8e6fde085ec89e48c3816ef66525f148"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a4bd2d36567ef27bf62b2916175986992ec6a8e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@a4bd2d36567ef27bf62b2916175986992ec6a8e5",
"title": "a4bd2d36567ef27bf62b2916175986992ec6a8e5",
"url": "https://chromium.googlesource.com/chromium/src/+/a4bd2d36567ef27bf62b2916175986992ec6a8e5"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "662cb9d8bc04a1f8c53539fe6a3d4f37769a83e1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@662cb9d8bc04a1f8c53539fe6a3d4f37769a83e1",
"title": "662cb9d8bc04a1f8c53539fe6a3d4f37769a83e1",
"url": "https://chromium.googlesource.com/chromium/src/+/662cb9d8bc04a1f8c53539fe6a3d4f37769a83e1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6e5bb1abaf0da6a51106152d2c6bcd7555945909"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@6e5bb1abaf0da6a51106152d2c6bcd7555945909",
"title": "6e5bb1abaf0da6a51106152d2c6bcd7555945909",
"url": "https://chromium.googlesource.com/chromium/src/+/6e5bb1abaf0da6a51106152d2c6bcd7555945909"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9262830431c2a53e89b8e181d319cc1f1884fd08"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@9262830431c2a53e89b8e181d319cc1f1884fd08",
"title": "9262830431c2a53e89b8e181d319cc1f1884fd08",
"url": "https://chromium.googlesource.com/chromium/src/+/9262830431c2a53e89b8e181d319cc1f1884fd08"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a85da4dfe5ff52e8b7ad0a1fdd8787fef4604db9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@a85da4dfe5ff52e8b7ad0a1fdd8787fef4604db9",
"title": "a85da4dfe5ff52e8b7ad0a1fdd8787fef4604db9",
"url": "https://chromium.googlesource.com/chromium/src/+/a85da4dfe5ff52e8b7ad0a1fdd8787fef4604db9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3e378c626d11bca038cf91d655bc00b5f373188c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@3e378c626d11bca038cf91d655bc00b5f373188c",
"title": "3e378c626d11bca038cf91d655bc00b5f373188c",
"url": "https://chromium.googlesource.com/chromium/src/+/3e378c626d11bca038cf91d655bc00b5f373188c"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3e378c626d11bca038cf91d655bc00b5f373188c",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8877495073248466032"
},
{
"key": "scheduler_job_id",
"value": "chromium-m138/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:06:18.598] Scheduled build:
{
"id": "8712065011233949889",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"number": 122,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-14T18:06:18.132137585Z",
"updateTime": "2025-06-14T18:06:18.132137585Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3e378c626d11bca038cf91d655bc00b5f373188c",
"ref": "refs/branch-heads/7204"
}
}
}
[18:06:18.598] Task URL: https://cr-buildbucket.appspot.com/build/8712065011233949889
[18:06:18.598] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:2:0) after 6m40s
[18:06:19.584] Received PubSub notification, asking Buildbucket for the build status
[18:06:19.605] Build status: SCHEDULED
[18:06:24.259] Received PubSub notification, asking Buildbucket for the build status
[18:06:24.298] Build status: STARTED
[18:12:58.617] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:2:0)
[18:12:58.633] Build status: STARTED
[18:12:58.633] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:5:0) after 1m40s
[18:14:38.749] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:5:0)
[18:14:39.021] Build status: STARTED
[18:14:39.021] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:6:0) after 7m41s
[18:22:20.055] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:6:0)
[18:22:20.083] Build status: STARTED
[18:22:20.083] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:7:0) after 7m37s
[18:29:57.126] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:7:0)
[18:29:57.150] Build status: STARTED
[18:29:57.150] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:8:0) after 3m33s
[18:33:30.174] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:8:0)
[18:33:30.198] Build status: STARTED
[18:33:30.198] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:9:0) after 1m12s
[18:34:42.519] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:9:0)
[18:34:42.547] Build status: STARTED
[18:34:42.547] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:10:0) after 9m14s
[18:43:56.586] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:10:0)
[18:43:56.747] Build status: STARTED
[18:43:56.748] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:11:0) after 1m14s
[18:45:10.809] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:11:0)
[18:45:10.889] Build status: STARTED
[18:45:10.889] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:12:0) after 8m4s
[18:53:14.902] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:12:0)
[18:53:14.921] Build status: STARTED
[18:53:14.921] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:13:0) after 5m20s
[18:58:34.944] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:13:0)
[18:58:34.974] Build status: STARTED
[18:58:34.974] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:14:0) after 6m27s
[19:05:02.102] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:14:0)
[19:05:02.123] Build status: STARTED
[19:05:02.123] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:15:0) after 5m43s
[19:10:45.448] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:15:0)
[19:10:45.472] Build status: STARTED
[19:10:45.472] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:16:0) after 2m41s
[19:13:26.528] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:16:0)
[19:13:26.552] Build status: STARTED
[19:13:26.552] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:17:0) after 1m53s
[19:15:19.578] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:17:0)
[19:15:19.601] Build status: STARTED
[19:15:19.601] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:18:0) after 8m42s
[19:24:01.777] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:18:0)
[19:24:01.832] Build status: STARTED
[19:24:01.832] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:19:0) after 6m7s
[19:30:09.097] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:19:0)
[19:30:09.123] Build status: STARTED
[19:30:09.124] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:20:0) after 9m22s
[19:39:31.278] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:20:0)
[19:39:31.329] Build status: STARTED
[19:39:31.329] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:21:0) after 4m5s
[19:43:36.513] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:21:0)
[19:43:36.536] Build status: STARTED
[19:43:36.536] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:22:0) after 3m11s
[19:46:47.602] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:22:0)
[19:46:47.690] Build status: STARTED
[19:46:47.690] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:23:0) after 3m49s
[19:50:36.810] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:23:0)
[19:50:36.834] Build status: STARTED
[19:50:36.834] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:24:0) after 7m28s
[19:58:05.074] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:24:0)
[19:58:05.098] Build status: STARTED
[19:58:05.098] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:25:0) after 7m7s
[20:05:12.123] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:25:0)
[20:05:12.154] Build status: STARTED
[20:05:12.155] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:26:0) after 2m14s
[20:07:26.176] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:26:0)
[20:07:26.201] Build status: STARTED
[20:07:26.202] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:27:0) after 4m28s
[20:11:54.277] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:27:0)
[20:11:54.325] Build status: STARTED
[20:11:54.325] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:28:0) after 1m23s
[20:13:17.542] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:28:0)
[20:13:17.627] Build status: STARTED
[20:13:17.627] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:29:0) after 2m24s
[20:15:42.035] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:29:0)
[20:15:42.075] Build status: STARTED
[20:15:42.075] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:30:0) after 8m26s
[20:24:08.199] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:30:0)
[20:24:08.310] Build status: STARTED
[20:24:08.310] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:31:0) after 9m17s
[20:33:25.469] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:31:0)
[20:33:25.487] Build status: STARTED
[20:33:25.487] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:32:0) after 7m45s
[20:41:10.769] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:32:0)
[20:41:10.786] Build status: STARTED
[20:41:10.786] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:33:0) after 1m52s
[20:43:02.812] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:33:0)
[20:43:02.834] Build status: STARTED
[20:43:02.834] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:34:0) after 3m11s
[20:46:13.808] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:34:0)
[20:46:13.832] Build status: STARTED
[20:46:13.832] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:35:0) after 6m8s
[20:52:21.868] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:35:0)
[20:52:21.933] Build status: STARTED
[20:52:21.933] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:36:0) after 2m21s
[20:54:42.949] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:36:0)
[20:54:42.966] Build status: STARTED
[20:54:42.966] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:37:0) after 5m1s
[20:59:43.988] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:37:0)
[20:59:44.015] Build status: STARTED
[20:59:44.015] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:38:0) after 5m47s
[21:05:31.037] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:38:0)
[21:05:31.054] Build status: STARTED
[21:05:31.054] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:39:0) after 7m28s
[21:12:59.076] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:39:0)
[21:12:59.093] Build status: STARTED
[21:12:59.093] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:40:0) after 4m36s
[21:17:35.112] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:40:0)
[21:17:35.143] Build status: STARTED
[21:17:35.143] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:41:0) after 7m21s
[21:24:56.777] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:41:0)
[21:24:56.806] Build status: STARTED
[21:24:56.806] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:42:0) after 6m24s
[21:31:21.168] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:42:0)
[21:31:21.186] Build status: STARTED
[21:31:21.186] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:43:0) after 8m13s
[21:39:34.210] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:43:0)
[21:39:34.228] Build status: STARTED
[21:39:34.228] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:44:0) after 4m10s
[21:43:44.420] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:44:0)
[21:43:44.458] Build status: STARTED
[21:43:44.458] Scheduling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:45:0) after 1m25s
[21:45:09.515] Handling timer "check-buildbucket-build-status" (chromium-m138/win32-official:8877495073248466032:45:0)
[21:45:09.553] Build:
{
"id": "8712065011233949889",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "win32-official"
},
"number": 122,
"createdBy": "project:chromium-m138",
"createTime": "2025-06-14T18:06:18.132137585Z",
"startTime": "2025-06-14T18:06:23.621063176Z",
"endTime": "2025-06-14T21:44:19.407973803Z",
"updateTime": "2025-06-14T21:44:19.407973803Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3e378c626d11bca038cf91d655bc00b5f373188c",
"ref": "refs/branch-heads/7204"
}
}
}
[21:45:09.553] Invocation finished in 3h38m52.725905036s with status SUCCEEDED