[00:36:30.538] New invocation is queued and will start shortly
[00:36:31.631] Starting the invocation (attempt 1)
[00:36:31.665] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/c3cbf2a46110209bd28cf20771e1a8dc90f463c6"
[00:36:31.665] Popped gitiles commit info from properties and tags
[00:36:31.665] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:36:31.665] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:36:31.665] Buildbucket request:
{
"requestId": "8888704546036993904",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888704546036993904",
"job": "fuchsia/fuchsia-profile-core.x64-release-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "82fa8c6bf8a89833cb4a7f3a4531ca3576c832dd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@82fa8c6bf8a89833cb4a7f3a4531ca3576c832dd",
"title": "82fa8c6bf8a89833cb4a7f3a4531ca3576c832dd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/82fa8c6bf8a89833cb4a7f3a4531ca3576c832dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "07a5ba829e2f8f41e6e13c127e19d77618eab655"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@07a5ba829e2f8f41e6e13c127e19d77618eab655",
"title": "07a5ba829e2f8f41e6e13c127e19d77618eab655",
"url": "https://fuchsia.googlesource.com/fuchsia/+/07a5ba829e2f8f41e6e13c127e19d77618eab655"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2e0cb929c6ff98096e3060c4d5d25bf9ba3ed820"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2e0cb929c6ff98096e3060c4d5d25bf9ba3ed820",
"title": "2e0cb929c6ff98096e3060c4d5d25bf9ba3ed820",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2e0cb929c6ff98096e3060c4d5d25bf9ba3ed820"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bb4b95c02e43879f2b406d72535391c552e1ea6f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bb4b95c02e43879f2b406d72535391c552e1ea6f",
"title": "bb4b95c02e43879f2b406d72535391c552e1ea6f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bb4b95c02e43879f2b406d72535391c552e1ea6f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4c448a5077aa530da1567eb88dadd00a75f1a4e6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4c448a5077aa530da1567eb88dadd00a75f1a4e6",
"title": "4c448a5077aa530da1567eb88dadd00a75f1a4e6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4c448a5077aa530da1567eb88dadd00a75f1a4e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f5ccae8c20c58867587bef039d0c008480d3159d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f5ccae8c20c58867587bef039d0c008480d3159d",
"title": "f5ccae8c20c58867587bef039d0c008480d3159d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f5ccae8c20c58867587bef039d0c008480d3159d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "428bdd0948d94139ee09a8980f58e8f5264b6be4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@428bdd0948d94139ee09a8980f58e8f5264b6be4",
"title": "428bdd0948d94139ee09a8980f58e8f5264b6be4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/428bdd0948d94139ee09a8980f58e8f5264b6be4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e26c9bc923f25c395f3a50295e84c91d562539f3"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e26c9bc923f25c395f3a50295e84c91d562539f3",
"title": "e26c9bc923f25c395f3a50295e84c91d562539f3",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e26c9bc923f25c395f3a50295e84c91d562539f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5387b6a254030d5930f11bcd552ff887d917a727"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5387b6a254030d5930f11bcd552ff887d917a727",
"title": "5387b6a254030d5930f11bcd552ff887d917a727",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5387b6a254030d5930f11bcd552ff887d917a727"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "93b5b7c6d3e931ecee73f02166f8eb2f58c3b61d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@93b5b7c6d3e931ecee73f02166f8eb2f58c3b61d",
"title": "93b5b7c6d3e931ecee73f02166f8eb2f58c3b61d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/93b5b7c6d3e931ecee73f02166f8eb2f58c3b61d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d1ac34a727deeb842afe54a2fca0f86ddc56262d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d1ac34a727deeb842afe54a2fca0f86ddc56262d",
"title": "d1ac34a727deeb842afe54a2fca0f86ddc56262d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d1ac34a727deeb842afe54a2fca0f86ddc56262d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4c4a9fb299462d4cab2af4659b9824e818f34c41"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4c4a9fb299462d4cab2af4659b9824e818f34c41",
"title": "4c4a9fb299462d4cab2af4659b9824e818f34c41",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4c4a9fb299462d4cab2af4659b9824e818f34c41"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7931b5a20686b918336618e4bfaf83f86ec68587"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7931b5a20686b918336618e4bfaf83f86ec68587",
"title": "7931b5a20686b918336618e4bfaf83f86ec68587",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7931b5a20686b918336618e4bfaf83f86ec68587"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6b2a81700c0526f5bc81b9fe151560a6ca2f66e4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6b2a81700c0526f5bc81b9fe151560a6ca2f66e4",
"title": "6b2a81700c0526f5bc81b9fe151560a6ca2f66e4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6b2a81700c0526f5bc81b9fe151560a6ca2f66e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7834eb14cea6536c6047ba2b539ea31508877291"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7834eb14cea6536c6047ba2b539ea31508877291",
"title": "7834eb14cea6536c6047ba2b539ea31508877291",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7834eb14cea6536c6047ba2b539ea31508877291"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b5c7933bb4277f8fca0604ebc075a3e97c3127ae"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b5c7933bb4277f8fca0604ebc075a3e97c3127ae",
"title": "b5c7933bb4277f8fca0604ebc075a3e97c3127ae",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b5c7933bb4277f8fca0604ebc075a3e97c3127ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2e38d3eb851470671393936ffdc62f5b5214cd7c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2e38d3eb851470671393936ffdc62f5b5214cd7c",
"title": "2e38d3eb851470671393936ffdc62f5b5214cd7c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2e38d3eb851470671393936ffdc62f5b5214cd7c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "c3cbf2a46110209bd28cf20771e1a8dc90f463c6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@c3cbf2a46110209bd28cf20771e1a8dc90f463c6",
"title": "c3cbf2a46110209bd28cf20771e1a8dc90f463c6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/c3cbf2a46110209bd28cf20771e1a8dc90f463c6"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "c3cbf2a46110209bd28cf20771e1a8dc90f463c6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888704546036993904"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/fuchsia-profile-core.x64-release-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:36:32.103] Scheduled build:
{
"id": "8723274484129959409",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-11T00:36:31.731242447Z",
"updateTime": "2025-02-11T00:36:31.731242447Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "c3cbf2a46110209bd28cf20771e1a8dc90f463c6",
"ref": "refs/heads/main"
}
}
}
[00:36:32.103] Task URL: https://cr-buildbucket.appspot.com/build/8723274484129959409
[00:36:32.103] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:2:0) after 3m52s
[00:36:32.976] Received PubSub notification, asking Buildbucket for the build status
[00:36:33.003] Build status: SCHEDULED
[00:37:53.807] Received PubSub notification, asking Buildbucket for the build status
[00:37:53.840] Build status: STARTED
[00:40:24.127] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:2:0)
[00:40:24.223] Build status: STARTED
[00:40:24.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:5:0) after 5m38s
[00:46:02.408] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:5:0)
[00:46:02.432] Build status: STARTED
[00:46:02.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:6:0) after 3m6s
[00:49:08.482] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:6:0)
[00:49:08.509] Build status: STARTED
[00:49:08.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:7:0) after 3m35s
[00:52:43.611] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:7:0)
[00:52:43.630] Build status: STARTED
[00:52:43.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:8:0) after 8m43s
[01:01:26.650] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:8:0)
[01:01:26.673] Build status: STARTED
[01:01:26.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:9:0) after 4m33s
[01:05:59.693] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:9:0)
[01:05:59.721] Build status: STARTED
[01:05:59.721] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:10:0) after 7m25s
[01:13:24.841] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:10:0)
[01:13:24.867] Build status: STARTED
[01:13:24.867] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:11:0) after 3m56s
[01:17:20.853] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:11:0)
[01:17:20.877] Build status: STARTED
[01:17:20.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:12:0) after 4m7s
[01:21:27.907] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:12:0)
[01:21:27.923] Build status: STARTED
[01:21:27.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:13:0) after 7m41s
[01:29:09.000] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:13:0)
[01:29:09.019] Build status: STARTED
[01:29:09.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:14:0) after 1m2s
[01:30:11.044] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:14:0)
[01:30:11.060] Build status: STARTED
[01:30:11.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:15:0) after 7m51s
[01:38:02.229] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:15:0)
[01:38:02.273] Build status: STARTED
[01:38:02.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:16:0) after 8m2s
[01:46:04.302] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:16:0)
[01:46:04.322] Build status: STARTED
[01:46:04.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:17:0) after 2m2s
[01:48:06.346] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:17:0)
[01:48:06.431] Build status: STARTED
[01:48:06.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:18:0) after 3m20s
[01:51:26.450] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:18:0)
[01:51:26.478] Build status: STARTED
[01:51:26.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:19:0) after 2m36s
[01:54:02.538] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:19:0)
[01:54:02.562] Build status: STARTED
[01:54:02.562] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:20:0) after 4m37s
[01:58:39.673] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:20:0)
[01:58:39.740] Build status: STARTED
[01:58:39.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:21:0) after 5m56s
[02:04:35.776] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:21:0)
[02:04:35.797] Build status: STARTED
[02:04:35.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:22:0) after 3m13s
[02:07:48.815] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:22:0)
[02:07:48.832] Build status: STARTED
[02:07:48.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:23:0) after 2m50s
[02:10:38.879] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:23:0)
[02:10:38.894] Build status: STARTED
[02:10:38.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:24:0) after 4m14s
[02:14:52.945] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:24:0)
[02:14:52.960] Build status: STARTED
[02:14:52.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:25:0) after 8m59s
[02:23:52.029] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:25:0)
[02:23:52.060] Build status: STARTED
[02:23:52.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:26:0) after 3m30s
[02:27:22.125] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:26:0)
[02:27:22.146] Build status: STARTED
[02:27:22.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:27:0) after 4m44s
[02:32:06.207] Handling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:27:0)
[02:32:06.234] Build status: STARTED
[02:32:06.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/fuchsia-profile-core.x64-release-af244ac6:8888704546036993904:28:0) after 7m33s
[02:39:27.441] Received PubSub notification, asking Buildbucket for the build status
[02:39:27.455] Build:
{
"id": "8723274484129959409",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "fuchsia-profile-core.x64-release"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-11T00:36:31.731242447Z",
"startTime": "2025-02-11T00:37:53.641199753Z",
"endTime": "2025-02-11T02:39:16.929020481Z",
"updateTime": "2025-02-11T02:39:16.929020481Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "c3cbf2a46110209bd28cf20771e1a8dc90f463c6",
"ref": "refs/heads/main"
}
}
}
[02:39:27.456] Invocation finished in 2h2m56.926718701s with status SUCCEEDED