Diff of the two buildlogs: -- --- b1/build.log 2024-06-11 07:57:30.221803345 +0000 +++ b2/build.log 2024-06-11 07:59:05.165758649 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Jul 14 02:18:49 -12 2025 -I: pbuilder-time-stamp: 1752502729 +I: Current time: Tue Jun 11 21:57:38 +14 2024 +I: pbuilder-time-stamp: 1718092658 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -30,52 +30,84 @@ dpkg-source: info: applying fix-test.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/488933/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/2826898/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 Jun 11 07:57 /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/2826898/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/2826898/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='54ea84ddc8f3420ba7dc43ac1f0dacd8' - 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='488933' - PS1='# ' - PS2='> ' + INVOCATION_ID=4e357e2242584fc989677aa633d7db89 + 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=2826898 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.YvbITZD0/pbuilderrc_9rUS --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.YvbITZD0/b1 --logfile b1/build.log node-winston_3.8.2-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.YvbITZD0/pbuilderrc_YCQt --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.YvbITZD0/b2 --logfile b2/build.log node-winston_3.8.2-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 codethink01-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 Jul 11 17:48 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/488933/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 9 11:24 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/2826898/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: 357 http://deb.debian.org/debian trixie/main arm64 node-std-mocks all 1.0.1+~1.0.1-2 [4308 B] Get: 358 http://deb.debian.org/debian trixie/main arm64 node-winston-compat all 0.1.5-3 [6648 B] Get: 359 http://deb.debian.org/debian trixie/main arm64 node-winston-transport all 4.5.0-2 [8996 B] -Fetched 46.1 MB in 1s (92.0 MB/s) +Fetched 46.1 MB in 1s (81.5 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 ... 19732 files and directories currently installed.) @@ -1973,7 +2005,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/node-winston-3.8.2/ && 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-winston_3.8.2-1_source.changes +I: user script /srv/workspace/pbuilder/2826898/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/2826898/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/node-winston-3.8.2/ && 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-winston_3.8.2-1_source.changes dpkg-buildpackage: info: source package node-winston dpkg-buildpackage: info: source version 3.8.2-1 dpkg-buildpackage: info: source distribution unstable @@ -2001,14 +2037,52 @@ ln -s ../. node_modules/winston /bin/sh -ex debian/tests/pkg-js/test + grep -v test/unit/winston/exception-handler.test.js -+ find test -name *.test.js -+ grep -v test/transports -+ grep -v test/unit/winston/rejection-handler.test.js +++ find test -name '*.test.js' +++ grep -v test/unit/winston/rejection-handler.test.js +++ grep -v test/transports + mocha --exit test/unit/winston/exception-handler.test.js test/unit/winston/create-logger.test.js test/unit/winston/transports/stream.test.js test/unit/winston/transports/http.test.js test/unit/winston/transports/file.test.js test/unit/winston/transports/file-tailrolling.test.js test/unit/winston/transports/file-maxfiles.test.js test/unit/winston/transports/file-create-dir.test.js test/unit/winston/transports/file-archive.test.js test/unit/winston/transports/error.test.js test/unit/winston/transports/console.test.js test/unit/winston/transports/01-file-maxsize.test.js test/unit/winston/transports/00-file-stress.test.js test/unit/winston/tail-file.test.js test/unit/winston/profiler.test.js test/unit/winston/logger.test.js test/unit/winston/logger-legacy.test.js test/unit/winston/log-exception.test.js test/unit/winston/exception-stream.test.js test/unit/winston/container.test.js test/unit/winston/config/config.test.js test/unit/formats/errors.test.js test/integration/formats.test.js test/integration/winston.test.js -(node:503215) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. +(node:2865033) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. (Use `node --trace-deprecation ...` to show where the warning was created) options.stream will be removed in winston@4. Use winston.transports.Stream -options.stream will be removed in winston@4. Use winston.transports.Stream + +/usr/share/nodejs/assume/index.js:995 + throw failure; + ^ +[Unknown assertation failure occured, assumed `'Cannot read properties of null (reading \'0\')'` to equal (===) `'wtf this error'` /build/reproducible-path/node-winston-3.8.2/test/unit/winston/exception-handler.test.js +  v + 77. assume(info.error).is.an('error'); + 78. assume(info.error.message).equals('wtf this error'); + 79. assume(info.message).includes('uncaughtException: wtf this error'); +  ^ + at Assume.equal (/usr/share/nodejs/assume/index.js:673:32) + at Writable.write [as _write] (/build/reproducible-path/node-winston-3.8.2/test/unit/winston/exception-handler.test.js:78:36) + at writeOrBuffer (node:internal/streams/writable:564:12) + at _write (node:internal/streams/writable:493:10) + at Writable.write (node:internal/streams/writable:502:10) + at Stream.log (/build/reproducible-path/node-winston-3.8.2/lib/winston/transports/stream.js:50:20) + at Stream._write (/usr/share/nodejs/winston-transport/index.js:82:19) + at doWrite (/usr/share/nodejs/readable-stream/lib/_stream_writable.js:409:139) + at writeOrBuffer (/usr/share/nodejs/readable-stream/lib/_stream_writable.js:398:5) + at Writable.write (/usr/share/nodejs/readable-stream/lib/_stream_writable.js:307:11) + at DerivedLogger.ondata (/usr/share/nodejs/readable-stream/lib/_stream_readable.js:681:20) + at DerivedLogger.emit (node:events:519:28) + at addChunk (/usr/share/nodejs/readable-stream/lib/_stream_readable.js:298:12) + at readableAddChunk (/usr/share/nodejs/readable-stream/lib/_stream_readable.js:280:11) + at Readable.push (/usr/share/nodejs/readable-stream/lib/_stream_readable.js:241:10) + at Transform.push (/usr/share/nodejs/readable-stream/lib/_stream_transform.js:139:32) + at DerivedLogger._transform (/build/reproducible-path/node-winston-3.8.2/lib/winston/logger.js:313:12) + at Transform._read (/usr/share/nodejs/readable-stream/lib/_stream_transform.js:177:10) + at Transform._write (/usr/share/nodejs/readable-stream/lib/_stream_transform.js:164:83) + at doWrite (/usr/share/nodejs/readable-stream/lib/_stream_writable.js:409:139) + at writeOrBuffer (/usr/share/nodejs/readable-stream/lib/_stream_writable.js:398:5) + at Writable.write (/usr/share/nodejs/readable-stream/lib/_stream_writable.js:307:11) + at DerivedLogger.log (/build/reproducible-path/node-winston-3.8.2/lib/winston/logger.js:216:12) + at ExceptionHandler._uncaughtException (/build/reproducible-path/node-winston-3.8.2/lib/winston/exception-handler.js:220:17) + at process.emit (node:events:519:28) + at process._fatalException (node:internal/process/execution:188:25) +] + +Node.js v20.14.0 ExceptionHandler @@ -2035,16 +2109,16 @@ Http({ host, port, path }) nominal - undefined should send logs over HTTP + undefined should send logs over HTTP (70ms) bacth mode: max message undefined test max message reached bacth mode: timeout - undefined test timeout reached (2011ms) + undefined test timeout reached (2056ms) circular structure undefined should be able to handle options with circular structure File({ filename }) - undefined should write to the file when logged to with expected object + undefined should write to the file when logged to with expected object (85ms) File({ stream }) - should display the deprecation notice @@ -2064,232 +2138,8 @@ /build/reproducible-path/node-winston-3.8.2/test/fixtures/logs/testtailrollingfiles.log /build/reproducible-path/node-winston-3.8.2/test/fixtures/logs/testtailrollingfiles.log /build/reproducible-path/node-winston-3.8.2/test/fixtures/logs/testtailrollingfiles.log -/build/reproducible-path/node-winston-3.8.2/test/fixtures/logs/testtailrollingfiles.log - undefined and when passed more files than the maxFiles (84ms) + undefined and when passed more files than the maxFiles (165ms) undefined should be 3 log files, base to maxFiles - 1 - undefined should have files in correct order - - winston/transports/file/createLogDir - undefined should create directory if it does not exist - undefined should create directory if it does not exist when write to the stream - - winston/transports/file/zippedArchive - An instance of the File Transport with tailable true - undefined init logger AFTER cleaning up old files - undefined when created archived files are rolled (40ms) - undefined should be only 3 files called testarchive.log, testarchive1.log.gz and testarchive2.log.gz - - transports issue 1364 - only log once - undefined logger transport has single correct transport - undefined error didn't - log twice - undefined logger transport has single correct transport - undefined error occurred - log thrice - undefined logger transport has single correct transport - undefined error occurred - log four times - undefined logger transport has single correct transport - undefined other error occurred - - Console transport - undefined { stderrLevels: ['info', 'error'] } logs to them appropriately - undefined { consoleWarnLevels: ['warn', 'debug'] } logs to them appropriately - undefined { eol } adds a custom EOL delimiter - with defaults - undefined logs all levels to stdout - undefined should set stderrLevels to [] by default - throws an appropriate error when - undefined if stderrLevels is set, but not an Array { stderrLevels: 'Not an Array' } - undefined if stderrLevels contains non-string elements { stderrLevels: ['good', /^invalid$/, 'valid'] - - Console transport (abstract-winston-tranport) - .log() - undefined should be present -{"level":"debug","message":"foo"} - undefined (with no callback) should return true -{"level":"debug","message":"foo"} - undefined (with callback) should return true - events -{"level":"debug","message":"foo"} - undefined should emit the "logged" event - - File (maxsize) - undefined should create multiple files correctly when passed more than the maxsize - - File (stress) - undefined should handle a high volume of writes (10470ms) - undefined should handle a high volume of large writes (11380ms) - undefined should handle a high volume of large writes synchronous (10020ms) - - tailFile - undefined is a function - undefined returns a stream that emits "line" for every line (5104ms) - - Profiler - undefined new Profiler() - undefined .done({ info }) (203ms) - - Logger Instance - Configuration - undefined .configure() - undefined .configure({ transports }) - undefined .configure({ transports, format }) - Transports - add - undefined should throw error when adding an invalid transport - undefined should add the expected transport - undefined should allow adding multiple transports - remove - undefined should do nothing if transport was not added - undefined should remove transport when matching one is found - undefined should remove specified logger even when duplicate exists - clear - undefined should do nothing when no transports exist - undefined should remove all transports - stream - undefined should return a log stream for all transports - Log Levels - undefined report unknown levels - undefined .() - undefined default levels - undefined custom levels - undefined sets transports levels - Log Levels Enabled - undefined default levels - undefined default levels, transport override - undefined default levels, no transports - undefined custom levels - undefined custom levels, no transports - undefined custom levels, transport override - Transport Events - undefined 'finish' event awaits transports to emit 'finish' - undefined error - undefined warn - Formats - undefined rethrows errors from user-defined formats - Profiling - undefined ending profiler with object argument should be included in output (101ms) - undefined calling profile with a callback function should not make a difference (103ms) - undefined should stop a timer when `done` is called on it (103ms) - Logging non-primitive data types - .log - undefined .log(new Error()) uses Error instance as info - undefined .info('Hello') preserve meta without splat format - undefined .info('Hello %d') does not mutate unnecessarily with string interpolation tokens - undefined .info('Hello') and .info('Hello %d') preserve meta with splat format - .info - undefined .info(undefined) creates info with { message: undefined } - undefined .info(null) creates info with { message: null } - undefined .info(new Error()) uses Error instance as info - - .info('any string', new Error()) - Metadata Precedence - Should support child loggers & defaultMeta - undefined sets child meta for text messages correctly - undefined sets child meta for json messages correctly - undefined merges child and provided meta correctly - undefined provided meta take precedence over defaultMeta - undefined provided meta take precedence over child meta - undefined handles error stack traces in child loggers correctly - undefined defaultMeta() autobinds correctly - Backwards Compatability - Winston V2 Log - undefined .log(level, message) - undefined .log(level, undefined) creates info with { message: undefined } - undefined .log(level, null) creates info with { message: null } - undefined .log(level, new Error()) uses Error instance as info - undefined .log(level, message, meta) - undefined .log(level, formatStr, ...splat) - undefined .log(level, formatStr, ...splat, meta) - - Logger (legacy API) - undefined new Logger({ DEPRECATED }) - undefined .cli() throws - LegacyTransport (inherits from winston@2 Transport) - undefined .add(legacy-test) - undefined .add(llegacy-test is a legacy winston transport. Consider upgrading: -- Upgrade docs: https://github.com/winstonjs/winston/blob/master/UPGRADE-3.0.md -legacy-mixed-test is a legacy winston transport. Consider upgrading: -- Upgrade docs: https://github.com/winstonjs/winston/blob/master/UPGRADE-3.0.md -egacy-test) multiple - undefined .remove() [LegacyTransportStream] - LegacyMixedTransport (inherits from winston@3 Transport) - undefined .add(legacy-mixed-test) - undefined .add(legacy-mixed-test) multiple - undefined .remove() [LegacyTransportStream] - - Logger, ExceptionHandler - undefined Custom exitOnError function does not exit (1014ms) - .exceptions.unhandle() - undefined does not log to any transports (318ms) - undefined handlers immutable - .exceptions.handle() - should save the error information to the specified file - undefined when strings are thrown as errors (1349ms) - undefined with a custom winston.Logger instance (1340ms) - undefined with the default winston logger (1334ms) - - ExceptionStream - undefined has expected methods - undefined throws without a transport - - Container - no transports - undefined .add(default-test) - undefined .get(default-test) - undefined .has(default-test) - undefined .has(not-has) - undefined .close(default-test) - undefined .close(non-existent) - undefined .close() - explicit transports - undefined .get(some-logger) - undefined .get(some-other-logger) - - winston.config - undefined should have expected methods - - format.errors (integration) - undefined logger.log(level, error) - undefined logger.log(level, error) [custom error properties] - undefined logger.log(level, error, meta) - undefined logger.log(level, error, meta) [custom error properties] - undefined logger.log(level, msg, meta) - undefined logger.log(level, msg, meta) [custom error properties] - undefined logger.(error) - undefined logger.(error) [custom error properties] - undefined logger.(error, meta) - undefined logger.(error, meta) [custom error properties] - undefined logger.(msg, meta) - undefined logger.(msg, meta) [custom error properties] - undefined Promise.reject().catch(logger.) - undefined Promise.reject().catch(logger.) [custom error properties] - - winston.format.colorize (Integration) - undefined non-TTY environment (290ms) - - winston - undefined winston.transports - undefined has expected initial state - undefined has expected methods - undefined exposes version - - abstract-winston-logger - deprecates winston < 3.0.0 properties - undefined .addRewriter() - undefined .addFilter() - undefined .cli() - undefined .clone() - undefined .extend() - undefined .emitErrs - undefined .levelLength - undefined .padLevels - undefined .stripColors - - - 167 passing (46s) - 3 pending - Removing node_modules/abstract-winston-transport Removing node_modules/hock Removing node_modules/url-equal @@ -2363,12 +2213,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/2826898/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/2826898/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/488933 and its subdirectories -I: Current time: Mon Jul 14 02:20:26 -12 2025 -I: pbuilder-time-stamp: 1752502826 +I: removing directory /srv/workspace/pbuilder/2826898 and its subdirectories +I: Current time: Tue Jun 11 21:59:04 +14 2024 +I: pbuilder-time-stamp: 1718092744