[Pidgin] #14976: MSN (windows live messenger) login hangs with message "file msg.c: line 179 (msn_message_parse_payload): should not be reached"

Pidgin trac at pidgin.im
Thu Mar 1 23:58:18 EST 2012 

#14976: MSN (windows live messenger) login hangs with message "file msg.c: line 179
(msn_message_parse_payload): should not be reached"
--------------------+-------------------------------------------------------
 Reporter:  sunyj   |     Owner:  QuLogic
     Type:  defect  |    Status:  new    
Component:  MSN     |   Version:  2.10.1 
 Keywords:          |  
--------------------+-------------------------------------------------------
 This problem has been there for a long time.

 After I login my WLM (windows live messenger) account with the official
 client on windows, it can never login through pidgin.

 Below are the log messages with PURPLE_VERBOSE_DEBUG set.

 The account name is substituted to a fake one.

 The login process hangs on "msn: UBX received."

 {{{
 (16:01:11) account: Connecting to account sun at msn.com.
 (16:01:11) connection: Connecting. gc = 0x201d600
 (16:01:11) msn: new httpconn (0x1fc9f80)
 (16:01:11) dnsquery: Performing DNS lookup for messenger.hotmail.com
 (16:01:11) dns: Wait for DNS child 11938 failed: No child processes
 (16:01:11) dns: Created new DNS child 11957, there are now 1 children.
 (16:01:11) dns: Successfully sent DNS request to child 11957
 (16:01:12) dns: Got response for 'messenger.hotmail.com'
 (16:01:12) dnsquery: IP resolved for messenger.hotmail.com
 (16:01:12) proxy: Attempting connection to 64.4.9.254
 (16:01:12) proxy: Connecting to messenger.hotmail.com:1863 with no proxy
 (16:01:12) proxy: Connection in progress
 (16:01:12) proxy: Connecting to messenger.hotmail.com:1863.
 (16:01:12) proxy: Connected to messenger.hotmail.com:1863.
 (16:01:12) msn: C: NS 000: VER 1 MSNP16 MSNP15 CVR0
 (16:01:12) msn: S: NS 000: VER 1 MSNP16
 (16:01:12) msn: Negotiated protocol version 16 with the server.
 (16:01:12) msn: C: NS 000: CVR 2 0x0409 winnt 5.1 i386 MSNMSGR 8.5.1302
 BC01 sun at msn.com
 (16:01:12) msn: S: NS 000: CVR 2 14.0.8117 14.0.8117 14.0.8117
 http://msgruser.dlservice.microsoft.com/download/A/6/1/A616CCD4-B0CA-
 4A3D-B975-3EDB38081B38/en/wlsetup-cvr.exe
 http://download.live.com/?sku=messenger
 (16:01:12) msn: C: NS 000: USR 3 SSO I sun at msn.com
 (16:01:12) msn: S: NS 000: XFR 3 NS 64.4.61.160:1863 U D
 (16:01:12) dnsquery: Performing DNS lookup for 64.4.61.160
 (16:01:12) dnsquery: IP resolved for 64.4.61.160
 (16:01:12) proxy: Attempting connection to 64.4.61.160
 (16:01:12) proxy: Connecting to 64.4.61.160:1863 with no proxy
 (16:01:12) proxy: Connection in progress
 (16:01:12) proxy: Connecting to 64.4.61.160:1863.
 (16:01:12) proxy: Connected to 64.4.61.160:1863.
 (16:01:12) msn: C: NS 000: VER 4 MSNP16 MSNP15 CVR0
 (16:01:13) msn: S: NS 000: VER 4 MSNP16
 (16:01:13) msn: Negotiated protocol version 16 with the server.
 (16:01:13) msn: C: NS 000: CVR 5 0x0409 winnt 5.1 i386 MSNMSGR 8.5.1302
 BC01 sun at msn.com
 (16:01:13) msn: S: NS 000: CVR 5 14.0.8117 14.0.8117 14.0.8117
 http://msgruser.dlservice.microsoft.com/download/A/6/1/A616CCD4-B0CA-
 4A3D-B975-3EDB38081B38/en/wlsetup-cvr.exe
 http://download.live.com/?sku=messenger
 (16:01:13) msn: C: NS 000: USR 6 SSO I sun at msn.com
 (16:01:13) msn: S: NS 000: GCF 0 5900
 (16:01:13) msn: Processing GCF command
 (16:01:13) msn: S: NS 000: USR 6 SSO S MBI_KEY
 pG31xebuW7y0Wqi7o8a7zOgFheDKKfKLtqbs/LJJKkM4HXj7LSsHs8wKRaN+5HPe
 (16:01:13) msn: Starting Windows Live ID authentication
 (16:01:13) msn: Logging on sun at msn.com, with policy 'MBI_KEY', nonce
 'pG31xebuW7y0Wqi7o8a7zOgFheDKKfKLtqbs/LJJKkM4HXj7LSsHs8wKRaN+5HPe'
 (16:01:13) dnsquery: Performing DNS lookup for login.live.com
 (16:01:13) dns: Successfully sent DNS request to child 11957
 (16:01:13) dns: Got response for 'login.live.com'
 (16:01:13) dnsquery: IP resolved for login.live.com
 (16:01:13) proxy: Attempting connection to 65.54.165.179
 (16:01:13) proxy: Connecting to login.live.com:443 with no proxy
 (16:01:13) proxy: Connection in progress
 (16:01:13) proxy: Connecting to login.live.com:443.
 (16:01:13) proxy: Connected to login.live.com:443.
 (16:01:13) gnutls: Starting handshake with login.live.com
 (16:01:14) gnutls: Handshake complete
 (16:01:14) gnutls/x509: Key print:
 f4:87:29:c1:0e:ab:49:5c:c2:1c:ef:59:c1:de:1b:1a:6e:f9:c0:58
 (16:01:14) gnutls/x509: Key print:
 2b:ac:95:6c:4e:e4:7f:9d:5c:1e:05:ae:8e:d7:f9:5d:47:c2:1f:80
 (16:01:14) gnutls/x509: Key print:
 f4:a8:0a:0c:d1:e6:cf:19:0b:8c:bc:6f:bc:99:17:11:d4:82:c9:d0
 (16:01:14) gnutls: Peer provided 3 certs
 (16:01:14) gnutls: Lvl 0 SHA1 fingerprint:
 f4:87:29:c1:0e:ab:49:5c:c2:1c:ef:59:c1:de:1b:1a:6e:f9:c0:58
 (16:01:14) gnutls: Serial: 02:9a:ee:64:54:95:b8:1d:e1:5a:1a:bb:cb:60:d6:c0
 (16:01:14) gnutls: Cert DN:
 (16:01:14) gnutls: Cert Issuer DN: C=US,O=VeriSign\, Inc.,OU=VeriSign
 Trust Network,OU=Terms of use at https://www.verisign.com/rpa
 (c)06,CN=VeriSign Class 3 Extended Validation SSL CA
 (16:01:14) gnutls: Lvl 1 SHA1 fingerprint:
 2b:ac:95:6c:4e:e4:7f:9d:5c:1e:05:ae:8e:d7:f9:5d:47:c2:1f:80
 (16:01:14) gnutls: Serial: 5b:77:59:c6:17:84:e1:5e:c7:27:c0:32:95:29:28:6b
 (16:01:14) gnutls: Cert DN: C=US,O=VeriSign\, Inc.,OU=VeriSign Trust
 Network,OU=Terms of use at https://www.verisign.com/rpa (c)06,CN=VeriSign
 Class 3 Extended Validation SSL CA
 (16:01:14) gnutls: Cert Issuer DN: C=US,O=VeriSign\, Inc.,OU=VeriSign
 Trust Network,OU=(c) 2006 VeriSign\, Inc. - For authorized use
 only,CN=VeriSign Class 3 Public Primary Certification Authority - G5
 (16:01:14) gnutls: Lvl 2 SHA1 fingerprint:
 f4:a8:0a:0c:d1:e6:cf:19:0b:8c:bc:6f:bc:99:17:11:d4:82:c9:d0
 (16:01:14) gnutls: Serial: 35:97:31:87:f3:87:3a:07:32:7e:ce:58:0c:9b:7e:da
 (16:01:14) gnutls: Cert DN: C=US,O=VeriSign\, Inc.,OU=VeriSign Trust
 Network,OU=(c) 2006 VeriSign\, Inc. - For authorized use only,CN=VeriSign
 Class 3 Public Primary Certification Authority - G5
 (16:01:14) gnutls: Cert Issuer DN: C=US,O=VeriSign\, Inc.,OU=Class 3
 Public Primary Certification Authority
 (16:01:14) certificate/x509/tls_cached: Starting verify for login.live.com
 (16:01:14) certificate/x509/tls_cached: Checking for cached cert...
 (16:01:14) certificate/x509/tls_cached: ...Found cached cert
 (16:01:14) gnutls: Attempting to load X.509 certificate from
 /home/sun/.purple/certificates/x509/tls_peers/login.live.com
 (16:01:14) certificate/x509/tls_cached: Peer cert matched cached
 (16:01:14) util: Writing file
 /home/sun/.purple/certificates/x509/tls_peers/login.live.com
 (16:01:14) certificate: Successfully verified certificate for
 login.live.com
 (16:01:14) soap: Sending secure request.
 (16:01:15) soap: read 384 bytes
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: Received secure request.
 (16:01:15) soap: read 15074 bytes
 (16:01:15) gnutls: receive failed: A TLS packet with unexpected length was
 received.
 (16:01:15) soap: read: Input/output error
 (16:01:15) soap: Received secure request.
 (16:01:15) msn: Updated ticket for domain 'messengerclear.live.com',
 expires at 1330617674.
 (16:01:15) msn: Updated ticket for domain 'messenger.msn.com', expires at
 1330588914.
 (16:01:15) msn: Updated ticket for domain 'contacts.msn.com', expires at
 1330617674.
 (16:01:15) msn: Updated ticket for domain 'messengersecure.live.com',
 expires at 1330675274.
 (16:01:15) msn: Updated ticket for domain 'spaces.live.com', expires at
 1330617674.
 (16:01:15) msn: Updated ticket for domain 'livecontacts.live.com', expires
 at 1330617674.
 (16:01:15) msn: Updated ticket for domain 'storage.live.com', expires at
 1330617674.
 (16:01:15) msn: C: NS 000: USR 7 SSO S
 t=EwAQAswbAQAUs1/VcBU2sH7mwYy3BysWZ71CRDGAAE5PnNUE7aQV0eIOFKQIKNhYxie0GhW5neFTIOyup+tGJcNjZEWBgN1gQYH5iw63J7Y7cMveLtFHiXi27pEOsobs8O/jFWXjNy4/dd2KnTrHDXbbSnnVb3m14Yb5jI47XZyH3SqnzZasRIcK1zRjxTI89s6nmulnzCV5iQnwdOUyA2YAAAgeQfrkB2TxwWABhkZVCojLHmTmmSDGDSxi0X/aSOx98McjnclYhGw/RN0StwMuhGZ30DCiI3BT9rYJqZhJJz8y1BduzHjBQpWlVmXu/guFFmSD73hJkAok8ZEqmddSKfLFSrztwZ9xL6H96kftnJP749avTFXauAbgT6Jf1j5ybDtvKRSuNBUdQ5SJbCZSO2ztWnqR+ywSW2opubmrhddi2gFpJUlh7PTu1n6mc2cfxg2A3Q9yhZ3i1KedEOvW0AtPqMR/RGmW1mIvpxvdnFwRq5EHWRmWX1V3KbVkB2cqwDXSzWdHCX4ykc3jDDMg90z/nEVZx2olDg5Bt6nzNsQuYUtyGaV6rPOpTupuE20uw+uUbpWD8UaNAIuvcQiX+KechfSlEQvTIeMw2xlXhpv67hET5HH9JDbi6E+rbUVI0JOq/DBjK3b0vv8Nlj6qiMBWK+hIDAzABTOd5F4toykXnnoPKW+NrPfm4YwB&p=
 HAAAAAEAAAADZgAABIAAAAgAAAAUAAAASAAAACfXZGm5qpcIayurSCrELGi9+gDsQzkmgID25lFce9+Fy85aBmyqhpiNHc+LRESvLr1N6Y82Zi14WJtnWlzL3vic8y6fZxXmPQ9mlJ8MyPHUW39Kyu1R8T9xiGMv+lOYrhPB8vQ=
 A29C945F-9183-1AAD-AEEA-A69125611D22
 (16:01:15) msn: S: NS 000: USR 7 OK sun at msn.com 1 0
 (16:01:15) msn: S: NS 000: SBS 0 null
 (16:01:15) msn: Processing SBS...
 (16:01:15) msn: S: NS 000: MSG Hotmail Hotmail 1123
 (16:01:15) msn: Processing MSG...
 (16:01:15) msn: message new (0x2065470)(0)
 (16:01:15) msn: message ref (0x2065470)[1]
 (16:01:15) g_log: file msg.c: line 179 (msn_message_parse_payload): should
 not be reached
 (16:01:15) msn: Message Notification:
 {MIME-Version: 1.0
 Content-Type: (null)

 }
 (16:01:15) msn: failed to find message content
 (16:01:15) msn: message unref (0x2065470)[0]
 (16:01:15) msn: message destroy (0x2065470)
 (16:01:15) msn: S: NS 000: UBX sun at msn.com 1 0
 (16:01:15) msn: UBX received.
 }}}

-- 
Ticket URL: <http://developer.pidgin.im/ticket/14976>
Pidgin <http://pidgin.im>
Pidgin

More information about the Tracker mailing list