2026-10-26 18:56:37.569239 | Job console starting
2026-10-26 18:56:37.577647 | Updating git repos
2026-10-26 18:56:37.655971 | Cloning repos into workspace
2026-10-26 18:56:37.727382 | Restoring repo states
2026-10-26 18:56:37.746542 | Merging changes
2026-10-26 18:56:38.681107 | Checking out repos
2026-10-26 18:56:39.004008 | Preparing playbooks
2026-10-26 18:56:41.770794 | Running Ansible setup
2026-10-26 18:56:45.196271 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-10-26 18:56:45.749807 | 
2026-10-26 18:56:45.749910 | PLAY [localhost]
2026-10-26 18:56:45.757131 | 
2026-10-26 18:56:45.757201 | TASK [Gathering Facts]
2026-10-26 18:56:46.674933 | localhost | ok
2026-10-26 18:56:46.687239 | 
2026-10-26 18:56:46.687381 | TASK [Setup log path fact]
2026-10-26 18:56:46.706993 | localhost | ok
2026-10-26 18:56:46.725422 | 
2026-10-26 18:56:46.725549 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-10-26 18:56:46.756290 | localhost | ok
2026-10-26 18:56:46.767055 | 
2026-10-26 18:56:46.767189 | TASK [emit-job-header : Print job information]
2026-10-26 18:56:46.808444 | # Job Information
2026-10-26 18:56:46.808674 | Ansible Version: 2.16.18
2026-10-26 18:56:46.808739 | Job: atmosphere-release-manifest
2026-10-26 18:56:46.808781 | Pipeline: check
2026-10-26 18:56:46.808820 | Executor: 2d72f0692154
2026-10-26 18:56:46.808857 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4348
2026-10-26 18:56:46.808901 | Event ID: 2037eda0-d16e-11f1-856f-35639ea78c6a
2026-10-26 18:56:46.813872 | 
2026-10-26 18:56:46.814010 | LOOP [emit-job-header : Print node information]
2026-10-26 18:56:46.921788 | localhost | ok:
2026-10-26 18:56:46.922050 | localhost | # Node Information
2026-10-26 18:56:46.922123 | localhost | Inventory Hostname: instance
2026-10-26 18:56:46.922209 | localhost | Hostname: np0000196393
2026-10-26 18:56:46.922313 | localhost | Username: zuul
2026-10-26 18:56:46.922372 | localhost | Distro: Ubuntu 22.04
2026-10-26 18:56:46.922417 | localhost | Provider: yul1
2026-10-26 18:56:46.922458 | localhost | Region: ca-ymq-1
2026-10-26 18:56:46.922500 | localhost | Label: ubuntu-jammy
2026-10-26 18:56:46.922541 | localhost | Product Name: OpenStack Nova
2026-10-26 18:56:46.922581 | localhost | Interface IP: 162.253.55.84
2026-10-26 18:56:46.934048 | 
2026-10-26 18:56:46.934229 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-10-26 18:56:47.324585 | localhost -> localhost | changed
2026-10-26 18:56:47.335262 | 
2026-10-26 18:56:47.335398 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-10-26 18:56:48.219907 | localhost -> localhost | changed
2026-10-26 18:56:48.225461 | 
2026-10-26 18:56:48.225526 | PLAY [all]
2026-10-26 18:56:48.232714 | 
2026-10-26 18:56:48.232814 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-10-26 18:56:48.469014 | instance -> localhost | ok
2026-10-26 18:56:48.480415 | 
2026-10-26 18:56:48.480599 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-10-26 18:56:48.514162 | instance | ok
2026-10-26 18:56:48.538245 | instance | included: /var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-10-26 18:56:48.547371 | 
2026-10-26 18:56:48.547445 | TASK [add-build-sshkey : Create Temp SSH key]
2026-10-26 18:56:49.141084 | instance -> localhost | Generating public/private rsa key pair.
2026-10-26 18:56:49.141229 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/work/09c1ff1ff0ba456e9b356b9f051ded99_id_rsa
2026-10-26 18:56:49.141255 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/work/09c1ff1ff0ba456e9b356b9f051ded99_id_rsa.pub
2026-10-26 18:56:49.141275 | instance -> localhost | The key fingerprint is:
2026-10-26 18:56:49.141293 | instance -> localhost | SHA256:ie74RO2JwnCQs5s4tQ0LzMVtB/sgsPi91Qshsg0Zd6E zuul-build-sshkey
2026-10-26 18:56:49.141323 | instance -> localhost | The key's randomart image is:
2026-10-26 18:56:49.141342 | instance -> localhost | +---[RSA 3072]----+
2026-10-26 18:56:49.141364 | instance -> localhost | | .. ..o.         |
2026-10-26 18:56:49.141383 | instance -> localhost | |. +=.oo          |
2026-10-26 18:56:49.141400 | instance -> localhost | |..B+E=..         |
2026-10-26 18:56:49.141416 | instance -> localhost | |o..Xo.+= .       |
2026-10-26 18:56:49.141434 | instance -> localhost | |.oB + =.S        |
2026-10-26 18:56:49.141450 | instance -> localhost | | + @ = + o       |
2026-10-26 18:56:49.141467 | instance -> localhost | |o = = + +        |
2026-10-26 18:56:49.141484 | instance -> localhost | | .   =           |
2026-10-26 18:56:49.141503 | instance -> localhost | |    ..o          |
2026-10-26 18:56:49.141521 | instance -> localhost | +----[SHA256]-----+
2026-10-26 18:56:49.141566 | instance -> localhost | ok: Runtime: 0:00:00.166676
2026-10-26 18:56:49.145792 | 
2026-10-26 18:56:49.145846 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-10-26 18:56:49.162103 | instance | ok
2026-10-26 18:56:49.169280 | instance | included: /var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-10-26 18:56:49.175575 | 
2026-10-26 18:56:49.175627 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-10-26 18:56:49.198467 | instance | skipping: Conditional result was False
2026-10-26 18:56:49.203785 | 
2026-10-26 18:56:49.203844 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-10-26 18:56:49.661127 | instance | changed
2026-10-26 18:56:49.670131 | 
2026-10-26 18:56:49.670253 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-10-26 18:56:49.881116 | instance | ok
2026-10-26 18:56:49.890947 | 
2026-10-26 18:56:49.891193 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-10-26 18:56:50.369068 | instance | changed
2026-10-26 18:56:50.380202 | 
2026-10-26 18:56:50.380326 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-10-26 18:56:50.890175 | instance | changed
2026-10-26 18:56:50.899156 | 
2026-10-26 18:56:50.899284 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-10-26 18:56:50.933811 | instance | skipping: Conditional result was False
2026-10-26 18:56:50.943827 | 
2026-10-26 18:56:50.943951 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-10-26 18:56:51.330479 | instance -> localhost | changed
2026-10-26 18:56:51.349402 | 
2026-10-26 18:56:51.349533 | TASK [add-build-sshkey : Add back temp key]
2026-10-26 18:56:51.728268 | instance -> localhost | Identity added: /var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/work/09c1ff1ff0ba456e9b356b9f051ded99_id_rsa (zuul-build-sshkey)
2026-10-26 18:56:51.728588 | instance -> localhost | ok: Runtime: 0:00:00.012486
2026-10-26 18:56:51.737938 | 
2026-10-26 18:56:51.738094 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-10-26 18:56:52.061878 | instance | ok
2026-10-26 18:56:52.067685 | 
2026-10-26 18:56:52.067768 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-10-26 18:56:52.093310 | instance | skipping: Conditional result was False
2026-10-26 18:56:52.105835 | 
2026-10-26 18:56:52.106012 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-10-26 18:56:52.453207 | instance | ok
2026-10-26 18:56:52.458103 | 
2026-10-26 18:56:52.458165 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-10-26 18:56:54.225606 | instance | Output suppressed because no_log was given
2026-10-26 18:56:54.250372 | 
2026-10-26 18:56:54.250511 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-10-26 18:56:54.455549 | instance | ok: "logs"
2026-10-26 18:56:54.455940 | instance | ok: All items complete
2026-10-26 18:56:54.456015 | 
2026-10-26 18:56:54.600055 | instance | ok: "artifacts"
2026-10-26 18:56:54.792564 | instance | ok: "docs"
2026-10-26 18:56:54.804982 | 
2026-10-26 18:56:54.805177 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-10-26 18:56:55.022697 | instance | changed: "logs"
2026-10-26 18:56:55.208130 | instance | changed: "artifacts"
2026-10-26 18:56:55.379949 | instance | changed: "docs"
2026-10-26 18:56:55.404598 | 
2026-10-26 18:56:55.405087 | PLAY RECAP
2026-10-26 18:56:55.405206 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-10-26 18:56:55.405274 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-10-26 18:56:55.405318 | 
2026-10-26 18:56:55.530117 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-10-26 18:56:55.531573 | RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/release-manifest/run.yaml@main]
2026-10-26 18:56:56.081556 | 
2026-10-26 18:56:56.081715 | PLAY [Build the release manifest]
2026-10-26 18:56:56.093630 | 
2026-10-26 18:56:56.093743 | TASK [Install Go]
2026-10-26 18:56:56.112005 | instance | ok
2026-10-26 18:56:56.125747 | 
2026-10-26 18:56:56.125906 | TASK [ensure-go : Check installed go version]
2026-10-26 18:56:56.657638 | instance | ok: ERROR (ignored)
2026-10-26 18:56:56.658098 | instance | {
2026-10-26 18:56:56.658169 | instance |   "failed_when_result": false,
2026-10-26 18:56:56.658216 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-10-26 18:56:56.658258 | instance |   "rc": 2
2026-10-26 18:56:56.658298 | instance | }
2026-10-26 18:56:56.667639 | 
2026-10-26 18:56:56.667772 | TASK [ensure-go : Skip if correct version of go is installed]
2026-10-26 18:56:56.731556 | instance | ok
2026-10-26 18:56:56.744388 | instance | included: /var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-10-26 18:56:56.754445 | 
2026-10-26 18:56:56.754574 | TASK [ensure-go : Create temp directory]
2026-10-26 18:56:57.152110 | instance | changed
2026-10-26 18:56:57.163498 | 
2026-10-26 18:56:57.163638 | TASK [ensure-go : Get archive checksum]
2026-10-26 18:56:57.938486 | instance | ok: OK (64 bytes)
2026-10-26 18:56:57.948424 | 
2026-10-26 18:56:57.948581 | TASK [ensure-go : Download go archive]
2026-10-26 18:56:59.046356 | instance | changed: OK (78559214 bytes)
2026-10-26 18:56:59.056659 | 
2026-10-26 18:56:59.056794 | TASK [ensure-go : Install go]
2026-10-26 18:57:04.933774 | instance | changed
2026-10-26 18:57:04.956830 | 
2026-10-26 18:57:04.957037 | TASK [atmosphere-build-manifest : Create the output directory]
2026-10-26 18:57:05.314662 | instance | changed
2026-10-26 18:57:05.323920 | 
2026-10-26 18:57:05.324087 | TASK [atmosphere-build-manifest : Build atmosphere-release]
2026-10-26 18:57:05.581814 | instance | go: downloading oras.land/oras-go/v2 v2.6.0
2026-10-26 18:57:05.581898 | instance | go: downloading github.com/sirupsen/logrus v1.9.3
2026-10-26 18:57:05.582024 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-10-26 18:57:05.583631 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-10-26 18:57:05.594481 | instance | go: downloading github.com/containers/image/v5 v5.35.0
2026-10-26 18:57:05.851569 | instance | go: downloading github.com/opencontainers/go-digest v1.0.0
2026-10-26 18:57:05.882845 | instance | go: downloading github.com/opencontainers/image-spec v1.1.1
2026-10-26 18:57:05.899823 | instance | go: downloading golang.org/x/sync v0.18.0
2026-10-26 18:57:06.023043 | instance | go: downloading golang.org/x/sys v0.38.0
2026-10-26 18:57:06.029222 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-10-26 18:57:06.287186 | instance | go: downloading github.com/containers/storage v1.58.0
2026-10-26 18:57:06.287244 | instance | go: downloading github.com/docker/distribution v2.8.3+incompatible
2026-10-26 18:57:06.287431 | instance | go: downloading github.com/docker/go-connections v0.5.0
2026-10-26 18:57:06.287460 | instance | go: downloading gopkg.in/yaml.v3 v3.0.1
2026-10-26 18:57:06.329380 | instance | go: downloading github.com/containers/libtrust v0.0.0-20230121012942-c1716e8a8d01
2026-10-26 18:57:06.336252 | instance | go: downloading github.com/containers/ocicrypt v1.2.1
2026-10-26 18:57:06.369420 | instance | go: downloading github.com/docker/docker v28.3.3+incompatible
2026-10-26 18:57:06.388503 | instance | go: downloading github.com/BurntSushi/toml v1.5.0
2026-10-26 18:57:06.440425 | instance | go: downloading github.com/docker/docker-credential-helpers v0.9.3
2026-10-26 18:57:06.483879 | instance | go: downloading github.com/distribution/reference v0.6.0
2026-10-26 18:57:06.516118 | instance | go: downloading github.com/gorilla/mux v1.8.1
2026-10-26 18:57:06.744763 | instance | go: downloading github.com/opencontainers/runtime-spec v1.2.1
2026-10-26 18:57:06.744835 | instance | go: downloading github.com/moby/sys/capability v0.4.0
2026-10-26 18:57:06.745008 | instance | go: downloading github.com/moby/sys/user v0.4.0
2026-10-26 18:57:06.745357 | instance | go: downloading github.com/moby/sys/mountinfo v0.7.2
2026-10-26 18:57:06.745381 | instance | go: downloading github.com/docker/go-units v0.5.0
2026-10-26 18:57:20.389511 | instance | ok: Runtime: 0:00:14.527139
2026-10-26 18:57:20.398564 | 
2026-10-26 18:57:20.398696 | TASK [atmosphere-build-manifest : Generate the release manifest]
2026-10-26 18:57:22.998458 | instance | time="2026-10-26T18:57:22Z" level=info msg="Wrote /home/zuul/atmosphere-release-manifest/release-manifest.yaml" images=216
2026-10-26 18:57:23.495263 | instance | ok: Runtime: 0:00:02.286146
2026-10-26 18:57:23.501444 | 
2026-10-26 18:57:23.501529 | TASK [atmosphere-build-manifest : Compute the release manifest artifact digest]
2026-10-26 18:57:23.767032 | instance | sha256:20e4364b666ea23f299bc12ada5a9ac80c23c8b33a4ad760178f5053bd104bf1
2026-10-26 18:57:24.035619 | instance | ok: Runtime: 0:00:00.024766
2026-10-26 18:57:24.045352 | 
2026-10-26 18:57:24.045490 | LOOP [atmosphere-build-manifest : Save the release manifest and atmosphere-release with the job logs]
2026-10-26 18:57:24.290009 | instance | changed: "release-manifest.yaml"
2026-10-26 18:57:24.645991 | instance | changed: "atmosphere-release"
2026-10-26 18:57:24.670594 | 
2026-10-26 18:57:24.670777 | TASK [atmosphere-build-manifest : Return the release manifest to dependent jobs]
2026-10-26 18:57:24.713635 | instance | ok
2026-10-26 18:57:24.723409 | 
2026-10-26 18:57:24.723506 | PLAY RECAP
2026-10-26 18:57:24.723589 | instance | ok: 12 changed: 7 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-10-26 18:57:24.723630 | 
2026-10-26 18:57:24.864531 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/release-manifest/run.yaml@main]
2026-10-26 18:57:24.865728 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-10-26 18:57:25.487774 | 
2026-10-26 18:57:25.488518 | PLAY [all]
2026-10-26 18:57:25.508086 | 
2026-10-26 18:57:25.508235 | TASK [fetch-output : Set log path for multiple nodes]
2026-10-26 18:57:25.563795 | instance | skipping: Conditional result was False
2026-10-26 18:57:25.573107 | 
2026-10-26 18:57:25.573261 | TASK [fetch-output : Set log path for single node]
2026-10-26 18:57:25.615512 | instance | ok
2026-10-26 18:57:25.624126 | 
2026-10-26 18:57:25.624257 | LOOP [fetch-output : Ensure local output dirs]
2026-10-26 18:57:26.008929 | instance -> localhost | ok: "/var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/work/logs"
2026-10-26 18:57:26.262754 | instance -> localhost | changed: "/var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/work/artifacts"
2026-10-26 18:57:26.524562 | instance -> localhost | changed: "/var/lib/zuul/builds/09c1ff1ff0ba456e9b356b9f051ded99/work/docs"
2026-10-26 18:57:26.540467 | 
2026-10-26 18:57:26.540629 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-10-26 18:57:27.282557 | instance | changed: .d..t...... ./
2026-10-26 18:57:27.282729 | instance | changed: All items complete
2026-10-26 18:57:27.282756 | 
2026-10-26 18:57:27.774662 | instance | changed: .d..t...... ./
2026-10-26 18:57:28.257724 | instance | changed: .d..t...... ./
2026-10-26 18:57:28.288797 | 
2026-10-26 18:57:28.288908 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-10-26 18:57:28.764626 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007008
2026-10-26 18:57:29.013170 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006294
2026-10-26 18:57:29.030845 | 
2026-10-26 18:57:29.031008 | PLAY [all]
2026-10-26 18:57:29.043515 | 
2026-10-26 18:57:29.043635 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-10-26 18:57:29.461376 | instance | changed
2026-10-26 18:57:29.466585 | 
2026-10-26 18:57:29.466632 | PLAY RECAP
2026-10-26 18:57:29.466673 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-10-26 18:57:29.466693 | 
2026-10-26 18:57:29.569618 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-10-26 18:57:29.571103 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-10-26 18:57:30.099793 | 
2026-10-26 18:57:30.099913 | PLAY [localhost]
2026-10-26 18:57:30.109822 | 
2026-10-26 18:57:30.109904 | TASK [Generate Zuul manifest]
2026-10-26 18:57:30.127953 | localhost | ok
2026-10-26 18:57:30.140619 | 
2026-10-26 18:57:30.140707 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-10-26 18:57:30.504052 | localhost | changed
2026-10-26 18:57:30.522428 | 
2026-10-26 18:57:30.522521 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-10-26 18:57:30.563426 | localhost | ok
2026-10-26 18:57:30.595223 | 
2026-10-26 18:57:30.595307 | TASK [Upload logs]
2026-10-26 18:57:30.615484 | localhost | ok
2026-10-26 18:57:30.664229 | 
2026-10-26 18:57:30.664334 | TASK [Set zuul-log-path fact]
2026-10-26 18:57:30.680187 | localhost | ok
2026-10-26 18:57:30.689357 | 
2026-10-26 18:57:30.689417 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-10-26 18:57:30.717519 | localhost | ok
2026-10-26 18:57:30.725183 | 
2026-10-26 18:57:30.725266 | TASK [upload-logs : Create log directories]
2026-10-26 18:57:31.122822 | localhost | changed
2026-10-26 18:57:31.133263 | 
2026-10-26 18:57:31.133397 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-10-26 18:57:31.651200 | localhost -> localhost | ok: Runtime: 0:00:00.005395
2026-10-26 18:57:31.660008 | 
2026-10-26 18:57:31.660147 | TASK [upload-logs : Upload logs to log server]
2026-10-26 18:57:32.247658 | localhost | Output suppressed because no_log was given
2026-10-26 18:57:32.264718 | 
2026-10-26 18:57:32.264820 | LOOP [upload-logs : Compress console log and json output]
2026-10-26 18:57:32.320034 | localhost | skipping: Conditional result was False
2026-10-26 18:57:32.327392 | localhost | skipping: Conditional result was False
2026-10-26 18:57:32.343797 | 
2026-10-26 18:57:32.343945 | LOOP [upload-logs : Upload compressed console log and json output]
2026-10-26 18:57:32.386789 | localhost | skipping: Conditional result was False
2026-10-26 18:57:32.387169 | 
2026-10-26 18:57:32.391170 | localhost | skipping: Conditional result was False
2026-10-26 18:57:32.407659 | 
2026-10-26 18:57:32.407807 | LOOP [upload-logs : Upload console log and json output]