Diff of the two buildlogs: -- --- b1/build.log 2024-05-06 09:13:53.419072675 +0000 +++ b2/build.log 2024-05-06 09:17:59.612604803 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun May 5 21:12:16 -12 2024 -I: pbuilder-time-stamp: 1714986736 +I: Current time: Mon Jun 9 05:36:56 +14 2025 +I: pbuilder-time-stamp: 1749397016 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -29,52 +29,84 @@ dpkg-source: info: applying openjdk-21.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/1128982/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/1451002/tmp/hooks/D01_modify_environment starting +debug: Running on codethink03-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 8 15:37 /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/1451002/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/1451002/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='unstable' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='da6a58f49534463491cb36bc8db8f060' - 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='1128982' - PS1='# ' - PS2='> ' + INVOCATION_ID=9e5bdb9274ae4a549801fd40a741775c + 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=1451002 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.t5fio2Kd/pbuilderrc_JPqK --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.t5fio2Kd/b1 --logfile b1/build.log grengine_3.0.2+dfsg-2.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.t5fio2Kd/pbuilderrc_tvrM --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.t5fio2Kd/b2 --logfile b2/build.log grengine_3.0.2+dfsg-2.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 codethink02-arm64 6.1.0-20-cloud-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-20-cloud-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 5 07:42 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/1128982/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 7 14:05 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/1451002/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -369,7 +401,7 @@ Get: 241 http://deb.debian.org/debian unstable/main arm64 libpicocli-java all 4.6.2-2 [390 kB] Get: 242 http://deb.debian.org/debian unstable/main arm64 libunivocity-parsers-java all 2.9.1-1 [397 kB] Get: 243 http://deb.debian.org/debian unstable/main arm64 junit5 all 5.10.1-1 [2458 kB] -Fetched 235 MB in 1s (248 MB/s) +Fetched 235 MB in 2s (95.8 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libpipeline1:arm64. (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.) @@ -1171,8 +1203,8 @@ Setting up tzdata (2024a-4) ... Current default time zone: 'Etc/UTC' -Local time is now: Mon May 6 09:12:49 UTC 2024. -Universal Time is now: Mon May 6 09:12:49 UTC 2024. +Local time is now: Sun Jun 8 15:38:23 UTC 2025. +Universal Time is now: Sun Jun 8 15:38:23 UTC 2025. Run 'dpkg-reconfigure tzdata' if you wish to change it. Setting up libgeronimo-annotation-1.3-spec-java (1.3-1) ... @@ -1564,7 +1596,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/grengine-3.0.2+dfsg/ && 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 > ../grengine_3.0.2+dfsg-2_source.changes +I: user script /srv/workspace/pbuilder/1451002/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/1451002/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/grengine-3.0.2+dfsg/ && 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 > ../grengine_3.0.2+dfsg-2_source.changes dpkg-buildpackage: info: source package grengine dpkg-buildpackage: info: source version 3.0.2+dfsg-2 dpkg-buildpackage: info: source distribution unstable @@ -1597,18 +1633,18 @@ To honour the JVM settings for this build a new JVM will be forked. Please consider using the daemon: https://docs.gradle.org/4.4.1/userguide/gradle_daemon.html. Starting process 'Gradle build daemon'. Working directory: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1 Command: /usr/lib/jvm/java-17-openjdk-arm64/bin/java --add-opens java.base/java.lang=ALL-UNNAMED -Xbootclasspath/a:/usr/share/java/gradle-helper-hook.jar:/usr/share/java/maven-repo-helper.jar -Dfile.encoding=UTF-8 -Duser.country=US -Duser.language=en -Duser.variant -cp /usr/share/gradle/lib/gradle-launcher-4.4.1.jar org.gradle.launcher.daemon.bootstrap.GradleDaemon 4.4.1 Successfully started process 'Gradle build daemon' -An attempt to start the daemon took 1.071 secs. -The client will now receive all logging from the daemon (pid: 1138357). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-1138357.out.log +An attempt to start the daemon took 2.853 secs. +The client will now receive all logging from the daemon (pid: 1467392). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-1467392.out.log Daemon will be stopped at the end of the build stopping after processing Closing daemon's stdin at end of input. The daemon will no longer process any standard input. Using 12 worker leases. -Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@6077e967 -Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@6077e967 -Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@1339f37d +Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@65bef64c +Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@65bef64c +Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@15484a5a Starting Build Compiling initialization script '/build/reproducible-path/grengine-3.0.2+dfsg/.gradle/init.d/init.gradle' using SubsetScriptTransformer. -Creating new cache for metadata-1.1/results, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/transforms-1/metadata-1.1/results.bin, access org.gradle.cache.internal.DefaultCacheAccess@42666d4e +Creating new cache for metadata-1.1/results, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/transforms-1/metadata-1.1/results.bin, access org.gradle.cache.internal.DefaultCacheAccess@2c750121 Compiling initialization script '/build/reproducible-path/grengine-3.0.2+dfsg/.gradle/init.d/init.gradle' using BuildScriptTransformer. Settings evaluated using settings file '/build/reproducible-path/grengine-3.0.2+dfsg/settings.gradle'. Settings file not found (/build/reproducible-path/grengine-3.0.2+dfsg/settings.gradle) @@ -1625,29 +1661,29 @@ Linking the generated javadoc to the system JDK API documentation All projects evaluated. Selected primary task 'jar' from project : -Creating new cache for annotation-processors, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@1dd3163b +Creating new cache for annotation-processors, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@3a98a814 Tasks to be executed: [task ':compileJava', task ':processResources', task ':classes', task ':debianMavenPom', task ':jar'] -Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@1339f37d -Creating new cache for taskHistory, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@451b517d -Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@67ec2df7 +Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@15484a5a +Creating new cache for taskHistory, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@61416e5c +Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@6661d777 :compileJava (Thread[Task worker for ':',5,main]) started. :compileJava -Putting task artifact state for task ':compileJava' into context took 0.01 secs. -Creating new cache for metadata-2.36/module-metadata, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@6b71a0f3 +Putting task artifact state for task ':compileJava' into context took 0.025 secs. +Creating new cache for metadata-2.36/module-metadata, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@46b1fa8d Loading the Maven rules... Replacing org.apache.groovy:groovy:jar:4.0.9 -> org.codehaus.groovy:groovy-all:jar:debian -Up-to-date check for task ':compileJava' took 1.274 secs. It is not up-to-date because: +Up-to-date check for task ':compileJava' took 2.854 secs. It is not up-to-date because: No history is available. All input files are considered out-of-date for incremental task ':compileJava'. Compiling with JDK Java compiler API. -:compileJava (Thread[Task worker for ':',5,main]) completed. Took 3.516 secs. +:compileJava (Thread[Task worker for ':',5,main]) completed. Took 8.606 secs. :processResources (Thread[Task worker for ':',5,main]) started. :processResources Putting task artifact state for task ':processResources' into context took 0.0 secs. file or directory '/build/reproducible-path/grengine-3.0.2+dfsg/src/main/resources', not found Skipping task ':processResources' as it has no source files and no previous output files. :processResources NO-SOURCE -:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.002 secs. +:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.016 secs. :classes (Thread[Task worker for ':',5,main]) started. :classes Skipping task ':classes' as it has no actions. @@ -1655,18 +1691,18 @@ :debianMavenPom (Thread[Task worker for ':',5,main]) started. :debianMavenPom Putting task artifact state for task ':debianMavenPom' into context took 0.0 secs. -Up-to-date check for task ':debianMavenPom' took 0.001 secs. It is not up-to-date because: +Up-to-date check for task ':debianMavenPom' took 0.0 secs. It is not up-to-date because: No history is available. Generating pom file /build/reproducible-path/grengine-3.0.2+dfsg/build/debian/grengine.pom -:debianMavenPom (Thread[Task worker for ':',5,main]) completed. Took 0.073 secs. +:debianMavenPom (Thread[Task worker for ':',5,main]) completed. Took 0.265 secs. :jar (Thread[Task worker for ':',5,main]) started. :jar Putting task artifact state for task ':jar' into context took 0.0 secs. -Up-to-date check for task ':jar' took 0.009 secs. It is not up-to-date because: +Up-to-date check for task ':jar' took 0.046 secs. It is not up-to-date because: No history is available. -:jar (Thread[Task worker for ':',5,main]) completed. Took 0.06 secs. +:jar (Thread[Task worker for ':',5,main]) completed. Took 0.202 secs. -BUILD SUCCESSFUL in 8s +BUILD SUCCESSFUL in 23s 3 actionable tasks: 3 executed jh_build -O--buildsystem=gradle debian/rules override_dh_auto_test @@ -1682,17 +1718,17 @@ To honour the JVM settings for this build a new JVM will be forked. Please consider using the daemon: https://docs.gradle.org/4.4.1/userguide/gradle_daemon.html. Starting process 'Gradle build daemon'. Working directory: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1 Command: /usr/lib/jvm/java-17-openjdk-arm64/bin/java --add-opens java.base/java.lang=ALL-UNNAMED -Xbootclasspath/a:/usr/share/java/gradle-helper-hook.jar:/usr/share/java/maven-repo-helper.jar -Dfile.encoding=UTF-8 -Duser.country=US -Duser.language=en -Duser.variant -cp /usr/share/gradle/lib/gradle-launcher-4.4.1.jar org.gradle.launcher.daemon.bootstrap.GradleDaemon 4.4.1 Successfully started process 'Gradle build daemon' -An attempt to start the daemon took 1.056 secs. -The client will now receive all logging from the daemon (pid: 1138918). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-1138918.out.log +An attempt to start the daemon took 3.208 secs. +The client will now receive all logging from the daemon (pid: 1469688). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-1469688.out.log Daemon will be stopped at the end of the build stopping after processing Closing daemon's stdin at end of input. The daemon will no longer process any standard input. Using 12 worker leases. -Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@343f5bdf -Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@343f5bdf -Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@31d3a3b +Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@26194eef +Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@26194eef +Creating new cache for fileHashes, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@2bef918 Starting Build -Creating new cache for metadata-1.1/results, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/transforms-1/metadata-1.1/results.bin, access org.gradle.cache.internal.DefaultCacheAccess@6e9c2669 +Creating new cache for metadata-1.1/results, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/transforms-1/metadata-1.1/results.bin, access org.gradle.cache.internal.DefaultCacheAccess@2f144e5d Settings evaluated using settings file '/build/reproducible-path/grengine-3.0.2+dfsg/settings.gradle'. Settings file not found (/build/reproducible-path/grengine-3.0.2+dfsg/settings.gradle) Root project name not defined in settings.gradle, defaulting to 'grengine' instead of the name of the root directory 'grengine-3.0.2+dfsg' @@ -1706,27 +1742,27 @@ Linking the generated javadoc to the system JDK API documentation All projects evaluated. Selected primary task 'compileTestJava' from project : -Creating new cache for annotation-processors, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@185cf76d +Creating new cache for annotation-processors, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@557719eb Tasks to be executed: [task ':compileJava', task ':processResources', task ':classes', task ':compileTestJava'] -Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@31d3a3b -Creating new cache for taskHistory, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@4b202257 -Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@40931446 +Creating new cache for resourceHashesCache, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@2bef918 +Creating new cache for taskHistory, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@6a64c51d +Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@e5976c1 :compileJava (Thread[Task worker for ':',5,main]) started. :compileJava -Putting task artifact state for task ':compileJava' into context took 0.017 secs. -Creating new cache for metadata-2.36/module-metadata, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@10cfd689 +Putting task artifact state for task ':compileJava' into context took 0.014 secs. +Creating new cache for metadata-2.36/module-metadata, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@527bdfbf Loading the Maven rules... Replacing org.apache.groovy:groovy:jar:4.0.9 -> org.codehaus.groovy:groovy-all:jar:debian -Skipping task ':compileJava' as it is up-to-date (took 0.372 secs). +Skipping task ':compileJava' as it is up-to-date (took 0.716 secs). :compileJava UP-TO-DATE -:compileJava (Thread[Task worker for ':',5,main]) completed. Took 0.439 secs. +:compileJava (Thread[Task worker for ':',5,main]) completed. Took 0.919 secs. :processResources (Thread[Task worker for ':',5,main]) started. :processResources Putting task artifact state for task ':processResources' into context took 0.0 secs. file or directory '/build/reproducible-path/grengine-3.0.2+dfsg/src/main/resources', not found Skipping task ':processResources' as it has no source files and no previous output files. :processResources NO-SOURCE -:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.004 secs. +:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.028 secs. :classes (Thread[Task worker for ':',5,main]) started. :classes Skipping task ':classes' as it has no actions. @@ -1747,13 +1783,13 @@ Replacing org.junit.platform:junit-platform-commons:jar:1.9.1 -> org.junit.platform:junit-platform-commons:jar:debian Passing through org.apiguardian:apiguardian-api:jar:debian Passing through org.hamcrest:hamcrest:jar:debian -Up-to-date check for task ':compileTestJava' took 0.82 secs. It is not up-to-date because: +Up-to-date check for task ':compileTestJava' took 2.239 secs. It is not up-to-date because: No history is available. All input files are considered out-of-date for incremental task ':compileTestJava'. Compiling with JDK Java compiler API. -:compileTestJava (Thread[Task worker for ':',5,main]) completed. Took 5.263 secs. +:compileTestJava (Thread[Task worker for ':',5,main]) completed. Took 15.446 secs. -BUILD SUCCESSFUL in 10s +BUILD SUCCESSFUL in 29s 2 actionable tasks: 1 executed, 1 up-to-date java -jar /usr/share/java/junit-platform-console-standalone.jar \ -cp $(readlink -f build/libs/grengine-*.jar):build/classes/java/test:/usr/share/java/groovy-all.jar:/usr/share/java/commons-io.jar:/usr/share/java/ivy.jar \ @@ -1762,122 +1798,122 @@ Thanks for using JUnit! Support its development at https://junit.org/sponsoring TEST LayeredEngine concurrent - top code cache - parent first -Thread 1 5: 318990 538516 634197 606779 506660 -Thread 2 5: 327497 508396 603347 585342 479724 -Thread 3 5: 273407 465740 589336 560887 466274 -Thread 4 5: 328271 490247 559490 551773 454398 -TOTAL OK 5: 1248165 2002899 2386370 2304781 1907056 -Script runs: 9849271 -Duration: 2567 ms -Average time per script run: 260 ns +Thread 2 5: 40319 205641 361754 176692 220575 +Thread 1 5: 35063 182605 300954 229872 240478 +Thread 3 5: 46323 134798 252664 180872 240058 +Thread 4 5: 34795 141688 282662 218740 221059 +TOTAL OK 5: 156500 664732 1198034 806176 922170 +Script runs: 3747612 +Duration: 2626 ms +Average time per script run: 700 ns TEST LayeredEngine concurrent - top code cache - current first -Thread 1 5: 498326 582255 562395 551080 472481 -Thread 2 5: 495116 586167 513080 527792 449378 -Thread 3 5: 501528 559233 514556 514860 441350 -Thread 4 5: 485441 531956 488616 495012 417716 -TOTAL OK 5: 1980411 2259611 2078647 2088744 1780925 -Script runs: 10188338 -Duration: 2546 ms -Average time per script run: 249 ns +Thread 1 5: 105650 97108 206256 342252 357725 +Thread 2 5: 96809 150065 196593 249591 203534 +Thread 4 5: 49457 188663 229814 268339 167554 +Thread 3 5: 65228 124874 226230 196318 181791 +TOTAL OK 5: 317144 560710 858893 1056500 910604 +Script runs: 3703851 +Duration: 2622 ms +Average time per script run: 707 ns TEST LayeredEngine concurrent - no top code cache -Thread 1 5: 490335 624257 499424 474285 452155 -Thread 2 5: 453915 516516 444783 454948 435127 -Thread 3 5: 461984 581508 461570 445018 423238 -Thread 4 5: 455073 567711 410457 431427 411551 -TOTAL OK 5: 1861307 2289992 1816234 1805678 1722071 -Script runs: 9495282 -Duration: 2550 ms -Average time per script run: 268 ns +Thread 1 5: 257356 235953 202071 133628 279441 +Thread 2 5: 232518 179136 423692 133120 227192 +Thread 3 5: 251234 206393 224051 96608 152195 +Thread 4 5: 209328 219614 205608 131259 174027 +TOTAL OK 5: 950436 841096 1055422 494615 832855 +Script runs: 4174424 +Duration: 2600 ms +Average time per script run: 622 ns Loader[engineId=ch.artecat.grengine.engine.EngineId@28782602, number=17, isAttached=true] Thread-0 about to load... -Thread-3 about to load... -Thread-2 about to load... +Thread-0 --- defineClass: about to sleep 100ms Thread-1 about to load... +Thread-1 --- defineClass: about to sleep 100ms +Thread-2 about to load... +Thread-3 about to load... Thread-4 about to load... Thread-5 about to load... Thread-6 about to load... Thread-7 about to load... -Thread-8 about to load... -Thread-9 about to load... -Thread-0 --- defineClass: about to sleep 100ms -Thread-3 --- defineClass: about to sleep 100ms Thread-0 --- defineClass: done sleeping -Thread-3 --- defineClass: done sleeping Thread-0 loaded. -Thread-3 loaded. -Thread-9 loaded. +Thread-1 --- defineClass: done sleeping Thread-1 loaded. +Thread-7 loaded. +Thread-3 loaded. +Thread-5 loaded. +Thread-4 loaded. Thread-6 loaded. -Thread-8 loaded. Thread-2 loaded. -Thread-4 loaded. -Thread-5 loaded. -Thread-7 loaded. +Thread-8 about to load... +Thread-8 loaded. +Thread-9 about to load... +Thread-9 loaded. Thread-0 about to load... +Thread-0 --- defineClass: about to sleep 100ms Thread-1 about to load... +Thread-1 --- defineClass: about to sleep 100ms Thread-2 about to load... Thread-3 about to load... Thread-4 about to load... -Thread-0 --- defineClass: about to sleep 100ms -Thread-6 about to load... Thread-5 about to load... -Thread-8 about to load... +Thread-6 about to load... Thread-7 about to load... -Thread-1 --- defineClass: about to sleep 100ms -Thread-9 about to load... +Thread-8 about to load... Thread-0 --- defineClass: done sleeping Thread-0 loaded. +Thread-9 about to load... +Thread-2 loaded. Thread-8 loaded. Thread-6 loaded. -Thread-1 --- defineClass: done sleeping -Thread-2 loaded. Thread-4 loaded. +Thread-1 --- defineClass: done sleeping Thread-1 loaded. Thread-9 loaded. Thread-7 loaded. -Thread-5 loaded. Thread-3 loaded. +Thread-5 loaded. Thread-0 about to load... +Thread-0 --- defineClass: about to sleep 100ms Thread-1 about to load... Thread-2 about to load... +Thread-3 about to load... Thread-4 about to load... -Thread-0 --- defineClass: about to sleep 100ms Thread-5 about to load... -Thread-3 about to load... Thread-6 about to load... Thread-7 about to load... Thread-8 about to load... -Thread-9 about to load... Thread-0 --- defineClass: done sleeping Thread-0 loaded. -Thread-9 loaded. Thread-8 loaded. +Thread-7 loaded. Thread-6 loaded. +Thread-9 about to load... +Thread-9 loaded. +Thread-5 loaded. +Thread-4 loaded. Thread-3 loaded. Thread-2 loaded. -Thread-7 loaded. Thread-1 loaded. -Thread-4 loaded. -Thread-5 loaded. Thread-0 about to load... +Thread-0 --- definePackage: about to sleep 100ms Thread-1 about to load... Thread-2 about to load... Thread-3 about to load... -Thread-0 --- definePackage: about to sleep 100ms Thread-4 about to load... Thread-5 about to load... Thread-6 about to load... Thread-7 about to load... Thread-8 about to load... -Thread-9 about to load... Thread-0 --- definePackage: done sleeping -Thread-6 loaded. -Thread-7 loaded. -Thread-9 loaded. -Thread-8 loaded. Thread-0 loaded. +Thread-8 loaded. +Thread-7 loaded. +Thread-6 loaded. Thread-5 loaded. Thread-4 loaded. +Thread-9 about to load... +Thread-9 loaded. Thread-3 loaded. Thread-2 loaded. Thread-1 loaded. @@ -1885,35 +1921,35 @@ hello world DefaultTextSource[ID=/groovy/script/ScriptEAC802B0B260F4204A095610E564BBBF, text='println 55'] DefaultTextSource[ID=/groovy/script/ScriptEAC802B0B260F4204A095610E564BBBF/FirstScript, text='println 55'] -DefaultUrlSource[ID=file:/tmp/gren13315204751484286870/MyScript.groovy] -DefaultFileSource[ID=/tmp/gren13543932578513569699/MyScript.groovy] +DefaultUrlSource[ID=file:/tmp/gren17412040393827625793/MyScript.groovy] +DefaultFileSource[ID=/tmp/gren16200624945958071053/MyScript.groovy] TEST Grengine concurrent - top code cache - parent first -Thread 1 5: 240931 411680 486402 550661 489183 -Thread 2 5: 238017 441520 473629 535782 464356 -Thread 3 5: 236482 330821 412953 515143 447029 -Thread 4 5: 237418 423602 417943 510223 437673 -TOTAL OK 5: 952848 1607623 1790927 2111809 1838241 -Script runs: 8301448 -Duration: 2553 ms -Average time per script run: 307 ns +Thread 2 5: 63007 74370 94931 148889 172572 +Thread 1 5: 56119 80233 190818 150723 220144 +Thread 3 5: 49736 73501 134843 153839 205597 +Thread 4 5: 49017 65380 127903 138905 177242 +TOTAL OK 5: 217879 293484 548495 592356 775555 +Script runs: 2427769 +Duration: 2591 ms +Average time per script run: 1067 ns TEST Grengine concurrent - top code cache - current first -Thread 1 5: 366971 431976 467547 444357 491710 -Thread 2 5: 344329 408481 418863 402435 437012 -Thread 3 5: 348716 400584 435541 268582 274019 -Thread 4 5: 428422 396335 441037 258859 366105 -TOTAL OK 5: 1488438 1637376 1762988 1374233 1568846 -Script runs: 7831881 -Duration: 2527 ms -Average time per script run: 322 ns +Thread 1 5: 189875 150769 135022 255303 157317 +Thread 4 5: 237453 143589 128213 440503 169635 +Thread 2 5: 191078 134005 150846 128946 160035 +Thread 3 5: 173779 143211 143630 125727 138495 +TOTAL OK 5: 792185 571574 557711 950479 625482 +Script runs: 3497431 +Duration: 2566 ms +Average time per script run: 733 ns TEST Grengine concurrent - no top code cache -Thread 1 5: 515798 463449 527351 610094 534556 -Thread 2 5: 468337 441904 522877 594925 540241 -Thread 3 5: 518970 425816 505140 571361 459312 -Thread 4 5: 489866 344788 497034 567170 522329 -TOTAL OK 5: 1992971 1675957 2052402 2343550 2056438 -Script runs: 10121318 -Duration: 2552 ms -Average time per script run: 252 ns +Thread 2 5: 233286 188349 195244 218927 202404 +Thread 1 5: 203603 261543 263623 183256 197558 +Thread 4 5: 203302 240764 235518 256574 207209 +Thread 3 5: 326558 188400 196728 166873 203316 +TOTAL OK 5: 966749 879056 891113 825630 810487 +Script runs: 4373035 +Duration: 2613 ms +Average time per script run: 597 ns . +-- JUnit Platform Suite [OK] +-- JUnit Jupiter [OK] @@ -2305,7 +2341,7 @@ | '-- testConstructSourcesCollectionNull() [OK] '-- JUnit Vintage [OK] -Test run finished after 21731 ms +Test run finished after 30298 ms [ 44 containers found ] [ 0 containers skipped ] [ 44 containers started ] @@ -2357,12 +2393,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/1451002/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/1451002/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/1128982 and its subdirectories -I: Current time: Sun May 5 21:13:52 -12 2024 -I: pbuilder-time-stamp: 1714986832 +I: removing directory /srv/workspace/pbuilder/1451002 and its subdirectories +I: Current time: Mon Jun 9 05:40:58 +14 2025 +I: pbuilder-time-stamp: 1749397258