GNOME Bugzilla – Bug 621118
ekiga ignore some outgoing calls until you call to 500@ekiga.net
Last modified: 2010-09-25 15:10:23 UTC
Hi, I'm running ekiga 3.2.7-1 from Debian Sid, Sometimes I try to call to sip:xxxx and nothing happens ( I try it with enter key and clicking in call button with mouse ). Then If I call to echo test (500@ekiga.net) and I try again calling to sip:xxxx it works fine. Like in #621115, how can I provide more info ? Sorry for my english Thanks!
When you write "nothing happens", does the green button become grayed and the red one becomes active, or not? Also, please start ekiga like this: ekiga -d 4 2>output and, when you hit the problem again, stop ekiga with Ctrl-c and send us the output file to analyse it.
No changes in buttons. I can click green button many times without any response from ekiga until I call to echo test. Today I can't reproduce this issue, but I'll post debug info when it happens again Thanks!
Such a bug was fixed in 3.2.7: when ekiga starts and write manually the URL (not double click the contact), pressing green button does not do anything, and the green button remains active. As soon as you doubl-click on a contact, everything works (even writing manually the URL). Is that your case? It is strange, since this bug was fixed... http://git.gnome.org/browse/ekiga/commit/?h=gnome-2-26&id=0fd41913afaa72f
It doesn't happen when ekiga start, it appears after playing with ekiga a while. I'm pretty sure that it happened when I reported this bug. I'll try to give -d 4 output if it happen again. Give me a month, and if I cannot reproduce again, I will reply about closing this bug. I upgraded to 3.2.7-1 on jun 2 Sorry for my english Thanks to Eugen and company,
Created attachment 164498 [details] 25000 last lines from ekiga.log enter button or green button doesn't work in the last call tried (1999). I pulsed sometimes enter and green button. I didn't to call 500@ekiga.net, to make review eassier (I hope) I can't attach the full log because it has 99M. Thank you very much
I hope last attachmment could help with this issue $ dpkg -l ekiga* Desired=Unknown/Install/Remove/Purge/Hold | Status=Not/Inst/Conf-files/Unpacked/halF-conf/Half-inst/trig-aWait/Trig-pend |/ Err?=(none)/Reinst-required (Status,Err: uppercase=bad) ||/ Nombre Versión Descripción +++-=============================-=============================-========================================================================== ii ekiga 3.2.7-2 H.323 and SIP compatible VoIP client ii ekiga-dbg 3.2.7-2 H.323 and SIP compatible VoIP client - debug symbols Regards,
While this bug was happening, I did a tail -f ekiga.log No related logs appeared when I pressed enter key. Other - I think - not related logs apperared: $ # this bug is happening .. $ tail -f ekiga.logs Call-ID: 34f7f8784e113f167c71dfa5012ec868@89.140.6.13 To: <sip:1723@192.168.101.120> Contact: <sip:1723@192.168.101.120> Content-Length: 0 2010/06/28 18:18:33.730 4:08:36.029 Opal Liste...0xb49c1b70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:33.731 4:08:36.030 Opal Liste...0xb49c1b70 Opal Transport clean up on termination 2010/06/28 18:18:33.731 4:08:36.030 Opal Liste...0xb49c1b70 Opal Transport Close 2010/06/28 18:18:33.731 4:08:36.030 Opal Liste...0xb49c1b70 Opal Deleted transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:55.983 4:08:58.282 Housekeeper:0xb4a02b70 SIP Starting REGISTER for binding refresh 2010/06/28 18:18:55.984 4:08:58.283 Housekeeper:0xb4a02b70 SIP Changing REGISTER handler from Subscribed to Refreshing, target=sipx 2010/06/28 18:18:55.984 4:08:58.283 Housekeeper:0xb4a02b70 SIP Transaction created. 2010/06/28 18:18:55.987 4:08:58.286 Housekeeper:0xb4a02b70 SIP No SRV lookup as has explicit port number. 2010/06/28 18:18:55.987 4:08:58.286 Housekeeper:0xb4a02b70 SIP Transaction remote address is udp$89.140.6.13:5060 2010/06/28 18:18:55.987 4:08:58.286 Housekeeper:0xb4a02b70 SIP Sending PDU (532 bytes) to: rem=udp$89.140.6.13:5060,local=udp$192.n REGISTER sip:89.140.6.13 SIP/2.0 Route: <sip:89.140.6.13:5060;lr> CSeq: 1492 REGISTER Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bKac22c584-3e81-df11-9c71-0017a420b5ad;rport User-Agent: Ekiga/3.2.7 From: <sip:1723@89.140.6.13>;tag=d65af2bd-1b81-df11-9c71-0017a420b5ad Call-ID: e691f1bd-1b81-df11-9c71-0017a420b5ad@pepinux To: <sip:1723@89.140.6.13> Contact: <sip:1723@192.168.101.120> Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 60 Content-Length: 0 Max-Forwards: 70 2010/06/28 18:18:55.988 4:08:58.287 Housekeeper:0xb4a02b70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:55.988 4:08:58.287 Housekeeper:0xb4a02b70 SIP Transaction timers set: retry=0.500, completion=6.000 2010/06/28 18:18:55.989 4:08:58.289 Opal Liste...0xb49c1b70 OpalUDP Binding to interface: 192.168.101.120:5060 2010/06/28 18:18:55.990 4:08:58.289 Opal Liste...0xb49c1b70 SIP Waiting for PDU on udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:55.991 4:08:58.290 Opal Liste...0xb49c1b70 SIP PDU received: rem=udp$89.140.6.13:5060,local=udp$192.168.101.120:50n SIP/2.0 100 Trying CSeq: 1492 REGISTER Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bKac22c584-3e81-df11-9c71-0017a420b5ad;received=89.140.6.2;rport=65288 User-Agent: Asterisk PBX From: <sip:1723@89.140.6.13>;tag=d65af2bd-1b81-df11-9c71-0017a420b5ad Call-ID: e691f1bd-1b81-df11-9c71-0017a420b5ad@pepinux Supported: replaces To: <sip:1723@89.140.6.13> Contact: <sip:1723@89.140.6.13> Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 2010/06/28 18:18:55.992 4:08:58.291 Opal Liste...0xb49c1b70 SIP Queueing PDU "1492 REGISTER <100>", transaction=z9hG4bKac22c584-3e8x 2010/06/28 18:18:55.992 4:08:58.291 Opal Liste...0xb49c1b70 Opal Transport clean up on termination 2010/06/28 18:18:55.992 4:08:58.291 Opal Liste...0xb49c1b70 Opal Transport Close 2010/06/28 18:18:55.992 4:08:58.291 Pool:0xb47beb70 SIP Handling PDU "1492 REGISTER <100>" for transaction=z9hG4bKac22c584-d 2010/06/28 18:18:55.992 4:08:58.291 Pool:0xb47beb70 SIP REGISTER transaction id=z9hG4bKac22c584-3e81-df11-9c71-0017a420b5ad. 2010/06/28 18:18:55.992 4:08:58.291 Pool:0xb47beb70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:55.992 4:08:58.291 Pool:0xb47beb70 SIP Handled PDU "1492 REGISTER <100>" 2010/06/28 18:18:55.992 4:08:58.291 Opal Liste...0xb49c1b70 Opal Deleted transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.033 4:08:58.332 Opal Liste...0xb49c1b70 OpalUDP Binding to interface: 192.168.101.120:5060 2010/06/28 18:18:56.033 4:08:58.332 Opal Liste...0xb49c1b70 SIP Waiting for PDU on udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.034 4:08:58.333 Opal Liste...0xb49c1b70 SIP PDU received: rem=udp$89.140.6.13:5060,local=udp$192.168.101.120:50n SIP/2.0 200 OK Date: Mon, 28 Jun 2010 16:18:56 GMT CSeq: 1492 REGISTER Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bKac22c584-3e81-df11-9c71-0017a420b5ad;received=89.140.6.2;rport=65288 User-Agent: Asterisk PBX From: <sip:1723@89.140.6.13>;tag=d65af2bd-1b81-df11-9c71-0017a420b5ad Call-ID: e691f1bd-1b81-df11-9c71-0017a420b5ad@pepinux Supported: replaces To: <sip:1723@89.140.6.13>;tag=as508bec0c Contact: <sip:1723@192.168.101.120>;expires=60 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Expires: 60 Content-Length: 0 2010/06/28 18:18:56.035 4:08:58.334 Opal Liste...0xb49c1b70 SIP Queueing PDU "1492 REGISTER <200>", transaction=z9hG4bKac22c584-3e8x 2010/06/28 18:18:56.035 4:08:58.334 Opal Liste...0xb49c1b70 Opal Transport clean up on termination 2010/06/28 18:18:56.035 4:08:58.334 Opal Liste...0xb49c1b70 Opal Transport Close 2010/06/28 18:18:56.035 4:08:58.334 Pool:0xb47beb70 SIP Handling PDU "1492 REGISTER <200>" for transaction=z9hG4bKac22c584-d 2010/06/28 18:18:56.035 4:08:58.334 Pool:0xb47beb70 SIP REGISTER transaction id=z9hG4bKac22c584-3e81-df11-9c71-0017a420b5ad. 2010/06/28 18:18:56.036 4:08:58.335 Pool:0xb47beb70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:56.036 4:08:58.335 Pool:0xb47beb70 SIP Product Info: name="Asterisk", version="", vendor="", comments="PBX" 2010/06/28 18:18:56.036 4:08:58.335 Pool:0xb47beb70 SIP Changing REGISTER handler from Refreshing to Subscribed, target=sipx 2010/06/28 18:18:56.035 4:08:58.334 Opal Liste...0xb49c1b70 Opal Deleted transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.036 4:08:58.335 Pool:0xb47beb70 SIP Expiry time for REGISTER set to 60 seconds. 2010/06/28 18:18:56.036 4:08:58.335 Pool:0xb47beb70 SIP Product Info: name="Asterisk", version="", vendor="", comments="PBX" 2010/06/28 18:18:56.037 4:08:58.336 Pool:0xb47beb70 SIP Expiry time for SUBSCRIBE set to 3600 seconds. 2010/06/28 18:18:56.037 4:08:58.336 Pool:0xb47beb70 SIP Changing SUBSCRIBE handler from Subscribed to Subscribing, target=sx 2010/06/28 18:18:56.037 4:08:58.336 Pool:0xb47beb70 SIP Transaction created. 2010/06/28 18:18:56.042 4:08:58.341 Pool:0xb47beb70 SIP No SRV lookup as has explicit port number. 2010/06/28 18:18:56.042 4:08:58.341 Pool:0xb47beb70 SIP Transaction remote address is udp$89.140.6.13:5060 2010/06/28 18:18:56.042 4:08:58.341 Pool:0xb47beb70 SIP Sending PDU (627 bytes) to: rem=udp$89.140.6.13:5060,local=udp$192.n SUBSCRIBE sip:asterisk@89.140.6.13 SIP/2.0 Route: <sip:89.140.6.13:5060;lr> CSeq: 500 SUBSCRIBE Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bKec2acd84-3e81-df11-9c71-0017a420b5ad;rport User-Agent: Ekiga/3.2.7 From: <sip:1723@89.140.6.13>;tag=340cfcbd-1b81-df11-9c71-0017a420b5ad Call-ID: 72d3fbbd-1b81-df11-9c71-0017a420b5ad@pepinux To: <sip:1723@89.140.6.13>;tag=as65a38870 Contact: <sip:1723@192.168.101.120> Accept: application/simple-message-summary Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Expires: 3600 Event: message-summary Content-Length: 0 Max-Forwards: 70 2010/06/28 18:18:56.043 4:08:58.342 Pool:0xb47beb70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:56.043 4:08:58.343 Pool:0xb47beb70 SIP Transaction timers set: retry=0.500, completion=6.000 2010/06/28 18:18:56.044 4:08:58.343 Pool:0xb47beb70 SIP Handled PDU "1492 REGISTER <200>" 2010/06/28 18:18:56.046 4:08:58.345 Opal Liste...0xb49c1b70 OpalUDP Binding to interface: 192.168.101.120:5060 2010/06/28 18:18:56.046 4:08:58.346 Opal Liste...0xb49c1b70 SIP Waiting for PDU on udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.047 4:08:58.347 Opal Liste...0xb49c1b70 SIP PDU received: rem=udp$89.140.6.13:5060,local=udp$192.168.101.120:50n SIP/2.0 200 OK CSeq: 500 SUBSCRIBE Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bKec2acd84-3e81-df11-9c71-0017a420b5ad;received=89.140.6.2;rport=65288 User-Agent: Asterisk PBX From: <sip:1723@89.140.6.13>;tag=340cfcbd-1b81-df11-9c71-0017a420b5ad Call-ID: 72d3fbbd-1b81-df11-9c71-0017a420b5ad@pepinux Supported: replaces To: <sip:1723@89.140.6.13>;tag=as65a38870 Contact: <sip:asterisk@89.140.6.13>;expires=3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Expires: 3600 Content-Length: 0 2010/06/28 18:18:56.049 4:08:58.348 Opal Liste...0xb49c1b70 SIP Queueing PDU "500 SUBSCRIBE <200>", transaction=z9hG4bKec2acd84-3e8x 2010/06/28 18:18:56.049 4:08:58.348 Pool:0xb47beb70 SIP Handling PDU "500 SUBSCRIBE <200>" for transaction=z9hG4bKec2acd84-d 2010/06/28 18:18:56.049 4:08:58.348 Pool:0xb47beb70 SIP SUBSCRIBE transaction id=z9hG4bKec2acd84-3e81-df11-9c71-0017a420b5a. 2010/06/28 18:18:56.049 4:08:58.348 Pool:0xb47beb70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:56.049 4:08:58.348 Pool:0xb47beb70 SIP Expiry time for SUBSCRIBE set to 3600 seconds. 2010/06/28 18:18:56.050 4:08:58.349 Pool:0xb47beb70 SIP Product Info: name="Asterisk", version="", vendor="", comments="PBX" 2010/06/28 18:18:56.050 4:08:58.349 Pool:0xb47beb70 SIP Changing SUBSCRIBE handler from Subscribing to Subscribed, target=sx 2010/06/28 18:18:56.050 4:08:58.349 Pool:0xb47beb70 SIP Set Request URI to sip:asterisk@89.140.6.13 2010/06/28 18:18:56.050 4:08:58.349 Pool:0xb47beb70 SIP Product Info: name="Asterisk", version="", vendor="", comments="PBX" 2010/06/28 18:18:56.051 4:08:58.350 Pool:0xb47beb70 SIP Handled PDU "500 SUBSCRIBE <200>" 2010/06/28 18:18:56.051 4:08:58.350 Opal Liste...0xb49c1b70 Opal Transport clean up on termination 2010/06/28 18:18:56.051 4:08:58.350 Opal Liste...0xb49c1b70 Opal Transport Close 2010/06/28 18:18:56.051 4:08:58.350 Opal Liste...0xb49c1b70 Opal Deleted transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.052 4:08:58.352 Opal Liste...0xb49c1b70 OpalUDP Binding to interface: 192.168.101.120:5060 2010/06/28 18:18:56.053 4:08:58.352 Opal Liste...0xb49c1b70 SIP Waiting for PDU on udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.054 4:08:58.353 Opal Liste...0xb49c1b70 SIP PDU received: rem=udp$89.140.6.13:5060,local=udp$192.168.101.120:50n NOTIFY sip:1723@192.168.101.120 SIP/2.0 CSeq: 599 NOTIFY Via: SIP/2.0/UDP 89.140.6.13:5060;branch=z9hG4bK42e204eb;rport User-Agent: Asterisk PBX From: "asterisk" <sip:asterisk@89.140.6.13>;tag=as65a38870 Call-ID: 72d3fbbd-1b81-df11-9c71-0017a420b5ad@pepinux To: <sip:sip:1723@192.168.101.120>;tag=340cfcbd-1b81-df11-9c71-0017a420b5ad Contact: <sip:asterisk@89.140.6.13> Subscription-State: active Event: message-summary Content-Type: application/simple-message-summary Content-Length: 91 Max-Forwards: 70 Messages-Waiting: no Message-Account: sip:asterisk@89.140.6.13 Voice-Message: 0/0 (0/0) 2010/06/28 18:18:56.055 4:08:58.354 Opal Liste...0xb49c1b70 SIP Received NOTIFY message-summary 2010/06/28 18:18:56.055 4:08:58.354 Opal Liste...0xb49c1b70 SIP Found a SUBSCRIBE corresponding to the NOTIFY message-summary 2010/06/28 18:18:56.055 4:08:58.354 Opal Liste...0xb49c1b70 SIP Subscription is active 2010/06/28 18:18:56.057 4:08:58.356 Opal Liste...0xb49c1b70 SIP Sending PDU (347 bytes) to: rem=udp$89.140.6.13:5060,local=udp$192.n SIP/2.0 200 OK CSeq: 599 NOTIFY Via: SIP/2.0/UDP 89.140.6.13:5060;branch=z9hG4bK42e204eb;rport From: "asterisk" <sip:asterisk@89.140.6.13>;tag=as65a38870 Call-ID: 72d3fbbd-1b81-df11-9c71-0017a420b5ad@pepinux To: <sip:sip:1723@192.168.101.120>;tag=340cfcbd-1b81-df11-9c71-0017a420b5ad Contact: <sip:sip@192.168.101.120> Content-Length: 0 2010/06/28 18:18:56.058 4:08:58.357 Opal Liste...0xb49c1b70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:56.058 4:08:58.357 Opal Liste...0xb49c1b70 Opal Transport clean up on termination 2010/06/28 18:18:56.058 4:08:58.357 Opal Liste...0xb49c1b70 Opal Transport Close 2010/06/28 18:18:56.058 4:08:58.358 Opal Liste...0xb49c1b70 Opal Deleted transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.112 4:08:58.411 SIP Changing PUBLISH handler from Unavailable to Subscribing, target=six 2010/06/28 18:18:56.112 4:08:58.411 OpalUDP Binding to interface: 0.0.0.0:5060 2010/06/28 18:18:56.112 4:08:58.411 SIP Created transport udp$89.140.6.13:5060<if=udp$*:5060> 2010/06/28 18:18:56.113 4:08:58.412 OpalUDP Started connect to 89.140.6.13:5060 2010/06/28 18:18:56.113 4:08:58.412 OpalUDP Writing to interface 0 - "192.168.101.120%br-lan" 2010/06/28 18:18:56.113 4:08:58.412 SIP Transaction created. 2010/06/28 18:18:56.118 4:08:58.417 SIP Transaction remote address is udp$89.140.6.13:5060 2010/06/28 18:18:56.119 4:08:58.418 SIP Sending PDU (804 bytes) to: rem=udp$89.140.6.13:5060,local=udp$192.n PUBLISH sip:1723@89.140.6.13 SIP/2.0 CSeq: 1493 PUBLISH Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bK56d8d884-3e81-df11-9c71-0017a420b5ad;rport User-Agent: Ekiga/3.2.7 From: <sip:1723@89.140.6.13>;tag=2681d884-3e81-df11-9c71-0017a420b5ad Call-ID: f633d884-3e81-df11-9c71-0017a420b5ad@pepinux To: <sip:1723@89.140.6.13> Contact: <sip:1723@192.168.101.120> Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 326 Max-Forwards: 70 <?xml version="1.0" encoding="UTF-8"?> <presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:1723@89.140.6.13"> <tuple id="sip:1723@89.140.6.13_on_pepinux"> <note>online - I'm online using Ekiga</note> <status> <basic>open</basic> </status> <contact priority="1">1723@89.140.6.13</contact> </tuple> </presence> 2010/06/28 18:18:56.119 4:08:58.418 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:56.120 4:08:58.419 SIP Transaction timers set: retry=0.500, completion=6.000 2010/06/28 18:18:56.120 4:08:58.419 OpalUDP Writing to interface 1 - "10.4.0.120%br-gest" 2010/06/28 18:18:56.120 4:08:58.419 SIP Transaction created. 2010/06/28 18:18:56.121 4:08:58.420 Opal Liste...0xb49c1b70 OpalUDP Binding to interface: 192.168.101.120:5060 2010/06/28 18:18:56.121 4:08:58.420 Opal Liste...0xb49c1b70 SIP Waiting for PDU on udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.122 4:08:58.421 Opal Liste...0xb49c1b70 SIP PDU received: rem=udp$89.140.6.13:5060,local=udp$192.168.101.120:50n SIP/2.0 501 Method Not Implemented CSeq: 1493 PUBLISH Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bK56d8d884-3e81-df11-9c71-0017a420b5ad;received=89.140.6.2;rport=65288 User-Agent: Asterisk PBX From: <sip:1723@89.140.6.13>;tag=2681d884-3e81-df11-9c71-0017a420b5ad Call-ID: f633d884-3e81-df11-9c71-0017a420b5ad@pepinux Supported: replaces To: <sip:1723@89.140.6.13>;tag=as1640d3af Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 2010/06/28 18:18:56.123 4:08:58.422 Opal Liste...0xb49c1b70 SIP Queueing PDU "1493 PUBLISH <501>", transaction=z9hG4bK56d8d884-3e81x 2010/06/28 18:18:56.123 4:08:58.422 Opal Liste...0xb49c1b70 Opal Transport clean up on termination 2010/06/28 18:18:56.123 4:08:58.422 Opal Liste...0xb49c1b70 Opal Transport Close 2010/06/28 18:18:56.123 4:08:58.423 Pool:0xb47beb70 SIP Handling PDU "1493 PUBLISH <501>" for transaction=z9hG4bK56d8d884-3d 2010/06/28 18:18:56.124 4:08:58.423 Pool:0xb47beb70 SIP PUBLISH transaction id=z9hG4bK56d8d884-3e81-df11-9c71-0017a420b5ad . 2010/06/28 18:18:56.124 4:08:58.423 Opal Liste...0xb49c1b70 Opal Deleted transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.126 4:08:58.425 SIP Transaction remote address is udp$89.140.6.13:5060 2010/06/28 18:18:56.126 4:08:58.425 SIP Sending PDU (794 bytes) to: rem=udp$89.140.6.13:5060,local=udp$10.4t PUBLISH sip:1723@89.140.6.13 SIP/2.0 CSeq: 1494 PUBLISH Via: SIP/2.0/UDP 10.4.0.120:5060;branch=z9hG4bKbae6d984-3e81-df11-9c71-0017a420b5ad;rport User-Agent: Ekiga/3.2.7 From: <sip:1723@89.140.6.13>;tag=0a88d984-3e81-df11-9c71-0017a420b5ad Call-ID: f633d884-3e81-df11-9c71-0017a420b5ad@pepinux To: <sip:1723@89.140.6.13> Contact: <sip:1723@10.4.0.120> Expires: 500 Event: presence Content-Type: application/pidf+xml Content-Length: 326 Max-Forwards: 70 <?xml version="1.0" encoding="UTF-8"?> <presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:1723@89.140.6.13"> <tuple id="sip:1723@89.140.6.13_on_pepinux"> <note>online - I'm online using Ekiga</note> <status> <basic>open</basic> </status> <contact priority="1">1723@89.140.6.13</contact> </tuple> </presence> 2010/06/28 18:18:56.127 4:08:58.426 OpalUDP Setting interface to 10.4.0.120%br-gest 2010/06/28 18:18:56.127 4:08:58.426 SIP Transaction timers set: retry=0.500, completion=6.000 2010/06/28 18:18:56.127 4:08:58.426 Pool:0xb47beb70 SIP PUBLISH transaction id=z9hG4bKbae6d984-3e81-df11-9c71-0017a420b5ad . 2010/06/28 18:18:56.127 4:08:58.426 Pool:0xb47beb70 SIP Set state Terminated_Aborted for PUBLISH transaction id=z9hG4bKbae6d 2010/06/28 18:18:56.127 4:08:58.426 Pool:0xb47beb70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/06/28 18:18:56.127 4:08:58.426 Pool:0xb47beb70 SIP Not retrying PUBLISH due to error response 501 Not Implemented 2010/06/28 18:18:56.127 4:08:58.426 Pool:0xb47beb70 SIP Changing PUBLISH handler from Subscribing to Unsubscribed, target=sx 2010/06/28 18:18:56.127 4:08:58.426 Pool:0xb47beb70 SIP Handled PDU "1493 PUBLISH <501>" 2010/06/28 18:18:56.389 4:08:58.688 Opal Garbage:0xb4a84b70 SIP Transaction id=z9hG4bKbae6d984-3e81-df11-9c71-0017a420b5ad destroye. 2010/06/28 18:18:56.389 4:08:58.688 Opal Garbage:0xb4a84b70 SIP Deleting SIPPublishHandler sip:1723@89.140.6.13 2010/06/28 18:18:56.389 4:08:58.688 Opal Garbage:0xb4a84b70 Opal Transport clean up on termination 2010/06/28 18:18:56.389 4:08:58.688 Opal Garbage:0xb4a84b70 Opal Transport Close 2010/06/28 18:18:56.389 4:08:58.688 Opal Garbage:0xb4a84b70 Opal Transport clean up on termination 2010/06/28 18:18:56.389 4:08:58.688 Opal Garbage:0xb4a84b70 Opal Transport Close 2010/06/28 18:18:56.390 4:08:58.689 Opal Garbage:0xb4a84b70 Opal Deleted transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:18:56.390 4:08:58.689 Opal Garbage:0xb4a84b70 SIP Deleted handler. 2010/06/28 18:18:57.044 4:08:59.343 Housekeeper:0xb4a02b70 SIP Set state Terminated_Success for REGISTER transaction id=z9hG4bKac2d 2010/06/28 18:18:57.051 4:08:59.350 Housekeeper:0xb4a02b70 SIP Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKecd 2010/06/28 18:18:57.128 4:08:59.427 Housekeeper:0xb4a02b70 SIP Set state Terminated_Success for PUBLISH transaction id=z9hG4bK56d8d 2010/06/28 18:18:57.391 4:08:59.690 Opal Garbage:0xb4a84b70 SIP Transaction id=z9hG4bK56d8d884-3e81-df11-9c71-0017a420b5ad destroye. 2010/06/28 18:18:57.391 4:08:59.690 Opal Garbage:0xb4a84b70 SIP Transaction id=z9hG4bKec2acd84-3e81-df11-9c71-0017a420b5ad destroye. 2010/06/28 18:18:57.391 4:08:59.691 Opal Garbage:0xb4a84b70 SIP Transaction id=z9hG4bKac22c584-3e81-df11-9c71-0017a420b5ad destroye. ======= Now I called 500@ekiga.net, and then ekiga worked again ==== 2010/06/28 18:19:18.279 4:09:20.578 OpalMan Set up call from pc:* to sip:500@ekiga.net 2010/06/28 18:19:18.280 4:09:20.579 Call Created Call[c09ed04285] 2010/06/28 18:19:18.280 4:09:20.579 OpalMan Set up connection to "pc:*" 2010/06/28 18:19:18.280 4:09:20.579 OpalCon Created connection Call[c09ed04285]-EP<pc>[jdfd07bc96] 2010/06/28 18:19:18.280 4:09:20.579 Silence Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200 2010/06/28 18:19:18.280 4:09:20.580 Silence Handler created 2010/06/28 18:19:18.281 4:09:20.580 Echo Canceler Handler created 2010/06/28 18:19:18.281 4:09:20.580 PCSS Created PC sound system connection: token="jdfd07bc96" player="EKIG" 2010/06/28 18:19:18.281 4:09:20.580 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[c09ed04285]] 2010/06/28 18:19:18.281 4:09:20.580 OpalMan OnIncoming connection Call[c09ed04285]-EP<pc>[jdfd07bc96] 2010/06/28 18:19:18.281 4:09:20.580 OpalCon Applying string options: 2010/06/28 18:19:18.281 4:09:20.580 Call GetOtherPartyConnection Call[c09ed04285]-EP<pc>[jdfd07bc96] 2010/06/28 18:19:18.281 4:09:20.580 OpalMan Searching for route "pc:javi sip:500@ekiga.net" 2010/06/28 18:19:18.281 4:09:20.580 OpalMan Set up connection to "sip:500@ekiga.net" 2010/06/28 18:19:18.282 4:09:20.581 OpalCon Created connection Call[c09ed04285]-EP<sip>[e40b0f92-3e81-df11-9c71] 2010/06/28 18:19:18.282 4:09:20.581 RFC2833 Handler created 2010/06/28 18:19:18.282 4:09:20.581 RFC2833 Handler created 2010/06/28 18:19:18.282 4:09:20.581 SIP Created connection. 2010/06/28 18:19:18.282 4:09:20.581 PCSS Outgoing call routed to sip:500@ekiga.net for Call[c09ed04285]-EP<p] 2010/06/28 18:19:18.282 4:09:20.582 OpalMan SetUpCall succeeded, call=Call[c09ed04285] 2010/06/28 18:19:18.283 4:09:20.582 CallSetup:0xb05ffb70 Call OnSetUp Call[c09ed04285]-EP<pc>[jdfd07bc96] 2010/06/28 18:19:18.283 4:09:20.582 CallSetup:0xb05ffb70 SIP SetUpConnection: sip:500@ekiga.net 2010/06/28 18:19:18.283 4:09:20.582 CallSetup:0xb05ffb70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[c09ed04285]] 2010/06/28 18:19:18.283 4:09:20.582 CallSetup:0xb05ffb70 OpalCon Applying string options: 2010/06/28 18:19:18.283 4:09:20.583 CallSetup:0xb05ffb70 SIP Found registrar on domain 89.140.6.13, using interface 192.168.101.n 2010/06/28 18:19:18.284 4:09:20.583 CallSetup:0xb05ffb70 OpalUDP Binding to interface: 192.168.101.120:5060 2010/06/28 18:19:18.284 4:09:20.584 CallSetup:0xb05ffb70 SIP Created transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/06/28 18:19:18.285 4:09:20.584 CallSetup:0xb05ffb70 OpalUDP Started connect to 89.140.6.13:5060 2010/06/28 18:19:18.286 4:09:20.585 CallSetup:0xb05ffb70 SIP Updating dialog tag from "" to "e40b0f92-3e81-df11-9c71-0017a420b5a" 2010/06/28 18:19:18.287 4:09:20.586 Call Call[abe3606593] destroyed. 2010/06/28 18:19:18.295 4:09:20.594 CallSetup:0xb05ffb70 SIP INVITE transaction id=z9hG4bK1e1b1192-3e81-df11-9c71-0017a420b5ad c. 2010/06/28 18:19:18.295 4:09:20.594 CallSetup:0xb05ffb70 SIP Creating INVITE request 2010/06/28 18:19:18.301 4:09:20.600 CallSetup:0xb05ffb70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.72z 2010/06/28 18:19:18.303 4:09:20.602 CallSetup:0xb05ffb70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.72z 2010/06/28 18:19:18.303 4:09:20.602 CallSetup:0xb05ffb70 Call GetMediaFormats for Call[c09ed04285]-EP<sip>[e40b0f92-3e81-df11-9c7] G.711-ALaw-64k GSM-06.10 CELT-48K CELT-32K theora H.261 H.261-CIF H.261-QCIF Thanks !
I think there is a variable unset in the code, I will check later, I do not have time right now.
Hi, I hit again with this bug (this time it doesn't recover with 500@ekiga.net call). See logs (it is not included the 500@ekiga.net call, but all tries (1?) are not getting dial). There is another issue: In this state, I try to close ekiga, but it doesn't dissapear from systray :( so I have to kill ekiga Thank you ! Logs: 2010/08/19 10:40:43.661 43:42:45.565 OpalMan Set up call from pc:* to sip:1999@89.140.6.13 2010/08/19 10:40:43.662 43:42:45.565 Call Created Call[bf46cc58c15] 2010/08/19 10:40:43.662 43:42:45.565 OpalMan Set up connection to "pc:*" 2010/08/19 10:40:43.662 43:42:45.566 OpalCon Created connection Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:43.662 43:42:45.566 Silence Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800 2010/08/19 10:40:43.662 43:42:45.566 Silence Handler created 2010/08/19 10:40:43.674 43:42:45.578 Echo Canceler Handler created 2010/08/19 10:40:43.675 43:42:45.578 PCSS Created PC sound system connection: token="r06e4dc3416" player="EKIGA" recorder="EKIGA" 2010/08/19 10:40:43.675 43:42:45.578 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:43.675 43:42:45.578 OpalMan OnIncoming connection Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:43.675 43:42:45.578 OpalCon Applying string options: 2010/08/19 10:40:43.675 43:42:45.578 Call GetOtherPartyConnection Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:43.675 43:42:45.579 OpalMan Searching for route "pc:javi sip:1999@89.140.6.13" 2010/08/19 10:40:43.675 43:42:45.579 OpalMan Set up connection to "sip:1999@89.140.6.13" 2010/08/19 10:40:43.676 43:42:45.579 OpalCon Created connection Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:43.676 43:42:45.579 RFC2833 Handler created 2010/08/19 10:40:43.676 43:42:45.579 RFC2833 Handler created 2010/08/19 10:40:43.676 43:42:45.580 SIP Created connection. 2010/08/19 10:40:43.677 43:42:45.580 PCSS Outgoing call routed to sip:1999@89.140.6.13 for Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:43.677 43:42:45.580 OpalMan SetUpCall succeeded, call=Call[bf46cc58c15] 2010/08/19 10:40:43.677 43:42:45.580 CallSetup:0xb04beb70 Call OnSetUp Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:43.677 43:42:45.581 CallSetup:0xb04beb70 SIP SetUpConnection: sip:1999@89.140.6.13 2010/08/19 10:40:43.677 43:42:45.581 CallSetup:0xb04beb70 OpalCon SetPhase from UninitialisedPhase to SetUpPhase for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:43.677 43:42:45.581 CallSetup:0xb04beb70 OpalCon Applying string options: 2010/08/19 10:40:43.678 43:42:45.581 CallSetup:0xb04beb70 SIP Found registrar on domain 89.140.6.13, using interface 192.168.101.120%br-lan 2010/08/19 10:40:43.678 43:42:45.581 CallSetup:0xb04beb70 PWLib File handle high water mark set: 86 PUDPSocket 2010/08/19 10:40:43.678 43:42:45.582 CallSetup:0xb04beb70 OpalUDP Binding to interface: 192.168.101.120:5060 2010/08/19 10:40:43.679 43:42:45.582 CallSetup:0xb04beb70 SIP Created transport udp$89.140.6.13:5060<if=udp$192.168.101.120:5060> 2010/08/19 10:40:43.679 43:42:45.583 CallSetup:0xb04beb70 OpalUDP Started connect to 89.140.6.13:5060 2010/08/19 10:40:43.680 43:42:45.583 CallSetup:0xb04beb70 SIP Updating dialog tag from "" to "ca628e1f-dba9-df11-9306-0017a420b5ad" 2010/08/19 10:40:43.687 43:42:45.590 CallSetup:0xb04beb70 SIP INVITE transaction id=z9hG4bK1e17901f-dba9-df11-9306-0017a420b5ad created. 2010/08/19 10:40:43.687 43:42:45.591 CallSetup:0xb04beb70 SIP Creating INVITE request 2010/08/19 10:40:43.694 43:42:45.598 CallSetup:0xb04beb70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2010/08/19 10:40:43.696 43:42:45.599 CallSetup:0xb04beb70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2010/08/19 10:40:43.696 43:42:45.599 CallSetup:0xb04beb70 Call GetMediaFormats for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] G.711-ALaw-64k GSM-06.10 CELT-48K CELT-32K theora H.261 H.261-CIF H.261-QCIF 2010/08/19 10:40:43.696 43:42:45.600 CallSetup:0xb04beb70 SIP Offering media type audio in SDP with formats G.711-ALaw-64k,GSM-06.10,CELT-48K,CELT-32K,theora,H.261,H.261-CIF,H.261-QCIF 2010/08/19 10:40:43.696 43:42:45.600 CallSetup:0xb04beb70 Call IsMediaBypassPossible Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] session 1 2010/08/19 10:40:43.696 43:42:45.600 CallSetup:0xb04beb70 OpalMan IsMediaBypassPossible: session 1 2010/08/19 10:40:43.696 43:42:45.600 CallSetup:0xb04beb70 OpalCon IsMediaBypassPossible: default returns false 2010/08/19 10:40:43.697 43:42:45.600 CallSetup:0xb04beb70 RTP Cannot find media session 1 2010/08/19 10:40:43.697 43:42:45.600 CallSetup:0xb04beb70 RTP Cannot find RTP session 1 2010/08/19 10:40:43.698 43:42:45.601 CallSetup:0xb04beb70 RTP_UDP Session 1, created with NAT flag set to 0 2010/08/19 10:40:43.698 43:42:45.601 CallSetup:0xb04beb70 PWLib File handle high water mark set: 87 PUDPSocket 2010/08/19 10:40:43.698 43:42:45.601 CallSetup:0xb04beb70 PWLib File handle low water mark set: 86 PUDPSocket 2010/08/19 10:40:43.698 43:42:45.602 CallSetup:0xb04beb70 RTP_UDP Session 1 created: 192.168.101.120:5084-5085 ssrc=1496792300 2010/08/19 10:40:43.699 43:42:45.602 CallSetup:0xb04beb70 PWLib File handle high water mark set: 88 PUDPSocket 2010/08/19 10:40:43.699 43:42:45.602 CallSetup:0xb04beb70 RTP Creating new session RTP_UDP 2010/08/19 10:40:43.699 43:42:45.602 CallSetup:0xb04beb70 RTP Found existing media session 1 2010/08/19 10:40:43.700 43:42:45.603 CallSetup:0xb04beb70 SIP Using default RTP payload [pt=101] for UserInput/RFC2833 2010/08/19 10:40:43.700 43:42:45.604 CallSetup:0xb04beb70 SIP Using default RTP payload [pt=122] for NamedSignalEvent 2010/08/19 10:40:43.705 43:42:45.608 CallSetup:0xb04beb70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2010/08/19 10:40:43.706 43:42:45.609 CallSetup:0xb04beb70 MediaFormat Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz 2010/08/19 10:40:43.706 43:42:45.610 CallSetup:0xb04beb70 Call GetMediaFormats for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] G.711-ALaw-64k GSM-06.10 CELT-48K CELT-32K theora H.261 H.261-CIF H.261-QCIF 2010/08/19 10:40:43.707 43:42:45.610 CallSetup:0xb04beb70 SIP Offering media type video in SDP with formats G.711-ALaw-64k,GSM-06.10,CELT-48K,CELT-32K,theora,H.261,H.261-CIF,H.261-QCIF 2010/08/19 10:40:43.707 43:42:45.610 CallSetup:0xb04beb70 Call IsMediaBypassPossible Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] session 2 2010/08/19 10:40:43.707 43:42:45.610 CallSetup:0xb04beb70 OpalMan IsMediaBypassPossible: session 2 2010/08/19 10:40:43.707 43:42:45.610 CallSetup:0xb04beb70 OpalCon IsMediaBypassPossible: default returns false 2010/08/19 10:40:43.707 43:42:45.610 CallSetup:0xb04beb70 RTP Cannot find media session 2 2010/08/19 10:40:43.707 43:42:45.610 CallSetup:0xb04beb70 RTP Cannot find RTP session 2 2010/08/19 10:40:43.708 43:42:45.611 CallSetup:0xb04beb70 RTP_UDP Session 2, created with NAT flag set to 0 2010/08/19 10:40:43.708 43:42:45.612 CallSetup:0xb04beb70 PWLib File handle high water mark set: 89 PUDPSocket 2010/08/19 10:40:43.708 43:42:45.612 CallSetup:0xb04beb70 PWLib File handle low water mark set: 88 PUDPSocket 2010/08/19 10:40:43.708 43:42:45.612 CallSetup:0xb04beb70 RTP_UDP Session 2 created: 192.168.101.120:5086-5087 ssrc=1102975294 2010/08/19 10:40:43.709 43:42:45.612 CallSetup:0xb04beb70 PWLib File handle high water mark set: 90 PUDPSocket 2010/08/19 10:40:43.709 43:42:45.612 CallSetup:0xb04beb70 RTP Creating new session RTP_UDP 2010/08/19 10:40:43.709 43:42:45.613 CallSetup:0xb04beb70 RTP Found existing media session 2 2010/08/19 10:40:43.710 43:42:45.614 CallSetup:0xb04beb70 SDP SDP not including H.261-CIF as it is not a SIP transportable format 2010/08/19 10:40:43.710 43:42:45.614 CallSetup:0xb04beb70 SDP SDP not including H.261-QCIF as it is not a SIP transportable format 2010/08/19 10:40:43.711 43:42:45.614 CallSetup:0xb04beb70 SIP No SRV lookup as has explicit port number. 2010/08/19 10:40:43.711 43:42:45.614 CallSetup:0xb04beb70 SIP Transaction remote address is udp$89.140.6.13:5060 2010/08/19 10:40:43.711 43:42:45.615 CallSetup:0xb04beb70 OpalPlugin to_customised_options: theora 2010/08/19 10:40:43.712 43:42:45.616 CallSetup:0xb04beb70 OpalPlugin to_customised_options: H.261 2010/08/19 10:40:43.713 43:42:45.617 CallSetup:0xb04beb70 OpalPlugin to_customised_options changed option "Frame Width" from "320" to "176" 2010/08/19 10:40:43.713 43:42:45.617 CallSetup:0xb04beb70 OpalPlugin to_customised_options changed option "Frame Height" from "240" to "144" 2010/08/19 10:40:43.714 43:42:45.617 CallSetup:0xb04beb70 SIP Sending PDU (1135 bytes) to: rem=udp$89.140.6.13:5060,local=udp$192.168.101.120:5060,if=192.168.101.120%br-lan INVITE sip:1999@89.140.6.13 SIP/2.0 Route: <sip:89.140.6.13:5060;lr> Date: Thu, 19 Aug 2010 08:40:43 GMT CSeq: 1 INVITE Via: SIP/2.0/UDP 192.168.101.120:5060;branch=z9hG4bK1e17901f-dba9-df11-9306-0017a420b5ad;rport User-Agent: Ekiga/3.2.7 From: "Javier Barroso" <sip:1723@89.140.6.13>;tag=ca628e1f-dba9-df11-9306-0017a420b5ad Call-ID: 1c758e1f-dba9-df11-9306-0017a420b5ad@pepinux To: <sip:1999@89.140.6.13> Contact: <sip:1723@192.168.101.120> Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING Content-Type: application/sdp Content-Length: 531 Max-Forwards: 70 v=0 o=- 1282207243 1 IN IP4 192.168.101.120 s=Opal SIP Session c=IN IP4 192.168.101.120 t=0 0 m=audio 5084 RTP/AVP 8 3 119 118 101 122 a=sendrecv a=rtpmap:8 PCMA/8000/1 a=rtpmap:3 gsm/8000/1 a=rtpmap:119 CELT/48000/1 a=rtpmap:118 CELT/32000/1 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16,32,36 a=rtpmap:122 NSE/8000 a=fmtp:122 192-193 m=video 5086 RTP/AVP 121 31 b=AS:4096 b=TIAS:4096000 a=sendrecv a=rtpmap:121 theora/90000 a=fmtp:121 height=576;width=704 a=rtpmap:31 h261/90000 a=fmtp:31 CIF=1;QCIF=1 2010/08/19 10:40:43.715 43:42:45.618 CallSetup:0xb04beb70 OpalUDP Setting interface to 192.168.101.120%br-lan 2010/08/19 10:40:43.715 43:42:45.618 CallSetup:0xb04beb70 SIP Transaction timers set: retry=0.500, completion=1:00.000 2010/08/19 10:40:43.715 43:42:45.618 CallSetup:0xb04beb70 OpalCon OnSetUpConnectionCall[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:43.715 43:42:45.619 CallSetup:0xb04beb70 OpalEP OnSetUpConnection Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:50.292 43:42:52.195 Network In...0xb4a52b70 PWLib File handle low water mark set: 68 PUDPSocket 2010/08/19 10:40:57.507 43:42:59.411 Call Clearing Call[bf46cc58c15] reason=EndedByLocalUser 2010/08/19 10:40:57.507 43:42:59.411 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:57.508 43:42:59.411 OpalCon Releasing Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:57.508 43:42:59.411 OpalCon Call end reason for Call[bf46cc58c15]-EP<pc>[r06e4dc3416] set to EndedByLocalUser 2010/08/19 10:40:57.508 43:42:59.411 OpalCon OnReleased Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:57.508 43:42:59.411 OpalEP OnReleased Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:57.508 43:42:59.411 OpalMan OnReleased Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:57.508 43:42:59.411 Call OnReleased Call[bf46cc58c15]-EP<pc>[r06e4dc3416] 2010/08/19 10:40:57.508 43:42:59.411 OpalCon SetPhase from SetUpPhase to ReleasingPhase for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.508 43:42:59.411 OpalCon Releasing Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.508 43:42:59.411 OpalCon Call end reason for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] set to EndedByLocalUser 2010/08/19 10:40:57.508 43:42:59.412 OpalCon Media streams closed. 2010/08/19 10:40:57.508 43:42:59.412 OnRelease:0xb04beb70 SIP OnReleased: Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad], phase = ReleasingPhase 2010/08/19 10:40:57.509 43:42:59.412 OnRelease:0xb04beb70 OpalCon SetPhase from ReleasingPhase to ReleasingPhase for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.509 43:42:59.412 OnRelease:0xb04beb70 SIP Cancelling 1 transactions. 2010/08/19 10:40:57.509 43:42:59.412 OnRelease:0xb04beb70 SIP INVITE transaction id=z9hG4bK1e17901f-dba9-df11-9306-0017a420b5ad aborted. 2010/08/19 10:40:57.509 43:42:59.412 OnRelease:0xb04beb70 SIP Set state Terminated_Aborted for INVITE transaction id=z9hG4bK1e17901f-dba9-df11-9306-0017a420b5ad 2010/08/19 10:40:57.509 43:42:59.412 OnRelease:0xb04beb70 SIP Response received for unknown handler ID: 1c758e1f-dba9-df11-9306-0017a420b5ad@pepinux 2010/08/19 10:40:57.510 43:42:59.414 OnRelease:0xb04beb70 OpalCon Media streams closed. 2010/08/19 10:40:57.510 43:42:59.414 OnRelease:0xb04beb70 SIP Awaiting forked INVITE transaction completion, id=z9hG4bK1e17901f-dba9-df11-9306-0017a420b5ad 2010/08/19 10:40:57.511 43:42:59.414 OnRelease:0xb04beb70 OpalCon SetPhase from ReleasingPhase to ReleasedPhase for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.511 43:42:59.414 OnRelease:0xb04beb70 OpalCon OnReleased Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.511 43:42:59.414 OnRelease:0xb04beb70 OpalEP OnReleased Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.511 43:42:59.414 OnRelease:0xb04beb70 OpalMan OnReleased Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.511 43:42:59.415 OnRelease:0xb04beb70 Call OnReleased Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.511 43:42:59.415 OnRelease:0xb04beb70 OpalCon Media streams closed. 2010/08/19 10:40:57.511 43:42:59.415 OnRelease:0xb04beb70 OpalCon OnRelease thread completed for Call[bf46cc58c15]-EP<sip>[ca628e1f-dba9-df11-9306-0017a420b5ad] 2010/08/19 10:40:57.656 43:42:59.560 AEScheduler Removing Event incoming_call_sound from queue 2010/08/19 10:40:57.657 43:42:59.560 AEScheduler Removing Event ring_tone_sound from queue 2010/08/19 10:40:57.657 43:42:59.560 OpalMan OnClearedCall Call[bf46cc58c15] from "pc:javi" to "sip:1999@89.140.6.13" 2010/08/19 10:40:57.657 43:42:59.560 OPAL Attempting to close recording on call bf46cc58c15 when not recording. ** (ekiga:14555): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed ** (ekiga:14555): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed ** (ekiga:14555): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed ** (ekiga:14555): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed ** (ekiga:14555): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed ** (ekiga:14555): CRITICAL **: dbus_g_proxy_disconnect_signal: assertion `!DBUS_G_PROXY_DESTROYED (proxy)' failed 2010/08/19 10:41:09.879 43:43:11.782 Network In...0xb4a52b70 IfaceMon Finished interface monitor thread.
Does this error in your case appear only after a missed call? I have just confirmed https://bugzilla.gnome.org/show_bug.cgi?id=629071 ?
Maybe, I didn't get this issue since many time. Close this when the other bug is closed, and I'll reopen If it happen again Thank you very much!
*** This bug has been marked as a duplicate of bug 629071 ***