[06:47:10.591] New invocation is queued and will start shortly
[06:47:11.695] Starting the invocation (attempt 1)
[06:47:11.722] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a01a229568157791d9c2d918125ca33a041a585"
[06:47:11.722] Popped gitiles commit info from properties and tags
[06:47:11.722] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:47:11.722] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:47:11.723] Buildbucket request:
{
"requestId": "8882701825869218464",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882701825869218464",
"job": "chromium-m136/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0a4f5ea3cbe3e35de6a6d4814b171bd0b81d70f4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@0a4f5ea3cbe3e35de6a6d4814b171bd0b81d70f4",
"title": "0a4f5ea3cbe3e35de6a6d4814b171bd0b81d70f4",
"url": "https://chromium.googlesource.com/chromium/src/+/0a4f5ea3cbe3e35de6a6d4814b171bd0b81d70f4"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "20f0aab630947ef61a16dfc1d4679c8d3b63c0ec"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@20f0aab630947ef61a16dfc1d4679c8d3b63c0ec",
"title": "20f0aab630947ef61a16dfc1d4679c8d3b63c0ec",
"url": "https://chromium.googlesource.com/chromium/src/+/20f0aab630947ef61a16dfc1d4679c8d3b63c0ec"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8c334001d3da928d91e1f07903657a46f4fa4e03"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@8c334001d3da928d91e1f07903657a46f4fa4e03",
"title": "8c334001d3da928d91e1f07903657a46f4fa4e03",
"url": "https://chromium.googlesource.com/chromium/src/+/8c334001d3da928d91e1f07903657a46f4fa4e03"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "305c87b01107d703ac23c0ffe49874015395223b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@305c87b01107d703ac23c0ffe49874015395223b",
"title": "305c87b01107d703ac23c0ffe49874015395223b",
"url": "https://chromium.googlesource.com/chromium/src/+/305c87b01107d703ac23c0ffe49874015395223b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7103",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6a01a229568157791d9c2d918125ca33a041a585"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7103@6a01a229568157791d9c2d918125ca33a041a585",
"title": "6a01a229568157791d9c2d918125ca33a041a585",
"url": "https://chromium.googlesource.com/chromium/src/+/6a01a229568157791d9c2d918125ca33a041a585"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6a01a229568157791d9c2d918125ca33a041a585",
"ref": "refs/branch-heads/7103"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882701825869218464"
},
{
"key": "scheduler_job_id",
"value": "chromium-m136/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:47:12.411] Scheduled build:
{
"id": "8717271763967373793",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 167,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-18T06:47:11.778567620Z",
"updateTime": "2025-04-18T06:47:11.778567620Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6a01a229568157791d9c2d918125ca33a041a585",
"ref": "refs/branch-heads/7103"
}
}
}
[06:47:12.411] Task URL: https://cr-buildbucket.appspot.com/build/8717271763967373793
[06:47:12.411] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:2:0) after 8m50s
[06:47:13.413] Received PubSub notification, asking Buildbucket for the build status
[06:47:13.434] Build status: SCHEDULED
[06:56:02.432] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:2:0)
[06:56:02.509] Build status: SCHEDULED
[06:56:02.509] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:4:0) after 5m46s
[07:01:48.528] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:4:0)
[07:01:48.587] Build status: SCHEDULED
[07:01:48.588] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:5:0) after 3m33s
[07:05:21.614] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:5:0)
[07:05:21.637] Build status: SCHEDULED
[07:05:21.637] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:6:0) after 4m27s
[07:09:48.671] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:6:0)
[07:09:48.694] Build status: SCHEDULED
[07:09:48.694] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:7:0) after 8m38s
[07:18:26.706] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:7:0)
[07:18:26.755] Build status: SCHEDULED
[07:18:26.755] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:8:0) after 8m42s
[07:27:08.832] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:8:0)
[07:27:08.884] Build status: SCHEDULED
[07:27:08.884] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:9:0) after 1m59s
[07:28:17.214] Received PubSub notification, asking Buildbucket for the build status
[07:28:17.237] Build status: STARTED
[07:29:07.951] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:9:0)
[07:29:07.974] Build status: STARTED
[07:29:07.974] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:11:0) after 5m2s
[07:34:10.051] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:11:0)
[07:34:10.079] Build status: STARTED
[07:34:10.079] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:12:0) after 3m59s
[07:38:09.068] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:12:0)
[07:38:09.094] Build status: STARTED
[07:38:09.094] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:13:0) after 5m31s
[07:43:40.182] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:13:0)
[07:43:40.205] Build status: STARTED
[07:43:40.205] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:14:0) after 8m52s
[07:52:32.226] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:14:0)
[07:52:32.282] Build status: STARTED
[07:52:32.282] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:15:0) after 3m3s
[07:55:35.536] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:15:0)
[07:55:35.555] Build status: STARTED
[07:55:35.555] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:16:0) after 3m8s
[07:58:44.198] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:16:0)
[07:58:44.283] Build status: STARTED
[07:58:44.283] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:17:0) after 5m46s
[08:04:30.308] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:17:0)
[08:04:30.330] Build status: STARTED
[08:04:30.330] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:18:0) after 7m29s
[08:11:59.394] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:18:0)
[08:11:59.493] Build status: STARTED
[08:11:59.493] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:19:0) after 4m19s
[08:16:18.703] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:19:0)
[08:16:18.786] Build status: STARTED
[08:16:18.786] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:20:0) after 7m12s
[08:23:30.803] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:20:0)
[08:23:30.873] Build status: STARTED
[08:23:30.873] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:21:0) after 8m59s
[08:32:29.994] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:21:0)
[08:32:30.019] Build status: STARTED
[08:32:30.019] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:22:0) after 4m19s
[08:36:49.030] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:22:0)
[08:36:49.080] Build status: STARTED
[08:36:49.080] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:23:0) after 4m19s
[08:41:08.082] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:23:0)
[08:41:08.106] Build status: STARTED
[08:41:08.106] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:24:0) after 7m56s
[08:49:04.339] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:24:0)
[08:49:04.359] Build status: STARTED
[08:49:04.359] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:25:0) after 4m56s
[08:54:00.379] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:25:0)
[08:54:00.396] Build status: STARTED
[08:54:00.396] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:26:0) after 6m4s
[09:00:04.496] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:26:0)
[09:00:04.513] Build status: STARTED
[09:00:04.513] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:27:0) after 5m57s
[09:06:01.580] Handling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:27:0)
[09:06:01.598] Build status: STARTED
[09:06:01.598] Scheduling timer "check-buildbucket-build-status" (chromium-m136/fuchsia-x64-cast-receiver-dbg:8882701825869218464:28:0) after 7m14s
[09:07:46.294] Received PubSub notification, asking Buildbucket for the build status
[09:07:46.319] Build:
{
"id": "8717271763967373793",
"builder": {
"project": "chromium-m136",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 167,
"createdBy": "project:chromium-m136",
"createTime": "2025-04-18T06:47:11.778567620Z",
"startTime": "2025-04-18T07:28:17.043221099Z",
"endTime": "2025-04-18T09:07:45.366198653Z",
"updateTime": "2025-04-18T09:07:45.366198653Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6a01a229568157791d9c2d918125ca33a041a585",
"ref": "refs/branch-heads/7103"
}
}
}
[09:07:46.319] Invocation finished in 2h20m35.737886921s with status SUCCEEDED