Diff of the two buildlogs: -- --- b1/build.log 2023-05-31 20:48:51.173188184 +0000 +++ b2/build.log 2023-05-31 22:05:40.131290027 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed May 31 08:29:13 -12 2023 -I: pbuilder-time-stamp: 1685564953 +I: Current time: Thu Jun 1 10:49:42 +14 2023 +I: pbuilder-time-stamp: 1685566182 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 [./oscar4_5.2.0+dfsg.orig.tar.xz] I: copying [./oscar4_5.2.0+dfsg-2.debian.tar.xz] I: Extracting source -gpgv: Signature made Wed Oct 5 19:17:54 2022 -12 +gpgv: Signature made Thu Oct 6 21:17:54 2022 +14 gpgv: using RSA key 772292F6F7AC85FAE041D41EE5F43F9C2734F287 gpgv: issuer "merkys@debian.org" gpgv: Can't check signature: No public key @@ -34,135 +34,167 @@ dpkg-source: info: applying remove-inchi.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/31013/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/6444/tmp/hooks/D01_modify_environment starting +debug: Running on cbxi4a. +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 1 10:50 /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/6444/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/6444/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='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='bookworm' - HOME='/root' - HOST_ARCH='armhf' + 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]="arm-unknown-linux-gnueabihf") + 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=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='681c8914f8ee4123962fa33824e89a46' - 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='31013' - PS1='# ' - PS2='> ' + INVOCATION_ID=fd6ef563c7114f7b86b143ddb47e432d + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + 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=6444 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.smNTKhgS/pbuilderrc_0sxZ --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.smNTKhgS/b1 --logfile b1/build.log oscar4_5.2.0+dfsg-2.dsc' - SUDO_GID='113' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + 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.smNTKhgS/pbuilderrc_LIlN --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.smNTKhgS/b2 --logfile b2/build.log --extrapackages usrmerge oscar4_5.2.0+dfsg-2.dsc' + SUDO_GID=113 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux virt64b 5.10.0-23-arm64 #1 SMP Debian 5.10.179-1 (2023-05-12) aarch64 GNU/Linux + Linux i-capture-the-hostname 5.10.0-23-armmp #1 SMP Debian 5.10.179-1 (2023-05-12) armv7l GNU/Linux I: ls -l /bin total 5072 - -rwxr-xr-x 1 root root 838488 Apr 23 09:24 bash - -rwxr-xr-x 3 root root 67144 Sep 18 2022 bunzip2 - -rwxr-xr-x 3 root root 67144 Sep 18 2022 bzcat - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzcmp -> bzdiff - -rwxr-xr-x 1 root root 2225 Sep 18 2022 bzdiff - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzegrep -> bzgrep - -rwxr-xr-x 1 root root 4893 Nov 27 2021 bzexe - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzfgrep -> bzgrep - -rwxr-xr-x 1 root root 3775 Sep 18 2022 bzgrep - -rwxr-xr-x 3 root root 67144 Sep 18 2022 bzip2 - -rwxr-xr-x 1 root root 67112 Sep 18 2022 bzip2recover - lrwxrwxrwx 1 root root 6 Sep 18 2022 bzless -> bzmore - -rwxr-xr-x 1 root root 1297 Sep 18 2022 bzmore - -rwxr-xr-x 1 root root 67632 Sep 20 2022 cat - -rwxr-xr-x 1 root root 67676 Sep 20 2022 chgrp - -rwxr-xr-x 1 root root 67644 Sep 20 2022 chmod - -rwxr-xr-x 1 root root 67684 Sep 20 2022 chown - -rwxr-xr-x 1 root root 133532 Sep 20 2022 cp - -rwxr-xr-x 1 root root 132868 Jan 5 01:20 dash - -rwxr-xr-x 1 root root 133220 Sep 20 2022 date - -rwxr-xr-x 1 root root 67732 Sep 20 2022 dd - -rwxr-xr-x 1 root root 68104 Sep 20 2022 df - -rwxr-xr-x 1 root root 133632 Sep 20 2022 dir - -rwxr-xr-x 1 root root 59128 Mar 22 21:02 dmesg - lrwxrwxrwx 1 root root 8 Dec 19 01:33 dnsdomainname -> hostname - lrwxrwxrwx 1 root root 8 Dec 19 01:33 domainname -> hostname - -rwxr-xr-x 1 root root 67560 Sep 20 2022 echo - -rwxr-xr-x 1 root root 41 Jan 24 02:43 egrep - -rwxr-xr-x 1 root root 67548 Sep 20 2022 false - -rwxr-xr-x 1 root root 41 Jan 24 02:43 fgrep - -rwxr-xr-x 1 root root 55748 Mar 22 21:02 findmnt - -rwsr-xr-x 1 root root 26208 Mar 22 20:15 fusermount - -rwxr-xr-x 1 root root 128608 Jan 24 02:43 grep - -rwxr-xr-x 2 root root 2346 Apr 9 2022 gunzip - -rwxr-xr-x 1 root root 6447 Apr 9 2022 gzexe - -rwxr-xr-x 1 root root 64220 Apr 9 2022 gzip - -rwxr-xr-x 1 root root 67032 Dec 19 01:33 hostname - -rwxr-xr-x 1 root root 67720 Sep 20 2022 ln - -rwxr-xr-x 1 root root 35132 Mar 22 21:51 login - -rwxr-xr-x 1 root root 133632 Sep 20 2022 ls - -rwxr-xr-x 1 root root 136808 Mar 22 21:02 lsblk - -rwxr-xr-x 1 root root 67800 Sep 20 2022 mkdir - -rwxr-xr-x 1 root root 67764 Sep 20 2022 mknod - -rwxr-xr-x 1 root root 67596 Sep 20 2022 mktemp - -rwxr-xr-x 1 root root 38504 Mar 22 21:02 more - -rwsr-xr-x 1 root root 38496 Mar 22 21:02 mount - -rwxr-xr-x 1 root root 9824 Mar 22 21:02 mountpoint - -rwxr-xr-x 1 root root 133532 Sep 20 2022 mv - lrwxrwxrwx 1 root root 8 Dec 19 01:33 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Apr 2 18:25 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 67608 Sep 20 2022 pwd - lrwxrwxrwx 1 root root 4 Apr 23 09:24 rbash -> bash - -rwxr-xr-x 1 root root 67600 Sep 20 2022 readlink - -rwxr-xr-x 1 root root 67672 Sep 20 2022 rm - -rwxr-xr-x 1 root root 67600 Sep 20 2022 rmdir - -rwxr-xr-x 1 root root 67400 Nov 2 2022 run-parts - -rwxr-xr-x 1 root root 133372 Jan 5 07:55 sed - lrwxrwxrwx 1 root root 4 Jan 5 01:20 sh -> dash - -rwxr-xr-x 1 root root 67584 Sep 20 2022 sleep - -rwxr-xr-x 1 root root 67644 Sep 20 2022 stty - -rwsr-xr-x 1 root root 50800 Mar 22 21:02 su - -rwxr-xr-x 1 root root 67584 Sep 20 2022 sync - -rwxr-xr-x 1 root root 336764 Apr 6 02:25 tar - -rwxr-xr-x 1 root root 67144 Nov 2 2022 tempfile - -rwxr-xr-x 1 root root 133224 Sep 20 2022 touch - -rwxr-xr-x 1 root root 67548 Sep 20 2022 true - -rwxr-xr-x 1 root root 9768 Mar 22 20:15 ulockmgr_server - -rwsr-xr-x 1 root root 22108 Mar 22 21:02 umount - -rwxr-xr-x 1 root root 67572 Sep 20 2022 uname - -rwxr-xr-x 2 root root 2346 Apr 9 2022 uncompress - -rwxr-xr-x 1 root root 133632 Sep 20 2022 vdir - -rwxr-xr-x 1 root root 42608 Mar 22 21:02 wdctl - lrwxrwxrwx 1 root root 8 Dec 19 01:33 ypdomainname -> hostname - -rwxr-xr-x 1 root root 1984 Apr 9 2022 zcat - -rwxr-xr-x 1 root root 1678 Apr 9 2022 zcmp - -rwxr-xr-x 1 root root 6460 Apr 9 2022 zdiff - -rwxr-xr-x 1 root root 29 Apr 9 2022 zegrep - -rwxr-xr-x 1 root root 29 Apr 9 2022 zfgrep - -rwxr-xr-x 1 root root 2081 Apr 9 2022 zforce - -rwxr-xr-x 1 root root 8103 Apr 9 2022 zgrep - -rwxr-xr-x 1 root root 2206 Apr 9 2022 zless - -rwxr-xr-x 1 root root 1842 Apr 9 2022 zmore - -rwxr-xr-x 1 root root 4577 Apr 9 2022 znew -I: user script /srv/workspace/pbuilder/31013/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 838488 Apr 24 11:24 bash + -rwxr-xr-x 3 root root 67144 Sep 19 2022 bunzip2 + -rwxr-xr-x 3 root root 67144 Sep 19 2022 bzcat + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzcmp -> bzdiff + -rwxr-xr-x 1 root root 2225 Sep 19 2022 bzdiff + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzegrep -> bzgrep + -rwxr-xr-x 1 root root 4893 Nov 28 2021 bzexe + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzfgrep -> bzgrep + -rwxr-xr-x 1 root root 3775 Sep 19 2022 bzgrep + -rwxr-xr-x 3 root root 67144 Sep 19 2022 bzip2 + -rwxr-xr-x 1 root root 67112 Sep 19 2022 bzip2recover + lrwxrwxrwx 1 root root 6 Sep 19 2022 bzless -> bzmore + -rwxr-xr-x 1 root root 1297 Sep 19 2022 bzmore + -rwxr-xr-x 1 root root 67632 Sep 21 2022 cat + -rwxr-xr-x 1 root root 67676 Sep 21 2022 chgrp + -rwxr-xr-x 1 root root 67644 Sep 21 2022 chmod + -rwxr-xr-x 1 root root 67684 Sep 21 2022 chown + -rwxr-xr-x 1 root root 133532 Sep 21 2022 cp + -rwxr-xr-x 1 root root 132868 Jan 6 03:20 dash + -rwxr-xr-x 1 root root 133220 Sep 21 2022 date + -rwxr-xr-x 1 root root 67732 Sep 21 2022 dd + -rwxr-xr-x 1 root root 68104 Sep 21 2022 df + -rwxr-xr-x 1 root root 133632 Sep 21 2022 dir + -rwxr-xr-x 1 root root 59128 Mar 23 23:02 dmesg + lrwxrwxrwx 1 root root 8 Dec 20 03:33 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Dec 20 03:33 domainname -> hostname + -rwxr-xr-x 1 root root 67560 Sep 21 2022 echo + -rwxr-xr-x 1 root root 41 Jan 25 04:43 egrep + -rwxr-xr-x 1 root root 67548 Sep 21 2022 false + -rwxr-xr-x 1 root root 41 Jan 25 04:43 fgrep + -rwxr-xr-x 1 root root 55748 Mar 23 23:02 findmnt + -rwsr-xr-x 1 root root 26208 Mar 23 22:15 fusermount + -rwxr-xr-x 1 root root 128608 Jan 25 04:43 grep + -rwxr-xr-x 2 root root 2346 Apr 10 2022 gunzip + -rwxr-xr-x 1 root root 6447 Apr 10 2022 gzexe + -rwxr-xr-x 1 root root 64220 Apr 10 2022 gzip + -rwxr-xr-x 1 root root 67032 Dec 20 03:33 hostname + -rwxr-xr-x 1 root root 67720 Sep 21 2022 ln + -rwxr-xr-x 1 root root 35132 Mar 23 23:51 login + -rwxr-xr-x 1 root root 133632 Sep 21 2022 ls + -rwxr-xr-x 1 root root 136808 Mar 23 23:02 lsblk + -rwxr-xr-x 1 root root 67800 Sep 21 2022 mkdir + -rwxr-xr-x 1 root root 67764 Sep 21 2022 mknod + -rwxr-xr-x 1 root root 67596 Sep 21 2022 mktemp + -rwxr-xr-x 1 root root 38504 Mar 23 23:02 more + -rwsr-xr-x 1 root root 38496 Mar 23 23:02 mount + -rwxr-xr-x 1 root root 9824 Mar 23 23:02 mountpoint + -rwxr-xr-x 1 root root 133532 Sep 21 2022 mv + lrwxrwxrwx 1 root root 8 Dec 20 03:33 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Apr 3 20:25 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 67608 Sep 21 2022 pwd + lrwxrwxrwx 1 root root 4 Apr 24 11:24 rbash -> bash + -rwxr-xr-x 1 root root 67600 Sep 21 2022 readlink + -rwxr-xr-x 1 root root 67672 Sep 21 2022 rm + -rwxr-xr-x 1 root root 67600 Sep 21 2022 rmdir + -rwxr-xr-x 1 root root 67400 Nov 3 2022 run-parts + -rwxr-xr-x 1 root root 133372 Jan 6 09:55 sed + lrwxrwxrwx 1 root root 9 Jun 1 10:50 sh -> /bin/bash + -rwxr-xr-x 1 root root 67584 Sep 21 2022 sleep + -rwxr-xr-x 1 root root 67644 Sep 21 2022 stty + -rwsr-xr-x 1 root root 50800 Mar 23 23:02 su + -rwxr-xr-x 1 root root 67584 Sep 21 2022 sync + -rwxr-xr-x 1 root root 336764 Apr 7 04:25 tar + -rwxr-xr-x 1 root root 67144 Nov 3 2022 tempfile + -rwxr-xr-x 1 root root 133224 Sep 21 2022 touch + -rwxr-xr-x 1 root root 67548 Sep 21 2022 true + -rwxr-xr-x 1 root root 9768 Mar 23 22:15 ulockmgr_server + -rwsr-xr-x 1 root root 22108 Mar 23 23:02 umount + -rwxr-xr-x 1 root root 67572 Sep 21 2022 uname + -rwxr-xr-x 2 root root 2346 Apr 10 2022 uncompress + -rwxr-xr-x 1 root root 133632 Sep 21 2022 vdir + -rwxr-xr-x 1 root root 42608 Mar 23 23:02 wdctl + lrwxrwxrwx 1 root root 8 Dec 20 03:33 ypdomainname -> hostname + -rwxr-xr-x 1 root root 1984 Apr 10 2022 zcat + -rwxr-xr-x 1 root root 1678 Apr 10 2022 zcmp + -rwxr-xr-x 1 root root 6460 Apr 10 2022 zdiff + -rwxr-xr-x 1 root root 29 Apr 10 2022 zegrep + -rwxr-xr-x 1 root root 29 Apr 10 2022 zfgrep + -rwxr-xr-x 1 root root 2081 Apr 10 2022 zforce + -rwxr-xr-x 1 root root 8103 Apr 10 2022 zgrep + -rwxr-xr-x 1 root root 2206 Apr 10 2022 zless + -rwxr-xr-x 1 root root 1842 Apr 10 2022 zmore + -rwxr-xr-x 1 root root 4577 Apr 10 2022 znew +I: user script /srv/workspace/pbuilder/6444/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -506,7 +538,7 @@ Get: 278 http://deb.debian.org/debian bookworm/main armhf maven all 3.8.7-1 [18.9 kB] Get: 279 http://deb.debian.org/debian bookworm/main armhf maven-repo-helper all 1.11 [142 kB] Get: 280 http://deb.debian.org/debian bookworm/main armhf maven-debian-helper all 2.6.3 [108 kB] -Fetched 445 MB in 47s (9485 kB/s) +Fetched 445 MB in 47s (9441 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sgml-base. (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 ... 19324 files and directories currently installed.) @@ -1826,8 +1858,19 @@ 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/oscar4-5.2.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 > ../oscar4_5.2.0+dfsg-2_source.changes +I: user script /srv/workspace/pbuilder/6444/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +removed '/etc/unsupported-skip-usrmerge-conversion' +The system has been successfully converted. +I: user script /srv/workspace/pbuilder/6444/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/oscar4-5.2.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 > ../oscar4_5.2.0+dfsg-2_source.changes dpkg-buildpackage: info: source package oscar4 dpkg-buildpackage: info: source version 5.2.0+dfsg-2 dpkg-buildpackage: info: source distribution unstable @@ -2201,24 +2244,24 @@ [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary for oscar4-parent 5.2.0: [INFO] -[INFO] oscar4-parent ...................................... SUCCESS [ 1.124 s] -[INFO] oscar4-core ........................................ SUCCESS [ 12.196 s] -[INFO] Chemical Name Dictionaries ......................... SUCCESS [ 4.569 s] -[INFO] oscar4-obo ......................................... SUCCESS [ 5.615 s] -[INFO] oscar4-tokenizer ................................... SUCCESS [ 1.552 s] -[INFO] oscar4-recogniser-core ............................. SUCCESS [ 3.991 s] -[INFO] oscar4-memmrecogniser .............................. SUCCESS [ 2.891 s] -[INFO] oscar4-patternrecogniser ........................... SUCCESS [ 0.967 s] -[INFO] oscar4-opsin ....................................... SUCCESS [ 0.586 s] -[INFO] oscar4-api ......................................... SUCCESS [ 0.814 s] -[INFO] oscar4-preprocessor ................................ SUCCESS [ 0.392 s] -[INFO] oscar4-formatter ................................... SUCCESS [ 0.257 s] -[INFO] oscar4-data ........................................ SUCCESS [ 0.976 s] +[INFO] oscar4-parent ...................................... SUCCESS [ 8.473 s] +[INFO] oscar4-core ........................................ SUCCESS [01:28 min] +[INFO] Chemical Name Dictionaries ......................... SUCCESS [ 26.946 s] +[INFO] oscar4-obo ......................................... SUCCESS [ 41.310 s] +[INFO] oscar4-tokenizer ................................... SUCCESS [ 14.963 s] +[INFO] oscar4-recogniser-core ............................. SUCCESS [ 30.846 s] +[INFO] oscar4-memmrecogniser .............................. SUCCESS [ 25.959 s] +[INFO] oscar4-patternrecogniser ........................... SUCCESS [ 6.597 s] +[INFO] oscar4-opsin ....................................... SUCCESS [ 4.563 s] +[INFO] oscar4-api ......................................... SUCCESS [ 9.158 s] +[INFO] oscar4-preprocessor ................................ SUCCESS [ 4.046 s] +[INFO] oscar4-formatter ................................... SUCCESS [ 3.005 s] +[INFO] oscar4-data ........................................ SUCCESS [ 7.956 s] [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ -[INFO] Total time: 36.570 s -[INFO] Finished at: 2023-05-31T08:34:30-12:00 +[INFO] Total time: 04:35 min +[INFO] Finished at: 2023-06-01T11:05:56+14:00 [INFO] ------------------------------------------------------------------------  dh_auto_test /usr/lib/jvm/default-java/bin/java -noverify -cp /usr/share/maven/boot/plexus-classworlds-2.x.jar -Dmaven.home=/usr/share/maven -Dmaven.multiModuleProjectDirectory=/build/oscar4-5.2.0\+dfsg -Dclassworlds.conf=/etc/maven/m2-debian.conf org.codehaus.plexus.classworlds.launcher.Launcher -s/etc/maven/settings-debian.xml -Ddebian.dir=/build/oscar4-5.2.0\+dfsg/debian -Dmaven.repo.local=/build/oscar4-5.2.0\+dfsg/debian/maven-repo --batch-mode test @@ -2271,37 +2314,37 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- -[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.NamedEntitityTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.195 s - in uk.ac.cam.ch.wwmm.oscar.document.NamedEntitityTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.TokenSequenceTest -[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.005 s - in uk.ac.cam.ch.wwmm.oscar.document.TokenSequenceTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.TokenTest -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in uk.ac.cam.ch.wwmm.oscar.document.TokenTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.types.BioTagTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscar.types.BioTagTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.types.NamedEntityTypeTest -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in uk.ac.cam.ch.wwmm.oscar.types.NamedEntityTypeTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.types.BioTypeTest -[INFO] Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.006 s - in uk.ac.cam.ch.wwmm.oscar.types.BioTypeTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.tools.StringToolsTest -[INFO] Tests run: 17, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.02 s - in uk.ac.cam.ch.wwmm.oscar.tools.StringToolsTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.tools.ResourceGetterTest -[WARNING] Tests run: 9, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.326 s - in uk.ac.cam.ch.wwmm.oscar.tools.ResourceGetterTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.tools.StandoffTableTest -[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.02 s - in uk.ac.cam.ch.wwmm.oscar.tools.StandoffTableTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.util.CacheMapTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscar.util.CacheMapTest [INFO] Running uk.ac.cam.ch.wwmm.oscar.terms.TermSetsTest SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 23, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.727 s - in uk.ac.cam.ch.wwmm.oscar.terms.TermSetsTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.xmltools.XOMToolsTest -[INFO] Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in uk.ac.cam.ch.wwmm.oscar.xmltools.XOMToolsTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.normalize.NormalizerTest -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscar.normalize.NormalizerTest +[INFO] Tests run: 23, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 9.078 s - in uk.ac.cam.ch.wwmm.oscar.terms.TermSetsTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.types.NamedEntityTypeTest +[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.039 s - in uk.ac.cam.ch.wwmm.oscar.types.NamedEntityTypeTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.types.BioTagTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.019 s - in uk.ac.cam.ch.wwmm.oscar.types.BioTagTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.types.BioTypeTest +[INFO] Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.061 s - in uk.ac.cam.ch.wwmm.oscar.types.BioTypeTest [INFO] Running uk.ac.cam.ch.wwmm.oscar.normalize.NormalizingReaderTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscar.normalize.NormalizingReaderTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.006 s - in uk.ac.cam.ch.wwmm.oscar.normalize.NormalizingReaderTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.normalize.NormalizerTest +[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.034 s - in uk.ac.cam.ch.wwmm.oscar.normalize.NormalizerTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.util.CacheMapTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.012 s - in uk.ac.cam.ch.wwmm.oscar.util.CacheMapTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.xmltools.XOMToolsTest +[INFO] Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.035 s - in uk.ac.cam.ch.wwmm.oscar.xmltools.XOMToolsTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.TokenSequenceTest +[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.046 s - in uk.ac.cam.ch.wwmm.oscar.document.TokenSequenceTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.TokenTest +[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.025 s - in uk.ac.cam.ch.wwmm.oscar.document.TokenTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.NamedEntitityTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 s - in uk.ac.cam.ch.wwmm.oscar.document.NamedEntitityTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.tools.StandoffTableTest +[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.021 s - in uk.ac.cam.ch.wwmm.oscar.tools.StandoffTableTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.tools.StringToolsTest +[INFO] Tests run: 17, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.26 s - in uk.ac.cam.ch.wwmm.oscar.tools.StringToolsTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.tools.ResourceGetterTest +[WARNING] Tests run: 9, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.62 s - in uk.ac.cam.ch.wwmm.oscar.tools.ResourceGetterTest [INFO] [INFO] Results: [INFO] @@ -2331,27 +2374,27 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ChemicalStructureTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.237 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ChemicalStructureTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ResolvedNamedEntityTest -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.025 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ResolvedNamedEntityTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.tools.ResourceGetterTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.438 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.tools.ResourceGetterTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.MutableChemNameDictTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.027 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.MutableChemNameDictTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ChemNameDictRegistryTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.DefaultDictionaryTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.095 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.DefaultDictionaryTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.PolymerDictionaryTest SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.675 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ChemNameDictRegistryTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ImmutableChemNameDictTest -[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ImmutableChemNameDictTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.PolymerDictionaryTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.PolymerDictionaryTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.DefaultDictionaryTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.061 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.DefaultDictionaryTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.252 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.PolymerDictionaryTest [INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.ChEBIDictionaryTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.ChEBIDictionaryTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 27.313 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.dictionaries.ChEBIDictionaryTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ImmutableChemNameDictTest +[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.059 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ImmutableChemNameDictTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ChemNameDictRegistryTest +[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.878 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.ChemNameDictRegistryTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.MutableChemNameDictTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.core.MutableChemNameDictTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ChemicalStructureTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ChemicalStructureTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ResolvedNamedEntityTest +[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.009 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.entities.ResolvedNamedEntityTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.chemnamedict.tools.ResourceGetterTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscar.chemnamedict.tools.ResourceGetterTest [INFO] [INFO] Results: [INFO] @@ -2381,17 +2424,17 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- -[INFO] Running uk.ac.cam.ch.wwmm.oscar.obo.OBOOntologyTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.ont.OntologyTermsTest SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 33.55 s - in uk.ac.cam.ch.wwmm.oscar.obo.OBOOntologyTest +[INFO] Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 22.062 s - in uk.ac.cam.ch.wwmm.oscar.ont.OntologyTermsTest [INFO] Running uk.ac.cam.ch.wwmm.oscar.ont.EquivalentTermSetTest -[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.066 s - in uk.ac.cam.ch.wwmm.oscar.ont.EquivalentTermSetTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.ont.OntologyTermsTest -[INFO] Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.405 s - in uk.ac.cam.ch.wwmm.oscar.ont.OntologyTermsTest +[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 s - in uk.ac.cam.ch.wwmm.oscar.ont.EquivalentTermSetTest [INFO] Running uk.ac.cam.ch.wwmm.oscar.ont.TermsFileReaderTest -[INFO] Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.238 s - in uk.ac.cam.ch.wwmm.oscar.ont.TermsFileReaderTest +[INFO] Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 18.189 s - in uk.ac.cam.ch.wwmm.oscar.ont.TermsFileReaderTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.obo.OBOOntologyTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 223.556 s - in uk.ac.cam.ch.wwmm.oscar.obo.OBOOntologyTest [INFO] [INFO] Results: [INFO] @@ -2421,19 +2464,19 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- -[INFO] Running uk.ac.cam.ch.wwmm.oscartokeniser.HyphenTokeniserTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.ProcessingDocumentFactoryTest SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.211 s - in uk.ac.cam.ch.wwmm.oscartokeniser.HyphenTokeniserTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.558 s - in uk.ac.cam.ch.wwmm.oscar.document.ProcessingDocumentFactoryTest +[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.AdditionalTokenSequenceTest +[INFO] Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.232 s - in uk.ac.cam.ch.wwmm.oscar.document.AdditionalTokenSequenceTest [INFO] Running uk.ac.cam.ch.wwmm.oscartokeniser.TokenClassifierTest -[INFO] Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.92 s - in uk.ac.cam.ch.wwmm.oscartokeniser.TokenClassifierTest +[INFO] Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.822 s - in uk.ac.cam.ch.wwmm.oscartokeniser.TokenClassifierTest [INFO] Running uk.ac.cam.ch.wwmm.oscartokeniser.TokeniserTest -[WARNING] Tests run: 35, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.071 s - in uk.ac.cam.ch.wwmm.oscartokeniser.TokeniserTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.ProcessingDocumentFactoryTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in uk.ac.cam.ch.wwmm.oscar.document.ProcessingDocumentFactoryTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.document.AdditionalTokenSequenceTest -[INFO] Tests run: 8, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 s - in uk.ac.cam.ch.wwmm.oscar.document.AdditionalTokenSequenceTest +[WARNING] Tests run: 35, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 7.883 s - in uk.ac.cam.ch.wwmm.oscartokeniser.TokeniserTest +[INFO] Running uk.ac.cam.ch.wwmm.oscartokeniser.HyphenTokeniserTest +[INFO] Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.052 s - in uk.ac.cam.ch.wwmm.oscartokeniser.HyphenTokeniserTest [INFO] [INFO] Results: [INFO] @@ -2463,29 +2506,29 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- -[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.regex.RegexRecogniserTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.saf.StandoffResolverTest +[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.903 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.saf.StandoffResolverTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.PrefixFinderTest +[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 15.575 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.PrefixFinderTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.NGramBuilderTest SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.694 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.regex.RegexRecogniserTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.saf.StandoffResolverTest -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.saf.StandoffResolverTest +[WARNING] Tests run: 11, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 266.398 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.NGramBuilderTest [INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.extractedtrainingdata.ExtractedTrainingDataTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.96 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.extractedtrainingdata.ExtractedTrainingDataTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAONTCPRFinderTest -[INFO] Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 9.229 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAONTCPRFinderTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.395 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.extractedtrainingdata.ExtractedTrainingDataTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.regex.RegexRecogniserTest +[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 8.734 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.regex.RegexRecogniserTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAFinderTest +[WARNING] Tests run: 8, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 89.908 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAFinderTest [INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFASupplementaryTermFinderTest -[WARNING] Tests run: 6, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.036 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFASupplementaryTermFinderTest +[WARNING] Tests run: 6, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 0.168 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFASupplementaryTermFinderTest [INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.finder.TermMapsTest [INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.TermMapsTest [INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.finder.AutomatonStateTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.AutomatonStateTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAFinderTest -[WARNING] Tests run: 8, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 15.089 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAFinderTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.PrefixFinderTest -[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.926 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.PrefixFinderTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.NGramBuilderTest -[WARNING] Tests run: 11, Failures: 0, Errors: 0, Skipped: 3, Time elapsed: 56.277 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.tokenanalysis.NGramBuilderTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.AutomatonStateTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAONTCPRFinderTest +[INFO] Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 28.932 s - in uk.ac.cam.ch.wwmm.oscarrecogniser.finder.DFAONTCPRFinderTest [INFO] [INFO] Results: [INFO] @@ -2515,23 +2558,23 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- -[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.models.PubMedModelTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.models.ChemPapersModelTest SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 14.732 s - in uk.ac.cam.ch.wwmm.oscarMEMM.models.PubMedModelTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.models.ChemPapersModelTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.509 s - in uk.ac.cam.ch.wwmm.oscarMEMM.models.ChemPapersModelTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.Oscar4RegressionTest -[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 25.557 s - in uk.ac.cam.ch.wwmm.oscarMEMM.Oscar4RegressionTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.ResourceGetterTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscarMEMM.ResourceGetterTest -[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.MEMMRecogniserTest -[INFO] Tests run: 31, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 112.355 s - in uk.ac.cam.ch.wwmm.oscarMEMM.MEMMRecogniserTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 48.811 s - in uk.ac.cam.ch.wwmm.oscarMEMM.models.ChemPapersModelTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.models.PubMedModelTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 23.648 s - in uk.ac.cam.ch.wwmm.oscarMEMM.models.PubMedModelTest [INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.memm.MEMMModelTest -[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.219 s - in uk.ac.cam.ch.wwmm.oscarMEMM.memm.MEMMModelTest +[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.2 s - in uk.ac.cam.ch.wwmm.oscarMEMM.memm.MEMMModelTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.MEMMRecogniserTest +[INFO] Tests run: 31, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 442.917 s - in uk.ac.cam.ch.wwmm.oscarMEMM.MEMMRecogniserTest [INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.rescorer.ProbabilityConvertorTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in uk.ac.cam.ch.wwmm.oscarMEMM.rescorer.ProbabilityConvertorTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in uk.ac.cam.ch.wwmm.oscarMEMM.rescorer.ProbabilityConvertorTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.ResourceGetterTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in uk.ac.cam.ch.wwmm.oscarMEMM.ResourceGetterTest +[INFO] Running uk.ac.cam.ch.wwmm.oscarMEMM.Oscar4RegressionTest +[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 46.102 s - in uk.ac.cam.ch.wwmm.oscarMEMM.Oscar4RegressionTest [INFO] [INFO] Results: [INFO] @@ -2565,7 +2608,7 @@ SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 22, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 216.075 s - in uk.ac.cam.ch.wwmm.oscarpattern.PatternRecogniserTest +[INFO] Tests run: 22, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 785.752 s - in uk.ac.cam.ch.wwmm.oscarpattern.PatternRecogniserTest [INFO] [INFO] Results: [INFO] @@ -2595,13 +2638,13 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- +[INFO] Running uk.ac.cam.ch.wwmm.oscar.opsin.OpsinDictionaryTest +[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 18.788 s - in uk.ac.cam.ch.wwmm.oscar.opsin.OpsinDictionaryTest [INFO] Running uk.ac.cam.ch.wwmm.oscar.opsin.OpsinResolutionTest SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 7.888 s - in uk.ac.cam.ch.wwmm.oscar.opsin.OpsinResolutionTest -[INFO] Running uk.ac.cam.ch.wwmm.oscar.opsin.OpsinDictionaryTest -[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.063 s - in uk.ac.cam.ch.wwmm.oscar.opsin.OpsinDictionaryTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 17.158 s - in uk.ac.cam.ch.wwmm.oscar.opsin.OpsinResolutionTest [INFO] [INFO] Results: [INFO] @@ -2635,7 +2678,7 @@ SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 177.48 s - in uk.ac.cam.ch.wwmm.oscar.OscarTest +[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 970.983 s - in uk.ac.cam.ch.wwmm.oscar.OscarTest [INFO] [INFO] Results: [INFO] @@ -2666,7 +2709,7 @@ [INFO] T E S T S [INFO] ------------------------------------------------------- [INFO] Running uk.ac.cam.ch.wwmm.oscarpreprocessor.InputAnalyzerTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.29 s - in uk.ac.cam.ch.wwmm.oscarpreprocessor.InputAnalyzerTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.611 s - in uk.ac.cam.ch.wwmm.oscarpreprocessor.InputAnalyzerTest [INFO] [INFO] Results: [INFO] @@ -2721,15 +2764,15 @@ SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". SLF4J: Defaulting to no-operation (NOP) logger implementation SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -[INFO] Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.044 s - in uk.ac.cam.ch.wwmm.oscardata.DataParserTest +[INFO] Tests run: 10, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 31.628 s - in uk.ac.cam.ch.wwmm.oscardata.DataParserTest [INFO] Running uk.ac.cam.ch.wwmm.oscardata.DataAnnotationTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.168 s - in uk.ac.cam.ch.wwmm.oscardata.DataAnnotationTest -[INFO] Running uk.ac.cam.ch.wwmm.oscardata.RPNodeTest -[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.042 s - in uk.ac.cam.ch.wwmm.oscardata.RPNodeTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.496 s - in uk.ac.cam.ch.wwmm.oscardata.DataAnnotationTest [INFO] Running uk.ac.cam.ch.wwmm.oscardata.OscarDataTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.044 s - in uk.ac.cam.ch.wwmm.oscardata.OscarDataTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.049 s - in uk.ac.cam.ch.wwmm.oscardata.OscarDataTest +[INFO] Running uk.ac.cam.ch.wwmm.oscardata.RPNodeTest +[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.032 s - in uk.ac.cam.ch.wwmm.oscardata.RPNodeTest [INFO] Running uk.ac.cam.ch.wwmm.oscardata.RegexValidationTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.182 s - in uk.ac.cam.ch.wwmm.oscardata.RegexValidationTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.161 s - in uk.ac.cam.ch.wwmm.oscardata.RegexValidationTest [INFO] [INFO] Results: [INFO] @@ -2738,24 +2781,24 @@ [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary for oscar4-parent 5.2.0: [INFO] -[INFO] oscar4-parent ...................................... SUCCESS [ 1.374 s] -[INFO] oscar4-core ........................................ SUCCESS [ 12.142 s] -[INFO] Chemical Name Dictionaries ......................... SUCCESS [ 10.225 s] -[INFO] oscar4-obo ......................................... SUCCESS [ 40.287 s] -[INFO] oscar4-tokenizer ................................... SUCCESS [ 10.159 s] -[INFO] oscar4-recogniser-core ............................. SUCCESS [01:40 min] -[INFO] oscar4-memmrecogniser .............................. SUCCESS [02:51 min] -[INFO] oscar4-patternrecogniser ........................... SUCCESS [03:40 min] -[INFO] oscar4-opsin ....................................... SUCCESS [ 10.566 s] -[INFO] oscar4-api ......................................... SUCCESS [03:00 min] -[INFO] oscar4-preprocessor ................................ SUCCESS [ 10.138 s] -[INFO] oscar4-formatter ................................... SUCCESS [ 0.139 s] -[INFO] oscar4-data ........................................ SUCCESS [ 10.170 s] +[INFO] oscar4-parent ...................................... SUCCESS [ 7.973 s] +[INFO] oscar4-core ........................................ SUCCESS [ 50.905 s] +[INFO] Chemical Name Dictionaries ......................... SUCCESS [ 51.743 s] +[INFO] oscar4-obo ......................................... SUCCESS [04:41 min] +[INFO] oscar4-tokenizer ................................... SUCCESS [ 30.886 s] +[INFO] oscar4-recogniser-core ............................. SUCCESS [07:11 min] +[INFO] oscar4-memmrecogniser .............................. SUCCESS [09:41 min] +[INFO] oscar4-patternrecogniser ........................... SUCCESS [13:20 min] +[INFO] oscar4-opsin ....................................... SUCCESS [ 50.981 s] +[INFO] oscar4-api ......................................... SUCCESS [16:21 min] +[INFO] oscar4-preprocessor ................................ SUCCESS [ 10.774 s] +[INFO] oscar4-formatter ................................... SUCCESS [ 0.680 s] +[INFO] oscar4-data ........................................ SUCCESS [ 40.749 s] [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ -[INFO] Total time: 12:58 min -[INFO] Finished at: 2023-05-31T08:47:33-12:00 +[INFO] Total time: 55:24 min +[INFO] Finished at: 2023-06-01T12:01:50+14:00 [INFO] ------------------------------------------------------------------------  create-stamp debian/debhelper-build-stamp dh_prep @@ -2938,24 +2981,24 @@ [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary for oscar4-parent 5.2.0: [INFO] -[INFO] oscar4-parent ...................................... SUCCESS [ 2.900 s] -[INFO] oscar4-core ........................................ SUCCESS [ 0.180 s] -[INFO] Chemical Name Dictionaries ......................... SUCCESS [ 0.202 s] -[INFO] oscar4-obo ......................................... SUCCESS [ 0.222 s] -[INFO] oscar4-tokenizer ................................... SUCCESS [ 0.288 s] -[INFO] oscar4-recogniser-core ............................. SUCCESS [ 0.187 s] -[INFO] oscar4-memmrecogniser .............................. SUCCESS [ 0.094 s] -[INFO] oscar4-patternrecogniser ........................... SUCCESS [ 0.190 s] -[INFO] oscar4-opsin ....................................... SUCCESS [ 0.090 s] -[INFO] oscar4-api ......................................... SUCCESS [ 0.089 s] -[INFO] oscar4-preprocessor ................................ SUCCESS [ 0.080 s] -[INFO] oscar4-formatter ................................... SUCCESS [ 0.104 s] -[INFO] oscar4-data ........................................ SUCCESS [ 0.076 s] +[INFO] oscar4-parent ...................................... SUCCESS [ 9.917 s] +[INFO] oscar4-core ........................................ SUCCESS [ 0.922 s] +[INFO] Chemical Name Dictionaries ......................... SUCCESS [ 0.696 s] +[INFO] oscar4-obo ......................................... SUCCESS [ 1.424 s] +[INFO] oscar4-tokenizer ................................... SUCCESS [ 0.682 s] +[INFO] oscar4-recogniser-core ............................. SUCCESS [ 1.229 s] +[INFO] oscar4-memmrecogniser .............................. SUCCESS [ 0.945 s] +[INFO] oscar4-patternrecogniser ........................... SUCCESS [ 0.652 s] +[INFO] oscar4-opsin ....................................... SUCCESS [ 0.618 s] +[INFO] oscar4-api ......................................... SUCCESS [ 0.608 s] +[INFO] oscar4-preprocessor ................................ SUCCESS [ 0.457 s] +[INFO] oscar4-formatter ................................... SUCCESS [ 0.333 s] +[INFO] oscar4-data ........................................ SUCCESS [ 0.393 s] [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ -[INFO] Total time: 5.610 s -[INFO] Finished at: 2023-05-31T08:47:45-12:00 +[INFO] Total time: 23.897 s +[INFO] Finished at: 2023-06-01T12:02:40+14:00 [INFO] ------------------------------------------------------------------------  mh_resolve_dependencies --non-interactive --offline --build -pliboscar4-java --base-directory=/build/oscar4-5.2.0\+dfsg --non-explore Analysing pom.xml... @@ -3005,12 +3048,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/6444/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/6444/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/31013 and its subdirectories -I: Current time: Wed May 31 08:48:46 -12 2023 -I: pbuilder-time-stamp: 1685566126 +I: removing directory /srv/workspace/pbuilder/6444 and its subdirectories +I: Current time: Thu Jun 1 12:05:34 +14 2023 +I: pbuilder-time-stamp: 1685570734