[01:36:42.166] New invocation is queued and will start shortly
[01:36:43.275] Starting the invocation (attempt 1)
[01:36:43.306] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/95c05e056c1f1688c2391e11072d3f1c62c3ae69"
[01:36:43.306] Popped gitiles commit info from properties and tags
[01:36:43.306] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:36:43.306] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:36:43.306] Buildbucket request:
{
"requestId": "8901384334305823456",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901384334305823456",
"job": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "2f2820c2060f611438ce356018f395b2628837ae"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@2f2820c2060f611438ce356018f395b2628837ae",
"title": "2f2820c2060f611438ce356018f395b2628837ae",
"url": "https://fuchsia.googlesource.com/fuchsia/+/2f2820c2060f611438ce356018f395b2628837ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8946925651aef767f956edcbd967a08eac457e0b"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8946925651aef767f956edcbd967a08eac457e0b",
"title": "8946925651aef767f956edcbd967a08eac457e0b",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8946925651aef767f956edcbd967a08eac457e0b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "f4e05e08cf0ea321eedf72221291d8526437d2cc"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@f4e05e08cf0ea321eedf72221291d8526437d2cc",
"title": "f4e05e08cf0ea321eedf72221291d8526437d2cc",
"url": "https://fuchsia.googlesource.com/fuchsia/+/f4e05e08cf0ea321eedf72221291d8526437d2cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7b811f950d81001514ee309ece9852872f54378a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7b811f950d81001514ee309ece9852872f54378a",
"title": "7b811f950d81001514ee309ece9852872f54378a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7b811f950d81001514ee309ece9852872f54378a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "534ba74a22b23c67c3c0841e233f4aeecc122113"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@534ba74a22b23c67c3c0841e233f4aeecc122113",
"title": "534ba74a22b23c67c3c0841e233f4aeecc122113",
"url": "https://fuchsia.googlesource.com/fuchsia/+/534ba74a22b23c67c3c0841e233f4aeecc122113"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "3527ac810debef6eb320e907206a803ccee38597"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@3527ac810debef6eb320e907206a803ccee38597",
"title": "3527ac810debef6eb320e907206a803ccee38597",
"url": "https://fuchsia.googlesource.com/fuchsia/+/3527ac810debef6eb320e907206a803ccee38597"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6298f1d3c8a25a609a9f862ea2ea4368f6d6429c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6298f1d3c8a25a609a9f862ea2ea4368f6d6429c",
"title": "6298f1d3c8a25a609a9f862ea2ea4368f6d6429c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6298f1d3c8a25a609a9f862ea2ea4368f6d6429c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ec04eb36fdb67474a2f9f4c9653aacce2e5b25b6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ec04eb36fdb67474a2f9f4c9653aacce2e5b25b6",
"title": "ec04eb36fdb67474a2f9f4c9653aacce2e5b25b6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ec04eb36fdb67474a2f9f4c9653aacce2e5b25b6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "9e8e5c7f6daee64ff06f404701282e8085be0108"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@9e8e5c7f6daee64ff06f404701282e8085be0108",
"title": "9e8e5c7f6daee64ff06f404701282e8085be0108",
"url": "https://fuchsia.googlesource.com/fuchsia/+/9e8e5c7f6daee64ff06f404701282e8085be0108"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5f99d87209d792ff499d7394ab510768ba3e5903"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5f99d87209d792ff499d7394ab510768ba3e5903",
"title": "5f99d87209d792ff499d7394ab510768ba3e5903",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5f99d87209d792ff499d7394ab510768ba3e5903"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fda175003cdeed2e1bdce25187de9e90d4ce1caa"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fda175003cdeed2e1bdce25187de9e90d4ce1caa",
"title": "fda175003cdeed2e1bdce25187de9e90d4ce1caa",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fda175003cdeed2e1bdce25187de9e90d4ce1caa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "7d30029fa4b8b0560c457954923ca14d686b1e9a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@7d30029fa4b8b0560c457954923ca14d686b1e9a",
"title": "7d30029fa4b8b0560c457954923ca14d686b1e9a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/7d30029fa4b8b0560c457954923ca14d686b1e9a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "95c05e056c1f1688c2391e11072d3f1c62c3ae69"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@95c05e056c1f1688c2391e11072d3f1c62c3ae69",
"title": "95c05e056c1f1688c2391e11072d3f1c62c3ae69",
"url": "https://fuchsia.googlesource.com/fuchsia/+/95c05e056c1f1688c2391e11072d3f1c62c3ae69"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "95c05e056c1f1688c2391e11072d3f1c62c3ae69",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901384334305823456"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-profile-qemu_kvm-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:36:43.836] Scheduled build:
{
"id": "8735954272315860689",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-24T01:36:43.400705641Z",
"updateTime": "2024-09-24T01:36:43.400705641Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "95c05e056c1f1688c2391e11072d3f1c62c3ae69",
"ref": "refs/heads/main"
}
}
}
[01:36:43.836] Task URL: https://cr-buildbucket.appspot.com/build/8735954272315860689
[01:36:43.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:2:0) after 9m56s
[01:36:44.754] Received PubSub notification, asking Buildbucket for the build status
[01:36:44.779] Build status: SCHEDULED
[01:36:49.173] Received PubSub notification, asking Buildbucket for the build status
[01:36:49.194] Build status: STARTED
[01:46:39.855] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:2:0)
[01:46:39.885] Build status: STARTED
[01:46:39.885] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:5:0) after 8m20s
[01:54:59.901] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:5:0)
[01:54:59.921] Build status: STARTED
[01:54:59.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:6:0) after 1m10s
[01:56:09.969] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:6:0)
[01:56:09.991] Build status: STARTED
[01:56:09.991] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:7:0) after 7m48s
[02:03:58.015] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:7:0)
[02:03:58.046] Build status: STARTED
[02:03:58.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:8:0) after 8m40s
[02:12:38.064] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:8:0)
[02:12:38.131] Build status: STARTED
[02:12:38.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:9:0) after 4m47s
[02:17:25.301] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:9:0)
[02:17:25.318] Build status: STARTED
[02:17:25.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:10:0) after 8m20s
[02:25:45.335] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:10:0)
[02:25:45.367] Build status: STARTED
[02:25:45.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:11:0) after 5m39s
[02:31:24.391] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:11:0)
[02:31:24.411] Build status: STARTED
[02:31:24.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:12:0) after 9m28s
[02:40:52.521] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:12:0)
[02:40:52.543] Build status: STARTED
[02:40:52.543] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:13:0) after 6m33s
[02:47:25.940] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:13:0)
[02:47:25.960] Build status: STARTED
[02:47:25.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:14:0) after 4m4s
[02:51:29.947] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:14:0)
[02:51:29.976] Build status: STARTED
[02:51:29.976] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:15:0) after 9m21s
[03:00:51.146] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:15:0)
[03:00:51.166] Build status: STARTED
[03:00:51.166] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:16:0) after 2m54s
[03:03:45.189] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:16:0)
[03:03:45.210] Build status: STARTED
[03:03:45.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:17:0) after 7m25s
[03:11:10.691] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:17:0)
[03:11:10.711] Build status: STARTED
[03:11:10.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:18:0) after 1m14s
[03:12:24.838] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:18:0)
[03:12:24.877] Build status: STARTED
[03:12:24.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-profile-qemu_kvm-af244ac6:8901384334305823456:19:0) after 8m32s
[03:14:07.863] Received PubSub notification, asking Buildbucket for the build status
[03:14:07.884] Build:
{
"id": "8735954272315860689",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "core.arm64-profile-qemu_kvm"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-24T01:36:43.400705641Z",
"startTime": "2024-09-24T01:36:48.995976751Z",
"endTime": "2024-09-24T03:14:07.327085733Z",
"updateTime": "2024-09-24T03:14:07.327085733Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "95c05e056c1f1688c2391e11072d3f1c62c3ae69",
"ref": "refs/heads/main"
}
}
}
[03:14:07.884] Invocation finished in 1h37m25.764999663s with status SUCCEEDED