2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute db(insert/spymap/${caller_id_number}/)
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:1059 switch_core_session_execute_application() sofia/internal/ludovic@192.168.1.1:5061 Expanded String db(insert/spymap/ludovic/0)
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute db(insert/last_dial/${caller_id_numbe)
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:1059 switch_core_session_execute_application() sofia/internal/ludovic@192.168.1.1:5061 Expanded String db(insert/last_dial/ludovi)
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute db(insert/last_dial/global/${uuid})   
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:1059 switch_core_session_execute_application() sofia/internal/ludovic@192.168.1.1:5061 Expanded String db(insert/last_dial/global)
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:141 switch_core_standard_on_execute() sofia/internal/ludovic@192.168.1.1:5061 Execute bridge(sofia/gateway/freephonie.net/0)
2009-03-16 18:29:42 [NOTICE] switch_channel.c:538 switch_channel_set_name() New Channel sofia/external/0123456789 [08ee8f46-1250-11de-9e6d-07496a8df75d]                            
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:2029 sofia_outgoing_channel() sofia/external/0123456789 State Change CS_NEW -> CS_INIT                                                      
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK]                                              
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_INIT                                        
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:416 switch_core_session_run() (sofia/external/0123456789) State INIT                                                        
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:85 sofia_on_init() sofia/external/0123456789 SOFIA INIT                                                                                     
2009-03-16 18:29:42 [DEBUG] sofia_glue.c:488 sofia_glue_ext_address_lookup() Stun Success [89.226.241.9]:[18992]                                                                    
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:112 sofia_on_init() sofia/external/0123456789 State Change CS_INIT -> CS_ROUTING                                                            
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK]                                              
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:416 switch_core_session_run() (sofia/external/0123456789) State INIT going to sleep                                         
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_ROUTING                                     
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:421 switch_core_session_run() (sofia/external/0123456789) State ROUTING                                                     
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:124 sofia_on_routing() sofia/external/0123456789 SOFIA ROUTING                                                                              
2009-03-16 18:29:42 [DEBUG] switch_ivr_originate.c:57 originate_on_routing() sofia/external/0123456789 State Change CS_ROUTING -> CS_CONSUME_MEDIA                                  
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK]                                              
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:421 switch_core_session_run() (sofia/external/0123456789) State ROUTING going to sleep                                      
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_CONSUME_MEDIA                               
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:443 switch_core_session_run() (sofia/external/0123456789) State CONSUME_MEDIA                                               
2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/external/0123456789                                       
2009-03-16 18:29:42 [DEBUG] sofia.c:2177 sofia_handle_sip_i_state() Channel sofia/external/0123456789 entering state [calling]                                                      
2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_r_invite] status [503][DNS Error] session: sofia/external/0123456789                                      
2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_i_state] status [503][DNS Error] session: sofia/external/0123456789                                       
2009-03-16 18:29:42 [DEBUG] sofia.c:2177 sofia_handle_sip_i_state() Channel sofia/external/0123456789 entering state [terminated]                                                   
2009-03-16 18:29:42 [NOTICE] sofia.c:2598 sofia_handle_sip_i_state() Hangup sofia/external/0123456789 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]                                 
2009-03-16 18:29:42 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/external/0123456789 [KILL]                                                             
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/external/0123456789 [BREAK]                                              
2009-03-16 18:29:42 [DEBUG] switch_ivr_originate.c:1448 switch_ivr_originate() Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]                                     
2009-03-16 18:29:42 [INFO] mod_dptools.c:1814 audio_bridge_function() Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE                                                            
2009-03-16 18:29:42 [NOTICE] mod_dptools.c:1841 audio_bridge_function() Hangup sofia/internal/ludovic@192.168.1.1:5061 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]                      
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:443 switch_core_session_run() (sofia/external/0123456789) State CONSUME_MEDIA going to sleep                                
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/external/0123456789 Running State Change CS_HANGUP                                      
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/external/0123456789) State HANGUP                                                      
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:269 sofia_on_hangup() Channel sofia/external/0123456789 hanging up, cause: NORMAL_TEMPORARY_FAILURE                                         
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:47 switch_core_standard_on_hangup() Standard HANGUP sofia/external/0123456789, cause: NORMAL_TEMPORARY_FAILURE              
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/external/0123456789) State HANGUP going to sleep                                       
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 22 (sofia/external/0123456789) Locked, Waiting on external entities                      
2009-03-16 18:29:42 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 22 (sofia/external/0123456789) Ended                                                    
2009-03-16 18:29:42 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/external/0123456789 [CS_HANGUP]                                             
2009-03-16 18:29:42 [DEBUG] switch_channel.c:1368 switch_channel_perform_hangup() Kill sofia/internal/ludovic@192.168.1.1:5061 [KILL]                                               
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:727 switch_core_session_signal_state_change() Kill sofia/internal/ludovic@192.168.1.1:5061 [BREAK]                                
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:431 switch_core_session_run() (sofia/internal/ludovic@192.168.1.1:5061) State EXECUTE going to sleep                        
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:366 switch_core_session_run() sofia/internal/ludovic@192.168.1.1:5061 Running State Change CS_HANGUP                        
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/internal/ludovic@192.168.1.1:5061) State HANGUP                                        
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:235 sofia_on_hangup() sofia/internal/ludovic@192.168.1.1:5061 Overriding SIP cause 503 with 503 from the other leg                          
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:269 sofia_on_hangup() Channel sofia/internal/ludovic@192.168.1.1:5061 hanging up, cause: NORMAL_TEMPORARY_FAILURE                           
2009-03-16 18:29:42 [DEBUG] mod_sofia.c:343 sofia_on_hangup() Responding to INVITE with: 503                                                                                        
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:47 switch_core_standard_on_hangup() Standard HANGUP sofia/internal/ludovic@192.168.1.1:5061, cause: NORMAL_TEMPORARY_FAILURE
2009-03-16 18:29:42 [DEBUG] switch_core_state_machine.c:394 switch_core_session_run() (sofia/internal/ludovic@192.168.1.1:5061) State HANGUP going to sleep                         
2009-03-16 18:29:42 [DEBUG] switch_core_session.c:794 switch_core_session_thread() Session 21 (sofia/internal/ludovic@192.168.1.1:5061) Locked, Waiting on external entities        
2009-03-16 18:29:42 [NOTICE] switch_core_session.c:812 switch_core_session_thread() Session 21 (sofia/internal/ludovic@192.168.1.1:5061) Ended                                      
2009-03-16 18:29:42 [NOTICE] switch_core_session.c:814 switch_core_session_thread() Close Channel sofia/internal/ludovic@192.168.1.1:5061 [CS_HANGUP]                               
2009-03-16 18:29:42 [DEBUG] sofia.c:206 sofia_event_callback() event [nua_i_state] status [503][Service Unavailable] session: n/a