[Apr 18 19:10:16] Asterisk SVN-branch-11-r386006 built by root @ ubuntu on a x86_64 running Linux on 2013-04-18 21:43:29 UTC [Apr 18 19:10:16] DEBUG[17061] config.c: Parsing /etc/asterisk/logger.conf [Apr 18 19:10:16] VERBOSE[17061] config.c: == Parsing '/etc/asterisk/logger.conf': Found [Apr 18 19:10:16] VERBOSE[17061] logger.c: Asterisk Queue Logger restarted [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: = Looking for Call ID: b83bc983-ce73af12-897471cd@10.24.18.180 (Checking From) --From tag 4757056-53849461 --To-tag [Apr 18 19:10:21] DEBUG[16475] acl.c: For destination '10.24.18.180', our source address is '10.24.18.124'. [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.18.124:5060 [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.180:5060' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.180' and port '5060'. [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: Allocating new SIP dialog for b83bc983-ce73af12-897471cd@10.24.18.180 - REGISTER (No RTP) [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.180:5060' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.180' and port '5060'. [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.180:5060 [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: = Looking for Call ID: b83bc983-ce73af12-897471cd@10.24.18.180 (Checking From) --From tag 4757056-53849461 --To-tag [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.124:5060' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.124' and port '5060'. [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.124:5060' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.124' and port '5060'. [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.180:5060' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.180' and port '5060'. [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: Store REGISTER's Contact header for call routing. [Apr 18 19:10:21] DEBUG[16475] netsock2.c: Splitting '10.24.18.180:5060' into... [Apr 18 19:10:21] DEBUG[16475] netsock2.c: ...host '10.24.18.180' and port '5060'. [Apr 18 19:10:21] DEBUG[16475] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.180:5060 [Apr 18 19:10:21] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for SIP - 6003 [Apr 18 19:10:21] DEBUG[16464] chan_sip.c: Checking device state for peer 6003 [Apr 18 19:10:21] DEBUG[16464] devicestate.c: Changing state for SIP/6003 - state 1 (Not in use) [Apr 18 19:10:21] DEBUG[16464] devicestate.c: device 'SIP/6003' state '1' [Apr 18 19:10:21] DEBUG[16499] app_queue.c: Device 'SIP/6003' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Apr 18 19:10:21] DEBUG[17061][C-0000000a] netsock2.c: Splitting '239.255.255.245:5555' into... [Apr 18 19:10:21] DEBUG[17061][C-0000000a] netsock2.c: ...host '239.255.255.245' and port '5555'. [Apr 18 19:10:21] DEBUG[17061][C-0000000a] rtp_engine.c: Using engine 'multicast' for RTP instance '0x7f08f40195a8' [Apr 18 19:10:21] DEBUG[17061][C-0000000a] rtp_engine.c: RTP instance '0x7f08f40195a8' is setup and ready to go [Apr 18 19:10:21] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for MulticastRTP - 0x7f08f40195a8 [Apr 18 19:10:21] DEBUG[16464] devicestate.c: Changing state for MulticastRTP/0x7f08f40195a8 - state 0 (Unknown) [Apr 18 19:10:21] VERBOSE[17240][C-0000000a] pbx.c: -- Launching Playback(/var/lib/asterisk/moh/macroform-cold_day) on MulticastRTP/0x7f08f40195a8 [Apr 18 19:10:21] DEBUG[16464] devicestate.c: device 'MulticastRTP/0x7f08f40195a8' state '0' [Apr 18 19:10:21] DEBUG[16499] app_queue.c: Device 'MulticastRTP/0x7f08f40195a8' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Apr 18 19:10:21] DEBUG[17240][C-0000000a] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Apr 18 19:10:21] VERBOSE[17240][C-0000000a] file.c: -- Playing '/var/lib/asterisk/moh/macroform-cold_day.slin' (language 'en') [Apr 18 19:10:53] DEBUG[16475] chan_sip.c: Auto destroying SIP dialog 'b83bc983-ce73af12-897471cd@10.24.18.180' [Apr 18 19:10:53] DEBUG[16475] chan_sip.c: Destroying SIP dialog b83bc983-ce73af12-897471cd@10.24.18.180 [Apr 18 19:11:10] DEBUG[17061] channel.c: Soft-Hanging up channel 'MulticastRTP/0x7f08f40195a8' [Apr 18 19:11:10] DEBUG[17240][C-0000000a] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Apr 18 19:11:10] DEBUG[17240][C-0000000a] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Apr 18 19:11:10] DEBUG[17240][C-0000000a] channel.c: Hanging up channel 'MulticastRTP/0x7f08f40195a8' [Apr 18 19:11:10] DEBUG[17240][C-0000000a] rtp_engine.c: Destroyed RTP instance '0x7f08f40195a8' [Apr 18 19:11:10] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for MulticastRTP - 0x7f08f40195a8 [Apr 18 19:11:10] DEBUG[16464] devicestate.c: Changing state for MulticastRTP/0x7f08f40195a8 - state 0 (Unknown) [Apr 18 19:11:10] DEBUG[16464] devicestate.c: device 'MulticastRTP/0x7f08f40195a8' state '0' [Apr 18 19:11:10] DEBUG[16499] app_queue.c: Device 'MulticastRTP/0x7f08f40195a8' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.