Task 62015642685

kernel: use structured logging and simplify logging interface

2026-01-30 16:00:39 completed 2026-01/kernel-logging-layering-predicate a7a87281ee3e3a7f356a3977688d1ba5fbaea027


Commands that took longer than 1 second (total 1h0m47s)
linedurationpercentagecommand
8210s0%dirname ./ci/test/00_setup_env.sh
8220s0%cd ./ci/test/../../
8230s0%pwd
8240s0%BASE_READ_ONLY_DIR=/home/admin/actions-runner/_work/bitcoin/bitcoin
8250s0%export BASE_READ_ONLY_DIR
8260s0%export BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp
8270s0%BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp
8280s0%export DEPENDS_DIR=/home/admin/actions-runner/_work/_temp/depends
8290s0%DEPENDS_DIR=/home/admin/actions-runner/_work/_temp/depends
8300s0%export BASE_SCRATCH_DIR=/home/admin/actions-runner/_work/_temp/ci/scratch
8310s0%BASE_SCRATCH_DIR=/home/admin/actions-runner/_work/_temp/ci/scratch
8320s0%echo 'Setting specific values in env'
8330s0%'[' -n ./ci/test/00_setup_env_native_fuzz_with_valgrind.sh ']'
8340s0%set -o errexit
8350s0%source ./ci/test/00_setup_env_native_fuzz_with_valgrind.sh
8360s0%export LC_ALL=C.UTF-8
8370s0%LC_ALL=C.UTF-8
8380s0%export CI_IMAGE_NAME_TAG=mirror.gcr.io/debian:trixie
8390s0%CI_IMAGE_NAME_TAG=mirror.gcr.io/debian:trixie
8400s0%export CONTAINER_NAME=ci_native_fuzz_valgrind
8410s0%CONTAINER_NAME=ci_native_fuzz_valgrind
8420s0%export 'PACKAGES=libevent-dev libboost-dev libsqlite3-dev valgrind libcapnp-dev capnproto'
8430s0%PACKAGES='libevent-dev libboost-dev libsqlite3-dev valgrind libcapnp-dev capnproto'
8440s0%export NO_DEPENDS=1
8450s0%NO_DEPENDS=1
8460s0%export RUN_UNIT_TESTS=false
8470s0%RUN_UNIT_TESTS=false
8480s0%export RUN_FUNCTIONAL_TESTS=false
8490s0%RUN_FUNCTIONAL_TESTS=false
8500s0%export RUN_FUZZ_TESTS=true
8510s0%RUN_FUZZ_TESTS=true
8520s0%export FUZZ_TESTS_CONFIG=--valgrind
8530s0%FUZZ_TESTS_CONFIG=--valgrind
8540s0%export GOAL=all
8550s0%GOAL=all
8560s0%export 'BITCOIN_CONFIG= -DBUILD_FOR_FUZZING=ON -DCMAKE_CXX_FLAGS='\''-Wno-error=array-bounds'\'' '
8570s0%BITCOIN_CONFIG=' -DBUILD_FOR_FUZZING=ON -DCMAKE_CXX_FLAGS='\''-Wno-error=array-bounds'\'' '
8590s0%echo 'Fallback to default values in env (if not yet set)'
8600s0%command -v nproc
8610s0%nproc
8630s0%export MAKEJOBS=-j8
8640s0%MAKEJOBS=-j8
8650s0%export RUN_UNIT_TESTS=false
8660s0%RUN_UNIT_TESTS=false
8670s0%export RUN_FUNCTIONAL_TESTS=false
8680s0%RUN_FUNCTIONAL_TESTS=false
8690s0%export RUN_TIDY=false
8700s0%RUN_TIDY=false
8710s0%export TEST_RUNNER_TIMEOUT_FACTOR=40
8720s0%TEST_RUNNER_TIMEOUT_FACTOR=40
8730s0%export RUN_FUZZ_TESTS=true
8740s0%RUN_FUZZ_TESTS=true
8750s0%export BOOST_TEST_RANDOM=1
8760s0%BOOST_TEST_RANDOM=1
8770s0%export DEBIAN_FRONTEND=noninteractive
8780s0%DEBIAN_FRONTEND=noninteractive
8790s0%export CCACHE_MAXSIZE=500M
8800s0%CCACHE_MAXSIZE=500M
8810s0%export CCACHE_TEMPDIR=/tmp/.ccache-temp
8820s0%CCACHE_TEMPDIR=/tmp/.ccache-temp
8830s0%export CCACHE_COMPRESS=1
8840s0%CCACHE_COMPRESS=1
8850s0%export CCACHE_DIR=/home/admin/actions-runner/_work/_temp/ccache_dir
8860s0%CCACHE_DIR=/home/admin/actions-runner/_work/_temp/ccache_dir
8870s0%export BASE_OUTDIR=/home/admin/actions-runner/_work/_temp/ci/scratch/out
8880s0%BASE_OUTDIR=/home/admin/actions-runner/_work/_temp/ci/scratch/out
8890s0%export PREVIOUS_RELEASES_DIR=/home/admin/actions-runner/_work/_temp/previous_releases
8900s0%PREVIOUS_RELEASES_DIR=/home/admin/actions-runner/_work/_temp/previous_releases
8910s0%export 'CI_BASE_PACKAGES=build-essential pkgconf curl ca-certificates ccache python3-dev rsync git procps bison e2fsprogs cmake ninja-build'
8920s0%CI_BASE_PACKAGES='build-essential pkgconf curl ca-certificates ccache python3-dev rsync git procps bison e2fsprogs cmake ninja-build'
8930s0%export GOAL=all
8940s0%GOAL=all
8950s0%export DIR_QA_ASSETS=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets
8960s0%DIR_QA_ASSETS=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets
8970s0%export CI_RETRY_EXE=retry
8980s0%CI_RETRY_EXE=retry
8990s0%export CI_IMAGE_PLATFORM=linux
9000s0%CI_IMAGE_PLATFORM=linux
9010s0%set -o errexit
9020s0%./ci/test/02_run_container.py
9040s0%bash -c 'grep export ./ci/test/00_setup_env*.sh'
9050s0%cat /tmp/env-admin-ci_native_fuzz_valgrind
93919s0%docker buildx build --file=/home/admin/actions-runner/_work/bitcoin/bitcoin/ci/test_imagefile --build-arg=CI_IMAGE_NAME_TAG=mirror.gcr.io/debian:trixie --build-arg=FILE_ENV=./ci/test/00_setup_env_native_fuzz_with_valgrind.sh --build-arg=BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp --platform=linux --label=bitcoin-ci-test --tag=ci_native_fuzz_valgrind --cache-from type=gha,url=http://127.0.0.1:12321/,url_v2=http://127.0.0.1:12321/,scope=ci_native_fuzz_valgrind --load /home/admin/actions-runner/_work/bitcoin/bitcoin
10120s0%docker volume create ci_native_fuzz_valgrind_ccache
10140s0%docker volume create ci_native_fuzz_valgrind_depends
10160s0%docker volume create ci_native_fuzz_valgrind_depends_sources
10180s0%docker volume create ci_native_fuzz_valgrind_previous_releases
10200s0%docker network create --ipv6 --subnet 1111:1111::/112 ci-ip6net
10230s0%docker image prune --force --filter label=bitcoin-ci-test
10251s0%docker run --rm --interactive --detach --tty --cap-add=LINUX_IMMUTABLE --mount=type=bind,src=/home/admin/actions-runner/_work/bitcoin/bitcoin,dst=/home/admin/actions-runner/_work/bitcoin/bitcoin,readonly --mount=type=bind,src=/home/admin/actions-runner/_work/_temp/ccache_dir,dst=/home/admin/actions-runner/_work/_temp/ccache_dir --mount=type=bind,src=/home/admin/actions-runner/_work/_temp/depends/built,dst=/home/admin/actions-runner/_work/_temp/depends/built --mount=type=bind,src=/home/admin/actions-runner/_work/_temp/depends/sources,dst=/home/admin/actions-runner/_work/_temp/depends/sources --mount=type=bind,src=/home/admin/actions-runner/_work/_temp/previous_releases,dst=/home/admin/actions-runner/_work/_temp/previous_releases --mount=type=bind,src=/home/admin/actions-runner/_work/_temp/build,dst=/home/admin/actions-runner/_work/_temp/build --env-file=/tmp/env-admin-ci_native_fuzz_valgrind --name=ci_native_fuzz_valgrind --network=ci-ip6net --platform=linux ci_native_fuzz_valgrind
10260s0%docker exec 4421ea61a0b7a321c4b23d888279e510cbe556cce369f87a3424195c65108767 rsync --recursive --perms --stats --human-readable /home/admin/actions-runner/_work/bitcoin/bitcoin/ /home/admin/actions-runner/_work/_temp
10440s0%docker exec 4421ea61a0b7a321c4b23d888279e510cbe556cce369f87a3424195c65108767 /home/admin/actions-runner/_work/_temp/ci/test/01_base_install.sh
10450s0%CFG_DONE=/home/admin/actions-runner/_work/_temp/ci.base-install-done
10460s0%cat /home/admin/actions-runner/_work/_temp/ci.base-install-done
10470s0%'[' done == done ']'
10480s0%echo 'Skip base install'
10490s0%exit 0
10510s0%docker exec 4421ea61a0b7a321c4b23d888279e510cbe556cce369f87a3424195c65108767 /home/admin/actions-runner/_work/_temp/ci/test/03_test_script.sh
10520s0%cd /home/admin/actions-runner/_work/_temp
10530s0%export 'PATH=/path_with space:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
10540s0%PATH='/path_with space:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
10550s0%export ASAN_OPTIONS=detect_leaks=1:detect_stack_use_after_return=1:check_initialization_order=1:strict_init_order=1
10560s0%ASAN_OPTIONS=detect_leaks=1:detect_stack_use_after_return=1:check_initialization_order=1:strict_init_order=1
10570s0%export LSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/lsan
10580s0%LSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/lsan
10590s0%export TSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/tsan:halt_on_error=1:second_deadlock_stack=1
10600s0%TSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/tsan:halt_on_error=1:second_deadlock_stack=1
10610s0%export UBSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/ubsan:print_stacktrace=1:halt_on_error=1:report_error_type=1
10620s0%UBSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/ubsan:print_stacktrace=1:halt_on_error=1:report_error_type=1
10630s0%nproc
10640s0%echo 'Number of available processing units: 8'
10650s0%'[' '' == macos ']'
10660s0%free -m -h
10710s0%uname --kernel-name --kernel-release
10720s0%echo 'System info: Linux 6.14.0-37-generic'
10730s0%lscpu
11180s0%echo 'Free disk space:'
11190s0%df -h
11200s0%[[ ci_native_fuzz_valgrind == \c\i\_\n\a\t\i\v\e\_\a\s\a\n ]]
11210s0%/home/admin/actions-runner/_work/_temp/depends/config.guess
11310s0%export HOST=x86_64-pc-linux-gnu
11320s0%HOST=x86_64-pc-linux-gnu
11330s0%echo '=== BEGIN env ==='
11340s0%env
11440s0%echo '=== END env ==='
11450s0%[[ '' != true ]]
11460s0%tee /dev/fd/63
11850s0%patch -p1
11860s0%'[' true = true ']'
11870s0%export DIR_FUZZ_IN=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/fuzz_corpora/
11880s0%DIR_FUZZ_IN=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/fuzz_corpora/
11890s0%'[' '!' -d /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/fuzz_corpora/ ']'
11900s0%retry git clone --depth=1 https://github.com/bitcoin-core/qa-assets /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets
11920s0%b/src/leveldb/db/db_impl.cc
120252s1%1].Add(stats);
13170s0%cd /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets
13180s0%echo 'Using qa-assets repo from commit ...'
13190s0%git log -1
13280s0%'[' '' == macos ']'
13290s0%echo
13300s0%'[' -z 1 ']'
13310s0%'[' '' = true ']'
13320s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON'
13330s0%'[' -z 1 ']'
13340s0%'[' -z '' ']'
13350s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DWERROR=ON'
13360s0%ccache --zero-stats
13380s0%PRINT_CCACHE_STATISTICS='ccache --version | head -n 1 && ccache --show-stats'
13390s0%BASE_BUILD_DIR=/home/admin/actions-runner/_work/_temp/build
13400s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev'
13410s0%[[ '' == true ]]
13420s0%[[ false == true ]]
13430s0%eval 'CMAKE_ARGS=(-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev -DBUILD_FOR_FUZZING=ON -DCMAKE_CXX_FLAGS='\''-Wno-error=array-bounds'\'' )'
13440s0%CMAKE_ARGS=(-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev -DBUILD_FOR_FUZZING=ON -DCMAKE_CXX_FLAGS='-Wno-error=array-bounds')
13450s0%cmake -S /home/admin/actions-runner/_work/_temp -B /home/admin/actions-runner/_work/_temp/build -DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev -DBUILD_FOR_FUZZING=ON -DCMAKE_CXX_FLAGS=-Wno-error=array-bounds
13498s0%- skipped
16490s0%compiler .......................... GNU 14.2.0, /usr/bin/c++
16520s0%compiler flags .................... -Wno-error=array-bounds -O2 -g -std=c++20 -fPIC -fno-extended-identifiers -fmacro-prefix-map=/home/admin/actions-runner/_work/_temp/src=. -fstack-reuse=none -U_FORTIFY_SOURCE -D_FORTIFY_SOURCE=3 -Wstack-protector -fstack-protector-all -fcf-protection=full -fstack-clash-protection -Werror -Wall -Wextra -Wformat -Wformat-security -Wvla -Wredundant-decls -Wdate-time -Wduplicated-branches -Wduplicated-cond -Wlogical-op -Woverloaded-virtual -Wsuggest-override -Wimplicit-fallthrough -Wunreachable-code -Wbidi-chars=any -Wundef -Wno-unused-parameter
16660s0%[[ all != all ]]
16673m4s5%cmake --build /home/admin/actions-runner/_work/_temp/build -j8 --target all
21690s0%bash -c 'ccache --version | head -n 1 && ccache --show-stats'
21810s0%ccache --show-stats
21820s0%grep Hits:
21830s0%head -1
21840s0%sed 's/.*(\(.*\)%).*/\1/'
21850s0%hit_rate=26.61
21860s0%'[' 26 -lt 75 ']'
21870s0%echo '::notice title=low ccache hitrate::Ccache hit-rate in ci_native_fuzz_valgrind was 26.61%'
21890s0%du -sh /home/admin/actions-runner/_work/_temp/depends/SDKs/ /home/admin/actions-runner/_work/_temp/depends/builders/ /home/admin/actions-runner/_work/_temp/depends/built/ /home/admin/actions-runner/_work/_temp/depends/hosts/ /home/admin/actions-runner/_work/_temp/depends/packages/ /home/admin/actions-runner/_work/_temp/depends/patches/ /home/admin/actions-runner/_work/_temp/depends/sdk-sources/ /home/admin/actions-runner/_work/_temp/depends/sources/
21980s0%du -sh /home/admin/actions-runner/_work/_temp/previous_releases
22000s0%'[' -n '' ']'
22010s0%'[' -n '' ']'
22020s0%'[' '' = true ']'
22030s0%'[' false = true ']'
22040s0%'[' false = true ']'
22050s0%'[' false = true ']'
22060s0%[[ '' == true ]]
22070s0%'[' true = true ']'
22080s0%LD_LIBRARY_PATH=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/lib
220955m30s91%/home/admin/actions-runner/_work/_temp/build/test/fuzz/test_runner.py --valgrind -j8 -l DEBUG /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/fuzz_corpora/ --empty_min_time=60
266215s0%docker container kill 4421ea61a0b7a321c4b23d888279e510cbe556cce369f87a3424195c65108767
Tags
  • Valgrind, fuzz
  • 2026-01/kernel-logging-layering-predicate
  • COMPLETED