[03:11:26.265] New invocation is queued and will start shortly
[03:11:27.310] Starting the invocation (attempt 1)
[03:11:27.336] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8956e7178cb2bfe844e4e4f69d4c0623f69259a6"
[03:11:27.336] Popped gitiles commit info from properties and tags
[03:11:27.336] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:11:27.336] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:11:27.337] Buildbucket request:
{
"requestId": "8886520471561758960",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8886520471561758960",
"job": "chromium-m135/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "017c919dc77eb1441aba3c93b4ca88379e43ad94"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@017c919dc77eb1441aba3c93b4ca88379e43ad94",
"title": "017c919dc77eb1441aba3c93b4ca88379e43ad94",
"url": "https://chromium.googlesource.com/chromium/src/+/017c919dc77eb1441aba3c93b4ca88379e43ad94"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c30149cb4575e5405b2245bc0f2eeb951ff67326"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@c30149cb4575e5405b2245bc0f2eeb951ff67326",
"title": "c30149cb4575e5405b2245bc0f2eeb951ff67326",
"url": "https://chromium.googlesource.com/chromium/src/+/c30149cb4575e5405b2245bc0f2eeb951ff67326"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5bb75379edd7a35b75e7f4cba31312f362a831e8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@5bb75379edd7a35b75e7f4cba31312f362a831e8",
"title": "5bb75379edd7a35b75e7f4cba31312f362a831e8",
"url": "https://chromium.googlesource.com/chromium/src/+/5bb75379edd7a35b75e7f4cba31312f362a831e8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "cfaf779df8155b4e19d0e6eb533c2b6472d5c857"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@cfaf779df8155b4e19d0e6eb533c2b6472d5c857",
"title": "cfaf779df8155b4e19d0e6eb533c2b6472d5c857",
"url": "https://chromium.googlesource.com/chromium/src/+/cfaf779df8155b4e19d0e6eb533c2b6472d5c857"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d73de0728b4e578e8f9cf3efa03b643b80c607fc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@d73de0728b4e578e8f9cf3efa03b643b80c607fc",
"title": "d73de0728b4e578e8f9cf3efa03b643b80c607fc",
"url": "https://chromium.googlesource.com/chromium/src/+/d73de0728b4e578e8f9cf3efa03b643b80c607fc"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d324445236d98e8979cc489e75391a664ebaeaa8"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@d324445236d98e8979cc489e75391a664ebaeaa8",
"title": "d324445236d98e8979cc489e75391a664ebaeaa8",
"url": "https://chromium.googlesource.com/chromium/src/+/d324445236d98e8979cc489e75391a664ebaeaa8"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8f2ace3114dd530074393a110a7fdd883c235d4f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@8f2ace3114dd530074393a110a7fdd883c235d4f",
"title": "8f2ace3114dd530074393a110a7fdd883c235d4f",
"url": "https://chromium.googlesource.com/chromium/src/+/8f2ace3114dd530074393a110a7fdd883c235d4f"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f26145df5bd2775067f0b3b7aa62efe5e4f52282"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@f26145df5bd2775067f0b3b7aa62efe5e4f52282",
"title": "f26145df5bd2775067f0b3b7aa62efe5e4f52282",
"url": "https://chromium.googlesource.com/chromium/src/+/f26145df5bd2775067f0b3b7aa62efe5e4f52282"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d288727784d253582be70bbdcd1ee806113e90f9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@d288727784d253582be70bbdcd1ee806113e90f9",
"title": "d288727784d253582be70bbdcd1ee806113e90f9",
"url": "https://chromium.googlesource.com/chromium/src/+/d288727784d253582be70bbdcd1ee806113e90f9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e5b83f2a8f07bf9015cf0ff89c11ff37ae0658ab"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@e5b83f2a8f07bf9015cf0ff89c11ff37ae0658ab",
"title": "e5b83f2a8f07bf9015cf0ff89c11ff37ae0658ab",
"url": "https://chromium.googlesource.com/chromium/src/+/e5b83f2a8f07bf9015cf0ff89c11ff37ae0658ab"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "367f76b6ac51646af54fd425e562101e5fb7dc59"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@367f76b6ac51646af54fd425e562101e5fb7dc59",
"title": "367f76b6ac51646af54fd425e562101e5fb7dc59",
"url": "https://chromium.googlesource.com/chromium/src/+/367f76b6ac51646af54fd425e562101e5fb7dc59"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1529266712efe69ae72d704cd5a8d0e304e1d191"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@1529266712efe69ae72d704cd5a8d0e304e1d191",
"title": "1529266712efe69ae72d704cd5a8d0e304e1d191",
"url": "https://chromium.googlesource.com/chromium/src/+/1529266712efe69ae72d704cd5a8d0e304e1d191"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "39d51ead624a9bfad6010e1a7f0a908d42a767a9"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@39d51ead624a9bfad6010e1a7f0a908d42a767a9",
"title": "39d51ead624a9bfad6010e1a7f0a908d42a767a9",
"url": "https://chromium.googlesource.com/chromium/src/+/39d51ead624a9bfad6010e1a7f0a908d42a767a9"
},
{
"gitiles": {
"ref": "refs/branch-heads/7049",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8956e7178cb2bfe844e4e4f69d4c0623f69259a6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7049@8956e7178cb2bfe844e4e4f69d4c0623f69259a6",
"title": "8956e7178cb2bfe844e4e4f69d4c0623f69259a6",
"url": "https://chromium.googlesource.com/chromium/src/+/8956e7178cb2bfe844e4e4f69d4c0623f69259a6"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8956e7178cb2bfe844e4e4f69d4c0623f69259a6",
"ref": "refs/branch-heads/7049"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8886520471561758960"
},
{
"key": "scheduler_job_id",
"value": "chromium-m135/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:11:27.889] Scheduled build:
{
"id": "8721090409728191745",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"number": 21,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-07T03:11:27.393206657Z",
"updateTime": "2025-03-07T03:11:27.393206657Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8956e7178cb2bfe844e4e4f69d4c0623f69259a6",
"ref": "refs/branch-heads/7049"
}
}
}
[03:11:27.890] Task URL: https://cr-buildbucket.appspot.com/build/8721090409728191745
[03:11:27.890] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:2:0) after 2m40s
[03:11:28.857] Received PubSub notification, asking Buildbucket for the build status
[03:11:28.876] Build status: SCHEDULED
[03:11:33.868] Received PubSub notification, asking Buildbucket for the build status
[03:11:33.888] Build status: STARTED
[03:14:07.908] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:2:0)
[03:14:07.938] Build status: STARTED
[03:14:07.938] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:5:0) after 8m13s
[03:22:20.955] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:5:0)
[03:22:21.038] Build status: STARTED
[03:22:21.038] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:6:0) after 5m38s
[03:27:59.083] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:6:0)
[03:27:59.124] Build status: STARTED
[03:27:59.124] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:7:0) after 5m54s
[03:33:53.148] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:7:0)
[03:33:53.169] Build status: STARTED
[03:33:53.169] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:8:0) after 1m30s
[03:35:23.194] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:8:0)
[03:35:23.227] Build status: STARTED
[03:35:23.227] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:9:0) after 4m25s
[03:39:48.250] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:9:0)
[03:39:48.320] Build status: STARTED
[03:39:48.320] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:10:0) after 7m56s
[03:47:45.280] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:10:0)
[03:47:45.294] Build status: STARTED
[03:47:45.294] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:11:0) after 6m34s
[03:54:19.317] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:11:0)
[03:54:19.350] Build status: STARTED
[03:54:19.350] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:12:0) after 5m53s
[04:00:12.372] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:12:0)
[04:00:12.392] Build status: STARTED
[04:00:12.392] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:13:0) after 4m6s
[04:04:18.871] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:13:0)
[04:04:18.928] Build status: STARTED
[04:04:18.928] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:14:0) after 2m47s
[04:07:06.134] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:14:0)
[04:07:06.160] Build status: STARTED
[04:07:06.160] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:15:0) after 9m52s
[04:16:58.294] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:15:0)
[04:16:58.359] Build status: STARTED
[04:16:58.360] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:16:0) after 1m32s
[04:18:30.385] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:16:0)
[04:18:30.399] Build status: STARTED
[04:18:30.399] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:17:0) after 9m37s
[04:28:07.551] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:17:0)
[04:28:07.578] Build status: STARTED
[04:28:07.579] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:18:0) after 3m32s
[04:31:39.868] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:18:0)
[04:31:39.885] Build status: STARTED
[04:31:39.885] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:19:0) after 1m49s
[04:33:28.912] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:19:0)
[04:33:28.928] Build status: STARTED
[04:33:28.929] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:20:0) after 9m27s
[04:42:55.954] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:20:0)
[04:42:55.972] Build status: STARTED
[04:42:55.972] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:21:0) after 5m12s
[04:48:07.997] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:21:0)
[04:48:08.014] Build status: STARTED
[04:48:08.014] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:22:0) after 8m50s
[04:56:58.138] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:22:0)
[04:56:58.254] Build status: STARTED
[04:56:58.254] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:23:0) after 3m10s
[05:00:08.296] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:23:0)
[05:00:08.311] Build status: STARTED
[05:00:08.311] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:24:0) after 5m41s
[05:05:49.335] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:24:0)
[05:05:49.352] Build status: STARTED
[05:05:49.352] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:25:0) after 6m34s
[05:12:23.373] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:25:0)
[05:12:23.391] Build status: STARTED
[05:12:23.391] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:26:0) after 7m7s
[05:19:30.414] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:26:0)
[05:19:30.437] Build status: STARTED
[05:19:30.438] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:27:0) after 3m11s
[05:22:41.458] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:27:0)
[05:22:41.474] Build status: STARTED
[05:22:41.474] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:28:0) after 8m39s
[05:31:20.492] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:28:0)
[05:31:20.517] Build status: STARTED
[05:31:20.517] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:29:0) after 2m56s
[05:34:17.738] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:29:0)
[05:34:17.757] Build status: STARTED
[05:34:17.757] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:30:0) after 7m16s
[05:41:34.091] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:30:0)
[05:41:34.107] Build status: STARTED
[05:41:34.107] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:31:0) after 5m22s
[05:46:57.039] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:31:0)
[05:46:57.098] Build status: STARTED
[05:46:57.098] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:32:0) after 7m23s
[05:54:20.122] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:32:0)
[05:54:20.144] Build status: STARTED
[05:54:20.144] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:33:0) after 4m27s
[05:58:47.166] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:33:0)
[05:58:47.186] Build status: STARTED
[05:58:47.186] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:34:0) after 3m51s
[06:02:38.205] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:34:0)
[06:02:38.229] Build status: STARTED
[06:02:38.230] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:35:0) after 3m35s
[06:06:13.727] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:35:0)
[06:06:13.762] Build status: STARTED
[06:06:13.762] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:36:0) after 3m31s
[06:09:44.758] Handling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:36:0)
[06:09:44.775] Build status: STARTED
[06:09:44.775] Scheduling timer "check-buildbucket-build-status" (chromium-m135/win-official:8886520471561758960:37:0) after 6m58s
[06:14:31.398] Received PubSub notification, asking Buildbucket for the build status
[06:14:31.414] Build:
{
"id": "8721090409728191745",
"builder": {
"project": "chromium-m135",
"bucket": "ci",
"builder": "win-official"
},
"number": 21,
"createdBy": "project:chromium-m135",
"createTime": "2025-03-07T03:11:27.393206657Z",
"startTime": "2025-03-07T03:11:33.681388974Z",
"endTime": "2025-03-07T06:14:29.738111961Z",
"updateTime": "2025-03-07T06:14:29.738111961Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8956e7178cb2bfe844e4e4f69d4c0623f69259a6",
"ref": "refs/branch-heads/7049"
}
}
}
[06:14:31.414] Invocation finished in 3h3m5.153460163s with status SUCCEEDED