g 21 18:57:38 DEBUG[21168]: channel.c:1388 ast_settimeout: Scheduling timer at 0 sample intervals Aug 21 18:57:38 DEBUG[21168]: channel.c:1388 ast_settimeout: Scheduling timer at 0 sample intervals Aug 21 18:57:38 DEBUG[21168]: channel.c:2023 set_format: Set channel SIP/66.165.175.35-08316898 to write format ulaw Aug 21 18:57:38 DEBUG[21168]: pbx.c:1622 pbx_extension_helper: Launching 'WaitExten' -- Executing WaitExten("SIP/66.165.175.35-08316898", "") in new stack Urgent handler Aug 21 18:57:41 DEBUG[21168]: rtp.c:159 send_dtmf: Sending dtmf: 53 (5), at 66.165.175.35 Aug 21 18:57:45 DEBUG[21168]: chan_sip.c:2904 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Aug 21 18:57:46 DEBUG[21168]: chan_sip.c:1135 __sip_autodestruct: Auto destroying call '56b518eb487c29a76a00348933a2473a@127.0.0.1' Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:2904 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: OPTIONS sip:246@10.224.224.138 SIP/2.0 (38) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Via: SIP/2.0/UDP 10.224.224.10:5060;branch=z9hG4bK5a4fb097 (58) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: From: "asterisk" ;tag=as518b81e3 (60) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: To: (28) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Contact: (37) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Call-ID: 22daabb00651a70a61d4d39b44a9fff1@10.224.224.10 (55) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: CSeq: 102 OPTIONS (17) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: User-Agent: Asterisk PBX (24) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Date: Mon, 22 Aug 2005 01:57:47 GMT (35) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY (55) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Content-Length: 0 (17) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: (0) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: SIP/2.0 200 OK (14) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Via: SIP/2.0/UDP 10.224.224.10:5060;branch=z9hG4bK5a4fb097 (58) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: From: "asterisk" ;tag=as518b81e3 (60) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: To: ;tag=2E2E1CFF-9607D890 (50) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: CSeq: 102 OPTIONS (17) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Call-ID: 22daabb00651a70a61d4d39b44a9fff1@10.224.224.10 (55) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Contact: (33) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: User-Agent: PolycomSoundPointIP-SPIP_501-UA/1.5.2.0054 (54) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: Content-Length: 0 (17) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:3100 parse_request: Header: (0) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:1207 __sip_ack: Stopping retransmission on '22daabb00651a70a61d4d39b44a9fff1@10.224.224.10' of Request 102: Found Aug 21 18:57:47 NOTICE[21168]: chan_sip.c:8475 handle_response_peerpoke: Peer '246' is now REACHABLE! (69ms / 2000ms) Aug 21 18:57:47 DEBUG[21168]: devicestate.c:166 do_state_change: Changing state for SIP/246 - state 0 (Unknown) Aug 21 18:57:47 DEBUG[21168]: app_queue.c:429 changethread: Device 'SIP/246' changed to state '0' (Unknown) Aug 21 18:57:47 DEBUG[21168]: chan_sip.c:1135 __sip_autodestruct: Auto destroying call '7c082d112336d07d20bc3d9520033bfd@127.0.0.1' Aug 21 18:57:48 DEBUG[21168]: res_config_mysql.c:120 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten = 's' AND context = 'internal-users-rt' AND priority = '5' Aug 21 18:57:48 DEBUG[21168]: res_config_mysql.c:613 mysql_reconnect: MySQL RealTime: Everything is fine. Aug 21 18:57:48 DEBUG[21168]: res_config_mysql.c:231 realtime_multi_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions_table WHERE exten LIKE '\_%' AND context = 'internal-users-rt' AND priority = '5' ORDER BY exten Aug 21 18:57:48 DEBUG[21168]: res_config_mysql.c:613 mysql_reconnect: MySQL RealTime: Everything is fine. -- Timeout on SIP/66.165.175.35-08316898, going to 't'