[03:05:14.078] New invocation is queued and will start shortly
[03:05:15.162] Starting the invocation (attempt 1)
[03:05:15.187] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef150c6ad01cc10ba4fc6d541fa490096cc064ff"
[03:05:15.187] Popped gitiles commit info from properties and tags
[03:05:15.187] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:05:15.187] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:05:15.188] Buildbucket request:
{
"requestId": "8864958783831800288",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8864958783831800288",
"job": "chromium-m143/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "682932703fa41276362807333b0468845b3cf624"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@682932703fa41276362807333b0468845b3cf624",
"title": "682932703fa41276362807333b0468845b3cf624",
"url": "https://chromium.googlesource.com/chromium/src/+/682932703fa41276362807333b0468845b3cf624"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e04744156679c70d9986a3083595b60098c2fbd9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e04744156679c70d9986a3083595b60098c2fbd9",
"title": "e04744156679c70d9986a3083595b60098c2fbd9",
"url": "https://chromium.googlesource.com/chromium/src/+/e04744156679c70d9986a3083595b60098c2fbd9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5a6e1d17bcf46663ff191d24ae9b2326bb172045"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@5a6e1d17bcf46663ff191d24ae9b2326bb172045",
"title": "5a6e1d17bcf46663ff191d24ae9b2326bb172045",
"url": "https://chromium.googlesource.com/chromium/src/+/5a6e1d17bcf46663ff191d24ae9b2326bb172045"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6713a470c38428bf885cab0e9be7885b9d581399"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@6713a470c38428bf885cab0e9be7885b9d581399",
"title": "6713a470c38428bf885cab0e9be7885b9d581399",
"url": "https://chromium.googlesource.com/chromium/src/+/6713a470c38428bf885cab0e9be7885b9d581399"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "314e87a02f076127c3d4528eca9082b2b310e62d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@314e87a02f076127c3d4528eca9082b2b310e62d",
"title": "314e87a02f076127c3d4528eca9082b2b310e62d",
"url": "https://chromium.googlesource.com/chromium/src/+/314e87a02f076127c3d4528eca9082b2b310e62d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e759617ff44f10f8ab9e7d720f227a6ef6d8c71f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e759617ff44f10f8ab9e7d720f227a6ef6d8c71f",
"title": "e759617ff44f10f8ab9e7d720f227a6ef6d8c71f",
"url": "https://chromium.googlesource.com/chromium/src/+/e759617ff44f10f8ab9e7d720f227a6ef6d8c71f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d95ca46be22c3872c3a8883a4244e9b6bd9e9269"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@d95ca46be22c3872c3a8883a4244e9b6bd9e9269",
"title": "d95ca46be22c3872c3a8883a4244e9b6bd9e9269",
"url": "https://chromium.googlesource.com/chromium/src/+/d95ca46be22c3872c3a8883a4244e9b6bd9e9269"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e12be6932c65093c08493618534c752cad3fac61"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@e12be6932c65093c08493618534c752cad3fac61",
"title": "e12be6932c65093c08493618534c752cad3fac61",
"url": "https://chromium.googlesource.com/chromium/src/+/e12be6932c65093c08493618534c752cad3fac61"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f426478cffa76f626555bae691fe142209fda9ac"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@f426478cffa76f626555bae691fe142209fda9ac",
"title": "f426478cffa76f626555bae691fe142209fda9ac",
"url": "https://chromium.googlesource.com/chromium/src/+/f426478cffa76f626555bae691fe142209fda9ac"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1c322b0e9c34738c31db8b6873435139d93b7dda"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@1c322b0e9c34738c31db8b6873435139d93b7dda",
"title": "1c322b0e9c34738c31db8b6873435139d93b7dda",
"url": "https://chromium.googlesource.com/chromium/src/+/1c322b0e9c34738c31db8b6873435139d93b7dda"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "59d9775a657bfcdcf19f2305760d203ee00d4949"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@59d9775a657bfcdcf19f2305760d203ee00d4949",
"title": "59d9775a657bfcdcf19f2305760d203ee00d4949",
"url": "https://chromium.googlesource.com/chromium/src/+/59d9775a657bfcdcf19f2305760d203ee00d4949"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "786274467c4dd9b5d63391cebf094cab805a2658"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@786274467c4dd9b5d63391cebf094cab805a2658",
"title": "786274467c4dd9b5d63391cebf094cab805a2658",
"url": "https://chromium.googlesource.com/chromium/src/+/786274467c4dd9b5d63391cebf094cab805a2658"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2b626a06023196f336a2499693f69daf892bd738"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@2b626a06023196f336a2499693f69daf892bd738",
"title": "2b626a06023196f336a2499693f69daf892bd738",
"url": "https://chromium.googlesource.com/chromium/src/+/2b626a06023196f336a2499693f69daf892bd738"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a01b472b2f78c60a49b6abf864a4c2c4e1668647"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@a01b472b2f78c60a49b6abf864a4c2c4e1668647",
"title": "a01b472b2f78c60a49b6abf864a4c2c4e1668647",
"url": "https://chromium.googlesource.com/chromium/src/+/a01b472b2f78c60a49b6abf864a4c2c4e1668647"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "511e768f51f6fd7d7202abe82c7c8cae2d9e55b4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@511e768f51f6fd7d7202abe82c7c8cae2d9e55b4",
"title": "511e768f51f6fd7d7202abe82c7c8cae2d9e55b4",
"url": "https://chromium.googlesource.com/chromium/src/+/511e768f51f6fd7d7202abe82c7c8cae2d9e55b4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3b3a92c8a031229b3f5a9401f7a1e50298cbc7ec"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@3b3a92c8a031229b3f5a9401f7a1e50298cbc7ec",
"title": "3b3a92c8a031229b3f5a9401f7a1e50298cbc7ec",
"url": "https://chromium.googlesource.com/chromium/src/+/3b3a92c8a031229b3f5a9401f7a1e50298cbc7ec"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a6af8cf411ea72cd47ef1a45ed4caec9fe64c163"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@a6af8cf411ea72cd47ef1a45ed4caec9fe64c163",
"title": "a6af8cf411ea72cd47ef1a45ed4caec9fe64c163",
"url": "https://chromium.googlesource.com/chromium/src/+/a6af8cf411ea72cd47ef1a45ed4caec9fe64c163"
},
{
"gitiles": {
"ref": "refs/branch-heads/7499",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ef150c6ad01cc10ba4fc6d541fa490096cc064ff"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7499@ef150c6ad01cc10ba4fc6d541fa490096cc064ff",
"title": "ef150c6ad01cc10ba4fc6d541fa490096cc064ff",
"url": "https://chromium.googlesource.com/chromium/src/+/ef150c6ad01cc10ba4fc6d541fa490096cc064ff"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ef150c6ad01cc10ba4fc6d541fa490096cc064ff",
"ref": "refs/branch-heads/7499"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8864958783831800288"
},
{
"key": "scheduler_job_id",
"value": "chromium-m143/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:05:15.898] Scheduled build:
{
"id": "8699528721879207233",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win-official"
},
"number": 23,
"createdBy": "project:chromium-m143",
"createTime": "2025-10-31T03:05:15.313419313Z",
"updateTime": "2025-10-31T03:05:15.313419313Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ef150c6ad01cc10ba4fc6d541fa490096cc064ff",
"ref": "refs/branch-heads/7499"
}
}
}
[03:05:15.898] Task URL: https://cr-buildbucket.appspot.com/build/8699528721879207233
[03:05:15.898] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:2:0) after 8m5s
[03:05:16.773] Received PubSub notification, asking Buildbucket for the build status
[03:05:16.798] Build status: SCHEDULED
[03:05:19.824] Received PubSub notification, asking Buildbucket for the build status
[03:05:19.839] Build status: STARTED
[03:13:20.890] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:2:0)
[03:13:21.017] Build status: STARTED
[03:13:21.017] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:5:0) after 8m36s
[03:21:57.045] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:5:0)
[03:21:57.091] Build status: STARTED
[03:21:57.091] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:6:0) after 4m12s
[03:26:09.287] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:6:0)
[03:26:09.311] Build status: STARTED
[03:26:09.311] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:7:0) after 7m46s
[03:33:55.405] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:7:0)
[03:33:55.435] Build status: STARTED
[03:33:55.435] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:8:0) after 4m13s
[03:38:08.451] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:8:0)
[03:38:08.466] Build status: STARTED
[03:38:08.466] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:9:0) after 4m43s
[03:42:51.486] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:9:0)
[03:42:51.511] Build status: STARTED
[03:42:51.512] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:10:0) after 1m14s
[03:44:05.539] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:10:0)
[03:44:05.555] Build status: STARTED
[03:44:05.555] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:11:0) after 2m4s
[03:46:09.569] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:11:0)
[03:46:09.735] Build status: STARTED
[03:46:09.735] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:12:0) after 2m16s
[03:48:25.751] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:12:0)
[03:48:25.767] Build status: STARTED
[03:48:25.767] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:13:0) after 6m56s
[03:55:21.800] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:13:0)
[03:55:21.820] Build status: STARTED
[03:55:21.820] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:14:0) after 7m20s
[04:02:41.833] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:14:0)
[04:02:41.894] Build status: STARTED
[04:02:41.894] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:15:0) after 5m46s
[04:08:27.913] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:15:0)
[04:08:27.931] Build status: STARTED
[04:08:27.931] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:16:0) after 8m9s
[04:16:36.948] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:16:0)
[04:16:36.976] Build status: STARTED
[04:16:36.976] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:17:0) after 2m58s
[04:19:34.991] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:17:0)
[04:19:35.078] Build status: STARTED
[04:19:35.078] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:18:0) after 4m0s
[04:23:35.159] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:18:0)
[04:23:35.178] Build status: STARTED
[04:23:35.178] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:19:0) after 3m48s
[04:27:23.197] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:19:0)
[04:27:23.218] Build status: STARTED
[04:27:23.219] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:20:0) after 3m9s
[04:30:32.366] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:20:0)
[04:30:32.382] Build status: STARTED
[04:30:32.383] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:21:0) after 8m33s
[04:39:05.629] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:21:0)
[04:39:05.644] Build status: STARTED
[04:39:05.644] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:22:0) after 2m0s
[04:41:05.895] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:22:0)
[04:41:05.911] Build status: STARTED
[04:41:05.911] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:23:0) after 5m12s
[04:46:18.701] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:23:0)
[04:46:18.769] Build status: STARTED
[04:46:18.769] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:24:0) after 8m0s
[04:54:18.803] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:24:0)
[04:54:18.934] Build status: STARTED
[04:54:18.934] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:25:0) after 3m49s
[04:58:07.952] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:25:0)
[04:58:07.974] Build status: STARTED
[04:58:07.974] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:26:0) after 2m15s
[05:00:22.999] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:26:0)
[05:00:23.052] Build status: STARTED
[05:00:23.052] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:27:0) after 6m3s
[05:06:26.073] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:27:0)
[05:06:26.091] Build status: STARTED
[05:06:26.091] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:28:0) after 1m17s
[05:07:43.120] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:28:0)
[05:07:43.135] Build status: STARTED
[05:07:43.135] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:29:0) after 6m17s
[05:14:00.263] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:29:0)
[05:14:00.279] Build status: STARTED
[05:14:00.279] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:30:0) after 3m30s
[05:17:30.271] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:30:0)
[05:17:30.286] Build status: STARTED
[05:17:30.286] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:31:0) after 1m52s
[05:19:22.335] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:31:0)
[05:19:22.373] Build status: STARTED
[05:19:22.373] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:32:0) after 8m37s
[05:27:59.386] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:32:0)
[05:27:59.425] Build status: STARTED
[05:27:59.425] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:33:0) after 9m36s
[05:37:35.442] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:33:0)
[05:37:35.461] Build status: STARTED
[05:37:35.461] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:34:0) after 4m1s
[05:41:36.576] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:34:0)
[05:41:36.612] Build status: STARTED
[05:41:36.613] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:35:0) after 1m49s
[05:43:25.895] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:35:0)
[05:43:25.910] Build status: STARTED
[05:43:25.910] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:36:0) after 3m4s
[05:46:30.018] Handling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:36:0)
[05:46:30.045] Build status: STARTED
[05:46:30.045] Scheduling timer "check-buildbucket-build-status" (chromium-m143/win-official:8864958783831800288:37:0) after 8m45s
[05:54:26.071] Received PubSub notification, asking Buildbucket for the build status
[05:54:26.088] Build:
{
"id": "8699528721879207233",
"builder": {
"project": "chromium-m143",
"bucket": "ci",
"builder": "win-official"
},
"number": 23,
"createdBy": "project:chromium-m143",
"createTime": "2025-10-31T03:05:15.313419313Z",
"startTime": "2025-10-31T03:05:19.579245987Z",
"endTime": "2025-10-31T05:54:25.465985266Z",
"updateTime": "2025-10-31T05:54:25.465985266Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ef150c6ad01cc10ba4fc6d541fa490096cc064ff",
"ref": "refs/branch-heads/7499"
}
}
}
[05:54:26.088] Invocation finished in 2h49m12.021085373s with status SUCCEEDED