[Aug 23 12:31:38]     -- Accepting AUTHENTICATED call from 10.0.0.2:
       > requested format = ulaw,
       > requested prefs = (),
       > actual format = gsm,
       > host prefs = (gsm|ulaw),
       > priority = mine
[Aug 23 12:31:38]     -- Executing AGI("IAX2/gate@10.0.0.2:4569-6", "voxtel/voxtel_standard.agi|65") in new stack
[Aug 23 12:31:38] DEBUG[16579]: chan_sip.c:1207 __sip_ack: Stopping retransmission on '3d301d8851d868275433f87437da8976@192.168.1.15' of Request 102: Found
[Aug 23 12:31:38]     -- Launched AGI Script /var/lib/asterisk/agi-bin/voxtel/voxtel_standard.agi
[Aug 23 12:31:38] DEBUG[16573]: chan_iax2.c:6519 socket_read: Ooh, voice format changed to 2
[Aug 23 12:31:38]     -- AGI Script Executing Application: (setLanguage) Options: (fr)
[Aug 23 12:31:38] DEBUG[18204]: chan_iax2.c:3462 calc_timestamp: predicted timestamp skew (273) > max (160), using real ts instead.
[Aug 23 12:31:38] DEBUG[18204]: channel.c:1388 ast_settimeout: Scheduling timer at 160 sample intervals
[Aug 23 12:31:38]     -- Playing 'voxtel/enter_extension' (language 'fr')
[Aug 23 12:31:40] DEBUG[18204]: channel.c:1388 ast_settimeout: Scheduling timer at 0 sample intervals
[Aug 23 12:31:41]     -- AGI Script Executing Application: (DIAL) Options: (SIP/100|20|t)
[Aug 23 12:31:41] DEBUG[18204]: chan_sip.c:1689 create_addr_from_peer: Setting NAT on RTP to 524288
[Aug 23 12:31:41] DEBUG[18204]: chan_sip.c:1874 sip_call: Outgoing Call for 100
[Aug 23 12:31:41]     -- Called 100
[Aug 23 12:31:41] DEBUG[16579]: chan_sip.c:1257 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '5d3715d86c37d6a400035d91315c2730@192.168.1.15' Request 102: Found
[Aug 23 12:31:41] DEBUG[16579]: chan_sip.c:1257 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '5d3715d86c37d6a400035d91315c2730@192.168.1.15' Request 102: Found
[Aug 23 12:31:41]     -- SIP/100-dc55 is ringing
[Aug 23 12:31:44] DEBUG[16579]: chan_sip.c:908 parse_sip_options: * SIP extension value: 2 for call 475eccaf-4d4e139-fb89d768@192.168.1.235
[Aug 23 12:31:44] DEBUG[16579]: chan_sip.c:6264 check_user_full: Setting NAT on RTP to 0
[Aug 23 12:31:44] DEBUG[16579]: chan_sip.c:1207 __sip_ack: Stopping retransmission on '475eccaf-4d4e139-fb89d768@192.168.1.235' of Response 1: Found
[Aug 23 12:31:44] DEBUG[16579]: chan_sip.c:6264 check_user_full: Setting NAT on RTP to 0
[Aug 23 12:31:44] DEBUG[16579]: chan_sip.c:9111 handle_request_invite: Checking SIP call limits for device 154
[Aug 23 12:31:44] DEBUG[16579]: chan_sip.c:5414 build_route: build_route: Contact hop: <sip:154@192.168.1.235>
[Aug 23 12:31:44] DEBUG[16565]: channel.c:699 channel_find_locked: Avoiding initial deadlock for 'SIP/154-0b7d'
[Aug 23 12:31:44]     -- Executing Pickup("SIP/154-0b7d", "100") in new stack
[Aug 23 12:31:44] DEBUG[18211]: app_directed_pickup.c:90 pickup_exec: No call pickup possible...
[Aug 23 12:31:44]   == Auto fallthrough, channel 'SIP/154-0b7d' status is 'UNKNOWN'
[Aug 23 12:31:44] DEBUG[18211]: chan_sip.c:2234 sip_hangup: update_user_counter(154) - decrement inUse counter
[Aug 23 12:31:44] DEBUG[16579]: chan_sip.c:1207 __sip_ack: Stopping retransmission on '475eccaf-4d4e139-fb89d768@192.168.1.235' of Response 2: Found
[Aug 23 12:31:50] DEBUG[16573]: chan_iax2.c:6792 socket_read: Immediately destroying 6, having received hangup
[Aug 23 12:31:50] DEBUG[18204]: chan_sip.c:2231 sip_hangup: update_user_counter(100) - decrement outUse counter
[Aug 23 12:31:50] DEBUG[18204]: app_dial.c:1656 dial_exec_full: Exiting with DIALSTATUS=CANCEL.
[Aug 23 12:31:50] DEBUG[18204]: res_agi.c:1787 run_agi: IAX2/gate@10.0.0.2:4569-6 hungup
[Aug 23 12:31:50]   == Spawn extension (test_sip, 5145, 1) exited non-zero on 'IAX2/gate@10.0.0.2:4569-6'
[Aug 23 12:31:50] DEBUG[18204]: chan_iax2.c:2965 iax2_hangup: We're hanging up IAX2/gate@10.0.0.2:4569-6 now...
[Aug 23 12:31:50] DEBUG[18204]: chan_iax2.c:2975 iax2_hangup: Really destroying IAX2/gate@10.0.0.2:4569-6 now...
[Aug 23 12:31:50]     -- Hungup 'IAX2/gate@10.0.0.2:4569-6'
[Aug 23 12:31:50] DEBUG[16579]: chan_sip.c:1189 __sip_ack: Acked pending invite 102
[Aug 23 12:31:50] DEBUG[16579]: chan_sip.c:1207 __sip_ack: Stopping retransmission on '5d3715d86c37d6a400035d91315c2730@192.168.1.15' of Request 102: Found
[Aug 23 12:31:50] DEBUG[16579]: chan_sip.c:1207 __sip_ack: Stopping retransmission on '5d3715d86c37d6a400035d91315c2730@192.168.1.15' of Request 102: Found
[Aug 23 12:31:51] DEBUG[16579]: chan_sip.c:1135 __sip_autodestruct: Auto destroying call 'e7dede7b-6c65ac65-35b9bc36@192.168.1.238'
[Aug 23 12:31:52] DEBUG[16579]: chan_sip.c:1135 __sip_autodestruct: Auto destroying call 'ec004bb5-c6f6a3df-61a9c57e@192.168.1.235'
[Aug 23 12:31:54] DEBUG[16579]: chan_sip.c:1135 __sip_autodestruct: Auto destroying call 'a588e1f-7259aba9-13fe9f58@192.168.1.235'
[Aug 23 12:31:54] DEBUG[16579]: chan_sip.c:1135 __sip_autodestruct: Auto destroying call '3277e283-a7fcaad-d7c9e11e@192.168.1.238'
asterisk*CLI>