[20:25:54.073] New invocation is queued and will start shortly
[20:25:55.158] Starting the invocation (attempt 1)
[20:25:55.188] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/94924dee1c6d4d03a28374cdf9affd987df7deb9"
[20:25:55.188] Popped gitiles commit info from properties and tags
[20:25:55.188] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:25:55.188] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:25:55.188] Buildbucket request:
{
"requestId": "8897327024720706176",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8897327024720706176",
"job": "fuchsia/core.arm64-thinlto-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "92eef67e1a7c748a164bff18046eb2b56c150061"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@92eef67e1a7c748a164bff18046eb2b56c150061",
"title": "92eef67e1a7c748a164bff18046eb2b56c150061",
"url": "https://fuchsia.googlesource.com/integration/+/92eef67e1a7c748a164bff18046eb2b56c150061"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fbff632bfb572cd7b979459db54bab5277226045"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fbff632bfb572cd7b979459db54bab5277226045",
"title": "fbff632bfb572cd7b979459db54bab5277226045",
"url": "https://fuchsia.googlesource.com/integration/+/fbff632bfb572cd7b979459db54bab5277226045"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5fe3ebcdb5356647b84f77ea3457c49857c65cb8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5fe3ebcdb5356647b84f77ea3457c49857c65cb8",
"title": "5fe3ebcdb5356647b84f77ea3457c49857c65cb8",
"url": "https://fuchsia.googlesource.com/integration/+/5fe3ebcdb5356647b84f77ea3457c49857c65cb8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9bbe67b79f7c12830e7f1ef16e742677deae2b48"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9bbe67b79f7c12830e7f1ef16e742677deae2b48",
"title": "9bbe67b79f7c12830e7f1ef16e742677deae2b48",
"url": "https://fuchsia.googlesource.com/integration/+/9bbe67b79f7c12830e7f1ef16e742677deae2b48"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "182c7488e78917fe3d55cfbaa4bb9861157eae35"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@182c7488e78917fe3d55cfbaa4bb9861157eae35",
"title": "182c7488e78917fe3d55cfbaa4bb9861157eae35",
"url": "https://fuchsia.googlesource.com/integration/+/182c7488e78917fe3d55cfbaa4bb9861157eae35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9d00f1a55f7e4ee086f50e57a3c1ab385a11df86"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9d00f1a55f7e4ee086f50e57a3c1ab385a11df86",
"title": "9d00f1a55f7e4ee086f50e57a3c1ab385a11df86",
"url": "https://fuchsia.googlesource.com/integration/+/9d00f1a55f7e4ee086f50e57a3c1ab385a11df86"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "505e70860310fdf9b80a250a09a7c2336cd9ed64"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@505e70860310fdf9b80a250a09a7c2336cd9ed64",
"title": "505e70860310fdf9b80a250a09a7c2336cd9ed64",
"url": "https://fuchsia.googlesource.com/integration/+/505e70860310fdf9b80a250a09a7c2336cd9ed64"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f6b5a6fe242905e2fae56a1ae43a2a2d943a3582"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f6b5a6fe242905e2fae56a1ae43a2a2d943a3582",
"title": "f6b5a6fe242905e2fae56a1ae43a2a2d943a3582",
"url": "https://fuchsia.googlesource.com/integration/+/f6b5a6fe242905e2fae56a1ae43a2a2d943a3582"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b9e1bdc5f8294876562372f205abb371e2424185"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b9e1bdc5f8294876562372f205abb371e2424185",
"title": "b9e1bdc5f8294876562372f205abb371e2424185",
"url": "https://fuchsia.googlesource.com/integration/+/b9e1bdc5f8294876562372f205abb371e2424185"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "719cd2b855f618b2d1c9be3da16941e061504d4f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@719cd2b855f618b2d1c9be3da16941e061504d4f",
"title": "719cd2b855f618b2d1c9be3da16941e061504d4f",
"url": "https://fuchsia.googlesource.com/integration/+/719cd2b855f618b2d1c9be3da16941e061504d4f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "413967d2e09404fd094b1063e825a20dbe68fb55"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@413967d2e09404fd094b1063e825a20dbe68fb55",
"title": "413967d2e09404fd094b1063e825a20dbe68fb55",
"url": "https://fuchsia.googlesource.com/integration/+/413967d2e09404fd094b1063e825a20dbe68fb55"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "94924dee1c6d4d03a28374cdf9affd987df7deb9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@94924dee1c6d4d03a28374cdf9affd987df7deb9",
"title": "94924dee1c6d4d03a28374cdf9affd987df7deb9",
"url": "https://fuchsia.googlesource.com/integration/+/94924dee1c6d4d03a28374cdf9affd987df7deb9"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "94924dee1c6d4d03a28374cdf9affd987df7deb9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897327024720706176"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-thinlto-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:25:55.598] Scheduled build:
{
"id": "8731896962820268193",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-07T20:25:55.260311197Z",
"updateTime": "2024-11-07T20:25:55.260311197Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "94924dee1c6d4d03a28374cdf9affd987df7deb9",
"ref": "refs/heads/main"
}
}
}
[20:25:55.598] Task URL: https://cr-buildbucket.appspot.com/build/8731896962820268193
[20:25:55.598] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:2:0) after 1m7s
[20:25:58.248] Received PubSub notification, asking Buildbucket for the build status
[20:25:58.278] Build status: SCHEDULED
[20:26:02.713] Received PubSub notification, asking Buildbucket for the build status
[20:26:02.736] Build status: STARTED
[20:27:02.627] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:2:0)
[20:27:02.712] Build status: STARTED
[20:27:02.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:5:0) after 5m4s
[20:32:06.744] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:5:0)
[20:32:06.764] Build status: STARTED
[20:32:06.764] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:6:0) after 5m35s
[20:37:41.805] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:6:0)
[20:37:41.826] Build status: STARTED
[20:37:41.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:7:0) after 1m13s
[20:38:54.878] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:7:0)
[20:38:54.909] Build status: STARTED
[20:38:54.909] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:8:0) after 7m44s
[20:46:38.953] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:8:0)
[20:46:39.247] Build status: STARTED
[20:46:39.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:9:0) after 2m0s
[20:48:39.270] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:9:0)
[20:48:39.288] Build status: STARTED
[20:48:39.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:10:0) after 2m43s
[20:51:22.309] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:10:0)
[20:51:22.327] Build status: STARTED
[20:51:22.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:11:0) after 3m57s
[20:55:19.395] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:11:0)
[20:55:19.443] Build status: STARTED
[20:55:19.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:12:0) after 6m38s
[21:01:57.439] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:12:0)
[21:01:57.456] Build status: STARTED
[21:01:57.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:13:0) after 2m37s
[21:04:34.473] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:13:0)
[21:04:34.636] Build status: STARTED
[21:04:34.636] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:14:0) after 2m12s
[21:06:46.657] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:14:0)
[21:06:46.672] Build status: STARTED
[21:06:46.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:15:0) after 7m58s
[21:14:44.698] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:15:0)
[21:14:44.798] Build status: STARTED
[21:14:44.798] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:16:0) after 9m15s
[21:23:59.842] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:16:0)
[21:23:59.864] Build status: STARTED
[21:23:59.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:17:0) after 3m37s
[21:27:36.867] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:17:0)
[21:27:36.953] Build status: STARTED
[21:27:36.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:18:0) after 4m17s
[21:31:53.956] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:18:0)
[21:31:53.978] Build status: STARTED
[21:31:53.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:19:0) after 1m7s
[21:33:01.028] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:19:0)
[21:33:01.076] Build status: STARTED
[21:33:01.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:20:0) after 9m47s
[21:42:48.098] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:20:0)
[21:42:48.129] Build status: STARTED
[21:42:48.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:21:0) after 2m58s
[21:45:46.155] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:21:0)
[21:45:46.177] Build status: STARTED
[21:45:46.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-thinlto-e5a0ec7f:8897327024720706176:22:0) after 3m15s
[21:47:34.171] Received PubSub notification, asking Buildbucket for the build status
[21:47:34.239] Build:
{
"id": "8731896962820268193",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-thinlto"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-07T20:25:55.260311197Z",
"startTime": "2024-11-07T20:26:00.539167019Z",
"endTime": "2024-11-07T21:47:33.628374476Z",
"updateTime": "2024-11-07T21:47:33.628374476Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "94924dee1c6d4d03a28374cdf9affd987df7deb9",
"ref": "refs/heads/main"
}
}
}
[21:47:34.239] Invocation finished in 1h21m40.175480633s with status SUCCEEDED