Jul 29 16:33:41 VERBOSE[9442] logger.c: <-- SIP read from 217.10.79.23:5060: INVITE sip:4795852@62.49.18.87:62844 SIP/2.0 Record-Route: Record-Route: Via: SIP/2.0/UDP 217.10.79.23;branch=z9hG4bK0b2e.ee6da572.0 Via: SIP/2.0/UDP 217.10.79.8;branch=z9hG4bK0b2e.dd3320d7.0 Via: SIP/2.0/UDP 217.10.79.30:5060;branch=z9hG4bK1a74dccd;rport=5060 From: 01619020300 ;tag=as1fb1b4b1 To: Contact: Call-ID: 67646bd13247f58d0d52bbaa4a5b2aaa@gw02.uk.sipgate.net CSeq: 102 INVITE User-Agent: sipgate asterisk Max-Forwards: 15 Date: Sat, 29 Jul 2006 15:25:50 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 470 v=0 o=root 867 867 IN IP4 217.10.79.30 s=session c=IN IP4 217.10.79.54 t=0 0 m=audio 38910 RTP/AVP 3 8 0 97 18 111 4 5 110 7 10 a=rtpmap:3 GSM/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:97 iLBC/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:111 G726-32/8000 a=rtpmap:4 G723/8000 a=rtpmap:5 DVI4/8000 a=rtpmap:110 speex/8000 a=rtpmap:7 LPC/8000 a=rtpmap:10 L16/8000 a=silenceSupp:off - - - - a=direction:active a=nortpproxy:yes Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (17 headers 21 lines)Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (17 headers 21 lines)--- Jul 29 16:33:41 DEBUG[9442] chan_sip.c: Allocating new SIP dialog for 67646bd13247f58d0d52bbaa4a5b2aaa@gw02.uk.sipgate.net - INVITE (With RTP) Jul 29 16:33:41 VERBOSE[9442] logger.c: Using INVITE request as basis request - 67646bd13247f58d0d52bbaa4a5b2aaa@gw02.uk.sipgate.net Jul 29 16:33:41 VERBOSE[9442] logger.c: Sending to 217.10.79.23 : 5060 (non-NAT) Jul 29 16:33:41 VERBOSE[9442] logger.c: Found peer 'sipgate' Jul 29 16:33:41 DEBUG[9442] chan_sip.c: Setting NAT on RTP to 524288 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 3 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 8 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 0 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 97 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 18 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 111 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 4 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 5 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 110 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 7 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found RTP audio format 10 Jul 29 16:33:41 VERBOSE[9442] logger.c: Peer audio RTP is at port 217.10.79.54:38910 Jul 29 16:33:41 DEBUG[9442] chan_sip.c: Peer audio RTP is at port 217.10.79.54:38910 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format GSM Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format PCMA Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format PCMU Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format iLBC Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format G729 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format G726-32 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format G723 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format DVI4 Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format speex Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format LPC Jul 29 16:33:41 VERBOSE[9442] logger.c: Found description format L16 Jul 29 16:33:41 VERBOSE[9442] logger.c: Capabilities: us - 0x100 (g729), peer - audio=0x7ff (g723|gsm|ulaw|alaw|g726|adpcm|slin|lpc10|g729|speex|ilbc)/video=0x0 (nothing), combined - 0x100 (g729) Jul 29 16:33:41 VERBOSE[9442] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing) Jul 29 16:33:41 DEBUG[9442] chan_sip.c: Checking SIP call limits for device 4795852 Jul 29 16:33:41 VERBOSE[9442] logger.c: Looking for 4795852 in from-trunk (domain 62.49.18.87) Jul 29 16:33:41 DEBUG[9442] chan_sip.c: build_route: Record-Route hop: Jul 29 16:33:41 DEBUG[9442] chan_sip.c: build_route: Record-Route hop: Jul 29 16:33:41 VERBOSE[9442] logger.c: list_route: hop: Jul 29 16:33:41 VERBOSE[9442] logger.c: list_route: hop: Jul 29 16:33:41 VERBOSE[9442] logger.c: Transmitting (NAT) to 217.10.79.23:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 217.10.79.23;branch=z9hG4bK0b2e.ee6da572.0;received=217.10.79.23 Via: SIP/2.0/UDP 217.10.79.8;branch=z9hG4bK0b2e.dd3320d7.0 Via: SIP/2.0/UDP 217.10.79.30:5060;branch=z9hG4bK1a74dccd;rport=5060 From: 01619020300 ;tag=as1fb1b4b1 To: Call-ID: 67646bd13247f58d0d52bbaa4a5b2aaa@gw02.uk.sipgate.net CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Goto' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Goto' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Macro' Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '0' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27485] pbx.c: Not taking any branch Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '0' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27485] pbx.c: Not taking any branch Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '01619020300' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27485] db.c: Unable to find key '01619020300/user' in family 'DEVICE' Jul 29 16:33:41 DEBUG[27485] func_db.c: DB: DEVICE/01619020300/user not found in database. Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] db.c: Unable to find key '/cidname' in family 'AMPUSER' Jul 29 16:33:41 DEBUG[27485] func_db.c: DB: AMPUSER//cidname not found in database. Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '"01619020300" <01619020300>' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '0' Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '01619020300' Jul 29 16:33:41 WARNING[27485] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input: "" != " ^ Jul 29 16:33:41 WARNING[27485] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source. Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '0' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27485] pbx.c: Not taking any branch Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '0' Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '01619020300' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '01619020300' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Macro' Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '0' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'AGI' Jul 29 16:33:41 DEBUG[27486] app_queue.c: Device 'SIP/4795852' changed to state '4' (Invalid) but we don't care because they're not a member of any queue. Jul 29 16:33:41 DEBUG[27485] db.c: Unable to find key '/recording' in family 'AMPUSER' Jul 29 16:33:41 VERBOSE[27485] logger.c: recordingcheck|20060729-163341|1154187221.68: No DB Entry AMPUSER//recording - Not recording Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Macro' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'AGI' Jul 29 16:33:41 VERBOSE[27485] logger.c: dialparties.agi: Starting New Dialparties.agi Jul 29 16:33:41 VERBOSE[27485] logger.c: dialparties.agi: Caller ID name is '01619020300' number is '01619020300' Jul 29 16:33:41 VERBOSE[27485] logger.c: dialparties.agi: Methodology of ring is 'hunt' Jul 29 16:33:41 DEBUG[27485] db.c: Unable to find key '07766087677#' in family 'CF' Jul 29 16:33:41 DEBUG[27485] db.c: Unable to find key '07766087677#' in family 'CW' Jul 29 16:33:41 DEBUG[27485] db.c: Unable to find key '07766087677#' in family 'CFB' Jul 29 16:33:41 DEBUG[27485] db.c: Unable to find key '07766087677#' in family 'CFU' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27485] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27485] pbx.c: Function result is '07766087677#' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Goto' Jul 29 16:33:41 DEBUG[27485] pbx.c: Launching 'Dial' Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-42. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable MACRO_DEPTH. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-34. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-33. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable CT_EXTEN. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-32. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-31. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable HuntMember. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-30. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-22. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable HuntLoop. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-21. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-20. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable HuntMembers. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable ds. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable HuntMember0. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable CALLTRACE_HUNT. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-dial-s-1. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable ARG3. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable ARG2. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable ARG1. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable MACRO_PRIORITY. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable MACRO_CONTEXT. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable MACRO_EXTEN. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-9. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable RingGroupMethod. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-8. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-record-enable-s-5. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable RecEnable. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-record-enable-s-4. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-record-enable-s-1. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-7. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable RecordMethod. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-6. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-5. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable RGPREFIX. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-4. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-3. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-2. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable AMPUSERCIDNAME. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable AMPUSER. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable REALCALLERIDNUM. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-findmefollow-201-1. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-did-s-2. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable FROM_DID. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-ext-did-s-1. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-from-trunk-4795852-2. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable STACK-from-trunk-4795852-1. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable SIPCALLID. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable SIPUSERAGENT. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable SIPDOMAIN. Jul 29 16:33:41 DEBUG[27485] channel.c: Not copying variable SIPURI. Jul 29 16:33:41 VERBOSE[27485] logger.c: Transmitting (NAT) to 217.10.79.23:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 217.10.79.23;branch=z9hG4bK0b2e.ee6da572.0;received=217.10.79.23 Via: SIP/2.0/UDP 217.10.79.8;branch=z9hG4bK0b2e.dd3320d7.0 Via: SIP/2.0/UDP 217.10.79.30:5060;branch=z9hG4bK1a74dccd;rport=5060 From: 01619020300 ;tag=as1fb1b4b1 To: ;tag=as3332438f Call-ID: 67646bd13247f58d0d52bbaa4a5b2aaa@gw02.uk.sipgate.net CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Macro' Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Macro' Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '"01619020300" <01619020300>' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '01619020300' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Macro' Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '0' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'AGI' Jul 29 16:33:41 DEBUG[27490] app_queue.c: Device 'Local/07766087677@from-internal' changed to state '2' (In use) but we don't care because they're not a member of any queue. Jul 29 16:33:41 DEBUG[9442] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Jul 29 16:33:41 VERBOSE[9442] logger.c: 12 headers, 0 lines Jul 29 16:33:41 VERBOSE[9442] logger.c: Reliably Transmitting (no NAT) to 192.168.253.200:5060: OPTIONS sip:103@192.168.253.200:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.253.15:5060;branch=z9hG4bK32b5f24d From: "Unknown" ;tag=as317e7c8b To: Contact: Call-ID: 00630c203e1268534cdb4cc51252ed30@192.168.253.15 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sat, 29 Jul 2006 15:33:41 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Jul 29 16:33:41 DEBUG[27489] db.c: Unable to find key '01619020300/recording' in family 'AMPUSER' Jul 29 16:33:41 VERBOSE[27489] logger.c: recordingcheck|20060729-163341|1154187221.70: No AMPUSER db entry for 01619020300. Not recording Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Macro' Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '0' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Not taking any branch Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '01619020300' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27489] db.c: Unable to find key '01619020300/outboundcid' in family 'AMPUSER' Jul 29 16:33:41 DEBUG[27489] func_db.c: DB: AMPUSER/01619020300/outboundcid not found in database. Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] db.c: Unable to find key '01619020300/emergency_cid' in family 'DEVICE' Jul 29 16:33:41 DEBUG[27489] func_db.c: DB: DEVICE/01619020300/emergency_cid not found in database. Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '1' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '"01619020300" <01619020300>' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'NoOp' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is '1' Jul 29 16:33:41 WARNING[27489] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input: 1 > ^ Jul 29 16:33:41 WARNING[27489] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source. Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '0' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Not taking any branch Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'AGI' Jul 29 16:33:41 VERBOSE[27489] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Function result is 'SIP/103' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Set' Jul 29 16:33:41 DEBUG[27489] pbx.c: Expression result is '0' Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'GotoIf' Jul 29 16:33:41 DEBUG[27489] pbx.c: Not taking any branch Jul 29 16:33:41 DEBUG[27489] pbx.c: Launching 'Dial' Jul 29 16:33:41 DEBUG[27489] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Jul 29 16:33:41 DEBUG[27489] chan_sip.c: Setting NAT on RTP to 0 Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-14. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable MACRO_DEPTH. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-13. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable custom. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-12. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable OUTNUM. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-11. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-10. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable DIAL_TRUNK. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-9. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable DIAL_NUMBER. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-8. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-7. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable GROUP. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-6. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable MACRO_PRIORITY. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable MACRO_CONTEXT. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable MACRO_EXTEN. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable ARG1. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-15. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-13. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-11. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-7. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable TRUNKOUTCID. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-6. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable EMERGENCYCID. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-5. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable USEROUTCID. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-4. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-3. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable REALCALLERIDNUM. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-2. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-outbound-callerid-s-1. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-5. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable ARG2. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-record-enable-s-5. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-record-enable-s-4. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-record-enable-s-1. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-4. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-3. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-macro-dialout-trunk-s-1. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable ARG4. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable ARG3. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable STACK-from-internal-07766087677-1. Jul 29 16:33:41 DEBUG[27489] channel.c: Not copying variable DIALEDPEERNUMBER. Jul 29 16:33:41 DEBUG[27489] chan_sip.c: Outgoing Call for 07766087677 Jul 29 16:33:41 VERBOSE[27489] logger.c: We're at 192.168.253.15 port 10506 Jul 29 16:33:41 VERBOSE[27489] logger.c: Adding codec 0x100 (g729) to SDP Jul 29 16:33:41 VERBOSE[27489] logger.c: Adding codec 0x2 (gsm) to SDP Jul 29 16:33:41 VERBOSE[27489] logger.c: Adding codec 0x4 (ulaw) to SDP Jul 29 16:33:41 VERBOSE[27489] logger.c: Adding codec 0x8 (alaw) to SDP Jul 29 16:33:41 VERBOSE[27489] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Jul 29 16:33:41 VERBOSE[27489] logger.c: 13 headers, 14 lines Jul 29 16:33:41 VERBOSE[27489] logger.c: Reliably Transmitting (no NAT) to 192.168.253.200:5060: INVITE sip:07766087677@192.168.253.200 SIP/2.0 Via: SIP/2.0/UDP 192.168.253.15:5060;branch=z9hG4bK0aebb603 From: "01619020300" ;tag=as0a3e6cb5 To: Contact: Call-ID: 5042d54f5a50a4ab3dbe91ea07a54463@192.168.253.15 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sat, 29 Jul 2006 15:33:41 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 312 v=0 o=root 9426 9426 IN IP4 192.168.253.15 s=session c=IN IP4 192.168.253.15 t=0 0 m=audio 10506 RTP/AVP 18 3 0 8 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Jul 29 16:33:41 VERBOSE[9442] logger.c: <-- SIP read from 192.168.253.200:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.253.15:5060;branch=z9hG4bK32b5f24d From: "Unknown" ;tag=as317e7c8b To: ;tag=4a9a3d88 Server: Crystal Media CM5000 Sip-Phone Contact: Call-ID: 00630c203e1268534cdb4cc51252ed30@192.168.253.15 CSeq: 102 OPTIONS Allow: INVITE,CANCEL,ACK,BYE,NOTIFY,REFER,OPTIONS Content-Length: 0 Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (10 headers 0 lines)Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (10 headers 0 lines)--- Jul 29 16:33:41 DEBUG[9442] chan_sip.c: Stopping retransmission on '00630c203e1268534cdb4cc51252ed30@192.168.253.15' of Request 102: Match Found Jul 29 16:33:41 VERBOSE[9442] logger.c: Destroying call '00630c203e1268534cdb4cc51252ed30@192.168.253.15' Jul 29 16:33:41 VERBOSE[9442] logger.c: <-- SIP read from 192.168.253.200:5060: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.253.15:5060;branch=z9hG4bK0aebb603 From: "01619020300" ;tag=as0a3e6cb5 To: Contact: Call-ID: 5042d54f5a50a4ab3dbe91ea07a54463@192.168.253.15 CSeq: 102 INVITE Content-Length: 0 Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (8 headers 0 lines)Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (8 headers 0 lines)--- Jul 29 16:33:41 DEBUG[9442] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '5042d54f5a50a4ab3dbe91ea07a54463@192.168.253.15' Request 102: Found Jul 29 16:33:41 VERBOSE[9442] logger.c: <-- SIP read from 192.168.253.200:5060: SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.253.15:5060;branch=z9hG4bK0aebb603 From: "01619020300" ;tag=as0a3e6cb5 To: ;tag=7f110003 Contact: Call-ID: 5042d54f5a50a4ab3dbe91ea07a54463@192.168.253.15 CSeq: 102 INVITE Content-Length: 0 Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (8 headers 0 lines)Jul 29 16:33:41 VERBOSE[9442] logger.c: --- (8 headers 0 lines)--- Jul 29 16:33:41 DEBUG[9442] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '5042d54f5a50a4ab3dbe91ea07a54463@192.168.253.15' Request 102: Found Jul 29 16:33:41 DEBUG[27493] app_queue.c: Device 'SIP/103' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.