[Jun 9 13:19:06] Asterisk 11.25.1 built by root @ bford-OptiPlex-3010 on a x86_64 running Linux on 2017-06-09 17:40:47 UTC [Jun 9 13:19:06] NOTICE[4856] loader.c: 2 modules will be loaded. [Jun 9 13:19:06] NOTICE[4856] res_odbc.c: Connecting asterisk [Jun 9 13:19:06] NOTICE[4856] res_odbc.c: res_odbc: Connected to asterisk [asterisk-connector] [Jun 9 13:19:06] NOTICE[4856] res_odbc.c: Registered ODBC class 'asterisk' dsn->[asterisk-connector] [Jun 9 13:19:06] NOTICE[4856] res_odbc.c: res_odbc loaded. [Jun 9 13:19:06] NOTICE[4856] config.c: Registered Config Engine odbc [Jun 9 13:19:06] WARNING[4856] http.c: Ignoring unknown option 'servername' in http.conf [Jun 9 13:19:06] NOTICE[4856] cdr.c: CDR simple logging enabled. [Jun 9 13:19:06] NOTICE[4856] loader.c: 225 modules will be loaded. [Jun 9 13:19:06] NOTICE[4856] res_smdi.c: No SMDI interfaces are available to listen on, not starting SMDI listener. [Jun 9 13:19:06] ERROR[4856] pbx_lua.c: Error loading extensions.lua: cannot open '/etc/asterisk/extensions.lua' for reading: No such file or directory [Jun 9 13:19:06] NOTICE[4856] config.c: Registered Config Engine sqlite [Jun 9 13:19:06] NOTICE[4856] config.c: Registered Config Engine pgsql [Jun 9 13:19:06] NOTICE[4856] config.c: Registered Config Engine curl [Jun 9 13:19:06] NOTICE[4856] config.c: Registered Config Engine sqlite3 [Jun 9 13:19:06] NOTICE[4856] res_config_ldap.c: No directory user found, anonymous binding as default. [Jun 9 13:19:06] ERROR[4856] res_config_ldap.c: No directory URL or host found. [Jun 9 13:19:06] ERROR[4856] res_config_ldap.c: Cannot load LDAP RealTime driver. [Jun 9 13:19:06] VERBOSE[4856] chan_sip.c: SIP channel loading... [Jun 9 13:19:07] WARNING[4856] chan_dahdi.c: Ignoring any changes to 'userbase' (on reload) at line 23. [Jun 9 13:19:07] WARNING[4856] chan_dahdi.c: Ignoring any changes to 'vmsecret' (on reload) at line 31. [Jun 9 13:19:07] WARNING[4856] chan_dahdi.c: Ignoring any changes to 'hassip' (on reload) at line 35. [Jun 9 13:19:07] WARNING[4856] chan_dahdi.c: Ignoring any changes to 'hasiax' (on reload) at line 39. [Jun 9 13:19:07] WARNING[4856] chan_dahdi.c: Ignoring any changes to 'hasmanager' (on reload) at line 47. [Jun 9 13:19:07] NOTICE[4856] chan_skinny.c: Configuring skinny from skinny.conf [Jun 9 13:19:07] NOTICE[4856] cdr_pgsql.c: cdr_pgsql configuration contains no global section, skipping module load. [Jun 9 13:19:07] NOTICE[4856] cel_tds.c: cel_tds has no global category, nothing to configure. [Jun 9 13:19:07] WARNING[4856] cel_tds.c: cel_tds module had config problems; declining load [Jun 9 13:19:07] WARNING[4856] cel_pgsql.c: CEL pgsql config file missing global section. [Jun 9 13:19:07] NOTICE[4856] cel_custom.c: No mappings found in cel_custom.conf. Not logging CEL to custom CSVs. [Jun 9 13:19:07] NOTICE[4856] pbx_ael.c: Starting AEL load process. [Jun 9 13:19:07] NOTICE[4856] pbx_ael.c: File /etc/asterisk/extensions.ael not found; AEL declining load [Jun 9 13:19:07] WARNING[4856] app_followme.c: Unknown keyword in profile 'default': enable_callee_prompt at line 62 of followme.conf [Jun 9 13:19:07] ERROR[4856] codec_dahdi.c: Failed to open /dev/dahdi/transcode: No such file or directory [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute returned an error -1: HY000: ERROR: relation "voicemessages" does not exist at character 50; Error while executing the query (96) [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute error -1! Verifying connection to asterisk [asterisk-connector]... [Jun 9 13:19:07] WARNING[4856] res_odbc.c: Connection is down attempting to reconnect... [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: Connecting asterisk [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: res_odbc: Connected to asterisk [asterisk-connector] [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute returned an error -1: HY000: ERROR: relation "voicemessages" does not exist at character 50; Error while executing the query (96) [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute error -1! Verifying connection to asterisk [asterisk-connector]... [Jun 9 13:19:07] WARNING[4856] res_odbc.c: Connection is down attempting to reconnect... [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: Connecting asterisk [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: res_odbc: Connected to asterisk [asterisk-connector] [Jun 9 13:19:07] WARNING[4856] app_voicemail.c: SQL Execute error! [SELECT COUNT(*) FROM voicemessages WHERE dir = '/var/spool/asterisk/voicemail/default/1234/INBOX'] [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute returned an error -1: HY000: ERROR: relation "voicemessages" does not exist at character 50; Error while executing the query (96) [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute error -1! Verifying connection to asterisk [asterisk-connector]... [Jun 9 13:19:07] WARNING[4856] res_odbc.c: Connection is down attempting to reconnect... [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: Connecting asterisk [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: res_odbc: Connected to asterisk [asterisk-connector] [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute returned an error -1: HY000: ERROR: relation "voicemessages" does not exist at character 50; Error while executing the query (96) [Jun 9 13:19:07] WARNING[4856] res_odbc.c: SQL Execute error -1! Verifying connection to asterisk [asterisk-connector]... [Jun 9 13:19:07] WARNING[4856] res_odbc.c: Connection is down attempting to reconnect... [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: Connecting asterisk [Jun 9 13:19:07] NOTICE[4856] res_odbc.c: res_odbc: Connected to asterisk [asterisk-connector] [Jun 9 13:19:07] WARNING[4856] app_voicemail.c: SQL Execute error! [SELECT COUNT(*) FROM voicemessages WHERE dir = '/var/spool/asterisk/voicemail/other/1234/INBOX'] [Jun 9 13:19:07] WARNING[4856] func_odbc.c: Section 'general' was found, but there was no SQL to execute. Ignoring. [Jun 9 13:19:07] ERROR[4856] func_odbc.c: Invalid parameters for category general [Jun 9 13:19:07] VERBOSE[4856] asterisk.c: Asterisk Ready. [Jun 9 13:19:11] NOTICE[5115] chan_sip.c: Received SIP subscribe for peer without mailbox: 8003 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: = Looking for Call ID: 8JFByiZMUn0ANpBaUioRy4.BiXppcT23 (Checking From) --From tag HLJp5bKxUDk5Q8tWsJihp.viUPclPIBG --To-tag [Jun 9 13:19:19] DEBUG[5115] acl.c: For destination '10.24.18.13', our source address is '10.24.21.95'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for 8JFByiZMUn0ANpBaUioRy4.BiXppcT23 - REGISTER (No RTP) [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: = Looking for Call ID: 8JFByiZMUn0ANpBaUioRy4.BiXppcT23 (Checking From) --From tag HLJp5bKxUDk5Q8tWsJihp.viUPclPIBG --To-tag [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Store REGISTER's src-IP:port for call routing. [Jun 9 13:19:19] VERBOSE[5115] chan_sip.c: > Saved useragent "Digium D40 2_2_1_8" for peer 8002 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:19] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:19] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:19] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 1 (Not in use) [Jun 9 13:19:19] DEBUG[5105] devicestate.c: device 'SIP/8002' state '1' [Jun 9 13:19:19] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: = Looking for Call ID: YN5D9dMJhsZUMnyQzXu-1aaAnQdApCF6 (Checking From) --From tag sVZdqRRFgE0kpXzJ4oFZn43m7EOAk7Uo --To-tag [Jun 9 13:19:19] DEBUG[5115] acl.c: For destination '10.24.18.13', our source address is '10.24.21.95'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for YN5D9dMJhsZUMnyQzXu-1aaAnQdApCF6 - SUBSCRIBE (No RTP) [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8002" [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: = Looking for Call ID: glxiTSPKENLYkUKoCWj5Zw4gHHNWh70M (Checking From) --From tag 5K1pZg51Vf390t-R2IobhBJysoYZr8Em --To-tag [Jun 9 13:19:19] DEBUG[5115] acl.c: For destination '10.24.18.13', our source address is '10.24.21.95'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for glxiTSPKENLYkUKoCWj5Zw4gHHNWh70M - SUBSCRIBE (No RTP) [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8002" [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: = Looking for Call ID: YN5D9dMJhsZUMnyQzXu-1aaAnQdApCF6 (Checking From) --From tag sVZdqRRFgE0kpXzJ4oFZn43m7EOAk7Uo --To-tag [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:19] NOTICE[5115] chan_sip.c: Received SIP subscribe for peer without mailbox: 8002 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Destroying SIP dialog YN5D9dMJhsZUMnyQzXu-1aaAnQdApCF6 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: = Looking for Call ID: glxiTSPKENLYkUKoCWj5Zw4gHHNWh70M (Checking From) --From tag 5K1pZg51Vf390t-R2IobhBJysoYZr8Em --To-tag [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:19] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:19] DEBUG[5115] chan_sip.c: Destroying SIP dialog glxiTSPKENLYkUKoCWj5Zw4gHHNWh70M [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: = Looking for Call ID: hMM6Xi-9VV-dDPrLFVCo1x6CdqhAKYIv (Checking From) --From tag oR89-VSIcsqO4SB0GUj.sIIg60jO9m0. --To-tag [Jun 9 13:19:26] DEBUG[5115] acl.c: For destination '10.24.19.15', our source address is '10.24.21.95'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for hMM6Xi-9VV-dDPrLFVCo1x6CdqhAKYIv - REGISTER (No RTP) [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.19.15:5060 [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: = Looking for Call ID: hMM6Xi-9VV-dDPrLFVCo1x6CdqhAKYIv (Checking From) --From tag oR89-VSIcsqO4SB0GUj.sIIg60jO9m0. --To-tag [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Store REGISTER's src-IP:port for call routing. [Jun 9 13:19:26] VERBOSE[5115] chan_sip.c: > Saved useragent "Digium D40 2_2_1_8" for peer 8004 [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.19.15:5060 [Jun 9 13:19:26] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8004 [Jun 9 13:19:26] DEBUG[5105] chan_sip.c: Checking device state for peer 8004 [Jun 9 13:19:26] DEBUG[5105] devicestate.c: Changing state for SIP/8004 - state 1 (Not in use) [Jun 9 13:19:26] DEBUG[5105] devicestate.c: device 'SIP/8004' state '1' [Jun 9 13:19:26] DEBUG[5147] app_queue.c: Device 'SIP/8004' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: = Looking for Call ID: sjFejRsCOGiPjxTpmRawtXIP6zNvFZ1O (Checking From) --From tag Mbr.LN0yUuUiN4UCTdh5escclsqQGZLC --To-tag [Jun 9 13:19:26] DEBUG[5115] acl.c: For destination '10.24.19.15', our source address is '10.24.21.95'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for sjFejRsCOGiPjxTpmRawtXIP6zNvFZ1O - SUBSCRIBE (No RTP) [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8004" [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.19.15:5060 [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: = Looking for Call ID: sjFejRsCOGiPjxTpmRawtXIP6zNvFZ1O (Checking From) --From tag Mbr.LN0yUuUiN4UCTdh5escclsqQGZLC --To-tag [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.19.15:5060 [Jun 9 13:19:26] NOTICE[5115] chan_sip.c: Received SIP subscribe for peer without mailbox: 8004 [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Destroying SIP dialog sjFejRsCOGiPjxTpmRawtXIP6zNvFZ1O [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: = Looking for Call ID: bfEc4KoSD54WZcSxajbno26keNULGarR (Checking From) --From tag 2QQQHjv9YrPmfHyyXaKvEqauNZXnpOV6 --To-tag [Jun 9 13:19:26] DEBUG[5115] acl.c: For destination '10.24.19.15', our source address is '10.24.21.95'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for bfEc4KoSD54WZcSxajbno26keNULGarR - SUBSCRIBE (No RTP) [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8004" [Jun 9 13:19:26] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:26] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:26] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.19.15:5060 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: = Looking for Call ID: bfEc4KoSD54WZcSxajbno26keNULGarR (Checking From) --From tag 2QQQHjv9YrPmfHyyXaKvEqauNZXnpOV6 --To-tag [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.19.15:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.19.15' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.19.15:5060 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Destroying SIP dialog bfEc4KoSD54WZcSxajbno26keNULGarR [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: = Looking for Call ID: l9kMSFnGKj2KS3V2GDRABqI.MNsj9VMo (Checking From) --From tag G2HJmaPzLiyI.NiMDy1p4Hd5pXUnOvPZ --To-tag [Jun 9 13:19:27] DEBUG[5115] acl.c: For destination '10.24.16.127', our source address is '10.24.21.95'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for l9kMSFnGKj2KS3V2GDRABqI.MNsj9VMo - REGISTER (No RTP) [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: = Looking for Call ID: l9kMSFnGKj2KS3V2GDRABqI.MNsj9VMo (Checking From) --From tag G2HJmaPzLiyI.NiMDy1p4Hd5pXUnOvPZ --To-tag [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Store REGISTER's src-IP:port for call routing. [Jun 9 13:19:27] VERBOSE[5115] chan_sip.c: > Saved useragent "Digium D40 2_2_1_8" for peer 8001 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:27] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:27] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:27] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 1 (Not in use) [Jun 9 13:19:27] DEBUG[5105] devicestate.c: device 'SIP/8001' state '1' [Jun 9 13:19:27] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: = Looking for Call ID: Uz5JcDkyMxACWFgMwaDkaRVbO7hUpmTl (Checking From) --From tag 15pif4YjoZz3Ct4iWPPXSUlPDmA2Cr3G --To-tag [Jun 9 13:19:27] DEBUG[5115] acl.c: For destination '10.24.16.127', our source address is '10.24.21.95'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for Uz5JcDkyMxACWFgMwaDkaRVbO7hUpmTl - SUBSCRIBE (No RTP) [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8001" [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: = Looking for Call ID: Uz5JcDkyMxACWFgMwaDkaRVbO7hUpmTl (Checking From) --From tag 15pif4YjoZz3Ct4iWPPXSUlPDmA2Cr3G --To-tag [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:27] NOTICE[5115] chan_sip.c: Received SIP subscribe for peer without mailbox: 8001 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Destroying SIP dialog Uz5JcDkyMxACWFgMwaDkaRVbO7hUpmTl [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: = Looking for Call ID: kWWn8QipgJJppnrTtuuj-.C9bnAi1gf- (Checking From) --From tag XB14bWTN2fQXP0mskHZOD0sdY-QFDF35 --To-tag [Jun 9 13:19:27] DEBUG[5115] acl.c: For destination '10.24.16.127', our source address is '10.24.21.95'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for kWWn8QipgJJppnrTtuuj-.C9bnAi1gf- - SUBSCRIBE (No RTP) [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8001" [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: = Looking for Call ID: kWWn8QipgJJppnrTtuuj-.C9bnAi1gf- (Checking From) --From tag XB14bWTN2fQXP0mskHZOD0sdY-QFDF35 --To-tag [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:27] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:27] DEBUG[5115] chan_sip.c: Destroying SIP dialog kWWn8QipgJJppnrTtuuj-.C9bnAi1gf- [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: = Looking for Call ID: 0tnENvZhlW2SOFrsa3zvtBGpa4udW6cx (Checking From) --From tag EuSrTY2D6jZg4y3S.930Ui-JxCJpcjD5 --To-tag [Jun 9 13:19:29] DEBUG[5115] acl.c: For destination '10.24.18.133', our source address is '10.24.21.95'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for 0tnENvZhlW2SOFrsa3zvtBGpa4udW6cx - REGISTER (No RTP) [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.133:5060 [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: = Looking for Call ID: 0tnENvZhlW2SOFrsa3zvtBGpa4udW6cx (Checking From) --From tag EuSrTY2D6jZg4y3S.930Ui-JxCJpcjD5 --To-tag [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Store REGISTER's src-IP:port for call routing. [Jun 9 13:19:29] VERBOSE[5115] chan_sip.c: > Saved useragent "Digium D45 2_0_3_0_79142" for peer 8005 [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.133:5060 [Jun 9 13:19:29] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8005 [Jun 9 13:19:29] DEBUG[5105] chan_sip.c: Checking device state for peer 8005 [Jun 9 13:19:29] DEBUG[5105] devicestate.c: Changing state for SIP/8005 - state 1 (Not in use) [Jun 9 13:19:29] DEBUG[5105] devicestate.c: device 'SIP/8005' state '1' [Jun 9 13:19:29] DEBUG[5147] app_queue.c: Device 'SIP/8005' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: = Looking for Call ID: DIFnkJhJGvbL4Vf2gRV6odkOF39PLhjf (Checking From) --From tag syRnzruttpXGK84b6tN0OS7dA-hBHMfR --To-tag [Jun 9 13:19:29] DEBUG[5115] acl.c: For destination '10.24.18.133', our source address is '10.24.21.95'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for DIFnkJhJGvbL4Vf2gRV6odkOF39PLhjf - SUBSCRIBE (No RTP) [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8005" [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.133:5060 [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: = Looking for Call ID: DIFnkJhJGvbL4Vf2gRV6odkOF39PLhjf (Checking From) --From tag syRnzruttpXGK84b6tN0OS7dA-hBHMfR --To-tag [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.18.133:5060 [Jun 9 13:19:29] NOTICE[5115] chan_sip.c: Received SIP subscribe for peer without mailbox: 8005 [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Destroying SIP dialog DIFnkJhJGvbL4Vf2gRV6odkOF39PLhjf [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: = Looking for Call ID: refAzVX.-25WeMuUeX3PKILAN4Chei8q (Checking From) --From tag F2wWjowAtfmozuZ8OnCHaxwfFk7qx.NZ --To-tag [Jun 9 13:19:29] DEBUG[5115] acl.c: For destination '10.24.18.133', our source address is '10.24.21.95'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for refAzVX.-25WeMuUeX3PKILAN4Chei8q - SUBSCRIBE (No RTP) [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8005" [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.133:5060 [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: = Looking for Call ID: refAzVX.-25WeMuUeX3PKILAN4Chei8q (Checking From) --From tag F2wWjowAtfmozuZ8OnCHaxwfFk7qx.NZ --To-tag [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.18.133:5060' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.18.133' and port '5060'. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:29] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.18.133:5060 [Jun 9 13:19:29] DEBUG[5115] chan_sip.c: Destroying SIP dialog refAzVX.-25WeMuUeX3PKILAN4Chei8q [Jun 9 13:19:38] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking From) --From tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- --To-tag [Jun 9 13:19:38] DEBUG[5115] acl.c: For destination '10.24.16.127', our source address is '10.24.21.95'. [Jun 9 13:19:38] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:38] DEBUG[5115] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:38] DEBUG[5115] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:38] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for axUspGgZnoThdywLU0aVNaMdwnESmhLn - INVITE (No RTP) [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces, 100rel, timer, norefersub" [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -replaces- [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: replaces [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -100rel- [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: 100rel [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -timer- [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: timer [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -norefersub- [Jun 9 13:19:38] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: norefersub [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:38] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking From) --From tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- --To-tag as4b930eec [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' of Response 15144: Match Found [Jun 9 13:19:38] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking From) --From tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- --To-tag [Jun 9 13:19:38] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:38] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:38] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:38] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f28f0057478' [Jun 9 13:19:38] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Allocated port 15128 for RTP instance '0x7f28f0057478' [Jun 9 13:19:38] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:15128 (15128) for RTP instance '0x7f28f0057478' [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: RTP instance '0x7f28f0057478' is setup and ready to go [Jun 9 13:19:38] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28f0057478' [Jun 9 13:19:38] VERBOSE[5115][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5 [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Setting NAT on RTP to Off [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403667 192403667 IN IP4 10.24.16.127... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 8 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 9 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 111 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 18 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 96 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port ''. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.16.127... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.16.127... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:111 G726-32/8000... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jun 9 13:19:38] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28f0057478' [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa3130 to 0x7f28f0057640 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 8 from 0x7f28f8aa3130 to 0x7f28f0057640 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 9 from 0x7f28f8aa3130 to 0x7f28f0057640 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 18 from 0x7f28f8aa3130 to 0x7f28f0057640 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 96 from 0x7f28f8aa3130 to 0x7f28f0057640 [Jun 9 13:19:38] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 111 from 0x7f28f8aa3130 to 0x7f28f0057640 [Jun 9 13:19:38] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f28f0057478' [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw|alaw|g726|g729|g722) [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Checking SIP call limits for device 8001 [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for incoming call [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:38] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Incoming INVITE with 'timer' option supported [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: INVITE also has "Session-Expires" header. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Session-Expires: 1800 [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: INVITE also has "Min-SE" header. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Received Min-SE: 90 [Jun 9 13:19:38] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:38] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:38] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 2 (In use) [Jun 9 13:19:38] DEBUG[5105] devicestate.c: device 'SIP/8001' state '2' [Jun 9 13:19:38] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:38] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: *** Our native formats are (ulaw) [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: *** Joint capabilities are (ulaw|alaw|g726|g729|g722) [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: *** Our capabilities are (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|speex16|ilbc|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|siren7|siren14|testlaw|g719|speex32|slin12|slin24|slin32|slin44|slin48|slin96|slin192|silk8|silk12|silk16|silk24) [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: *** AST_CODEC_CHOOSE formats are ulaw [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: This channel will not be able to handle video. [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: build_route: Contact hop: "8001" [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: SIP/8001-00000000: New call is still down.... Trying... [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:38] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:38] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:38] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 2 (In use) [Jun 9 13:19:38] DEBUG[5105] devicestate.c: device 'SIP/8001' state '2' [Jun 9 13:19:38] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:38] DEBUG[5153][C-00000000] pbx.c: Launching 'Dial' [Jun 9 13:19:38] VERBOSE[5153][C-00000000] pbx.c: -- Executing [8002@siptest:1] Dial("SIP/8001-00000000", "SIP/8002") in new stack [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Asked to create a SIP channel with formats: (ulaw) [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Allocating new SIP dialog for 497c5d9432f3de9f1b26beed4a54eb29@127.0.1.1:5061 - INVITE (No RTP) [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f28e0012a98' [Jun 9 13:19:38] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Allocated port 16870 for RTP instance '0x7f28e0012a98' [Jun 9 13:19:38] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:16870 (16870) for RTP instance '0x7f28e0012a98' [Jun 9 13:19:38] DEBUG[5153][C-00000000] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:38] DEBUG[5153][C-00000000] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: RTP instance '0x7f28e0012a98' is setup and ready to go [Jun 9 13:19:38] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28e0012a98' [Jun 9 13:19:38] VERBOSE[5153][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5 [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Setting NAT on RTP to Off [Jun 9 13:19:38] DEBUG[5153][C-00000000] acl.c: For destination '10.24.18.13', our source address is '10.24.21.95'. [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:38] DEBUG[5153][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:38] DEBUG[5153][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Setting NAT on RTP to Off [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: SIP call-id changed from '497c5d9432f3de9f1b26beed4a54eb29@127.0.1.1:5061' to '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' [Jun 9 13:19:38] DEBUG[5153][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: *** Our native formats are (ulaw) [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: *** Joint capabilities are (ulaw) [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: *** Our capabilities are (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|speex16|ilbc|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|siren7|siren14|testlaw|g719|speex32|slin12|slin24|slin32|slin44|slin48|slin96|slin192|silk8|silk12|silk16|silk24) [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: *** AST_CODEC_CHOOSE formats are ulaw [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: *** Our preferred formats from the incoming channel are (ulaw) [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: This channel will not be able to handle video. [Jun 9 13:19:38] DEBUG[5153][C-00000000] channel_internal_api.c: Channel Call ID changing from [C-00000000] to [C-00000000] [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f0057640 to 0x7f28e0012c60 [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Copying payload 8 from 0x7f28f0057640 to 0x7f28e0012c60 [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Copying payload 9 from 0x7f28f0057640 to 0x7f28e0012c60 [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Copying payload 18 from 0x7f28f0057640 to 0x7f28e0012c60 [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Copying payload 96 from 0x7f28f0057640 to 0x7f28e0012c60 [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Copying payload 111 from 0x7f28f0057640 to 0x7f28e0012c60 [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Seeded SDP of 'SIP/8002-00000001' with that of 'SIP/8001-00000000' [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Outgoing Call for 8002 [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jun 9 13:19:38] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:38] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:38] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 6 (Ringing) [Jun 9 13:19:38] DEBUG[5105] devicestate.c: device 'SIP/8002' state '6' [Jun 9 13:19:38] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: This call needs video offers, but there's no video support enabled! [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: This call needs text offers, but there's no text support enabled ! [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: ** Our capability: (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|speex16|ilbc|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|siren7|siren14|testlaw|g719|speex32|slin12|slin24|slin32|slin44|slin48|slin96|slin192|silk8|silk12|silk16|silk24) Video flag: False Text flag: False [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: ** Our prefcodec: (ulaw) [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|speex16|ilbc|g726aal2|g722|slin16|jpeg|png|h261|h263|h263p|h264|mpeg4|red|t140|siren7|siren14|testlaw|g719|speex32|slin12|slin24|slin32|slin44|slin48|slin96|slin192|silk8|silk12|silk16|silk24) [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Initializing initreq for method INVITE - callid 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:38] VERBOSE[5153][C-00000000] app_dial.c: -- Called SIP/8002 [Jun 9 13:19:38] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking To) --From tag as08e7180f --To-tag [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' Request 102: Found [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: SIP response 100 to standard invite [Jun 9 13:19:38] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking To) --From tag as08e7180f --To-tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' Request 102: Found [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: SIP response 180 to standard invite [Jun 9 13:19:38] DEBUG[5115][C-00000000] chan_sip.c: build_route: Contact hop: "8002" [Jun 9 13:19:38] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:38] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:38] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 6 (Ringing) [Jun 9 13:19:38] DEBUG[5105] devicestate.c: device 'SIP/8002' state '6' [Jun 9 13:19:38] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Jun 9 13:19:38] VERBOSE[5153][C-00000000] app_dial.c: -- SIP/8002-00000001 is ringing [Jun 9 13:19:38] DEBUG[5153][C-00000000] rtp_engine.c: Setting early bridge SDP of 'SIP/8001-00000000' with that of 'SIP/8002-00000001' [Jun 9 13:19:38] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 180' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:40] DEBUG[5153][C-00000000] res_rtp_asterisk.c: 0x7f28e0018080 -- Probation learning mode pass with source address 10.24.18.13:4012 [Jun 9 13:19:40] VERBOSE[5153][C-00000000] res_rtp_asterisk.c: > 0x7f28e0018080 -- Probation passed - setting RTP source address to 10.24.18.13:4012 [Jun 9 13:19:40] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Got RTCP report of 40 bytes [Jun 9 13:19:40] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking To) --From tag as08e7180f --To-tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Acked pending invite 102 [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' of Request 102: Match Found [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: SIP response 200 to standard invite [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403668 192403669 IN IP4 10.24.18.13... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port ''. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.18.13... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.18.13... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x7f28e0012a98) [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x7f28e0012a98) [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28e0012a98' [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa2280 to 0x7f28e0012c60 [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f28e0012a98' [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw) [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:40] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: build_route: Contact hop: "8002" [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'ACK sip:800' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:40] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:40] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:40] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 2 (In use) [Jun 9 13:19:40] DEBUG[5105] devicestate.c: device 'SIP/8002' state '2' [Jun 9 13:19:40] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:40] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:40] VERBOSE[5153][C-00000000] app_dial.c: -- SIP/8002-00000001 answered SIP/8001-00000000 [Jun 9 13:19:40] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:40] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 2 (In use) [Jun 9 13:19:40] DEBUG[5105] devicestate.c: device 'SIP/8002' state '2' [Jun 9 13:19:40] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:40] DEBUG[5153][C-00000000] rtp_engine.c: Setting early bridge SDP of 'SIP/8001-00000000' with that of 'SIP/8002-00000001' [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: SIP answering channel: SIP/8001-00000000 [Jun 9 13:19:40] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jun 9 13:19:40] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:40] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:40] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 2 (In use) [Jun 9 13:19:40] DEBUG[5105] devicestate.c: device 'SIP/8001' state '2' [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Setting framing from config on incoming call [Jun 9 13:19:40] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our capability: (ulaw|alaw|g726|g729|g722) Video flag: True Text flag: True [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw|alaw|g726|g729|g722) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Session timer started: 10 - axUspGgZnoThdywLU0aVNaMdwnESmhLn 900000ms [Jun 9 13:19:40] DEBUG[5153][C-00000000] features.c: bridge answer set, chan answer set [Jun 9 13:19:40] DEBUG[5153][C-00000000] features.c: Removing dialed interfaces datastore on SIP/8002-00000001 since we're bridging [Jun 9 13:19:40] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jun 9 13:19:40] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jun 9 13:19:40] VERBOSE[5153][C-00000000] rtp_engine.c: -- Remotely bridging SIP/8001-00000000 and SIP/8002-00000001 [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Deferring reinvite on SIP 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' - It's audio will be redirected to IP 10.24.18.13:4012 [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Sending reinvite on SIP '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' - It's audio soon redirected to IP 10.24.16.127:4012 [Jun 9 13:19:40] DEBUG[5153][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:40] DEBUG[5153][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our capability: (ulaw) Video flag: True Text flag: True [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our prefcodec: (ulaw) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our native-bridge filtered capablity: (ulaw) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Initializing already initialized SIP dialog 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (presumably reinvite) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:40] DEBUG[5153][C-00000000] res_rtp_asterisk.c: 0x7f28e0018080 -- Probation learning mode pass with source address 10.24.18.13:4012 [Jun 9 13:19:40] VERBOSE[5153][C-00000000] res_rtp_asterisk.c: > 0x7f28e0018080 -- Probation passed - setting RTP source address to 10.24.18.13:4012 [Jun 9 13:19:40] DEBUG[5153][C-00000000] res_rtp_asterisk.c: 0x7f28f005c680 -- Probation learning mode pass with source address 10.24.16.127:4012 [Jun 9 13:19:40] VERBOSE[5153][C-00000000] res_rtp_asterisk.c: > 0x7f28f005c680 -- Probation passed - setting RTP source address to 10.24.16.127:4012 [Jun 9 13:19:40] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking From) --From tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- --To-tag as42ec810e [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' of Response 15145: Match Found [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Sending pending reinvite on 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: ** Our capability: (ulaw|alaw|g726|g729|g722) Video flag: True Text flag: True [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: ** Our native-bridge filtered capablity: (ulaw) [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Initializing already initialized SIP dialog axUspGgZnoThdywLU0aVNaMdwnESmhLn (presumably reinvite) [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:40] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking To) --From tag as08e7180f --To-tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Acked pending invite 103 [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' of Request 103: Match Found [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403668 192403670 IN IP4 10.24.18.13... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port ''. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.18.13... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.18.13... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x7f28e0012a98) [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x7f28e0012a98) [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa2280 to 0x7f28e0012c60 [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28e0012a98' [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw) [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:40] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:40] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:40] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:40] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 2 (In use) [Jun 9 13:19:40] DEBUG[5105] devicestate.c: device 'SIP/8002' state '2' [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'ACK sip:800' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:40] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:40] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking To) --From tag as42ec810e --To-tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Acked pending invite 102 [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' of Request 102: Match Found [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403667 192403668 IN IP4 10.24.16.127... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 96 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port ''. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.16.127... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.16.127... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x7f28f0057478) [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x7f28f0057478) [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa2280 to 0x7f28f0057640 [Jun 9 13:19:40] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 96 from 0x7f28f8aa2280 to 0x7f28f0057640 [Jun 9 13:19:40] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28f0057478' [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw) [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:40] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for incoming call [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:40] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Session-Expires: 1800 [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Refresher: UAC [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Session timer stopped: 10 - axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:40] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Session timer started: 4 - axUspGgZnoThdywLU0aVNaMdwnESmhLn 900000ms [Jun 9 13:19:40] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 2 (In use) [Jun 9 13:19:40] DEBUG[5105] devicestate.c: device 'SIP/8001' state '2' [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:40] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:40] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'ACK sip:800' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:40] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:40] DEBUG[5153][C-00000000] rtp_engine.c: Oooh, 'SIP/8001-00000000' changed end address to 10.24.16.127:4012 (format (ulaw)) [Jun 9 13:19:40] DEBUG[5153][C-00000000] rtp_engine.c: Oooh, 'SIP/8001-00000000' was 10.24.16.127:4012/(format (ulaw|alaw|g726|g729|g722)) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Sending reinvite on SIP '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' - It's audio soon redirected to IP 10.24.16.127:4012 [Jun 9 13:19:40] DEBUG[5153][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:40] DEBUG[5153][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our capability: (ulaw) Video flag: True Text flag: True [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our prefcodec: (ulaw) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: ** Our native-bridge filtered capablity: (ulaw) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Initializing already initialized SIP dialog 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (presumably reinvite) [Jun 9 13:19:40] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:41] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking To) --From tag as08e7180f --To-tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Acked pending invite 104 [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' of Request 104: Match Found [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403668 192403671 IN IP4 10.24.18.13... OK. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:41] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:41] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13' into... [Jun 9 13:19:41] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port ''. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.18.13... OK. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.18.13... UNSUPPORTED OR FAILED. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:41] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x7f28e0012a98) [Jun 9 13:19:41] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x7f28e0012a98) [Jun 9 13:19:41] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa2280 to 0x7f28e0012c60 [Jun 9 13:19:41] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28e0012a98' [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw) [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:41] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jun 9 13:19:41] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:41] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:41] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:41] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:41] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:41] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:41] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'ACK sip:800' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:41] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 2 (In use) [Jun 9 13:19:41] DEBUG[5105] devicestate.c: device 'SIP/8002' state '2' [Jun 9 13:19:41] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:42] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking From) --From tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ --To-tag as08e7180f [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces, 100rel, timer, norefersub" [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -replaces- [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: replaces [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -100rel- [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: 100rel [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -timer- [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: timer [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Found SIP option: -norefersub- [Jun 9 13:19:42] DEBUG[5115][C-00000000] sip/reqresp_parser.c: Matched SIP option: norefersub [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403668 192403672 IN IP4 10.24.18.13... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 8 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 9 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 111 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 18 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 96 based on m type on 0x7f28f8aa3130 [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port ''. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.18.13... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.18.13... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:111 G726-32/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendonly... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28e0012a98' [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa3130 to 0x7f28e0012c60 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 8 from 0x7f28f8aa3130 to 0x7f28e0012c60 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 9 from 0x7f28f8aa3130 to 0x7f28e0012c60 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 18 from 0x7f28f8aa3130 to 0x7f28e0012c60 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 96 from 0x7f28f8aa3130 to 0x7f28e0012c60 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 111 from 0x7f28f8aa3130 to 0x7f28e0012c60 [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f28e0012a98' [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw|alaw|g726|g729|g722) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:42] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28e0012a98' [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Incoming INVITE with 'timer' option supported [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: INVITE also has "Session-Expires" header. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Session-Expires: 1800 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: INVITE also has "Min-SE" header. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Received Min-SE: 90 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: SIP/8002-00000001: This call is UP.... [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Setting framing from config on incoming call [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: ** Our capability: (ulaw|alaw|g726|g729|g722) Video flag: True Text flag: True [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: ** Our prefcodec: (ulaw) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw|alaw|g726|g729|g722) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28f0057478' [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: Sending reinvite on SIP 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' - It's audio soon redirected to IP 10.24.21.95:5061 [Jun 9 13:19:42] DEBUG[5153][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:42] DEBUG[5153][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: ** Our capability: (ulaw) Video flag: True Text flag: True [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: Initializing already initialized SIP dialog axUspGgZnoThdywLU0aVNaMdwnESmhLn (presumably reinvite) [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_config_odbc.c: Skip: 0; SQL: SELECT * FROM musiconhold WHERE name = ? [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_config_odbc.c: Parameter 1 ('name') = 'default' [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_odbc.c: odbc_release_obj2(0x298f2d8) called (obj->txf = (nil)) [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_musiconhold.c: Scanning '/var/lib/asterisk/moh' for files for class 'default' [Jun 9 13:19:42] VERBOSE[5153][C-00000000] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/8001-00000000 [Jun 9 13:19:42] DEBUG[5153][C-00000000] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setting the marker bit due to a source update [Jun 9 13:19:42] DEBUG[5153][C-00000000] chan_sip.c: Deferring reinvite on SIP 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' - It's audio will be redirected to IP (null) [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_musiconhold.c: SIP/8001-00000000 Opened file 0 '/var/lib/asterisk/moh/macroform-the_simplicity' [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [Jun 9 13:19:42] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking From) --From tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ --To-tag as08e7180f [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' of Response 20990: Match Found [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: 0x7f28f005c680 -- Probation learning mode pass with source address 10.24.16.127:4012 [Jun 9 13:19:42] VERBOSE[5153][C-00000000] res_rtp_asterisk.c: > 0x7f28f005c680 -- Probation passed - setting RTP source address to 10.24.16.127:4012 [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking To) --From tag as42ec810e --To-tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Acked pending invite 103 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' of Request 103: Match Found [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403667 192403669 IN IP4 10.24.16.127... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 96 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port ''. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.16.127... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.16.127... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x7f28f0057478) [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x7f28f0057478) [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa2280 to 0x7f28f0057640 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 96 from 0x7f28f8aa2280 to 0x7f28f0057640 [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28f0057478' [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:42] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for incoming call [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:42] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:42] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:42] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 2 (In use) [Jun 9 13:19:42] DEBUG[5105] devicestate.c: device 'SIP/8001' state '2' [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:42] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Session-Expires: 1800 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Refresher: UAC [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Session timer stopped: 4 - axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Session timer started: 16 - axUspGgZnoThdywLU0aVNaMdwnESmhLn 900000ms [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'ACK sip:800' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Sending pending reinvite on 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: ** Our capability: (ulaw) Video flag: True Text flag: True [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: ** Our prefcodec: (nothing) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Initializing already initialized SIP dialog axUspGgZnoThdywLU0aVNaMdwnESmhLn (presumably reinvite) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x7f28f0057478' [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: 0x7f28f005c680 -- Probation learning mode pass with source address 10.24.16.127:4012 [Jun 9 13:19:42] VERBOSE[5153][C-00000000] res_rtp_asterisk.c: > 0x7f28f005c680 -- Probation passed - setting RTP source address to 10.24.16.127:4012 [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Got RTCP report of 72 bytes [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking To) --From tag as42ec810e --To-tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Acked pending invite 104 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on 'axUspGgZnoThdywLU0aVNaMdwnESmhLn' of Request 104: Match Found [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403667 192403670 IN IP4 10.24.16.127... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 96 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port ''. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.16.127... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.16.127... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x7f28f0057478) [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x7f28f0057478) [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28f0057478' [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa2280 to 0x7f28f0057640 [Jun 9 13:19:42] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 96 from 0x7f28f8aa2280 to 0x7f28f0057640 [Jun 9 13:19:42] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f28f0057478' [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw) [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:42] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for incoming call [Jun 9 13:19:42] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:42] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:42] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 2 (In use) [Jun 9 13:19:42] DEBUG[5105] devicestate.c: device 'SIP/8001' state '2' [Jun 9 13:19:42] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Session-Expires: 1800 [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Refresher: UAC [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Session timer stopped: 16 - axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Session timer started: 9 - axUspGgZnoThdywLU0aVNaMdwnESmhLn 900000ms [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:42] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:42] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'ACK sip:800' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: 0x7f28f005c680 -- Probation learning mode pass with source address 10.24.16.127:4012 [Jun 9 13:19:42] VERBOSE[5153][C-00000000] res_rtp_asterisk.c: > 0x7f28f005c680 -- Probation passed - setting RTP source address to 10.24.16.127:4012 [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:42] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:43] DEBUG[5115] chan_sip.c: Auto destroying SIP dialog 'XaO-2QDGW9G6.VZp-1LXcNmlSb2p4wKa' [Jun 9 13:19:43] DEBUG[5115] chan_sip.c: Destroying SIP dialog XaO-2QDGW9G6.VZp-1LXcNmlSb2p4wKa [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:44] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:45] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:46] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Got RTCP report of 64 bytes [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:47] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:48] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:49] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:50] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5115] chan_sip.c: Auto destroying SIP dialog '8JFByiZMUn0ANpBaUioRy4.BiXppcT23' [Jun 9 13:19:51] DEBUG[5115] chan_sip.c: Destroying SIP dialog 8JFByiZMUn0ANpBaUioRy4.BiXppcT23 [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:51] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Got RTCP report of 64 bytes [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Remote address is null, most likely RTP has been stopped [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Got RTCP report of 72 bytes [Jun 9 13:19:52] DEBUG[5115] chan_sip.c: = Looking for Call ID: axUspGgZnoThdywLU0aVNaMdwnESmhLn (Checking From) --From tag Wj3kQHpT2Znepgq0OP8eKXs.ohI8wu2- --To-tag as42ec810e [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.16.127:5060' into... [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.16.127' and port '5060'. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Setting SIP_ALREADYGONE on dialog axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:52] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28f0057478' [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Session timer stopped: 9 - axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Received bye, issuing owner hangup [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.16.127:5060 [Jun 9 13:19:52] VERBOSE[5153][C-00000000] res_musiconhold.c: -- Stopped music on hold on SIP/8001-00000000 [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_musiconhold.c: Destroying MOH class 'default' [Jun 9 13:19:52] DEBUG[5153][C-00000000] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Jun 9 13:19:52] DEBUG[5153][C-00000000] rtp_engine.c: Oooh, got a hangup [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f28e0012a98' [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Sending reinvite on SIP '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' - It's audio soon redirected to IP 10.24.21.95:5061 [Jun 9 13:19:52] DEBUG[5153][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:52] DEBUG[5153][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: ** Our capability: (ulaw|alaw|g726|g729|g722) Video flag: True Text flag: True [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: ** Our prefcodec: (ulaw) [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: -- Done with adding codecs to SDP [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Done building SDP. Settling with this capability: (ulaw|alaw|g726|g729|g722) [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Initializing already initialized SIP dialog 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (presumably reinvite) [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:52] DEBUG[5153][C-00000000] channel.c: Returning from native bridge, channels: SIP/8001-00000000, SIP/8002-00000001 [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: About to query table structure: SELECT sql FROM sqlite_master WHERE type='table' AND tbl_name='ast_cdr' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: id INTEGER [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: clid VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: src VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: dst VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: dcontext VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: channel VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: dstchannel VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: lastapp VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: lastdata VARCHAR(80) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: start DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: answer DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: end DATETIME NOT NULL DEFAULT '0000-00-00 00:00:00' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: duration INT(11) NOT NULL DEFAULT 0 [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: billsec INT(11) NOT NULL DEFAULT 0 [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: disposition VARCHAR(45) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: amaflags INT(11) NOT NULL DEFAULT 0 [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: accountcode VARCHAR(20) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: uniqueid VARCHAR(32) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: userfield VARCHAR(255) NOT NULL DEFAULT '' [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: Found field: PRIMARY KEY (id) [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_config_sqlite.c: SQL query: INSERT INTO ast_cdr (clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,start,answer,end,duration,billsec,disposition,amaflags,uniqueid) VALUES ('"8001" <8001>','8001','8002','siptest','SIP/8001-00000000','SIP/8002-00000001','Dial','SIP/8002','2017-06-09 13:19:38','2017-06-09 13:19:40','2017-06-09 13:19:52','14','12','ANSWERED','DOCUMENTATION','1497032378.0') [Jun 9 13:19:52] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking To) --From tag as08e7180f --To-tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Acked pending invite 105 [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' of Request 105: Match Found [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: SIP response 200 to RE-invite on outgoing call 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP o=- 192403668 192403673 IN IP4 10.24.18.13... OK. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP b=AS:84... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 0 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:52] DEBUG[5115][C-00000000] rtp_engine.c: Setting payload 96 based on m type on 0x7f28f8aa2280 [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13' into... [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port ''. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 10.24.18.13... OK. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP b=TIAS:64000... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.18.13... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-16... UNSUPPORTED OR FAILED. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Processing media-level (audio) SDP a=sendonly... OK. [Jun 9 13:19:52] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role to CONTROLLING (0x7f28e0012a98) [Jun 9 13:19:52] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Set role failed; no ice instance (0x7f28e0012a98) [Jun 9 13:19:52] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28e0012a98' [Jun 9 13:19:52] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 0 from 0x7f28f8aa2280 to 0x7f28e0012c60 [Jun 9 13:19:52] DEBUG[5115][C-00000000] rtp_engine.c: Copying payload 96 from 0x7f28f8aa2280 to 0x7f28e0012c60 [Jun 9 13:19:52] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f28e0012a98' [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: We're settling with these formats: (ulaw) [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: We have an owner, now see if we need to change this call [Jun 9 13:19:52] DEBUG[5115][C-00000000] format_pref.c: Could not find preferred codec - Going for the best codec [Jun 9 13:19:52] DEBUG[5115][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28e0012a98' [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:52] DEBUG[5115][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Trying to put 'ACK sip:800' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:52] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 2 (In use) [Jun 9 13:19:52] DEBUG[5105] devicestate.c: device 'SIP/8002' state '2' [Jun 9 13:19:52] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Jun 9 13:19:52] DEBUG[5153][C-00000000] channel.c: Hanging up channel 'SIP/8002-00000001' [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Hangup call SIP/8002-00000001, SIP callid 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Updating call counter for outgoing call [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28e0012a98' [Jun 9 13:19:52] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:52] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 1 (Not in use) [Jun 9 13:19:52] DEBUG[5105] devicestate.c: device 'SIP/8002' state '1' [Jun 9 13:19:52] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:52] DEBUG[5153][C-00000000] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:52] DEBUG[5153][C-00000000] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Trying to put 'BYE sip:800' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:52] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 1 (Not in use) [Jun 9 13:19:52] DEBUG[5105] devicestate.c: device 'SIP/8002' state '1' [Jun 9 13:19:52] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:52] DEBUG[5153][C-00000000] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Jun 9 13:19:52] DEBUG[5153][C-00000000] pbx.c: Spawn extension (siptest,8002,1) exited non-zero on 'SIP/8001-00000000' [Jun 9 13:19:52] VERBOSE[5153][C-00000000] pbx.c: == Spawn extension (siptest, 8002, 1) exited non-zero on 'SIP/8001-00000000' [Jun 9 13:19:52] DEBUG[5153][C-00000000] channel.c: Soft-Hanging up channel 'SIP/8001-00000000' [Jun 9 13:19:52] DEBUG[5153][C-00000000] channel.c: Hanging up channel 'SIP/8001-00000000' [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Hangup call SIP/8001-00000000, SIP callid axUspGgZnoThdywLU0aVNaMdwnESmhLn [Jun 9 13:19:52] DEBUG[5153][C-00000000] chan_sip.c: Updating call counter for incoming call [Jun 9 13:19:52] DEBUG[5153][C-00000000] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f28f0057478' [Jun 9 13:19:52] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:52] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 1 (Not in use) [Jun 9 13:19:52] DEBUG[5105] devicestate.c: device 'SIP/8001' state '1' [Jun 9 13:19:52] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8001 [Jun 9 13:19:52] DEBUG[5105] chan_sip.c: Checking device state for peer 8001 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: Changing state for SIP/8001 - state 1 (Not in use) [Jun 9 13:19:52] DEBUG[5105] devicestate.c: device 'SIP/8001' state '1' [Jun 9 13:19:52] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:52] DEBUG[5147] app_queue.c: Device 'SIP/8001' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:52] DEBUG[5115] chan_sip.c: = Looking for Call ID: 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 (Checking To) --From tag as08e7180f --To-tag P1EuuI-yxXPOCUyPKXZybpPiVatb91aQ [Jun 9 13:19:52] DEBUG[5115][C-00000000] chan_sip.c: Stopping retransmission on '3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061' of Request 106: Match Found [Jun 9 13:19:52] DEBUG[5115] chan_sip.c: Destroying SIP dialog 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 [Jun 9 13:19:52] DEBUG[5115] chan_sip.c: Updating call counter for outgoing call [Jun 9 13:19:52] DEBUG[5115] chan_sip.c: This call did not properly clean up call limits. Call ID 3df66f424ffdfc770de5f6a8357fc0fa@10.24.21.95:5061 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: No provider found, checking channel drivers for SIP - 8002 [Jun 9 13:19:52] DEBUG[5105] chan_sip.c: Checking device state for peer 8002 [Jun 9 13:19:52] DEBUG[5105] devicestate.c: Changing state for SIP/8002 - state 1 (Not in use) [Jun 9 13:19:52] DEBUG[5105] devicestate.c: device 'SIP/8002' state '1' [Jun 9 13:19:52] DEBUG[5115] rtp_engine.c: Destroyed RTP instance '0x7f28e0012a98' [Jun 9 13:19:52] DEBUG[5147] app_queue.c: Device 'SIP/8002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: = Looking for Call ID: -OVv9MLbcCyAxUrTggG-PAoIPCXIBAlM (Checking From) --From tag rpUaxE2MzSf1hpdaMW2H3GDHkYxIfATH --To-tag [Jun 9 13:19:53] DEBUG[5115] acl.c: For destination '10.24.18.13', our source address is '10.24.21.95'. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.21.95:5061 [Jun 9 13:19:53] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:53] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: Allocating new SIP dialog for -OVv9MLbcCyAxUrTggG-PAoIPCXIBAlM - SUBSCRIBE (No RTP) [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:53] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:53] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: build_route: Contact hop: "8002" [Jun 9 13:19:53] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:53] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: = Looking for Call ID: -OVv9MLbcCyAxUrTggG-PAoIPCXIBAlM (Checking From) --From tag rpUaxE2MzSf1hpdaMW2H3GDHkYxIfATH --To-tag [Jun 9 13:19:53] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:53] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:53] DEBUG[5115] netsock2.c: Splitting '10.24.21.95:5061' into... [Jun 9 13:19:53] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port '5061'. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE [Jun 9 13:19:53] DEBUG[5115] netsock2.c: Splitting '10.24.18.13:5060' into... [Jun 9 13:19:53] DEBUG[5115] netsock2.c: ...host '10.24.18.13' and port '5060'. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: build_route: Retaining previous route: [Jun 9 13:19:53] DEBUG[5115] netsock2.c: Splitting '10.24.21.95' into... [Jun 9 13:19:53] DEBUG[5115] netsock2.c: ...host '10.24.21.95' and port ''. [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 10.24.18.13:5060 [Jun 9 13:19:53] NOTICE[5115] chan_sip.c: Received SIP subscribe for peer without mailbox: 8002 [Jun 9 13:19:53] DEBUG[5115] chan_sip.c: Destroying SIP dialog -OVv9MLbcCyAxUrTggG-PAoIPCXIBAlM [Jun 9 13:19:56] DEBUG[4856] taskprocessor.c: destroying taskprocessor 'ast_msg_queue' [Jun 9 13:19:56] VERBOSE[4856] asterisk.c: Beginning asterisk shutdown.... [Jun 9 13:19:56] VERBOSE[4856] asterisk.c: Asterisk cleanly ending (0). [Jun 9 13:19:56] VERBOSE[4856] asterisk.c: Executing last minute cleanups [Jun 9 13:19:56] VERBOSE[4856] res_musiconhold.c: == Destroying musiconhold processes [Jun 9 13:19:56] VERBOSE[4856] manager.c: == Manager unregistered action DBGet [Jun 9 13:19:56] VERBOSE[4856] manager.c: == Manager unregistered action DBPut [Jun 9 13:19:56] VERBOSE[4856] manager.c: == Manager unregistered action DBDel [Jun 9 13:19:56] VERBOSE[4856] manager.c: == Manager unregistered action DBDelTree [Jun 9 13:19:56] DEBUG[4856] asterisk.c: Asterisk ending (0).