[Jan 29 13:41:35] == DTLS ECDH initialized (automatic), faster PFS enabled [Jan 29 13:41:36] == DTLS ECDH initialized (automatic), faster PFS enabled [Jan 29 13:41:36] == Using SIP VIDEO CoS mark 6 [Jan 29 13:41:36] == Using SIP RTP CoS mark 5 [Jan 29 13:41:36] == Using UDPTL CoS mark 5 [Jan 29 13:41:36] -- Executing [1005@administrativos:1] GotoIf("SIP/1099-00000039", "0?67:2") in new stack [Jan 29 13:41:36] -- Goto (administrativos,1005,2) [Jan 29 13:41:36] -- Executing [1005@administrativos:2] Set("SIP/1099-00000039", "CALLERID(name)=Integra") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:3] Set("SIP/1099-00000039", "CHANNEL(Language)=en") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:4] Set("SIP/1099-00000039", "DIALED=1005") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:5] Set("SIP/1099-00000039", "desagent=Ramiro") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:6] Set("SIP/1099-00000039", "event=UNPAUSE") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:7] GotoIf("SIP/1099-00000039", "0?61:8") in new stack [Jan 29 13:41:36] -- Goto (administrativos,1005,8) [Jan 29 13:41:36] -- Executing [1005@administrativos:8] Set("SIP/1099-00000039", "DSVT-ON=0") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:9] GotoIf("SIP/1099-00000039", "0?58:10") in new stack [Jan 29 13:41:36] -- Goto (administrativos,1005,10) [Jan 29 13:41:36] -- Executing [1005@administrativos:10] Set("SIP/1099-00000039", "DND-ON=") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:11] GotoIf("SIP/1099-00000039", "0?55:12") in new stack [Jan 29 13:41:36] -- Goto (administrativos,1005,12) [Jan 29 13:41:36] -- Executing [1005@administrativos:12] Set("SIP/1099-00000039", "VM-ON=TRUE") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:13] GUID("SIP/1099-00000039", "__guid") in new stack [Jan 29 13:41:36] -- app_guidgen: new guid 677fae94-6dbc-46cb-91f8-47221219f1f3 [Jan 29 13:41:36] -- Executing [1005@administrativos:14] Set("SIP/1099-00000039", "CDR(guid)=677fae94-6dbc-46cb-91f8-47221219f1f3") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:15] Set("SIP/1099-00000039", "__Ani=1005") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:16] Set("SIP/1099-00000039", "CDR(campaign)=INTERNOS->") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:17] Set("SIP/1099-00000039", "rec-var-to=FALSE") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:18] Set("SIP/1099-00000039", "rec-var-from=FALSE") in new stack [Jan 29 13:41:36] -- Executing [1005@administrativos:19] GotoIf("SIP/1099-00000039", "0?52:20") in new stack [Jan 29 13:41:36] -- Goto (administrativos,1005,20) [Jan 29 13:41:36] -- Executing [1005@administrativos:20] Dial("SIP/1099-00000039", "SIP/1005,30,tTKk,") in new stack [Jan 29 13:41:36] == DTLS ECDH initialized (automatic), faster PFS enabled [Jan 29 13:41:36] == DTLS ECDH initialized (automatic), faster PFS enabled [Jan 29 13:41:37] == Using SIP VIDEO CoS mark 6 [Jan 29 13:41:37] == Using SIP RTP CoS mark 5 [Jan 29 13:41:37] Audio is at 13088 [Jan 29 13:41:37] Adding codec alaw to SDP [Jan 29 13:41:37] Adding codec ulaw to SDP [Jan 29 13:41:37] Adding non-codec 0x1 (telephone-event) to SDP [Jan 29 13:41:37] Reliably Transmitting (NAT) to 192.168.99.146:58109: [Jan 29 13:41:37] INVITE sip:nf93fjiv@62tfevecab0d.invalid;transport=ws SIP/2.0 [Jan 29 13:41:37] Via: SIP/2.0/WS 192.168.99.254:5060;branch=z9hG4bK2209b900;rport [Jan 29 13:41:37] Max-Forwards: 70 [Jan 29 13:41:37] From: "Integra" ;tag=as7af5a97d [Jan 29 13:41:37] To: [Jan 29 13:41:37] Contact: [Jan 29 13:41:37] Call-ID: 69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060 [Jan 29 13:41:37] CSeq: 102 INVITE [Jan 29 13:41:37] User-Agent: integraccs [Jan 29 13:41:37] Date: Mon, 29 Jan 2018 16:41:37 GMT [Jan 29 13:41:37] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 29 13:41:37] Supported: replaces, timer [Jan 29 13:41:37] Content-Type: application/sdp [Jan 29 13:41:37] Content-Length: 874 [Jan 29 13:41:37] [Jan 29 13:41:37] v=0 [Jan 29 13:41:37] o=root 1878467259 1878467259 IN IP4 192.168.99.254 [Jan 29 13:41:37] s=Asterisk PBX 13.19.0 [Jan 29 13:41:37] c=IN IP4 192.168.99.254 [Jan 29 13:41:37] t=0 0 [Jan 29 13:41:37] m=audio 13088 RTP/SAVPF 8 0 101 [Jan 29 13:41:37] a=rtpmap:8 PCMA/8000 [Jan 29 13:41:37] a=rtpmap:0 PCMU/8000 [Jan 29 13:41:37] a=rtpmap:101 telephone-event/8000 [Jan 29 13:41:37] a=fmtp:101 0-16 [Jan 29 13:41:37] a=maxptime:150 [Jan 29 13:41:37] a=ice-ufrag:2d195d8a1a87978a2ea1a32f50483418 [Jan 29 13:41:37] a=ice-pwd:54137ab10276232906676bc223dc512d [Jan 29 13:41:37] a=candidate:Hc0a863fe 1 UDP 2130706431 192.168.99.254 13088 typ host [Jan 29 13:41:37] a=candidate:Sba32d49f 1 UDP 1694498815 186.50.212.159 13088 typ srflx raddr 192.168.99.254 rport 13088 [Jan 29 13:41:37] a=candidate:Hc0a863fe 2 UDP 2130706430 192.168.99.254 13089 typ host [Jan 29 13:41:37] a=candidate:Sba32d49f 2 UDP 1694498814 186.50.212.159 13089 typ srflx raddr 192.168.99.254 rport 13089 [Jan 29 13:41:37] a=connection:new [Jan 29 13:41:37] a=setup:actpass [Jan 29 13:41:37] a=fingerprint:SHA-256 C3:35:29:5F:00:47:BD:06:EE:99:B0:DC:8F:51:5A:0B:01:09:B0:4B:25:73:95:51:A7:46:3F:0E:FF:B7:CF:22 [Jan 29 13:41:37] a=rtcp-mux [Jan 29 13:41:37] a=sendrecv [Jan 29 13:41:37] [Jan 29 13:41:37] --- [Jan 29 13:41:37] -- Called SIP/1005 [Jan 29 13:41:37] [Jan 29 13:41:37] <--- SIP read from WS:192.168.99.146:58109 ---> [Jan 29 13:41:37] SIP/2.0 100 Trying [Jan 29 13:41:37] Via: SIP/2.0/WS 192.168.99.254:5060;branch=z9hG4bK2209b900;rport [Jan 29 13:41:37] To: [Jan 29 13:41:37] From: "Integra" ;tag=as7af5a97d [Jan 29 13:41:37] Call-ID: 69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060 [Jan 29 13:41:37] CSeq: 102 INVITE [Jan 29 13:41:37] Supported: ice,replaces,outbound [Jan 29 13:41:37] Content-Length: 0 [Jan 29 13:41:37] [Jan 29 13:41:37] <-------------> [Jan 29 13:41:37] --- (8 headers 0 lines) --- [Jan 29 13:41:37] [Jan 29 13:41:37] <--- SIP read from WS:192.168.99.146:58109 ---> [Jan 29 13:41:37] SIP/2.0 180 Ringing [Jan 29 13:41:37] Via: SIP/2.0/WS 192.168.99.254:5060;branch=z9hG4bK2209b900;rport [Jan 29 13:41:37] To: ;tag=su4uj4n5tl [Jan 29 13:41:37] From: "Integra" ;tag=as7af5a97d [Jan 29 13:41:37] Call-ID: 69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060 [Jan 29 13:41:37] CSeq: 102 INVITE [Jan 29 13:41:37] Contact: [Jan 29 13:41:37] Supported: ice,replaces,outbound [Jan 29 13:41:37] Content-Length: 0 [Jan 29 13:41:37] [Jan 29 13:41:37] <-------------> [Jan 29 13:41:37] --- (9 headers 0 lines) --- [Jan 29 13:41:37] sip_route_dump: route/path hop: [Jan 29 13:41:37] -- SIP/1005-0000003a is ringing [Jan 29 13:41:40] [Jan 29 13:41:40] <--- SIP read from WS:192.168.99.146:58109 ---> [Jan 29 13:41:40] SIP/2.0 200 OK [Jan 29 13:41:40] Via: SIP/2.0/WS 192.168.99.254:5060;branch=z9hG4bK2209b900;rport [Jan 29 13:41:40] To: ;tag=su4uj4n5tl [Jan 29 13:41:40] From: "Integra" ;tag=as7af5a97d [Jan 29 13:41:40] Call-ID: 69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060 [Jan 29 13:41:40] CSeq: 102 INVITE [Jan 29 13:41:40] Contact: [Jan 29 13:41:40] Supported: ice,replaces,outbound [Jan 29 13:41:40] Content-Type: application/sdp [Jan 29 13:41:40] Content-Length: 1044 [Jan 29 13:41:40] [Jan 29 13:41:40] v=0 [Jan 29 13:41:40] o=- 4130668019977053925 2 IN IP4 127.0.0.1 [Jan 29 13:41:40] s=- [Jan 29 13:41:40] t=0 0 [Jan 29 13:41:40] a=msid-semantic: WMS wkSZZOvTSFzB9bD6NfTer5gfipkxpDJotoCC [Jan 29 13:41:40] m=audio 61802 RTP/SAVPF 8 0 101 [Jan 29 13:41:40] c=IN IP4 192.168.99.146 [Jan 29 13:41:40] a=rtcp:9 IN IP4 0.0.0.0 [Jan 29 13:41:40] a=candidate:3654509170 1 udp 2122255103 2001::5ef5:79fd:3c2f:2cc5:45cd:2b60 61801 typ host generation 0 network-id 2 network-cost 50 [Jan 29 13:41:40] a=candidate:1057163414 1 udp 2122194687 192.168.99.146 61802 typ host generation 0 network-id 1 network-cost 10 [Jan 29 13:41:40] a=ice-ufrag:xVIt [Jan 29 13:41:40] a=ice-pwd:Jptq8Fcu5rIN1TWJcyQKMljv [Jan 29 13:41:40] a=ice-options:trickle [Jan 29 13:41:40] a=fingerprint:sha-256 B9:11:EE:DF:B1:A2:5A:20:00:C8:5C:C0:5B:39:3E:BD:9D:A1:C8:A3:CC:F5:00:90:E6:78:C5:9B:EC:94:3F:9A [Jan 29 13:41:40] a=setup:active [Jan 29 13:41:40] a=mid:audio [Jan 29 13:41:40] a=sendrecv [Jan 29 13:41:40] a=rtcp-mux [Jan 29 13:41:40] a=rtpmap:8 PCMA/8000 [Jan 29 13:41:40] a=rtpmap:0 PCMU/8000 [Jan 29 13:41:40] a=rtpmap:101 telephone-event/8000 [Jan 29 13:41:40] a=ssrc:2975205058 cname:werUyvlPpWsMy6M3 [Jan 29 13:41:40] a=ssrc:2975205058 msid:wkSZZOvTSFzB9bD6NfTer5gfipkxpDJotoCC f33b36b7-0d39-40ab-8526-fd409d975f95 [Jan 29 13:41:40] a=ssrc:2975205058 mslabel:wkSZZOvTSFzB9bD6NfTer5gfipkxpDJotoCC [Jan 29 13:41:40] a=ssrc:2975205058 label:f33b36b7-0d39-40ab-8526-fd409d975f95 [Jan 29 13:41:40] <-------------> [Jan 29 13:41:40] --- (10 headers 25 lines) --- [Jan 29 13:41:40] == Using UDPTL CoS mark 5 [Jan 29 13:41:40] Found RTP audio format 8 [Jan 29 13:41:40] Found RTP audio format 0 [Jan 29 13:41:40] Found RTP audio format 101 [Jan 29 13:41:40] Found audio description format PCMA for ID 8 [Jan 29 13:41:40] Found audio description format PCMU for ID 0 [Jan 29 13:41:40] Found audio description format telephone-event for ID 101 [Jan 29 13:41:40] Capabilities: us - (alaw|ulaw|vp8), peer - audio=(ulaw|alaw)/video=(nothing)/text=(nothing), combined - (alaw|ulaw) [Jan 29 13:41:40] Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Jan 29 13:41:40] Peer audio RTP is at port 192.168.99.146:61802 [Jan 29 13:41:40] sip_route_dump: route/path hop: [Jan 29 13:41:40] Transmitting (NAT) to 192.168.99.146:58109: [Jan 29 13:41:40] ACK sip:nf93fjiv@62tfevecab0d.invalid;transport=ws SIP/2.0 [Jan 29 13:41:40] Via: SIP/2.0/WS 192.168.99.254:5060;branch=z9hG4bK062cb162;rport [Jan 29 13:41:40] Max-Forwards: 70 [Jan 29 13:41:40] From: "Integra" ;tag=as7af5a97d [Jan 29 13:41:40] To: ;tag=su4uj4n5tl [Jan 29 13:41:40] Contact: [Jan 29 13:41:40] Call-ID: 69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060 [Jan 29 13:41:40] CSeq: 102 ACK [Jan 29 13:41:40] User-Agent: integraccs [Jan 29 13:41:40] Content-Length: 0 [Jan 29 13:41:40] [Jan 29 13:41:40] [Jan 29 13:41:40] --- [Jan 29 13:41:40] -- SIP/1005-0000003a answered SIP/1099-00000039 [Jan 29 13:41:40] -- Channel SIP/1005-0000003a joined 'simple_bridge' basic-bridge [Jan 29 13:41:40] -- Channel SIP/1099-00000039 joined 'simple_bridge' basic-bridge [Jan 29 13:41:40] WARNING[27098][C-00000029]: chan_iax2.c:1239 jb_warning_output: Resyncing the jb. last_delay 0, this delay -412999492, threshold 1000, new offset 412999492 [Jan 29 13:41:40] WARNING[27101][C-00000029]: chan_iax2.c:1239 jb_warning_output: Resyncing the jb. last_delay 0, this delay -156542384, threshold 1000, new offset 156542384 [Jan 29 13:41:41] Reliably Transmitting (NAT) to 192.168.99.146:58109: [Jan 29 13:41:41] OPTIONS sip:nf93fjiv@62tfevecab0d.invalid;transport=ws SIP/2.0 [Jan 29 13:41:41] Via: SIP/2.0/WS 192.168.99.254:5060;branch=z9hG4bK034de43c;rport [Jan 29 13:41:41] Max-Forwards: 70 [Jan 29 13:41:41] From: "uContact" ;tag=as47f4f9bc [Jan 29 13:41:41] To: [Jan 29 13:41:41] Contact: [Jan 29 13:41:41] Call-ID: 6def51000308d86a257780c866391084@192.168.99.254:5060 [Jan 29 13:41:41] CSeq: 102 OPTIONS [Jan 29 13:41:41] User-Agent: integraccs [Jan 29 13:41:41] Date: Mon, 29 Jan 2018 16:41:41 GMT [Jan 29 13:41:41] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 29 13:41:41] Supported: replaces, timer [Jan 29 13:41:41] Content-Length: 0 [Jan 29 13:41:41] [Jan 29 13:41:41] [Jan 29 13:41:41] --- [Jan 29 13:41:41] [Jan 29 13:41:41] <--- SIP read from WS:192.168.99.146:58109 ---> [Jan 29 13:41:41] SIP/2.0 200 OK [Jan 29 13:41:41] Via: SIP/2.0/WS 192.168.99.254:5060;branch=z9hG4bK034de43c;rport [Jan 29 13:41:41] To: ;tag=nebk9kmb7d [Jan 29 13:41:41] From: "uContact" ;tag=as47f4f9bc [Jan 29 13:41:41] Call-ID: 6def51000308d86a257780c866391084@192.168.99.254:5060 [Jan 29 13:41:41] CSeq: 102 OPTIONS [Jan 29 13:41:41] Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER,INFO [Jan 29 13:41:41] Accept: application/sdp, application/dtmf-relay [Jan 29 13:41:41] Supported: outbound [Jan 29 13:41:41] Content-Length: 0 [Jan 29 13:41:41] [Jan 29 13:41:41] <-------------> [Jan 29 13:41:41] --- (10 headers 0 lines) --- [Jan 29 13:41:42] Really destroying SIP dialog '6def51000308d86a257780c866391084@192.168.99.254:5060' Method: OPTIONS [Jan 29 13:41:51] [Jan 29 13:41:51] <--- SIP read from WS:192.168.99.146:58109 ---> [Jan 29 13:41:51] BYE sip:1099@192.168.99.254:5060;transport=ws SIP/2.0 [Jan 29 13:41:51] Via: SIP/2.0/WSS 62tfevecab0d.invalid;branch=z9hG4bK6247139 [Jan 29 13:41:51] Max-Forwards: 69 [Jan 29 13:41:51] To: ;tag=as7af5a97d [Jan 29 13:41:51] From: ;tag=su4uj4n5tl [Jan 29 13:41:51] Call-ID: 69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060 [Jan 29 13:41:51] CSeq: 4170 BYE [Jan 29 13:41:51] Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER,INFO [Jan 29 13:41:51] Supported: outbound [Jan 29 13:41:51] User-Agent: JsSIP 3.2.4 [Jan 29 13:41:51] Content-Length: 0 [Jan 29 13:41:51] [Jan 29 13:41:51] <-------------> [Jan 29 13:41:51] --- (11 headers 0 lines) --- [Jan 29 13:41:51] -- Channel SIP/1005-0000003a left 'simple_bridge' basic-bridge [Jan 29 13:41:51] -- Channel SIP/1099-00000039 left 'simple_bridge' basic-bridge [Jan 29 13:41:51] Scheduling destruction of SIP dialog '69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060' in 6400 ms (Method: BYE) [Jan 29 13:41:51] [Jan 29 13:41:51] <--- Transmitting (NAT) to 192.168.99.146:58109 ---> [Jan 29 13:41:51] SIP/2.0 200 OK [Jan 29 13:41:51] Via: SIP/2.0/WSS 62tfevecab0d.invalid;branch=z9hG4bK6247139;received=192.168.99.146;rport=58109 [Jan 29 13:41:51] From: ;tag=su4uj4n5tl [Jan 29 13:41:51] To: ;tag=as7af5a97d [Jan 29 13:41:51] Call-ID: 69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060 [Jan 29 13:41:51] CSeq: 4170 BYE [Jan 29 13:41:51] Server: integraccs [Jan 29 13:41:51] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Jan 29 13:41:51] Supported: replaces, timer [Jan 29 13:41:51] Content-Length: 0 [Jan 29 13:41:51] [Jan 29 13:41:51] [Jan 29 13:41:51] <------------> [Jan 29 13:41:51] == Spawn extension (administrativos, 1005, 20) exited non-zero on 'SIP/1099-00000039' [Jan 29 13:41:51] -- Executing [h@administrativos:1] Set("SIP/1099-00000039", "HASH(rates)=") in new stack [Jan 29 13:41:51] -- Executing [h@administrativos:2] Set("SIP/1099-00000039", "talkedminutes=0.183333") in new stack [Jan 29 13:41:51] -- Executing [h@administrativos:3] Set("SIP/1099-00000039", "talkedminutes=1") in new stack [Jan 29 13:41:51] WARNING[27098][C-00000029]: func_math.c:269 math: ' ' is not a valid number [Jan 29 13:41:51] -- Executing [h@administrativos:4] Set("SIP/1099-00000039", "chargedbalance=") in new stack [Jan 29 13:41:51] WARNING[27098][C-00000029]: func_math.c:269 math: ' ' is not a valid number [Jan 29 13:41:51] -- Executing [h@administrativos:5] Set("SIP/1099-00000039", "realbalance=") in new stack [Jan 29 13:41:51] -- Executing [h@administrativos:6] Set("SIP/1099-00000039", "CDR(charged_balance)=") in new stack [Jan 29 13:41:51] -- Executing [h@administrativos:7] Set("SIP/1099-00000039", "CDR(real_balance)=") in new stack [Jan 29 13:41:51] -- Executing [h@administrativos:8] Set("SIP/1099-00000039", "CDR(note)=") in new stack [Jan 29 13:41:51] -- Executing [h@administrativos:9] Set("SIP/1099-00000039", "CDR(carrier)=") in new stack [Jan 29 13:41:51] -- Executing [h@administrativos:10] QueueUpdate("SIP/1099-00000039", ",1517244096.95,,ANSWER,11,15|1005||") in new stack [Jan 29 13:41:51] WARNING[27098][C-00000029]: app_queue.c:10628 qupd_exec: Missing argument to UpdateQueue (queuename,uniqueid,membername,status,talktime,params[totaltime|callednumber]) [Jan 29 13:41:51] == Spawn extension (administrativos, h, 10) exited non-zero on 'SIP/1099-00000039' [Jan 29 13:41:51] Really destroying SIP dialog '69a8d3dd0843ef807932647530b13f37@192.168.99.254:5060' Method: BYE