(11:25:55) sipe: sipe_core_connect: user specified SIP server pod56515.outlook.com:443
A HTTP server is running at this host/port address:
MESSAGE START <<<<<<<<<< SIP - 2012-10-20T09:25:58.026216Z
HTTP/1.1 400 Bad Request
Have you tried without setting the server name, i.e. with autodetection? Otherwise you'll have to check with your IT or grab the correct value from the settings of an OCS/Lync client.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
I am getting this in my logs, what I have made wrong?
No -debug log, no answer. We're not running a guessing game here.
(11:25:53) prefs: Reading /home/marek/.purple/prefs.xml
(11:25:53) prefs: Finished reading /home/marek/.purple/prefs.xml
(11:25:53) prefs: purple_prefs_get_path: Unknown pref /pidgin/browsers/command
(11:25:53) dbus: okkk
(11:25:53) plugins: probing /usr/lib/pidgin/timestamp.so
(11:25:53) plugins: probing /usr/lib/pidgin/spellchk.so
(11:25:53) plugins: probing /usr/lib/pidgin/iconaway.so
(11:25:53) plugins: probing /usr/lib/pidgin/pidginrc.so
(11:25:53) plugins: probing /usr/lib/pidgin/ticker.so
(11:25:53) plugins: probing /usr/lib/pidgin/themeedit.so
(11:25:53) plugins: probing /usr/lib/pidgin/musicmessaging.so
(11:25:53) plugins: probing /usr/lib/pidgin/notify.so
(11:25:53) plugins: probing /usr/lib/pidgin/markerline.so
(11:25:53) plugins: probing /usr/lib/pidgin/timestamp_format.so
(11:25:53) plugins: probing /usr/lib/pidgin/sendbutton.so
(11:25:53) plugins: probing /usr/lib/pidgin/convcolors.so
(11:25:53) plugins: probing /usr/lib/pidgin/vvconfig.so
(11:25:53) plugins: probing /usr/lib/pidgin/cap.so
(11:25:53) plugins: probing /usr/lib/pidgin/gestures.so
(11:25:53) plugins: probing /usr/lib/pidgin/gtkbuddynote.so
(11:25:53) plugins: probing /usr/lib/pidgin/extplacement.so
(11:25:53) plugins: probing /usr/lib/pidgin/xmppdisco.so
(11:25:53) plugins: probing /usr/lib/pidgin/xmppconsole.so
(11:25:53) plugins: probing /usr/lib/pidgin/history.so
(11:25:53) plugins: probing /usr/lib/purple-2/pidgin-libnotify.so
(11:25:53) plugins: probing /usr/lib/purple-2/libsimple.so
(11:25:53) plugins: probing /usr/lib/purple-2/newline.so
(11:25:53) plugins: probing /usr/lib/purple-2/libymsg.so
(11:25:53) plugins: /usr/lib/purple-2/libymsg.so is not usable because the 'purple_init_plugin' symbol could not be found. Does the plugin call the PURPLE_INIT_PLUGIN() macro?
(11:25:53) plugins: probing /usr/lib/purple-2/libyahoo.so
(11:25:53) plugins: probing /usr/lib/purple-2/offlinemsg.so
(11:25:53) plugins: probing /usr/lib/purple-2/libjabber.so
(11:25:53) plugins: /usr/lib/purple-2/libjabber.so is not usable because the 'purple_init_plugin' symbol could not be found. Does the plugin call the PURPLE_INIT_PLUGIN() macro?
(11:25:53) plugins: probing /usr/lib/purple-2/idle.so
(11:25:53) plugins: probing /usr/lib/purple-2/tcl.so
(11:25:53) plugins: probing /usr/lib/purple-2/libsametime.so
(11:25:53) plugins: /usr/lib/purple-2/libsametime.so has a prefs_info, but is a prpl. This is no longer supported.
(11:25:53) plugins: probing /usr/lib/purple-2/buddynote.so
(11:25:53) plugins: probing /usr/lib/purple-2/statenotify.so
(11:25:53) plugins: probing /usr/lib/purple-2/log_reader.so
(11:25:53) plugins: probing /usr/lib/purple-2/ssl-nss.so
(11:25:53) plugins: probing /usr/lib/purple-2/libmyspace.so
(11:25:53) plugins: probing /usr/lib/purple-2/libsipe.so
(11:25:53) sipe: bindtextdomain = /usr/local/share/locale
(11:25:53) sipe: bind_textdomain_codeset = UTF-8
(11:25:53) sipe: NSS initialised
(11:25:53) plugins: probing /usr/lib/purple-2/libmxit.so
(11:25:53) prpl-loubserp-mxit: Loading MXit libPurple plugin...
(11:25:53) plugins: probing /usr/lib/purple-2/joinpart.so
(11:25:53) plugins: probing /usr/lib/purple-2/liboscar.so
(11:25:53) plugins: /usr/lib/purple-2/liboscar.so is not usable because the 'purple_init_plugin' symbol could not be found. Does the plugin call the PURPLE_INIT_PLUGIN() macro?
(11:25:53) plugins: probing /usr/lib/purple-2/libaim.so
(11:25:53) plugins: probing /usr/lib/purple-2/libbonjour.so
(11:25:53) plugins: probing /usr/lib/purple-2/perl.so
(11:25:53) plugins: probing /usr/lib/purple-2/libzephyr.so
(11:25:53) plugins: probing /usr/lib/purple-2/libicq.so
(11:25:53) plugins: probing /usr/lib/purple-2/libgg.so
(11:25:53) plugins: probing /usr/lib/purple-2/libxmpp.so
(11:25:53) plugins: probing /usr/lib/purple-2/libyahoojp.so
(11:25:53) plugins: probing /usr/lib/purple-2/libirc.so
(11:25:53) plugins: probing /usr/lib/purple-2/dbus-example.so
(11:25:53) plugins: probing /usr/lib/purple-2/psychic.so
(11:25:53) plugins: probing /usr/lib/purple-2/ssl.so
(11:25:53) plugins: probing /usr/lib/purple-2/autoaccept.so
(11:25:53) plugins: probing /usr/lib/purple-2/libnovell.so
(11:25:53) plugins: probing /usr/lib/purple-2/libmsn.so
(11:25:53) util: Reading file xmpp-caps.xml from directory /home/marek/.purple
(11:25:53) jabber: creating hash tables for data objects
(11:25:53) prefs: /purple/status/scores/offline changed, scheduling save.
(11:25:53) prefs: /purple/status/scores/available changed, scheduling save.
(11:25:53) prefs: /purple/status/scores/invisible changed, scheduling save.
(11:25:53) prefs: /purple/status/scores/away changed, scheduling save.
(11:25:53) prefs: /purple/status/scores/extended_away changed, scheduling save.
(11:25:53) prefs: /purple/status/scores/idle changed, scheduling save.
(11:25:53) prefs: /purple/status/scores/offline_msg changed, scheduling save.
(11:25:53) util: Reading file accounts.xml from directory /home/marek/.purple
(11:25:53) util: Reading file status.xml from directory /home/marek/.purple
(11:25:53) certificate: CertificateVerifier x509, singleuse requested but not found.
(11:25:53) certificate: CertificateVerifier singleuse registered
(11:25:53) certificate: CertificatePool x509, ca requested but not found.
(11:25:53) certificate: CertificateScheme x509 requested but not found.
(11:25:53) certificate/x509/ca: Lazy init failed because an X.509 Scheme is not yet registered. Maybe it will be better later.
(11:25:53) certificate/x509/ca: Init failed, probably because a dependency is not yet registered. It has been deferred to later.
(11:25:53) certificate: CertificatePool ca registered
(11:25:53) certificate: CertificatePool x509, tls_peers requested but not found.
(11:25:53) certificate: CertificatePool tls_peers registered
(11:25:53) certificate: CertificateVerifier x509, tls_cached requested but not found.
(11:25:53) certificate: CertificateVerifier tls_cached registered
(11:25:53) prefs: /purple/logging/format changed, scheduling save.
(11:25:53) prefs: /purple/logging/format changed, scheduling save.
(11:25:53) prefs: /purple/proxy/type changed, scheduling save.
(11:25:53) prefs: /purple/proxy/host changed, scheduling save.
(11:25:53) prefs: /purple/proxy/port changed, scheduling save.
(11:25:53) prefs: /purple/proxy/username changed, scheduling save.
(11:25:53) prefs: /purple/proxy/password changed, scheduling save.
(11:25:53) certificate: CertificateScheme x509 requested but not found.
(11:25:53) certificate: CertificateScheme x509 registered
(11:25:53) util: Reading file smileys.xml from directory /home/marek/.purple
(11:25:53) util: File /home/marek/.purple/smileys.xml does not exist (this is not necessarily an error)
(11:25:53) stun: using server
(11:25:53) sound: Initializing sound output drivers.
(11:25:53) prefs: /pidgin/conversations/placement changed, scheduling save.
(11:25:53) prefs: purple_prefs_get_bool: Unknown pref /pidgin/docklet/x11/embedded
(11:25:53) GLib-GObject: /build/buildd/glib2.0-2.32.1/./gobject/gsignal.c:2455: signal
destroy' is invalid for instance0x225e8248'(11:25:53) gtkblist: added visibility manager: 1
(11:25:53) docklet: GTK+ created
(11:25:54) gtkmedia: Registering media element types
(11:25:54) util: Reading file blist.xml from directory /home/marek/.purple
(11:25:54) plugins: Loading saved plugin /usr/lib/purple-2/ssl-nss.so
(11:25:54) plugins: Loading saved plugin /usr/lib/purple-2/pidgin-libnotify.so
(11:25:54) plugins: Loading saved plugin /usr/lib/purple-2/ssl.so
(11:25:54) pounce: Error reading pounces: Otwarcie pliku "/home/marek/.purple/pounces.xml" się nie powiodło: Nie ma takiego pliku ani katalogu
(11:25:54) Session Management: ICE initialized.
(11:25:54) Session Management: Connecting with no previous ID
(11:25:54) Session Management: Handling new ICE connection...
(11:25:54) done.
(11:25:54) Session Management: Connected to manager (gnome-session) with client ID 1056cf2588ee703c5f135072515416206700000017530112
(11:25:54) Session Management: Using pidgin as command
(11:25:54) gtkutils: gdk_pixbuf_new_from_file() returned nothing for file /usr/share/pixmaps/pidgin/protocols/16/sipe.png: Otwarcie pliku "/usr/share/pixmaps/pidgin/protocols/16/sipe.png" się nie powiodło: Nie ma takiego pliku ani katalogu
(11:25:54) account: Connecting to account marwooj@microcomp.com,.
(11:25:54) connection: Connecting. gc = 0x23666298
(11:25:54) sipe: sipe_purple_login: username 'marwooj@microcomp.com,'
(11:25:54) sipe: sipe_core_allocate: signin_name 'marwooj@microcomp.com'
(11:25:54) sipe: sipe_core_allocate: user 'marwooj' domain 'microcomp.com'
(11:25:54) sipe: sipe_cert_crypto_init: generate key pair, this might take a while...
(11:25:55) sipe: sipe_cert_crypto_init: key pair generated
(11:25:55) sipe: sipe_certificate_init: DONE
(11:25:55) sipe: sipe_core_connect: user specified SIP server pod56515.outlook.com:443
(11:25:55) sipe: transport_connect - hostname: pod56515.outlook.com port: 443
(11:25:55) sipe: using SSL
(11:25:55) dnsquery: Performing DNS lookup for pod56515.outlook.com
(11:25:55) Session Management: Received first save_yourself
(11:25:55) dns: Created new DNS child 3241, there are now 1 children.
(11:25:55) dns: Successfully sent DNS request to child 3241
(11:25:55) Session Management: Received save_complete
(11:25:55) docklet: embedded
(11:25:55) prefs: /pidgin/docklet/gtk/embedded changed, scheduling save.
(11:25:57) dns: Got response for 'pod56515.outlook.com'
(11:25:57) dnsquery: IP resolved for pod56515.outlook.com
(11:25:57) proxy: Attempting connection to 157.56.252.166
(11:25:57) proxy: Connecting to pod56515.outlook.com:443 with no proxy
(11:25:57) proxy: Connection in progress
(11:25:57) proxy: Connecting to pod56515.outlook.com:443.
(11:25:57) proxy: Connected to pod56515.outlook.com:443.
(11:25:57) nss: subject=CN=outlook.com,OU=Exchange,O=Microsoft Corporation,L=Redmond,ST=Washington,C=US issuer=CN=Microsoft Secure Server Authority,DC=redmond,DC=corp,DC=microsoft,DC=com
(11:25:57) nss: subject=CN=Microsoft Secure Server Authority,DC=redmond,DC=corp,DC=microsoft,DC=com issuer=CN=Microsoft Internet Authority
(11:25:57) nss: subject=CN=Microsoft Internet Authority issuer=CN=GTE CyberTrust Global Root,OU="GTE CyberTrust Solutions, Inc.",O=GTE Corporation,C=US
(11:25:57) nss: subject=CN=GTE CyberTrust Global Root,OU="GTE CyberTrust Solutions, Inc.",O=GTE Corporation,C=US issuer=CN=GTE CyberTrust Global Root,OU="GTE CyberTrust Solutions, Inc.",O=GTE Corporation,C=US
(11:25:57) certificate/x509/tls_cached: Starting verify for pod56515.outlook.com
(11:25:57) certificate/x509/tls_cached: Checking for cached cert...
(11:25:57) certificate/x509/tls_cached: ...Found cached cert
(11:25:57) nss/x509: Loading certificate from /home/marek/.purple/certificates/x509/tls_peers/pod56515.outlook.com
(11:25:57) certificate/x509/tls_cached: Peer cert matched cached
(11:25:57) nss/x509: Exporting certificate to /home/marek/.purple/certificates/x509/tls_peers/pod56515.outlook.com
(11:25:57) util: Writing file /home/marek/.purple/certificates/x509/tls_peers/pod56515.outlook.com
(11:25:57) certificate: Successfully verified certificate for pod56515.outlook.com
(11:25:57) stun: using server
(11:25:57) stun: using server
(11:25:57) stun: using server
(11:25:57) sipe: sipe_schedule_allocate timeouts count 1 after addition
(11:25:57) sipe: scheduling action <transaction timeout=""><DE64g70E4a25B5iA211m6523t3465b22D0x7FB1x><1 REGISTER> timeout 60 seconds
(11:25:57) sipe: SIP transactions count:1 after addition
(11:25:57) sipe:
MESSAGE START >>>>>>>>>> SIP - 2012-10-20T09:25:57.885430Z
REGISTER sip:microcomp.com SIP/2.0
Via: SIP/2.0/tls 192.168.0.103:52144;branch=z9hG4bK3AD770FAEDDDA57EA225
From: sip:marwooj@microcomp.com;tag=175851079;epid=9af1d9087eaa
To: sip:marwooj@microcomp.com
Max-Forwards: 70
CSeq: 1 REGISTER
User-Agent: UCCAPI/4.0.7577.4072 OC/4.0.7577.4087 (Microsoft Lync 2010)
Call-ID: DE64g70E4a25B5iA211m6523t3465b22D0x7FB1x
Contact: <sip:192.168.0.103:52144;transport=tls;ms-opaque=d3470f2e1d>;methods="INVITE, MESSAGE, INFO, SUBSCRIBE, OPTIONS, BYE, CANCEL, NOTIFY, ACK, REFER, BENOTIFY";proxy=replace;+sip.instance="<urn:uuid:c7ee84a3-b4e6-5e01-bcc2-3b5e66a9c2f4>"
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 - 2012-10-20T09:25:57.885430Z
(11:25:58) sipe: transport_input_common: new buffer length 4096
(11:25:58) sipe:
MESSAGE START <<<<<<<<<< SIP - 2012-10-20T09:25:58.026216Z
HTTP/1.1 400 Bad Request
Content-Type: text/html; charset=us-ascii
Server: Microsoft-HTTPAPI/2.0
Date: Sat, 20 Oct 2012 09:25:57 GMT
Connection: close
Content-Length: 311
<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01//EN""http://www.w3.org/TR/html4/strict.dtd">
<HTML><HEAD><TITLE>Bad Request</TITLE>
<META HTTP-EQUIV="Content-Type" Content="text/html; charset=us-ascii"></HEAD>
<BODY>
Bad Request
HTTP Error 400. The request is badly formed.
</BODY></HTML>
MESSAGE END <<<<<<<<<< SIP - 2012-10-20T09:25:58.026216Z
(11:25:58) sipe: process_input_message: msg->response(400),msg->method(NOT FOUND)
(11:25:58) sipe: transaction_find: no Call-ID or CSeq!
(11:25:58) sipe: process_input_message: received response to unknown transaction
(11:25:58) sipe: received a unknown sip message with method NOT FOUND and response 400
(11:25:58) sipe: Server has disconnected
(11:25:58) connection: Connection error on 0x23666298 (reason: 0 description: Serwer został rozłączony)
(11:25:58) sipe: Server has disconnected
(11:25:58) account: Disconnecting account marwooj@microcomp.com, (0x223f52a0)
(11:25:58) connection: Disconnecting connection 0x23666298
(11:25:58) sipe: SIP transactions count:0 after removal
(11:25:58) sipe: sipe_schedule_remove: action name=<transaction timeout=""><DE64g70E4a25B5iA211m6523t3465b22D0x7FB1x><1 REGISTER>
(11:25:58) GLib: g_hash_table_destroy: assertion `hash_table != NULL' failed
(11:25:58) gtkutils: gdk_pixbuf_new_from_file() returned nothing for file /usr/share/pixmaps/pidgin/protocols/16/sipe.png: Otwarcie pliku "/usr/share/pixmaps/pidgin/protocols/16/sipe.png" się nie powiodło: Nie ma takiego pliku ani katalogu
(11:25:58) connection: Destroying connection 0x23666298
(11:25:58) util: Writing file prefs.xml to directory /home/marek/.purple
(11:25:58) util: Writing file /home/marek/.purple/prefs.xml
(11:25:58) util: Writing file accounts.xml to directory /home/marek/.purple
(11:25:58) util: Writing file /home/marek/.purple/accounts.xml
(11:25:59) util: Writing file blist.xml to directory /home/marek/.purple
(11:25:59) util: Writing file /home/marek/.purple/blist.xml
(11:26:05) prefs: /pidgin/blist/list_visible changed, scheduling save.
(11:26:08) certificate: CertificateVerifier tls_cached unregistered
(11:26:08) certificate: CertificateVerifier singleuse unregistered
(11:26:08) certificate: CertificatePool tls_peers unregistered
(11:26:08) certificate: CertificatePool ca unregistered
(11:26:08) main: Unloading normal plugins
(11:26:08) plugins: Unloading plugin Powiadomienia libnotify
(11:26:08) plugins: Unloading plugin NSS
(11:26:08) certificate: CertificateScheme x509 unregistered
(11:26:08) plugins: Unloading plugin SSL
(11:26:08) blist: Destroying
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) accels: accel changed, scheduling save.
(11:26:08) util: Writing file status.xml to directory /home/marek/.purple
(11:26:08) util: Writing file /home/marek/.purple/status.xml
(11:26:08) account: Destroying account 0x223f52a0
(11:26:08) account: Destroying account 0x223f8e58
(11:26:08) account: Destroying account 0x223e89f0
(11:26:08) main: Unloading all plugins
(11:26:08) plugins: Unloading plugin SIMPLE
(11:26:08) plugins: Unloading plugin Yahoo
(11:26:08) plugins: Unloading plugin Tcl Plugin Loader
(11:26:08) plugins: Unloading plugin Sametime
(11:26:08) plugins: Unloading plugin MySpaceIM
(11:26:08) plugins: Unloading plugin Office Communicator
(11:26:08) plugins: Unloading plugin MXit
(11:26:08) plugins: Unloading plugin AIM
(11:26:08) plugins: Unloading plugin Bonjour
(11:26:08) plugins: Unloading plugin Perl Plugin Loader
(11:26:08) plugins: Unloading plugin Zephyr
(11:26:08) plugins: Unloading plugin ICQ
(11:26:08) plugins: Unloading plugin Gadu-Gadu
(11:26:08) plugins: Unloading plugin XMPP
(11:26:08) jabber: destroying hash tables for data objects
(11:26:08) plugins: Unloading plugin Yahoo JAPAN
(11:26:08) plugins: Unloading plugin IRC
(11:26:08) plugins: Unloading plugin GroupWise
(11:26:08) plugins: Unloading plugin MSN
(11:26:08) Session Management: Handling closed ICE connection...
(11:26:08) done.
(11:26:08) Session Management: Connection closed.
(11:26:08) gtkblist: removed visibility manager: 0
(11:26:08) docklet: detached
(11:26:08) docklet: GTK+ destroyed
(11:26:08) util: Writing file prefs.xml to directory /home/marek/.purple
(11:26:08) util: Writing file /home/marek/.purple/prefs.xml
Your server setting is wrong:
A HTTP server is running at this host/port address:
Have you tried without setting the server name, i.e. with autodetection? Otherwise you'll have to check with your IT or grab the correct value from the settings of an OCS/Lync client.
I have tried - connection timeout.
How to do that?
You can get the right hostname my logging into Outlook Web access at https://portal.microsoftonline.com/
Go into mail (Outlook) and then Help (The question mark icon) -> About.
"External POP setting" shows your correct podnnnnnn.outlook.com hostname.
John.
However, for my work domain, autodetection works, as we've set up a SRV record at _sip._tls.example.com
The only things I need to change are the User Agent and set the authentication scheme as TLS-DSK.
In fact the SRV record just points at sipdir.online.lync.com, which presumably works for all Office365 accounts.
Thank you all for your help. I tracked down traffic of my Lync and it came out it was completely different host I should use.