Diff of the two buildlogs: -- --- b1/build.log 2024-05-16 12:47:45.181022867 +0000 +++ b2/build.log 2024-05-16 12:48:48.102346022 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed Jun 18 07:09:55 -12 2025 -I: pbuilder-time-stamp: 1750273795 +I: Current time: Fri May 17 02:47:48 +14 2024 +I: pbuilder-time-stamp: 1715863668 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -33,52 +33,84 @@ dpkg-source: info: applying privacy.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2845148/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/1961825/tmp/hooks/D01_modify_environment starting +debug: Running on codethink02-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 May 16 12:47 /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/1961825/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/1961825/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]="21" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.21(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='trixie' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='55b5afda8fc74556ba30e9817ea4314c' - 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='2845148' - PS1='# ' - PS2='> ' + INVOCATION_ID=d681ce0a3de442fd81c3d9b27345615a + 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=1961825 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.agmBKtL9/pbuilderrc_vycW --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.agmBKtL9/b1 --logfile b1/build.log node-log4js_6.9.1+~cs8.4.19-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.4: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.agmBKtL9/pbuilderrc_IPYh --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.agmBKtL9/b2 --logfile b2/build.log node-log4js_6.9.1+~cs8.4.19-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.4:3128 I: uname -a - Linux codethink03-arm64 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Jun 18 17:46 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/2845148/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 15 11:24 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/1961825/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -508,7 +540,7 @@ Get: 376 http://deb.debian.org/debian trixie/main arm64 node-widest-line all 3.1.0-2 [4092 B] Get: 377 http://deb.debian.org/debian trixie/main arm64 node-ws all 8.11.0+~cs13.7.3-2 [51.1 kB] Get: 378 http://deb.debian.org/debian trixie/main arm64 node-tap all 16.3.7+ds1+~cs50.9.19-4 [103 kB] -Fetched 45.2 MB in 0s (119 MB/s) +Fetched 45.2 MB in 0s (125 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 ... 19744 files and directories currently installed.) @@ -2049,7 +2081,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/node-log4js-6.9.1+~cs8.4.19/ && 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.9.1+~cs8.4.19-1_source.changes +I: user script /srv/workspace/pbuilder/1961825/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/1961825/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/node-log4js-6.9.1+~cs8.4.19/ && 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.9.1+~cs8.4.19-1_source.changes dpkg-buildpackage: info: source package node-log4js dpkg-buildpackage: info: source version 6.9.1+~cs8.4.19-1 dpkg-buildpackage: info: source distribution unstable @@ -2088,9 +2124,9 @@ dh_auto_test --buildsystem=nodejs ln -s ../. node_modules/log4js /bin/sh -ex debian/tests/pkg-js/test -+ + xargs grep -L sandboxed -ls test/tap/LoggingEvent-test.js test/tap/appender-dependencies-test.js+ grep -v -f debian/tests/test_excluded - 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 @@ -2098,7 +2134,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=14.824ms + ok 1 - should throw error for invalid location # time=7.123ms # Subtest: should serialise to flatted ok 1 - should be equal @@ -2116,7 +2152,7 @@ ok 13 - should be equal ok 14 - should be equal 1..14 - ok 2 - should serialise to flatted # time=22.024ms + ok 2 - should serialise to flatted # time=10.572ms # Subtest: should deserialise from flatted ok 1 - type is LoggingEvent @@ -2142,7 +2178,7 @@ ok 21 - should be equal ok 22 - should be equal 1..22 - ok 3 - should deserialise from flatted # time=6.967ms + ok 3 - should deserialise from flatted # time=7.498ms # Subtest: Should correct construct with/without location info ok 1 - should be equal @@ -2162,7 +2198,7 @@ ok 15 - should be equal ok 16 - should be equal 1..16 - ok 4 - Should correct construct with/without location info # time=2.533ms + ok 4 - Should correct construct with/without location info # time=2.707ms # Subtest: Should contain class, method and alias names ok 1 - should be equal @@ -2174,19 +2210,19 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 5 - Should contain class, method and alias names # time=1.987ms + ok 5 - Should contain class, method and alias names # time=3.691ms # Subtest: Should correctly serialize and deserialize ok 1 - should match pattern provided 1..1 - ok 6 - Should correctly serialize and deserialize # time=7.513ms + ok 6 - Should correctly serialize and deserialize # time=7.091ms 1..6 - ok 1 - LoggingEvent # time=107.924ms + ok 1 - LoggingEvent # time=83.277ms 1..1 - # time=132.833ms -ok 1 - test/tap/LoggingEvent-test.js # time=132.833ms + # time=103.757ms +ok 1 - test/tap/LoggingEvent-test.js # time=103.757ms # Subtest: test/tap/cluster-test.js # Subtest: cluster master @@ -2209,23 +2245,23 @@ ok 16 - should be equal ok 17 - should be equal 1..17 - ok 1 - events should be logged # time=16.988ms + ok 1 - events should be logged # time=16.535ms 1..1 - ok 1 - cluster master # time=53.836ms + ok 1 - cluster master # time=43.24ms # 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.915ms + ok 1 - logger should get correct config # time=1.009ms 1..1 - ok 2 - cluster worker # time=5.304ms + ok 2 - cluster worker # time=9.192ms 1..2 - # time=102.618ms -ok 2 - test/tap/cluster-test.js # time=102.618ms + # time=71.274ms +ok 2 - test/tap/cluster-test.js # time=71.274ms # Subtest: test/tap/configuration-inheritance-test.js # Subtest: log4js category inherit all appenders from direct parent @@ -2236,7 +2272,7 @@ ok 4 - inherited stdout2 ok 5 - child level overrides parent 1..5 - ok 1 - should inherit appenders from direct parent # time=16.069ms + ok 1 - should inherit appenders from direct parent # time=18.33ms # Subtest: multiple children should inherit config from shared parent ok 1 - inherited 1 appender @@ -2248,7 +2284,7 @@ ok 7 - kept stdout2 ok 8 - inherited parent level 1..8 - ok 2 - multiple children should inherit config from shared parent # time=8.391ms + ok 2 - multiple children should inherit config from shared parent # time=8.263ms # Subtest: should inherit appenders from multiple parents ok 1 - expect truthy value @@ -2260,7 +2296,7 @@ ok 7 - inherited stdout1 ok 8 - kept stdout2 1..8 - ok 3 - should inherit appenders from multiple parents # time=9.349ms + ok 3 - should inherit appenders from multiple parents # time=7.336ms # Subtest: should inherit appenders from deep parent with missing direct parent ok 1 - expect truthy value @@ -2270,14 +2306,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=3.226ms + ok 4 - should inherit appenders from deep parent with missing direct parent # time=3.513ms # 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.292ms + ok 5 - should deal gracefully with missing parent # time=3.543ms # Subtest: should not get duplicate appenders if parent has the same one ok 1 - expect truthy value @@ -2285,14 +2321,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=3.471ms + ok 6 - should not get duplicate appenders if parent has the same one # time=3.829ms # Subtest: inherit:falses should disable inheritance ok 1 - expect truthy value ok 2 - inherited no appender ok 3 - kept stdout2 1..3 - ok 7 - inherit:falses should disable inheritance # time=3.115ms + ok 7 - inherit:falses should disable inheritance # time=7.856ms # Subtest: inheritance should stop if direct parent has inherit off ok 1 - expect truthy value @@ -2302,20 +2338,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=15.466ms + ok 8 - inheritance should stop if direct parent has inherit off # time=13.384ms # 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=2.915ms + ok 9 - should inherit level when it is missing # time=4.055ms 1..9 - ok 1 - log4js category inherit all appenders from direct parent # time=134.291ms + ok 1 - log4js category inherit all appenders from direct parent # time=135.552ms 1..1 - # time=147.022ms -ok 3 - test/tap/configuration-inheritance-test.js # time=147.022ms + # time=144.574ms +ok 3 - test/tap/configuration-inheritance-test.js # time=144.574ms # Subtest: test/tap/connect-logger-test.js # Subtest: log4js connect logger @@ -2324,7 +2360,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=8.278ms + ok 2 - should take a log4js logger and return a "connect logger" # time=7.807ms # Subtest: log events ok 1 - type is Array @@ -2335,13 +2371,13 @@ ok 6 - should match pattern provided ok 7 - should match pattern provided 1..7 - ok 3 - log events # time=10.746ms + ok 3 - log events # time=15.833ms # Subtest: log events with level below logging level ok 1 - type is Array ok 2 - should be equal 1..2 - ok 4 - log events with level below logging level # time=3.142ms + ok 4 - log events with level below logging level # time=4.267ms # Subtest: log events with non-default level and custom format ok 1 - type is Array @@ -2349,7 +2385,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=2.243ms + ok 5 - log events with non-default level and custom format # time=3.159ms # Subtest: adding multiple loggers should only log once ok 1 - type is Array @@ -2357,96 +2393,96 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 6 - adding multiple loggers should only log once # time=4.834ms + ok 6 - adding multiple loggers should only log once # time=2.557ms 1..6 - ok 1 - getConnectLoggerModule # time=67.926ms + ok 1 - getConnectLoggerModule # time=82.184ms # Subtest: logger with options as string ok 1 - should be equal 1..1 - ok 2 - logger with options as string # time=0.995ms + ok 2 - logger with options as string # time=1.015ms # 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=1.391ms + ok 1 - should use INFO for 2xx # time=1.949ms # Subtest: should use WARN for 3xx ok 1 - expect truthy value 1..1 - ok 2 - should use WARN for 3xx # time=0.786ms + ok 2 - should use WARN for 3xx # time=0.806ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=0.729ms + ok 3 - should use ERROR for 4xx # time=1.146ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=1.008ms + ok 4 - should use ERROR for 5xx # time=0.709ms 1..4 - ok 3 - auto log levels # time=39.406ms + ok 3 - auto log levels # time=31.755ms # 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=0.899ms + ok 1 - should use DEBUG for 2xx # time=0.856ms # 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.844ms + ok 2 - should use WARN for 3xx, DEBUG for 304 # time=0.825ms # Subtest: should use ERROR for 4xx ok 1 - expect truthy value 1..1 - ok 3 - should use ERROR for 4xx # time=1.2ms + ok 3 - should use ERROR for 4xx # time=1.205ms # Subtest: should use ERROR for 5xx ok 1 - expect truthy value 1..1 - ok 4 - should use ERROR for 5xx # time=0.756ms + ok 4 - should use ERROR for 5xx # time=0.738ms 1..4 - ok 4 - logger with status code rules applied # time=17.027ms + ok 4 - logger with status code rules applied # time=16.492ms # Subtest: format using a function ok 1 - should be equal 1..1 - ok 5 - format using a function # time=1.025ms + ok 5 - format using a function # time=1.042ms # 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=0.996ms + ok 6 - format using a function that also uses tokens # time=0.976ms # 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.928ms + ok 7 - format using a function, but do not log anything if the function returns nothing # time=0.884ms # Subtest: format that includes request headers ok 1 - should be equal 1..1 - ok 8 - format that includes request headers # time=1.157ms + ok 8 - format that includes request headers # time=1.011ms # Subtest: format that includes response headers ok 1 - should be equal 1..1 - ok 9 - format that includes response headers # time=1.108ms + ok 9 - format that includes response headers # time=1.046ms # 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=0.923ms + ok 10 - url token should check originalUrl and url # time=0.805ms # Subtest: log events with custom token ok 1 - type is Array @@ -2454,7 +2490,7 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 11 - log events with custom token # time=1.375ms + ok 11 - log events with custom token # time=1.397ms # Subtest: log events with custom override token ok 1 - type is Array @@ -2462,32 +2498,32 @@ ok 3 - expect truthy value ok 4 - should be equal 1..4 - ok 12 - log events with custom override token # time=1.363ms + ok 12 - log events with custom override token # time=1.318ms # 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=1.246ms + ok 13 - log events with custom format # time=1.211ms # 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=0.87ms + ok 14 - handle weird old node versions where socket contains socket # time=0.866ms # 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=1.126ms + ok 15 - handles as soon as any of the events end/finish/error/close triggers (only once) # time=1.106ms 1..15 - ok 1 - log4js connect logger # time=234.915ms + ok 1 - log4js connect logger # time=207.767ms 1..1 - # time=241.881ms -ok 4 - test/tap/connect-logger-test.js # time=241.881ms + # time=214.016ms +ok 4 - test/tap/connect-logger-test.js # time=214.016ms # Subtest: test/tap/disable-cluster-test.js # Subtest: cluster worker @@ -2498,13 +2534,13 @@ ok 4 - type is Error ok 5 - should match pattern provided 1..5 - ok 1 - should send events to its own appender # time=19.519ms + ok 1 - should send events to its own appender # time=12.309ms 1..1 - ok 1 - cluster worker # time=66.54ms + ok 1 - cluster worker # time=38.922ms 1..1 - # time=103.63ms + # time=59.648ms # Subtest: cluster master # Subtest: only master events should be logged # Subtest @@ -2514,38 +2550,38 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 1 - only master events should be logged # time=10.216ms + ok 1 - only master events should be logged # time=9.049ms 1..1 - ok 1 - cluster master # time=46.55ms + ok 1 - cluster master # time=33.591ms 1..1 - # time=96.158ms -ok 5 - test/tap/disable-cluster-test.js # time=96.158ms + # time=49.887ms +ok 5 - test/tap/disable-cluster-test.js # time=49.887ms # 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=240.8ms + ok 1 - initial log4js configure to increase file descriptor count # time=234.022ms # 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=243.324ms + ok 2 - repeated log4js configure to not increase file descriptor count # time=228.491ms # 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.253ms + ok 3 - file descriptor count should return back to initial count # time=205.344ms 1..3 - ok 1 - multiple log4js configure fd leak test # time=723.759ms + ok 1 - multiple log4js configure fd leak test # time=700.285ms 1..1 - # time=731.341ms -ok 6 - test/tap/file-descriptor-leak-test.js # time=731.341ms + # time=713.749ms +ok 6 - test/tap/file-descriptor-leak-test.js # time=713.749ms # Subtest: test/tap/layouts-test.js # Subtest: log4js layouts @@ -2553,15 +2589,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=7.201ms + ok 1 - should apply level colour codes to output # time=7.902ms # 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.037ms + ok 2 - should support the console.log format for the message # time=2.514ms 1..2 - ok 1 - colouredLayout # time=29.86ms + ok 1 - colouredLayout # time=32.535ms # Subtest: messagePassThroughLayout ok 1 - should take a logevent and output only the message @@ -2573,10 +2609,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=5.1ms + ok 5 - with passed augmented errors # time=6.649ms 1..5 - ok 2 - messagePassThroughLayout # time=15.872ms + ok 2 - messagePassThroughLayout # time=21.128ms # Subtest: basicLayout ok 1 - should be equal @@ -2594,100 +2630,100 @@ 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=4.141ms + ok 2 - should output a stacktrace, message if the event has an error attached # time=4.415ms # 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=1.678ms + ok 3 - should output any extra data in the log event as util.inspect strings # time=1.865ms 1..3 - ok 3 - basicLayout # time=23.175ms + ok 3 - basicLayout # time=20.239ms # 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.877ms + ok 1 - should output just the first element of the log data # time=1.386ms 1..1 - ok 4 - dummyLayout # time=6.79ms + ok 4 - dummyLayout # time=6.434ms # 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.494ms + ok 1 - should default to "time logLevel loggerName - message" # time=5.665ms # Subtest: %r should output time only ok 1 - should be equal 1..1 - ok 2 - %r should output time only # time=1.093ms + ok 2 - %r should output time only # time=0.86ms # Subtest: %p should output the log level ok 1 - should be equal 1..1 - ok 3 - %p should output the log level # time=0.797ms + ok 3 - %p should output the log level # time=0.78ms # Subtest: %c should output the log category ok 1 - should be equal 1..1 - ok 4 - %c should output the log category # time=0.738ms + ok 4 - %c should output the log category # time=0.786ms # Subtest: %m should output the log data ok 1 - should be equal 1..1 - ok 5 - %m should output the log data # time=0.817ms + ok 5 - %m should output the log data # time=0.882ms # Subtest: %m should apply util.format on data ok 1 - should be equal 1..1 - ok 6 - %m should apply util.format on data # time=0.896ms + ok 6 - %m should apply util.format on data # time=0.935ms # Subtest: %m{1} should only consider data.slice(1) ok 1 - should be equal 1..1 - ok 7 - %m{1} should only consider data.slice(1) # time=0.913ms + ok 7 - %m{1} should only consider data.slice(1) # time=0.952ms # Subtest: %m{0,1} should behave like a dummy layout ok 1 - should be equal 1..1 - ok 8 - %m{0,1} should behave like a dummy layout # time=0.948ms + ok 8 - %m{0,1} should behave like a dummy layout # time=0.986ms # Subtest: %m{1,2} should only consider data.slice(1, 2) ok 1 - should be equal 1..1 - ok 9 - %m{1,2} should only consider data.slice(1, 2) # time=0.965ms + ok 9 - %m{1,2} should only consider data.slice(1, 2) # time=1.027ms # Subtest: %m{0,-1} should consider the whole data except the last element ok 1 - should be equal 1..1 - ok 10 - %m{0,-1} should consider the whole data except the last element # time=0.931ms + ok 10 - %m{0,-1} should consider the whole data except the last element # time=0.968ms # Subtest: %n should output a new line ok 1 - should be equal 1..1 - ok 11 - %n should output a new line # time=0.789ms + ok 11 - %n should output a new line # time=0.849ms # Subtest: %h should output hostname ok 1 - should be equal 1..1 - ok 12 - %h should output hostname # time=5.848ms + ok 12 - %h should output hostname # time=1.068ms # Subtest: %z should output pid ok 1 - should be equal 1..1 - ok 13 - %z should output pid # time=0.756ms + ok 13 - %z should output pid # time=0.833ms # Subtest: %z should pick up pid from log event if present ok 1 - should be equal 1..1 - ok 14 - %z should pick up pid from log event if present # time=0.779ms + ok 14 - %z should pick up pid from log event if present # time=0.901ms # Subtest: %y should output pid (was cluster info) ok 1 - should be equal 1..1 - ok 15 - %y should output pid (was cluster info) # time=0.848ms + ok 15 - %y should output pid (was cluster info) # time=0.901ms # Subtest: %c should handle category names like java-style package names ok 1 - should be equal @@ -2697,12 +2733,12 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 16 - %c should handle category names like java-style package names # time=1.646ms + ok 16 - %c should handle category names like java-style package names # time=1.796ms # Subtest: %d should output the date in ISO8601 format ok 1 - should be equal 1..1 - ok 17 - %d should output the date in ISO8601 format # time=0.956ms + ok 17 - %d should output the date in ISO8601 format # time=1.117ms # Subtest: %d should allow for format specification ok 1 - should be equal @@ -2717,17 +2753,17 @@ ok 10 - should be equal ok 11 - should be equal 1..11 - ok 18 - %d should allow for format specification # time=5.529ms + ok 18 - %d should allow for format specification # time=5.912ms # Subtest: %% should output % ok 1 - should be equal 1..1 - ok 19 - %% should output % # time=0.802ms + ok 19 - %% should output % # time=0.863ms # Subtest: %f should output filename ok 1 - should be equal 1..1 - ok 20 - %f should output filename # time=0.911ms + ok 20 - %f should output filename # time=0.993ms # Subtest: %f should handle filename depth ok 1 - should be equal @@ -2737,7 +2773,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 21 - %f should handle filename depth # time=2.874ms + ok 21 - %f should handle filename depth # time=2.123ms # Subtest: %f should accept truncation and padding ok 1 - should be equal @@ -2745,12 +2781,12 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 22 - %f should accept truncation and padding # time=1.654ms + ok 22 - %f should accept truncation and padding # time=1.89ms # Subtest: %l should output line number ok 1 - should be equal 1..1 - ok 23 - %l should output line number # time=0.741ms + ok 23 - %l should output line number # time=1.465ms # Subtest: %l should accept truncation and padding ok 1 - should be equal @@ -2758,12 +2794,12 @@ ok 3 - should be equal ok 4 - should be equal 1..4 - ok 24 - %l should accept truncation and padding # time=1.79ms + ok 24 - %l should accept truncation and padding # time=1.197ms # Subtest: %o should output column postion ok 1 - should be equal 1..1 - ok 25 - %o should output column postion # time=1.283ms + ok 25 - %o should output column postion # time=0.813ms # Subtest: %o should accept truncation and padding ok 1 - should be equal @@ -2772,48 +2808,48 @@ ok 4 - should be equal ok 5 - should be equal 1..5 - ok 26 - %o should accept truncation and padding # time=1.914ms + ok 26 - %o should accept truncation and padding # time=1.932ms # Subtest: %s should output stack ok 1 - should be equal 1..1 - ok 27 - %s should output stack # time=2.909ms + ok 27 - %s should output stack # time=0.855ms # Subtest: %f should output empty string when fileName not exist ok 1 - should be equal 1..1 - ok 28 - %f should output empty string when fileName not exist # time=0.694ms + ok 28 - %f should output empty string when fileName not exist # time=0.766ms # Subtest: %l should output empty string when lineNumber not exist ok 1 - should be equal 1..1 - ok 29 - %l should output empty string when lineNumber not exist # time=0.745ms + ok 29 - %l should output empty string when lineNumber not exist # time=0.719ms # Subtest: %o should output empty string when columnNumber not exist ok 1 - should be equal 1..1 - ok 30 - %o should output empty string when columnNumber not exist # time=0.698ms + ok 30 - %o should output empty string when columnNumber not exist # time=0.788ms # Subtest: %s should output empty string when callStack not exist ok 1 - should be equal 1..1 - ok 31 - %s should output empty string when callStack not exist # time=0.699ms + ok 31 - %s should output empty string when callStack not exist # time=0.761ms # Subtest: should output anything not preceded by % as literal ok 1 - should be equal 1..1 - ok 32 - should output anything not preceded by % as literal # time=0.717ms + ok 32 - should output anything not preceded by % as literal # time=0.808ms # Subtest: should output the original string if no replacer matches the token ok 1 - should be equal 1..1 - ok 33 - should output the original string if no replacer matches the token # time=0.708ms + ok 33 - should output the original string if no replacer matches the token # time=0.744ms # Subtest: should handle complicated patterns ok 1 - should be equal ok 2 - should be equal 1..2 - ok 34 - should handle complicated patterns # time=1.179ms + ok 34 - should handle complicated patterns # time=1.304ms # Subtest: should truncate fields if specified ok 1 - should be equal @@ -2823,7 +2859,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 35 - should truncate fields if specified # time=1.349ms + ok 35 - should truncate fields if specified # time=1.418ms # Subtest: should pad fields if specified ok 1 - should be equal @@ -2835,105 +2871,105 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 36 - should pad fields if specified # time=3.306ms + ok 36 - should pad fields if specified # time=1.757ms # Subtest: %[%r%] should output colored time ok 1 - should be equal 1..1 - ok 37 - %[%r%] should output colored time # time=0.779ms + ok 37 - %[%r%] should output colored time # time=1.328ms # 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.78ms + ok 38 - %x{testString} should output the string stored in tokens # time=0.805ms # 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.688ms + ok 39 - %x{testFunction} should output the result of the function stored in tokens # time=0.759ms # 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.758ms + ok 40 - %x{doesNotExist} should output the string stored in tokens # time=0.799ms # 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.804ms + ok 41 - %x{fnThatUsesLogEvent} should be able to use the logEvent # time=0.757ms # 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.679ms + ok 42 - %x should output the string stored in tokens # time=0.711ms # Subtest: %X{testString} should output the string stored in tokens ok 1 - should be equal 1..1 - ok 43 - %X{testString} should output the string stored in tokens # time=0.761ms + ok 43 - %X{testString} should output the string stored in tokens # time=0.757ms # Subtest: %X{testFunction} should output the result of the function stored in tokens ok 1 - should be equal 1..1 - ok 44 - %X{testFunction} should output the result of the function stored in tokens # time=0.713ms + ok 44 - %X{testFunction} should output the result of the function stored in tokens # time=0.711ms # Subtest: %X{doesNotExist} should output the string stored in tokens ok 1 - should be equal 1..1 - ok 45 - %X{doesNotExist} should output the string stored in tokens # time=0.693ms + ok 45 - %X{doesNotExist} should output the string stored in tokens # time=0.682ms # Subtest: %X{fnThatUsesLogEvent} should be able to use the logEvent ok 1 - should be equal 1..1 - ok 46 - %X{fnThatUsesLogEvent} should be able to use the logEvent # time=0.708ms + ok 46 - %X{fnThatUsesLogEvent} should be able to use the logEvent # time=0.779ms # Subtest: %X should output the string stored in tokens ok 1 - should be equal 1..1 - ok 47 - %X should output the string stored in tokens # time=0.763ms + ok 47 - %X should output the string stored in tokens # time=1.818ms # Subtest: %M should output function name ok 1 - should be equal 1..1 - ok 48 - %M should output function name # time=1.565ms + ok 48 - %M should output function name # time=0.904ms # Subtest: %M should output empty string when functionName not exist ok 1 - should be equal 1..1 - ok 49 - %M should output empty string when functionName not exist # time=0.759ms + ok 49 - %M should output empty string when functionName not exist # time=0.877ms # Subtest: %C should output class name ok 1 - should be equal 1..1 - ok 50 - %C should output class name # time=0.796ms + ok 50 - %C should output class name # time=0.841ms # Subtest: %C should output empty string when className not exist ok 1 - should be equal 1..1 - ok 51 - %C should output empty string when className not exist # time=0.768ms + ok 51 - %C should output empty string when className not exist # time=0.821ms # Subtest: %A should output function alias ok 1 - should be equal 1..1 - ok 52 - %A should output function alias # time=0.75ms + ok 52 - %A should output function alias # time=0.845ms # Subtest: %A should output empty string when functionAlias not exist ok 1 - should be equal 1..1 - ok 53 - %A should output empty string when functionAlias not exist # time=0.741ms + ok 53 - %A should output empty string when functionAlias not exist # time=0.754ms # Subtest: %F should output fully qualified caller name ok 1 - should be equal 1..1 - ok 54 - %F should output fully qualified caller name # time=0.745ms + ok 54 - %F should output fully qualified caller name # time=0.817ms # Subtest: %F should output empty string when callerName not exist ok 1 - should be equal 1..1 - ok 55 - %F should output empty string when callerName not exist # time=0.74ms + ok 55 - %F should output empty string when callerName not exist # time=0.946ms 1..55 - ok 5 - patternLayout # time=311.183ms + ok 5 - patternLayout # time=271.356ms # Subtest: layout makers # Subtest: should have a maker for each layout @@ -2944,16 +2980,16 @@ ok 5 - expect truthy value ok 6 - expect truthy value 1..6 - ok 1 - should have a maker for each layout # time=2.612ms + ok 1 - should have a maker for each layout # time=2.023ms # 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=7.093ms + ok 2 - layout pattern maker should pass pattern and tokens to layout from config # time=1.896ms 1..2 - ok 6 - layout makers # time=16.497ms + ok 6 - layout makers # time=10.024ms # Subtest: add layout # Subtest: should be able to add a layout @@ -2961,17 +2997,17 @@ ok 2 - expect truthy value ok 3 - should be equal 1..3 - ok 1 - should be able to add a layout # time=1.266ms + ok 1 - should be able to add a layout # time=1.226ms 1..1 - ok 7 - add layout # time=4.833ms + ok 7 - add layout # time=4.159ms 1..7 - ok 1 - log4js layouts # time=444.595ms + ok 1 - log4js layouts # time=410.184ms 1..1 - # time=452.697ms -ok 7 - test/tap/layouts-test.js # time=452.697ms + # time=418.686ms +ok 7 - test/tap/layouts-test.js # time=418.686ms # Subtest: test/tap/levels-before-configure-test.js # Subtest: Accessing things setup in configure before configure is called @@ -2979,14 +3015,14 @@ ok 1 - expect truthy value ok 2 - expect truthy value 1..2 - ok 1 - should work # time=100.74ms + ok 1 - should work # time=84.719ms 1..1 - ok 1 - Accessing things setup in configure before configure is called # time=125.058ms + ok 1 - Accessing things setup in configure before configure is called # time=110.301ms 1..1 - # time=147.189ms -ok 8 - test/tap/levels-before-configure-test.js # time=147.189ms + # time=128.785ms +ok 8 - test/tap/levels-before-configure-test.js # time=128.785ms # Subtest: test/tap/levels-test.js # Subtest: levels @@ -3002,7 +3038,7 @@ ok 8 - expect truthy value ok 9 - expect truthy value 1..9 - ok 1 - should define some levels # time=13.973ms + ok 1 - should define some levels # time=12.854ms # Subtest: ALL ok 1 - expect truthy value @@ -3032,7 +3068,7 @@ ok 25 - expect falsey value ok 26 - expect falsey value 1..26 - ok 2 - ALL # time=14.903ms + ok 2 - ALL # time=9.215ms # Subtest: TRACE ok 1 - expect truthy value @@ -3062,7 +3098,7 @@ ok 25 - expect falsey value ok 26 - expect falsey value 1..26 - ok 3 - TRACE # time=7.634ms + ok 3 - TRACE # time=5.552ms # Subtest: DEBUG ok 1 - expect truthy value @@ -3091,7 +3127,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 4 - DEBUG # time=4.715ms + ok 4 - DEBUG # time=3.968ms # Subtest: INFO ok 1 - expect truthy value @@ -3120,7 +3156,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 5 - INFO # time=7.726ms + ok 5 - INFO # time=3.901ms # Subtest: WARN ok 1 - expect truthy value @@ -3148,7 +3184,7 @@ ok 23 - expect falsey value ok 24 - expect falsey value 1..24 - ok 6 - WARN # time=7.56ms + ok 6 - WARN # time=3.43ms # Subtest: ERROR ok 1 - expect truthy value @@ -3177,7 +3213,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 7 - ERROR # time=19.364ms + ok 7 - ERROR # time=15.916ms # Subtest: FATAL ok 1 - expect truthy value @@ -3206,7 +3242,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 8 - FATAL # time=3.171ms + ok 8 - FATAL # time=3.236ms # Subtest: MARK ok 1 - expect truthy value @@ -3235,7 +3271,7 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 9 - MARK # time=3.432ms + ok 9 - MARK # time=3.79ms # Subtest: OFF ok 1 - expect falsey value @@ -3264,10 +3300,10 @@ ok 24 - expect falsey value ok 25 - expect falsey value 1..25 - ok 10 - OFF # time=3.101ms + ok 10 - OFF # time=3.319ms 1..10 - ok 1 - values # time=154.327ms + ok 1 - values # time=117.678ms # Subtest: isGreaterThanOrEqualTo ok 1 - expect truthy value @@ -3279,7 +3315,7 @@ ok 7 - expect falsey value ok 8 - expect falsey value 1..8 - ok 2 - isGreaterThanOrEqualTo # time=1.512ms + ok 2 - isGreaterThanOrEqualTo # time=1.359ms # Subtest: isLessThanOrEqualTo ok 1 - expect falsey value @@ -3291,14 +3327,14 @@ ok 7 - expect truthy value ok 8 - expect truthy value 1..8 - ok 3 - isLessThanOrEqualTo # time=1.344ms + ok 3 - isLessThanOrEqualTo # time=1.539ms # Subtest: isEqualTo ok 1 - expect truthy value ok 2 - expect truthy value ok 3 - expect truthy value 1..3 - ok 4 - isEqualTo # time=0.862ms + ok 4 - isEqualTo # time=0.94ms # Subtest: getLevel ok 1 - should be equal @@ -3308,14 +3344,14 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 5 - getLevel # time=1.416ms + ok 5 - getLevel # time=1.576ms 1..5 - ok 1 - levels # time=193.786ms + ok 1 - levels # time=156.956ms 1..1 - # time=205.192ms -ok 9 - test/tap/levels-test.js # time=205.192ms + # time=163.617ms +ok 9 - test/tap/levels-test.js # time=163.617ms # Subtest: test/tap/logLevelFilter-test.js # Subtest: log4js logLevelFilter @@ -3325,36 +3361,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=8.59ms + ok 1 - should only pass log events greater than or equal to its own level # time=8.514ms 1..1 - ok 1 - appender # time=157.886ms + ok 1 - appender # time=114.627ms # 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=24.893ms + ok 1 - logLevelFilter.log should contain all log messages # time=24.583ms # 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=10.669ms + ok 2 - logLevelFilter-warnings.log should contain only error and warning logs # time=9.203ms # 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=4.928ms + ok 3 - logLevelFilter-debugs.log should contain only trace and debug logs # time=4.255ms 1..3 - ok 2 - configure # time=262.301ms + ok 2 - configure # time=265.582ms 1..2 - ok 1 - log4js logLevelFilter # time=446.581ms + ok 1 - log4js logLevelFilter # time=415.176ms 1..1 - # time=452.282ms -ok 10 - test/tap/logLevelFilter-test.js # time=452.282ms + # time=422.317ms +ok 10 - test/tap/logLevelFilter-test.js # time=422.317ms # Subtest: test/tap/pause-test.js # Subtest: Drain event test @@ -3362,20 +3398,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=181.937ms + ok 1 - Should emit pause event and resume when logging in a file with high frequency # time=76.649ms # 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=151.9ms + ok 2 - Should emit pause event and resume when logging in a date file with high frequency # time=14.323ms 1..2 - ok 1 - Drain event test # time=386.42ms + ok 1 - Drain event test # time=118.064ms 1..1 - # time=391.844ms -ok 11 - test/tap/pause-test.js # time=391.844ms + # time=123.523ms +ok 11 - test/tap/pause-test.js # time=123.523ms # Subtest: test/tap/pm2-support-test.js # Subtest: PM2 Support @@ -3383,26 +3419,26 @@ ok 1 - expect falsey value ok 2 - expect falsey value 1..2 - ok 1 - should not get any events when turned off # time=15.054ms + ok 1 - should not get any events when turned off # time=8.526ms # 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=6.779ms + ok 2 - should get events on app instance 0 # time=4.162ms # Subtest: should not get events on app instance 1 ok 1 - should be equal 1..1 - ok 3 - should not get events on app instance 1 # time=1.826ms + ok 3 - should not get events on app instance 1 # time=2.266ms 1..3 - ok 1 - PM2 Support # time=63.302ms + ok 1 - PM2 Support # time=56.258ms 1..1 - # time=78.988ms -ok 12 - test/tap/pm2-support-test.js # time=78.988ms + # time=77.467ms +ok 12 - test/tap/pm2-support-test.js # time=77.467ms # Subtest: test/tap/recordingAppender-test.js # Subtest: recording appender @@ -3411,11 +3447,11 @@ ok 3 - should be equal ok 4 - There should be 0 recorded events 1..4 - ok 1 - recording appender # time=15.019ms + ok 1 - recording appender # time=16.893ms 1..1 - # time=35.276ms -ok 13 - test/tap/recordingAppender-test.js # time=35.276ms + # time=45.441ms +ok 13 - test/tap/recordingAppender-test.js # time=45.441ms # Subtest: test/tap/server-test.js # Subtest: TCP Server @@ -3426,7 +3462,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=244.225ms + ok 1 - should listen for TCP messages and re-send via process.send # time=267.281ms # Subtest: sending incomplete messages in chunks ok 1 - should be equal @@ -3435,14 +3471,14 @@ ok 4 - should match pattern provided ok 5 - should match pattern provided 1..5 - ok 2 - sending incomplete messages in chunks # time=716.558ms + ok 2 - sending incomplete messages in chunks # time=726.442ms 1..2 - ok 1 - TCP Server # time=990.548ms + ok 1 - TCP Server # time=1021.184ms 1..1 - # time=996.123ms -ok 14 - test/tap/server-test.js # time=996.123ms + # time=1028.448ms +ok 14 - test/tap/server-test.js # time=1028.448ms # Subtest: test/tap/setLevel-asymmetry-test.js # Subtest: log4js setLevel @@ -3455,7 +3491,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=11.239ms + ok 1 - should convert string to level correctly # time=11.738ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3465,10 +3501,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=3.405ms + ok 2 - should also accept a Level # time=4.016ms 1..2 - ok 1 - is called with a Trace as string # time=31.009ms + ok 1 - is called with a Trace as string # time=32.453ms # Subtest: is called with a Debug as string # Subtest: should convert string to level correctly @@ -3479,7 +3515,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=2.502ms + ok 1 - should convert string to level correctly # time=2.534ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3489,10 +3525,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=2.068ms + ok 2 - should also accept a Level # time=2.354ms 1..2 - ok 2 - is called with a Debug as string # time=12.083ms + ok 2 - is called with a Debug as string # time=13.984ms # Subtest: is called with a Info as string # Subtest: should convert string to level correctly @@ -3503,7 +3539,7 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 1 - should convert string to level correctly # time=2.169ms + ok 1 - should convert string to level correctly # time=2.053ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3513,10 +3549,10 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=1.267ms + ok 2 - should also accept a Level # time=1.695ms 1..2 - ok 3 - is called with a Info as string # time=23.375ms + ok 3 - is called with a Info as string # time=20.453ms # Subtest: is called with a Warn as string # Subtest: should convert string to level correctly @@ -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=1.285ms + ok 1 - should convert string to level correctly # time=1.849ms # 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=1.251ms + ok 2 - should also accept a Level # time=1.257ms 1..2 - ok 4 - is called with a Warn as string # time=9.812ms + ok 4 - is called with a Warn as string # time=10.87ms # Subtest: is called with a Error 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=1.273ms + ok 1 - should convert string to level correctly # time=1.306ms # 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=1.422ms + ok 2 - should also accept a Level # time=1.426ms 1..2 - ok 5 - is called with a Error as string # time=9.43ms + ok 5 - is called with a Error as string # time=10.141ms # Subtest: is called with a Fatal 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=1.496ms + ok 1 - should convert string to level correctly # time=1.472ms # Subtest: should also accept a Level ok 1 - should be equal @@ -3585,27 +3621,27 @@ ok 5 - should be equal ok 6 - should be equal 1..6 - ok 2 - should also accept a Level # time=1.357ms + ok 2 - should also accept a Level # time=1.406ms 1..2 - ok 6 - is called with a Fatal as string # time=9.343ms + ok 6 - is called with a Fatal as string # time=9.587ms 1..6 - ok 1 - log4js setLevel # time=133.034ms + ok 1 - log4js setLevel # time=139.87ms 1..1 - # time=142.209ms -ok 15 - test/tap/setLevel-asymmetry-test.js # time=142.209ms + # time=149.455ms +ok 15 - test/tap/setLevel-asymmetry-test.js # time=149.455ms # 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=90.721ms + ok 1 - Stacktraces from errors in different VM context # time=102.099ms 1..1 - # time=107.786ms -ok 16 - test/tap/stacktraces-test.js # time=107.786ms + # time=121.781ms +ok 16 - test/tap/stacktraces-test.js # time=121.781ms # Subtest: test/tap/subcategories-test.js # Subtest: subcategories @@ -3620,10 +3656,10 @@ ok 7 - should be equal ok 8 - should be equal 1..8 - ok 1 - check logger levels # time=12.081ms + ok 1 - check logger levels # time=13.01ms 1..1 - ok 1 - loggers created after levels configuration is loaded # time=24.645ms + ok 1 - loggers created after levels configuration is loaded # time=25.967ms # Subtest: loggers created before levels configuration is loaded # Subtest: should still get new levels @@ -3636,35 +3672,35 @@ ok 7 - should be equivalent ok 8 - should be equivalent 1..8 - ok 1 - should still get new levels # time=5.349ms + ok 1 - should still get new levels # time=5.456ms 1..1 - ok 2 - loggers created before levels configuration is loaded # time=12.021ms + ok 2 - loggers created before levels configuration is loaded # time=12.099ms # 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.882ms + ok 1 - should inherit parent level # time=1.444ms # 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.882ms + ok 2 - changing child level should not change parent level # time=1.736ms 1..2 - ok 3 - setting level on subcategories should not set parent level # time=20.011ms + ok 3 - setting level on subcategories should not set parent level # time=13.274ms 1..3 - ok 1 - subcategories # time=88.012ms + ok 1 - subcategories # time=82.16ms 1..1 - # time=94.833ms -ok 17 - test/tap/subcategories-test.js # time=94.833ms + # time=110.566ms +ok 17 - test/tap/subcategories-test.js # time=110.566ms 1..17 -# time=8871.482ms +# time=8743.934ms Removing node_modules/log4js create-stamp debian/debhelper-build-stamp dh_prep @@ -3780,14 +3816,14 @@ make[1]: Entering directory '/build/reproducible-path/node-log4js-6.9.1+~cs8.4.19' dh_installdocs dh_nodejs_autodocs + 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/ - 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/ make[1]: Leaving directory '/build/reproducible-path/node-log4js-6.9.1+~cs8.4.19' dh_installchangelogs dh_installexamples @@ -3811,12 +3847,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/1961825/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/1961825/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/2845148 and its subdirectories -I: Current time: Wed Jun 18 07:10:43 -12 2025 -I: pbuilder-time-stamp: 1750273843 +I: removing directory /srv/workspace/pbuilder/1961825 and its subdirectories +I: Current time: Fri May 17 02:48:46 +14 2024 +I: pbuilder-time-stamp: 1715863726