[root@app11 clarity]# grep "Jan 18 11:04:19" /var/log/asterisk/full [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Result of 'EXTEN' is '13473654898' [Jan 18 11:04:19] DEBUG[15264] pbx.c: Expression result is '0' [Jan 18 11:04:19] DEBUG[15264] pbx.c: Launching 'GotoIf' [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=3.81E+04, Et=1.91E+07, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=9.43E+03, Et=7.37E+05, s/n= 0.01 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=3.62E+02, Et=1.31E+06, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=8.61E+04, Et=4.40E+05, s/n= 0.24 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Not taking any branch [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=1.60E+03, Et=6.55E+05, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=1.61E+05, Et=1.18E+07, s/n= 0.01 [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Result of 'EXTEN' is '13473654898' [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=8.60E+04, Et=7.37E+05, s/n= 0.13 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Expression result is '0' [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=5.30E+04, Et=6.76E+05, s/n= 0.09 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Launching 'GotoIf' [Jan 18 11:04:19] DEBUG[15264] pbx.c: Not taking any branch [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Result of 'EXTEN' is '13473654898' [Jan 18 11:04:19] DEBUG[15264] pbx.c: Expression result is '0' [Jan 18 11:04:19] DEBUG[15264] pbx.c: Launching 'GotoIf' [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=2.55E+05, Et=2.24E+07, s/n= 0.01 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Not taking any branch [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=2.34E+04, Et=6.55E+05, s/n= 0.04 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=5.22E+02, Et=7.99E+05, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=7.80E+04, Et=6.96E+05, s/n= 0.13 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15264] pbx.c: Result of 'EXTEN' is '13473654898' [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=3.41E+04, Et=5.43E+05, s/n= 0.07 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Expression result is '0' [Jan 18 11:04:19] DEBUG[15264] pbx.c: Launching 'GotoIf' [Jan 18 11:04:19] DEBUG[15264] pbx.c: Not taking any branch [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=1.00E+05, Et=1.10E+07, s/n= 0.01 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=3.03E+03, Et=3.75E+07, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15264] pbx.c: Launching 'ctc_inbound' [Jan 18 11:04:19] DEBUG[15264] app_clarity.c: ctc_inbound [Jan 18 11:04:19] DEBUG[15264] app_clarity.c: ctc_inbound SQL [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=9.52E+03, Et=6.55E+05, s/n= 0.01 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=2.92E+05, Et=9.16E+06, s/n= 0.03 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15264] app_clarity.c: ctc_inbound SQL=sql://crystal:ncc1701d@data/crystal [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15264] app_clarity.c: ctc_inbound DID=13473654898 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=1.48E+03, Et=7.07E+05, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15264] app_clarity.c: ctc_inbound ANI=7187154835 [Jan 18 11:04:19] DEBUG[15264] app_clarity.c: ctc_inbound parsed the SQL [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=2.17E+04, Et=1.23E+07, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=1.74E+05, Et=2.88E+08, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=4.08E+03, Et=7.58E+06, s/n= 0.00 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=6.31E+04, Et=6.76E+05, s/n= 0.10 [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=4.39E+04, Et=6.35E+05, s/n= 0.07 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=4.58E+05, Et=1.62E+08, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=8.72E+04, Et=6.76E+05, s/n= 0.15 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=1.44E+05, Et=4.53E+06, s/n= 0.03 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=4.33E+03, Et=5.53E+05, s/n= 0.01 [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=8.85E+05, Et=2.46E+08, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=1.06E+05, Et=6.14E+05, s/n= 0.21 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=1.24E+04, Et=3.41E+06, s/n= 0.00 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=8.62E+04, Et=6.45E+05, s/n= 0.15 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=1.24E+05, Et=1.19E+08, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=1.22E+03, Et=7.37E+05, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=1.05E+05, Et=2.37E+06, s/n= 0.05 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=9.76E+04, Et=6.86E+05, s/n= 0.17 [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[6702] chan_sip.c: = Looking for Call ID: 5a2577533ac161b16c3413682503c5e3@67.192.61.166:5068 (Checking From) --From tag 21cb6-f41b-c90 --To-tag as00ece672 [Jan 18 11:04:19] DEBUG[6702] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Jan 18 11:04:19] DEBUG[6702] netsock2.c: Splitting '72.32.185.51' into... [Jan 18 11:04:19] DEBUG[6702] netsock2.c: ...host '72.32.185.51' and port ''. [Jan 18 11:04:19] DEBUG[6702] chan_sip.c: Setting SIP_ALREADYGONE on dialog 5a2577533ac161b16c3413682503c5e3@67.192.61.166:5068 [Jan 18 11:04:19] DEBUG[6702] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7fb1c0dc18f8' [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=1.11E+05, Et=8.94E+06, s/n= 0.01 [Jan 18 11:04:19] DEBUG[9383] channel.c: Deadlock avoided for write to channel 'SIP/clarity.1-000003aa' [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[6702] chan_sip.c: Received bye, issuing owner hangup [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=8.69E+04, Et=6.76E+05, s/n= 0.15 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=3.06E+04, Et=5.32E+05, s/n= 0.06 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=1.26E+03, Et=1.28E+06, s/n= 0.00 [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[6702] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 72.32.185.51:5060 [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: Asked to create a SIP channel with formats: 0x4 (ulaw) [Jan 18 11:04:19] DEBUG[9384] channel.c: Hanging up channel 'SIP/clarity.1-000003aa' [Jan 18 11:04:19] DEBUG[9384] chan_sip.c: Hanging up zombie call. Be scared. [Jan 18 11:04:19] DEBUG[9384] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7fb1c0dc18f8' [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: Allocating new SIP dialog for 177311cf7a212f9f1eaef31124e47b4d@67.192.61.166:0 - INVITE (No RTP) [Jan 18 11:04:19] DEBUG[6695] devicestate.c: No provider found, checking channel drivers for SIP - clarity.1 [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: Checking device state for peer clarity.1 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=1.36E+05, Et=3.17E+07, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15264] res_config_mysql.c: MySQL RealTime: Connection okay. [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15264] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM ast_sip_peers WHERE name = 'clarity.6' AND host = 'dynamic' [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=3.27E+03, Et=1.06E+06, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=7.22E+02, Et=1.74E+05, s/n= 0.00 [Jan 18 11:04:19] DEBUG[6695] res_config_mysql.c: MySQL RealTime: Connection okay. [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=8.61E+04, Et=6.55E+05, s/n= 0.15 [Jan 18 11:04:19] DEBUG[6695] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM ast_sip_peers WHERE name = 'clarity.1' AND host = 'dynamic' [Jan 18 11:04:19] DEBUG[15264] res_config_mysql.c: MySQL RealTime: Connection okay. [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=1.41E+05, Et=8.25E+06, s/n= 0.02 [Jan 18 11:04:19] DEBUG[15264] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM ast_sip_peers WHERE name = 'clarity.6' [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: -REALTIME- peer built. Name: clarity.6. Peer objects: 144 [Jan 18 11:04:19] DEBUG[6695] res_config_mysql.c: MySQL RealTime: Connection okay. [Jan 18 11:04:19] DEBUG[6695] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM ast_sip_peers WHERE name = 'clarity.1' [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: -REALTIME- peer built. Name: clarity.1. Peer objects: 145 [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Jan 18 11:04:19] DEBUG[15264] netsock2.c: Splitting '208.94.157.10' into... [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=9.03E+04, Et=1.14E+06, s/n= 0.09 [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: -REALTIME- loading peer from database to memory. Name: clarity.1. Peer objects: 145 [Jan 18 11:04:19] DEBUG[15264] netsock2.c: ...host '208.94.157.10' and port ''. [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: Destroying SIP peer clarity.1 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=1.11E+04, Et=6.76E+05, s/n= 0.02 [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: Not an IPv4 nor IPv6 address, cannot get port. [Jan 18 11:04:19] DEBUG[6695] chan_sip.c: -REALTIME- peer Destroyed. Name: clarity.1. Realtime Peer objects: 144 [Jan 18 11:04:19] DEBUG[6695] devicestate.c: Changing state for SIP/clarity.1 - state 5 (Unavailable) [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: Not an IPv4 nor IPv6 address, cannot set port. [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=2.21E+05, Et=3.78E+07, s/n= 0.01 [Jan 18 11:04:19] DEBUG[6695] devicestate.c: device 'SIP/clarity.1' state '5' [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: -REALTIME- loading peer from database to memory. Name: clarity.6. Peer objects: 144 [Jan 18 11:04:19] DEBUG[6709] app_clarity_queue.c: Device 'SIP/clarity.1' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue. [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15264] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7fb1c0d066b8' [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15264] res_rtp_asterisk.c: Allocated port 18198 for RTP instance '0x7fb1c0d066b8' [Jan 18 11:04:19] DEBUG[15264] rtp_engine.c: RTP instance '0x7fb1c0d066b8' is setup and ready to go [Jan 18 11:04:19] DEBUG[15264] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7fb1c0d066b8' [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=6.46E+03, Et=9.11E+05, s/n= 0.01 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=8.60E+04, Et=8.60E+05, s/n= 0.11 [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: Setting NAT on RTP to On [Jan 18 11:04:19] DEBUG[15264] acl.c: For destination '208.94.157.10', our source address is '67.192.61.166'. [Jan 18 11:04:19] DEBUG[15264] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 67.192.61.166:5068 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=2.74E+05, Et=4.28E+07, s/n= 0.01 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=9.14E+04, Et=6.86E+05, s/n= 0.15 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=8.61E+04, Et=1.01E+06, s/n= 0.09 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=8.57E+04, Et=8.19E+05, s/n= 0.12 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=8.52E+04, Et=7.07E+05, s/n= 0.14 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=4.41E+04, Et=6.55E+05, s/n= 0.07 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=5.50E+05, Et=1.16E+07, s/n= 0.05 [Jan 18 11:04:19] DEBUG[15180] dsp.c: tone 1100, Ew=2.61E+04, Et=6.04E+05, s/n= 0.05 [Jan 18 11:04:19] DEBUG[14944] res_rtp_asterisk.c: No remote address on RTP instance '0x7fb1f80d9f68' so dropping frame [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=8.55E+04, Et=7.37E+05, s/n= 0.13 [Jan 18 11:04:19] DEBUG[13381] app_fax.c: frame 2/64, len=320 [Jan 18 11:04:19] DEBUG[15207] dsp.c: tone 1100, Ew=2.44E+03, Et=6.55E+05, s/n= 0.00 [Jan 18 11:04:19] DEBUG[15230] dsp.c: tone 1100, Ew=9.97E+04, Et=6.65E+06, s/n= 0.02 [Jan 18 11:04:19] DEBUG[15241] dsp.c: tone 1100, Ew=8.59E+04, Et=6.25E+05, s/n= 0.16