Task 62024749375

kernel: use structured logging and simplify logging interface

2026-01-30 17:25:51 completed 2026-01/kernel-logging-layering-predicate ebe4409b722facd7d019aa800e226e26db404f81


Commands that took longer than 1 second (total 40m45s)
linedurationpercentagecommand
2333s1%LSan + UBSan + integer
8520s0%dirname ./ci/test/00_setup_env.sh
8530s0%cd ./ci/test/../../
8540s0%pwd
8550s0%BASE_READ_ONLY_DIR=/home/admin/actions-runner/_work/bitcoin/bitcoin
8560s0%export BASE_READ_ONLY_DIR
8570s0%export BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp
8580s0%BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp
8590s0%export DEPENDS_DIR=/home/admin/actions-runner/_work/_temp/depends
8600s0%DEPENDS_DIR=/home/admin/actions-runner/_work/_temp/depends
8610s0%export BASE_SCRATCH_DIR=/home/admin/actions-runner/_work/_temp/ci/scratch
8620s0%BASE_SCRATCH_DIR=/home/admin/actions-runner/_work/_temp/ci/scratch
8630s0%echo 'Setting specific values in env'
8650s0%'[' -n ./ci/test/00_setup_env_native_asan.sh ']'
8660s0%set -o errexit
8670s0%source ./ci/test/00_setup_env_native_asan.sh
8680s0%export LC_ALL=C.UTF-8
8690s0%LC_ALL=C.UTF-8
8700s0%export CI_IMAGE_NAME_TAG=mirror.gcr.io/ubuntu:24.04
8710s0%CI_IMAGE_NAME_TAG=mirror.gcr.io/ubuntu:24.04
8720s0%[[ true == \t\r\u\e ]]
8730s0%uname --kernel-release
8740s0%BPFCC_PACKAGE='bpfcc-tools linux-headers-6.14.0-37-generic'
8750s0%export 'CI_CONTAINER_CAP=--privileged -v /sys/kernel:/sys/kernel:rw'
8760s0%CI_CONTAINER_CAP='--privileged -v /sys/kernel:/sys/kernel:rw'
8770s0%export CONTAINER_NAME=ci_native_asan
8780s0%CONTAINER_NAME=ci_native_asan
8790s0%export APT_LLVM_V=21
8800s0%APT_LLVM_V=21
8810s0%export 'PACKAGES=systemtap-sdt-dev clang-21 llvm-21 libclang-rt-21-dev mold python3-zmq qt6-base-dev qt6-tools-dev qt6-l10n-tools libevent-dev libboost-dev libzmq3-dev libqrencode-dev libsqlite3-dev bpfcc-tools linux-headers-6.14.0-37-generic libcapnp-dev capnproto python3-pip'
8820s0%PACKAGES='systemtap-sdt-dev clang-21 llvm-21 libclang-rt-21-dev mold python3-zmq qt6-base-dev qt6-tools-dev qt6-l10n-tools libevent-dev libboost-dev libzmq3-dev libqrencode-dev libsqlite3-dev bpfcc-tools linux-headers-6.14.0-37-generic libcapnp-dev capnproto python3-pip'
8830s0%export 'PIP_PACKAGES=--break-system-packages pycapnp'
8840s0%PIP_PACKAGES='--break-system-packages pycapnp'
8850s0%export NO_DEPENDS=1
8860s0%NO_DEPENDS=1
8870s0%export GOAL=install
8880s0%GOAL=install
8890s0%export CI_LIMIT_STACK_SIZE=1
8900s0%CI_LIMIT_STACK_SIZE=1
8910s0%export 'BITCOIN_CONFIG= --preset=dev-mode -DSANITIZERS=address,float-divide-by-zero,integer,undefined -DCMAKE_C_COMPILER=clang -DCMAKE_CXX_COMPILER=clang++ -DCMAKE_C_FLAGS='\''-ftrivial-auto-var-init=pattern'\'' -DCMAKE_CXX_FLAGS='\''-ftrivial-auto-var-init=pattern'\'' -DCMAKE_EXE_LINKER_FLAGS='\''-fuse-ld=mold'\'' -DAPPEND_CXXFLAGS='\''-std=c++23'\'' -DAPPEND_CPPFLAGS='\''-DARENA_DEBUG -DDEBUG_LOCKORDER'\'' '
8920s0%BITCOIN_CONFIG=' --preset=dev-mode -DSANITIZERS=address,float-divide-by-zero,integer,undefined -DCMAKE_C_COMPILER=clang -DCMAKE_CXX_COMPILER=clang++ -DCMAKE_C_FLAGS='\''-ftrivial-auto-var-init=pattern'\'' -DCMAKE_CXX_FLAGS='\''-ftrivial-auto-var-init=pattern'\'' -DCMAKE_EXE_LINKER_FLAGS='\''-fuse-ld=mold'\'' -DAPPEND_CXXFLAGS='\''-std=c++23'\'' -DAPPEND_CPPFLAGS='\''-DARENA_DEBUG -DDEBUG_LOCKORDER'\'' '
8930s0%echo 'Fallback to default values in env (if not yet set)'
8940s0%command -v nproc
8950s0%nproc
8970s0%export MAKEJOBS=-j8
8980s0%MAKEJOBS=-j8
8990s0%export RUN_UNIT_TESTS=true
9000s0%RUN_UNIT_TESTS=true
9010s0%export RUN_FUNCTIONAL_TESTS=true
9020s0%RUN_FUNCTIONAL_TESTS=true
9030s0%export RUN_TIDY=false
9040s0%RUN_TIDY=false
9050s0%export TEST_RUNNER_TIMEOUT_FACTOR=40
9060s0%TEST_RUNNER_TIMEOUT_FACTOR=40
9070s0%export RUN_FUZZ_TESTS=false
9080s0%RUN_FUZZ_TESTS=false
9090s0%export BOOST_TEST_RANDOM=1
9100s0%BOOST_TEST_RANDOM=1
9110s0%export DEBIAN_FRONTEND=noninteractive
9120s0%DEBIAN_FRONTEND=noninteractive
9130s0%export CCACHE_MAXSIZE=500M
9140s0%CCACHE_MAXSIZE=500M
9150s0%export CCACHE_TEMPDIR=/tmp/.ccache-temp
9160s0%CCACHE_TEMPDIR=/tmp/.ccache-temp
9170s0%export CCACHE_COMPRESS=1
9180s0%CCACHE_COMPRESS=1
9190s0%export CCACHE_DIR=/home/admin/actions-runner/_work/_temp/ccache_dir
9200s0%CCACHE_DIR=/home/admin/actions-runner/_work/_temp/ccache_dir
9210s0%export BASE_OUTDIR=/home/admin/actions-runner/_work/_temp/ci/scratch/out
9220s0%BASE_OUTDIR=/home/admin/actions-runner/_work/_temp/ci/scratch/out
9230s0%export PREVIOUS_RELEASES_DIR=/home/admin/actions-runner/_work/_temp/previous_releases
9240s0%PREVIOUS_RELEASES_DIR=/home/admin/actions-runner/_work/_temp/previous_releases
9250s0%export 'CI_BASE_PACKAGES=build-essential pkgconf curl ca-certificates ccache python3-dev rsync git procps bison e2fsprogs cmake ninja-build'
9260s0%CI_BASE_PACKAGES='build-essential pkgconf curl ca-certificates ccache python3-dev rsync git procps bison e2fsprogs cmake ninja-build'
9270s0%export GOAL=install
9280s0%GOAL=install
9290s0%export DIR_QA_ASSETS=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets
9300s0%DIR_QA_ASSETS=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets
9310s0%export CI_RETRY_EXE=retry
9320s0%CI_RETRY_EXE=retry
9330s0%export CI_IMAGE_PLATFORM=linux
9340s0%CI_IMAGE_PLATFORM=linux
9350s0%set -o errexit
9360s0%./ci/test/02_run_container.py
9380s0%bash -c 'grep export ./ci/test/00_setup_env*.sh'
9390s0%cat /tmp/env-admin-ci_native_asan
9640s0%-DCMAKE_C_FLAGS='-ftrivial-auto-var-init=pattern' -DCMAKE_CXX_FLAGS='-ftrivial-auto-var-init=pattern' -DCMAKE_EXE_LINKER_FLAGS='-fuse-ld=mold' -DAPPEND_CXXFLAGS='-std=c++23' -DAPPEND_CPPFLAGS='-DARENA_DEBUG -DDEBUG_LOCKORDER'
97646s1%docker buildx build --file=/home/admin/actions-runner/_work/bitcoin/bitcoin/ci/test_imagefile --build-arg=CI_IMAGE_NAME_TAG=mirror.gcr.io/ubuntu:24.04 --build-arg=FILE_ENV=./ci/test/00_setup_env_native_asan.sh --build-arg=BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp --platform=linux --label=bitcoin-ci-test --tag=ci_native_asan --cache-from type=gha,url=http://127.0.0.1:12321/,url_v2=http://127.0.0.1:12321/,scope=ci_native_asan --load /home/admin/actions-runner/_work/bitcoin/bitcoin
10560s0%docker volume create ci_native_asan_ccache
10580s0%docker volume create ci_native_asan_depends
10600s0%docker volume create ci_native_asan_depends_sources
10620s0%docker volume create ci_native_asan_previous_releases
10640s0%docker network create --ipv6 --subnet 1111:1111::/112 ci-ip6net
10670s0%docker image prune --force --filter label=bitcoin-ci-test
10691s0%docker run --rm --interactive --detach --tty --cap-add=LINUX_IMMUTABLE --privileged -v /sys/kernel:/sys/kernel:rw --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_asan --name=ci_native_asan --network=ci-ip6net --platform=linux ci_native_asan
10700s0%docker exec 86b41934d0ea3c94abdbd2a8d5c4783c0a23b8474591161a94890cfc14c38591 rsync --recursive --perms --stats --human-readable /home/admin/actions-runner/_work/bitcoin/bitcoin/ /home/admin/actions-runner/_work/_temp
10880s0%docker exec 86b41934d0ea3c94abdbd2a8d5c4783c0a23b8474591161a94890cfc14c38591 /home/admin/actions-runner/_work/_temp/ci/test/01_base_install.sh
10890s0%CFG_DONE=/home/admin/actions-runner/_work/_temp/ci.base-install-done
10900s0%cat /home/admin/actions-runner/_work/_temp/ci.base-install-done
10910s0%'[' done == done ']'
10920s0%echo 'Skip base install'
10930s0%exit 0
10950s0%docker exec 86b41934d0ea3c94abdbd2a8d5c4783c0a23b8474591161a94890cfc14c38591 /home/admin/actions-runner/_work/_temp/ci/test/03_test_script.sh
10960s0%cd /home/admin/actions-runner/_work/_temp
10970s0%export 'PATH=/path_with space:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
10980s0%PATH='/path_with space:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
10990s0%export ASAN_OPTIONS=detect_leaks=1:detect_stack_use_after_return=1:check_initialization_order=1:strict_init_order=1
11000s0%ASAN_OPTIONS=detect_leaks=1:detect_stack_use_after_return=1:check_initialization_order=1:strict_init_order=1
11010s0%export LSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/lsan
11020s0%LSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/lsan
11030s0%export TSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/tsan:halt_on_error=1:second_deadlock_stack=1
11040s0%TSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/tsan:halt_on_error=1:second_deadlock_stack=1
11050s0%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
11060s0%UBSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/ubsan:print_stacktrace=1:halt_on_error=1:report_error_type=1
11070s0%nproc
11080s0%echo 'Number of available processing units: 8'
11090s0%'[' '' == macos ']'
11100s0%free -m -h
11150s0%uname --kernel-name --kernel-release
11160s0%echo 'System info: Linux 6.14.0-37-generic'
11170s0%lscpu
11640s0%echo 'Free disk space:'
11650s0%df -h
11660s0%[[ ci_native_asan == \c\i\_\n\a\t\i\v\e\_\a\s\a\n ]]
11672s0%retry apt-get update
11891s0%retry bash -c 'apt-get install --no-install-recommends --no-upgrade -y systemtap-sdt-dev clang-21 llvm-21 libclang-rt-21-dev mold python3-zmq qt6-base-dev qt6-tools-dev qt6-l10n-tools libevent-dev libboost-dev libzmq3-dev libqrencode-dev libsqlite3-dev bpfcc-tools linux-headers-6.14.0-37-generic libcapnp-dev capnproto python3-pip'
12130s0%/home/admin/actions-runner/_work/_temp/depends/config.guess
12140s0%export HOST=x86_64-pc-linux-gnu
12150s0%HOST=x86_64-pc-linux-gnu
12160s0%echo '=== BEGIN env ==='
12170s0%env
12510s0%-DCMAKE_C_FLAGS='-ftrivial-auto-var-init=pattern' -DCMAKE_CXX_FLAGS='-ftrivial-auto-var-init=pattern' -DCMAKE_EXE_LINKER_FLAGS='-fuse-ld=mold' -DAPPEND_CXXFLAGS='-std=c++23' -DAPPEND_CPPFLAGS='-DARENA_DEBUG -DDEBUG_LOCKORDER'
12670s0%echo '=== END env ==='
12680s0%[[ '' != true ]]
12690s0%tee /dev/fd/63
12700s0%patch -p1
12710s0%'[' false = true ']'
12720s0%'[' true = true ']'
12730s0%export DIR_UNIT_TEST_DATA=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
12740s0%DIR_UNIT_TEST_DATA=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
12750s0%'[' '!' -d /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/ ']'
12760s0%mkdir -p /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
12790s0%b/src/leveldb/db/db_impl.cc
12890s0%1].Add(stats);
12910s0%retry curl --location --fail https://github.com/bitcoin-core/qa-assets/raw/main/unit_test_data/script_assets_test.json -o /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data//script_assets_test.json
13000s0%'[' '' == macos ']'
13010s0%echo
13020s0%'[' -z 1 ']'
13030s0%'[' '' = true ']'
13040s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON'
13050s0%'[' -z 1 ']'
13060s0%'[' -z '' ']'
13070s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DWERROR=ON'
13080s0%ccache --zero-stats
13100s0%PRINT_CCACHE_STATISTICS='ccache --version | head -n 1 && ccache --show-stats'
13110s0%BASE_BUILD_DIR=/home/admin/actions-runner/_work/_temp/build
13120s0%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'
13130s0%[[ '' == true ]]
13140s0%[[ false == true ]]
13150s0%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 --preset=dev-mode -DSANITIZERS=address,float-divide-by-zero,integer,undefined -DCMAKE_C_COMPILER=clang -DCMAKE_CXX_COMPILER=clang++ -DCMAKE_C_FLAGS='\''-ftrivial-auto-var-init=pattern'\'' -DCMAKE_CXX_FLAGS='\''-ftrivial-auto-var-init=pattern'\'' -DCMAKE_EXE_LINKER_FLAGS='\''-fuse-ld=mold'\'' -DAPPEND_CXXFLAGS='\''-std=c++23'\'' -DAPPEND_CPPFLAGS='\''-DARENA_DEBUG -DDEBUG_LOCKORDER'\'' )'
13160s0%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 --preset=dev-mode -DSANITIZERS=address,float-divide-by-zero,integer,undefined -DCMAKE_C_COMPILER=clang -DCMAKE_CXX_COMPILER=clang++ -DCMAKE_C_FLAGS='-ftrivial-auto-var-init=pattern' -DCMAKE_CXX_FLAGS='-ftrivial-auto-var-init=pattern' -DCMAKE_EXE_LINKER_FLAGS='-fuse-ld=mold' -DAPPEND_CXXFLAGS='-std=c++23' -DAPPEND_CPPFLAGS='-DARENA_DEBUG -DDEBUG_LOCKORDER')
13170s0%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 --preset=dev-mode -DSANITIZERS=address,float-divide-by-zero,integer,undefined -DCMAKE_C_COMPILER=clang -DCMAKE_CXX_COMPILER=clang++ -DCMAKE_C_FLAGS=-ftrivial-auto-var-init=pattern -DCMAKE_CXX_FLAGS=-ftrivial-auto-var-init=pattern -DCMAKE_EXE_LINKER_FLAGS=-fuse-ld=mold -DAPPEND_CXXFLAGS=-std=c++23 '-DAPPEND_CPPFLAGS=-DARENA_DEBUG -DDEBUG_LOCKORDER'
134111s0%- skipped
16630s0%compiler .......................... Clang 21.1.8, /usr/bin/clang++
16660s0%compiler flags .................... -ftrivial-auto-var-init=pattern -O2 -g -std=c++20 -fPIC -fmacro-prefix-map=/home/admin/actions-runner/_work/_temp/src=. -U_FORTIFY_SOURCE -D_FORTIFY_SOURCE=3 -Wstack-protector -fstack-protector-all -fcf-protection=full -fstack-clash-protection -Werror -fsanitize=address,float-divide-by-zero,integer,undefined -Wall -Wextra -Wgnu -Wformat -Wformat-security -Wvla -Wshadow-field -Wthread-safety -Wthread-safety-pointer -Wloop-analysis -Wredundant-decls -Wunused-member-function -Wdate-time -Wconditional-uninitialized -Woverloaded-virtual -Wsuggest-override -Wimplicit-fallthrough -Wunreachable-code -Wdocumentation -Wself-assign -Wundef -Wno-unused-parameter -DARENA_DEBUG -DDEBUG_LOCKORDER -std=c++23
16800s0%[[ install != all ]]
16810s0%[[ install != codegen ]]
16820s0%GOAL='all install'
16837m19s17%cmake --build /home/admin/actions-runner/_work/_temp/build -j8 --target all install
28730s0%bash -c 'ccache --version | head -n 1 && ccache --show-stats'
28850s0%ccache --show-stats
28860s0%grep Hits:
28870s0%head -1
28880s0%sed 's/.*(\(.*\)%).*/\1/'
28890s0%hit_rate=22.77
28900s0%'[' 22 -lt 75 ']'
28910s0%echo '::notice title=low ccache hitrate::Ccache hit-rate in ci_native_asan was 22.77%'
28930s0%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/
29020s0%du -sh /home/admin/actions-runner/_work/_temp/previous_releases
29040s0%'[' -n 1 ']'
29050s0%ulimit -s 512
29060s0%'[' -n '' ']'
29070s0%'[' '' = true ']'
29080s0%'[' true = true ']'
29090s0%DIR_UNIT_TEST_DATA=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
29100s0%LD_LIBRARY_PATH=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/lib
29110s0%CTEST_OUTPUT_ON_FAILURE=ON
29123m9s7%ctest --test-dir /home/admin/actions-runner/_work/_temp/build --stop-on-failure -j8 --timeout 2400
32290s0%'[' true = true ']'
32300s0%eval 'TEST_RUNNER_EXTRA=()'
32310s0%TEST_RUNNER_EXTRA=()
32320s0%LD_LIBRARY_PATH=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/lib
323328m27s69%/home/admin/actions-runner/_work/_temp/build/test/functional/test_runner.py -j8 --tmpdirprefix /home/admin/actions-runner/_work/_temp/ci/scratch/test_runner/ --ansi --combinedlogslen=99999999 --timeout-factor=40 --quiet --failfast
46100s0%'[' false = true ']'
46110s0%[[ '' == true ]]
46120s0%'[' false = true ']'
46146s0%docker container kill 86b41934d0ea3c94abdbd2a8d5c4783c0a23b8474591161a94890cfc14c38591
Tags
  • ASan + LSan + UBSan + integer
  • 2026-01/kernel-logging-layering-predicate
  • COMPLETED