Task 62086609355

refactor: separate log generation from log handling

2026-01-31 14:06:58 completed pr/mlog 9569400e51aa04dbbb99f4ee9162bdacafa0a818


Commands that took longer than 1 second (total 19m50s)
linedurationpercentagecommand
8530s0%dirname ./ci/test/00_setup_env.sh
8540s0%cd ./ci/test/../../
8550s0%pwd
8560s0%BASE_READ_ONLY_DIR=/home/admin/actions-runner/_work/bitcoin/bitcoin
8570s0%export BASE_READ_ONLY_DIR
8580s0%export BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp
8590s0%BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp
8600s0%export DEPENDS_DIR=/home/admin/actions-runner/_work/_temp/depends
8610s0%DEPENDS_DIR=/home/admin/actions-runner/_work/_temp/depends
8620s0%export BASE_SCRATCH_DIR=/home/admin/actions-runner/_work/_temp/ci/scratch
8630s0%BASE_SCRATCH_DIR=/home/admin/actions-runner/_work/_temp/ci/scratch
8640s0%echo 'Setting specific values in env'
8650s0%'[' -n ./ci/test/00_setup_env_native_tsan.sh ']'
8660s0%set -o errexit
8670s0%source ./ci/test/00_setup_env_native_tsan.sh
8690s0%export LC_ALL=C.UTF-8
8700s0%LC_ALL=C.UTF-8
8710s0%export CONTAINER_NAME=ci_native_tsan
8720s0%CONTAINER_NAME=ci_native_tsan
8730s0%export CI_IMAGE_NAME_TAG=mirror.gcr.io/ubuntu:24.04
8740s0%CI_IMAGE_NAME_TAG=mirror.gcr.io/ubuntu:24.04
8750s0%export APT_LLVM_V=21
8760s0%APT_LLVM_V=21
8770s0%LIBCXX_DIR=/cxx_build/
8780s0%LIBCXX_FLAGS='-fsanitize=thread -nostdinc++ -nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument'
8790s0%export 'PACKAGES=clang-21 llvm-21 llvm-21-dev libclang-21-dev libclang-rt-21-dev python3-zmq python3-pip'
8800s0%PACKAGES='clang-21 llvm-21 llvm-21-dev libclang-21-dev libclang-rt-21-dev python3-zmq python3-pip'
8810s0%export 'PIP_PACKAGES=--break-system-packages pycapnp'
8820s0%PIP_PACKAGES='--break-system-packages pycapnp'
8830s0%export 'DEP_OPTS=CC=clang CXX=clang++ CXXFLAGS='\''-fsanitize=thread -nostdinc++ -nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument'\'' NO_QT=1'
8840s0%DEP_OPTS='CC=clang CXX=clang++ CXXFLAGS='\''-fsanitize=thread -nostdinc++ -nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument'\'' NO_QT=1'
8850s0%export GOAL=install
8860s0%GOAL=install
8870s0%export CI_LIMIT_STACK_SIZE=1
8880s0%CI_LIMIT_STACK_SIZE=1
8890s0%export 'BITCOIN_CONFIG= --preset=dev-mode -DBUILD_GUI=OFF -DSANITIZERS=thread -DAPPEND_CPPFLAGS='\''-DARENA_DEBUG -DDEBUG_LOCKCONTENTION -D_LIBCPP_REMOVE_TRANSITIVE_INCLUDES'\'' '
8900s0%BITCOIN_CONFIG=' --preset=dev-mode -DBUILD_GUI=OFF -DSANITIZERS=thread -DAPPEND_CPPFLAGS='\''-DARENA_DEBUG -DDEBUG_LOCKCONTENTION -D_LIBCPP_REMOVE_TRANSITIVE_INCLUDES'\'' '
8910s0%export USE_INSTRUMENTED_LIBCPP=Thread
8920s0%USE_INSTRUMENTED_LIBCPP=Thread
8930s0%echo 'Fallback to default values in env (if not yet set)'
8950s0%command -v nproc
8960s0%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_tsan
9730s0%CXXFLAGS='-fsanitize=thread -nostdinc++ -nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument' NO_QT=1
97625s2%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_tsan.sh --build-arg=BASE_ROOT_DIR=/home/admin/actions-runner/_work/_temp --platform=linux --label=bitcoin-ci-test --tag=ci_native_tsan --cache-from type=gha,url=http://127.0.0.1:12321/,url_v2=http://127.0.0.1:12321/,scope=ci_native_tsan --load /home/admin/actions-runner/_work/bitcoin/bitcoin
10510s0%docker volume create ci_native_tsan_ccache
10530s0%docker volume create ci_native_tsan_depends
10550s0%docker volume create ci_native_tsan_depends_sources
10570s0%docker volume create ci_native_tsan_previous_releases
10590s0%docker network create --ipv6 --subnet 1111:1111::/112 ci-ip6net
10620s0%docker image prune --force --filter label=bitcoin-ci-test
10640s0%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_tsan --name=ci_native_tsan --network=ci-ip6net --platform=linux ci_native_tsan
10650s0%docker exec 7d85dbd238465874160efc1eeb20794c0816badfe49eca79d93f1189d58bf19e rsync --recursive --perms --stats --human-readable /home/admin/actions-runner/_work/bitcoin/bitcoin/ /home/admin/actions-runner/_work/_temp
10830s0%docker exec 7d85dbd238465874160efc1eeb20794c0816badfe49eca79d93f1189d58bf19e /home/admin/actions-runner/_work/_temp/ci/test/01_base_install.sh
10840s0%CFG_DONE=/home/admin/actions-runner/_work/_temp/ci.base-install-done
10850s0%cat /home/admin/actions-runner/_work/_temp/ci.base-install-done
10860s0%'[' done == done ']'
10870s0%echo 'Skip base install'
10880s0%exit 0
10900s0%docker exec 7d85dbd238465874160efc1eeb20794c0816badfe49eca79d93f1189d58bf19e /home/admin/actions-runner/_work/_temp/ci/test/03_test_script.sh
10910s0%cd /home/admin/actions-runner/_work/_temp
10920s0%export 'PATH=/path_with space:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
10930s0%PATH='/path_with space:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin'
10940s0%export ASAN_OPTIONS=detect_leaks=1:detect_stack_use_after_return=1:check_initialization_order=1:strict_init_order=1
10950s0%ASAN_OPTIONS=detect_leaks=1:detect_stack_use_after_return=1:check_initialization_order=1:strict_init_order=1
10960s0%export LSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/lsan
10970s0%LSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/lsan
10980s0%export TSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/tsan:halt_on_error=1:second_deadlock_stack=1
10990s0%TSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/tsan:halt_on_error=1:second_deadlock_stack=1
11000s0%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
11010s0%UBSAN_OPTIONS=suppressions=/home/admin/actions-runner/_work/_temp/test/sanitizer_suppressions/ubsan:print_stacktrace=1:halt_on_error=1:report_error_type=1
11020s0%nproc
11030s0%echo 'Number of available processing units: 8'
11050s0%'[' '' == macos ']'
11060s0%free -m -h
11100s0%uname --kernel-name --kernel-release
11110s0%echo 'System info: Linux 6.14.0-37-generic'
11120s0%lscpu
11340s0%echo 'Free disk space:'
11350s0%df -h
11600s0%[[ ci_native_tsan == \c\i\_\n\a\t\i\v\e\_\a\s\a\n ]]
11610s0%/home/admin/actions-runner/_work/_temp/depends/config.guess
11700s0%export HOST=x86_64-pc-linux-gnu
11710s0%HOST=x86_64-pc-linux-gnu
11720s0%echo '=== BEGIN env ==='
11730s0%env
12190s0%CXXFLAGS='-fsanitize=thread -nostdinc++ -nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument' NO_QT=1
12230s0%echo '=== END env ==='
12240s0%[[ '' != true ]]
12250s0%tee /dev/fd/63
12260s0%patch -p1
12290s0%b/src/leveldb/db/db_impl.cc
12390s0%1].Add(stats);
12400s0%'[' false = true ']'
12410s0%'[' true = true ']'
12420s0%export DIR_UNIT_TEST_DATA=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
12430s0%DIR_UNIT_TEST_DATA=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
12440s0%'[' '!' -d /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/ ']'
12450s0%mkdir -p /home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
12471s0%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
12560s0%'[' '' == macos ']'
12570s0%echo
12580s0%'[' -z '' ']'
12590s0%[[ mirror.gcr.io/ubuntu:24.04 == *alpine* ]]
12600s0%SHELL_OPTS=CONFIG_SHELL=
12614s0%bash -c 'CONFIG_SHELL= make -j8 -C depends HOST=x86_64-pc-linux-gnu CC=clang CXX=clang++ CXXFLAGS='\''-fsanitize=thread -nostdinc++ -nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument'\'' NO_QT=1 LOG=1'
12750s0%'[' '' = true ']'
12760s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON'
12770s0%'[' -z '' ']'
12780s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DCMAKE_TOOLCHAIN_FILE=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/toolchain.cmake'
12790s0%'[' -z '' ']'
12800s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DCMAKE_TOOLCHAIN_FILE=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/toolchain.cmake -DWERROR=ON'
12810s0%ccache --zero-stats
12830s0%PRINT_CCACHE_STATISTICS='ccache --version | head -n 1 && ccache --show-stats'
12840s0%BASE_BUILD_DIR=/home/admin/actions-runner/_work/_temp/build
12850s0%BITCOIN_CONFIG_ALL='-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DCMAKE_TOOLCHAIN_FILE=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/toolchain.cmake -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev'
12860s0%[[ '' == true ]]
12870s0%[[ false == true ]]
12880s0%eval 'CMAKE_ARGS=(-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DCMAKE_TOOLCHAIN_FILE=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/toolchain.cmake -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev --preset=dev-mode -DBUILD_GUI=OFF -DSANITIZERS=thread -DAPPEND_CPPFLAGS='\''-DARENA_DEBUG -DDEBUG_LOCKCONTENTION -D_LIBCPP_REMOVE_TRANSITIVE_INCLUDES'\'' )'
12890s0%CMAKE_ARGS=(-DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DCMAKE_TOOLCHAIN_FILE=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/toolchain.cmake -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev --preset=dev-mode -DBUILD_GUI=OFF -DSANITIZERS=thread -DAPPEND_CPPFLAGS='-DARENA_DEBUG -DDEBUG_LOCKCONTENTION -D_LIBCPP_REMOVE_TRANSITIVE_INCLUDES')
12900s0%cmake -S /home/admin/actions-runner/_work/_temp -B /home/admin/actions-runner/_work/_temp/build -DBUILD_BENCH=ON -DBUILD_FUZZ_BINARY=ON -DCMAKE_TOOLCHAIN_FILE=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/toolchain.cmake -DWERROR=ON -DCMAKE_INSTALL_PREFIX=/home/admin/actions-runner/_work/_temp/ci/scratch/out -Werror=dev --preset=dev-mode -DBUILD_GUI=OFF -DSANITIZERS=thread '-DAPPEND_CPPFLAGS=-DARENA_DEBUG -DDEBUG_LOCKCONTENTION -D_LIBCPP_REMOVE_TRANSITIVE_INCLUDES'
131313s1%- skipped
16250s0%compiler .......................... Clang 21.1.8, /bin/clang++
16280s0%compiler flags .................... -fsanitize=thread -nostdinc++ -nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument -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=thread -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_LOCKCONTENTION -D_LIBCPP_REMOVE_TRANSITIVE_INCLUDES
16290s0%-nostdlib++ -isystem /cxx_build/include/c++/v1 -L/cxx_build/lib -Wl,-rpath,/cxx_build/lib -lc++ -lc++abi -lpthread -Wno-unused-command-line-argument -O2 -g -fstack-protector-all -fcf-protection=full -fstack-clash-protection -Wl,-z,relro -Wl,-z,now -Wl,-z,separate-code -fsanitize=thread -fPIE -pie
16420s0%[[ install != all ]]
16430s0%[[ install != codegen ]]
16440s0%GOAL='all install'
16454m50s24%cmake --build /home/admin/actions-runner/_work/_temp/build -j8 --target all install
26170s0%bash -c 'ccache --version | head -n 1 && ccache --show-stats'
26290s0%ccache --show-stats
26300s0%grep Hits:
26310s0%head -1
26320s0%sed 's/.*(\(.*\)%).*/\1/'
26330s0%hit_rate=27.04
26340s0%'[' 27 -lt 75 ']'
26350s0%echo '::notice title=low ccache hitrate::Ccache hit-rate in ci_native_tsan was 27.04%'
26370s0%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/ /home/admin/actions-runner/_work/_temp/depends/work/ /home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/
26480s0%du -sh /home/admin/actions-runner/_work/_temp/previous_releases
26500s0%'[' -n 1 ']'
26510s0%ulimit -s 512
26520s0%'[' -n '' ']'
26530s0%'[' '' = true ']'
26540s0%'[' true = true ']'
26550s0%DIR_UNIT_TEST_DATA=/home/admin/actions-runner/_work/_temp/ci/scratch/qa-assets/unit_test_data/
26560s0%LD_LIBRARY_PATH=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/lib
26570s0%CTEST_OUTPUT_ON_FAILURE=ON
26585m6s25%ctest --test-dir /home/admin/actions-runner/_work/_temp/build --stop-on-failure -j8 --timeout 2400
29710s0%'[' true = true ']'
29720s0%eval 'TEST_RUNNER_EXTRA=()'
29730s0%TEST_RUNNER_EXTRA=()
29740s0%LD_LIBRARY_PATH=/home/admin/actions-runner/_work/_temp/depends/x86_64-pc-linux-gnu/lib
29758m28s42%/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
43040s0%'[' false = true ']'
43050s0%[[ '' == true ]]
43060s0%'[' false = true ']'
43567s0%docker container kill 7d85dbd238465874160efc1eeb20794c0816badfe49eca79d93f1189d58bf19e
Tags
  • TSan
  • pr/mlog
  • COMPLETED