sipe stopped working on Fedora 20

Help
2014-05-22
2015-01-28
  • Jeff Gustafson

    Jeff Gustafson - 2014-05-22

    Hi all,
    I have been using SIPE for a while now on Fedora (now 20). I have been using the NSS_SSL_CBC_RANDOM_IV=0 variable to get around the 'Read error' problem. But now, SIPE stopped working. I'm guessing something over the past month was updated (openssl?) that caused the breakage. What do I need to do to troubleshoot the problem?

    ...Jeff

     
  • Stefan Becker

    Stefan Becker - 2014-05-22

    Without a --debug log nobody will be able to help you. Please read the FAQ.

    I use SIPE on Fedora 20 for my company account and a Office 365 test account and have not seen any problems with it.

     
    • Jeff Gustafson

      Jeff Gustafson - 2014-05-22

      We're still on Lync 2010. At one point my notebook (also Fedora 20) was not working, but my desktop was. I figure it was because I leave pidgin running for much longer than on my notebook. So if a library changed underneath it, I wouldn't have noticed a problem for weeks. Now both systems do not work.

      I scrubbed username and domain info from the log. The error I see in pidgin is 'Read error' just like the original problem with SSL. BUT I am using the NSS_SSL_CBC_RANDOM_IV=0 fix. It USED to work. Now it doesn't. Here is the debug log:

      $ NSS_SSL_CBC_RANDOM_IV=0 pidgin --debug
      (13:38:00) prefs: Reading /home/user/.purple/prefs.xml
      (13:38:00) prefs: Finished reading /home/user/.purple/prefs.xml
      (13:38:00) prefs: purple_prefs_get_path: Unknown pref /pidgin/browsers/command
      (13:38:00) dbus: okkk
      (13:38:00) plugins: probing /usr/lib64/pidgin/convcolors.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/gestures.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/vvconfig.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/iconaway.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/ticker.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/sendbutton.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/timestamp_format.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/themeedit.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/pidginrc.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/history.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/notify.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/extplacement.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/timestamp.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/markerline.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/gtkbuddynote.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/spellchk.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/xmppconsole.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/xmppdisco.so
      (13:38:00) plugins: probing /usr/lib64/pidgin/musicmessaging.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/dbus-example.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libyahoojp.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/joinpart.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/ssl-nss.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libgg.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libyahoo.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libxmpp.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libaim.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libirc.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libsilcpurple.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libicq.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/ssl.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libmyspace.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/offlinemsg.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/autoaccept.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libsipe.so
      (13:38:00) sipe: bindtextdomain = /usr/share/locale
      (13:38:00) sipe: bind_textdomain_codeset = UTF-8
      (13:38:00) sipe: NSS initialised
      (13:38:00) plugins: probing /usr/lib64/purple-2/libmxit.so
      (13:38:00) prpl-loubserp-mxit: Loading MXit libPurple plugin...
      (13:38:00) plugins: probing /usr/lib64/purple-2/libnovell.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/one_time_password.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libmsn.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libzephyr.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libsimple.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/idle.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/newline.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/buddynote.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/statenotify.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/log_reader.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/psychic.so
      (13:38:00) plugins: probing /usr/lib64/purple-2/libsametime.so
      (13:38:00) plugins: /usr/lib64/purple-2/libsametime.so has a prefs_info, but is a prpl. This is no longer supported.
      (13:38:00) plugins: probing /usr/lib64/purple-2/libbonjour.so
      (13:38:00) util: Reading file xmpp-caps.xml from directory /home/user/.purple
      (13:38:00) util: File /home/user/.purple/xmpp-caps.xml does not exist (this is not necessarily an error)
      (13:38:00) jabber: creating hash tables for data objects
      (13:38:00) prefs: /purple/status/scores/offline changed, scheduling save.
      (13:38:00) prefs: /purple/status/scores/available changed, scheduling save.
      (13:38:00) prefs: /purple/status/scores/invisible changed, scheduling save.
      (13:38:00) prefs: /purple/status/scores/away changed, scheduling save.
      (13:38:00) prefs: /purple/status/scores/extended_away changed, scheduling save.
      (13:38:00) prefs: /purple/status/scores/idle changed, scheduling save.
      (13:38:00) prefs: /purple/status/scores/offline_msg changed, scheduling save.
      (13:38:00) util: Reading file accounts.xml from directory /home/user/.purple
      (13:38:00) util: Reading file status.xml from directory /home/user/.purple
      (13:38:00) certificate: CertificateVerifier x509, singleuse requested but not found.
      (13:38:00) certificate: CertificateVerifier singleuse registered
      (13:38:00) certificate: CertificatePool x509, ca requested but not found.
      (13:38:00) certificate: CertificateScheme x509 requested but not found.
      (13:38:00) certificate/x509/ca: Lazy init failed because an X.509 Scheme is not yet registered. Maybe it will be better later.
      (13:38:00) certificate/x509/ca: Init failed, probably because a dependency is not yet registered. It has been deferred to later.
      (13:38:00) certificate: CertificatePool ca registered
      (13:38:00) certificate: CertificatePool x509, tls_peers requested but not found.
      (13:38:00) certificate: CertificatePool tls_peers registered
      (13:38:00) certificate: CertificateVerifier x509, tls_cached requested but not found.
      (13:38:00) certificate: CertificateVerifier tls_cached registered
      (13:38:00) prefs: /purple/logging/format changed, scheduling save.
      (13:38:00) prefs: /purple/logging/format changed, scheduling save.
      (13:38:00) prefs: /purple/proxy/type changed, scheduling save.
      (13:38:00) prefs: /purple/proxy/host changed, scheduling save.
      (13:38:00) prefs: /purple/proxy/port changed, scheduling save.
      (13:38:00) prefs: /purple/proxy/username changed, scheduling save.
      (13:38:00) prefs: /purple/proxy/password changed, scheduling save.
      (13:38:00) certificate: CertificateScheme x509 requested but not found.
      (13:38:00) certificate: CertificateScheme x509 registered
      (13:38:00) util: Reading file smileys.xml from directory /home/user/.purple
      (13:38:00) util: File /home/user/.purple/smileys.xml does not exist (this is not necessarily an error)
      (13:38:00) stun: using server
      (13:38:00) sound: Initializing sound output drivers.
      (13:38:00) prefs: /pidgin/conversations/placement changed, scheduling save.
      (13:38:01) prefs: purple_prefs_get_bool: Unknown pref /pidgin/docklet/x11/embedded
      (13:38:01) GLib-GObject: gsignal.c:2475: signal 'destroy' is invalid for instance '0x1591160' of type 'GtkStatusIcon'
      (13:38:01) gtkblist: added visibility manager: 1
      (13:38:01) docklet: GTK+ created
      (13:38:01) gtkmedia: Registering media element types
      (13:38:01) util: Reading file blist.xml from directory /home/user/.purple
      (13:38:01) plugins: Loading saved plugin /usr/lib64/pidgin/extplacement.so
      (13:38:01) prefs: /pidgin/conversations/placement changed, scheduling save.
      (13:38:01) plugins: Loading saved plugin /usr/lib64/pidgin/history.so
      (13:38:01) plugins: Loading saved plugin /usr/lib64/pidgin/notify.so
      (13:38:01) plugins: probing /usr/lib/pidgin/nautilus.so
      (13:38:01) plugins: Unable to find saved plugin /usr/lib/pidgin/nautilus.so
      (13:38:01) pounce: Error reading pounces: Failed to open file '/home/user/.purple/pounces.xml': No such file or directory
      (13:38:01) Session Management: ICE initialized.
      (13:38:01) Session Management: Connecting with no previous ID
      (13:38:01) Session Management: Handling new ICE connection...
      (13:38:01) done.
      (13:38:01) Session Management: Connected to manager (gnome-session) with client ID 1083a4de922f1bef0f140079108113323700000019010036
      (13:38:01) Session Management: Using pidgin as command
      (13:38:01) account: Connecting to account myusername@mycompany.com,domain\domainusername.
      (13:38:01) connection: Connecting. gc = 0x1bc9a00
      (13:38:01) Session Management: Received first save_yourself
      (13:38:01) Session Management: Received save_complete
      (13:38:01) docklet: embedded
      (13:38:01) prefs: /pidgin/docklet/gtk/embedded changed, scheduling save.
      (13:38:05) sipe: sipe_purple_login: username 'myusername@mycompany.com,domain\domainusername'
      (13:38:05) sipe: sipe_purple_login: login 'domain\domainusername'
      (13:38:05) sipe: sipe_purple_login: auth domain 'domain' user 'domainusername'
      (13:38:05) sipe: sipe_core_allocate: SIPE version 1.18.1 signin_name 'myusername@mycompany.com'
      (13:38:05) sipe: sipe_core_allocate: user 'jgustafson' domain 'zynx.com'
      (13:38:05) dnssrv: querying SRV record for zynx.com: _sipinternaltls._tcp.zynx.com
      (13:38:05) dnssrv: found 1 SRV entries
      (13:38:05) sipe: sipe_core_dns_resolved - SRV hostname: siplync.domain.com port: 5061
      (13:38:05) sipe: transport_connect - hostname: siplync.domain.com port: 5061
      (13:38:05) sipe: using SSL
      (13:38:05) dnsquery: Performing DNS lookup for siplync.domain.com
      (13:38:05) dns: Created new DNS child 4172, there are now 1 children.
      (13:38:05) dns: Successfully sent DNS request to child 4172
      (13:38:05) dns: Got response for 'siplync.domain.com'
      (13:38:05) dnsquery: IP resolved for siplync.domain.com
      (13:38:05) proxy: Attempting connection to xxx.xxx.xxx.xxx
      (13:38:05) proxy: Connecting to siplync.domain.com:5061 with no proxy
      (13:38:05) proxy: Connection in progress
      (13:38:05) proxy: Connecting to siplync.domain.com:5061.
      (13:38:05) proxy: Connected to siplync.domain.com:5061.
      (13:38:05) util: Writing file prefs.xml to directory /home/user/.purple
      (13:38:05) util: Writing file /home/user/.purple/prefs.xml
      (13:38:06) util: Writing file accounts.xml to directory /home/user/.purple
      (13:38:06) util: Writing file /home/user/.purple/accounts.xml
      (13:38:06) util: Writing file blist.xml to directory /home/user/.purple
      (13:38:06) util: Writing file /home/user/.purple/blist.xml
      (13:38:06) nss: subject=CN=siplync.domain.com,OU=xxxx,O=xxxxx,L=Charlotte,ST=North Carolina,C=US issuer=CN=DigiCert High Assurance CA-3,OU=www.digicert.com,O=DigiCert Inc,C=US
      (13:38:06) nss: subject=CN=DigiCert High Assurance CA-3,OU=www.digicert.com,O=DigiCert Inc,C=US issuer=CN=DigiCert High Assurance EV Root CA,OU=www.digicert.com,O=DigiCert Inc,C=US
      (13:38:06) nss: partial certificate chain
      (13:38:06) certificate/x509/tls_cached: Starting verify for siplync.domain.com
      (13:38:06) certificate/x509/tls_cached: Checking for cached cert...
      (13:38:06) certificate/x509/tls_cached: ...Found cached cert
      (13:38:06) nss/x509: Loading certificate from /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
      (13:38:06) certificate/x509/tls_cached: Peer cert matched cached
      (13:38:06) nss/x509: Exporting certificate to /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
      (13:38:06) util: Writing file /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
      (13:38:06) certificate: Successfully verified certificate for siplync.domain.com
      (13:38:06) sipe: sipe_schedule_allocate timeouts count 1 after addition
      (13:38:06) sipe: scheduling action <+keepalive-timeout> timeout 60 seconds
      (13:38:06) stun: using server
      (13:38:06) stun: using server
      (13:38:06) stun: using server
      (13:38:06) sipe: sipe_schedule_allocate timeouts count 2 after addition
      (13:38:06) sipe: scheduling action <transaction timeout=""><CC0FgA911a1DA1i1BFFmBB06tE6E0b833Ax7AD9x><1 REGISTER> timeout 60 seconds
      (13:38:06) sipe: SIP transactions count:1 after addition
      (13:38:06) sipe:
      MESSAGE START >>>>>>>>>> SIP - 2014-05-22T20:38:06.157121Z
      REGISTER sip:zynx.com SIP/2.0
      Via: SIP/2.0/tls 172.28.24.129:58320;branch=z9hG4bKDFB1F40AEAF68CAB5A65
      From: sip:myusername@mycompany.com;tag=4835011202;epid=563e03dff8aa
      To: sip:myusername@mycompany.com
      Max-Forwards: 70
      CSeq: 1 REGISTER
      User-Agent: Purple/2.10.9 Sipe/1.18.1 (linux-x86_64; )
      Call-ID: CC0FgA911a1DA1i1BFFmBB06tE6E0b833Ax7AD9x
      Contact: <sip:172.28.24.129:58320;transport=tls;ms-opaque=d3470f2e1d>;methods="INVITE, MESSAGE, INFO, SUBSCRIBE, OPTIONS, BYE, CANCEL, NOTIFY, ACK, REFER, BENOTIFY";proxy=replace;+sip.instance="<urn:uuid:a3218743-f79f-535a-b2cd-b68290f9ec45>"
      Supported: gruu-10, adhoclist, msrtc-event-categories, com.microsoft.msrtc.presence
      Event: registration
      Allow-Events: presence
      ms-keep-alive: UAC;hop-hop=yes
      Content-Length: 0

      MESSAGE END >>>>>>>>>> SIP - 2014-05-22T20:38:06.157121Z
      dns[4172]: nobody needs me... =(
      (13:38:37) sipe: transport_input_common: new buffer length 4096
      (13:38:37) sipe: Read error: Connection reset by peer (104)
      (13:38:37) connection: Connection error on 0x1bc9a00 (reason: 0 description: Read error)
      (13:38:38) sipe: Server has disconnected
      (13:38:38) account: Disconnecting account myusername@mycompany.com,domain\domainusername (0x13639d0)
      (13:38:38) connection: Disconnecting connection 0x1bc9a00
      (13:38:38) sipe: SIP transactions count:0 after removal
      (13:38:38) sipe: sipe_schedule_remove: action name=<transaction timeout=""><CC0FgA911a1DA1i1BFFmBB06tE6E0b833Ax7AD9x><1 REGISTER>
      (13:38:38) sipe: sipe_schedule_remove: action name=<+keepalive-timeout>
      (13:38:38) GLib: g_hash_table_destroy: assertion 'hash_table != NULL' failed
      (13:38:38) sipe: sipe_purple_dns_query_cancel_all: entered
      (13:38:38) sipe: sipe_purple_transport_close_all: entered
      (13:38:38) connection: Destroying connection 0x1bc9a00
      (13:38:38) sipe: transport_deferred_destroy: 0x1bd2620
      (13:38:42) util: Writing file accounts.xml to directory /home/user/.purple
      (13:38:42) util: Writing file /home/user/.purple/accounts.xml
      (13:39:17) autorecon: do_signon called
      (13:39:17) autorecon: calling purple_account_connect
      (13:39:17) account: Connecting to account myusername@mycompany.com,domain\domainusername.
      (13:39:17) connection: Connecting. gc = 0x1c70fa0
      (13:39:17) sipe: sipe_purple_login: username 'myusername@mycompany.com,domain\domainusername'
      (13:39:17) sipe: sipe_purple_login: login 'domain\domainusername'
      (13:39:17) sipe: sipe_purple_login: auth domain 'domain' user 'domainusername'
      (13:39:17) sipe: sipe_core_allocate: SIPE version 1.18.1 signin_name 'myusername@mycompany.com'
      (13:39:17) sipe: sipe_core_allocate: user 'jgustafson' domain 'zynx.com'
      (13:39:17) dnssrv: querying SRV record for zynx.com: _sipinternaltls._tcp.zynx.com
      (13:39:17) autorecon: done calling purple_account_connect
      (13:39:17) dnssrv: found 1 SRV entries
      (13:39:17) sipe: sipe_core_dns_resolved - SRV hostname: siplync.domain.com port: 5061
      (13:39:17) sipe: transport_connect - hostname: siplync.domain.com port: 5061
      (13:39:17) sipe: using SSL
      (13:39:17) dnsquery: Performing DNS lookup for siplync.domain.com
      (13:39:17) dns: Wait for DNS child 4172 failed: No child processes
      (13:39:17) dns: Created new DNS child 4237, there are now 1 children.
      (13:39:17) dns: Successfully sent DNS request to child 4237
      (13:39:17) dns: Got response for 'siplync.domain.com'
      (13:39:17) dnsquery: IP resolved for siplync.domain.com
      (13:39:17) proxy: Attempting connection to xxx.xxx.xxx.xxx
      (13:39:17) proxy: Connecting to siplync.domain.com:5061 with no proxy
      (13:39:17) proxy: Connection in progress
      (13:39:17) proxy: Connecting to siplync.domain.com:5061.
      (13:39:17) proxy: Connected to siplync.domain.com:5061.
      (13:39:17) nss: subject=CN=siplync.domain.com,OU=xxxxx,O=xxxxxx,L=Charlotte,ST=North Carolina,C=US issuer=CN=DigiCert High Assurance CA-3,OU=www.digicert.com,O=DigiCert Inc,C=US
      (13:39:17) nss: partial certificate chain
      (13:39:17) certificate/x509/tls_cached: Starting verify for siplync.domain.com
      (13:39:17) certificate/x509/tls_cached: Checking for cached cert...
      (13:39:17) certificate/x509/tls_cached: ...Found cached cert
      (13:39:17) nss/x509: Loading certificate from /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
      (13:39:17) certificate/x509/tls_cached: Peer cert matched cached
      (13:39:17) nss/x509: Exporting certificate to /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
      (13:39:17) util: Writing file /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
      (13:39:17) certificate: Successfully verified certificate for siplync.domain.com
      (13:39:17) sipe: sipe_schedule_allocate timeouts count 1 after addition
      (13:39:17) sipe: scheduling action <+keepalive-timeout> timeout 60 seconds
      (13:39:17) stun: using server
      (13:39:17) stun: using server
      (13:39:17) stun: using server
      (13:39:17) sipe: sipe_schedule_allocate timeouts count 2 after addition
      (13:39:17) sipe: scheduling action <transaction timeout=""><BA45g2CD9a947Bi2E13m8908t6DFFb9718xC382x><1 REGISTER> timeout 60 seconds
      (13:39:17) sipe: SIP transactions count:1 after addition
      (13:39:17) sipe:
      MESSAGE START >>>>>>>>>> SIP - 2014-05-22T20:39:17.507757Z
      REGISTER sip:zynx.com SIP/2.0
      Via: SIP/2.0/tls 172.28.24.129:58322;branch=z9hG4bK526839F59606DFD72A84
      From: sip:myusername@mycompany.com;tag=5876854081;epid=563e03dff8aa
      To: sip:myusername@mycompany.com
      Max-Forwards: 70
      CSeq: 1 REGISTER
      User-Agent: Purple/2.10.9 Sipe/1.18.1 (linux-x86_64; )
      Call-ID: BA45g2CD9a947Bi2E13m8908t6DFFb9718xC382x
      Contact: <sip:172.28.24.129:58322;transport=tls;ms-opaque=d3470f2e1d>;methods="INVITE, MESSAGE, INFO, SUBSCRIBE, OPTIONS, BYE, CANCEL, NOTIFY, ACK, REFER, BENOTIFY";proxy=replace;+sip.instance="<urn:uuid:a3218743-f79f-535a-b2cd-b68290f9ec45>"
      Supported: gruu-10, adhoclist, msrtc-event-categories, com.microsoft.msrtc.presence
      Event: registration
      Allow-Events: presence
      ms-keep-alive: UAC;hop-hop=yes
      Content-Length: 0

      MESSAGE END >>>>>>>>>> SIP - 2014-05-22T20:39:17.507757Z

       
  • Jeff Gustafson

    Jeff Gustafson - 2014-05-22

    $ NSS_SSL_CBC_RANDOM_IV=0 pidgin --debug
    (13:38:00) prefs: Reading /home/user/.purple/prefs.xml
    (13:38:00) prefs: Finished reading /home/user/.purple/prefs.xml
    (13:38:00) prefs: purple_prefs_get_path: Unknown pref /pidgin/browsers/command
    (13:38:00) dbus: okkk
    (13:38:00) plugins: probing /usr/lib64/pidgin/convcolors.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/gestures.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/vvconfig.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/iconaway.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/ticker.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/sendbutton.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/timestamp_format.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/themeedit.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/pidginrc.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/history.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/notify.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/extplacement.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/timestamp.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/markerline.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/gtkbuddynote.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/spellchk.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/xmppconsole.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/xmppdisco.so
    (13:38:00) plugins: probing /usr/lib64/pidgin/musicmessaging.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/dbus-example.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libyahoojp.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/joinpart.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/ssl-nss.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libgg.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libyahoo.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libxmpp.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libaim.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libirc.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libsilcpurple.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libicq.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/ssl.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libmyspace.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/offlinemsg.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/autoaccept.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libsipe.so
    (13:38:00) sipe: bindtextdomain = /usr/share/locale
    (13:38:00) sipe: bind_textdomain_codeset = UTF-8
    (13:38:00) sipe: NSS initialised
    (13:38:00) plugins: probing /usr/lib64/purple-2/libmxit.so
    (13:38:00) prpl-loubserp-mxit: Loading MXit libPurple plugin...
    (13:38:00) plugins: probing /usr/lib64/purple-2/libnovell.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/one_time_password.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libmsn.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libzephyr.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libsimple.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/idle.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/newline.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/buddynote.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/statenotify.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/log_reader.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/psychic.so
    (13:38:00) plugins: probing /usr/lib64/purple-2/libsametime.so
    (13:38:00) plugins: /usr/lib64/purple-2/libsametime.so has a prefs_info, but is a prpl. This is no longer supported.
    (13:38:00) plugins: probing /usr/lib64/purple-2/libbonjour.so
    (13:38:00) util: Reading file xmpp-caps.xml from directory /home/user/.purple
    (13:38:00) util: File /home/user/.purple/xmpp-caps.xml does not exist (this is not necessarily an error)
    (13:38:00) jabber: creating hash tables for data objects
    (13:38:00) prefs: /purple/status/scores/offline changed, scheduling save.
    (13:38:00) prefs: /purple/status/scores/available changed, scheduling save.
    (13:38:00) prefs: /purple/status/scores/invisible changed, scheduling save.
    (13:38:00) prefs: /purple/status/scores/away changed, scheduling save.
    (13:38:00) prefs: /purple/status/scores/extended_away changed, scheduling save.
    (13:38:00) prefs: /purple/status/scores/idle changed, scheduling save.
    (13:38:00) prefs: /purple/status/scores/offline_msg changed, scheduling save.
    (13:38:00) util: Reading file accounts.xml from directory /home/user/.purple
    (13:38:00) util: Reading file status.xml from directory /home/user/.purple
    (13:38:00) certificate: CertificateVerifier x509, singleuse requested but not found.
    (13:38:00) certificate: CertificateVerifier singleuse registered
    (13:38:00) certificate: CertificatePool x509, ca requested but not found.
    (13:38:00) certificate: CertificateScheme x509 requested but not found.
    (13:38:00) certificate/x509/ca: Lazy init failed because an X.509 Scheme is not yet registered. Maybe it will be better later.
    (13:38:00) certificate/x509/ca: Init failed, probably because a dependency is not yet registered. It has been deferred to later.
    (13:38:00) certificate: CertificatePool ca registered
    (13:38:00) certificate: CertificatePool x509, tls_peers requested but not found.
    (13:38:00) certificate: CertificatePool tls_peers registered
    (13:38:00) certificate: CertificateVerifier x509, tls_cached requested but not found.
    (13:38:00) certificate: CertificateVerifier tls_cached registered
    (13:38:00) prefs: /purple/logging/format changed, scheduling save.
    (13:38:00) prefs: /purple/logging/format changed, scheduling save.
    (13:38:00) prefs: /purple/proxy/type changed, scheduling save.
    (13:38:00) prefs: /purple/proxy/host changed, scheduling save.
    (13:38:00) prefs: /purple/proxy/port changed, scheduling save.
    (13:38:00) prefs: /purple/proxy/username changed, scheduling save.
    (13:38:00) prefs: /purple/proxy/password changed, scheduling save.
    (13:38:00) certificate: CertificateScheme x509 requested but not found.
    (13:38:00) certificate: CertificateScheme x509 registered
    (13:38:00) util: Reading file smileys.xml from directory /home/user/.purple
    (13:38:00) util: File /home/user/.purple/smileys.xml does not exist (this is not necessarily an error)
    (13:38:00) stun: using server
    (13:38:00) sound: Initializing sound output drivers.
    (13:38:00) prefs: /pidgin/conversations/placement changed, scheduling save.
    (13:38:01) prefs: purple_prefs_get_bool: Unknown pref /pidgin/docklet/x11/embedded
    (13:38:01) GLib-GObject: gsignal.c:2475: signal 'destroy' is invalid for instance '0x1591160' of type 'GtkStatusIcon'
    (13:38:01) gtkblist: added visibility manager: 1
    (13:38:01) docklet: GTK+ created
    (13:38:01) gtkmedia: Registering media element types
    (13:38:01) util: Reading file blist.xml from directory /home/user/.purple
    (13:38:01) plugins: Loading saved plugin /usr/lib64/pidgin/extplacement.so
    (13:38:01) prefs: /pidgin/conversations/placement changed, scheduling save.
    (13:38:01) plugins: Loading saved plugin /usr/lib64/pidgin/history.so
    (13:38:01) plugins: Loading saved plugin /usr/lib64/pidgin/notify.so
    (13:38:01) plugins: probing /usr/lib/pidgin/nautilus.so
    (13:38:01) plugins: Unable to find saved plugin /usr/lib/pidgin/nautilus.so
    (13:38:01) pounce: Error reading pounces: Failed to open file '/home/user/.purple/pounces.xml': No such file or directory
    (13:38:01) Session Management: ICE initialized.
    (13:38:01) Session Management: Connecting with no previous ID
    (13:38:01) Session Management: Handling new ICE connection...
    (13:38:01) done.
    (13:38:01) Session Management: Connected to manager (gnome-session) with client ID 1083a4de922f1bef0f140079108113323700000019010036
    (13:38:01) Session Management: Using pidgin as command
    (13:38:01) account: Connecting to account myusername@company.com,domain\domainusername.
    (13:38:01) connection: Connecting. gc = 0x1bc9a00
    (13:38:01) Session Management: Received first save_yourself
    (13:38:01) Session Management: Received save_complete
    (13:38:01) docklet: embedded
    (13:38:01) prefs: /pidgin/docklet/gtk/embedded changed, scheduling save.
    (13:38:05) sipe: sipe_purple_login: username 'myusername@company.com,domain\domainusername'
    (13:38:05) sipe: sipe_purple_login: login 'domain\domainusername'
    (13:38:05) sipe: sipe_purple_login: auth domain 'domain' user 'domainusername'
    (13:38:05) sipe: sipe_core_allocate: SIPE version 1.18.1 signin_name 'myusername@company.com'
    (13:38:05) sipe: sipe_core_allocate: user 'myusername' domain 'company.com'
    (13:38:05) dnssrv: querying SRV record for company.com: _sipinternaltls._tcp.company.com
    (13:38:05) dnssrv: found 1 SRV entries
    (13:38:05) sipe: sipe_core_dns_resolved - SRV hostname: siplync.domain.com port: 5061
    (13:38:05) sipe: transport_connect - hostname: siplync.domain.com port: 5061
    (13:38:05) sipe: using SSL
    (13:38:05) dnsquery: Performing DNS lookup for siplync.domain.com
    (13:38:05) dns: Created new DNS child 4172, there are now 1 children.
    (13:38:05) dns: Successfully sent DNS request to child 4172
    (13:38:05) dns: Got response for 'siplync.domain.com'
    (13:38:05) dnsquery: IP resolved for siplync.domain.com
    (13:38:05) proxy: Attempting connection to xxx.xxx.xxx.xxx
    (13:38:05) proxy: Connecting to siplync.domain.com:5061 with no proxy
    (13:38:05) proxy: Connection in progress
    (13:38:05) proxy: Connecting to siplync.domain.com:5061.
    (13:38:05) proxy: Connected to siplync.domain.com:5061.
    (13:38:05) util: Writing file prefs.xml to directory /home/user/.purple
    (13:38:05) util: Writing file /home/user/.purple/prefs.xml
    (13:38:06) util: Writing file accounts.xml to directory /home/user/.purple
    (13:38:06) util: Writing file /home/user/.purple/accounts.xml
    (13:38:06) util: Writing file blist.xml to directory /home/user/.purple
    (13:38:06) util: Writing file /home/user/.purple/blist.xml
    (13:38:06) nss: subject=CN=siplync.domain.com,OU=Company Service Center,O=The Company Corporation,L=Charlotte,ST=North Carolina,C=US issuer=CN=DigiCert High Assurance CA-3,OU=www.digicert.com,O=DigiCert Inc,C=US
    (13:38:06) nss: subject=CN=DigiCert High Assurance CA-3,OU=www.digicert.com,O=DigiCert Inc,C=US issuer=CN=DigiCert High Assurance EV Root CA,OU=www.digicert.com,O=DigiCert Inc,C=US
    (13:38:06) nss: partial certificate chain
    (13:38:06) certificate/x509/tls_cached: Starting verify for siplync.domain.com
    (13:38:06) certificate/x509/tls_cached: Checking for cached cert...
    (13:38:06) certificate/x509/tls_cached: ...Found cached cert
    (13:38:06) nss/x509: Loading certificate from /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
    (13:38:06) certificate/x509/tls_cached: Peer cert matched cached
    (13:38:06) nss/x509: Exporting certificate to /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
    (13:38:06) util: Writing file /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
    (13:38:06) certificate: Successfully verified certificate for siplync.domain.com
    (13:38:06) sipe: sipe_schedule_allocate timeouts count 1 after addition
    (13:38:06) sipe: scheduling action <+keepalive-timeout> timeout 60 seconds
    (13:38:06) stun: using server
    (13:38:06) stun: using server
    (13:38:06) stun: using server
    (13:38:06) sipe: sipe_schedule_allocate timeouts count 2 after addition
    (13:38:06) sipe: scheduling action <transaction timeout=""><CC0FgA911a1DA1i1BFFmBB06tE6E0b833Ax7AD9x><1 REGISTER> timeout 60 seconds
    (13:38:06) sipe: SIP transactions count:1 after addition
    (13:38:06) sipe:
    MESSAGE START >>>>>>>>>> SIP - 2014-05-22T20:38:06.157121Z
    REGISTER sip:company.com SIP/2.0
    Via: SIP/2.0/tls 172.28.24.129:58320;branch=z9hG4bKDFB1F40AEAF68CAB5A65
    From: sip:myusername@company.com;tag=4835011202;epid=563e03dff8aa
    To: sip:myusername@company.com
    Max-Forwards: 70
    CSeq: 1 REGISTER
    User-Agent: Purple/2.10.9 Sipe/1.18.1 (linux-x86_64; )
    Call-ID: CC0FgA911a1DA1i1BFFmBB06tE6E0b833Ax7AD9x
    Contact: <sip:172.28.24.129:58320;transport=tls;ms-opaque=d3470f2e1d>;methods="INVITE, MESSAGE, INFO, SUBSCRIBE, OPTIONS, BYE, CANCEL, NOTIFY, ACK, REFER, BENOTIFY";proxy=replace;+sip.instance="<urn:uuid:a3218743-f79f-535a-b2cd-b68290f9ec45>"
    Supported: gruu-10, adhoclist, msrtc-event-categories, com.microsoft.msrtc.presence
    Event: registration
    Allow-Events: presence
    ms-keep-alive: UAC;hop-hop=yes
    Content-Length: 0

    MESSAGE END >>>>>>>>>> SIP - 2014-05-22T20:38:06.157121Z
    dns[4172]: nobody needs me... =(
    (13:38:37) sipe: transport_input_common: new buffer length 4096
    (13:38:37) sipe: Read error: Connection reset by peer (104)
    (13:38:37) connection: Connection error on 0x1bc9a00 (reason: 0 description: Read error)
    (13:38:38) sipe: Server has disconnected
    (13:38:38) account: Disconnecting account myusername@company.com,domain\domainusername (0x13639d0)
    (13:38:38) connection: Disconnecting connection 0x1bc9a00
    (13:38:38) sipe: SIP transactions count:0 after removal
    (13:38:38) sipe: sipe_schedule_remove: action name=<transaction timeout=""><CC0FgA911a1DA1i1BFFmBB06tE6E0b833Ax7AD9x><1 REGISTER>
    (13:38:38) sipe: sipe_schedule_remove: action name=<+keepalive-timeout>
    (13:38:38) GLib: g_hash_table_destroy: assertion 'hash_table != NULL' failed
    (13:38:38) sipe: sipe_purple_dns_query_cancel_all: entered
    (13:38:38) sipe: sipe_purple_transport_close_all: entered
    (13:38:38) connection: Destroying connection 0x1bc9a00
    (13:38:38) sipe: transport_deferred_destroy: 0x1bd2620
    (13:38:42) util: Writing file accounts.xml to directory /home/user/.purple
    (13:38:42) util: Writing file /home/user/.purple/accounts.xml
    (13:39:17) autorecon: do_signon called
    (13:39:17) autorecon: calling purple_account_connect
    (13:39:17) account: Connecting to account myusername@company.com,domain\domainusername.
    (13:39:17) connection: Connecting. gc = 0x1c70fa0
    (13:39:17) sipe: sipe_purple_login: username 'myusername@company.com,domain\domainusername'
    (13:39:17) sipe: sipe_purple_login: login 'domain\domainusername'
    (13:39:17) sipe: sipe_purple_login: auth domain 'domain' user 'domainusername'
    (13:39:17) sipe: sipe_core_allocate: SIPE version 1.18.1 signin_name 'myusername@company.com'
    (13:39:17) sipe: sipe_core_allocate: user 'myusername' domain 'company.com'
    (13:39:17) dnssrv: querying SRV record for company.com: _sipinternaltls._tcp.company.com
    (13:39:17) autorecon: done calling purple_account_connect
    (13:39:17) dnssrv: found 1 SRV entries
    (13:39:17) sipe: sipe_core_dns_resolved - SRV hostname: siplync.domain.com port: 5061
    (13:39:17) sipe: transport_connect - hostname: siplync.domain.com port: 5061
    (13:39:17) sipe: using SSL
    (13:39:17) dnsquery: Performing DNS lookup for siplync.domain.com
    (13:39:17) dns: Wait for DNS child 4172 failed: No child processes
    (13:39:17) dns: Created new DNS child 4237, there are now 1 children.
    (13:39:17) dns: Successfully sent DNS request to child 4237
    (13:39:17) dns: Got response for 'siplync.domain.com'
    (13:39:17) dnsquery: IP resolved for siplync.domain.com
    (13:39:17) proxy: Attempting connection to xxx.xxx.xxx.xxx
    (13:39:17) proxy: Connecting to siplync.domain.com:5061 with no proxy
    (13:39:17) proxy: Connection in progress
    (13:39:17) proxy: Connecting to siplync.domain.com:5061.
    (13:39:17) proxy: Connected to siplync.domain.com:5061.
    (13:39:17) nss: subject=CN=siplync.domain.com,OU=Company Service Center,O=The Company Corporation,L=Charlotte,ST=North Carolina,C=US issuer=CN=DigiCert High Assurance CA-3,OU=www.digicert.com,O=DigiCert Inc,C=US
    (13:39:17) nss: partial certificate chain
    (13:39:17) certificate/x509/tls_cached: Starting verify for siplync.domain.com
    (13:39:17) certificate/x509/tls_cached: Checking for cached cert...
    (13:39:17) certificate/x509/tls_cached: ...Found cached cert
    (13:39:17) nss/x509: Loading certificate from /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
    (13:39:17) certificate/x509/tls_cached: Peer cert matched cached
    (13:39:17) nss/x509: Exporting certificate to /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
    (13:39:17) util: Writing file /home/user/.purple/certificates/x509/tls_peers/siplync.domain.com
    (13:39:17) certificate: Successfully verified certificate for siplync.domain.com
    (13:39:17) sipe: sipe_schedule_allocate timeouts count 1 after addition
    (13:39:17) sipe: scheduling action <+keepalive-timeout> timeout 60 seconds
    (13:39:17) stun: using server
    (13:39:17) stun: using server
    (13:39:17) stun: using server
    (13:39:17) sipe: sipe_schedule_allocate timeouts count 2 after addition
    (13:39:17) sipe: scheduling action <transaction timeout=""><BA45g2CD9a947Bi2E13m8908t6DFFb9718xC382x><1 REGISTER> timeout 60 seconds
    (13:39:17) sipe: SIP transactions count:1 after addition
    (13:39:17) sipe:
    MESSAGE START >>>>>>>>>> SIP - 2014-05-22T20:39:17.507757Z
    REGISTER sip:company.com SIP/2.0
    Via: SIP/2.0/tls 172.28.24.129:58322;branch=z9hG4bK526839F59606DFD72A84
    From: sip:myusername@company.com;tag=5876854081;epid=563e03dff8aa
    To: sip:myusername@company.com
    Max-Forwards: 70
    CSeq: 1 REGISTER
    User-Agent: Purple/2.10.9 Sipe/1.18.1 (linux-x86_64; )
    Call-ID: BA45g2CD9a947Bi2E13m8908t6DFFb9718xC382x
    Contact: <sip:172.28.24.129:58322;transport=tls;ms-opaque=d3470f2e1d>;methods="INVITE, MESSAGE, INFO, SUBSCRIBE, OPTIONS, BYE, CANCEL, NOTIFY, ACK, REFER, BENOTIFY";proxy=replace;+sip.instance="<urn:uuid:a3218743-f79f-535a-b2cd-b68290f9ec45>"
    Supported: gruu-10, adhoclist, msrtc-event-categories, com.microsoft.msrtc.presence
    Event: registration
    Allow-Events: presence
    ms-keep-alive: UAC;hop-hop=yes
    Content-Length: 0

    MESSAGE END >>>>>>>>>> SIP - 2014-05-22T20:39:17.507757Z

     
  • Jeff Gustafson

    Jeff Gustafson - 2014-05-22

    Some more log info:
    (15:22:36) sipe: transport_input_common: new buffer length 4096
    (15:22:36) sipe: Read error: Connection reset by peer (104)
    (15:22:36) connection: Connection error on 0x27ef3a0 (reason: 0 description: Read error)
    (15:22:36) sipe: Server has disconnected

     
  • Jeff Gustafson

    Jeff Gustafson - 2014-05-22

    I also tried connecting to the port directly using openssl. I get this:

    REGISTER sip:zynx.com SIP/2.0
    RENEGOTIATING
    write:errno=104

    Is it normal that it immediately sends back "RENEGOTIATING"

     
  • Stefan Becker

    Stefan Becker - 2014-05-23

    Please don't copy & paste the log, it is unusable because it got truncated. Attach it or put it up on a pastebin service.

    But the other post shows that the problem is most likely SSL BEAST mitigation. Are you 100% sure you set the variable in such a way that it is set when you start Pidgin? E.g.

    $ NSS_SSL_CBC_RANDOM_IV=0 pidgin
    

    In Fedora 20 you must set this variable, because the default has changed from Fedora 19.

     
    • Jeff Gustafson

      Jeff Gustafson - 2014-05-23

      I wouldn't be posting here if NSS_SSL_CBC_RANDOM_IV=0 worked.

      Since it used to work, I know that I set the env variable correctly. Nothing changed in how I launch the program. It is almost like the variable is being ignored or something. Just to restate, my notebook stopped working first, then my desktop. I exit and re-launch pidgin on my notebook, but on my desktop I leave it running for weeks on end. My guess is that some library changed underneath that broke it since I didn't change how I launched the program (straight command line with 'NSS_SSL_CBC_RANDOM_IV=0 pidgin'.

      How can I determine that the environment variable is being honored in NSS?

       
  • Jeff Gustafson

    Jeff Gustafson - 2014-05-23

    So, it appears that the problem is with the company's server configuration. The Read Error was a red herring. The admins for the system admitted that something is up on their side and they don't know when they will be able to figure out how fix it. Not sure why it wasn't impacting everyone's Lync client. They blamed me because I'm the only one using a Linux desktop (parent company is old school). But now they tell me some native Lync client people are complaining.

    I fixed the issue by entering a hostname, that they supplied, that does not appear in any of the SRV records or any of the debug logs.

     
    • tcz8

      tcz8 - 2014-10-08

      Hi Jeff,

      I have the same problem, could you give me more details on the hostname they had you enter?

      Were did you did you put it and who's hostname is this exactly?

      BTW did they eventually figure out the issue? I will have to bring this up to the "admin" (big fat quotes) in charge of this server and as usual I will have to paint him a picture or he wont understand.

      Thank you.

       
      Last edit: tcz8 2014-10-08

Get latest updates about Open Source Projects, Conferences and News.

Sign up for the SourceForge newsletter:





No, thanks