Diff of the two buildlogs: -- --- b1/build.log 2023-04-13 20:57:31.999743492 +0000 +++ b2/build.log 2023-04-13 20:59:43.941886430 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Thu Apr 13 08:55:04 -12 2023 -I: pbuilder-time-stamp: 1681419304 +I: Current time: Thu May 16 17:20:33 +14 2024 +I: pbuilder-time-stamp: 1715829633 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -17,7 +17,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 @@ -32,137 +32,146 @@ dpkg-source: info: applying t_no_libconfig I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/28795/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/115168/tmp/hooks/D01_modify_environment starting +debug: Running on ionos16-i386. +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 +lrwxrwxrwx 1 root root 4 Jan 6 2023 /bin/sh -> dash +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/115168/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/115168/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' + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME='pbuilder2' BUILD_ARCH='i386' DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=8' + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=15' DISTRIBUTION='bookworm' HOME='/root' HOST_ARCH='i386' IFS=' ' - INVOCATION_ID='211a569084584b3ca3b1bdbdbae0f603' + INVOCATION_ID='6d0591e8755a452e8c401f0ac85d9946' LANG='C' - LANGUAGE='en_US:en' + LANGUAGE='de_CH:de' LC_ALL='C' LD_LIBRARY_PATH='/usr/lib/libeatmydata' LD_PRELOAD='libeatmydata.so' MAIL='/var/mail/root' OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' + 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' - PPID='28795' + PPID='115168' PS1='# ' PS2='> ' 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.56m8iWUL/pbuilderrc_PuJO --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.56m8iWUL/b1 --logfile b1/build.log sslh_1.20-1.dsc' + 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.56m8iWUL/pbuilderrc_Nk0H --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.56m8iWUL/b2 --logfile b2/build.log --extrapackages usrmerge sslh_1.20-1.dsc' SUDO_GID='112' SUDO_UID='107' SUDO_USER='jenkins' TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' + TZ='/usr/share/zoneinfo/Etc/GMT-14' USER='root' _='/usr/bin/systemd-run' - http_proxy='http://78.137.99.97:3128' + http_proxy='http://85.184.249.68:3128' I: uname -a - Linux ionos2-i386 5.10.0-21-686-pae #1 SMP Debian 5.10.162-1 (2023-01-21) i686 GNU/Linux + Linux i-capture-the-hostname 5.10.0-21-amd64 #1 SMP Debian 5.10.162-1 (2023-01-21) x86_64 GNU/Linux I: ls -l /bin total 6040 - -rwxr-xr-x 1 root root 1408088 Feb 12 08:21 bash - -rwxr-xr-x 3 root root 38404 Sep 18 2022 bunzip2 - -rwxr-xr-x 3 root root 38404 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 38404 Sep 18 2022 bzip2 - -rwxr-xr-x 1 root root 17892 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 42920 Sep 20 2022 cat - -rwxr-xr-x 1 root root 79816 Sep 20 2022 chgrp - -rwxr-xr-x 1 root root 67496 Sep 20 2022 chmod - -rwxr-xr-x 1 root root 79816 Sep 20 2022 chown - -rwxr-xr-x 1 root root 162024 Sep 20 2022 cp - -rwxr-xr-x 1 root root 136916 Jan 5 01:20 dash - -rwxr-xr-x 1 root root 137160 Sep 20 2022 date - -rwxr-xr-x 1 root root 100364 Sep 20 2022 dd - -rwxr-xr-x 1 root root 108940 Sep 20 2022 df - -rwxr-xr-x 1 root root 162152 Sep 20 2022 dir - -rwxr-xr-x 1 root root 87760 Mar 22 22:20 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 38760 Sep 20 2022 echo - -rwxr-xr-x 1 root root 41 Jan 24 02:43 egrep - -rwxr-xr-x 1 root root 34664 Sep 20 2022 false - -rwxr-xr-x 1 root root 41 Jan 24 02:43 fgrep - -rwxr-xr-x 1 root root 84272 Mar 22 22:20 findmnt - -rwsr-xr-x 1 root root 30240 Mar 22 20:38 fusermount - -rwxr-xr-x 1 root root 218680 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 100952 Apr 9 2022 gzip - -rwxr-xr-x 1 root root 21916 Dec 19 01:33 hostname - -rwxr-xr-x 1 root root 75756 Sep 20 2022 ln - -rwxr-xr-x 1 root root 55600 Mar 22 23:43 login - -rwxr-xr-x 1 root root 162152 Sep 20 2022 ls - -rwxr-xr-x 1 root root 214568 Mar 22 22:20 lsblk - -rwxr-xr-x 1 root root 96328 Sep 20 2022 mkdir - -rwxr-xr-x 1 root root 84008 Sep 20 2022 mknod - -rwxr-xr-x 1 root root 38792 Sep 20 2022 mktemp - -rwxr-xr-x 1 root root 63016 Mar 22 22:20 more - -rwsr-xr-x 1 root root 58912 Mar 22 22:20 mount - -rwxr-xr-x 1 root root 13856 Mar 22 22:20 mountpoint - -rwxr-xr-x 1 root root 157932 Sep 20 2022 mv - lrwxrwxrwx 1 root root 8 Dec 19 01:33 nisdomainname -> hostname - lrwxrwxrwx 1 root root 14 Dec 18 05:00 pidof -> /sbin/killall5 - -rwxr-xr-x 1 root root 38792 Sep 20 2022 pwd - lrwxrwxrwx 1 root root 4 Feb 12 08:21 rbash -> bash - -rwxr-xr-x 1 root root 51080 Sep 20 2022 readlink - -rwxr-xr-x 1 root root 75720 Sep 20 2022 rm - -rwxr-xr-x 1 root root 51080 Sep 20 2022 rmdir - -rwxr-xr-x 1 root root 22308 Nov 2 04:31 run-parts - -rwxr-xr-x 1 root root 133224 Jan 5 07:55 sed - lrwxrwxrwx 1 root root 4 Jan 5 01:20 sh -> dash - -rwxr-xr-x 1 root root 38760 Sep 20 2022 sleep - -rwxr-xr-x 1 root root 87976 Sep 20 2022 stty - -rwsr-xr-x 1 root root 83492 Mar 22 22:20 su - -rwxr-xr-x 1 root root 38792 Sep 20 2022 sync - -rwxr-xr-x 1 root root 602584 Feb 16 2021 tar - -rwxr-xr-x 1 root root 13860 Nov 2 04:31 tempfile - -rwxr-xr-x 1 root root 120776 Sep 20 2022 touch - -rwxr-xr-x 1 root root 34664 Sep 20 2022 true - -rwxr-xr-x 1 root root 17892 Mar 22 20:38 ulockmgr_server - -rwsr-xr-x 1 root root 30236 Mar 22 22:20 umount - -rwxr-xr-x 1 root root 38760 Sep 20 2022 uname - -rwxr-xr-x 2 root root 2346 Apr 9 2022 uncompress - -rwxr-xr-x 1 root root 162152 Sep 20 2022 vdir - -rwxr-xr-x 1 root root 71216 Mar 22 22:20 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/28795/tmp/hooks/D02_print_environment finished + -rwxr-xr-x 1 root root 1408088 Feb 13 2023 bash + -rwxr-xr-x 3 root root 38404 Sep 19 2022 bunzip2 + -rwxr-xr-x 3 root root 38404 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 38404 Sep 19 2022 bzip2 + -rwxr-xr-x 1 root root 17892 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 42920 Sep 21 2022 cat + -rwxr-xr-x 1 root root 79816 Sep 21 2022 chgrp + -rwxr-xr-x 1 root root 67496 Sep 21 2022 chmod + -rwxr-xr-x 1 root root 79816 Sep 21 2022 chown + -rwxr-xr-x 1 root root 162024 Sep 21 2022 cp + -rwxr-xr-x 1 root root 136916 Jan 6 2023 dash + -rwxr-xr-x 1 root root 137160 Sep 21 2022 date + -rwxr-xr-x 1 root root 100364 Sep 21 2022 dd + -rwxr-xr-x 1 root root 108940 Sep 21 2022 df + -rwxr-xr-x 1 root root 162152 Sep 21 2022 dir + -rwxr-xr-x 1 root root 87760 Mar 24 2023 dmesg + lrwxrwxrwx 1 root root 8 Dec 20 2022 dnsdomainname -> hostname + lrwxrwxrwx 1 root root 8 Dec 20 2022 domainname -> hostname + -rwxr-xr-x 1 root root 38760 Sep 21 2022 echo + -rwxr-xr-x 1 root root 41 Jan 25 2023 egrep + -rwxr-xr-x 1 root root 34664 Sep 21 2022 false + -rwxr-xr-x 1 root root 41 Jan 25 2023 fgrep + -rwxr-xr-x 1 root root 84272 Mar 24 2023 findmnt + -rwsr-xr-x 1 root root 30240 Mar 23 2023 fusermount + -rwxr-xr-x 1 root root 218680 Jan 25 2023 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 100952 Apr 10 2022 gzip + -rwxr-xr-x 1 root root 21916 Dec 20 2022 hostname + -rwxr-xr-x 1 root root 75756 Sep 21 2022 ln + -rwxr-xr-x 1 root root 55600 Mar 24 2023 login + -rwxr-xr-x 1 root root 162152 Sep 21 2022 ls + -rwxr-xr-x 1 root root 214568 Mar 24 2023 lsblk + -rwxr-xr-x 1 root root 96328 Sep 21 2022 mkdir + -rwxr-xr-x 1 root root 84008 Sep 21 2022 mknod + -rwxr-xr-x 1 root root 38792 Sep 21 2022 mktemp + -rwxr-xr-x 1 root root 63016 Mar 24 2023 more + -rwsr-xr-x 1 root root 58912 Mar 24 2023 mount + -rwxr-xr-x 1 root root 13856 Mar 24 2023 mountpoint + -rwxr-xr-x 1 root root 157932 Sep 21 2022 mv + lrwxrwxrwx 1 root root 8 Dec 20 2022 nisdomainname -> hostname + lrwxrwxrwx 1 root root 14 Dec 19 2022 pidof -> /sbin/killall5 + -rwxr-xr-x 1 root root 38792 Sep 21 2022 pwd + lrwxrwxrwx 1 root root 4 Feb 13 2023 rbash -> bash + -rwxr-xr-x 1 root root 51080 Sep 21 2022 readlink + -rwxr-xr-x 1 root root 75720 Sep 21 2022 rm + -rwxr-xr-x 1 root root 51080 Sep 21 2022 rmdir + -rwxr-xr-x 1 root root 22308 Nov 3 2022 run-parts + -rwxr-xr-x 1 root root 133224 Jan 6 2023 sed + lrwxrwxrwx 1 root root 4 Jan 6 2023 sh -> dash + -rwxr-xr-x 1 root root 38760 Sep 21 2022 sleep + -rwxr-xr-x 1 root root 87976 Sep 21 2022 stty + -rwsr-xr-x 1 root root 83492 Mar 24 2023 su + -rwxr-xr-x 1 root root 38792 Sep 21 2022 sync + -rwxr-xr-x 1 root root 602584 Feb 17 2021 tar + -rwxr-xr-x 1 root root 13860 Nov 3 2022 tempfile + -rwxr-xr-x 1 root root 120776 Sep 21 2022 touch + -rwxr-xr-x 1 root root 34664 Sep 21 2022 true + -rwxr-xr-x 1 root root 17892 Mar 23 2023 ulockmgr_server + -rwsr-xr-x 1 root root 30236 Mar 24 2023 umount + -rwxr-xr-x 1 root root 38760 Sep 21 2022 uname + -rwxr-xr-x 2 root root 2346 Apr 10 2022 uncompress + -rwxr-xr-x 1 root root 162152 Sep 21 2022 vdir + -rwxr-xr-x 1 root root 71216 Mar 24 2023 wdctl + lrwxrwxrwx 1 root root 8 Dec 20 2022 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/115168/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -264,7 +273,7 @@ Get: 48 http://deb.debian.org/debian bookworm/main i386 libwrap0 i386 7.6.q-32 [56.0 kB] Get: 49 http://deb.debian.org/debian bookworm/main i386 libwrap0-dev i386 7.6.q-32 [23.5 kB] Get: 50 http://deb.debian.org/debian bookworm/main i386 psmisc i386 23.6-1 [260 kB] -Fetched 22.0 MB in 1s (41.7 MB/s) +Fetched 22.0 MB in 1s (25.2 MB/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 ... 19604 files and directories currently installed.) @@ -481,10 +490,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/115168/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/115168/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 @@ -494,7 +510,7 @@ fakeroot debian/rules clean dh clean dh_auto_clean - make -j8 distclean + make -j15 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 @@ -509,7 +525,7 @@ debian/rules override_dh_auto_build make[1]: Entering directory '/build/sslh-1.20' dh_auto_build -- USELIBWRAP=1 USELIBCAP=1 - make -j8 "INSTALL=install --strip-program=true" USELIBWRAP=1 USELIBCAP=1 + make -j15 "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 @@ -546,26 +562,26 @@ make[2]: Leaving directory '/build/sslh-1.20' make[1]: Leaving directory '/build/sslh-1.20' dh_auto_test - make -j8 test + make -j15 test make[1]: Entering directory '/build/sslh-1.20' Makefile:66: warning: ignoring prerequisites on suffix rule definition ./t -./echosrv --listen localhost:39561 --prefix 'ssh: ' -./echosrv --listen localhost:35713 --prefix 'socks5: ' -./echosrv --listen localhost:37251 --prefix 'http: ' -./echosrv --listen localhost:35383 --prefix 'tinc: ' -./echosrv --listen localhost:46275 --prefix 'openvpn: ' -./echosrv --listen localhost:40797 --prefix 'xmpp: ' -./echosrv --listen localhost:39417 --prefix 'adb: ' -./echosrv --listen localhost:42281 --prefix 'tls:sni1;alpn1,alpn2: ' -./echosrv --listen localhost:40189 --prefix 'tls:sni2,sni3;alpn1,alpn2: ' -./echosrv --listen localhost:35867 --prefix 'tls:;alpn3: ' -./echosrv --listen localhost:36541 --prefix 'tls:sni3;: ' -./echosrv --listen localhost:34151 --prefix 'tls: ' -./echosrv --listen localhost:44435 --prefix 'anyprot: ' +./echosrv --listen localhost:35957 --prefix 'ssh: ' +./echosrv --listen localhost:42061 --prefix 'socks5: ' +./echosrv --listen localhost:45149 --prefix 'http: ' +./echosrv --listen localhost:43721 --prefix 'tinc: ' +./echosrv --listen localhost:35707 --prefix 'openvpn: ' +./echosrv --listen localhost:33235 --prefix 'xmpp: ' +./echosrv --listen localhost:33371 --prefix 'adb: ' +./echosrv --listen localhost:46781 --prefix 'tls:sni1;alpn1,alpn2: ' +./echosrv --listen localhost:44087 --prefix 'tls:sni2,sni3;alpn1,alpn2: ' +./echosrv --listen localhost:38875 --prefix 'tls:;alpn3: ' +./echosrv --listen localhost:33925 --prefix 'tls:sni3;: ' +./echosrv --listen localhost:36833 --prefix 'tls: ' +./echosrv --listen localhost:38035 --prefix 'anyprot: ' Testing sslh-select -spawned 32450 -./sslh-select -v -f -u pbuilder1 -Ftest.cfg +spawned 10286 +./sslh-select -v -f -u pbuilder2 -Ftest.cfg tls: sni_hostnames[0]: sni1 tls: alpn_protocols[0]: alpn1 tls: alpn_protocols[1]: alpn2 @@ -577,27 +593,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:39561. libwrap service: (null) log_level: 1 family 2 2 [] [fork] -socks5 addr: localhost:35713. libwrap service: (null) log_level: 1 family 2 2 [] [] -http addr: localhost:37251. libwrap service: (null) log_level: 1 family 2 2 [] [] -tinc addr: localhost:35383. libwrap service: (null) log_level: 1 family 2 2 [] [] -openvpn addr: localhost:46275. libwrap service: (null) log_level: 1 family 2 2 [] [] -xmpp addr: localhost:40797. libwrap service: (null) log_level: 1 family 2 2 [] [] -adb addr: localhost:39417. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:42281. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:40189. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:35867. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:36541. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:34151. libwrap service: (null) log_level: 1 family 2 2 [] [] -anyprot addr: localhost:44435. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:35957. libwrap service: (null) log_level: 1 family 2 2 [] [fork] +socks5 addr: localhost:42061. libwrap service: (null) log_level: 1 family 2 2 [] [] +http addr: localhost:45149. libwrap service: (null) log_level: 1 family 2 2 [] [] +tinc addr: localhost:43721. libwrap service: (null) log_level: 1 family 2 2 [] [] +openvpn addr: localhost:35707. libwrap service: (null) log_level: 1 family 2 2 [] [] +xmpp addr: localhost:33235. libwrap service: (null) log_level: 1 family 2 2 [] [] +adb addr: localhost:33371. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:46781. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:44087. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:38875. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:33925. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:36833. libwrap service: (null) log_level: 1 family 2 2 [] [] +anyprot addr: localhost:38035. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:36063 [keepalive] + localhost:44443 [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 @@ -629,20 +645,21 @@ timed out, connect to ssh closing fd 5 selecting... max_fd=6 num_probing=0 -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:50272 to localhost:36063 forwarded from localhost:42404 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:58428 to localhost:44443 forwarded from localhost:43126 to localhost:35957 flushing deferred data to fd 4 ok 1 - Shy SSH connection ***Test: One SSL half-started then one SSH +socket closed +connection closed down accepted fd 5 on slot 0 selecting... max_fd=6 num_probing=1 +accepted fd 6 on 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.<(socket closed -$.jx.... -0x000020: 90 58 d7 65 f7 bb 2d b2 8d a0 75 00 00 38 c0 2c .X.e.connection closed down -.-...u..8., +0x000010: 7a c3 89 45 51 12 3c 28 24 1b 6a 78 bf be 95 d8 z..EQ.<($.jx.... +0x000020: 90 58 d7 65 f7 bb 2d b2 8d a0 75 00 00 38 c0 2c .X.e..-...u..8., 0x000030: c0 30 00 9f cc a9 cc a8 cc aa c0 2b c0 2f 00 9e .0.........+./.. 0x000040: c0 24 c0 28 00 6b c0 23 c0 27 00 67 c0 0a c0 14 .$.(.k.#.'.g.... 0x000050: 00 39 c0 09 c0 13 00 33 00 9d 00 9c 00 3d 00 3c .9.....3.....=.< @@ -665,14 +682,12 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:45392 to localhost:36063 forwarded from localhost:56154 to localhost:34151 -flushing deferred data to fd 6 -selecting... max_fd=7 num_probing=0 -accepted fd 7 on slot 1 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:58298 to localhost:44443 forwarded from localhost:34064 to localhost:36833 +flushing deferred data to fd 7 selecting... max_fd=8 num_probing=1 processing fd1 slot 0 -activity on fd6 +activity on fd7 selecting... max_fd=8 num_probing=1 processing fd0 slot 1 hexdump of incoming packet: @@ -698,25 +713,26 @@ selecting... max_fd=8 num_probing=1 processing fd0 slot 1 timed out, connect to ssh -closing fd 7 +closing fd 6 selecting... max_fd=8 num_probing=0 -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:45398 to localhost:36063 forwarded from localhost:47116 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:58310 to localhost:44443 forwarded from localhost:46898 to localhost:35957 flushing deferred data to fd 4 ok 2 - SSH during SSL being established ok 3 - SSL connection interrupted by SSH ***Test: One SSH half-started then one SSL -socket closed -connection closed down -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=1 +closing fd 7 +selecting... max_fd=8 num_probing=0 +socket closed +connection closed down accepted fd 5 on slot 0 +selecting... max_fd=8 num_probing=1 +accepted fd 6 on slot 1 selecting... max_fd=8 num_probing=2 -processing fd0 slot 0 +processing fd0 slot 1 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.... @@ -743,15 +759,15 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:48868 to localhost:36063 forwarded from localhost:58188 to localhost:34151 -flushing deferred data to fd 6 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:44946 to localhost:44443 forwarded from localhost:32834 to localhost:36833 +flushing deferred data to fd 7 selecting... max_fd=8 num_probing=1 -processing fd1 slot 0 -activity on fd6 +processing fd1 slot 1 +activity on fd7 selecting... max_fd=8 num_probing=1 ok 4 - SSL during SSH being established -processing fd0 slot 1 +processing fd0 slot 0 hexdump of incoming packet: 0x000000: 68 65 6c 6c 6f 20 77 6f 72 6c 64 0a hello world. **** writing deferred on fd -1 @@ -773,37 +789,37 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT selecting... max_fd=8 num_probing=1 -processing fd0 slot 1 +processing fd0 slot 0 timed out, connect to ssh -closing fd 7 +closing fd 5 selecting... max_fd=8 num_probing=0 -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:48860 to localhost:36063 forwarded from localhost:33520 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:44940 to localhost:44443 forwarded from localhost:42808 to localhost:35957 flushing deferred data to fd 4 ok 5 - SSH connection interrupted by SSL -accepted fd 7 on slot 1 -processing fd0 slot 0 -activity on fd5 -closing fd 5 +accepted fd 5 on slot 0 +processing fd0 slot 1 +activity on fd6 closing fd 6 +closing fd 7 selecting... max_fd=8 num_probing=1 -processing fd0 slot 1 +processing fd0 slot 0 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 -closing fd 7 +closing fd 5 selecting... max_fd=8 num_probing=0 -socket closed -connection closed down -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:38890 to localhost:36063 forwarded from localhost:33534 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:38048 to localhost:44443 forwarded from localhost:42812 to localhost:35957 flushing deferred data to fd 4 -Received: protocol ssh data [SSH-2.0 tester] socket closed connection closed down +Received: protocol ssh data [SSH-2.0 tester] ok 6 - probe ssh connected correctly ok 7 - data shoveled correctly +socket closed +connection closed down accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 @@ -812,8 +828,8 @@ **** writing deferred on fd -1 probing for ssh: PROBE_NEXT probing for socks5: PROBE_MATCH -connecting to localhost:35713 family 2 len 16 -socks5:connection from localhost:38904 to localhost:36063 forwarded from localhost:42614 to localhost:35713 +connecting to localhost:42061 family 2 len 16 +socks5:connection from localhost:38062 to localhost:44443 forwarded from localhost:55462 to localhost:42061 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -836,8 +852,8 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:37251 family 2 len 16 -http:connection from localhost:38912 to localhost:36063 forwarded from localhost:57900 to localhost:37251 +connecting to localhost:45149 family 2 len 16 +http:connection from localhost:38074 to localhost:44443 forwarded from localhost:34764 to localhost:45149 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 @@ -860,8 +876,8 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:35383 family 2 len 16 -tinc:connection from localhost:38914 to localhost:36063 forwarded from localhost:43212 to localhost:35383 +connecting to localhost:43721 family 2 len 16 +tinc:connection from localhost:38078 to localhost:44443 forwarded from localhost:35936 to localhost:43721 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -869,14 +885,15 @@ selecting... max_fd=8 num_probing=0 Received: protocol tinc data [0 hello] ok 12 - probe tinc connected correctly -ok 13 - data shoveled correctly -accepted fd 7 on slot 1 processing fd0 slot 0 activity on fd6 closing fd 6 closing fd 5 +selecting... max_fd=8 num_probing=0 +ok 13 - data shoveled correctly +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: 00 00 .. **** writing deferred on fd -1 @@ -885,23 +902,23 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46275 family 2 len 16 -openvpn:connection from localhost:38922 to localhost:36063 forwarded from localhost:49890 to localhost:46275 -flushing deferred data to fd 5 +connecting to localhost:35707 family 2 len 16 +openvpn:connection from localhost:38086 to localhost:44443 forwarded from localhost:35546 to localhost:35707 +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 openvpn data [] ok 14 - probe openvpn connected correctly ok 15 - data shoveled correctly -accepted fd 6 on slot 0 -processing fd0 slot 1 -activity on fd7 -closing fd 7 +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=1 -processing fd0 slot 0 +processing fd0 slot 1 hexdump of incoming packet: 0x000000: 49 20 73 68 6f 75 6c 64 20 67 65 74 20 61 20 72 I should get a r 0x000010: 65 61 6c 20 6a 61 62 62 65 72 20 63 6f 6e 6e 65 eal jabber conne @@ -914,23 +931,24 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:40797 family 2 len 16 -xmpp:connection from localhost:38930 to localhost:36063 forwarded from localhost:38202 to localhost:40797 +connecting to localhost:33235 family 2 len 16 +xmpp:connection from localhost:38088 to localhost:44443 forwarded from localhost:60610 to localhost:33235 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 -processing fd1 slot 0 +processing fd1 slot 1 activity on fd5 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 -accepted fd 7 on slot 1 -processing fd0 slot 0 -activity on fd6 -closing fd 6 +processing fd0 slot 1 +activity on fd7 +closing fd 7 closing fd 5 +selecting... max_fd=8 num_probing=0 +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: 43 4e 58 4e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e CNXN............ 0x000010: 2e 2e 2e 2e 2e 2e 2e 2e 68 6f 73 74 3a 2e 2e 2e ........host:... @@ -942,25 +960,25 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39417 family 2 len 16 -adb:connection from localhost:38946 to localhost:36063 forwarded from localhost:54718 to localhost:39417 -flushing deferred data to fd 5 +connecting to localhost:33371 family 2 len 16 +adb:connection from localhost:38104 to localhost:44443 forwarded from localhost:34092 to localhost:33371 +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 adb data [CNXN....................host:...] ok 18 - probe adb connected correctly ok 19 - data shoveled correctly sni: sni1 alpn: alpn1 -accepted fd 6 on slot 0 -processing fd0 slot 1 -activity on fd7 -closing fd 7 +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=1 -processing fd0 slot 0 +processing fd0 slot 1 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. @@ -987,28 +1005,29 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:38962 to localhost:36063 forwarded from localhost:58510 to localhost:42281 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:38114 to localhost:44443 forwarded from localhost:34782 to localhost:46781 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 -processing fd1 slot 0 +processing fd1 slot 1 activity on fd5 selecting... max_fd=8 num_probing=0 Received: protocol tls:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  # alpn1hello sni/alpn] +processing fd0 slot 1 +activity on fd7 +closing fd 7 +closing fd 5 +selecting... max_fd=8 num_probing=0 ok 20 - probe tls:sni1;alpn1,alpn2 connected correctly ok 21 - data shoveled correctly sni: sni1 alpn: alpn2 -accepted fd 7 on slot 1 -processing fd0 slot 0 -activity on fd6 -closing fd 6 -closing fd 5 +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. @@ -1036,12 +1055,12 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:38966 to localhost:36063 forwarded from localhost:58522 to localhost:42281 -flushing deferred data to fd 5 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:38124 to localhost:44443 forwarded from localhost:34786 to localhost:46781 +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:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  @@ -1051,13 +1070,13 @@ ok 23 - data shoveled correctly sni: sni2 alpn: alpn1 -accepted fd 6 on slot 0 -processing fd0 slot 1 -activity on fd7 -closing fd 7 +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=1 -processing fd0 slot 0 +processing fd0 slot 1 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. @@ -1086,11 +1105,11 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:38978 to localhost:36063 forwarded from localhost:45836 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38138 to localhost:44443 forwarded from localhost:46866 to localhost:44087 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 -processing fd1 slot 0 +processing fd1 slot 1 activity on fd5 selecting... max_fd=8 num_probing=0 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ @@ -1101,13 +1120,13 @@ ok 25 - data shoveled correctly sni: sni2 alpn: alpn2 -accepted fd 7 on slot 1 -processing fd0 slot 0 -activity on fd6 -closing fd 6 +accepted fd 6 on slot 0 +processing fd0 slot 1 +activity on fd7 +closing fd 7 closing fd 5 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. @@ -1137,11 +1156,11 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:38984 to localhost:36063 forwarded from localhost:45838 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38152 to localhost:44443 forwarded from localhost:46868 to localhost:44087 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 -processing fd1 slot 1 +processing fd1 slot 0 activity on fd5 selecting... max_fd=8 num_probing=0 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ @@ -1152,13 +1171,13 @@ ok 27 - data shoveled correctly sni: sni3 alpn: alpn1 -accepted fd 6 on slot 0 -processing fd0 slot 1 -activity on fd7 -closing fd 7 +accepted fd 7 on slot 1 +processing fd0 slot 0 +activity on fd6 +closing fd 6 closing fd 5 selecting... max_fd=8 num_probing=1 -processing fd0 slot 0 +processing fd0 slot 1 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. @@ -1188,11 +1207,11 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:38988 to localhost:36063 forwarded from localhost:45848 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38168 to localhost:44443 forwarded from localhost:46872 to localhost:44087 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 -processing fd1 slot 0 +processing fd1 slot 1 activity on fd5 selecting... max_fd=8 num_probing=0 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ @@ -1200,16 +1219,17 @@ # alpn1hello sni/alpn] ok 28 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly +processing fd0 slot 1 +activity on fd7 +closing fd 7 +closing fd 5 +selecting... max_fd=8 num_probing=0 ok 29 - data shoveled correctly sni: sni3 alpn: alpn2 -accepted fd 7 on slot 1 -processing fd0 slot 0 -activity on fd6 -closing fd 6 -closing fd 5 +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. @@ -1240,12 +1260,12 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:39002 to localhost:36063 forwarded from localhost:45858 to localhost:40189 -flushing deferred data to fd 5 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38176 to localhost:44443 forwarded from localhost:46876 to localhost:44087 +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/ÿ_ sni3  @@ -1255,13 +1275,13 @@ ok 31 - data shoveled correctly sni: alpn: alpn3 -accepted fd 6 on slot 0 -processing fd0 slot 1 -activity on fd7 -closing fd 7 +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=1 -processing fd0 slot 0 +processing fd0 slot 1 hexdump of incoming packet: 0x000000: 16 03 01 00 b7 01 00 00 b3 03 03 e2 90 a2 29 03 ..............). 0x000010: 31 ad 98 44 51 54 90 5b d9 51 0e 66 b5 3f e8 8b 1..DQT.[.Q.f.?.. @@ -1294,11 +1314,11 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:35867 family 2 len 16 -tls:connection from localhost:39012 to localhost:36063 forwarded from localhost:42486 to localhost:35867 +connecting to localhost:38875 family 2 len 16 +tls:connection from localhost:38178 to localhost:44443 forwarded from localhost:55976 to localhost:38875 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 -processing fd1 slot 0 +processing fd1 slot 1 activity on fd5 selecting... max_fd=8 num_probing=0 Received: protocol tls:;alpn3 data [·³â¢)1­˜DQT[ÙQfµ?è‹ Éä+—$ï­VÉ8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ @@ -1309,12 +1329,11 @@ ok 33 - data shoveled correctly sni: sni3 alpn: -processing fd0 slot 0 -activity on fd6 -closing fd 6 +accepted fd 6 on slot 0 +processing fd0 slot 1 +activity on fd7 +closing fd 7 closing fd 5 -selecting... max_fd=8 num_probing=0 -accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 hexdump of incoming packet: @@ -1347,12 +1366,12 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:36541 family 2 len 16 -tls:connection from localhost:39026 to localhost:36063 forwarded from localhost:59128 to localhost:36541 -flushing deferred data to fd 6 +connecting to localhost:33925 family 2 len 16 +tls:connection from localhost:38188 to localhost:44443 forwarded from localhost:50116 to localhost:33925 +flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 -activity on fd6 +activity on fd5 selecting... max_fd=8 num_probing=0 Received: protocol tls:sni3; data [¸´—äé­†á!ýÄ['­KUÂPä†/7%ÞèœYü©728À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿS sni3  @@ -1362,9 +1381,9 @@ ok 35 - data shoveled correctly accepted fd 7 on slot 1 processing fd0 slot 0 -activity on fd5 -closing fd 5 +activity on fd6 closing fd 6 +closing fd 5 selecting... max_fd=8 num_probing=1 processing fd0 slot 1 hexdump of incoming packet: @@ -1393,19 +1412,19 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:39042 to localhost:36063 forwarded from localhost:56770 to localhost:34151 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:38192 to localhost:44443 forwarded from localhost:58034 to localhost:36833 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 activity on fd5 -selecting... max_fd=8 num_probing=0 Received: protocol tls data [«§‰"3•CzÉEQ<($jx¿¾•ØX×e÷»-² u8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿF  # hello ssl alone] ok 36 - probe tls connected correctly ok 37 - data shoveled correctly +selecting... max_fd=8 num_probing=0 accepted fd 6 on slot 0 processing fd0 slot 1 activity on fd7 @@ -1441,8 +1460,8 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:44435 family 2 len 16 -anyprot:connection from localhost:39056 to localhost:36063 forwarded from localhost:49744 to localhost:44435 +connecting to localhost:38035 family 2 len 16 +anyprot:connection from localhost:38198 to localhost:44443 forwarded from localhost:35224 to localhost:38035 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1515,12 +1534,10 @@ probing for ssh: PROBE_MATCH closing fd 7 selecting... max_fd=8 num_probing=0 -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:39064 to localhost:36063 forwarded from localhost:33544 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:38206 to localhost:44443 forwarded from localhost:42816 to localhost:35957 flushing deferred data to fd 4 Received: protocol ssh data [SSH-2.0 tester] -socket closed -connection closed down ok 40 - probe ssh connected correctly ok 41 - data shoveled correctly accepted fd 5 on slot 0 @@ -1542,6 +1559,8 @@ probing for tls: PROBE_AGAIN probing for tls: PROBE_AGAIN selecting... max_fd=8 num_probing=1 +socket closed +connection closed down processing fd0 slot 0 hexdump of incoming packet: 0x000000: 04 . @@ -1621,8 +1640,8 @@ **** writing deferred on fd -1 probing for ssh: PROBE_NEXT probing for socks5: PROBE_MATCH -connecting to localhost:35713 family 2 len 16 -socks5:connection from localhost:39074 to localhost:36063 forwarded from localhost:42620 to localhost:35713 +connecting to localhost:42061 family 2 len 16 +socks5:connection from localhost:38216 to localhost:44443 forwarded from localhost:55470 to localhost:42061 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1645,8 +1664,8 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:37251 family 2 len 16 -http:connection from localhost:39078 to localhost:36063 forwarded from localhost:57914 to localhost:37251 +connecting to localhost:45149 family 2 len 16 +http:connection from localhost:38230 to localhost:44443 forwarded from localhost:34766 to localhost:45149 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 @@ -1669,8 +1688,8 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:35383 family 2 len 16 -tinc:connection from localhost:39088 to localhost:36063 forwarded from localhost:43222 to localhost:35383 +connecting to localhost:43721 family 2 len 16 +tinc:connection from localhost:38240 to localhost:44443 forwarded from localhost:35946 to localhost:43721 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -1694,8 +1713,8 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46275 family 2 len 16 -openvpn:connection from localhost:39090 to localhost:36063 forwarded from localhost:49898 to localhost:46275 +connecting to localhost:35707 family 2 len 16 +openvpn:connection from localhost:38248 to localhost:44443 forwarded from localhost:35556 to localhost:35707 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 @@ -1704,11 +1723,12 @@ Received: protocol openvpn data [] ok 48 - probe openvpn connected correctly ok 49 - data shoveled correctly -accepted fd 6 on slot 0 processing fd0 slot 1 activity on fd7 closing fd 7 closing fd 5 +selecting... max_fd=8 num_probing=0 +accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 hexdump of incoming packet: @@ -1723,21 +1743,21 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:40797 family 2 len 16 -xmpp:connection from localhost:39102 to localhost:36063 forwarded from localhost:38218 to localhost:40797 -flushing deferred data to fd 5 +connecting to localhost:33235 family 2 len 16 +xmpp:connection from localhost:38254 to localhost:44443 forwarded from localhost:60618 to localhost:33235 +flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 -activity on fd5 +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 ok 51 - data shoveled correctly accepted fd 7 on slot 1 processing fd0 slot 0 -activity on fd6 -closing fd 6 +activity on fd5 closing fd 5 +closing fd 6 selecting... max_fd=8 num_probing=1 processing fd0 slot 1 hexdump of incoming packet: @@ -1751,8 +1771,8 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39417 family 2 len 16 -adb:connection from localhost:39118 to localhost:36063 forwarded from localhost:54722 to localhost:39417 +connecting to localhost:33371 family 2 len 16 +adb:connection from localhost:38266 to localhost:44443 forwarded from localhost:34102 to localhost:33371 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 @@ -1761,13 +1781,14 @@ Received: protocol adb data [CNXN....................host:...] ok 52 - probe adb connected correctly ok 53 - data shoveled correctly -sni: sni1 -alpn: alpn1 -accepted fd 6 on slot 0 processing fd0 slot 1 activity on fd7 closing fd 7 +sni: sni1 +alpn: alpn1 closing fd 5 +selecting... max_fd=8 num_probing=0 +accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 hexdump of incoming packet: @@ -1796,12 +1817,12 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:39132 to localhost:36063 forwarded from localhost:58536 to localhost:42281 -flushing deferred data to fd 5 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:38268 to localhost:44443 forwarded from localhost:34800 to localhost:46781 +flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 -activity on fd5 +activity on fd6 selecting... max_fd=8 num_probing=0 Received: protocol tls:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  @@ -1813,9 +1834,9 @@ alpn: alpn2 accepted fd 7 on slot 1 processing fd0 slot 0 -activity on fd6 -closing fd 6 +activity on fd5 closing fd 5 +closing fd 6 selecting... max_fd=8 num_probing=1 processing fd0 slot 1 hexdump of incoming packet: @@ -1845,8 +1866,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:39134 to localhost:36063 forwarded from localhost:58546 to localhost:42281 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:38278 to localhost:44443 forwarded from localhost:34814 to localhost:46781 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 @@ -1858,13 +1879,14 @@ # alpn2hello sni/alpn] ok 56 - probe tls:sni1;alpn1,alpn2 connected correctly ok 57 - data shoveled correctly -sni: sni2 -alpn: alpn1 -accepted fd 6 on slot 0 processing fd0 slot 1 activity on fd7 closing fd 7 closing fd 5 +selecting... max_fd=8 num_probing=0 +sni: sni2 +alpn: alpn1 +accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 hexdump of incoming packet: @@ -1895,12 +1917,12 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:39138 to localhost:36063 forwarded from localhost:45874 to localhost:40189 -flushing deferred data to fd 5 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38292 to localhost:44443 forwarded from localhost:46878 to localhost:44087 +flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 -activity on fd5 +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  @@ -1912,9 +1934,9 @@ alpn: alpn2 accepted fd 7 on slot 1 processing fd0 slot 0 -activity on fd6 -closing fd 6 +activity on fd5 closing fd 5 +closing fd 6 selecting... max_fd=8 num_probing=1 processing fd0 slot 1 hexdump of incoming packet: @@ -1946,8 +1968,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:39144 to localhost:36063 forwarded from localhost:45882 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38308 to localhost:44443 forwarded from localhost:46894 to localhost:44087 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 @@ -1997,8 +2019,8 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:39150 to localhost:36063 forwarded from localhost:45890 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38322 to localhost:44443 forwarded from localhost:46902 to localhost:44087 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2049,8 +2071,8 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:39162 to localhost:36063 forwarded from localhost:45900 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:38330 to localhost:44443 forwarded from localhost:46904 to localhost:44087 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 1 @@ -2103,8 +2125,8 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:35867 family 2 len 16 -tls:connection from localhost:39172 to localhost:36063 forwarded from localhost:42502 to localhost:35867 +connecting to localhost:38875 family 2 len 16 +tls:connection from localhost:38342 to localhost:44443 forwarded from localhost:55990 to localhost:38875 flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2114,15 +2136,15 @@ À9À À3œ=<5/ÿR  # alpn3hello alpn] +ok 66 - probe tls:;alpn3 connected correctly processing fd0 slot 0 +ok 67 - data shoveled correctly activity on fd6 closing fd 6 closing fd 5 -selecting... max_fd=8 num_probing=0 -ok 66 - probe tls:;alpn3 connected correctly -ok 67 - data shoveled correctly sni: sni3 alpn: +selecting... max_fd=8 num_probing=0 accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 @@ -2156,8 +2178,8 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:36541 family 2 len 16 -tls:connection from localhost:39184 to localhost:36063 forwarded from localhost:59140 to localhost:36541 +connecting to localhost:33925 family 2 len 16 +tls:connection from localhost:38346 to localhost:44443 forwarded from localhost:50118 to localhost:33925 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2168,12 +2190,12 @@ # hello sni] ok 68 - probe tls:sni3; connected correctly +ok 69 - data shoveled correctly processing fd0 slot 0 activity on fd5 closing fd 5 closing fd 6 selecting... max_fd=8 num_probing=0 -ok 69 - data shoveled correctly accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 processing fd0 slot 0 @@ -2203,8 +2225,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:39192 to localhost:36063 forwarded from localhost:56782 to localhost:34151 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:38350 to localhost:44443 forwarded from localhost:58048 to localhost:36833 flushing deferred data to fd 6 selecting... max_fd=8 num_probing=0 processing fd1 slot 0 @@ -2214,16 +2236,15 @@ À9À À3œ=<5/ÿF  # hello ssl alone] +ok 70 - probe tls connected correctly +ok 71 - data shoveled correctly +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 70 - probe tls connected correctly -ok 71 - data shoveled correctly -accepted fd 5 on slot 0 selecting... max_fd=8 num_probing=1 -processing fd0 slot 0 +processing fd0 slot 1 hexdump of incoming packet: 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 @@ -2252,26 +2273,26 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:44435 family 2 len 16 -anyprot:connection from localhost:39208 to localhost:36063 forwarded from localhost:49760 to localhost:44435 -flushing deferred data to fd 6 +connecting to localhost:38035 family 2 len 16 +anyprot:connection from localhost:38356 to localhost:44443 forwarded from localhost:35238 to localhost:38035 +flushing deferred data to fd 5 selecting... max_fd=8 num_probing=0 -processing fd1 slot 0 -activity on fd6 +processing fd1 slot 1 +activity on fd5 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!] -processing fd0 slot 0 -activity on fd5 +processing fd0 slot 1 +activity on fd7 +closing fd 7 closing fd 5 -ok 72 - probe anyprot connected correctly -closing fd 6 selecting... max_fd=8 num_probing=0 +ok 72 - probe anyprot connected correctly ok 73 - data shoveled correctly -killing 32457 +killing 10289 Testing sslh-fork -spawned 8740 -./sslh-fork -v -f -u pbuilder1 -Ftest.cfg +spawned 47414 +./sslh-fork -v -f -u pbuilder2 -Ftest.cfg tls: sni_hostnames[0]: sni1 tls: alpn_protocols[0]: alpn1 tls: alpn_protocols[1]: alpn2 @@ -2283,27 +2304,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:39561. libwrap service: (null) log_level: 1 family 2 2 [] [fork] -socks5 addr: localhost:35713. libwrap service: (null) log_level: 1 family 2 2 [] [] -http addr: localhost:37251. libwrap service: (null) log_level: 1 family 2 2 [] [] -tinc addr: localhost:35383. libwrap service: (null) log_level: 1 family 2 2 [] [] -openvpn addr: localhost:46275. libwrap service: (null) log_level: 1 family 2 2 [] [] -xmpp addr: localhost:40797. libwrap service: (null) log_level: 1 family 2 2 [] [] -adb addr: localhost:39417. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:42281. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:40189. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:35867. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:36541. libwrap service: (null) log_level: 1 family 2 2 [] [] -tls addr: localhost:34151. libwrap service: (null) log_level: 1 family 2 2 [] [] -anyprot addr: localhost:44435. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:35957. libwrap service: (null) log_level: 1 family 2 2 [] [fork] +socks5 addr: localhost:42061. libwrap service: (null) log_level: 1 family 2 2 [] [] +http addr: localhost:45149. libwrap service: (null) log_level: 1 family 2 2 [] [] +tinc addr: localhost:43721. libwrap service: (null) log_level: 1 family 2 2 [] [] +openvpn addr: localhost:35707. libwrap service: (null) log_level: 1 family 2 2 [] [] +xmpp addr: localhost:33235. libwrap service: (null) log_level: 1 family 2 2 [] [] +adb addr: localhost:33371. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:46781. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:44087. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:38875. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:33925. libwrap service: (null) log_level: 1 family 2 2 [] [] +tls addr: localhost:36833. libwrap service: (null) log_level: 1 family 2 2 [] [] +anyprot addr: localhost:38035. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:36063 [keepalive] + localhost:44443 [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 @@ -2328,15 +2349,15 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT timed out, connect to ssh -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:47302 to localhost:36063 forwarded from localhost:43936 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:44802 to localhost:44443 forwarded from localhost:36662 to localhost:35957 flushing deferred data to fd 3 ok 74 - Shy SSH connection ***Test: One SSL half-started then one SSH -accepted fd 5 -accepted fd 5 server socket closed +accepted fd 5 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 0x000010: 7a c3 89 45 51 12 3c 28 24 1b 6a 78 bf be 95 d8 z..EQ.<($.jx.... @@ -2363,8 +2384,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:45638 to localhost:36063 forwarded from localhost:33002 to localhost:34151 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:60400 to localhost:44443 forwarded from localhost:54976 to localhost:36833 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. @@ -2387,18 +2408,18 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT timed out, connect to ssh -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:45640 to localhost:36063 forwarded from localhost:60184 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:60404 to localhost:44443 forwarded from localhost:37700 to localhost:35957 flushing deferred data to fd 3 ok 75 - SSH during SSL being established ok 76 - SSL connection interrupted by SSH +***Test: One SSH half-started then one SSL server socket closed connection closed down -***Test: One SSH half-started then one SSL -accepted fd 5 server socket closed connection closed down accepted fd 5 +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 0x000010: 7a c3 89 45 51 12 3c 28 24 1b 6a 78 bf be 95 d8 z..EQ.<($.jx.... @@ -2425,8 +2446,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:54262 to localhost:36063 forwarded from localhost:44734 to localhost:34151 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:42524 to localhost:44443 forwarded from localhost:41498 to localhost:36833 flushing deferred data to fd 3 ok 77 - SSL during SSH being established hexdump of incoming packet: @@ -2450,41 +2471,41 @@ Request did not begin with TLS handshake. probing for tls: PROBE_NEXT timed out, connect to ssh -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:54248 to localhost:36063 forwarded from localhost:47088 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:42508 to localhost:44443 forwarded from localhost:46986 to localhost:35957 flushing deferred data to fd 3 ok 78 - SSH connection interrupted by SSL server socket closed -server socket closed -connection closed down connection closed down accepted fd 5 +server socket closed +connection closed down 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:39561 family 2 len 16 -ssh:connection from localhost:55176 to localhost:36063 forwarded from localhost:47104 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:50678 to localhost:44443 forwarded from localhost:47002 to localhost:35957 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 accepted fd 5 +server socket closed +connection closed down 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:35713 family 2 len 16 -socks5:connection from localhost:55180 to localhost:36063 forwarded from localhost:53790 to localhost:35713 +connecting to localhost:42061 family 2 len 16 +socks5:connection from localhost:50692 to localhost:44443 forwarded from localhost:40186 to localhost:42061 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 @@ -2493,14 +2514,14 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:37251 family 2 len 16 -http:connection from localhost:55190 to localhost:36063 forwarded from localhost:38378 to localhost:37251 +connecting to localhost:45149 family 2 len 16 +http:connection from localhost:50696 to localhost:44443 forwarded from localhost:42338 to localhost:45149 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 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 30 20 68 65 6c 6c 6f 0 hello @@ -2509,8 +2530,8 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:35383 family 2 len 16 -tinc:connection from localhost:55200 to localhost:36063 forwarded from localhost:42036 to localhost:35383 +connecting to localhost:43721 family 2 len 16 +tinc:connection from localhost:50698 to localhost:44443 forwarded from localhost:51326 to localhost:43721 flushing deferred data to fd 3 Received: protocol tinc data [0 hello] server socket closed @@ -2526,13 +2547,13 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46275 family 2 len 16 -openvpn:connection from localhost:55214 to localhost:36063 forwarded from localhost:54922 to localhost:46275 +connecting to localhost:35707 family 2 len 16 +openvpn:connection from localhost:50712 to localhost:44443 forwarded from localhost:58864 to localhost:35707 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: @@ -2547,15 +2568,15 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:40797 family 2 len 16 -xmpp:connection from localhost:55230 to localhost:36063 forwarded from localhost:52802 to localhost:40797 +connecting to localhost:33235 family 2 len 16 +xmpp:connection from localhost:50722 to localhost:44443 forwarded from localhost:48436 to localhost:33235 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 accepted fd 5 +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 43 4e 58 4e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e 2e CNXN............ 0x000010: 2e 2e 2e 2e 2e 2e 2e 2e 68 6f 73 74 3a 2e 2e 2e ........host:... @@ -2567,8 +2588,8 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39417 family 2 len 16 -adb:connection from localhost:55232 to localhost:36063 forwarded from localhost:38960 to localhost:39417 +connecting to localhost:33371 family 2 len 16 +adb:connection from localhost:50728 to localhost:44443 forwarded from localhost:36076 to localhost:33371 flushing deferred data to fd 3 Received: protocol adb data [CNXN....................host:...] server socket closed @@ -2604,20 +2625,20 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:55242 to localhost:36063 forwarded from localhost:54048 to localhost:42281 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:50744 to localhost:44443 forwarded from localhost:50288 to localhost:46781 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 93 - probe tls:sni1;alpn1,alpn2 connected correctly ok 94 - data shoveled correctly sni: sni1 alpn: alpn2 +server socket closed accepted fd 5 +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. @@ -2645,20 +2666,20 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:55244 to localhost:36063 forwarded from localhost:54058 to localhost:42281 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:50758 to localhost:44443 forwarded from localhost:50292 to localhost:46781 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 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. @@ -2687,19 +2708,19 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55258 to localhost:36063 forwarded from localhost:48488 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50764 to localhost:44443 forwarded from localhost:42900 to localhost:44087 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 ...............@ @@ -2730,15 +2751,13 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55270 to localhost:36063 forwarded from localhost:48496 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50768 to localhost:44443 forwarded from localhost:42904 to localhost:44087 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] -server socket closed -connection closed down ok 99 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 100 - data shoveled correctly sni: sni3 @@ -2773,8 +2792,10 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55280 to localhost:36063 forwarded from localhost:48506 to localhost:40189 +server socket closed +connection closed down +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50778 to localhost:44443 forwarded from localhost:42912 to localhost:44087 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni3  @@ -2817,16 +2838,16 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55294 to localhost:36063 forwarded from localhost:48514 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50788 to localhost:44443 forwarded from localhost:42928 to localhost:44087 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 @@ -2863,19 +2884,19 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:35867 family 2 len 16 -tls:connection from localhost:55310 to localhost:36063 forwarded from localhost:39038 to localhost:35867 +connecting to localhost:38875 family 2 len 16 +tls:connection from localhost:50802 to localhost:44443 forwarded from localhost:47018 to localhost:38875 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] -server socket closed -connection closed down ok 105 - probe tls:;alpn3 connected correctly ok 106 - data shoveled correctly sni: sni3 alpn: +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 b8 01 00 00 b4 03 03 97 e4 e9 ad 86 ................ @@ -2907,8 +2928,8 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:36541 family 2 len 16 -tls:connection from localhost:55318 to localhost:36063 forwarded from localhost:53872 to localhost:36541 +connecting to localhost:33925 family 2 len 16 +tls:connection from localhost:50808 to localhost:44443 forwarded from localhost:47332 to localhost:33925 flushing deferred data to fd 3 Received: protocol tls:sni3; data [¸´—äé­†á!ýÄ['­KUÂPä†/7%ÞèœYü©728À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿS sni3  @@ -2945,8 +2966,8 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:55328 to localhost:36063 forwarded from localhost:38964 to localhost:34151 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:50816 to localhost:44443 forwarded from localhost:46920 to localhost:36833 flushing deferred data to fd 3 Received: protocol tls data [«§‰"3•CzÉEQ<($jx¿¾•ØX×e÷»-² u8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿF  @@ -2985,8 +3006,8 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:44435 family 2 len 16 -anyprot:connection from localhost:55334 to localhost:36063 forwarded from localhost:36082 to localhost:44435 +connecting to localhost:38035 family 2 len 16 +anyprot:connection from localhost:50824 to localhost:44443 forwarded from localhost:35586 to localhost:38035 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!] server socket closed @@ -3043,12 +3064,10 @@ 0x000000: 2d - **** writing deferred on fd -1 probing for ssh: PROBE_MATCH -connecting to localhost:39561 family 2 len 16 -ssh:connection from localhost:55340 to localhost:36063 forwarded from localhost:47118 to localhost:39561 +connecting to localhost:35957 family 2 len 16 +ssh:connection from localhost:50826 to localhost:44443 forwarded from localhost:47018 to localhost:35957 flushing deferred data to fd 3 Received: protocol ssh data [SSH-2.0 tester] -server socket closed -connection closed down ok 113 - probe ssh connected correctly ok 114 - data shoveled correctly accepted fd 5 @@ -3067,6 +3086,8 @@ probing for tls: PROBE_AGAIN probing for tls: PROBE_AGAIN probing for tls: PROBE_AGAIN +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 04 . **** writing deferred on fd -1 @@ -3137,14 +3158,14 @@ **** writing deferred on fd -1 probing for ssh: PROBE_NEXT probing for socks5: PROBE_MATCH -connecting to localhost:35713 family 2 len 16 -socks5:connection from localhost:55342 to localhost:36063 forwarded from localhost:53806 to localhost:35713 +connecting to localhost:42061 family 2 len 16 +socks5:connection from localhost:50840 to localhost:44443 forwarded from localhost:40188 to localhost:42061 flushing deferred data to fd 3 Received: protocol socks5 data [] -server socket closed -connection closed down ok 115 - probe socks5 connected correctly ok 116 - 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 @@ -3153,15 +3174,15 @@ probing for ssh: PROBE_NEXT probing for socks5: PROBE_NEXT probing for http: PROBE_MATCH -connecting to localhost:37251 family 2 len 16 -http:connection from localhost:55356 to localhost:36063 forwarded from localhost:38386 to localhost:37251 +connecting to localhost:45149 family 2 len 16 +http:connection from localhost:50846 to localhost:44443 forwarded from localhost:42350 to localhost:45149 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 @@ -3169,8 +3190,8 @@ probing for socks5: PROBE_NEXT probing for http: PROBE_NEXT probing for tinc: PROBE_MATCH -connecting to localhost:35383 family 2 len 16 -tinc:connection from localhost:55362 to localhost:36063 forwarded from localhost:42038 to localhost:35383 +connecting to localhost:43721 family 2 len 16 +tinc:connection from localhost:50860 to localhost:44443 forwarded from localhost:51332 to localhost:43721 flushing deferred data to fd 3 Received: protocol tinc data [0 hello] server socket closed @@ -3186,15 +3207,15 @@ probing for http: PROBE_AGAIN probing for tinc: PROBE_NEXT probing for openvpn: PROBE_MATCH -connecting to localhost:46275 family 2 len 16 -openvpn:connection from localhost:55366 to localhost:36063 forwarded from localhost:54934 to localhost:46275 +connecting to localhost:35707 family 2 len 16 +openvpn:connection from localhost:50868 to localhost:44443 forwarded from localhost:58880 to localhost:35707 flushing deferred data to fd 3 Received: protocol openvpn data [] -server socket closed -connection closed down ok 121 - probe openvpn connected correctly ok 122 - data shoveled correctly accepted fd 5 +server socket closed +connection closed down hexdump of incoming packet: 0x000000: 49 20 73 68 6f 75 6c 64 20 67 65 74 20 61 20 72 I should get a r 0x000010: 65 61 6c 20 6a 61 62 62 65 72 20 63 6f 6e 6e 65 eal jabber conne @@ -3207,13 +3228,13 @@ probing for tinc: PROBE_NEXT probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_MATCH -connecting to localhost:40797 family 2 len 16 -xmpp:connection from localhost:55378 to localhost:36063 forwarded from localhost:52804 to localhost:40797 +connecting to localhost:33235 family 2 len 16 +xmpp:connection from localhost:50874 to localhost:44443 forwarded from localhost:48440 to localhost:33235 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: @@ -3227,8 +3248,8 @@ probing for openvpn: PROBE_NEXT probing for xmpp: PROBE_AGAIN probing for adb: PROBE_MATCH -connecting to localhost:39417 family 2 len 16 -adb:connection from localhost:55390 to localhost:36063 forwarded from localhost:38974 to localhost:39417 +connecting to localhost:33371 family 2 len 16 +adb:connection from localhost:50888 to localhost:44443 forwarded from localhost:36086 to localhost:33371 flushing deferred data to fd 3 Received: protocol adb data [CNXN....................host:...] server socket closed @@ -3264,8 +3285,8 @@ matching [sni1] with [sni1] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:55396 to localhost:36063 forwarded from localhost:54066 to localhost:42281 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:50898 to localhost:44443 forwarded from localhost:50294 to localhost:46781 flushing deferred data to fd 3 Received: protocol tls:sni1;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni1  @@ -3305,16 +3326,16 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:42281 family 2 len 16 -tls:connection from localhost:55412 to localhost:36063 forwarded from localhost:54082 to localhost:42281 +connecting to localhost:46781 family 2 len 16 +tls:connection from localhost:50906 to localhost:44443 forwarded from localhost:50296 to localhost:46781 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 +connection closed down ok 130 - data shoveled correctly sni: sni2 alpn: alpn1 @@ -3347,20 +3368,20 @@ matching [sni2] with [sni2] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55424 to localhost:36063 forwarded from localhost:48518 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50922 to localhost:44443 forwarded from localhost:42932 to localhost:44087 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 accepted fd 5 +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. @@ -3390,20 +3411,20 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55438 to localhost:36063 forwarded from localhost:48522 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50924 to localhost:44443 forwarded from localhost:42944 to localhost:44087 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] -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 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. @@ -3433,8 +3454,8 @@ matching [sni3] with [sni3] matching [alpn1] with [alpn1] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55450 to localhost:36063 forwarded from localhost:48526 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50928 to localhost:44443 forwarded from localhost:42946 to localhost:44087 flushing deferred data to fd 3 Received: protocol tls:sni2,sni3;alpn1,alpn2 data [ÄÀ@Ì”­ ]Òe#pÞÑ<]Ë' |,‰8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿ_ sni3  @@ -3477,19 +3498,19 @@ matching [alpn2] with [alpn1] matching [alpn2] with [alpn2] probing for tls: PROBE_MATCH -connecting to localhost:40189 family 2 len 16 -tls:connection from localhost:55464 to localhost:36063 forwarded from localhost:48528 to localhost:40189 +connecting to localhost:44087 family 2 len 16 +tls:connection from localhost:50940 to localhost:44443 forwarded from localhost:42956 to localhost:44087 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] -server socket closed -connection closed down ok 137 - probe tls:sni2,sni3;alpn1,alpn2 connected correctly ok 138 - data shoveled correctly sni: alpn: alpn3 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 16 03 01 00 b7 01 00 00 b3 03 03 e2 90 a2 29 03 ..............). @@ -3523,8 +3544,8 @@ probing for tls: PROBE_NEXT matching [alpn3] with [alpn3] probing for tls: PROBE_MATCH -connecting to localhost:35867 family 2 len 16 -tls:connection from localhost:55480 to localhost:36063 forwarded from localhost:39042 to localhost:35867 +connecting to localhost:38875 family 2 len 16 +tls:connection from localhost:50946 to localhost:44443 forwarded from localhost:47026 to localhost:38875 flushing deferred data to fd 3 Received: protocol tls:;alpn3 data [·³â¢)1­˜DQT[ÙQfµ?è‹ Éä+—$ï­VÉ8À,À0ŸÌ©Ì¨ÌªÀ+À/žÀ$À(kÀ#À'gÀ À9À À3œ=<5/ÿR  @@ -3567,18 +3588,18 @@ probing for tls: PROBE_NEXT matching [sni3] with [sni3] probing for tls: PROBE_MATCH -connecting to localhost:36541 family 2 len 16 -tls:connection from localhost:55494 to localhost:36063 forwarded from localhost:53874 to localhost:36541 +connecting to localhost:33925 family 2 len 16 +tls:connection from localhost:50954 to localhost:44443 forwarded from localhost:47334 to localhost:33925 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 accepted fd 5 +server socket closed +connection closed down 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.... @@ -3605,17 +3626,17 @@ probing for tls: PROBE_NEXT probing for tls: PROBE_NEXT probing for tls: PROBE_MATCH -connecting to localhost:34151 family 2 len 16 -tls:connection from localhost:55500 to localhost:36063 forwarded from localhost:38978 to localhost:34151 +connecting to localhost:36833 family 2 len 16 +tls:connection from localhost:50960 to localhost:44443 forwarded from localhost:46928 to localhost:36833 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 +server socket closed +connection closed down accepted fd 5 hexdump of incoming packet: 0x000000: 68 65 6c 6c 6f 20 61 6e 79 70 72 6f 74 20 74 68 hello anyprot th @@ -3645,28 +3666,28 @@ probing for tls: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:44435 family 2 len 16 -anyprot:connection from localhost:55502 to localhost:36063 forwarded from localhost:36090 to localhost:44435 +connecting to localhost:38035 family 2 len 16 +anyprot:connection from localhost:50964 to localhost:44443 forwarded from localhost:35602 to localhost:38035 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!] server socket closed connection closed down ok 145 - probe anyprot connected correctly ok 146 - data shoveled correctly -killing 8741 +killing 47415 ***Test: Connecting to non-existant server -spawned 14474 +spawned 76459 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:46037. libwrap service: sshd log_level: 1 family 2 2 [] [fork] -tls addr: localhost:46037. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:37361. libwrap service: sshd log_level: 1 family 2 2 [] [fork] +tls addr: localhost:37361. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:36063 [] + localhost:44443 [] 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 @@ -3678,45 +3699,45 @@ probing for ssh: PROBE_NEXT Request did not begin with TLS handshake. probing for tls: PROBE_NEXT -connecting to localhost:46037 family 2 len 16 +connecting to localhost:37361 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 14656 +spawned 79649 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -36063: names must be fully specified as hostname:port +44443: 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 14657 +spawned 79652 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:39561. libwrap service: sshd log_level: 1 family 2 2 [] [fork] -tls addr: localhost:36541. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:35957. libwrap service: sshd log_level: 1 family 2 2 [] [fork] +tls addr: localhost:33925. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:36063 [] + localhost:44443 [] 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 14658 +spawned 79660 Usage of 'ssl' setting is deprecated and will be removed in v1.21. Please use 'tls' instead -ssh addr: localhost:39561. libwrap service: sshd log_level: 1 family 2 2 [] [fork] -tls addr: localhost:36541. libwrap service: (null) log_level: 1 family 2 2 [] [] +ssh addr: localhost:35957. libwrap service: sshd log_level: 1 family 2 2 [] [fork] +tls addr: localhost:33925. libwrap service: (null) log_level: 1 family 2 2 [] [] listening on: - localhost:36063 [] + localhost:44443 [] timeout: 2 on-timeout: ssh listening to 1 addresses -/dont_exist//tmp/uPb8Zt8Opw/sslh.pid: No such file or directory +/dont_exist//tmp/FOwCceVSj6/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 14659 +spawned 79664 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' @@ -3743,8 +3764,8 @@ Terminated Terminated Terminated -1..150 Terminated +1..150 make[1]: Leaving directory '/build/sslh-1.20' create-stamp debian/debhelper-build-stamp fakeroot debian/rules binary @@ -3756,7 +3777,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 -j8 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 -j15 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 @@ -3804,12 +3825,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/115168/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/115168/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/28795 and its subdirectories -I: Current time: Thu Apr 13 08:57:31 -12 2023 -I: pbuilder-time-stamp: 1681419451 +I: removing directory /srv/workspace/pbuilder/115168 and its subdirectories +I: Current time: Thu May 16 17:22:43 +14 2024 +I: pbuilder-time-stamp: 1715829763