[2016-05-24 17:25:57] DEBUG[508] chan_sip.c: Header 0 [ 3]: jaK [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 0 [ 43]: INVITE sip:88001000936@192.168.1.50 SIP/2.0 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 1 [ 65]: Via: SIP/2.0/UDP 192.168.1.50:50600;rport;branch=z9hG4bK346136130 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 2 [ 54]: From: ;tag=568262454 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 3 [ 34]: To: [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 4 [ 18]: Call-ID: 584509577 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 5 [ 15]: CSeq: 20 INVITE [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 6 [ 49]: Contact: [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 7 [ 29]: Content-Type: application/sdp [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 8 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 9 [ 16]: Max-Forwards: 70 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 10 [ 42]: User-Agent: Linphone/3.6.1 (eXosip2/4.0.0) [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 11 [ 19]: Subject: Phone call [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 12 [ 19]: Content-Length: 444 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Header 13 [ 0]: [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 0 [ 3]: v=0 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 1 [ 44]: o=statusprofi_107 5 3002 IN IP4 192.168.1.50 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 2 [ 6]: s=Talk [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 3 [ 21]: c=IN IP4 192.168.1.50 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 4 [ 5]: t=0 0 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 5 [ 40]: m=audio 7078 RTP/AVP 124 111 110 0 8 101 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 6 [ 23]: a=rtpmap:124 opus/48000 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 7 [ 35]: a=fmtp:124 useinbandfec=1; usedtx=1 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 8 [ 24]: a=rtpmap:111 speex/16000 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 9 [ 17]: a=fmtp:111 vbr=on [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 10 [ 23]: a=rtpmap:110 speex/8000 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 11 [ 17]: a=fmtp:110 vbr=on [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 12 [ 33]: a=rtpmap:101 telephone-event/8000 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 13 [ 15]: a=fmtp:101 0-11 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 14 [ 27]: m=video 9078 RTP/AVP 103 99 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 15 [ 22]: a=rtpmap:103 VP8/90000 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 16 [ 25]: a=rtpmap:99 MP4V-ES/90000 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Body 17 [ 28]: a=fmtp:99 profile-level-id=3 [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: = Looking for Call ID: 584509577 (Checking From) --From tag 568262454 --To-tag [2016-05-24 17:26:01] DEBUG[508] acl.c: For destination '192.168.1.50', our source address is '192.168.1.50'. [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.1.50:5060 [2016-05-24 17:26:01] DEBUG[508] netsock2.c: Splitting '192.168.1.50:50600' into... [2016-05-24 17:26:01] DEBUG[508] netsock2.c: ...host '192.168.1.50' and port '50600'. [2016-05-24 17:26:01] DEBUG[508] chan_sip.c: Allocating new SIP dialog for 584509577 - INVITE (No RTP) [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: Splitting '192.168.1.50:50600' into... [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: ...host '192.168.1.50' and port '50600'. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Initializing initreq for method INVITE - callid 584509577 [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: Splitting '192.168.1.50' into... [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: ...host '192.168.1.50' and port ''. [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f1768006648' [2016-05-24 17:26:01] DEBUG[508][C-00000002] res_rtp_asterisk.c: Allocated port 14524 for RTP instance '0x7f1768006648' [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: RTP instance '0x7f1768006648' is setup and ready to go [2016-05-24 17:26:01] DEBUG[508][C-00000002] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f1768006648' [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Setting NAT on RTP to On [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing session-level SDP o=statusprofi_107 5 3002 IN IP4 192.168.1.50... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing session-level SDP s=Talk... UNSUPPORTED OR FAILED. [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: Splitting '192.168.1.50' into... [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: ...host '192.168.1.50' and port ''. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing session-level SDP c=IN IP4 192.168.1.50... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 124 based on m type on 0x7f17acbf3d90 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 111 based on m type on 0x7f17acbf3d90 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 110 based on m type on 0x7f17acbf3d90 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 0 based on m type on 0x7f17acbf3d90 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 8 based on m type on 0x7f17acbf3d90 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 101 based on m type on 0x7f17acbf3d90 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Unsetting payload 124 on 0x7f17acbf3d90 [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:124 opus/48000... UNSUPPORTED OR FAILED. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=fmtp:124 useinbandfec=1; usedtx=1... UNSUPPORTED OR FAILED. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:111 speex/16000... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=fmtp:111 vbr=on... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:110 speex/8000... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=fmtp:110 vbr=on... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-11... UNSUPPORTED OR FAILED. [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 103 based on m type on 0x7f17acbf80e0 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Setting payload 99 based on m type on 0x7f17acbf80e0 [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Received offer with crypto line for media stream that is not enabled [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (video) SDP a=rtpmap:103 VP8/90000... UNSUPPORTED OR FAILED. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Received offer with crypto line for media stream that is not enabled [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (video) SDP a=rtpmap:99 MP4V-ES/90000... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Received offer with crypto line for media stream that is not enabled [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Processing media-level (video) SDP a=fmtp:99 profile-level-id=3... OK. [2016-05-24 17:26:01] DEBUG[508][C-00000002] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f1768006648' [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Copying payload 0 from 0x7f17acbf3d90 to 0x7f1768006810 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Copying payload 8 from 0x7f17acbf3d90 to 0x7f1768006810 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Copying payload 101 from 0x7f17acbf3d90 to 0x7f1768006810 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Copying payload 110 from 0x7f17acbf3d90 to 0x7f1768006810 [2016-05-24 17:26:01] DEBUG[508][C-00000002] rtp_engine.c: Copying payload 111 from 0x7f17acbf3d90 to 0x7f1768006810 [2016-05-24 17:26:01] DEBUG[508][C-00000002] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f1768006648' [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: We're settling with these formats: (ulaw) [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Checking SIP call limits for device statusprofi_107 [2016-05-24 17:26:01] DEBUG[508][C-00000002] chan_sip.c: Updating call counter for incoming call [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: Splitting '192.168.1.50' into... [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: ...host '192.168.1.50' and port ''. [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: Splitting '192.168.1.50' into... [2016-05-24 17:26:01] DEBUG[508][C-00000002] netsock2.c: ...host '192.168.1.50' and port ''. [2016-05-24 17:26:01] DEBUG[508][C-00000002] pbx.c: return at end of func [2016-05-24 17:26:01] DEBUG[508][C-00000002] res_config_sqlite3.c: DB: asterisk SQL: SELECT * FROM "sip_extensions" WHERE "exten" = '88001000936' AND "context" = 'statusprofi_107' AND "priority" = '1' LIMIT 1 [2016-05-24 17:26:02] DEBUG[508][C-00000002] res_config_sqlite3.c: DB: asterisk SQL: SELECT * FROM "sip_extensions" WHERE "exten" LIKE '\_%' AND "context" = 'statusprofi_107' AND "priority" = '1' [2016-05-24 17:26:02] DEBUG[508][C-00000002] pbx.c: return at end of func [2016-05-24 17:26:02] DEBUG[508][C-00000002] res_config_sqlite3.c: DB: asterisk SQL: SELECT * FROM "sip_extensions" WHERE "exten" = '88001000936' AND "context" = 'statusprofi_107' AND "priority" = '1' LIMIT 1 [2016-05-24 17:26:03] DEBUG[508][C-00000002] res_config_sqlite3.c: DB: asterisk SQL: SELECT * FROM "sip_extensions" WHERE "exten" LIKE '\_%' AND "context" = 'statusprofi_107' AND "priority" = '1' [2016-05-24 17:26:03] DEBUG[508][C-00000002] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #3 [2016-05-24 17:26:03] DEBUG[508][C-00000002] chan_sip.c: Trying to put 'SIP/2.0 404' onto UDP socket destined for 192.168.1.50:50600 [2016-05-24 17:26:03] DEBUG[508][C-00000002] chan_sip.c: Updating call counter for incoming call