Call failed on Mac OS X
Issue generated from Tuleap's migration script. Originally submitted by: Maxime Chambreuil (mchambreuil)
Valerias-MacBook-Pro:MacOS val$ ./Ring -v
avcapture 1/1 UVC Camera VendorID_1452 ProductID_34055 0x2440000005ac8507
("/Applications/Ring.app/Contents/Plugins")
[1448132422.576|0x7fff72762300|ringbufferpool.cpp:114 ] Ringbuffer created with id 'audiolayer_id'
[1448132422.586|0x7fff72762300|account_factory.cpp:47 ] registered SIP account
[1448132422.586|0x7fff72762300|account_factory.cpp:51 ] registered IAX account
[1448132422.586|0x7fff72762300|account_factory.cpp:56 ] registered RING account
[1448132422.691|0x7fff72762300|manager.cpp:193 ] Not initialized
[1448132422.714|0x7fff72762300|manager.cpp:193 ] Not initialized
14:00:22.729 os_core_unix.c !pjlib 2.4.5 for POSIX initialized
[1448132422.737|0x7fff72762300|manager.cpp:272 ] pjsip version 2.4.5 for darwin initialized
[1448132422.737|0x7fff72762300|manager.cpp:276 ] GNU TLS version 3.4.6 initialized
[1448132422.743|0x7fff72762300|manager.cpp:282 ] Configuration file path: /Users/val/Library/Application Support/ring/dring.yml
[1448132422.801|0x7fff72762300|system_codec_container.cpp:152] Encoders found: H264 VP8 MP4V-ES H263-1998 opus G722 speex speex speex PCMA PCMU
[1448132422.801|0x7fff72762300|system_codec_container.cpp:153] Decoders found: H264 VP8 MP4V-ES H263-1998 opus G722 speex speex speex PCMA PCMU
[1448132422.816|0x7fff72762300|upnp_context.cpp:128 ] UPnP: using IPv4
[1448132422.828|0x7fff72762300|upnp_context.cpp:139 ] UPnP: initialiazed on 192.168.0.106:49152
[1448132422.854|0x7fff72762300|upnp_context.cpp:151 ] UPnP: ctrlptrHandle=1
[1448132423.719|0x7fff72762300|sipvoiplink.cpp:512 ] Using SIP nameserver: 192.168.0.101
[1448132423.720|0x7fff72762300|siptransport.cpp:188 ] SipTransportBroker@0x7f8340cca5d0
[1448132423.972|0x7fff72762300|sipvoiplink.cpp:593 ] SIPVoIPLink@0x7f83410b7c18
[1448132424.067|0x7fff72762300|sipaccount.cpp:1728 ] Presence enabled for IP2IP : false.
[1448132424.067|0x7fff72762300|sipaccount.cpp:1595 ] Cannot authenticate with empty credentials list
[1448132424.242|0x7fff72762300|ringaccount.cpp:694 ] UPnP: waiting for IGD to register RING account
[1448132424.243|0x7fff72762300|sipaccount.cpp:724 ] doRegister
[1448132424.243|0x7fff72762300|sipaccount.cpp:782 ] doRegister isIP2IP.
[1448132424.243|0x7fff72762300|siptransport.cpp:349 ] Created UDP transport on default : 0.0.0.0:5060
[1448132424.243|0x7fff72762300|siptransport.cpp:82 ] SipTransport@0x7f8340cb0148 {tr=0x7f8340cc16e8 {rc=2}}
Daemon is running
Updating all accounts
[1448132424.249|0x10ac6e000|ringaccount.cpp:1152 ] Generating DH params with 2048 bits
[1448132424.250|0x10acf1000|upnp_context.cpp:210 ] UPnP: check for valid IGD timeout
Loading "8f34b93c358f6f3b"
Account parameter " "Account.presenceSubscribeSupported" " not found
Loading "IP2IP"
[1448132424.409|0x7fff72762300|certstore.cpp:73 ] CertificateStore: loaded 0 local certificates.
[1448132424.413|0x10acf1000|ringaccount.cpp:1024 ] Could not load /Users/val/Library/Caches/ring/8f34b93c358f6f3b/treatedCalls
[1448132424.413|0x10acf1000|ringaccount.cpp:1024 ] Could not load /Users/val/Library/Caches/ring/8f34b93c358f6f3b/treatedMessages
[1448132424.531|0x10ae21000|certstore.cpp:200 ] CertificateStore: loaded 0 certificates from /Users/val/Library/Application Support/Ring/certs//.
QObject::startTimer: Timers can only be used with threads started with QThread
[1448132424.790|0x10acf1000|ringaccount.cpp:1143 ] Loaded 2 values
2015-11-21 14:00:25.426 Ring[1342:34212] INIT HVC
[1448132425.450|0x10ac6e000|ringaccount.cpp:1169 ] Generated DH params with 2048 bits in 1.200713s
2015-11-21 14:00:25.483 Ring[1342:34212] INIT PersonsVC
2015-11-21 14:00:25.499 Ring[1342:34212] INIT SmartView VC
[1448132425.862|0x10acf1000|ringaccount.cpp:794 ] Bootstrap node: 192.95.9.63:4222
[1448132425.862|0x10acf1000|ringaccount.cpp:794 ] Bootstrap node: [2607:5300:60:24bf::3]:4222
[1448132425.863|0x10acf1000|ringaccount.cpp:801 ] Listening on callto:94a2cc16e85a0214425dd7349ce195ac8f630753 : 5104eccf9c878cd37ea96a53722927bb7dfe6bc2
2015-11-21 14:00:26.592 Ring[1342:34212] Init ChatVC
2015-11-21 14:00:26.592 Ring[1342:34212] INIT CurrentCall VC
[1448132427.552|0x7fff72762300|ringaccount.cpp:727 ] Dht status : IPv4 connecting; IPv6 disconnected
[1448132427.600|0x7fff72762300|ringaccount.cpp:754 ] Query for local certificate store: 239e01f6262afbf3c7d276d2c661173ddddc0340: 0 found.
[1448132427.604|0x7fff72762300|ringaccount.cpp:727 ] Dht status : IPv4 connected; IPv6 disconnected
[1448132428.581|0x7fff72762300|ringaccount.cpp:727 ] Dht status : IPv4 connected; IPv6 connecting
Malformatted Address
Malformatted Address
Malformatted Address
QObject::startTimer: Timers can only be used with threads started with QThread
2015-11-21 14:00:59.392 Ring[1342:34212] animateOut
State changing from "New" to "Dialing" on Call(0x7f8340d2bce0)
State changing from "Dialing" to "Searching for" on Call(0x7f8340d2bce0)
Calling "239e01f6262afbf3c7d276d2c661173ddddc0340" with account Account(0x7f8340e0b250) , CallId: Call(0x7f8340d2bce0) , ConfId: Call(0x7f8340d2bce0)
[1448132459.403|0x7fff72762300|manager.cpp:436 ] New outgoing call to 239e01f6262afbf3c7d276d2c661173ddddc0340
[1448132459.403|0x7fff72762300|ringaccount.cpp:178 ] Calling DHT peer 239e01f6262afbf3c7d276d2c661173ddddc0340
[1448132459.403|0x7fff72762300|audiorecord.cpp:84 ] Generate filename for this call 20151121-140059
[1448132459.403|0x7fff72762300|recordable.cpp:29 ] Set recording options:
[1448132459.403|0x7fff72762300|ringbufferpool.cpp:114 ] Ringbuffer created with id '4474401525806323562'
[1448132459.608|0x7fff72762300|upnp_context.cpp:210 ] UPnP: check for valid IGD timeout
[1448132459.608|0x7fff72762300|upnp_context.cpp:210 ] UPnP: check for valid IGD timeout
[1448132459.608|0x7fff72762300|sipaccountbase.cpp:351 ] [Account 8f34b93c358f6f3b] Using public address 66.36.130.205
[1448132459.609|0x1105b4000|ice_transport.cpp:63 ] Registered thread 0x7f8341241800 (0x53E)
[1448132459.609|0x7fff72762300|ice_transport.cpp:256 ] ICE initialization success
[1448132459.609|0x7fff72762300|ice_transport.cpp:317 ] ICE as master
[1448132459.609|0x7fff72762300|ice_transport.cpp:310 ] ICE [local] ufrag=3ddc2e5a, pwd=42673670
[1448132459.610|0x7fff72762300|ice_transport.cpp:624 ] succeed to add candidate for comp_id=1 : 192.168.0.106 : 66.36.130.205
[1448132459.610|0x7fff72762300|manager.cpp:408 ] ----- Switch current call id to '4474401525806323562' -----
Calling action ACCEPT (0) on Call(0x7f8340d2bce0) with state "Dialing" . Become "Searching for"
[1448132459.633|0x7fff72762300|ringaccount.cpp:754 ] Query for local certificate store: 239e01f6262afbf3c7d276d2c661173ddddc0340: 0 found.
[1448132459.633|0x7fff72762300|call.cpp:137 ] [call:4474401525806323562] state change 0/0, cnx 0/1, code 0
[1448132459.685|0x7fff72762300|ringaccount.cpp:754 ] Query for local certificate store: 239e01f6262afbf3c7d276d2c661173ddddc0340: 0 found.
2015-11-21 14:00:59.877 Ring[1342:34212] animateIn
[1448132460.096|0x7fff72762300|videomanager.cpp:82 ] Setting default device to Built-in iSight
[1448132478.643|0x7fff72762300|ringaccount.cpp:231 ] Can't put ICE descriptor on DHT
[1448132478.643|0x7fff72762300|call.cpp:137 ] [call:4474401525806323562] state change 0/4, cnx 1/0, code 0
[1448132478.643|0x7fff72762300|call.cpp:156 ] [call:4474401525806323562] emit client call state change FAILURE, code 0
[1448132478.643|0x7fff72762300|manager.cpp:1755 ] [call:4474401525806323562] Failed
[1448132478.643|0x7fff72762300|corelayer.cpp:287 ] START STREAM
[1448132478.643|0x7fff72762300|corelayer.cpp:97 ] INIT AUDIO PLAYBACK
[1448132480.640|0x7fff72762300|audiolayer.cpp:54 ] Hardware audio format available : {2 channels, 44100Hz}
[1448132480.640|0x7fff72762300|manager.cpp:2265 ] Audio format changed: {1 channels, 16000Hz} -> {2 channels, 44100Hz}
[1448132480.718|0x7fff72762300|corelayer.cpp:159 ] INIT AUDIO INPUT
[1448132481.044|0x7fff72762300|call_factory.cpp:39 ] Removing call 4474401525806323562
[1448132481.044|0x7fff72762300|call_factory.cpp:43 ] Remaining 0 SIP call(s)
[1448132481.044|0x7fff72762300|ringaccount.cpp:564 ] Removing deleted call from pending calls
Call State Changed for call "4474401525806323562" . New state : "FAILURE"
Call found Call(0x7f8340d2bce0) "Searching for"
State changing from "Searching for" to "Failed" on Call(0x7f8340d2bce0)
[1448132481.081|0x7fff72762300|manager.cpp:2572 ] Call is NULL
Warning : call Call(0x7f8340d2bce0) had an unexpected transition of state.( "Failed" )
Stoping call. callId : Call(0x7f8340d2bce0) ConfId: Call(0x7f8340d2bce0)
Calling stateChanged "FAILURE" -> 6 on call with state "Searching for" . Become "Failed"
State changing from "Failed" to "Over" on Call(0x7f8340d2bce0)
2015-11-21 14:01:23.883 Ring[1342:34212] animateOut
[1448132483.885|0x7fff72762300|manager.cpp:547 ] Could not hang up non-existant call 4474401525806323562
[1448132483.885|0x7fff72762300|corelayer.cpp:315 ] STOP STREAM
Cannot remove Call(0x7f8340d2bce0) : call not found in tree
Calling action REFUSE (1) on Call(0x7f8340d2bce0) with state "Failed" . Become "Over"
2015-11-21 14:04:43.893 Ring[1342:34212] animateOut
State changing from "New" to "Dialing" on Call(0x7f83425f3b80)
State changing from "Dialing" to "Searching for" on Call(0x7f83425f3b80)
Calling "239e01f6262afbf3c7d276d2c661173ddddc0340" with account Account(0x7f8340e0b250) , CallId: Call(0x7f83425f3b80) , ConfId: Call(0x7f83425f3b80)
[1448132683.903|0x7fff72762300|manager.cpp:436 ] New outgoing call to 239e01f6262afbf3c7d276d2c661173ddddc0340
[1448132683.903|0x7fff72762300|ringaccount.cpp:178 ] Calling DHT peer 239e01f6262afbf3c7d276d2c661173ddddc0340
[1448132683.903|0x7fff72762300|audiorecord.cpp:84 ] Generate filename for this call 20151121-140443
[1448132683.903|0x7fff72762300|recordable.cpp:29 ] Set recording options:
[1448132683.903|0x7fff72762300|ringbufferpool.cpp:114 ] Ringbuffer created with id '7267063970563432557'
[1448132683.903|0x7fff72762300|upnp_context.cpp:210 ] UPnP: check for valid IGD timeout
[1448132683.903|0x7fff72762300|upnp_context.cpp:210 ] UPnP: check for valid IGD timeout
[1448132683.904|0x110f45000|ice_transport.cpp:63 ] Registered thread 0x7f8341d23800 (0x53E)
[1448132684.143|0x7fff72762300|ice_transport.cpp:256 ] ICE initialization success
[1448132684.143|0x7fff72762300|ice_transport.cpp:317 ] ICE as master
[1448132684.143|0x7fff72762300|ice_transport.cpp:310 ] ICE [local] ufrag=6cb20e3d, pwd=1dc4fe8b
[1448132684.143|0x7fff72762300|ice_transport.cpp:624 ] succeed to add candidate for comp_id=1 : 192.168.0.106 : 66.36.130.205
[1448132684.143|0x7fff72762300|manager.cpp:408 ] ----- Switch current call id to '7267063970563432557' -----
Calling action ACCEPT (0) on Call(0x7f83425f3b80) with state "Dialing" . Become "Searching for"
[1448132684.153|0x7fff72762300|ringaccount.cpp:754 ] Query for local certificate store: 239e01f6262afbf3c7d276d2c661173ddddc0340: 0 found.
[1448132684.154|0x7fff72762300|call.cpp:137 ] [call:7267063970563432557] state change 0/0, cnx 0/1, code 0
[1448132684.202|0x7fff72762300|ringaccount.cpp:754 ] Query for local certificate store: 239e01f6262afbf3c7d276d2c661173ddddc0340: 0 found.
2015-11-21 14:04:44.353 Ring[1342:34212] animateIn
[1448132684.931|0x7fff72762300|ringaccount.cpp:231 ] Can't put ICE descriptor on DHT
[1448132684.931|0x7fff72762300|call.cpp:137 ] [call:7267063970563432557] state change 0/4, cnx 1/0, code 0
[1448132684.931|0x7fff72762300|call.cpp:156 ] [call:7267063970563432557] emit client call state change FAILURE, code 0
[1448132684.931|0x7fff72762300|manager.cpp:1755 ] [call:7267063970563432557] Failed
[1448132684.931|0x7fff72762300|corelayer.cpp:287 ] START STREAM
[1448132684.931|0x7fff72762300|corelayer.cpp:97 ] INIT AUDIO PLAYBACK
[1448132684.932|0x7fff72762300|audiolayer.cpp:54 ] Hardware audio format available : {2 channels, 44100Hz}
[1448132684.933|0x7fff72762300|corelayer.cpp:159 ] INIT AUDIO INPUT
[1448132685.255|0x7fff72762300|call_factory.cpp:39 ] Removing call 7267063970563432557
[1448132685.255|0x7fff72762300|call_factory.cpp:43 ] Remaining 0 SIP call(s)
[1448132685.255|0x7fff72762300|ringaccount.cpp:564 ] Removing deleted call from pending calls
Call State Changed for call "7267063970563432557" . New state : "FAILURE"
Call found Call(0x7f83425f3b80) "Searching for"
State changing from "Searching for" to "Failed" on Call(0x7f83425f3b80)
[1448132685.261|0x7fff72762300|manager.cpp:2572 ] Call is NULL
Warning : call Call(0x7f83425f3b80) had an unexpected transition of state.( "Failed" )
Stoping call. callId : Call(0x7f83425f3b80) ConfId: Call(0x7f83425f3b80)
Calling stateChanged "FAILURE" -> 6 on call with state "Searching for" . Become "Failed"
State changing from "Failed" to "Over" on Call(0x7f83425f3b80)
2015-11-21 14:04:49.762 Ring[1342:34212] animateOut
[1448132689.764|0x7fff72762300|manager.cpp:547 ] Could not hang up non-existant call 7267063970563432557
[1448132689.764|0x7fff72762300|corelayer.cpp:315 ] STOP STREAM
Cannot remove Call(0x7f83425f3b80) : call not found in tree
Calling action REFUSE (1) on Call(0x7f83425f3b80) with state "Failed" . Become "Over"