[Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.154.140' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.154.140' and port ''. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Sending to 158.58.154.140:5060 (no NAT) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Initializing initreq for method INVITE - callid 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Using INVITE request as basis request - 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting '194.6.238.93' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host '194.6.238.93' and port ''. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found peer 'sip2' for '+55243445' from 158.58.154.140:5060 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f90cc006788' [Nov 23 09:04:02] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Allocated port 11848 for RTP instance '0x7f90cc006788' [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: RTP instance '0x7f90cc006788' is setup and ready to go [Nov 23 09:04:02] DEBUG[13731][C-001a2126] acl.c: Not an IPv4 nor IPv6 address, cannot get port. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting 'pbx11.sig14.osl.nordicom.no' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host 'pbx11.sig14.osl.nordicom.no' and port ''. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f90cc006788' [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Setting NAT on RTP to Off [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP o=Sippy 3661178290213760010 1 IN IP4 194.6.238.93... OK. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP s=T001... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 8 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f9114301630 [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 18 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 18 based on m type on 0x7f9114301630 [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 9 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 9 based on m type on 0x7f9114301630 [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 101 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f9114301630 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting '194.6.238.93' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host '194.6.238.93' and port ''. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 194.6.238.93... OK. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format PCMA for ID 8 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format G729 for ID 18 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format G722 for ID 9 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format telephone-event for ID 101 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Capabilities: us - (alaw|ulaw|g729), peer - audio=(alaw|g722|g729)/video=(nothing)/text=(nothing), combined - (alaw|g729) [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f90cc006788' [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Peer audio RTP is at port 194.6.238.93:38510 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 8 (0x7f90cc173220) from 0x7f9114301630 to 0x7f90cc006950 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 9 (0x7f90cc0309c0) from 0x7f9114301630 to 0x7f90cc006950 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 18 (0x7f90cc0671e0) from 0x7f9114301630 to 0x7f90cc006950 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 101 (0x7f90cc228740) from 0x7f9114301630 to 0x7f90cc006950 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f90cc006788' [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: We're settling with these formats: (alaw|g729) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.152.14:5060' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.152.14' and port ''. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting '194.6.238.93' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host '194.6.238.93' and port ''. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Looking for +4755191855 in cc_customer (domain 158.58.152.14) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: *** Our native formats are (alaw) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: *** Joint capabilities are (alaw|g729) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: *** Our capabilities are (alaw|ulaw|g729) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: *** AST_CODEC_CHOOSE formats are alaw [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: This channel will not be able to handle video. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] sip/route.c: sip_route_process_header: [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] sip/route.c: sip_route_dump: route/path hop: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: SIP/sip2-003434ab: New call is still down.... Trying... [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 1 [109]: Via: SIP/2.0/UDP 158.58.154.140;branch=z9hG4bK7ab9.f251bc0d2886adb808f7467114402881.0;received=158.58.154.140 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 2 [ 99]: Via: SIP/2.0/UDP 194.6.238.93:5061;received=194.6.238.93;rport=5061;branch=z9hG4bK-elbhqqqngnsvhksu [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 3 [ 37]: Record-Route: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 4 [ 66]: From: 55243445 ;tag=qummqu7b53s3cfs2.o [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 5 [ 36]: To: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 6 [ 48]: Call-ID: 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 7 [ 16]: CSeq: 665 INVITE [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 8 [ 16]: Server: Nordicom [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 9 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 10 [ 16]: Supported: timer [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 11 [ 45]: Contact: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Header 13 [ 0]: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 158.58.154.140:5060 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] pbx_variables.c: Result of 'nodestatus' is NULL [Nov 23 09:04:02] DEBUG[24483][C-001a2126] pbx_variables.c: Expression result is '0' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] pbx.c: Launching 'ExecIf' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] pbx_variables.c: Function DB(fastagi/hostname) result is '158.58.152.122' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] pbx.c: Launching 'AGI' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] netsock2.c: Splitting '158.58.152.122' into... [Nov 23 09:04:02] DEBUG[24483][C-001a2126] netsock2.c: ...host '158.58.152.122' and port ''. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_agi.c: Wow, connected! [Nov 23 09:04:02] DEBUG[24483][C-001a2126] pbx_variables.c: Result of 'ncomgelfdebug' is NULL [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: SIP Header added "P-Asserted-Identity: " as __SIPADDHEADER01 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: SIP Header added "X-NCOM-CALLTYPE: 01" as __SIPADDHEADER02 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: SIP Header added "X-NCOM-ACCOUNT: 21651100" as __SIPADDHEADER03 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: SIP Header added "X-NCOM-CUSTOMERID: 9899" as __SIPADDHEADER04 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Asked to create a SIP channel with formats: (alaw) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Allocating new SIP dialog for 42c12c1214a6b122056eb0cc167147c7@158.58.152.14:5060 - INVITE (No RTP) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f908036ace8' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Allocated port 14320 for RTP instance '0x7f908036ace8' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] rtp_engine.c: RTP instance '0x7f908036ace8' is setup and ready to go [Nov 23 09:04:02] DEBUG[24483][C-001a2126] acl.c: Not an IPv4 nor IPv6 address, cannot get port. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] netsock2.c: Splitting 'pbx11.sig14.osl.nordicom.no' into... [Nov 23 09:04:02] DEBUG[24483][C-001a2126] netsock2.c: ...host 'pbx11.sig14.osl.nordicom.no' and port ''. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f908036ace8' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Setting NAT on RTP to Off [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: OBPROXY: Not applying OBproxy to this call [Nov 23 09:04:02] DEBUG[24483][C-001a2126] acl.c: For destination '158.58.154.18', our source address is '158.58.152.14'. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Setting AST_TRANSPORT_UDP with address 158.58.152.14:5060 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Setting NAT on RTP to Off [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: SIP call-id changed from '42c12c1214a6b122056eb0cc167147c7@158.58.152.14:5060' to '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: *** Our native formats are (alaw) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: *** Joint capabilities are (alaw) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: *** Our capabilities are (alaw) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: *** AST_CODEC_CHOOSE formats are alaw [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: *** Our preferred formats from the incoming channel are (alaw) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: This channel will not be able to handle video. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] channel_internal_api.c: Channel Call ID changing from [C-001a2126] to [C-001a2126] [Nov 23 09:04:02] DEBUG[24483][C-001a2126] channel.c: Inheriting variable __SIPADDHEADER04 from SIP/sip2-003434ab to SIP/sipic2-003434ac. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] channel.c: Inheriting variable __SIPADDHEADER03 from SIP/sip2-003434ab to SIP/sipic2-003434ac. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] channel.c: Inheriting variable __SIPADDHEADER02 from SIP/sip2-003434ab to SIP/sipic2-003434ac. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] channel.c: Inheriting variable __SIPADDHEADER01 from SIP/sip2-003434ab to SIP/sipic2-003434ac. [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Outgoing Call for 55191855 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Adding SIP Header "X-NCOM-CUSTOMERID" with content :9899: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Adding SIP Header "X-NCOM-ACCOUNT" with content :21651100: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Adding SIP Header "X-NCOM-CALLTYPE" with content :01: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Adding SIP Header "P-Asserted-Identity" with content :: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: ** Our capability: (alaw) Video flag: False Text flag: False [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: ** Our prefcodec: (alaw) [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Audio is at 14320 [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Adding codec alaw to SDP [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: -- Done with adding codecs to SDP [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Done building SDP. Settling with this capability: (alaw) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Initializing initreq for method INVITE - callid 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 0 [ 52]: INVITE sip:55191855@158.58.154.18;user=phone SIP/2.0 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK2c97650c [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 3 [ 71]: From: "55243445" ;tag=as4f007889 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 4 [ 43]: To: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 5 [ 42]: Contact: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 6 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 7 [ 16]: CSeq: 102 INVITE [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 8 [ 20]: User-Agent: Nordicom [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 9 [ 35]: Date: Wed, 23 Nov 2016 08:04:02 GMT [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 10 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 11 [ 16]: Supported: timer [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 12 [ 23]: X-NCOM-CUSTOMERID: 9899 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 13 [ 24]: X-NCOM-ACCOUNT: 21651100 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 14 [ 19]: X-NCOM-CALLTYPE: 01 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 15 [ 72]: P-Asserted-Identity: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 16 [ 29]: Content-Type: application/sdp [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Reliably Transmitting (no NAT) to 158.58.154.18:5060: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 0 [ 52]: INVITE sip:55191855@158.58.154.18;user=phone SIP/2.0 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK2c97650c [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 3 [ 71]: From: "55243445" ;tag=as4f007889 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 4 [ 43]: To: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 5 [ 42]: Contact: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 6 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 7 [ 16]: CSeq: 102 INVITE [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 8 [ 20]: User-Agent: Nordicom [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 9 [ 35]: Date: Wed, 23 Nov 2016 08:04:02 GMT [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 10 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 11 [ 16]: Supported: timer [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 12 [ 23]: X-NCOM-CUSTOMERID: 9899 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 13 [ 24]: X-NCOM-ACCOUNT: 21651100 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 14 [ 19]: X-NCOM-CALLTYPE: 01 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 15 [ 72]: P-Asserted-Identity: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 16 [ 29]: Content-Type: application/sdp [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 17 [ 19]: Content-Length: 240 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 18 [ 0]: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 0 [ 3]: v=0 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 1 [ 47]: o=root 384685532 384685532 IN IP4 158.58.152.14 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 2 [ 10]: s=Nordicom [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 3 [ 22]: c=IN IP4 158.58.152.14 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 5 [ 27]: m=audio 14320 RTP/AVP 8 101 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 7 [ 33]: a=rtpmap:101 telephone-event/8000 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 8 [ 15]: a=fmtp:101 0-16 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 9 [ 10]: a=ptime:20 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 10 [ 14]: a=maxptime:150 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 11 [ 10]: a=sendrecv [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #156 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 158.58.154.18:5060 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: *** SIP TIMER: Cancelling retransmission #156 - INVITE (got response) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' Request 102: Found [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 100 to standard invite [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' Request 102: Found [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 183 to standard invite [Nov 23 09:04:02] DEBUG[13731][C-001a2126] sip/route.c: sip_route_process_header: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] sip/route.c: sip_route_process_header: [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] sip/route.c: sip_route_dump: route/path hop: [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] sip/route.c: sip_route_dump: route/path hop: [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP o=- 11486692 11486692 IN IP4 MSC1.MSS.VENTELO.NET... OK. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting '185.97.84.36' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host '185.97.84.36' and port ''. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP c=IN IP4 185.97.84.36... OK. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP a=sendrecv... OK. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 8 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f9114301400 [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 101 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f9114301400 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: Splitting '185.97.84.36' into... [Nov 23 09:04:02] DEBUG[13731][C-001a2126] netsock2.c: ...host '185.97.84.36' and port ''. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 185.97.84.36... OK. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP b=RR:0... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP b=RS:0... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format PCMA for ID 8 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format telephone-event for ID 101 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=maxptime:40... UNSUPPORTED OR FAILED. [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Capabilities: us - (alaw), peer - audio=(alaw)/video=(nothing)/text=(nothing), combined - (alaw) [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f908036ace8' [Nov 23 09:04:02] VERBOSE[13731][C-001a2126] chan_sip.c: Peer audio RTP is at port 185.97.84.36:2302 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 8 (0x7f90cc004150) from 0x7f9114301400 to 0x7f908036aeb0 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 101 (0x7f90cc115880) from 0x7f9114301400 to 0x7f908036aeb0 [Nov 23 09:04:02] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f908036ace8' [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: We're settling with these formats: (alaw) [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: We have an owner, now see if we need to change this call [Nov 23 09:04:02] DEBUG[13731][C-001a2126] chan_sip.c: Setting native formats after processing SDP. peer joint formats (alaw), old nativeformats (alaw) [Nov 23 09:04:02] DEBUG[24483][C-001a2126] rtp_engine.c: Setting early bridge SDP of 'SIP/sip2-003434ab' with that of 'SIP/sipic2-003434ac' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: ** Our capability: (alaw|g729) Video flag: True Text flag: True [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: ** Our prefcodec: (nothing) [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Audio is at 11848 [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Adding codec alaw to SDP [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Adding codec g729 to SDP [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: -- Done with adding codecs to SDP [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Setting framing on incoming call: 20 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Done building SDP. Settling with this capability: (alaw|g729) [Nov 23 09:04:02] VERBOSE[24483][C-001a2126] chan_sip.c: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 0 [ 28]: SIP/2.0 183 Session Progress [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 1 [109]: Via: SIP/2.0/UDP 158.58.154.140;branch=z9hG4bK7ab9.f251bc0d2886adb808f7467114402881.0;received=158.58.154.140 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 2 [ 99]: Via: SIP/2.0/UDP 194.6.238.93:5061;received=194.6.238.93;rport=5061;branch=z9hG4bK-elbhqqqngnsvhksu [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 3 [ 37]: Record-Route: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 4 [ 66]: From: 55243445 ;tag=qummqu7b53s3cfs2.o [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 5 [ 51]: To: ;tag=as78a2676b [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 6 [ 48]: Call-ID: 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 7 [ 16]: CSeq: 665 INVITE [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 8 [ 16]: Server: Nordicom [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 9 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 10 [ 16]: Supported: timer [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 11 [ 45]: Contact: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 13 [ 19]: Content-Length: 289 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Header 14 [ 0]: [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 0 [ 3]: v=0 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 1 [ 49]: o=root 1498883100 1498883100 IN IP4 158.58.152.14 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 2 [ 10]: s=Nordicom [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 3 [ 22]: c=IN IP4 158.58.152.14 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 5 [ 30]: m=audio 11848 RTP/AVP 8 18 101 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 7 [ 21]: a=rtpmap:18 G729/8000 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 8 [ 19]: a=fmtp:18 annexb=no [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 9 [ 33]: a=rtpmap:101 telephone-event/8000 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 10 [ 15]: a=fmtp:101 0-16 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 12 [ 14]: a=maxptime:150 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Body 13 [ 10]: a=sendrecv [Nov 23 09:04:02] DEBUG[24483][C-001a2126] chan_sip.c: Trying to put 'SIP/2.0 183' onto UDP socket destined for 158.58.154.140:5060 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: 0x7f9080256940 -- Probation learning mode pass with source address 185.97.84.36:2302 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Ooh, format changed from none to alaw [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x7f90cc006788' [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: 0x7f90cc1d8a00 -- Probation learning mode pass with source address 194.6.238.93:38510 [Nov 23 09:04:02] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Ooh, format changed from none to alaw [Nov 23 09:04:03] DEBUG[13731][C-001a2126] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' Request 102: Found [Nov 23 09:04:03] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 180 to standard invite [Nov 23 09:04:03] DEBUG[13731][C-001a2126] sip/route.c: sip_route_process_header: [Nov 23 09:04:03] DEBUG[13731][C-001a2126] sip/route.c: sip_route_process_header: [Nov 23 09:04:03] VERBOSE[13731][C-001a2126] sip/route.c: sip_route_dump: route/path hop: [Nov 23 09:04:03] VERBOSE[13731][C-001a2126] sip/route.c: sip_route_dump: route/path hop: [Nov 23 09:04:03] DEBUG[24483][C-001a2126] rtp_engine.c: Setting early bridge SDP of 'SIP/sip2-003434ab' with that of 'SIP/sipic2-003434ac' [Nov 23 09:04:03] DEBUG[24483][C-001a2126] channel.c: Driver for channel 'SIP/sip2-003434ab' does not support indication 3, emulating it [Nov 23 09:04:03] DEBUG[24483][C-001a2126] channel.c: Channel SIP/sip2-003434ab setting write format path: slin -> alaw [Nov 23 09:04:03] DEBUG[24483][C-001a2126] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Nov 23 09:04:03] DEBUG[24483][C-001a2126] channel.c: Prodding channel 'SIP/sip2-003434ab' [Nov 23 09:04:03] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Received frame with no data for RTP instance '0x7f90cc006788' so dropping frame [Nov 23 09:04:03] DEBUG[24483][C-001a2126] channel.c: Channel SIP/sip2-003434ab setting write format path: alaw -> alaw [Nov 23 09:04:03] DEBUG[24483][C-001a2126] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Acked pending invite 102 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Stopping retransmission on '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' of Request 102: Match Found Rseq 0 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 200 to standard invite [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP o=- 11486692 11486692 IN IP4 MSC1.MSS.VENTELO.NET... OK. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: Splitting '185.97.84.36' into... [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: ...host '185.97.84.36' and port ''. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP c=IN IP4 185.97.84.36... OK. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP a=sendrecv... OK. [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 8 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 8 based on m type on 0x7f9114301410 [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Found RTP audio format 101 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] rtp_engine.c: Setting tx payload type 101 based on m type on 0x7f9114301410 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: Splitting '185.97.84.36' into... [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: ...host '185.97.84.36' and port ''. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP c=IN IP4 185.97.84.36... OK. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP b=RR:0... UNSUPPORTED OR FAILED. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP b=RS:0... UNSUPPORTED OR FAILED. [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format PCMA for ID 8 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Found audio description format telephone-event for ID 101 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (audio) SDP a=maxptime:40... UNSUPPORTED OR FAILED. [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Capabilities: us - (alaw), peer - audio=(alaw)/video=(nothing)/text=(nothing), combined - (alaw) [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Nov 23 09:04:09] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f908036ace8' [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Peer audio RTP is at port 185.97.84.36:2302 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 8 (0x7f90cc1871b0) from 0x7f9114301410 to 0x7f908036aeb0 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] rtp_engine.c: Copying payload 101 (0x7f90cc2410d0) from 0x7f9114301410 to 0x7f908036aeb0 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f908036ace8' [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: We're settling with these formats: (alaw) [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: We have an owner, now see if we need to change this call [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Setting native formats after processing SDP. peer joint formats (alaw), old nativeformats (alaw) [Nov 23 09:04:09] DEBUG[13731][C-001a2126] sip/route.c: sip_route_process_header: [Nov 23 09:04:09] DEBUG[13731][C-001a2126] sip/route.c: sip_route_process_header: [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] sip/route.c: sip_route_dump: route/path hop: [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] sip/route.c: sip_route_dump: route/path hop: [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: Splitting 'MSC1.MSS.VENTELO.NET:5060' into... [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: ...host 'MSC1.MSS.VENTELO.NET' and port '5060'. [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.154.18' into... [Nov 23 09:04:09] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.154.18' and port ''. [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: set_destination: set destination to 158.58.154.18:5060 [Nov 23 09:04:09] VERBOSE[13731][C-001a2126] chan_sip.c: Transmitting (no NAT) to 158.58.154.18:5060: [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 0 [ 55]: ACK sip:MSC1.MSS.VENTELO.NET:5060;transport=UDP SIP/2.0 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK66db2826 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 2 [ 91]: Route: , [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 3 [ 16]: Max-Forwards: 70 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 4 [ 71]: From: "55243445" ;tag=as4f007889 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 5 [ 62]: To: ;tag=15003182364635 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 6 [ 42]: Contact: [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 7 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 8 [ 13]: CSeq: 102 ACK [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 9 [ 20]: User-Agent: Nordicom [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Header 11 [ 0]: [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Trying to put 'ACK sip:MSC' onto UDP socket destined for 158.58.154.18:5060 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] rtp_engine.c: Setting early bridge SDP of 'SIP/sip2-003434ab' with that of 'SIP/sipic2-003434ac' [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: SIP answering channel: SIP/sip2-003434ab [Nov 23 09:04:09] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Setting the marker bit due to a source update [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: ** Our capability: (alaw|g729) Video flag: True Text flag: True [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: ** Our prefcodec: (nothing) [Nov 23 09:04:09] VERBOSE[24483][C-001a2126] chan_sip.c: Audio is at 11848 [Nov 23 09:04:09] VERBOSE[24483][C-001a2126] chan_sip.c: Adding codec alaw to SDP [Nov 23 09:04:09] VERBOSE[24483][C-001a2126] chan_sip.c: Adding codec g729 to SDP [Nov 23 09:04:09] VERBOSE[24483][C-001a2126] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: -- Done with adding codecs to SDP [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Setting framing on incoming call: 20 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Done building SDP. Settling with this capability: (alaw|g729) [Nov 23 09:04:09] VERBOSE[24483][C-001a2126] chan_sip.c: [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 1 [109]: Via: SIP/2.0/UDP 158.58.154.140;branch=z9hG4bK7ab9.f251bc0d2886adb808f7467114402881.0;received=158.58.154.140 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 2 [ 99]: Via: SIP/2.0/UDP 194.6.238.93:5061;received=194.6.238.93;rport=5061;branch=z9hG4bK-elbhqqqngnsvhksu [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 3 [ 37]: Record-Route: [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 4 [ 66]: From: 55243445 ;tag=qummqu7b53s3cfs2.o [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 5 [ 51]: To: ;tag=as78a2676b [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 6 [ 48]: Call-ID: 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 7 [ 16]: CSeq: 665 INVITE [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 8 [ 16]: Server: Nordicom [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 9 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 10 [ 16]: Supported: timer [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 11 [ 45]: Contact: [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 13 [ 19]: Content-Length: 289 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Header 14 [ 0]: [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 0 [ 3]: v=0 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 1 [ 49]: o=root 1498883100 1498883100 IN IP4 158.58.152.14 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 2 [ 10]: s=Nordicom [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 3 [ 22]: c=IN IP4 158.58.152.14 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 5 [ 30]: m=audio 11848 RTP/AVP 8 18 101 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 7 [ 21]: a=rtpmap:18 G729/8000 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 8 [ 19]: a=fmtp:18 annexb=no [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 9 [ 33]: a=rtpmap:101 telephone-event/8000 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 10 [ 15]: a=fmtp:101 0-16 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 11 [ 10]: a=ptime:20 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 12 [ 14]: a=maxptime:150 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Body 13 [ 10]: a=sendrecv [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #309 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 158.58.154.140:5060 [Nov 23 09:04:09] DEBUG[24483][C-001a2126] dahdi/bridge_native_dahdi.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: Cannot use native DAHDI. Must have two channels. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology softmix has less preference than simple_bridge (10 <= 50). Skipping. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge_native_rtp.c: Bridge 'a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc' can not use native RTP bridge as two channels are required [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Chose bridge technology simple_bridge [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling simple_bridge technology constructor [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling simple_bridge technology start [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f9080296668(SIP/sipic2-003434ac) is joining [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: pushing 0x7f9080296668(SIP/sipic2-003434ac) [Nov 23 09:04:09] DEBUG[24489][C-001a2126] dahdi/bridge_native_dahdi.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: Cannot use native DAHDI. Must have two channels. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge_native_rtp.c: Bridge 'a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc' can not use native RTP bridge as two channels are required [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge technology native_rtp is not compatible with properties of existing bridge. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Chose bridge technology simple_bridge [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc is already using the new technology. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f9080296668(SIP/sipic2-003434ac) is joining simple_bridge technology [Nov 23 09:04:09] DEBUG[24489][C-001a2126] res_rtp_asterisk.c: Changing ssrc from 27603476 to 1794303300 due to a source change [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f908028e1f8(SIP/sip2-003434ab) is joining [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: pushing 0x7f908028e1f8(SIP/sip2-003434ab) [Nov 23 09:04:09] DEBUG[24483][C-001a2126] dahdi/bridge_native_dahdi.c: Channel 'SIP/sipic2-003434ac' is not DAHDI. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] dahdi/bridge_native_dahdi.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: Cannot use native DAHDI. Channel 'SIP/sipic2-003434ac' not compatible. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Chose bridge technology native_rtp [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling native_rtp technology constructor [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: moving 0x7f9080296668(SIP/sipic2-003434ac) to dummy bridge temporarily [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f9080296668(SIP/sipic2-003434ac) is leaving simple_bridge technology (dummy) [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling simple_bridge technology stop [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f908028e1f8(SIP/sip2-003434ab) is joining native_rtp technology [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f9080296668(SIP/sipic2-003434ac) is joining native_rtp technology [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling native_rtp technology start [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling simple_bridge technology destructor [Nov 23 09:04:09] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Changing ssrc from 1676577391 to 908936389 due to a source change [Nov 23 09:04:09] DEBUG[24489][C-001a2126] dahdi/bridge_native_dahdi.c: Channel 'SIP/sip2-003434ab' is not DAHDI. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] dahdi/bridge_native_dahdi.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: Cannot use native DAHDI. Channel 'SIP/sip2-003434ab' not compatible. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Chose bridge technology native_rtp [Nov 23 09:04:09] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc is already using the new technology. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] dahdi/bridge_native_dahdi.c: Channel 'SIP/sip2-003434ab' is not DAHDI. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] dahdi/bridge_native_dahdi.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: Cannot use native DAHDI. Channel 'SIP/sip2-003434ab' not compatible. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology native_dahdi is not compatible with properties of existing bridge. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology softmix does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge technology holding_bridge does not have any capabilities we want. [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Chose bridge technology native_rtp [Nov 23 09:04:09] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc is already using the new technology. [Nov 23 09:04:09] DEBUG[24489][C-001a2126] res_rtp_asterisk.c: 0x7f9080256940 -- Probation learning mode pass with source address 185.97.84.36:2302 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #309 [Nov 23 09:04:09] DEBUG[13731][C-001a2126] chan_sip.c: Stopping retransmission on '020A474FED81400000000719@85.200.240.142' of Response 665: Match Found Rseq 0 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.154.18' into... [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.154.18' and port ''. [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Sending to 158.58.154.18:5060 (no NAT) [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Initializing initreq for method INVITE - callid 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP o=- 11486692 11486693 IN IP4 MSC1.MSS.VENTELO.NET... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: Splitting '185.97.84.36' into... [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: ...host '185.97.84.36' and port ''. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP c=IN IP4 185.97.84.36... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP a=sendrecv... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Setting NAT on UDPTL to Off [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Got T.38 offer in SDP in dialog 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: Splitting '185.97.84.36' into... [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: ...host '185.97.84.36' and port ''. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP c=IN IP4 185.97.84.36... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: FaxVersion: 0 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxVersion:0... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: T38MaxBitRate: 14400 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38MaxBitRate:14400... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: MaxBufferSize:1800 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxMaxBuffer:1800... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: FaxMaxDatagram: 150 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxMaxDatagram:150... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: RateManagement: transferredtcf [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxRateManagement:transferredTCF... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: UDP EC: t38udpredundancy [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxUdpEC:t38UDPRedundancy... OK. [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Capabilities: us - (alaw), peer - audio=(nothing)/video=(nothing)/text=(nothing), combined - (nothing) [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x0 (nothing), combined - 0x0 (nothing) [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Got T.38 Re-invite without audio. Keeping RTP active during T.38 session. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Peer T.38 UDPTL is at port 185.97.84.36:17914 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: T38 state changed to 2 on channel SIP/sipic2-003434ac [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Have T.38 but no audio, accepting offer anyway [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Got a SIP re-invite for call 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: SIP/sipic2-003434ac: This call is UP.... [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 0 [ 18]: SIP/2.0 100 Trying [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 1 [107]: Via: SIP/2.0/UDP 158.58.154.18;branch=z9hG4bK32a7.a1b33037422ee273a2971592f067f418.0;received=158.58.154.18 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 2 [100]: Via: SIP/2.0/UDP MSC1.MSS.VENTELO.NET:5060;received=185.97.84.230;branch=z9hG4bK00000014035031794496 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 3 [ 36]: Record-Route: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 4 [ 64]: From: ;tag=15003182364635 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 5 [ 69]: To: "55243445" ;tag=as4f007889 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 6 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 7 [ 14]: CSeq: 1 INVITE [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 8 [ 16]: Server: Nordicom [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 9 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 10 [ 16]: Supported: timer [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 11 [ 42]: Contact: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 13 [ 0]: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 158.58.154.18:5060 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Setting NAT on UDPTL to Off [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: T38 state changed to 1 on channel SIP/sip2-003434ab [Nov 23 09:04:15] VERBOSE[24483][C-001a2126] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 23 09:04:15] DEBUG[24483][C-001a2126] netsock2.c: Splitting '158.58.154.140' into... [Nov 23 09:04:15] DEBUG[24483][C-001a2126] netsock2.c: ...host '158.58.154.140' and port ''. [Nov 23 09:04:15] VERBOSE[24483][C-001a2126] chan_sip.c: set_destination: set destination to 158.58.154.140:5060 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: T.38 UDPTL is at 158.58.152.14 port 4914 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Done building SDP. Settling with this capability: (nothing) [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Initializing already initialized SIP dialog 020A474FED81400000000719@85.200.240.142 (presumably reinvite) [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 0 [ 46]: INVITE sip:+55243445@194.6.238.93:5061 SIP/2.0 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK13060f4f [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 2 [ 30]: Route: [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 3 [ 16]: Max-Forwards: 70 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 4 [ 53]: From: ;tag=as78a2676b [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 5 [ 64]: To: 55243445 ;tag=qummqu7b53s3cfs2.o [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 6 [ 45]: Contact: [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 7 [ 48]: Call-ID: 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 8 [ 16]: CSeq: 102 INVITE [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 9 [ 20]: User-Agent: Nordicom [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 10 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 11 [ 16]: Supported: timer [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 12 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Nov 23 09:04:15] VERBOSE[24483][C-001a2126] chan_sip.c: Reliably Transmitting (no NAT) to 158.58.154.140:5060: [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 0 [ 46]: INVITE sip:+55243445@194.6.238.93:5061 SIP/2.0 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK13060f4f [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 2 [ 30]: Route: [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 3 [ 16]: Max-Forwards: 70 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 4 [ 53]: From: ;tag=as78a2676b [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 5 [ 64]: To: 55243445 ;tag=qummqu7b53s3cfs2.o [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 6 [ 45]: Contact: [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 7 [ 48]: Call-ID: 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 8 [ 16]: CSeq: 102 INVITE [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 9 [ 20]: User-Agent: Nordicom [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 10 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 11 [ 16]: Supported: timer [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 12 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 14 [ 19]: Content-Length: 254 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Header 15 [ 0]: [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 0 [ 3]: v=0 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 1 [ 49]: o=root 1498883100 1498883101 IN IP4 158.58.152.14 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 2 [ 10]: s=Nordicom [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 3 [ 22]: c=IN IP4 158.58.152.14 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 5 [ 22]: m=image 4914 udptl t38 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 6 [ 17]: a=T38FaxVersion:0 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 7 [ 21]: a=T38MaxBitRate:14400 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 8 [ 37]: a=T38FaxRateManagement:transferredTCF [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 9 [ 23]: a=T38FaxMaxDatagram:150 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Body 10 [ 23]: a=T38FaxUdpEC:t38UDPFEC [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #3 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 158.58.154.140:5060 [Nov 23 09:04:15] DEBUG[24483][C-001a2126] res_rtp_asterisk.c: Setting the marker bit due to a source update [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: *** SIP TIMER: Cancelling retransmission #3 - INVITE (got response) [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '020A474FED81400000000719@85.200.240.142' Request 102: Found [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 100 to RE-invite on outgoing call 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Acked pending invite 102 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Stopping retransmission on '020A474FED81400000000719@85.200.240.142' of Request 102: Match Found Rseq 0 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 200 to RE-invite on outgoing call 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP o=Sippy 3661178290213760010 2 IN IP4 194.6.238.93... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Got T.38 offer in SDP in dialog 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: Splitting '194.6.238.93' into... [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: ...host '194.6.238.93' and port ''. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP c=IN IP4 194.6.238.93... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: FaxVersion: 0 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxVersion:0... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: RateManagement: transferredtcf [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxRateManagement:transferredTCF... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: T38MaxBitRate: 14400 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38maxBitRate:14400... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: MaxBufferSize:1800 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxMaxBuffer:1800... OK. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: FaxMaxDatagram: 150 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Processing media-level (image) SDP a=T38FaxMaxDatagram:150... OK. [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Capabilities: us - (alaw|ulaw|g729), peer - audio=(nothing)/video=(nothing)/text=(nothing), combined - (nothing) [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x0 (nothing), combined - 0x0 (nothing) [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Got T.38 Re-invite without audio. Keeping RTP active during T.38 session. [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Peer T.38 UDPTL is at port 194.6.238.93:38510 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: T38 state changed to 3 on channel SIP/sip2-003434ab [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Have T.38 but no audio, accepting offer anyway [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: Splitting '194.6.238.93:5061' into... [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: ...host '194.6.238.93' and port '5061'. [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.154.140' into... [Nov 23 09:04:15] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.154.140' and port ''. [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: set_destination: set destination to 158.58.154.140:5060 [Nov 23 09:04:15] VERBOSE[13731][C-001a2126] chan_sip.c: Transmitting (no NAT) to 158.58.154.140:5060: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 0 [ 43]: ACK sip:+55243445@194.6.238.93:5061 SIP/2.0 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK78ac5ca6 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 2 [ 30]: Route: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 3 [ 16]: Max-Forwards: 70 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 4 [ 53]: From: ;tag=as78a2676b [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 5 [ 64]: To: 55243445 ;tag=qummqu7b53s3cfs2.o [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 6 [ 45]: Contact: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 7 [ 48]: Call-ID: 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 8 [ 13]: CSeq: 102 ACK [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 9 [ 20]: User-Agent: Nordicom [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Header 11 [ 0]: [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Trying to put 'ACK sip:+55' onto UDP socket destined for 158.58.154.140:5060 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: T38 state changed to 3 on channel SIP/sipic2-003434ac [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: T.38 UDPTL is at 158.58.152.14 port 4760 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Done building SDP. Settling with this capability: (nothing) [Nov 23 09:04:15] VERBOSE[24489][C-001a2126] chan_sip.c: [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 1 [107]: Via: SIP/2.0/UDP 158.58.154.18;branch=z9hG4bK32a7.a1b33037422ee273a2971592f067f418.0;received=158.58.154.18 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 2 [100]: Via: SIP/2.0/UDP MSC1.MSS.VENTELO.NET:5060;received=185.97.84.230;branch=z9hG4bK00000014035031794496 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 3 [ 36]: Record-Route: [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 4 [ 64]: From: ;tag=15003182364635 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 5 [ 69]: To: "55243445" ;tag=as4f007889 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 6 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 7 [ 14]: CSeq: 1 INVITE [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 8 [ 16]: Server: Nordicom [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 9 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 10 [ 16]: Supported: timer [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 11 [ 42]: Contact: [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 12 [ 29]: Content-Type: application/sdp [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 13 [ 19]: Content-Length: 259 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Header 14 [ 0]: [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 0 [ 3]: v=0 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 1 [ 47]: o=root 384685532 384685533 IN IP4 158.58.152.14 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 2 [ 10]: s=Nordicom [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 3 [ 22]: c=IN IP4 158.58.152.14 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 5 [ 22]: m=image 4760 udptl t38 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 6 [ 17]: a=T38FaxVersion:0 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 7 [ 21]: a=T38MaxBitRate:14400 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 8 [ 37]: a=T38FaxRateManagement:transferredTCF [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 9 [ 23]: a=T38FaxMaxDatagram:582 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Body 10 [ 30]: a=T38FaxUdpEC:t38UDPRedundancy [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #119 [Nov 23 09:04:15] DEBUG[24489][C-001a2126] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 158.58.154.18:5060 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #119 [Nov 23 09:04:15] DEBUG[13731][C-001a2126] chan_sip.c: Stopping retransmission on '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' of Response 1: Match Found Rseq 0 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Initializing initreq for method BYE - callid 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.154.140' into... [Nov 23 09:04:47] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.154.140' and port ''. [Nov 23 09:04:47] VERBOSE[13731][C-001a2126] chan_sip.c: Sending to 158.58.154.140:5060 (no NAT) [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Setting SIP_ALREADYGONE on dialog 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:47] VERBOSE[13731][C-001a2126] chan_sip.c: Scheduling destruction of SIP dialog '020A474FED81400000000719@85.200.240.142' in 32000 ms (Method: BYE) [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Received bye, issuing owner hangup [Nov 23 09:04:47] VERBOSE[13731][C-001a2126] chan_sip.c: [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 1 [109]: Via: SIP/2.0/UDP 158.58.154.140;branch=z9hG4bK4ab9.54a0f9a321c802314c34d69deffba0d7.0;received=158.58.154.140 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 2 [ 99]: Via: SIP/2.0/UDP 194.6.238.93:5061;received=194.6.238.93;rport=5061;branch=z9hG4bK-hkhkug3mbhiv7qbp [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 3 [ 66]: From: 55243445 ;tag=qummqu7b53s3cfs2.o [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 4 [ 51]: To: ;tag=as78a2676b [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 5 [ 48]: Call-ID: 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 6 [ 13]: CSeq: 666 BYE [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 7 [ 16]: Server: Nordicom [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 8 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 9 [ 16]: Supported: timer [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 11 [ 0]: [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 158.58.154.140:5060 [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge_channel.c: Setting 0x7f908028e1f8(SIP/sip2-003434ab) state from:0 to:1 [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: pulling 0x7f908028e1f8(SIP/sip2-003434ab) [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f908028e1f8(SIP/sip2-003434ab) is leaving native_rtp technology [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge_native_rtp.c: Discontinued RTP bridging of 'SIP/sip2-003434ab' and 'SIP/sipic2-003434ac' - media will flow through Asterisk core [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: dissolving bridge with cause 16(Normal Clearing) [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge_channel.c: Setting 0x7f9080296668(SIP/sipic2-003434ac) state from:0 to:2 [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: queueing action type:13 sub:1001 [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge_channel.c: T.38 terminate simulated to bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc because SIP/sip2-003434ab left. [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc is dissolved, not performing smart bridge operation. [Nov 23 09:04:47] DEBUG[24483][C-001a2126] bridge_channel.c: Channel SIP/sip2-003434ab simulating T.38 terminate for bridge end. [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: pulling 0x7f9080296668(SIP/sipic2-003434ac) [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge_channel.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: 0x7f9080296668(SIP/sipic2-003434ac) is leaving native_rtp technology [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge_channel.c: Channel SIP/sipic2-003434ac will survive this bridge; clearing outgoing (dialed) flag [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge_channel.c: T.38 terminate simulated to bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc because SIP/sipic2-003434ac left. [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc is dissolved, not performing smart bridge operation. [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge_channel.c: Channel SIP/sipic2-003434ac simulating T.38 terminate for bridge end. [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: T38 state changed to 0 on channel SIP/sipic2-003434ac [Nov 23 09:04:47] DEBUG[24489][C-001a2126] acl.c: Not an IPv4 nor IPv6 address, cannot get port. [Nov 23 09:04:47] DEBUG[24489][C-001a2126] netsock2.c: Splitting 'pbx11.sig14.osl.nordicom.no' into... [Nov 23 09:04:47] DEBUG[24489][C-001a2126] netsock2.c: ...host 'pbx11.sig14.osl.nordicom.no' and port ''. [Nov 23 09:04:47] DEBUG[24483][C-001a2126] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Nov 23 09:04:47] DEBUG[24483][C-001a2126] res_agi.c: SIP/sip2-003434ab hungup [Nov 23 09:04:47] DEBUG[24489][C-001a2126] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f908036ace8' [Nov 23 09:04:47] VERBOSE[24489][C-001a2126] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 23 09:04:47] DEBUG[24489][C-001a2126] netsock2.c: Splitting '158.58.154.18' into... [Nov 23 09:04:47] DEBUG[24489][C-001a2126] netsock2.c: ...host '158.58.154.18' and port ''. [Nov 23 09:04:47] VERBOSE[24489][C-001a2126] chan_sip.c: set_destination: set destination to 158.58.154.18:5060 [Nov 23 09:04:47] DEBUG[24483][C-001a2126] pbx_variables.c: Result of 'DIALSTATUS' is 'ANSWER' [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: ** Our capability: (alaw) Video flag: True Text flag: True [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: ** Our prefcodec: (alaw) [Nov 23 09:04:47] VERBOSE[24489][C-001a2126] chan_sip.c: Audio is at 14320 [Nov 23 09:04:47] VERBOSE[24489][C-001a2126] chan_sip.c: Adding codec alaw to SDP [Nov 23 09:04:47] VERBOSE[24489][C-001a2126] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: -- Done with adding codecs to SDP [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Setting framing on incoming call: 20 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Done building SDP. Settling with this capability: (alaw) [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Initializing already initialized SIP dialog 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 (presumably reinvite) [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 0 [ 58]: INVITE sip:MSC1.MSS.VENTELO.NET:5060;transport=UDP SIP/2.0 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK2d956846 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 2 [ 91]: Route: , [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 3 [ 16]: Max-Forwards: 70 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 4 [ 71]: From: "55243445" ;tag=as4f007889 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 5 [ 62]: To: ;tag=15003182364635 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 6 [ 42]: Contact: [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 7 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 8 [ 16]: CSeq: 103 INVITE [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 9 [ 20]: User-Agent: Nordicom [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 10 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 11 [ 16]: Supported: timer [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 12 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Nov 23 09:04:47] VERBOSE[24489][C-001a2126] chan_sip.c: Reliably Transmitting (no NAT) to 158.58.154.18:5060: [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 0 [ 58]: INVITE sip:MSC1.MSS.VENTELO.NET:5060;transport=UDP SIP/2.0 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK2d956846 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 2 [ 91]: Route: , [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 3 [ 16]: Max-Forwards: 70 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 4 [ 71]: From: "55243445" ;tag=as4f007889 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 5 [ 62]: To: ;tag=15003182364635 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 6 [ 42]: Contact: [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 7 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 8 [ 16]: CSeq: 103 INVITE [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 9 [ 20]: User-Agent: Nordicom [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 10 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 11 [ 16]: Supported: timer [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 12 [ 52]: X-asterisk-Info: SIP re-invite (External RTP bridge) [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 14 [ 19]: Content-Length: 240 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Header 15 [ 0]: [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 0 [ 3]: v=0 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 1 [ 47]: o=root 384685532 384685534 IN IP4 158.58.152.14 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 2 [ 10]: s=Nordicom [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 3 [ 22]: c=IN IP4 158.58.152.14 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 5 [ 27]: m=audio 14320 RTP/AVP 8 101 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 7 [ 33]: a=rtpmap:101 telephone-event/8000 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 8 [ 15]: a=fmtp:101 0-16 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 9 [ 10]: a=ptime:20 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 10 [ 14]: a=maxptime:150 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Body 11 [ 10]: a=sendrecv [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #135 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Trying to put 'INVITE sip:' onto UDP socket destined for 158.58.154.18:5060 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] res_rtp_asterisk.c: Changing ssrc from 1794303300 to 8143852 due to a source change [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: actually destroying basic bridge, nobody wants it anymore [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling basic bridge destructor [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling native_rtp technology stop [Nov 23 09:04:47] DEBUG[24489][C-001a2126] bridge.c: Bridge a92d670b-feb8-4cda-ad5a-ac9c0e79f2fc: calling native_rtp technology destructor [Nov 23 09:04:47] DEBUG[24489][C-001a2126] channel.c: Hanging up channel 'SIP/sipic2-003434ac' [Nov 23 09:04:47] DEBUG[24489][C-001a2126] chan_sip.c: Hangup call SIP/sipic2-003434ac, SIP callid 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:47] DEBUG[24489][C-001a2126] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f908036ace8' [Nov 23 09:04:47] VERBOSE[24489][C-001a2126] chan_sip.c: Scheduling destruction of SIP dialog '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' in 6400 ms (Method: ACK) [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: *** SIP TIMER: Cancelling retransmission #135 - INVITE (got response) [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' Request 103: Found [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 100 to RE-invite on outgoing call 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Acked pending invite 103 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Stopping retransmission on '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' of Request 103: Match Found Rseq 0 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: SIP response 488 to RE-invite on outgoing call 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:47] VERBOSE[13731][C-001a2126] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 23 09:04:47] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.154.18' into... [Nov 23 09:04:47] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.154.18' and port ''. [Nov 23 09:04:47] VERBOSE[13731][C-001a2126] chan_sip.c: set_destination: set destination to 158.58.154.18:5060 [Nov 23 09:04:47] VERBOSE[13731][C-001a2126] chan_sip.c: Transmitting (no NAT) to 158.58.154.18:5060: [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 0 [ 55]: ACK sip:MSC1.MSS.VENTELO.NET:5060;transport=UDP SIP/2.0 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 158.58.152.14:5060;branch=z9hG4bK2d956846 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 2 [ 91]: Route: , [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 3 [ 16]: Max-Forwards: 70 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 4 [ 71]: From: "55243445" ;tag=as4f007889 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 5 [ 62]: To: ;tag=15003182364635 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 6 [ 42]: Contact: [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 7 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 8 [ 13]: CSeq: 103 ACK [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 9 [ 20]: User-Agent: Nordicom [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Header 11 [ 0]: [Nov 23 09:04:47] DEBUG[13731][C-001a2126] chan_sip.c: Trying to put 'ACK sip:MSC' onto UDP socket destined for 158.58.154.18:5060 [Nov 23 09:04:47] DEBUG[24483][C-001a2126] pbx.c: Spawn extension (cc_customer,+4755191855,2) exited non-zero on 'SIP/sip2-003434ab' [Nov 23 09:04:47] DEBUG[24483][C-001a2126] channel.c: Soft-Hanging (0x10) up channel 'SIP/sip2-003434ab' [Nov 23 09:04:47] DEBUG[24483][C-001a2126] channel.c: Hanging up channel 'SIP/sip2-003434ab' [Nov 23 09:04:47] DEBUG[24483][C-001a2126] chan_sip.c: Hangup call SIP/sip2-003434ab, SIP callid 020A474FED81400000000719@85.200.240.142 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Initializing initreq for method BYE - callid 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] netsock2.c: Splitting '158.58.154.18' into... [Nov 23 09:04:48] DEBUG[13731][C-001a2126] netsock2.c: ...host '158.58.154.18' and port ''. [Nov 23 09:04:48] VERBOSE[13731][C-001a2126] chan_sip.c: Sending to 158.58.154.18:5060 (no NAT) [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Setting SIP_ALREADYGONE on dialog 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f908036ace8' [Nov 23 09:04:48] VERBOSE[13731][C-001a2126] chan_sip.c: Scheduling destruction of SIP dialog '203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060' in 6400 ms (Method: BYE) [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Received bye, no owner, selfdestruct soon. [Nov 23 09:04:48] VERBOSE[13731][C-001a2126] chan_sip.c: [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 1 [107]: Via: SIP/2.0/UDP 158.58.154.18;branch=z9hG4bK02a7.1d4246dfa74b5fca210a703fbf0947aa.0;received=158.58.154.18 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 2 [100]: Via: SIP/2.0/UDP MSC1.MSS.VENTELO.NET:5060;received=185.97.84.230;branch=z9hG4bK00000052427935348984 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 3 [ 36]: Record-Route: [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 4 [ 64]: From: ;tag=15003182364635 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 5 [ 69]: To: "55243445" ;tag=as4f007889 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 6 [ 60]: Call-ID: 203f326067605d070c51b7f967ffa7b2@158.58.152.14:5060 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 7 [ 11]: CSeq: 2 BYE [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 8 [ 16]: Server: Nordicom [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 9 [ 97]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE, PRACK [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 10 [ 16]: Supported: timer [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 11 [ 17]: Content-Length: 0 [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Header 12 [ 0]: [Nov 23 09:04:48] DEBUG[13731][C-001a2126] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 158.58.154.18:5060