[Jan 17 16:42:32] Asterisk Ready.
]1;Asterisk]2;Asterisk Console on 'vidar' (pid 13801)*CLI> [Jan 17 16:42:33] DEBUG[13826]: chan_sip.c:4315 find_call: = No match Their Call ID: 111a97905daa142967cecbde004b3d26@216.239.128.123 Their Tag  Our tag: as29958894
[Jan 17 16:42:33] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 0308233b506b7f3409abe0bc1d6779ab@216.239.128.123 Their Tag  Our tag: as64af5734
[Jan 17 16:42:33] DEBUG[13826]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '0308233b506b7f3409abe0bc1d6779ab@216.239.128.123' of Request 102: Match Not Found
[Jan 17 16:42:33] Really destroying SIP dialog '0308233b506b7f3409abe0bc1d6779ab@216.239.128.123' Method: NOTIFY
[Jan 17 16:42:33] DEBUG[13826]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:4315 find_call: = No match Their Call ID: 1df5d5f5142e92061b14bfed134999dc@216.239.128.123 Their Tag  Our tag: as094959ca
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 111a97905daa142967cecbde004b3d26@216.239.128.123 Their Tag  Our tag: as29958894
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '111a97905daa142967cecbde004b3d26@216.239.128.123' of Request 102: Match Not Found
[Jan 17 16:42:35] NOTICE[13826]: chan_sip.c:12001 handle_response_peerpoke: Peer '3213' is now Lagged. (3071ms / 2000ms)
[Jan 17 16:42:35] DEBUG[13826]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3213
[Jan 17 16:42:35] Really destroying SIP dialog '111a97905daa142967cecbde004b3d26@216.239.128.123' Method: OPTIONS
[Jan 17 16:42:35] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3213
[Jan 17 16:42:35] DEBUG[13806]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3213
[Jan 17 16:42:35] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for SIP/3213 - state 5 (Unavailable)
[Jan 17 16:42:35] DEBUG[13835]: app_queue.c:546 changethread: Device 'SIP/3213' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue.
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:4315 find_call: = No match Their Call ID: 1df5d5f5142e92061b14bfed134999dc@216.239.128.123 Their Tag  Our tag: as094959ca
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 492
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 1df5d5f5142e92061b14bfed134999dc@216.239.128.123 Their Tag  Our tag: as094959ca
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '1df5d5f5142e92061b14bfed134999dc@216.239.128.123' of Request 102: Match Not Found
[Jan 17 16:42:35] NOTICE[13826]: chan_sip.c:12001 handle_response_peerpoke: Peer '3201' is now Reachable. (1883ms / 2000ms)
[Jan 17 16:42:35] DEBUG[13826]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3201
[Jan 17 16:42:35] Really destroying SIP dialog '1df5d5f5142e92061b14bfed134999dc@216.239.128.123' Method: OPTIONS
[Jan 17 16:42:35] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3201
[Jan 17 16:42:35] DEBUG[13806]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3201
[Jan 17 16:42:35] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for SIP/3201 - state 1 (Not in use)
[Jan 17 16:42:35] DEBUG[13836]: app_queue.c:546 changethread: Device 'SIP/3201' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 492
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 492
[Jan 17 16:42:35] DEBUG[13826]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 492
[Jan 17 16:42:36] DEBUG[13826]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for e98f1325-2f23e68e-f15b5dfb@216.239.128.199 - REGISTER (No RTP)
[Jan 17 16:42:36] DEBUG[13826]: chan_sip.c:14453 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 17 16:42:36] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: e98f1325-2f23e68e-f15b5dfb@216.239.128.199 Their Tag B47C66FC-B254AB61 Our tag: as425d1ec2
[Jan 17 16:42:36] DEBUG[13826]: chan_sip.c:14453 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 17 16:42:36]     -- Saved useragent "PolycomSoundPointIP-SPIP_650-UA/2.0.3.0131" for peer 3213
[Jan 17 16:42:36] DEBUG[13826]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3213
[Jan 17 16:42:36] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3213
[Jan 17 16:42:36] DEBUG[13806]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3213
[Jan 17 16:42:36] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for SIP/3213 - state 5 (Unavailable)
[Jan 17 16:42:36] DEBUG[13837]: app_queue.c:546 changethread: Device 'SIP/3213' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue.
[Jan 17 16:42:37] DEBUG[13826]: chan_sip.c:4315 find_call: = No match Their Call ID: e98f1325-2f23e68e-f15b5dfb@216.239.128.199 Their Tag B47C66FC-B254AB61 Our tag: as425d1ec2
[Jan 17 16:42:37] DEBUG[13826]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for 123ad5f3-486903d4-63ccf499@216.239.128.199 - REGISTER (No RTP)
[Jan 17 16:42:37] DEBUG[13826]: chan_sip.c:14453 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 17 16:42:37] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 123ad5f3-486903d4-63ccf499@216.239.128.199 Their Tag 137C552-A0F05D4F Our tag: as72b92e70
[Jan 17 16:42:37] DEBUG[13826]: chan_sip.c:14453 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 17 16:42:37]     -- Saved useragent "PolycomSoundPointIP-SPIP_650-UA/2.0.3.0131" for peer 3201
[Jan 17 16:42:37] DEBUG[13826]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3201
[Jan 17 16:42:37] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3201
[Jan 17 16:42:37] DEBUG[13806]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3201
[Jan 17 16:42:37] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for SIP/3201 - state 1 (Not in use)
[Jan 17 16:42:37] DEBUG[13838]: app_queue.c:546 changethread: Device 'SIP/3201' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 17 16:42:43] DEBUG[13826]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Jan 17 16:42:43] DEBUG[13826]: chan_sip.c:3080 sip_destroy: Destroying SIP dialog 4c909aac65d5974559e43ce06a0003a9@216.239.128.123
[Jan 17 16:42:43] Really destroying SIP dialog '4c909aac65d5974559e43ce06a0003a9@216.239.128.123' Method: NOTIFY
[Jan 17 16:42:43] DEBUG[13826]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Jan 17 16:42:43] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 4ce1a9be43909e676a726f34400638bb@216.239.128.123 Their Tag  Our tag: as3a701327
[Jan 17 16:42:43] DEBUG[13826]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '4ce1a9be43909e676a726f34400638bb@216.239.128.123' of Request 102: Match Not Found
[Jan 17 16:42:43] Really destroying SIP dialog '4ce1a9be43909e676a726f34400638bb@216.239.128.123' Method: NOTIFY
[Jan 17 16:42:45] DEBUG[13826]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 17 16:42:45] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 05455cc05e4eaaa6436bef0021bd201c@216.239.128.123 Their Tag  Our tag: as0af6fee2
[Jan 17 16:42:45] DEBUG[13826]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '05455cc05e4eaaa6436bef0021bd201c@216.239.128.123' of Request 102: Match Not Found
[Jan 17 16:42:45] NOTICE[13826]: chan_sip.c:12001 handle_response_peerpoke: Peer '3213' is now Reachable. (14ms / 2000ms)
[Jan 17 16:42:45] DEBUG[13826]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3213
[Jan 17 16:42:45] Really destroying SIP dialog '05455cc05e4eaaa6436bef0021bd201c@216.239.128.123' Method: OPTIONS
[Jan 17 16:42:45] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3213
[Jan 17 16:42:45] DEBUG[13806]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3213
[Jan 17 16:42:45] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for SIP/3213 - state 1 (Not in use)
[Jan 17 16:42:45] DEBUG[13840]: app_queue.c:546 changethread: Device 'SIP/3213' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 17 16:42:48] DEBUG[13839]: manager.c:1931 process_message: Manager received command 'login'
[Jan 17 16:42:48]   == Parsing '/etc/asterisk/manager.conf': [Jan 17 16:42:48] DEBUG[13839]: config.c:844 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 17 16:42:48] Found
[Jan 17 16:42:48]   == Manager 'manager' logged on from 216.239.128.100
[Jan 17 16:42:52] DEBUG[13839]: manager.c:1931 process_message: Manager received command 'queues'
[Jan 17 16:42:58] DEBUG[13811]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel IAX2/3221
[Jan 17 16:42:58] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for IAX2 - 3221
[Jan 17 16:42:58] DEBUG[13806]: chan_iax2.c:9600 iax2_devicestate: Checking device state for device 3221
[Jan 17 16:42:58] DEBUG[13806]: chan_iax2.c:9608 iax2_devicestate: iax2_devicestate: Found peer. What's device state of 3221? addr=-2122256424, defaddr=0 maxms=0, lastms=0
[Jan 17 16:42:58] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for IAX2/3221 - state 1 (Not in use)
[Jan 17 16:42:58] DEBUG[13841]: app_queue.c:546 changethread: Device 'IAX2/3221' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 17 16:43:00] DEBUG[13839]: manager.c:1931 process_message: Manager received command 'queueadd'
[Jan 17 16:43:00] DEBUG[13839]: app_queue.c:705 add_to_interfaces: Adding SIP/3201 to the list of interfaces that make up all of our queue members.
[Jan 17 16:43:00] DEBUG[13839]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3201
[Jan 17 16:43:00] DEBUG[13839]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3201
[Jan 17 16:43:06] DEBUG[13826]: chan_sip.c:4315 find_call: = No match Their Call ID: 123ad5f3-486903d4-63ccf499@216.239.128.199 Their Tag 137C552-A0F05D4F Our tag: as72b92e70
[Jan 17 16:43:06] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: e98f1325-2f23e68e-f15b5dfb@216.239.128.199 Their Tag B47C66FC-B254AB61 Our tag: as425d1ec2
[Jan 17 16:43:06] DEBUG[13826]: chan_sip.c:14453 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 17 16:43:06] DEBUG[13826]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3213
[Jan 17 16:43:06] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3213
[Jan 17 16:43:06] DEBUG[13806]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3213
[Jan 17 16:43:06] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for SIP/3213 - state 1 (Not in use)
[Jan 17 16:43:06] DEBUG[13842]: app_queue.c:546 changethread: Device 'SIP/3213' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 17 16:43:07] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 123ad5f3-486903d4-63ccf499@216.239.128.199 Their Tag 137C552-A0F05D4F Our tag: as72b92e70
[Jan 17 16:43:07] DEBUG[13826]: chan_sip.c:14453 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 17 16:43:07] DEBUG[13826]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/3201
[Jan 17 16:43:07] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 3201
[Jan 17 16:43:07] DEBUG[13806]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 3201
[Jan 17 16:43:07] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for SIP/3201 - state 1 (Not in use)
[Jan 17 16:43:07] DEBUG[13843]: app_queue.c:552 changethread: Device 'SIP/3201' changed to state '1' (Not in use)
[Jan 17 16:43:13]     -- Accepting AUTHENTICATED call from 216.239.128.129:
       > requested format = gsm,
       > requested prefs = (),
       > actual format = ulaw,
       > host prefs = (ulaw|gsm),
       > priority = mine
