Diff of the two buildlogs: -- --- b1/build.log 2023-04-24 19:17:58.447154073 +0000 +++ b2/build.log 2023-04-24 19:23:43.903834284 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Apr 24 07:12:14 -12 2023 -I: pbuilder-time-stamp: 1682363534 +I: Current time: Mon May 27 15:41:01 +14 2024 +I: pbuilder-time-stamp: 1716774061 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./erlang-lager_3.9.2.orig.tar.gz] I: copying [./erlang-lager_3.9.2-2.debian.tar.xz] I: Extracting source -gpgv: Signature made Mon Dec 20 18:59:54 2021 -12 +gpgv: Signature made Tue Dec 21 20:59:54 2021 +14 gpgv: using RSA key 08418BBE44720F2DBAC505ECBA7A2496DAE40D1C gpgv: issuer "debalance@debian.org" gpgv: Can't check signature: No public key @@ -28,52 +28,84 @@ dpkg-source: info: applying remove-deps.diff I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/20039/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/D01_modify_environment starting +debug: Running on codethink11-arm64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 May 27 15:41 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='arm64' - DEBIAN_FRONTEND='noninteractive' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="15" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.15(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=8' - DISTRIBUTION='bookworm' - HOME='/var/lib/jenkins' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/var/lib/jenkins + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='20039' - PS1='# ' - PS2='> ' + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=13339 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.Y3DmkNJS/pbuilderrc_rmE9 --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.Y3DmkNJS/b1 --logfile b1/build.log erlang-lager_3.9.2-2.dsc' - SUDO_GID='117' - SUDO_UID='110' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - USERNAME='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.16:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.Y3DmkNJS/pbuilderrc_xgmz --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.Y3DmkNJS/b2 --logfile b2/build.log --extrapackages usrmerge erlang-lager_3.9.2-2.dsc' + SUDO_GID=117 + SUDO_UID=110 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + USERNAME=root + _='I: set' + http_proxy=http://192.168.101.16:3128 I: uname -a - Linux codethink14-arm64 4.15.0-209-generic #220-Ubuntu SMP Tue Mar 21 19:52:44 UTC 2023 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-209-generic #220-Ubuntu SMP Tue Mar 21 19:52:44 UTC 2023 aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Apr 21 22:25 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/20039/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 25 06:47 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -405,7 +437,7 @@ Get: 284 http://deb.debian.org/debian bookworm/main arm64 rebar arm64 2.6.4-3 [203 kB] Get: 285 http://deb.debian.org/debian bookworm/main arm64 dh-rebar all 0.0.6 [7820 B] Get: 286 http://deb.debian.org/debian bookworm/main arm64 erlang-goldrush arm64 0.2.0-8 [72.6 kB] -Fetched 168 MB in 3s (50.7 MB/s) +Fetched 168 MB in 3s (49.3 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libargon2-1:arm64. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19616 files and directories currently installed.) @@ -1585,8 +1617,17 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +usrmerge is already the newest version (35). +0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/erlang-lager-3.9.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../erlang-lager_3.9.2-2_source.changes +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/A99_set_merged_usr finished +hostname: Temporary failure in name resolution +I: Running cd /build/erlang-lager-3.9.2/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../erlang-lager_3.9.2-2_source.changes dpkg-buildpackage: info: source package erlang-lager dpkg-buildpackage: info: source version 3.9.2-2 dpkg-buildpackage: info: source distribution unstable @@ -1730,13 +1771,13 @@ Compiled src/lager_util.erl Compiled src/lager_transform.erl Compiled src/lager_rotator_behaviour.erl -Compiled src/lager.erl Compiled src/lager_default_formatter.erl +Compiled src/lager.erl Compiled src/lager_config.erl Compiled src/lager_format.erl Compiled src/lager_handler_watcher.erl -Compiled src/lager_crash_log.erl Compiled src/lager_stdlib.erl +Compiled src/lager_crash_log.erl Compiled src/lager_file_backend.erl Compiled src/lager_backend_throttle.erl Compiled src/lager_console_backend.erl @@ -1747,19 +1788,19 @@ Compiled src/lager_sup.erl Compiled src/lager_handler_watcher_sup.erl Compiled src/lager_rotator_default.erl -Compiled src/error_logger_lager_h.erl Compiled test/lager_slow_backend.erl Compiled test/trunc_io_eqc.erl Compiled test/sync_error_logger.erl +Compiled src/error_logger_lager_h.erl Compiled test/pr_composite_test.erl Compiled test/lager_metadata_whitelist_test.erl Compiled test/lager_app_tests.erl -Compiled src/lager_app.erl -Compiled test/lager_rotate.erl Compiled test/special_process.erl -Compiled test/lager_trace_test.erl -Compiled test/lager_crash_backend.erl +Compiled test/lager_rotate.erl +Compiled src/lager_app.erl Compiled test/lager_manager_killer_test.erl +Compiled test/lager_crash_backend.erl +Compiled test/lager_trace_test.erl Compiled test/crash_statem.erl pr_stacktrace_test.erl:14:5: Warning: the call to integer_to_list/1 will fail with a 'badarg' exception pr_stacktrace_test.erl:14:5: Warning: the call to integer_to_list/1 will fail with a 'badarg' exception @@ -1778,8 +1819,8 @@ module 'lager_util' lager_util: parse_test...ok lager_util: parse_fail_test...ok - lager_util: rotation_calculation_test...[0.006 s] ok - lager_util: check_trace_test...[0.227 s] ok + lager_util: rotation_calculation_test...[0.005 s] ok + lager_util: check_trace_test...[0.208 s] ok lager_util:753: -is_loggable_test_/0-fun-9- (Loggable by severity only)...ok lager_util:754: -is_loggable_test_/0-fun-7- (Not loggable by severity only)...ok lager_util:755: -is_loggable_test_/0-fun-5- (Loggable by severity with destination)...ok @@ -1796,14 +1837,9 @@ lager_util: expand_path_test...ok lager_util:845: -sink_name_test_/0-fun-3-...ok lager_util:846: -sink_name_test_/0-fun-1-...ok - [done in 0.293 s] + [done in 0.273 s] module 'lager_transform' module 'lager_rotator_behaviour' -module 'lager' - lager: get_sink_handler_status_ascii_test...ok - lager: get_sink_handler_status_latin_test...ok - lager: get_sink_handler_status_unicode_test...ok - [done in 0.009 s] module 'lager_default_formatter' lager_default_formatter:277: -basic_test_/0-fun-59- (Default formatting test)...ok lager_default_formatter:286: -basic_test_/0-fun-57- (Basic Formatting)...ok @@ -1835,47 +1871,52 @@ lager_default_formatter:550: -basic_test_/0-fun-3- (pterm absence test)...ok lager_default_formatter:566: -basic_test_/0-fun-1- (node formatting basic)...ok [done in 0.087 s] +module 'lager' + lager: get_sink_handler_status_ascii_test...ok + lager: get_sink_handler_status_latin_test...ok + lager: get_sink_handler_status_unicode_test...ok + [done in 0.009 s] module 'lager_config' module 'lager_format' module 'lager_handler_watcher' - lager_handler_watcher: -reinstall_on_initial_failure_test_/0-fun-3-...[6.072 s] ok - lager_handler_watcher: -reinstall_on_runtime_failure_test_/0-fun-3-...[6.050 s] ok - lager_handler_watcher: -reinstall_handlers_after_killer_hwm_test_/0-fun-1-...[6.105 s] ok - [done in 18.236 s] + lager_handler_watcher: -reinstall_on_initial_failure_test_/0-fun-3-...[6.065 s] ok + lager_handler_watcher: -reinstall_on_runtime_failure_test_/0-fun-3-...[6.051 s] ok + lager_handler_watcher: -reinstall_handlers_after_killer_hwm_test_/0-fun-1-...[6.112 s] ok + [done in 18.237 s] +module 'lager_stdlib' module 'lager_crash_log' lager_crash_log: -filesystem_test_/0-fun-30- (under normal circumstances, file should be opened)...[0.001 s] ok lager_crash_log: -filesystem_test_/0-fun-27- (file can't be opened on startup triggers an error message)...[0.006 s] ok lager_crash_log: -filesystem_test_/0-fun-21- (file that becomes unavailable at runtime should trigger an error message)...[0.002 s] ok - lager_crash_log: -filesystem_test_/0-fun-11- (unavailable files that are fixed at runtime should start having log messages written)...[0.001 s] ok + lager_crash_log: -filesystem_test_/0-fun-11- (unavailable files that are fixed at runtime should start having log messages written)...[0.002 s] ok lager_crash_log: -filesystem_test_/0-fun-6- (external logfile rotation/deletion should be handled)...[0.003 s] ok - [done in 5.310 s] -module 'lager_stdlib' + [done in 5.292 s] module 'lager_file_backend' lager_file_backend: get_loglevel_test...ok lager_file_backend: -rotation_test_/0-fun-17- (External rotation should work)...[0.002 s] ok - lager_file_backend: -rotation_test_/0-fun-8- (Internal rotation and delayed write)...[1.003 s] ok + lager_file_backend: -rotation_test_/0-fun-8- (Internal rotation and delayed write)...[1.002 s] ok lager_file_backend: -filesystem_test_/0-fun-81- (under normal circumstances, file should be opened)...[0.001 s] ok lager_file_backend: -filesystem_test_/0-fun-79- (don't choke on unicode)...[0.001 s] ok lager_file_backend: -filesystem_test_/0-fun-77- (don't choke on latin-1)...[0.001 s] ok lager_file_backend: -filesystem_test_/0-fun-75- (file can't be opened on startup triggers an error message)...[0.002 s] ok lager_file_backend: -filesystem_test_/0-fun-69- (file that becomes unavailable at runtime should trigger an error message)...[0.003 s] ok - lager_file_backend: -filesystem_test_/0-fun-62- (unavailable files that are fixed at runtime should start having log messages written)...[0.003 s] ok - lager_file_backend: -filesystem_test_/0-fun-55- (external logfile rotation/deletion should be handled)...[0.004 s] ok + lager_file_backend: -filesystem_test_/0-fun-62- (unavailable files that are fixed at runtime should start having log messages written)...[0.002 s] ok + lager_file_backend: -filesystem_test_/0-fun-55- (external logfile rotation/deletion should be handled)...[0.003 s] ok lager_file_backend: -filesystem_test_/0-fun-45- (internal size rotation should work)...[0.002 s] ok lager_file_backend: -filesystem_test_/0-fun-43- (internal time rotation should work)...[0.002 s] ok - lager_file_backend: -filesystem_test_/0-fun-41- (rotation call should work)...[0.003 s] ok - lager_file_backend: -filesystem_test_/0-fun-39- (sync_on option should work)...[0.002 s] ok - lager_file_backend: -filesystem_test_/0-fun-36- (sync_on none option should work (also tests sync_interval))...[2.003 s] ok - lager_file_backend: -filesystem_test_/0-fun-32- (sync_size option should work)...[0.003 s] ok - lager_file_backend: -filesystem_test_/0-fun-28- (runtime level changes)...[0.003 s] ok + lager_file_backend: -filesystem_test_/0-fun-41- (rotation call should work)...[0.002 s] ok + lager_file_backend: -filesystem_test_/0-fun-39- (sync_on option should work)...[0.001 s] ok + lager_file_backend: -filesystem_test_/0-fun-36- (sync_on none option should work (also tests sync_interval))...[2.002 s] ok + lager_file_backend: -filesystem_test_/0-fun-32- (sync_size option should work)...[0.004 s] ok + lager_file_backend: -filesystem_test_/0-fun-28- (runtime level changes)...[0.002 s] ok lager_file_backend: -filesystem_test_/0-fun-23- (invalid runtime level changes)...[0.001 s] ok lager_file_backend: -filesystem_test_/0-fun-21- (tracing should work)...[1.004 s] ok lager_file_backend: -filesystem_test_/0-fun-18- (tracing should not duplicate messages)...[0.507 s] ok - lager_file_backend: -filesystem_test_/0-fun-12- (tracing to a dedicated file should work)...[0.056 s] ok - lager_file_backend: -filesystem_test_/0-fun-10- (tracing to a dedicated file should work even if root_log is set)...[0.048 s] ok - lager_file_backend: -filesystem_test_/0-fun-8- (tracing with options should work)...[0.076 s] ok - lager_file_backend: -filesystem_test_/0-fun-5- (no silent hwm drops)...[1.983 s] ok - lager_file_backend: -trace_files_test_/0-fun-5- (a trace using file backend set up in configuration should work)...[1.006 s] ok + lager_file_backend: -filesystem_test_/0-fun-12- (tracing to a dedicated file should work)...[0.050 s] ok + lager_file_backend: -filesystem_test_/0-fun-10- (tracing to a dedicated file should work even if root_log is set)...[0.052 s] ok + lager_file_backend: -filesystem_test_/0-fun-8- (tracing with options should work)...[0.068 s] ok + lager_file_backend: -filesystem_test_/0-fun-5- (no silent hwm drops)...[1.282 s] ok + lager_file_backend: -trace_files_test_/0-fun-5- (a trace using file backend set up in configuration should work)...[1.005 s] ok lager_file_backend: -formatting_test_/0-fun-2- (Should have two log files, the second prefixed with 2>)...[0.002 s] ok lager_file_backend:1137: -config_validation_test_/0-fun-27- (missing file)...ok lager_file_backend:1141: -config_validation_test_/0-fun-25- (bad level)...ok @@ -1891,7 +1932,7 @@ lager_file_backend:1181: -config_validation_test_/0-fun-5- (bad formatter module)...ok lager_file_backend:1185: -config_validation_test_/0-fun-3- (bad formatter config)...ok lager_file_backend:1189: -config_validation_test_/0-fun-1- (unknown option)...ok - [done in 9.269 s] + [done in 8.532 s] module 'lager_backend_throttle' module 'lager_console_backend' lager_console_backend:242: -console_config_validation_test_/0-fun-7-...ok @@ -1899,21 +1940,21 @@ lager_console_backend:244: -console_config_validation_test_/0-fun-3-...ok lager_console_backend:245: -console_config_validation_test_/0-fun-1-...ok lager_console_backend: -console_log_test_/0-fun-51- (regular console logging)...[0.001 s] ok - lager_console_backend: -console_log_test_/0-fun-48- (verbose console logging)...[0.001 s] ok + lager_console_backend: -console_log_test_/0-fun-48- (verbose console logging)...ok lager_console_backend: -console_log_test_/0-fun-45- (custom format console logging)...[0.002 s] ok - lager_console_backend: -console_log_test_/0-fun-42- (tracing should work)...[0.551 s] ok - lager_console_backend: -console_log_test_/0-fun-37- (tracing doesn't duplicate messages)...[1.051 s] ok - lager_console_backend: -console_log_test_/0-fun-30- (blacklisting a loglevel works)...[0.502 s] ok - lager_console_backend: -console_log_test_/0-fun-25- (whitelisting a loglevel works)...[0.502 s] ok - lager_console_backend: -console_log_test_/0-fun-20- (console backend with custom group leader)...[0.603 s] ok + lager_console_backend: -console_log_test_/0-fun-42- (tracing should work)...[0.547 s] ok + lager_console_backend: -console_log_test_/0-fun-37- (tracing doesn't duplicate messages)...[1.048 s] ok + lager_console_backend: -console_log_test_/0-fun-30- (blacklisting a loglevel works)...[0.503 s] ok + lager_console_backend: -console_log_test_/0-fun-25- (whitelisting a loglevel works)...[0.503 s] ok + lager_console_backend: -console_log_test_/0-fun-20- (console backend with custom group leader)...[0.604 s] ok lager_console_backend: -console_log_test_/0-fun-13- (console backend with custom group leader using a trace and an ID)...[1.152 s] ok - lager_console_backend: -set_loglevel_test_/0-fun-8- (Get/set loglevel test)...[0.002 s] ok + lager_console_backend: -set_loglevel_test_/0-fun-8- (Get/set loglevel test)...[0.003 s] ok lager_console_backend: -set_loglevel_test_/0-fun-3- (Get/set invalid loglevel test)...ok - [done in 4.973 s] + [done in 4.963 s] module 'lager_msg' module 'lager_common_test_backend' module 'lager_trunc_io' - lager_trunc_io: format_test...[0.001 s] ok + lager_trunc_io: format_test...ok lager_trunc_io: atom_quoting_test...ok lager_trunc_io: sane_float_printing_test...ok lager_trunc_io: float_inside_list_test...ok @@ -1935,34 +1976,34 @@ module 'lager_rotator_default' lager_rotator_default: rotate_file_test...*failed* in function lager_util:delete_test_dir/1 - called as delete_test_dir("/tmp/lager_test/73266293") + called as delete_test_dir("/tmp/lager_test/43458690") in call from lager_rotator_default:rotate_file_test/0 **error:undef output:<<"">> lager_rotator_default: rotate_file_zero_count_test...*failed* in function lager_util:delete_test_dir/1 - called as delete_test_dir("/tmp/lager_test/100176704") + called as delete_test_dir("/tmp/lager_test/69787566") in call from lager_rotator_default:rotate_file_zero_count_test/0 **error:undef output:<<"">> lager_rotator_default: rotate_file_fail_test...*failed* in function lager_util:delete_test_dir/1 - called as delete_test_dir("/tmp/lager_test/78081502") + called as delete_test_dir("/tmp/lager_test/119968008") in call from lager_rotator_default:rotate_file_fail_test/0 **error:undef output:<<"">> - [done in 0.060 s] -module 'error_logger_lager_h' - error_logger_lager_h: -no_silent_hwm_drops_test_/0-fun-2-...[2.371 s] ok - error_logger_lager_h: -shaper_does_not_forward_sup_progress_messages_to_info_level_backend_test_/0-fun-1-...[0.105 s] ok - error_logger_lager_h: -supressed_messages_are_not_counted_for_hwm_test_/0-fun-2-...[0.104 s] ok - [done in 2.589 s] + [done in 0.079 s] module 'lager_slow_backend' module 'trunc_io_eqc' module 'sync_error_logger' +module 'error_logger_lager_h' + error_logger_lager_h: -no_silent_hwm_drops_test_/0-fun-2-...[2.335 s] ok + error_logger_lager_h: -shaper_does_not_forward_sup_progress_messages_to_info_level_backend_test_/0-fun-1-...[0.101 s] ok + error_logger_lager_h: -supressed_messages_are_not_counted_for_hwm_test_/0-fun-2-...[0.099 s] ok + [done in 2.544 s] module 'pr_composite_test' pr_composite_test: nested_record_test...ok pr_composite_test: list_field_test...ok @@ -1974,7 +2015,13 @@ lager_metadata_whitelist_test: -basic_test_/0-fun-5- (Meta1)...ok lager_metadata_whitelist_test: -basic_test_/0-fun-3- (Meta2)...ok lager_metadata_whitelist_test: -basic_test_/0-fun-1- (Meta3)...ok - [done in 1.212 s] + [done in 1.217 s] +module 'special_process' +module 'lager_rotate' + lager_rotate: -rotate_test_/0-fun-7- (Rotate single file)...[0.003 s] ok + lager_rotate: -rotate_test_/0-fun-4- (Rotate sink)...[0.003 s] ok + lager_rotate: -rotate_test_/0-fun-1- (Rotate all)...[0.005 s] ok + [done in 3.183 s] module 'lager_app' lager_app:326: -application_config_mangling_test_/0-fun-9- (Explode the file backend handlers)...ok lager_app:339: -application_config_mangling_test_/0-fun-7- (Explode the short form of backend file handlers)...ok @@ -1982,28 +2029,18 @@ lager_app:363: -application_config_mangling_test_/0-fun-3- (Explode short form with short formatter info)...ok lager_app:374: -application_config_mangling_test_/0-fun-1- (New form needs no expansion)...ok lager_app:408: -check_handler_config_test_/0-fun-15- (lager_file_backend_good)...ok -=ERROR REPORT==== 24-Apr-2023::07:14:36.551301 === +=ERROR REPORT==== 27-May-2024::15:43:26.729099 === Cannot have same file ("same_file.log") in multiple file backends lager_app:411: -check_handler_config_test_/0-fun-12- (lager_file_backend_bad)...ok lager_app:414: -check_handler_config_test_/0-fun-9- (Invalid config dies)...ok - lager_app:417: -check_handler_config_test_/0-fun-7- (Invalid config dies)...[0.005 s] ok + lager_app:417: -check_handler_config_test_/0-fun-7- (Invalid config dies)...[0.010 s] ok lager_app:420: -check_handler_config_test_/0-fun-5- (Old Lager config works)...ok lager_app:423: -check_handler_config_test_/0-fun-2- (New Config missing its list should fail)...ok lager_app_tests: get_env_test (module 'lager_app_tests')...ok - [done in 0.047 s] -module 'lager_rotate' - lager_rotate: -rotate_test_/0-fun-7- (Rotate single file)...[0.003 s] ok - lager_rotate: -rotate_test_/0-fun-4- (Rotate sink)...[0.003 s] ok - lager_rotate: -rotate_test_/0-fun-1- (Rotate all)...[0.005 s] ok - [done in 3.199 s] -module 'special_process' -module 'lager_trace_test' - lager_trace_test: -trace_test_/0-fun-2- (Trace combined with log_root)...[4.176 s] ok - [done in 4.179 s] -module 'lager_crash_backend' + [done in 0.052 s] module 'lager_manager_killer_test' - lager_manager_killer_test: -overload_test_/0-fun-3-...=ERROR REPORT==== 24-Apr-2023::07:14:44.114482 === + lager_manager_killer_test: -overload_test_/0-fun-3-...=ERROR REPORT==== 27-May-2024::15:43:26.768215 === calling logger:remove_handler(default) failed: error {badmatch, {error, {not_found,default}}} @@ -2023,18 +2060,18 @@ {value,false}]} output:<<"">> - lager_manager_killer_test: -overload_alternate_sink_test_/0-fun-3-...=ERROR REPORT==== 24-Apr-2023::07:14:46.117373 === + lager_manager_killer_test: -overload_alternate_sink_test_/0-fun-3-...=ERROR REPORT==== 27-May-2024::15:43:28.770114 === ** gen_event handler lager_manager_killer crashed. ** Was installed in lager_event ** Last event was: {log,{lager_msg,[], - [{pid,<0.2515.0>}, + [{pid,<0.2482.0>}, {line,127}, {file,"lager_handler_watcher.erl"}, {module,lager_handler_watcher}], debug, - {["2023",45,"04",45,"24"], - ["07",58,"14",58,"44",46,"117"]}, - {1682,363684,117612}, + {["2024",45,"05",45,"27"], + ["15",58,"43",58,"26",46,"769"]}, + {1716,774206,769770}, [76,97,103,101,114,32,105,110,115,116,97, 108,108,101,100,32,104,97,110,100,108,101, 114,32, @@ -2044,60 +2081,62 @@ [[" ",32,32,32]," ",32,32,32], [" ",32,32,32], " ",32,32,32], - "\"/tmp/lager_test/90279360/sink.log\""], + "\"/tmp/lager_test/90552618/sink.log\""], 125], 32,105,110,116,111,32,"sink_event"]}} ** When handler state == {state,10,1000} ** Reason == {kill_me,[10,1000]} -=INFO REPORT==== 24-Apr-2023::07:14:50.280526 === +=INFO REPORT==== 27-May-2024::15:43:32.919761 === application: lager exited: stopped type: temporary -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2522.0>,spawned,<0.719.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2462.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,unlink,<0.2471.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2489.0>,spawned,<0.719.0>, {eunit_proc,group_leader_process,[<0.719.0>]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2522.0>,getting_linked,<0.719.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,unlink,<0.2504.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2504.0>,getting_unlinked,<0.44.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2516.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2514.0>,unlink,<0.2515.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2514.0>,exit,shutdown} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2515.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2514.0>,unregister,sink_event} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,getting_unlinked,<0.2515.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,getting_unlinked,<0.2514.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,unlink,<0.2514.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2509.0>,exit,shutdown} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2510.0>,exit,shutdown} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2513.0>,exit,shutdown} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2507.0>,getting_unlinked,<0.2509.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2507.0>,getting_unlinked,<0.2510.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,getting_unlinked,<0.2509.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,getting_unlinked,<0.2510.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,getting_unlinked,<0.2513.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,unlink,<0.2509.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,unlink,<0.2510.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,unlink,<0.2513.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,exit,shutdown} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2508.0>,unregister,lager_handler_watcher_sup} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2511.0>,getting_unlinked,<0.2513.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,getting_unlinked,<0.2508.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,unlink,<0.2508.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2507.0>,exit,killed} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2507.0>,unregister,lager_event} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,getting_unlinked,<0.2507.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,unlink,<0.2507.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,exit,shutdown} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2506.0>,unregister,lager_sup} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2505.0>,getting_unlinked,<0.2506.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2505.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2504.0>,getting_unlinked,<0.2505.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2504.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,spawn,<0.2523.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2489.0>,getting_linked,<0.719.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2471.0>,getting_unlinked,<0.44.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2483.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2481.0>,unlink,<0.2482.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2481.0>,exit,shutdown} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2481.0>,unregister,sink_event} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2482.0>,getting_unlinked,<0.2481.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,getting_unlinked,<0.2481.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,unlink,<0.2481.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2482.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,getting_unlinked,<0.2482.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2476.0>,exit,shutdown} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,unlink,<0.2482.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,getting_unlinked,<0.2476.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,unlink,<0.2476.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2477.0>,exit,shutdown} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2474.0>,getting_unlinked,<0.2476.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2474.0>,getting_unlinked,<0.2477.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2480.0>,exit,shutdown} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,getting_unlinked,<0.2477.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,unlink,<0.2477.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2478.0>,getting_unlinked,<0.2480.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,getting_unlinked,<0.2480.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,unlink,<0.2480.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,exit,shutdown} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2475.0>,unregister,lager_handler_watcher_sup} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,getting_unlinked,<0.2475.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,unlink,<0.2475.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2474.0>,exit,killed} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2474.0>,unregister,lager_event} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,getting_unlinked,<0.2474.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,unlink,<0.2474.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,exit,shutdown} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2473.0>,unregister,lager_sup} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2472.0>,getting_unlinked,<0.2473.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2472.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2471.0>,getting_unlinked,<0.2472.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2471.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,spawn,<0.2490.0>, {application_controller,init_starter, - [{<0.719.0>,#Ref<0.1438558619.4139253762.137754>}, + [{<0.719.0>,#Ref<0.3905142072.2827223041.167258>}, {appl,lager, {appl_data,lager, [lager_sup,lager_event,lager_crash_log, @@ -2118,7 +2157,7 @@ "Erlang logging framework",[],"3.9.2",undefined,[],[], [kernel,stdlib,goldrush]}, {state,[],[],[], - [{goldrush,<0.2497.0>}, + [{goldrush,<0.2464.0>}, {compiler,undefined}, {syntax_tools,undefined}, {crypto,undefined}, @@ -2133,10 +2172,10 @@ {kernel,permanent}], [],[]}, normal]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,link,<0.2523.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2523.0>,spawned,<0.44.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,link,<0.2490.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2490.0>,spawned,<0.44.0>, {application_controller,init_starter, - [{<0.719.0>,#Ref<0.1438558619.4139253762.137754>}, + [{<0.719.0>,#Ref<0.3905142072.2827223041.167258>}, {appl,lager, {appl_data,lager, [lager_sup,lager_event,lager_crash_log, @@ -2157,7 +2196,7 @@ "Erlang logging framework",[],"3.9.2",undefined,[],[], [kernel,stdlib,goldrush]}, {state,[],[],[], - [{goldrush,<0.2497.0>}, + [{goldrush,<0.2464.0>}, {compiler,undefined}, {syntax_tools,undefined}, {crypto,undefined}, @@ -2172,11 +2211,11 @@ {kernel,permanent}], [],[]}, normal]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2523.0>,getting_linked,<0.44.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2523.0>,spawn,<0.2524.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2490.0>,getting_linked,<0.44.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2490.0>,spawn,<0.2491.0>, {proc_lib,init_p, - [<0.2523.0>,[],application_master,init, - [<0.44.0>,<0.2523.0>, + [<0.2490.0>,[],application_master,init, + [<0.44.0>,<0.2490.0>, {appl_data,lager, [lager_sup,lager_event,lager_crash_log, lager_handler_watcher_sup], @@ -2193,11 +2232,11 @@ lager_transform,lager_trunc_io,lager_util], infinity,infinity}, normal]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2523.0>,link,<0.2524.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2524.0>,spawned,<0.2523.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2490.0>,link,<0.2491.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2491.0>,spawned,<0.2490.0>, {proc_lib,init_p, - [<0.2523.0>,[],application_master,init, - [<0.44.0>,<0.2523.0>, + [<0.2490.0>,[],application_master,init, + [<0.44.0>,<0.2490.0>, {appl_data,lager, [lager_sup,lager_event,lager_crash_log, lager_handler_watcher_sup], @@ -2214,11 +2253,11 @@ lager_transform,lager_trunc_io,lager_util], infinity,infinity}, normal]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2524.0>,getting_linked,<0.2523.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2524.0>,link,<0.44.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2524.0>,spawn,<0.2525.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2491.0>,getting_linked,<0.2490.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2491.0>,link,<0.44.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2491.0>,spawn,<0.2492.0>, {application_master,start_it, - [#Ref<0.1438558619.4139253762.137755>, + [#Ref<0.3905142072.2827223041.167259>, {state,undefined, {appl_data,lager, [lager_sup,lager_event,lager_crash_log, @@ -2237,11 +2276,11 @@ lager_trunc_io,lager_util], infinity,infinity}, [],0,<0.65.0>,[]}, - <0.2524.0>,normal]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2524.0>,link,<0.2525.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2525.0>,spawned,<0.2524.0>, + <0.2491.0>,normal]}} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2491.0>,link,<0.2492.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2492.0>,spawned,<0.2491.0>, {application_master,start_it, - [#Ref<0.1438558619.4139253762.137755>, + [#Ref<0.3905142072.2827223041.167259>, {state,undefined, {appl_data,lager, [lager_sup,lager_event,lager_crash_log, @@ -2260,192 +2299,192 @@ lager_trunc_io,lager_util], infinity,infinity}, [],0,<0.65.0>,[]}, - <0.2524.0>,normal]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2525.0>,getting_linked,<0.2524.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,getting_linked,<0.2524.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2525.0>,spawn,<0.2526.0>, + <0.2491.0>,normal]}} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2492.0>,getting_linked,<0.2491.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,getting_linked,<0.2491.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2492.0>,spawn,<0.2493.0>, {proc_lib,init_p, - [<0.2525.0>,[],gen,init_it, - [gen_server,<0.2525.0>,<0.2525.0>, + [<0.2492.0>,[],gen,init_it, + [gen_server,<0.2492.0>,<0.2492.0>, {local,lager_sup}, supervisor, {{local,lager_sup},lager_sup,[]}, []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2525.0>,link,<0.2526.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,spawned,<0.2525.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2492.0>,link,<0.2493.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,spawned,<0.2492.0>, {proc_lib,init_p, - [<0.2525.0>,[],gen,init_it, - [gen_server,<0.2525.0>,<0.2525.0>, + [<0.2492.0>,[],gen,init_it, + [gen_server,<0.2492.0>,<0.2492.0>, {local,lager_sup}, supervisor, {{local,lager_sup},lager_sup,[]}, []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,getting_linked,<0.2525.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,register,lager_sup} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,spawn,<0.2527.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,getting_linked,<0.2492.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,register,lager_sup} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,spawn,<0.2494.0>, {proc_lib,init_p, [lager_sup, - [<0.2525.0>], + [<0.2492.0>], gen,init_it, - [gen_event,<0.2526.0>,<0.2526.0>, + [gen_event,<0.2493.0>,<0.2493.0>, {local,lager_event}, 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,link,<0.2527.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2527.0>,spawned,<0.2526.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,link,<0.2494.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2494.0>,spawned,<0.2493.0>, {proc_lib,init_p, [lager_sup, - [<0.2525.0>], + [<0.2492.0>], gen,init_it, - [gen_event,<0.2526.0>,<0.2526.0>, + [gen_event,<0.2493.0>,<0.2493.0>, {local,lager_event}, 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2527.0>,getting_linked,<0.2526.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2527.0>,register,lager_event} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,spawn,<0.2528.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2494.0>,getting_linked,<0.2493.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2494.0>,register,lager_event} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,spawn,<0.2495.0>, {proc_lib,init_p, [lager_sup, - [<0.2525.0>], + [<0.2492.0>], gen,init_it, - [gen_server,<0.2526.0>,<0.2526.0>, + [gen_server,<0.2493.0>,<0.2493.0>, {local,lager_handler_watcher_sup}, supervisor, {{local,lager_handler_watcher_sup}, lager_handler_watcher_sup,[]}, []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,link,<0.2528.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,spawned,<0.2526.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,link,<0.2495.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,spawned,<0.2493.0>, {proc_lib,init_p, [lager_sup, - [<0.2525.0>], + [<0.2492.0>], gen,init_it, - [gen_server,<0.2526.0>,<0.2526.0>, + [gen_server,<0.2493.0>,<0.2493.0>, {local,lager_handler_watcher_sup}, supervisor, {{local,lager_handler_watcher_sup}, lager_handler_watcher_sup,[]}, []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,getting_linked,<0.2526.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,register,lager_handler_watcher_sup} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,spawn,<0.2529.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,getting_linked,<0.2493.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,register,lager_handler_watcher_sup} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,spawn,<0.2496.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, [lager_event,lager_manager_killer,[10,1000]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,link,<0.2529.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2529.0>,spawned,<0.2528.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,link,<0.2496.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2496.0>,spawned,<0.2495.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, [lager_event,lager_manager_killer,[10,1000]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2529.0>,getting_linked,<0.2528.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2527.0>,link,<0.2529.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2529.0>,getting_linked,<0.2527.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,spawn,<0.2530.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2496.0>,getting_linked,<0.2495.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2494.0>,link,<0.2496.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2496.0>,getting_linked,<0.2494.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,spawn,<0.2497.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, [error_logger,error_logger_lager_h,[5,handle]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,link,<0.2530.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2530.0>,spawned,<0.2528.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,link,<0.2497.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2497.0>,spawned,<0.2495.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, [error_logger,error_logger_lager_h,[5,handle]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2530.0>,getting_linked,<0.2528.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2511.0>,link,<0.2530.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2530.0>,getting_linked,<0.2511.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,spawn,<0.2531.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2497.0>,getting_linked,<0.2495.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2478.0>,link,<0.2497.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2497.0>,getting_linked,<0.2478.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,spawn,<0.2498.0>, {proc_lib,init_p, [lager_sup, - [<0.2525.0>], + [<0.2492.0>], gen,init_it, - [gen_event,<0.2526.0>,<0.2526.0>, + [gen_event,<0.2493.0>,<0.2493.0>, {local,'__lager_test_sink_lager_event'}, 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2526.0>,link,<0.2531.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2531.0>,spawned,<0.2526.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2493.0>,link,<0.2498.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2498.0>,spawned,<0.2493.0>, {proc_lib,init_p, [lager_sup, - [<0.2525.0>], + [<0.2492.0>], gen,init_it, - [gen_event,<0.2526.0>,<0.2526.0>, + [gen_event,<0.2493.0>,<0.2493.0>, {local,'__lager_test_sink_lager_event'}, 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2531.0>,getting_linked,<0.2526.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2531.0>,register,'__lager_test_sink_lager_event'} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,spawn,<0.2532.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2498.0>,getting_linked,<0.2493.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2498.0>,register,'__lager_test_sink_lager_event'} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,spawn,<0.2499.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, ['__lager_test_sink_lager_event', lager_manager_killer, [10,1000]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,link,<0.2532.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2532.0>,spawned,<0.2528.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,link,<0.2499.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2499.0>,spawned,<0.2495.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, ['__lager_test_sink_lager_event', lager_manager_killer, [10,1000]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2532.0>,getting_linked,<0.2528.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2531.0>,link,<0.2532.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2532.0>,getting_linked,<0.2531.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,spawn,<0.2533.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2499.0>,getting_linked,<0.2495.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2498.0>,link,<0.2499.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2499.0>,getting_linked,<0.2498.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,spawn,<0.2500.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, ['__lager_test_sink_lager_event', lager_slow_backend, [{delay,1000}]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2528.0>,link,<0.2533.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2533.0>,spawned,<0.2528.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2500.0>,spawned,<0.2495.0>, {proc_lib,init_p, [lager_handler_watcher_sup, - [lager_sup,<0.2525.0>], + [lager_sup,<0.2492.0>], gen,init_it, - [gen_server,<0.2528.0>,<0.2528.0>, + [gen_server,<0.2495.0>,<0.2495.0>, lager_handler_watcher, ['__lager_test_sink_lager_event', lager_slow_backend, [{delay,1000}]], []]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2533.0>,getting_linked,<0.2528.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2531.0>,link,<0.2533.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2533.0>,getting_linked,<0.2531.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2525.0>,spawn,<0.2534.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2500.0>,getting_linked,<0.2495.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2495.0>,link,<0.2500.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2498.0>,link,<0.2500.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2500.0>,getting_linked,<0.2498.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2492.0>,spawn,<0.2501.0>, {erlang,apply,[#Fun,[]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2534.0>,spawned,<0.2525.0>, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2501.0>,spawned,<0.2492.0>, {erlang,apply,[#Fun,[]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2534.0>,exit, +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2501.0>,exit, {ok,lager_default_tracer, <<70,79,82,49,0,0,8,140,66,69,65,77,65,116,85,56,0,0,1, 126,0,0,0,39,20,108,97,103,101,114,95,100,101,102,97, @@ -2572,11 +2611,11 @@ 11,0,0,0,0,0,0,0,0,84,121,112,101,0,0,0,26,0,0,0,1,0, 0,0,1,31,255,0,0,0,0,0,0,0,0,255,255,255,255,255,255, 255,255,0,0>>}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2525.0>,link,<0.2526.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2524.0>,unlink,<0.2523.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2523.0>,getting_unlinked,<0.2524.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2523.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,getting_unlinked,<0.2523.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2492.0>,link,<0.2493.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2491.0>,unlink,<0.2490.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2490.0>,getting_unlinked,<0.2491.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.2490.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.44.0>,getting_unlinked,<0.2490.0>} *failed* in function lager_manager_killer_test:confirm_manager_exit/2 (lager_manager_killer_test.erl, line 106) in call from lager_manager_killer_test:'-overload_alternate_sink_test_/0-fun-3-'/0 (lager_manager_killer_test.erl, line 71) @@ -2593,11 +2632,24 @@ {value,false}]} output:<<"">> - [done in 7.375 s] + [done in 7.331 s] +module 'lager_crash_backend' +module 'lager_trace_test' + lager_trace_test: -trace_test_/0-fun-2- (Trace combined with log_root)...*timed out* +in function timer:sleep/1 (timer.erl, line 219) +in call from lager_trace_test:wait_until/3 (lager_trace_test.erl, line 89) +in call from lager_trace_test:'-trace_test_/0-fun-2-'/0 (lager_trace_test.erl, line 56) +in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) +in call from eunit_proc:run_test/1 (eunit_proc.erl, line 531) +in call from eunit_proc:with_timeout/3 (eunit_proc.erl, line 356) +in call from eunit_proc:handle_test/2 (eunit_proc.erl, line 514) +in call from eunit_proc:tests_inorder/3 (eunit_proc.erl, line 456) + undefined +[done in 5.001 s] module 'crash_statem' module 'pr_stacktrace_test' - pr_stacktrace_test: pr_stacktrace_throw_test...ok - pr_stacktrace_test: pr_stacktrace_bad_arg_test...*failed* +pr_stacktrace_test: pr_stacktrace_throw_test...ok +pr_stacktrace_test: pr_stacktrace_bad_arg_test...*failed* in function pr_stacktrace_test:pr_stacktrace_bad_arg_test/0 (pr_stacktrace_test.erl, line 34) in call from eunit_test:'-mf_wrapper/2-fun-0-'/2 (eunit_test.erl, line 273) in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) @@ -2612,143 +2664,53 @@ {value,nomatch}]} output:<<"">> - pr_stacktrace_test: pr_stacktrace_bad_arity_test...ok - pr_stacktrace_test: pr_stacktrace_no_reverse_test...ok - [done in 0.013 s] +pr_stacktrace_test: pr_stacktrace_bad_arity_test...ok +pr_stacktrace_test: pr_stacktrace_no_reverse_test...ok +[done in 0.012 s] compress_pr_record_test: nested_record_test (module 'compress_pr_record_test')...ok module 'lager_test_function_transform' - lager_test_function_transform: not_running_test...*failed* -in function lager_test_function_transform:not_running_test/0 (lager_test_function_transform.erl, line 60) -in call from eunit_test:'-mf_wrapper/2-fun-0-'/2 (eunit_test.erl, line 273) -in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) -in call from eunit_proc:run_test/1 (eunit_proc.erl, line 531) -in call from eunit_proc:with_timeout/3 (eunit_proc.erl, line 356) -in call from eunit_proc:handle_test/2 (eunit_proc.erl, line 514) -in call from eunit_proc:tests_inorder/3 (eunit_proc.erl, line 456) -in call from eunit_proc:with_timeout/3 (eunit_proc.erl, line 346) -**error:{assertEqual,[{module,lager_test_function_transform}, - {line,60}, - {expression,"lager : log ( info , self ( ) , \"not running\" )"}, - {expected,{error,lager_not_running}}, - {value,ok}]} - output:<<"">> - - undefined - *** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -undefined -*** context setup failed *** -**in function lager_test_function_transform:setup/0 (lager_test_function_transform.erl, line 81) -**error:{badmatch,{error,bad_module}} - - -[done in 2.263 s] +lager_test_function_transform: not_running_test...ok +lager_test_function_transform: -transform_function_test_/0-fun-37- (observe that there is nothing up my sleeve)...ok +lager_test_function_transform: -transform_function_test_/0-fun-34- (logging works)...ok +lager_test_function_transform: -transform_function_test_/0-fun-30- (Testing calling a function returns the same content on emit)...ok +lager_test_function_transform: -transform_function_test_/0-fun-27- (Testing calling a function which returns content which can change on emit)...ok +lager_test_function_transform: -transform_function_test_/0-fun-21- (Testing a undefined function returns undefined on emit)...[0.001 s] ok +lager_test_function_transform: -transform_function_test_/0-fun-18- (Testing calling a function returns the same content on log)...[0.001 s] ok +lager_test_function_transform: -transform_function_test_/0-fun-15- (Testing calling a dynamic function on log which returns the same value)...[0.001 s] ok +lager_test_function_transform: -transform_function_test_/0-fun-8- (Testing differences in results for on_log vs on emit from dynamic function)...[0.001 s] ok +lager_test_function_transform: -transform_function_test_/0-fun-3- (Testing a function provided via metadata)...ok +[done in 2.806 s] zzzz_gh280_crash: gh280_crash_test (module 'zzzz_gh280_crash')...ok module 'crash_fsm' module 'crash' module 'lager_test_backend' -lager_test_backend: not_running_test...*failed* -in function lager_test_backend:not_running_test/0 (lager_test_backend.erl, line 173) -in call from eunit_test:'-mf_wrapper/2-fun-0-'/2 (eunit_test.erl, line 273) -in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) -in call from eunit_proc:run_test/1 (eunit_proc.erl, line 531) -in call from eunit_proc:with_timeout/3 (eunit_proc.erl, line 356) -in call from eunit_proc:handle_test/2 (eunit_proc.erl, line 514) -in call from eunit_proc:tests_inorder/3 (eunit_proc.erl, line 456) -in call from eunit_proc:with_timeout/3 (eunit_proc.erl, line 346) -**error:{assertEqual,[{module,lager_test_backend}, - {line,173}, - {expression,"lager : log ( info , self ( ) , \"not running\" )"}, - {expected,{error,lager_not_running}}, - {value,ok}]} - output:<<"">> - -lager_test_backend: -lager_test_/0-fun-206- (observe that there is nothing up my sleeve)...*failed* -in function lager_test_backend:'-lager_test_/0-fun-206-'/0 (lager_test_backend.erl, line 182) -in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) -in call from eunit_proc:run_test/1 (eunit_proc.erl, line 531) -in call from eunit_proc:with_timeout/3 (eunit_proc.erl, line 356) -in call from eunit_proc:handle_test/2 (eunit_proc.erl, line 514) -in call from eunit_proc:tests_inorder/3 (eunit_proc.erl, line 456) -in call from eunit_proc:with_timeout/3 (eunit_proc.erl, line 346) -in call from eunit_proc:run_group/2 (eunit_proc.erl, line 570) -**error:{assertEqual,[{module,lager_test_backend}, - {line,182}, - {expression,"pop ( )"}, - {expected,undefined}, - {value,{error,bad_module}}]} - output:<<"">> - +lager_test_backend: not_running_test...ok +lager_test_backend: -lager_test_/0-fun-206- (observe that there is nothing up my sleeve)...ok lager_test_backend: -lager_test_/0-fun-203- (test sink not running)...ok lager_test_backend: -lager_test_/0-fun-201- (logging works)...[0.001 s] ok -lager_test_backend: -lager_test_/0-fun-197- (logging with macro works)...ok +lager_test_backend: -lager_test_/0-fun-197- (logging with macro works)...[0.001 s] ok lager_test_backend: -lager_test_/0-fun-193- (unsafe logging works)...[0.001 s] ok -lager_test_backend: -lager_test_/0-fun-189- (logging with arguments works)...[0.001 s] ok -lager_test_backend: -lager_test_/0-fun-185- (logging with macro and arguments works)...[0.001 s] ok -lager_test_backend: -lager_test_/0-fun-181- (unsafe logging with args works)...[0.001 s] ok -lager_test_backend: -lager_test_/0-fun-177- (logging works from inside a begin/end block)...[0.001 s] ok -lager_test_backend: -lager_test_/0-fun-174- (logging works from inside a list comprehension)...[0.002 s] ok -lager_test_backend: -lager_test_/0-fun-170- (logging works from a begin/end block inside a list comprehension)...[0.002 s] ok -lager_test_backend: -lager_test_/0-fun-166- (logging works from a nested list comprehension)...[0.007 s] ok +lager_test_backend: -lager_test_/0-fun-189- (logging with arguments works)...ok +lager_test_backend: -lager_test_/0-fun-185- (logging with macro and arguments works)...ok +lager_test_backend: -lager_test_/0-fun-181- (unsafe logging with args works)...ok +lager_test_backend: -lager_test_/0-fun-177- (logging works from inside a begin/end block)...ok +lager_test_backend: -lager_test_/0-fun-174- (logging works from inside a list comprehension)...[0.001 s] ok +lager_test_backend: -lager_test_/0-fun-170- (logging works from a begin/end block inside a list comprehension)...[0.001 s] ok +lager_test_backend: -lager_test_/0-fun-166- (logging works from a nested list comprehension)...[0.005 s] ok lager_test_backend: -lager_test_/0-fun-161- (logging with only metadata works)...ok lager_test_backend: -lager_test_/0-fun-158- (variables inplace of literals in logging statements work)...[0.001 s] ok lager_test_backend: -lager_test_/0-fun-147- (list comprehension inplace of literals in logging statements work)...[0.001 s] ok lager_test_backend: -lager_test_/0-fun-128- (function calls inplace of literals in logging statements work)...[0.001 s] ok lager_test_backend: -lager_test_/0-fun-116- (record fields inplace of literals in logging statements work)...[0.001 s] ok lager_test_backend: -lager_test_/0-fun-105- (log messages below the threshold are ignored)...[0.001 s] ok -lager_test_backend: -lager_test_/0-fun-95- (tracing works)...[0.058 s] ok -lager_test_backend: -lager_test_/0-fun-91- (tracing works with custom attributes)...[0.210 s] ok -lager_test_backend: -lager_test_/0-fun-85- (tracing works with custom attributes and event stream processing)...[1.042 s] ok -lager_test_backend: -lager_test_/0-fun-72- (tracing custom attributes works with event stream processing statistics and reductions)...[0.494 s] ok -lager_test_backend: -lager_test_/0-fun-61- (persistent traces work)...[0.134 s] ok -lager_test_backend: -lager_test_/0-fun-57- (tracing honors loglevel)...[0.122 s] ok -lager_test_backend: -lager_test_/0-fun-52- (stopped trace stops and removes its event handler - default sink (gh#267))...[0.227 s] ok -lager_test_backend: -lager_test_/0-fun-41- (record printing works)...[0.101 s] ok +lager_test_backend: -lager_test_/0-fun-95- (tracing works)...[0.047 s] ok +lager_test_backend: -lager_test_/0-fun-91- (tracing works with custom attributes)...[0.156 s] ok +lager_test_backend: -lager_test_/0-fun-85- (tracing works with custom attributes and event stream processing)...[0.836 s] ok +lager_test_backend: -lager_test_/0-fun-72- (tracing custom attributes works with event stream processing statistics and reductions)...[0.463 s] ok +lager_test_backend: -lager_test_/0-fun-61- (persistent traces work)...[0.122 s] ok +lager_test_backend: -lager_test_/0-fun-57- (tracing honors loglevel)...[0.090 s] ok +lager_test_backend: -lager_test_/0-fun-52- (stopped trace stops and removes its event handler - default sink (gh#267))...[0.205 s] ok +lager_test_backend: -lager_test_/0-fun-41- (record printing works)...[0.102 s] ok lager_test_backend: -lager_test_/0-fun-38- (record printing fails gracefully)...[0.101 s] ok lager_test_backend: -lager_test_/0-fun-35- (record printing fails gracefully when no lager_record attribute)...[0.101 s] ok lager_test_backend: -lager_test_/0-fun-31- (record printing fails gracefully when input is not a tuple)...[0.101 s] ok @@ -2757,44 +2719,44 @@ lager_test_backend: -lager_test_/0-fun-20- (metadata in the process dictionary works)...[0.001 s] ok lager_test_backend: -lager_test_/0-fun-11- (unsafe messages really are not truncated)...[0.002 s] ok lager_test_backend: -lager_test_/0-fun-9- (can't store invalid metadata)...ok -lager_test_backend: -lager_test_/0-fun-5- (dates should be local by default)...ok +lager_test_backend: -lager_test_/0-fun-5- (dates should be local by default)...[0.001 s] ok lager_test_backend: -lager_test_/0-fun-2- (dates should be UTC if SASL is configured as UTC)...[0.001 s] ok -lager_test_backend: -extra_sinks_test_/0-fun-42- (observe that there is nothing up my sleeve)...ok +lager_test_backend: -extra_sinks_test_/0-fun-42- (observe that there is nothing up my sleeve)...[0.001 s] ok lager_test_backend: -extra_sinks_test_/0-fun-39- (logging works)...ok -lager_test_backend: -extra_sinks_test_/0-fun-35- (logging with arguments works)...[0.001 s] ok +lager_test_backend: -extra_sinks_test_/0-fun-35- (logging with arguments works)...ok lager_test_backend: -extra_sinks_test_/0-fun-31- (variables inplace of literals in logging statements work)...[0.001 s] ok -lager_test_backend: -extra_sinks_test_/0-fun-20- (stopped trace stops and removes its event handler - test sink (gh#267))...[0.267 s] ok +lager_test_backend: -extra_sinks_test_/0-fun-20- (stopped trace stops and removes its event handler - test sink (gh#267))...[0.219 s] ok lager_test_backend: -extra_sinks_test_/0-fun-9- (log messages below the threshold are ignored)...[0.001 s] ok FSM crash output tests -Default sink -lager_test_backend: -crash_fsm_test_/0-fun-7- (again, there is nothing up my sleeve)...[0.001 s] ok -lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem crash)...[0.101 s] ok -lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem stop)...[0.101 s] ok -lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem timeout)...[0.101 s] ok -[done in 1.133 s] -Error logger sink -lager_test_backend: -crash_fsm_test_/0-fun-7- (again, there is nothing up my sleeve)...[0.001 s] ok -lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem crash)...[0.101 s] ok -lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem stop)...[0.101 s] ok -lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem timeout)...[0.101 s] ok -[done in 1.117 s] -[done in 2.250 s] + Default sink + lager_test_backend: -crash_fsm_test_/0-fun-7- (again, there is nothing up my sleeve)...ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem crash)...[0.101 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem stop)...[0.101 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem timeout)...[0.101 s] ok + [done in 1.040 s] + Error logger sink + lager_test_backend: -crash_fsm_test_/0-fun-7- (again, there is nothing up my sleeve)...ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem crash)...[0.101 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem stop)...[0.101 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem timeout)...[0.101 s] ok + [done in 1.059 s] + [done in 2.099 s] Error logger redirect crash -Redirect to default sink -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-7- (again, there is nothing up my sleeve)...ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value with string)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return uncaught throw)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause string)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (function clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (if clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (try clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (undefined function)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad math)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad match)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arity)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg1)...*failed* + Redirect to default sink + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-7- (again, there is nothing up my sleeve)...ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value with string)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return uncaught throw)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause string)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (function clause)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (if clause)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (try clause)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (undefined function)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad math)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad match)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arity)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg1)...*failed* in function lager_test_backend:test_body/2 (lager_test_backend.erl, line 927) in call from lager_test_backend:'-error_logger_redirect_crash_test_/0-fun-2-'/3 (lager_test_backend.erl, line 1096) in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) @@ -2810,8 +2772,8 @@ {value,"gen_server crash terminated with reason: bad argument in call to erlang:list"}]} output:<<"">> -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg2)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad record)...*failed* + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg2)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad record)...*failed* in function lager_test_backend:test_body/2 (lager_test_backend.erl, line 927) in call from lager_test_backend:'-error_logger_redirect_crash_test_/0-fun-2-'/3 (lager_test_backend.erl, line 1096) in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) @@ -2827,26 +2789,26 @@ {value,"gen_server crash terminated with reason: bad record {} in crash:handle_call/3 li"}]} output:<<"">> -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc)...lager_test_backend.erl:949:<0.4755.0>: Trailing data " in gen_event:call1/3" following "gen_server crash terminated with reason: no such process or port in call to gen_event:call(foo, bar, baz)" + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc)...lager_test_backend.erl:949:<0.5066.0>: Trailing data " in gen_event:call1/3" following "gen_server crash terminated with reason: no such process or port in call to gen_event:call(foo, bar, baz)" [0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc_proc_lib)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (badfun)...[0.101 s] ok -[done in 3.634 s] -Redirect to error_logger_lager_event sink -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-7- (again, there is nothing up my sleeve)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value with string)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return uncaught throw)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause string)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (function clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (if clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (try clause)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (undefined function)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad math)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad match)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arity)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg1)...*failed* + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc_proc_lib)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (badfun)...[0.101 s] ok + [done in 3.454 s] + Redirect to error_logger_lager_event sink + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-7- (again, there is nothing up my sleeve)...ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value with string)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return uncaught throw)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause string)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (function clause)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (if clause)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (try clause)...[0.102 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (undefined function)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad math)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad match)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arity)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg1)...*failed* in function lager_test_backend:test_body/2 (lager_test_backend.erl, line 927) in call from lager_test_backend:'-error_logger_redirect_crash_test_/0-fun-2-'/3 (lager_test_backend.erl, line 1096) in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) @@ -2862,8 +2824,8 @@ {value,"gen_server crash terminated with reason: bad argument in call to erlang:list"}]} output:<<"">> -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg2)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad record)...*failed* + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arg2)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad record)...*failed* in function lager_test_backend:test_body/2 (lager_test_backend.erl, line 927) in call from lager_test_backend:'-error_logger_redirect_crash_test_/0-fun-2-'/3 (lager_test_backend.erl, line 1096) in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) @@ -2879,118 +2841,118 @@ {value,"gen_server crash terminated with reason: bad record {} in crash:handle_call/3 li"}]} output:<<"">> -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc)...lager_test_backend.erl:949:<0.5511.0>: Trailing data " in gen_event:call1/3" following "gen_server crash terminated with reason: no such process or port in call to gen_event:call(foo, bar, baz)" + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc)...lager_test_backend.erl:949:<0.5831.0>: Trailing data " in gen_event:call1/3" following "gen_server crash terminated with reason: no such process or port in call to gen_event:call(foo, bar, baz)" [0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc_proc_lib)...[0.101 s] ok -lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (badfun)...[0.101 s] ok -[done in 4.384 s] -[done in 8.018 s] + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc_proc_lib)...[0.101 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (badfun)...[0.101 s] ok + [done in 3.978 s] + [done in 7.432 s] Error logger redirect -Redirect to default sink -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error reports are printed)...[0.004 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string error reports are printed)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages with unicode characters in Args are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are truncated at 4096 characters)...[0.010 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are printed)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are truncated at 4096 characters)...[0.009 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term info reports are printed)...[0.004 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term error reports are printed)...[0.003 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are printed)...[0.004 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are truncated at 4096 characters)...[0.021 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (strings in a mixed report are printed as strings)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are printed)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are truncated at 4096 characters)...[0.009 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages with unicode characters in Args are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages with unicode characters in Args are printed)...[0.003 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages are printed at the correct level)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning reports are printed at the correct level)...[0.004 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term warning reports are printed at the correct level)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application stop reports)...[0.002 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports)...[0.004 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error and pid)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor_bridge reports)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application progress report)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report)...[0.007 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report with pid)...[0.003 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for emfile)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit2)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system ets table limit)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for unknown system limit should be truncated at 500 characters)...[0.002 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - function_clause)...[0.502 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - case_clause)...[0.502 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - exit)...[0.502 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - error)...[0.502 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (webmachine error reports)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 8 arg version)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 10 arg version)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 5 arg version)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 6 arg version)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (messages should not be generated if they don't satisfy the threshold)...[0.006 s] ok -[done in 51.077 s] -Redirect to error_logger_lager_event sink -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error reports are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string error reports are printed)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages with unicode characters in Args are printed)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are truncated at 4096 characters)...[0.008 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are printed)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are truncated at 4096 characters)...[0.008 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term info reports are printed)...[0.002 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term error reports are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are truncated at 4096 characters)...[0.019 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (strings in a mixed report are printed as strings)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are truncated at 4096 characters)...[0.008 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages with unicode characters in Args are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages with unicode characters in Args are printed)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages are printed at the correct level)...[0.001 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning reports are printed at the correct level)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term warning reports are printed at the correct level)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application stop reports)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error and pid)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor_bridge reports)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application progress report)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report)...[0.003 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report with pid)...[0.002 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for emfile)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit2)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system ets table limit)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for unknown system limit should be truncated at 500 characters)...[0.002 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - function_clause)...[0.501 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - case_clause)...[0.501 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - exit)...[0.501 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - error)...[0.501 s] ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (webmachine error reports)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 8 arg version)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 10 arg version)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 5 arg version)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 6 arg version)...ok -lager_test_backend: -error_logger_redirect_test_/0-fun-170- (messages should not be generated if they don't satisfy the threshold)...[0.006 s] ok -[done in 51.864 s] -[done in 102.941 s] -lager_test_backend: safe_format_test...[0.001 s] ok -lager_test_backend: unsafe_format_test...[0.005 s] ok -lager_test_backend: -async_threshold_test_/0-fun-8- (async threshold works)...[1.508 s] ok -lager_test_backend: -high_watermark_test_/0-fun-7- (Nothing dropped when error_logger high watermark is undefined)...[0.603 s] ok + Redirect to default sink + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string error reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages with unicode characters in Args are printed)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are truncated at 4096 characters)...[0.008 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are truncated at 4096 characters)...[0.008 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term info reports are printed)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term error reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are truncated at 4096 characters)...[0.019 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (strings in a mixed report are printed as strings)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are truncated at 4096 characters)...[0.007 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages with unicode characters in Args are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages with unicode characters in Args are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages are printed at the correct level)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning reports are printed at the correct level)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term warning reports are printed at the correct level)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application stop reports)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error and pid)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor_bridge reports)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application progress report)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report)...[0.004 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report with pid)...[0.003 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for emfile)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit2)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system ets table limit)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for unknown system limit should be truncated at 500 characters)...[0.002 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - function_clause)...[0.502 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - case_clause)...[0.502 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - exit)...[0.502 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - error)...[0.502 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (webmachine error reports)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 8 arg version)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 10 arg version)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 5 arg version)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 6 arg version)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (messages should not be generated if they don't satisfy the threshold)...[0.006 s] ok + [done in 50.631 s] + Redirect to error_logger_lager_event sink + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string error reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are printed)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages with unicode characters in Args are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are truncated at 4096 characters)...[0.008 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are printed)...[0.004 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are truncated at 4096 characters)...[0.009 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term info reports are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term error reports are printed)...[0.004 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are printed)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are truncated at 4096 characters)...[0.019 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (strings in a mixed report are printed as strings)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages are truncated at 4096 characters)...[0.008 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages with unicode characters in Args are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages with unicode characters in Args are printed)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages are printed at the correct level)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning reports are printed at the correct level)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (single term warning reports are printed at the correct level)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application stop reports)...[0.003 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor reports with real error and pid)...[0.005 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor_bridge reports)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application progress report)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report)...[0.006 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report with pid)...[0.002 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for emfile)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system process limit2)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system port limit)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for system ets table limit)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for unknown system limit should be truncated at 500 characters)...[0.002 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - function_clause)...[0.501 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - case_clause)...[0.501 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - exit)...[0.501 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - error)...[0.501 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (webmachine error reports)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 8 arg version)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 10 arg version)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 5 arg version)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (Cowboy error reports, 6 arg version)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (messages should not be generated if they don't satisfy the threshold)...[0.006 s] ok + [done in 52.331 s] + [done in 102.962 s] +lager_test_backend: safe_format_test...ok +lager_test_backend: unsafe_format_test...[0.006 s] ok +lager_test_backend: -async_threshold_test_/0-fun-8- (async threshold works)...[1.507 s] ok +lager_test_backend: -high_watermark_test_/0-fun-7- (Nothing dropped when error_logger high watermark is undefined)...[0.602 s] ok lager_test_backend: -high_watermark_test_/0-fun-5- (Mostly dropped according to error_logger high watermark)...[1.102 s] ok lager_test_backend: -high_watermark_test_/0-fun-3- (Non-notifications are not dropped)...[1.153 s] ok -[done in 166.991 s] +[done in 160.180 s] ======================================================= - Failed: 13. Skipped: 0. Passed: 339. + Failed: 10. Skipped: 0. Passed: 350. One or more tests were cancelled. Cover analysis: /build/erlang-lager-3.9.2/.eunit/index.html -=INFO REPORT==== 24-Apr-2023::07:17:41.331759 === +=INFO REPORT==== 27-May-2024::15:46:27.631474 === application: goldrush exited: killed type: permanent @@ -3127,12 +3089,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/13339/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/20039 and its subdirectories -I: Current time: Mon Apr 24 07:17:57 -12 2023 -I: pbuilder-time-stamp: 1682363877 +I: removing directory /srv/workspace/pbuilder/13339 and its subdirectories +I: Current time: Mon May 27 15:46:42 +14 2024 +I: pbuilder-time-stamp: 1716774402