[Nov 4 14:08:56] NOTICE[14545] manager.c: Unable to open AMI configuration manager.conf, or configuration is invalid. Asterisk management interface (AMI) disabled. [Nov 4 14:08:56] NOTICE[14545] loader.c: 150 modules will be loaded. [Nov 4 14:08:57] NOTICE[14545] res_smdi.c: Unable to load config smdi.conf: SMDI disabled [Nov 4 14:08:57] NOTICE[14545] res_smdi.c: No SMDI interfaces are available to listen on, not starting SMDI listener. [Nov 4 14:08:57] ERROR[14545] res_stun_monitor.c: Unable to load config res_stun_monitor.conf [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] ERROR[14545] res_calendar.c: Unable to load config calendar.conf [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] WARNING[14545] loader.c: Module 'app_konference.so' was not compiled with the same compile-time options as this version of Asterisk. [Nov 4 14:08:57] WARNING[14545] loader.c: Module 'app_konference.so' will not be initialized as it may cause instability. [Nov 4 14:08:57] WARNING[14545] loader.c: Module 'app_konference.so' could not be loaded. [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] chan_sip.c: SIP channel loading... [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] NOTICE[14545] chan_agent.c: No agent configuration found -- agent support disabled [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] ERROR[14545] cdr_custom.c: Unable to load cdr_custom.conf. Not logging custom CSV CDRs. [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] ERROR[14545] cel_custom.c: Unable to load cel_custom.conf. Not logging CEL to custom CSVs. [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] WARNING[14545] cel_manager.c: Failed to load configuration file. CEL manager Module not activated. [Nov 4 14:08:57] WARNING[14545] cdr_manager.c: Failed to load configuration file. Module not activated. [Nov 4 14:08:57] WARNING[14545] cdr_csv.c: unable to load config: cdr.conf [Nov 4 14:08:57] ERROR[14545] cdr_syslog.c: Unable to load cdr_syslog.conf. Not logging custom CSV CDRs to syslog. [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] WARNING[14545] res_phoneprov.c: Unable to load users.conf [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] res_clialiases.c: == Aliased CLI command 'hangup request' to 'channel request hangup' [Nov 4 14:08:57] VERBOSE[14545] res_clialiases.c: == Aliased CLI command 'originate' to 'channel originate' [Nov 4 14:08:57] VERBOSE[14545] res_clialiases.c: == Aliased CLI command 'help' to 'core show help' [Nov 4 14:08:57] VERBOSE[14545] res_clialiases.c: == Aliased CLI command 'pri intense debug span' to 'pri set debug 2 span' [Nov 4 14:08:57] VERBOSE[14545] res_clialiases.c: == Aliased CLI command 'reload' to 'module reload' [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] WARNING[14545] app_festival.c: No such configuration file festival.conf [Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] VERBOSE[14545] loader.c: .[Nov 4 14:08:57] NOTICE[14545] app_queue.c: No queuerules.conf file found, queues will not follow penalty rules [Nov 4 14:08:57] NOTICE[14545] app_queue.c: No call queueing config file (queues.conf), so no call queues [Nov 4 14:08:57] VERBOSE[14545] asterisk.c: ] [Nov 4 14:08:57] VERBOSE[14545] asterisk.c: Asterisk Ready. [Nov 4 14:10:00] VERBOSE[14545] config.c: == Parsing '/etc/asterisk/logger.conf': [Nov 4 14:10:00] DEBUG[14545] config.c: Parsing /etc/asterisk/logger.conf [Nov 4 14:10:00] VERBOSE[14545] config.c: == Found [Nov 4 14:10:00] VERBOSE[14545] logger.c: Asterisk Queue Logger restarted [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: <--- SIP read from UDP:177.177.101.183:5060 ---> INVITE sip:880@177.177.109.241 SIP/2.0 Record-Route: To: Call-ID: DLda08699869-1079616693@Macintosh.local. From: "client" ;tag=DLf78e6452f1;epid=0x19d0d210 CSeq: 1 INVITE Max-Forwards: 69 Via: SIP/2.0/UDP 177.177.101.183;branch=z9hG4bKe22e.f1b3254.0 Via: SIP/2.0/UDP 192.168.100.7:5060;rport=41413;received=166.166.208.204;branch=z9hG4bK-7cb82be879-DL Contact: "c001" Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Supported: replaces,norefersub User-Agent: Dylogic Mirial 7.0.33 Content-Type: application/sdp Content-Length: 779 P-hint: outbound v=0 o=c001 315076184 0 IN IP4 192.168.100.7 s=- i=Dylogic Mirial 7.0.33 c=IN IP4 177.177.101.183 b=AS:512 t=0 0 m=audio 40364 RTP/AVP 96 97 98 0 8 4 101 a=rtpmap:96 G7221/32000 a=fmtp:96 bitrate=48000 a=rtpmap:97 G7221/32000 a=fmtp:97 bitrate=32000 a=rtpmap:98 G7221/32000 a=fmtp:98 bitrate=24000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=x-mpdp:192.168.100.7:53256 m=video 42324 RTP/AVP 99 34 a=rtpmap:99 H263-1998/90000 a=fmtp:99 CIF=1; QCIF=1; SQCIF=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; SQCIF=1 a=x-mpdp:192.168.100.7:53258 m=application 55532 RTP/AVP 100 a=rtpmap:100 H224/4800 a=x-mpdp:192.168.100.7:53260 a=nortpproxy:yes <-------------> [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 0 [ 38]: INVITE sip:880@177.177.109.241 SIP/2.0 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 1 [ 41]: Record-Route: [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 2 [ 29]: To: [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 3 [ 49]: Call-ID: DLda08699869-1079616693@Macintosh.local. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 4 [ 84]: From: "client" ;tag=DLf78e6452f1;epid=0x19d0d210 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 6 [ 16]: Max-Forwards: 69 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 7 [ 61]: Via: SIP/2.0/UDP 177.177.101.183;branch=z9hG4bKe22e.f1b3254.0 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 8 [100]: Via: SIP/2.0/UDP 192.168.100.7:5060;rport=41413;received=166.166.208.204;branch=z9hG4bK-7cb82be879-DL [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 9 [ 47]: Contact: "c001" [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 10 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 11 [ 30]: Supported: replaces,norefersub [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 12 [ 33]: User-Agent: Dylogic Mirial 7.0.33 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 13 [ 29]: Content-Type: application/sdp [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 14 [ 19]: Content-Length: 779 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 15 [ 16]: P-hint: outbound [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 16 [ 0]: [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 0 [ 3]: v=0 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 1 [ 39]: o=c001 315076184 0 IN IP4 192.168.100.7 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 2 [ 3]: s=- [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 3 [ 23]: i=Dylogic Mirial 7.0.33 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 4 [ 24]: c=IN IP4 177.177.101.183 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 5 [ 8]: b=AS:512 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 6 [ 5]: t=0 0 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 7 [ 40]: m=audio 40364 RTP/AVP 96 97 98 0 8 4 101 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 8 [ 23]: a=rtpmap:96 G7221/32000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 9 [ 23]: a=fmtp:96 bitrate=48000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 10 [ 23]: a=rtpmap:97 G7221/32000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 11 [ 23]: a=fmtp:97 bitrate=32000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 12 [ 23]: a=rtpmap:98 G7221/32000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 13 [ 23]: a=fmtp:98 bitrate=24000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 14 [ 20]: a=rtpmap:0 PCMU/8000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 15 [ 20]: a=rtpmap:8 PCMA/8000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 16 [ 20]: a=rtpmap:4 G723/8000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 17 [ 33]: a=rtpmap:101 telephone-event/8000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 18 [ 15]: a=fmtp:101 0-16 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 19 [ 28]: a=x-mpdp:192.168.100.7:53256 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 20 [ 27]: m=video 42324 RTP/AVP 99 34 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 21 [ 27]: a=rtpmap:99 H263-1998/90000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 22 [ 67]: a=fmtp:99 CIF=1; QCIF=1; SQCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 23 [ 22]: a=rtpmap:34 H263/90000 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 24 [ 32]: a=fmtp:34 CIF=1; QCIF=1; SQCIF=1 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 25 [ 28]: a=x-mpdp:192.168.100.7:53258 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 26 [ 31]: m=application 55532 RTP/AVP 100 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 27 [ 22]: a=rtpmap:100 H224/4800 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 28 [ 28]: a=x-mpdp:192.168.100.7:53260 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Body 29 [ 16]: a=nortpproxy:yes [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: --- (16 headers 30 lines) --- [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: = Looking for Call ID: DLda08699869-1079616693@Macintosh.local. (Checking From) --From tag DLf78e6452f1 --To-tag [Nov 4 14:11:57] DEBUG[14557] acl.c: For destination '177.177.101.183', our source address is '177.177.109.241'. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 177.177.109.241:5060 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Allocating new SIP dialog for DLda08699869-1079616693@Macintosh.local. - INVITE (No RTP) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Nov 4 14:11:57] DEBUG[14557] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces,norefersub" [Nov 4 14:11:57] DEBUG[14557] sip/reqresp_parser.c: Found SIP option: -replaces- [Nov 4 14:11:57] DEBUG[14557] sip/reqresp_parser.c: Matched SIP option: replaces [Nov 4 14:11:57] DEBUG[14557] sip/reqresp_parser.c: Found SIP option: -norefersub- [Nov 4 14:11:57] DEBUG[14557] sip/reqresp_parser.c: Matched SIP option: norefersub [Nov 4 14:11:57] DEBUG[14557] netsock2.c: Splitting '177.177.101.183' gives... [Nov 4 14:11:57] DEBUG[14557] netsock2.c: ...host '177.177.101.183' and port '(null)'. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Sending to 177.177.101.183:5060 (no NAT) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Initializing initreq for method INVITE - callid DLda08699869-1079616693@Macintosh.local. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Using INVITE request as basis request - DLda08699869-1079616693@Macintosh.local. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: No matching peer for 'c001' from '177.177.101.183:5060' [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x2a00468' [Nov 4 14:11:57] DEBUG[14557] res_rtp_asterisk.c: Allocated port 11334 for RTP instance '0x2a00468' [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: RTP instance '0x2a00468' is setup and ready to go [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x2a05178' [Nov 4 14:11:57] DEBUG[14557] res_rtp_asterisk.c: Allocated port 10840 for RTP instance '0x2a05178' [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: RTP instance '0x2a05178' is setup and ready to go [Nov 4 14:11:57] DEBUG[14557] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x2a05178' [Nov 4 14:11:57] DEBUG[14557] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x2a00468' [Nov 4 14:11:57] VERBOSE[14557] netsock2.c: == Using SIP RTP CoS mark 5 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Setting NAT on RTP to Off [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Setting NAT on VRTP to Off [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing session-level SDP o=c001 315076184 0 IN IP4 192.168.100.7... UNSUPPORTED. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing session-level SDP s=-... UNSUPPORTED. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing session-level SDP i=Dylogic Mirial 7.0.33... UNSUPPORTED. [Nov 4 14:11:57] DEBUG[14557] netsock2.c: Splitting '177.177.101.183' gives... [Nov 4 14:11:57] DEBUG[14557] netsock2.c: ...host '177.177.101.183' and port '(null)'. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing session-level SDP c=IN IP4 177.177.101.183... OK. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing session-level SDP b=AS:512... UNSUPPORTED. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP audio format 96 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP audio format 97 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 97 based on m type on 0x7fdc00b09f90 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP audio format 98 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 98 based on m type on 0x7fdc00b09f90 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP audio format 0 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 0 based on m type on 0x7fdc00b09f90 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP audio format 8 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 8 based on m type on 0x7fdc00b09f90 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP audio format 4 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 4 based on m type on 0x7fdc00b09f90 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP audio format 101 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 101 based on m type on 0x7fdc00b09f90 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found audio description format G7221 for ID 96 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 G7221/32000... OK. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 bitrate=48000... OK. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found audio description format G7221 for ID 97 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:97 G7221/32000... OK. [Nov 4 14:11:57] WARNING[14557] chan_sip.c: Got Siren14 offer at 32000 bps, but only 48000 bps supported; ignoring. [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Unsetting payload 97 on 0x7fdc00b09f90 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=fmtp:97 bitrate=32000... UNSUPPORTED. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found audio description format G7221 for ID 98 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:98 G7221/32000... OK. [Nov 4 14:11:57] WARNING[14557] chan_sip.c: Got Siren14 offer at 24000 bps, but only 48000 bps supported; ignoring. [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Unsetting payload 98 on 0x7fdc00b09f90 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=fmtp:98 bitrate=24000... UNSUPPORTED. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found audio description format PCMU for ID 0 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found audio description format PCMA for ID 8 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found audio description format G723 for ID 4 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:4 G723/8000... OK. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found audio description format telephone-event for ID 101 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (audio) SDP a=x-mpdp:192.168.100.7:53256... UNSUPPORTED. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP video format 99 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 99 based on m type on 0x7fdc00b08f10 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found RTP video format 34 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Setting payload 34 based on m type on 0x7fdc00b08f10 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found video description format H263-1998 for ID 99 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (video) SDP a=rtpmap:99 H263-1998/90000... OK. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (video) SDP a=fmtp:99 CIF=1; QCIF=1; SQCIF=1; D=1; F=1; I=1; J=1; L=1; S=1; T=1... UNSUPPORTED. [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Found video description format H263 for ID 34 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (video) SDP a=rtpmap:34 H263/90000... OK. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (video) SDP a=fmtp:34 CIF=1; QCIF=1; SQCIF=1... UNSUPPORTED. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Processing media-level (video) SDP a=x-mpdp:192.168.100.7:53258... UNSUPPORTED. [Nov 4 14:11:57] WARNING[14557] chan_sip.c: Unsupported SDP media type in offer: application 55532 RTP/AVP 100 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Incorporating payload 0 on 0x7fdc00b09f90 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Incorporating payload 4 on 0x7fdc00b09f90 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Incorporating payload 8 on 0x7fdc00b09f90 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Incorporating payload 96 on 0x7fdc00b09f90 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Incorporating payload 101 on 0x7fdc00b09f90 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Incorporating payload 34 on 0x7fdc00b08f10 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Incorporating payload 99 on 0x7fdc00b08f10 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Capabilities: us - 0x80104 (ulaw|g729|h263), peer - audio=0x400d (g723|ulaw|alaw|siren14)/video=0x180000 (h263|h263p)/text=0x0 (nothing), combined - 0x80004 (ulaw|h263) [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Nov 4 14:11:57] DEBUG[14557] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x2a00468' [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Peer audio RTP is at port 177.177.101.183:40364 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Copying payload 0 from 0x7fdc00b09f90 to 0x2a00630 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Copying payload 4 from 0x7fdc00b09f90 to 0x2a00630 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Copying payload 8 from 0x7fdc00b09f90 to 0x2a00630 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Copying payload 96 from 0x7fdc00b09f90 to 0x2a00630 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Copying payload 101 from 0x7fdc00b09f90 to 0x2a00630 [Nov 4 14:11:57] DEBUG[14557] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x2a05178' [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Peer video RTP is at port 177.177.101.183:42324 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Copying payload 34 from 0x7fdc00b08f10 to 0x2a05340 [Nov 4 14:11:57] DEBUG[14557] rtp_engine.c: Copying payload 99 from 0x7fdc00b08f10 to 0x2a05340 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: We're settling with these formats: 0x80004 (ulaw|h263) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Checking SIP call limits for device [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Updating call counter for incoming call [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: Looking for 880 in default (domain 177.177.109.241) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: *** Our native formats are 0x80004 (ulaw|h263) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: *** Joint capabilities are 0x80004 (ulaw|h263) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: *** Our capabilities are 0x80104 (ulaw|g729|h263) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: This channel can handle video! HOLLYWOOD next! [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: build_route: Record-Route hop: [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: list_route: hop: [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: SIP/sip.acme.net-00000000: New call is still down.... Trying... [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: <--- Transmitting (no NAT) to 177.177.101.183:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 177.177.101.183;branch=z9hG4bKe22e.f1b3254.0;received=177.177.101.183 Via: SIP/2.0/UDP 192.168.100.7:5060;rport=41413;received=166.166.208.204;branch=z9hG4bK-7cb82be879-DL Record-Route: From: "client" ;tag=DLf78e6452f1;epid=0x19d0d210 To: Call-ID: DLda08699869-1079616693@Macintosh.local. CSeq: 1 INVITE Server: Asterisk PBX 1.8.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 177.177.101.183:5060 [Nov 4 14:11:57] DEBUG[14550] devicestate.c: No provider found, checking channel drivers for SIP - sip.acme.net [Nov 4 14:11:57] DEBUG[14550] chan_sip.c: Checking device state for peer sip.acme.net [Nov 4 14:11:57] DEBUG[14550] devicestate.c: Changing state for SIP/sip.acme.net - state 2 (In use) [Nov 4 14:11:57] DEBUG[14550] devicestate.c: device 'SIP/sip.acme.net' state '2' [Nov 4 14:11:57] DEBUG[14562] pbx.c: Launching 'Answer' [Nov 4 14:11:57] VERBOSE[14562] pbx.c: -- Executing [880@default:1] Answer("SIP/sip.acme.net-00000000", "") in new stack [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: SIP answering channel: SIP/sip.acme.net-00000000 [Nov 4 14:11:57] DEBUG[14562] res_rtp_asterisk.c: Setting the marker bit due to a source update [Nov 4 14:11:57] DEBUG[14550] devicestate.c: No provider found, checking channel drivers for SIP - sip.acme.net [Nov 4 14:11:57] DEBUG[14550] chan_sip.c: Checking device state for peer sip.acme.net [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: Setting framing from config on incoming call [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: This call needs video offers! [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: ** Our capability: 0x80004 (ulaw|h263) Video flag: False Text flag: True [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Nov 4 14:11:57] VERBOSE[14562] chan_sip.c: Audio is at 5060 [Nov 4 14:11:57] VERBOSE[14562] chan_sip.c: Video is at 177.177.109.241:5060 [Nov 4 14:11:57] VERBOSE[14562] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Nov 4 14:11:57] VERBOSE[14562] chan_sip.c: Adding video codec 0x80000 (h263) to SDP [Nov 4 14:11:57] VERBOSE[14562] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: -- Done with adding codecs to SDP [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: Done building SDP. Settling with this capability: 0x80004 (ulaw|h263) [Nov 4 14:11:57] VERBOSE[14562] chan_sip.c: <--- Reliably Transmitting (no NAT) to 177.177.101.183:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 177.177.101.183;branch=z9hG4bKe22e.f1b3254.0;received=177.177.101.183 Via: SIP/2.0/UDP 192.168.100.7:5060;rport=41413;received=166.166.208.204;branch=z9hG4bK-7cb82be879-DL Record-Route: From: "client" ;tag=DLf78e6452f1;epid=0x19d0d210 To: ;tag=as519a2038 Call-ID: DLda08699869-1079616693@Macintosh.local. CSeq: 1 INVITE Server: Asterisk PBX 1.8.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 337 v=0 o=root 763206165 763206165 IN IP4 177.177.109.241 s=Asterisk PBX 1.8.0 c=IN IP4 177.177.109.241 b=CT:384 t=0 0 m=audio 11334 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 10840 RTP/AVP 34 a=rtpmap:34 H263/90000 a=sendrecv <------------> [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #7 [Nov 4 14:11:57] DEBUG[14562] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 177.177.101.183:5060 [Nov 4 14:11:57] DEBUG[14550] devicestate.c: Changing state for SIP/sip.acme.net - state 2 (In use) [Nov 4 14:11:57] DEBUG[14550] devicestate.c: device 'SIP/sip.acme.net' state '2' [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: <--- SIP read from UDP:177.177.101.183:5060 ---> ACK sip:880@177.177.109.241:5060 SIP/2.0 CSeq: 1 ACK Via: SIP/2.0/UDP 177.177.101.183;branch=z9hG4bKe22e.f1b3254.2 Via: SIP/2.0/UDP 192.168.100.7:5060;rport=41413;received=166.166.208.204;branch=z9hG4bK-895c13602f-DL To: ;tag=as519a2038 From: "c001" ;tag=DLf78e6452f1;epid=0x19d0d210 Call-ID: DLda08699869-1079616693@Macintosh.local. Max-Forwards: 69 Contact: "c001" Content-Length: 0 <-------------> [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 0 [ 40]: ACK sip:880@177.177.109.241:5060 SIP/2.0 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 1 [ 11]: CSeq: 1 ACK [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 2 [ 61]: Via: SIP/2.0/UDP 177.177.101.183;branch=z9hG4bKe22e.f1b3254.2 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 3 [100]: Via: SIP/2.0/UDP 192.168.100.7:5060;rport=41413;received=166.166.208.204;branch=z9hG4bK-895c13602f-DL [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 4 [ 44]: To: ;tag=as519a2038 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 5 [ 74]: From: "c001" ;tag=DLf78e6452f1;epid=0x19d0d210 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 6 [ 49]: Call-ID: DLda08699869-1079616693@Macintosh.local. [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 7 [ 16]: Max-Forwards: 69 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 8 [ 47]: Contact: "c001" [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Nov 4 14:11:57] VERBOSE[14557] chan_sip.c: --- (10 headers 0 lines) --- [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: = Looking for Call ID: DLda08699869-1079616693@Macintosh.local. (Checking From) --From tag DLf78e6452f1 --To-tag as519a2038 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #7 [Nov 4 14:11:57] DEBUG[14557] chan_sip.c: Stopping retransmission on 'DLda08699869-1079616693@Macintosh.local.' of Response 1: Match Found [Nov 4 14:11:57] DEBUG[14562] channel.c: Didn't receive a media frame from SIP/sip.acme.net-00000000 within 500 ms of answering. Continuing anyway [Nov 4 14:11:57] DEBUG[14562] pbx.c: Launching 'Playback' [Nov 4 14:11:57] VERBOSE[14562] pbx.c: -- Executing [880@default:2] Playback("SIP/sip.acme.net-00000000", "acme-queue-pleasewait") in new stack [Nov 4 14:11:57] DEBUG[14562] res_rtp_asterisk.c: Ooh, format changed from unknown to ulaw [Nov 4 14:11:57] DEBUG[14562] res_rtp_asterisk.c: Created smoother: format: ulaw ms: 20 len: 160 [Nov 4 14:11:57] DEBUG[14562] res_rtp_asterisk.c: Starting RTCP transmission on RTP instance '0x2a00468' [Nov 4 14:11:57] DEBUG[14562] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Nov 4 14:11:57] VERBOSE[14562] file.c: -- Playing 'acme-queue-pleasewait.ulaw' (language 'en') [Nov 4 14:12:04] DEBUG[14562] channel.c: Scheduling timer at (100 requested / 100 actual) timer ticks per second [Nov 4 14:12:04] DEBUG[14562] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 4 14:12:04] DEBUG[14562] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 4 14:12:04] DEBUG[14562] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 4 14:12:04] DEBUG[14562] pbx.c: Launching 'Hangup' [Nov 4 14:12:04] VERBOSE[14562] pbx.c: -- Executing [880@default:3] Hangup("SIP/sip.acme.net-00000000", "") in new stack [Nov 4 14:12:04] DEBUG[14562] pbx.c: Spawn extension (default,880,3) exited non-zero on 'SIP/sip.acme.net-00000000' [Nov 4 14:12:04] VERBOSE[14562] pbx.c: == Spawn extension (default, 880, 3) exited non-zero on 'SIP/sip.acme.net-00000000' [Nov 4 14:12:04] DEBUG[14562] channel.c: Soft-Hanging up channel 'SIP/sip.acme.net-00000000' [Nov 4 14:12:04] DEBUG[14562] pbx.c: Launching 'NoOp' [Nov 4 14:12:04] VERBOSE[14562] pbx.c: -- Executing [h@default:1] NoOp("SIP/sip.acme.net-00000000", ""Hanging up now..."") in new stack [Nov 4 14:12:04] DEBUG[14562] pbx.c: Launching 'Hangup' [Nov 4 14:12:04] VERBOSE[14562] pbx.c: -- Executing [h@default:2] Hangup("SIP/sip.acme.net-00000000", "") in new stack [Nov 4 14:12:04] DEBUG[14562] pbx.c: Spawn extension (default,h,2) exited non-zero on 'SIP/sip.acme.net-00000000' [Nov 4 14:12:04] VERBOSE[14562] pbx.c: == Spawn extension (default, h, 2) exited non-zero on 'SIP/sip.acme.net-00000000' [Nov 4 14:12:04] DEBUG[14562] channel.c: Hanging up channel 'SIP/sip.acme.net-00000000' [Nov 4 14:12:04] DEBUG[14562] chan_sip.c: Hangup call SIP/sip.acme.net-00000000, SIP callid DLda08699869-1079616693@Macintosh.local. [Nov 4 14:12:04] DEBUG[14562] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x2a00468' [Nov 4 14:12:04] DEBUG[14562] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x2a05178' [Nov 4 14:12:04] VERBOSE[14562] chan_sip.c: Scheduling destruction of SIP dialog 'DLda08699869-1079616693@Macintosh.local.' in 32000 ms (Method: ACK) [Nov 4 14:12:04] VERBOSE[14562] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 4 14:12:04] DEBUG[14562] netsock2.c: Splitting '177.177.101.183' gives... [Nov 4 14:12:04] DEBUG[14562] netsock2.c: ...host '177.177.101.183' and port '(null)'. [Nov 4 14:12:04] VERBOSE[14562] chan_sip.c: set_destination: set destination to 177.177.101.183:5060 [Nov 4 14:12:04] VERBOSE[14562] chan_sip.c: Reliably Transmitting (no NAT) to 177.177.101.183:5060: BYE sip:c001@166.166.208.204:41413 SIP/2.0 Via: SIP/2.0/UDP 177.177.109.241:5060;branch=z9hG4bK4f8bb778 Route: Max-Forwards: 70 From: ;tag=as519a2038 To: "client" ;tag=DLf78e6452f1;epid=0x19d0d210 Call-ID: DLda08699869-1079616693@Macintosh.local. CSeq: 102 BYE User-Agent: Asterisk PBX 1.8.0 X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- [Nov 4 14:12:04] DEBUG[14562] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #11 [Nov 4 14:12:04] DEBUG[14562] chan_sip.c: Trying to put 'BYE sip:c00' onto UDP socket destined for 177.177.101.183:5060 [Nov 4 14:12:04] DEBUG[14550] devicestate.c: No provider found, checking channel drivers for SIP - sip.acme.net [Nov 4 14:12:04] DEBUG[14550] chan_sip.c: Checking device state for peer sip.acme.net [Nov 4 14:12:04] DEBUG[14550] devicestate.c: Changing state for SIP/sip.acme.net - state 0 (Unknown) [Nov 4 14:12:04] DEBUG[14550] devicestate.c: device 'SIP/sip.acme.net' state '0' [Nov 4 14:12:04] VERBOSE[14557] chan_sip.c: <--- SIP read from UDP:177.177.101.183:5060 ---> SIP/2.0 200 OK From: ;tag=as519a2038;epid=0x19d0d210 Call-ID: DLda08699869-1079616693@Macintosh.local. CSeq: 102 BYE Via: SIP/2.0/UDP 177.177.109.241:5060;rport=5060;received=177.177.109.241;branch=z9hG4bK4f8bb778 To: "client" ;tag=DLf78e6452f1;epid=0x19d0d210 Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER Contact: "c001" User-Agent: Dylogic Mirial 7.0.33 Content-Length: 0 <-------------> [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 1 [ 62]: From: ;tag=as519a2038;epid=0x19d0d210 [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 2 [ 49]: Call-ID: DLda08699869-1079616693@Macintosh.local. [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 3 [ 13]: CSeq: 102 BYE [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 4 [ 96]: Via: SIP/2.0/UDP 177.177.109.241:5060;rport=5060;received=177.177.109.241;branch=z9hG4bK4f8bb778 [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 5 [ 82]: To: "client" ;tag=DLf78e6452f1;epid=0x19d0d210 [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 6 [ 79]: Allow: INVITE,CANCEL,ACK,OPTIONS,INFO,SUBSCRIBE,NOTIFY,BYE,MESSAGE,UPDATE,REFER [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 7 [ 45]: Contact: "c001" [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 8 [ 33]: User-Agent: Dylogic Mirial 7.0.33 [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Nov 4 14:12:04] VERBOSE[14557] chan_sip.c: --- (10 headers 0 lines) --- [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: = Looking for Call ID: DLda08699869-1079616693@Macintosh.local. (Checking To) --From tag as519a2038 --To-tag DLf78e6452f1 [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #11 [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Stopping retransmission on 'DLda08699869-1079616693@Macintosh.local.' of Request 102: Match Found [Nov 4 14:12:04] VERBOSE[14557] chan_sip.c: SIP Response message for INCOMING dialog BYE arrived [Nov 4 14:12:04] DEBUG[14557] chan_sip.c: Destroying SIP dialog DLda08699869-1079616693@Macintosh.local. [Nov 4 14:12:04] VERBOSE[14557] chan_sip.c: Really destroying SIP dialog 'DLda08699869-1079616693@Macintosh.local.' Method: ACK [Nov 4 14:12:04] DEBUG[14557] rtp_engine.c: Destroyed RTP instance '0x2a00468' [Nov 4 14:12:04] DEBUG[14557] rtp_engine.c: Destroyed RTP instance '0x2a05178'