[16:11:34.712] New invocation is queued and will start shortly
[16:11:36.158] Starting the invocation (attempt 1)
[16:11:36.191] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9946315c2dffba077c097a0c6245b86abb16404"
[16:11:36.191] Popped gitiles commit info from properties and tags
[16:11:36.191] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:11:36.191] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:11:36.191] Buildbucket request:
{
"requestId": "8899517352517291136",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899517352517291136",
"job": "chromium-m130/fuchsia-x64-cast-receiver-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "821d41202c56c55a79bddde33f778b9dbb511982"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@821d41202c56c55a79bddde33f778b9dbb511982",
"title": "821d41202c56c55a79bddde33f778b9dbb511982",
"url": "https://chromium.googlesource.com/chromium/src/+/821d41202c56c55a79bddde33f778b9dbb511982"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "769c50bc238f7b7e5aa39d89f37c921b43a56c5d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@769c50bc238f7b7e5aa39d89f37c921b43a56c5d",
"title": "769c50bc238f7b7e5aa39d89f37c921b43a56c5d",
"url": "https://chromium.googlesource.com/chromium/src/+/769c50bc238f7b7e5aa39d89f37c921b43a56c5d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "204e675450c60fcc0bef67dcbbd2fcf3199011e3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@204e675450c60fcc0bef67dcbbd2fcf3199011e3",
"title": "204e675450c60fcc0bef67dcbbd2fcf3199011e3",
"url": "https://chromium.googlesource.com/chromium/src/+/204e675450c60fcc0bef67dcbbd2fcf3199011e3"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b9946315c2dffba077c097a0c6245b86abb16404"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@b9946315c2dffba077c097a0c6245b86abb16404",
"title": "b9946315c2dffba077c097a0c6245b86abb16404",
"url": "https://chromium.googlesource.com/chromium/src/+/b9946315c2dffba077c097a0c6245b86abb16404"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b9946315c2dffba077c097a0c6245b86abb16404",
"ref": "refs/branch-heads/6723"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899517352517291136"
},
{
"key": "scheduler_job_id",
"value": "chromium-m130/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:11:36.769] Scheduled build:
{
"id": "8734087290236282145",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 230,
"createdBy": "project:chromium-m130",
"createTime": "2024-10-14T16:11:36.259971716Z",
"updateTime": "2024-10-14T16:11:36.259971716Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b9946315c2dffba077c097a0c6245b86abb16404",
"ref": "refs/branch-heads/6723"
}
}
}
[16:11:36.769] Task URL: https://cr-buildbucket.appspot.com/build/8734087290236282145
[16:11:36.769] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:2:0) after 9m31s
[16:11:37.822] Received PubSub notification, asking Buildbucket for the build status
[16:11:37.859] Build status: SCHEDULED
[16:21:08.032] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:2:0)
[16:21:08.058] Build status: SCHEDULED
[16:21:08.058] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:4:0) after 2m4s
[16:23:12.191] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:4:0)
[16:23:12.228] Build status: SCHEDULED
[16:23:12.228] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:5:0) after 6m45s
[16:29:57.492] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:5:0)
[16:29:57.519] Build status: SCHEDULED
[16:29:57.519] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:6:0) after 4m38s
[16:34:35.536] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:6:0)
[16:34:35.599] Build status: SCHEDULED
[16:34:35.599] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:7:0) after 2m52s
[16:37:27.617] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:7:0)
[16:37:27.636] Build status: SCHEDULED
[16:37:27.636] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:8:0) after 5m4s
[16:42:31.982] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:8:0)
[16:42:31.999] Build status: SCHEDULED
[16:42:31.999] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:9:0) after 3m55s
[16:46:27.233] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:9:0)
[16:46:27.259] Build status: SCHEDULED
[16:46:27.259] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:10:0) after 9m32s
[16:55:59.283] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:10:0)
[16:55:59.316] Build status: SCHEDULED
[16:55:59.316] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:11:0) after 1m42s
[16:57:41.330] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:11:0)
[16:57:41.345] Build status: SCHEDULED
[16:57:41.345] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:12:0) after 1m54s
[16:59:35.364] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:12:0)
[16:59:35.380] Build status: SCHEDULED
[16:59:35.380] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:13:0) after 9m28s
[17:09:03.405] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:13:0)
[17:09:03.427] Build status: SCHEDULED
[17:09:03.427] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:14:0) after 5m38s
[17:14:41.445] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:14:0)
[17:14:41.463] Build status: SCHEDULED
[17:14:41.463] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:15:0) after 8m22s
[17:23:03.527] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:15:0)
[17:23:03.559] Build status: SCHEDULED
[17:23:03.559] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:16:0) after 8m57s
[17:32:00.580] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:16:0)
[17:32:00.602] Build status: SCHEDULED
[17:32:00.602] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:17:0) after 5m30s
[17:37:30.620] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:17:0)
[17:37:30.637] Build status: SCHEDULED
[17:37:30.637] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:18:0) after 3m24s
[17:40:54.674] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:18:0)
[17:40:54.709] Build status: SCHEDULED
[17:40:54.709] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:19:0) after 7m51s
[17:48:45.729] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:19:0)
[17:48:45.752] Build status: SCHEDULED
[17:48:45.752] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:20:0) after 6m55s
[17:55:40.770] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:20:0)
[17:55:40.789] Build status: SCHEDULED
[17:55:40.789] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:21:0) after 9m28s
[18:05:08.810] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:21:0)
[18:05:08.829] Build status: SCHEDULED
[18:05:08.829] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:22:0) after 9m19s
[18:05:56.410] Received PubSub notification, asking Buildbucket for the build status
[18:05:56.552] Build status: STARTED
[18:14:27.849] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:22:0)
[18:14:27.875] Build status: STARTED
[18:14:27.875] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:24:0) after 2m20s
[18:16:47.864] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:24:0)
[18:16:47.885] Build status: STARTED
[18:16:47.886] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:25:0) after 7m59s
[18:24:46.910] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:25:0)
[18:24:46.927] Build status: STARTED
[18:24:46.927] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:26:0) after 4m47s
[18:29:33.947] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:26:0)
[18:29:34.019] Build status: STARTED
[18:29:34.019] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:27:0) after 6m58s
[18:36:32.039] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:27:0)
[18:36:32.054] Build status: STARTED
[18:36:32.054] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:28:0) after 3m25s
[18:39:57.075] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:28:0)
[18:39:57.100] Build status: STARTED
[18:39:57.100] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:29:0) after 6m32s
[18:46:29.124] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:29:0)
[18:46:29.140] Build status: STARTED
[18:46:29.140] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:30:0) after 8m20s
[18:54:49.131] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:30:0)
[18:54:49.149] Build status: STARTED
[18:54:49.149] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:31:0) after 4m22s
[18:59:11.142] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:31:0)
[18:59:11.162] Build status: STARTED
[18:59:11.162] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:32:0) after 5m57s
[19:05:08.263] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:32:0)
[19:05:08.283] Build status: STARTED
[19:05:08.283] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:33:0) after 7m2s
[19:12:10.333] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:33:0)
[19:12:10.349] Build status: STARTED
[19:12:10.349] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:34:0) after 7m49s
[19:19:59.469] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:34:0)
[19:19:59.504] Build status: STARTED
[19:19:59.505] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:35:0) after 3m14s
[19:23:13.525] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:35:0)
[19:23:13.541] Build status: STARTED
[19:23:13.541] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:36:0) after 8m50s
[19:32:03.560] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:36:0)
[19:32:03.585] Build status: STARTED
[19:32:03.585] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:37:0) after 9m27s
[19:41:30.633] Handling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:37:0)
[19:41:30.661] Build status: STARTED
[19:41:30.661] Scheduling timer "check-buildbucket-build-status" (chromium-m130/fuchsia-x64-cast-receiver-dbg:8899517352517291136:38:0) after 9m4s
[19:48:08.591] Received PubSub notification, asking Buildbucket for the build status
[19:48:08.608] Build:
{
"id": "8734087290236282145",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 230,
"createdBy": "project:chromium-m130",
"createTime": "2024-10-14T16:11:36.259971716Z",
"startTime": "2024-10-14T18:05:56.223896874Z",
"endTime": "2024-10-14T19:48:07.868185432Z",
"updateTime": "2024-10-14T19:48:07.868185432Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b9946315c2dffba077c097a0c6245b86abb16404",
"ref": "refs/branch-heads/6723"
}
}
}
[19:48:08.608] Invocation finished in 3h36m33.907932173s with status SUCCEEDED