Diff of the two buildlogs: -- --- b1/build.log 2023-04-25 00:54:25.628141244 +0000 +++ b2/build.log 2023-04-25 01:00:55.990838448 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Apr 24 12:50:48 -12 2023 -I: pbuilder-time-stamp: 1682383848 +I: Current time: Mon May 27 21:17:32 +14 2024 +I: pbuilder-time-stamp: 1716794252 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./grengine_3.0.0+dfsg.orig.tar.xz] I: copying [./grengine_3.0.0+dfsg-2.debian.tar.xz] I: Extracting source -gpgv: Signature made Tue Aug 17 20:21:57 2021 -12 +gpgv: Signature made Wed Aug 18 22:21:57 2021 +14 gpgv: using RSA key 33CB284313E90BD27DCB4523600316A6DC277476 gpgv: Can't check signature: No public key dpkg-source: warning: cannot verify inline signature for ./grengine_3.0.0+dfsg-2.dsc: no acceptable signature found @@ -28,52 +28,84 @@ dpkg-source: info: applying omit_tests_touching_home.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/12044/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/24833/tmp/hooks/D01_modify_environment starting +debug: Running on codethink13-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 27 21:17 /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/24833/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/24833/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='arm64' - DEBIAN_FRONTEND='noninteractive' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="15" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.15(1)-release' + BUILDDIR=/build + 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=8' - DISTRIBUTION='bookworm' - HOME='/var/lib/jenkins' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/var/lib/jenkins + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - 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='12044' - PS1='# ' - PS2='> ' + 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=24833 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.sMXb013R/pbuilderrc_St0l --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.sMXb013R/b1 --logfile b1/build.log grengine_3.0.0+dfsg-2.dsc' - SUDO_GID='117' - SUDO_UID='110' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - USERNAME='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.16: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.sMXb013R/pbuilderrc_wGAV --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.sMXb013R/b2 --logfile b2/build.log --extrapackages usrmerge grengine_3.0.0+dfsg-2.dsc' + SUDO_GID=117 + SUDO_UID=110 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + USERNAME=root + _='I: set' + http_proxy=http://192.168.101.16:3128 I: uname -a - Linux codethink14-arm64 4.15.0-209-generic #220-Ubuntu SMP Tue Mar 21 19:52:44 UTC 2023 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-209-generic #220-Ubuntu SMP Tue Mar 21 19:52:44 UTC 2023 aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Apr 21 22:25 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/12044/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 26 06:49 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/24833/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -381,7 +413,7 @@ Get: 250 http://deb.debian.org/debian bookworm/main arm64 gradle-debian-helper all 2.4 [24.5 kB] Get: 251 http://deb.debian.org/debian bookworm/main arm64 javahelper all 0.78 [97.2 kB] Get: 252 http://deb.debian.org/debian bookworm/main arm64 libjacoco-java all 0.8.6-2 [621 kB] -Fetched 389 MB in 31s (12.6 MB/s) +Fetched 389 MB in 50s (7776 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libpython3.11-minimal: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 ... 19616 files and directories currently installed.) @@ -1592,8 +1624,17 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +usrmerge is already the newest version (35). +0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/grengine-3.0.0+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.0+dfsg-2_source.changes +I: user script /srv/workspace/pbuilder/24833/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +I: user script /srv/workspace/pbuilder/24833/tmp/hooks/A99_set_merged_usr finished +hostname: Temporary failure in name resolution +I: Running cd /build/grengine-3.0.0+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.0+dfsg-2_source.changes dpkg-buildpackage: info: source package grengine dpkg-buildpackage: info: source version 3.0.0+dfsg-2 dpkg-buildpackage: info: source distribution unstable @@ -1626,11 +1667,11 @@ 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/grengine-3.0.0+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 2.55 secs. -The client will now receive all logging from the daemon (pid: 25818). The daemon log file: /build/grengine-3.0.0+dfsg/.gradle/daemon/4.4.1/daemon-25818.out.log +An attempt to start the daemon took 5.183 secs. +The client will now receive all logging from the daemon (pid: 18642). The daemon log file: /build/grengine-3.0.0+dfsg/.gradle/daemon/4.4.1/daemon-18642.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. -Daemon will be stopped at the end of the build stopping after processing Using 8 worker leases. Creating new cache for fileHashes, path /build/grengine-3.0.0+dfsg/.gradle/caches/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@6250b033 Creating new cache for resourceHashesCache, path /build/grengine-3.0.0+dfsg/.gradle/caches/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@6250b033 @@ -1654,29 +1695,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/grengine-3.0.0+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@3137293d +Creating new cache for annotation-processors, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@2f13d0f8 Tasks to be executed: [task ':compileJava', task ':processResources', task ':classes', task ':debianMavenPom', task ':jar'] Creating new cache for resourceHashesCache, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@2c6f5f6f -Creating new cache for taskHistory, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@58f4ec94 -Creating new cache for outputFiles, path /build/grengine-3.0.0+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@2946b26b +Creating new cache for taskHistory, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@688157e1 +Creating new cache for outputFiles, path /build/grengine-3.0.0+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@1dac23f7 :compileJava (Thread[Task worker for ':',5,main]) started. :compileJava -Putting task artifact state for task ':compileJava' into context took 0.076 secs. -Creating new cache for metadata-2.36/module-metadata, path /build/grengine-3.0.0+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@3888f816 +Putting task artifact state for task ':compileJava' into context took 0.072 secs. +Creating new cache for metadata-2.36/module-metadata, path /build/grengine-3.0.0+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@7e96d871 Loading the Maven rules... Replacing org.codehaus.groovy:groovy:jar:2.5.5 -> org.codehaus.groovy:groovy:jar:debian -Up-to-date check for task ':compileJava' took 4.894 secs. It is not up-to-date because: +Up-to-date check for task ':compileJava' took 6.418 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 15.725 secs. +:compileJava (Thread[Task worker for ':',5,main]) completed. Took 22.689 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/grengine-3.0.0+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.007 secs. +:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.019 secs. :classes (Thread[Task worker for ':',5,main]) started. :classes Skipping task ':classes' as it has no actions. @@ -1684,18 +1725,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.003 secs. It is not up-to-date because: +Up-to-date check for task ':debianMavenPom' took 0.009 secs. It is not up-to-date because: No history is available. Generating pom file /build/grengine-3.0.0+dfsg/build/debian/grengine.pom -:debianMavenPom (Thread[Task worker for ':',5,main]) completed. Took 0.257 secs. +:debianMavenPom (Thread[Task worker for ':',5,main]) completed. Took 0.766 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.032 secs. It is not up-to-date because: +Up-to-date check for task ':jar' took 0.114 secs. It is not up-to-date because: No history is available. -:jar (Thread[Task worker for ':',5,main]) completed. Took 0.195 secs. +:jar (Thread[Task worker for ':',5,main]) completed. Took 0.463 secs. -BUILD SUCCESSFUL in 34s +BUILD SUCCESSFUL in 58s 3 actionable tasks: 3 executed jh_build -O--buildsystem=gradle dh_auto_test -O--buildsystem=gradle @@ -1709,17 +1750,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/grengine-3.0.0+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 2.674 secs. -The client will now receive all logging from the daemon (pid: 26225). The daemon log file: /build/grengine-3.0.0+dfsg/.gradle/daemon/4.4.1/daemon-26225.out.log +An attempt to start the daemon took 5.211 secs. +The client will now receive all logging from the daemon (pid: 23326). The daemon log file: /build/grengine-3.0.0+dfsg/.gradle/daemon/4.4.1/daemon-23326.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 8 worker leases. -Creating new cache for fileHashes, path /build/grengine-3.0.0+dfsg/.gradle/caches/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@1d368ae3 -Creating new cache for resourceHashesCache, path /build/grengine-3.0.0+dfsg/.gradle/caches/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@1d368ae3 -Creating new cache for fileHashes, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@64cc7f57 +Creating new cache for fileHashes, path /build/grengine-3.0.0+dfsg/.gradle/caches/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@6250b033 +Creating new cache for resourceHashesCache, path /build/grengine-3.0.0+dfsg/.gradle/caches/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@6250b033 +Creating new cache for fileHashes, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileHashes/fileHashes.bin, access org.gradle.cache.internal.DefaultCacheAccess@2c6f5f6f Starting Build -Creating new cache for metadata-1.1/results, path /build/grengine-3.0.0+dfsg/.gradle/caches/transforms-1/metadata-1.1/results.bin, access org.gradle.cache.internal.DefaultCacheAccess@142d4605 +Creating new cache for metadata-1.1/results, path /build/grengine-3.0.0+dfsg/.gradle/caches/transforms-1/metadata-1.1/results.bin, access org.gradle.cache.internal.DefaultCacheAccess@5d0f6efe Settings evaluated using settings file '/build/grengine-3.0.0+dfsg/settings.gradle'. Settings file not found (/build/grengine-3.0.0+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.0+dfsg' @@ -1733,33 +1774,33 @@ Linking the generated javadoc to the system JDK API documentation All projects evaluated. Selected primary task 'test' from project : -Creating new cache for annotation-processors, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@217560ec +Creating new cache for annotation-processors, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileContent/annotation-processors.bin, access org.gradle.cache.internal.DefaultCacheAccess@1c8021c7 Tasks to be executed: [task ':compileJava', task ':processResources', task ':classes', task ':compileTestJava', task ':processTestResources', task ':testClasses', task ':test'] -Creating new cache for resourceHashesCache, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@64cc7f57 -Creating new cache for taskHistory, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@1b735aa9 -Creating new cache for outputFiles, path /build/grengine-3.0.0+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@55bd5a5e -:compileJava (Thread[Task worker for ':' Thread 2,5,main]) started. +Creating new cache for resourceHashesCache, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/fileHashes/resourceHashesCache.bin, access org.gradle.cache.internal.DefaultCacheAccess@2c6f5f6f +Creating new cache for taskHistory, path /build/grengine-3.0.0+dfsg/.gradle/4.4.1/taskHistory/taskHistory.bin, access org.gradle.cache.internal.DefaultCacheAccess@48596c93 +Creating new cache for outputFiles, path /build/grengine-3.0.0+dfsg/.gradle/buildOutputCleanup/outputFiles.bin, access org.gradle.cache.internal.DefaultCacheAccess@2d63b18 +:compileJava (Thread[Task worker for ':',5,main]) started. :compileJava -Putting task artifact state for task ':compileJava' into context took 0.011 secs. -Creating new cache for metadata-2.36/module-metadata, path /build/grengine-3.0.0+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@280305e4 +Putting task artifact state for task ':compileJava' into context took 0.088 secs. +Creating new cache for metadata-2.36/module-metadata, path /build/grengine-3.0.0+dfsg/.gradle/caches/modules-2/metadata-2.36/module-metadata.bin, access org.gradle.cache.internal.DefaultCacheAccess@2379b4fd Loading the Maven rules... Replacing org.codehaus.groovy:groovy:jar:2.5.5 -> org.codehaus.groovy:groovy:jar:debian -Skipping task ':compileJava' as it is up-to-date (took 0.683 secs). +Skipping task ':compileJava' as it is up-to-date (took 1.803 secs). :compileJava UP-TO-DATE -:compileJava (Thread[Task worker for ':' Thread 2,5,main]) completed. Took 0.766 secs. -:processResources (Thread[Task worker for ':' Thread 2,5,main]) started. +:compileJava (Thread[Task worker for ':',5,main]) completed. Took 2.342 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/grengine-3.0.0+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 ':' Thread 2,5,main]) completed. Took 0.008 secs. -:classes (Thread[Task worker for ':' Thread 2,5,main]) started. +:processResources (Thread[Task worker for ':',5,main]) completed. Took 0.036 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 ':' Thread 2,5,main]) completed. Took 0.002 secs. -:compileTestJava (Thread[Task worker for ':' Thread 2,5,main]) started. +:classes (Thread[Task worker for ':',5,main]) completed. Took 0.004 secs. +:compileTestJava (Thread[Task worker for ':',5,main]) started. :compileTestJava Putting task artifact state for task ':compileTestJava' into context took 0.0 secs. Passing through junit:junit:jar:4.x @@ -1770,30 +1811,30 @@ Passing through org.apache.commons:commons-parent:jar:debian Passing through org.apache:apache:jar:debian Passing through org.hamcrest:hamcrest:jar:debian -Up-to-date check for task ':compileTestJava' took 1.73 secs. It is not up-to-date because: +Up-to-date check for task ':compileTestJava' took 3.813 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 ':' Thread 2,5,main]) completed. Took 22.06 secs. -:processTestResources (Thread[Task worker for ':' Thread 2,5,main]) started. +:compileTestJava (Thread[Task worker for ':',5,main]) completed. Took 46.233 secs. +:processTestResources (Thread[Task worker for ':',5,main]) started. :processTestResources Putting task artifact state for task ':processTestResources' into context took 0.0 secs. file or directory '/build/grengine-3.0.0+dfsg/src/test/resources', not found Skipping task ':processTestResources' as it has no source files and no previous output files. :processTestResources NO-SOURCE -:processTestResources (Thread[Task worker for ':' Thread 2,5,main]) completed. Took 0.008 secs. -:testClasses (Thread[Task worker for ':' Thread 2,5,main]) started. +:processTestResources (Thread[Task worker for ':',5,main]) completed. Took 0.013 secs. +:testClasses (Thread[Task worker for ':',5,main]) started. :testClasses Skipping task ':testClasses' as it has no actions. -:testClasses (Thread[Task worker for ':' Thread 2,5,main]) completed. Took 0.001 secs. -:test (Thread[Task worker for ':' Thread 2,5,main]) started. +:testClasses (Thread[Task worker for ':',5,main]) completed. Took 0.001 secs. +:test (Thread[Task worker for ':',5,main]) started. :test Putting task artifact state for task ':test' into context took 0.0 secs. Replacing org.jacoco:org.jacoco.agent:jar:0.7.9 -> org.jacoco:org.jacoco.agent:jar:debian Passing through org.jacoco:org.jacoco.build:jar:debian -Up-to-date check for task ':test' took 0.685 secs. It is not up-to-date because: +Up-to-date check for task ':test' took 2.132 secs. It is not up-to-date because: No history is available. -Starting process 'Gradle Test Executor 1'. Working directory: /build/grengine-3.0.0+dfsg Command: /usr/lib/jvm/java-17-openjdk-arm64/bin/java -Dorg.gradle.native=false -javaagent:build/tmp/expandedArchives/org.jacoco.agent-debian.jar_bc1a9505e0683f49c4357991486dc83f/jacocoagent.jar=destfile=build/jacoco/test.exec,append=true,dumponexit=true,output=file @/tmp/gradle-worker-classpath17642365812548372144txt -Dfile.encoding=UTF-8 -Duser.country=US -Duser.language=en -Duser.variant -ea worker.org.gradle.process.internal.worker.GradleWorkerMain 'Gradle Test Executor 1' +Starting process 'Gradle Test Executor 1'. Working directory: /build/grengine-3.0.0+dfsg Command: /usr/lib/jvm/java-17-openjdk-arm64/bin/java -Dorg.gradle.native=false -javaagent:build/tmp/expandedArchives/org.jacoco.agent-debian.jar_bc1a9505e0683f49c4357991486dc83f/jacocoagent.jar=destfile=build/jacoco/test.exec,append=true,dumponexit=true,output=file @/tmp/gradle-worker-classpath12065682390917746342txt -Dfile.encoding=UTF-8 -Duser.country=US -Duser.language=en -Duser.variant -ea worker.org.gradle.process.internal.worker.GradleWorkerMain 'Gradle Test Executor 1' Successfully started process 'Gradle Test Executor 1' Gradle Test Executor 1 started executing tests. @@ -1803,35 +1844,35 @@ ch.artecat.grengine.load.BytecodeClassLoaderConcurrencyTest > testConcurrentMultiClassNoPackage STANDARD_OUT Thread-1 about to load... + Thread-1 --- defineClass: about to sleep 100ms Thread-0 about to load... - Thread-4 about to load... - Thread-3 about to load... - Thread-4 --- defineClass: about to sleep 100ms + Thread-0 --- defineClass: about to sleep 100ms Thread-2 about to load... - Thread-1 --- defineClass: about to sleep 100ms + 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-4 --- defineClass: done sleeping - Thread-8 loaded. - Thread-4 loaded. - Thread-6 loaded. - Thread-2 loaded. - Thread-0 loaded. Thread-1 --- defineClass: done sleeping Thread-1 loaded. + Thread-0 --- defineClass: done sleeping + Thread-0 loaded. Thread-9 loaded. Thread-7 loaded. Thread-5 loaded. + Thread-8 loaded. Thread-3 loaded. + Thread-6 loaded. + Thread-4 loaded. + Thread-2 loaded. ch.artecat.grengine.load.BytecodeClassLoaderConcurrencyTest > testConcurrentMultiClassWithPackage STANDARD_OUT 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-0 --- defineClass: about to sleep 100ms Thread-2 about to load... Thread-3 about to load... Thread-4 about to load... @@ -1841,68 +1882,68 @@ 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-4 loaded. + Thread-2 loaded. Thread-1 --- defineClass: done sleeping Thread-1 loaded. - Thread-9 loaded. - Thread-6 loaded. + Thread-7 loaded. Thread-5 loaded. Thread-3 loaded. - Thread-2 loaded. - Thread-7 loaded. - Thread-0 loaded. - Thread-8 loaded. - Thread-4 loaded. + Thread-9 loaded. ch.artecat.grengine.load.BytecodeClassLoaderConcurrencyTest > testConcurrentSingleClassNoPackage STANDARD_OUT Thread-0 about to load... + Thread-0 --- defineClass: about to sleep 100ms Thread-2 about to load... - Thread-3 about to load... Thread-1 about to load... - Thread-0 --- defineClass: about to sleep 100ms + 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-8 about to load... Thread-0 --- defineClass: done sleeping Thread-0 loaded. - Thread-9 loaded. Thread-8 loaded. + Thread-9 loaded. Thread-7 loaded. Thread-6 loaded. - Thread-5 loaded. Thread-3 loaded. - Thread-1 loaded. - Thread-4 loaded. Thread-2 loaded. + Thread-5 loaded. + Thread-4 loaded. + Thread-1 loaded. ch.artecat.grengine.load.BytecodeClassLoaderConcurrencyTest > testConcurrentSingleClassWithPackage STANDARD_OUT - Thread-3 about to load... Thread-0 about to load... + Thread-0 --- definePackage: about to sleep 100ms Thread-2 about to load... - Thread-1 about to load... - Thread-3 --- definePackage: about to sleep 100ms + Thread-3 about to load... Thread-5 about to load... Thread-4 about to load... Thread-6 about to load... Thread-7 about to load... - Thread-8 about to load... + Thread-1 about to load... Thread-9 about to load... - Thread-3 --- definePackage: done sleeping - Thread-3 loaded. - Thread-6 loaded. + Thread-8 about to load... + Thread-0 --- definePackage: done sleeping + Thread-0 loaded. Thread-8 loaded. Thread-9 loaded. + Thread-1 loaded. Thread-7 loaded. - Thread-4 loaded. + Thread-6 loaded. Thread-5 loaded. - Thread-1 loaded. + Thread-3 loaded. Thread-2 loaded. - Thread-0 loaded. + Thread-4 loaded. ch.artecat.grengine.source.DefaultFileSourceTest > testConstructFromFilePlusGetters STANDARD_OUT - DefaultFileSource[ID=/tmp/gren11404657541944822749/MyScript.groovy] + DefaultFileSource[ID=/tmp/gren4430486068298711531/MyScript.groovy] ch.artecat.grengine.source.DefaultTextSourceTest > testConstructFromTextPlusGetters STANDARD_OUT DefaultTextSource[ID=/groovy/script/ScriptEAC802B0B260F4204A095610E564BBBF, text='println 55'] @@ -1911,73 +1952,73 @@ DefaultTextSource[ID=/groovy/script/ScriptEAC802B0B260F4204A095610E564BBBF/FirstScript, text='println 55'] ch.artecat.grengine.source.DefaultUrlSourceTest > testConstructFromUrlPlusGetters STANDARD_OUT - DefaultUrlSource[ID=file:/tmp/gren4379884886007215474/MyScript.groovy] + DefaultUrlSource[ID=file:/tmp/gren12594814474418348362/MyScript.groovy] ch.artecat.grengine.GrengineConcurrencyTest > testConcurrentTopCodeCacheParentFirst STANDARD_OUT TEST Grengine concurrent - top code cache - parent first - Thread 1 5: 19939 43534 97481 127371 123691 - Thread 2 5: 19410 42023 93463 123691 124387 - Thread 3 5: 20719 34867 94804 120302 118740 - Thread 4 5: 21049 33902 92461 116971 119878 - TOTAL OK 5: 81117 154326 378209 488335 486696 - Script runs: 1588683 - Duration: 2585 ms - Average time per script run: 1627 ns + Thread 3 5: 664 3586 4738 6836 11983 + Thread 2 5: 891 3808 7765 13744 9094 + Thread 1 5: 508 3366 3729 7418 10753 + Thread 4 5: 858 4280 5656 5369 8415 + TOTAL OK 5: 2921 15040 21888 33367 40245 + Script runs: 113461 + Duration: 2713 ms + Average time per script run: 23911 ns ch.artecat.grengine.GrengineConcurrencyTest > testConcurrentTopCodeCacheCurrentFirst STANDARD_OUT TEST Grengine concurrent - top code cache - current first - Thread 1 5: 99917 118169 120057 113639 131597 - Thread 2 5: 98648 109447 119128 115157 130272 - Thread 3 5: 103541 110516 117878 107778 127442 - Thread 4 5: 102958 108450 111857 104328 124182 - TOTAL OK 5: 405064 446582 468920 440902 513493 - Script runs: 2274961 - Duration: 2537 ms - Average time per script run: 1115 ns + Thread 1 5: 7179 4969 8312 8252 12523 + Thread 4 5: 14632 7779 11156 11012 11699 + Thread 2 5: 13488 8909 9749 8841 14058 + Thread 3 5: 9474 3919 8846 7730 11939 + TOTAL OK 5: 44773 25576 38063 35835 50219 + Script runs: 194466 + Duration: 2666 ms + Average time per script run: 13709 ns ch.artecat.grengine.GrengineConcurrencyTest > testConcurrentNoTopCodeCache STANDARD_OUT TEST Grengine concurrent - no top code cache - Thread 1 5: 122068 141064 139866 139634 126750 - Thread 2 5: 123807 137549 132747 134807 125011 - Thread 3 5: 120691 136927 128976 130385 122158 - Thread 4 5: 123954 131643 125057 125669 117064 - TOTAL OK 5: 490520 547183 526646 530495 490983 - Script runs: 2585827 - Duration: 2554 ms - Average time per script run: 987 ns + Thread 2 5: 16384 13551 10835 14427 30568 + Thread 4 5: 12679 10247 6105 16426 25100 + Thread 1 5: 17127 19032 7766 18126 24247 + Thread 3 5: 17348 13975 10452 21877 22053 + TOTAL OK 5: 63538 56805 35158 70856 101968 + Script runs: 328325 + Duration: 2690 ms + Average time per script run: 8193 ns ch.artecat.grengine.engine.LayeredEngineConcurrencyTest > testConcurrentTopCodeCacheParentFirst STANDARD_OUT TEST LayeredEngine concurrent - top code cache - parent first - Thread 1 5: 147706 164522 171904 167683 160213 - Thread 2 5: 147949 159390 167169 163194 155344 - Thread 3 5: 148154 155498 162344 155895 152073 - Thread 4 5: 149206 153239 158980 154107 148687 - TOTAL OK 5: 593015 632649 660397 640879 616317 - Script runs: 3143257 - Duration: 2566 ms - Average time per script run: 816 ns + Thread 1 5: 22571 12615 26684 30668 64853 + Thread 4 5: 20983 13125 25308 31035 46746 + Thread 3 5: 20459 17560 24878 30193 36916 + Thread 2 5: 19043 13612 25880 24191 36196 + TOTAL OK 5: 83056 56912 102750 116087 184711 + Script runs: 543516 + Duration: 2823 ms + Average time per script run: 5193 ns ch.artecat.grengine.engine.LayeredEngineConcurrencyTest > testConcurrentTopCodeCacheCurrentFirst STANDARD_OUT TEST LayeredEngine concurrent - top code cache - current first - Thread 1 5: 149157 158209 145074 151155 157267 - Thread 2 5: 147843 153610 140947 146508 152295 - Thread 3 5: 149187 149848 138256 143222 149425 - Thread 4 5: 148051 146754 133828 138426 144894 - TOTAL OK 5: 594238 608421 558105 579311 603881 - Script runs: 2943956 - Duration: 2567 ms - Average time per script run: 871 ns + Thread 1 5: 29381 32356 38947 34116 44212 + Thread 4 5: 25506 29968 30273 44718 33830 + Thread 3 5: 27651 29573 22809 33392 30262 + Thread 2 5: 27609 31212 29456 43678 42562 + TOTAL OK 5: 110147 123109 121485 155904 150866 + Script runs: 661511 + Duration: 2754 ms + Average time per script run: 4163 ns ch.artecat.grengine.engine.LayeredEngineConcurrencyTest > testConcurrentNoTopCodeCache STANDARD_OUT TEST LayeredEngine concurrent - no top code cache - Thread 1 5: 164065 171528 167681 174843 163898 - Thread 2 5: 164865 166837 162326 168357 158785 - Thread 3 5: 164979 163763 158925 166252 152880 - Thread 4 5: 164820 160378 155623 160548 152479 - TOTAL OK 5: 658729 662506 644555 670000 628042 - Script runs: 3263832 - Duration: 2570 ms - Average time per script run: 787 ns + Thread 2 5: 41357 56047 50042 48907 34269 + Thread 1 5: 44525 52550 48287 40364 34308 + Thread 4 5: 40202 44438 51272 46784 31075 + Thread 3 5: 42307 51249 46764 41245 29738 + TOTAL OK 5: 168391 204284 196365 177300 129390 + Script runs: 875730 + Duration: 2793 ms + Average time per script run: 3189 ns ch.artecat.grengine.engine.LoaderTest > testConstructAndGetSetSourceClassLoader STANDARD_OUT Loader[engineId=ch.artecat.grengine.engine.EngineId@582c0fd8, number=17, isAttached=true] @@ -1991,17 +2032,17 @@ ch.artecat.grengine.GrengineGrapeTest > testNoGrapeByDefault SKIPPED ch.artecat.grengine.GrengineGrapeTest > testHelloWorldWithGrape SKIPPED -Gradle Test Executor 1 finished executing tests. WARNING: A terminally deprecated method in java.lang.System has been called -WARNING: System::setSecurityManager has been called by org.gradle.api.internal.tasks.testing.worker.TestWorker (file:/usr/share/gradle/lib/plugins/gradle-testing-base-4.4.1.jar) +WARNING: System::setSecurityManager has been called by org.gradle.api.internal.tasks.testing.worker.TestWorker (file:/usr/share/gradle/lib/plugins/gradle-testing-base-4.4.1.jar)Gradle Test Executor 1 finished executing tests. + WARNING: Please consider reporting this to the maintainers of org.gradle.api.internal.tasks.testing.worker.TestWorker WARNING: System::setSecurityManager will be removed in a future release -Finished generating test XML results (0.066 secs) into: /build/grengine-3.0.0+dfsg/build/test-results/test +Finished generating test XML results (0.093 secs) into: /build/grengine-3.0.0+dfsg/build/test-results/test Generating HTML test report... -Finished generating test html results (0.077 secs) into: /build/grengine-3.0.0+dfsg/build/reports/tests/test -:test (Thread[Task worker for ':' Thread 2,5,main]) completed. Took 33.474 secs. +Finished generating test html results (0.266 secs) into: /build/grengine-3.0.0+dfsg/build/reports/tests/test +:test (Thread[Task worker for ':',5,main]) completed. Took 1 mins 8.192 secs. -BUILD SUCCESSFUL in 1m 16s +BUILD SUCCESSFUL in 2m 28s 3 actionable tasks: 2 executed, 1 up-to-date create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=gradle @@ -2036,12 +2077,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/24833/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/24833/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/12044 and its subdirectories -I: Current time: Mon Apr 24 12:54:23 -12 2023 -I: pbuilder-time-stamp: 1682384063 +I: removing directory /srv/workspace/pbuilder/24833 and its subdirectories +I: Current time: Mon May 27 21:23:54 +14 2024 +I: pbuilder-time-stamp: 1716794634