Diff of the two buildlogs: -- --- b1/build.log 2024-05-26 18:06:26.918227063 +0000 +++ b2/build.log 2024-05-26 18:10:13.200580845 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun May 26 06:04:30 -12 2024 -I: pbuilder-time-stamp: 1716746670 +I: Current time: Mon May 27 08:06:30 +14 2024 +I: pbuilder-time-stamp: 1716746790 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/2628792/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/2942079/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 26 18:06 /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/2942079/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/2942079/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='29e714d28c5345adbeb6ba85f07c95c7' - 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='2628792' - PS1='# ' - PS2='> ' + INVOCATION_ID=d03a9c98ede542628ec8e6198b370890 + 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=2942079 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.mpEWacQZ/pbuilderrc_HqPH --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.mpEWacQZ/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.mpEWacQZ/pbuilderrc_Nt1V --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.mpEWacQZ/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 codethink04-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 May 25 07:43 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/2628792/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 25 07:42 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/2942079/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -367,7 +399,7 @@ Get: 239 http://deb.debian.org/debian unstable/main arm64 libpicocli-java all 4.6.2-2 [390 kB] Get: 240 http://deb.debian.org/debian unstable/main arm64 libunivocity-parsers-java all 2.9.1-1 [397 kB] Get: 241 http://deb.debian.org/debian unstable/main arm64 junit5 all 5.10.1-1 [2458 kB] -Fetched 231 MB in 1s (230 MB/s) +Fetched 231 MB in 2s (104 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 ... 19763 files and directories currently installed.) @@ -1163,8 +1195,8 @@ Setting up tzdata (2024a-4) ... Current default time zone: 'Etc/UTC' -Local time is now: Sun May 26 18:05:13 UTC 2024. -Universal Time is now: Sun May 26 18:05:13 UTC 2024. +Local time is now: Sun May 26 18:07:53 UTC 2024. +Universal Time is now: Sun May 26 18:07:53 UTC 2024. Run 'dpkg-reconfigure tzdata' if you wish to change it. Setting up libgeronimo-annotation-1.3-spec-java (1.3-1) ... @@ -1554,7 +1586,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/2942079/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/2942079/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 @@ -1587,18 +1623,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.699 secs. -The client will now receive all logging from the daemon (pid: 2652081). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-2652081.out.log +An attempt to start the daemon took 2.638 secs. +The client will now receive all logging from the daemon (pid: 2953221). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-2953221.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@3d563da1 -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@3d563da1 -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@6a843df +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@dca5231 +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@dca5231 +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@77e19462 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@5dde4f67 +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@547d9b4c 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) @@ -1615,29 +1651,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@a33228e +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@1eb9a080 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@6a843df -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@49d3fd91 -Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@5f3b0065 +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@77e19462 +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@68800150 +Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@3f5ba2c2 :compileJava (Thread[Task worker for ':',5,main]) started. :compileJava -Putting task artifact state for task ':compileJava' into context took 0.005 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@7e7b87d8 +Putting task artifact state for task ':compileJava' into context took 0.004 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@1b72a3c2 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.253 secs. It is not up-to-date because: +Up-to-date check for task ':compileJava' took 3.148 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 4.121 secs. +:compileJava (Thread[Task worker for ':',5,main]) completed. Took 8.242 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.012 secs. +:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.03 secs. :classes (Thread[Task worker for ':',5,main]) started. :classes Skipping task ':classes' as it has no actions. @@ -1645,18 +1681,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.008 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.123 secs. +:debianMavenPom (Thread[Task worker for ':',5,main]) completed. Took 0.222 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.036 secs. It is not up-to-date because: +Up-to-date check for task ':jar' took 0.039 secs. It is not up-to-date because: No history is available. -:jar (Thread[Task worker for ':',5,main]) completed. Took 0.138 secs. +:jar (Thread[Task worker for ':',5,main]) completed. Took 0.194 secs. -BUILD SUCCESSFUL in 12s +BUILD SUCCESSFUL in 20s 3 actionable tasks: 3 executed jh_build -O--buildsystem=gradle debian/rules override_dh_auto_test @@ -1672,17 +1708,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.054 secs. -The client will now receive all logging from the daemon (pid: 2654854). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-2654854.out.log +An attempt to start the daemon took 2.657 secs. +The client will now receive all logging from the daemon (pid: 2953545). The daemon log file: /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/daemon/4.4.1/daemon-2953545.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@62ebc5ff -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@62ebc5ff -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@42ab2309 +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@286f743c +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@286f743c +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@241c7b00 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@6dcae66b +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@7675ec0c 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' @@ -1696,32 +1732,32 @@ 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@7721271e +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@5559eedf 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@42ab2309 -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@5b7da65a -Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@6bc88194 +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@241c7b00 +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@57a30ea2 +Creating new cache for outputFiles, path /build/reproducible-path/grengine-3.0.2+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@30271e7c :compileJava (Thread[Task worker for ':',5,main]) started. :compileJava -Putting task artifact state for task ':compileJava' into context took 0.009 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@59e23fb2 +Putting task artifact state for task ':compileJava' into context took 0.004 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@13bed938 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.316 secs). +Skipping task ':compileJava' as it is up-to-date (took 1.559 secs). :compileJava UP-TO-DATE -:compileJava (Thread[Task worker for ':',5,main]) completed. Took 0.377 secs. +:compileJava (Thread[Task worker for ':',5,main]) completed. Took 1.62 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.01 secs. +:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.063 secs. :classes (Thread[Task worker for ':',5,main]) started. :classes Skipping task ':classes' as it has no actions. :classes UP-TO-DATE -:classes (Thread[Task worker for ':',5,main]) completed. Took 0.001 secs. +:classes (Thread[Task worker for ':',5,main]) completed. Took 0.0 secs. :compileTestJava (Thread[Task worker for ':',5,main]) started. :compileTestJava Putting task artifact state for task ':compileTestJava' into context took 0.0 secs. @@ -1737,13 +1773,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.88 secs. It is not up-to-date because: +Up-to-date check for task ':compileTestJava' took 3.099 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.257 secs. +:compileTestJava (Thread[Task worker for ':',5,main]) completed. Took 13.47 secs. -BUILD SUCCESSFUL in 11s +BUILD SUCCESSFUL in 26s 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 \ @@ -1752,158 +1788,158 @@ Thanks for using JUnit! Support its development at https://junit.org/sponsoring TEST LayeredEngine concurrent - top code cache - parent first -Thread 1 5: 203295 455623 523327 562044 562918 -Thread 2 5: 230270 399773 514024 538085 527372 -Thread 3 5: 260609 410648 497102 522568 513662 -Thread 4 5: 315651 420786 493758 527136 527599 -TOTAL OK 5: 1009825 1686830 2028211 2149833 2131551 -Script runs: 9006250 -Duration: 2572 ms -Average time per script run: 285 ns +Thread 1 5: 29068 202325 229283 220965 201035 +Thread 2 5: 51195 240850 291826 252854 251368 +Thread 3 5: 30794 201417 176216 208367 187916 +Thread 4 5: 36643 215703 185253 176446 311075 +TOTAL OK 5: 147700 860295 882578 858632 951394 +Script runs: 3700599 +Duration: 2615 ms +Average time per script run: 706 ns TEST LayeredEngine concurrent - top code cache - current first -Thread 1 5: 426247 512510 569516 564836 478807 -Thread 2 5: 420201 462291 523131 547526 512981 -Thread 3 5: 417499 475759 513903 532367 443600 -Thread 4 5: 391492 445324 474621 474978 470548 -TOTAL OK 5: 1655439 1895884 2081171 2119707 1905936 -Script runs: 9658137 -Duration: 2557 ms -Average time per script run: 264 ns +Thread 1 5: 203119 229997 293883 279194 254827 +Thread 4 5: 191386 210252 265385 240686 204332 +Thread 2 5: 230940 252225 290647 302344 608297 +Thread 3 5: 195336 216780 267707 247899 229164 +TOTAL OK 5: 820781 909254 1117622 1070123 1296620 +Script runs: 5214400 +Duration: 2596 ms +Average time per script run: 497 ns TEST LayeredEngine concurrent - no top code cache -Thread 1 5: 494035 516264 559403 526182 531680 -Thread 2 5: 454463 493527 533707 507935 511443 -Thread 3 5: 460374 481941 521185 498281 492560 -Thread 4 5: 492578 459122 510686 487933 489053 -TOTAL OK 5: 1901450 1950854 2124981 2020331 2024736 -Script runs: 10022352 -Duration: 2550 ms -Average time per script run: 254 ns +Thread 1 5: 219695 318561 342866 309828 297649 +Thread 2 5: 263303 316002 249298 342137 268829 +Thread 3 5: 225250 365089 344804 340064 293503 +Thread 4 5: 226674 280421 229703 280734 284645 +TOTAL OK 5: 934922 1280073 1166671 1272763 1144626 +Script runs: 5799055 +Duration: 2600 ms +Average time per script run: 448 ns Loader[engineId=ch.artecat.grengine.engine.EngineId@28782602, number=17, isAttached=true] +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-5 about to load... Thread-4 about to load... -Thread-2 about to load... -Thread-0 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-4 --- defineClass: about to sleep 100ms -Thread-1 --- defineClass: about to sleep 100ms -Thread-4 --- defineClass: done sleeping -Thread-1 --- defineClass: done sleeping -Thread-4 loaded. -Thread-8 loaded. -Thread-1 loaded. +Thread-0 --- defineClass: done sleeping Thread-0 loaded. Thread-6 loaded. +Thread-4 loaded. Thread-2 loaded. +Thread-8 about to load... +Thread-8 loaded. +Thread-1 --- defineClass: done sleeping +Thread-1 loaded. Thread-7 loaded. +Thread-5 loaded. Thread-3 loaded. +Thread-9 about to load... Thread-9 loaded. -Thread-5 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-5 about to load... -Thread-7 about to load... -Thread-8 about to load... Thread-6 about to load... -Thread-9 about to load... -Thread-0 --- defineClass: about to sleep 100ms -Thread-9 --- defineClass: about to sleep 100ms Thread-0 --- defineClass: done sleeping -Thread-9 --- defineClass: done sleeping Thread-0 loaded. -Thread-2 loaded. Thread-6 loaded. +Thread-4 loaded. +Thread-2 loaded. +Thread-7 about to load... +Thread-1 --- defineClass: done sleeping +Thread-1 loaded. +Thread-7 loaded. +Thread-8 about to load... Thread-8 loaded. Thread-5 loaded. Thread-3 loaded. -Thread-1 loaded. -Thread-7 loaded. +Thread-9 about to load... Thread-9 loaded. -Thread-4 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-0 --- defineClass: 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 --- defineClass: done sleeping Thread-0 loaded. -Thread-8 loaded. +Thread-6 loaded. +Thread-5 loaded. +Thread-7 about to load... Thread-7 loaded. -Thread-9 loaded. Thread-4 loaded. -Thread-2 loaded. -Thread-5 loaded. -Thread-6 loaded. Thread-3 loaded. +Thread-2 loaded. Thread-1 loaded. +Thread-8 about to load... +Thread-8 loaded. +Thread-9 about to load... +Thread-9 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-4 about to load... Thread-5 about to load... -Thread-0 --- definePackage: about to sleep 100ms 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-0 loaded. -Thread-9 loaded. -Thread-7 loaded. -Thread-8 loaded. Thread-6 loaded. +Thread-7 about to load... +Thread-7 loaded. Thread-5 loaded. Thread-4 loaded. Thread-3 loaded. +Thread-8 about to load... +Thread-8 loaded. Thread-2 loaded. Thread-1 loaded. +Thread-9 about to load... +Thread-9 loaded. hello world 123 hello world DefaultTextSource[ID=/groovy/script/ScriptEAC802B0B260F4204A095610E564BBBF, text='println 55'] DefaultTextSource[ID=/groovy/script/ScriptEAC802B0B260F4204A095610E564BBBF/FirstScript, text='println 55'] -DefaultUrlSource[ID=file:/tmp/gren16129628671094549726/MyScript.groovy] -DefaultFileSource[ID=/tmp/gren8963875047770243279/MyScript.groovy] +DefaultUrlSource[ID=file:/tmp/gren11371058487633480355/MyScript.groovy] +DefaultFileSource[ID=/tmp/gren15343983180597975816/MyScript.groovy] TEST Grengine concurrent - top code cache - parent first -Thread 1 5: 322010 527651 388599 431219 490984 -Thread 2 5: 271319 498477 303181 368181 475632 -Thread 3 5: 297579 419999 335390 374425 464090 -Thread 4 5: 303888 461887 344892 392455 444718 -TOTAL OK 5: 1194796 1908014 1372062 1566280 1875424 -Script runs: 7916576 -Duration: 2530 ms -Average time per script run: 319 ns +Thread 2 5: 58758 191742 231300 259484 239105 +Thread 1 5: 73315 201682 255654 316474 250371 +Thread 3 5: 53189 339291 236642 232773 307724 +Thread 4 5: 52446 161680 221830 238106 265312 +TOTAL OK 5: 237708 894395 945426 1046837 1062512 +Script runs: 4186878 +Duration: 2634 ms +Average time per script run: 629 ns TEST Grengine concurrent - top code cache - current first -Thread 2 5: 434915 495452 510263 510516 489051 -Thread 1 5: 441559 510743 518278 526606 447944 -Thread 3 5: 444095 485608 497080 498183 413603 -Thread 4 5: 440782 470464 483787 479576 358483 -TOTAL OK 5: 1761351 1962267 2009408 2014881 1709081 -Script runs: 9456988 -Duration: 2533 ms -Average time per script run: 267 ns +Thread 1 5: 238304 214569 235486 234816 215483 +Thread 2 5: 235322 216868 240264 220371 244825 +Thread 3 5: 309477 210765 211199 215381 259132 +Thread 4 5: 187303 205175 226151 243620 214100 +TOTAL OK 5: 970406 847377 913100 914188 933540 +Script runs: 4578611 +Duration: 2596 ms +Average time per script run: 566 ns TEST Grengine concurrent - no top code cache -Thread 1 5: 477393 473716 413302 502357 497107 -Thread 2 5: 477089 435959 403067 463733 468728 -Thread 3 5: 457379 417062 394222 436627 466731 -Thread 4 5: 481748 414682 411001 453776 429268 -TOTAL OK 5: 1893609 1741419 1621592 1856493 1861834 -Script runs: 8974947 -Duration: 2564 ms -Average time per script run: 285 ns +Thread 1 5: 287445 296567 281065 239506 275474 +Thread 2 5: 275491 330974 239406 222865 267433 +Thread 3 5: 325366 278197 343257 263533 289350 +Thread 4 5: 188171 250714 280787 229530 243588 +TOTAL OK 5: 1076473 1156452 1144515 955434 1075845 +Script runs: 5408719 +Duration: 2627 ms +Average time per script run: 485 ns . +-- JUnit Platform Suite [OK] +-- JUnit Jupiter [OK] @@ -2295,7 +2331,7 @@ | '-- testConstructSourcesCollectionNull() [OK] '-- JUnit Vintage [OK] -Test run finished after 21703 ms +Test run finished after 26095 ms [ 44 containers found ] [ 0 containers skipped ] [ 44 containers started ] @@ -2347,12 +2383,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/2942079/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/2942079/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/2628792 and its subdirectories -I: Current time: Sun May 26 06:06:25 -12 2024 -I: pbuilder-time-stamp: 1716746785 +I: removing directory /srv/workspace/pbuilder/2942079 and its subdirectories +I: Current time: Mon May 27 08:10:12 +14 2024 +I: pbuilder-time-stamp: 1716747012