[2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 0: INVITE sip:036108YYYY@202.52.129.50:5060;user=phone SIP/2.0 (59) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 125.213.160.7:5060;branch=z9hG4bK17dd5a0074cb712f5-dfda5-0 (75) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 2: Max-Forwards: 70 (16) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 3: Contact: (44) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 4: To: (39) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 5: From: "074687XXXX";tag=53ffde04-co9169-INS001 (80) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 6: Call-ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (58) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 7: CSeq: 916901 INVITE (19) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 8: Content-Type: application/sdp (29) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 9: Supported: 100rel (17) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 10: User-Agent: ENSR2.5.4 (21) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 11: Content-Length: 452 (19) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 12: (0) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: v=0 (3) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: o=- 1409277444 1409277444 IN IP4 125.213.160.7 (46) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: s=ENSResip (10) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: c=IN IP4 125.213.160.11 (23) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: t=0 0 (5) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: m=audio 17902 RTP/AVP 18 2 98 8 0 97 4 101 (42) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=fmtp:18 annexb=yes (20) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=fmtp:98 mode=20 (17) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=fmtp:97 mode=30 (17) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=fmtp:101 0-15 (15) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:98 iLBC/8000 (21) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:97 iLBC/8000 (21) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:4 G723/8000 (20) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: a=sendrecv (10) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Setting NAT on RTP to Off [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Setting NAT on UDPTL to Off [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Allocating new SIP dialog for 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 - INVITE (With RTP) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Begin: parsing SIP "Supported: 100rel" [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Found SIP option: -100rel- [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Matched SIP option: 100rel [2010-10-15 00:25:57] DEBUG[10733] res_config_mysql.c: MySQL RealTime: Everything is fine. [2010-10-15 00:25:57] DEBUG[10733] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_accounts WHERE name = '074687XXXX' [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Setting NAT on RTP to Off [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Setting NAT on UDPTL to Off [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing session-level SDP o=- 1409277444 1409277444 IN IP4 125.213.160.7... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing session-level SDP s=ENSResip... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing session-level SDP c=IN IP4 125.213.160.11... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=fmtp:18 annexb=yes... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=fmtp:98 mode=20... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=fmtp:97 mode=30... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:2 G726-32/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:98 iLBC/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:97 iLBC/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:4 G723/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=silenceSupp:off - - - -... UNSUPPORTED. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: T38 state changed to 0 on channel [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: We're settling with these formats: 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Checking SIP call limits for device [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Updating call counter for incoming call [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: *** Our native formats are 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: *** Joint capabilities are 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: *** Our capabilities are 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: This channel will not be able to handle video. [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: build_route: Contact hop: [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: SIP/fromsymbio-0000001f: New call is still down.... Trying... [2010-10-15 00:25:57] DEBUG[10733] devicestate.c: Notification of state change to be queued on device/channel SIP/fromsymbio [2010-10-15 00:25:57] DEBUG[10728] devicestate.c: No provider found, checking channel drivers for SIP - fromsymbio [2010-10-15 00:25:57] DEBUG[10728] chan_sip.c: Checking device state for peer fromsymbio [2010-10-15 00:25:57] DEBUG[10728] devicestate.c: Changing state for SIP/fromsymbio - state 1 (Not in use) [2010-10-15 00:25:57] DEBUG[10754] app_queue.c: Device 'SIP/fromsymbio' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Set' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Set' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Goto' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Set' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Set' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Set' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Progress' [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Setting framing from config on incoming call [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: T38 is in state 0 on channel SIP/fromsymbio-0000001f [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: ** Our capability: 0x8 (alaw) Video flag: True [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: -- Done with adding codecs to SDP [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Done building SDP. Settling with this capability: 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'PlayTones' [2010-10-15 00:25:57] DEBUG[11561] channel.c: Set channel SIP/fromsymbio-0000001f to write format slin [2010-10-15 00:25:57] DEBUG[11561] channel.c: Prodding channel 'SIP/fromsymbio-0000001f' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Function result is '074687XXXX' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Function result is '074687XXXX' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Function result is '' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'AGI' [2010-10-15 00:25:57] DEBUG[11561] pbx.c: Launching 'Dial' [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Asked to create a SIP channel with formats: 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [2010-10-15 00:25:57] DEBUG[11561] res_config_mysql.c: MySQL RealTime: Everything is fine. [2010-10-15 00:25:57] DEBUG[11561] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_accounts WHERE name = '74189' AND host = 'dynamic' [2010-10-15 00:25:57] DEBUG[11561] res_config_mysql.c: MySQL RealTime: Everything is fine. [2010-10-15 00:25:57] DEBUG[11561] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_accounts WHERE name = '74189' [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Our T38 capability (3872) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Setting NAT on RTP to On [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Setting NAT on UDPTL to On [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Destroying SIP peer 74189 [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: *** Our native formats are 0x4 (ulaw) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: *** Joint capabilities are 0x0 (nothing) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: *** Our capabilities are 0x10c (ulaw|alaw|g729) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: *** Our preferred formats from the incoming channel are 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: This channel will not be able to handle video. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable DIALEDTIME. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable ANSWEREDTIME. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable DIALEDPEERNAME. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable DIALEDPEERNUMBER. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable DIALSTATUS. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable AGISTATUS. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable TO_DIAL2. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable TO_DIAL. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable TRUNK_GROUP. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable TRUNK_ORDER. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable DIVERT_DEST. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable PASSERT. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable CLIENTID. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable DIDNO. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable RING_TIME. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable CALLING_NUMBER. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable TRUECID. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable RELATED_CHANNELS. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable FROM_DID. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable EXTN_TO_DIAL. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable SIPCALLID. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable SIPUSERAGENT. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable SIPDOMAIN. [2010-10-15 00:25:57] DEBUG[11561] channel.c: Not copying variable SIPURI. [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Outgoing Call for 036108YYYY [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Updating call counter for outgoing call [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Our T38 capability (3872), joint T38 capability (3872) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: T38 is in state 0 on channel SIP/74189-00000020 [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: ** Our capability: 0x10c (ulaw|alaw|g729) Video flag: False [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: ** Our prefcodec: 0x8 (alaw) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: -- Done with adding codecs to SDP [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: T38 UDPTL is currently at 202.52.129.50 port 4008 [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Our T38 capability (3872), peer T38 capability (0), joint capability (3872) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: T38MaxBitRate 9600 found [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Done building SDP. Settling with this capability: 0x10c (ulaw|alaw|g729) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 0: INVITE sip:036108YYYY@27.50.95.170:5060 SIP/2.0 (47) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 1: Via: SIP/2.0/UDP 202.52.129.50:5060;branch=z9hG4bK6083705d;rport (64) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 2: From: "074687XXXX" ;tag=as5f44bd0d (64) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 3: To: (38) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 4: Contact: (39) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 5: Call-ID: 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 (55) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 7: User-Agent: MaxoTel (19) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 8: Max-Forwards: 70 (16) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 9: Date: Thu, 14 Oct 2010 14:25:57 GMT (35) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO (72) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 11: Supported: replaces (19) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: Header 12: Content-Type: application/sdp (29) [2010-10-15 00:25:57] DEBUG[11561] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #-1 [2010-10-15 00:25:57] DEBUG[11561] channel.c: Driver for channel 'SIP/fromsymbio-0000001f' does not support indication 3, emulating it [2010-10-15 00:25:57] DEBUG[11561] channel.c: Set channel SIP/fromsymbio-0000001f to write format alaw [2010-10-15 00:25:57] DEBUG[11561] channel.c: Set channel SIP/fromsymbio-0000001f to write format slin [2010-10-15 00:25:57] DEBUG[11561] channel.c: Prodding channel 'SIP/fromsymbio-0000001f' [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 0: SIP/2.0 100 Giving a try (24) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 202.52.129.50:5060;branch=z9hG4bK6083705d;rport=5060 (69) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 2: From: "074687XXXX" ;tag=as5f44bd0d (64) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 3: To: (38) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 4: Call-ID: 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 (55) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 5: CSeq: 102 INVITE (16) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 6: Server: VoipNow (15) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 7: Content-Length: 0 (17) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 8: (0) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: = Found Their Call ID: 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 Their Tag Our tag: as5f44bd0d [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: *** SIP TIMER: Cancelling retransmission #25040 - INVITE (got response) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50' Request 102: Found [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: SIP response 100 to standard invite [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Header 0: (0) [2010-10-15 00:25:57] DEBUG[10733] chan_sip.c: Line: (0) [2010-10-15 00:25:57] DEBUG[11561] rtp.c: Ooh, format changed from unknown to alaw [2010-10-15 00:25:57] DEBUG[11561] rtp.c: Created smoother: format: 8 ms: 20 len: 160 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 202.52.129.50:5060;received=202.52.129.50;branch=z9hG4bK6083705d;rport=5060 (92) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 2: Record-Route: (71) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 3: From: "074687XXXX" ;tag=as5f44bd0d (64) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 4: To: ;tag=as43d96d63 (53) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 5: Call-ID: 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 (55) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 6: Contact: (43) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 7: CSeq: 102 INVITE (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 8: Server: abctel (30) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO (72) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 10: Supported: replaces, timer (26) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 11: Content-Type: application/sdp (29) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 12: Content-Length: 335 (19) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 13: (0) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: v=0 (3) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: o=root 2137167876 2137167876 IN IP4 27.50.95.170 (48) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: s=Asterisk PBX 1.6.1.20 (23) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: c=IN IP4 27.50.95.170 (21) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: t=0 0 (5) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: m=audio 10814 RTP/AVP 0 8 18 101 (32) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=fmtp:18 annexb=no (19) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=fmtp:101 0-16 (15) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=ptime:20 (10) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=sendrecv (10) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: m=image 0 udptl t38 (19) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: = Found Their Call ID: 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 Their Tag Our tag: as5f44bd0d [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Acked pending invite 102 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Stopping retransmission on '69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50' of Request 102: Match Found [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: SIP response 200 to standard invite [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP o=root 2137167876 2137167876 IN IP4 27.50.95.170... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP s=Asterisk PBX 1.6.1.20... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP c=IN IP4 27.50.95.170... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=fmtp:18 annexb=no... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=silenceSupp:off - - - -... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=ptime:20... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: T38 state changed to 5 on channel SIP/74189-00000020 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Our T38 capability = (3872), peer T38 capability (0), joint T38 capability (3872) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Peer doesn't provide T.38 UDPTL [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: We're settling with these formats: 0x10c (ulaw|alaw|g729) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: We have an owner, now see if we need to change this call [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Updating call counter for outgoing call [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: build_route: Record-Route hop: [2010-10-15 00:25:59] DEBUG[11561] devicestate.c: Notification of state change to be queued on device/channel SIP/74189 [2010-10-15 00:25:59] DEBUG[10728] devicestate.c: No provider found, checking channel drivers for SIP - 74189 [2010-10-15 00:25:59] DEBUG[10728] chan_sip.c: Checking device state for peer 74189 [2010-10-15 00:25:59] DEBUG[10733] res_config_mysql.c: MySQL RealTime: Everything is fine. [2010-10-15 00:25:59] DEBUG[11561] pbx.c: Launching 'AGI' [2010-10-15 00:25:59] DEBUG[10728] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_accounts WHERE name = '74189' AND host = 'dynamic' [2010-10-15 00:25:59] DEBUG[10728] res_config_mysql.c: MySQL RealTime: Everything is fine. [2010-10-15 00:25:59] DEBUG[10728] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_accounts WHERE name = '74189' [2010-10-15 00:25:59] DEBUG[10728] chan_sip.c: Destroying SIP peer 74189 [2010-10-15 00:25:59] DEBUG[10728] devicestate.c: Changing state for SIP/74189 - state 1 (Not in use) [2010-10-15 00:25:59] DEBUG[10754] app_queue.c: Device 'SIP/74189' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2010-10-15 00:25:59] DEBUG[11561] app_macro.c: Executed application: AGI [2010-10-15 00:25:59] DEBUG[11561] pbx.c: Launching 'NoOp' [2010-10-15 00:25:59] DEBUG[11561] app_macro.c: Executed application: NoOp [2010-10-15 00:25:59] DEBUG[11561] app_dial.c: Macro exited with status 0 [2010-10-15 00:25:59] DEBUG[11561] channel.c: Set channel SIP/fromsymbio-0000001f to write format alaw [2010-10-15 00:25:59] DEBUG[11561] channel.c: Set channel SIP/fromsymbio-0000001f to read format ulaw [2010-10-15 00:25:59] DEBUG[11561] channel.c: Set channel SIP/74189-00000020 to read format alaw [2010-10-15 00:25:59] DEBUG[11561] devicestate.c: Notification of state change to be queued on device/channel SIP/fromsymbio [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: SIP answering channel: SIP/fromsymbio-0000001f [2010-10-15 00:25:59] DEBUG[10728] devicestate.c: No provider found, checking channel drivers for SIP - fromsymbio [2010-10-15 00:25:59] DEBUG[11561] rtp.c: Setting the marker bit due to a source update [2010-10-15 00:25:59] DEBUG[10728] chan_sip.c: Checking device state for peer fromsymbio [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: Setting framing from config on incoming call [2010-10-15 00:25:59] DEBUG[10728] devicestate.c: Changing state for SIP/fromsymbio - state 1 (Not in use) [2010-10-15 00:25:59] DEBUG[10754] app_queue.c: Device 'SIP/fromsymbio' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: T38 is in state 0 on channel SIP/fromsymbio-0000001f [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: ** Our capability: 0x8 (alaw) Video flag: True [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: -- Done with adding codecs to SDP [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: Done building SDP. Settling with this capability: 0x8 (alaw) [2010-10-15 00:25:59] DEBUG[11561] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #-1 [2010-10-15 00:25:59] DEBUG[11561] rtp.c: Changing ssrc from 1603764827 to 796007585 due to a source change [2010-10-15 00:25:59] DEBUG[11561] rtp.c: Changing ssrc from 669578210 to 931568769 due to a source change [2010-10-15 00:25:59] DEBUG[11561] rtp.c: Cannot packet2packet bridge - raw formats are incompatible [2010-10-15 00:25:59] DEBUG[11561] rtp.c: Ooh, format changed from unknown to ulaw [2010-10-15 00:25:59] DEBUG[11561] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 0: INVITE sip:074687XXXX@202.52.129.50 SIP/2.0 (43) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 27.50.95.170:5060;branch=z9hG4bK5ced.353bc98.0 (63) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 2: Max-Forwards: 69 (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 3: From: ;tag=as43d96d63 (55) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 4: To: "074687XXXX" ;tag=as5f44bd0d (62) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 5: Call-ID: 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 (55) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 6: Contact: (43) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 7: CSeq: 102 INVITE (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 8: User-Agent: abctel (34) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO (72) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 10: Supported: replaces, timer (26) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 11: Content-Type: application/sdp (29) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 12: Content-Length: 297 (19) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 13: (0) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: v=0 (3) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: o=root 2137167876 2137167877 IN IP4 27.50.95.170 (48) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: s=Asterisk PBX 1.6.1.20 (23) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: c=IN IP4 27.50.95.170 (21) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: t=0 0 (5) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: m=image 4491 udptl t38 (22) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=T38FaxVersion:0 (17) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=T38MaxBitRate:14400 (21) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=T38FaxFillBitRemoval (22) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=T38FaxRateManagement:transferredTCF (37) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=T38FaxMaxDatagram:1400 (24) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=T38FaxUdpEC:t38UDPRedundancy (30) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: = Found Their Call ID: 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 Their Tag as43d96d63 Our tag: as5f44bd0d [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Begin: parsing SIP "Supported: replaces, timer" [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Found SIP option: -replaces- [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Matched SIP option: replaces [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Found SIP option: -timer- [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Matched SIP option: timer [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP o=root 2137167876 2137167877 IN IP4 27.50.95.170... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP s=Asterisk PBX 1.6.1.20... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP c=IN IP4 27.50.95.170... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: T38 state changed to 5 on channel SIP/74189-00000020 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: FaxVersion: 0 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxVersion:0... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: T38MaxBitRate: 14400 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38MaxBitRate:14400... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: FillBitRemoval [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxFillBitRemoval... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: RateManagement: transferredTCF [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxRateManagement:transferredTCF... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: FaxMaxDatagram: 1400 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxMaxDatagram:1400... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: UDP EC: t38UDPRedundancy [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxUdpEC:t38UDPRedundancy... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Our T38 capability = (3872), peer T38 capability (16161), joint T38 capability (3873) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Detected a T38 Only call! [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: T38 state changed to 5 on channel SIP/74189-00000020 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Peer T.38 UDPTL is at port 27.50.95.170:4491 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: We're settling with these formats: 0x0 (nothing) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: We have an owner, now see if we need to change this call [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Got a SIP re-invite for call 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: SIP/74189-00000020: This call is UP.... [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Deferring reinvite on SIP '3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3' - It's UDPTL will be redirected to us (IP 202.52.129.50) [2010-10-15 00:25:59] DEBUG[11561] rtp.c: Setting the marker bit due to a source update [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 0: ACK sip:036108YYYY@202.52.129.50 SIP/2.0 (40) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 125.213.160.7:5060;branch=z9hG4bK17dd5a0074cb712f5-dfda5-1 (75) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 2: Max-Forwards: 70 (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 3: To: ;tag=as3efd7e2b (54) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 4: From: "074687XXXX";tag=53ffde04-co9169-INS001 (80) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 5: Call-ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (58) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 6: CSeq: 916901 ACK (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 7: User-Agent: ENSR2.5.4 (21) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 8: Content-Length: 0 (17) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 9: (0) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: = Found Their Call ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 Their Tag 53ffde04-co9169-INS001 Our tag: as3efd7e2b [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #25115 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Stopping retransmission on '3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3' of Response 916901: Match Found [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Sending pending reinvite on '3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3' [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Strict routing enforced for session 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: ** Our capability: 0x8 (alaw) Video flag: True [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: -- Done with adding codecs to SDP [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Done building SDP. Settling with this capability: 0x8 (alaw) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Initializing already initialized SIP dialog 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (presumably reinvite) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 0: INVITE sip:074687XXXX@125.213.160.7:5060 SIP/2.0 (48) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 202.52.129.50:5060;branch=z9hG4bK521b4c3f;rport (64) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 2: From: ;tag=as3efd7e2b (56) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 3: To: "074687XXXX";tag=53ffde04-co9169-INS001 (78) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 4: Contact: (39) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 5: Call-ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (58) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 7: User-Agent: MaxoTel (19) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 8: Max-Forwards: 70 (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO (72) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 10: Supported: replaces (19) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 11: X-asterisk-Info: SIP re-invite (External RTP bridge) (52) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 12: Content-Type: application/sdp (29) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #-1 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 0: SIP/2.0 200 OK (14) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 202.52.129.50:5060;branch=z9hG4bK521b4c3f;rport (64) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 2: Contact: (44) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 3: To: ;tag=53ffde04-co9169-INS001 (66) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 4: From: ;tag=as3efd7e2b (56) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 5: Call-ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (58) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 6: CSeq: 102 INVITE (16) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 7: Content-Type: application/sdp (29) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 8: User-Agent: ENSR2.5.4 (21) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 9: Content-Length: 239 (19) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Header 10: (0) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: v=0 (3) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: o=- 1409277444 1409277445 IN IP4 125.213.160.7 (46) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: s=ENSResip (10) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: c=IN IP4 125.213.160.11 (23) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: t=0 0 (5) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: m=audio 17902 RTP/AVP 8 101 (27) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=fmtp:101 0-15 (15) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=silenceSupp:off - - - - (25) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Line: a=sendrecv (10) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: = Found Their Call ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 Their Tag 53ffde04-co9169-INS001 Our tag: as3efd7e2b [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Acked pending invite 102 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #25121 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Stopping retransmission on '3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3' of Request 102: Match Found [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: SIP response 200 to RE-invite on outgoing call 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP o=- 1409277444 1409277445 IN IP4 125.213.160.7... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP s=ENSResip... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP c=IN IP4 125.213.160.11... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=silenceSupp:off - - - -... UNSUPPORTED. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: T38 state changed to 0 on channel SIP/fromsymbio-0000001f [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: We're settling with these formats: 0x8 (alaw) [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: We have an owner, now see if we need to change this call [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Updating call counter for incoming call [2010-10-15 00:25:59] DEBUG[10733] chan_sip.c: Strict routing enforced for session 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 0: INVITE sip:036108YYYY@202.52.129.50 SIP/2.0 (43) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 125.213.160.7:5060;branch=z9hG4bK17dd5a0074cb712f5-dfda6-0 (75) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 2: Max-Forwards: 70 (16) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 3: Contact: (44) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 4: To: ;tag=as3efd7e2b (54) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 5: From: "074687XXXX";tag=53ffde04-co9169-INS001 (80) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 6: Call-ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (58) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 7: CSeq: 916902 INVITE (19) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 8: Content-Type: application/sdp (29) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 9: Supported: 100rel (17) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 10: User-Agent: ENSR2.5.4 (21) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 11: Content-Length: 360 (19) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 12: (0) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: v=0 (3) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: o=- 1409277444 1409277446 IN IP4 125.213.160.7 (46) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: s=ENSResip (10) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: c=IN IP4 125.213.160.11 (23) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: t=0 0 (5) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: m=image 17902 udptl t38 (23) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxVersion:0 (17) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38MaxBitRate:9600 (20) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxMaxBuffer:200 (21) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxMaxDatagram:72 (22) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxRateManagement:transferredTCF (37) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxUdpEC:t38UDPRedundancy (30) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxFillBitRemoval:0 (24) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxTranscodingMMR:0 (24) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Line: a=T38FaxTranscodingJBIG:0 (25) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: = Found Their Call ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 Their Tag 53ffde04-co9169-INS001 Our tag: as3efd7e2b [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing session-level SDP o=- 1409277444 1409277446 IN IP4 125.213.160.7... UNSUPPORTED. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing session-level SDP s=ENSResip... UNSUPPORTED. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing session-level SDP c=IN IP4 125.213.160.11... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: T38 state changed to 5 on channel SIP/fromsymbio-0000001f [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: FaxVersion: 0 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxVersion:0... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: T38MaxBitRate: 9600 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38MaxBitRate:9600... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: MaxBufferSize:200 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxMaxBuffer:200... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: FaxMaxDatagram: 72 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxMaxDatagram:72... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: RateManagement: transferredTCF [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxRateManagement:transferredTCF... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: UDP EC: t38UDPRedundancy [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxUdpEC:t38UDPRedundancy... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: FillBitRemoval: 0 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxFillBitRemoval:0... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Transcoding MMR: 0 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxTranscodingMMR:0... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Transcoding JBIG: 0 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Processing media-level (image) SDP a=T38FaxTranscodingJBIG:0... OK. [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Detected a T38 Only call! [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: T38 state changed to 5 on channel SIP/fromsymbio-0000001f [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: We're settling with these formats: 0x0 (nothing) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: We have an owner, now see if we need to change this call [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Got a SIP re-invite for call 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: SIP/fromsymbio-0000001f: This call is UP.... [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: T38MaxBitRate 9600 found [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Done building SDP. Settling with this capability: 0x0 (nothing) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #-1 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: T38 state changed to 5 on channel SIP/fromsymbio-0000001f [2010-10-15 00:26:10] DEBUG[11561] rtp.c: Setting the marker bit due to a source update [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Invalid SIP message - rejected , no callid, len 350 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 0: ACK sip:036108YYYY@202.52.129.50 SIP/2.0 (40) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 125.213.160.7:5060;branch=z9hG4bK17dd5a0074cb712f5-dfda6-1 (75) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 2: Max-Forwards: 70 (16) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 3: To: ;tag=as3efd7e2b (54) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 4: From: "074687XXXX";tag=53ffde04-co9169-INS001 (80) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 5: Call-ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (58) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 6: CSeq: 916902 ACK (16) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 7: User-Agent: ENSR2.5.4 (21) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 8: Content-Length: 0 (17) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Header 9: (0) [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: = Found Their Call ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 Their Tag 53ffde04-co9169-INS001 Our tag: as3efd7e2b [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #25265 [2010-10-15 00:26:10] DEBUG[10733] chan_sip.c: Stopping retransmission on '3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3' of Response 916902: Match Found [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 0: BYE sip:036108YYYY@202.52.129.50 SIP/2.0 (40) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 1: Via: SIP/2.0/UDP 125.213.160.7:5060;branch=z9hG4bK17dd5a0074cb712f5-dfda7-0 (75) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 2: Max-Forwards: 70 (16) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 3: To: ;tag=as3efd7e2b (54) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 4: From: "074687XXXX";tag=53ffde04-co9169-INS001 (80) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 5: Call-ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 (58) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 6: CSeq: 916903 BYE (16) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 7: User-Agent: ENSR2.5.4 (21) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 8: Content-Length: 0 (17) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Header 9: (0) [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: = Found Their Call ID: 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 Their Tag 53ffde04-co9169-INS001 Our tag: as3efd7e2b [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Setting SIP_ALREADYGONE on dialog 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3 [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Received bye, issuing owner hangup [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Auto destroying SIP dialog '745559c-e3087b67@192.168.0.63' [2010-10-15 00:26:38] DEBUG[10733] chan_sip.c: Destroying SIP dialog 745559c-e3087b67@192.168.0.63 [2010-10-15 00:26:38] DEBUG[11561] channel.c: Didn't get a frame from channel: SIP/fromsymbio-0000001f [2010-10-15 00:26:38] DEBUG[11561] rtp.c: Setting the marker bit due to a source update [2010-10-15 00:26:38] DEBUG[11561] channel.c: Bridge stops bridging channels SIP/fromsymbio-0000001f and SIP/74189-00000020 [2010-10-15 00:26:38] DEBUG[11561] pbx.c: Launching 'DeadAGI' [2010-10-15 00:26:38] DEBUG[11561] channel.c: Soft-Hanging up channel 'SIP/fromsymbio-0000001f' [2010-10-15 00:26:38] DEBUG[11561] channel.c: Hanging up channel 'SIP/74189-00000020' [2010-10-15 00:26:38] DEBUG[11561] chan_sip.c: Hangup call SIP/74189-00000020, SIP callid 69cb2f8f68ef867e526ba83921ba3d3f@202.52.129.50) [2010-10-15 00:26:38] DEBUG[11561] devicestate.c: Notification of state change to be queued on device/channel SIP/74189 [2010-10-15 00:26:38] DEBUG[11561] rtp.c: Channel '' has no RTP, not doing anything [2010-10-15 00:26:38] DEBUG[11561] app_dial.c: Exiting with DIALSTATUS=ANSWER. [2010-10-15 00:26:38] DEBUG[10728] devicestate.c: No provider found, checking channel drivers for SIP - 74189 [2010-10-15 00:26:38] DEBUG[11561] pbx.c: Spawn extension (from-external,s,7) exited non-zero on 'SIP/fromsymbio-0000001f' [2010-10-15 00:26:38] DEBUG[10728] chan_sip.c: Checking device state for peer 74189 [2010-10-15 00:26:38] DEBUG[11561] channel.c: Soft-Hanging up channel 'SIP/fromsymbio-0000001f' [2010-10-15 00:26:38] DEBUG[11561] channel.c: Hanging up channel 'SIP/fromsymbio-0000001f' [2010-10-15 00:26:38] DEBUG[11561] chan_sip.c: Hangup call SIP/fromsymbio-0000001f, SIP callid 3eb2-45a-9142010142557-img-01-mas-0-125.213.168.3) [2010-10-15 00:26:38] DEBUG[11561] devicestate.c: Notification of state change to be queued on device/channel SIP/fromsymbio [2010-10-15 00:26:38] DEBUG[10728] res_config_mysql.c: MySQL RealTime: Everything is fine. [2010-10-15 00:26:38] DEBUG[10728] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_accounts WHERE name = '74189' AND host = 'dynamic' [2010-10-15 00:26:38] DEBUG[10728] res_config_mysql.c: MySQL RealTime: Everything is fine. [2010-10-15 00:26:38] DEBUG[10728] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_accounts WHERE name = '74189' [2010-10-15 00:26:38] DEBUG[10728] chan_sip.c: Destroying SIP peer 74189 [2010-10-15 00:26:38] DEBUG[10728] devicestate.c: Changing state for SIP/74189 - state 1 (Not in use) [2010-10-15 00:26:38] DEBUG[10728] devicestate.c: No provider found, checking channel drivers for SIP - fromsymbio [2010-10-15 00:26:38] DEBUG[10754] app_queue.c: Device 'SIP/74189' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [2010-10-15 00:26:38] DEBUG[10728] chan_sip.c: Checking device state for peer fromsymbio [2010-10-15 00:26:38] DEBUG[10728] devicestate.c: Changing state for SIP/fromsymbio - state 1 (Not in use) [2010-10-15 00:26:38] DEBUG[10754] app_queue.c: Device 'SIP/fromsymbio' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Fax Fails