2025-11-18 22:45:34.692330 | Job console starting 2025-11-18 22:45:34.711574 | Updating git repos 2025-11-18 22:45:34.743862 | Cloning repos into workspace 2025-11-18 22:45:34.889468 | Restoring repo states 2025-11-18 22:45:34.930750 | Merging changes 2025-11-18 22:45:35.559579 | Checking out repos 2025-11-18 22:45:35.663543 | Preparing playbooks 2025-11-18 22:45:36.978977 | Running Ansible setup 2025-11-18 22:45:43.909985 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-18 22:45:45.285431 | 2025-11-18 22:45:45.285593 | PLAY [Base pre] 2025-11-18 22:45:45.304523 | 2025-11-18 22:45:45.304629 | TASK [Setup log path fact] 2025-11-18 22:45:45.332949 | debian-bookworm | ok 2025-11-18 22:45:45.349369 | 2025-11-18 22:45:45.349481 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-18 22:45:45.398188 | debian-bookworm | ok 2025-11-18 22:45:45.406280 | 2025-11-18 22:45:45.406367 | TASK [emit-job-header : Print job information] 2025-11-18 22:45:45.435477 | # Job Information 2025-11-18 22:45:45.435634 | Ansible Version: 2.16.14 2025-11-18 22:45:45.435665 | Job: container-image-python-osism-frontend-build 2025-11-18 22:45:45.435692 | Pipeline: check 2025-11-18 22:45:45.435713 | Executor: 521e9411259a 2025-11-18 22:45:45.435730 | Triggered by: https://github.com/osism/python-osism/pull/1711 2025-11-18 22:45:45.435749 | Event ID: 26bb5be0-c4d0-11f0-88bc-955a094309fb 2025-11-18 22:45:45.451870 | 2025-11-18 22:45:45.451974 | LOOP [emit-job-header : Print node information] 2025-11-18 22:45:45.564035 | debian-bookworm | ok: 2025-11-18 22:45:45.564210 | debian-bookworm | # Node Information 2025-11-18 22:45:45.564239 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-18 22:45:45.564260 | debian-bookworm | Hostname: debian 2025-11-18 22:45:45.564958 | debian-bookworm | Username: zuul 2025-11-18 22:45:45.565012 | debian-bookworm | Distro: Debian 12.12 2025-11-18 22:45:45.565042 | debian-bookworm | Provider: regiocloud-a 2025-11-18 22:45:45.565067 | debian-bookworm | Region: 2025-11-18 22:45:45.565086 | debian-bookworm | Label: debian-bookworm 2025-11-18 22:45:45.565105 | debian-bookworm | Product Name: OpenStack Nova 2025-11-18 22:45:45.565122 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe26:455f 2025-11-18 22:45:45.580480 | 2025-11-18 22:45:45.580568 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-18 22:45:46.347202 | debian-bookworm -> localhost | changed 2025-11-18 22:45:46.353286 | 2025-11-18 22:45:46.353377 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-18 22:45:48.664384 | debian-bookworm -> localhost | changed 2025-11-18 22:45:48.683626 | 2025-11-18 22:45:48.683740 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-18 22:45:48.989968 | debian-bookworm -> localhost | ok 2025-11-18 22:45:48.994798 | 2025-11-18 22:45:48.994945 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-18 22:45:49.021813 | debian-bookworm | ok 2025-11-18 22:45:49.072398 | debian-bookworm | included: /var/lib/zuul/builds/e01555c46e0e4b5e8b6146c878dc1530/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-18 22:45:49.097234 | 2025-11-18 22:45:49.097330 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-18 22:45:52.242133 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-18 22:45:52.242289 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e01555c46e0e4b5e8b6146c878dc1530/work/e01555c46e0e4b5e8b6146c878dc1530_id_rsa 2025-11-18 22:45:52.242320 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e01555c46e0e4b5e8b6146c878dc1530/work/e01555c46e0e4b5e8b6146c878dc1530_id_rsa.pub 2025-11-18 22:45:52.242341 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-18 22:45:52.242360 | debian-bookworm -> localhost | SHA256:hdQMrI6SVsvDR7tgd0E/5DFavGAk6jV7ntnA5WaY89I zuul-build-sshkey 2025-11-18 22:45:52.242388 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-18 22:45:52.242407 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-18 22:45:52.242424 | debian-bookworm -> localhost | | .o+= | 2025-11-18 22:45:52.242441 | debian-bookworm -> localhost | | . o=.O | 2025-11-18 22:45:52.242458 | debian-bookworm -> localhost | | . o+.Bo+ | 2025-11-18 22:45:52.242474 | debian-bookworm -> localhost | | ...o+o*= | 2025-11-18 22:45:52.242491 | debian-bookworm -> localhost | | =.=..S.+. | 2025-11-18 22:45:52.242509 | debian-bookworm -> localhost | | + O =o.@ | 2025-11-18 22:45:52.242525 | debian-bookworm -> localhost | | . o = o= E | 2025-11-18 22:45:52.242541 | debian-bookworm -> localhost | | . . | 2025-11-18 22:45:52.242557 | debian-bookworm -> localhost | | | 2025-11-18 22:45:52.242573 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-18 22:45:52.242615 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.228583 2025-11-18 22:45:52.249510 | 2025-11-18 22:45:52.249595 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-18 22:45:52.277463 | debian-bookworm | ok 2025-11-18 22:45:52.302645 | debian-bookworm | included: /var/lib/zuul/builds/e01555c46e0e4b5e8b6146c878dc1530/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-18 22:45:52.314433 | 2025-11-18 22:45:52.314523 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-18 22:45:52.358463 | debian-bookworm | skipping: Conditional result was False 2025-11-18 22:45:52.364121 | 2025-11-18 22:45:52.364212 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-18 22:45:53.347585 | debian-bookworm | changed 2025-11-18 22:45:53.355985 | 2025-11-18 22:45:53.356066 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-18 22:45:53.675359 | debian-bookworm | ok 2025-11-18 22:45:53.680095 | 2025-11-18 22:45:53.680187 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-18 22:45:54.583495 | debian-bookworm | changed 2025-11-18 22:45:54.590296 | 2025-11-18 22:45:54.590386 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-18 22:45:55.492781 | debian-bookworm | changed 2025-11-18 22:45:55.497567 | 2025-11-18 22:45:55.497655 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-18 22:45:55.532132 | debian-bookworm | skipping: Conditional result was False 2025-11-18 22:45:55.538099 | 2025-11-18 22:45:55.538186 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-18 22:45:56.472293 | debian-bookworm -> localhost | changed 2025-11-18 22:45:56.481713 | 2025-11-18 22:45:56.481838 | TASK [add-build-sshkey : Add back temp key] 2025-11-18 22:45:57.453714 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e01555c46e0e4b5e8b6146c878dc1530/work/e01555c46e0e4b5e8b6146c878dc1530_id_rsa (zuul-build-sshkey) 2025-11-18 22:45:57.453917 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.056170 2025-11-18 22:45:57.460352 | 2025-11-18 22:45:57.460438 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-18 22:45:58.425093 | debian-bookworm | ok 2025-11-18 22:45:58.429270 | 2025-11-18 22:45:58.429347 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-18 22:45:58.453023 | debian-bookworm | skipping: Conditional result was False 2025-11-18 22:45:58.461368 | 2025-11-18 22:45:58.461458 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-18 22:45:59.114012 | debian-bookworm | ok 2025-11-18 22:45:59.140453 | 2025-11-18 22:45:59.140557 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-18 22:45:59.197682 | debian-bookworm | ok 2025-11-18 22:45:59.202065 | 2025-11-18 22:45:59.202154 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-18 22:46:00.352260 | debian-bookworm -> localhost | ok 2025-11-18 22:46:00.357358 | 2025-11-18 22:46:00.357436 | TASK [validate-host : Collect information about the host] 2025-11-18 22:46:02.098815 | debian-bookworm | ok 2025-11-18 22:46:02.113891 | 2025-11-18 22:46:02.113990 | TASK [validate-host : Sanitize hostname] 2025-11-18 22:46:02.297063 | debian-bookworm | ok 2025-11-18 22:46:02.378857 | 2025-11-18 22:46:02.378965 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-18 22:46:03.776436 | debian-bookworm -> localhost | changed 2025-11-18 22:46:03.781973 | 2025-11-18 22:46:03.782073 | TASK [validate-host : Collect information about zuul worker] 2025-11-18 22:46:04.307589 | debian-bookworm | ok 2025-11-18 22:46:04.312978 | 2025-11-18 22:46:04.313076 | TASK [validate-host : Write out all zuul information for each host] 2025-11-18 22:46:05.895816 | debian-bookworm -> localhost | changed 2025-11-18 22:46:05.904964 | 2025-11-18 22:46:05.905061 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-18 22:46:06.208962 | debian-bookworm | ok 2025-11-18 22:46:06.214216 | 2025-11-18 22:46:06.214309 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-18 22:46:08.256751 | debian-bookworm | changed: 2025-11-18 22:46:08.256945 | debian-bookworm | cd+++++++++ src/ 2025-11-18 22:46:08.256979 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-18 22:46:08.257003 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-18 22:46:08.257025 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-11-18 22:46:08.257046 | debian-bookworm | Containerfile 2025-11-18 22:46:08.257160 | debian-bookworm | Containerfile 2025-11-18 22:46:08.262418 | debian-bookworm | 11.6.2 2025-11-18 22:47:42.018014 | debian-bookworm | #9 18.58 npm notice Changelog: https://github.com/npm/cli/releases/tag/v11.6.2 2025-11-18 22:47:42.018083 | debian-bookworm | #9 18.58 npm notice To update run: npm install -g npm@11.6.2 2025-11-18 22:47:42.018117 | debian-bookworm | #9 18.58 npm notice 2025-11-18 22:47:42.553216 | debian-bookworm | #9 DONE 19.3s 2025-11-18 22:47:42.708602 | debian-bookworm | 2025-11-18 22:47:42.708699 | debian-bookworm | #11 [build 5/6] COPY . . 2025-11-18 22:47:42.982477 | debian-bookworm | #11 DONE 0.4s 2025-11-18 22:47:43.135473 | debian-bookworm | 2025-11-18 22:47:43.135554 | debian-bookworm | #12 [build 6/6] RUN npm run build 2025-11-18 22:47:43.421803 | debian-bookworm | #12 0.437 2025-11-18 22:47:43.421915 | debian-bookworm | #12 0.437 > frontend@0.1.0 build 2025-11-18 22:47:43.421947 | debian-bookworm | #12 0.437 > next build 2025-11-18 22:47:43.421974 | debian-bookworm | #12 0.437 2025-11-18 22:47:44.965912 | debian-bookworm | #12 1.980 ▲ Next.js 15.5.0 2025-11-18 22:47:44.966032 | debian-bookworm | #12 1.980 2025-11-18 22:47:45.189776 | debian-bookworm | #12 2.054 Creating an optimized production build ... 2025-11-18 22:48:06.294431 | debian-bookworm | #12 23.31 ✓ Compiled successfully in 17.2s 2025-11-18 22:48:06.449388 | debian-bookworm | #12 23.31 Linting and checking validity of types ... 2025-11-18 22:48:13.684706 | debian-bookworm | #12 30.70 Collecting page data ... 2025-11-18 22:48:16.307007 | debian-bookworm | #12 33.32 Generating static pages (0/10) ... 2025-11-18 22:48:17.095757 | debian-bookworm | #12 34.11 Generating static pages (2/10) 2025-11-18 22:48:17.581741 | debian-bookworm | #12 34.60 Generating static pages (4/10) 2025-11-18 22:48:17.732690 | debian-bookworm | #12 34.60 Generating static pages (7/10) 2025-11-18 22:48:17.732728 | debian-bookworm | #12 34.60 ✓ Generating static pages (10/10) 2025-11-18 22:48:18.709992 | debian-bookworm | #12 35.73 Finalizing page optimization ... 2025-11-18 22:48:18.860880 | debian-bookworm | #12 35.73 Collecting build traces ... 2025-11-18 22:48:44.614371 | debian-bookworm | #12 61.63 2025-11-18 22:48:44.770971 | debian-bookworm | #12 61.63 Route (app) Size First Load JS 2025-11-18 22:48:44.771038 | debian-bookworm | #12 61.63 ┌ ○ / 1.79 kB 136 kB 2025-11-18 22:48:44.771057 | debian-bookworm | #12 61.63 ├ ○ /_not-found 991 B 103 kB 2025-11-18 22:48:44.771074 | debian-bookworm | #12 61.63 ├ ƒ /api/config 127 B 102 kB 2025-11-18 22:48:44.771090 | debian-bookworm | #12 61.63 ├ ƒ /api/health 127 B 102 kB 2025-11-18 22:48:44.771106 | debian-bookworm | #12 61.63 ├ ○ /events 12.9 kB 115 kB 2025-11-18 22:48:44.771123 | debian-bookworm | #12 61.63 ├ ○ /nodes 3.38 kB 134 kB 2025-11-18 22:48:44.771142 | debian-bookworm | #12 61.63 └ ○ /services 1.41 kB 103 kB 2025-11-18 22:48:44.771159 | debian-bookworm | #12 61.63 + First Load JS shared by all 102 kB 2025-11-18 22:48:44.771175 | debian-bookworm | #12 61.63 ├ chunks/255-01c481785f268126.js 45.7 kB 2025-11-18 22:48:44.771191 | debian-bookworm | #12 61.63 ├ chunks/4bd1b696-c023c6e3521b1417.js 54.2 kB 2025-11-18 22:48:44.771206 | debian-bookworm | #12 61.63 └ other shared chunks (total) 1.92 kB 2025-11-18 22:48:44.771221 | debian-bookworm | #12 61.63 2025-11-18 22:48:44.771238 | debian-bookworm | #12 61.63 2025-11-18 22:48:44.771253 | debian-bookworm | #12 61.63 ○ (Static) prerendered as static content 2025-11-18 22:48:44.771268 | debian-bookworm | #12 61.63 ƒ (Dynamic) server-rendered on demand 2025-11-18 22:48:44.771284 | debian-bookworm | #12 61.63 2025-11-18 22:48:44.794378 | debian-bookworm | #12 DONE 61.8s 2025-11-18 22:48:45.651367 | debian-bookworm | 2025-11-18 22:48:45.651503 | debian-bookworm | #13 [runner 5/7] COPY --from=build /app/public ./public 2025-11-18 22:48:45.774823 | debian-bookworm | #13 DONE 0.1s 2025-11-18 22:48:45.930089 | debian-bookworm | 2025-11-18 22:48:45.930162 | debian-bookworm | #14 [runner 6/7] COPY --from=build --chown=nextjs:nodejs /app/.next/standalone ./ 2025-11-18 22:48:46.281308 | debian-bookworm | #14 DONE 0.5s 2025-11-18 22:48:46.439014 | debian-bookworm | 2025-11-18 22:48:46.439111 | debian-bookworm | #15 [runner 7/7] COPY --from=build --chown=nextjs:nodejs /app/.next/static ./.next/static 2025-11-18 22:48:46.450609 | debian-bookworm | #15 DONE 0.2s 2025-11-18 22:48:46.615201 | debian-bookworm | 2025-11-18 22:48:46.615327 | debian-bookworm | #16 exporting to image 2025-11-18 22:48:46.615361 | debian-bookworm | #16 exporting layers 2025-11-18 22:48:51.104280 | debian-bookworm | #16 exporting layers 4.6s done 2025-11-18 22:48:51.207807 | debian-bookworm | #16 exporting manifest sha256:678e207e7cc4ca4477b6bcba1af9f0594c144a6bffa512c36b8a845c0bc45826 0.0s done 2025-11-18 22:48:51.207957 | debian-bookworm | #16 exporting config sha256:6dc9d9f89f70fdfa4cf30820cdab8aa68021efa98d8e6b33f255f71a5fc506aa 0.0s done 2025-11-18 22:48:51.208043 | debian-bookworm | #16 exporting attestation manifest sha256:4ea8683ca1499eb77218824cc170203ac2a383089d565ab0c76a6e3276aa981b 0.0s done 2025-11-18 22:48:51.386608 | debian-bookworm | #16 exporting manifest list sha256:d7d62171e5051d9c27d76386ed9de81a0c9284ad385e2430ee12e744f8e6f669 0.0s done 2025-11-18 22:48:51.386697 | debian-bookworm | #16 naming to docker.io/library/386dfaa:latest done 2025-11-18 22:48:51.386714 | debian-bookworm | #16 unpacking to docker.io/library/386dfaa:latest 2025-11-18 22:48:52.447312 | debian-bookworm | #16 unpacking to docker.io/library/386dfaa:latest 1.2s done 2025-11-18 22:48:52.644795 | debian-bookworm | #16 DONE 6.0s 2025-11-18 22:48:52.667845 | debian-bookworm | + docker images 2025-11-18 22:48:52.739433 | debian-bookworm | WARNING: This output is designed for human readability. For machine-readable output, please use --format. 2025-11-18 22:48:52.739509 | debian-bookworm | IMAGE ID DISK USAGE CONTENT SIZE EXTRA 2025-11-18 22:48:52.739518 | debian-bookworm | 386dfaa:latest d7d62171e505 336MB 80.8MB 2025-11-18 22:48:53.082249 | debian-bookworm | ok: Runtime: 0:01:36.847658 2025-11-18 22:48:53.089259 | 2025-11-18 22:48:53.089377 | TASK [Run push script] 2025-11-18 22:48:53.634343 | 2025-11-18 22:48:53.634647 | TASK [Run cosign script] 2025-11-18 22:48:54.174250 | debian-bookworm | skipping: Conditional result was False 2025-11-18 22:48:54.184557 | 2025-11-18 22:48:54.184772 | TASK [Run sbom script] 2025-11-18 22:48:54.714388 | debian-bookworm | skipping: Conditional result was False 2025-11-18 22:48:54.715842 | 2025-11-18 22:48:54.715935 | PLAY RECAP 2025-11-18 22:48:54.715997 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-11-18 22:48:54.716021 | 2025-11-18 22:48:54.883765 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/python-osism/playbooks/build.yml@main] 2025-11-18 22:48:54.885025 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-18 22:48:55.665596 | 2025-11-18 22:48:55.665797 | PLAY [Base post] 2025-11-18 22:48:55.679991 | 2025-11-18 22:48:55.680186 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-18 22:48:56.471641 | debian-bookworm | changed 2025-11-18 22:48:56.478119 | 2025-11-18 22:48:56.478228 | PLAY RECAP 2025-11-18 22:48:56.478290 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-18 22:48:56.478354 | 2025-11-18 22:48:56.624076 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-18 22:48:56.625055 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-18 22:48:57.531381 | 2025-11-18 22:48:57.531553 | PLAY [Base post-logs] 2025-11-18 22:48:57.542363 | 2025-11-18 22:48:57.542498 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-18 22:48:58.029537 | localhost | changed 2025-11-18 22:48:58.046385 | 2025-11-18 22:48:58.046557 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-18 22:48:58.084500 | localhost | ok 2025-11-18 22:48:58.091031 | 2025-11-18 22:48:58.091210 | TASK [Set zuul-log-path fact] 2025-11-18 22:48:58.108070 | localhost | ok 2025-11-18 22:48:58.120113 | 2025-11-18 22:48:58.120248 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-18 22:48:58.146724 | localhost | ok 2025-11-18 22:48:58.149947 | 2025-11-18 22:48:58.150051 | TASK [upload-logs : Create log directories] 2025-11-18 22:48:58.687678 | localhost | changed 2025-11-18 22:48:58.692479 | 2025-11-18 22:48:58.692637 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-18 22:48:59.208833 | localhost -> localhost | ok: Runtime: 0:00:00.007049 2025-11-18 22:48:59.215360 | 2025-11-18 22:48:59.215501 | TASK [upload-logs : Upload logs to log server] 2025-11-18 22:48:59.854653 | localhost | Output suppressed because no_log was given 2025-11-18 22:48:59.860558 | 2025-11-18 22:48:59.860810 | LOOP [upload-logs : Compress console log and json output] 2025-11-18 22:48:59.911375 | localhost | skipping: Conditional result was False 2025-11-18 22:48:59.919402 | localhost | skipping: Conditional result was False 2025-11-18 22:48:59.935097 | 2025-11-18 22:48:59.935331 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-18 22:49:00.003457 | localhost | skipping: Conditional result was False 2025-11-18 22:49:00.003850 | 2025-11-18 22:49:00.005141 | localhost | skipping: Conditional result was False 2025-11-18 22:49:00.020545 | 2025-11-18 22:49:00.020816 | LOOP [upload-logs : Upload console log and json output]