Diff of the two buildlogs: -- --- b1/build.log 2024-01-08 08:32:21.169532566 +0000 +++ b2/build.log 2024-01-08 08:33:20.830844954 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun Feb 9 02:52:40 -12 2025 -I: pbuilder-time-stamp: 1739112760 +I: Current time: Mon Jan 8 22:32:24 +14 2024 +I: pbuilder-time-stamp: 1704702744 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -33,49 +33,81 @@ dpkg-source: info: applying privacy.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2709141/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/402969/tmp/hooks/D01_modify_environment starting +debug: Running on codethink04-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 +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Jan 8 08:32 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/402969/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/402969/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='arm64' - DEBIAN_FRONTEND='noninteractive' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="15" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.15(1)-release' + BUILDDIR=/build/reproducible-path + 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 parallel=12 ' - DISTRIBUTION='bookworm' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='5bab0c8774eb43ad8e3237bd0d574d94' - 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='2709141' - PS1='# ' - PS2='> ' + INVOCATION_ID=e2a05a60c2534dd880e0f09770ae4e96 + 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=402969 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.Pv4RmFgJ/pbuilderrc_tQff --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.Pv4RmFgJ/b1 --logfile b1/build.log node-log4js_6.7.1+~cs8.4.17-1.dsc' - SUDO_GID='109' - SUDO_UID='104' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.104:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.Pv4RmFgJ/pbuilderrc_2APv --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.Pv4RmFgJ/b2 --logfile b2/build.log node-log4js_6.7.1+~cs8.4.17-1.dsc' + SUDO_GID=109 + SUDO_UID=104 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://192.168.101.104:3128 I: uname -a - Linux codethink01-arm64 6.1.0-17-cloud-arm64 #1 SMP Debian 6.1.69-1 (2023-12-30) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-17-cloud-arm64 #1 SMP Debian 6.1.69-1 (2023-12-30) aarch64 GNU/Linux I: ls -l /bin total 7104 -rwxr-xr-x 1 root root 1346480 Apr 23 2023 bash @@ -133,15 +165,15 @@ -rwxr-xr-x 1 root root 68456 Sep 20 2022 readlink -rwxr-xr-x 1 root root 68592 Sep 20 2022 rm -rwxr-xr-x 1 root root 68480 Sep 20 2022 rmdir - -rwxr-xr-x 1 root root 68392 Jul 28 2023 run-parts + -rwxr-xr-x 1 root root 68392 Jul 28 23:46 run-parts -rwxr-xr-x 1 root root 134536 Jan 5 2023 sed - lrwxrwxrwx 1 root root 4 Jan 5 2023 sh -> dash + lrwxrwxrwx 1 root root 9 Jan 8 08:32 sh -> /bin/bash -rwxr-xr-x 1 root root 68432 Sep 20 2022 sleep -rwxr-xr-x 1 root root 134088 Sep 20 2022 stty -rwsr-xr-x 1 root root 133312 Mar 23 2023 su -rwxr-xr-x 1 root root 68448 Sep 20 2022 sync -rwxr-xr-x 1 root root 539896 Apr 6 2023 tar - -rwxr-xr-x 1 root root 67904 Jul 28 2023 tempfile + -rwxr-xr-x 1 root root 67904 Jul 28 23:46 tempfile -rwxr-xr-x 1 root root 134160 Sep 20 2022 touch -rwxr-xr-x 1 root root 68384 Sep 20 2022 true -rwxr-xr-x 1 root root 67664 Mar 23 2023 ulockmgr_server @@ -161,7 +193,7 @@ -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/2709141/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/402969/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -594,7 +626,7 @@ Get: 379 http://deb.debian.org/debian bookworm/main arm64 node-widest-line all 3.1.0-2 [4092 B] Get: 380 http://deb.debian.org/debian bookworm/main arm64 node-ws all 8.11.0+~cs13.7.3-1 [51.1 kB] Get: 381 http://deb.debian.org/debian bookworm/main arm64 node-tap all 16.3.2+ds1+~cs50.8.16-1 [101 kB] -Fetched 44.2 MB in 1s (45.9 MB/s) +Fetched 44.2 MB in 1s (57.0 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package netbase. (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 ... 18171 files and directories currently installed.) @@ -2147,7 +2179,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/node-log4js-6.7.1+~cs8.4.17/ && 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.7.1+~cs8.4.17-1_source.changes +I: user script /srv/workspace/pbuilder/402969/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for bookworm +I: user script /srv/workspace/pbuilder/402969/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/node-log4js-6.7.1+~cs8.4.17/ && 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.7.1+~cs8.4.17-1_source.changes dpkg-buildpackage: info: source package node-log4js dpkg-buildpackage: info: source version 6.7.1+~cs8.4.17-1 dpkg-buildpackage: info: source distribution unstable @@ -2185,9 +2221,9 @@ dh_auto_test --buildsystem=nodejs ln -s ../. node_modules/log4js /bin/sh -ex debian/tests/pkg-js/test -+ grep -v -f debian/tests/test_excluded -+ 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/file-descriptor-leak-test.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/recordingAppender-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 +++ 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/file-descriptor-leak-test.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/recordingAppender-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 +++ grep -v -f debian/tests/test_excluded + tap -R tap --no-cov --bail test/tap/LoggingEvent-test.js test/tap/cluster-test.js test/tap/configuration-inheritance-test.js test/tap/connect-logger-test.js test/tap/disable-cluster-test.js test/tap/file-descriptor-leak-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/pause-test.js test/tap/pm2-support-test.js test/tap/recordingAppender-test.js test/tap/server-test.js test/tap/setLevel-asymmetry-test.js test/tap/stacktraces-test.js test/tap/subcategories-test.js TAP version 13 # Subtest: test/tap/LoggingEvent-test.js @@ -2195,7 +2231,7 @@ # Subtest: should throw error for invalid location ok 1 - Invalid location type passed to LoggingEvent constructor 1..1 - ok 1 - should throw error for invalid location # time=27.262ms + ok 1 - should throw error for invalid location # time=6.542ms # Subtest: should serialise to flatted ok 1 - should be equal @@ -2213,7 +2249,7 @@ ok 13 - should be equal ok 14 - should be equal 1..14 - ok 2 - should serialise to flatted # time=34.689ms + ok 2 - should serialise to flatted # time=11.258ms # Subtest: should deserialise from flatted ok 1 - type is LoggingEvent @@ -2239,7 +2275,7 @@ ok 21 - should be equal ok 22 - should be equal 1..22 - ok 3 - should deserialise from flatted # time=25.584ms + ok 3 - should deserialise from flatted # time=7.015ms # Subtest: Should correct construct with/without location info ok 1 - should be equal @@ -2259,7 +2295,7 @@ ok 15 - should be equal ok 16 - should be equal 1..16 - ok 4 - Should correct construct with/without location info # time=22.988ms + ok 4 - Should correct construct with/without location info # time=2.628ms # Subtest: Should contain class, method and alias names ok 1 - should be equal @@ -2271,19 +2307,19 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 5 - Should contain class, method and alias names # time=2.147ms + ok 5 - Should contain class, method and alias names # time=1.968ms # Subtest: Should correctly serialize and deserialize ok 1 - should match pattern provided 1..1 - ok 6 - Should correctly serialize and deserialize # time=30.782ms + ok 6 - Should correctly serialize and deserialize # time=12.541ms 1..6 - ok 1 - LoggingEvent # time=281.952ms + ok 1 - LoggingEvent # time=96.182ms 1..1 - # time=314.396ms -ok 1 - test/tap/LoggingEvent-test.js # time=314.396ms + # time=114.029ms +ok 1 - test/tap/LoggingEvent-test.js # time=114.029ms # Subtest: test/tap/cluster-test.js # Subtest: cluster master @@ -2306,23 +2342,23 @@ ok 16 - should be equal ok 17 - should be equal 1..17 - ok 1 - events should be logged # time=45.464ms + ok 1 - events should be logged # time=17.943ms 1..1 - ok 1 - cluster master # time=115.18ms + ok 1 - cluster master # time=42.422ms # Subtest: cluster worker # Subtest: logger should get correct config ok 1 - should be equal 1..1 - ok 1 - logger should get correct config # time=0.938ms + ok 1 - logger should get correct config # time=0.956ms 1..1 - ok 2 - cluster worker # time=13.57ms + ok 2 - cluster worker # time=6.107ms 1..2 - # time=187.404ms -ok 2 - test/tap/cluster-test.js # time=187.404ms + # time=57.551ms +ok 2 - test/tap/cluster-test.js # time=57.551ms # Subtest: test/tap/configuration-inheritance-test.js # Subtest: log4js category inherit all appenders from direct parent @@ -2333,7 +2369,7 @@ ok 4 - inherited stdout2 ok 5 - child level overrides parent 1..5 - ok 1 - should inherit appenders from direct parent # time=55.458ms + ok 1 - should inherit appenders from direct parent # time=17.886ms # Subtest: multiple children should inherit config from shared parent ok 1 - inherited 1 appender @@ -2345,7 +2381,7 @@ ok 7 - kept stdout2 ok 8 - inherited parent level 1..8 - ok 2 - multiple children should inherit config from shared parent # time=16.697ms + ok 2 - multiple children should inherit config from shared parent # time=8.13ms # Subtest: should inherit appenders from multiple parents ok 1 - expect truthy value @@ -2357,7 +2393,7 @@ ok 7 - inherited stdout1 ok 8 - kept stdout2 1..8 - ok 3 - should inherit appenders from multiple parents # time=41.127ms + ok 3 - should inherit appenders from multiple parents # time=15.759ms # Subtest: should inherit appenders from deep parent with missing direct parent ok 1 - expect truthy value @@ -2367,14 +2403,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=19.771ms + ok 4 - should inherit appenders from deep parent with missing direct parent # time=3.477ms # 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=3.501ms + ok 5 - should deal gracefully with missing parent # time=3.457ms # Subtest: should not get duplicate appenders if parent has the same one ok 1 - expect truthy value @@ -2382,14 +2418,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=11.95ms + ok 6 - should not get duplicate appenders if parent has the same one # time=3.773ms # 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=16.001ms + ok 7 - inherit:falses should disable inheritance # time=3.232ms # Subtest: inheritance should stop if direct parent has inherit off ok 1 - expect truthy value @@ -2399,20 +2435,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.166ms + ok 8 - inheritance should stop if direct parent has inherit off # time=4.042ms # 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=3.028ms + ok 9 - should inherit level when it is missing # time=2.987ms 1..9 - ok 1 - log4js category inherit all appenders from direct parent # time=358.192ms + ok 1 - log4js category inherit all appenders from direct parent # time=122.748ms 1..1 - # time=390.817ms -ok 3 - test/tap/configuration-inheritance-test.js # time=390.817ms + # time=130.889ms +ok 3 - test/tap/configuration-inheritance-test.js # time=130.889ms # Subtest: test/tap/connect-logger-test.js # Subtest: log4js connect logger @@ -2421,7 +2457,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=17.956ms + ok 2 - should take a log4js logger and return a "connect logger" # time=5.725ms # Subtest: log events ok 1 - type is Array @@ -2432,13 +2468,13 @@ ok 6 - should match pattern provided ok 7 - should match pattern provided 1..7 - ok 3 - log events # time=269.461ms + ok 3 - log events # time=11.776ms # 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=2.892ms + ok 4 - log events with level below logging level # time=2.795ms # Subtest: log events with non-default level and custom format ok 1 - type is Array @@ -2446,7 +2482,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=16.682ms + ok 5 - log events with non-default level and custom format # time=2.4ms # Subtest: adding multiple loggers should only log once ok 1 - type is Array @@ -2454,96 +2490,96 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 6 - adding multiple loggers should only log once # time=2.673ms + ok 6 - adding multiple loggers should only log once # time=2.899ms 1..6 - ok 1 - getConnectLoggerModule # time=454.946ms + ok 1 - getConnectLoggerModule # time=59.469ms # Subtest: logger with options as string ok 1 - should be equal 1..1 - ok 2 - logger with options as string # time=1.026ms + ok 2 - logger with options as string # time=0.933ms # 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=10.212ms + ok 1 - should use INFO for 2xx # time=1.466ms # Subtest: should use WARN for 3xx ok 1 - expect truthy value 1..1 - ok 2 - should use WARN for 3xx # time=0.927ms + ok 2 - should use WARN for 3xx # time=0.753ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=0.75ms + ok 3 - should use ERROR for 4xx # time=0.939ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=0.755ms + ok 4 - should use ERROR for 5xx # time=0.893ms 1..4 - ok 3 - auto log levels # time=49.418ms + ok 3 - auto log levels # time=33.897ms # 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=1.116ms + ok 1 - should use DEBUG for 2xx # time=0.901ms # 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=0.982ms + ok 2 - should use WARN for 3xx, DEBUG for 304 # time=0.874ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=1.333ms + ok 3 - should use ERROR for 4xx # time=1.3ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=0.866ms + ok 4 - should use ERROR for 5xx # time=0.793ms 1..4 - ok 4 - logger with status code rules applied # time=57.553ms + ok 4 - logger with status code rules applied # time=17.198ms # Subtest: format using a function ok 1 - should be equal 1..1 - ok 5 - format using a function # time=13.255ms + ok 5 - format using a function # time=1.122ms # 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=1.006ms + ok 6 - format using a function that also uses tokens # time=1.119ms # 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=0.929ms + ok 7 - format using a function, but do not log anything if the function returns nothing # time=0.94ms # Subtest: format that includes request headers ok 1 - should be equal 1..1 - ok 8 - format that includes request headers # time=1.675ms + ok 8 - format that includes request headers # time=1.102ms # Subtest: format that includes response headers ok 1 - should be equal 1..1 - ok 9 - format that includes response headers # time=0.985ms + ok 9 - format that includes response headers # time=1.018ms # 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=13.093ms + ok 10 - url token should check originalUrl and url # time=0.838ms # Subtest: log events with custom token ok 1 - type is Array @@ -2551,7 +2587,7 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 11 - log events with custom token # time=5.751ms + ok 11 - log events with custom token # time=1.366ms # Subtest: log events with custom override token ok 1 - type is Array @@ -2559,32 +2595,32 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 12 - log events with custom override token # time=5.7ms + ok 12 - log events with custom override token # time=1.308ms # 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=10.054ms + ok 13 - log events with custom format # time=1.262ms # 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=1.024ms + ok 14 - handle weird old node versions where socket contains socket # time=0.858ms # Subtest: handles as soon as any of the events end/finish/error/close triggers (only once) ok 1 - should be equal ok 2 - should be equal ok 3 - should be equal 1..3 - ok 15 - handles as soon as any of the events end/finish/error/close triggers (only once) # time=9.519ms + ok 15 - handles as soon as any of the events end/finish/error/close triggers (only once) # time=1.207ms 1..15 - ok 1 - log4js connect logger # time=805.066ms + ok 1 - log4js connect logger # time=191.339ms 1..1 - # time=832.84ms -ok 4 - test/tap/connect-logger-test.js # time=832.84ms + # time=197.412ms +ok 4 - test/tap/connect-logger-test.js # time=197.412ms # Subtest: test/tap/disable-cluster-test.js # Subtest: cluster worker @@ -2595,13 +2631,13 @@ ok 4 - type is Error ok 5 - should match pattern provided 1..5 - ok 1 - should send events to its own appender # time=24.893ms + ok 1 - should send events to its own appender # time=11.741ms 1..1 - ok 1 - cluster worker # time=74.311ms + ok 1 - cluster worker # time=37.924ms 1..1 - # time=114.656ms + # time=60.153ms # Subtest: cluster master # Subtest: only master events should be logged # Subtest @@ -2611,38 +2647,38 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 1 - only master events should be logged # time=23.746ms + ok 1 - only master events should be logged # time=9.563ms 1..1 - ok 1 - cluster master # time=71.827ms + ok 1 - cluster master # time=39.968ms 1..1 - # time=113.175ms -ok 5 - test/tap/disable-cluster-test.js # time=113.175ms + # time=45.646ms +ok 5 - test/tap/disable-cluster-test.js # time=45.646ms # Subtest: test/tap/file-descriptor-leak-test.js # Subtest: multiple log4js configure fd leak test # Subtest: initial log4js configure to increase file descriptor count ok 1 - file descriptor count should increase by 11 after 1st configure() call 1..1 - ok 1 - initial log4js configure to increase file descriptor count # time=278.716ms + ok 1 - initial log4js configure to increase file descriptor count # time=233.124ms # Subtest: repeated log4js configure to not increase file descriptor count ok 1 - file descriptor count should be identical after repeated configure() calls 1..1 - ok 2 - repeated log4js configure to not increase file descriptor count # time=264.397ms + ok 2 - repeated log4js configure to not increase file descriptor count # time=224.45ms # Subtest: file descriptor count should return back to initial count ok 1 - file descriptor count should be back to initial 1..1 - ok 3 - file descriptor count should return back to initial count # time=205.929ms + ok 3 - file descriptor count should return back to initial count # time=203.969ms 1..3 - ok 1 - multiple log4js configure fd leak test # time=828.031ms + ok 1 - multiple log4js configure fd leak test # time=696.711ms 1..1 - # time=851.98ms -ok 6 - test/tap/file-descriptor-leak-test.js # time=851.98ms + # time=704.157ms +ok 6 - test/tap/file-descriptor-leak-test.js # time=704.157ms # Subtest: test/tap/layouts-test.js # Subtest: log4js layouts @@ -2650,15 +2686,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=63.544ms + ok 1 - should apply level colour codes to output # time=7.968ms # 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=2.117ms + ok 2 - should support the console.log format for the message # time=2.255ms 1..2 - ok 1 - colouredLayout # time=121.674ms + ok 1 - colouredLayout # time=29.33ms # Subtest: messagePassThroughLayout ok 1 - should take a logevent and output only the message @@ -2670,10 +2706,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=13.201ms + ok 5 - with passed augmented errors # time=4.855ms 1..5 - ok 2 - messagePassThroughLayout # time=45.82ms + ok 2 - messagePassThroughLayout # time=16.38ms # Subtest: basicLayout ok 1 - should be equal @@ -2691,75 +2727,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=16.097ms + ok 2 - should output a stacktrace, message if the event has an error attached # time=4.787ms # 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.09ms + ok 3 - should output any extra data in the log event as util.inspect strings # time=2.23ms 1..3 - ok 3 - basicLayout # time=56.461ms + ok 3 - basicLayout # time=32.054ms # 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=0.982ms + ok 1 - should output just the first element of the log data # time=0.912ms 1..1 - ok 4 - dummyLayout # time=17.797ms + ok 4 - dummyLayout # time=11.147ms # 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=2.489ms + ok 1 - should default to "time logLevel loggerName - message" # time=1.88ms # Subtest: %r should output time only ok 1 - should be equal 1..1 - ok 2 - %r should output time only # time=1.222ms + ok 2 - %r should output time only # time=0.96ms # Subtest: %p should output the log level ok 1 - should be equal 1..1 - ok 3 - %p should output the log level # time=0.838ms + ok 3 - %p should output the log level # time=1.067ms # Subtest: %c should output the log category ok 1 - should be equal 1..1 - ok 4 - %c should output the log category # time=0.865ms + ok 4 - %c should output the log category # time=0.821ms # Subtest: %m should output the log data ok 1 - should be equal 1..1 - ok 5 - %m should output the log data # time=0.869ms + ok 5 - %m should output the log data # time=0.916ms # Subtest: %n should output a new line ok 1 - should be equal 1..1 - ok 6 - %n should output a new line # time=0.844ms + ok 6 - %n should output a new line # time=0.833ms # Subtest: %h should output hostname ok 1 - should be equal 1..1 - ok 7 - %h should output hostname # time=5.117ms + ok 7 - %h should output hostname # time=0.919ms # Subtest: %z should output pid ok 1 - should be equal 1..1 - ok 8 - %z should output pid # time=9.247ms + ok 8 - %z should output pid # time=0.869ms # 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=9.163ms + ok 9 - %z should pick up pid from log event if present # time=0.921ms # 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=9.055ms + ok 10 - %y should output pid (was cluster info) # time=0.912ms # Subtest: %c should handle category names like java-style package names ok 1 - should be equal @@ -2769,12 +2805,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=1.813ms + ok 11 - %c should handle category names like java-style package names # time=1.81ms # 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=1.024ms + ok 12 - %d should output the date in ISO8601 format # time=1.146ms # Subtest: %d should allow for format specification ok 1 - should be equal @@ -2789,17 +2825,17 @@ ok 10 - should be equal ok 11 - should be equal 1..11 - ok 13 - %d should allow for format specification # time=8.788ms + ok 13 - %d should allow for format specification # time=4.851ms # Subtest: %% should output % ok 1 - should be equal 1..1 - ok 14 - %% should output % # time=1.118ms + ok 14 - %% should output % # time=1.158ms # Subtest: %f should output filename ok 1 - should be equal 1..1 - ok 15 - %f should output filename # time=1ms + ok 15 - %f should output filename # time=0.948ms # Subtest: %f should handle filename depth ok 1 - should be equal @@ -2809,7 +2845,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 16 - %f should handle filename depth # time=10.565ms + ok 16 - %f should handle filename depth # time=1.787ms # Subtest: %f should accept truncation and padding ok 1 - should be equal @@ -2817,12 +2853,12 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 17 - %f should accept truncation and padding # time=2.029ms + ok 17 - %f should accept truncation and padding # time=1.674ms # Subtest: %l should output line number ok 1 - should be equal 1..1 - ok 18 - %l should output line number # time=0.847ms + ok 18 - %l should output line number # time=0.733ms # Subtest: %l should accept truncation and padding ok 1 - should be equal @@ -2830,12 +2866,12 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 19 - %l should accept truncation and padding # time=1.303ms + ok 19 - %l should accept truncation and padding # time=1.083ms # Subtest: %o should output column postion ok 1 - should be equal 1..1 - ok 20 - %o should output column postion # time=0.777ms + ok 20 - %o should output column postion # time=0.793ms # Subtest: %o should accept truncation and padding ok 1 - should be equal @@ -2844,48 +2880,48 @@ ok 4 - should be equal ok 5 - should be equal 1..5 - ok 21 - %o should accept truncation and padding # time=1.329ms + ok 21 - %o should accept truncation and padding # time=1.146ms # Subtest: %s should output stack ok 1 - should be equal 1..1 - ok 22 - %s should output stack # time=0.786ms + ok 22 - %s should output stack # time=0.743ms # 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=0.846ms + ok 23 - %f should output empty string when fileName not exist # time=0.725ms # 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=0.749ms + ok 24 - %l should output empty string when lineNumber not exist # time=0.726ms # 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=0.724ms + ok 25 - %o should output empty string when columnNumber not exist # time=0.681ms # 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=8.868ms + ok 26 - %s should output empty string when callStack not exist # time=0.717ms # 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=5.026ms + ok 27 - should output anything not preceded by % as literal # time=0.731ms # 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=0.685ms + ok 28 - should output the original string if no replacer matches the token # time=0.716ms # Subtest: should handle complicated patterns ok 1 - should be equal ok 2 - should be equal 1..2 - ok 29 - should handle complicated patterns # time=1.229ms + ok 29 - should handle complicated patterns # time=1.165ms # Subtest: should truncate fields if specified ok 1 - should be equal @@ -2895,7 +2931,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 30 - should truncate fields if specified # time=1.417ms + ok 30 - should truncate fields if specified # time=1.346ms # Subtest: should pad fields if specified ok 1 - should be equal @@ -2907,105 +2943,105 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 31 - should pad fields if specified # time=1.587ms + ok 31 - should pad fields if specified # time=1.648ms # Subtest: %[%r%] should output colored time ok 1 - should be equal 1..1 - ok 32 - %[%r%] should output colored time # time=0.759ms + ok 32 - %[%r%] should output colored time # time=0.768ms # 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=0.772ms + ok 33 - %x{testString} should output the string stored in tokens # time=0.851ms # 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=0.704ms + ok 34 - %x{testFunction} should output the result of the function stored in tokens # time=0.794ms # 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=0.734ms + ok 35 - %x{doesNotExist} should output the string stored in tokens # time=0.7ms # 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=0.795ms + ok 36 - %x{fnThatUsesLogEvent} should be able to use the logEvent # time=0.784ms # 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=8.965ms + ok 37 - %x should output the string stored in tokens # time=0.794ms # 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=0.802ms + ok 38 - %X{testString} should output the string stored in tokens # time=0.745ms # 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=0.784ms + ok 39 - %X{testFunction} should output the result of the function stored in tokens # time=0.717ms # 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=0.729ms + ok 40 - %X{doesNotExist} should output the string stored in tokens # time=0.669ms # 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=0.77ms + ok 41 - %X{fnThatUsesLogEvent} should be able to use the logEvent # time=0.695ms # 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=0.823ms + ok 42 - %X should output the string stored in tokens # time=1.056ms # Subtest: %M should output function name ok 1 - should be equal 1..1 - ok 43 - %M should output function name # time=0.826ms + ok 43 - %M should output function name # time=0.812ms # Subtest: %M should output empty string when functionName not exist ok 1 - should be equal 1..1 - ok 44 - %M should output empty string when functionName not exist # time=4.996ms + ok 44 - %M should output empty string when functionName not exist # time=0.775ms # Subtest: %C should output class name ok 1 - should be equal 1..1 - ok 45 - %C should output class name # time=0.756ms + ok 45 - %C should output class name # time=0.789ms # Subtest: %C should output empty string when className not exist ok 1 - should be equal 1..1 - ok 46 - %C should output empty string when className not exist # time=0.685ms + ok 46 - %C should output empty string when className not exist # time=0.769ms # Subtest: %A should output function alias ok 1 - should be equal 1..1 - ok 47 - %A should output function alias # time=0.81ms + ok 47 - %A should output function alias # time=0.772ms # Subtest: %A should output empty string when functionAlias not exist ok 1 - should be equal 1..1 - ok 48 - %A should output empty string when functionAlias not exist # time=0.761ms + ok 48 - %A should output empty string when functionAlias not exist # time=0.702ms # Subtest: %F should output fully qualified caller name ok 1 - should be equal 1..1 - ok 49 - %F should output fully qualified caller name # time=0.8ms + ok 49 - %F should output fully qualified caller name # time=0.822ms # Subtest: %F should output empty string when callerName not exist ok 1 - should be equal 1..1 - ok 50 - %F should output empty string when callerName not exist # time=0.78ms + ok 50 - %F should output empty string when callerName not exist # time=0.735ms 1..50 - ok 5 - patternLayout # time=670.206ms + ok 5 - patternLayout # time=223.606ms # Subtest: layout makers # Subtest: should have a maker for each layout @@ -3016,16 +3052,16 @@ ok 5 - expect truthy value ok 6 - expect truthy value 1..6 - ok 1 - should have a maker for each layout # time=11.492ms + ok 1 - should have a maker for each layout # time=2.188ms # 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.115ms + ok 2 - layout pattern maker should pass pattern and tokens to layout from config # time=0.983ms 1..2 - ok 6 - layout makers # time=27.01ms + ok 6 - layout makers # time=9.212ms # Subtest: add layout # Subtest: should be able to add a layout @@ -3033,17 +3069,17 @@ ok 2 - expect truthy value ok 3 - should be equal 1..3 - ok 1 - should be able to add a layout # time=9.7ms + ok 1 - should be able to add a layout # time=1.242ms 1..1 - ok 7 - add layout # time=12.841ms + ok 7 - add layout # time=4.183ms 1..7 - ok 1 - log4js layouts # time=1073.137ms + ok 1 - log4js layouts # time=370.037ms 1..1 - # time=1112.332ms -ok 7 - test/tap/layouts-test.js # time=1112.332ms + # time=377.393ms +ok 7 - test/tap/layouts-test.js # time=377.393ms # Subtest: test/tap/levels-before-configure-test.js # Subtest: Accessing things setup in configure before configure is called @@ -3051,14 +3087,14 @@ ok 1 - expect truthy value ok 2 - expect truthy value 1..2 - ok 1 - should work # time=320.683ms + ok 1 - should work # time=84.144ms 1..1 - ok 1 - Accessing things setup in configure before configure is called # time=391.059ms + ok 1 - Accessing things setup in configure before configure is called # time=109.635ms 1..1 - # time=447.286ms -ok 8 - test/tap/levels-before-configure-test.js # time=447.286ms + # time=129.669ms +ok 8 - test/tap/levels-before-configure-test.js # time=129.669ms # Subtest: test/tap/levels-test.js # Subtest: levels @@ -3074,7 +3110,7 @@ ok 8 - expect truthy value ok 9 - expect truthy value 1..9 - ok 1 - should define some levels # time=27.66ms + ok 1 - should define some levels # time=12.884ms # Subtest: ALL ok 1 - expect truthy value @@ -3104,7 +3140,7 @@ ok 25 - expect falsey value ok 26 - expect falsey value 1..26 - ok 2 - ALL # time=8.728ms + ok 2 - ALL # time=9.689ms # Subtest: TRACE ok 1 - expect truthy value @@ -3134,7 +3170,7 @@ ok 25 - expect falsey value ok 26 - expect falsey value 1..26 - ok 3 - TRACE # time=11.044ms + ok 3 - TRACE # time=5.673ms # Subtest: DEBUG ok 1 - expect truthy value @@ -3163,7 +3199,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 4 - DEBUG # time=9.154ms + ok 4 - DEBUG # time=4.462ms # Subtest: INFO ok 1 - expect truthy value @@ -3192,7 +3228,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 5 - INFO # time=21.491ms + ok 5 - INFO # time=3.731ms # Subtest: WARN ok 1 - expect truthy value @@ -3220,7 +3256,7 @@ ok 23 - expect falsey value ok 24 - expect falsey value 1..24 - ok 6 - WARN # time=21.411ms + ok 6 - WARN # time=4.615ms # Subtest: ERROR ok 1 - expect truthy value @@ -3249,7 +3285,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 7 - ERROR # time=10.42ms + ok 7 - ERROR # time=10.102ms # Subtest: FATAL ok 1 - expect truthy value @@ -3278,7 +3314,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 8 - FATAL # time=13.267ms + ok 8 - FATAL # time=3.171ms # Subtest: MARK ok 1 - expect truthy value @@ -3307,7 +3343,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 9 - MARK # time=9.667ms + ok 9 - MARK # time=3.474ms # Subtest: OFF ok 1 - expect falsey value @@ -3336,10 +3372,10 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 10 - OFF # time=3.508ms + ok 10 - OFF # time=3.072ms 1..10 - ok 1 - values # time=280.053ms + ok 1 - values # time=132.591ms # Subtest: isGreaterThanOrEqualTo ok 1 - expect truthy value @@ -3351,7 +3387,7 @@ ok 7 - expect falsey value ok 8 - expect falsey value 1..8 - ok 2 - isGreaterThanOrEqualTo # time=3.727ms + ok 2 - isGreaterThanOrEqualTo # time=1.428ms # Subtest: isLessThanOrEqualTo ok 1 - expect falsey value @@ -3363,14 +3399,14 @@ ok 7 - expect truthy value ok 8 - expect truthy value 1..8 - ok 3 - isLessThanOrEqualTo # time=7.254ms + ok 3 - isLessThanOrEqualTo # time=1.362ms # Subtest: isEqualTo ok 1 - expect truthy value ok 2 - expect truthy value ok 3 - expect truthy value 1..3 - ok 4 - isEqualTo # time=0.939ms + ok 4 - isEqualTo # time=0.875ms # Subtest: getLevel ok 1 - should be equal @@ -3380,14 +3416,14 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 5 - getLevel # time=1.487ms + ok 5 - getLevel # time=1.453ms 1..5 - ok 1 - levels # time=355.44ms + ok 1 - levels # time=172.36ms 1..1 - # time=370.166ms -ok 9 - test/tap/levels-test.js # time=370.166ms + # time=179.337ms +ok 9 - test/tap/levels-test.js # time=179.337ms # Subtest: test/tap/logLevelFilter-test.js # Subtest: log4js logLevelFilter @@ -3397,36 +3433,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=25.181ms + ok 1 - should only pass log events greater than or equal to its own level # time=8.691ms 1..1 - ok 1 - appender # time=280.778ms + ok 1 - appender # time=97.116ms # Subtest: configure # Subtest: logLevelFilter.log should contain all log messages ok 1 - should be equivalent 1..1 - ok 1 - logLevelFilter.log should contain all log messages # time=32.692ms + ok 1 - logLevelFilter.log should contain all log messages # time=14.18ms # Subtest: logLevelFilter-warnings.log should contain only error and warning logs ok 1 - should be equivalent 1..1 - ok 2 - logLevelFilter-warnings.log should contain only error and warning logs # time=7.824ms + ok 2 - logLevelFilter-warnings.log should contain only error and warning logs # time=6.207ms # Subtest: logLevelFilter-debugs.log should contain only trace and debug logs ok 1 - should be equivalent 1..1 - ok 3 - logLevelFilter-debugs.log should contain only trace and debug logs # time=2.495ms + ok 3 - logLevelFilter-debugs.log should contain only trace and debug logs # time=3.043ms 1..3 - ok 2 - configure # time=315.024ms + ok 2 - configure # time=251.412ms 1..2 - ok 1 - log4js logLevelFilter # time=674.409ms + ok 1 - log4js logLevelFilter # time=381.412ms 1..1 - # time=679.331ms -ok 10 - test/tap/logLevelFilter-test.js # time=679.331ms + # time=386.651ms +ok 10 - test/tap/logLevelFilter-test.js # time=386.651ms # Subtest: test/tap/pause-test.js # Subtest: Drain event test @@ -3434,20 +3470,20 @@ ok 1 - log4js:pause, true ok 2 - log4js:pause, false 1..2 - ok 1 - Should emit pause event and resume when logging in a file with high frequency # time=233.856ms + ok 1 - Should emit pause event and resume when logging in a file with high frequency # time=53.222ms # Subtest: Should emit pause event and resume when logging in a date file with high frequency ok 1 - log4js:pause, true ok 2 - log4js:pause, false 1..2 - ok 2 - Should emit pause event and resume when logging in a date file with high frequency # time=42.26ms + ok 2 - Should emit pause event and resume when logging in a date file with high frequency # time=15.109ms 1..2 - ok 1 - Drain event test # time=338.813ms + ok 1 - Drain event test # time=97.422ms 1..1 - # time=348.358ms -ok 11 - test/tap/pause-test.js # time=348.358ms + # time=103.153ms +ok 11 - test/tap/pause-test.js # time=103.153ms # Subtest: test/tap/pm2-support-test.js # Subtest: PM2 Support @@ -3455,26 +3491,26 @@ ok 1 - expect falsey value ok 2 - expect falsey value 1..2 - ok 1 - should not get any events when turned off # time=12.038ms + ok 1 - should not get any events when turned off # time=8.668ms # 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=4.646ms + ok 2 - should get events on app instance 0 # time=4.977ms # 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=5.891ms + ok 3 - should not get events on app instance 1 # time=1.703ms 1..3 - ok 1 - PM2 Support # time=90.759ms + ok 1 - PM2 Support # time=62.111ms 1..1 - # time=105.23ms -ok 12 - test/tap/pm2-support-test.js # time=105.23ms + # time=85.566ms +ok 12 - test/tap/pm2-support-test.js # time=85.566ms # Subtest: test/tap/recordingAppender-test.js # Subtest: recording appender @@ -3483,11 +3519,11 @@ ok 3 - should be equal ok 4 - There should be 0 recorded events 1..4 - ok 1 - recording appender # time=35.103ms + ok 1 - recording appender # time=16.302ms 1..1 - # time=81.806ms -ok 13 - test/tap/recordingAppender-test.js # time=81.806ms + # time=37.642ms +ok 13 - test/tap/recordingAppender-test.js # time=37.642ms # Subtest: test/tap/server-test.js # Subtest: TCP Server @@ -3498,7 +3534,7 @@ 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=372.822ms + ok 1 - should listen for TCP messages and re-send via process.send # time=260.45ms # Subtest: sending incomplete messages in chunks ok 1 - should be equal @@ -3507,14 +3543,14 @@ ok 4 - should match pattern provided ok 5 - should match pattern provided 1..5 - ok 2 - sending incomplete messages in chunks # time=751.701ms + ok 2 - sending incomplete messages in chunks # time=723.259ms 1..2 - ok 1 - TCP Server # time=1194.156ms + ok 1 - TCP Server # time=1009.303ms 1..1 - # time=1208.407ms -ok 14 - test/tap/server-test.js # time=1208.407ms + # time=1015.936ms +ok 14 - test/tap/server-test.js # time=1015.936ms # Subtest: test/tap/setLevel-asymmetry-test.js # Subtest: log4js setLevel @@ -3527,7 +3563,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=29.792ms + ok 1 - should convert string to level correctly # time=12.159ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3537,10 +3573,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=15.336ms + ok 2 - should also accept a Level # time=4.186ms 1..2 - ok 1 - is called with a Trace as string # time=81.85ms + ok 1 - is called with a Trace as string # time=34.234ms # Subtest: is called with a Debug as string # Subtest: should convert string to level correctly @@ -3551,7 +3587,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=2.073ms + ok 1 - should convert string to level correctly # time=2.201ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3561,10 +3597,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=2.041ms + ok 2 - should also accept a Level # time=1.862ms 1..2 - ok 2 - is called with a Debug as string # time=22.825ms + ok 2 - is called with a Debug as string # time=11.701ms # Subtest: is called with a Info as string # Subtest: should convert string to level correctly @@ -3575,7 +3611,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=5.597ms + ok 1 - should convert string to level correctly # time=1.287ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3585,10 +3621,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=5.81ms + ok 2 - should also accept a Level # time=1.523ms 1..2 - ok 3 - is called with a Info as string # time=19.182ms + ok 3 - is called with a Info as string # time=10.651ms # Subtest: is called with a Warn as string # Subtest: should convert string to level correctly @@ -3599,7 +3635,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=1.364ms + ok 1 - should convert string to level correctly # time=1.391ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3609,10 +3645,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=9.868ms + ok 2 - should also accept a Level # time=1.318ms 1..2 - ok 4 - is called with a Warn as string # time=23.786ms + ok 4 - is called with a Warn as string # time=10.874ms # Subtest: is called with a Error as string # Subtest: should convert string to level correctly @@ -3623,7 +3659,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=5.833ms + ok 1 - should convert string to level correctly # time=1.333ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3633,10 +3669,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=1.319ms + ok 2 - should also accept a Level # time=1.289ms 1..2 - ok 5 - is called with a Error as string # time=34.559ms + ok 5 - is called with a Error as string # time=12.407ms # Subtest: is called with a Fatal as string # Subtest: should convert string to level correctly @@ -3647,7 +3683,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=1.388ms + ok 1 - should convert string to level correctly # time=1.224ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3657,27 +3693,27 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=1.661ms + ok 2 - should also accept a Level # time=1.331ms 1..2 - ok 6 - is called with a Fatal as string # time=21.62ms + ok 6 - is called with a Fatal as string # time=8.645ms 1..6 - ok 1 - log4js setLevel # time=291.91ms + ok 1 - log4js setLevel # time=131.01ms 1..1 - # time=309.012ms -ok 15 - test/tap/setLevel-asymmetry-test.js # time=309.012ms + # time=151.958ms +ok 15 - test/tap/setLevel-asymmetry-test.js # time=151.958ms # 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=267.037ms + ok 1 - Stacktraces from errors in different VM context # time=100.298ms 1..1 - # time=325.438ms -ok 16 - test/tap/stacktraces-test.js # time=325.438ms + # time=121.237ms +ok 16 - test/tap/stacktraces-test.js # time=121.237ms # Subtest: test/tap/subcategories-test.js # Subtest: subcategories @@ -3692,10 +3728,10 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 1 - check logger levels # time=24.851ms + ok 1 - check logger levels # time=12.993ms 1..1 - ok 1 - loggers created after levels configuration is loaded # time=52.976ms + ok 1 - loggers created after levels configuration is loaded # time=26.29ms # Subtest: loggers created before levels configuration is loaded # Subtest: should still get new levels @@ -3708,35 +3744,35 @@ ok 7 - should be equivalent ok 8 - should be equivalent 1..8 - ok 1 - should still get new levels # time=10.891ms + ok 1 - should still get new levels # time=7.055ms 1..1 - ok 2 - loggers created before levels configuration is loaded # time=51.594ms + ok 2 - loggers created before levels configuration is loaded # time=19.788ms # 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=1.674ms + ok 1 - should inherit parent level # time=1.649ms # 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=1.771ms + ok 2 - changing child level should not change parent level # time=1.791ms 1..2 - ok 3 - setting level on subcategories should not set parent level # time=31.776ms + ok 3 - setting level on subcategories should not set parent level # time=13.727ms 1..3 - ok 1 - subcategories # time=190.977ms + ok 1 - subcategories # time=91.017ms 1..1 - # time=202.337ms -ok 17 - test/tap/subcategories-test.js # time=202.337ms + # time=102.875ms +ok 17 - test/tap/subcategories-test.js # time=102.875ms 1..17 -# time=11358.914ms +# time=8814.809ms Removing node_modules/log4js create-stamp debian/debhelper-build-stamp dh_prep @@ -3850,14 +3886,14 @@ make[1]: Entering directory '/build/reproducible-path/node-log4js-6.7.1+~cs8.4.17' dh_installdocs dh_nodejs_autodocs - install -m 644 rfdc/readme.md debian/node-log4js/usr/share/doc//node-rfdc/ - ln -s ../node-log4js/copyright debian/node-log4js/usr/share/doc//node-rfdc/ - install -m 644 date-format/README.md debian/node-log4js/usr/share/doc//node-date-format/ - install -m 644 date-format/CHANGELOG.md debian/node-log4js/usr/share/doc//node-date-format/changelog - ln -s ../node-log4js/copyright debian/node-log4js/usr/share/doc//node-date-format/ install -m 644 streamroller/README.md debian/node-log4js/usr/share/doc//node-streamroller/ install -m 644 streamroller/CHANGELOG.md debian/node-log4js/usr/share/doc//node-streamroller/changelog ln -s ../node-log4js/copyright debian/node-log4js/usr/share/doc//node-streamroller/ + install -m 644 date-format/README.md debian/node-log4js/usr/share/doc//node-date-format/ + install -m 644 date-format/CHANGELOG.md debian/node-log4js/usr/share/doc//node-date-format/changelog + ln -s ../node-log4js/copyright debian/node-log4js/usr/share/doc//node-date-format/ + install -m 644 rfdc/readme.md debian/node-log4js/usr/share/doc//node-rfdc/ + ln -s ../node-log4js/copyright debian/node-log4js/usr/share/doc//node-rfdc/ make[1]: Leaving directory '/build/reproducible-path/node-log4js-6.7.1+~cs8.4.17' dh_installchangelogs dh_installexamples @@ -3881,12 +3917,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/402969/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/402969/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/2709141 and its subdirectories -I: Current time: Sun Feb 9 02:55:19 -12 2025 -I: pbuilder-time-stamp: 1739112919 +I: removing directory /srv/workspace/pbuilder/402969 and its subdirectories +I: Current time: Mon Jan 8 22:33:19 +14 2024 +I: pbuilder-time-stamp: 1704702799