[Nov 25 14:33:22] VERBOSE[4740] logger.c: Asterisk Event Logger restarted [Nov 25 14:33:22] VERBOSE[4740] logger.c: Asterisk Queue Logger restarted [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = Looking for Call ID: 995760468@192.168.34.38 (Checking From) --From tag 1860585239 --To-tag [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 93354336@192.168.34.26 Their Tag 813326708 Our tag: as4aec998f [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 991581528@192.168.34.26 Their Tag 1215546991 Our tag: as22e803a7 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 589808368@192.168.34.26 Their Tag 1398766311 Our tag: as3fb202c1 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 424076244@192.168.34.26 Their Tag 1025282175 Our tag: as57268c03 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 693247599@192.168.34.26 Their Tag 1856898716 Our tag: as1c31e7af [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 378495940@192.168.34.26 Their Tag 553429138 Our tag: as66d1c62a [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: c5476db1-5040ba5a@192.168.34.55 Their Tag e196237f76cdce89o0 Our tag: as3a985b21 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 495102444@192.168.34.26 Their Tag 1887491937 Our tag: as66394c70 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1095238421@192.168.34.26 Their Tag 1737378279 Our tag: as69f4e9e6 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 266880571@192.168.34.26 Their Tag 764340817 Our tag: as38ba5fdd [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1703081106@192.168.34.26 Their Tag 1256270160 Our tag: as32b751c6 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 717168466@192.168.34.26 Their Tag 323419648 Our tag: as25317042 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 879943302@192.168.34.26 Their Tag 1062421346 Our tag: as69790903 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 988410876@192.168.34.26 Their Tag 1504485854 Our tag: as6b71395a [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1956137246@192.168.34.26 Their Tag 628371843 Our tag: as3c8a5547 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1265421820@192.168.34.26 Their Tag 1998209006 Our tag: as6a53730b [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1168061056@192.168.34.26 Their Tag 1840694843 Our tag: as626279fb [Nov 25 14:33:33] DEBUG[3868] acl.c: Found IP address for this socket [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.34.44:5060 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Allocating new SIP dialog for 995760468@192.168.34.38 - REGISTER (No RTP) [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' AND host = 'dynamic' [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.34.38:5064 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Auto destroying SIP dialog 'c5476db1-5040ba5a@192.168.34.55' [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Destroying SIP dialog c5476db1-5040ba5a@192.168.34.55 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = Looking for Call ID: 995760468@192.168.34.38 (Checking From) --From tag 1860585239 --To-tag [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 93354336@192.168.34.26 Their Tag 813326708 Our tag: as4aec998f [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 991581528@192.168.34.26 Their Tag 1215546991 Our tag: as22e803a7 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 589808368@192.168.34.26 Their Tag 1398766311 Our tag: as3fb202c1 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 424076244@192.168.34.26 Their Tag 1025282175 Our tag: as57268c03 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 693247599@192.168.34.26 Their Tag 1856898716 Our tag: as1c31e7af [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = Found Their Call ID: 995760468@192.168.34.38 Their Tag 1860585239 Our tag: as58dc3c88 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Store REGISTER's src-IP:port for call routing. [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Allocating new SIP dialog for 2a63f3ea2c44177d07e3f1a9267addf3@127.0.1.1 - OPTIONS (No RTP) [Nov 25 14:33:33] DEBUG[3868] acl.c: Found IP address for this socket [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.34.44:5060 [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' AND host = 'dynamic' [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Initializing initreq for method OPTIONS - callid 151cd8e8061e987b3b431b2d5132a839@192.168.34.44 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.34.38:5064 [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Update SQL: UPDATE sipusers SET ipaddr = '192.168.34.38', port = '5064', regseconds = '1290695793', defaultuser = '802', useragent = 'Yealink SIP-T28P 2.50.23.2', lastms = '54', fullcontact = 'sip:802@192.168.34.38:5064' WHERE name = '802' [Nov 25 14:33:33] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Updated 1 rows on table: sipusers [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.34.38:5064 [Nov 25 14:33:33] DEBUG[3864] devicestate.c: No provider found, checking channel drivers for SIP - 802 [Nov 25 14:33:33] DEBUG[3864] chan_sip.c: Checking device state for peer 802 [Nov 25 14:33:33] DEBUG[3864] devicestate.c: Changing state for SIP/802 - state 1 (Not in use) [Nov 25 14:33:33] DEBUG[3864] devicestate.c: device 'SIP/802' state '1' [Nov 25 14:33:33] DEBUG[3882] app_queue.c: Device 'SIP/802' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = Looking for Call ID: 151cd8e8061e987b3b431b2d5132a839@192.168.34.44 (Checking To) --From tag as65df2816 --To-tag 1672420960 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: = Found Their Call ID: 151cd8e8061e987b3b431b2d5132a839@192.168.34.44 Their Tag Our tag: as65df2816 [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Stopping retransmission on '151cd8e8061e987b3b431b2d5132a839@192.168.34.44' of Request 102: Match Found [Nov 25 14:33:33] DEBUG[3868] chan_sip.c: Destroying SIP dialog 151cd8e8061e987b3b431b2d5132a839@192.168.34.44 [Nov 25 14:33:39] DEBUG[3868] chan_sip.c: Auto destroying SIP dialog '1703081106@192.168.34.26' [Nov 25 14:33:39] DEBUG[3868] chan_sip.c: Destroying SIP dialog 1703081106@192.168.34.26 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Allocating new SIP dialog for 467f556c3f5c716556fff85534ab8d50@127.0.1.1 - OPTIONS (No RTP) [Nov 25 14:34:01] DEBUG[3868] acl.c: Found IP address for this socket [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.34.44:5060 [Nov 25 14:34:01] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:01] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' AND host = 'dynamic' [Nov 25 14:34:01] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:01] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Initializing initreq for method OPTIONS - callid 0b1ac04322809ae255c0398575af47b9@192.168.34.44 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 0 [ 42]: OPTIONS sip:803@192.168.34.55:5060 SIP/2.0 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK3dcd8212;rport [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 3 [ 54]: From: "DV-IP" ;tag=as01236e81 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 4 [ 32]: To: [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 5 [ 34]: Contact: [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 6 [ 55]: Call-ID: 0b1ac04322809ae255c0398575af47b9@192.168.34.44 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 8 [ 17]: User-Agent: DV-IP [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 9 [ 35]: Date: Thu, 25 Nov 2010 14:34:01 GMT [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: Reliably Transmitting (NAT) to 192.168.34.55:5060: OPTIONS sip:803@192.168.34.55:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK3dcd8212;rport Max-Forwards: 70 From: "DV-IP" ;tag=as01236e81 To: Contact: Call-ID: 0b1ac04322809ae255c0398575af47b9@192.168.34.44 CSeq: 102 OPTIONS User-Agent: DV-IP Date: Thu, 25 Nov 2010 14:34:01 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #89099 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.34.55:5060 [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.55:5060 ---> SIP/2.0 200 OK To: ;tag=d6f47372de43e31i0 From: "DV-IP" ;tag=as01236e81 Call-ID: 0b1ac04322809ae255c0398575af47b9@192.168.34.44 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK3dcd8212 Server: Linksys/SPA941-5.1.8 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: replaces <-------------> [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 1 [ 54]: To: ;tag=d6f47372de43e31i0 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 2 [ 54]: From: "DV-IP" ;tag=as01236e81 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 3 [ 55]: Call-ID: 0b1ac04322809ae255c0398575af47b9@192.168.34.44 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 4 [ 17]: CSeq: 102 OPTIONS [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 5 [ 58]: Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK3dcd8212 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 6 [ 28]: Server: Linksys/SPA941-5.1.8 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 8 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 9 [ 19]: Supported: replaces [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: --- (10 headers 0 lines) --- [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = Looking for Call ID: 0b1ac04322809ae255c0398575af47b9@192.168.34.44 (Checking To) --From tag as01236e81 --To-tag d6f47372de43e31i0 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 93354336@192.168.34.26 Their Tag 813326708 Our tag: as4aec998f [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 991581528@192.168.34.26 Their Tag 1215546991 Our tag: as22e803a7 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 589808368@192.168.34.26 Their Tag 1398766311 Our tag: as3fb202c1 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 424076244@192.168.34.26 Their Tag 1025282175 Our tag: as57268c03 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 693247599@192.168.34.26 Their Tag 1856898716 Our tag: as1c31e7af [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 995760468@192.168.34.38 Their Tag 1860585239 Our tag: as58dc3c88 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 378495940@192.168.34.26 Their Tag 553429138 Our tag: as66d1c62a [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 495102444@192.168.34.26 Their Tag 1887491937 Our tag: as66394c70 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1095238421@192.168.34.26 Their Tag 1737378279 Our tag: as69f4e9e6 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 266880571@192.168.34.26 Their Tag 764340817 Our tag: as38ba5fdd [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 717168466@192.168.34.26 Their Tag 323419648 Our tag: as25317042 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 879943302@192.168.34.26 Their Tag 1062421346 Our tag: as69790903 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 988410876@192.168.34.26 Their Tag 1504485854 Our tag: as6b71395a [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1956137246@192.168.34.26 Their Tag 628371843 Our tag: as3c8a5547 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1265421820@192.168.34.26 Their Tag 1998209006 Our tag: as6a53730b [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = Found Their Call ID: 0b1ac04322809ae255c0398575af47b9@192.168.34.44 Their Tag Our tag: as01236e81 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #89099 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Stopping retransmission on '0b1ac04322809ae255c0398575af47b9@192.168.34.44' of Request 102: Match Found [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Destroying SIP dialog 0b1ac04322809ae255c0398575af47b9@192.168.34.44 [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: Really destroying SIP dialog '0b1ac04322809ae255c0398575af47b9@192.168.34.44' Method: OPTIONS [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> INVITE sip:*118@192.168.34.44 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK1246879633 From: "Proto" ;tag=2003725197 To: Call-ID: 700466466@192.168.34.26 CSeq: 1 INVITE Contact: Content-Type: application/sdp Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T28P 2.50.23.2 Supported: replaces Expires: 360 Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 296 v=0 o=- 20180 20180 IN IP4 192.168.34.26 s=SDP data c=IN IP4 192.168.34.26 t=0 0 m=audio 11788 RTP/AVP 0 8 18 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv <-------------> [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 0 [ 37]: INVITE sip:*118@192.168.34.44 SIP/2.0 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 1 [ 60]: Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK1246879633 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 2 [ 52]: From: "Proto" ;tag=2003725197 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 3 [ 28]: To: [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 4 [ 32]: Call-ID: 700466466@192.168.34.26 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 5 [ 14]: CSeq: 1 INVITE [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 6 [ 37]: Contact: [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 7 [ 29]: Content-Type: application/sdp [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 8 [115]: Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 9 [ 16]: Max-Forwards: 70 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 10 [ 38]: User-Agent: Yealink SIP-T28P 2.50.23.2 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 11 [ 19]: Supported: replaces [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 12 [ 12]: Expires: 360 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 13 [ 51]: Allow-Events: talk,hold,conference,refer,check-sync [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 14 [ 19]: Content-Length: 296 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Header 15 [ 0]: [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 0 [ 3]: v=0 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 1 [ 36]: o=- 20180 20180 IN IP4 192.168.34.26 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 2 [ 10]: s=SDP data [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 3 [ 22]: c=IN IP4 192.168.34.26 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 5 [ 34]: m=audio 11788 RTP/AVP 0 8 18 9 101 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 7 [ 20]: a=rtpmap:8 PCMA/8000 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 8 [ 21]: a=rtpmap:18 G729/8000 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 9 [ 19]: a=fmtp:18 annexb=no [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 10 [ 20]: a=rtpmap:9 G722/8000 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-15 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 12 [ 33]: a=rtpmap:101 telephone-event/8000 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Body 13 [ 10]: a=sendrecv [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: --- (15 headers 14 lines) --- [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = Looking for Call ID: 700466466@192.168.34.26 (Checking From) --From tag 2003725197 --To-tag [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 93354336@192.168.34.26 Their Tag 813326708 Our tag: as4aec998f [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 991581528@192.168.34.26 Their Tag 1215546991 Our tag: as22e803a7 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 589808368@192.168.34.26 Their Tag 1398766311 Our tag: as3fb202c1 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 424076244@192.168.34.26 Their Tag 1025282175 Our tag: as57268c03 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 693247599@192.168.34.26 Their Tag 1856898716 Our tag: as1c31e7af [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 995760468@192.168.34.38 Their Tag 1860585239 Our tag: as58dc3c88 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 378495940@192.168.34.26 Their Tag 553429138 Our tag: as66d1c62a [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 495102444@192.168.34.26 Their Tag 1887491937 Our tag: as66394c70 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1095238421@192.168.34.26 Their Tag 1737378279 Our tag: as69f4e9e6 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 266880571@192.168.34.26 Their Tag 764340817 Our tag: as38ba5fdd [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 717168466@192.168.34.26 Their Tag 323419648 Our tag: as25317042 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 879943302@192.168.34.26 Their Tag 1062421346 Our tag: as69790903 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 988410876@192.168.34.26 Their Tag 1504485854 Our tag: as6b71395a [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1956137246@192.168.34.26 Their Tag 628371843 Our tag: as3c8a5547 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1265421820@192.168.34.26 Their Tag 1998209006 Our tag: as6a53730b [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1168061056@192.168.34.26 Their Tag 1840694843 Our tag: as626279fb [Nov 25 14:34:01] DEBUG[3868] acl.c: Found IP address for this socket [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.34.44:5060 [Nov 25 14:34:01] VERBOSE[3868] netsock.c: == Using SIP RTP TOS bits 184 [Nov 25 14:34:01] VERBOSE[3868] netsock.c: == Using SIP RTP CoS mark 5 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Setting NAT on RTP to Off [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Allocating new SIP dialog for 700466466@192.168.34.26 - INVITE (With RTP) [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Begin: parsing SIP "Supported: replaces" [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Found SIP option: -replaces- [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Matched SIP option: replaces [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: Sending to 192.168.34.26 : 5066 (no NAT) [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Initializing initreq for method INVITE - callid 700466466@192.168.34.26 [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: Using INVITE request as basis request - 700466466@192.168.34.26 [Nov 25 14:34:01] VERBOSE[3868] chan_sip.c: Found peer '801' for '801' from 192.168.34.26:5066 [Nov 25 14:34:01] DEBUG[3868] chan_sip.c: Setting NAT on RTP to On [Nov 25 14:34:02] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:02] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' AND host = 'dynamic' [Nov 25 14:34:02] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:02] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: <--- Reliably Transmitting (NAT) to 192.168.34.26:5066 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK1246879633;received=192.168.34.26 From: "Proto" ;tag=2003725197 To: ;tag=as72d8d598 Call-ID: 700466466@192.168.34.26 CSeq: 1 INVITE Server: DV-IP Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="DV-IP", nonce="1aca4a98" Content-Length: 0 <------------> [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #89102 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Scheduling destruction of SIP dialog '700466466@192.168.34.26' in 6400 ms (Method: INVITE) [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> ACK sip:*118@192.168.34.44 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK1246879633 From: "Proto" ;tag=2003725197 To: ;tag=as72d8d598 Call-ID: 700466466@192.168.34.26 CSeq: 1 ACK Content-Length: 0 <-------------> [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 0 [ 34]: ACK sip:*118@192.168.34.44 SIP/2.0 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 1 [ 60]: Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK1246879633 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 2 [ 52]: From: "Proto" ;tag=2003725197 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 3 [ 43]: To: ;tag=as72d8d598 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 4 [ 32]: Call-ID: 700466466@192.168.34.26 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 5 [ 11]: CSeq: 1 ACK [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 6 [ 17]: Content-Length: 0 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: --- (7 headers 0 lines) --- [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Looking for Call ID: 700466466@192.168.34.26 (Checking From) --From tag 2003725197 --To-tag as72d8d598 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Found Their Call ID: 700466466@192.168.34.26 Their Tag 2003725197 Our tag: as72d8d598 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #89102 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Stopping retransmission on '700466466@192.168.34.26' of Response 1: Match Found [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> INVITE sip:*118@192.168.34.44 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK67598327 From: "Proto" ;tag=2003725197 To: Call-ID: 700466466@192.168.34.26 CSeq: 2 INVITE Contact: Authorization: Digest username="801", realm="DV-IP", nonce="1aca4a98", uri="sip:*118@192.168.34.44", response="1d600426756fae01d64eba6ed429e054", algorithm=MD5 Content-Type: application/sdp Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE Max-Forwards: 70 User-Agent: Yealink SIP-T28P 2.50.23.2 Supported: replaces Expires: 360 Allow-Events: talk,hold,conference,refer,check-sync Content-Length: 296 v=0 o=- 20180 20180 IN IP4 192.168.34.26 s=SDP data c=IN IP4 192.168.34.26 t=0 0 m=audio 11788 RTP/AVP 0 8 18 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:9 G722/8000 a=fmtp:101 0-15 a=rtpmap:101 telephone-event/8000 a=sendrecv <-------------> [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 0 [ 37]: INVITE sip:*118@192.168.34.44 SIP/2.0 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 1 [ 58]: Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK67598327 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 2 [ 52]: From: "Proto" ;tag=2003725197 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 3 [ 28]: To: [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 4 [ 32]: Call-ID: 700466466@192.168.34.26 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 5 [ 14]: CSeq: 2 INVITE [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 6 [ 37]: Contact: [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 7 [159]: Authorization: Digest username="801", realm="DV-IP", nonce="1aca4a98", uri="sip:*118@192.168.34.44", response="1d600426756fae01d64eba6ed429e054", algorithm=MD5 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 8 [ 29]: Content-Type: application/sdp [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 9 [115]: Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 10 [ 16]: Max-Forwards: 70 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 11 [ 38]: User-Agent: Yealink SIP-T28P 2.50.23.2 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 12 [ 19]: Supported: replaces [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 13 [ 12]: Expires: 360 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 14 [ 51]: Allow-Events: talk,hold,conference,refer,check-sync [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 15 [ 19]: Content-Length: 296 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 16 [ 0]: [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 0 [ 3]: v=0 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 1 [ 36]: o=- 20180 20180 IN IP4 192.168.34.26 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 2 [ 10]: s=SDP data [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 3 [ 22]: c=IN IP4 192.168.34.26 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 4 [ 5]: t=0 0 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 5 [ 34]: m=audio 11788 RTP/AVP 0 8 18 9 101 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 7 [ 20]: a=rtpmap:8 PCMA/8000 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 8 [ 21]: a=rtpmap:18 G729/8000 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 9 [ 19]: a=fmtp:18 annexb=no [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 10 [ 20]: a=rtpmap:9 G722/8000 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 11 [ 15]: a=fmtp:101 0-15 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 12 [ 33]: a=rtpmap:101 telephone-event/8000 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Body 13 [ 10]: a=sendrecv [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: --- (16 headers 14 lines) --- [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Looking for Call ID: 700466466@192.168.34.26 (Checking From) --From tag 2003725197 --To-tag [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Found Their Call ID: 700466466@192.168.34.26 Their Tag 2003725197 Our tag: as72d8d598 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Sending to 192.168.34.26 : 5066 (NAT) [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Initializing initreq for method INVITE - callid 700466466@192.168.34.26 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Using INVITE request as basis request - 700466466@192.168.34.26 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found peer '801' for '801' from 192.168.34.26:5066 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Setting NAT on RTP to On [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing session-level SDP o=- 20180 20180 IN IP4 192.168.34.26... UNSUPPORTED. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing session-level SDP s=SDP data... UNSUPPORTED. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing session-level SDP c=IN IP4 192.168.34.26... OK. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED. [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found RTP audio format 0 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found RTP audio format 8 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found RTP audio format 18 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found RTP audio format 9 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found RTP audio format 101 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found audio description format PCMU for ID 0 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found audio description format PCMA for ID 8 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found audio description format G729 for ID 18 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=fmtp:18 annexb=no... UNSUPPORTED. [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found audio description format G722 for ID 9 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED. [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Found audio description format telephone-event for ID 101 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Capabilities: us - 0x10c (ulaw|alaw|g729), peer - audio=0x110c (ulaw|alaw|g729|g722)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x10c (ulaw|alaw|g729) [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Peer audio RTP is at port 192.168.34.26:11788 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: We're settling with these formats: 0x10c (ulaw|alaw|g729) [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Checking SIP call limits for device 801 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Updating call counter for incoming call [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Call from peer '801' is 1 out of 2 [Nov 25 14:34:02] DEBUG[3864] devicestate.c: No provider found, checking channel drivers for SIP - 801 [Nov 25 14:34:02] DEBUG[3864] chan_sip.c: Checking device state for peer 801 [Nov 25 14:34:02] DEBUG[3864] devicestate.c: Changing state for SIP/801 - state 2 (In use) [Nov 25 14:34:02] DEBUG[3864] devicestate.c: device 'SIP/801' state '2' [Nov 25 14:34:02] DEBUG[3865] chan_sip.c: Strict routing enforced for session 2105523894@192.168.34.26 [Nov 25 14:34:02] VERBOSE[3865] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 25 14:34:02] VERBOSE[3865] chan_sip.c: set_destination: set destination to 192.168.34.26, port 5066 [Nov 25 14:34:02] VERBOSE[3865] chan_sip.c: Reliably Transmitting (NAT) to 192.168.34.26:5066: NOTIFY sip:801@192.168.34.26:5066 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK0d9b0daf;rport Max-Forwards: 70 From: ;tag=as4a4dfb34 To: "Proto" ;tag=1252339994 Contact: Call-ID: 2105523894@192.168.34.26 CSeq: 1165 NOTIFY User-Agent: DV-IP Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 206 confirmed --- [Nov 25 14:34:02] DEBUG[3865] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #89104 [Nov 25 14:34:02] DEBUG[3865] chan_sip.c: Trying to put 'NOTIFY sip:' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:02] VERBOSE[3865] chan_sip.c: == Extension Changed 201[hints] new state InUse for Notify User 801 [Nov 25 14:34:02] DEBUG[3882] app_queue.c: Device 'SIP/801' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: Looking for *118 in dv-ip (domain 192.168.34.44) [Nov 25 14:34:02] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:02] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '*118' AND context = 'features' AND priority = '1' [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: *** Our native formats are 0x4 (ulaw) [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: *** Joint capabilities are 0x10c (ulaw|alaw|g729) [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: *** Our capabilities are 0x10c (ulaw|alaw|g729) [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: This channel will not be able to handle video. [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: build_route: Contact hop: [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: list_route: hop: [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: SIP/801-00000003: New call is still down.... Trying... [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: <--- Transmitting (NAT) to 192.168.34.26:5066 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK67598327;received=192.168.34.26 From: "Proto" ;tag=2003725197 To: Call-ID: 700466466@192.168.34.26 CSeq: 2 INVITE Server: DV-IP Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:02] DEBUG[3864] devicestate.c: No provider found, checking channel drivers for SIP - 801 [Nov 25 14:34:02] DEBUG[3864] chan_sip.c: Checking device state for peer 801 [Nov 25 14:34:02] DEBUG[3864] devicestate.c: Changing state for SIP/801 - state 2 (In use) [Nov 25 14:34:02] DEBUG[3864] devicestate.c: device 'SIP/801' state '2' [Nov 25 14:34:02] DEBUG[3882] app_queue.c: Device 'SIP/801' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '*118' AND context = 'features' AND priority = '1' [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '*118' AND context = 'features' AND priority = '1' [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = '*118' AND context = 'features' AND priority = '1' [Nov 25 14:34:02] VERBOSE[4741] pbx_realtime.c: -- Executing Directory("SIP/801-00000003", "dv-ip,dv-ip,b") [Nov 25 14:34:02] VERBOSE[4741] config.c: == Parsing '/etc/asterisk/voicemail.conf': [Nov 25 14:34:02] DEBUG[4741] config.c: Parsing /etc/asterisk/voicemail.conf [Nov 25 14:34:02] VERBOSE[4741] config.c: == Found [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:02] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM voicemail WHERE mailbox LIKE '%' AND context = 'dv-ip' ORDER BY mailbox [Nov 25 14:34:02] VERBOSE[4741] config.c: == Parsing '/etc/asterisk/users.conf': [Nov 25 14:34:02] DEBUG[4741] config.c: Parsing /etc/asterisk/users.conf [Nov 25 14:34:02] VERBOSE[4741] config.c: == Found [Nov 25 14:34:02] DEBUG[3864] devicestate.c: No provider found, checking channel drivers for SIP - 801 [Nov 25 14:34:02] DEBUG[3864] chan_sip.c: Checking device state for peer 801 [Nov 25 14:34:02] DEBUG[3864] devicestate.c: Changing state for SIP/801 - state 2 (In use) [Nov 25 14:34:02] DEBUG[3864] devicestate.c: device 'SIP/801' state '2' [Nov 25 14:34:02] DEBUG[3882] app_queue.c: Device 'SIP/801' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: SIP answering channel: SIP/801-00000003 [Nov 25 14:34:02] DEBUG[4741] rtp.c: Setting the marker bit due to a source update [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: Setting framing from config on incoming call [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: ** Our capability: 0x10c (ulaw|alaw|g729) Video flag: True Text flag: True [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: ** Our prefcodec: 0x0 (nothing) [Nov 25 14:34:02] VERBOSE[4741] chan_sip.c: Audio is at 192.168.34.44 port 10326 [Nov 25 14:34:02] VERBOSE[4741] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK0d9b0daf;rport=5060 From: ;tag=as4a4dfb34 To: "Proto" ;tag=1252339994 Call-ID: 2105523894@192.168.34.26 CSeq: 1165 NOTIFY User-Agent: Yealink SIP-T28P 2.50.23.2 Content-Length: 0 <-------------> [Nov 25 14:34:02] VERBOSE[4741] chan_sip.c: Adding codec 0x8 (alaw) to SDP [Nov 25 14:34:02] VERBOSE[4741] chan_sip.c: Adding codec 0x100 (g729) to SDP [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 25 14:34:02] VERBOSE[4741] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 1 [ 69]: Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK0d9b0daf;rport=5060 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 2 [ 44]: From: ;tag=as4a4dfb34 [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: -- Done with adding codecs to SDP [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 3 [ 50]: To: "Proto" ;tag=1252339994 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 4 [ 33]: Call-ID: 2105523894@192.168.34.26 [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: Done building SDP. Settling with this capability: 0x10c (ulaw|alaw|g729) [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 5 [ 17]: CSeq: 1165 NOTIFY [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 6 [ 38]: User-Agent: Yealink SIP-T28P 2.50.23.2 [Nov 25 14:34:02] VERBOSE[4741] chan_sip.c: <--- Reliably Transmitting (NAT) to 192.168.34.26:5066 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK67598327;received=192.168.34.26 From: "Proto" ;tag=2003725197 To: ;tag=as54f895aa Call-ID: 700466466@192.168.34.26 CSeq: 2 INVITE Server: DV-IP Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 310 v=0 o=root 1675913077 1675913077 IN IP4 192.168.34.44 s=Asterisk PBX 1.6.2.14 c=IN IP4 192.168.34.44 t=0 0 m=audio 10326 RTP/AVP 0 8 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <------------> [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: --- (8 headers 0 lines) --- [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Looking for Call ID: 2105523894@192.168.34.26 (Checking To) --From tag as4a4dfb34 --To-tag 1252339994 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #89106 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:02] DEBUG[4741] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Found Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Acked pending invite 1165 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #89104 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Stopping retransmission on '2105523894@192.168.34.26' of Request 1165: Match Found [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: SIP Response message for INCOMING dialog NOTIFY arrived [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> ACK sip:*118@192.168.34.44 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK1795238382 From: "Proto" ;tag=2003725197 To: ;tag=as54f895aa Call-ID: 700466466@192.168.34.26 CSeq: 2 ACK Contact: Max-Forwards: 70 User-Agent: Yealink SIP-T28P 2.50.23.2 Content-Length: 0 <-------------> [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 0 [ 34]: ACK sip:*118@192.168.34.44 SIP/2.0 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 1 [ 60]: Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK1795238382 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 2 [ 52]: From: "Proto" ;tag=2003725197 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 3 [ 43]: To: ;tag=as54f895aa [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 4 [ 32]: Call-ID: 700466466@192.168.34.26 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 5 [ 11]: CSeq: 2 ACK [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 6 [ 37]: Contact: [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 8 [ 38]: User-Agent: Yealink SIP-T28P 2.50.23.2 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Nov 25 14:34:02] VERBOSE[3868] chan_sip.c: --- (10 headers 0 lines) --- [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Looking for Call ID: 700466466@192.168.34.26 (Checking From) --From tag 2003725197 --To-tag as54f895aa [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: = Found Their Call ID: 700466466@192.168.34.26 Their Tag 2003725197 Our tag: as54f895aa [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #89106 [Nov 25 14:34:02] DEBUG[3868] chan_sip.c: Stopping retransmission on '700466466@192.168.34.26' of Response 2: Match Found [Nov 25 14:34:02] DEBUG[4741] channel.c: Set channel SIP/801-00000003 to write format gsm [Nov 25 14:34:02] DEBUG[4741] rtp.c: Ooh, format changed from unknown to ulaw [Nov 25 14:34:02] DEBUG[4741] rtp.c: Created smoother: format: 4 ms: 20 len: 160 [Nov 25 14:34:02] DEBUG[4741] channel.c: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Nov 25 14:34:02] VERBOSE[4741] file.c: -- Playing 'dir-welcome.gsm' (language 'en') [Nov 25 14:34:04] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.38:5064 ---> <-------------> [Nov 25 14:34:04] DEBUG[3868] chan_sip.c: Header 0 [ 0]: [Nov 25 14:34:05] DEBUG[3868] chan_sip.c: Auto destroying SIP dialog '995760468@192.168.34.38' [Nov 25 14:34:05] DEBUG[3868] chan_sip.c: Destroying SIP dialog 995760468@192.168.34.38 [Nov 25 14:34:05] VERBOSE[3868] chan_sip.c: Really destroying SIP dialog '995760468@192.168.34.38' Method: REGISTER [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> BYE sip:*118@192.168.34.44 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK905821770 From: "Proto" ;tag=2003725197 To: ;tag=as54f895aa Call-ID: 700466466@192.168.34.26 CSeq: 3 BYE Contact: Proxy-Authorization: Digest username="801", realm="DV-IP", nonce="1aca4a98", uri="sip:*118@192.168.34.44", response="1a724fcd9d7df595d74640e5bb69c93d", algorithm=MD5 Max-Forwards: 70 User-Agent: Yealink SIP-T28P 2.50.23.2 Content-Length: 0 <-------------> [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 0 [ 34]: BYE sip:*118@192.168.34.44 SIP/2.0 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 1 [ 59]: Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK905821770 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 2 [ 52]: From: "Proto" ;tag=2003725197 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 3 [ 43]: To: ;tag=as54f895aa [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 4 [ 32]: Call-ID: 700466466@192.168.34.26 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 5 [ 11]: CSeq: 3 BYE [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 6 [ 37]: Contact: [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 7 [165]: Proxy-Authorization: Digest username="801", realm="DV-IP", nonce="1aca4a98", uri="sip:*118@192.168.34.44", response="1a724fcd9d7df595d74640e5bb69c93d", algorithm=MD5 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 9 [ 38]: User-Agent: Yealink SIP-T28P 2.50.23.2 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: --- (11 headers 0 lines) --- [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = Looking for Call ID: 700466466@192.168.34.26 (Checking From) --From tag 2003725197 --To-tag as54f895aa [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = Found Their Call ID: 700466466@192.168.34.26 Their Tag 2003725197 Our tag: as54f895aa [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Initializing initreq for method BYE - callid 700466466@192.168.34.26 [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: Sending to 192.168.34.26 : 5066 (NAT) [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Setting SIP_ALREADYGONE on dialog 700466466@192.168.34.26 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Received bye, issuing owner hangup [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: <--- Transmitting (NAT) to 192.168.34.26:5066 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.34.26:5066;branch=z9hG4bK905821770;received=192.168.34.26 From: "Proto" ;tag=2003725197 To: ;tag=as54f895aa Call-ID: 700466466@192.168.34.26 CSeq: 3 BYE Server: DV-IP Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 <------------> [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:07] DEBUG[4741] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 25 14:34:07] DEBUG[4741] channel.c: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Nov 25 14:34:07] DEBUG[4741] channel.c: Set channel SIP/801-00000003 to write format ulaw [Nov 25 14:34:07] DEBUG[4741] pbx.c: Spawn extension (dv-ip,*118,1) exited non-zero on 'SIP/801-00000003' [Nov 25 14:34:07] VERBOSE[4741] pbx.c: == Spawn extension (dv-ip, *118, 1) exited non-zero on 'SIP/801-00000003' [Nov 25 14:34:07] DEBUG[4741] channel.c: Soft-Hanging up channel 'SIP/801-00000003' [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'features' AND priority = '1' [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'features' AND priority = '1' ORDER BY exten [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'speed-dials' AND priority = '1' [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'speed-dials' AND priority = '1' ORDER BY exten [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'outgoing' AND priority = '1' [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'outgoing' AND priority = '1' ORDER BY exten [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten = 'h' AND context = 'internal' AND priority = '1' [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[4741] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM extensions WHERE exten LIKE '\\_%' AND context = 'internal' AND priority = '1' ORDER BY exten [Nov 25 14:34:07] DEBUG[4741] channel.c: Hanging up channel 'SIP/801-00000003' [Nov 25 14:34:07] DEBUG[4741] chan_sip.c: Hangup call SIP/801-00000003, SIP callid 700466466@192.168.34.26 [Nov 25 14:34:07] DEBUG[4741] chan_sip.c: update_call_counter(801) - decrement call limit counter on hangup [Nov 25 14:34:07] DEBUG[4741] chan_sip.c: Updating call counter for incoming call [Nov 25 14:34:07] DEBUG[4741] chan_sip.c: Call from peer '801' removed from call limit 2 [Nov 25 14:34:07] DEBUG[3864] devicestate.c: No provider found, checking channel drivers for SIP - 801 [Nov 25 14:34:07] DEBUG[3864] chan_sip.c: Checking device state for peer 801 [Nov 25 14:34:07] DEBUG[3864] devicestate.c: Changing state for SIP/801 - state 1 (Not in use) [Nov 25 14:34:07] DEBUG[3864] devicestate.c: device 'SIP/801' state '1' [Nov 25 14:34:07] DEBUG[3865] chan_sip.c: Strict routing enforced for session 2105523894@192.168.34.26 [Nov 25 14:34:07] VERBOSE[3865] chan_sip.c: set_destination: Parsing for address/port to send to [Nov 25 14:34:07] VERBOSE[3865] chan_sip.c: set_destination: set destination to 192.168.34.26, port 5066 [Nov 25 14:34:07] VERBOSE[3865] chan_sip.c: Reliably Transmitting (NAT) to 192.168.34.26:5066: NOTIFY sip:801@192.168.34.26:5066 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK5d70e037;rport Max-Forwards: 70 From: ;tag=as4a4dfb34 To: "Proto" ;tag=1252339994 Contact: Call-ID: 2105523894@192.168.34.26 CSeq: 1166 NOTIFY User-Agent: DV-IP Event: dialog Content-Type: application/dialog-info+xml Subscription-State: active Content-Length: 207 terminated --- [Nov 25 14:34:07] DEBUG[3865] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #89108 [Nov 25 14:34:07] DEBUG[3865] chan_sip.c: Trying to put 'NOTIFY sip:' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:07] VERBOSE[3865] chan_sip.c: == Extension Changed 201[hints] new state Idle for Notify User 801 [Nov 25 14:34:07] DEBUG[3882] app_queue.c: Device 'SIP/801' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 25 14:34:07] DEBUG[4741] cdr_addon_mysql.c: Inserting a CDR record. [Nov 25 14:34:07] DEBUG[4741] cdr_addon_mysql.c: SQL command as follows: INSERT INTO cdr (uniqueid,src,dst,dcontext,clid,channel,lastapp,lastdata,calldate,duration,billsec,disposition,amaflags) VALUES ('DV-IP-1290695642.5','801','*118','dv-ip','\"Agent 201\" <801>','SIP/801-00000003','Directory','dv-ip,dv-ip,b','2010-11-25 14:34:02','5','5','ANSWERED','3') [Nov 25 14:34:07] DEBUG[3864] devicestate.c: No provider found, checking channel drivers for SIP - 801 [Nov 25 14:34:07] DEBUG[3864] chan_sip.c: Checking device state for peer 801 [Nov 25 14:34:07] DEBUG[3864] devicestate.c: Changing state for SIP/801 - state 1 (Not in use) [Nov 25 14:34:07] DEBUG[3864] devicestate.c: device 'SIP/801' state '1' [Nov 25 14:34:07] DEBUG[3882] app_queue.c: Device 'SIP/801' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK5d70e037;rport=5060 From: ;tag=as4a4dfb34 To: "Proto" ;tag=1252339994 Call-ID: 2105523894@192.168.34.26 CSeq: 1166 NOTIFY User-Agent: Yealink SIP-T28P 2.50.23.2 Content-Length: 0 <-------------> [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 1 [ 69]: Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK5d70e037;rport=5060 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 2 [ 44]: From: ;tag=as4a4dfb34 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 3 [ 50]: To: "Proto" ;tag=1252339994 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 4 [ 33]: Call-ID: 2105523894@192.168.34.26 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 5 [ 17]: CSeq: 1166 NOTIFY [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 6 [ 38]: User-Agent: Yealink SIP-T28P 2.50.23.2 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: --- (8 headers 0 lines) --- [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = Looking for Call ID: 2105523894@192.168.34.26 (Checking To) --From tag as4a4dfb34 --To-tag 1252339994 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: = Found Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Acked pending invite 1166 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #89108 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Stopping retransmission on '2105523894@192.168.34.26' of Request 1166: Match Found [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: SIP Response message for INCOMING dialog NOTIFY arrived [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Destroying SIP dialog 700466466@192.168.34.26 [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: Really destroying SIP dialog '700466466@192.168.34.26' Method: BYE [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Allocating new SIP dialog for 01997fd80783dab6227e395330b857bc@127.0.1.1 - OPTIONS (No RTP) [Nov 25 14:34:07] DEBUG[3868] acl.c: Found IP address for this socket [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.34.44:5060 [Nov 25 14:34:07] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' AND host = 'dynamic' [Nov 25 14:34:07] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Connection okay. [Nov 25 14:34:07] DEBUG[3868] res_config_mysql.c: MySQL RealTime: Retrieve SQL: SELECT * FROM sipusers WHERE name = '' [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Initializing initreq for method OPTIONS - callid 1319af045feb1bc51e29231276ec54f0@192.168.34.44 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 0 [ 42]: OPTIONS sip:801@192.168.34.26:5066 SIP/2.0 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 1 [ 64]: Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK446092fd;rport [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 3 [ 54]: From: "DV-IP" ;tag=as3d0b5382 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 4 [ 32]: To: [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 5 [ 34]: Contact: [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 6 [ 55]: Call-ID: 1319af045feb1bc51e29231276ec54f0@192.168.34.44 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 8 [ 17]: User-Agent: DV-IP [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 9 [ 35]: Date: Thu, 25 Nov 2010 14:34:07 GMT [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Nov 25 14:34:07] VERBOSE[3868] chan_sip.c: Reliably Transmitting (NAT) to 192.168.34.26:5066: OPTIONS sip:801@192.168.34.26:5066 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK446092fd;rport Max-Forwards: 70 From: "DV-IP" ;tag=as3d0b5382 To: Contact: Call-ID: 1319af045feb1bc51e29231276ec54f0@192.168.34.44 CSeq: 102 OPTIONS User-Agent: DV-IP Date: Thu, 25 Nov 2010 14:34:07 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #89109 [Nov 25 14:34:07] DEBUG[3868] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:08] DEBUG[3868] chan_sip.c: SIP TIMER: Not rescheduling id #89109:OPTIONS (Method 3) (No timer T1) [Nov 25 14:34:08] VERBOSE[3868] chan_sip.c: Retransmitting #1 (NAT) to 192.168.34.26:5066: OPTIONS sip:801@192.168.34.26:5066 SIP/2.0 Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK446092fd;rport Max-Forwards: 70 From: "DV-IP" ;tag=as3d0b5382 To: Contact: Call-ID: 1319af045feb1bc51e29231276ec54f0@192.168.34.44 CSeq: 102 OPTIONS User-Agent: DV-IP Date: Thu, 25 Nov 2010 14:34:07 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [Nov 25 14:34:08] DEBUG[3868] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.34.26:5066 [Nov 25 14:34:09] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK446092fd;rport=5060 From: "DV-IP" ;tag=as3d0b5382 To: ;tag=635021963 Call-ID: 1319af045feb1bc51e29231276ec54f0@192.168.34.44 CSeq: 102 OPTIONS User-Agent: Yealink SIP-T28P 2.50.23.2 Content-Length: 0 <-------------> [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 1 [ 69]: Via: SIP/2.0/UDP 192.168.34.44:5060;branch=z9hG4bK446092fd;rport=5060 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 2 [ 54]: From: "DV-IP" ;tag=as3d0b5382 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 3 [ 46]: To: ;tag=635021963 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 4 [ 55]: Call-ID: 1319af045feb1bc51e29231276ec54f0@192.168.34.44 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 6 [ 38]: User-Agent: Yealink SIP-T28P 2.50.23.2 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Nov 25 14:34:09] VERBOSE[3868] chan_sip.c: --- (8 headers 0 lines) --- [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = Looking for Call ID: 1319af045feb1bc51e29231276ec54f0@192.168.34.44 (Checking To) --From tag as3d0b5382 --To-tag 635021963 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 680790282@192.168.34.26 Their Tag 1127307980 Our tag: as4cdbb2e5 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 315505223@192.168.34.26 Their Tag 475292003 Our tag: as4a31d7f1 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 549312216@192.168.34.26 Their Tag 430033397 Our tag: as542a1457 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 1722193660@192.168.34.26 Their Tag 985848730 Our tag: as0b5a95c9 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 2105523894@192.168.34.26 Their Tag 1252339994 Our tag: as4a4dfb34 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 93354336@192.168.34.26 Their Tag 813326708 Our tag: as4aec998f [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 991581528@192.168.34.26 Their Tag 1215546991 Our tag: as22e803a7 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 589808368@192.168.34.26 Their Tag 1398766311 Our tag: as3fb202c1 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 424076244@192.168.34.26 Their Tag 1025282175 Our tag: as57268c03 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = No match Their Call ID: 693247599@192.168.34.26 Their Tag 1856898716 Our tag: as1c31e7af [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: = Found Their Call ID: 1319af045feb1bc51e29231276ec54f0@192.168.34.44 Their Tag Our tag: as3d0b5382 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #89109 [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Stopping retransmission on '1319af045feb1bc51e29231276ec54f0@192.168.34.44' of Request 102: Match Found [Nov 25 14:34:09] DEBUG[3868] chan_sip.c: Destroying SIP dialog 1319af045feb1bc51e29231276ec54f0@192.168.34.44 [Nov 25 14:34:09] VERBOSE[3868] chan_sip.c: Really destroying SIP dialog '1319af045feb1bc51e29231276ec54f0@192.168.34.44' Method: OPTIONS [Nov 25 14:34:18] VERBOSE[3868] chan_sip.c: <--- SIP read from UDP:192.168.34.26:5066 ---> <-------------> [Nov 25 14:34:18] DEBUG[3868] chan_sip.c: Header 0 [ 0]: