Gentoo Websites Logo
Go to: Gentoo Home Documentation Forums Lists Bugs Planet Store Wiki Get Gentoo!

Bug 381279

Summary: net-im/jabberd2-2.2.4: error: XML parse error (not well-formed (invalid token)) in c2s log for some client software
Product: Gentoo Linux Reporter: Alexander Gorodinski <testor>
Component: [OLD] ServerAssignee: Julian Ospald <hasufell>
Status: RESOLVED TEST-REQUEST    
Severity: major CC: bugs.gentoo.devel, marko.durkovic, net-im, phils
Priority: Normal    
Version: unspecified   
Hardware: x86   
OS: Linux   
Whiteboard:
Package list:
Runtime testing required: ---
Attachments: last 5000 lines of c2s jabberd2 log
Patch to revert buggy commit in gSASL

Description Alexander Gorodinski 2011-08-31 12:33:23 UTC
I'm not sure if this happens after updating. It seems that I forgot to restart jabberd2 after updating, and problem happens after powerfail.

Configuration files is unchanged since the time when it works well.

Some of clients are fails when try to connect to my server, but not all. For example, connecting from Pidgin 2.9.0 (libpurple 2.9.0) leads to "error: XML parse error (not well-formed (invalid token))" in c2s.log. For some another cilents connection is silently ignored, now I'm not sure about client software versions, I'll add this information later.

I use multidomain configuration, maybe this matters.

Also version 2.2.4 is very old, 2.2.14 available on jabberd2 site.

Reproducible: Didn't try

Steps to Reproduce:
1. Install Pidgin 2.9.0 (libpurple 2.9.0)
2. Connect to zazoid.com
3. See log http://zazoid.com/c2s.php
Actual Results:  
Connection failed, error: XML parse error (not well-formed (invalid token)) in c2s.log

Expected Results:  
Jabber client works as usual

last 200 lines of actual log on my server: http://zazoid.com/c2s.php
updates on page refresh
Comment 1 Jeroen Roovers (RETIRED) gentoo-dev 2011-09-03 15:13:44 UTC
1) Please attach your log file(s) so we have an example.
2) Please post your `emerge --info' output too.
Comment 2 Alexander Gorodinski 2011-09-05 11:04:31 UTC
Created attachment 285607 [details]
last 5000 lines of c2s jabberd2 log
Comment 3 Alexander Gorodinski 2011-09-05 11:11:48 UTC
(In reply to comment #1)
> 1) Please attach your log file(s) so we have an example.
> 2) Please post your `emerge --info' output too.

> 1) Please attach your log file(s) so we have an example.

in case of pidgin(libpurple) problem is here:
Mon Sep  5 13:45:30 2011 [notice] [12] [80.94.239.51, port=1492] connect
Mon Sep  5 13:45:31 2011 [notice] [12] [80.94.239.51, port=1492] error: XML parse error (not well-formed (invalid token))
Mon Sep  5 13:45:31 2011 [notice] [12] [80.94.239.51, port=1492] disconnect jid=unbound, packets: 0

in case of psi it stops after:
Mon Sep  5 13:36:00 2011 [notice] [9] [80.94.239.51, port=1309] connect
then waits for some timeout and disconnects.

last 5000 lines of log file in attachment

