Diff of the two buildlogs: -- --- b1/build.log 2024-05-13 09:54:38.675267344 +0000 +++ b2/build.log 2024-05-13 10:04:55.397040292 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun Jun 15 04:13:05 -12 2025 -I: pbuilder-time-stamp: 1750003985 +I: Current time: Mon May 13 23:54:42 +14 2024 +I: pbuilder-time-stamp: 1715594082 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -27,54 +27,86 @@ dpkg-source: info: unpacking erlang-lager_3.9.2-3.debian.tar.xz I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/85335/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/45216/tmp/hooks/D01_modify_environment starting +debug: Running on ionos12-i386. +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 13 09:54 /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/45216/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/45216/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='i386' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=22 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='i386' + 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]="21" [3]="1" [4]="release" [5]="i686-pc-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=i386 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=10 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='8c46c10462f14cf9946429b7f114256e' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - LD_LIBRARY_PATH='/usr/lib/libeatmydata' - LD_PRELOAD='libeatmydata.so' - 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='85335' - PS1='# ' - PS2='> ' + INVOCATION_ID=49eab0e1667243408cd2832598a31f77 + LANG=C + LANGUAGE=de_CH:de + LC_ALL=C + LD_LIBRARY_PATH=/usr/lib/libeatmydata + LD_PRELOAD=libeatmydata.so + MACHTYPE=i686-pc-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=45216 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.sxrblSZy/pbuilderrc_gufR --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.sxrblSZy/b1 --logfile b1/build.log erlang-lager_3.9.2-3.dsc' - SUDO_GID='112' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://213.165.73.152: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.sxrblSZy/pbuilderrc_n6Ru --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.sxrblSZy/b2 --logfile b2/build.log erlang-lager_3.9.2-3.dsc' + SUDO_GID=112 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://46.16.76.132:3128 I: uname -a - Linux ionos6-i386 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Jun 15 14:06 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/85335/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 13 07:43 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/45216/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -420,7 +452,7 @@ Get: 298 http://deb.debian.org/debian unstable/main i386 rebar i386 2.6.4-4 [204 kB] Get: 299 http://deb.debian.org/debian unstable/main i386 dh-rebar all 0.0.6 [7820 B] Get: 300 http://deb.debian.org/debian unstable/main i386 erlang-goldrush i386 0.2.0-9 [72.3 kB] -Fetched 189 MB in 4s (44.6 MB/s) +Fetched 189 MB in 8s (23.3 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libapparmor1:i386. (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 ... 19684 files and directories currently installed.) @@ -1657,7 +1689,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/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-3_source.changes +I: user script /srv/workspace/pbuilder/45216/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/45216/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/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-3_source.changes dpkg-buildpackage: info: source package erlang-lager dpkg-buildpackage: info: source version 3.9.2-3 dpkg-buildpackage: info: source distribution unstable @@ -1815,26 +1851,26 @@ Compiled src/lager_transform.erl Compiled src/lager_rotator_behaviour.erl DEBUG: Starting 3 compile worker(s) +Compiled src/lager_handler_watcher_sup.erl +Compiled src/lager_crash_log.erl Compiled src/lager_default_formatter.erl -Compiled src/lager_console_backend.erl +Compiled src/lager_config.erl Compiled src/lager_handler_watcher.erl -Compiled src/lager_crash_log.erl +Compiled src/lager_trunc_io.erl +Compiled src/lager_backend_throttle.erl Compiled src/lager.erl +Compiled src/lager_manager_killer.erl Compiled src/lager_sup.erl Compiled src/lager_common_test_backend.erl -Compiled src/error_logger_lager_h.erl -Compiled src/lager_trunc_io.erl -Compiled src/lager_config.erl -Compiled src/lager_manager_killer.erl -Compiled src/lager_backend_throttle.erl -Compiled src/lager_app.erl +Compiled src/lager_console_backend.erl Compiled src/lager_rotator_default.erl -Compiled src/lager_format.erl -Compiled src/lager_handler_watcher_sup.erl Compiled src/lager_msg.erl -DEBUG: Worker exited cleanly +Compiled src/lager_app.erl +Compiled src/lager_format.erl Compiled src/lager_stdlib.erl DEBUG: Worker exited cleanly +Compiled src/error_logger_lager_h.erl +DEBUG: Worker exited cleanly Compiled src/lager_file_backend.erl DEBUG: Worker exited cleanly INFO: No app_vars_file defined. @@ -1847,84 +1883,80 @@ Compiled src/lager_util.erl Compiled src/lager_transform.erl Compiled src/lager_rotator_behaviour.erl -Compiled src/lager_default_formatter.erl +Compiled src/lager_handler_watcher_sup.erl Compiled src/lager_crash_log.erl -Compiled src/lager_console_backend.erl -Compiled src/lager_handler_watcher.erl -Compiled src/error_logger_lager_h.erl -Compiled src/lager_sup.erl -Compiled src/lager_common_test_backend.erl -Compiled src/lager.erl Compiled src/lager_config.erl +Compiled src/lager_default_formatter.erl +Compiled src/lager.erl +Compiled src/lager_handler_watcher.erl Compiled src/lager_backend_throttle.erl Compiled src/lager_manager_killer.erl -Compiled src/lager_format.erl -Compiled src/lager_app.erl -Compiled src/lager_trunc_io.erl +Compiled src/lager_console_backend.erl +Compiled src/lager_sup.erl Compiled src/lager_rotator_default.erl -Compiled src/lager_handler_watcher_sup.erl +Compiled src/lager_common_test_backend.erl +Compiled src/lager_trunc_io.erl Compiled src/lager_msg.erl -Compiled test/trunc_io_eqc.erl -Compiled test/lager_crash_backend.erl -Compiled test/compress_pr_record_test.erl -Compiled test/lager_slow_backend.erl -Compiled test/lager_metadata_whitelist_test.erl +Compiled src/lager_format.erl +Compiled src/lager_app.erl Compiled src/lager_stdlib.erl -Compiled test/zzzz_gh280_crash.erl -Compiled test/lager_manager_killer_test.erl -Compiled test/lager_app_tests.erl Compiled test/pr_composite_test.erl -Compiled test/lager_rotate.erl -Compiled test/special_process.erl -Compiled test/crash_statem.erl -Compiled test/sync_error_logger.erl -Compiled test/lager_test_function_transform.erl crash.erl:27:13: Warning: invalid function call crash.erl:66:11: Warning: the call to list_to_binary/1 will fail with a 'badarg' exception crash.erl:27:13: Warning: invalid function call crash.erl:66:11: Warning: the call to list_to_binary/1 will fail with a 'badarg' exception Compiled test/crash.erl -Compiled src/lager_file_backend.erl -Compiled test/lager_trace_test.erl -Compiled test/crash_fsm.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 Compiled test/pr_stacktrace_test.erl +Compiled test/lager_manager_killer_test.erl +Compiled test/lager_app_tests.erl +Compiled test/lager_metadata_whitelist_test.erl +Compiled test/crash_fsm.erl +Compiled test/lager_slow_backend.erl +Compiled test/sync_error_logger.erl +Compiled src/error_logger_lager_h.erl +Compiled test/zzzz_gh280_crash.erl +Compiled test/special_process.erl +Compiled test/crash_statem.erl +Compiled src/lager_file_backend.erl +Compiled test/compress_pr_record_test.erl +Compiled test/lager_rotate.erl +Compiled test/lager_test_function_transform.erl +Compiled test/trunc_io_eqc.erl +Compiled test/lager_trace_test.erl +Compiled test/lager_crash_backend.erl Compiled test/lager_test_backend.erl ======================== EUnit ======================== -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 'sync_error_logger' +module 'lager_config' +module 'special_process' module 'lager_file_backend' lager_file_backend: get_loglevel_test...ok - lager_file_backend: -rotation_test_/0-fun-17- (External rotation should work)...[0.007 s] ok - lager_file_backend: -rotation_test_/0-fun-8- (Internal rotation and delayed write)...[1.004 s] ok - lager_file_backend: -filesystem_test_/0-fun-81- (under normal circumstances, file should be opened)...[0.002 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.003 s] ok - lager_file_backend: -filesystem_test_/0-fun-69- (file that becomes unavailable at runtime should trigger an error message)...[0.002 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.004 s] ok - lager_file_backend: -filesystem_test_/0-fun-45- (internal size rotation should work)...[0.003 s] ok - lager_file_backend: -filesystem_test_/0-fun-43- (internal time rotation should work)...[0.004 s] ok - lager_file_backend: -filesystem_test_/0-fun-41- (rotation call should work)...[0.004 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.001 s] ok - lager_file_backend: -filesystem_test_/0-fun-32- (sync_size option should work)...[0.002 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.005 s] ok - lager_file_backend: -filesystem_test_/0-fun-18- (tracing should not duplicate messages)...[0.505 s] ok - lager_file_backend: -filesystem_test_/0-fun-12- (tracing to a dedicated file should work)...[0.034 s] ok - lager_file_backend: -filesystem_test_/0-fun-10- (tracing to a dedicated file should work even if root_log is set)...[0.033 s] ok - lager_file_backend: -filesystem_test_/0-fun-8- (tracing with options should work)...[0.048 s] ok - lager_file_backend: -filesystem_test_/0-fun-5- (no silent hwm drops)...[1.921 s] ok - lager_file_backend: -trace_files_test_/0-fun-5- (a trace using file backend set up in configuration should work)...[1.007 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: -rotation_test_/0-fun-17- (External rotation should work)...[0.017 s] ok + lager_file_backend: -rotation_test_/0-fun-8- (Internal rotation and delayed write)...[1.096 s] ok + lager_file_backend: -filesystem_test_/0-fun-81- (under normal circumstances, file should be opened)...[0.005 s] ok + lager_file_backend: -filesystem_test_/0-fun-79- (don't choke on unicode)...[0.032 s] ok + lager_file_backend: -filesystem_test_/0-fun-77- (don't choke on latin-1)...[0.040 s] ok + lager_file_backend: -filesystem_test_/0-fun-75- (file can't be opened on startup triggers an error message)...[0.011 s] ok + lager_file_backend: -filesystem_test_/0-fun-69- (file that becomes unavailable at runtime should trigger an error message)...[0.007 s] ok + lager_file_backend: -filesystem_test_/0-fun-62- (unavailable files that are fixed at runtime should start having log messages written)...[0.010 s] ok + lager_file_backend: -filesystem_test_/0-fun-55- (external logfile rotation/deletion should be handled)...[0.101 s] ok + lager_file_backend: -filesystem_test_/0-fun-45- (internal size rotation should work)...[0.104 s] ok + lager_file_backend: -filesystem_test_/0-fun-43- (internal time rotation should work)...[0.031 s] ok + lager_file_backend: -filesystem_test_/0-fun-41- (rotation call should work)...[0.180 s] ok + lager_file_backend: -filesystem_test_/0-fun-39- (sync_on option should work)...[0.063 s] ok + lager_file_backend: -filesystem_test_/0-fun-36- (sync_on none option should work (also tests sync_interval))...[2.048 s] ok + lager_file_backend: -filesystem_test_/0-fun-32- (sync_size option should work)...[0.063 s] ok + lager_file_backend: -filesystem_test_/0-fun-28- (runtime level changes)...[0.013 s] ok + lager_file_backend: -filesystem_test_/0-fun-23- (invalid runtime level changes)...[0.042 s] ok + lager_file_backend: -filesystem_test_/0-fun-21- (tracing should work)...[1.234 s] ok + lager_file_backend: -filesystem_test_/0-fun-18- (tracing should not duplicate messages)...[0.607 s] ok + lager_file_backend: -filesystem_test_/0-fun-12- (tracing to a dedicated file should work)...[0.153 s] ok + lager_file_backend: -filesystem_test_/0-fun-10- (tracing to a dedicated file should work even if root_log is set)...[0.083 s] ok + lager_file_backend: -filesystem_test_/0-fun-8- (tracing with options should work)...[0.189 s] ok + lager_file_backend: -filesystem_test_/0-fun-5- (no silent hwm drops)...[2.084 s] ok + lager_file_backend: -trace_files_test_/0-fun-5- (a trace using file backend set up in configuration should work)...[1.103 s] ok + lager_file_backend: -formatting_test_/0-fun-2- (Should have two log files, the second prefixed with 2>)...[0.036 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 lager_file_backend:1145: -config_validation_test_/0-fun-23- (bad size)...ok @@ -1939,196 +1971,171 @@ 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 8.693 s] -module 'lager_manager_killer_test' - lager_manager_killer_test: -overload_test_/0-fun-3-...lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1380.0>,unlink,<0.1377.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1377.0>,getting_unlinked,<0.1380.0>} -lager_manager_killer_test.erl:101:<0.719.0>: Manager <0.1377.0> killed -[2.014 s] ok - lager_manager_killer_test: -overload_alternate_sink_test_/0-fun-3-...lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1377.0>,unregister,lager_event} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1376.0>,getting_unlinked,<0.1377.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.49.0>,spawn,<0.1391.0>, - {proc_lib,init_p, - [kernel_sup, - [<0.47.0>], - gen,init_it, - [gen_server,<0.49.0>,<0.49.0>, - {local,timer_server}, - timer,[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.49.0>,link,<0.1391.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1391.0>,spawned,<0.49.0>, - {proc_lib,init_p, - [kernel_sup, - [<0.47.0>], - gen,init_it, - [gen_server,<0.49.0>,<0.49.0>, - {local,timer_server}, - timer,[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1391.0>,getting_linked,<0.49.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1381.0>,getting_unlinked,<0.1377.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1391.0>,register,timer_server} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1376.0>,spawn,<0.1392.0>, - {proc_lib,init_p, - [lager_sup, - [<0.1375.0>], - gen,init_it, - [gen_event,<0.1376.0>,<0.1376.0>, - {local,lager_event}, - 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1376.0>,link,<0.1392.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1392.0>,spawned,<0.1376.0>, - {proc_lib,init_p, - [lager_sup, - [<0.1375.0>], - gen,init_it, - [gen_event,<0.1376.0>,<0.1376.0>, - {local,lager_event}, - 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1392.0>,getting_linked,<0.1376.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1380.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1381.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1378.0>,getting_unlinked,<0.1380.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1378.0>,getting_unlinked,<0.1381.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1408.0>,unlink,<0.1407.0>} -lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.1407.0>,getting_unlinked,<0.1408.0>} -lager_manager_killer_test.erl:101:<0.719.0>: Manager <0.1407.0> killed -[2.043 s] ok - [done in 4.064 s] -module 'lager_backend_throttle' -module 'lager_crash_backend' + [done in 15.992 s] +module 'pr_composite_test' + pr_composite_test: nested_record_test...ok + pr_composite_test: list_field_test...ok + pr_composite_test: list_of_records_test...ok + pr_composite_test: improper_list_test...ok + [done in 0.023 s] +compress_pr_record_test: nested_record_test (module 'compress_pr_record_test')...ok +module 'lager_trunc_io' + lager_trunc_io: format_test...[0.001 s] ok + lager_trunc_io: atom_quoting_test...ok + lager_trunc_io: sane_float_printing_test...ok + lager_trunc_io: float_inside_list_test...ok + lager_trunc_io: quote_strip_test...ok + lager_trunc_io: binary_printing_test...[0.001 s] ok + lager_trunc_io: bitstring_printing_test...[0.001 s] ok + lager_trunc_io: list_printing_test...[0.001 s] ok + lager_trunc_io: iolist_printing_test...[0.001 s] ok + lager_trunc_io: tuple_printing_test...[0.001 s] ok + lager_trunc_io: map_printing_test...[0.001 s] ok + lager_trunc_io: unicode_test...ok + lager_trunc_io: depth_limit_test...[0.003 s] ok + lager_trunc_io: print_terms_without_format_string_test...[0.001 s] ok + lager_trunc_io: improper_io_list_test...ok + [done in 0.094 s] +module 'lager_handler_watcher' + lager_handler_watcher: -reinstall_on_initial_failure_test_/0-fun-3-...[6.085 s] ok + lager_handler_watcher: -reinstall_on_runtime_failure_test_/0-fun-3-...[6.141 s] ok + lager_handler_watcher: -reinstall_handlers_after_killer_hwm_test_/0-fun-1-...[6.321 s] ok + [done in 18.579 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)...[0.001 s] ok + lager_app:352: -application_config_mangling_test_/0-fun-5- (Explode with formatter info)...ok + 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 + lager_app:411: -check_handler_config_test_/0-fun-12- (lager_file_backend_bad)...=ERROR REPORT==== 13-May-2024::10:00:14.633200 === +Cannot have same file ("same_file.log") in multiple file backends + +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.021 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.110 s] module 'lager_console_backend' lager_console_backend:242: -console_config_validation_test_/0-fun-7-...ok lager_console_backend:243: -console_config_validation_test_/0-fun-5-...ok 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)...ok - lager_console_backend: -console_log_test_/0-fun-48- (verbose console logging)...ok + lager_console_backend: -console_log_test_/0-fun-51- (regular console logging)...[0.007 s] ok + lager_console_backend: -console_log_test_/0-fun-48- (verbose console logging)...[0.015 s] ok lager_console_backend: -console_log_test_/0-fun-45- (custom format console logging)...[0.001 s] ok - lager_console_backend: -console_log_test_/0-fun-42- (tracing should work)...[0.533 s] ok - lager_console_backend: -console_log_test_/0-fun-37- (tracing doesn't duplicate messages)...[1.034 s] ok + lager_console_backend: -console_log_test_/0-fun-42- (tracing should work)...[0.667 s] ok + lager_console_backend: -console_log_test_/0-fun-37- (tracing doesn't duplicate messages)...[1.120 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-13- (console backend with custom group leader using a trace and an ID)...[1.135 s] ok - lager_console_backend: -set_loglevel_test_/0-fun-8- (Get/set loglevel test)...[0.001 s] ok - lager_console_backend: -set_loglevel_test_/0-fun-3- (Get/set invalid loglevel test)...[0.001 s] ok - [done in 4.711 s] -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/111094132") -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/14018144") -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/57430317") -in call from lager_rotator_default:rotate_file_fail_test/0 -**error:undef + lager_console_backend: -console_log_test_/0-fun-25- (whitelisting a loglevel works)...[0.516 s] ok + lager_console_backend: -console_log_test_/0-fun-20- (console backend with custom group leader)...[0.612 s] ok + lager_console_backend: -console_log_test_/0-fun-13- (console backend with custom group leader using a trace and an ID)...*failed* +in function lager_console_backend:'-console_log_test_/0-fun-13-'/1 (lager_console_backend.erl, line 550) +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_console_backend}, + {line,550}, + {expression,"lager_config : get ( { lager_event , loglevel } )"}, + {expected,{0,[]}}, + {value,{0, + [{{all,[{module,'=',lager_console_backend}]}, + {mask,255}, + {lager_console_backend,trace_test}}]}}]} output:<<"">> - [done in 0.037 s] -module 'lager_format' -module 'pr_composite_test' - pr_composite_test: nested_record_test...ok - pr_composite_test: list_field_test...ok - pr_composite_test: list_of_records_test...ok - pr_composite_test: improper_list_test...ok - [done in 0.012 s] -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] -compress_pr_record_test: nested_record_test (module 'compress_pr_record_test')...[0.001 s] ok + lager_console_backend: -set_loglevel_test_/0-fun-8- (Get/set loglevel test)...[0.014 s] ok + lager_console_backend: -set_loglevel_test_/0-fun-3- (Get/set invalid loglevel test)...ok + [done in 6.090 s] module 'lager_metadata_whitelist_test' lager_metadata_whitelist_test: -basic_test_/0-fun-7- (Meta)...ok 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.146 s] -module 'lager_transform' -module 'lager_manager_killer' -module 'lager_stdlib' + [done in 1.577 s] +module 'lager_backend_throttle' +module 'lager_trace_test' + lager_trace_test: -trace_test_/0-fun-2- (Trace combined with log_root)...[3.699 s] ok + [done in 3.712 s] module 'lager_test_backend' 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)...ok - lager_test_backend: -lager_test_/0-fun-197- (logging with macro works)...ok - lager_test_backend: -lager_test_/0-fun-193- (unsafe logging works)...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)...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.001 s] ok lager_test_backend: -lager_test_/0-fun-170- (logging works from a begin/end block inside a list comprehension)...ok - lager_test_backend: -lager_test_/0-fun-166- (logging works from a nested list comprehension)...[0.002 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)...ok - lager_test_backend: -lager_test_/0-fun-147- (list comprehension inplace of literals in logging statements work)...ok - lager_test_backend: -lager_test_/0-fun-128- (function calls inplace of literals in logging statements work)...ok - lager_test_backend: -lager_test_/0-fun-116- (record fields inplace of literals in logging statements work)...ok - lager_test_backend: -lager_test_/0-fun-105- (log messages below the threshold are ignored)...ok - lager_test_backend: -lager_test_/0-fun-95- (tracing works)...[0.033 s] ok - lager_test_backend: -lager_test_/0-fun-91- (tracing works with custom attributes)...[0.105 s] ok - lager_test_backend: -lager_test_/0-fun-85- (tracing works with custom attributes and event stream processing)...[0.563 s] ok - lager_test_backend: -lager_test_/0-fun-72- (tracing custom attributes works with event stream processing statistics and reductions)...[0.306 s] ok - lager_test_backend: -lager_test_/0-fun-61- (persistent traces work)...[0.069 s] ok - lager_test_backend: -lager_test_/0-fun-57- (tracing honors loglevel)...[0.062 s] ok - lager_test_backend: -lager_test_/0-fun-52- (stopped trace stops and removes its event handler - default sink (gh#267))...[0.133 s] ok - lager_test_backend: -lager_test_/0-fun-41- (record printing works)...[0.101 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 - lager_test_backend: -lager_test_/0-fun-27- (record printing fails gracefully when module is invalid)...[1.001 s] ok - lager_test_backend: -lager_test_/0-fun-23- (installing a new handler adjusts the global loglevel if necessary)...ok - lager_test_backend: -lager_test_/0-fun-20- (metadata in the process dictionary works)...ok - lager_test_backend: -lager_test_/0-fun-11- (unsafe messages really are not truncated)...[0.001 s] ok + lager_test_backend: -lager_test_/0-fun-166- (logging works from a nested list comprehension)...[0.011 s] ok + lager_test_backend: -lager_test_/0-fun-161- (logging with only metadata works)...[0.001 s] 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.002 s] ok + lager_test_backend: -lager_test_/0-fun-105- (log messages below the threshold are ignored)...[0.002 s] ok + lager_test_backend: -lager_test_/0-fun-95- (tracing works)...[0.160 s] ok + lager_test_backend: -lager_test_/0-fun-91- (tracing works with custom attributes)...[0.639 s] ok + lager_test_backend: -lager_test_/0-fun-85- (tracing works with custom attributes and event stream processing)...[2.664 s] ok + lager_test_backend: -lager_test_/0-fun-72- (tracing custom attributes works with event stream processing statistics and reductions)...[1.522 s] ok + lager_test_backend: -lager_test_/0-fun-61- (persistent traces work)...[0.169 s] ok + lager_test_backend: -lager_test_/0-fun-57- (tracing honors loglevel)...[0.278 s] ok + lager_test_backend: -lager_test_/0-fun-52- (stopped trace stops and removes its event handler - default sink (gh#267))...[0.437 s] ok + lager_test_backend: -lager_test_/0-fun-41- (record printing works)...[0.104 s] ok + lager_test_backend: -lager_test_/0-fun-38- (record printing fails gracefully)...[0.104 s] ok + lager_test_backend: -lager_test_/0-fun-35- (record printing fails gracefully when no lager_record attribute)...[0.104 s] ok + lager_test_backend: -lager_test_/0-fun-31- (record printing fails gracefully when input is not a tuple)...[0.104 s] ok + lager_test_backend: -lager_test_/0-fun-27- (record printing fails gracefully when module is invalid)...[1.004 s] ok + lager_test_backend: -lager_test_/0-fun-23- (installing a new handler adjusts the global loglevel if necessary)...[0.001 s] ok + 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-2- (dates should be UTC if SASL is configured as UTC)...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-39- (logging works)...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)...ok - lager_test_backend: -extra_sinks_test_/0-fun-20- (stopped trace stops and removes its event handler - test sink (gh#267))...[0.131 s] ok - lager_test_backend: -extra_sinks_test_/0-fun-9- (log messages below the threshold are ignored)...[0.001 s] 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-31- (variables inplace of literals in logging statements work)...[0.005 s] ok + lager_test_backend: -extra_sinks_test_/0-fun-20- (stopped trace stops and removes its event handler - test sink (gh#267))...[0.538 s] ok + lager_test_backend: -extra_sinks_test_/0-fun-9- (log messages below the threshold are ignored)...[0.011 s] ok FSM crash output tests 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 0.847 s] + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem crash)...[0.104 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem stop)...[0.104 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem timeout)...[0.103 s] ok + [done in 1.373 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 0.849 s] - [done in 1.697 s] + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem crash)...[0.104 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem stop)...[0.104 s] ok + lager_test_backend: -crash_fsm_test_/0-fun-2- (gen_statem timeout)...[0.105 s] ok + [done in 1.380 s] + [done in 2.753 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 value)...[0.103 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad return value with string)...[0.104 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- (case clause string)...[0.102 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- (if clause)...[0.104 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (try clause)...[0.105 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 math)...[0.104 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad match)...[0.103 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad arity)...[0.104 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) @@ -2145,7 +2152,7 @@ {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 arg2)...[0.104 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) @@ -2162,25 +2169,25 @@ {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.4300.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 2.478 s] + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc)...lager_test_backend.erl:949:<0.3749.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.102 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc_proc_lib)...[0.104 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (badfun)...[0.102 s] ok + [done in 5.868 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)...[0.108 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- (bad return uncaught throw)...[0.103 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause)...[0.102 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (case clause string)...[0.103 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (function clause)...[0.103 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- (try clause)...[0.106 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (undefined function)...[0.102 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (bad math)...[0.103 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 arity)...[0.102 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) @@ -2214,124 +2221,182 @@ {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.5082.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 2.534 s] - [done in 5.012 s] + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc)...lager_test_backend.erl:949:<0.4488.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.102 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (noproc_proc_lib)...[0.102 s] ok + lager_test_backend: -error_logger_redirect_crash_test_/0-fun-2- (badfun)...[0.104 s] ok + [done in 6.650 s] + [done in 12.518 s] Error logger redirect 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 reports are printed)...[0.001 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)...[0.005 s] ok - lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are truncated at 4096 characters)...[0.026 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.007 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)...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.013 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.004 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- (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.025 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.032 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)...[0.002 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.050 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.011 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages with unicode characters in Args are printed)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages with unicode characters in Args are printed)...[0.001 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.001 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.001 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.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.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report)...[0.002 s] ok lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report with pid)...[0.001 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 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)...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.030 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- (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.504 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - exit)...[0.504 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - error)...[0.505 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, 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.001 s] ok - [done in 47.749 s] + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (messages should not be generated if they don't satisfy the threshold)...[0.021 s] ok + [done in 54.829 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- (error reports are printed)...[0.001 s] 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.005 s] 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)...[0.001 s] ok lager_test_backend: -error_logger_redirect_test_/0-fun-170- (error messages are truncated at 4096 characters)...[0.025 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.007 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)...ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info reports are truncated at 4096 characters)...[0.040 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)...[0.001 s] 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.013 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info reports are truncated at 4096 characters)...[0.057 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.004 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- (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.017 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (info messages with unicode characters in Args are printed)...[0.001 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (warning messages with unicode characters in Args are printed)...[0.001 s] 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- (warning reports are printed at the correct level)...[0.001 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.001 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)...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)...ok - lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report with pid)...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- (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.003 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (supervisor progress report with pid)...[0.004 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)...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 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.029 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- (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.504 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - case_clause)...[0.504 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - exit)...[0.504 s] ok + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash reports for 'special processes' should be handled right - error)...[0.503 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.001 s] ok - [done in 47.781 s] - [done in 95.530 s] + lager_test_backend: -error_logger_redirect_test_/0-fun-170- (messages should not be generated if they don't satisfy the threshold)...[0.005 s] ok + [done in 56.790 s] + [done in 111.619 s] lager_test_backend: safe_format_test...ok - lager_test_backend: unsafe_format_test...ok - lager_test_backend: -async_threshold_test_/0-fun-8- (async threshold works)...*failed* -in function gen:do_call/4 (gen.erl, line 243) -in call from gen_event:rpc/2 (gen_event.erl, line 459) -in call from lager_test_backend:'-async_threshold_test_/0-fun-8-'/0 (lager_test_backend.erl, line 1804) -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) -**exit:killed - output:<<"">> - - 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 145.591 s] + lager_test_backend: unsafe_format_test...[0.001 s] ok + lager_test_backend: -async_threshold_test_/0-fun-8- (async threshold works)...[22.783 s] ok + lager_test_backend: -high_watermark_test_/0-fun-7- (Nothing dropped when error_logger high watermark is undefined)...[0.608 s] ok + lager_test_backend: -high_watermark_test_/0-fun-5- (Mostly dropped according to error_logger high watermark)...[1.112 s] ok + lager_test_backend: -high_watermark_test_/0-fun-3- (Non-notifications are not dropped)...[1.159 s] ok + [done in 203.895 s] +module 'trunc_io_eqc' +module 'lager_transform' +module 'crash_fsm' +zzzz_gh280_crash: gh280_crash_test (module 'zzzz_gh280_crash')...ok +module 'lager_stdlib' +module 'sync_error_logger' +module 'crash' +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.010 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 + lager_default_formatter:295: -basic_test_/0-fun-55- (Default equivalent formatting test)...ok + lager_default_formatter:305: -basic_test_/0-fun-53- (Non existent metadata can default to string)...ok + lager_default_formatter:315: -basic_test_/0-fun-51- (Non existent metadata can default to other metadata)...ok + lager_default_formatter:325: -basic_test_/0-fun-49- (Non existent metadata can default to a string2)...ok + lager_default_formatter:335: -basic_test_/0-fun-47- (Metadata can have extra formatting)...ok + lager_default_formatter:345: -basic_test_/0-fun-45- (Metadata can have extra formatting1)...ok + lager_default_formatter:355: -basic_test_/0-fun-43- (Metadata can have extra formatting2)...ok + lager_default_formatter:365: -basic_test_/0-fun-41- (Metadata can have extra formatting3)...ok + lager_default_formatter:375: -basic_test_/0-fun-39- (Metadata can be printed in its enterity)...ok + lager_default_formatter:385: -basic_test_/0-fun-37- (Metadata can be printed in its enterity with custom seperators)...ok + lager_default_formatter:395: -basic_test_/0-fun-35- (Metadata can have extra formatting with width 1)...ok + lager_default_formatter:409: -basic_test_/0-fun-33- (Metadata can have extra formatting with width 2)...ok + lager_default_formatter:419: -basic_test_/0-fun-31- (Metadata can have extra formatting with width 3)...ok + lager_default_formatter:429: -basic_test_/0-fun-29- (Metadata can have extra formatting with width 4)...ok + lager_default_formatter:439: -basic_test_/0-fun-27- (Metadata can have extra formatting with width 5)...ok + lager_default_formatter:449: -basic_test_/0-fun-25- (Metadata can have extra formatting with width 6)...ok + lager_default_formatter:459: -basic_test_/0-fun-23- (Uppercase Severity Formatting - DEBUG)...ok + lager_default_formatter:468: -basic_test_/0-fun-21- (Uppercase Severity Formatting - INFO)...ok + lager_default_formatter:477: -basic_test_/0-fun-19- (Uppercase Severity Formatting - NOTICE)...ok + lager_default_formatter:486: -basic_test_/0-fun-17- (Uppercase Severity Formatting - WARNING)...ok + lager_default_formatter:495: -basic_test_/0-fun-15- (Uppercase Severity Formatting - ERROR)...ok + lager_default_formatter:504: -basic_test_/0-fun-13- (Uppercase Severity Formatting - CRITICAL)...ok + lager_default_formatter:513: -basic_test_/0-fun-11- (Uppercase Severity Formatting - ALERT)...ok + lager_default_formatter:522: -basic_test_/0-fun-9- (Uppercase Severity Formatting - EMERGENCY)...ok + lager_default_formatter:534: -basic_test_/0-fun-5- (pterm presence test)...ok + 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.106 s] +module 'lager_rotator_behaviour' +module 'lager_sup' +module 'lager_test_function_transform' + 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)...[0.001 s] ok + lager_test_function_transform: -transform_function_test_/0-fun-30- (Testing calling a function returns the same content on emit)...[0.001 s] ok + lager_test_function_transform: -transform_function_test_/0-fun-27- (Testing calling a function which returns content which can change on emit)...[0.001 s] 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)...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)...[0.001 s] ok + [done in 3.892 s] +module 'lager_msg' +module 'lager_rotate' + lager_rotate: -rotate_test_/0-fun-7- (Rotate single file)...[0.130 s] ok + lager_rotate: -rotate_test_/0-fun-4- (Rotate sink)...[0.188 s] ok + lager_rotate: -rotate_test_/0-fun-1- (Rotate all)...[0.296 s] ok + [done in 4.518 s] +module 'error_logger_lager_h' + error_logger_lager_h: -no_silent_hwm_drops_test_/0-fun-2-...[3.058 s] ok + error_logger_lager_h: -shaper_does_not_forward_sup_progress_messages_to_info_level_backend_test_/0-fun-1-...[0.280 s] ok + error_logger_lager_h: -supressed_messages_are_not_counted_for_hwm_test_/0-fun-2-...[0.428 s] ok + [done in 3.796 s] +module 'lager_slow_backend' module 'pr_stacktrace_test' pr_stacktrace_test: pr_stacktrace_throw_test...ok pr_stacktrace_test: pr_stacktrace_bad_arg_test...*failed* @@ -2349,24 +2414,148 @@ {value,nomatch}]} output:<<"">> - pr_stacktrace_test: pr_stacktrace_bad_arity_test...ok - pr_stacktrace_test: pr_stacktrace_no_reverse_test...ok - [done in 0.012 s] + pr_stacktrace_test: pr_stacktrace_bad_arity_test...[0.001 s] ok + pr_stacktrace_test: pr_stacktrace_no_reverse_test...[0.001 s] ok + [done in 0.023 s] +module 'lager_handler_watcher_sup' +module 'lager_common_test_backend' +module 'lager_format' +module 'lager_crash_backend' +module 'lager_manager_killer' +module 'lager_crash_log' + lager_crash_log: -filesystem_test_/0-fun-30- (under normal circumstances, file should be opened)...[0.006 s] ok + lager_crash_log: -filesystem_test_/0-fun-27- (file can't be opened on startup triggers an error message)...[0.013 s] ok + lager_crash_log: -filesystem_test_/0-fun-21- (file that becomes unavailable at runtime should trigger an error message)...[0.072 s] ok + lager_crash_log: -filesystem_test_/0-fun-11- (unavailable files that are fixed at runtime should start having log messages written)...[0.039 s] ok + lager_crash_log: -filesystem_test_/0-fun-6- (external logfile rotation/deletion should be handled)...[0.065 s] ok + [done in 6.138 s] +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/60851107") +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/111662572") +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/91204913") +in call from lager_rotator_default:rotate_file_fail_test/0 +**error:undef + output:<<"">> + + [done in 0.308 s] +module 'lager_manager_killer_test' + lager_manager_killer_test: -overload_test_/0-fun-3-...lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8552.0>,unlink,<0.8550.0>} +=ERROR REPORT==== 13-May-2024::10:04:13.665366 === +** gen_event handler lager_manager_killer crashed. +** Was installed in lager_event +** Last event was: {log,{lager_msg,[], + [{pid,<0.8554.0>}, + {line,127}, + {file,"lager_handler_watcher.erl"}, + {module,lager_handler_watcher}], + debug, + {["2024",45,"05",45,"13"], + ["10",58,"04",58,"12",46,"663"]}, + {1715,594652,663021}, + [76,97,103,101,114,32,105,110,115,116,97, + 108,108,101,100,32,104,97,110,100,108,101, + 114,32,"error_logger_lager_h",32,105,110, + 116,111,32,"error_logger"]}} +** When handler state == {state,10,1000} +** Reason == {kill_me,[10,1000]} + +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8550.0>,getting_unlinked,<0.8552.0>} +lager_manager_killer_test.erl:101:<0.719.0>: Manager <0.8550.0> killed +=ERROR REPORT==== 13-May-2024::10:04:13.666192 === +Killing sink lager_event, current message_queue_len:12 + +=INFO REPORT==== 13-May-2024::10:04:14.720199 === + application: lager + exited: stopped + type: temporary + +[2.063 s] ok + lager_manager_killer_test: -overload_alternate_sink_test_/0-fun-3-...lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8550.0>,unregister,lager_event} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8549.0>,getting_unlinked,<0.8550.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8549.0>,spawn,<0.8563.0>, + {proc_lib,init_p, + [lager_sup, + [<0.8548.0>], + gen,init_it, + [gen_event,<0.8549.0>,<0.8549.0>, + {local,lager_event}, + 'no callback module',[],[]]]}} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8549.0>,link,<0.8563.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8563.0>,spawned,<0.8549.0>, + {proc_lib,init_p, + [lager_sup, + [<0.8548.0>], + gen,init_it, + [gen_event,<0.8549.0>,<0.8549.0>, + {local,lager_event}, + 'no callback module',[],[]]]}} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8563.0>,getting_linked,<0.8549.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8553.0>,getting_unlinked,<0.8550.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8552.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8551.0>,getting_unlinked,<0.8552.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8553.0>,exit,normal} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8551.0>,getting_unlinked,<0.8553.0>} +=ERROR REPORT==== 13-May-2024::10:04:15.849362 === +** gen_event handler lager_manager_killer crashed. +** Was installed in '__lager_test_sink_lager_event' +** Last event was: {log,{lager_msg,[], + [{module,lager_manager_killer_test}, + {function,overload_alternate_sink_test_}, + {line,{69,16}}, + {pid,"<0.719.0>"}, + {node,nonode@nohost}], + info, + {["2024",45,"05",45,"13"], + ["10",58,"04",58,"14",46,"847"]}, + {1715,594654,847462}, + ["1",39,116,104,32,109,101,115,115,97,103, + 101]}} +** When handler state == {state,10,1000} +** Reason == {kill_me,[10,1000]} + +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8578.0>,unlink,<0.8577.0>} +lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: {trace,<0.8577.0>,getting_unlinked,<0.8578.0>} +lager_manager_killer_test.erl:101:<0.719.0>: Manager <0.8577.0> killed +=ERROR REPORT==== 13-May-2024::10:04:15.850444 === +Killing sink '__lager_test_sink_lager_event', current message_queue_len:10 + +=INFO REPORT==== 13-May-2024::10:04:16.897219 === + application: lager + exited: stopped + type: temporary + +[2.165 s] ok + [done in 4.247 s] +module 'crash_statem' module 'lager_util' lager_util: parse_test...ok lager_util: parse_fail_test...ok lager_util: rotation_calculation_test...ok - lager_util: check_trace_test...=INFO REPORT==== 15-Jun-2025::16:16:58.395462 === + lager_util: check_trace_test...=INFO REPORT==== 13-May-2024::10:04:18.517719 === application: lager exited: stopped type: permanent -=INFO REPORT==== 15-Jun-2025::16:16:58.396156 === +=INFO REPORT==== 13-May-2024::10:04:18.519741 === application: goldrush exited: stopped - type: permanent + type: temporary -[0.060 s] ok +[0.243 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 @@ -2380,122 +2569,10 @@ lager_util: config_to_levels_test...ok lager_util: config_to_mask_test...ok lager_util: mask_to_levels_test...ok - lager_util: expand_path_test...ok + lager_util: expand_path_test...[0.005 s] ok lager_util:845: -sink_name_test_/0-fun-3-...ok lager_util:846: -sink_name_test_/0-fun-1-...ok - [done in 0.120 s] -module 'trunc_io_eqc' -module 'lager_crash_log' - lager_crash_log: -filesystem_test_/0-fun-30- (under normal circumstances, file should be opened)...ok - lager_crash_log: -filesystem_test_/0-fun-27- (file can't be opened on startup triggers an error message)...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-6- (external logfile rotation/deletion should be handled)...[0.004 s] ok - [done in 5.190 s] -module 'special_process' -module 'lager_config' -module 'lager_handler_watcher_sup' -module 'crash_statem' -module 'lager_slow_backend' -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 - lager_default_formatter:295: -basic_test_/0-fun-55- (Default equivalent formatting test)...ok - lager_default_formatter:305: -basic_test_/0-fun-53- (Non existent metadata can default to string)...ok - lager_default_formatter:315: -basic_test_/0-fun-51- (Non existent metadata can default to other metadata)...ok - lager_default_formatter:325: -basic_test_/0-fun-49- (Non existent metadata can default to a string2)...ok - lager_default_formatter:335: -basic_test_/0-fun-47- (Metadata can have extra formatting)...ok - lager_default_formatter:345: -basic_test_/0-fun-45- (Metadata can have extra formatting1)...ok - lager_default_formatter:355: -basic_test_/0-fun-43- (Metadata can have extra formatting2)...ok - lager_default_formatter:365: -basic_test_/0-fun-41- (Metadata can have extra formatting3)...ok - lager_default_formatter:375: -basic_test_/0-fun-39- (Metadata can be printed in its enterity)...ok - lager_default_formatter:385: -basic_test_/0-fun-37- (Metadata can be printed in its enterity with custom seperators)...ok - lager_default_formatter:395: -basic_test_/0-fun-35- (Metadata can have extra formatting with width 1)...ok - lager_default_formatter:409: -basic_test_/0-fun-33- (Metadata can have extra formatting with width 2)...ok - lager_default_formatter:419: -basic_test_/0-fun-31- (Metadata can have extra formatting with width 3)...ok - lager_default_formatter:429: -basic_test_/0-fun-29- (Metadata can have extra formatting with width 4)...ok - lager_default_formatter:439: -basic_test_/0-fun-27- (Metadata can have extra formatting with width 5)...ok - lager_default_formatter:449: -basic_test_/0-fun-25- (Metadata can have extra formatting with width 6)...ok - lager_default_formatter:459: -basic_test_/0-fun-23- (Uppercase Severity Formatting - DEBUG)...ok - lager_default_formatter:468: -basic_test_/0-fun-21- (Uppercase Severity Formatting - INFO)...ok - lager_default_formatter:477: -basic_test_/0-fun-19- (Uppercase Severity Formatting - NOTICE)...ok - lager_default_formatter:486: -basic_test_/0-fun-17- (Uppercase Severity Formatting - WARNING)...ok - lager_default_formatter:495: -basic_test_/0-fun-15- (Uppercase Severity Formatting - ERROR)...ok - lager_default_formatter:504: -basic_test_/0-fun-13- (Uppercase Severity Formatting - CRITICAL)...ok - lager_default_formatter:513: -basic_test_/0-fun-11- (Uppercase Severity Formatting - ALERT)...ok - lager_default_formatter:522: -basic_test_/0-fun-9- (Uppercase Severity Formatting - EMERGENCY)...ok - lager_default_formatter:534: -basic_test_/0-fun-5- (pterm presence test)...ok - 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 'crash' -module 'lager_trunc_io' - lager_trunc_io: format_test...[0.001 s] ok - lager_trunc_io: atom_quoting_test...ok - lager_trunc_io: sane_float_printing_test...ok - lager_trunc_io: float_inside_list_test...ok - lager_trunc_io: quote_strip_test...ok - lager_trunc_io: binary_printing_test...ok - lager_trunc_io: bitstring_printing_test...ok - lager_trunc_io: list_printing_test...ok - lager_trunc_io: iolist_printing_test...ok - lager_trunc_io: tuple_printing_test...ok - lager_trunc_io: map_printing_test...ok - lager_trunc_io: unicode_test...ok - lager_trunc_io: depth_limit_test...[0.001 s] ok - lager_trunc_io: print_terms_without_format_string_test...ok - lager_trunc_io: improper_io_list_test...ok - [done in 0.047 s] -zzzz_gh280_crash: gh280_crash_test (module 'zzzz_gh280_crash')...ok -module 'lager_test_function_transform' - 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)...ok - lager_test_function_transform: -transform_function_test_/0-fun-18- (Testing calling a function returns the same content on log)...ok - lager_test_function_transform: -transform_function_test_/0-fun-15- (Testing calling a dynamic function on log which returns the same value)...ok - lager_test_function_transform: -transform_function_test_/0-fun-8- (Testing differences in results for on_log vs on emit from dynamic function)...ok - lager_test_function_transform: -transform_function_test_/0-fun-3- (Testing a function provided via metadata)...ok - [done in 2.574 s] -module 'lager_handler_watcher' - lager_handler_watcher: -reinstall_on_initial_failure_test_/0-fun-3-...[6.034 s] ok - lager_handler_watcher: -reinstall_on_runtime_failure_test_/0-fun-3-...[6.033 s] ok - lager_handler_watcher: -reinstall_handlers_after_killer_hwm_test_/0-fun-1-...[6.067 s] ok - [done in 18.143 s] -module 'crash_fsm' -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 - lager_app:352: -application_config_mangling_test_/0-fun-5- (Explode with formatter info)...ok - 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==== 15-Jun-2025::16:17:24.558157 === -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.003 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.042 s] -module 'lager_rotate' - lager_rotate: -rotate_test_/0-fun-7- (Rotate single file)...[0.009 s] ok - lager_rotate: -rotate_test_/0-fun-4- (Rotate sink)...[0.008 s] ok - lager_rotate: -rotate_test_/0-fun-1- (Rotate all)...[0.008 s] ok - [done in 3.143 s] -module 'lager_msg' -module 'lager_common_test_backend' -module 'error_logger_lager_h' - error_logger_lager_h: -no_silent_hwm_drops_test_/0-fun-2-...[2.308 s] ok - error_logger_lager_h: -shaper_does_not_forward_sup_progress_messages_to_info_level_backend_test_/0-fun-1-...[0.086 s] ok - error_logger_lager_h: -supressed_messages_are_not_counted_for_hwm_test_/0-fun-2-...[0.086 s] ok - [done in 2.489 s] -module 'lager_rotator_behaviour' -module 'lager_sup' + [done in 0.317 s] ======================================================= Failed: 9. Skipped: 0. Passed: 352. Cover analysis: /build/reproducible-path/erlang-lager-3.9.2/.eunit/index.html @@ -2630,12 +2707,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/45216/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/45216/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/85335 and its subdirectories -I: Current time: Sun Jun 15 04:17:36 -12 2025 -I: pbuilder-time-stamp: 1750004256 +I: removing directory /srv/workspace/pbuilder/45216 and its subdirectories +I: Current time: Tue May 14 00:04:54 +14 2024 +I: pbuilder-time-stamp: 1715594694