Source: erlang-lager
Version: 3.9.2-2
Severity: minor
Tags: trixie sid ftbfs
User: [email protected]
Usertags: ftbfs-binary-20230816 ftbfs-binary-after-build
User: [email protected]
Usertags: qa-doublebuild

Hi,

This package fails to do build a binary-only build (not source) after a
successful build (dpkg-buildpackage ; dpkg-buildpackage -b).

This is probably a clear violation of Debian Policy section 4.9 (clean target),
but this is filed as severity:minor for now, because a discussion on
debian-devel showed that we might want to revisit the requirement of a working
'clean' target.

More information about this class of issues, included common problems and
solutions, is available at
https://wiki.debian.org/qa.debian.org/FTBFS/DoubleBuild

Relevant part of the build log:
> cd /<<PKGBUILDDIR>> && runuser -u user42 -- dpkg-buildpackage --sanitize-env 
> -us -uc -rfakeroot -b
> -------------------------------------------------------------------------------------------------------------------------------
> 
> dpkg-buildpackage: info: source package erlang-lager
> dpkg-buildpackage: info: source version 3.9.2-2
> dpkg-buildpackage: info: source distribution unstable
> dpkg-buildpackage: info: source changed by Philipp Huebner 
> <[email protected]>
>  dpkg-source --before-build .
> dpkg-buildpackage: info: host architecture amd64
>  debian/rules clean
> dh clean --buildsystem=rebar --with rebar
>    dh_auto_clean -O--buildsystem=rebar
>       make --no-print-directory -f /usr/share/dh-rebar/make/dh-rebar.Makefile 
> clean
> if [ -e debian/trash ]; then rm -f -f `cat debian/trash`; fi
> rm -f -rf debian/trash
> echo clean
> clean
> rebar clean skip_deps=true -vv
> DEBUG: Evaluating config script "/<<PKGBUILDDIR>>/rebar.config.script"
> DEBUG: Consult config file "/<<PKGBUILDDIR>>/rebar.config"
> DEBUG: Rebar location: "/usr/bin/rebar"
> DEBUG: Consult config file "/<<PKGBUILDDIR>>/src/lager.app.src"
> DEBUG: Available deps: []
> DEBUG: Missing deps  : []
> DEBUG: Plugins requested while processing /<<PKGBUILDDIR>>: []
> DEBUG: Predirs: []
> ==> erlang-lager-3.9.2 (clean)
> INFO:  sh info:
>       cwd: "/<<PKGBUILDDIR>>"
>       cmd: rm -rf ebin/*.beam
> DEBUG:        opts: [{use_stdout,false},abort_on_error]
> DEBUG: Port Cmd: "rm -rf ebin/*.beam"
> Port Opts: [exit_status,{line,16384},use_stdio,stderr_to_stdout,hide]
> INFO:  sh info:
>       cwd: "/<<PKGBUILDDIR>>"
>       cmd: rm -rf priv/mibs/*.bin
> DEBUG:        opts: [{use_stdout,false},abort_on_error]
> DEBUG: Port Cmd: "rm -rf priv/mibs/*.bin"
> Port Opts: [exit_status,{line,16384},use_stdio,stderr_to_stdout,hide]
> INFO:  sh info:
>       cwd: "/<<PKGBUILDDIR>>"
>       cmd: rm -rf /<<PKGBUILDDIR>>/.rebar/erlcinfo
> DEBUG:        opts: [{use_stdout,false},abort_on_error]
> DEBUG: Port Cmd: "rm -rf /<<PKGBUILDDIR>>/.rebar/erlcinfo"
> Port Opts: [exit_status,{line,16384},use_stdio,stderr_to_stdout,hide]
> INFO:  sh info:
>       cwd: "/<<PKGBUILDDIR>>"
>       cmd: rm -rf .eunit
> DEBUG:        opts: [{use_stdout,false},abort_on_error]
> DEBUG: Port Cmd: "rm -rf .eunit"
> Port Opts: [exit_status,{line,16384},use_stdio,stderr_to_stdout,hide]
> INFO:  sh info:
>       cwd: "/<<PKGBUILDDIR>>"
>       cmd: rm -rf .qc
> DEBUG:        opts: [{use_stdout,false},abort_on_error]
> DEBUG: Port Cmd: "rm -rf .qc"
> Port Opts: [exit_status,{line,16384},use_stdio,stderr_to_stdout,hide]
> DEBUG: Postdirs: []
> rm -rf ebin/*.beam
> rm -rf ebin/*.app
> rm -rf c_src/*.o
> rm -rf priv/.so
> rm -rf .eunit
>    dh_autoreconf_clean -O--buildsystem=rebar
>    dh_clean -O--buildsystem=rebar
>       rm -f debian/debhelper-build-stamp
>       rm -rf debian/.debhelper/
>       rm -f debian/erlang-lager.debhelper.log
>       rm -f -- debian/erlang-lager.substvars debian/files
>       rm -fr -- debian/erlang-lager/ debian/tmp/
>       find .  \( \( \
>               \( -path .\*/.git -o -path .\*/.svn -o -path .\*/.bzr -o -path 
> .\*/.hg -o -path .\*/CVS -o -path .\*/.pc -o -path .\*/_darcs \) -prune -o 
> -type f -a \
>               \( -name '#*#' -o -name '.*~' -o -name '*~' -o -name DEADJOE \
>                -o -name '*.orig' -o -name '*.rej' -o -name '*.bak' \
>                -o -name '.*.orig' -o -name .*.rej -o -name '.SUMS' \
>                -o -name TAGS -o \( -path '*/.deps/*' -a -name '*.P' \) \
>               \) -exec rm -f {} + \) -o \
>               \( -type d -a -name autom4te.cache -prune -exec rm -rf {} + \) 
> \)
>  debian/rules binary
> dh binary --buildsystem=rebar --with rebar
>    dh_update_autotools_config -O--buildsystem=rebar
>    dh_autoreconf -O--buildsystem=rebar
>    dh_auto_configure -O--buildsystem=rebar
>       make --no-print-directory -f /usr/share/dh-rebar/make/dh-rebar.Makefile 
> configure
> make[1]: Nothing to be done for 'configure'.
>    dh_auto_build -O--buildsystem=rebar
>       make --no-print-directory -f /usr/share/dh-rebar/make/dh-rebar.Makefile 
> build
> echo rebar_compile
> rebar_compile
> rebar compile skip_deps=true -vv
> DEBUG: Evaluating config script "/<<PKGBUILDDIR>>/rebar.config.script"
> DEBUG: Consult config file "/<<PKGBUILDDIR>>/rebar.config"
> DEBUG: Rebar location: "/usr/bin/rebar"
> DEBUG: Consult config file "/<<PKGBUILDDIR>>/src/lager.app.src"
> DEBUG: Available deps: []
> DEBUG: Missing deps  : []
> DEBUG: Plugins requested while processing /<<PKGBUILDDIR>>: []
> DEBUG: Predirs: []
> ==> erlang-lager-3.9.2 (compile)
> DEBUG: Matched required ERTS version: 13.1.5 -> .*
> DEBUG: Matched required OTP release: 25 -> .*
> DEBUG: Min OTP version unconfigured
> DEBUG: erl_opts [debug_info,
>                  {d,'HAVE_PERSISTENT_TERM'},
>                  {lager_extra_sinks,['__lager_test_sink']},
>                  debug_info,report,verbose,warn_deprecated_function,
>                  warn_deprecated_type,warn_export_all,warn_export_vars,
>                  warn_obsolete_guard,warn_untyped_record,warn_unused_import]
> DEBUG: Files to compile first: ["src/lager_util.erl",
>                                 "src/lager_transform.erl",
>                                 "src/lager_rotator_behaviour.erl"]
> Compiled src/lager_util.erl
> 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.erl
> Compiled src/lager_rotator_default.erl
> Compiled src/lager_backend_throttle.erl
> Compiled src/lager_config.erl
> Compiled src/lager_file_backend.erl
> Compiled src/lager_stdlib.erl
> Compiled src/lager_msg.erl
> Compiled src/lager_console_backend.erl
> Compiled src/lager_app.erl
> Compiled src/lager_default_formatter.erl
> Compiled src/lager_manager_killer.erl
> Compiled src/lager_trunc_io.erl
> Compiled src/lager_handler_watcher.erl
> Compiled src/lager_common_test_backend.erl
> Compiled src/lager_sup.erl
> DEBUG: Worker exited cleanly
> Compiled src/lager_format.erl
> DEBUG: Worker exited cleanly
> Compiled src/error_logger_lager_h.erl
> DEBUG: Worker exited cleanly
> INFO:  No app_vars_file defined.
> DEBUG: Postdirs: []
>    debian/rules override_dh_auto_test
> make[1]: Entering directory '/<<PKGBUILDDIR>>'
> # Test framework is wonky, so run it but don't fail the build
> rebar eunit || true
> ==> erlang-lager-3.9.2 (eunit)
> Compiled src/lager_util.erl
> Compiled src/lager_transform.erl
> Compiled src/lager_rotator_behaviour.erl
> Compiled src/lager_handler_watcher_sup.erl
> Compiled src/lager_crash_log.erl
> Compiled src/lager.erl
> Compiled src/lager_rotator_default.erl
> Compiled src/lager_backend_throttle.erl
> Compiled src/lager_config.erl
> Compiled src/lager_stdlib.erl
> Compiled src/lager_console_backend.erl
> Compiled src/lager_msg.erl
> Compiled src/lager_file_backend.erl
> Compiled src/lager_app.erl
> Compiled src/lager_default_formatter.erl
> Compiled src/lager_manager_killer.erl
> Compiled src/lager_trunc_io.erl
> Compiled src/lager_common_test_backend.erl
> Compiled src/lager_sup.erl
> Compiled src/lager_handler_watcher.erl
> Compiled test/lager_metadata_whitelist_test.erl
> Compiled src/lager_format.erl
> Compiled test/special_process.erl
> Compiled test/zzzz_gh280_crash.erl
> Compiled test/lager_rotate.erl
> Compiled test/lager_slow_backend.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 test/trunc_io_eqc.erl
> Compiled test/compress_pr_record_test.erl
> Compiled test/lager_test_function_transform.erl
> Compiled test/pr_composite_test.erl
> Compiled test/lager_crash_backend.erl
> Compiled src/error_logger_lager_h.erl
> Compiled test/lager_manager_killer_test.erl
> Compiled test/lager_app_tests.erl
> Compiled test/lager_trace_test.erl
> Compiled test/crash_statem.erl
> Compiled test/sync_error_logger.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_test_backend.erl
> ======================== EUnit ========================
> module 'crash_fsm'
> module 'error_logger_lager_h'
>   error_logger_lager_h: -no_silent_hwm_drops_test_/0-fun-2-...[3.018 s] ok
>   error_logger_lager_h: 
> -shaper_does_not_forward_sup_progress_messages_to_info_level_backend_test_/0-fun-1-...[0.096
>  s] ok
>   error_logger_lager_h: 
> -supressed_messages_are_not_counted_for_hwm_test_/0-fun-2-...[0.095 s] ok
>   [done in 3.219 s]
> compress_pr_record_test: nested_record_test (module 
> 'compress_pr_record_test')...ok
> module 'crash_statem'
> 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_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/134078090")
> 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/4576835")
> 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/5760943")
> in call from lager_rotator_default:rotate_file_fail_test/0
> **error:undef
>   output:<<"">>
> 
>   [done in 0.081 s]
> module 'lager_config'
> module 'lager_trace_test'
>   lager_trace_test: -trace_test_/0-fun-2- (Trace combined with 
> log_root)...[4.182 s] ok
>   [done in 4.185 s]
> 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.609 s]
> module 'special_process'
> module 'lager_stdlib'
> module 'lager_trunc_io'
>   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
>   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.046 s]
> module 'pr_stacktrace_test'
>   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)
> 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:{assertNotEqual,[{module,pr_stacktrace_test},
>                  {line,34},
>                  {expression,"string : find ( Got , Want )"},
>                  {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]
> module 'lager_crash_backend'
> module 'lager_file_backend'
>   lager_file_backend: get_loglevel_test...ok
>   lager_file_backend: -rotation_test_/0-fun-17- (External rotation should 
> work)...[0.009 s] ok
>   lager_file_backend: -rotation_test_/0-fun-8- (Internal rotation and delayed 
> write)...[1.052 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-81- (under normal 
> circumstances, file should be opened)...[0.043 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-79- (don't choke on 
> unicode)...[0.029 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-77- (don't choke on 
> latin-1)...[0.033 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-75- (file can't be opened on 
> startup triggers an error message)...[0.034 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-69- (file that becomes 
> unavailable at runtime should trigger an error message)...[0.032 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-62- (unavailable files that are 
> fixed at runtime should start having log messages written)...[0.039 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-55- (external logfile 
> rotation/deletion should be handled)...[0.108 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-45- (internal size rotation 
> should work)...[0.129 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-43- (internal time rotation 
> should work)...[0.173 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-41- (rotation call should 
> work)...[0.243 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-39- (sync_on option should 
> work)...[0.067 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.006 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-28- (runtime level 
> changes)...[0.005 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-23- (invalid runtime level 
> changes)...[0.002 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.512 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-12- (tracing to a dedicated 
> file should work)...[0.043 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-10- (tracing to a dedicated 
> file should work even if root_log is set)...[0.038 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-8- (tracing with options should 
> work)...[0.062 s] ok
>   lager_file_backend: -filesystem_test_/0-fun-5- (no silent hwm 
> drops)...[2.165 s] ok
>   lager_file_backend: -trace_files_test_/0-fun-5- (a trace using file backend 
> set up in configuration should work)...[1.013 s] ok
>   lager_file_backend: -formatting_test_/0-fun-2- (Should have two log files, 
> the second prefixed with 2>)...[0.007 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
>   lager_file_backend:1149: -config_validation_test_/0-fun-21- (bad count)...ok
>   lager_file_backend:1153: -config_validation_test_/0-fun-19- (bad high water 
> mark)...ok
>   lager_file_backend:1157: -config_validation_test_/0-fun-17- (bad date)...ok
>   lager_file_backend:1161: -config_validation_test_/0-fun-15- (blank date is 
> ok)...ok
>   lager_file_backend:1165: -config_validation_test_/0-fun-13- (bad 
> sync_interval)...ok
>   lager_file_backend:1169: -config_validation_test_/0-fun-11- (bad 
> sync_size)...ok
>   lager_file_backend:1173: -config_validation_test_/0-fun-9- (bad 
> check_interval)...ok
>   lager_file_backend:1177: -config_validation_test_/0-fun-7- (bad sync_on 
> level)...ok
>   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 10.124 s]
> module 'lager_rotate'
>   lager_rotate: -rotate_test_/0-fun-7- (Rotate single file)...[0.026 s] ok
>   lager_rotate: -rotate_test_/0-fun-4- (Rotate sink)...[0.018 s] ok
>   lager_rotate: -rotate_test_/0-fun-1- (Rotate all)...[0.027 s] ok
>   [done in 3.213 s]
> module 'lager_msg'
> module 'lager_rotator_behaviour'
> module 'crash'
> 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.191 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.012 s]
> 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==== 
> 14-Aug-2023::14:49:59.016694 ===
>     application: lager
>     exited: stopped
>     type: permanent
> 
> =INFO REPORT==== 14-Aug-2023::14:49:59.024731 ===
>     application: goldrush
>     exited: stopped
>     type: permanent
> 
> [0.080 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
>   lager_util:756: -is_loggable_test_/0-fun-3- (Not loggable by severity with 
> destination)...ok
>   lager_util:757: -is_loggable_test_/0-fun-1- (Loggable by destination 
> overriding severity)...ok
>   lager_util:762: -format_time_test_/0-fun-9-...ok
>   lager_util:767: -format_time_test_/0-fun-7-...ok
>   lager_util:772: -format_time_test_/0-fun-5-...ok
>   lager_util:777: -format_time_test_/0-fun-3-...ok
>   lager_util:782: -format_time_test_/0-fun-1-...ok
>   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:845: -sink_name_test_/0-fun-3-...ok
>   lager_util:846: -sink_name_test_/0-fun-1-...ok
>   [done in 0.140 s]
> module 'lager_manager_killer_test'
>   lager_manager_killer_test: -overload_test_/0-fun-3-...=ERROR REPORT==== 
> 14-Aug-2023::14:49:59.099173 ===
> calling logger:remove_handler(default) failed: error {badmatch,
>                                                       {error,
>                                                        {not_found,default}}}
> *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_test_/0-fun-3-'/0 
> (lager_manager_killer_test.erl, line 31)
> 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:{assert,[{module,lager_manager_killer_test},
>          {line,106},
>          {expression,"false"},
>          {expected,true},
>          {value,false}]}
>   output:<<"">>
> 
>   lager_manager_killer_test: -overload_alternate_sink_test_/0-fun-3-...=ERROR 
> REPORT==== 14-Aug-2023::14:50:01.100897 ===
> ** gen_event handler lager_manager_killer crashed.
> ** Was installed in lager_event
> ** Last event was: {log,{lager_msg,[],
>                                    [{pid,<0.2150.0>},
>                                     {line,127},
>                                     {file,"lager_handler_watcher.erl"},
>                                     {module,lager_handler_watcher}],
>                                    debug,
>                                    {["2023",45,"08",45,"14"],
>                                     ["14",58,"49",58,"59",46,"100"]},
>                                    {1692,24599,100116},
>                                    [76,97,103,101,114,32,105,110,115,116,97,
>                                     108,108,101,100,32,104,97,110,100,108,101,
>                                     114,32,
>                                     [123,
>                                      ["lager_file_backend",44,10,
>                                       ["  ","  ",
>                                        [["   ",32,32,32],"   ",32,32,32],
>                                        ["   ",32,32,32],
>                                        "   ",32,32,32],
>                                       
> "\"/tmp/lager_test/66907008/sink.log\""],
>                                      125],
>                                     32,105,110,116,111,32,"sink_event"]}}
> ** When handler state == {state,10,1000}
> ** Reason == {kill_me,[10,1000]}
> 
> =INFO REPORT==== 14-Aug-2023::14:50:05.243063 ===
>     application: lager
>     exited: stopped
>     type: temporary
> 
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2130.0>,exit,normal}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2157.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.2157.0>,getting_linked,<0.719.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.44.0>,unlink,<0.2139.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2139.0>,getting_unlinked,<0.44.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2151.0>,exit,normal}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2149.0>,unlink,<0.2150.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2150.0>,getting_unlinked,<0.2149.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2149.0>,exit,shutdown}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2149.0>,unregister,sink_event}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2150.0>,exit,normal}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,getting_unlinked,<0.2149.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,getting_unlinked,<0.2150.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,unlink,<0.2149.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2148.0>,exit,shutdown}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2146.0>,getting_unlinked,<0.2148.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2144.0>,exit,shutdown}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,getting_unlinked,<0.2148.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,getting_unlinked,<0.2144.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,unlink,<0.2148.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2145.0>,exit,shutdown}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,unlink,<0.2144.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,getting_unlinked,<0.2145.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,unlink,<0.2145.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2142.0>,getting_unlinked,<0.2144.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2142.0>,getting_unlinked,<0.2145.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,exit,shutdown}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2143.0>,unregister,lager_handler_watcher_sup}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,getting_unlinked,<0.2143.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,unlink,<0.2143.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2142.0>,exit,killed}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2142.0>,unregister,lager_event}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,getting_unlinked,<0.2142.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,unlink,<0.2142.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,exit,shutdown}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2141.0>,unregister,lager_sup}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2140.0>,getting_unlinked,<0.2141.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2140.0>,exit,normal}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2139.0>,getting_unlinked,<0.2140.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2139.0>,exit,normal}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.44.0>,spawn,<0.2158.0>,
>                {application_controller,init_starter,
>                    [{<0.719.0>,#Ref<0.2786941298.1444151300.211246>},
>                     {appl,lager,
>                         {appl_data,lager,
>                             [lager_sup,lager_event,lager_crash_log,
>                              lager_handler_watcher_sup],
>                             undefined,
>                             {lager_app,[]},
>                             [error_logger_lager_h,lager,lager_app,
>                              lager_backend_throttle,lager_common_test_backend,
>                              lager_config,lager_console_backend,
>                              lager_crash_log,lager_default_formatter,
>                              lager_file_backend,lager_format,
>                              lager_handler_watcher,lager_handler_watcher_sup,
>                              lager_manager_killer,lager_msg,
>                              lager_rotator_behaviour,lager_rotator_default,
>                              lager_stdlib,lager_sup,lager_transform,
>                              lager_trunc_io,lager_util],
>                             infinity,infinity},
>                         "Erlang logging framework",[],"3.9.2",undefined,[],[],
>                         [kernel,stdlib,goldrush]},
>                     {state,[],[],[],
>                         [{goldrush,<0.2132.0>},
>                          {compiler,undefined},
>                          {syntax_tools,undefined},
>                          {crypto,undefined},
>                          {stdlib,undefined},
>                          {kernel,<0.46.0>}],
>                         [],
>                         [{goldrush,temporary},
>                          {compiler,permanent},
>                          {syntax_tools,permanent},
>                          {crypto,temporary},
>                          {stdlib,permanent},
>                          {kernel,permanent}],
>                         [],[]},
>                     normal]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.44.0>,link,<0.2158.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2158.0>,spawned,<0.44.0>,
>                {application_controller,init_starter,
>                    [{<0.719.0>,#Ref<0.2786941298.1444151300.211246>},
>                     {appl,lager,
>                         {appl_data,lager,
>                             [lager_sup,lager_event,lager_crash_log,
>                              lager_handler_watcher_sup],
>                             undefined,
>                             {lager_app,[]},
>                             [error_logger_lager_h,lager,lager_app,
>                              lager_backend_throttle,lager_common_test_backend,
>                              lager_config,lager_console_backend,
>                              lager_crash_log,lager_default_formatter,
>                              lager_file_backend,lager_format,
>                              lager_handler_watcher,lager_handler_watcher_sup,
>                              lager_manager_killer,lager_msg,
>                              lager_rotator_behaviour,lager_rotator_default,
>                              lager_stdlib,lager_sup,lager_transform,
>                              lager_trunc_io,lager_util],
>                             infinity,infinity},
>                         "Erlang logging framework",[],"3.9.2",undefined,[],[],
>                         [kernel,stdlib,goldrush]},
>                     {state,[],[],[],
>                         [{goldrush,<0.2132.0>},
>                          {compiler,undefined},
>                          {syntax_tools,undefined},
>                          {crypto,undefined},
>                          {stdlib,undefined},
>                          {kernel,<0.46.0>}],
>                         [],
>                         [{goldrush,temporary},
>                          {compiler,permanent},
>                          {syntax_tools,permanent},
>                          {crypto,temporary},
>                          {stdlib,permanent},
>                          {kernel,permanent}],
>                         [],[]},
>                     normal]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2158.0>,getting_linked,<0.44.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2158.0>,spawn,<0.2159.0>,
>                {proc_lib,init_p,
>                    [<0.2158.0>,[],application_master,init,
>                     [<0.44.0>,<0.2158.0>,
>                      {appl_data,lager,
>                          [lager_sup,lager_event,lager_crash_log,
>                           lager_handler_watcher_sup],
>                          undefined,
>                          {lager_app,[]},
>                          [error_logger_lager_h,lager,lager_app,
>                           lager_backend_throttle,lager_common_test_backend,
>                           lager_config,lager_console_backend,lager_crash_log,
>                           lager_default_formatter,lager_file_backend,
>                           lager_format,lager_handler_watcher,
>                           lager_handler_watcher_sup,lager_manager_killer,
>                           lager_msg,lager_rotator_behaviour,
>                           lager_rotator_default,lager_stdlib,lager_sup,
>                           lager_transform,lager_trunc_io,lager_util],
>                          infinity,infinity},
>                      normal]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2158.0>,link,<0.2159.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2159.0>,spawned,<0.2158.0>,
>                {proc_lib,init_p,
>                    [<0.2158.0>,[],application_master,init,
>                     [<0.44.0>,<0.2158.0>,
>                      {appl_data,lager,
>                          [lager_sup,lager_event,lager_crash_log,
>                           lager_handler_watcher_sup],
>                          undefined,
>                          {lager_app,[]},
>                          [error_logger_lager_h,lager,lager_app,
>                           lager_backend_throttle,lager_common_test_backend,
>                           lager_config,lager_console_backend,lager_crash_log,
>                           lager_default_formatter,lager_file_backend,
>                           lager_format,lager_handler_watcher,
>                           lager_handler_watcher_sup,lager_manager_killer,
>                           lager_msg,lager_rotator_behaviour,
>                           lager_rotator_default,lager_stdlib,lager_sup,
>                           lager_transform,lager_trunc_io,lager_util],
>                          infinity,infinity},
>                      normal]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2159.0>,getting_linked,<0.2158.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2159.0>,link,<0.44.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2159.0>,spawn,<0.2160.0>,
>                {application_master,start_it,
>                    [#Ref<0.2786941298.1444151300.211247>,
>                     {state,undefined,
>                         {appl_data,lager,
>                             [lager_sup,lager_event,lager_crash_log,
>                              lager_handler_watcher_sup],
>                             undefined,
>                             {lager_app,[]},
>                             [error_logger_lager_h,lager,lager_app,
>                              lager_backend_throttle,lager_common_test_backend,
>                              lager_config,lager_console_backend,
>                              lager_crash_log,lager_default_formatter,
>                              lager_file_backend,lager_format,
>                              lager_handler_watcher,lager_handler_watcher_sup,
>                              lager_manager_killer,lager_msg,
>                              lager_rotator_behaviour,lager_rotator_default,
>                              lager_stdlib,lager_sup,lager_transform,
>                              lager_trunc_io,lager_util],
>                             infinity,infinity},
>                         [],0,<0.65.0>,[]},
>                     <0.2159.0>,normal]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2159.0>,link,<0.2160.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2160.0>,spawned,<0.2159.0>,
>                {application_master,start_it,
>                    [#Ref<0.2786941298.1444151300.211247>,
>                     {state,undefined,
>                         {appl_data,lager,
>                             [lager_sup,lager_event,lager_crash_log,
>                              lager_handler_watcher_sup],
>                             undefined,
>                             {lager_app,[]},
>                             [error_logger_lager_h,lager,lager_app,
>                              lager_backend_throttle,lager_common_test_backend,
>                              lager_config,lager_console_backend,
>                              lager_crash_log,lager_default_formatter,
>                              lager_file_backend,lager_format,
>                              lager_handler_watcher,lager_handler_watcher_sup,
>                              lager_manager_killer,lager_msg,
>                              lager_rotator_behaviour,lager_rotator_default,
>                              lager_stdlib,lager_sup,lager_transform,
>                              lager_trunc_io,lager_util],
>                             infinity,infinity},
>                         [],0,<0.65.0>,[]},
>                     <0.2159.0>,normal]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2160.0>,getting_linked,<0.2159.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.44.0>,getting_linked,<0.2159.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2160.0>,spawn,<0.2161.0>,
>                   {proc_lib,init_p,
>                             [<0.2160.0>,[],gen,init_it,
>                              [gen_server,<0.2160.0>,<0.2160.0>,
>                               {local,lager_sup},
>                               supervisor,
>                               {{local,lager_sup},lager_sup,[]},
>                               []]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2160.0>,link,<0.2161.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,spawned,<0.2160.0>,
>                   {proc_lib,init_p,
>                             [<0.2160.0>,[],gen,init_it,
>                              [gen_server,<0.2160.0>,<0.2160.0>,
>                               {local,lager_sup},
>                               supervisor,
>                               {{local,lager_sup},lager_sup,[]},
>                               []]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,getting_linked,<0.2160.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,register,lager_sup}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,spawn,<0.2162.0>,
>                   {proc_lib,init_p,
>                             [lager_sup,
>                              [<0.2160.0>],
>                              gen,init_it,
>                              [gen_event,<0.2161.0>,<0.2161.0>,
>                               {local,lager_event},
>                               'no callback module',[],[]]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,link,<0.2162.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2162.0>,spawned,<0.2161.0>,
>                   {proc_lib,init_p,
>                             [lager_sup,
>                              [<0.2160.0>],
>                              gen,init_it,
>                              [gen_event,<0.2161.0>,<0.2161.0>,
>                               {local,lager_event},
>                               'no callback module',[],[]]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2162.0>,getting_linked,<0.2161.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2162.0>,register,lager_event}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,spawn,<0.2163.0>,
>                   {proc_lib,init_p,
>                             [lager_sup,
>                              [<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2161.0>,<0.2161.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.2161.0>,link,<0.2163.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2163.0>,spawned,<0.2161.0>,
>                   {proc_lib,init_p,
>                             [lager_sup,
>                              [<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2161.0>,<0.2161.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.2163.0>,getting_linked,<0.2161.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2163.0>,register,lager_handler_watcher_sup}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2163.0>,spawn,<0.2164.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.0>,
>                               lager_handler_watcher,
>                               [lager_event,lager_manager_killer,[10,1000]],
>                               []]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2163.0>,link,<0.2164.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2164.0>,spawned,<0.2163.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.0>,
>                               lager_handler_watcher,
>                               [lager_event,lager_manager_killer,[10,1000]],
>                               []]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2164.0>,getting_linked,<0.2163.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2162.0>,link,<0.2164.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2164.0>,getting_linked,<0.2162.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2163.0>,spawn,<0.2165.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.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.2163.0>,link,<0.2165.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2165.0>,spawned,<0.2163.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.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.2165.0>,getting_linked,<0.2163.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2146.0>,link,<0.2165.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2165.0>,getting_linked,<0.2146.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,spawn,<0.2166.0>,
>                   {proc_lib,init_p,
>                             [lager_sup,
>                              [<0.2160.0>],
>                              gen,init_it,
>                              [gen_event,<0.2161.0>,<0.2161.0>,
>                               {local,'__lager_test_sink_lager_event'},
>                               'no callback module',[],[]]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2161.0>,link,<0.2166.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2166.0>,spawned,<0.2161.0>,
>                   {proc_lib,init_p,
>                             [lager_sup,
>                              [<0.2160.0>],
>                              gen,init_it,
>                              [gen_event,<0.2161.0>,<0.2161.0>,
>                               {local,'__lager_test_sink_lager_event'},
>                               'no callback module',[],[]]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2166.0>,getting_linked,<0.2161.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2166.0>,register,'__lager_test_sink_lager_event'}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2163.0>,spawn,<0.2167.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.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.2163.0>,link,<0.2167.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2167.0>,spawned,<0.2163.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.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.2167.0>,getting_linked,<0.2163.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2166.0>,link,<0.2167.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2167.0>,getting_linked,<0.2166.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2163.0>,spawn,<0.2168.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.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.2163.0>,link,<0.2168.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2168.0>,spawned,<0.2163.0>,
>                   {proc_lib,init_p,
>                             [lager_handler_watcher_sup,
>                              [lager_sup,<0.2160.0>],
>                              gen,init_it,
>                              [gen_server,<0.2163.0>,<0.2163.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.2168.0>,getting_linked,<0.2163.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2166.0>,link,<0.2168.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2168.0>,getting_linked,<0.2166.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2160.0>,spawn,<0.2169.0>,
>                   {erlang,apply,[#Fun<compile.4.50072894>,[]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2169.0>,spawned,<0.2160.0>,
>                   {erlang,apply,[#Fun<compile.4.50072894>,[]]}}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2169.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,
>                         117,108,116,95,116,114,97,99,101,114,3,103,101,116,
>                         10,115,116,97,116,105,115,116,105,99,115,4,105,110,
>                         102,111,6,102,105,108,116,101,114,5,105,110,112,117,
>                         116,9,106,111,98,95,101,114,114,111,114,9,106,111,98,
>                         95,105,110,112,117,116,7,106,111,98,95,114,117,110,8,
>                         106,111,98,95,116,105,109,101,6,111,117,116,112,117,
>                         116,5,113,117,101,114,121,8,99,111,117,110,116,101,
>                         114,115,10,103,114,95,99,111,117,110,116,101,114,14,
>                         108,111,111,107,117,112,95,101,108,101,109,101,110,
>                         116,6,98,97,100,97,114,103,6,101,114,108,97,110,103,
>                         5,101,114,114,111,114,14,114,101,115,101,116,95,99,
>                         111,117,110,116,101,114,115,3,97,108,108,5,116,97,98,
>                         108,101,6,112,97,114,97,109,115,30,103,114,95,108,97,
>                         103,101,114,95,100,101,102,97,117,108,116,95,116,114,
>                         97,99,101,114,95,112,97,114,97,109,115,32,103,114,95,
>                         108,97,103,101,114,95,100,101,102,97,117,108,116,95,
>                         116,114,97,99,101,114,95,99,111,117,110,116,101,114,
>                         115,6,104,97,110,100,108,101,14,117,112,100,97,116,
>                         101,95,99,111,117,110,116,101,114,6,114,117,110,106,
>                         111,98,7,104,97,110,100,108,101,95,4,106,111,98,95,7,
>                         103,108,99,95,114,117,110,7,101,120,101,99,117,116,
>                         101,10,106,111,98,95,114,101,115,117,108,116,3,103,
>                         114,101,5,112,97,105,114,115,7,114,117,110,116,105,
>                         109,101,2,43,43,4,109,97,107,101,11,109,111,100,117,
>                         108,101,95,105,110,102,111,15,103,101,116,95,109,111,
>                         100,117,108,101,95,105,110,102,111,0,0,67,111,100,
>                         101,0,0,4,38,0,0,0,16,0,0,0,0,0,0,0,169,0,0,0,48,0,0,
>                         0,11,1,16,153,0,2,18,34,16,1,32,43,53,3,50,64,71,0,3,
>                         19,1,48,64,71,16,3,19,1,64,153,0,2,18,66,16,1,80,59,
>                         3,229,23,8,16,82,213,98,197,114,181,130,165,146,149,
>                         162,133,178,117,194,101,1,96,64,71,32,3,19,1,112,12,
>                         0,0,64,210,3,4,16,13,27,64,178,19,8,32,0,0,1,128,12,
>                         0,0,64,210,3,4,16,13,27,64,162,19,8,32,0,0,1,144,12,
>                         0,0,64,210,3,4,16,13,27,64,146,19,8,32,0,0,1,160,12,
>                         0,0,64,210,3,4,16,13,27,64,130,19,8,32,0,0,1,176,12,
>                         0,0,64,210,3,4,16,13,27,64,114,19,8,32,0,0,1,192,12,
>                         0,0,64,210,3,4,16,13,27,64,98,19,8,32,0,0,1,208,12,0,
>                         0,64,210,3,4,16,13,27,64,82,19,8,32,0,0,1,224,64,10,
>                         16,3,78,16,16,1,240,153,0,2,18,10,19,16,1,8,16,59,3,
>                         13,25,23,8,16,10,20,13,24,82,13,23,98,13,22,114,13,
>                         21,130,13,20,146,13,19,162,13,18,178,13,17,1,8,17,12,
>                         0,0,64,210,3,4,16,13,27,64,178,19,8,32,32,0,1,8,18,
>                         12,0,0,64,210,3,4,16,13,27,64,162,19,8,32,32,0,1,8,
>                         19,12,0,0,64,210,3,4,16,13,27,64,146,19,8,32,32,0,1,
>                         8,20,12,0,0,64,210,3,4,16,13,27,64,130,19,8,32,32,0,
>                         1,8,21,12,0,0,64,210,3,4,16,13,27,64,114,19,8,32,32,
>                         0,1,8,22,12,0,0,64,210,3,4,16,13,27,64,98,19,8,32,32,
>                         0,1,8,23,12,0,0,64,210,3,4,16,13,27,64,82,19,8,32,32,
>                         0,1,8,24,12,0,0,64,210,3,4,16,13,27,64,71,48,19,8,32,
>                         32,0,1,8,25,64,10,16,3,78,16,16,1,8,26,153,0,2,18,10,
>                         21,16,1,8,27,59,3,13,30,23,64,210,13,29,10,22,13,28,
>                         1,8,28,64,10,23,3,19,1,8,29,64,10,24,3,19,1,8,30,64,
>                         10,16,3,78,16,16,1,8,31,153,0,2,18,10,25,16,1,8,32,
>                         12,16,16,64,3,4,64,210,3,4,16,13,27,64,98,19,64,71,
>                         64,35,7,48,48,64,4,3,5,16,13,36,16,1,8,33,153,0,2,18,
>                         10,27,32,1,8,34,12,32,32,64,19,4,64,3,20,64,210,3,4,
>                         16,13,27,64,130,19,64,71,64,35,7,48,48,64,4,19,64,20,
>                         3,5,32,13,38,32,1,8,35,153,0,2,18,10,28,16,1,8,36,12,
>                         0,0,64,210,3,4,16,13,27,64,82,19,64,71,64,35,8,48,48,
>                         0,1,8,37,153,0,2,18,10,29,32,1,8,38,13,32,32,32,64,
>                         19,4,69,19,71,80,19,62,20,13,39,7,32,64,1,8,39,63,20,
>                         64,4,19,5,32,13,41,32,1,8,40,153,0,2,18,10,32,32,1,8,
>                         41,57,13,43,3,58,13,43,3,32,66,3,0,35,66,3,16,51,159,
>                         13,42,51,32,10,18,12,48,64,64,35,4,64,51,20,64,19,36,
>                         64,210,3,4,16,13,27,64,98,19,64,71,64,35,7,48,48,64,
>                         210,3,4,16,13,27,64,114,19,64,71,64,35,7,48,48,64,36,
>                         3,64,4,36,136,16,32,7,16,80,97,20,39,0,96,71,96,39,
>                         16,101,5,39,0,39,16,39,0,16,55,48,0,80,16,16,32,0,16,
>                         96,39,0,19,164,19,23,32,10,35,19,69,19,2,19,7,32,96,
>                         16,32,16,69,4,3,3,64,71,112,19,7,32,112,4,16,13,36,
>                         64,210,3,4,16,13,27,16,48,0,164,35,23,32,33,20,64,
>                         162,19,64,10,24,3,7,48,48,16,48,0,164,3,23,32,20,4,
>                         18,32,19,1,8,42,12,48,48,64,35,4,64,19,20,64,3,36,64,
>                         210,3,4,16,13,27,64,98,19,64,71,64,35,7,48,48,64,210,
>                         3,4,16,13,27,64,146,19,64,71,64,35,7,48,48,64,20,3,
>                         64,4,20,136,16,32,7,16,80,97,4,39,0,96,71,96,39,16,
>                         101,5,39,0,39,16,39,0,16,55,48,0,80,16,16,32,0,16,96,
>                         39,0,19,164,19,23,32,10,35,19,69,19,2,19,7,32,96,64,
>                         71,112,19,7,32,112,4,16,13,36,64,210,3,4,16,13,27,16,
>                         48,0,164,35,23,32,33,4,64,162,19,136,16,16,64,10,24,
>                         3,7,48,48,64,4,3,18,16,19,1,8,43,74,3,1,8,44,153,0,2,
>                         18,10,38,0,1,8,45,64,18,3,78,16,128,1,8,46,153,0,2,
>                         18,10,38,16,1,8,47,64,3,19,64,18,3,78,32,144,3,0,0,
>                         83,116,114,84,0,0,0,0,73,109,112,84,0,0,0,124,0,0,0,
>                         10,0,0,0,14,0,0,0,15,0,0,0,2,0,0,0,17,0,0,0,18,0,0,0,
>                         1,0,0,0,14,0,0,0,19,0,0,0,2,0,0,0,14,0,0,0,26,0,0,0,
>                         3,0,0,0,30,0,0,0,31,0,0,0,2,0,0,0,33,0,0,0,34,0,0,0,
>                         1,0,0,0,17,0,0,0,36,0,0,0,2,0,0,0,33,0,0,0,37,0,0,0,
>                         2,0,0,0,17,0,0,0,39,0,0,0,1,0,0,0,17,0,0,0,39,0,0,0,
>                         2,69,120,112,84,0,0,0,100,0,0,0,8,0,0,0,38,0,0,0,1,0,
>                         0,0,47,0,0,0,38,0,0,0,0,0,0,0,45,0,0,0,27,0,0,0,2,0,
>                         0,0,34,0,0,0,25,0,0,0,1,0,0,0,32,0,0,0,21,0,0,0,1,0,
>                         0,0,27,0,0,0,19,0,0,0,1,0,0,0,16,0,0,0,4,0,0,0,1,0,0,
>                         0,5,0,0,0,2,0,0,0,1,0,0,0,2,76,105,116,84,0,0,0,166,
>                         0,0,0,220,120,156,37,78,193,13,194,48,12,116,171,182,
>                         180,149,42,241,97,0,22,96,7,62,48,6,10,52,21,9,33,65,
>                         137,35,127,145,202,224,216,141,31,214,249,238,124,54,
>                         0,244,0,48,173,207,154,234,240,162,6,99,214,76,28,
>                         132,104,117,140,33,210,144,253,172,23,227,245,204,
>                         194,94,132,198,103,231,168,93,148,75,98,190,174,142,
>                         251,142,90,227,63,25,169,91,140,67,29,169,11,25,101,
>                         30,108,184,223,138,180,19,24,179,167,94,0,154,183,46,
>                         234,118,200,74,8,199,171,90,85,12,143,91,106,181,53,
>                         190,57,38,84,104,18,154,71,42,95,90,241,143,235,229,
>                         124,250,125,161,212,84,86,168,113,108,180,127,32,107,
>                         61,204,0,0,76,111,99,84,0,0,0,40,0,0,0,3,0,0,0,32,0,
>                         0,0,2,0,0,0,41,0,0,0,29,0,0,0,2,0,0,0,38,0,0,0,28,0,
>                         0,0,1,0,0,0,36,65,116,116,114,0,0,0,40,131,108,0,0,0,
>                         1,104,2,100,0,3,118,115,110,108,0,0,0,1,110,16,0,44,
>                         23,214,72,109,227,44,246,131,9,236,168,236,223,98,
>                         159,106,106,67,73,110,102,0,0,0,27,131,108,0,0,0,1,
>                         104,2,100,0,7,118,101,114,115,105,111,110,107,0,5,56,
>                         46,50,46,51,106,0,68,98,103,105,0,0,0,91,131,104,3,
>                         100,0,13,100,101,98,117,103,95,105,110,102,111,95,
>                         118,49,100,0,17,101,114,108,95,97,98,115,116,114,97,
>                         99,116,95,99,111,100,101,104,2,100,0,4,110,111,110,
>                         101,108,0,0,0,2,100,0,18,110,111,119,97,114,110,95,
>                         117,110,117,115,101,100,95,118,97,114,115,100,0,13,
>                         100,101,116,101,114,109,105,110,105,115,116,105,99,
>                         106,0,76,105,110,101,0,0,0,20,0,0,0,0,0,0,0,0,0,0,0,
>                         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.2160.0>,link,<0.2161.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2159.0>,unlink,<0.2158.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2158.0>,getting_unlinked,<0.2159.0>}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.2158.0>,exit,normal}
> lager_manager_killer_test.erl:103:<0.719.0>: OTHER MSG: 
> {trace,<0.44.0>,getting_unlinked,<0.2158.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)
> 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:{assert,[{module,lager_manager_killer_test},
>          {line,106},
>          {expression,"false"},
>          {expected,true},
>          {value,false}]}
>   output:<<"">>
> 
>   [done in 7.321 s]
> module 'trunc_io_eqc'
> module 'lager_common_test_backend'
> module 'lager_backend_throttle'
> module 'lager_manager_killer'
> module 'lager_crash_log'
>   undefined
>   *** context setup failed ***
> **in function lager_crash_log:'-filesystem_test_/0-fun-33-'/0 
> (lager_crash_log.erl, line 256)
> **error:{badmatch,{error,bad_module}}
> 
> 
> undefined
> *** context setup failed ***
> **in function lager_crash_log:'-filesystem_test_/0-fun-33-'/0 
> (lager_crash_log.erl, line 256)
> **error:{badmatch,{error,bad_module}}
> 
> 
> undefined
> *** context setup failed ***
> **in function lager_crash_log:'-filesystem_test_/0-fun-33-'/0 
> (lager_crash_log.erl, line 256)
> **error:{badmatch,{error,bad_module}}
> 
> 
> undefined
> *** context setup failed ***
> **in function lager_crash_log:'-filesystem_test_/0-fun-33-'/0 
> (lager_crash_log.erl, line 256)
> **error:{badmatch,{error,bad_module}}
> 
> 
> undefined
> *** context setup failed ***
> **in function lager_crash_log:'-filesystem_test_/0-fun-33-'/0 
> (lager_crash_log.erl, line 256)
> **error:{badmatch,{error,bad_module}}
> 
> 
> [done in 5.020 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
> 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)...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.004 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.054 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.087 s]
> module 'sync_error_logger'
> module 'lager_transform'
> module 'lager_handler_watcher_sup'
> zzzz_gh280_crash: gh280_crash_test (module 'zzzz_gh280_crash')...ok
> module 'lager_format'
> module 'lager_sup'
> module 'lager_handler_watcher'
> lager_handler_watcher: -reinstall_on_initial_failure_test_/0-fun-3-...*failed*
> in function 
> lager_handler_watcher:'-reinstall_on_initial_failure_test_/0-fun-3-'/0 
> (lager_handler_watcher.erl, line 161)
> 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:{badmatch,{error,bad_module}}
>   output:<<"">>
> 
> lager_handler_watcher: -reinstall_on_runtime_failure_test_/0-fun-3-...[6.028 
> s] ok
> lager_handler_watcher: 
> -reinstall_handlers_after_killer_hwm_test_/0-fun-1-...[6.077 s] ok
> [done in 17.119 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)...[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.001 s] ok
> lager_console_backend: -console_log_test_/0-fun-42- (tracing should 
> work)...[0.528 s] ok
> lager_console_backend: -console_log_test_/0-fun-37- (tracing doesn't 
> duplicate messages)...[1.028 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.503 s] ok
> lager_console_backend: -console_log_test_/0-fun-20- (console backend with 
> custom group leader)...[0.602 s] ok
> lager_console_backend: -console_log_test_/0-fun-13- (console backend with 
> custom group leader using a trace and an ID)...[1.129 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-3- (Get/set invalid loglevel 
> test)...ok
> [done in 4.772 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)...[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.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)...[0.001 s] 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)...[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.017 s] ok
> lager_test_backend: -lager_test_/0-fun-91- (tracing works with custom 
> attributes)...[0.086 s] ok
> lager_test_backend: -lager_test_/0-fun-85- (tracing works with custom 
> attributes and event stream processing)...[0.423 s] ok
> lager_test_backend: -lager_test_/0-fun-72- (tracing custom attributes works 
> with event stream processing statistics and reductions)...[0.219 s] ok
> lager_test_backend: -lager_test_/0-fun-61- (persistent traces work)...[0.066 
> s] ok
> lager_test_backend: -lager_test_/0-fun-57- (tracing honors loglevel)...[0.046 
> s] ok
> lager_test_backend: -lager_test_/0-fun-52- (stopped trace stops and removes 
> its event handler - default sink (gh#267))...[0.087 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)...[0.001 s] 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-9- (can't store invalid metadata)...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)...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)...[0.001 s] ok
> lager_test_backend: -extra_sinks_test_/0-fun-31- (variables inplace of 
> literals in logging statements work)...[0.002 s] ok
> lager_test_backend: -extra_sinks_test_/0-fun-20- (stopped trace stops and 
> removes its event handler - test sink (gh#267))...[0.102 s] ok
> lager_test_backend: -extra_sinks_test_/0-fun-9- (log messages below the 
> threshold are ignored)...[0.002 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 0.909 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.006 s]
> [done in 1.916 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*
> 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)
> 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,927},
>               {expression,"Body"},
>               {expected,"gen_server crash terminated with reason: bad 
> argument in crash:handle_call/3"},
>               {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*
> 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)
> 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,927},
>               {expression,"Body"},
>               {expected,"gen_server crash terminated with reason: bad record 
> state in crash:handle_call/3"},
>               {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.4912.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.102 s] ok
> [done in 2.744 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.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)
> 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,927},
>               {expression,"Body"},
>               {expected,"gen_server crash terminated with reason: bad 
> argument in crash:handle_call/3"},
>               {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*
> 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)
> 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,927},
>               {expression,"Body"},
>               {expected,"gen_server crash terminated with reason: bad record 
> state in crash:handle_call/3"},
>               {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.5675.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.074 s]
> [done in 5.836 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 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.012 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)...[0.001 s] ok
> lager_test_backend: -error_logger_redirect_test_/0-fun-170- (string info 
> reports are truncated at 4096 characters)...[0.010 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.005 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.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)...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)...[0.006 s] ok
> lager_test_backend: -error_logger_redirect_test_/0-fun-170- (application 
> progress report)...[0.004 s] 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 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)...[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)...[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)...ok
> lager_test_backend: -error_logger_redirect_test_/0-fun-170- (crash report for 
> unknown system limit should be truncated at 500 characters)...[0.001 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)...[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.010 s] ok
> [done in 48.652 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.005 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.006 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.015 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.006 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)...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.001 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 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.001 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.502 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.004 s] ok
> [done in 49.638 s]
> [done in 98.290 s]
> lager_test_backend: safe_format_test...ok
> lager_test_backend: unsafe_format_test...[0.012 s] ok
> lager_test_backend: -async_threshold_test_/0-fun-8- (async threshold 
> works)...*failed*
> in function lager_test_backend:'-async_threshold_test_/0-fun-8-'/0 
> (lager_test_backend.erl, line 1830)
> 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,1830},
>               {expression,"lager_config : get ( async )"},
>               {expected,true},
>               {value,false}]}
>   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 151.228 s]
> module 'lager_slow_backend'
> =======================================================
>   Failed: 12.  Skipped: 0.  Passed: 344.
> One or more tests were cancelled.
> Cover analysis: /<<PKGBUILDDIR>>/.eunit/index.html
> =INFO REPORT==== 14-Aug-2023::14:53:05.075919 ===
>     application: goldrush
>     exited: killed
>     type: permanent
> 
> ERROR: One or more eunit tests failed.
> ERROR: eunit failed while processing /<<PKGBUILDDIR>>: rebar_abort
> make[1]: Leaving directory '/<<PKGBUILDDIR>>'
>    create-stamp debian/debhelper-build-stamp
>    dh_testroot -O--buildsystem=rebar
>    dh_prep -O--buildsystem=rebar
>       rm -f -- debian/erlang-lager.substvars
>       rm -fr -- debian/.debhelper/generated/erlang-lager/ 
> debian/erlang-lager/ debian/tmp/
>    debian/rules override_dh_auto_install
> make[1]: Entering directory '/<<PKGBUILDDIR>>'
> dh_auto_install
>       install -m0755 -d /<<PKGBUILDDIR>>/debian/erlang-lager
>       make --no-print-directory -f /usr/share/dh-rebar/make/dh-rebar.Makefile 
> install /<<PKGBUILDDIR>>/debian/erlang-lager
> echo install
> install
> echo  debian/erlang-lager.install >> debian/trash
> if [ -e debian/erlang-lager.install.in ]; then\
>       echo "Filling in debian/erlang-lager.install using 
> debian/erlang-lager.install.in";\
>       cat debian/erlang-lager.install.in |    sed -e 
> 's/@@PKG_VERSION@@/3.9.2/g' -e 's/@@E_PKG_NAME@@/lager/g' -e 
> 's/@@REBAR_LIB_DIR@@/priv/g' -e 's/@@REBAR_BIN_DIR@@/ebin/g' -e 
> 's/@@REBAR_INCLUDE_DIR@@/include/g' -e '/^test /e' | \
>                       (while read line; do [ -e  debian/erlang-lager.install 
> ] || touch  debian/erlang-lager.install; if grep -q -F "$line"  
> debian/erlang-lager.install; then echo "Skipping already existing line: 
> $line"; else echo "Adding new line: $line"; echo "$line" >>  
> debian/erlang-lager.install; fi; done);\
> else \
>       echo "Filling in debian/erlang-lager.install using 
> /usr/share/dh-rebar/template/pkg.install.in";\
>       cat /usr/share/dh-rebar/template/pkg.install.in |       sed -e 
> 's/@@PKG_VERSION@@/3.9.2/g' -e 's/@@E_PKG_NAME@@/lager/g' -e 
> 's/@@REBAR_LIB_DIR@@/priv/g' -e 's/@@REBAR_BIN_DIR@@/ebin/g' -e 
> 's/@@REBAR_INCLUDE_DIR@@/include/g' -e '/^test /e' | \
>                       (while read line; do [ -e  debian/erlang-lager.install 
> ] || touch  debian/erlang-lager.install; if grep -q -F "$line"  
> debian/erlang-lager.install; then echo "Skipping already existing line: 
> $line"; else echo "Adding new line: $line"; echo "$line" >>  
> debian/erlang-lager.install; fi; done);\
> fi
> Filling in debian/erlang-lager.install using 
> /usr/share/dh-rebar/template/pkg.install.in
> Adding new line: usr/lib/erlang/lib/lager-3.9.2/ebin
> Adding new line: usr/lib/erlang/lib/lager-3.9.2/priv
> echo  debian/erlang-lager-dev.install >> debian/trash
> if [ -e debian/erlang-lager-dev.install.in ]; then\
>       echo "Filling in debian/erlang-lager-dev.install using 
> debian/erlang-lager-dev.install.in";\
>       cat debian/erlang-lager-dev.install.in |        sed -e 
> 's/@@PKG_VERSION@@/3.9.2/g' -e 's/@@E_PKG_NAME@@/lager/g' -e 
> 's/@@REBAR_LIB_DIR@@/priv/g' -e 's/@@REBAR_BIN_DIR@@/ebin/g' -e 
> 's/@@REBAR_INCLUDE_DIR@@/include/g' -e '/^test /e' | \
>                       (while read line; do [ -e  
> debian/erlang-lager-dev.install ] || touch  debian/erlang-lager-dev.install; 
> if grep -q -F "$line"  debian/erlang-lager-dev.install; then echo "Skipping 
> already existing line: $line"; else echo "Adding new line: $line"; echo 
> "$line" >>  debian/erlang-lager-dev.install; fi; done);\
> else \
>       echo "Filling in debian/erlang-lager-dev.install using 
> /usr/share/dh-rebar/template/dev.install.in";\
>       cat /usr/share/dh-rebar/template/dev.install.in |       sed -e 
> 's/@@PKG_VERSION@@/3.9.2/g' -e 's/@@E_PKG_NAME@@/lager/g' -e 
> 's/@@REBAR_LIB_DIR@@/priv/g' -e 's/@@REBAR_BIN_DIR@@/ebin/g' -e 
> 's/@@REBAR_INCLUDE_DIR@@/include/g' -e '/^test /e' | \
>                       (while read line; do [ -e  
> debian/erlang-lager-dev.install ] || touch  debian/erlang-lager-dev.install; 
> if grep -q -F "$line"  debian/erlang-lager-dev.install; then echo "Skipping 
> already existing line: $line"; else echo "Adding new line: $line"; echo 
> "$line" >>  debian/erlang-lager-dev.install; fi; done);\
> fi
> Filling in debian/erlang-lager-dev.install using 
> /usr/share/dh-rebar/template/dev.install.in
> Adding new line: usr/lib/erlang/lib/lager-3.9.2/include
> install -d \
>       debian/tmp/usr/lib/erlang/lib/lager-3.9.2/ebin
> install -m 644 ebin/* \
>       debian/tmp/usr/lib/erlang/lib/lager-3.9.2/ebin/
> if [ -e include ]; then \
>       install -d \
>               debian/tmp/usr/lib/erlang/lib/lager-3.9.2/include ;\
>       install -m 644 include/* \
>               debian/tmp/usr/lib/erlang/lib/lager-3.9.2/include/;\
> fi
> if [ -e priv ]; then \
>       install -d \
>               debian/tmp/usr/lib/erlang/lib/lager-3.9.2/priv ;\
>       install -m 644 priv/* \
>               debian/tmp/usr/lib/erlang/lib/lager-3.9.2/priv/ ;\
>       rm -rf 
> debian/tmp/usr/lib/erlang/lib/lager-3.9.2/priv/Run-eunit-loop.expect ;\
> fi
> make[2]: Nothing to be done for '/<<PKGBUILDDIR>>/debian/erlang-lager'.
> for file in include/*.hrl ; do \
>       fname=$(basename ${file}) ; \
>       subdir=$(basename $(dirname ${file})) ; \
>       if [ ! -f 
> /<<PKGBUILDDIR>>/debian/erlang-lager/usr/lib/erlang/lib/lager-3.9.2/${subdir}/${fname}
>  ] ; then \
>               install -m 755 -d 
> /<<PKGBUILDDIR>>/debian/erlang-lager/usr/lib/erlang/lib/lager-3.9.2/${subdir} 
> ; \
>               install -m 644 ${file} \
>               
> /<<PKGBUILDDIR>>/debian/erlang-lager/usr/lib/erlang/lib/lager-3.9.2/${subdir} 
> ; \
>       fi ; \
> done
> make[1]: Leaving directory '/<<PKGBUILDDIR>>'
>    dh_install -O--buildsystem=rebar
>       install -m0755 -d debian/erlang-lager//usr/lib/erlang/lib/lager-3.9.2
>       cp --reflink=auto -a debian/tmp/usr/lib/erlang/lib/lager-3.9.2/ebin 
> debian/tmp/usr/lib/erlang/lib/lager-3.9.2/priv 
> debian/erlang-lager//usr/lib/erlang/lib/lager-3.9.2/
>    dh_installdocs -O--buildsystem=rebar
>       install -m0755 -d debian/erlang-lager/usr/share/doc/erlang-lager
>       install -m0755 -d debian/erlang-lager/usr/share/doc/erlang-lager
>       cp --reflink=auto -a ./README.md 
> debian/erlang-lager/usr/share/doc/erlang-lager
>       chmod -R u\+rw,go=rX debian/erlang-lager/usr/share/doc
>       install -p -m0644 debian/copyright 
> debian/erlang-lager/usr/share/doc/erlang-lager/copyright
>    dh_installchangelogs -O--buildsystem=rebar
>       install -m0755 -d debian/erlang-lager/usr/share/doc/erlang-lager
>       install -p -m0644 
> debian/.debhelper/generated/erlang-lager/dh_installchangelogs.dch.trimmed 
> debian/erlang-lager/usr/share/doc/erlang-lager/changelog.Debian
>    dh_installsystemduser -O--buildsystem=rebar
>    dh_lintian -O--buildsystem=rebar
>       install -m0755 -d debian/erlang-lager/usr/share/lintian/overrides
>       install -p -m0644 debian/erlang-lager.lintian-overrides 
> debian/erlang-lager/usr/share/lintian/overrides/erlang-lager
>    dh_perl -O--buildsystem=rebar
>    dh_link -O--buildsystem=rebar
>    dh_strip_nondeterminism -O--buildsystem=rebar
>    dh_compress "-X.erl -X.beam" -O--buildsystem=rebar
>       cd debian/erlang-lager
>       chmod a-x usr/share/doc/erlang-lager/README.md 
> usr/share/doc/erlang-lager/changelog.Debian
>       gzip -9nf usr/share/doc/erlang-lager/README.md 
> usr/share/doc/erlang-lager/changelog.Debian
>       cd '/<<PKGBUILDDIR>>'
>    dh_fixperms -O--buildsystem=rebar
>       find debian/erlang-lager ! -type l -a -true -a -true -print0 
> 2>/dev/null | xargs -0r chmod go=rX,u+rw,a-s
>       find debian/erlang-lager/usr/share/doc -type f -a -true -a ! -regex 
> 'debian/erlang-lager/usr/share/doc/[^/]*/examples/.*' -print0 2>/dev/null | 
> xargs -0r chmod 0644
>       find debian/erlang-lager/usr/share/doc -type d -a -true -a -true 
> -print0 2>/dev/null | xargs -0r chmod 0755
>       find debian/erlang-lager/usr/share/lintian/overrides -type f -a -true 
> -a -true -print0 2>/dev/null | xargs -0r chmod 0644
>       find debian/erlang-lager -type f \( -name '*.so.*' -o -name '*.so' -o 
> -name '*.la' -o -name '*.a' -o -name '*.js' -o -name '*.css' -o -name 
> '*.scss' -o -name '*.sass' -o -name '*.jpeg' -o -name '*.jpg' -o -name 
> '*.png' -o -name '*.gif' -o -name '*.cmxs' -o -name '*.node' \) -a -true -a 
> -true -print0 2>/dev/null | xargs -0r chmod 0644
>       find debian/erlang-lager/usr/lib -type f -name '*.ali' -a -true -a 
> -true -print0 2>/dev/null | xargs -0r chmod uga-w
>    dh_missing -O--buildsystem=rebar
> dh_missing: warning: usr/lib/erlang/lib/lager-3.9.2/include/lager.hrl exists 
> in debian/tmp but is not installed to anywhere 
> dh_missing: error: missing files, aborting
>       The following debhelper tools have reported what they installed (with 
> files per package)
>        * dh_install: erlang-lager (2)
>        * dh_installdocs: erlang-lager (1)
>       If the missing files are installed by another tool, please file a bug 
> against it.
>       When filing the report, if the tool is not part of debhelper itself, 
> please reference the
>       "Logging helpers and dh_missing" section from the "PROGRAMMING" guide 
> for debhelper (10.6.3+).
>         (in the debhelper package: /usr/share/doc/debhelper/PROGRAMMING.gz)
>       Be sure to test with dpkg-buildpackage -A/-B as the results may vary 
> when only a subset is built
>       If the omission is intentional or no other helper can take care of this 
> consider adding the
>       paths to debian/not-installed.
> make: *** [debian/rules:11: binary] Error 25
> dpkg-buildpackage: error: debian/rules binary subprocess returned exit status 
> 2
> 
> E: Command 'cd /<<PKGBUILDDIR>> && runuser -u user42 -- dpkg-buildpackage 
> --sanitize-env -us -uc -rfakeroot -b' failed to run.


The full build log is available from:
http://qa-logs.debian.net/2023/08/16/erlang-lager_3.9.2-2_unstable.log

If you reassign this bug to another package, please mark it as 'affects'-ing
this package. See https://www.debian.org/Bugs/server-control#affects

If you fail to reproduce this, please provide a build log and diff it with mine
so that we can identify if something relevant changed in the meantime.

Reply via email to