[Dec 23 02:31:18] VERBOSE[9723] config.c: == Parsing '/etc/asterisk/logger.conf': [Dec 23 02:31:18] DEBUG[9723] config.c: Parsing /etc/asterisk/logger.conf [Dec 23 02:31:18] VERBOSE[9723] config.c: == Found [Dec 23 02:31:18] VERBOSE[9723] logger.c: Asterisk Queue Logger restarted [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> INVITE sip:1337@vcc.fr.acme.net SIP/2.0 Record-Route: To: Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. From: ;tag=DLc8a7686686;epid=0x204151d0 CSeq: 1 INVITE Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.0 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d783c9aca7-DL Contact: "200" Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Supported: replaces,norefersub User-Agent: Dylogic Mirial 7.0.36 Content-Type: application/sdp Content-Length: 490 P-hint: outbound P-Conference-ID: 1293067914 v=0 o=200 1912900292 0 IN IP4 192.168.0.195 s=- i=Dylogic Mirial 7.0.36 c=IN IP4 111.111.173.219 b=AS:768 t=0 0 m=audio 53534 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=x-mpdp:192.168.0.195:42000 m=video 44186 RTP/AVP 96 34 a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 a=rtpmap:34 H263/90000 a=fmtp:34 CIF=1; QCIF=1 a=x-mpdp:192.168.0.195:42002 a=nortpproxy:yes <-------------> [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 0 [ 44]: INVITE sip:1337@vcc.fr.acme.net SIP/2.0 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 1 [ 40]: Record-Route: [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 2 [ 35]: To: [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 3 [ 62]: Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 4 [ 69]: From: ;tag=DLc8a7686686;epid=0x204151d0 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 6 [ 16]: Max-Forwards: 69 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 7 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.0 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 8 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d783c9aca7-DL [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 9 [ 44]: Contact: "200" [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 10 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 11 [ 30]: Supported: replaces,norefersub [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 12 [ 33]: User-Agent: Dylogic Mirial 7.0.36 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 14 [ 19]: Content-Length: 490 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 15 [ 16]: P-hint: outbound [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 16 [ 28]: P-Conference-ID: 1293067914 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Header 17 [ 0]: [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 0 [ 3]: v=0 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 1 [ 39]: o=200 1912900292 0 IN IP4 192.168.0.195 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 2 [ 3]: s=- [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 3 [ 23]: i=Dylogic Mirial 7.0.36 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 4 [ 23]: c=IN IP4 111.111.173.219 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 5 [ 8]: b=AS:768 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 6 [ 5]: t=0 0 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 7 [ 29]: m=audio 53534 RTP/AVP 0 8 101 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 9 [ 20]: a=rtpmap:8 PCMA/8000 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-16 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 12 [ 28]: a=x-mpdp:192.168.0.195:42000 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 13 [ 27]: m=video 44186 RTP/AVP 96 34 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 14 [ 27]: a=rtpmap:96 H263-1998/90000 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 15 [ 58]: a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 16 [ 22]: a=rtpmap:34 H263/90000 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 17 [ 23]: a=fmtp:34 CIF=1; QCIF=1 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 18 [ 28]: a=x-mpdp:192.168.0.195:42002 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Body 19 [ 16]: a=nortpproxy:yes [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: --- (17 headers 20 lines) --- [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DLc8a7686686 --To-tag [Dec 23 02:31:58] DEBUG[9718] acl.c: For destination '111.111.173.219', our source address is '111.111.22.105'. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 111.111.22.105:5060 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Allocating new SIP dialog for DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. - INVITE (No RTP) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 23 02:31:58] DEBUG[9718] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces,norefersub" [Dec 23 02:31:58] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -replaces- [Dec 23 02:31:58] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: replaces [Dec 23 02:31:58] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -norefersub- [Dec 23 02:31:58] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: norefersub [Dec 23 02:31:58] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:31:58] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Sending to 111.111.173.219:5060 (no NAT) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Initializing initreq for method INVITE - callid DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Using INVITE request as basis request - DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: No matching peer for '200' from '111.111.173.219:5060' [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x71c25e8' [Dec 23 02:31:58] DEBUG[9718] res_rtp_asterisk.c: Allocated port 13314 for RTP instance '0x71c25e8' [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: RTP instance '0x71c25e8' is setup and ready to go [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x718ba28' [Dec 23 02:31:58] DEBUG[9718] res_rtp_asterisk.c: Allocated port 12074 for RTP instance '0x718ba28' [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: RTP instance '0x718ba28' is setup and ready to go [Dec 23 02:31:58] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x718ba28' [Dec 23 02:31:58] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x71c25e8' [Dec 23 02:31:58] VERBOSE[9718] netsock2.c: == Using SIP RTP CoS mark 5 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Setting NAT on RTP to Off [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Setting NAT on VRTP to Off [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing session-level SDP o=200 1912900292 0 IN IP4 192.168.0.195... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing session-level SDP i=Dylogic Mirial 7.0.36... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:31:58] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing session-level SDP c=IN IP4 111.111.173.219... OK. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing session-level SDP b=AS:768... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found RTP audio format 0 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Setting payload 0 based on m type on 0x7ffa2cae58a0 [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found RTP audio format 8 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Setting payload 8 based on m type on 0x7ffa2cae58a0 [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found RTP audio format 101 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Setting payload 101 based on m type on 0x7ffa2cae58a0 [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found audio description format PCMU for ID 0 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found audio description format PCMA for ID 8 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found audio description format telephone-event for ID 101 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=x-mpdp:192.168.0.195:42000... UNSUPPORTED. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found RTP video format 96 [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found RTP video format 34 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Setting payload 34 based on m type on 0x7ffa2cae4820 [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found video description format H263-1998 for ID 96 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:96 H263-1998/90000... OK. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1... UNSUPPORTED. [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Found video description format H263 for ID 34 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:34 H263/90000... OK. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:34 CIF=1; QCIF=1... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=x-mpdp:192.168.0.195:42002... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=nortpproxy:yes... UNSUPPORTED. [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Incorporating payload 0 on 0x7ffa2cae58a0 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Incorporating payload 8 on 0x7ffa2cae58a0 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Incorporating payload 101 on 0x7ffa2cae58a0 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Incorporating payload 34 on 0x7ffa2cae4820 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Incorporating payload 96 on 0x7ffa2cae4820 [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Capabilities: us - 0x180004 (ulaw|h263|h263p), peer - audio=0xc (ulaw|alaw)/video=0x180000 (h263|h263p)/text=0x0 (nothing), combined - 0x180004 (ulaw|h263|h263p) [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Dec 23 02:31:58] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x71c25e8' [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Peer audio RTP is at port 111.111.173.219:53534 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Copying payload 0 from 0x7ffa2cae58a0 to 0x71c27b0 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Copying payload 8 from 0x7ffa2cae58a0 to 0x71c27b0 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Copying payload 101 from 0x7ffa2cae58a0 to 0x71c27b0 [Dec 23 02:31:58] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x718ba28' [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Peer video RTP is at port 111.111.173.219:44186 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Copying payload 34 from 0x7ffa2cae4820 to 0x718bbf0 [Dec 23 02:31:58] DEBUG[9718] rtp_engine.c: Copying payload 96 from 0x7ffa2cae4820 to 0x718bbf0 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: We're settling with these formats: 0x180004 (ulaw|h263|h263p) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Checking SIP call limits for device [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Updating call counter for incoming call [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Looking for 1337 in default (domain vcc.fr.acme.net) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: *** Our native formats are 0x180004 (ulaw|h263|h263p) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: *** Joint capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: *** Our capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: This channel can handle video! HOLLYWOOD next! [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: build_route: Record-Route hop: [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: list_route: hop: [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: SIP/sip.fr.acme.net-0000003e: New call is still down.... Trying... [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d783c9aca7-DL Record-Route: From: ;tag=DLc8a7686686;epid=0x204151d0 To: Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:31:58] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:31:58] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:31:58] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:31:58] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:31:58] DEBUG[9792] pbx.c: Launching 'AGI' [Dec 23 02:31:58] VERBOSE[9792] pbx.c: -- Executing [1337@default:1] AGI("SIP/sip.fr.acme.net-0000003e", "agi://127.0.0.1") in new stack [Dec 23 02:31:58] DEBUG[9792] res_agi.c: Wow, connected! [Dec 23 02:31:58] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:31:58] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: SIP answering channel: SIP/sip.fr.acme.net-0000003e [Dec 23 02:31:58] DEBUG[9792] res_rtp_asterisk.c: Setting the marker bit due to a source update [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: Setting framing from config on incoming call [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: This call needs video offers! [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: ** Our capability: 0x180004 (ulaw|h263|h263p) Video flag: False Text flag: True [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Dec 23 02:31:58] VERBOSE[9792] chan_sip.c: Audio is at 5060 [Dec 23 02:31:58] VERBOSE[9792] chan_sip.c: Video is at 111.111.22.105:5060 [Dec 23 02:31:58] VERBOSE[9792] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Dec 23 02:31:58] VERBOSE[9792] chan_sip.c: Adding video codec 0x80000 (h263) to SDP [Dec 23 02:31:58] VERBOSE[9792] chan_sip.c: Adding video codec 0x100000 (h263p) to SDP [Dec 23 02:31:58] VERBOSE[9792] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: -- Done with adding codecs to SDP [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: Done building SDP. Settling with this capability: 0x180004 (ulaw|h263|h263p) [Dec 23 02:31:58] VERBOSE[9792] chan_sip.c: <--- Reliably Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d783c9aca7-DL Record-Route: From: ;tag=DLc8a7686686;epid=0x204151d0 To: ;tag=as052409df Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 383 v=0 o=root 279705246 279705246 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 13314 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 12074 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv <------------> [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #305 [Dec 23 02:31:58] DEBUG[9792] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:31:58] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:31:58] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: SIP TIMER: Rescheduling retransmission #305 (1) SIP/2.0 - 1 [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #305)) [Dec 23 02:31:58] VERBOSE[9718] chan_sip.c: Retransmitting #1 (no NAT) to 111.111.173.219:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d783c9aca7-DL Record-Route: From: ;tag=DLc8a7686686;epid=0x204151d0 To: ;tag=as052409df Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 383 v=0 o=root 279705246 279705246 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 13314 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 12074 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv --- [Dec 23 02:31:58] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:31:58] DEBUG[9792] channel.c: Didn't receive a media frame from SIP/sip.fr.acme.net-0000003e within 500 ms of answering. Continuing anyway [Dec 23 02:31:58] VERBOSE[9792] res_agi.c: -- AGI Script Executing Application: (MusicOnHold) Options: () [Dec 23 02:31:58] VERBOSE[9792] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/sip.fr.acme.net-0000003e [Dec 23 02:31:58] DEBUG[9792] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Dec 23 02:31:59] DEBUG[9792] res_musiconhold.c: SIP/sip.fr.acme.net-0000003e Opened file 0 '/var/lib/asterisk/moh/macroform-cold_day' [Dec 23 02:31:59] DEBUG[9792] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [Dec 23 02:31:59] DEBUG[9792] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [Dec 23 02:31:59] DEBUG[9792] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x71c25e8' [Dec 23 02:31:59] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1337@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-9d414b0871-DL To: ;tag=as052409df From: "200" ;tag=DLc8a7686686;epid=0x204151d0 Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 0 [ 39]: ACK sip:1337@111.111.22.105:5060 SIP/2.0 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.2 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-9d414b0871-DL [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 4 [ 50]: To: ;tag=as052409df [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DLc8a7686686;epid=0x204151d0 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:31:59] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DLc8a7686686 --To-tag as052409df [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #305 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Stopping retransmission on 'DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local.' of Response 1: Match Found [Dec 23 02:31:59] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1337@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-93089668e1-DL To: ;tag=as052409df From: "200" ;tag=DLc8a7686686;epid=0x204151d0 Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 0 [ 39]: ACK sip:1337@111.111.22.105:5060 SIP/2.0 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKd672.5b1bfd56.2 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-93089668e1-DL [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 4 [ 50]: To: ;tag=as052409df [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DLc8a7686686;epid=0x204151d0 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:31:59] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DLc8a7686686 --To-tag as052409df [Dec 23 02:31:59] DEBUG[9718] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 23 02:31:59] DEBUG[9792] channel.c: Generator got voice, switching to phase locked mode [Dec 23 02:31:59] DEBUG[9792] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:02] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> BYE sip:1337@111.111.22.105:5060 SIP/2.0 To: ;tag=as052409df From: "200" ;tag=DLc8a7686686;epid=0x204151d0 CSeq: 2 BYE Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKa672.c8b92ca2.0 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-dc04e40f57-DL Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 0 [ 39]: BYE sip:1337@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 1 [ 50]: To: ;tag=as052409df [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 2 [ 75]: From: "200" ;tag=DLc8a7686686;epid=0x204151d0 [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 3 [ 11]: CSeq: 2 BYE [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 4 [ 62]: Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 5 [ 16]: Max-Forwards: 69 [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 6 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKa672.c8b92ca2.0 [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 7 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-dc04e40f57-DL [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:02] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DLc8a7686686 --To-tag as052409df [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Initializing initreq for method BYE - callid DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:02] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:02] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:02] VERBOSE[9718] chan_sip.c: Sending to 111.111.173.219:5060 (no NAT) [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Setting SIP_ALREADYGONE on dialog DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:02] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x71c25e8' [Dec 23 02:32:02] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x718ba28' [Dec 23 02:32:02] VERBOSE[9718] chan_sip.c: Scheduling destruction of SIP dialog 'DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local.' in 32000 ms (Method: BYE) [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Received bye, issuing owner hangup [Dec 23 02:32:02] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKa672.c8b92ca2.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-dc04e40f57-DL From: "200" ;tag=DLc8a7686686;epid=0x204151d0 To: ;tag=as052409df Call-ID: DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. CSeq: 2 BYE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [Dec 23 02:32:02] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:02] VERBOSE[9792] res_musiconhold.c: -- Stopped music on hold on SIP/sip.fr.acme.net-0000003e [Dec 23 02:32:02] DEBUG[9792] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:02] VERBOSE[9792] res_agi.c: -- AGI Script agi://127.0.0.1 completed, returning 0 [Dec 23 02:32:02] DEBUG[9792] pbx.c: Extension 1337, priority 1 returned normally even though call was hung up [Dec 23 02:32:02] DEBUG[9792] channel.c: Soft-Hanging up channel 'SIP/sip.fr.acme.net-0000003e' [Dec 23 02:32:02] DEBUG[9792] pbx.c: Launching 'NoOp' [Dec 23 02:32:02] VERBOSE[9792] pbx.c: -- Executing [h@default:1] NoOp("SIP/sip.fr.acme.net-0000003e", ""Hanging up now..."") in new stack [Dec 23 02:32:02] DEBUG[9792] pbx.c: Launching 'Hangup' [Dec 23 02:32:02] VERBOSE[9792] pbx.c: -- Executing [h@default:2] Hangup("SIP/sip.fr.acme.net-0000003e", "") in new stack [Dec 23 02:32:02] DEBUG[9792] pbx.c: Spawn extension (default,h,2) exited non-zero on 'SIP/sip.fr.acme.net-0000003e' [Dec 23 02:32:02] VERBOSE[9792] pbx.c: == Spawn extension (default, h, 2) exited non-zero on 'SIP/sip.fr.acme.net-0000003e' [Dec 23 02:32:02] DEBUG[9792] channel.c: Hanging up channel 'SIP/sip.fr.acme.net-0000003e' [Dec 23 02:32:02] DEBUG[9792] chan_sip.c: Hangup call SIP/sip.fr.acme.net-0000003e, SIP callid DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:02] DEBUG[9792] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x71c25e8' [Dec 23 02:32:02] DEBUG[9792] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x718ba28' [Dec 23 02:32:02] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:02] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:02] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 0 (Unknown) [Dec 23 02:32:02] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '0' [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> INVITE sip:1337@vcc.fr.acme.net SIP/2.0 Record-Route: To: Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. From: ;tag=DL5c59f38fec;epid=0x204151d0 CSeq: 1 INVITE Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKddbe.9bfd15f1.0 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-ea69251ae6-DL Contact: "200" Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Supported: replaces,norefersub User-Agent: Dylogic Mirial 7.0.36 Content-Type: application/sdp Content-Length: 489 P-hint: outbound P-Conference-ID: 1293067919 v=0 o=200 458873939 0 IN IP4 192.168.0.195 s=- i=Dylogic Mirial 7.0.36 c=IN IP4 111.111.173.219 b=AS:768 t=0 0 m=audio 49800 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=x-mpdp:192.168.0.195:42000 m=video 47414 RTP/AVP 96 34 a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 a=rtpmap:34 H263/90000 a=fmtp:34 CIF=1; QCIF=1 a=x-mpdp:192.168.0.195:42002 a=nortpproxy:yes <-------------> [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 0 [ 44]: INVITE sip:1337@vcc.fr.acme.net SIP/2.0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 1 [ 40]: Record-Route: [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 2 [ 35]: To: [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 3 [ 62]: Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 4 [ 69]: From: ;tag=DL5c59f38fec;epid=0x204151d0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 6 [ 16]: Max-Forwards: 69 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 7 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKddbe.9bfd15f1.0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 8 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-ea69251ae6-DL [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 9 [ 44]: Contact: "200" [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 10 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 11 [ 30]: Supported: replaces,norefersub [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 12 [ 33]: User-Agent: Dylogic Mirial 7.0.36 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 14 [ 19]: Content-Length: 489 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 15 [ 16]: P-hint: outbound [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 16 [ 28]: P-Conference-ID: 1293067919 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 17 [ 0]: [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 0 [ 3]: v=0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 1 [ 38]: o=200 458873939 0 IN IP4 192.168.0.195 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 2 [ 3]: s=- [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 3 [ 23]: i=Dylogic Mirial 7.0.36 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 4 [ 23]: c=IN IP4 111.111.173.219 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 5 [ 8]: b=AS:768 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 6 [ 5]: t=0 0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 7 [ 29]: m=audio 49800 RTP/AVP 0 8 101 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 9 [ 20]: a=rtpmap:8 PCMA/8000 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-16 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 12 [ 28]: a=x-mpdp:192.168.0.195:42000 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 13 [ 27]: m=video 47414 RTP/AVP 96 34 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 14 [ 27]: a=rtpmap:96 H263-1998/90000 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 15 [ 58]: a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 16 [ 22]: a=rtpmap:34 H263/90000 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 17 [ 23]: a=fmtp:34 CIF=1; QCIF=1 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 18 [ 28]: a=x-mpdp:192.168.0.195:42002 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Body 19 [ 16]: a=nortpproxy:yes [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: --- (17 headers 20 lines) --- [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL5c59f38fec --To-tag [Dec 23 02:32:03] DEBUG[9718] acl.c: For destination '111.111.173.219', our source address is '111.111.22.105'. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 111.111.22.105:5060 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Allocating new SIP dialog for DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. - INVITE (No RTP) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 23 02:32:03] DEBUG[9718] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces,norefersub" [Dec 23 02:32:03] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -replaces- [Dec 23 02:32:03] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: replaces [Dec 23 02:32:03] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -norefersub- [Dec 23 02:32:03] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: norefersub [Dec 23 02:32:03] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:03] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Sending to 111.111.173.219:5060 (no NAT) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Initializing initreq for method INVITE - callid DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Using INVITE request as basis request - DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: No matching peer for '200' from '111.111.173.219:5060' [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x718fb98' [Dec 23 02:32:03] DEBUG[9718] res_rtp_asterisk.c: Allocated port 19174 for RTP instance '0x718fb98' [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: RTP instance '0x718fb98' is setup and ready to go [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7179d28' [Dec 23 02:32:03] DEBUG[9718] res_rtp_asterisk.c: Allocated port 10454 for RTP instance '0x7179d28' [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: RTP instance '0x7179d28' is setup and ready to go [Dec 23 02:32:03] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7179d28' [Dec 23 02:32:03] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x718fb98' [Dec 23 02:32:03] VERBOSE[9718] netsock2.c: == Using SIP RTP CoS mark 5 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Setting NAT on RTP to Off [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Setting NAT on VRTP to Off [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing session-level SDP o=200 458873939 0 IN IP4 192.168.0.195... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing session-level SDP i=Dylogic Mirial 7.0.36... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:03] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing session-level SDP c=IN IP4 111.111.173.219... OK. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing session-level SDP b=AS:768... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found RTP audio format 0 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Setting payload 0 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found RTP audio format 8 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Setting payload 8 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found RTP audio format 101 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Setting payload 101 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found audio description format PCMU for ID 0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found audio description format PCMA for ID 8 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found audio description format telephone-event for ID 101 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=x-mpdp:192.168.0.195:42000... UNSUPPORTED. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found RTP video format 96 [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found RTP video format 34 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Setting payload 34 based on m type on 0x7ffa2cae4820 [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found video description format H263-1998 for ID 96 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:96 H263-1998/90000... OK. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1... UNSUPPORTED. [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Found video description format H263 for ID 34 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:34 H263/90000... OK. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:34 CIF=1; QCIF=1... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=x-mpdp:192.168.0.195:42002... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=nortpproxy:yes... UNSUPPORTED. [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Incorporating payload 0 on 0x7ffa2cae58a0 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Incorporating payload 8 on 0x7ffa2cae58a0 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Incorporating payload 101 on 0x7ffa2cae58a0 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Incorporating payload 34 on 0x7ffa2cae4820 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Incorporating payload 96 on 0x7ffa2cae4820 [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Capabilities: us - 0x180004 (ulaw|h263|h263p), peer - audio=0xc (ulaw|alaw)/video=0x180000 (h263|h263p)/text=0x0 (nothing), combined - 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Dec 23 02:32:03] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x718fb98' [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Peer audio RTP is at port 111.111.173.219:49800 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Copying payload 0 from 0x7ffa2cae58a0 to 0x718fd60 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Copying payload 8 from 0x7ffa2cae58a0 to 0x718fd60 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Copying payload 101 from 0x7ffa2cae58a0 to 0x718fd60 [Dec 23 02:32:03] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7179d28' [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Peer video RTP is at port 111.111.173.219:47414 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Copying payload 34 from 0x7ffa2cae4820 to 0x7179ef0 [Dec 23 02:32:03] DEBUG[9718] rtp_engine.c: Copying payload 96 from 0x7ffa2cae4820 to 0x7179ef0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: We're settling with these formats: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Checking SIP call limits for device [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Updating call counter for incoming call [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: Looking for 1337 in default (domain vcc.fr.acme.net) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: *** Our native formats are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: *** Joint capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: *** Our capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: This channel can handle video! HOLLYWOOD next! [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: build_route: Record-Route hop: [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: list_route: hop: [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: SIP/sip.fr.acme.net-0000003f: New call is still down.... Trying... [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKddbe.9bfd15f1.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-ea69251ae6-DL Record-Route: From: ;tag=DL5c59f38fec;epid=0x204151d0 To: Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:03] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:03] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:03] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:32:03] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:32:03] DEBUG[9793] pbx.c: Launching 'AGI' [Dec 23 02:32:03] VERBOSE[9793] pbx.c: -- Executing [1337@default:1] AGI("SIP/sip.fr.acme.net-0000003f", "agi://127.0.0.1") in new stack [Dec 23 02:32:03] DEBUG[9793] res_agi.c: Wow, connected! [Dec 23 02:32:03] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:03] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: SIP answering channel: SIP/sip.fr.acme.net-0000003f [Dec 23 02:32:03] DEBUG[9793] res_rtp_asterisk.c: Setting the marker bit due to a source update [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: Setting framing from config on incoming call [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: This call needs video offers! [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: ** Our capability: 0x180004 (ulaw|h263|h263p) Video flag: False Text flag: True [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Dec 23 02:32:03] VERBOSE[9793] chan_sip.c: Audio is at 5060 [Dec 23 02:32:03] VERBOSE[9793] chan_sip.c: Video is at 111.111.22.105:5060 [Dec 23 02:32:03] VERBOSE[9793] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Dec 23 02:32:03] VERBOSE[9793] chan_sip.c: Adding video codec 0x80000 (h263) to SDP [Dec 23 02:32:03] VERBOSE[9793] chan_sip.c: Adding video codec 0x100000 (h263p) to SDP [Dec 23 02:32:03] VERBOSE[9793] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: -- Done with adding codecs to SDP [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: Done building SDP. Settling with this capability: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:03] VERBOSE[9793] chan_sip.c: <--- Reliably Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKddbe.9bfd15f1.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-ea69251ae6-DL Record-Route: From: ;tag=DL5c59f38fec;epid=0x204151d0 To: ;tag=as7135907a Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 383 v=0 o=root 337570153 337570153 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 19174 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 10454 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv <------------> [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #310 [Dec 23 02:32:03] DEBUG[9793] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:03] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:32:03] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1337@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKddbe.9bfd15f1.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-3458a14cb8-DL To: ;tag=as7135907a From: "200" ;tag=DL5c59f38fec;epid=0x204151d0 Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 0 [ 39]: ACK sip:1337@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKddbe.9bfd15f1.2 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-3458a14cb8-DL [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 4 [ 50]: To: ;tag=as7135907a [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DL5c59f38fec;epid=0x204151d0 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:03] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL5c59f38fec --To-tag as7135907a [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #310 [Dec 23 02:32:03] DEBUG[9718] chan_sip.c: Stopping retransmission on 'DL8f10ff1511-1079616693@j-y-MacBook-Pro.local.' of Response 1: Match Found [Dec 23 02:32:03] VERBOSE[9793] res_agi.c: -- AGI Script Executing Application: (MusicOnHold) Options: () [Dec 23 02:32:03] VERBOSE[9793] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/sip.fr.acme.net-0000003f [Dec 23 02:32:03] DEBUG[9793] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Dec 23 02:32:03] DEBUG[9793] res_musiconhold.c: SIP/sip.fr.acme.net-0000003f Opened file 0 '/var/lib/asterisk/moh/macroform-cold_day' [Dec 23 02:32:03] DEBUG[9793] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [Dec 23 02:32:03] DEBUG[9793] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [Dec 23 02:32:03] DEBUG[9793] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x718fb98' [Dec 23 02:32:03] DEBUG[9793] channel.c: Generator got voice, switching to phase locked mode [Dec 23 02:32:03] DEBUG[9793] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:06] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> BYE sip:1337@111.111.22.105:5060 SIP/2.0 To: ;tag=as7135907a From: "200" ;tag=DL5c59f38fec;epid=0x204151d0 CSeq: 2 BYE Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKadbe.3e096e4.0 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-90bd50741b-DL Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 0 [ 39]: BYE sip:1337@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 1 [ 50]: To: ;tag=as7135907a [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 2 [ 75]: From: "200" ;tag=DL5c59f38fec;epid=0x204151d0 [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 3 [ 11]: CSeq: 2 BYE [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 4 [ 62]: Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 5 [ 16]: Max-Forwards: 69 [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 6 [ 60]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKadbe.3e096e4.0 [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 7 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-90bd50741b-DL [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:06] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL5c59f38fec --To-tag as7135907a [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Initializing initreq for method BYE - callid DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:06] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:06] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:06] VERBOSE[9718] chan_sip.c: Sending to 111.111.173.219:5060 (no NAT) [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Setting SIP_ALREADYGONE on dialog DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:06] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x718fb98' [Dec 23 02:32:06] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7179d28' [Dec 23 02:32:06] VERBOSE[9718] chan_sip.c: Scheduling destruction of SIP dialog 'DL8f10ff1511-1079616693@j-y-MacBook-Pro.local.' in 32000 ms (Method: BYE) [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Received bye, issuing owner hangup [Dec 23 02:32:06] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKadbe.3e096e4.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-90bd50741b-DL From: "200" ;tag=DL5c59f38fec;epid=0x204151d0 To: ;tag=as7135907a Call-ID: DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. CSeq: 2 BYE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [Dec 23 02:32:06] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:06] VERBOSE[9793] res_musiconhold.c: -- Stopped music on hold on SIP/sip.fr.acme.net-0000003f [Dec 23 02:32:06] DEBUG[9793] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:06] VERBOSE[9793] res_agi.c: -- AGI Script agi://127.0.0.1 completed, returning 0 [Dec 23 02:32:06] DEBUG[9793] pbx.c: Extension 1337, priority 1 returned normally even though call was hung up [Dec 23 02:32:06] DEBUG[9793] channel.c: Soft-Hanging up channel 'SIP/sip.fr.acme.net-0000003f' [Dec 23 02:32:06] DEBUG[9793] pbx.c: Launching 'NoOp' [Dec 23 02:32:06] VERBOSE[9793] pbx.c: -- Executing [h@default:1] NoOp("SIP/sip.fr.acme.net-0000003f", ""Hanging up now..."") in new stack [Dec 23 02:32:06] DEBUG[9793] pbx.c: Launching 'Hangup' [Dec 23 02:32:06] VERBOSE[9793] pbx.c: -- Executing [h@default:2] Hangup("SIP/sip.fr.acme.net-0000003f", "") in new stack [Dec 23 02:32:06] DEBUG[9793] pbx.c: Spawn extension (default,h,2) exited non-zero on 'SIP/sip.fr.acme.net-0000003f' [Dec 23 02:32:06] VERBOSE[9793] pbx.c: == Spawn extension (default, h, 2) exited non-zero on 'SIP/sip.fr.acme.net-0000003f' [Dec 23 02:32:06] DEBUG[9793] channel.c: Hanging up channel 'SIP/sip.fr.acme.net-0000003f' [Dec 23 02:32:06] DEBUG[9793] chan_sip.c: Hangup call SIP/sip.fr.acme.net-0000003f, SIP callid DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:06] DEBUG[9793] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x718fb98' [Dec 23 02:32:06] DEBUG[9793] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7179d28' [Dec 23 02:32:06] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:06] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:06] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 0 (Unknown) [Dec 23 02:32:06] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '0' [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> INVITE sip:1337@vcc.fr.acme.net SIP/2.0 Record-Route: To: Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. From: ;tag=DL1a6df5e52e;epid=0x204151d0 CSeq: 1 INVITE Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKf285.dbb1af56.0 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-63f5135824-DL Contact: "200" Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Supported: replaces,norefersub User-Agent: Dylogic Mirial 7.0.36 Content-Type: application/sdp Content-Length: 489 P-hint: outbound P-Conference-ID: 1293067949 v=0 o=200 388811172 0 IN IP4 192.168.0.195 s=- i=Dylogic Mirial 7.0.36 c=IN IP4 111.111.173.219 b=AS:768 t=0 0 m=audio 35380 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=x-mpdp:192.168.0.195:42000 m=video 60080 RTP/AVP 96 34 a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 a=rtpmap:34 H263/90000 a=fmtp:34 CIF=1; QCIF=1 a=x-mpdp:192.168.0.195:42002 a=nortpproxy:yes <-------------> [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 0 [ 44]: INVITE sip:1337@vcc.fr.acme.net SIP/2.0 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 1 [ 40]: Record-Route: [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 2 [ 35]: To: [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 3 [ 62]: Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 4 [ 69]: From: ;tag=DL1a6df5e52e;epid=0x204151d0 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 6 [ 16]: Max-Forwards: 69 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 7 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKf285.dbb1af56.0 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 8 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-63f5135824-DL [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 9 [ 44]: Contact: "200" [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 10 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 11 [ 30]: Supported: replaces,norefersub [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 12 [ 33]: User-Agent: Dylogic Mirial 7.0.36 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 14 [ 19]: Content-Length: 489 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 15 [ 16]: P-hint: outbound [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 16 [ 28]: P-Conference-ID: 1293067949 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Header 17 [ 0]: [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 0 [ 3]: v=0 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 1 [ 38]: o=200 388811172 0 IN IP4 192.168.0.195 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 2 [ 3]: s=- [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 3 [ 23]: i=Dylogic Mirial 7.0.36 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 4 [ 23]: c=IN IP4 111.111.173.219 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 5 [ 8]: b=AS:768 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 6 [ 5]: t=0 0 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 7 [ 29]: m=audio 35380 RTP/AVP 0 8 101 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 9 [ 20]: a=rtpmap:8 PCMA/8000 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-16 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 12 [ 28]: a=x-mpdp:192.168.0.195:42000 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 13 [ 27]: m=video 60080 RTP/AVP 96 34 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 14 [ 27]: a=rtpmap:96 H263-1998/90000 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 15 [ 58]: a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 16 [ 22]: a=rtpmap:34 H263/90000 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 17 [ 23]: a=fmtp:34 CIF=1; QCIF=1 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 18 [ 28]: a=x-mpdp:192.168.0.195:42002 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Body 19 [ 16]: a=nortpproxy:yes [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: --- (17 headers 20 lines) --- [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL1a6df5e52e --To-tag [Dec 23 02:32:33] DEBUG[9718] acl.c: For destination '111.111.173.219', our source address is '111.111.22.105'. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 111.111.22.105:5060 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Allocating new SIP dialog for DL5975e935d5-1079616693@j-y-MacBook-Pro.local. - INVITE (No RTP) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 23 02:32:33] DEBUG[9718] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces,norefersub" [Dec 23 02:32:33] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -replaces- [Dec 23 02:32:33] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: replaces [Dec 23 02:32:33] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -norefersub- [Dec 23 02:32:33] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: norefersub [Dec 23 02:32:33] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:33] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Sending to 111.111.173.219:5060 (no NAT) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Initializing initreq for method INVITE - callid DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Using INVITE request as basis request - DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: No matching peer for '200' from '111.111.173.219:5060' [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x717bb88' [Dec 23 02:32:33] DEBUG[9718] res_rtp_asterisk.c: Allocated port 13350 for RTP instance '0x717bb88' [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: RTP instance '0x717bb88' is setup and ready to go [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x719ced8' [Dec 23 02:32:33] DEBUG[9718] res_rtp_asterisk.c: Allocated port 12424 for RTP instance '0x719ced8' [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: RTP instance '0x719ced8' is setup and ready to go [Dec 23 02:32:33] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x719ced8' [Dec 23 02:32:33] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x717bb88' [Dec 23 02:32:33] VERBOSE[9718] netsock2.c: == Using SIP RTP CoS mark 5 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Setting NAT on RTP to Off [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Setting NAT on VRTP to Off [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing session-level SDP o=200 388811172 0 IN IP4 192.168.0.195... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing session-level SDP i=Dylogic Mirial 7.0.36... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:33] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing session-level SDP c=IN IP4 111.111.173.219... OK. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing session-level SDP b=AS:768... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found RTP audio format 0 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Setting payload 0 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found RTP audio format 8 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Setting payload 8 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found RTP audio format 101 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Setting payload 101 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found audio description format PCMU for ID 0 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found audio description format PCMA for ID 8 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found audio description format telephone-event for ID 101 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=x-mpdp:192.168.0.195:42000... UNSUPPORTED. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found RTP video format 96 [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found RTP video format 34 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Setting payload 34 based on m type on 0x7ffa2cae4820 [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found video description format H263-1998 for ID 96 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:96 H263-1998/90000... OK. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1... UNSUPPORTED. [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Found video description format H263 for ID 34 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:34 H263/90000... OK. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:34 CIF=1; QCIF=1... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=x-mpdp:192.168.0.195:42002... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=nortpproxy:yes... UNSUPPORTED. [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Incorporating payload 0 on 0x7ffa2cae58a0 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Incorporating payload 8 on 0x7ffa2cae58a0 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Incorporating payload 101 on 0x7ffa2cae58a0 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Incorporating payload 34 on 0x7ffa2cae4820 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Incorporating payload 96 on 0x7ffa2cae4820 [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Capabilities: us - 0x180004 (ulaw|h263|h263p), peer - audio=0xc (ulaw|alaw)/video=0x180000 (h263|h263p)/text=0x0 (nothing), combined - 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Dec 23 02:32:33] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x717bb88' [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Peer audio RTP is at port 111.111.173.219:35380 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Copying payload 0 from 0x7ffa2cae58a0 to 0x717bd50 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Copying payload 8 from 0x7ffa2cae58a0 to 0x717bd50 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Copying payload 101 from 0x7ffa2cae58a0 to 0x717bd50 [Dec 23 02:32:33] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x719ced8' [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Peer video RTP is at port 111.111.173.219:60080 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Copying payload 34 from 0x7ffa2cae4820 to 0x719d0a0 [Dec 23 02:32:33] DEBUG[9718] rtp_engine.c: Copying payload 96 from 0x7ffa2cae4820 to 0x719d0a0 [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: We're settling with these formats: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Checking SIP call limits for device [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Updating call counter for incoming call [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: Looking for 1337 in default (domain vcc.fr.acme.net) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: *** Our native formats are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: *** Joint capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: *** Our capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: This channel can handle video! HOLLYWOOD next! [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: build_route: Record-Route hop: [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: list_route: hop: [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: SIP/sip.fr.acme.net-00000040: New call is still down.... Trying... [Dec 23 02:32:33] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKf285.dbb1af56.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-63f5135824-DL Record-Route: From: ;tag=DL1a6df5e52e;epid=0x204151d0 To: Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Dec 23 02:32:33] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:33] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:33] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:33] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:32:33] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:32:33] DEBUG[9794] pbx.c: Launching 'AGI' [Dec 23 02:32:33] VERBOSE[9794] pbx.c: -- Executing [1337@default:1] AGI("SIP/sip.fr.acme.net-00000040", "agi://127.0.0.1") in new stack [Dec 23 02:32:33] DEBUG[9794] res_agi.c: Wow, connected! [Dec 23 02:32:33] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:33] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: SIP answering channel: SIP/sip.fr.acme.net-00000040 [Dec 23 02:32:33] DEBUG[9794] res_rtp_asterisk.c: Setting the marker bit due to a source update [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: Setting framing from config on incoming call [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: This call needs video offers! [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: ** Our capability: 0x180004 (ulaw|h263|h263p) Video flag: False Text flag: True [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Dec 23 02:32:33] VERBOSE[9794] chan_sip.c: Audio is at 5060 [Dec 23 02:32:33] VERBOSE[9794] chan_sip.c: Video is at 111.111.22.105:5060 [Dec 23 02:32:33] VERBOSE[9794] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Dec 23 02:32:33] VERBOSE[9794] chan_sip.c: Adding video codec 0x80000 (h263) to SDP [Dec 23 02:32:33] VERBOSE[9794] chan_sip.c: Adding video codec 0x100000 (h263p) to SDP [Dec 23 02:32:33] VERBOSE[9794] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: -- Done with adding codecs to SDP [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: Done building SDP. Settling with this capability: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:33] VERBOSE[9794] chan_sip.c: <--- Reliably Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKf285.dbb1af56.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-63f5135824-DL Record-Route: From: ;tag=DL1a6df5e52e;epid=0x204151d0 To: ;tag=as09e75c4b Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 2137584486 2137584486 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 13350 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 12424 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv <------------> [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #315 [Dec 23 02:32:33] DEBUG[9794] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:33] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:32:33] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:32:34] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1337@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKf285.dbb1af56.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-4c8ceea6a3-DL To: ;tag=as09e75c4b From: "200" ;tag=DL1a6df5e52e;epid=0x204151d0 Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 0 [ 39]: ACK sip:1337@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKf285.dbb1af56.2 [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-4c8ceea6a3-DL [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 4 [ 50]: To: ;tag=as09e75c4b [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DL1a6df5e52e;epid=0x204151d0 [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:34] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL1a6df5e52e --To-tag as09e75c4b [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #315 [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Stopping retransmission on 'DL5975e935d5-1079616693@j-y-MacBook-Pro.local.' of Response 1: Match Found [Dec 23 02:32:34] VERBOSE[9794] res_agi.c: -- AGI Script Executing Application: (MusicOnHold) Options: () [Dec 23 02:32:34] VERBOSE[9794] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/sip.fr.acme.net-00000040 [Dec 23 02:32:34] DEBUG[9794] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Dec 23 02:32:34] DEBUG[9794] res_musiconhold.c: SIP/sip.fr.acme.net-00000040 Opened file 0 '/var/lib/asterisk/moh/macroform-cold_day' [Dec 23 02:32:34] DEBUG[9794] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [Dec 23 02:32:34] DEBUG[9794] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [Dec 23 02:32:34] DEBUG[9794] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x717bb88' [Dec 23 02:32:34] DEBUG[9794] channel.c: Generator got voice, switching to phase locked mode [Dec 23 02:32:34] DEBUG[9794] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Auto destroying SIP dialog 'DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local.' [Dec 23 02:32:34] DEBUG[9718] chan_sip.c: Destroying SIP dialog DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:34] VERBOSE[9718] chan_sip.c: Really destroying SIP dialog 'DL2de0e3b34d-1079616693@j-y-MacBook-Pro.local.' Method: BYE [Dec 23 02:32:34] DEBUG[9718] rtp_engine.c: Destroyed RTP instance '0x71c25e8' [Dec 23 02:32:34] DEBUG[9718] rtp_engine.c: Destroyed RTP instance '0x718ba28' [Dec 23 02:32:36] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> BYE sip:1337@111.111.22.105:5060 SIP/2.0 To: ;tag=as09e75c4b From: "200" ;tag=DL1a6df5e52e;epid=0x204151d0 CSeq: 2 BYE Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKc285.fc77e9d6.0 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-68c2698601-DL Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 0 [ 39]: BYE sip:1337@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 1 [ 50]: To: ;tag=as09e75c4b [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 2 [ 75]: From: "200" ;tag=DL1a6df5e52e;epid=0x204151d0 [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 3 [ 11]: CSeq: 2 BYE [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 4 [ 62]: Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 5 [ 16]: Max-Forwards: 69 [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 6 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKc285.fc77e9d6.0 [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 7 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-68c2698601-DL [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:36] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL1a6df5e52e --To-tag as09e75c4b [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Initializing initreq for method BYE - callid DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:36] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:36] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:36] VERBOSE[9718] chan_sip.c: Sending to 111.111.173.219:5060 (no NAT) [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Setting SIP_ALREADYGONE on dialog DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:36] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x717bb88' [Dec 23 02:32:36] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x719ced8' [Dec 23 02:32:36] VERBOSE[9718] chan_sip.c: Scheduling destruction of SIP dialog 'DL5975e935d5-1079616693@j-y-MacBook-Pro.local.' in 32000 ms (Method: BYE) [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Received bye, issuing owner hangup [Dec 23 02:32:36] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bKc285.fc77e9d6.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-68c2698601-DL From: "200" ;tag=DL1a6df5e52e;epid=0x204151d0 To: ;tag=as09e75c4b Call-ID: DL5975e935d5-1079616693@j-y-MacBook-Pro.local. CSeq: 2 BYE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [Dec 23 02:32:36] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:36] VERBOSE[9794] res_musiconhold.c: -- Stopped music on hold on SIP/sip.fr.acme.net-00000040 [Dec 23 02:32:36] DEBUG[9794] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:36] VERBOSE[9794] res_agi.c: -- AGI Script agi://127.0.0.1 completed, returning 0 [Dec 23 02:32:36] DEBUG[9794] pbx.c: Extension 1337, priority 1 returned normally even though call was hung up [Dec 23 02:32:36] DEBUG[9794] channel.c: Soft-Hanging up channel 'SIP/sip.fr.acme.net-00000040' [Dec 23 02:32:36] DEBUG[9794] pbx.c: Launching 'NoOp' [Dec 23 02:32:36] VERBOSE[9794] pbx.c: -- Executing [h@default:1] NoOp("SIP/sip.fr.acme.net-00000040", ""Hanging up now..."") in new stack [Dec 23 02:32:36] DEBUG[9794] pbx.c: Launching 'Hangup' [Dec 23 02:32:36] VERBOSE[9794] pbx.c: -- Executing [h@default:2] Hangup("SIP/sip.fr.acme.net-00000040", "") in new stack [Dec 23 02:32:36] DEBUG[9794] pbx.c: Spawn extension (default,h,2) exited non-zero on 'SIP/sip.fr.acme.net-00000040' [Dec 23 02:32:36] VERBOSE[9794] pbx.c: == Spawn extension (default, h, 2) exited non-zero on 'SIP/sip.fr.acme.net-00000040' [Dec 23 02:32:36] DEBUG[9794] channel.c: Hanging up channel 'SIP/sip.fr.acme.net-00000040' [Dec 23 02:32:36] DEBUG[9794] chan_sip.c: Hangup call SIP/sip.fr.acme.net-00000040, SIP callid DL5975e935d5-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:36] DEBUG[9794] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x717bb88' [Dec 23 02:32:36] DEBUG[9794] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x719ced8' [Dec 23 02:32:36] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:36] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:36] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 0 (Unknown) [Dec 23 02:32:36] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '0' [Dec 23 02:32:38] DEBUG[9718] chan_sip.c: Auto destroying SIP dialog 'DL8f10ff1511-1079616693@j-y-MacBook-Pro.local.' [Dec 23 02:32:38] DEBUG[9718] chan_sip.c: Destroying SIP dialog DL8f10ff1511-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:38] VERBOSE[9718] chan_sip.c: Really destroying SIP dialog 'DL8f10ff1511-1079616693@j-y-MacBook-Pro.local.' Method: BYE [Dec 23 02:32:38] DEBUG[9718] rtp_engine.c: Destroyed RTP instance '0x718fb98' [Dec 23 02:32:38] DEBUG[9718] rtp_engine.c: Destroyed RTP instance '0x7179d28' [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> INVITE sip:1233123@vcc.fr.acme.net SIP/2.0 Record-Route: To: Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. From: ;tag=DL96aa7382af;epid=0x204151d0 CSeq: 1 INVITE Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Contact: "200" Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Supported: replaces,norefersub User-Agent: Dylogic Mirial 7.0.36 Content-Type: application/sdp Content-Length: 489 P-hint: outbound v=0 o=200 439479018 0 IN IP4 192.168.0.195 s=- i=Dylogic Mirial 7.0.36 c=IN IP4 111.111.173.219 b=AS:768 t=0 0 m=audio 43292 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=x-mpdp:192.168.0.195:42002 m=video 40186 RTP/AVP 96 34 a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 a=rtpmap:34 H263/90000 a=fmtp:34 CIF=1; QCIF=1 a=x-mpdp:192.168.0.195:42004 a=nortpproxy:yes <-------------> [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 0 [ 47]: INVITE sip:1233123@vcc.fr.acme.net SIP/2.0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 1 [ 40]: Record-Route: [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 2 [ 38]: To: [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 3 [ 62]: Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 4 [ 69]: From: ;tag=DL96aa7382af;epid=0x204151d0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 6 [ 16]: Max-Forwards: 69 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 7 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 8 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 9 [ 44]: Contact: "200" [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 10 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 11 [ 30]: Supported: replaces,norefersub [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 12 [ 33]: User-Agent: Dylogic Mirial 7.0.36 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 14 [ 19]: Content-Length: 489 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 15 [ 16]: P-hint: outbound [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 16 [ 0]: [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 0 [ 3]: v=0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 1 [ 38]: o=200 439479018 0 IN IP4 192.168.0.195 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 2 [ 3]: s=- [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 3 [ 23]: i=Dylogic Mirial 7.0.36 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 4 [ 23]: c=IN IP4 111.111.173.219 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 5 [ 8]: b=AS:768 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 6 [ 5]: t=0 0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 7 [ 29]: m=audio 43292 RTP/AVP 0 8 101 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 9 [ 20]: a=rtpmap:8 PCMA/8000 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-16 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 12 [ 28]: a=x-mpdp:192.168.0.195:42002 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 13 [ 27]: m=video 40186 RTP/AVP 96 34 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 14 [ 27]: a=rtpmap:96 H263-1998/90000 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 15 [ 58]: a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 16 [ 22]: a=rtpmap:34 H263/90000 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 17 [ 23]: a=fmtp:34 CIF=1; QCIF=1 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 18 [ 28]: a=x-mpdp:192.168.0.195:42004 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Body 19 [ 16]: a=nortpproxy:yes [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: --- (16 headers 20 lines) --- [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag [Dec 23 02:32:44] DEBUG[9718] acl.c: For destination '111.111.173.219', our source address is '111.111.22.105'. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 111.111.22.105:5060 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Allocating new SIP dialog for DL81d3b36811-1079616693@j-y-MacBook-Pro.local. - INVITE (No RTP) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 23 02:32:44] DEBUG[9718] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces,norefersub" [Dec 23 02:32:44] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -replaces- [Dec 23 02:32:44] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: replaces [Dec 23 02:32:44] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -norefersub- [Dec 23 02:32:44] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: norefersub [Dec 23 02:32:44] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:44] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Sending to 111.111.173.219:5060 (no NAT) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Initializing initreq for method INVITE - callid DL81d3b36811-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Using INVITE request as basis request - DL81d3b36811-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: No matching peer for '200' from '111.111.173.219:5060' [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7184918' [Dec 23 02:32:44] DEBUG[9718] res_rtp_asterisk.c: Allocated port 17852 for RTP instance '0x7184918' [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: RTP instance '0x7184918' is setup and ready to go [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7188ce8' [Dec 23 02:32:44] DEBUG[9718] res_rtp_asterisk.c: Allocated port 11966 for RTP instance '0x7188ce8' [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: RTP instance '0x7188ce8' is setup and ready to go [Dec 23 02:32:44] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7188ce8' [Dec 23 02:32:44] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7184918' [Dec 23 02:32:44] VERBOSE[9718] netsock2.c: == Using SIP RTP CoS mark 5 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Setting NAT on RTP to Off [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Setting NAT on VRTP to Off [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing session-level SDP o=200 439479018 0 IN IP4 192.168.0.195... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing session-level SDP i=Dylogic Mirial 7.0.36... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] netsock2.c: Splitting '111.111.173.219' gives... [Dec 23 02:32:44] DEBUG[9718] netsock2.c: ...host '111.111.173.219' and port '(null)'. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing session-level SDP c=IN IP4 111.111.173.219... OK. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing session-level SDP b=AS:768... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found RTP audio format 0 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Setting payload 0 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found RTP audio format 8 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Setting payload 8 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found RTP audio format 101 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Setting payload 101 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found audio description format PCMU for ID 0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found audio description format PCMA for ID 8 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found audio description format telephone-event for ID 101 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=x-mpdp:192.168.0.195:42002... UNSUPPORTED. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found RTP video format 96 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found RTP video format 34 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Setting payload 34 based on m type on 0x7ffa2cae4820 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found video description format H263-1998 for ID 96 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:96 H263-1998/90000... OK. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:96 CIF=1; QCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1... UNSUPPORTED. [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Found video description format H263 for ID 34 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:34 H263/90000... OK. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:34 CIF=1; QCIF=1... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=x-mpdp:192.168.0.195:42004... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=nortpproxy:yes... UNSUPPORTED. [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Incorporating payload 0 on 0x7ffa2cae58a0 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Incorporating payload 8 on 0x7ffa2cae58a0 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Incorporating payload 101 on 0x7ffa2cae58a0 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Incorporating payload 34 on 0x7ffa2cae4820 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Incorporating payload 96 on 0x7ffa2cae4820 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Capabilities: us - 0x180004 (ulaw|h263|h263p), peer - audio=0xc (ulaw|alaw)/video=0x180000 (h263|h263p)/text=0x0 (nothing), combined - 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Dec 23 02:32:44] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7184918' [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Peer audio RTP is at port 111.111.173.219:43292 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Copying payload 0 from 0x7ffa2cae58a0 to 0x7184ae0 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Copying payload 8 from 0x7ffa2cae58a0 to 0x7184ae0 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Copying payload 101 from 0x7ffa2cae58a0 to 0x7184ae0 [Dec 23 02:32:44] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7188ce8' [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Peer video RTP is at port 111.111.173.219:40186 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Copying payload 34 from 0x7ffa2cae4820 to 0x7188eb0 [Dec 23 02:32:44] DEBUG[9718] rtp_engine.c: Copying payload 96 from 0x7ffa2cae4820 to 0x7188eb0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: We're settling with these formats: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Checking SIP call limits for device [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Updating call counter for incoming call [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Looking for 1233123 in default (domain vcc.fr.acme.net) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: *** Our native formats are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: *** Joint capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: *** Our capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: This channel can handle video! HOLLYWOOD next! [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: build_route: Record-Route hop: [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: list_route: hop: [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: SIP/sip.fr.acme.net-00000041: New call is still down.... Trying... [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Record-Route: From: ;tag=DL96aa7382af;epid=0x204151d0 To: Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:44] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:44] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:44] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:32:44] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:32:44] DEBUG[9795] pbx.c: Launching 'AGI' [Dec 23 02:32:44] VERBOSE[9795] pbx.c: -- Executing [1233123@default:1] AGI("SIP/sip.fr.acme.net-00000041", "agi://127.0.0.1") in new stack [Dec 23 02:32:44] DEBUG[9795] res_agi.c: Wow, connected! [Dec 23 02:32:44] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.fr.acme.net [Dec 23 02:32:44] DEBUG[9708] chan_sip.c: Checking device state for peer sip.fr.acme.net [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: SIP answering channel: SIP/sip.fr.acme.net-00000041 [Dec 23 02:32:44] DEBUG[9795] res_rtp_asterisk.c: Setting the marker bit due to a source update [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: Setting framing from config on incoming call [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: This call needs video offers! [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: ** Our capability: 0x180004 (ulaw|h263|h263p) Video flag: False Text flag: True [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Dec 23 02:32:44] VERBOSE[9795] chan_sip.c: Audio is at 5060 [Dec 23 02:32:44] VERBOSE[9795] chan_sip.c: Video is at 111.111.22.105:5060 [Dec 23 02:32:44] VERBOSE[9795] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Dec 23 02:32:44] VERBOSE[9795] chan_sip.c: Adding video codec 0x80000 (h263) to SDP [Dec 23 02:32:44] VERBOSE[9795] chan_sip.c: Adding video codec 0x100000 (h263p) to SDP [Dec 23 02:32:44] VERBOSE[9795] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: -- Done with adding codecs to SDP [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: Done building SDP. Settling with this capability: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:44] VERBOSE[9795] chan_sip.c: <--- Reliably Transmitting (no NAT) to 111.111.173.219:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Record-Route: From: ;tag=DL96aa7382af;epid=0x204151d0 To: ;tag=as20187d5b Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 1519123334 1519123334 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 17852 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 11966 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv <------------> [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #320 [Dec 23 02:32:44] DEBUG[9795] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:44] DEBUG[9708] devicestate.c: Changing state for SIP/sip.fr.acme.net - state 2 (In use) [Dec 23 02:32:44] DEBUG[9708] devicestate.c: device 'SIP/sip.fr.acme.net' state '2' [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: SIP TIMER: Rescheduling retransmission #320 (1) SIP/2.0 - 1 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #320)) [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: Retransmitting #1 (no NAT) to 111.111.173.219:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Record-Route: From: ;tag=DL96aa7382af;epid=0x204151d0 To: ;tag=as20187d5b Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 1519123334 1519123334 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 17852 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 11966 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv --- [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:44] DEBUG[9795] channel.c: Didn't receive a media frame from SIP/sip.fr.acme.net-00000041 within 500 ms of answering. Continuing anyway [Dec 23 02:32:44] DEBUG[9795] channel.c: Soft-Hanging up channel 'SIP/sip.fr.acme.net-00000041' [Dec 23 02:32:44] DEBUG[9795] res_agi.c: SIP/sip.fr.acme.net-00000041 hungup [Dec 23 02:32:44] VERBOSE[9795] res_agi.c: -- AGI Script Executing Application: (MusicOnHold) Options: () [Dec 23 02:32:44] VERBOSE[9795] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/sip.fr.acme.net-00000041 [Dec 23 02:32:44] DEBUG[9795] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Dec 23 02:32:44] VERBOSE[9795] res_musiconhold.c: -- Stopped music on hold on SIP/sip.fr.acme.net-00000041 [Dec 23 02:32:44] DEBUG[9795] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1233123@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-9e1f50c309-DL To: ;tag=as20187d5b From: "200" ;tag=DL96aa7382af;epid=0x204151d0 Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 0 [ 42]: ACK sip:1233123@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-9e1f50c309-DL [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 4 [ 53]: To: ;tag=as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DL96aa7382af;epid=0x204151d0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1233123@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-ec027227cf-DL To: ;tag=as20187d5b From: "200" ;tag=DL96aa7382af;epid=0x204151d0 Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 0 [ 42]: ACK sip:1233123@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-ec027227cf-DL [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 4 [ 53]: To: ;tag=as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DL96aa7382af;epid=0x204151d0 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:44] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:44] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: SIP TIMER: Rescheduling retransmission #320 (2) SIP/2.0 - 1 [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: ** SIP timers: Rescheduling retransmission 3 to 2000 ms (t1 500 ms (Retrans id #320)) [Dec 23 02:32:45] VERBOSE[9718] chan_sip.c: Retransmitting #2 (no NAT) to 111.111.173.219:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Record-Route: From: ;tag=DL96aa7382af;epid=0x204151d0 To: ;tag=as20187d5b Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 1519123334 1519123334 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 17852 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 11966 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv --- [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:45] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1233123@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d139e6a17b-DL To: ;tag=as20187d5b From: "200" ;tag=DL96aa7382af;epid=0x204151d0 Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 0 [ 42]: ACK sip:1233123@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d139e6a17b-DL [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 4 [ 53]: To: ;tag=as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DL96aa7382af;epid=0x204151d0 [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:45] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:45] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: SIP TIMER: Rescheduling retransmission #320 (3) SIP/2.0 - 1 [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: ** SIP timers: Rescheduling retransmission 4 to 4000 ms (t1 500 ms (Retrans id #320)) [Dec 23 02:32:47] VERBOSE[9718] chan_sip.c: Retransmitting #3 (no NAT) to 111.111.173.219:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Record-Route: From: ;tag=DL96aa7382af;epid=0x204151d0 To: ;tag=as20187d5b Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 1519123334 1519123334 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 17852 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 11966 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv --- [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:47] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.173.219:5060 ---> ACK sip:1233123@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d5af0051e5-DL To: ;tag=as20187d5b From: "200" ;tag=DL96aa7382af;epid=0x204151d0 Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. Max-Forwards: 69 Contact: "200" Content-Length: 0 <-------------> [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 0 [ 42]: ACK sip:1233123@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.2 [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 3 [ 99]: Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-d5af0051e5-DL [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 4 [ 53]: To: ;tag=as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 5 [ 75]: From: "200" ;tag=DL96aa7382af;epid=0x204151d0 [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 8 [ 44]: Contact: "200" [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:47] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:47] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. (Checking From) --From tag DL96aa7382af --To-tag as20187d5b [Dec 23 02:32:51] DEBUG[9718] chan_sip.c: SIP TIMER: Rescheduling retransmission #320 (4) SIP/2.0 - 1 [Dec 23 02:32:51] DEBUG[9718] chan_sip.c: ** SIP timers: Rescheduling retransmission 5 to 4000 ms (t1 500 ms (Retrans id #320)) [Dec 23 02:32:51] VERBOSE[9718] chan_sip.c: Retransmitting #4 (no NAT) to 111.111.173.219:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Record-Route: From: ;tag=DL96aa7382af;epid=0x204151d0 To: ;tag=as20187d5b Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 1519123334 1519123334 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 17852 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 11966 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv --- [Dec 23 02:32:51] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.101.183:5060 ---> INVITE sip:200@vcc.fr.acme.net SIP/2.0 Record-Route: To: Call-ID: DL798e8e2553-1079858431@Acer-PC From: "user" ;tag=DL928206e1a2;epid=01C92160 CSeq: 1 INVITE Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKeb81.4d2afb42.0 Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-328b39ae30-DL Contact: "acer" Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Supported: replaces,norefersub User-Agent: Dylogic Mirial 7.0.36 Content-Type: application/sdp Content-Length: 475 P-hint: outbound v=0 o=acer 902667428 0 IN IP4 192.168.0.191 s=- i=Dylogic Mirial 7.0.36 c=IN IP4 111.111.101.183 b=AS:768 t=0 0 m=audio 62864 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=x-mpdp:192.168.0.191:15336 m=video 42836 RTP/AVP 96 34 a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 a=rtpmap:34 H263/90000 a=fmtp:34 CIF=1 a=x-mpdp:192.168.0.191:15338 a=nortpproxy:yes <-------------> [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 0 [ 43]: INVITE sip:200@vcc.fr.acme.net SIP/2.0 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 1 [ 41]: Record-Route: [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 2 [ 34]: To: [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 3 [ 40]: Call-ID: DL798e8e2553-1079858431@Acer-PC [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 4 [ 74]: From: "user" ;tag=DL928206e1a2;epid=01C92160 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 6 [ 16]: Max-Forwards: 69 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 7 [ 62]: Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKeb81.4d2afb42.0 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 8 [ 98]: Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-328b39ae30-DL [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 9 [ 45]: Contact: "acer" [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 10 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 11 [ 30]: Supported: replaces,norefersub [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 12 [ 33]: User-Agent: Dylogic Mirial 7.0.36 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 14 [ 19]: Content-Length: 475 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 15 [ 16]: P-hint: outbound [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Header 16 [ 0]: [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 0 [ 3]: v=0 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 1 [ 39]: o=acer 902667428 0 IN IP4 192.168.0.191 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 2 [ 3]: s=- [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 3 [ 23]: i=Dylogic Mirial 7.0.36 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 4 [ 24]: c=IN IP4 111.111.101.183 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 5 [ 8]: b=AS:768 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 6 [ 5]: t=0 0 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 7 [ 29]: m=audio 62864 RTP/AVP 0 8 101 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 9 [ 20]: a=rtpmap:8 PCMA/8000 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-16 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 12 [ 28]: a=x-mpdp:192.168.0.191:15336 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 13 [ 27]: m=video 42836 RTP/AVP 96 34 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 14 [ 27]: a=rtpmap:96 H263-1998/90000 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 15 [ 50]: a=fmtp:96 CIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 16 [ 22]: a=rtpmap:34 H263/90000 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 17 [ 15]: a=fmtp:34 CIF=1 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 18 [ 28]: a=x-mpdp:192.168.0.191:15338 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Body 19 [ 16]: a=nortpproxy:yes [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: --- (16 headers 20 lines) --- [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL798e8e2553-1079858431@Acer-PC (Checking From) --From tag DL928206e1a2 --To-tag [Dec 23 02:32:52] DEBUG[9718] acl.c: For destination '111.111.101.183', our source address is '111.111.22.105'. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 111.111.22.105:5060 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Allocating new SIP dialog for DL798e8e2553-1079858431@Acer-PC - INVITE (No RTP) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 23 02:32:52] DEBUG[9718] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces,norefersub" [Dec 23 02:32:52] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -replaces- [Dec 23 02:32:52] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: replaces [Dec 23 02:32:52] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -norefersub- [Dec 23 02:32:52] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: norefersub [Dec 23 02:32:52] DEBUG[9718] netsock2.c: Splitting '111.111.101.183' gives... [Dec 23 02:32:52] DEBUG[9718] netsock2.c: ...host '111.111.101.183' and port '(null)'. [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Sending to 111.111.101.183:5060 (no NAT) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Initializing initreq for method INVITE - callid DL798e8e2553-1079858431@Acer-PC [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Using INVITE request as basis request - DL798e8e2553-1079858431@Acer-PC [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: No matching peer for 'acer' from '111.111.101.183:5060' [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7192718' [Dec 23 02:32:52] DEBUG[9718] res_rtp_asterisk.c: Allocated port 14196 for RTP instance '0x7192718' [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: RTP instance '0x7192718' is setup and ready to go [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7196888' [Dec 23 02:32:52] DEBUG[9718] res_rtp_asterisk.c: Allocated port 13778 for RTP instance '0x7196888' [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: RTP instance '0x7196888' is setup and ready to go [Dec 23 02:32:52] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7196888' [Dec 23 02:32:52] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7192718' [Dec 23 02:32:52] VERBOSE[9718] netsock2.c: == Using SIP RTP CoS mark 5 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Setting NAT on RTP to Off [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Setting NAT on VRTP to Off [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing session-level SDP o=acer 902667428 0 IN IP4 192.168.0.191... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing session-level SDP i=Dylogic Mirial 7.0.36... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] netsock2.c: Splitting '111.111.101.183' gives... [Dec 23 02:32:52] DEBUG[9718] netsock2.c: ...host '111.111.101.183' and port '(null)'. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing session-level SDP c=IN IP4 111.111.101.183... OK. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing session-level SDP b=AS:768... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found RTP audio format 0 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Setting payload 0 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found RTP audio format 8 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Setting payload 8 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found RTP audio format 101 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Setting payload 101 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found audio description format PCMU for ID 0 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found audio description format PCMA for ID 8 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found audio description format telephone-event for ID 101 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=x-mpdp:192.168.0.191:15336... UNSUPPORTED. [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found RTP video format 96 [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found RTP video format 34 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Setting payload 34 based on m type on 0x7ffa2cae4820 [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found video description format H263-1998 for ID 96 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:96 H263-1998/90000... OK. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:96 CIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1... UNSUPPORTED. [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Found video description format H263 for ID 34 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:34 H263/90000... OK. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:34 CIF=1... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=x-mpdp:192.168.0.191:15338... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=nortpproxy:yes... UNSUPPORTED. [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Incorporating payload 0 on 0x7ffa2cae58a0 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Incorporating payload 8 on 0x7ffa2cae58a0 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Incorporating payload 101 on 0x7ffa2cae58a0 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Incorporating payload 34 on 0x7ffa2cae4820 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Incorporating payload 96 on 0x7ffa2cae4820 [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Capabilities: us - 0x180004 (ulaw|h263|h263p), peer - audio=0xc (ulaw|alaw)/video=0x180000 (h263|h263p)/text=0x0 (nothing), combined - 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Dec 23 02:32:52] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7192718' [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Peer audio RTP is at port 111.111.101.183:62864 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Copying payload 0 from 0x7ffa2cae58a0 to 0x71928e0 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Copying payload 8 from 0x7ffa2cae58a0 to 0x71928e0 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Copying payload 101 from 0x7ffa2cae58a0 to 0x71928e0 [Dec 23 02:32:52] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7196888' [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Peer video RTP is at port 111.111.101.183:42836 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Copying payload 34 from 0x7ffa2cae4820 to 0x7196a50 [Dec 23 02:32:52] DEBUG[9718] rtp_engine.c: Copying payload 96 from 0x7ffa2cae4820 to 0x7196a50 [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: We're settling with these formats: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Checking SIP call limits for device [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Updating call counter for incoming call [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: Looking for 200 in default (domain vcc.fr.acme.net) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: *** Our native formats are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: *** Joint capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: *** Our capabilities are 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: This channel can handle video! HOLLYWOOD next! [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: build_route: Record-Route hop: [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: list_route: hop: [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: SIP/sip.acme.net-00000042: New call is still down.... Trying... [Dec 23 02:32:52] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.101.183:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKeb81.4d2afb42.0;received=111.111.101.183 Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-328b39ae30-DL Record-Route: From: "user" ;tag=DL928206e1a2;epid=01C92160 To: Call-ID: DL798e8e2553-1079858431@Acer-PC CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Dec 23 02:32:52] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 111.111.101.183:5060 [Dec 23 02:32:52] DEBUG[9708] devicestate.c: No provider found, checking channel drivers for SIP - sip.acme.net [Dec 23 02:32:52] DEBUG[9708] chan_sip.c: Checking device state for peer sip.acme.net [Dec 23 02:32:52] DEBUG[9796] pbx.c: Launching 'AGI' [Dec 23 02:32:52] VERBOSE[9796] pbx.c: -- Executing [200@default:1] AGI("SIP/sip.acme.net-00000042", "agi://127.0.0.1") in new stack [Dec 23 02:32:52] DEBUG[9796] res_agi.c: Wow, connected! [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: SIP answering channel: SIP/sip.acme.net-00000042 [Dec 23 02:32:52] DEBUG[9796] res_rtp_asterisk.c: Setting the marker bit due to a source update [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: Setting framing from config on incoming call [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: This call needs video offers! [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: ** Our capability: 0x180004 (ulaw|h263|h263p) Video flag: False Text flag: True [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Dec 23 02:32:52] VERBOSE[9796] chan_sip.c: Audio is at 5060 [Dec 23 02:32:52] VERBOSE[9796] chan_sip.c: Video is at 111.111.22.105:5060 [Dec 23 02:32:52] VERBOSE[9796] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Dec 23 02:32:52] VERBOSE[9796] chan_sip.c: Adding video codec 0x80000 (h263) to SDP [Dec 23 02:32:52] VERBOSE[9796] chan_sip.c: Adding video codec 0x100000 (h263p) to SDP [Dec 23 02:32:52] VERBOSE[9796] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: -- Done with adding codecs to SDP [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: Done building SDP. Settling with this capability: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:52] VERBOSE[9796] chan_sip.c: <--- Reliably Transmitting (no NAT) to 111.111.101.183:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKeb81.4d2afb42.0;received=111.111.101.183 Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-328b39ae30-DL Record-Route: From: "user" ;tag=DL928206e1a2;epid=01C92160 To: ;tag=as07f3ea20 Call-ID: DL798e8e2553-1079858431@Acer-PC CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 1616518955 1616518955 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 14196 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 13778 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv <------------> [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #323 [Dec 23 02:32:52] DEBUG[9796] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.101.183:5060 [Dec 23 02:32:53] DEBUG[9796] channel.c: Soft-Hanging up channel 'SIP/sip.acme.net-00000042' [Dec 23 02:32:53] DEBUG[9796] res_agi.c: SIP/sip.acme.net-00000042 hungup [Dec 23 02:32:53] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.101.183:5060 ---> ACK sip:200@111.111.22.105:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKeb81.4d2afb42.2 Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-88dbb3d491-DL To: ;tag=as07f3ea20 From: "acer" ;tag=DL928206e1a2;epid=01C92160 Call-ID: DL798e8e2553-1079858431@Acer-PC Max-Forwards: 69 Contact: "acer" Content-Length: 0 <-------------> [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 0 [ 38]: ACK sip:200@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 2 [ 62]: Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKeb81.4d2afb42.2 [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 3 [ 98]: Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-88dbb3d491-DL [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 4 [ 49]: To: ;tag=as07f3ea20 [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 5 [ 72]: From: "acer" ;tag=DL928206e1a2;epid=01C92160 [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 6 [ 40]: Call-ID: DL798e8e2553-1079858431@Acer-PC [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 8 [ 45]: Contact: "acer" [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:53] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL798e8e2553-1079858431@Acer-PC (Checking From) --From tag DL928206e1a2 --To-tag as07f3ea20 [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #323 [Dec 23 02:32:53] DEBUG[9718] chan_sip.c: Stopping retransmission on 'DL798e8e2553-1079858431@Acer-PC' of Response 1: Match Found [Dec 23 02:32:53] VERBOSE[9796] res_agi.c: -- AGI Script Executing Application: (MusicOnHold) Options: () [Dec 23 02:32:53] VERBOSE[9796] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/sip.acme.net-00000042 [Dec 23 02:32:53] DEBUG[9796] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Dec 23 02:32:53] VERBOSE[9796] res_musiconhold.c: -- Stopped music on hold on SIP/sip.acme.net-00000042 [Dec 23 02:32:53] DEBUG[9796] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Dec 23 02:32:53] VERBOSE[9796] res_agi.c: -- AGI Script agi://127.0.0.1 completed, returning -1 [Dec 23 02:32:53] DEBUG[9796] pbx.c: Extension 200, priority 1 returned normally even though call was hung up [Dec 23 02:32:53] DEBUG[9796] channel.c: Soft-Hanging up channel 'SIP/sip.acme.net-00000042' [Dec 23 02:32:53] DEBUG[9796] pbx.c: Launching 'NoOp' [Dec 23 02:32:53] VERBOSE[9796] pbx.c: -- Executing [h@default:1] NoOp("SIP/sip.acme.net-00000042", ""Hanging up now..."") in new stack [Dec 23 02:32:53] DEBUG[9796] pbx.c: Launching 'Hangup' [Dec 23 02:32:53] VERBOSE[9796] pbx.c: -- Executing [h@default:2] Hangup("SIP/sip.acme.net-00000042", "") in new stack [Dec 23 02:32:53] DEBUG[9796] pbx.c: Spawn extension (default,h,2) exited non-zero on 'SIP/sip.acme.net-00000042' [Dec 23 02:32:53] VERBOSE[9796] pbx.c: == Spawn extension (default, h, 2) exited non-zero on 'SIP/sip.acme.net-00000042' [Dec 23 02:32:55] DEBUG[9718] chan_sip.c: SIP TIMER: Rescheduling retransmission #320 (5) SIP/2.0 - 1 [Dec 23 02:32:55] DEBUG[9718] chan_sip.c: ** SIP timers: Rescheduling retransmission 6 to 4000 ms (t1 500 ms (Retrans id #320)) [Dec 23 02:32:55] VERBOSE[9718] chan_sip.c: Retransmitting #5 (no NAT) to 111.111.173.219:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.173.219;branch=z9hG4bK66df.d5cd28f5.0;received=111.111.173.219 Via: SIP/2.0/UDP 192.168.0.195:5060;rport=52318;received=111.111.146.25;branch=z9hG4bK-2af52d668d-DL Record-Route: From: ;tag=DL96aa7382af;epid=0x204151d0 To: ;tag=as20187d5b Call-ID: DL81d3b36811-1079616693@j-y-MacBook-Pro.local. CSeq: 1 INVITE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 385 v=0 o=root 1519123334 1519123334 IN IP4 111.111.22.105 s=Asterisk PBX SVN-branch-1.8-r299004M c=IN IP4 111.111.22.105 b=CT:384 t=0 0 m=audio 17852 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv m=video 11966 RTP/AVP 34 96 a=rtpmap:34 H263/90000 a=rtpmap:96 h263-1998/90000 a=sendrecv --- [Dec 23 02:32:55] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.173.219:5060 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.101.183:5060 ---> BYE sip:200@111.111.22.105:5060 SIP/2.0 To: ;tag=as07f3ea20 From: "acer" ;tag=DL928206e1a2;epid=01C92160 CSeq: 2 BYE Call-ID: DL798e8e2553-1079858431@Acer-PC Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKbb81.b71531b5.0 Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-6ac13d95e8-DL Contact: "acer" Content-Length: 0 <-------------> [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 0 [ 38]: BYE sip:200@111.111.22.105:5060 SIP/2.0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 1 [ 49]: To: ;tag=as07f3ea20 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 2 [ 72]: From: "acer" ;tag=DL928206e1a2;epid=01C92160 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 3 [ 11]: CSeq: 2 BYE [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 4 [ 40]: Call-ID: DL798e8e2553-1079858431@Acer-PC [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 5 [ 16]: Max-Forwards: 69 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 6 [ 62]: Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKbb81.b71531b5.0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 7 [ 98]: Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-6ac13d95e8-DL [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 8 [ 45]: Contact: "acer" [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: --- (10 headers 0 lines) --- [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: = Looking for Call ID: DL798e8e2553-1079858431@Acer-PC (Checking From) --From tag DL928206e1a2 --To-tag as07f3ea20 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Initializing initreq for method BYE - callid DL798e8e2553-1079858431@Acer-PC [Dec 23 02:32:57] DEBUG[9718] netsock2.c: Splitting '111.111.101.183' gives... [Dec 23 02:32:57] DEBUG[9718] netsock2.c: ...host '111.111.101.183' and port '(null)'. [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Sending to 111.111.101.183:5060 (no NAT) [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Setting SIP_ALREADYGONE on dialog DL798e8e2553-1079858431@Acer-PC [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7192718' [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7196888' [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Scheduling destruction of SIP dialog 'DL798e8e2553-1079858431@Acer-PC' in 32000 ms (Method: BYE) [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Received bye, issuing owner hangup [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: <--- Transmitting (no NAT) to 111.111.101.183:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bKbb81.b71531b5.0;received=111.111.101.183 Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-6ac13d95e8-DL From: "acer" ;tag=DL928206e1a2;epid=01C92160 To: ;tag=as07f3ea20 Call-ID: DL798e8e2553-1079858431@Acer-PC CSeq: 2 BYE Server: Asterisk PBX SVN-branch-1.8-r299004M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 111.111.101.183:5060 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: <--- SIP read from UDP:111.111.101.183:5060 ---> INVITE sip:200@vcc.fr.acme.net SIP/2.0 Record-Route: To: Call-ID: DLf9d4a9aad7-1079858431@Acer-PC From: "user" ;tag=DL0e5b887127;epid=01C92160 CSeq: 1 INVITE Max-Forwards: 69 Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bK4f8a.fbee8aa4.0 Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-546359fdd6-DL Contact: "acer" Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Supported: replaces,norefersub User-Agent: Dylogic Mirial 7.0.36 Content-Type: application/sdp Content-Length: 475 P-hint: outbound v=0 o=acer 823020436 0 IN IP4 192.168.0.191 s=- i=Dylogic Mirial 7.0.36 c=IN IP4 111.111.101.183 b=AS:768 t=0 0 m=audio 59548 RTP/AVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=x-mpdp:192.168.0.191:15348 m=video 36276 RTP/AVP 96 34 a=rtpmap:96 H263-1998/90000 a=fmtp:96 CIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 a=rtpmap:34 H263/90000 a=fmtp:34 CIF=1 a=x-mpdp:192.168.0.191:15334 a=nortpproxy:yes <-------------> [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 0 [ 43]: INVITE sip:200@vcc.fr.acme.net SIP/2.0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 1 [ 41]: Record-Route: [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 2 [ 34]: To: [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 3 [ 40]: Call-ID: DLf9d4a9aad7-1079858431@Acer-PC [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 4 [ 74]: From: "user" ;tag=DL0e5b887127;epid=01C92160 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 6 [ 16]: Max-Forwards: 69 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 7 [ 62]: Via: SIP/2.0/UDP 111.111.101.183;branch=z9hG4bK4f8a.fbee8aa4.0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 8 [ 98]: Via: SIP/2.0/UDP 192.168.0.191:5060;rport=9648;received=111.111.146.25;branch=z9hG4bK-546359fdd6-DL [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 9 [ 45]: Contact: "acer" [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 10 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 11 [ 30]: Supported: replaces,norefersub [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 12 [ 33]: User-Agent: Dylogic Mirial 7.0.36 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 14 [ 19]: Content-Length: 475 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 15 [ 16]: P-hint: outbound [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Header 16 [ 0]: [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 0 [ 3]: v=0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 1 [ 39]: o=acer 823020436 0 IN IP4 192.168.0.191 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 2 [ 3]: s=- [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 3 [ 23]: i=Dylogic Mirial 7.0.36 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 4 [ 24]: c=IN IP4 111.111.101.183 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 5 [ 8]: b=AS:768 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 6 [ 5]: t=0 0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 7 [ 29]: m=audio 59548 RTP/AVP 0 8 101 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 9 [ 20]: a=rtpmap:8 PCMA/8000 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 10 [ 33]: a=rtpmap:101 telephone-event/8000 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-16 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 12 [ 28]: a=x-mpdp:192.168.0.191:15348 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 13 [ 27]: m=video 36276 RTP/AVP 96 34 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 14 [ 27]: a=rtpmap:96 H263-1998/90000 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 15 [ 50]: a=fmtp:96 CIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 16 [ 22]: a=rtpmap:34 H263/90000 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 17 [ 15]: a=fmtp:34 CIF=1 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 18 [ 28]: a=x-mpdp:192.168.0.191:15334 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Body 19 [ 16]: a=nortpproxy:yes [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: --- (16 headers 20 lines) --- [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: = Looking for Call ID: DLf9d4a9aad7-1079858431@Acer-PC (Checking From) --From tag DL0e5b887127 --To-tag [Dec 23 02:32:57] DEBUG[9718] acl.c: For destination '111.111.101.183', our source address is '111.111.22.105'. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 111.111.22.105:5060 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Allocating new SIP dialog for DLf9d4a9aad7-1079858431@Acer-PC - INVITE (No RTP) [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Dec 23 02:32:57] DEBUG[9718] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces,norefersub" [Dec 23 02:32:57] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -replaces- [Dec 23 02:32:57] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: replaces [Dec 23 02:32:57] DEBUG[9718] sip/reqresp_parser.c: Found SIP option: -norefersub- [Dec 23 02:32:57] DEBUG[9718] sip/reqresp_parser.c: Matched SIP option: norefersub [Dec 23 02:32:57] DEBUG[9718] netsock2.c: Splitting '111.111.101.183' gives... [Dec 23 02:32:57] DEBUG[9718] netsock2.c: ...host '111.111.101.183' and port '(null)'. [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Sending to 111.111.101.183:5060 (no NAT) [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Initializing initreq for method INVITE - callid DLf9d4a9aad7-1079858431@Acer-PC [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Using INVITE request as basis request - DLf9d4a9aad7-1079858431@Acer-PC [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: No matching peer for 'acer' from '111.111.101.183:5060' [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x71afdb8' [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Allocated port 13690 for RTP instance '0x71afdb8' [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: RTP instance '0x71afdb8' is setup and ready to go [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x71b3f28' [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Allocated port 14042 for RTP instance '0x71b3f28' [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: RTP instance '0x71b3f28' is setup and ready to go [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x71b3f28' [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x71afdb8' [Dec 23 02:32:57] VERBOSE[9718] netsock2.c: == Using SIP RTP CoS mark 5 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Setting NAT on RTP to Off [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Setting NAT on VRTP to Off [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing session-level SDP o=acer 823020436 0 IN IP4 192.168.0.191... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing session-level SDP i=Dylogic Mirial 7.0.36... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] netsock2.c: Splitting '111.111.101.183' gives... [Dec 23 02:32:57] DEBUG[9718] netsock2.c: ...host '111.111.101.183' and port '(null)'. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing session-level SDP c=IN IP4 111.111.101.183... OK. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing session-level SDP b=AS:768... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found RTP audio format 0 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Setting payload 0 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found RTP audio format 8 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Setting payload 8 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found RTP audio format 101 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Setting payload 101 based on m type on 0x7ffa2cae58a0 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found audio description format PCMU for ID 0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found audio description format PCMA for ID 8 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found audio description format telephone-event for ID 101 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (audio) SDP a=x-mpdp:192.168.0.191:15348... UNSUPPORTED. [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found RTP video format 96 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found RTP video format 34 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Setting payload 34 based on m type on 0x7ffa2cae4820 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found video description format H263-1998 for ID 96 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:96 H263-1998/90000... OK. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:96 CIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1... UNSUPPORTED. [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Found video description format H263 for ID 34 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=rtpmap:34 H263/90000... OK. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=fmtp:34 CIF=1... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=x-mpdp:192.168.0.191:15334... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Processing media-level (video) SDP a=nortpproxy:yes... UNSUPPORTED. [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Incorporating payload 0 on 0x7ffa2cae58a0 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Incorporating payload 8 on 0x7ffa2cae58a0 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Incorporating payload 101 on 0x7ffa2cae58a0 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Incorporating payload 34 on 0x7ffa2cae4820 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Incorporating payload 96 on 0x7ffa2cae4820 [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Capabilities: us - 0x180004 (ulaw|h263|h263p), peer - audio=0xc (ulaw|alaw)/video=0x180000 (h263|h263p)/text=0x0 (nothing), combined - 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x71afdb8' [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Peer audio RTP is at port 111.111.101.183:59548 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Copying payload 0 from 0x7ffa2cae58a0 to 0x71aff80 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Copying payload 8 from 0x7ffa2cae58a0 to 0x71aff80 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Copying payload 101 from 0x7ffa2cae58a0 to 0x71aff80 [Dec 23 02:32:57] DEBUG[9718] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x71b3f28' [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Peer video RTP is at port 111.111.101.183:36276 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Copying payload 34 from 0x7ffa2cae4820 to 0x71b40f0 [Dec 23 02:32:57] DEBUG[9718] rtp_engine.c: Copying payload 96 from 0x7ffa2cae4820 to 0x71b40f0 [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: We're settling with these formats: 0x180004 (ulaw|h263|h263p) [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Checking SIP call limits for device [Dec 23 02:32:57] DEBUG[9718] chan_sip.c: Updating call counter for incoming call [Dec 23 02:32:57] VERBOSE[9718] chan_sip.c: Looking for 200 in default (domain vcc.fr.acme.net)