[02:25:41.024] New invocation is queued and will start shortly
[02:25:42.379] Starting the invocation (attempt 1)
[02:25:42.405] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5b767cb55dd9eb8819a74d93dd79c3377ef71a32"
[02:25:42.405] Popped gitiles commit info from properties and tags
[02:25:42.405] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:25:42.405] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:25:42.405] Buildbucket request:
{
"requestId": "8865233063060676912",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865233063060676912",
"job": "chromium-m142/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "22e4a0ab25a1f5a5498c7a560654beb6a16e4931"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@22e4a0ab25a1f5a5498c7a560654beb6a16e4931",
"title": "22e4a0ab25a1f5a5498c7a560654beb6a16e4931",
"url": "https://chromium.googlesource.com/chromium/src/+/22e4a0ab25a1f5a5498c7a560654beb6a16e4931"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9851e65cd9b1c9f3535ffc7fc77ca63e18636465"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@9851e65cd9b1c9f3535ffc7fc77ca63e18636465",
"title": "9851e65cd9b1c9f3535ffc7fc77ca63e18636465",
"url": "https://chromium.googlesource.com/chromium/src/+/9851e65cd9b1c9f3535ffc7fc77ca63e18636465"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ba2f0aba9c5d18940c3ae71b9b774127373d323a"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@ba2f0aba9c5d18940c3ae71b9b774127373d323a",
"title": "ba2f0aba9c5d18940c3ae71b9b774127373d323a",
"url": "https://chromium.googlesource.com/chromium/src/+/ba2f0aba9c5d18940c3ae71b9b774127373d323a"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9a608c160d37ce8588e32bfcbb63466e83766dfa"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@9a608c160d37ce8588e32bfcbb63466e83766dfa",
"title": "9a608c160d37ce8588e32bfcbb63466e83766dfa",
"url": "https://chromium.googlesource.com/chromium/src/+/9a608c160d37ce8588e32bfcbb63466e83766dfa"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1a5160ee12f13c3171875b9b1f1266e2bb55b46e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@1a5160ee12f13c3171875b9b1f1266e2bb55b46e",
"title": "1a5160ee12f13c3171875b9b1f1266e2bb55b46e",
"url": "https://chromium.googlesource.com/chromium/src/+/1a5160ee12f13c3171875b9b1f1266e2bb55b46e"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "da6022c634e5501bd9ef6c644a543ba832ff09c4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@da6022c634e5501bd9ef6c644a543ba832ff09c4",
"title": "da6022c634e5501bd9ef6c644a543ba832ff09c4",
"url": "https://chromium.googlesource.com/chromium/src/+/da6022c634e5501bd9ef6c644a543ba832ff09c4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cd3b38db808e69645664e7f0832299f3fdeab728"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@cd3b38db808e69645664e7f0832299f3fdeab728",
"title": "cd3b38db808e69645664e7f0832299f3fdeab728",
"url": "https://chromium.googlesource.com/chromium/src/+/cd3b38db808e69645664e7f0832299f3fdeab728"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ab19bea9cb6ea292f2bfdcee27c18f7d919568da"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@ab19bea9cb6ea292f2bfdcee27c18f7d919568da",
"title": "ab19bea9cb6ea292f2bfdcee27c18f7d919568da",
"url": "https://chromium.googlesource.com/chromium/src/+/ab19bea9cb6ea292f2bfdcee27c18f7d919568da"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4276bb3a6ccc524288e6685efd990fa78dceebab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@4276bb3a6ccc524288e6685efd990fa78dceebab",
"title": "4276bb3a6ccc524288e6685efd990fa78dceebab",
"url": "https://chromium.googlesource.com/chromium/src/+/4276bb3a6ccc524288e6685efd990fa78dceebab"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4b530427fd08df499cda0940a25cc45a994eeb3b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@4b530427fd08df499cda0940a25cc45a994eeb3b",
"title": "4b530427fd08df499cda0940a25cc45a994eeb3b",
"url": "https://chromium.googlesource.com/chromium/src/+/4b530427fd08df499cda0940a25cc45a994eeb3b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "74c56444a55bc781f98f5a5610d61eb2ac4268d6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@74c56444a55bc781f98f5a5610d61eb2ac4268d6",
"title": "74c56444a55bc781f98f5a5610d61eb2ac4268d6",
"url": "https://chromium.googlesource.com/chromium/src/+/74c56444a55bc781f98f5a5610d61eb2ac4268d6"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6d12db94b00c416759b0f9f4e9f5a8cb4e711e5c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@6d12db94b00c416759b0f9f4e9f5a8cb4e711e5c",
"title": "6d12db94b00c416759b0f9f4e9f5a8cb4e711e5c",
"url": "https://chromium.googlesource.com/chromium/src/+/6d12db94b00c416759b0f9f4e9f5a8cb4e711e5c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "64276cd390e5afd9e8a939f6ae431677448c5c16"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@64276cd390e5afd9e8a939f6ae431677448c5c16",
"title": "64276cd390e5afd9e8a939f6ae431677448c5c16",
"url": "https://chromium.googlesource.com/chromium/src/+/64276cd390e5afd9e8a939f6ae431677448c5c16"
},
{
"gitiles": {
"ref": "refs/branch-heads/7444",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5b767cb55dd9eb8819a74d93dd79c3377ef71a32"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7444@5b767cb55dd9eb8819a74d93dd79c3377ef71a32",
"title": "5b767cb55dd9eb8819a74d93dd79c3377ef71a32",
"url": "https://chromium.googlesource.com/chromium/src/+/5b767cb55dd9eb8819a74d93dd79c3377ef71a32"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5b767cb55dd9eb8819a74d93dd79c3377ef71a32",
"ref": "refs/branch-heads/7444"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865233063060676912"
},
{
"key": "scheduler_job_id",
"value": "chromium-m142/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:25:42.917] Scheduled build:
{
"id": "8699803000889728385",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"number": 171,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-28T02:25:42.465797035Z",
"updateTime": "2025-10-28T02:25:42.465797035Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5b767cb55dd9eb8819a74d93dd79c3377ef71a32",
"ref": "refs/branch-heads/7444"
}
}
}
[02:25:42.917] Task URL: https://cr-buildbucket.appspot.com/build/8699803000889728385
[02:25:42.917] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:2:0) after 6m33s
[02:25:43.865] Received PubSub notification, asking Buildbucket for the build status
[02:25:43.910] Build status: SCHEDULED
[02:25:47.154] Received PubSub notification, asking Buildbucket for the build status
[02:25:47.184] Build status: STARTED
[02:32:15.934] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:2:0)
[02:32:15.976] Build status: STARTED
[02:32:15.976] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:5:0) after 5m9s
[02:37:25.017] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:5:0)
[02:37:25.045] Build status: STARTED
[02:37:25.045] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:6:0) after 8m1s
[02:45:26.106] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:6:0)
[02:45:26.176] Build status: STARTED
[02:45:26.176] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:7:0) after 3m59s
[02:49:25.192] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:7:0)
[02:49:25.226] Build status: STARTED
[02:49:25.226] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:8:0) after 3m38s
[02:53:03.293] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:8:0)
[02:53:03.321] Build status: STARTED
[02:53:03.321] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:9:0) after 5m24s
[02:58:27.354] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:9:0)
[02:58:27.368] Build status: STARTED
[02:58:27.368] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:10:0) after 7m11s
[03:05:38.480] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:10:0)
[03:05:38.505] Build status: STARTED
[03:05:38.505] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:11:0) after 3m5s
[03:08:43.524] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:11:0)
[03:08:43.542] Build status: STARTED
[03:08:43.542] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:12:0) after 2m35s
[03:11:18.564] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:12:0)
[03:11:18.583] Build status: STARTED
[03:11:18.583] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:13:0) after 6m52s
[03:18:10.614] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:13:0)
[03:18:10.627] Build status: STARTED
[03:18:10.627] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:14:0) after 2m11s
[03:20:21.643] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:14:0)
[03:20:21.656] Build status: STARTED
[03:20:21.656] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:15:0) after 5m35s
[03:25:56.772] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:15:0)
[03:25:56.791] Build status: STARTED
[03:25:56.791] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:16:0) after 2m35s
[03:28:31.908] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:16:0)
[03:28:31.967] Build status: STARTED
[03:28:31.967] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:17:0) after 5m49s
[03:34:20.985] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:17:0)
[03:34:21.002] Build status: STARTED
[03:34:21.002] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:18:0) after 8m22s
[03:42:43.018] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:18:0)
[03:42:43.033] Build status: STARTED
[03:42:43.033] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:19:0) after 9m33s
[03:52:16.053] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:19:0)
[03:52:16.071] Build status: STARTED
[03:52:16.071] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:20:0) after 7m11s
[03:59:27.086] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:20:0)
[03:59:27.106] Build status: STARTED
[03:59:27.107] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:21:0) after 9m47s
[04:09:14.198] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:21:0)
[04:09:14.226] Build status: STARTED
[04:09:14.226] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:22:0) after 2m19s
[04:11:33.244] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:22:0)
[04:11:33.341] Build status: STARTED
[04:11:33.341] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:23:0) after 1m5s
[04:12:38.361] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:23:0)
[04:12:38.382] Build status: STARTED
[04:12:38.382] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:24:0) after 2m29s
[04:15:07.399] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:24:0)
[04:15:07.414] Build status: STARTED
[04:15:07.414] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:25:0) after 7m28s
[04:22:35.428] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:25:0)
[04:22:35.450] Build status: STARTED
[04:22:35.450] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:26:0) after 5m38s
[04:28:13.467] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:26:0)
[04:28:13.570] Build status: STARTED
[04:28:13.570] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:27:0) after 3m8s
[04:31:21.587] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:27:0)
[04:31:21.605] Build status: STARTED
[04:31:21.605] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:28:0) after 5m49s
[04:37:10.612] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:28:0)
[04:37:10.630] Build status: STARTED
[04:37:10.630] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:29:0) after 6m48s
[04:43:58.604] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:29:0)
[04:43:58.630] Build status: STARTED
[04:43:58.630] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:30:0) after 6m2s
[04:50:00.642] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:30:0)
[04:50:00.659] Build status: STARTED
[04:50:00.659] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:31:0) after 4m58s
[04:54:58.673] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:31:0)
[04:54:58.696] Build status: STARTED
[04:54:58.696] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:32:0) after 6m32s
[05:01:30.717] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:32:0)
[05:01:30.733] Build status: STARTED
[05:01:30.733] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:33:0) after 5m45s
[05:07:15.796] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:33:0)
[05:07:15.814] Build status: STARTED
[05:07:15.814] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:34:0) after 2m4s
[05:09:19.834] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:34:0)
[05:09:19.849] Build status: STARTED
[05:09:19.849] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:35:0) after 7m18s
[05:16:37.940] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:35:0)
[05:16:37.957] Build status: STARTED
[05:16:37.958] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:36:0) after 9m25s
[05:26:02.974] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:36:0)
[05:26:02.992] Build status: STARTED
[05:26:02.992] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:37:0) after 8m28s
[05:34:31.021] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:37:0)
[05:34:31.046] Build status: STARTED
[05:34:31.046] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:38:0) after 8m19s
[05:42:50.059] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:38:0)
[05:42:50.113] Build status: STARTED
[05:42:50.113] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:39:0) after 6m31s
[05:49:21.142] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:39:0)
[05:49:21.163] Build status: STARTED
[05:49:21.163] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:40:0) after 9m5s
[05:58:26.198] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:40:0)
[05:58:26.266] Build status: STARTED
[05:58:26.266] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:41:0) after 7m53s
[06:06:19.330] Handling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:41:0)
[06:06:19.347] Build status: STARTED
[06:06:19.347] Scheduling timer "check-buildbucket-build-status" (chromium-m142/win32-official:8865233063060676912:42:0) after 6m37s
[06:08:17.649] Received PubSub notification, asking Buildbucket for the build status
[06:08:17.669] Build:
{
"id": "8699803000889728385",
"builder": {
"project": "chromium-m142",
"bucket": "ci",
"builder": "win32-official"
},
"number": 171,
"createdBy": "project:chromium-m142",
"createTime": "2025-10-28T02:25:42.465797035Z",
"startTime": "2025-10-28T02:25:46.871654534Z",
"endTime": "2025-10-28T06:08:17.315643163Z",
"updateTime": "2025-10-28T06:08:17.315643163Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5b767cb55dd9eb8819a74d93dd79c3377ef71a32",
"ref": "refs/branch-heads/7444"
}
}
}
[06:08:17.670] Invocation finished in 3h42m36.658526324s with status SUCCEEDED