Diff of the two buildlogs: -- --- b1/build.log 2022-09-15 01:18:28.015634911 +0000 +++ b2/build.log 2022-09-15 01:19:42.468823572 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed Sep 14 13:16:42 -12 2022 -I: pbuilder-time-stamp: 1663204602 +I: Current time: Wed Oct 18 21:41:30 +14 2023 +I: pbuilder-time-stamp: 1697614890 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.kxakVw_7/trustedkeys.kbx': General error -gpgv: Signature made Sun Aug 7 08:58:53 2022 -12 +gpgv: keyblock resource '/tmp/dpkg-verify-sig.sf8nxur3/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/12414/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/9612/tmp/hooks/D01_modify_environment starting +debug: Running on codethink9-arm64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +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 18 21:41 /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/9612/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/9612/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='arm64' - DEBIAN_FRONTEND='noninteractive' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges: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]="aarch64-unknown-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=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=8' - DISTRIBUTION='' - HOME='/var/lib/jenkins' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION= + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/var/lib/jenkins + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='12414' - PS1='# ' - PS2='> ' + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=9612 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.LEZRTaRW4a/pbuilderrc_cW6S --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.LEZRTaRW4a/b1 --logfile b1/build.log node-log4js_6.3.0+~cs8.3.10-1+deb11u1.dsc' - SUDO_GID='117' - SUDO_UID='110' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - USERNAME='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.16:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/tmp.LEZRTaRW4a/pbuilderrc_nxt1 --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/tmp.LEZRTaRW4a/b2 --logfile b2/build.log node-log4js_6.3.0+~cs8.3.10-1+deb11u1.dsc' + SUDO_GID=117 + SUDO_UID=110 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + USERNAME=root + _='I: set' + http_proxy=http://192.168.101.16:3128 I: uname -a - Linux codethink16-arm64 4.15.0-192-generic #203-Ubuntu SMP Wed Aug 10 17:42:41 UTC 2022 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-192-generic #203-Ubuntu SMP Wed Aug 10 17:42:41 UTC 2022 aarch64 GNU/Linux I: ls -l /bin total 5248 - -rwxr-xr-x 1 root root 1282512 Mar 27 06:40 bash - -rwxr-xr-x 3 root root 34808 Jul 20 2020 bunzip2 - -rwxr-xr-x 3 root root 34808 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 34808 Jul 20 2020 bzip2 - -rwxr-xr-x 1 root root 14264 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 39832 Sep 22 2020 cat - -rwxr-xr-x 1 root root 64512 Sep 22 2020 chgrp - -rwxr-xr-x 1 root root 60368 Sep 22 2020 chmod - -rwxr-xr-x 1 root root 64528 Sep 22 2020 chown - -rwxr-xr-x 1 root root 138896 Sep 22 2020 cp - -rwxr-xr-x 1 root root 129544 Dec 10 2020 dash - -rwxr-xr-x 1 root root 101384 Sep 22 2020 date - -rwxr-xr-x 1 root root 80984 Sep 22 2020 dd - -rwxr-xr-x 1 root root 89824 Sep 22 2020 df - -rwxr-xr-x 1 root root 143088 Sep 22 2020 dir - -rwxr-xr-x 1 root root 76152 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 35632 Sep 22 2020 echo - -rwxr-xr-x 1 root root 28 Nov 9 2020 egrep - -rwxr-xr-x 1 root root 31512 Sep 22 2020 false - -rwxr-xr-x 1 root root 28 Nov 9 2020 fgrep - -rwxr-xr-x 1 root root 64856 Jan 20 2022 findmnt - -rwsr-xr-x 1 root root 34824 Feb 26 2021 fusermount - -rwxr-xr-x 1 root root 178400 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 93744 Apr 9 17:50 gzip - -rwxr-xr-x 1 root root 18440 Nov 6 2019 hostname - -rwxr-xr-x 1 root root 68720 Sep 22 2020 ln - -rwxr-xr-x 1 root root 52720 Feb 7 2020 login - -rwxr-xr-x 1 root root 143088 Sep 22 2020 ls - -rwxr-xr-x 1 root root 157864 Jan 20 2022 lsblk - -rwxr-xr-x 1 root root 85200 Sep 22 2020 mkdir - -rwxr-xr-x 1 root root 68744 Sep 22 2020 mknod - -rwxr-xr-x 1 root root 43976 Sep 22 2020 mktemp - -rwxr-xr-x 1 root root 51368 Jan 20 2022 more - -rwsr-xr-x 1 root root 51360 Jan 20 2022 mount - -rwxr-xr-x 1 root root 14496 Jan 20 2022 mountpoint - -rwxr-xr-x 1 root root 134808 Sep 22 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 35720 Sep 22 2020 pwd - lrwxrwxrwx 1 root root 4 Mar 27 06:40 rbash -> bash - -rwxr-xr-x 1 root root 43872 Sep 22 2020 readlink - -rwxr-xr-x 1 root root 68592 Sep 22 2020 rm - -rwxr-xr-x 1 root root 43880 Sep 22 2020 rmdir - -rwxr-xr-x 1 root root 19208 Sep 27 2020 run-parts - -rwxr-xr-x 1 root root 114016 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Sep 6 21:24 sh -> dash - -rwxr-xr-x 1 root root 35656 Sep 22 2020 sleep - -rwxr-xr-x 1 root root 72640 Sep 22 2020 stty - -rwsr-xr-x 1 root root 67776 Jan 20 2022 su - -rwxr-xr-x 1 root root 35672 Sep 22 2020 sync - -rwxr-xr-x 1 root root 535768 Feb 16 2021 tar - -rwxr-xr-x 1 root root 10568 Sep 27 2020 tempfile - -rwxr-xr-x 1 root root 89120 Sep 22 2020 touch - -rwxr-xr-x 1 root root 31512 Sep 22 2020 true - -rwxr-xr-x 1 root root 14264 Feb 26 2021 ulockmgr_server - -rwsr-xr-x 1 root root 30880 Jan 20 2022 umount - -rwxr-xr-x 1 root root 35640 Sep 22 2020 uname - -rwxr-xr-x 2 root root 2346 Apr 9 17:50 uncompress - -rwxr-xr-x 1 root root 143088 Sep 22 2020 vdir - -rwxr-xr-x 1 root root 59584 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/12414/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1282512 Mar 28 2022 bash + -rwxr-xr-x 3 root root 34808 Jul 21 2020 bunzip2 + -rwxr-xr-x 3 root root 34808 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 34808 Jul 21 2020 bzip2 + -rwxr-xr-x 1 root root 14264 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 39832 Sep 23 2020 cat + -rwxr-xr-x 1 root root 64512 Sep 23 2020 chgrp + -rwxr-xr-x 1 root root 60368 Sep 23 2020 chmod + -rwxr-xr-x 1 root root 64528 Sep 23 2020 chown + -rwxr-xr-x 1 root root 138896 Sep 23 2020 cp + -rwxr-xr-x 1 root root 129544 Dec 11 2020 dash + -rwxr-xr-x 1 root root 101384 Sep 23 2020 date + -rwxr-xr-x 1 root root 80984 Sep 23 2020 dd + -rwxr-xr-x 1 root root 89824 Sep 23 2020 df + -rwxr-xr-x 1 root root 143088 Sep 23 2020 dir + -rwxr-xr-x 1 root root 76152 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 35632 Sep 23 2020 echo + -rwxr-xr-x 1 root root 28 Nov 10 2020 egrep + -rwxr-xr-x 1 root root 31512 Sep 23 2020 false + -rwxr-xr-x 1 root root 28 Nov 10 2020 fgrep + -rwxr-xr-x 1 root root 64856 Jan 21 2022 findmnt + -rwsr-xr-x 1 root root 34824 Feb 27 2021 fusermount + -rwxr-xr-x 1 root root 178400 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 93744 Apr 10 2022 gzip + -rwxr-xr-x 1 root root 18440 Nov 8 2019 hostname + -rwxr-xr-x 1 root root 68720 Sep 23 2020 ln + -rwxr-xr-x 1 root root 52720 Feb 8 2020 login + -rwxr-xr-x 1 root root 143088 Sep 23 2020 ls + -rwxr-xr-x 1 root root 157864 Jan 21 2022 lsblk + -rwxr-xr-x 1 root root 85200 Sep 23 2020 mkdir + -rwxr-xr-x 1 root root 68744 Sep 23 2020 mknod + -rwxr-xr-x 1 root root 43976 Sep 23 2020 mktemp + -rwxr-xr-x 1 root root 51368 Jan 21 2022 more + -rwsr-xr-x 1 root root 51360 Jan 21 2022 mount + -rwxr-xr-x 1 root root 14496 Jan 21 2022 mountpoint + -rwxr-xr-x 1 root root 134808 Sep 23 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 35720 Sep 23 2020 pwd + lrwxrwxrwx 1 root root 4 Mar 28 2022 rbash -> bash + -rwxr-xr-x 1 root root 43872 Sep 23 2020 readlink + -rwxr-xr-x 1 root root 68592 Sep 23 2020 rm + -rwxr-xr-x 1 root root 43880 Sep 23 2020 rmdir + -rwxr-xr-x 1 root root 19208 Sep 28 2020 run-parts + -rwxr-xr-x 1 root root 114016 Dec 23 2018 sed + lrwxrwxrwx 1 root root 4 Oct 18 21:41 sh -> bash + lrwxrwxrwx 1 root root 4 Oct 11 05:49 sh.distrib -> dash + -rwxr-xr-x 1 root root 35656 Sep 23 2020 sleep + -rwxr-xr-x 1 root root 72640 Sep 23 2020 stty + -rwsr-xr-x 1 root root 67776 Jan 21 2022 su + -rwxr-xr-x 1 root root 35672 Sep 23 2020 sync + -rwxr-xr-x 1 root root 535768 Feb 17 2021 tar + -rwxr-xr-x 1 root root 10568 Sep 28 2020 tempfile + -rwxr-xr-x 1 root root 89120 Sep 23 2020 touch + -rwxr-xr-x 1 root root 31512 Sep 23 2020 true + -rwxr-xr-x 1 root root 14264 Feb 27 2021 ulockmgr_server + -rwsr-xr-x 1 root root 30880 Jan 21 2022 umount + -rwxr-xr-x 1 root root 35640 Sep 23 2020 uname + -rwxr-xr-x 2 root root 2346 Apr 10 2022 uncompress + -rwxr-xr-x 1 root root 143088 Sep 23 2020 vdir + -rwxr-xr-x 1 root root 59584 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/9612/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 arm64 node-write-file-atomic all 3.0.3+~3.0.1-1 [8048 B] Get: 125 http://deb.debian.org/debian bullseye/main arm64 node-tap all 12.0.1+ds-3 [43.4 kB] Get: 126 http://deb.debian.org/debian bullseye/main arm64 pkg-js-tools all 0.9.65 [38.0 kB] -Fetched 30.6 MB in 2s (13.2 MB/s) +Fetched 30.6 MB in 4s (8019 kB/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 ... 19695 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: Temporary failure in name resolution +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 -+ + grepxargs -v grep multi - -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 +++ 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 +++ grep -v no-cluster-test + 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=30.629ms + ok 1 - should serialise to flatted # time=19.159ms # 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=15.951ms + ok 2 - should deserialise from flatted # time=11.733ms # 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=10.789ms + ok 3 - Should correct construct with/without location info # time=7.946ms 1..3 - ok 1 - LoggingEvent # time=109.348ms + ok 1 - LoggingEvent # time=74.832ms 1..1 - # time=138.754ms -ok 1 - test/tap/LoggingEvent-test.js # time=1122.682ms + # time=94.162ms +ok 1 - test/tap/LoggingEvent-test.js # time=695.157ms # 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.767ms + ok 1 - should resolve if defined in dependency order # time=18.905ms # 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=9.548ms + ok 2 - should process log events normally # time=6.395ms 1..2 - ok 1 - in order # time=94.856ms + ok 1 - in order # time=55.722ms # 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=9.014ms + ok 1 - should resolve if defined out of dependency order # time=5.908ms # 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=6.913ms + ok 2 - should process log events normally # time=4.423ms 1..2 - ok 2 - not in order # time=51.052ms + ok 2 - not in order # time=33.164ms # 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=7.804ms + ok 1 - should throw an error if if a dependency loop is found # time=5.306ms 1..1 - ok 3 - with dependency loop # time=36.972ms + ok 3 - with dependency loop # time=19.452ms 1..3 - ok 1 - log4js appender dependencies # time=457.13ms + ok 1 - log4js appender dependencies # time=283.907ms 1..1 - # time=490.16ms -ok 2 - test/tap/appender-dependencies-test.js # time=1383.846ms + # time=304.927ms +ok 2 - test/tap/appender-dependencies-test.js # time=873.177ms # 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=34.706ms + ok 1 - appender should exclude categories # time=26.15ms # 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=10.185ms + ok 2 - should not really need a category filter any more # time=7.566ms 1..2 - ok 1 - log4js categoryFilter # time=98.016ms + ok 1 - log4js categoryFilter # time=62.229ms 1..1 - # time=130.943ms -ok 3 - test/tap/categoryFilter-test.js # time=1392.487ms + # time=81.789ms +ok 3 - test/tap/categoryFilter-test.js # time=789.176ms # 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=65.858ms + ok 1 - events should be logged # time=29.159ms 1..1 - ok 1 - cluster master # time=113.265ms + ok 1 - cluster master # time=53.269ms # Subtest: cluster worker # Subtest: logger should get correct config ok 1 - should be equal 1..1 - ok 1 - logger should get correct config # time=5.657ms + ok 1 - logger should get correct config # time=3.754ms 1..1 - ok 2 - cluster worker # time=26.565ms + ok 2 - cluster worker # time=13.258ms 1..2 - # time=177.955ms -ok 4 - test/tap/cluster-test.js # time=2507.518ms + # time=88.775ms +ok 4 - test/tap/cluster-test.js # time=1504.089ms # 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=41.955ms + ok 1 - should inherit appenders from direct parent # time=26.179ms # 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=17.58ms + ok 2 - multiple children should inherit config from shared parent # time=12.766ms # 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=16.464ms + ok 3 - should inherit appenders from multiple parents # time=11.843ms # 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=12.42ms + ok 4 - should inherit appenders from deep parent with missing direct parent # time=9.163ms # 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=10.103ms + ok 5 - should deal gracefully with missing parent # time=6.64ms # 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=10.55ms + ok 6 - should not get duplicate appenders if parent has the same one # time=7.277ms # 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=9.561ms + ok 7 - inherit:falses should disable inheritance # time=6.231ms # 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=12.502ms + ok 8 - inheritance should stop if direct parent has inherit off # time=8.464ms # 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=6.522ms + ok 9 - should inherit level when it is missing # time=4.545ms 1..9 - ok 1 - log4js category inherit all appenders from direct parent # time=275.666ms + ok 1 - log4js category inherit all appenders from direct parent # time=180.734ms 1..1 - # time=306.722ms -ok 5 - test/tap/configuration-inheritance-test.js # time=1471.145ms + # time=203.183ms +ok 5 - test/tap/configuration-inheritance-test.js # time=910.488ms # 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=31.413ms + ok 1 - response should be included in context # time=21.004ms 1..1 - ok 1 - with context config # time=61.148ms + ok 1 - with context config # time=39.55ms # 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=10.722ms + ok 1 - response should not be included in context # time=7.8ms 1..1 - ok 2 - without context config # time=30.887ms + ok 2 - without context config # time=20.975ms 1..2 - ok 1 - log4js connect logger # time=131.407ms + ok 1 - log4js connect logger # time=86.275ms 1..1 - # time=162.335ms -ok 6 - test/tap/connect-context-test.js # time=1091.645ms + # time=106.439ms +ok 6 - test/tap/connect-context-test.js # time=697.195ms # 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.02ms + ok 2 - should take a log4js logger and return a "connect logger" # time=9.808ms # 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=24.823ms + ok 3 - log events # time=17.418ms # 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=7.132ms + ok 4 - log events with level below logging level # time=5.371ms # 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=8.605ms + ok 5 - log events with non-default level and custom format # time=7.424ms # 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=8.755ms + ok 6 - adding multiple loggers should only log once # time=7.358ms 1..6 - ok 1 - getConnectLoggerModule # time=169.652ms + ok 1 - getConnectLoggerModule # time=116.489ms # Subtest: logger with options as string ok 1 - should be equal 1..1 - ok 2 - logger with options as string # time=4.602ms + ok 2 - logger with options as string # time=3.586ms # 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=6.429ms + ok 1 - should use INFO for 2xx # time=5.11ms # Subtest: should use WARN for 3xx ok 1 - expect truthy value 1..1 - ok 2 - should use WARN for 3xx # time=4.515ms + ok 2 - should use WARN for 3xx # time=3.505ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=4.057ms + ok 3 - should use ERROR for 4xx # time=3.365ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=4.442ms + ok 4 - should use ERROR for 5xx # time=3.299ms 1..4 - ok 3 - auto log levels # time=93.586ms + ok 3 - auto log levels # time=62.918ms # 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=9.055ms + ok 1 - should use DEBUG for 2xx # time=4.876ms # 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=5.13ms + ok 2 - should use WARN for 3xx, DEBUG for 304 # time=4.289ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=4.337ms + ok 3 - should use ERROR for 4xx # time=3.295ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=4.273ms + ok 4 - should use ERROR for 5xx # time=3.39ms 1..4 - ok 4 - logger with status code rules applied # time=93.331ms + ok 4 - logger with status code rules applied # time=59.613ms # Subtest: format using a function ok 1 - should be equal 1..1 - ok 5 - format using a function # time=3.894ms + ok 5 - format using a function # time=3.007ms # 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=4.097ms + ok 6 - format using a function that also uses tokens # time=3.015ms # 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=6.432ms + ok 7 - format using a function, but do not log anything if the function returns nothing # time=2.982ms # Subtest: format that includes request headers ok 1 - should be equal 1..1 - ok 8 - format that includes request headers # time=4.534ms + ok 8 - format that includes request headers # time=3.149ms # Subtest: format that includes response headers ok 1 - should be equal 1..1 - ok 9 - format that includes response headers # time=4.373ms + ok 9 - format that includes response headers # time=2.966ms # 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=3.84ms + ok 10 - url token should check originalUrl and url # time=2.776ms # 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=6.868ms + ok 11 - log events with custom token # time=5.444ms # 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=6.452ms + ok 12 - log events with custom override token # time=5.461ms # 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=5.356ms + ok 13 - log events with custom format # time=4.247ms # 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=3.903ms + ok 14 - handle weird old node versions where socket contains socket # time=3.088ms 1..14 - ok 1 - log4js connect logger # time=543.261ms + ok 1 - log4js connect logger # time=382.977ms 1..1 - # time=604.173ms -ok 7 - test/tap/connect-logger-test.js # time=1569.575ms + # time=419.387ms +ok 7 - test/tap/connect-logger-test.js # time=1016.19ms # 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=36.277ms + ok 1 - check unmatch url request # time=26.654ms # Subtest: check match url request ok 1 - type is Array ok 2 - should be equal 1..2 - ok 2 - check match url request # time=7.774ms + ok 2 - check match url request # time=6.036ms 1..2 - ok 1 - with nolog config # time=87.845ms + ok 1 - with nolog config # time=62.339ms # 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=13.101ms + ok 1 - check unmatch url request (png) # time=10.755ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=5.315ms + ok 2 - check match url request (gif) # time=3.673ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=5.205ms + ok 3 - check match url request (jpeg) # time=4.078ms 1..3 - ok 2 - nolog Strings # time=79.825ms + ok 2 - nolog Strings # time=55.365ms # 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=11.955ms + ok 1 - check unmatch url request (png) # time=10.156ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=4.812ms + ok 2 - check match url request (gif) # time=3.807ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=5.083ms + ok 3 - check match url request (jpeg) # time=3.595ms 1..3 - ok 3 - nolog Array # time=88.368ms + ok 3 - nolog Array # time=54.138ms # 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=11.892ms + ok 1 - check unmatch url request (png) # time=9.393ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=5.377ms + ok 2 - check match url request (gif) # time=3.725ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=5.558ms + ok 3 - check match url request (jpeg) # time=3.483ms 1..3 - ok 4 - nolog RegExp # time=68.702ms + ok 4 - nolog RegExp # time=47.368ms # 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=11.744ms + ok 1 - check unmatch url request (png) # time=9.515ms # Subtest: check match url request (gif) ok 1 - should be equal 1..1 - ok 2 - check match url request (gif) # time=5.226ms + ok 2 - check match url request (gif) # time=3.548ms # Subtest: check match url request (jpeg) ok 1 - should be equal 1..1 - ok 3 - check match url request (jpeg) # time=5.152ms + ok 3 - check match url request (jpeg) # time=3.49ms 1..3 - ok 5 - nolog Array # time=87.194ms + ok 5 - nolog Array # time=49.489ms 1..5 - ok 1 - log4js connect logger # time=478.863ms + ok 1 - log4js connect logger # time=315.778ms 1..1 - # time=538.664ms -ok 8 - test/tap/connect-nolog-test.js # time=1574.054ms + # time=349.354ms +ok 8 - test/tap/connect-nolog-test.js # time=946.493ms # 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=28.156ms + ok 1 - should send events to its own appender # time=18.502ms 1..1 - ok 1 - cluster worker # time=65.975ms + ok 1 - cluster worker # time=41.212ms 1..1 - # time=98.306ms + # time=63.404ms # 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=35.414ms + ok 1 - only master events should be logged # time=15.297ms 1..1 - ok 1 - cluster master # time=89.319ms + ok 1 - cluster master # time=39.396ms 1..1 - # time=117.515ms -ok 9 - test/tap/disable-cluster-test.js # time=2602.997ms + # time=53.94ms +ok 9 - test/tap/disable-cluster-test.js # time=1562.818ms # 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=118.917ms + ok 1 - with default fileSyncAppender settings # time=68.596ms # 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=24.505ms + ok 1 - log file should only contain the second message # time=21.532ms # Subtest: there should be two test files ok 1 - should be equal 1..1 - ok 2 - there should be two test files # time=8.295ms + ok 2 - there should be two test files # time=5.987ms 1..2 - ok 2 - with a max file size and no backups # time=90.482ms + ok 2 - with a max file size and no backups # time=51.392ms # 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=28.042ms + ok 1 - log file should only contain the second message # time=17.525ms # Subtest: there should be two test files ok 1 - should be equal 1..1 - ok 2 - there should be two test files # time=6.905ms + ok 2 - there should be two test files # time=5.806ms 1..2 - ok 3 - with a max file size in unit mode and no backups # time=72.181ms + ok 3 - with a max file size in unit mode and no backups # time=47.227ms # 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=21.81ms + ok 1 - the log files # time=15.961ms 1..1 - ok 4 - with a max file size and 2 backups # time=52.084ms + ok 4 - with a max file size and 2 backups # time=35.126ms # Subtest: configure with fileSyncAppender ok 1 - should match pattern provided ok 2 - should be equal 1..2 - ok 5 - configure with fileSyncAppender # time=9.516ms + ok 5 - configure with fileSyncAppender # time=6.367ms # Subtest: test options ok 1 - should match pattern provided 1..1 - ok 6 - test options # time=13.948ms + ok 6 - test options # time=5.592ms 1..6 - ok 1 - log4js fileSyncAppender # time=397.796ms + ok 1 - log4js fileSyncAppender # time=245.237ms 1..1 - # time=415.333ms -ok 11 - test/tap/fileSyncAppender-test.js # time=1597.871ms + # time=259.063ms +ok 11 - test/tap/fileSyncAppender-test.js # time=969.752ms # 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=26.911ms + ok 1 - should apply level colour codes to output # time=16.286ms # 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=8.41ms + ok 2 - should support the console.log format for the message # time=5.679ms 1..2 - ok 1 - colouredLayout # time=84.55ms + ok 1 - colouredLayout # time=54.248ms # 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=14.468ms + ok 5 - with passed augmented errors # time=9.623ms 1..5 - ok 2 - messagePassThroughLayout # time=70.015ms + ok 2 - messagePassThroughLayout # time=39.014ms # 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=22.694ms + ok 2 - should output a stacktrace, message if the event has an error attached # time=16.614ms # 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=6.097ms + ok 3 - should output any extra data in the log event as util.inspect strings # time=3.983ms 1..3 - ok 3 - basicLayout # time=63.202ms + ok 3 - basicLayout # time=43.934ms # 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=6.487ms + ok 1 - should output just the first element of the log data # time=4.208ms 1..1 - ok 4 - dummyLayout # time=24.313ms + ok 4 - dummyLayout # time=16.058ms # 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=9.031ms + ok 1 - should default to "time logLevel loggerName - message" # time=6.241ms # Subtest: %r should output time only ok 1 - should be equal 1..1 - ok 2 - %r should output time only # time=5.44ms + ok 2 - %r should output time only # time=3.422ms # Subtest: %p should output the log level ok 1 - should be equal 1..1 - ok 3 - %p should output the log level # time=9.498ms + ok 3 - %p should output the log level # time=3.286ms # Subtest: %c should output the log category ok 1 - should be equal 1..1 - ok 4 - %c should output the log category # time=4.668ms + ok 4 - %c should output the log category # time=3.286ms # Subtest: %m should output the log data ok 1 - should be equal 1..1 - ok 5 - %m should output the log data # time=4.55ms + ok 5 - %m should output the log data # time=3.273ms # Subtest: %n should output a new line ok 1 - should be equal 1..1 - ok 6 - %n should output a new line # time=4.956ms + ok 6 - %n should output a new line # time=3.416ms # Subtest: %h should output hostname ok 1 - should be equal 1..1 - ok 7 - %h should output hostname # time=5.289ms + ok 7 - %h should output hostname # time=3.422ms # Subtest: %z should output pid ok 1 - should be equal 1..1 - ok 8 - %z should output pid # time=5.273ms + ok 8 - %z should output pid # time=3.443ms # 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=5.352ms + ok 9 - %z should pick up pid from log event if present # time=3.304ms # 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=4.656ms + ok 10 - %y should output pid (was cluster info) # time=3.391ms # 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.003ms + ok 11 - %c should handle category names like java-style package names # time=8.558ms # 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=5.924ms + ok 12 - %d should output the date in ISO8601 format # time=3.662ms # 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=12.936ms + ok 13 - %d should allow for format specification # time=8.922ms # Subtest: %% should output % ok 1 - should be equal 1..1 - ok 14 - %% should output % # time=4.993ms + ok 14 - %% should output % # time=3.421ms # Subtest: %f should output filename ok 1 - should be equal 1..1 - ok 15 - %f should output filename # time=5.109ms + ok 15 - %f should output filename # time=3.567ms # 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=13.309ms + ok 16 - %f should handle filename depth # time=9.103ms # 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=9.1ms + ok 17 - %f should accept truncation and padding # time=6.697ms # Subtest: %l should output line number ok 1 - should be equal 1..1 - ok 18 - %l should output line number # time=4.835ms + ok 18 - %l should output line number # time=3.489ms # 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=16.908ms + ok 19 - %l should accept truncation and padding # time=10.316ms # Subtest: %o should output column postion ok 1 - should be equal 1..1 - ok 20 - %o should output column postion # time=5.401ms + ok 20 - %o should output column postion # time=3.152ms # 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=8.962ms + ok 21 - %o should accept truncation and padding # time=6.739ms # Subtest: %s should output stack ok 1 - should be equal 1..1 - ok 22 - %s should output stack # time=4.825ms + ok 22 - %s should output stack # time=2.995ms # 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=4.86ms + ok 23 - %f should output empty string when fileName not exist # time=2.947ms # 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=4.52ms + ok 24 - %l should output empty string when lineNumber not exist # time=2.91ms # 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=6.973ms + ok 25 - %o should output empty string when columnNumber not exist # time=2.917ms # 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=4.572ms + ok 26 - %s should output empty string when callStack not exist # time=2.898ms # 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=4.601ms + ok 27 - should output anything not preceded by % as literal # time=2.929ms # 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=4.712ms + ok 28 - should output the original string if no replacer matches the token # time=2.941ms # Subtest: should handle complicated patterns ok 1 - should be equal 1..1 - ok 29 - should handle complicated patterns # time=4.693ms + ok 29 - should handle complicated patterns # time=3.077ms # 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=10.908ms + ok 30 - should truncate fields if specified # time=7.359ms # 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=16.339ms + ok 31 - should pad fields if specified # time=8.717ms # Subtest: %[%r%] should output colored time ok 1 - should be equal 1..1 - ok 32 - %[%r%] should output colored time # time=8.398ms + ok 32 - %[%r%] should output colored time # time=3.014ms # 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=4.678ms + ok 33 - %x{testString} should output the string stored in tokens # time=2.882ms # 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=4.101ms + ok 34 - %x{testFunction} should output the result of the function stored in tokens # time=2.778ms # 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=17.712ms + ok 35 - %x{doesNotExist} should output the string stored in tokens # time=2.776ms # 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=4.241ms + ok 36 - %x{fnThatUsesLogEvent} should be able to use the logEvent # time=2.877ms # 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=4ms + ok 37 - %x should output the string stored in tokens # time=2.706ms # 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=4.504ms + ok 38 - %X{testString} should output the string stored in tokens # time=2.891ms # 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=3.919ms + ok 39 - %X{testFunction} should output the result of the function stored in tokens # time=2.779ms # 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=4.446ms + ok 40 - %X{doesNotExist} should output the string stored in tokens # time=2.723ms # 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=34.761ms + ok 41 - %X{fnThatUsesLogEvent} should be able to use the logEvent # time=8.528ms # 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=4.178ms + ok 42 - %X should output the string stored in tokens # time=2.871ms 1..42 - ok 5 - patternLayout # time=979.935ms + ok 5 - patternLayout # time=570.463ms # 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=16.875ms + ok 1 - should have a maker for each layout # time=9.989ms # 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=6.038ms + ok 2 - layout pattern maker should pass pattern and tokens to layout from config # time=3.757ms 1..2 - ok 6 - layout makers # time=53.972ms + ok 6 - layout makers # time=34.244ms # 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.071ms + ok 1 - should be able to add a layout # time=5.067ms 1..1 - ok 7 - add layout # time=45.876ms + ok 7 - add layout # time=13.59ms 1..7 - ok 1 - log4js layouts # time=1434.303ms + ok 1 - log4js layouts # time=842.388ms 1..1 - # time=1525.664ms -ok 12 - test/tap/layouts-test.js # time=2500.435ms + # time=890.567ms +ok 12 - test/tap/layouts-test.js # time=1470.708ms # 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=252.98ms + ok 1 - should work # time=157.979ms 1..1 - ok 1 - Accessing things setup in configure before configure is called # time=285.01ms + ok 1 - Accessing things setup in configure before configure is called # time=177.654ms 1..1 - # time=315.64ms -ok 13 - test/tap/levels-before-configure-test.js # time=1290.203ms + # time=197.688ms +ok 13 - test/tap/levels-before-configure-test.js # time=762.601ms # 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=32.68ms + ok 1 - should define some levels # time=23.226ms # 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=45.323ms + ok 2 - ALL # time=33.824ms # 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=40.998ms + ok 3 - TRACE # time=29.185ms # 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=36.555ms + ok 4 - DEBUG # time=29.088ms # 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=35.657ms + ok 5 - INFO # time=27.649ms # 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=64.764ms + ok 6 - WARN # time=30.403ms # 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=37.771ms + ok 7 - ERROR # time=22.651ms # 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=53.302ms + ok 8 - FATAL # time=27.353ms # 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=52.116ms + ok 9 - MARK # time=20.187ms # 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=37.081ms + ok 10 - OFF # time=27.743ms 1..10 - ok 1 - values # time=629.389ms + ok 1 - values # time=388.008ms # 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=8.22ms + ok 2 - isGreaterThanOrEqualTo # time=5.55ms # 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=16.401ms + ok 3 - isLessThanOrEqualTo # time=7.317ms # Subtest: isEqualTo ok 1 - expect truthy value ok 2 - expect truthy value ok 3 - expect truthy value 1..3 - ok 4 - isEqualTo # time=8.457ms + ok 4 - isEqualTo # time=3.281ms # 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=6.994ms + ok 5 - getLevel # time=4.647ms 1..5 - ok 1 - levels # time=737.839ms + ok 1 - levels # time=449.467ms 1..1 - # time=797.179ms -ok 14 - test/tap/levels-test.js # time=1845.57ms + # time=479.078ms +ok 14 - test/tap/levels-test.js # time=1073.095ms # 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.171ms + ok 1 - should only pass log events greater than or equal to its own level # time=16.272ms 1..1 - ok 1 - appender # time=303.69ms + ok 1 - appender # time=188.678ms # 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=33.279ms + ok 1 - tmp-tests.log should contain all log messages # time=18.103ms # 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=10.176ms + ok 2 - tmp-tests-warnings.log should contain only error and warning logs # time=6.195ms # 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=8.893ms + ok 3 - tmp-tests-debugs.log should contain only trace and debug logs # time=5.679ms 1..3 - ok 2 - configure # time=603.612ms + ok 2 - configure # time=567.782ms 1..2 - ok 1 - log4js logLevelFilter # time=943.144ms + ok 1 - log4js logLevelFilter # time=780.569ms 1..1 - # time=963.518ms -ok 15 - test/tap/logLevelFilter-test.js # time=1936.078ms + # time=795.229ms +ok 15 - test/tap/logLevelFilter-test.js # time=1364.462ms # 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=29.27ms + ok 1 - should export new log level in levels module # time=16.731ms 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=84.355ms + ok 1 - creating a new log level # time=42.59ms # 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=11.446ms + ok 1 - should export new log level to levels module # time=7.511ms 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=35.35ms + ok 2 - creating a new log level with underscores # time=23.523ms # 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=14.854ms + ok 1 - should show log events with new log level # time=10.221ms 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=39.731ms + ok 3 - creating log events containing newly created log level # time=27.526ms # 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=40.337ms + ok 4 - creating a new log level with incorrect parameters # time=18.699ms # 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=6.368ms + ok 5 - calling log with an undefined log level # time=4.252ms # 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=7.031ms + ok 6 - creating a new level with an existing level name # time=4.724ms 1..6 - ok 1 - ../../lib/logger # time=293.466ms + ok 1 - ../../lib/logger # time=177.928ms 1..1 - # time=326.114ms -ok 16 - test/tap/newLevel-test.js # time=1473.775ms + # time=199.398ms +ok 16 - test/tap/newLevel-test.js # time=916.72ms # 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=37.942ms + ok 1 - appender should exclude events that match the regexp string # time=25.486ms # 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=15.045ms + ok 2 - appender should exclude events that match the regexp string contained in the array # time=8.492ms # 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=11.338ms + ok 3 - appender should evaluate the regexp using incase sentitive option # time=6.001ms # 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=10.868ms + ok 4 - appender should skip the match in case of empty or null regexp # time=5.666ms # 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=10.007ms + ok 5 - appender should exclude the events that contains digits # time=5.119ms # 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=9.362ms + ok 6 - appender should exclude not valid events according to the documentation # time=5.602ms 1..6 - ok 1 - log4js noLogFilter # time=208.32ms + ok 1 - log4js noLogFilter # time=113.226ms 1..1 - # time=242.13ms -ok 17 - test/tap/noLogFilter-test.js # time=1424.937ms + # time=133.652ms +ok 17 - test/tap/noLogFilter-test.js # time=845.233ms # 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=60.836ms + ok 1 - Should emit pause event and resume when logging in a file with high frequency # time=35.882ms # 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=17.13ms + ok 2 - Should emit pause event and resume when logging in a date file with high frequency # time=11.085ms 1..2 - ok 1 - Drain event test # time=132.247ms + ok 1 - Drain event test # time=78.199ms 1..1 - # time=241.296ms -ok 18 - test/tap/pause-test.js # time=1448.005ms + # time=137.231ms +ok 18 - test/tap/pause-test.js # time=846.415ms # 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=19.975ms + ok 1 - should not get any events when turned off # time=13.121ms # 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=9.446ms + ok 2 - should get events on app instance 0 # time=5.966ms # 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=4.575ms + ok 3 - should not get events on app instance 1 # time=2.839ms 1..3 - ok 1 - PM2 Support # time=96.313ms + ok 1 - PM2 Support # time=55.639ms 1..1 - # time=120.128ms -ok 19 - test/tap/pm2-support-test.js # time=9588.078ms + # time=71.14ms +ok 19 - test/tap/pm2-support-test.js # time=8402.821ms # 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=277.695ms + ok 1 - should listen for TCP messages and re-send via process.send # time=233.46ms 1..1 - ok 1 - TCP Server # time=312.242ms + ok 1 - TCP Server # time=255.993ms 1..1 - # time=332.676ms -ok 20 - test/tap/server-test.js # time=1515.297ms + # time=269.475ms +ok 20 - test/tap/server-test.js # time=995.084ms # 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=31.853ms + ok 1 - should convert string to level correctly # time=19.941ms # 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=13.977ms + ok 2 - should also accept a Level # time=11.052ms 1..2 - ok 1 - is called with a Trace as string # time=92.456ms + ok 1 - is called with a Trace as string # time=62.746ms # 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=13.343ms + ok 1 - should convert string to level correctly # time=10.138ms # 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=11.461ms + ok 2 - should also accept a Level # time=9.042ms 1..2 - ok 2 - is called with a Debug as string # time=58.486ms + ok 2 - is called with a Debug as string # time=42.753ms # 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=10.649ms + ok 1 - should convert string to level correctly # time=8.854ms # 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=12.17ms + ok 2 - should also accept a Level # time=8.66ms 1..2 - ok 3 - is called with a Info as string # time=54.165ms + ok 3 - is called with a Info as string # time=39.373ms # 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=10.01ms + ok 1 - should convert string to level correctly # time=8.887ms # 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=11.661ms + ok 2 - should also accept a Level # time=8.528ms 1..2 - ok 4 - is called with a Warn as string # time=57.368ms + ok 4 - is called with a Warn as string # time=44.051ms # 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=9.488ms + ok 1 - should convert string to level correctly # time=8.925ms # 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=9.409ms + ok 2 - should also accept a Level # time=8.263ms 1..2 - ok 5 - is called with a Error as string # time=48.881ms + ok 5 - is called with a Error as string # time=37.87ms # 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=9.536ms + ok 1 - should convert string to level correctly # time=8.627ms # 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=9.136ms + ok 2 - should also accept a Level # time=8.593ms 1..2 - ok 6 - is called with a Fatal as string # time=43.904ms + ok 6 - is called with a Fatal as string # time=35.564ms 1..6 - ok 1 - log4js setLevel # time=447.029ms + ok 1 - log4js setLevel # time=319.137ms 1..1 - # time=501.54ms -ok 21 - test/tap/setLevel-asymmetry-test.js # time=1685.348ms + # time=351.32ms +ok 21 - test/tap/setLevel-asymmetry-test.js # time=1086.33ms # 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=295.251ms + ok 1 - Stacktraces from errors in different VM context # time=174.681ms 1..1 - # time=329.058ms -ok 22 - test/tap/stacktraces-test.js # time=1269.941ms + # time=196.117ms +ok 22 - test/tap/stacktraces-test.js # time=766.089ms # 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=34.742ms + ok 1 - check logger levels # time=20.642ms 1..1 - ok 1 - loggers created after levels configuration is loaded # time=79.946ms + ok 1 - loggers created after levels configuration is loaded # time=45.858ms # 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=19.121ms + ok 1 - should still get new levels # time=12.917ms 1..1 - ok 2 - loggers created before levels configuration is loaded # time=41.802ms + ok 2 - loggers created before levels configuration is loaded # time=27.666ms # 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=5.898ms + ok 1 - should inherit parent level # time=3.903ms # 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=6.693ms + ok 2 - changing child level should not change parent level # time=4.659ms 1..2 - ok 3 - setting level on subcategories should not set parent level # time=57.116ms + ok 3 - setting level on subcategories should not set parent level # time=34.031ms 1..3 - ok 1 - subcategories # time=226.576ms + ok 1 - subcategories # time=138.098ms 1..1 - # time=258.133ms -ok 23 - test/tap/subcategories-test.js # time=1421.515ms + # time=158.767ms +ok 23 - test/tap/subcategories-test.js # time=867.238ms # 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=239.645ms + ok 1 - Default Configuration # time=165.171ms # 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=117.814ms + ok 2 - Custom EndMessage String # time=112.103ms # 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=123.754ms + ok 3 - Custom Layout # time=110.294ms 1..3 - ok 1 - TCP Appender # time=519.517ms + ok 1 - TCP Appender # time=413.333ms 1..1 - # time=547.764ms -ok 24 - test/tap/tcp-appender-test.js # time=1812.093ms + # time=427.593ms +ok 24 - test/tap/tcp-appender-test.js # time=1136.583ms 1..24 # skip: 1 -# time=46088.885ms +# time=30809.606ms 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/9612/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/9612/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/12414 and its subdirectories -I: Current time: Wed Sep 14 13:18:27 -12 2022 -I: pbuilder-time-stamp: 1663204707 +I: removing directory /srv/workspace/pbuilder/9612 and its subdirectories +I: Current time: Wed Oct 18 21:42:41 +14 2023 +I: pbuilder-time-stamp: 1697614961