> 2) Please post your `emerge --info' output too.

# emerge --info
Portage 2.1.10.11 (default/linux/x86/10.0, gcc-4.4.5, glibc-2.12.2-r0, 2.6.28-gentoo-r5 i686)
=================================================================
System uname: Linux-2.6.28-gentoo-r5-i686-Intel-R-_Celeron-R-_CPU_1.70GHz-with-gentoo-2.0.3
Timestamp of tree: Sun, 04 Sep 2011 20:00:01 +0000
app-shells/bash:          4.1_p9
dev-lang/python:          2.6.6-r2, 2.7.1-r1, 3.1.3-r1
dev-util/cmake:           2.8.4-r1
dev-util/pkgconfig:       0.26
sys-apps/baselayout:      2.0.3
sys-apps/openrc:          0.8.3-r1
sys-apps/sandbox:         2.4
sys-devel/autoconf:       2.68
sys-devel/automake:       1.10.3, 1.11.1
sys-devel/binutils:       2.20.1-r1
sys-devel/gcc:            4.3.4, 4.4.5
sys-devel/gcc-config:     1.4.1-r1
sys-devel/libtool:        2.4-r1
sys-devel/make:           3.82
sys-kernel/linux-headers: 2.6.36.1 (virtual/os-headers)
sys-libs/glibc:           2.12.2
Repositories: gentoo funroll-loops
ACCEPT_KEYWORDS="x86"
ACCEPT_LICENSE="* -@EULA"
CBUILD="i686-pc-linux-gnu"
CFLAGS="-O2 -march=pentium4 -pipe"
CHOST="i686-pc-linux-gnu"
CONFIG_PROTECT="/etc /usr/share/gnupg/qualified.txt /usr/share/openvpn/easy-rsa /var/bind"
CONFIG_PROTECT_MASK="/etc/ca-certificates.conf /etc/env.d /etc/fonts/fonts.conf /etc/gconf /etc/gentoo-release /etc/php/apache2-php5.2/ext-active/ /etc/php/apache2-php5.3/ext-active/ /etc/php/cgi-php5.2/ext-active/ /etc/php/cgi-php5.3/ext-active/ /etc/php/cli-php5.2/ext-active/ /etc/php/cli-php5.3/ext-active/ /etc/revdep-rebuild /etc/sandbox.d /etc/terminfo"
CXXFLAGS="-O2 -march=pentium4 -pipe"
DISTDIR="/usr/portage/distfiles"
FEATURES="assume-digests binpkg-logs distlocks ebuild-locks fixlafiles fixpackages news parallel-fetch protect-owned sandbox sfperms strict unknown-features-warn unmerge-logs unmerge-orphans userfetch"
FFLAGS=""
GENTOO_MIRRORS="http://distfiles.gentoo.org"
LC_ALL="en_GB.utf8"
LDFLAGS="-Wl,-O1 -Wl,--as-needed"
LINGUAS="ru"
PKGDIR="/usr/portage/packages"
PORTAGE_CONFIGROOT="/"
PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --compress --force --whole-file --delete --stats --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages"
PORTAGE_TMPDIR="/var/tmp"
PORTDIR="/usr/portage"
PORTDIR_OVERLAY="/var/lib/layman/funroll-loops"
SYNC="rsync://rsync.gentoo.org/gentoo-portage"
USE="7zip acl apache2 bash-completion berkdb bzip2 cli cracklib crypt cxx dri fontconfig fortran gd gdbm gpm iconv jpeg logrotate mmx modules mudflap mysql ncurses nls nptl nptlonly openmp pam pcre perl php png pppd python readline samba session slang ssl svg sysfs tiff tordns truetype unicode utf8 wmf x86 xml xorg zlib" ALSA_CARDS="ali5451 als4000 atiixp atiixp-modem bt87x ca0106 cmipci emu10k1 emu10k1x ens1370 ens1371 es1938 es1968 fm801 hda-intel intel8x0 intel8x0m maestro3 trident usb-audio via82xx via82xx-modem ymfpci" ALSA_PCM_PLUGINS="adpcm alaw asym copy dmix dshare dsnoop empty extplug file hooks iec958 ioplug ladspa lfloat linear meter mmap_emul mulaw multi null plug rate route share shm softvol" APACHE2_MODULES="status cgi dav proxy proxy_http alias auth_basic authn_file authz_user authz_host autoindex ext_filter filter log_config logio rewrite dir env imagemap mime" APACHE2_MPMS="peruser" CALLIGRA_FEATURES="kexi words flow plan stage tables krita karbon braindump" CAMERAS="ptp2" COLLECTD_PLUGINS="df interface irq load memory rrdtool swap syslog" ELIBC="glibc" GPSD_PROTOCOLS="ashtech aivdm earthmate evermore fv18 garmin garmintxt gpsclock itrax mtk3301 nmea ntrip navcom oceanserver oldstyle oncore rtcm104v2 rtcm104v3 sirf superstar2 timing tsip tripmate tnt ubx" INPUT_DEVICES="keyboard mouse evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" LINGUAS="ru" PHP_TARGETS="php5-3" RUBY_TARGETS="ruby18" USERLAND="GNU" VIDEO_CARDS="fbdev vesa vga" XTABLES_ADDONS="quota2 psd pknock lscan length2 ipv4options ipset ipp2p iface geoip fuzzy condition tee tarpit sysrq steal rawnat logmark ipmark dhcpmac delude chaos account"
Unset:  CPPFLAGS, CTARGET, EMERGE_DEFAULT_OPTS, INSTALL_MASK, LANG, MAKEOPTS, PORTAGE_BUNZIP2_COMMAND, PORTAGE_COMPRESS, PORTAGE_COMPRESS_FLAGS, PORTAGE_RSYNC_EXTRA_OPTS
Comment 4 Alexander Gorodinski 2011-09-05 11:31:41 UTC
also I make some experiments:
1)I update jabberd2 from 2.2.4(from main portage tree) to 2.2.14(from funroll-loops overlay) - same problems still exists

2)I check mysql database by mysqlrepair tool - database looks fine

3)I update pidgin(ligpurple) from 2.9.0 to 2.10.0 on my client - it still works fine for me (in case of pidgin and in case of finch)

4)I install pidgin on windows PC - it reports error "Invalid response from server" on client side and "error: XML parse error (not well-formed (invalid token))" in c2s log on server side. Error exists in Russian and English locales. Russian locale on gentoo pidgin client works fine, so problem is locale independent.

5)I install psi 0.14 on windows - it cannot connect to server too, but behavior is another: server and client side don't reports any errors, it simply stops after "[notice] [9] [80.94.239.51, port=1309] connect
" in server log, and disconnects by some timeout.
Comment 5 Alexander Gorodinski 2011-09-07 17:22:16 UTC
debug output from pidgin 2.10.0 on windiws:

(19:41:27) util: Writing file accounts.xml to directory C:\Documents and Settings\Administrator\Application Data\.purple
(19:41:27) util: Writing file C:\Documents and Settings\Administrator\Application Data\.purple\accounts.xml
(19:41:28) prefs: /pidgin/blist/list_visible changed, scheduling save.
(19:41:29) prefs: /pidgin/blist/list_visible changed, scheduling save.
(19:41:34) util: Writing file prefs.xml to directory C:\Documents and Settings\Administrator\Application Data\.purple
(19:41:34) util: Writing file C:\Documents and Settings\Administrator\Application Data\.purple\prefs.xml
(19:41:35) prefs: /purple/savedstatus/default changed, scheduling save.
(19:41:35) account: Connecting to account testor@zazoid.com/windows.
(19:41:35) connection: Connecting. gc = 029F53F0
(19:41:35) dnssrv: querying SRV record for zazoid.com: _xmpp-client._tcp.zazoid.com
(19:41:35) dnssrv: Couldn't look up SRV record. DNS-имя не существует. (9003).
(19:41:35) dnsquery: Performing DNS lookup for zazoid.com
(19:41:35) dnsquery: IP resolved for zazoid.com
(19:41:35) proxy: Attempting connection to 91.149.158.47
(19:41:35) proxy: Connecting to zazoid.com:5222 with no proxy
(19:41:35) proxy: Connection in progress
(19:41:35) proxy: Connecting to zazoid.com:5222.
(19:41:35) proxy: Connected to zazoid.com:5222.
(19:41:35) jabber: Sending (testor@zazoid.com/windows): <?xml version='1.0' ?>
(19:41:35) jabber: Sending (testor@zazoid.com/windows): <stream:stream to='zazoid.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(19:41:35) jabber: Recv (244): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' from='zazoid.com' version='1.0' id='xbu8yzthrqlffao1ffytcns0ljh0e4kbgfkfeew6' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
(19:41:35) jabber: Recv (442): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><address xmlns='http://affinix.com/jabber/address'>80.94.239.51</address><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth xmlns='http://jabber.org/features/iq-auth'/><register xmlns='http://jabber.org/features/iq-register'/></stream:features>
(19:41:35) jabber: Sending (testor@zazoid.com/windows): <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
(19:41:35) jabber: Recv (50): <proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
(19:41:35) nss: subject=E=testor@zazoid.com,CN=Alexander Gorodinski,O=Zazoid.com,L=Minsk,ST=Some-State,C=BY issuer=E=testor@zazoid.com,CN=Alexander Gorodinski,O=Zazoid.com,L=Minsk,ST=Some-State,C=BY
(19:41:35) certificate/x509/tls_cached: Starting verify for zazoid.com
(19:41:35) certificate/x509/tls_cached: Checking for cached cert...
(19:41:35) certificate/x509/tls_cached: ...Found cached cert
(19:41:35) nss/x509: Loading certificate from C:\Documents and Settings\Administrator\Application Data\.purple\certificates\x509\tls_peers\zazoid.com
(19:41:35) certificate/x509/tls_cached: Peer cert matched cached
(19:41:35) nss/x509: Exporting certificate to C:\Documents and Settings\Administrator\Application Data\.purple\certificates\x509\tls_peers\zazoid.com
(19:41:35) util: Writing file C:\Documents and Settings\Administrator\Application Data\.purple\certificates\x509\tls_peers\zazoid.com
(19:41:35) certificate: Successfully verified certificate for zazoid.com
(19:41:35) jabber: Sending (ssl) (testor@zazoid.com/windows): <stream:stream to='zazoid.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(19:41:36) jabber: Recv (ssl)(244): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' from='zazoid.com' version='1.0' id='4hmksal8qj127u3teltspd46spl73hd8z0srbez1' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
(19:41:36) jabber: Recv (ssl)(422): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><address xmlns='http://affinix.com/jabber/address'>80.94.239.51</address><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>EXTERNAL</mechanism><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth xmlns='http://jabber.org/features/iq-auth'/><register xmlns='http://jabber.org/features/iq-register'/></stream:features>
(19:41:36) sasl: Mechs found: PLAIN DIGEST-MD5
(19:41:36) jabber: Sending (ssl) (testor@zazoid.com/windows): <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='DIGEST-MD5' xmlns:ga='http://www.google.com/talk/protocol/auth' ga:client-uses-full-bind-result='true'/>
(19:41:36) jabber: Recv (ssl)(196): <challenge xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cmVhbG09Inphem9pZC5jb20iLCBub25jZT0ianFobnFtbjF6NDc5cVJOQ2VndEZEQT09IiwgcW9wPSJhdXRoIiwgY2hhcnNldD11dGYtOCwgYWxnb3JpdGhtPW1kNS1zZXNz</challenge>
(19:41:36) sasl: DIGEST-MD5 client step 2
(19:41:36) jabber: Sending (ssl) (testor@zazoid.com/windows): <response xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>dXNlcm5hbWU9InRlc3RvciIscmVhbG09Inphem9pZC5jb20iLG5vbmNlPSJqcWhucW1uMXo0NzlxUk5DZWd0RkRBPT0iLGNub25jZT0iSjBrOFlSaGRmd0ZTV0JZSkkxWnJSeTlqSFZFZEdud2tBbUlUQmdFc1Vpcz0iLG5jPTAwMDAwMDAxLHFvcD1hdXRoLGRpZ2VzdC11cmk9InhtcHAvemF6b2lkLmNvbSIscmVzcG9uc2U9NDhhYTkyZTM5ZDVkMjk2YWNhOTZiZmE0YmFkZDkzN2QsY2hhcnNldD11dGYtOA==</response>
(19:41:36) jabber: Recv (ssl)(51): <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
(19:41:36) sasl: DIGEST-MD5 client step 2
(19:41:36) sasl: no server challenge
(19:41:36) connection: Connection error on 029F53F0 (reason: 3 description: Invalid response from server)
(19:41:36) account: Disconnecting account testor@zazoid.com/windows (00E58CF0)
(19:41:36) connection: Disconnecting connection 029F53F0
(19:41:36) jabber: Sending (ssl) (testor@zazoid.com/windows): </stream:stream>
(19:41:36) connection: Destroying connection 029F53F0
(19:41:40) util: Writing file prefs.xml to directory C:\Documents and Settings\Administrator\Application Data\.purple
(19:41:40) util: Writing file C:\Documents and Settings\Administrator\Application Data\.purple\prefs.xml
(19:41:40) util: Writing file accounts.xml to directory C:\Documents and Settings\Administrator\Application Data\.purple
(19:41:40) util: Writing file C:\Documents and Settings\Administrator\Application Data\.purple\accounts.xml
(19:41:41) prefs: /pidgin/blist/list_visible changed, scheduling save.
(19:41:42) prefs: /pidgin/blist/list_visible changed, scheduling save.
(19:41:47) util: Writing file prefs.xml to directory C:\Documents and Settings\Administrator\Application Data\.purple
(19:41:47) util: Writing file C:\Documents and Settings\Administrator\Application Data\.purple\prefs.xml
Comment 6 Alexander Gorodinski 2011-09-07 17:23:30 UTC
same version of pidgin works fine on linux:

(20:00:52) util: Writing file prefs.xml to directory /home/n0153/.purple
(20:00:52) util: Writing file /home/n0153/.purple/prefs.xml
(20:00:52) util: Writing file blist.xml to directory /home/n0153/.purple
(20:00:52) util: Writing file /home/n0153/.purple/blist.xml
(20:00:52) util: Writing file accounts.xml to directory /home/n0153/.purple
(20:00:52) util: Writing file /home/n0153/.purple/accounts.xml
(20:03:03) prefs: /purple/savedstatus/default changed, scheduling save.
(20:03:03) account: Connecting to account testor@zazoid.com/finch.
(20:03:03) connection: Connecting. gc = 0x91330c8
(20:03:03) dnssrv: querying SRV record for zazoid.com: _xmpp-client._tcp.zazoid.com
(20:03:03) dnssrv: res_query returned an error
(20:03:03) dnsquery: Performing DNS lookup for zazoid.com
(20:03:03) dns: Wait for DNS child 31446 failed: No child processes
(20:03:03) dns: Created new DNS child 31551, there are now 1 children.
(20:03:03) dns: Successfully sent DNS request to child 31551
(20:03:03) dns: Got response for 'zazoid.com'
(20:03:03) dnsquery: IP resolved for zazoid.com
(20:03:03) proxy: Attempting connection to 91.149.158.47
(20:03:03) proxy: Connecting to zazoid.com:5222 with no proxy
(20:03:03) proxy: Connection in progress
(20:03:03) proxy: Connecting to zazoid.com:5222.
(20:03:03) proxy: Connected to zazoid.com:5222.
(20:03:03) jabber: Sending (testor@zazoid.com/finch): <?xml version='1.0' ?>
(20:03:03) jabber: Sending (testor@zazoid.com/finch): <stream:stream to='zazoid.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(20:03:03) jabber: Recv (244): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' from='zazoid.com' version='1.0' id='xufg7ghv81lg91uo7hhwop943d79om4lhj1ozjk8' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
(20:03:03) jabber: Recv (442): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/><address xmlns='http://affinix.com/jabber/address'>80.94.239.51</address><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth xmlns='http://jabber.org/features/iq-auth'/><register xmlns='http://jabber.org/features/iq-register'/></stream:features>
(20:03:03) jabber: Sending (testor@zazoid.com/finch): <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
(20:03:03) jabber: Recv (50): <proceed xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
(20:03:03) gnutls: Starting handshake with zazoid.com
(20:03:03) gnutls: Handshake complete
(20:03:03) gnutls/x509: Key print: 9e:97:af:04:40:6d:9d:0d:71:2a:b0:97:02:6a:3e:53:4b:dd:92:ec
(20:03:03) gnutls: Peer provided 1 certs
(20:03:03) gnutls: Lvl 0 SHA1 fingerprint: 9e:97:af:04:40:6d:9d:0d:71:2a:b0:97:02:6a:3e:53:4b:dd:92:ec
(20:03:03) gnutls: Serial: 00:dc:a6:6f:dc:12:55:f5:ba
(20:03:03) gnutls: Cert DN: C=BY,ST=Some-State,L=Minsk,O=Zazoid.com,CN=Alexander Gorodinski,EMAIL=testor@zazoid.com
(20:03:03) gnutls: Cert Issuer DN: C=BY,ST=Some-State,L=Minsk,O=Zazoid.com,CN=Alexander Gorodinski,EMAIL=testor@zazoid.com
(20:03:03) certificate/x509/tls_cached: Starting verify for zazoid.com
(20:03:03) certificate/x509/tls_cached: Checking for cached cert...
(20:03:03) certificate/x509/tls_cached: ...Found cached cert
(20:03:03) gnutls: Attempting to load X.509 certificate from /home/n0153/.purple/certificates/x509/tls_peers/zazoid.com
(20:03:03) certificate/x509/tls_cached: Peer cert matched cached
(20:03:03) util: Writing file /home/n0153/.purple/certificates/x509/tls_peers/zazoid.com
(20:03:03) certificate: Successfully verified certificate for zazoid.com
(20:03:03) jabber: Sending (ssl) (testor@zazoid.com/finch): <stream:stream to='zazoid.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(20:03:03) jabber: Recv (ssl)(244): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' from='zazoid.com' version='1.0' id='k5ot6iid00apqjtux13ln874s9tssd0cip6p7p38' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
(20:03:03) jabber: Recv (ssl)(422): <stream:features xmlns:stream='http://etherx.jabber.org/streams'><address xmlns='http://affinix.com/jabber/address'>80.94.239.51</address><mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>EXTERNAL</mechanism><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth xmlns='http://jabber.org/features/iq-auth'/><register xmlns='http://jabber.org/features/iq-register'/></stream:features>
(20:03:03) jabber: Sending (ssl) (testor@zazoid.com/finch): <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='DIGEST-MD5'/>
(20:03:04) jabber: Recv (ssl)(196): <challenge xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cmVhbG09Inphem9pZC5jb20iLCBub25jZT0iaytsOW4wbTVOdVJJVGRqT281MzJqdz09IiwgcW9wPSJhdXRoIiwgY2hhcnNldD11dGYtOCwgYWxnb3JpdGhtPW1kNS1zZXNz</challenge>
(20:03:04) jabber: decoded challenge (99): realm="zazoid.com", nonce="k+l9n0m5NuRITdjOo532jw==", qop="auth", charset=utf-8, algorithm=md5-sess
(20:03:04) jabber: decoded response (211): username="testor",realm="zazoid.com",nonce="k+l9n0m5NuRITdjOo532jw==",cnonce="21fc2eb413154149848230ad34",nc=00000001,qop=auth,digest-uri="xmpp/zazoid.com",response=eee62342224f639c9dca3687d0f14a01,charset=utf-8
(20:03:04) jabber: Sending (ssl) (testor@zazoid.com/finch): <response xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>dXNlcm5hbWU9InRlc3RvciIscmVhbG09Inphem9pZC5jb20iLG5vbmNlPSJrK2w5bjBtNU51UklUZGpPbzUzMmp3PT0iLGNub25jZT0iMjFmYzJlYjQxMzE1NDE0OTg0ODIzMGFkMzQiLG5jPTAwMDAwMDAxLHFvcD1hdXRoLGRpZ2VzdC11cmk9InhtcHAvemF6b2lkLmNvbSIscmVzcG9uc2U9ZWVlNjIzNDIyMjRmNjM5YzlkY2EzNjg3ZDBmMTRhMDEsY2hhcnNldD11dGYtOA==</response>
(20:03:04) jabber: Recv (ssl)(51): <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
(20:03:04) jabber: Sending (ssl) (testor@zazoid.com/finch): <stream:stream to='zazoid.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
(20:03:04) jabber: Recv (ssl)(244): <?xml version='1.0'?><stream:stream xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client' from='zazoid.com' version='1.0' id='pdxgwrauseggmnlewe6pr72aw8z4y2cof94c1f7t' xmlns:ack='http://www.xmpp.org/extensions/xep-0198.html#ns'>
Comment 7 Joshua Pettett 2012-02-01 01:58:33 UTC
Created attachment 300583 [details, diff]
Patch to revert buggy commit in gSASL

On my system, this bug seems to have been caused by a bug in gSASL, as described at <http://forums.freebsd.org/showpost.php?p=149160&postcount=3>.  Attached is a patch against libgsasl that reverts the commit mentioned in the post.  I'll leave it up to someone else to determine how to assign this and whether or not it also applies to the gsasl package.
Comment 8 Alexander Gorodinski 2012-02-05 21:33:56 UTC
thank you! it really helps!
Comment 9 damage 2012-07-17 21:32:11 UTC
Just for statistics: Kopete also silently "hangs" on sasl authentication. But if you set the connection server manually Kopete is switching back to old authentication which works.

Is the upstream informed about that? Is that a problem of gSASL or of jabberd2?
Comment 10 Anton Bolshakov 2012-08-28 12:46:25 UTC
The proper solution is to patch jabberd2.

The upstream seems fixed it, however the patch has been lost somehow.
Here is more details:
https://bugs.launchpad.net/jabberd2/+bug/899284
and the proper patch:
https://launchpadlibrarian.net/86792544/0001-Send-any-remaining-SASL-data-with-success-authentica.patch
Comment 11 Anton Bolshakov 2012-08-30 01:47:03 UTC
Ok, I would like to confirm that the bug is fixed in 2.2.15 and above.

It is a configuration issue if you still facing the bug. Bellow are more details: 
https://bugs.launchpad.net/jabberd2/+bug/537606
"require-starttls option may cause XML parse error
This is how it currently works.
If you have any suggestion how to fix it, feel free to reopen the bug."
Comment 12 Phil Stracchino (Unix Ronin) 2012-10-20 06:44:48 UTC
So .........   whare does one GET 2.2.15?
Comment 13 Julian Ospald 2013-11-03 12:42:58 UTC
does this still happen with 2.2.17?
Comment 14 Julian Ospald 2013-11-03 12:54:15 UTC
test again and reopen if it still applies