[Jan 17 16:43:13] DEBUG[13844]: pbx.c:1767 pbx_extension_helper: Launching 'Answer'
[Jan 17 16:43:13]     -- Executing [99913@outbound:1] Answer("IAX2/3221-2", "") in new stack
[Jan 17 16:43:13] DEBUG[13844]: chan_iax2.c:3179 iax2_answer: Answering IAX2 call
[Jan 17 16:43:13] DEBUG[13844]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel IAX2/3221-2
[Jan 17 16:43:13] DEBUG[13806]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for IAX2 - 3221
[Jan 17 16:43:13] DEBUG[13806]: chan_iax2.c:9600 iax2_devicestate: Checking device state for device 3221
[Jan 17 16:43:13] DEBUG[13806]: chan_iax2.c:9608 iax2_devicestate: iax2_devicestate: Found peer. What's device state of 3221? addr=-2122256424, defaddr=0 maxms=0, lastms=0
[Jan 17 16:43:13] DEBUG[13806]: devicestate.c:287 do_state_change: Changing state for IAX2/3221 - state 2 (In use)
[Jan 17 16:43:13] DEBUG[13845]: app_queue.c:546 changethread: Device 'IAX2/3221' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Jan 17 16:43:13] DEBUG[13844]: pbx.c:1767 pbx_extension_helper: Launching 'Queue'
[Jan 17 16:43:13]     -- Executing [99913@outbound:2] Queue("IAX2/3221-2", "omniscustomerservice|i") in new stack
[Jan 17 16:43:13] DEBUG[13844]: app_queue.c:3325 queue_exec: NO QUEUE_PRIO variable found. Using default.
[Jan 17 16:43:13] DEBUG[13844]: app_queue.c:3348 queue_exec: queue: omniscustomerservice, options: i, url: (null), announce: (null), expires: 0, priority: 0
[Jan 17 16:43:13] DEBUG[13844]: app_queue.c:1207 join_queue: Queue 'omniscustomerservice' Join, Channel 'IAX2/3221-2', Position '1'
[Jan 17 16:43:13] DEBUG[13844]: channel.c:2731 set_format: Set channel IAX2/3221-2 to write format slin
[Jan 17 16:43:13]     -- Started music on hold, class 'default', on channel 'IAX2/3221-2'
[Jan 17 16:43:13] DEBUG[13844]: channel.c:1908 ast_settimeout: Scheduling timer at 160 sample intervals
[Jan 17 16:43:13] DEBUG[13844]: app_queue.c:2129 is_our_turn: There are 1 available members.
[Jan 17 16:43:13] DEBUG[13844]: app_queue.c:2139 is_our_turn: It's our turn (IAX2/3221-2).
[Jan 17 16:43:13] DEBUG[13844]: app_queue.c:2357 try_calling: IAX2/3221-2 is trying to call a queue member.
[Jan 17 16:43:13] DEBUG[13844]: app_queue.c:1724 ring_one: Trying 'SIP/3201' with metric 0
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:15106 sip_request_call: Asked to create a SIP channel with formats: 0x4 (ulaw)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:2559 do_setnat: Setting NAT on RTP to Off
[Jan 17 16:43:13] DEBUG[13844]: frame.c:1252 ast_codec_choose: Could not find preferred codec - Going for the best codec
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:3766 sip_new: *** Our native formats are 0x4 (ulaw)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:3767 sip_new: *** Joint capabilities are 0x0 (nothing)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:3768 sip_new: *** Our capabilities are 0x8000e (gsm|ulaw|alaw|h263)
[Jan 17 16:43:13] DEBUG[13844]: frame.c:1252 ast_codec_choose: Could not find preferred codec - Going for the best codec
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:3769 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:3771 sip_new: *** Our preferred formats from the incoming channel are 0x4 (ulaw)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:3792 sip_new: This channel will not be able to handle video.
[Jan 17 16:43:13] DEBUG[13844]: channel.c:3185 ast_channel_inherit_variables: Not copying variable STACK-outbound-99913-2.
[Jan 17 16:43:13] DEBUG[13844]: channel.c:3185 ast_channel_inherit_variables: Not copying variable STACK-outbound-99913-1.
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:2812 sip_call: Outgoing Call for 3201
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:2978 update_call_counter: Updating call counter for outgoing call
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:2826 sip_call: Our T38 capability (0), joint T38 capability (0)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:6122 add_sdp: ** Our capability: 0x8000e (gsm|ulaw|alaw|h263) Video flag: False
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:6123 add_sdp: ** Our prefcodec: 0x4 (ulaw)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:6140 add_sdp: This call needs video offers, but there's no video support enabled!
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:6254 add_sdp: -- Done with adding codecs to SDP
[Jan 17 16:43:13] DEBUG[13844]: channel.c:2274 ast_internal_timing_enabled: Internal timing is disabled (option_internal_timing=0 chan->timingfd=28)
[Jan 17 16:43:13] DEBUG[13844]: chan_sip.c:6299 add_sdp: Done building SDP. Settling with this capability: 0x8000e (gsm|ulaw|alaw|h263)
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 1937504918989b552039484043343f33@216.239.128.123 Their Tag  Our tag: as10cc5bd3
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:2113 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '1937504918989b552039484043343f33@216.239.128.123' Request 102: Found
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:11531 handle_response_invite: SIP response 100 to standard invite
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:4315 find_call: = Found Their Call ID: 1937504918989b552039484043343f33@216.239.128.123 Their Tag 28CA7EFA-5F8AB497 Our tag: as10cc5bd3
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:2053 __sip_ack: Acked pending invite 102
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '1937504918989b552039484043343f33@216.239.128.123' of Request 102: Match Not Found
[Jan 17 16:43:13]     -- Got SIP response 302 "Moved Temporarily" back from 216.239.128.199
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:11489 parse_moved_contact: Received 302 Redirect to extension '3213' (domain voip.omnis.com)
[Jan 17 16:43:13]     -- Forwarding IAX2/3221-2 to '3213' prevented.
[Jan 17 16:43:13] DEBUG[13826]: chan_sip.c:1615 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 1937504918989b552039484043343f33@216.239.128.123