Diff of the two buildlogs: -- --- b1/build.log 2022-09-19 14:49:47.852218953 +0000 +++ b2/build.log 2022-09-19 14:53:35.903566134 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Sep 19 02:47:36 -12 2022 -I: pbuilder-time-stamp: 1663598856 +I: Current time: Mon Oct 23 11:12:51 +14 2023 +I: pbuilder-time-stamp: 1698009171 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bullseye-reproducible-base.tgz] I: copying local configuration @@ -20,8 +20,8 @@ I: copying [./node-log4js_6.3.0+~cs8.3.10-1+deb11u1.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.Vp55TEIU/trustedkeys.kbx': General error -gpgv: Signature made Sun Aug 7 08:58:53 2022 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.huK5ZQzP/trustedkeys.kbx': General error +gpgv: Signature made Mon Aug 8 10:58:53 2022 +14 gpgv: using RSA key 00DFE58B8B555779D1005EC9F6D74CA7C999EEE9 gpgv: Can't check signature: No public key dpkg-source: warning: failed to verify signature on ./node-log4js_6.3.0+~cs8.3.10-1+deb11u1.dsc @@ -36,135 +36,170 @@ dpkg-source: info: applying CVE-2022-21704.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/3991443/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/4036813/tmp/hooks/D01_modify_environment starting +debug: Running on ionos15-amd64. +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' +lrwxrwxrwx 1 root root 4 Oct 23 11:13 /bin/sh -> 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/4036813/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/4036813/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='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=15' - DISTRIBUTION='' - HOME='/root' - HOST_ARCH='amd64' + 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]="x86_64-pc-linux-gnu") + 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=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=16' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='9585f61d00ed461c9fb5618603173bd6' - 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='3991443' - PS1='# ' - PS2='> ' + INVOCATION_ID=5d9a3e986c874085abf8059ac36dfca1 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=4036813 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.EQJafq7gNl/pbuilderrc_yFP1 --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.EQJafq7gNl/b1 --logfile b1/build.log node-log4js_6.3.0+~cs8.3.10-1+deb11u1.dsc' - SUDO_GID='110' - SUDO_UID='105' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://78.137.99.97:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.EQJafq7gNl/pbuilderrc_GSMK --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.EQJafq7gNl/b2 --logfile b2/build.log node-log4js_6.3.0+~cs8.3.10-1+deb11u1.dsc' + SUDO_GID=111 + SUDO_UID=106 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://85.184.249.68:3128 I: uname -a - Linux ionos1-amd64 5.10.0-18-amd64 #1 SMP Debian 5.10.140-1 (2022-09-02) x86_64 GNU/Linux + Linux i-capture-the-hostname 5.18.0-0.deb11.4-amd64 #1 SMP PREEMPT_DYNAMIC Debian 5.18.16-1~bpo11+1 (2022-08-12) x86_64 GNU/Linux I: ls -l /bin total 5476 - -rwxr-xr-x 1 root root 1234376 Mar 27 06:40 bash - -rwxr-xr-x 3 root root 38984 Jul 20 2020 bunzip2 - -rwxr-xr-x 3 root root 38984 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 38984 Jul 20 2020 bzip2 - -rwxr-xr-x 1 root root 18424 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 43936 Sep 23 2020 cat - -rwxr-xr-x 1 root root 72672 Sep 23 2020 chgrp - -rwxr-xr-x 1 root root 64448 Sep 23 2020 chmod - -rwxr-xr-x 1 root root 72672 Sep 23 2020 chown - -rwxr-xr-x 1 root root 151168 Sep 23 2020 cp - -rwxr-xr-x 1 root root 125560 Dec 10 2020 dash - -rwxr-xr-x 1 root root 113664 Sep 23 2020 date - -rwxr-xr-x 1 root root 80968 Sep 23 2020 dd - -rwxr-xr-x 1 root root 93936 Sep 23 2020 df - -rwxr-xr-x 1 root root 147176 Sep 23 2020 dir - -rwxr-xr-x 1 root root 84440 Jan 20 2022 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 39712 Sep 23 2020 echo - -rwxr-xr-x 1 root root 28 Nov 9 2020 egrep - -rwxr-xr-x 1 root root 39680 Sep 23 2020 false - -rwxr-xr-x 1 root root 28 Nov 9 2020 fgrep - -rwxr-xr-x 1 root root 69032 Jan 20 2022 findmnt - -rwsr-xr-x 1 root root 34896 Feb 26 2021 fusermount - -rwxr-xr-x 1 root root 203072 Nov 9 2020 grep - -rwxr-xr-x 2 root root 2346 Apr 9 17:50 gunzip - -rwxr-xr-x 1 root root 6447 Apr 9 17:50 gzexe - -rwxr-xr-x 1 root root 98048 Apr 9 17:50 gzip - -rwxr-xr-x 1 root root 22600 Nov 6 2019 hostname - -rwxr-xr-x 1 root root 72840 Sep 23 2020 ln - -rwxr-xr-x 1 root root 56952 Feb 7 2020 login - -rwxr-xr-x 1 root root 147176 Sep 23 2020 ls - -rwxr-xr-x 1 root root 149736 Jan 20 2022 lsblk - -rwxr-xr-x 1 root root 85184 Sep 23 2020 mkdir - -rwxr-xr-x 1 root root 76896 Sep 23 2020 mknod - -rwxr-xr-x 1 root root 48064 Sep 23 2020 mktemp - -rwxr-xr-x 1 root root 59632 Jan 20 2022 more - -rwsr-xr-x 1 root root 55528 Jan 20 2022 mount - -rwxr-xr-x 1 root root 18664 Jan 20 2022 mountpoint - -rwxr-xr-x 1 root root 147080 Sep 23 2020 mv - lrwxrwxrwx 1 root root 8 Nov 6 2019 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Dec 16 2021 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 43872 Sep 23 2020 pwd - lrwxrwxrwx 1 root root 4 Mar 27 06:40 rbash -> bash - -rwxr-xr-x 1 root root 52032 Sep 23 2020 readlink - -rwxr-xr-x 1 root root 72704 Sep 23 2020 rm - -rwxr-xr-x 1 root root 52032 Sep 23 2020 rmdir - -rwxr-xr-x 1 root root 27472 Sep 27 2020 run-parts - -rwxr-xr-x 1 root root 122224 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Sep 6 21:25 sh -> dash - -rwxr-xr-x 1 root root 43808 Sep 23 2020 sleep - -rwxr-xr-x 1 root root 84928 Sep 23 2020 stty - -rwsr-xr-x 1 root root 71912 Jan 20 2022 su - -rwxr-xr-x 1 root root 39744 Sep 23 2020 sync - -rwxr-xr-x 1 root root 531928 Feb 16 2021 tar - -rwxr-xr-x 1 root root 14456 Sep 27 2020 tempfile - -rwxr-xr-x 1 root root 101408 Sep 23 2020 touch - -rwxr-xr-x 1 root root 39680 Sep 23 2020 true - -rwxr-xr-x 1 root root 14328 Feb 26 2021 ulockmgr_server - -rwsr-xr-x 1 root root 35040 Jan 20 2022 umount - -rwxr-xr-x 1 root root 39744 Sep 23 2020 uname - -rwxr-xr-x 2 root root 2346 Apr 9 17:50 uncompress - -rwxr-xr-x 1 root root 147176 Sep 23 2020 vdir - -rwxr-xr-x 1 root root 63744 Jan 20 2022 wdctl - lrwxrwxrwx 1 root root 8 Nov 6 2019 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Apr 9 17:50 zcat - -rwxr-xr-x 1 root root 1678 Apr 9 17:50 zcmp - -rwxr-xr-x 1 root root 5898 Apr 9 17:50 zdiff - -rwxr-xr-x 1 root root 29 Apr 9 17:50 zegrep - -rwxr-xr-x 1 root root 29 Apr 9 17:50 zfgrep - -rwxr-xr-x 1 root root 2081 Apr 9 17:50 zforce - -rwxr-xr-x 1 root root 8049 Apr 9 17:50 zgrep - -rwxr-xr-x 1 root root 2206 Apr 9 17:50 zless - -rwxr-xr-x 1 root root 1842 Apr 9 17:50 zmore - -rwxr-xr-x 1 root root 4577 Apr 9 17:50 znew -I: user script /srv/workspace/pbuilder/3991443/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1234376 Mar 28 2022 bash + -rwxr-xr-x 3 root root 38984 Jul 21 2020 bunzip2 + -rwxr-xr-x 3 root root 38984 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 38984 Jul 21 2020 bzip2 + -rwxr-xr-x 1 root root 18424 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 43936 Sep 24 2020 cat + -rwxr-xr-x 1 root root 72672 Sep 24 2020 chgrp + -rwxr-xr-x 1 root root 64448 Sep 24 2020 chmod + -rwxr-xr-x 1 root root 72672 Sep 24 2020 chown + -rwxr-xr-x 1 root root 151168 Sep 24 2020 cp + -rwxr-xr-x 1 root root 125560 Dec 11 2020 dash + -rwxr-xr-x 1 root root 113664 Sep 24 2020 date + -rwxr-xr-x 1 root root 80968 Sep 24 2020 dd + -rwxr-xr-x 1 root root 93936 Sep 24 2020 df + -rwxr-xr-x 1 root root 147176 Sep 24 2020 dir + -rwxr-xr-x 1 root root 84440 Jan 21 2022 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 39712 Sep 24 2020 echo + -rwxr-xr-x 1 root root 28 Nov 10 2020 egrep + -rwxr-xr-x 1 root root 39680 Sep 24 2020 false + -rwxr-xr-x 1 root root 28 Nov 10 2020 fgrep + -rwxr-xr-x 1 root root 69032 Jan 21 2022 findmnt + -rwsr-xr-x 1 root root 34896 Feb 27 2021 fusermount + -rwxr-xr-x 1 root root 203072 Nov 10 2020 grep + -rwxr-xr-x 2 root root 2346 Apr 10 2022 gunzip + -rwxr-xr-x 1 root root 6447 Apr 10 2022 gzexe + -rwxr-xr-x 1 root root 98048 Apr 10 2022 gzip + -rwxr-xr-x 1 root root 22600 Nov 8 2019 hostname + -rwxr-xr-x 1 root root 72840 Sep 24 2020 ln + -rwxr-xr-x 1 root root 56952 Feb 8 2020 login + -rwxr-xr-x 1 root root 147176 Sep 24 2020 ls + -rwxr-xr-x 1 root root 149736 Jan 21 2022 lsblk + -rwxr-xr-x 1 root root 85184 Sep 24 2020 mkdir + -rwxr-xr-x 1 root root 76896 Sep 24 2020 mknod + -rwxr-xr-x 1 root root 48064 Sep 24 2020 mktemp + -rwxr-xr-x 1 root root 59632 Jan 21 2022 more + -rwsr-xr-x 1 root root 55528 Jan 21 2022 mount + -rwxr-xr-x 1 root root 18664 Jan 21 2022 mountpoint + -rwxr-xr-x 1 root root 147080 Sep 24 2020 mv + lrwxrwxrwx 1 root root 8 Nov 8 2019 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Dec 17 2021 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 43872 Sep 24 2020 pwd + lrwxrwxrwx 1 root root 4 Mar 28 2022 rbash -> bash + -rwxr-xr-x 1 root root 52032 Sep 24 2020 readlink + -rwxr-xr-x 1 root root 72704 Sep 24 2020 rm + -rwxr-xr-x 1 root root 52032 Sep 24 2020 rmdir + -rwxr-xr-x 1 root root 27472 Sep 28 2020 run-parts + -rwxr-xr-x 1 root root 122224 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Oct 23 11:13 sh -> bash + lrwxrwxrwx 1 root root 4 Oct 11 05:46 sh.distrib -> dash + -rwxr-xr-x 1 root root 43808 Sep 24 2020 sleep + -rwxr-xr-x 1 root root 84928 Sep 24 2020 stty + -rwsr-xr-x 1 root root 71912 Jan 21 2022 su + -rwxr-xr-x 1 root root 39744 Sep 24 2020 sync + -rwxr-xr-x 1 root root 531928 Feb 17 2021 tar + -rwxr-xr-x 1 root root 14456 Sep 28 2020 tempfile + -rwxr-xr-x 1 root root 101408 Sep 24 2020 touch + -rwxr-xr-x 1 root root 39680 Sep 24 2020 true + -rwxr-xr-x 1 root root 14328 Feb 27 2021 ulockmgr_server + -rwsr-xr-x 1 root root 35040 Jan 21 2022 umount + -rwxr-xr-x 1 root root 39744 Sep 24 2020 uname + -rwxr-xr-x 2 root root 2346 Apr 10 2022 uncompress + -rwxr-xr-x 1 root root 147176 Sep 24 2020 vdir + -rwxr-xr-x 1 root root 63744 Jan 21 2022 wdctl + lrwxrwxrwx 1 root root 8 Nov 8 2019 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Apr 10 2022 zcat + -rwxr-xr-x 1 root root 1678 Apr 10 2022 zcmp + -rwxr-xr-x 1 root root 5898 Apr 10 2022 zdiff + -rwxr-xr-x 1 root root 29 Apr 10 2022 zegrep + -rwxr-xr-x 1 root root 29 Apr 10 2022 zfgrep + -rwxr-xr-x 1 root root 2081 Apr 10 2022 zforce + -rwxr-xr-x 1 root root 8049 Apr 10 2022 zgrep + -rwxr-xr-x 1 root root 2206 Apr 10 2022 zless + -rwxr-xr-x 1 root root 1842 Apr 10 2022 zmore + -rwxr-xr-x 1 root root 4577 Apr 10 2022 znew +I: user script /srv/workspace/pbuilder/4036813/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -338,7 +373,7 @@ Get: 124 http://deb.debian.org/debian bullseye/main amd64 node-write-file-atomic all 3.0.3+~3.0.1-1 [8048 B] Get: 125 http://deb.debian.org/debian bullseye/main amd64 node-tap all 12.0.1+ds-3 [43.4 kB] Get: 126 http://deb.debian.org/debian bullseye/main amd64 pkg-js-tools all 0.9.65 [38.0 kB] -Fetched 31.7 MB in 2s (20.8 MB/s) +Fetched 31.7 MB in 1s (26.9 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package bsdextrautils. (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 ... 19704 files and directories currently installed.) @@ -866,7 +901,8 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/node-log4js-6.3.0+~cs8.3.10/ && 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 > ../node-log4js_6.3.0+~cs8.3.10-1+deb11u1_source.changes +hostname: Name or service not known +I: Running cd /build/node-log4js-6.3.0+~cs8.3.10/ && 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 > ../node-log4js_6.3.0+~cs8.3.10-1+deb11u1_source.changes dpkg-buildpackage: info: source package node-log4js dpkg-buildpackage: info: source version 6.3.0+~cs8.3.10-1+deb11u1 dpkg-buildpackage: info: source distribution bullseye @@ -905,10 +941,10 @@ ln -s ../. node_modules/log4js /bin/sh -ex debian/tests/pkg-js/test + set -e -+ grep -v no-cluster-test -+ xargs grep -L sandboxed -+ grep -v multi -+ ls test/tap/LoggingEvent-test.js test/tap/appender-dependencies-test.js test/tap/categoryFilter-test.js test/tap/cluster-test.js test/tap/configuration-inheritance-test.js test/tap/configuration-test.js test/tap/configuration-validation-test.js test/tap/connect-context-test.js test/tap/connect-logger-test.js test/tap/connect-nolog-test.js test/tap/consoleAppender-test.js test/tap/dateFileAppender-test.js test/tap/default-settings-test.js test/tap/disable-cluster-test.js test/tap/dummy-appender.js test/tap/file-sighup-test.js test/tap/fileAppender-test.js test/tap/fileSyncAppender-test.js test/tap/layouts-test.js test/tap/levels-before-configure-test.js test/tap/levels-test.js test/tap/logLevelFilter-test.js test/tap/logger-test.js test/tap/logging-test.js test/tap/multi-file-appender-test.js test/tap/multiprocess-shutdown-test.js test/tap/multiprocess-test.js test/tap/newLevel-test.js test/tap/no-cluster-test.js test/tap/noLogFilter-test.js test/tap/passenger-test.js test/tap/pause-test.js test/tap/pm2-support-test.js test/tap/server-test.js test/tap/setLevel-asymmetry-test.js test/tap/stacktraces-test.js test/tap/stderrAppender-test.js test/tap/stdoutAppender-test.js test/tap/subcategories-test.js test/tap/tcp-appender-test.js +++ xargs grep -L sandboxed +++ ls test/tap/LoggingEvent-test.js test/tap/appender-dependencies-test.js test/tap/categoryFilter-test.js test/tap/cluster-test.js test/tap/configuration-inheritance-test.js test/tap/configuration-test.js test/tap/configuration-validation-test.js test/tap/connect-context-test.js test/tap/connect-logger-test.js test/tap/connect-nolog-test.js test/tap/consoleAppender-test.js test/tap/dateFileAppender-test.js test/tap/default-settings-test.js test/tap/disable-cluster-test.js test/tap/dummy-appender.js test/tap/file-sighup-test.js test/tap/fileAppender-test.js test/tap/fileSyncAppender-test.js test/tap/layouts-test.js test/tap/levels-before-configure-test.js test/tap/levels-test.js test/tap/logLevelFilter-test.js test/tap/logger-test.js test/tap/logging-test.js test/tap/multi-file-appender-test.js test/tap/multiprocess-shutdown-test.js test/tap/multiprocess-test.js test/tap/newLevel-test.js test/tap/no-cluster-test.js test/tap/noLogFilter-test.js test/tap/passenger-test.js test/tap/pause-test.js test/tap/pm2-support-test.js test/tap/server-test.js test/tap/setLevel-asymmetry-test.js test/tap/stacktraces-test.js test/tap/stderrAppender-test.js test/tap/stdoutAppender-test.js test/tap/subcategories-test.js test/tap/tcp-appender-test.js +++ grep -v no-cluster-test +++ grep -v multi + tap test/tap/LoggingEvent-test.js test/tap/appender-dependencies-test.js test/tap/categoryFilter-test.js test/tap/cluster-test.js test/tap/configuration-inheritance-test.js test/tap/connect-context-test.js test/tap/connect-logger-test.js test/tap/connect-nolog-test.js test/tap/disable-cluster-test.js test/tap/dummy-appender.js test/tap/fileSyncAppender-test.js test/tap/layouts-test.js test/tap/levels-before-configure-test.js test/tap/levels-test.js test/tap/logLevelFilter-test.js test/tap/newLevel-test.js test/tap/noLogFilter-test.js test/tap/pause-test.js test/tap/pm2-support-test.js test/tap/server-test.js test/tap/setLevel-asymmetry-test.js test/tap/stacktraces-test.js test/tap/subcategories-test.js test/tap/tcp-appender-test.js TAP version 13 # Subtest: test/tap/LoggingEvent-test.js @@ -921,7 +957,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should serialise to flatted # time=13.299ms + ok 1 - should serialise to flatted # time=41.724ms # Subtest: should deserialise from flatted ok 1 - type is LoggingEvent @@ -932,7 +968,7 @@ ok 6 - should be equal ok 7 - should be equal 1..7 - ok 2 - should deserialise from flatted # time=8.098ms + ok 2 - should deserialise from flatted # time=33.029ms # Subtest: Should correct construct with/without location info ok 1 - should be equal @@ -944,14 +980,14 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 3 - Should correct construct with/without location info # time=5.645ms + ok 3 - Should correct construct with/without location info # time=18.03ms 1..3 - ok 1 - LoggingEvent # time=55.175ms + ok 1 - LoggingEvent # time=211.938ms 1..1 - # time=68.947ms -ok 1 - test/tap/LoggingEvent-test.js # time=773.743ms + # time=263.626ms +ok 1 - test/tap/LoggingEvent-test.js # time=2347.049ms # Subtest: test/tap/appender-dependencies-test.js # Subtest: log4js appender dependencies @@ -959,47 +995,47 @@ # Subtest: should resolve if defined in dependency order ok 1 - this should not trigger an error 1..1 - ok 1 - should resolve if defined in dependency order # time=28.622ms + ok 1 - should resolve if defined in dependency order # time=35.537ms # Subtest: should process log events normally ok 1 - should be equal ok 2 - should be equal 1..2 - ok 2 - should process log events normally # time=12.158ms + ok 2 - should process log events normally # time=3.954ms 1..2 - ok 1 - in order # time=93.749ms + ok 1 - in order # time=102.176ms # Subtest: not in order # Subtest: should resolve if defined out of dependency order ok 1 - this should not trigger an error 1..1 - ok 1 - should resolve if defined out of dependency order # time=3.785ms + ok 1 - should resolve if defined out of dependency order # time=6.575ms # Subtest: should process log events normally ok 1 - should be equal ok 2 - should be equal 1..2 - ok 2 - should process log events normally # time=3.121ms + ok 2 - should process log events normally # time=5.451ms 1..2 - ok 2 - not in order # time=47.805ms + ok 2 - not in order # time=27.957ms # Subtest: with dependency loop # Subtest: should throw an error if if a dependency loop is found ok 1 - Dependency loop detected for appender filtered. 1..1 - ok 1 - should throw an error if if a dependency loop is found # time=3.473ms + ok 1 - should throw an error if if a dependency loop is found # time=4.413ms 1..1 - ok 3 - with dependency loop # time=39.506ms + ok 3 - with dependency loop # time=43.57ms 1..3 - ok 1 - log4js appender dependencies # time=456.358ms + ok 1 - log4js appender dependencies # time=355.508ms 1..1 - # time=487.333ms -ok 2 - test/tap/appender-dependencies-test.js # time=1379.795ms + # time=373.112ms +ok 2 - test/tap/appender-dependencies-test.js # time=1801.142ms # Subtest: test/tap/categoryFilter-test.js # Subtest: log4js categoryFilter @@ -1008,21 +1044,21 @@ ok 2 - should be equal ok 3 - should be equal 1..3 - ok 1 - appender should exclude categories # time=31.778ms + ok 1 - appender should exclude categories # time=46.616ms # Subtest: should not really need a category filter any more ok 1 - should be equal ok 2 - should be equal ok 3 - should be equal 1..3 - ok 2 - should not really need a category filter any more # time=13.2ms + ok 2 - should not really need a category filter any more # time=18.93ms 1..2 - ok 1 - log4js categoryFilter # time=93.633ms + ok 1 - log4js categoryFilter # time=127.8ms 1..1 - # time=122.465ms -ok 3 - test/tap/categoryFilter-test.js # time=1227.377ms + # time=165.728ms +ok 3 - test/tap/categoryFilter-test.js # time=1606.665ms # Subtest: test/tap/cluster-test.js # Subtest: cluster master @@ -1045,23 +1081,23 @@ ok 16 - should be equal ok 17 - should be equal 1..17 - ok 1 - events should be logged # time=62.935ms + ok 1 - events should be logged # time=45.975ms 1..1 - ok 1 - cluster master # time=103.428ms + ok 1 - cluster master # time=121.972ms # Subtest: cluster worker # Subtest: logger should get correct config ok 1 - should be equal 1..1 - ok 1 - logger should get correct config # time=2.118ms + ok 1 - logger should get correct config # time=7.308ms 1..1 - ok 2 - cluster worker # time=25.793ms + ok 2 - cluster worker # time=27.938ms 1..2 - # time=152.819ms -ok 4 - test/tap/cluster-test.js # time=1999.681ms + # time=198.881ms +ok 4 - test/tap/cluster-test.js # time=3658.61ms # Subtest: test/tap/configuration-inheritance-test.js # Subtest: log4js category inherit all appenders from direct parent @@ -1072,7 +1108,7 @@ ok 4 - inherited stdout2 ok 5 - child level overrides parent 1..5 - ok 1 - should inherit appenders from direct parent # time=35.382ms + ok 1 - should inherit appenders from direct parent # time=58.518ms # Subtest: multiple children should inherit config from shared parent ok 1 - inherited 1 appender @@ -1084,7 +1120,7 @@ ok 7 - kept stdout2 ok 8 - inherited parent level 1..8 - ok 2 - multiple children should inherit config from shared parent # time=11.674ms + ok 2 - multiple children should inherit config from shared parent # time=29.322ms # Subtest: should inherit appenders from multiple parents ok 1 - expect truthy value @@ -1096,7 +1132,7 @@ ok 7 - inherited stdout1 ok 8 - kept stdout2 1..8 - ok 3 - should inherit appenders from multiple parents # time=7.726ms + ok 3 - should inherit appenders from multiple parents # time=27.911ms # Subtest: should inherit appenders from deep parent with missing direct parent ok 1 - expect truthy value @@ -1106,14 +1142,14 @@ ok 5 - created with 1 inherited appender ok 6 - inherited stdout1 1..6 - ok 4 - should inherit appenders from deep parent with missing direct parent # time=6.676ms + ok 4 - should inherit appenders from deep parent with missing direct parent # time=18.058ms # Subtest: should deal gracefully with missing parent ok 1 - expect truthy value ok 2 - should be equal ok 3 - expect truthy value 1..3 - ok 5 - should deal gracefully with missing parent # time=5.405ms + ok 5 - should deal gracefully with missing parent # time=13.196ms # Subtest: should not get duplicate appenders if parent has the same one ok 1 - expect truthy value @@ -1121,14 +1157,14 @@ ok 3 - still have stdout1 ok 4 - inherited stdout2 1..4 - ok 6 - should not get duplicate appenders if parent has the same one # time=5.148ms + ok 6 - should not get duplicate appenders if parent has the same one # time=13.804ms # Subtest: inherit:falses should disable inheritance ok 1 - expect truthy value ok 2 - inherited no appender ok 3 - kept stdout2 1..3 - ok 7 - inherit:falses should disable inheritance # time=3.889ms + ok 7 - inherit:falses should disable inheritance # time=24.942ms # Subtest: inheritance should stop if direct parent has inherit off ok 1 - expect truthy value @@ -1138,20 +1174,20 @@ ok 5 - did not inherit new appenders ok 6 - kept stdout2 1..6 - ok 8 - inheritance should stop if direct parent has inherit off # time=7.635ms + ok 8 - inheritance should stop if direct parent has inherit off # time=18.238ms # Subtest: should inherit level when it is missing ok 1 - inherited level ok 2 - generate parent inherited level from base 1..2 - ok 9 - should inherit level when it is missing # time=4.647ms + ok 9 - should inherit level when it is missing # time=15.408ms 1..9 - ok 1 - log4js category inherit all appenders from direct parent # time=164.968ms + ok 1 - log4js category inherit all appenders from direct parent # time=479.36ms 1..1 - # time=181.722ms -ok 5 - test/tap/configuration-inheritance-test.js # time=1262.778ms + # time=525.171ms +ok 5 - test/tap/configuration-inheritance-test.js # time=2354.699ms # Subtest: test/tap/connect-context-test.js # Subtest: log4js connect logger @@ -1161,10 +1197,10 @@ ok 2 - should be equal ok 3 - type is MockResponse 1..3 - ok 1 - response should be included in context # time=30.681ms + ok 1 - response should be included in context # time=63.104ms 1..1 - ok 1 - with context config # time=57.211ms + ok 1 - with context config # time=117.632ms # Subtest: without context config # Subtest: response should not be included in context @@ -1172,17 +1208,17 @@ ok 2 - should be equal ok 3 - type is undefined 1..3 - ok 1 - response should not be included in context # time=13.277ms + ok 1 - response should not be included in context # time=25.341ms 1..1 - ok 2 - without context config # time=31.6ms + ok 2 - without context config # time=59.94ms 1..2 - ok 1 - log4js connect logger # time=128.755ms + ok 1 - log4js connect logger # time=253.729ms 1..1 - # time=159.429ms -ok 6 - test/tap/connect-context-test.js # time=862.787ms + # time=301.285ms +ok 6 - test/tap/connect-context-test.js # time=2134.903ms # Subtest: test/tap/connect-logger-test.js # Subtest: log4js connect logger @@ -1191,7 +1227,7 @@ # Subtest: should take a log4js logger and return a "connect logger" ok 1 - type is function 1..1 - ok 2 - should take a log4js logger and return a "connect logger" # time=14.732ms + ok 2 - should take a log4js logger and return a "connect logger" # time=18.287ms # Subtest: log events ok 1 - type is Array @@ -1202,13 +1238,13 @@ ok 6 - should match pattern provided ok 7 - should match pattern provided 1..7 - ok 3 - log events # time=27.647ms + ok 3 - log events # time=43.296ms # Subtest: log events with level below logging level ok 1 - type is Array ok 2 - should be equal 1..2 - ok 4 - log events with level below logging level # time=3.343ms + ok 4 - log events with level below logging level # time=15.611ms # Subtest: log events with non-default level and custom format ok 1 - type is Array @@ -1216,7 +1252,7 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 5 - log events with non-default level and custom format # time=4.453ms + ok 5 - log events with non-default level and custom format # time=20.714ms # Subtest: adding multiple loggers should only log once ok 1 - type is Array @@ -1224,96 +1260,96 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 6 - adding multiple loggers should only log once # time=12.752ms + ok 6 - adding multiple loggers should only log once # time=16.672ms 1..6 - ok 1 - getConnectLoggerModule # time=162.149ms + ok 1 - getConnectLoggerModule # time=278.037ms # Subtest: logger with options as string ok 1 - should be equal 1..1 - ok 2 - logger with options as string # time=2.976ms + ok 2 - logger with options as string # time=2.314ms # Subtest: auto log levels # Subtest: should use INFO for 2xx ok 1 - expect truthy value ok 2 - expect truthy value 1..2 - ok 1 - should use INFO for 2xx # time=3.464ms + ok 1 - should use INFO for 2xx # time=15.956ms # Subtest: should use WARN for 3xx ok 1 - expect truthy value 1..1 - ok 2 - should use WARN for 3xx # time=10.76ms + ok 2 - should use WARN for 3xx # time=10.93ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=2.301ms + ok 3 - should use ERROR for 4xx # time=2.125ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=2.208ms + ok 4 - should use ERROR for 5xx # time=2.307ms 1..4 - ok 3 - auto log levels # time=104.993ms + ok 3 - auto log levels # time=191.48ms # Subtest: logger with status code rules applied # Subtest: should use DEBUG for 2xx ok 1 - expect truthy value ok 2 - expect truthy value 1..2 - ok 1 - should use DEBUG for 2xx # time=11.515ms + ok 1 - should use DEBUG for 2xx # time=2.394ms # Subtest: should use WARN for 3xx, DEBUG for 304 ok 1 - expect truthy value ok 2 - expect truthy value 1..2 - ok 2 - should use WARN for 3xx, DEBUG for 304 # time=11.108ms + ok 2 - should use WARN for 3xx, DEBUG for 304 # time=11.413ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=2.157ms + ok 3 - should use ERROR for 4xx # time=2.241ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=2.364ms + ok 4 - should use ERROR for 5xx # time=2.159ms 1..4 - ok 4 - logger with status code rules applied # time=96.511ms + ok 4 - logger with status code rules applied # time=179.117ms # Subtest: format using a function ok 1 - should be equal 1..1 - ok 5 - format using a function # time=6.586ms + ok 5 - format using a function # time=1.944ms # Subtest: format using a function that also uses tokens ok 1 - should be equal 1..1 - ok 6 - format using a function that also uses tokens # time=2.083ms + ok 6 - format using a function that also uses tokens # time=2.262ms # Subtest: format using a function, but do not log anything if the function returns nothing ok 1 - should be equal 1..1 - ok 7 - format using a function, but do not log anything if the function returns nothing # time=2.219ms + ok 7 - format using a function, but do not log anything if the function returns nothing # time=2.268ms # Subtest: format that includes request headers ok 1 - should be equal 1..1 - ok 8 - format that includes request headers # time=2.227ms + ok 8 - format that includes request headers # time=2.135ms # Subtest: format that includes response headers ok 1 - should be equal 1..1 - ok 9 - format that includes response headers # time=2.417ms + ok 9 - format that includes response headers # time=10.765ms # Subtest: url token should check originalUrl and url ok 1 - should be equal 1..1 - ok 10 - url token should check originalUrl and url # time=1.993ms + ok 10 - url token should check originalUrl and url # time=14.46ms # Subtest: log events with custom token ok 1 - type is Array @@ -1321,7 +1357,7 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 11 - log events with custom token # time=12.012ms + ok 11 - log events with custom token # time=11.887ms # Subtest: log events with custom override token ok 1 - type is Array @@ -1329,25 +1365,25 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 12 - log events with custom override token # time=3.515ms + ok 12 - log events with custom override token # time=19.971ms # Subtest: log events with custom format ok 1 - expect truthy value ok 2 - should be equal 1..2 - ok 13 - log events with custom format # time=2.953ms + ok 13 - log events with custom format # time=3.152ms # Subtest: handle weird old node versions where socket contains socket ok 1 - should be equal 1..1 - ok 14 - handle weird old node versions where socket contains socket # time=2.485ms + ok 14 - handle weird old node versions where socket contains socket # time=2.157ms 1..14 - ok 1 - log4js connect logger # time=586.217ms + ok 1 - log4js connect logger # time=1057.168ms 1..1 - # time=643.22ms -ok 7 - test/tap/connect-logger-test.js # time=1587.284ms + # time=1148.926ms +ok 7 - test/tap/connect-logger-test.js # time=2998.901ms # Subtest: test/tap/connect-nolog-test.js # Subtest: log4js connect logger @@ -1361,16 +1397,16 @@ ok 6 - should match pattern provided ok 7 - should match pattern provided 1..7 - ok 1 - check unmatch url request # time=15.565ms + ok 1 - check unmatch url request # time=66.971ms # Subtest: check match url request ok 1 - type is Array ok 2 - should be equal 1..2 - ok 2 - check match url request # time=3.849ms + ok 2 - check match url request # time=11.797ms 1..2 - ok 1 - with nolog config # time=38.401ms + ok 1 - with nolog config # time=166.917ms # Subtest: nolog Strings # Subtest: check unmatch url request (png) @@ -1381,20 +1417,20 @@ ok 5 - should match pattern provided ok 6 - should match pattern provided 1..6 - ok 1 - check unmatch url request (png) # time=8.973ms + ok 1 - check unmatch url request (png) # time=24.274ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=2.515ms + ok 2 - check match url request (gif) # time=10.981ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=2.725ms + ok 3 - check match url request (jpeg) # time=2.728ms 1..3 - ok 2 - nolog Strings # time=62.221ms + ok 2 - nolog Strings # time=164.636ms # Subtest: nolog Array # Subtest: check unmatch url request (png) @@ -1405,20 +1441,20 @@ ok 5 - should match pattern provided ok 6 - should match pattern provided 1..6 - ok 1 - check unmatch url request (png) # time=7.917ms + ok 1 - check unmatch url request (png) # time=26.674ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=2.451ms + ok 2 - check match url request (gif) # time=10.921ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=2.392ms + ok 3 - check match url request (jpeg) # time=18.802ms 1..3 - ok 3 - nolog Array # time=55.873ms + ok 3 - nolog Array # time=155.938ms # Subtest: nolog RegExp # Subtest: check unmatch url request (png) @@ -1429,20 +1465,20 @@ ok 5 - should match pattern provided ok 6 - should match pattern provided 1..6 - ok 1 - check unmatch url request (png) # time=5.423ms + ok 1 - check unmatch url request (png) # time=13.781ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=2.305ms + ok 2 - check match url request (gif) # time=14.873ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=4.664ms + ok 3 - check match url request (jpeg) # time=15.053ms 1..3 - ok 4 - nolog RegExp # time=38.462ms + ok 4 - nolog RegExp # time=126.848ms # Subtest: nolog Array # Subtest: check unmatch url request (png) @@ -1453,27 +1489,27 @@ ok 5 - should match pattern provided ok 6 - should match pattern provided 1..6 - ok 1 - check unmatch url request (png) # time=4.969ms + ok 1 - check unmatch url request (png) # time=9.2ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=2.269ms + ok 2 - check match url request (gif) # time=14.906ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=2.123ms + ok 3 - check match url request (jpeg) # time=2.49ms 1..3 - ok 5 - nolog Array # time=36.996ms + ok 5 - nolog Array # time=126.992ms 1..5 - ok 1 - log4js connect logger # time=271.375ms + ok 1 - log4js connect logger # time=874.825ms 1..1 - # time=299.955ms -ok 8 - test/tap/connect-nolog-test.js # time=1126.812ms + # time=971.338ms +ok 8 - test/tap/connect-nolog-test.js # time=2798.911ms # Subtest: test/tap/disable-cluster-test.js # Subtest: cluster worker @@ -1484,13 +1520,13 @@ ok 4 - type is Error ok 5 - should match pattern provided 1..5 - ok 1 - should send events to its own appender # time=8.144ms + ok 1 - should send events to its own appender # time=35.497ms 1..1 - ok 1 - cluster worker # time=31.797ms + ok 1 - cluster worker # time=90.879ms 1..1 - # time=52.395ms + # time=128.696ms # Subtest: cluster master # Subtest: only master events should be logged # Subtest @@ -1500,14 +1536,14 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 1 - only master events should be logged # time=33.209ms + ok 1 - only master events should be logged # time=50.624ms 1..1 - ok 1 - cluster master # time=61.126ms + ok 1 - cluster master # time=152.37ms 1..1 - # time=87.689ms -ok 9 - test/tap/disable-cluster-test.js # time=2091.016ms + # time=211.369ms +ok 9 - test/tap/disable-cluster-test.js # time=4071.051ms # Subtest: test/tap/dummy-appender.js 1..0 # no tests found @@ -1519,37 +1555,37 @@ ok 1 - should match pattern provided ok 2 - should match pattern provided 1..2 - ok 1 - with default fileSyncAppender settings # time=138.191ms + ok 1 - with default fileSyncAppender settings # time=176.777ms # Subtest: with a max file size and no backups # Subtest: log file should only contain the second message ok 1 - should match pattern provided ok 2 - should be equal 1..2 - ok 1 - log file should only contain the second message # time=45.257ms + ok 1 - log file should only contain the second message # time=63.727ms # Subtest: there should be two test files ok 1 - should be equal 1..1 - ok 2 - there should be two test files # time=12.682ms + ok 2 - there should be two test files # time=16.367ms 1..2 - ok 2 - with a max file size and no backups # time=91.526ms + ok 2 - with a max file size and no backups # time=150.627ms # Subtest: with a max file size in unit mode and no backups # Subtest: log file should only contain the second message ok 1 - should match pattern provided ok 2 - should not match pattern provided 1..2 - ok 1 - log file should only contain the second message # time=44.321ms + ok 1 - log file should only contain the second message # time=59.964ms # Subtest: there should be two test files ok 1 - should be equal 1..1 - ok 2 - there should be two test files # time=9.395ms + ok 2 - there should be two test files # time=9.14ms 1..2 - ok 3 - with a max file size in unit mode and no backups # time=105.559ms + ok 3 - with a max file size in unit mode and no backups # time=156.699ms # Subtest: with a max file size and 2 backups # Subtest: the log files @@ -1559,28 +1595,28 @@ ok 3 - should match pattern provided ok 4 - should match pattern provided ok 5 - should match pattern provided - ok 1 - the log files # time=70.431ms + ok 1 - the log files # time=69.622ms 1..1 - ok 4 - with a max file size and 2 backups # time=99.588ms + ok 4 - with a max file size and 2 backups # time=127.96ms # Subtest: configure with fileSyncAppender ok 1 - should match pattern provided ok 2 - should be equal 1..2 - ok 5 - configure with fileSyncAppender # time=32.644ms + ok 5 - configure with fileSyncAppender # time=36.257ms # Subtest: test options ok 1 - should match pattern provided 1..1 - ok 6 - test options # time=26.396ms + ok 6 - test options # time=42.751ms 1..6 - ok 1 - log4js fileSyncAppender # time=531.018ms + ok 1 - log4js fileSyncAppender # time=744.711ms 1..1 - # time=553.521ms -ok 11 - test/tap/fileSyncAppender-test.js # time=1709.801ms + # time=771.83ms +ok 11 - test/tap/fileSyncAppender-test.js # time=2285.556ms # Subtest: test/tap/layouts-test.js # Subtest: log4js layouts @@ -1588,15 +1624,15 @@ # Subtest: should apply level colour codes to output ok 1 - should be equal 1..1 - ok 1 - should apply level colour codes to output # time=10.67ms + ok 1 - should apply level colour codes to output # time=29.566ms # Subtest: should support the console.log format for the message ok 1 - should be equal 1..1 - ok 2 - should support the console.log format for the message # time=3.724ms + ok 2 - should support the console.log format for the message # time=8.246ms 1..2 - ok 1 - colouredLayout # time=37.037ms + ok 1 - colouredLayout # time=89.991ms # Subtest: messagePassThroughLayout ok 1 - should take a logevent and output only the message @@ -1608,10 +1644,10 @@ ok 2 - should print error augmented string attributes ok 3 - should print error augmented object attributes 1..3 - ok 5 - with passed augmented errors # time=15.524ms + ok 5 - with passed augmented errors # time=8.36ms 1..5 - ok 2 - messagePassThroughLayout # time=34.42ms + ok 2 - messagePassThroughLayout # time=70.051ms # Subtest: basicLayout ok 1 - should be equal @@ -1629,75 +1665,75 @@ ok 11 - should be equal ok 12 - should be equal 1..12 - ok 2 - should output a stacktrace, message if the event has an error attached # time=9.304ms + ok 2 - should output a stacktrace, message if the event has an error attached # time=15.503ms # Subtest: should output any extra data in the log event as util.inspect strings ok 1 - should be equal 1..1 - ok 3 - should output any extra data in the log event as util.inspect strings # time=2.556ms + ok 3 - should output any extra data in the log event as util.inspect strings # time=3.134ms 1..3 - ok 3 - basicLayout # time=27.144ms + ok 3 - basicLayout # time=84.817ms # Subtest: dummyLayout # Subtest: should output just the first element of the log data ok 1 - should be equal 1..1 - ok 1 - should output just the first element of the log data # time=2.062ms + ok 1 - should output just the first element of the log data # time=6.97ms 1..1 - ok 4 - dummyLayout # time=9.355ms + ok 4 - dummyLayout # time=27.853ms # Subtest: patternLayout # Subtest: should default to "time logLevel loggerName - message" ok 1 - should be equal 1..1 - ok 1 - should default to "time logLevel loggerName - message" # time=8.137ms + ok 1 - should default to "time logLevel loggerName - message" # time=12.133ms # Subtest: %r should output time only ok 1 - should be equal 1..1 - ok 2 - %r should output time only # time=1.952ms + ok 2 - %r should output time only # time=10.593ms # Subtest: %p should output the log level ok 1 - should be equal 1..1 - ok 3 - %p should output the log level # time=6.977ms + ok 3 - %p should output the log level # time=2.095ms # Subtest: %c should output the log category ok 1 - should be equal 1..1 - ok 4 - %c should output the log category # time=2.099ms + ok 4 - %c should output the log category # time=2.019ms # Subtest: %m should output the log data ok 1 - should be equal 1..1 - ok 5 - %m should output the log data # time=1.883ms + ok 5 - %m should output the log data # time=1.851ms # Subtest: %n should output a new line ok 1 - should be equal 1..1 - ok 6 - %n should output a new line # time=24.673ms + ok 6 - %n should output a new line # time=2.119ms # Subtest: %h should output hostname ok 1 - should be equal 1..1 - ok 7 - %h should output hostname # time=2.065ms + ok 7 - %h should output hostname # time=6.684ms # Subtest: %z should output pid ok 1 - should be equal 1..1 - ok 8 - %z should output pid # time=2.085ms + ok 8 - %z should output pid # time=2.242ms # Subtest: %z should pick up pid from log event if present ok 1 - should be equal 1..1 - ok 9 - %z should pick up pid from log event if present # time=2.325ms + ok 9 - %z should pick up pid from log event if present # time=6.665ms # Subtest: %y should output pid (was cluster info) ok 1 - should be equal 1..1 - ok 10 - %y should output pid (was cluster info) # time=2.069ms + ok 10 - %y should output pid (was cluster info) # time=7.412ms # Subtest: %c should handle category names like java-style package names ok 1 - should be equal @@ -1707,12 +1743,12 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 11 - %c should handle category names like java-style package names # time=12.74ms + ok 11 - %c should handle category names like java-style package names # time=13.344ms # Subtest: %d should output the date in ISO8601 format ok 1 - should be equal 1..1 - ok 12 - %d should output the date in ISO8601 format # time=2.484ms + ok 12 - %d should output the date in ISO8601 format # time=2.528ms # Subtest: %d should allow for format specification ok 1 - should be equal @@ -1722,17 +1758,17 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 13 - %d should allow for format specification # time=6.008ms + ok 13 - %d should allow for format specification # time=18.379ms # Subtest: %% should output % ok 1 - should be equal 1..1 - ok 14 - %% should output % # time=10.353ms + ok 14 - %% should output % # time=10.561ms # Subtest: %f should output filename ok 1 - should be equal 1..1 - ok 15 - %f should output filename # time=10.574ms + ok 15 - %f should output filename # time=2.398ms # Subtest: %f should handle filename depth ok 1 - should be equal @@ -1742,7 +1778,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 16 - %f should handle filename depth # time=5.158ms + ok 16 - %f should handle filename depth # time=22.76ms # Subtest: %f should accept truncation and padding ok 1 - should be equal @@ -1750,12 +1786,12 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 17 - %f should accept truncation and padding # time=12.139ms + ok 17 - %f should accept truncation and padding # time=12.875ms # Subtest: %l should output line number ok 1 - should be equal 1..1 - ok 18 - %l should output line number # time=10.488ms + ok 18 - %l should output line number # time=2.389ms # Subtest: %l should accept truncation and padding ok 1 - should be equal @@ -1763,12 +1799,12 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 19 - %l should accept truncation and padding # time=4.79ms + ok 19 - %l should accept truncation and padding # time=5.078ms # Subtest: %o should output column postion ok 1 - should be equal 1..1 - ok 20 - %o should output column postion # time=2.068ms + ok 20 - %o should output column postion # time=6.655ms # Subtest: %o should accept truncation and padding ok 1 - should be equal @@ -1777,47 +1813,47 @@ ok 4 - should be equal ok 5 - should be equal 1..5 - ok 21 - %o should accept truncation and padding # time=9.986ms + ok 21 - %o should accept truncation and padding # time=12.738ms # Subtest: %s should output stack ok 1 - should be equal 1..1 - ok 22 - %s should output stack # time=2.077ms + ok 22 - %s should output stack # time=2.242ms # Subtest: %f should output empty string when fileName not exist ok 1 - should be equal 1..1 - ok 23 - %f should output empty string when fileName not exist # time=2.071ms + ok 23 - %f should output empty string when fileName not exist # time=6.478ms # Subtest: %l should output empty string when lineNumber not exist ok 1 - should be equal 1..1 - ok 24 - %l should output empty string when lineNumber not exist # time=5.964ms + ok 24 - %l should output empty string when lineNumber not exist # time=6.576ms # Subtest: %o should output empty string when columnNumber not exist ok 1 - should be equal 1..1 - ok 25 - %o should output empty string when columnNumber not exist # time=9.375ms + ok 25 - %o should output empty string when columnNumber not exist # time=17.818ms # Subtest: %s should output empty string when callStack not exist ok 1 - should be equal 1..1 - ok 26 - %s should output empty string when callStack not exist # time=1.935ms + ok 26 - %s should output empty string when callStack not exist # time=11.422ms # Subtest: should output anything not preceded by % as literal ok 1 - should be equal 1..1 - ok 27 - should output anything not preceded by % as literal # time=1.81ms + ok 27 - should output anything not preceded by % as literal # time=2.143ms # Subtest: should output the original string if no replacer matches the token ok 1 - should be equal 1..1 - ok 28 - should output the original string if no replacer matches the token # time=9.919ms + ok 28 - should output the original string if no replacer matches the token # time=6.424ms # Subtest: should handle complicated patterns ok 1 - should be equal 1..1 - ok 29 - should handle complicated patterns # time=1.602ms + ok 29 - should handle complicated patterns # time=6.568ms # Subtest: should truncate fields if specified ok 1 - should be equal @@ -1827,7 +1863,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 30 - should truncate fields if specified # time=3.153ms + ok 30 - should truncate fields if specified # time=4.521ms # Subtest: should pad fields if specified ok 1 - should be equal @@ -1839,65 +1875,65 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 31 - should pad fields if specified # time=7.737ms + ok 31 - should pad fields if specified # time=26.41ms # Subtest: %[%r%] should output colored time ok 1 - should be equal 1..1 - ok 32 - %[%r%] should output colored time # time=1.474ms + ok 32 - %[%r%] should output colored time # time=11.47ms # Subtest: %x{testString} should output the string stored in tokens ok 1 - should be equal 1..1 - ok 33 - %x{testString} should output the string stored in tokens # time=5.654ms + ok 33 - %x{testString} should output the string stored in tokens # time=2.251ms # Subtest: %x{testFunction} should output the result of the function stored in tokens ok 1 - should be equal 1..1 - ok 34 - %x{testFunction} should output the result of the function stored in tokens # time=1.339ms + ok 34 - %x{testFunction} should output the result of the function stored in tokens # time=14.725ms # Subtest: %x{doesNotExist} should output the string stored in tokens ok 1 - should be equal 1..1 - ok 35 - %x{doesNotExist} should output the string stored in tokens # time=9.178ms + ok 35 - %x{doesNotExist} should output the string stored in tokens # time=14.373ms # Subtest: %x{fnThatUsesLogEvent} should be able to use the logEvent ok 1 - should be equal 1..1 - ok 36 - %x{fnThatUsesLogEvent} should be able to use the logEvent # time=5.806ms + ok 36 - %x{fnThatUsesLogEvent} should be able to use the logEvent # time=6.62ms # Subtest: %x should output the string stored in tokens ok 1 - should be equal 1..1 - ok 37 - %x should output the string stored in tokens # time=1.198ms + ok 37 - %x should output the string stored in tokens # time=2.024ms # Subtest: %X{testString} should output the string stored in tokens ok 1 - should be equal 1..1 - ok 38 - %X{testString} should output the string stored in tokens # time=1.369ms + ok 38 - %X{testString} should output the string stored in tokens # time=2.229ms # Subtest: %X{testFunction} should output the result of the function stored in tokens ok 1 - should be equal 1..1 - ok 39 - %X{testFunction} should output the result of the function stored in tokens # time=1.342ms + ok 39 - %X{testFunction} should output the result of the function stored in tokens # time=1.906ms # Subtest: %X{doesNotExist} should output the string stored in tokens ok 1 - should be equal 1..1 - ok 40 - %X{doesNotExist} should output the string stored in tokens # time=5.498ms + ok 40 - %X{doesNotExist} should output the string stored in tokens # time=10.445ms # Subtest: %X{fnThatUsesLogEvent} should be able to use the logEvent ok 1 - should be equal 1..1 - ok 41 - %X{fnThatUsesLogEvent} should be able to use the logEvent # time=1.328ms + ok 41 - %X{fnThatUsesLogEvent} should be able to use the logEvent # time=38.538ms # Subtest: %X should output the string stored in tokens ok 1 - should be equal 1..1 - ok 42 - %X should output the string stored in tokens # time=1.17ms + ok 42 - %X should output the string stored in tokens # time=6.705ms 1..42 - ok 5 - patternLayout # time=839.463ms + ok 5 - patternLayout # time=1138.158ms # Subtest: layout makers # Subtest: should have a maker for each layout @@ -1908,16 +1944,16 @@ ok 5 - expect truthy value ok 6 - expect truthy value 1..6 - ok 1 - should have a maker for each layout # time=11.481ms + ok 1 - should have a maker for each layout # time=22.683ms # Subtest: layout pattern maker should pass pattern and tokens to layout from config ok 1 - should be equal ok 2 - should be equal 1..2 - ok 2 - layout pattern maker should pass pattern and tokens to layout from config # time=1.542ms + ok 2 - layout pattern maker should pass pattern and tokens to layout from config # time=2.584ms 1..2 - ok 6 - layout makers # time=32.342ms + ok 6 - layout makers # time=63.714ms # Subtest: add layout # Subtest: should be able to add a layout @@ -1925,17 +1961,17 @@ ok 2 - expect truthy value ok 3 - should be equal 1..3 - ok 1 - should be able to add a layout # time=6.642ms + ok 1 - should be able to add a layout # time=11.769ms 1..1 - ok 7 - add layout # time=11.201ms + ok 7 - add layout # time=26.607ms 1..7 - ok 1 - log4js layouts # time=1052.019ms + ok 1 - log4js layouts # time=1658.262ms 1..1 - # time=1102.032ms -ok 12 - test/tap/layouts-test.js # time=1814.615ms + # time=1740.987ms +ok 12 - test/tap/layouts-test.js # time=3013.555ms # Subtest: test/tap/levels-before-configure-test.js # Subtest: Accessing things setup in configure before configure is called @@ -1943,14 +1979,14 @@ ok 1 - expect truthy value ok 2 - expect truthy value 1..2 - ok 1 - should work # time=235.555ms + ok 1 - should work # time=434.404ms 1..1 - ok 1 - Accessing things setup in configure before configure is called # time=258.26ms + ok 1 - Accessing things setup in configure before configure is called # time=489.748ms 1..1 - # time=288.051ms -ok 13 - test/tap/levels-before-configure-test.js # time=1219.413ms + # time=518.564ms +ok 13 - test/tap/levels-before-configure-test.js # time=2148.253ms # Subtest: test/tap/levels-test.js # Subtest: levels @@ -1966,7 +2002,7 @@ ok 8 - expect truthy value ok 9 - expect truthy value 1..9 - ok 1 - should define some levels # time=39.026ms + ok 1 - should define some levels # time=56.933ms # Subtest: ALL ok 1 - expect truthy value @@ -1996,7 +2032,7 @@ ok 25 - expect falsey value ok 26 - expect falsey value 1..26 - ok 2 - ALL # time=42.023ms + ok 2 - ALL # time=86.426ms # Subtest: TRACE ok 1 - expect truthy value @@ -2026,7 +2062,7 @@ ok 25 - expect falsey value ok 26 - expect falsey value 1..26 - ok 3 - TRACE # time=45.372ms + ok 3 - TRACE # time=92.193ms # Subtest: DEBUG ok 1 - expect truthy value @@ -2055,7 +2091,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 4 - DEBUG # time=33.389ms + ok 4 - DEBUG # time=56.138ms # Subtest: INFO ok 1 - expect truthy value @@ -2084,7 +2120,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 5 - INFO # time=42.026ms + ok 5 - INFO # time=57.22ms # Subtest: WARN ok 1 - expect truthy value @@ -2112,7 +2148,7 @@ ok 23 - expect falsey value ok 24 - expect falsey value 1..24 - ok 6 - WARN # time=33.786ms + ok 6 - WARN # time=57.238ms # Subtest: ERROR ok 1 - expect truthy value @@ -2141,7 +2177,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 7 - ERROR # time=34.593ms + ok 7 - ERROR # time=73.115ms # Subtest: FATAL ok 1 - expect truthy value @@ -2170,7 +2206,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 8 - FATAL # time=40.017ms + ok 8 - FATAL # time=71.917ms # Subtest: MARK ok 1 - expect truthy value @@ -2199,7 +2235,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 9 - MARK # time=31.127ms + ok 9 - MARK # time=79.213ms # Subtest: OFF ok 1 - expect falsey value @@ -2228,10 +2264,10 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 10 - OFF # time=51.766ms + ok 10 - OFF # time=87.971ms 1..10 - ok 1 - values # time=583.897ms + ok 1 - values # time=1048.915ms # Subtest: isGreaterThanOrEqualTo ok 1 - expect truthy value @@ -2243,7 +2279,7 @@ ok 7 - expect falsey value ok 8 - expect falsey value 1..8 - ok 2 - isGreaterThanOrEqualTo # time=4.091ms + ok 2 - isGreaterThanOrEqualTo # time=16.108ms # Subtest: isLessThanOrEqualTo ok 1 - expect falsey value @@ -2255,14 +2291,14 @@ ok 7 - expect truthy value ok 8 - expect truthy value 1..8 - ok 3 - isLessThanOrEqualTo # time=13.826ms + ok 3 - isLessThanOrEqualTo # time=26.499ms # Subtest: isEqualTo ok 1 - expect truthy value ok 2 - expect truthy value ok 3 - expect truthy value 1..3 - ok 4 - isEqualTo # time=2.166ms + ok 4 - isEqualTo # time=2.231ms # Subtest: getLevel ok 1 - should be equal @@ -2272,14 +2308,14 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 5 - getLevel # time=11.999ms + ok 5 - getLevel # time=11.458ms 1..5 - ok 1 - levels # time=688.963ms + ok 1 - levels # time=1220.412ms 1..1 - # time=746.593ms -ok 14 - test/tap/levels-test.js # time=1390.86ms + # time=1286.525ms +ok 14 - test/tap/levels-test.js # time=3051.194ms # Subtest: test/tap/logLevelFilter-test.js # Subtest: log4js logLevelFilter @@ -2289,36 +2325,36 @@ ok 2 - should be equal ok 3 - should be equal 1..3 - ok 1 - should only pass log events greater than or equal to its own level # time=23.93ms + ok 1 - should only pass log events greater than or equal to its own level # time=53.954ms 1..1 - ok 1 - appender # time=300.353ms + ok 1 - appender # time=629.008ms # Subtest: configure # Subtest: tmp-tests.log should contain all log messages ok 1 - should be equivalent 1..1 - ok 1 - tmp-tests.log should contain all log messages # time=44.256ms + ok 1 - tmp-tests.log should contain all log messages # time=76.179ms # Subtest: tmp-tests-warnings.log should contain only error and warning logs ok 1 - should be equivalent 1..1 - ok 2 - tmp-tests-warnings.log should contain only error and warning logs # time=32.181ms + ok 2 - tmp-tests-warnings.log should contain only error and warning logs # time=44.085ms # Subtest: tmp-tests-debugs.log should contain only trace and debug logs ok 1 - should be equivalent 1..1 - ok 3 - tmp-tests-debugs.log should contain only trace and debug logs # time=26.279ms + ok 3 - tmp-tests-debugs.log should contain only trace and debug logs # time=33.149ms 1..3 - ok 2 - configure # time=665.821ms + ok 2 - configure # time=763.536ms 1..2 - ok 1 - log4js logLevelFilter # time=992.487ms + ok 1 - log4js logLevelFilter # time=1465.493ms 1..1 - # time=1020.187ms -ok 15 - test/tap/logLevelFilter-test.js # time=1975.145ms + # time=1508.887ms +ok 15 - test/tap/logLevelFilter-test.js # time=3459.105ms # Subtest: test/tap/newLevel-test.js # Subtest: ../../lib/logger @@ -2329,13 +2365,13 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 1 - should export new log level in levels module # time=11.459ms + ok 1 - should export new log level in levels module # time=55.003ms ok 2 - should create named function on logger prototype ok 3 - should create isLevelEnabled function on logger prototype ok 4 - should retain default levels 1..4 - ok 1 - creating a new log level # time=36.314ms + ok 1 - creating a new log level # time=150.479ms # Subtest: creating a new log level with underscores # Subtest: should export new log level to levels module @@ -2344,12 +2380,12 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 1 - should export new log level to levels module # time=5.144ms + ok 1 - should export new log level to levels module # time=12.423ms ok 2 - should create named function on logger prototype in camel case ok 3 - should create named isLevelEnabled function on logger prototype in camel case 1..3 - ok 2 - creating a new log level with underscores # time=16.979ms + ok 2 - creating a new log level with underscores # time=65.85ms # Subtest: creating log events containing newly created log level # Subtest: should show log events with new log level @@ -2362,11 +2398,11 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 1 - should show log events with new log level # time=7.926ms + ok 1 - should show log events with new log level # time=19.801ms ok 2 - should not be present if min log level is greater than newly created level 1..2 - ok 3 - creating log events containing newly created log level # time=21.202ms + ok 3 - creating log events containing newly created log level # time=58.066ms # Subtest: creating a new log level with incorrect parameters ok 1 - level "cheese".value must have an integer value @@ -2380,27 +2416,27 @@ ok 9 - level name "2" is not a valid identifier (must start with a letter, only contain A-Z,a-z,0-9,_) ok 10 - level name "cheese!" is not a valid identifier (must start with a letter, only contain A-Z,a-z,0-9,_) 1..10 - ok 4 - creating a new log level with incorrect parameters # time=43.821ms + ok 4 - creating a new log level with incorrect parameters # time=33.456ms # Subtest: calling log with an undefined log level ok 1 - should fall back to INFO ok 2 - should fall back to INFO 1..2 - ok 5 - calling log with an undefined log level # time=3.125ms + ok 5 - calling log with an undefined log level # time=11.633ms # Subtest: creating a new level with an existing level name ok 1 - should override the existing log level ok 2 - should override the existing log level ok 3 - should override the existing log level 1..3 - ok 6 - creating a new level with an existing level name # time=8.141ms + ok 6 - creating a new level with an existing level name # time=7.926ms 1..6 - ok 1 - ../../lib/logger # time=186.499ms + ok 1 - ../../lib/logger # time=450.232ms 1..1 - # time=210.543ms -ok 16 - test/tap/newLevel-test.js # time=1287.59ms + # time=492.359ms +ok 16 - test/tap/newLevel-test.js # time=2223.148ms # Subtest: test/tap/noLogFilter-test.js # Subtest: log4js noLogFilter @@ -2409,7 +2445,7 @@ ok 2 - should be equal ok 3 - should be equal 1..3 - ok 1 - appender should exclude events that match the regexp string # time=30.938ms + ok 1 - appender should exclude events that match the regexp string # time=25.665ms # Subtest: appender should exclude events that match the regexp string contained in the array ok 1 - should be equal @@ -2417,58 +2453,58 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 2 - appender should exclude events that match the regexp string contained in the array # time=13.916ms + ok 2 - appender should exclude events that match the regexp string contained in the array # time=5.487ms # Subtest: appender should evaluate the regexp using incase sentitive option ok 1 - should be equal ok 2 - should be equal ok 3 - should be equal 1..3 - ok 3 - appender should evaluate the regexp using incase sentitive option # time=4.315ms + ok 3 - appender should evaluate the regexp using incase sentitive option # time=14.473ms # Subtest: appender should skip the match in case of empty or null regexp ok 1 - should be equal ok 2 - should be equal ok 3 - should be equal 1..3 - ok 4 - appender should skip the match in case of empty or null regexp # time=3.964ms + ok 4 - appender should skip the match in case of empty or null regexp # time=4.093ms # Subtest: appender should exclude the events that contains digits ok 1 - should be equal ok 2 - should be equal 1..2 - ok 5 - appender should exclude the events that contains digits # time=8.162ms + ok 5 - appender should exclude the events that contains digits # time=3.853ms # Subtest: appender should exclude not valid events according to the documentation ok 1 - should be equal ok 2 - should be equal ok 3 - should be equal 1..3 - ok 6 - appender should exclude not valid events according to the documentation # time=8.206ms + ok 6 - appender should exclude not valid events according to the documentation # time=10.161ms 1..6 - ok 1 - log4js noLogFilter # time=166.057ms + ok 1 - log4js noLogFilter # time=141.133ms 1..1 - # time=200.007ms -ok 17 - test/tap/noLogFilter-test.js # time=1453.844ms + # time=159.222ms +ok 17 - test/tap/noLogFilter-test.js # time=2186.934ms # Subtest: test/tap/pause-test.js # Subtest: Drain event test # Subtest: Should emit pause event and resume when logging in a file with high frequency 1..0 - ok 1 - Should emit pause event and resume when logging in a file with high frequency # time=47.051ms + ok 1 - Should emit pause event and resume when logging in a file with high frequency # time=139.385ms # Subtest: Should emit pause event and resume when logging in a date file with high frequency 1..0 - ok 2 - Should emit pause event and resume when logging in a date file with high frequency # time=8.249ms + ok 2 - Should emit pause event and resume when logging in a date file with high frequency # time=28.962ms 1..2 - ok 1 - Drain event test # time=77.98ms + ok 1 - Drain event test # time=252.047ms 1..1 - # time=419.307ms -ok 18 - test/tap/pause-test.js # time=1140.191ms + # time=748.716ms +ok 18 - test/tap/pause-test.js # time=2815.497ms # Subtest: test/tap/pm2-support-test.js # Subtest: PM2 Support @@ -2476,26 +2512,26 @@ ok 1 - expect falsey value ok 2 - expect falsey value 1..2 - ok 1 - should not get any events when turned off # time=17.489ms + ok 1 - should not get any events when turned off # time=43.019ms # Subtest: should get events on app instance 0 ok 1 - should be equal ok 2 - should be equal ok 3 - should be equal 1..3 - ok 2 - should get events on app instance 0 # time=3.24ms + ok 2 - should get events on app instance 0 # time=16.553ms # Subtest: should not get events on app instance 1 ok 1 - should be equal 1..1 - ok 3 - should not get events on app instance 1 # time=1.933ms + ok 3 - should not get events on app instance 1 # time=10.628ms 1..3 - ok 1 - PM2 Support # time=80.06ms + ok 1 - PM2 Support # time=167.243ms 1..1 - # time=97.888ms -ok 19 - test/tap/pm2-support-test.js # time=9126.843ms + # time=212.405ms +ok 19 - test/tap/pm2-support-test.js # time=9518.754ms # Subtest: test/tap/server-test.js # Subtest: TCP Server @@ -2506,14 +2542,14 @@ ok 4 - should match pattern provided ok 5 - should match pattern provided 1..5 - ok 1 - should listen for TCP messages and re-send via process.send # time=251.673ms + ok 1 - should listen for TCP messages and re-send via process.send # time=295.202ms 1..1 - ok 1 - TCP Server # time=280.919ms + ok 1 - TCP Server # time=352.275ms 1..1 - # time=306.124ms -ok 20 - test/tap/server-test.js # time=1447.264ms + # time=390.525ms +ok 20 - test/tap/server-test.js # time=2423.424ms # Subtest: test/tap/setLevel-asymmetry-test.js # Subtest: log4js setLevel @@ -2526,7 +2562,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=13.192ms + ok 1 - should convert string to level correctly # time=60.553ms # Subtest: should also accept a Level ok 1 - should be equal @@ -2536,10 +2572,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=6.382ms + ok 2 - should also accept a Level # time=34.38ms 1..2 - ok 1 - is called with a Trace as string # time=43.591ms + ok 1 - is called with a Trace as string # time=181.941ms # Subtest: is called with a Debug as string # Subtest: should convert string to level correctly @@ -2550,7 +2586,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=5.635ms + ok 1 - should convert string to level correctly # time=17.764ms # Subtest: should also accept a Level ok 1 - should be equal @@ -2560,10 +2596,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=5.556ms + ok 2 - should also accept a Level # time=22.577ms 1..2 - ok 2 - is called with a Debug as string # time=27.776ms + ok 2 - is called with a Debug as string # time=112.13ms # Subtest: is called with a Info as string # Subtest: should convert string to level correctly @@ -2574,7 +2610,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=5.23ms + ok 1 - should convert string to level correctly # time=25.512ms # Subtest: should also accept a Level ok 1 - should be equal @@ -2584,10 +2620,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=6.662ms + ok 2 - should also accept a Level # time=17.3ms 1..2 - ok 3 - is called with a Info as string # time=28.252ms + ok 3 - is called with a Info as string # time=90.473ms # Subtest: is called with a Warn as string # Subtest: should convert string to level correctly @@ -2598,7 +2634,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=5.071ms + ok 1 - should convert string to level correctly # time=12.758ms # Subtest: should also accept a Level ok 1 - should be equal @@ -2608,10 +2644,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=5.127ms + ok 2 - should also accept a Level # time=16.855ms 1..2 - ok 4 - is called with a Warn as string # time=28.524ms + ok 4 - is called with a Warn as string # time=89.659ms # Subtest: is called with a Error as string # Subtest: should convert string to level correctly @@ -2622,7 +2658,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=5.023ms + ok 1 - should convert string to level correctly # time=13.054ms # Subtest: should also accept a Level ok 1 - should be equal @@ -2632,10 +2668,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=5.019ms + ok 2 - should also accept a Level # time=17.122ms 1..2 - ok 5 - is called with a Error as string # time=28.901ms + ok 5 - is called with a Error as string # time=81.472ms # Subtest: is called with a Fatal as string # Subtest: should convert string to level correctly @@ -2646,7 +2682,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=5.02ms + ok 1 - should convert string to level correctly # time=13.448ms # Subtest: should also accept a Level ok 1 - should be equal @@ -2656,27 +2692,27 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=5.043ms + ok 2 - should also accept a Level # time=12.683ms 1..2 - ok 6 - is called with a Fatal as string # time=24.001ms + ok 6 - is called with a Fatal as string # time=100.882ms 1..6 - ok 1 - log4js setLevel # time=230.296ms + ok 1 - log4js setLevel # time=878.171ms 1..1 - # time=260.969ms -ok 21 - test/tap/setLevel-asymmetry-test.js # time=871.218ms + # time=974.564ms +ok 21 - test/tap/setLevel-asymmetry-test.js # time=2318.558ms # Subtest: test/tap/stacktraces-test.js # Subtest: Stacktraces from errors in different VM context ok 1 - should match pattern provided 1..1 - ok 1 - Stacktraces from errors in different VM context # time=172.844ms + ok 1 - Stacktraces from errors in different VM context # time=358.139ms 1..1 - # time=195.794ms -ok 22 - test/tap/stacktraces-test.js # time=1046.14ms + # time=403.785ms +ok 22 - test/tap/stacktraces-test.js # time=1843.397ms # Subtest: test/tap/subcategories-test.js # Subtest: subcategories @@ -2691,10 +2727,10 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 1 - check logger levels # time=14.714ms + ok 1 - check logger levels # time=63.011ms 1..1 - ok 1 - loggers created after levels configuration is loaded # time=37.211ms + ok 1 - loggers created after levels configuration is loaded # time=124.938ms # Subtest: loggers created before levels configuration is loaded # Subtest: should still get new levels @@ -2707,32 +2743,32 @@ ok 7 - should be equivalent ok 8 - should be equivalent 1..8 - ok 1 - should still get new levels # time=9.05ms + ok 1 - should still get new levels # time=33.926ms 1..1 - ok 2 - loggers created before levels configuration is loaded # time=19.805ms + ok 2 - loggers created before levels configuration is loaded # time=84.871ms # Subtest: setting level on subcategories should not set parent level # Subtest: should inherit parent level ok 1 - should be equivalent 1..1 - ok 1 - should inherit parent level # time=2.628ms + ok 1 - should inherit parent level # time=15.208ms # Subtest: changing child level should not change parent level ok 1 - should be equivalent ok 2 - should be equivalent 1..2 - ok 2 - changing child level should not change parent level # time=3.617ms + ok 2 - changing child level should not change parent level # time=29.088ms 1..2 - ok 3 - setting level on subcategories should not set parent level # time=23.471ms + ok 3 - setting level on subcategories should not set parent level # time=113.711ms 1..3 - ok 1 - subcategories # time=115.956ms + ok 1 - subcategories # time=407.658ms 1..1 - # time=130.54ms -ok 23 - test/tap/subcategories-test.js # time=824.235ms + # time=462.906ms +ok 23 - test/tap/subcategories-test.js # time=2507.144ms # Subtest: test/tap/tcp-appender-test.js # Subtest: TCP Appender @@ -2741,32 +2777,32 @@ ok 2 - should match pattern provided ok 3 - should match pattern provided 1..3 - ok 1 - Default Configuration # time=196.099ms + ok 1 - Default Configuration # time=320.089ms # Subtest: Custom EndMessage String ok 1 - should be equal ok 2 - should match pattern provided ok 3 - should match pattern provided 1..3 - ok 2 - Custom EndMessage String # time=112.676ms + ok 2 - Custom EndMessage String # time=118.682ms # Subtest: Custom Layout ok 1 - should be equal ok 2 - should match pattern provided ok 3 - should match pattern provided 1..3 - ok 3 - Custom Layout # time=106.381ms + ok 3 - Custom Layout # time=134.204ms 1..3 - ok 1 - TCP Appender # time=434.606ms + ok 1 - TCP Appender # time=636.415ms 1..1 - # time=443.205ms -ok 24 - test/tap/tcp-appender-test.js # time=1139.253ms + # time=687.827ms +ok 24 - test/tap/tcp-appender-test.js # time=2955.557ms 1..24 # skip: 1 -# time=39160.964ms +# time=67301.145ms Removing node_modules/log4js create-stamp debian/debhelper-build-stamp dh_prep @@ -2900,12 +2936,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/4036813/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/4036813/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/3991443 and its subdirectories -I: Current time: Mon Sep 19 02:49:47 -12 2022 -I: pbuilder-time-stamp: 1663598987 +I: removing directory /srv/workspace/pbuilder/4036813 and its subdirectories +I: Current time: Mon Oct 23 11:16:35 +14 2023 +I: pbuilder-time-stamp: 1698009395