Jun 12 05:23:35 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '21b8de7748f352a17459c28002fcf5a7@202.5.145.56' of Request 102: Match FoundJun 12 05:23:53 DEBUG[19990]: chan_sip.c:7042 check_user_full: Setting NAT on RTP to 524288Jun 12 05:23:53 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '15066115e110ff6fMDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.' of Response 1: Match FoundJun 12 05:23:53 DEBUG[19990]: chan_sip.c:7042 check_user_full: Setting NAT on RTP to 524288Jun 12 05:23:53 DEBUG[19990]: chan_sip.c:10354 handle_request_invite: Checking SIP call limits for device 701Jun 12 05:23:53 DEBUG[19990]: chan_sip.c:6047 build_route: build_route: Contact hop: <sip:701@192.168.129.109:58956> -- Executing AddQueueMember("SIP/701-da61", "techsupport|SIP/701") in new stackJun 12 05:23:53 WARNING[20033]: app_queue.c:2840 aqm_exec: Unable to add interface 'SIP/701' to queue 'techsupport': Already there -- Executing Playback("SIP/701-da61", "agent-loginok") in new stack -- Playing 'agent-loginok' (language 'en')Jun 12 05:23:53 DEBUG[20033]: chan_sip.c:2981 sip_rtp_read: Oooh, format changed to 4Jun 12 05:23:53 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '15066115e110ff6fMDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.' of Response 2: Match Found == Spawn extension (default, 104, 2) exited non-zero on 'SIP/701-da61'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '"Testing Client" <701>'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '701'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '104'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'default'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'SIP/701-da61'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '(null)'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'Playback'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'agent-loginok'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '2006-06-12 05:23:53'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '2006-06-12 05:23:53'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '2006-06-12 05:23:53'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '0'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '0'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '(null)'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '1150104233.5'Jun 12 05:23:53 DEBUG[20033]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '(null)'Jun 12 05:23:53 DEBUG[20033]: chan_sip.c:2419 sip_hangup: update_call_counter(701) - decrement call limit counter -- Unregistered SIP '701' -- Registered SIP '1' at 192.168.129.109 port 23420 expires 3600 -- Saved useragent "eyeBeam release 1002l stamp 29115" for peer 1Jun 12 05:23:59 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '063966a00a405a830d1703102cd723ce@202.5.145.56' of Request 102: Match Found -- Registered SIP '701' at 192.168.129.109 port 14074 expires 3600 -- Saved useragent "eyeBeam release 1002l stamp 29115" for peer 701Jun 12 05:24:02 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '78c2f8437e3f083566d9ca4f7e4aa59c@202.5.145.56' of Request 102: Match FoundJun 12 05:24:04 DEBUG[19990]: chan_sip.c:7042 check_user_full: Setting NAT on RTP to 524288Jun 12 05:24:04 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '3e2b0a3b6f277a29MDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.' of Response 1: Match FoundJun 12 05:24:05 DEBUG[19990]: chan_sip.c:7042 check_user_full: Setting NAT on RTP to 524288Jun 12 05:24:05 DEBUG[19990]: chan_sip.c:10354 handle_request_invite: Checking SIP call limits for device 1Jun 12 05:24:05 DEBUG[19990]: chan_sip.c:6047 build_route: build_route: Contact hop: <sip:1@192.168.129.109:23420> -- Executing Goto("SIP/1-c229", "mainmenu|s|1") in new stack -- Goto (mainmenu,s,1) -- Executing GotoIfTime("SIP/1-c229", "*|*|1|jan?holiday|s|1") in new stack -- Executing GotoIfTime("SIP/1-c229", "*|*|4|jul?holiday|s|1") in new stack -- Executing GotoIfTime("SIP/1-c229", "17:00-23:59|*|24|nov?holiday|s|1") in new stack -- Executing GotoIfTime("SIP/1-c229", "*|*|25|nov?holiday|s|1") in new stack -- Executing GotoIfTime("SIP/1-c229", "17:00-23:59|*|24|dec?holiday|s|1") in new stack -- Executing GotoIfTime("SIP/1-c229", "*|*|25|dec?holiday|s|1") in new stack -- Executing GotoIfTime("SIP/1-c229", "07:00-18:59|mon-fri|*|*?day|s|1") in new stack -- Executing GotoIfTime("SIP/1-c229", "19:01-09:59|mon-fri|*|*?night|s|1") in new stack -- Goto (night,s,1) -- Executing Set("SIP/1-c229", "CALLERID(name)=Maxim Support") in new stack -- Executing Wait("SIP/1-c229", "2") in new stack -- Executing Answer("SIP/1-c229", "") in new stack -- Executing BackGround("SIP/1-c229", "night-msg") in new stack -- Playing 'night-msg' (language 'en')Jun 12 05:24:07 DEBUG[20042]: chan_sip.c:2981 sip_rtp_read: Oooh, format changed to 4Jun 12 05:24:07 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '3e2b0a3b6f277a29MDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.' of Response 2: Match FoundJun 12 05:24:09 DEBUG[20042]: pbx.c:2297 __ast_pbx_run: Oooh, got something to jump out with ('3')!Jun 12 05:24:09 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '6958b5035db2778360af6d2a241d6c31@202.5.145.56' of Request 102: Match FoundJun 12 05:24:09 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '5206abe84f8686496f9b2c6559105099@202.5.145.56' of Request 102: Match FoundJun 12 05:24:09 DEBUG[19990]: chan_sip.c:10950 handle_request: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0Jun 12 05:24:13 DEBUG[19990]: chan_sip.c:1316 __sip_autodestruct: Auto destroying call '736e3b1f0e1be042MDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.' == CDR updated on SIP/1-c229 -- Executing Playback("SIP/1-c229", "quality") in new stack -- Playing 'quality' (language 'en')Jun 12 05:24:14 DEBUG[19990]: chan_sip.c:1316 __sip_autodestruct: Auto destroying call 'bf749b783d2bd513MDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.'Jun 12 05:24:17 DEBUG[19990]: chan_sip.c:1316 __sip_autodestruct: Auto destroying call '89429d000179d109MDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.' -- Executing Playback("SIP/1-c229", "please-wait") in new stackJun 12 05:24:18 WARNING[20042]: file.c:509 ast_openstream_full: File please-wait does not exist in any formatJun 12 05:24:18 WARNING[20042]: file.c:821 ast_streamfile: Unable to open please-wait (format ulaw): No such file or directoryJun 12 05:24:18 WARNING[20042]: app_playback.c:132 playback_exec: ast_streamfile failed on SIP/1-c229 for please-wait -- Executing SetMusicOnHold("SIP/1-c229", "default") in new stack -- Executing SetCallerID("SIP/1-c229", "3") in new stackJun 12 05:24:18 WARNING[20042]: pbx.c:1697 pbx_extension_helper: No application 'SetCallerIDName' for extension (night, 3, 5) == Spawn extension (night, 3, 5) exited non-zero on 'SIP/1-c229'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '3'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '3'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '3'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'night'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'SIP/1-c229'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '(null)'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'SetCallerID'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '3'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '2006-06-12 05:24:05'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '2006-06-12 05:24:07'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '2006-06-12 05:24:18'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '13'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '11'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '(null)'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '1150104245.6'Jun 12 05:24:18 DEBUG[20042]: pbx.c:1519 pbx_substitute_variables_helper_full: Function result is '(null)'Jun 12 05:24:18 DEBUG[20042]: chan_sip.c:2419 sip_hangup: update_call_counter(1) - decrement call limit counterJun 12 05:24:18 DEBUG[19990]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '3e2b0a3b6f277a29MDE4MjZkYzQzMjUzOGUxNmZkOGVhNGE2MWQwMzA2ZGM.' of Request 102: Match Foundmbox*CLI>