Diff of the two buildlogs: -- --- b1/build.log 2023-06-07 07:32:27.071988467 +0000 +++ b2/build.log 2023-06-07 07:38:42.091578512 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue Jun 6 19:26:13 -12 2023 -I: pbuilder-time-stamp: 1686122773 +I: Current time: Wed Jun 7 21:32:49 +14 2023 +I: pbuilder-time-stamp: 1686123169 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 [./sslh_1.20.orig.tar.gz] I: copying [./sslh_1.20-1.debian.tar.xz] I: Extracting source -gpgv: Signature made Fri Sep 20 17:57:42 2019 -12 +gpgv: Signature made Sat Sep 21 19:57:42 2019 +14 gpgv: using RSA key 37942061E068204CA2855C96F55025658A66BF3F gpgv: Can't check signature: No public key dpkg-source: warning: cannot verify inline signature for ./sslh_1.20-1.dsc: no acceptable signature found @@ -31,135 +31,167 @@ dpkg-source: info: applying t_no_libconfig I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/16717/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/6076/tmp/hooks/D01_modify_environment starting +debug: Running on wbq0. +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 7 21:34 /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/6076/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/6076/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='b60162cde9a34279b5304b8621322998' - 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='16717' - PS1='# ' - PS2='> ' + INVOCATION_ID=59602092184b49d099fd5e832228c792 + 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=6076 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.ciT5ypwW/pbuilderrc_2X0t --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.ciT5ypwW/b1 --logfile b1/build.log sslh_1.20-1.dsc' - SUDO_GID='114' - SUDO_UID='110' - 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.ciT5ypwW/pbuilderrc_GtLk --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.ciT5ypwW/b2 --logfile b2/build.log --extrapackages usrmerge sslh_1.20-1.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 jtx1b 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/16717/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 7 21:34 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/6076/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -261,7 +293,7 @@ Get: 48 http://deb.debian.org/debian bookworm/main armhf libwrap0 armhf 7.6.q-32 [52.6 kB] Get: 49 http://deb.debian.org/debian bookworm/main armhf libwrap0-dev armhf 7.6.q-32 [19.8 kB] Get: 50 http://deb.debian.org/debian bookworm/main armhf psmisc armhf 23.6-1 [255 kB] -Fetched 20.7 MB in 2s (10.5 MB/s) +Fetched 20.7 MB in 2s (9154 kB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (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.) @@ -478,10 +510,17 @@ Reading package lists... Building dependency tree... Reading state information... +usrmerge is already the newest version (35). fakeroot is already the newest version (1.31-1.2). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/sslh-1.20/ && 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 > ../sslh_1.20-1_source.changes +I: user script /srv/workspace/pbuilder/6076/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/6076/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/sslh-1.20/ && 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 > ../sslh_1.20-1_source.changes dpkg-buildpackage: info: source package sslh dpkg-buildpackage: info: source version 1.20-1 dpkg-buildpackage: info: source distribution unstable @@ -491,7 +530,7 @@ fakeroot debian/rules clean dh clean dh_auto_clean - make -j3 distclean + make -j4 distclean make[1]: Entering directory '/build/sslh-1.20' Makefile:66: warning: ignoring prerequisites on suffix rule definition rm -f sslh-fork sslh-select echosrv version.h sslh.8.gz systemd-sslh-generator *.o *.gcov *.gcno *.gcda *.png *.html *.css *.info @@ -506,7 +545,7 @@ debian/rules override_dh_auto_build make[1]: Entering directory '/build/sslh-1.20' dh_auto_build -- USELIBWRAP=1 USELIBCAP=1 - make -j3 "INSTALL=install --strip-program=true" USELIBWRAP=1 USELIBCAP=1 + make -j4 "INSTALL=install --strip-program=true" USELIBWRAP=1 USELIBCAP=1 make[2]: Entering directory '/build/sslh-1.20' Makefile:66: warning: ignoring prerequisites on suffix rule definition ./genver.sh >version.h @@ -538,31 +577,31 @@ cc -g -O2 -ffile-prefix-map=/build/sslh-1.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wl,-z,relro -Wl,-z,now -o sslh-fork sslh-fork.o common.o sslh-main.o probe.o tls.o -lwrap -lpcreposix -lconfig -lcap cc -g -O2 -ffile-prefix-map=/build/sslh-1.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wl,-z,relro -Wl,-z,now -o sslh-select sslh-select.o common.o sslh-main.o probe.o tls.o -lwrap -lpcreposix -lconfig -lcap cc -g -O2 -ffile-prefix-map=/build/sslh-1.20=. -fstack-protector-strong -Wformat -Werror=format-security -Wl,-z,relro -Wl,-z,now -o echosrv echosrv.o probe.o common.o tls.o -lwrap -lpcreposix -lconfig -lcap -#strip sslh-fork #strip sslh-select +#strip sslh-fork make[2]: Leaving directory '/build/sslh-1.20' make[1]: Leaving directory '/build/sslh-1.20' dh_auto_test - make -j3 test + make -j4 test make[1]: Entering directory '/build/sslh-1.20' Makefile:66: warning: ignoring prerequisites on suffix rule definition ./t -./echosrv --listen localhost:35221 --prefix 'ssh: ' -./echosrv --listen localhost:40223 --prefix 'socks5: ' -./echosrv --listen localhost:41023 --prefix 'http: ' -./echosrv --listen localhost:39403 --prefix 'tinc: ' -./echosrv --listen localhost:46825 --prefix 'openvpn: ' -./echosrv --listen localhost:38225 --prefix 'xmpp: ' -./echosrv --listen localhost:39349 --prefix 'adb: ' -./echosrv --listen localhost:40793 --prefix 'tls:sni1;alpn1,alpn2: ' -./echosrv --listen localhost:44535 --prefix 'tls:sni2,sni3;alpn1,alpn2: ' -./echosrv --listen localhost:44653 --prefix 'tls:;alpn3: ' -./echosrv --listen localhost:33335 --prefix 'tls:sni3;: ' -./echosrv --listen localhost:43109 --prefix 'tls: ' -./echosrv --listen localhost:43371 --prefix 'anyprot: ' +./echosrv --listen localhost:39237 --prefix 'ssh: ' +./echosrv --listen localhost:35777 --prefix 'socks5: ' +./echosrv --listen localhost:42461 --prefix 'http: ' +./echosrv --listen localhost:39899 --prefix 'tinc: ' +./echosrv --listen localhost:34423 --prefix 'openvpn: ' +./echosrv --listen localhost:37117 --prefix 'xmpp: ' +./echosrv --listen localhost:42391 --prefix 'adb: ' +./echosrv --listen localhost:33945 --prefix 'tls:sni1;alpn1,alpn2: ' +./echosrv --listen localhost:41559 --prefix 'tls:sni2,sni3;alpn1,alpn2: ' +./echosrv --listen localhost:41517 --prefix 'tls:;alpn3: ' +./echosrv --listen localhost:43885 --prefix 'tls:sni3;: ' +./echosrv --listen localhost:32999 --prefix 'tls: ' +./echosrv --listen localhost:38493 --prefix 'anyprot: ' Testing sslh-select -spawned 19442 -./sslh-select -v -f -u pbuilder1 -Ftest.cfg +spawned 10309 +./sslh-select -v -f -u pbuilder2 -Ftest.cfg tls: sni_hostnames[0]: sni1 tls: alpn_protocols[0]: alpn1 tls: alpn_protocols[1]: alpn2 @@ -574,27 +613,27 @@ tls: alpn_protocols[0]: alpn3 tls: sni_hostnames[0]: sni3 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:35221. libwrap service: (null) log_level: 1 family 2 2 [] [fork] -socks5 addr: localhost:40223. libwrap service: (null) log_level: 1 family 2 2 [] [] -http addr: localhost:41023. libwrap service: (null) log_level: 1 family 2 2 [] [] -tinc addr: localhost:39403. libwrap service: (null) log_level: 1 family 2 2 [] [] -openvpn addr: localhost:46825. libwrap service: (null) log_level: 1 family 2 2 [] [] -xmpp addr: localhost:38225. libwrap service: (null) log_level: 1 family 2 2 [] [] -adb addr: localhost:39349. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:40793. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:44535. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:44653. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:33335. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:43109. libwrap service: (null) log_level: 1 family 2 2 [] [] -anyprot addr: localhost:43371. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:39237. libwrap service: (null) log_level: 1 family 2 2 [] [fork] +socks5 addr: localhost:35777. libwrap service: (null) log_level: 1 family 2 2 [] [] +http addr: localhost:42461. libwrap service: (null) log_level: 1 family 2 2 [] [] +tinc addr: localhost:39899. libwrap service: (null) log_level: 1 family 2 2 [] [] +openvpn addr: localhost:34423. libwrap service: (null) log_level: 1 family 2 2 [] [] +xmpp addr: localhost:37117. libwrap service: (null) log_level: 1 family 2 2 [] [] +adb addr: localhost:42391. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:33945. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:41559. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:41517. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:43885. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:32999. libwrap service: (null) log_level: 1 family 2 2 [] [] +anyprot addr: localhost:38493. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:37887 [keepalive] + localhost:40211 [keepalive] localhost:8081 [keepalive] timeout: 10 on-timeout: ssh listening to 2 addresses sslh-select 1.20-1 started -turning into pbuilder1 +turning into pbuilder2 capabilities: = selecting... max_fd=5 num_probing=0 ***Test: Shy SSH connection @@ -626,15 +665,15 @@ timed out, connect to ssh closing fd 5 selecting... max_fd=6 num_probing=0 -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:50962 to localhost:37887 forwarded from localhost:47996 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:54362 to localhost:40211 forwarded from localhost:38476 to localhost:39237 flushing deferred data to fd 4 ok 1 - Shy SSH connection -socket closed ***Test: One SSL half-started then one SSH -connection closed down accepted fd 5 on slot 0 selecting... max_fd=6 num_probing=1 +socket closed +connection closed down accepted fd 6 on slot 1 processing fd0 slot 0 hexdump of incoming packet: @@ -663,8 +702,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:35292 to localhost:37887 forwarded from localhost:34968 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:42802 to localhost:40211 forwarded from localhost:52656 to localhost:32999 flushing deferred data to fd 7 selecting... max_fd=8 num_probing=1 processing fd1 slot 0 @@ -696,24 +735,23 @@ timed out, connect to ssh closing fd 6 selecting... max_fd=8 num_probing=0 -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:35298 to localhost:37887 forwarded from localhost:60126 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:42806 to localhost:40211 forwarded from localhost:57042 to localhost:39237 flushing deferred data to fd 4 ok 2 - SSH during SSL being established ok 3 - SSL connection interrupted by SSH -socket closed ***Test: One SSH half-started then one SSL +socket closed connection closed down +accepted fd 6 on slot 1 processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 7 -selecting... max_fd=8 num_probing=0 -accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 -accepted fd 6 on slot 1 +accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=2 -processing fd0 slot 1 +processing fd0 slot 0 hexdump of incoming packet: 0x000000: 16 03 01 00 ab 01 00 00 a7 03 03 89 22 33 95 43 ............"3.C 0x000010: 7a c3 89 45 51 12 3c 28 24 1b 6a 78 bf be 95 d8 z..EQ.<($.jx.... @@ -740,15 +778,15 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:54998 to localhost:37887 forwarded from localhost:51544 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:36368 to localhost:40211 forwarded from localhost:48474 to localhost:32999 flushing deferred data to fd 7 selecting... max_fd=8 num_probing=1 -processing fd1 slot 1 +processing fd1 slot 0 activity on fd7 selecting... max_fd=8 num_probing=1 ok 4 - SSL during SSH being established -processing fd0 slot 0 +processing fd0 slot 1 hexdump of incoming packet: 0x000000: 68 65 6c 6c 6f 20 77 6f 72 6c 64 0a hello world. **** writing deferred on fd -1 @@ -770,37 +808,36 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT selecting... max_fd=8 num_probing=1 -processing fd0 slot 0 +processing fd0 slot 1 timed out, connect to ssh -closing fd 5 +closing fd 6 selecting... max_fd=8 num_probing=0 -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:54990 to localhost:37887 forwarded from localhost:37044 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:36354 to localhost:40211 forwarded from localhost:55988 to localhost:39237 flushing deferred data to fd 4 ok 5 - SSH connection interrupted by SSL -processing fd0 slot 1 -activity on fd6 -closing fd 6 -closing fd 7 socket closed -selecting... max_fd=8 num_probing=0 -accepted fd 5 on slot 0 -selecting... max_fd=8 num_probing=1 +accepted fd 6 on slot 1 processing fd0 slot 0 +activity on fd5 +closing fd 5 +connection closed down +closing fd 7 +selecting... max_fd=8 num_probing=1 +processing fd0 slot 1 hexdump of incoming packet: -0x000000: 53 53 48 2d 32 2e 30 20 74 connection closed down -65 73 74 65 72 SSH-2.0 tester +0x000000: 53 53 48 2d 32 2e 30 20 74 65 73 74 65 72 SSH-2.0 tester **** writing deferred on fd -1 probing for ssh: PROBE_MATCH -closing fd 5 +closing fd 6 selecting... max_fd=8 num_probing=0 -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:41990 to localhost:37887 forwarded from localhost:37046 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:38430 to localhost:40211 forwarded from localhost:55996 to localhost:39237 flushing deferred data to fd 4 Received: protocol ssh data [SSH-2.0 tester] +ok 6 - probe ssh connected correctly socket closed connection closed down -ok 6 - probe ssh connected correctly ok 7 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -810,20 +847,20 @@ **** writing deferred on fd -1 probing for ssh: PROBE_NEXT probing for socks5: PROBE_MATCH -connecting to localhost:40223 family 2 len 16 -socks5:connection from localhost:42002 to localhost:37887 forwarded from localhost:41598 to localhost:40223 +connecting to localhost:35777 family 2 len 16 +socks5:connection from localhost:38442 to localhost:40211 forwarded from localhost:45942 to localhost:35777 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol socks5 data [] +ok 8 - probe socks5 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 8 - probe socks5 connected correctly ok 9 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -835,20 +872,20 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:41023 family 2 len 16 -http:connection from localhost:42014 to localhost:37887 forwarded from localhost:60470 to localhost:41023 +connecting to localhost:42461 family 2 len 16 +http:connection from localhost:38452 to localhost:40211 forwarded from localhost:37776 to localhost:42461 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol http data [GET index.html HTTP/1.1] +ok 10 - probe http connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 10 - probe http connected correctly ok 11 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -860,20 +897,20 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:39403 family 2 len 16 -tinc:connection from localhost:42026 to localhost:37887 forwarded from localhost:41272 to localhost:39403 +connecting to localhost:39899 family 2 len 16 +tinc:connection from localhost:38454 to localhost:40211 forwarded from localhost:53634 to localhost:39899 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol tinc data [0 hello] +ok 12 - probe tinc connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 12 - probe tinc connected correctly ok 13 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -886,20 +923,20 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46825 family 2 len 16 -openvpn:connection from localhost:42028 to localhost:37887 forwarded from localhost:33348 to localhost:46825 +connecting to localhost:34423 family 2 len 16 +openvpn:connection from localhost:38462 to localhost:40211 forwarded from localhost:48326 to localhost:34423 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol openvpn data [] +ok 14 - probe openvpn connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 14 - probe openvpn connected correctly ok 15 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -916,21 +953,21 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:38225 family 2 len 16 -xmpp:connection from localhost:42034 to localhost:37887 forwarded from localhost:57674 to localhost:38225 +connecting to localhost:37117 family 2 len 16 +xmpp:connection from localhost:38464 to localhost:40211 forwarded from localhost:41822 to localhost:37117 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol xmpp data [I should get a real jabber connection initialisation here] +ok 16 - probe xmpp connected correctly +ok 17 - data shoveled correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 16 - probe xmpp connected correctly -ok 17 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 @@ -945,20 +982,20 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39349 family 2 len 16 -adb:connection from localhost:42048 to localhost:37887 forwarded from localhost:50358 to localhost:39349 +connecting to localhost:42391 family 2 len 16 +adb:connection from localhost:38472 to localhost:40211 forwarded from localhost:33904 to localhost:42391 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol adb data [CNXN....................host:...] +ok 18 - probe adb connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 18 - probe adb connected correctly ok 19 - data shoveled correctly sni: sni1 alpn: alpn1 @@ -991,8 +1028,8 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:42052 to localhost:37887 forwarded from localhost:53556 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:38474 to localhost:40211 forwarded from localhost:48800 to localhost:33945 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1002,13 +1039,13 @@ À9À À3œ=<5/ÿ_ sni1  # alpn1hello sni/alpn] +ok 20 - probe tls:sni1;alpn1,alpn2 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 -selecting... max_fd=8 num_probing=0 -ok 20 - probe tls:sni1;alpn1,alpn2 connected correctly ok 21 - data shoveled correctly +selecting... max_fd=8 num_probing=0 sni: sni1 alpn: alpn2 accepted fd 5 on slot 0 @@ -1041,8 +1078,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:42060 to localhost:37887 forwarded from localhost:53570 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:38490 to localhost:40211 forwarded from localhost:48816 to localhost:33945 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1052,12 +1089,12 @@ À9À À3œ=<5/ÿ_ sni1  # alpn2hello sni/alpn] +ok 22 - probe tls:sni1;alpn1,alpn2 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 22 - probe tls:sni1;alpn1,alpn2 connected correctly ok 23 - data shoveled correctly sni: sni2 alpn: alpn1 @@ -1092,8 +1129,8 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:42072 to localhost:37887 forwarded from localhost:41304 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38498 to localhost:40211 forwarded from localhost:37720 to localhost:41559 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1103,15 +1140,15 @@ À9À À3œ=<5/ÿ_ sni2  # alpn1hello sni/alpn] +ok 24 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly +ok 25 - data shoveled correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 -selecting... max_fd=8 num_probing=0 -ok 24 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly -ok 25 - data shoveled correctly sni: sni2 alpn: alpn2 +selecting... max_fd=8 num_probing=0 accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 @@ -1144,8 +1181,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:42074 to localhost:37887 forwarded from localhost:41312 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38506 to localhost:40211 forwarded from localhost:37736 to localhost:41559 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1155,15 +1192,15 @@ À9À À3œ=<5/ÿ_ sni2  # alpn2hello sni/alpn] +ok 26 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly +ok 27 - data shoveled correctly processing fd0 slot 0 activity on fd5 closing fd 5 -closing fd 6 -selecting... max_fd=8 num_probing=0 -ok 26 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly -ok 27 - data shoveled correctly sni: sni3 alpn: alpn1 +closing fd 6 +selecting... max_fd=8 num_probing=0 accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 @@ -1196,8 +1233,8 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:42084 to localhost:37887 forwarded from localhost:41316 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38522 to localhost:40211 forwarded from localhost:37740 to localhost:41559 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1207,15 +1244,15 @@ À9À À3œ=<5/ÿ_ sni3  # alpn1hello sni/alpn] +ok 28 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly +ok 29 - data shoveled correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 -selecting... max_fd=8 num_probing=0 -ok 28 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly -ok 29 - data shoveled correctly sni: sni3 alpn: alpn2 +selecting... max_fd=8 num_probing=0 accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 @@ -1249,8 +1286,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:42094 to localhost:37887 forwarded from localhost:41330 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38536 to localhost:40211 forwarded from localhost:37756 to localhost:41559 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1260,13 +1297,13 @@ À9À À3œ=<5/ÿ_ sni3  # alpn2hello sni/alpn] +ok 30 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly +ok 31 - data shoveled correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 30 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly -ok 31 - data shoveled correctly sni: alpn: alpn3 accepted fd 5 on slot 0 @@ -1304,8 +1341,8 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:44653 family 2 len 16 -tls:connection from localhost:42106 to localhost:37887 forwarded from localhost:51948 to localhost:44653 +connecting to localhost:41517 family 2 len 16 +tls:connection from localhost:38552 to localhost:40211 forwarded from localhost:53954 to localhost:41517 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1315,12 +1352,12 @@ À9À À3œ=<5/ÿR  # alpn3hello alpn] +ok 32 - probe tls:;alpn3 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 32 - probe tls:;alpn3 connected correctly ok 33 - data shoveled correctly sni: sni3 alpn: @@ -1357,8 +1394,8 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:33335 family 2 len 16 -tls:connection from localhost:42108 to localhost:37887 forwarded from localhost:42360 to localhost:33335 +connecting to localhost:43885 family 2 len 16 +tls:connection from localhost:38562 to localhost:40211 forwarded from localhost:36480 to localhost:43885 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1368,12 +1405,12 @@ À9À À3œ=<5/ÿS sni3  # hello sni] +ok 34 - probe tls:sni3; connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 34 - probe tls:sni3; connected correctly ok 35 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1404,8 +1441,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:42118 to localhost:37887 forwarded from localhost:59310 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:38576 to localhost:40211 forwarded from localhost:44970 to localhost:32999 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1415,12 +1452,12 @@ À9À À3œ=<5/ÿF  # hello ssl alone] +ok 36 - probe tls connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 36 - probe tls connected correctly ok 37 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1453,20 +1490,20 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:43371 family 2 len 16 -anyprot:connection from localhost:42120 to localhost:37887 forwarded from localhost:56878 to localhost:43371 +connecting to localhost:38493 family 2 len 16 +anyprot:connection from localhost:38592 to localhost:40211 forwarded from localhost:54314 to localhost:38493 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol anyprot data [hello anyprot this needs to be longer than xmpp and adb which expect about 50 characters, which I all have to write before the timeout!] +ok 38 - probe anyprot connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 38 - probe anyprot connected correctly ok 39 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1528,13 +1565,13 @@ probing for ssh: PROBE_MATCH closing fd 5 selecting... max_fd=8 num_probing=0 -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:42126 to localhost:37887 forwarded from localhost:37048 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:38596 to localhost:40211 forwarded from localhost:56000 to localhost:39237 flushing deferred data to fd 4 Received: protocol ssh data [SSH-2.0 tester] +ok 40 - probe ssh connected correctly socket closed connection closed down -ok 40 - probe ssh connected correctly ok 41 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1634,20 +1671,20 @@ **** writing deferred on fd -1 probing for ssh: PROBE_NEXT probing for socks5: PROBE_MATCH -connecting to localhost:40223 family 2 len 16 -socks5:connection from localhost:40544 to localhost:37887 forwarded from localhost:46922 to localhost:40223 +connecting to localhost:35777 family 2 len 16 +socks5:connection from localhost:38612 to localhost:40211 forwarded from localhost:45946 to localhost:35777 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol socks5 data [] +ok 42 - probe socks5 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 42 - probe socks5 connected correctly ok 43 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1659,20 +1696,20 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:41023 family 2 len 16 -http:connection from localhost:40560 to localhost:37887 forwarded from localhost:40708 to localhost:41023 +connecting to localhost:42461 family 2 len 16 +http:connection from localhost:38626 to localhost:40211 forwarded from localhost:37788 to localhost:42461 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol http data [GET index.html HTTP/1.1] +ok 44 - probe http connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 44 - probe http connected correctly ok 45 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1684,20 +1721,20 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:39403 family 2 len 16 -tinc:connection from localhost:40570 to localhost:37887 forwarded from localhost:41844 to localhost:39403 +connecting to localhost:39899 family 2 len 16 +tinc:connection from localhost:38640 to localhost:40211 forwarded from localhost:53642 to localhost:39899 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol tinc data [0 hello] +ok 46 - probe tinc connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 46 - probe tinc connected correctly ok 47 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1710,20 +1747,20 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46825 family 2 len 16 -openvpn:connection from localhost:40574 to localhost:37887 forwarded from localhost:43094 to localhost:46825 +connecting to localhost:34423 family 2 len 16 +openvpn:connection from localhost:38654 to localhost:40211 forwarded from localhost:48330 to localhost:34423 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol openvpn data [] +ok 48 - probe openvpn connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 48 - probe openvpn connected correctly ok 49 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1740,20 +1777,20 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:38225 family 2 len 16 -xmpp:connection from localhost:40578 to localhost:37887 forwarded from localhost:33110 to localhost:38225 +connecting to localhost:37117 family 2 len 16 +xmpp:connection from localhost:38662 to localhost:40211 forwarded from localhost:41838 to localhost:37117 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol xmpp data [I should get a real jabber connection initialisation here] +ok 50 - probe xmpp connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 50 - probe xmpp connected correctly ok 51 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -1769,8 +1806,8 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39349 family 2 len 16 -adb:connection from localhost:40588 to localhost:37887 forwarded from localhost:37372 to localhost:39349 +connecting to localhost:42391 family 2 len 16 +adb:connection from localhost:38670 to localhost:40211 forwarded from localhost:33916 to localhost:42391 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1815,8 +1852,8 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:40590 to localhost:37887 forwarded from localhost:53290 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:38674 to localhost:40211 forwarded from localhost:48828 to localhost:33945 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1826,12 +1863,12 @@ À9À À3œ=<5/ÿ_ sni1  # alpn1hello sni/alpn] +ok 54 - probe tls:sni1;alpn1,alpn2 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 54 - probe tls:sni1;alpn1,alpn2 connected correctly ok 55 - data shoveled correctly sni: sni1 alpn: alpn2 @@ -1865,8 +1902,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:40604 to localhost:37887 forwarded from localhost:53302 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:38686 to localhost:40211 forwarded from localhost:48842 to localhost:33945 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1877,16 +1914,17 @@ # alpn2hello sni/alpn] ok 56 - probe tls:sni1;alpn1,alpn2 connected correctly -ok 57 - data shoveled correctly -sni: sni2 -alpn: alpn1 -accepted fd 7 on slot 1 processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 +selecting... max_fd=8 num_probing=0 +ok 57 - data shoveled correctly +sni: sni2 +alpn: alpn1 +accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 -processing fd0 slot 1 +processing fd0 slot 0 hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ 0x000010: 14 13 cc 1b 94 ad 20 5d 13 1a 8d d2 65 23 70 de ...... ]....e#p. @@ -1915,23 +1953,23 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:40618 to localhost:37887 forwarded from localhost:51160 to localhost:44535 -flushing deferred data to fd 5 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38688 to localhost:40211 forwarded from localhost:37762 to localhost:41559 +flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 -processing fd1 slot 1 -activity on fd5 +processing fd1 slot 0 +activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni2  # alpn1hello sni/alpn] -processing fd0 slot 1 -activity on fd7 -closing fd 7 +ok 58 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly +processing fd0 slot 0 +activity on fd5 closing fd 5 +closing fd 6 selecting... max_fd=8 num_probing=0 -ok 58 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 59 - data shoveled correctly sni: sni2 alpn: alpn2 @@ -1967,8 +2005,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:40628 to localhost:37887 forwarded from localhost:51164 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38704 to localhost:40211 forwarded from localhost:37778 to localhost:41559 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1978,12 +2016,12 @@ À9À À3œ=<5/ÿ_ sni2  # alpn2hello sni/alpn] +ok 60 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 60 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 61 - data shoveled correctly sni: sni3 alpn: alpn1 @@ -2019,8 +2057,8 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:40640 to localhost:37887 forwarded from localhost:51174 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38720 to localhost:40211 forwarded from localhost:37786 to localhost:41559 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2030,12 +2068,12 @@ À9À À3œ=<5/ÿ_ sni3  # alpn1hello sni/alpn] +ok 62 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 62 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 63 - data shoveled correctly sni: sni3 alpn: alpn2 @@ -2072,8 +2110,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:40646 to localhost:37887 forwarded from localhost:51186 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:38726 to localhost:40211 forwarded from localhost:37794 to localhost:41559 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2083,12 +2121,12 @@ À9À À3œ=<5/ÿ_ sni3  # alpn2hello sni/alpn] +ok 64 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 64 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 65 - data shoveled correctly sni: alpn: alpn3 @@ -2127,8 +2165,8 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:44653 family 2 len 16 -tls:connection from localhost:40654 to localhost:37887 forwarded from localhost:37998 to localhost:44653 +connecting to localhost:41517 family 2 len 16 +tls:connection from localhost:38734 to localhost:40211 forwarded from localhost:53964 to localhost:41517 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2138,12 +2176,12 @@ À9À À3œ=<5/ÿR  # alpn3hello alpn] +ok 66 - probe tls:;alpn3 connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 66 - probe tls:;alpn3 connected correctly ok 67 - data shoveled correctly sni: sni3 alpn: @@ -2180,8 +2218,8 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:33335 family 2 len 16 -tls:connection from localhost:40656 to localhost:37887 forwarded from localhost:49452 to localhost:33335 +connecting to localhost:43885 family 2 len 16 +tls:connection from localhost:38742 to localhost:40211 forwarded from localhost:36482 to localhost:43885 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2191,12 +2229,12 @@ À9À À3œ=<5/ÿS sni3  # hello sni] +ok 68 - probe tls:sni3; connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 68 - probe tls:sni3; connected correctly ok 69 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -2227,8 +2265,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:40668 to localhost:37887 forwarded from localhost:47984 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:38750 to localhost:40211 forwarded from localhost:44984 to localhost:32999 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2238,12 +2276,12 @@ À9À À3œ=<5/ÿF  # hello ssl alone] +ok 70 - probe tls connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 70 - probe tls connected correctly ok 71 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 @@ -2276,26 +2314,26 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:43371 family 2 len 16 -anyprot:connection from localhost:40670 to localhost:37887 forwarded from localhost:57276 to localhost:43371 +connecting to localhost:38493 family 2 len 16 +anyprot:connection from localhost:38764 to localhost:40211 forwarded from localhost:54330 to localhost:38493 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol anyprot data [hello anyprot this needs to be longer than xmpp and adb which expect about 50 characters, which I all have to write before the timeout!] +ok 72 - probe anyprot connected correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 72 - probe anyprot connected correctly ok 73 - data shoveled correctly -killing 19443 +killing 10311 Testing sslh-fork -spawned 19897 -./sslh-fork -v -f -u pbuilder1 -Ftest.cfg +spawned 11146 +./sslh-fork -v -f -u pbuilder2 -Ftest.cfg tls: sni_hostnames[0]: sni1 tls: alpn_protocols[0]: alpn1 tls: alpn_protocols[1]: alpn2 @@ -2307,27 +2345,27 @@ tls: alpn_protocols[0]: alpn3 tls: sni_hostnames[0]: sni3 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:35221. libwrap service: (null) log_level: 1 family 2 2 [] [fork] -socks5 addr: localhost:40223. libwrap service: (null) log_level: 1 family 2 2 [] [] -http addr: localhost:41023. libwrap service: (null) log_level: 1 family 2 2 [] [] -tinc addr: localhost:39403. libwrap service: (null) log_level: 1 family 2 2 [] [] -openvpn addr: localhost:46825. libwrap service: (null) log_level: 1 family 2 2 [] [] -xmpp addr: localhost:38225. libwrap service: (null) log_level: 1 family 2 2 [] [] -adb addr: localhost:39349. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:40793. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:44535. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:44653. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:33335. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:43109. libwrap service: (null) log_level: 1 family 2 2 [] [] -anyprot addr: localhost:43371. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:39237. libwrap service: (null) log_level: 1 family 2 2 [] [fork] +socks5 addr: localhost:35777. libwrap service: (null) log_level: 1 family 2 2 [] [] +http addr: localhost:42461. libwrap service: (null) log_level: 1 family 2 2 [] [] +tinc addr: localhost:39899. libwrap service: (null) log_level: 1 family 2 2 [] [] +openvpn addr: localhost:34423. libwrap service: (null) log_level: 1 family 2 2 [] [] +xmpp addr: localhost:37117. libwrap service: (null) log_level: 1 family 2 2 [] [] +adb addr: localhost:42391. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:33945. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:41559. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:41517. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:43885. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:32999. libwrap service: (null) log_level: 1 family 2 2 [] [] +anyprot addr: localhost:38493. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:37887 [keepalive] + localhost:40211 [keepalive] localhost:8081 [keepalive] timeout: 10 on-timeout: ssh listening to 2 addresses sslh-fork 1.20-1 started -turning into pbuilder1 +turning into pbuilder2 capabilities: = ***Test: Shy SSH connection accepted fd 5 @@ -2352,14 +2390,14 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT timed out, connect to ssh -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:40684 to localhost:37887 forwarded from localhost:42446 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:60832 to localhost:40211 forwarded from localhost:43176 to localhost:39237 flushing deferred data to fd 3 ok 74 - Shy SSH connection -server socket closed ***Test: One SSL half-started then one SSH -connection closed down accepted fd 5 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 ab 01 00 00 a7 03 03 89 22 33 95 43 ............"3.C @@ -2387,8 +2425,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:47368 to localhost:37887 forwarded from localhost:47004 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:59736 to localhost:40211 forwarded from localhost:40786 to localhost:32999 flushing deferred data to fd 3 hexdump of incoming packet: 0x000000: 68 65 6c 6c 6f 20 77 6f 72 6c 64 0a hello world. @@ -2411,17 +2449,17 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT timed out, connect to ssh -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:47376 to localhost:37887 forwarded from localhost:40154 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:59744 to localhost:40211 forwarded from localhost:50904 to localhost:39237 flushing deferred data to fd 3 ok 75 - SSH during SSL being established ok 76 - SSL connection interrupted by SSH -server socket closed ***Test: One SSH half-started then one SSL -connection closed down server socket closed connection closed down accepted fd 5 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 ab 01 00 00 a7 03 03 89 22 33 95 43 ............"3.C @@ -2449,8 +2487,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:41412 to localhost:37887 forwarded from localhost:58860 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:60000 to localhost:40211 forwarded from localhost:55762 to localhost:32999 flushing deferred data to fd 3 ok 77 - SSL during SSH being established hexdump of incoming packet: @@ -2474,41 +2512,41 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT timed out, connect to ssh -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:41402 to localhost:37887 forwarded from localhost:36694 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:59990 to localhost:40211 forwarded from localhost:51254 to localhost:39237 flushing deferred data to fd 3 ok 78 - SSH connection interrupted by SSL server socket closed -server socket closed connection closed down +server socket closed connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 53 53 48 2d 32 2e 30 20 74 65 73 74 65 72 SSH-2.0 tester **** writing deferred on fd -1 probing for ssh: PROBE_MATCH -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:47820 to localhost:37887 forwarded from localhost:36706 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:60322 to localhost:40211 forwarded from localhost:51256 to localhost:39237 flushing deferred data to fd 3 Received: protocol ssh data [SSH-2.0 tester] -server socket closed -connection closed down ok 79 - probe ssh connected correctly ok 80 - data shoveled correctly +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 05 04 01 02 03 04 ...... **** writing deferred on fd -1 probing for ssh: PROBE_NEXT probing for socks5: PROBE_MATCH -connecting to localhost:40223 family 2 len 16 -socks5:connection from localhost:47822 to localhost:37887 forwarded from localhost:55538 to localhost:40223 +connecting to localhost:35777 family 2 len 16 +socks5:connection from localhost:60334 to localhost:40211 forwarded from localhost:44182 to localhost:35777 flushing deferred data to fd 3 Received: protocol socks5 data [] -server socket closed -connection closed down ok 81 - probe socks5 connected correctly ok 82 - data shoveled correctly +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 47 45 54 20 69 6e 64 65 78 2e 68 74 6d 6c 20 48 GET index.html H @@ -2517,15 +2555,15 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:41023 family 2 len 16 -http:connection from localhost:47828 to localhost:37887 forwarded from localhost:37068 to localhost:41023 +connecting to localhost:42461 family 2 len 16 +http:connection from localhost:60336 to localhost:40211 forwarded from localhost:36846 to localhost:42461 flushing deferred data to fd 3 Received: protocol http data [GET index.html HTTP/1.1] -server socket closed -connection closed down ok 83 - probe http connected correctly ok 84 - data shoveled correctly accepted fd 5 +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 30 20 68 65 6c 6c 6f 0 hello **** writing deferred on fd -1 @@ -2533,15 +2571,15 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:39403 family 2 len 16 -tinc:connection from localhost:47836 to localhost:37887 forwarded from localhost:33496 to localhost:39403 +connecting to localhost:39899 family 2 len 16 +tinc:connection from localhost:60338 to localhost:40211 forwarded from localhost:43324 to localhost:39899 flushing deferred data to fd 3 Received: protocol tinc data [0 hello] -server socket closed -connection closed down ok 85 - probe tinc connected correctly ok 86 - data shoveled correctly accepted fd 5 +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 00 00 .. **** writing deferred on fd -1 @@ -2550,13 +2588,13 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46825 family 2 len 16 -openvpn:connection from localhost:47846 to localhost:37887 forwarded from localhost:38598 to localhost:46825 +connecting to localhost:34423 family 2 len 16 +openvpn:connection from localhost:60350 to localhost:40211 forwarded from localhost:57840 to localhost:34423 flushing deferred data to fd 3 Received: protocol openvpn data [] +ok 87 - probe openvpn connected correctly server socket closed connection closed down -ok 87 - probe openvpn connected correctly ok 88 - data shoveled correctly accepted fd 5 hexdump of incoming packet: @@ -2571,14 +2609,14 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:38225 family 2 len 16 -xmpp:connection from localhost:47862 to localhost:37887 forwarded from localhost:43006 to localhost:38225 +connecting to localhost:37117 family 2 len 16 +xmpp:connection from localhost:60364 to localhost:40211 forwarded from localhost:50392 to localhost:37117 flushing deferred data to fd 3 Received: protocol xmpp data [I should get a real jabber connection initialisation here] -server socket closed -connection closed down ok 89 - probe xmpp connected correctly ok 90 - data shoveled correctly +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 43 4e 58 4e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e CNXN............ @@ -2591,8 +2629,8 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39349 family 2 len 16 -adb:connection from localhost:47878 to localhost:37887 forwarded from localhost:50922 to localhost:39349 +connecting to localhost:42391 family 2 len 16 +adb:connection from localhost:60366 to localhost:40211 forwarded from localhost:51882 to localhost:42391 flushing deferred data to fd 3 Received: protocol adb data [CNXN....................host:...] server socket closed @@ -2628,8 +2666,8 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:47884 to localhost:37887 forwarded from localhost:33698 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:60378 to localhost:40211 forwarded from localhost:40884 to localhost:33945 flushing deferred data to fd 3 Received: protocol tls:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  @@ -2669,19 +2707,19 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:47894 to localhost:37887 forwarded from localhost:33700 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:60382 to localhost:40211 forwarded from localhost:40898 to localhost:33945 flushing deferred data to fd 3 Received: protocol tls:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  # alpn2hello sni/alpn] -server socket closed -connection closed down ok 95 - probe tls:sni1;alpn1,alpn2 connected correctly ok 96 - data shoveled correctly sni: sni2 alpn: alpn1 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ @@ -2711,19 +2749,19 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:47896 to localhost:37887 forwarded from localhost:43764 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60392 to localhost:40211 forwarded from localhost:60220 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni2  # alpn1hello sni/alpn] -server socket closed -connection closed down ok 97 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 98 - data shoveled correctly sni: sni2 alpn: alpn2 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ @@ -2754,8 +2792,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:47904 to localhost:37887 forwarded from localhost:43770 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60394 to localhost:40211 forwarded from localhost:60236 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni2  @@ -2797,20 +2835,20 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:47912 to localhost:37887 forwarded from localhost:43780 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60404 to localhost:40211 forwarded from localhost:60248 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni3  # alpn1hello sni/alpn] -server socket closed -connection closed down ok 101 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 102 - data shoveled correctly sni: sni3 alpn: alpn2 accepted fd 5 +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ 0x000010: 14 13 cc 1b 94 ad 20 5d 13 1a 8d d2 65 23 70 de ...... ]....e#p. @@ -2841,16 +2879,16 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:47922 to localhost:37887 forwarded from localhost:43796 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60406 to localhost:40211 forwarded from localhost:60256 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni3  # alpn2hello sni/alpn] +ok 103 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly server socket closed connection closed down -ok 103 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 104 - data shoveled correctly sni: alpn: alpn3 @@ -2887,16 +2925,16 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:44653 family 2 len 16 -tls:connection from localhost:47924 to localhost:37887 forwarded from localhost:46456 to localhost:44653 +connecting to localhost:41517 family 2 len 16 +tls:connection from localhost:60420 to localhost:40211 forwarded from localhost:54698 to localhost:41517 flushing deferred data to fd 3 Received: protocol tls:;alpn3 data [·³â¢)1­˜DQT[ÙQfµ?è‹ Éä+—$ï­VÉ8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿR  # alpn3hello alpn] +ok 105 - probe tls:;alpn3 connected correctly server socket closed connection closed down -ok 105 - probe tls:;alpn3 connected correctly ok 106 - data shoveled correctly sni: sni3 alpn: @@ -2931,8 +2969,8 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:33335 family 2 len 16 -tls:connection from localhost:47934 to localhost:37887 forwarded from localhost:36248 to localhost:33335 +connecting to localhost:43885 family 2 len 16 +tls:connection from localhost:60436 to localhost:40211 forwarded from localhost:38804 to localhost:43885 flushing deferred data to fd 3 Received: protocol tls:sni3; data [¸´—äé­†á!ýÄ['­KUÂPä†/7%ÞèœYü©728À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿS sni3  @@ -2969,16 +3007,16 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:47944 to localhost:37887 forwarded from localhost:33350 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:60438 to localhost:40211 forwarded from localhost:60742 to localhost:32999 flushing deferred data to fd 3 Received: protocol tls data [«§‰"3•CzÉEQ<($jx¿¾•ØX×e÷»-² u8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿF  # hello ssl alone] +ok 109 - probe tls connected correctly server socket closed connection closed down -ok 109 - probe tls connected correctly ok 110 - data shoveled correctly accepted fd 5 hexdump of incoming packet: @@ -3009,13 +3047,13 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:43371 family 2 len 16 -anyprot:connection from localhost:47950 to localhost:37887 forwarded from localhost:44778 to localhost:43371 +connecting to localhost:38493 family 2 len 16 +anyprot:connection from localhost:60446 to localhost:40211 forwarded from localhost:50776 to localhost:38493 flushing deferred data to fd 3 Received: protocol anyprot data [hello anyprot this needs to be longer than xmpp and adb which expect about 50 characters, which I all have to write before the timeout!] +ok 111 - probe anyprot connected correctly server socket closed connection closed down -ok 111 - probe anyprot connected correctly ok 112 - data shoveled correctly accepted fd 5 hexdump of incoming packet: @@ -3067,13 +3105,13 @@ 0x000000: 2d - **** writing deferred on fd -1 probing for ssh: PROBE_MATCH -connecting to localhost:35221 family 2 len 16 -ssh:connection from localhost:47958 to localhost:37887 forwarded from localhost:36718 to localhost:35221 +connecting to localhost:39237 family 2 len 16 +ssh:connection from localhost:60462 to localhost:40211 forwarded from localhost:51264 to localhost:39237 flushing deferred data to fd 3 Received: protocol ssh data [SSH-2.0 tester] +ok 113 - probe ssh connected correctly server socket closed connection closed down -ok 113 - probe ssh connected correctly ok 114 - data shoveled correctly accepted fd 5 hexdump of incoming packet: @@ -3161,8 +3199,8 @@ **** writing deferred on fd -1 probing for ssh: PROBE_NEXT probing for socks5: PROBE_MATCH -connecting to localhost:40223 family 2 len 16 -socks5:connection from localhost:47966 to localhost:37887 forwarded from localhost:55550 to localhost:40223 +connecting to localhost:35777 family 2 len 16 +socks5:connection from localhost:60464 to localhost:40211 forwarded from localhost:44192 to localhost:35777 flushing deferred data to fd 3 Received: protocol socks5 data [] server socket closed @@ -3177,15 +3215,15 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:41023 family 2 len 16 -http:connection from localhost:47970 to localhost:37887 forwarded from localhost:37072 to localhost:41023 +connecting to localhost:42461 family 2 len 16 +http:connection from localhost:60480 to localhost:40211 forwarded from localhost:36858 to localhost:42461 flushing deferred data to fd 3 Received: protocol http data [GET index.html HTTP/1.1] -server socket closed -connection closed down ok 117 - probe http connected correctly ok 118 - data shoveled correctly accepted fd 5 +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 30 20 68 65 6c 6c 6f 0 hello **** writing deferred on fd -1 @@ -3193,13 +3231,13 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:39403 family 2 len 16 -tinc:connection from localhost:47974 to localhost:37887 forwarded from localhost:33512 to localhost:39403 +connecting to localhost:39899 family 2 len 16 +tinc:connection from localhost:60492 to localhost:40211 forwarded from localhost:43332 to localhost:39899 flushing deferred data to fd 3 Received: protocol tinc data [0 hello] +ok 119 - probe tinc connected correctly server socket closed connection closed down -ok 119 - probe tinc connected correctly ok 120 - data shoveled correctly accepted fd 5 hexdump of incoming packet: @@ -3210,13 +3248,13 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46825 family 2 len 16 -openvpn:connection from localhost:47986 to localhost:37887 forwarded from localhost:38614 to localhost:46825 +connecting to localhost:34423 family 2 len 16 +openvpn:connection from localhost:60508 to localhost:40211 forwarded from localhost:57854 to localhost:34423 flushing deferred data to fd 3 Received: protocol openvpn data [] +ok 121 - probe openvpn connected correctly server socket closed connection closed down -ok 121 - probe openvpn connected correctly ok 122 - data shoveled correctly accepted fd 5 hexdump of incoming packet: @@ -3231,13 +3269,13 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:38225 family 2 len 16 -xmpp:connection from localhost:47998 to localhost:37887 forwarded from localhost:43014 to localhost:38225 +connecting to localhost:37117 family 2 len 16 +xmpp:connection from localhost:60510 to localhost:40211 forwarded from localhost:50398 to localhost:37117 flushing deferred data to fd 3 Received: protocol xmpp data [I should get a real jabber connection initialisation here] +ok 123 - probe xmpp connected correctly server socket closed connection closed down -ok 123 - probe xmpp connected correctly ok 124 - data shoveled correctly accepted fd 5 hexdump of incoming packet: @@ -3251,16 +3289,16 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39349 family 2 len 16 -adb:connection from localhost:48004 to localhost:37887 forwarded from localhost:50924 to localhost:39349 +connecting to localhost:42391 family 2 len 16 +adb:connection from localhost:60512 to localhost:40211 forwarded from localhost:51896 to localhost:42391 flushing deferred data to fd 3 Received: protocol adb data [CNXN....................host:...] -server socket closed -connection closed down ok 125 - probe adb connected correctly ok 126 - data shoveled correctly sni: sni1 alpn: alpn1 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ @@ -3288,19 +3326,19 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:48020 to localhost:37887 forwarded from localhost:33702 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:60514 to localhost:40211 forwarded from localhost:40908 to localhost:33945 flushing deferred data to fd 3 Received: protocol tls:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  # alpn1hello sni/alpn] -server socket closed -connection closed down ok 127 - probe tls:sni1;alpn1,alpn2 connected correctly ok 128 - data shoveled correctly sni: sni1 alpn: alpn2 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ @@ -3329,20 +3367,20 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40793 family 2 len 16 -tls:connection from localhost:48032 to localhost:37887 forwarded from localhost:33708 to localhost:40793 +connecting to localhost:33945 family 2 len 16 +tls:connection from localhost:60520 to localhost:40211 forwarded from localhost:40912 to localhost:33945 flushing deferred data to fd 3 Received: protocol tls:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  # alpn2hello sni/alpn] -server socket closed -connection closed down ok 129 - probe tls:sni1;alpn1,alpn2 connected correctly ok 130 - data shoveled correctly sni: sni2 alpn: alpn1 accepted fd 5 +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ 0x000010: 14 13 cc 1b 94 ad 20 5d 13 1a 8d d2 65 23 70 de ...... ]....e#p. @@ -3371,19 +3409,19 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:48040 to localhost:37887 forwarded from localhost:43798 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60522 to localhost:40211 forwarded from localhost:60270 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni2  # alpn1hello sni/alpn] -server socket closed -connection closed down ok 131 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 132 - data shoveled correctly sni: sni2 alpn: alpn2 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ @@ -3414,16 +3452,16 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:48052 to localhost:37887 forwarded from localhost:43802 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60524 to localhost:40211 forwarded from localhost:60274 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni2  # alpn2hello sni/alpn] +ok 133 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly server socket closed connection closed down -ok 133 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 134 - data shoveled correctly sni: sni3 alpn: alpn1 @@ -3457,19 +3495,19 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:48056 to localhost:37887 forwarded from localhost:43806 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60536 to localhost:40211 forwarded from localhost:60284 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni3  # alpn1hello sni/alpn] -server socket closed -connection closed down ok 135 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 136 - data shoveled correctly sni: sni3 alpn: alpn2 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 c4 01 00 00 c0 03 03 03 19 01 00 40 ...............@ @@ -3501,16 +3539,16 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:44535 family 2 len 16 -tls:connection from localhost:48070 to localhost:37887 forwarded from localhost:43814 to localhost:44535 +connecting to localhost:41559 family 2 len 16 +tls:connection from localhost:60548 to localhost:40211 forwarded from localhost:60294 to localhost:41559 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni3  # alpn2hello sni/alpn] +ok 137 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly server socket closed connection closed down -ok 137 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 138 - data shoveled correctly sni: alpn: alpn3 @@ -3547,16 +3585,16 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:44653 family 2 len 16 -tls:connection from localhost:48084 to localhost:37887 forwarded from localhost:46472 to localhost:44653 +connecting to localhost:41517 family 2 len 16 +tls:connection from localhost:60560 to localhost:40211 forwarded from localhost:54706 to localhost:41517 flushing deferred data to fd 3 Received: protocol tls:;alpn3 data [·³â¢)1­˜DQT[ÙQfµ?è‹ Éä+—$ï­VÉ8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿR  # alpn3hello alpn] +ok 139 - probe tls:;alpn3 connected correctly server socket closed connection closed down -ok 139 - probe tls:;alpn3 connected correctly ok 140 - data shoveled correctly sni: sni3 alpn: @@ -3591,17 +3629,17 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:33335 family 2 len 16 -tls:connection from localhost:48092 to localhost:37887 forwarded from localhost:36264 to localhost:33335 +connecting to localhost:43885 family 2 len 16 +tls:connection from localhost:60566 to localhost:40211 forwarded from localhost:38818 to localhost:43885 flushing deferred data to fd 3 Received: protocol tls:sni3; data [¸´—äé­†á!ýÄ['­KUÂPä†/7%ÞèœYü©728À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿS sni3  # hello sni] -server socket closed -connection closed down ok 141 - probe tls:sni3; connected correctly ok 142 - data shoveled correctly +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 ab 01 00 00 a7 03 03 89 22 33 95 43 ............"3.C @@ -3629,19 +3667,19 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:43109 family 2 len 16 -tls:connection from localhost:48094 to localhost:37887 forwarded from localhost:33364 to localhost:43109 +connecting to localhost:32999 family 2 len 16 +tls:connection from localhost:60574 to localhost:40211 forwarded from localhost:60750 to localhost:32999 flushing deferred data to fd 3 Received: protocol tls data [«§‰"3•CzÉEQ<($jx¿¾•ØX×e÷»-² u8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿF  # hello ssl alone] -server socket closed -connection closed down ok 143 - probe tls connected correctly ok 144 - data shoveled correctly accepted fd 5 +server socket closed hexdump of incoming packet: +connection closed down 0x000000: 68 65 6c 6c 6f 20 61 6e 79 70 72 6f 74 20 74 68 hello anyprot th 0x000010: 69 73 20 6e 65 65 64 73 20 74 6f 20 62 65 20 6c is needs to be l 0x000020: 6f 6e 67 65 72 20 74 68 61 6e 20 78 6d 70 70 20 onger than xmpp @@ -3669,28 +3707,28 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:43371 family 2 len 16 -anyprot:connection from localhost:48104 to localhost:37887 forwarded from localhost:44792 to localhost:43371 +connecting to localhost:38493 family 2 len 16 +anyprot:connection from localhost:60578 to localhost:40211 forwarded from localhost:50792 to localhost:38493 flushing deferred data to fd 3 Received: protocol anyprot data [hello anyprot this needs to be longer than xmpp and adb which expect about 50 characters, which I all have to write before the timeout!] +ok 145 - probe anyprot connected correctly server socket closed connection closed down -ok 145 - probe anyprot connected correctly ok 146 - data shoveled correctly -killing 19898 +killing 11147 ***Test: Connecting to non-existant server -spawned 21056 +spawned 11392 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:33245. libwrap service: sshd log_level: 1 family 2 2 [] [fork] -tls addr: localhost:33245. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:35585. libwrap service: sshd log_level: 1 family 2 2 [] [fork] +tls addr: localhost:35585. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:37887 [] + localhost:40211 [] timeout: 2 on-timeout: ssh listening to 1 addresses sslh-select 1.20-1 started -turning into pbuilder1 +turning into pbuilder2 capabilities: = selecting... max_fd=4 num_probing=0 accepted fd 4 on slot 0 @@ -3702,45 +3740,45 @@ probing for ssh: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:33245 family 2 len 16 +connecting to localhost:35585 family 2 len 16 forward to tls failed:connect: Connection refused closing fd 4 selecting... max_fd=5 num_probing=0 ***Test: No hostname in address -spawned 21060 +spawned 11402 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -37887: names must be fully specified as hostname:port +40211: names must be fully specified as hostname:port exited with 1 ok 147 - Exit status on illegal option ***Test: Changing to non-existant username -spawned 21061 +spawned 11403 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:35221. libwrap service: sshd log_level: 1 family 2 2 [] [fork] -tls addr: localhost:33335. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:39237. libwrap service: sshd log_level: 1 family 2 2 [] [fork] +tls addr: localhost:43885. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:37887 [] + localhost:40211 [] timeout: 2 on-timeout: ssh listening to 1 addresses sslh-select 1.20-1 started -pbuilder1_doesnt_exist: not found +pbuilder2_doesnt_exist: not found exited with 2 ok 148 - Exit status on non-existant username ***Test: Can't open PID file -spawned 21062 +spawned 11404 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:35221. libwrap service: sshd log_level: 1 family 2 2 [] [fork] -tls addr: localhost:33335. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:39237. libwrap service: sshd log_level: 1 family 2 2 [] [fork] +tls addr: localhost:43885. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:37887 [] + localhost:40211 [] timeout: 2 on-timeout: ssh listening to 1 addresses -/dont_exist//tmp/rLR_PiXTXS/sslh.pid: No such file or directory +/dont_exist//tmp/fRYFVvdUuC/sslh.pid: No such file or directory exited with 3 ok 149 - Exit status if can't open PID file ***Test: Can't resolve address -spawned 21063 +spawned 11405 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead Temporary failure in name resolution `blahblah.dontexist:9000' Temporary failure in name resolution `blahblah.dontexist' @@ -3755,19 +3793,6 @@ Subroutine process_intermediate redefined at /usr/bin/geninfo line 2944. geninfo: WARNING: no .gcda files found in . - skipping! genhtml: ERROR: no valid records found in tracefile sslh_cov.info -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated -Terminated 1..150 make[1]: Leaving directory '/build/sslh-1.20' create-stamp debian/debhelper-build-stamp @@ -3780,7 +3805,7 @@ make[1]: Entering directory '/build/sslh-1.20' # auto install fork version, see debian/install for select version dh_auto_install -- DESTDIR=/build/sslh-1.20/debian/sslh/usr PREFIX=/ install - make -j3 install DESTDIR=/build/sslh-1.20/debian/sslh AM_UPDATE_INFO_DIR=no "INSTALL=install --strip-program=true" DESTDIR=/build/sslh-1.20/debian/sslh/usr PREFIX=/ install + make -j4 install DESTDIR=/build/sslh-1.20/debian/sslh AM_UPDATE_INFO_DIR=no "INSTALL=install --strip-program=true" DESTDIR=/build/sslh-1.20/debian/sslh/usr PREFIX=/ install make[2]: Entering directory '/build/sslh-1.20' Makefile:66: warning: ignoring prerequisites on suffix rule definition mkdir -p /build/sslh-1.20/debian/sslh/usr///sbin @@ -3828,12 +3853,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/6076/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/6076/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/16717 and its subdirectories -I: Current time: Tue Jun 6 19:32:17 -12 2023 -I: pbuilder-time-stamp: 1686123137 +I: removing directory /srv/workspace/pbuilder/6076 and its subdirectories +I: Current time: Wed Jun 7 21:38:33 +14 2023 +I: pbuilder-time-stamp: 1686123513