Diff of the two buildlogs: -- --- b1/build.log 2021-07-23 06:03:41.494151122 +0000 +++ b2/build.log 2021-07-23 06:13:09.268730222 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Thu Jul 22 17:56:31 -12 2021 -I: pbuilder-time-stamp: 1627019791 +I: Current time: Fri Jul 23 20:03:57 +14 2021 +I: pbuilder-time-stamp: 1627020237 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bullseye-reproducible-base.tgz] I: copying local configuration @@ -16,8 +16,8 @@ I: copying [./erlang-lager_3.8.1-3.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.1tv4FieL/trustedkeys.kbx': General error -gpgv: Signature made Sun Jan 31 07:05:57 2021 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.qQgD8OdE/trustedkeys.kbx': General error +gpgv: Signature made Mon Feb 1 09:05:57 2021 +14 gpgv: using RSA key 08418BBE44720F2DBAC505ECBA7A2496DAE40D1C gpgv: issuer "debalance@debian.org" gpgv: Can't check signature: No public key @@ -29,135 +29,169 @@ dpkg-source: info: applying remove-deps.diff I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/31726/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/9701/tmp/hooks/D01_modify_environment starting +debug: Running on virt32c. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +Removing 'diversion of /bin/sh to /bin/sh.distrib by dash' +Adding 'diversion of /bin/sh to /bin/sh.distrib by bash' +Removing 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by dash' +Adding 'diversion of /usr/share/man/man1/sh.1.gz to /usr/share/man/man1/sh.distrib.1.gz by bash' +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/9701/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/9701/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=3' - DISTRIBUTION='' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="1" [2]="4" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.1.4(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=4' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='1594cfb402844a77bffe3c44ff098a7f' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='31726' - PS1='# ' - PS2='> ' + INVOCATION_ID=060fdc78763041e990a8eae2f1804ff8 + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=9701 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.vGdWKlgsZ2/pbuilderrc_T84J --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.vGdWKlgsZ2/b1 --logfile b1/build.log erlang-lager_3.8.1-3.dsc' - SUDO_GID='113' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:8000/' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.vGdWKlgsZ2/pbuilderrc_RLpk --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.vGdWKlgsZ2/b2 --logfile b2/build.log --extrapackages usrmerge erlang-lager_3.8.1-3.dsc' + SUDO_GID=113 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:8000/ I: uname -a - Linux virt64c 5.10.0-8-arm64 #1 SMP Debian 5.10.46-1 (2021-06-24) aarch64 GNU/Linux + Linux i-capture-the-hostname 5.10.0-8-armmp-lpae #1 SMP Debian 5.10.46-1 (2021-06-24) armv7l GNU/Linux I: ls -l /bin total 3580 - -rwxr-xr-x 1 root root 816764 Jun 21 14:26 bash - -rwxr-xr-x 3 root root 26052 Jul 20 2020 bunzip2 - -rwxr-xr-x 3 root root 26052 Jul 20 2020 bzcat - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2225 Jul 20 2020 bzdiff - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4877 Sep 4 2019 bzexe - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3775 Jul 20 2020 bzgrep - -rwxr-xr-x 3 root root 26052 Jul 20 2020 bzip2 - -rwxr-xr-x 1 root root 9636 Jul 20 2020 bzip2recover - lrwxrwxrwx 1 root root 6 Jul 20 2020 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Jul 20 2020 bzmore - -rwxr-xr-x 1 root root 26668 Sep 22 2020 cat - -rwxr-xr-x 1 root root 43104 Sep 22 2020 chgrp - -rwxr-xr-x 1 root root 38984 Sep 22 2020 chmod - -rwxr-xr-x 1 root root 43112 Sep 22 2020 chown - -rwxr-xr-x 1 root root 92616 Sep 22 2020 cp - -rwxr-xr-x 1 root root 75524 Dec 10 2020 dash - -rwxr-xr-x 1 root root 75880 Sep 22 2020 date - -rwxr-xr-x 1 root root 55436 Sep 22 2020 dd - -rwxr-xr-x 1 root root 59912 Sep 22 2020 df - -rwxr-xr-x 1 root root 96764 Sep 22 2020 dir - -rwxr-xr-x 1 root root 55012 Feb 7 02:38 dmesg - lrwxrwxrwx 1 root root 8 Nov 6 2019 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Nov 6 2019 domainname -> hostname - -rwxr-xr-x 1 root root 22508 Sep 22 2020 echo - -rwxr-xr-x 1 root root 28 Nov 9 2020 egrep - -rwxr-xr-x 1 root root 22496 Sep 22 2020 false - -rwxr-xr-x 1 root root 28 Nov 9 2020 fgrep - -rwxr-xr-x 1 root root 47492 Feb 7 02:38 findmnt - -rwsr-xr-x 1 root root 26076 Feb 26 04:12 fusermount - -rwxr-xr-x 1 root root 124508 Nov 9 2020 grep - -rwxr-xr-x 2 root root 2346 Mar 2 11:30 gunzip - -rwxr-xr-x 1 root root 6376 Mar 2 11:30 gzexe - -rwxr-xr-x 1 root root 64212 Mar 2 11:30 gzip - -rwxr-xr-x 1 root root 13784 Nov 6 2019 hostname - -rwxr-xr-x 1 root root 43180 Sep 22 2020 ln - -rwxr-xr-x 1 root root 35068 Feb 7 2020 login - -rwxr-xr-x 1 root root 96764 Sep 22 2020 ls - -rwxr-xr-x 1 root root 99940 Feb 7 02:38 lsblk - -rwxr-xr-x 1 root root 51408 Sep 22 2020 mkdir - -rwxr-xr-x 1 root root 43184 Sep 22 2020 mknod - -rwxr-xr-x 1 root root 30780 Sep 22 2020 mktemp - -rwxr-xr-x 1 root root 34408 Feb 7 02:38 more - -rwsr-xr-x 1 root root 34400 Feb 7 02:38 mount - -rwxr-xr-x 1 root root 9824 Feb 7 02:38 mountpoint - -rwxr-xr-x 1 root root 88524 Sep 22 2020 mv - lrwxrwxrwx 1 root root 8 Nov 6 2019 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Apr 18 03:38 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 26652 Sep 22 2020 pwd - lrwxrwxrwx 1 root root 4 Jun 21 14:26 rbash -> bash - -rwxr-xr-x 1 root root 30740 Sep 22 2020 readlink - -rwxr-xr-x 1 root root 43104 Sep 22 2020 rm - -rwxr-xr-x 1 root root 30732 Sep 22 2020 rmdir - -rwxr-xr-x 1 root root 14144 Sep 27 2020 run-parts - -rwxr-xr-x 1 root root 76012 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Jul 21 21:25 sh -> dash - -rwxr-xr-x 1 root root 22532 Sep 22 2020 sleep - -rwxr-xr-x 1 root root 55360 Sep 22 2020 stty - -rwsr-xr-x 1 root root 46704 Feb 7 02:38 su - -rwxr-xr-x 1 root root 22532 Sep 22 2020 sync - -rwxr-xr-x 1 root root 340872 Feb 16 21:55 tar - -rwxr-xr-x 1 root root 9808 Sep 27 2020 tempfile - -rwxr-xr-x 1 root root 67696 Sep 22 2020 touch - -rwxr-xr-x 1 root root 22496 Sep 22 2020 true - -rwxr-xr-x 1 root root 9636 Feb 26 04:12 ulockmgr_server - -rwsr-xr-x 1 root root 22108 Feb 7 02:38 umount - -rwxr-xr-x 1 root root 22520 Sep 22 2020 uname - -rwxr-xr-x 2 root root 2346 Mar 2 11:30 uncompress - -rwxr-xr-x 1 root root 96764 Sep 22 2020 vdir - -rwxr-xr-x 1 root root 38512 Feb 7 02:38 wdctl - lrwxrwxrwx 1 root root 8 Nov 6 2019 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Mar 2 11:30 zcat - -rwxr-xr-x 1 root root 1678 Mar 2 11:30 zcmp - -rwxr-xr-x 1 root root 5880 Mar 2 11:30 zdiff - -rwxr-xr-x 1 root root 29 Mar 2 11:30 zegrep - -rwxr-xr-x 1 root root 29 Mar 2 11:30 zfgrep - -rwxr-xr-x 1 root root 2081 Mar 2 11:30 zforce - -rwxr-xr-x 1 root root 7585 Mar 2 11:30 zgrep - -rwxr-xr-x 1 root root 2206 Mar 2 11:30 zless - -rwxr-xr-x 1 root root 1842 Mar 2 11:30 zmore - -rwxr-xr-x 1 root root 4553 Mar 2 11:30 znew -I: user script /srv/workspace/pbuilder/31726/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 816764 Jun 22 16:26 bash + -rwxr-xr-x 3 root root 26052 Jul 21 2020 bunzip2 + -rwxr-xr-x 3 root root 26052 Jul 21 2020 bzcat + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2225 Jul 21 2020 bzdiff + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4877 Sep 5 2019 bzexe + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3775 Jul 21 2020 bzgrep + -rwxr-xr-x 3 root root 26052 Jul 21 2020 bzip2 + -rwxr-xr-x 1 root root 9636 Jul 21 2020 bzip2recover + lrwxrwxrwx 1 root root 6 Jul 21 2020 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Jul 21 2020 bzmore + -rwxr-xr-x 1 root root 26668 Sep 23 2020 cat + -rwxr-xr-x 1 root root 43104 Sep 23 2020 chgrp + -rwxr-xr-x 1 root root 38984 Sep 23 2020 chmod + -rwxr-xr-x 1 root root 43112 Sep 23 2020 chown + -rwxr-xr-x 1 root root 92616 Sep 23 2020 cp + -rwxr-xr-x 1 root root 75524 Dec 11 2020 dash + -rwxr-xr-x 1 root root 75880 Sep 23 2020 date + -rwxr-xr-x 1 root root 55436 Sep 23 2020 dd + -rwxr-xr-x 1 root root 59912 Sep 23 2020 df + -rwxr-xr-x 1 root root 96764 Sep 23 2020 dir + -rwxr-xr-x 1 root root 55012 Feb 8 04:38 dmesg + lrwxrwxrwx 1 root root 8 Nov 8 2019 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Nov 8 2019 domainname -> hostname + -rwxr-xr-x 1 root root 22508 Sep 23 2020 echo + -rwxr-xr-x 1 root root 28 Nov 10 2020 egrep + -rwxr-xr-x 1 root root 22496 Sep 23 2020 false + -rwxr-xr-x 1 root root 28 Nov 10 2020 fgrep + -rwxr-xr-x 1 root root 47492 Feb 8 04:38 findmnt + -rwsr-xr-x 1 root root 26076 Feb 27 06:12 fusermount + -rwxr-xr-x 1 root root 124508 Nov 10 2020 grep + -rwxr-xr-x 2 root root 2346 Mar 3 13:30 gunzip + -rwxr-xr-x 1 root root 6376 Mar 3 13:30 gzexe + -rwxr-xr-x 1 root root 64212 Mar 3 13:30 gzip + -rwxr-xr-x 1 root root 13784 Nov 8 2019 hostname + -rwxr-xr-x 1 root root 43180 Sep 23 2020 ln + -rwxr-xr-x 1 root root 35068 Feb 8 2020 login + -rwxr-xr-x 1 root root 96764 Sep 23 2020 ls + -rwxr-xr-x 1 root root 99940 Feb 8 04:38 lsblk + -rwxr-xr-x 1 root root 51408 Sep 23 2020 mkdir + -rwxr-xr-x 1 root root 43184 Sep 23 2020 mknod + -rwxr-xr-x 1 root root 30780 Sep 23 2020 mktemp + -rwxr-xr-x 1 root root 34408 Feb 8 04:38 more + -rwsr-xr-x 1 root root 34400 Feb 8 04:38 mount + -rwxr-xr-x 1 root root 9824 Feb 8 04:38 mountpoint + -rwxr-xr-x 1 root root 88524 Sep 23 2020 mv + lrwxrwxrwx 1 root root 8 Nov 8 2019 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Apr 19 05:38 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 26652 Sep 23 2020 pwd + lrwxrwxrwx 1 root root 4 Jun 22 16:26 rbash -> bash + -rwxr-xr-x 1 root root 30740 Sep 23 2020 readlink + -rwxr-xr-x 1 root root 43104 Sep 23 2020 rm + -rwxr-xr-x 1 root root 30732 Sep 23 2020 rmdir + -rwxr-xr-x 1 root root 14144 Sep 28 2020 run-parts + -rwxr-xr-x 1 root root 76012 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Jul 23 20:04 sh -> bash + lrwxrwxrwx 1 root root 4 Jul 22 23:24 sh.distrib -> dash + -rwxr-xr-x 1 root root 22532 Sep 23 2020 sleep + -rwxr-xr-x 1 root root 55360 Sep 23 2020 stty + -rwsr-xr-x 1 root root 46704 Feb 8 04:38 su + -rwxr-xr-x 1 root root 22532 Sep 23 2020 sync + -rwxr-xr-x 1 root root 340872 Feb 17 23:55 tar + -rwxr-xr-x 1 root root 9808 Sep 28 2020 tempfile + -rwxr-xr-x 1 root root 67696 Sep 23 2020 touch + -rwxr-xr-x 1 root root 22496 Sep 23 2020 true + -rwxr-xr-x 1 root root 9636 Feb 27 06:12 ulockmgr_server + -rwsr-xr-x 1 root root 22108 Feb 8 04:38 umount + -rwxr-xr-x 1 root root 22520 Sep 23 2020 uname + -rwxr-xr-x 2 root root 2346 Mar 3 13:30 uncompress + -rwxr-xr-x 1 root root 96764 Sep 23 2020 vdir + -rwxr-xr-x 1 root root 38512 Feb 8 04:38 wdctl + lrwxrwxrwx 1 root root 8 Nov 8 2019 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Mar 3 13:30 zcat + -rwxr-xr-x 1 root root 1678 Mar 3 13:30 zcmp + -rwxr-xr-x 1 root root 5880 Mar 3 13:30 zdiff + -rwxr-xr-x 1 root root 29 Mar 3 13:30 zegrep + -rwxr-xr-x 1 root root 29 Mar 3 13:30 zfgrep + -rwxr-xr-x 1 root root 2081 Mar 3 13:30 zforce + -rwxr-xr-x 1 root root 7585 Mar 3 13:30 zgrep + -rwxr-xr-x 1 root root 2206 Mar 3 13:30 zless + -rwxr-xr-x 1 root root 1842 Mar 3 13:30 zmore + -rwxr-xr-x 1 root root 4553 Mar 3 13:30 znew +I: user script /srv/workspace/pbuilder/9701/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -395,7 +429,7 @@ Get: 190 http://deb.debian.org/debian bullseye/main armhf rebar armhf 2.6.4-3 [203 kB] Get: 191 http://deb.debian.org/debian bullseye/main armhf dh-rebar all 0.0.4+nmu1 [7508 B] Get: 192 http://deb.debian.org/debian bullseye/main armhf erlang-goldrush armhf 0.2.0-7 [62.2 kB] -Fetched 113 MB in 16s (7210 kB/s) +Fetched 113 MB in 19s (6066 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libapparmor1:armhf. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19398 files and directories currently installed.) @@ -1196,8 +1230,45 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +The following additional packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl +The following NEW packages will be installed: + libfile-find-rule-perl libnumber-compare-perl libtext-glob-perl usrmerge +0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. +Need to get 59.5 kB of archives. +After this operation, 157 kB of additional disk space will be used. +Get:1 http://deb.debian.org/debian bullseye/main armhf libnumber-compare-perl all 0.03-1.1 [6956 B] +Get:2 http://deb.debian.org/debian bullseye/main armhf libtext-glob-perl all 0.11-1 [8888 B] +Get:3 http://deb.debian.org/debian bullseye/main armhf libfile-find-rule-perl all 0.34-1 [30.6 kB] +Get:4 http://deb.debian.org/debian bullseye/main armhf usrmerge all 25 [13.0 kB] +debconf: delaying package configuration, since apt-utils is not installed +Fetched 59.5 kB in 1s (74.2 kB/s) +Selecting previously unselected package libnumber-compare-perl. +(Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 33167 files and directories currently installed.) +Preparing to unpack .../libnumber-compare-perl_0.03-1.1_all.deb ... +Unpacking libnumber-compare-perl (0.03-1.1) ... +Selecting previously unselected package libtext-glob-perl. +Preparing to unpack .../libtext-glob-perl_0.11-1_all.deb ... +Unpacking libtext-glob-perl (0.11-1) ... +Selecting previously unselected package libfile-find-rule-perl. +Preparing to unpack .../libfile-find-rule-perl_0.34-1_all.deb ... +Unpacking libfile-find-rule-perl (0.34-1) ... +Selecting previously unselected package usrmerge. +Preparing to unpack .../archives/usrmerge_25_all.deb ... +Unpacking usrmerge (25) ... +Setting up libtext-glob-perl (0.11-1) ... +Setting up libnumber-compare-perl (0.03-1.1) ... +Setting up libfile-find-rule-perl (0.34-1) ... +Setting up usrmerge (25) ... +The system has been successfully converted. +Processing triggers for man-db (2.9.4-2) ... +Not building database; man-db/auto-update is not 'true'. I: Building the package -I: Running cd /build/erlang-lager-3.8.1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../erlang-lager_3.8.1-3_source.changes +hostname: Name or service not known +I: Running cd /build/erlang-lager-3.8.1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../erlang-lager_3.8.1-3_source.changes dpkg-buildpackage: info: source package erlang-lager dpkg-buildpackage: info: source version 3.8.1-3 dpkg-buildpackage: info: source distribution unstable @@ -1311,27 +1382,27 @@ Compiled src/lager_transform.erl Compiled src/lager_rotator_behaviour.erl DEBUG: Starting 3 compile worker(s) +Compiled src/lager_config.erl +Compiled src/lager_default_formatter.erl Compiled src/lager_backend_throttle.erl -Compiled src/lager_handler_watcher_sup.erl -Compiled src/lager_handler_watcher.erl -Compiled src/lager_sup.erl -Compiled src/lager_app.erl +Compiled src/lager.erl +Compiled src/lager_common_test_backend.erl +Compiled src/lager_trunc_io.erl Compiled src/lager_format.erl -Compiled src/lager_manager_killer.erl Compiled src/lager_file_backend.erl -Compiled src/lager_trunc_io.erl -Compiled src/lager_config.erl -Compiled src/lager_common_test_backend.erl -Compiled src/lager_rotator_default.erl -Compiled src/lager.erl -Compiled src/lager_console_backend.erl -Compiled src/lager_default_formatter.erl +Compiled src/lager_app.erl Compiled src/lager_msg.erl Compiled src/lager_crash_log.erl +Compiled src/lager_manager_killer.erl +Compiled src/lager_console_backend.erl +Compiled src/error_logger_lager_h.erl +Compiled src/lager_handler_watcher_sup.erl +Compiled src/lager_handler_watcher.erl +Compiled src/lager_rotator_default.erl DEBUG: Worker exited cleanly -Compiled src/lager_stdlib.erl +Compiled src/lager_sup.erl DEBUG: Worker exited cleanly -Compiled src/error_logger_lager_h.erl +Compiled src/lager_stdlib.erl DEBUG: Worker exited cleanly INFO: No app_vars_file defined. DEBUG: Postdirs: [] @@ -1343,112 +1414,131 @@ Compiled src/lager_util.erl Compiled src/lager_transform.erl Compiled src/lager_rotator_behaviour.erl +Compiled src/lager_config.erl +Compiled src/lager.erl Compiled src/lager_backend_throttle.erl -Compiled src/lager_handler_watcher_sup.erl +Compiled src/lager_default_formatter.erl +Compiled src/lager_common_test_backend.erl +Compiled src/lager_trunc_io.erl +Compiled src/error_logger_lager_h.erl Compiled src/lager_app.erl -Compiled src/lager_handler_watcher.erl -Compiled src/lager_sup.erl Compiled src/lager_format.erl +Compiled src/lager_msg.erl +Compiled src/lager_file_backend.erl Compiled src/lager_manager_killer.erl -Compiled src/lager.erl -Compiled src/lager_config.erl +Compiled src/lager_crash_log.erl Compiled src/lager_rotator_default.erl -Compiled src/lager_common_test_backend.erl -Compiled src/lager_trunc_io.erl -Compiled src/lager_file_backend.erl +Compiled src/lager_handler_watcher_sup.erl +Compiled src/lager_handler_watcher.erl +Compiled src/lager_sup.erl +Compiled test/crash_statem.erl +Compiled test/compress_pr_record_test.erl +Compiled test/trunc_io_eqc.erl +Compiled test/zzzz_gh280_crash.erl Compiled src/lager_console_backend.erl +Compiled test/sync_error_logger.erl Compiled src/lager_stdlib.erl -Compiled src/lager_msg.erl -Compiled src/lager_default_formatter.erl -Compiled src/lager_crash_log.erl -Compiled test/lager_slow_backend.erl +Compiled test/lager_crash_backend.erl +Compiled test/lager_metadata_whitelist_test.erl +Compiled test/pr_composite_test.erl +Compiled test/special_process.erl +Compiled test/lager_rotate.erl +Compiled test/lager_app_tests.erl pr_stacktrace_test.erl:22: Warning: this expression will fail with a 'badarg' exception pr_stacktrace_test.erl:22: Warning: this expression will fail with a 'badarg' exception Compiled test/pr_stacktrace_test.erl -Compiled test/lager_rotate.erl -Compiled test/lager_trace_test.erl -Compiled src/error_logger_lager_h.erl -Compiled test/lager_metadata_whitelist_test.erl -Compiled test/lager_crash_backend.erl -Compiled test/lager_app_tests.erl +Compiled test/crash_fsm.erl +Compiled test/lager_manager_killer_test.erl +Compiled test/lager_slow_backend.erl crash.erl:27: Warning: invalid function call crash.erl:66: Warning: this expression will fail with a 'badarg' exception crash.erl:27: Warning: invalid function call crash.erl:66: Warning: this expression will fail with a 'badarg' exception Compiled test/crash.erl -Compiled test/pr_composite_test.erl +Compiled test/lager_trace_test.erl Compiled test/lager_test_function_transform.erl -Compiled test/zzzz_gh280_crash.erl -Compiled test/compress_pr_record_test.erl -Compiled test/crash_statem.erl -Compiled test/crash_fsm.erl -Compiled test/special_process.erl -Compiled test/trunc_io_eqc.erl -Compiled test/sync_error_logger.erl -Compiled test/lager_manager_killer_test.erl Compiled test/lager_test_backend.erl ======================== EUnit ======================== 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/31333822") + called as delete_test_dir("/tmp/lager_test/45459137") 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/94533246") + called as delete_test_dir("/tmp/lager_test/124721868") 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/95464806") + called as delete_test_dir("/tmp/lager_test/99040731") in call from lager_rotator_default:rotate_file_fail_test/0 **error:undef output:<<"">> - [done in 0.096 s] -zzzz_gh280_crash: gh280_crash_test (module 'zzzz_gh280_crash')...ok -module 'crash' -module 'lager_sup' + [done in 0.201 s] module 'lager_backend_throttle' +module 'lager_stdlib' +module 'crash_statem' +module 'lager_rotator_behaviour' +module 'lager_trace_test' + lager_trace_test: trace_test_ (Trace combined with log_root)...[4.252 s] ok + [done in 4.282 s] +module 'lager_config' +module 'lager_transform' +module 'lager_slow_backend' +module 'lager_crash_log' + lager_crash_log: filesystem_test_ (under normal circumstances, file should be opened)...[0.040 s] ok + lager_crash_log: filesystem_test_ (file can't be opened on startup triggers an error message)...[0.851 s] ok + lager_crash_log: filesystem_test_ (file that becomes unavailable at runtime should trigger an error message)...[0.480 s] ok + lager_crash_log: filesystem_test_ (unavailable files that are fixed at runtime should start having log messages written)...[0.020 s] ok + lager_crash_log: filesystem_test_ (external logfile rotation/deletion should be handled)...[0.600 s] ok + [done in 13.525 s] +module 'lager_rotate' + lager_rotate: rotate_test_ (Rotate single file)...[0.572 s] ok + lager_rotate: rotate_test_ (Rotate sink)...[0.119 s] ok + lager_rotate: rotate_test_ (Rotate all)...[0.104 s] ok + [done in 5.241 s] +module 'lager_crash_backend' module 'lager_test_backend' - lager_test_backend: not_running_test...ok - lager_test_backend: lager_test_ (observe that there is nothing up my sleeve)...ok + lager_test_backend: not_running_test...[0.001 s] ok + lager_test_backend: lager_test_ (observe that there is nothing up my sleeve)...[0.001 s] ok lager_test_backend: lager_test_ (test sink not running)...ok lager_test_backend: lager_test_ (logging works)...ok lager_test_backend: lager_test_ (logging with macro works)...ok lager_test_backend: lager_test_ (unsafe logging works)...ok - lager_test_backend: lager_test_ (logging with arguments works)...ok + lager_test_backend: lager_test_ (logging with arguments works)...[0.001 s] ok lager_test_backend: lager_test_ (logging with macro and arguments works)...ok lager_test_backend: lager_test_ (unsafe logging with args works)...ok lager_test_backend: lager_test_ (logging works from inside a begin/end block)...ok lager_test_backend: lager_test_ (logging works from inside a list comprehension)...[0.001 s] ok - lager_test_backend: lager_test_ (logging works from a begin/end block inside a list comprehension)...[0.001 s] ok - lager_test_backend: lager_test_ (logging works from a nested list comprehension)...[0.005 s] ok - lager_test_backend: lager_test_ (logging with only metadata works)...ok + lager_test_backend: lager_test_ (logging works from a begin/end block inside a list comprehension)...[0.002 s] ok + lager_test_backend: lager_test_ (logging works from a nested list comprehension)...[0.007 s] ok + lager_test_backend: lager_test_ (logging with only metadata works)...[0.001 s] ok lager_test_backend: lager_test_ (variables inplace of literals in logging statements work)...[0.001 s] ok - lager_test_backend: lager_test_ (list comprehension inplace of literals in logging statements work)...[0.001 s] ok + lager_test_backend: lager_test_ (list comprehension inplace of literals in logging statements work)...[0.002 s] ok lager_test_backend: lager_test_ (function calls inplace of literals in logging statements work)...[0.001 s] ok lager_test_backend: lager_test_ (record fields inplace of literals in logging statements work)...[0.001 s] ok lager_test_backend: lager_test_ (log messages below the threshold are ignored)...[0.003 s] ok - lager_test_backend: lager_test_ (tracing works)...[0.082 s] ok - lager_test_backend: lager_test_ (tracing works with custom attributes)...[0.239 s] ok - lager_test_backend: lager_test_ (tracing works with custom attributes and event stream processing)...[1.264 s] ok - lager_test_backend: lager_test_ (tracing custom attributes works with event stream processing statistics and reductions)...[0.682 s] ok - lager_test_backend: lager_test_ (persistent traces work)...[0.153 s] ok - lager_test_backend: lager_test_ (tracing honors loglevel)...[0.132 s] ok - lager_test_backend: lager_test_ (stopped trace stops and removes its event handler - default sink (gh#267))...[0.314 s] ok + lager_test_backend: lager_test_ (tracing works)...[0.093 s] ok + lager_test_backend: lager_test_ (tracing works with custom attributes)...[0.278 s] ok + lager_test_backend: lager_test_ (tracing works with custom attributes and event stream processing)...[1.453 s] ok + lager_test_backend: lager_test_ (tracing custom attributes works with event stream processing statistics and reductions)...[0.762 s] ok + lager_test_backend: lager_test_ (persistent traces work)...[0.178 s] ok + lager_test_backend: lager_test_ (tracing honors loglevel)...[0.135 s] ok + lager_test_backend: lager_test_ (stopped trace stops and removes its event handler - default sink (gh#267))...[0.339 s] ok lager_test_backend: lager_test_ (record printing works)...[0.101 s] ok lager_test_backend: lager_test_ (record printing fails gracefully)...[0.101 s] ok lager_test_backend: lager_test_ (record printing fails gracefully when no lager_record attribute)...[0.101 s] ok lager_test_backend: lager_test_ (record printing fails gracefully when input is not a tuple)...[0.101 s] ok lager_test_backend: lager_test_ (record printing fails gracefully when module is invalid)...[1.001 s] ok lager_test_backend: lager_test_ (installing a new handler adjusts the global loglevel if necessary)...[0.001 s] ok - lager_test_backend: lager_test_ (metadata in the process dictionary works)...[0.001 s] ok + lager_test_backend: lager_test_ (metadata in the process dictionary works)...ok lager_test_backend: lager_test_ (unsafe messages really are not truncated)...[0.003 s] ok lager_test_backend: lager_test_ (can't store invalid metadata)...ok lager_test_backend: lager_test_ (dates should be local by default)...ok @@ -1457,13 +1547,13 @@ lager_test_backend: extra_sinks_test_ (logging works)...[0.001 s] ok lager_test_backend: extra_sinks_test_ (logging with arguments works)...[0.001 s] ok lager_test_backend: extra_sinks_test_ (variables inplace of literals in logging statements work)...[0.001 s] ok - lager_test_backend: extra_sinks_test_ (stopped trace stops and removes its event handler - test sink (gh#267))...[0.313 s] ok + lager_test_backend: extra_sinks_test_ (stopped trace stops and removes its event handler - test sink (gh#267))...[0.329 s] ok lager_test_backend: extra_sinks_test_ (log messages below the threshold are ignored)...[0.002 s] ok FSM crash output tests Default sink lager_test_backend: crash_fsm_test_ (again, there is nothing up my sleeve)...ok - lager_test_backend: crash_fsm_test_ (gen_fsm crash)...lager_test_backend.erl:946:<0.1958.0>: Trailing data "8" following "gen_fsm crash_fsm in state state1 terminated with reason: call to undefined function crash_fsm:state1/3 from gen_fsm:handle_msg/" -[0.102 s] ok + lager_test_backend: crash_fsm_test_ (gen_fsm crash)...lager_test_backend.erl:946:<0.2451.0>: Trailing data "8" following "gen_fsm crash_fsm in state state1 terminated with reason: call to undefined function crash_fsm:state1/3 from gen_fsm:handle_msg/" +[0.101 s] ok lager_test_backend: crash_fsm_test_ (gen_statem crash)...*failed* in function lager_test_backend:'-crash_fsm_test_/0-fun-2-'/5 (lager_test_backend.erl, line 1040) in call from eunit_test:run_testfun/1 (eunit_test.erl, line 71) @@ -1489,7 +1579,7 @@ {line,924}, {expression,"Body"}, {expected,"gen_statem crash_statem in state state1 terminated with reason: explode"}, - {value,"Error in process <0.2034.0> with exit value:\n{undef,[{crash_statem,stop"}]} + {value,"Error in process <0.2521.0> with exit value:\n{undef,[{crash_statem,stop"}]} output:<<"">> lager_test_backend: crash_fsm_test_ (gen_statem timeout)...*failed* @@ -1505,13 +1595,13 @@ {line,924}, {expression,"Body"}, {expected,"gen_statem crash_statem in state state1 terminated with reason: timeout"}, - {value,"Error in process <0.2071.0> with exit value:\n{undef,[{crash_statem,time"}]} + {value,"Error in process <0.2555.0> with exit value:\n{undef,[{crash_statem,time"}]} output:<<"">> - [done in 1.329 s] + [done in 1.359 s] Error logger sink lager_test_backend: crash_fsm_test_ (again, there is nothing up my sleeve)...ok - lager_test_backend: crash_fsm_test_ (gen_fsm crash)...lager_test_backend.erl:946:<0.2143.0>: Trailing data "8" following "gen_fsm crash_fsm in state state1 terminated with reason: call to undefined function crash_fsm:state1/3 from gen_fsm:handle_msg/" + lager_test_backend: crash_fsm_test_ (gen_fsm crash)...lager_test_backend.erl:946:<0.2623.0>: Trailing data "8" following "gen_fsm crash_fsm in state state1 terminated with reason: call to undefined function crash_fsm:state1/3 from gen_fsm:handle_msg/" [0.102 s] ok lager_test_backend: crash_fsm_test_ (gen_statem crash)...*failed* in function lager_test_backend:'-crash_fsm_test_/0-fun-2-'/5 (lager_test_backend.erl, line 1040) @@ -1538,7 +1628,7 @@ {line,924}, {expression,"Body"}, {expected,"gen_statem crash_statem in state state1 terminated with reason: explode"}, - {value,"Error in process <0.2221.0> with exit value:\n{undef,[{crash_statem,stop"}]} + {value,"Error in process <0.2697.0> with exit value:\n{undef,[{crash_statem,stop"}]} output:<<"">> lager_test_backend: crash_fsm_test_ (gen_statem timeout)...*failed* @@ -1554,43 +1644,43 @@ {line,924}, {expression,"Body"}, {expected,"gen_statem crash_statem in state state1 terminated with reason: timeout"}, - {value,"Error in process <0.2259.0> with exit value:\n{undef,[{crash_statem,time"}]} + {value,"Error in process <0.2733.0> with exit value:\n{undef,[{crash_statem,time"}]} output:<<"">> - [done in 1.308 s] - [done in 2.637 s] + [done in 1.397 s] + [done in 2.756 s] Error logger redirect crash Redirect to default sink lager_test_backend: error_logger_redirect_crash_test_ (again, there is nothing up my sleeve)...ok - lager_test_backend: error_logger_redirect_crash_test_ (bad return value)...[0.102 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (bad return value)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad return value with string)...[0.101 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (bad return uncaught throw)...[0.101 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (bad return uncaught throw)...[0.102 s] ok lager_test_backend: error_logger_redirect_crash_test_ (case clause)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (case clause string)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (function clause)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (if clause)...[0.101 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (try clause)...[0.101 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (undefined function)...[0.101 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (try clause)...[0.104 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (undefined function)...[0.103 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad math)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad match)...[0.101 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (bad arity)...[0.101 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (bad arity)...[0.102 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad arg1)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad arg2)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad record)...[0.102 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (noproc)...lager_test_backend.erl:946:<0.2882.0>: Trailing data " in gen_event:call1/3" following "gen_server crash terminated with reason: no such process or port in call to gen_event:call(foo, bar, baz)" + lager_test_backend: error_logger_redirect_crash_test_ (noproc)...lager_test_backend.erl:946:<0.3320.0>: Trailing data " in gen_event:call1/3" following "gen_server crash terminated with reason: no such process or port in call to gen_event:call(foo, bar, baz)" [0.102 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (noproc_proc_lib)...[0.101 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (noproc_proc_lib)...[0.102 s] ok lager_test_backend: error_logger_redirect_crash_test_ (badfun)...[0.101 s] ok - [done in 3.319 s] + [done in 3.551 s] Redirect to error_logger_lager_event sink - lager_test_backend: error_logger_redirect_crash_test_ (again, there is nothing up my sleeve)...[0.001 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (again, there is nothing up my sleeve)...ok lager_test_backend: error_logger_redirect_crash_test_ (bad return value)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad return value with string)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad return uncaught throw)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (case clause)...[0.101 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (case clause string)...[0.101 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (case clause string)...[0.102 s] ok lager_test_backend: error_logger_redirect_crash_test_ (function clause)...[0.101 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (if clause)...[0.101 s] ok + lager_test_backend: error_logger_redirect_crash_test_ (if clause)...[0.102 s] ok lager_test_backend: error_logger_redirect_crash_test_ (try clause)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (undefined function)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad math)...[0.101 s] ok @@ -1599,249 +1689,181 @@ lager_test_backend: error_logger_redirect_crash_test_ (bad arg1)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad arg2)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (bad record)...[0.101 s] ok - lager_test_backend: error_logger_redirect_crash_test_ (noproc)...lager_test_backend.erl:946:<0.3683.0>: Trailing data " in gen_event:call1/3" following "gen_server crash terminated with reason: no such process or port in call to gen_event:call(foo, bar, baz)" + lager_test_backend: error_logger_redirect_crash_test_ (noproc)...lager_test_backend.erl:946:<0.4080.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_ (noproc_proc_lib)...[0.101 s] ok lager_test_backend: error_logger_redirect_crash_test_ (badfun)...[0.101 s] ok - [done in 3.446 s] - [done in 6.765 s] + [done in 3.624 s] + [done in 7.175 s] Error logger redirect Redirect to default sink - lager_test_backend: error_logger_redirect_test_ (error reports are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (string error reports are printed)...[0.002 s] ok + lager_test_backend: error_logger_redirect_test_ (error reports are printed)...ok + lager_test_backend: error_logger_redirect_test_ (string error reports are printed)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (error messages are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (error messages with unicode characters in Args are printed)...[0.013 s] ok - lager_test_backend: error_logger_redirect_test_ (error messages are truncated at 4096 characters)...[0.062 s] ok + lager_test_backend: error_logger_redirect_test_ (error messages with unicode characters in Args are printed)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (error messages are truncated at 4096 characters)...[0.012 s] ok lager_test_backend: error_logger_redirect_test_ (info reports are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (info reports are truncated at 4096 characters)...[0.018 s] ok + lager_test_backend: error_logger_redirect_test_ (info reports are truncated at 4096 characters)...[0.021 s] ok lager_test_backend: error_logger_redirect_test_ (single term info reports are printed)...ok lager_test_backend: error_logger_redirect_test_ (single term error reports are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (string info reports are printed)...ok - lager_test_backend: error_logger_redirect_test_ (string info reports are truncated at 4096 characters)...[0.031 s] ok + lager_test_backend: error_logger_redirect_test_ (string info reports are printed)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (string info reports are truncated at 4096 characters)...[0.037 s] ok lager_test_backend: error_logger_redirect_test_ (strings in a mixed report are printed as strings)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (info messages are printed)...ok - lager_test_backend: error_logger_redirect_test_ (info messages are truncated at 4096 characters)...[0.010 s] ok - lager_test_backend: error_logger_redirect_test_ (info messages with unicode characters in Args are printed)...ok - lager_test_backend: error_logger_redirect_test_ (warning messages with unicode characters in Args are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (warning messages are printed at the correct level)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (warning reports are printed at the correct level)...ok - lager_test_backend: error_logger_redirect_test_ (single term warning reports are printed at the correct level)...ok + lager_test_backend: error_logger_redirect_test_ (info messages are printed)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (info messages are truncated at 4096 characters)...[0.011 s] ok + lager_test_backend: error_logger_redirect_test_ (info messages with unicode characters in Args are printed)...[0.004 s] ok + lager_test_backend: error_logger_redirect_test_ (warning messages with unicode characters in Args are printed)...ok + lager_test_backend: error_logger_redirect_test_ (warning messages are printed at the correct level)...ok + lager_test_backend: error_logger_redirect_test_ (warning reports are printed at the correct level)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (single term warning reports are printed at the correct level)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (application stop reports)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor reports)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error and pid)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor_bridge reports)...[0.007 s] ok - lager_test_backend: error_logger_redirect_test_ (application progress report)...ok - lager_test_backend: error_logger_redirect_test_ (supervisor progress report)...[0.003 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor reports)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error and pid)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor_bridge reports)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (application progress report)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor progress report)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (supervisor progress report with pid)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for emfile)...[0.002 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for emfile)...ok lager_test_backend: error_logger_redirect_test_ (crash report for system process limit)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (crash report for system process limit2)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for system port limit)...[0.002 s] ok lager_test_backend: error_logger_redirect_test_ (crash report for system port limit)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for system ets table limit)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for unknown system limit should be truncated at 500 characters)...[0.072 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for system port limit)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for system ets table limit)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for unknown system limit should be truncated at 500 characters)...[0.003 s] ok lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - function_clause)...[0.501 s] ok lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - case_clause)...[0.501 s] ok lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - exit)...[0.501 s] ok - lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - error)...[0.501 s] ok + lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - error)...[0.502 s] ok lager_test_backend: error_logger_redirect_test_ (webmachine error reports)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 8 arg version)...[0.009 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 10 arg version)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 5 arg version)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 6 arg version)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (messages should not be generated if they don't satisfy the threshold)...[0.004 s] ok - [done in 49.956 s] + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 8 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 10 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 5 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 6 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (messages should not be generated if they don't satisfy the threshold)...[0.005 s] ok + [done in 50.013 s] Redirect to error_logger_lager_event sink - lager_test_backend: error_logger_redirect_test_ (error reports are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (string error reports are printed)...[0.002 s] ok + lager_test_backend: error_logger_redirect_test_ (error reports are printed)...ok + lager_test_backend: error_logger_redirect_test_ (string error reports are printed)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (error messages are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (error messages with unicode characters in Args are printed)...[0.015 s] ok - lager_test_backend: error_logger_redirect_test_ (error messages are truncated at 4096 characters)...[0.061 s] ok + lager_test_backend: error_logger_redirect_test_ (error messages with unicode characters in Args are printed)...ok + lager_test_backend: error_logger_redirect_test_ (error messages are truncated at 4096 characters)...[0.011 s] ok lager_test_backend: error_logger_redirect_test_ (info reports are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (info reports are truncated at 4096 characters)...[0.017 s] ok + lager_test_backend: error_logger_redirect_test_ (info reports are truncated at 4096 characters)...[0.038 s] ok lager_test_backend: error_logger_redirect_test_ (single term info reports are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (single term error reports are printed)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (single term error reports are printed)...ok lager_test_backend: error_logger_redirect_test_ (string info reports are printed)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (string info reports are truncated at 4096 characters)...[0.033 s] ok + lager_test_backend: error_logger_redirect_test_ (string info reports are truncated at 4096 characters)...[0.036 s] ok lager_test_backend: error_logger_redirect_test_ (strings in a mixed report are printed as strings)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (info messages are printed)...ok - lager_test_backend: error_logger_redirect_test_ (info messages are truncated at 4096 characters)...[0.010 s] ok - lager_test_backend: error_logger_redirect_test_ (info messages with unicode characters in Args are printed)...ok + lager_test_backend: error_logger_redirect_test_ (info messages are truncated at 4096 characters)...[0.019 s] ok + lager_test_backend: error_logger_redirect_test_ (info messages with unicode characters in Args are printed)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (warning messages with unicode characters in Args are printed)...ok lager_test_backend: error_logger_redirect_test_ (warning messages are printed at the correct level)...ok - lager_test_backend: error_logger_redirect_test_ (warning reports are printed at the correct level)...ok + lager_test_backend: error_logger_redirect_test_ (warning reports are printed at the correct level)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (single term warning reports are printed at the correct level)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (application stop reports)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor reports)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error and pid)...[0.003 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor_bridge reports)...[0.002 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor reports)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor reports with real error and pid)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor_bridge reports)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (application progress report)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (supervisor progress report)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (supervisor progress report with pid)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for emfile)...[0.003 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for system process limit)...[0.002 s] ok + lager_test_backend: error_logger_redirect_test_ (supervisor progress report with pid)...[0.002 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for emfile)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for system process limit)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (crash report for system process limit2)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (crash report for system port limit)...[0.001 s] ok lager_test_backend: error_logger_redirect_test_ (crash report for system port limit)...[0.001 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for system ets table limit)...[0.003 s] ok - lager_test_backend: error_logger_redirect_test_ (crash report for unknown system limit should be truncated at 500 characters)...[0.071 s] ok - lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - function_clause)...[0.501 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for system ets table limit)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (crash report for unknown system limit should be truncated at 500 characters)...[0.007 s] ok + lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - function_clause)...[0.502 s] ok lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - case_clause)...[0.501 s] ok lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - exit)...[0.501 s] ok lager_test_backend: error_logger_redirect_test_ (crash reports for 'special processes' should be handled right - error)...[0.501 s] ok - lager_test_backend: error_logger_redirect_test_ (webmachine error reports)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 8 arg version)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 10 arg version)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 5 arg version)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 6 arg version)...[0.002 s] ok - lager_test_backend: error_logger_redirect_test_ (messages should not be generated if they don't satisfy the threshold)...[0.004 s] ok - [done in 50.115 s] - [done in 100.071 s] - lager_test_backend: safe_format_test...ok - lager_test_backend: unsafe_format_test...[0.001 s] ok - lager_test_backend: async_threshold_test_ (async threshold works)...[1.508 s] ok - lager_test_backend: high_watermark_test_ (Nothing dropped when error_logger high watermark is undefined)...[0.603 s] ok - lager_test_backend: high_watermark_test_ (Mostly dropped according to error_logger high watermark)...[1.102 s] ok + lager_test_backend: error_logger_redirect_test_ (webmachine error reports)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 8 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 10 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 5 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (Cowboy error reports, 6 arg version)...[0.001 s] ok + lager_test_backend: error_logger_redirect_test_ (messages should not be generated if they don't satisfy the threshold)...[0.013 s] ok + [done in 50.728 s] + [done in 100.741 s] + lager_test_backend: safe_format_test...[0.001 s] ok + lager_test_backend: unsafe_format_test...[0.002 s] ok + lager_test_backend: async_threshold_test_ (async threshold works)...[1.511 s] ok + lager_test_backend: high_watermark_test_ (Nothing dropped when error_logger high watermark is undefined)...[0.602 s] ok + lager_test_backend: high_watermark_test_ (Mostly dropped according to error_logger high watermark)...[1.104 s] ok lager_test_backend: high_watermark_test_ (Non-notifications are not dropped)...[1.153 s] ok - [done in 158.162 s] -module 'lager_crash_log' - lager_crash_log: filesystem_test_ (under normal circumstances, file should be opened)...[0.002 s] ok - lager_crash_log: filesystem_test_ (file can't be opened on startup triggers an error message)...[0.005 s] ok - lager_crash_log: filesystem_test_ (file that becomes unavailable at runtime should trigger an error message)...[0.007 s] ok - lager_crash_log: filesystem_test_ (unavailable files that are fixed at runtime should start having log messages written)...[0.003 s] ok - lager_crash_log: filesystem_test_ (external logfile rotation/deletion should be handled)...[0.011 s] ok - [done in 5.424 s] -module 'lager_slow_backend' -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_app' - lager_app:326: application_config_mangling_test_ (Explode the file backend handlers)...ok - lager_app:339: application_config_mangling_test_ (Explode the short form of backend file handlers)...ok - lager_app:352: application_config_mangling_test_ (Explode with formatter info)...ok - lager_app:363: application_config_mangling_test_ (Explode short form with short formatter info)...ok - lager_app:374: application_config_mangling_test_ (New form needs no expansion)...ok - lager_app:408: check_handler_config_test_ (lager_file_backend_good)...ok -=ERROR REPORT==== 22-Jul-2021::18:02:22.604998 === -Cannot have same file ("same_file.log") in multiple file backends - - lager_app:411: check_handler_config_test_ (lager_file_backend_bad)...ok - lager_app:414: check_handler_config_test_ (Invalid config dies)...ok - lager_app:417: check_handler_config_test_ (Invalid config dies)...[0.007 s] ok - lager_app:420: check_handler_config_test_ (Old Lager config works)...ok - lager_app:423: check_handler_config_test_ (New Config missing its list should fail)...ok - lager_app_tests: get_env_test (module 'lager_app_tests')...ok - [done in 0.050 s] + [done in 160.611 s] module 'lager_trunc_io' - lager_trunc_io: format_test...ok + lager_trunc_io: format_test...[0.001 s] ok lager_trunc_io: atom_quoting_test...ok - lager_trunc_io: sane_float_printing_test...ok + lager_trunc_io: sane_float_printing_test...[0.001 s] ok lager_trunc_io: float_inside_list_test...ok lager_trunc_io: quote_strip_test...ok lager_trunc_io: binary_printing_test...[0.001 s] ok lager_trunc_io: bitstring_printing_test...ok lager_trunc_io: list_printing_test...[0.001 s] 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: tuple_printing_test...[0.001 s] ok + lager_trunc_io: map_printing_test...[0.001 s] ok lager_trunc_io: unicode_test...ok - lager_trunc_io: depth_limit_test...[0.002 s] ok + lager_trunc_io: depth_limit_test...[0.003 s] ok lager_trunc_io: print_terms_without_format_string_test...ok lager_trunc_io: improper_io_list_test...ok - [done in 0.051 s] -module 'crash_statem' + [done in 0.055 s] +module 'lager_handler_watcher' + lager_handler_watcher: reinstall_on_initial_failure_test_...[6.088 s] ok + lager_handler_watcher: reinstall_on_runtime_failure_test_...[6.098 s] ok + lager_handler_watcher: reinstall_handlers_after_killer_hwm_test_...[6.165 s] ok + [done in 18.361 s] +module 'crash_fsm' +module 'lager' + lager: get_sink_handler_status_ascii_test...ok + lager: get_sink_handler_status_latin_test...ok + lager: get_sink_handler_status_unicode_test...ok + [done in 0.010 s] +module 'lager_test_function_transform' + lager_test_function_transform: not_running_test...ok + lager_test_function_transform: transform_function_test_ (observe that there is nothing up my sleeve)...[0.001 s] ok + lager_test_function_transform: transform_function_test_ (logging works)...ok + lager_test_function_transform: transform_function_test_ (Testing calling a function returns the same content on emit)...ok + lager_test_function_transform: transform_function_test_ (Testing calling a function which returns content which can change on emit)...ok + lager_test_function_transform: transform_function_test_ (Testing a undefined function returns undefined on emit)...[0.001 s] ok + lager_test_function_transform: transform_function_test_ (Testing calling a function returns the same content on log)...ok + lager_test_function_transform: transform_function_test_ (Testing calling a dynamic function on log which returns the same value)...[0.001 s] ok + lager_test_function_transform: transform_function_test_ (Testing differences in results for on_log vs on emit from dynamic function)...ok + lager_test_function_transform: transform_function_test_ (Testing a function provided via metadata)...ok + [done in 3.107 s] +module 'trunc_io_eqc' +module 'lager_manager_killer' +module 'sync_error_logger' module 'lager_msg' -module 'lager_console_backend' - lager_console_backend:242: console_config_validation_test_...ok - lager_console_backend:243: console_config_validation_test_...ok - lager_console_backend:244: console_config_validation_test_...ok - lager_console_backend:245: console_config_validation_test_...ok - lager_console_backend: console_log_test_ (regular console logging)...[0.002 s] ok - lager_console_backend: console_log_test_ (verbose console logging)...[0.002 s] ok - lager_console_backend: console_log_test_ (custom format console logging)...[0.003 s] ok - lager_console_backend: console_log_test_ (tracing should work)...[0.577 s] ok - lager_console_backend: console_log_test_ (tracing doesn't duplicate messages)...[1.077 s] ok - lager_console_backend: console_log_test_ (blacklisting a loglevel works)...[0.502 s] ok - lager_console_backend: console_log_test_ (whitelisting a loglevel works)...[0.504 s] ok - lager_console_backend: console_log_test_ (console backend with custom group leader)...[0.603 s] ok - lager_console_backend: console_log_test_ (console backend with custom group leader using a trace and an ID)...[1.174 s] ok - lager_console_backend: set_loglevel_test_ (Get/set loglevel test)...[0.002 s] ok - lager_console_backend: set_loglevel_test_ (Get/set invalid loglevel test)...ok - [done in 5.256 s] -module 'lager_crash_backend' -compress_pr_record_test: nested_record_test (module 'compress_pr_record_test')...[0.001 s] ok -module 'error_logger_lager_h' - error_logger_lager_h: no_silent_hwm_drops_test_...[3.031 s] ok - error_logger_lager_h: shaper_does_not_forward_sup_progress_messages_to_info_level_backend_test_...[0.124 s] ok - error_logger_lager_h: supressed_messages_are_not_counted_for_hwm_test_...[0.123 s] ok - [done in 3.287 s] -module 'lager_trace_test' - lager_trace_test: trace_test_ (Trace combined with log_root)...[4.177 s] ok - [done in 4.181 s] -module 'lager_format' +module 'pr_stacktrace_test' + pr_stacktrace_test: pr_stacktrace_throw_test...[0.001 s] ok + pr_stacktrace_test: pr_stacktrace_bad_arg_test...ok + pr_stacktrace_test: pr_stacktrace_bad_arity_test...ok + pr_stacktrace_test: pr_stacktrace_no_reverse_test...ok + [done in 0.013 s] +module 'lager_common_test_backend' +module 'special_process' module 'lager_handler_watcher_sup' -module 'lager_file_backend' - lager_file_backend: get_loglevel_test...ok - lager_file_backend: rotation_test_ (External rotation should work)...[0.008 s] ok - lager_file_backend: rotation_test_ (Internal rotation and delayed write)...[1.006 s] ok - lager_file_backend: filesystem_test_ (under normal circumstances, file should be opened)...[0.005 s] ok - lager_file_backend: filesystem_test_ (don't choke on unicode)...[0.006 s] ok - lager_file_backend: filesystem_test_ (don't choke on latin-1)...[0.005 s] ok - lager_file_backend: filesystem_test_ (file can't be opened on startup triggers an error message)...[0.005 s] ok - lager_file_backend: filesystem_test_ (file that becomes unavailable at runtime should trigger an error message)...[0.008 s] ok - lager_file_backend: filesystem_test_ (unavailable files that are fixed at runtime should start having log messages written)...[0.007 s] ok - lager_file_backend: filesystem_test_ (external logfile rotation/deletion should be handled)...[0.016 s] ok - lager_file_backend: filesystem_test_ (internal size rotation should work)...[0.010 s] ok - lager_file_backend: filesystem_test_ (internal time rotation should work)...[0.013 s] ok - lager_file_backend: filesystem_test_ (rotation call should work)...[0.014 s] ok - lager_file_backend: filesystem_test_ (sync_on option should work)...[0.005 s] ok - lager_file_backend: filesystem_test_ (sync_on none option should work (also tests sync_interval))...[2.003 s] ok - lager_file_backend: filesystem_test_ (sync_size option should work)...[0.004 s] ok - lager_file_backend: filesystem_test_ (runtime level changes)...[0.008 s] ok - lager_file_backend: filesystem_test_ (invalid runtime level changes)...[0.002 s] ok - lager_file_backend: filesystem_test_ (tracing should work)...[1.006 s] ok - lager_file_backend: filesystem_test_ (tracing should not duplicate messages)...[0.515 s] ok - lager_file_backend: filesystem_test_ (tracing to a dedicated file should work)...[0.077 s] ok - lager_file_backend: filesystem_test_ (tracing to a dedicated file should work even if root_log is set)...[0.079 s] ok - lager_file_backend: filesystem_test_ (tracing with options should work)...[0.104 s] ok - lager_file_backend: filesystem_test_ (no silent hwm drops)...[1.928 s] ok - lager_file_backend: trace_files_test_ (a trace using file backend set up in configuration should work)...[1.016 s] ok - lager_file_backend: formatting_test_ (Should have two log files, the second prefixed with 2>)...[0.008 s] ok - lager_file_backend:1137: config_validation_test_ (missing file)...ok - lager_file_backend:1141: config_validation_test_ (bad level)...ok - lager_file_backend:1145: config_validation_test_ (bad size)...ok - lager_file_backend:1149: config_validation_test_ (bad count)...ok - lager_file_backend:1153: config_validation_test_ (bad high water mark)...ok - lager_file_backend:1157: config_validation_test_ (bad date)...ok - lager_file_backend:1161: config_validation_test_ (blank date is ok)...ok - lager_file_backend:1165: config_validation_test_ (bad sync_interval)...ok - lager_file_backend:1169: config_validation_test_ (bad sync_size)...ok - lager_file_backend:1173: config_validation_test_ (bad check_interval)...ok - lager_file_backend:1177: config_validation_test_ (bad sync_on level)...ok - lager_file_backend:1181: config_validation_test_ (bad formatter module)...ok - lager_file_backend:1185: config_validation_test_ (bad formatter config)...ok - lager_file_backend:1189: config_validation_test_ (unknown option)...ok - [done in 9.967 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==== 22-Jul-2021::18:02:45.794735 === + lager_util: check_trace_test...=INFO REPORT==== 23-Jul-2021::20:12:13.545960 === application: lager exited: stopped type: permanent -=INFO REPORT==== 22-Jul-2021::18:02:45.796986 === +=INFO REPORT==== 23-Jul-2021::20:12:13.549465 === application: goldrush exited: stopped type: permanent -[0.138 s] ok +[0.149 s] ok lager_util:757: is_loggable_test_ (Loggable by severity only)...ok lager_util:758: is_loggable_test_ (Not loggable by severity only)...ok lager_util:759: is_loggable_test_ (Loggable by severity with destination)...ok @@ -1855,98 +1877,52 @@ lager_util: config_to_levels_test...ok lager_util: config_to_mask_test...ok lager_util: mask_to_levels_test...ok - lager_util: expand_path_test...ok + lager_util: expand_path_test...[0.001 s] ok lager_util:850: sink_name_test_...ok lager_util:851: sink_name_test_...ok - [done in 0.198 s] -module 'lager_transform' -module 'lager' - lager: get_sink_handler_status_ascii_test...ok - lager: get_sink_handler_status_latin_test...ok - lager: get_sink_handler_status_unicode_test...ok - [done in 0.009 s] -module 'lager_default_formatter' - lager_default_formatter:277: basic_test_ (Default formatting test)...ok - lager_default_formatter:286: basic_test_ (Basic Formatting)...ok - lager_default_formatter:295: basic_test_ (Default equivalent formatting test)...ok - lager_default_formatter:305: basic_test_ (Non existent metadata can default to string)...ok - lager_default_formatter:315: basic_test_ (Non existent metadata can default to other metadata)...ok - lager_default_formatter:325: basic_test_ (Non existent metadata can default to a string2)...ok - lager_default_formatter:335: basic_test_ (Metadata can have extra formatting)...ok - lager_default_formatter:345: basic_test_ (Metadata can have extra formatting1)...ok - lager_default_formatter:355: basic_test_ (Metadata can have extra formatting2)...ok - lager_default_formatter:365: basic_test_ (Metadata can have extra formatting3)...ok - lager_default_formatter:375: basic_test_ (Metadata can be printed in its enterity)...ok - lager_default_formatter:385: basic_test_ (Metadata can be printed in its enterity with custom seperators)...ok - lager_default_formatter:395: basic_test_ (Metadata can have extra formatting with width 1)...ok - lager_default_formatter:409: basic_test_ (Metadata can have extra formatting with width 2)...ok - lager_default_formatter:419: basic_test_ (Metadata can have extra formatting with width 3)...ok - lager_default_formatter:429: basic_test_ (Metadata can have extra formatting with width 4)...ok - lager_default_formatter:439: basic_test_ (Metadata can have extra formatting with width 5)...ok - lager_default_formatter:449: basic_test_ (Metadata can have extra formatting with width 6)...ok - lager_default_formatter:459: basic_test_ (Uppercase Severity Formatting - DEBUG)...ok - lager_default_formatter:468: basic_test_ (Uppercase Severity Formatting - INFO)...ok - lager_default_formatter:477: basic_test_ (Uppercase Severity Formatting - NOTICE)...ok - lager_default_formatter:486: basic_test_ (Uppercase Severity Formatting - WARNING)...ok - lager_default_formatter:495: basic_test_ (Uppercase Severity Formatting - ERROR)...ok - lager_default_formatter:504: basic_test_ (Uppercase Severity Formatting - CRITICAL)...ok - lager_default_formatter:513: basic_test_ (Uppercase Severity Formatting - ALERT)...ok - lager_default_formatter:522: basic_test_ (Uppercase Severity Formatting - EMERGENCY)...ok - lager_default_formatter:534: basic_test_ (pterm presence test)...ok - lager_default_formatter:550: basic_test_ (pterm absence test)...ok - lager_default_formatter:566: basic_test_ (node formatting basic)...ok - [done in 0.087 s] -module 'lager_config' -module 'special_process' -module 'crash_fsm' + [done in 0.211 s] module 'lager_metadata_whitelist_test' lager_metadata_whitelist_test: basic_test_ (Meta)...ok - lager_metadata_whitelist_test: basic_test_ (Meta1)...ok - lager_metadata_whitelist_test: basic_test_ (Meta2)...ok + lager_metadata_whitelist_test: basic_test_ (Meta1)...[0.001 s] ok + lager_metadata_whitelist_test: basic_test_ (Meta2)...[0.001 s] ok lager_metadata_whitelist_test: basic_test_ (Meta3)...ok - [done in 1.304 s] -module 'sync_error_logger' -module 'lager_test_function_transform' - lager_test_function_transform: not_running_test...ok - lager_test_function_transform: transform_function_test_ (observe that there is nothing up my sleeve)...ok - lager_test_function_transform: transform_function_test_ (logging works)...ok - lager_test_function_transform: transform_function_test_ (Testing calling a function returns the same content on emit)...ok - lager_test_function_transform: transform_function_test_ (Testing calling a function which returns content which can change on emit)...ok - lager_test_function_transform: transform_function_test_ (Testing a undefined function returns undefined on emit)...ok - lager_test_function_transform: transform_function_test_ (Testing calling a function returns the same content on log)...ok - lager_test_function_transform: transform_function_test_ (Testing calling a dynamic function on log which returns the same value)...ok - lager_test_function_transform: transform_function_test_ (Testing differences in results for on_log vs on emit from dynamic function)...ok - lager_test_function_transform: transform_function_test_ (Testing a function provided via metadata)...ok - [done in 2.922 s] -module 'lager_rotate' - lager_rotate: rotate_test_ (Rotate single file)...[0.023 s] ok - lager_rotate: rotate_test_ (Rotate sink)...[0.023 s] ok - lager_rotate: rotate_test_ (Rotate all)...[0.024 s] ok - [done in 3.322 s] -module 'lager_stdlib' -module 'lager_common_test_backend' -module 'lager_handler_watcher' - lager_handler_watcher: reinstall_on_initial_failure_test_...[6.081 s] ok - lager_handler_watcher: reinstall_on_runtime_failure_test_...[6.075 s] ok - lager_handler_watcher: reinstall_handlers_after_killer_hwm_test_...[6.150 s] ok - [done in 18.317 s] + [done in 1.369 s] +module 'lager_app' + lager_app:326: application_config_mangling_test_ (Explode the file backend handlers)...ok + lager_app:339: application_config_mangling_test_ (Explode the short form of backend file handlers)...ok + lager_app:352: application_config_mangling_test_ (Explode with formatter info)...ok + lager_app:363: application_config_mangling_test_ (Explode short form with short formatter info)...ok + lager_app:374: application_config_mangling_test_ (New form needs no expansion)...ok + lager_app:408: check_handler_config_test_ (lager_file_backend_good)...ok +=ERROR REPORT==== 23-Jul-2021::20:12:15.018930 === +Cannot have same file ("same_file.log") in multiple file backends + + lager_app:411: check_handler_config_test_ (lager_file_backend_bad)...ok + lager_app:414: check_handler_config_test_ (Invalid config dies)...ok + lager_app:417: check_handler_config_test_ (Invalid config dies)...[0.011 s] ok + lager_app:420: check_handler_config_test_ (Old Lager config works)...ok + lager_app:423: check_handler_config_test_ (New Config missing its list should fail)...ok + lager_app_tests: get_env_test (module 'lager_app_tests')...ok + [done in 0.062 s] +compress_pr_record_test: nested_record_test (module 'compress_pr_record_test')...ok module 'lager_manager_killer_test' - lager_manager_killer_test: overload_test_...=ERROR REPORT==== 22-Jul-2021::18:03:11.955512 === + lager_manager_killer_test: overload_test_...=ERROR REPORT==== 23-Jul-2021::20:12:15.088817 === calling logger:remove_handler(default) failed: error {badmatch, {error, {not_found,default}}} -=ERROR REPORT==== 22-Jul-2021::18:03:12.957024 === +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7957.0>,unlink,<0.7955.0>} +=ERROR REPORT==== 23-Jul-2021::20:12:16.089892 === ** gen_event handler lager_manager_killer crashed. ** Was installed in lager_event ** Last event was: {log,{lager_msg,[], - [{pid,<0.9228.0>}, + [{pid,<0.7961.0>}, {line,127}, {file,"lager_handler_watcher.erl"}, {module,lager_handler_watcher}], debug, - {["2021",45,"07",45,"22"], - ["18",58,"03",58,"11",46,"956"]}, - {1627,20191,956023}, + {["2021",45,"07",45,"23"], + ["20",58,"12",58,"15",46,"089"]}, + {1627,20735,89494}, [76,97,103,101,114,32,105,110,115,116,97, 108,108,101,100,32,104,97,110,100,108,101, 114,32,"error_logger_lager_h",32,105,110, @@ -1954,45 +1930,40 @@ ** When handler state == {state,10,1000} ** Reason == {kill_me,[10,1000]} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9224.0>,unlink,<0.9222.0>} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9222.0>,getting_unlinked,<0.9224.0>} -=ERROR REPORT==== 22-Jul-2021::18:03:12.958185 === -Killing sink lager_event, current message_queue_len:13 +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7955.0>,getting_unlinked,<0.7957.0>} +lager_manager_killer_test.erl:101:<0.718.0>: Manager <0.7955.0> killed +=ERROR REPORT==== 23-Jul-2021::20:12:16.091435 === +Killing sink lager_event, current message_queue_len:12 -lager_manager_killer_test.erl:101:<0.718.0>: Manager <0.9222.0> killed -=INFO REPORT==== 22-Jul-2021::18:03:13.973832 === +=INFO REPORT==== 23-Jul-2021::20:12:17.109574 === application: lager exited: stopped type: temporary -[2.023 s] ok - lager_manager_killer_test: overload_alternate_sink_test_...lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9222.0>,unregister,lager_event} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9225.0>,getting_unlinked,<0.9222.0>} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9221.0>,getting_unlinked,<0.9222.0>} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9224.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9223.0>,getting_unlinked,<0.9224.0>} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9221.0>,spawn,<0.9237.0>, +[2.029 s] ok + lager_manager_killer_test: overload_alternate_sink_test_...lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7955.0>,unregister,lager_event} +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7954.0>,getting_unlinked,<0.7955.0>} +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7954.0>,spawn,<0.7967.0>, {proc_lib,init_p, [lager_sup, - [<0.9220.0>], + [<0.7953.0>], gen,init_it, - [gen_event,<0.9221.0>,<0.9221.0>, + [gen_event,<0.7954.0>,<0.7954.0>, {local,lager_event}, 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9221.0>,link,<0.9237.0>} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9237.0>,spawned,<0.9221.0>, +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7954.0>,link,<0.7967.0>} +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7967.0>,spawned,<0.7954.0>, {proc_lib,init_p, [lager_sup, - [<0.9220.0>], + [<0.7953.0>], gen,init_it, - [gen_event,<0.9221.0>,<0.9221.0>, + [gen_event,<0.7954.0>,<0.7954.0>, {local,lager_event}, 'no callback module',[],[]]]}} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9237.0>,getting_linked,<0.9221.0>} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9225.0>,exit,normal} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9223.0>,getting_unlinked,<0.9225.0>} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9252.0>,unlink,<0.9251.0>} -=ERROR REPORT==== 22-Jul-2021::18:03:15.051018 === +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7967.0>,getting_linked,<0.7954.0>} +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7958.0>,getting_unlinked,<0.7955.0>} +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7957.0>,exit,normal} +=ERROR REPORT==== 23-Jul-2021::20:12:18.197886 === ** gen_event handler lager_manager_killer crashed. ** Was installed in '__lager_test_sink_lager_event' ** Last event was: {log,{lager_msg,[], @@ -2002,35 +1973,132 @@ {pid,"<0.718.0>"}, {node,nonode@nohost}], info, - {["2021",45,"07",45,"22"], - ["18",58,"03",58,"14",46,"049"]}, - {1627,20194,49394}, + {["2021",45,"07",45,"23"], + ["20",58,"12",58,"17",46,"197"]}, + {1627,20737,197314}, ["1",39,116,104,32,109,101,115,115,97,103, 101]}} ** When handler state == {state,10,1000} ** Reason == {kill_me,[10,1000]} -lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.9251.0>,getting_unlinked,<0.9252.0>} -lager_manager_killer_test.erl:101:<0.718.0>: Manager <0.9251.0> killed -=ERROR REPORT==== 22-Jul-2021::18:03:15.052042 === +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7982.0>,unlink,<0.7981.0>} +lager_manager_killer_test.erl:103:<0.718.0>: OTHER MSG: {trace,<0.7981.0>,getting_unlinked,<0.7982.0>} +lager_manager_killer_test.erl:101:<0.718.0>: Manager <0.7981.0> killed +=ERROR REPORT==== 23-Jul-2021::20:12:18.199098 === Killing sink '__lager_test_sink_lager_event', current message_queue_len:10 -=INFO REPORT==== 22-Jul-2021::18:03:16.065918 === +=INFO REPORT==== 23-Jul-2021::20:12:19.215738 === application: lager exited: stopped type: temporary -[2.089 s] ok - [done in 4.118 s] -module 'lager_rotator_behaviour' -module 'pr_stacktrace_test' - pr_stacktrace_test: pr_stacktrace_throw_test...[0.001 s] ok - pr_stacktrace_test: pr_stacktrace_bad_arg_test...ok - pr_stacktrace_test: pr_stacktrace_bad_arity_test...ok - pr_stacktrace_test: pr_stacktrace_no_reverse_test...ok - [done in 0.013 s] -module 'trunc_io_eqc' -module 'lager_manager_killer' +[2.103 s] ok + [done in 4.139 s] +module 'lager_console_backend' + lager_console_backend:242: console_config_validation_test_...ok + lager_console_backend:243: console_config_validation_test_...ok + lager_console_backend:244: console_config_validation_test_...ok + lager_console_backend:245: console_config_validation_test_...ok + lager_console_backend: console_log_test_ (regular console logging)...[0.002 s] ok + lager_console_backend: console_log_test_ (verbose console logging)...[0.002 s] ok + lager_console_backend: console_log_test_ (custom format console logging)...[0.003 s] ok + lager_console_backend: console_log_test_ (tracing should work)...[0.581 s] ok + lager_console_backend: console_log_test_ (tracing doesn't duplicate messages)...[1.085 s] ok + lager_console_backend: console_log_test_ (blacklisting a loglevel works)...[0.502 s] ok + lager_console_backend: console_log_test_ (whitelisting a loglevel works)...[0.502 s] ok + lager_console_backend: console_log_test_ (console backend with custom group leader)...[0.603 s] ok + lager_console_backend: console_log_test_ (console backend with custom group leader using a trace and an ID)...[1.187 s] ok + lager_console_backend: set_loglevel_test_ (Get/set loglevel test)...[0.003 s] ok + lager_console_backend: set_loglevel_test_ (Get/set invalid loglevel test)...ok + [done in 5.564 s] +module 'lager_format' +module 'pr_composite_test' + pr_composite_test: nested_record_test...ok + pr_composite_test: list_field_test...ok + pr_composite_test: list_of_records_test...ok + pr_composite_test: improper_list_test...ok + [done in 0.012 s] +zzzz_gh280_crash: gh280_crash_test (module 'zzzz_gh280_crash')...ok +module 'lager_default_formatter' + lager_default_formatter:277: basic_test_ (Default formatting test)...ok + lager_default_formatter:286: basic_test_ (Basic Formatting)...ok + lager_default_formatter:295: basic_test_ (Default equivalent formatting test)...ok + lager_default_formatter:305: basic_test_ (Non existent metadata can default to string)...ok + lager_default_formatter:315: basic_test_ (Non existent metadata can default to other metadata)...ok + lager_default_formatter:325: basic_test_ (Non existent metadata can default to a string2)...ok + lager_default_formatter:335: basic_test_ (Metadata can have extra formatting)...ok + lager_default_formatter:345: basic_test_ (Metadata can have extra formatting1)...ok + lager_default_formatter:355: basic_test_ (Metadata can have extra formatting2)...ok + lager_default_formatter:365: basic_test_ (Metadata can have extra formatting3)...ok + lager_default_formatter:375: basic_test_ (Metadata can be printed in its enterity)...ok + lager_default_formatter:385: basic_test_ (Metadata can be printed in its enterity with custom seperators)...ok + lager_default_formatter:395: basic_test_ (Metadata can have extra formatting with width 1)...ok + lager_default_formatter:409: basic_test_ (Metadata can have extra formatting with width 2)...ok + lager_default_formatter:419: basic_test_ (Metadata can have extra formatting with width 3)...ok + lager_default_formatter:429: basic_test_ (Metadata can have extra formatting with width 4)...ok + lager_default_formatter:439: basic_test_ (Metadata can have extra formatting with width 5)...ok + lager_default_formatter:449: basic_test_ (Metadata can have extra formatting with width 6)...ok + lager_default_formatter:459: basic_test_ (Uppercase Severity Formatting - DEBUG)...ok + lager_default_formatter:468: basic_test_ (Uppercase Severity Formatting - INFO)...ok + lager_default_formatter:477: basic_test_ (Uppercase Severity Formatting - NOTICE)...ok + lager_default_formatter:486: basic_test_ (Uppercase Severity Formatting - WARNING)...ok + lager_default_formatter:495: basic_test_ (Uppercase Severity Formatting - ERROR)...ok + lager_default_formatter:504: basic_test_ (Uppercase Severity Formatting - CRITICAL)...ok + lager_default_formatter:513: basic_test_ (Uppercase Severity Formatting - ALERT)...ok + lager_default_formatter:522: basic_test_ (Uppercase Severity Formatting - EMERGENCY)...ok + lager_default_formatter:534: basic_test_ (pterm presence test)...ok + lager_default_formatter:550: basic_test_ (pterm absence test)...ok + lager_default_formatter:566: basic_test_ (node formatting basic)...ok + [done in 0.088 s] +module 'error_logger_lager_h' + error_logger_lager_h: no_silent_hwm_drops_test_...[3.073 s] ok + error_logger_lager_h: shaper_does_not_forward_sup_progress_messages_to_info_level_backend_test_...[0.147 s] ok + error_logger_lager_h: supressed_messages_are_not_counted_for_hwm_test_...[0.148 s] ok + [done in 3.377 s] +module 'crash' +module 'lager_sup' +module 'lager_file_backend' + lager_file_backend: get_loglevel_test...ok + lager_file_backend: rotation_test_ (External rotation should work)...[0.024 s] ok + lager_file_backend: rotation_test_ (Internal rotation and delayed write)...[1.013 s] ok + lager_file_backend: filesystem_test_ (under normal circumstances, file should be opened)...[0.015 s] ok + lager_file_backend: filesystem_test_ (don't choke on unicode)...[0.009 s] ok + lager_file_backend: filesystem_test_ (don't choke on latin-1)...[0.011 s] ok + lager_file_backend: filesystem_test_ (file can't be opened on startup triggers an error message)...[0.005 s] ok + lager_file_backend: filesystem_test_ (file that becomes unavailable at runtime should trigger an error message)...[0.018 s] ok + lager_file_backend: filesystem_test_ (unavailable files that are fixed at runtime should start having log messages written)...[0.014 s] ok + lager_file_backend: filesystem_test_ (external logfile rotation/deletion should be handled)...[0.027 s] ok + lager_file_backend: filesystem_test_ (internal size rotation should work)...[0.064 s] ok + lager_file_backend: filesystem_test_ (internal time rotation should work)...[0.053 s] ok + lager_file_backend: filesystem_test_ (rotation call should work)...[0.049 s] ok + lager_file_backend: filesystem_test_ (sync_on option should work)...[0.017 s] ok + lager_file_backend: filesystem_test_ (sync_on none option should work (also tests sync_interval))...[2.008 s] ok + lager_file_backend: filesystem_test_ (sync_size option should work)...[0.018 s] ok + lager_file_backend: filesystem_test_ (runtime level changes)...[0.017 s] ok + lager_file_backend: filesystem_test_ (invalid runtime level changes)...[0.005 s] ok + lager_file_backend: filesystem_test_ (tracing should work)...[1.013 s] ok + lager_file_backend: filesystem_test_ (tracing should not duplicate messages)...[0.539 s] ok + lager_file_backend: filesystem_test_ (tracing to a dedicated file should work)...[0.100 s] ok + lager_file_backend: filesystem_test_ (tracing to a dedicated file should work even if root_log is set)...[0.100 s] ok + lager_file_backend: filesystem_test_ (tracing with options should work)...[0.117 s] ok + lager_file_backend: filesystem_test_ (no silent hwm drops)...[2.019 s] ok + lager_file_backend: trace_files_test_ (a trace using file backend set up in configuration should work)...[1.055 s] ok + lager_file_backend: formatting_test_ (Should have two log files, the second prefixed with 2>)...[0.019 s] ok + lager_file_backend:1137: config_validation_test_ (missing file)...ok + lager_file_backend:1141: config_validation_test_ (bad level)...ok + lager_file_backend:1145: config_validation_test_ (bad size)...ok + lager_file_backend:1149: config_validation_test_ (bad count)...ok + lager_file_backend:1153: config_validation_test_ (bad high water mark)...ok + lager_file_backend:1157: config_validation_test_ (bad date)...ok + lager_file_backend:1161: config_validation_test_ (blank date is ok)...ok + lager_file_backend:1165: config_validation_test_ (bad sync_interval)...ok + lager_file_backend:1169: config_validation_test_ (bad sync_size)...ok + lager_file_backend:1173: config_validation_test_ (bad check_interval)...ok + lager_file_backend:1177: config_validation_test_ (bad sync_on level)...ok + lager_file_backend:1181: config_validation_test_ (bad formatter module)...ok + lager_file_backend:1185: config_validation_test_ (bad formatter config)...ok + lager_file_backend:1189: config_validation_test_ (unknown option)...ok + [done in 11.384 s] ======================================================= Failed: 9. Skipped: 0. Passed: 354. Cover analysis: /build/erlang-lager-3.8.1/.eunit/index.html @@ -2161,12 +2229,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/9701/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/9701/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/31726 and its subdirectories -I: Current time: Thu Jul 22 18:03:34 -12 2021 -I: pbuilder-time-stamp: 1627020214 +I: removing directory /srv/workspace/pbuilder/9701 and its subdirectories +I: Current time: Fri Jul 23 20:13:03 +14 2021 +I: pbuilder-time-stamp: 1627020783