Jun 5 20:48:39 whistle1 freeswitch[998]: recv 584 bytes from udp/[98.254.245.79]:29712 at 20:48:39.620806: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: REGISTER sip:whistle1.vinixtech.com SIP/2.0 Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-8ceec0207eecf032-1---d8754z-;rport Jun 5 20:48:39 whistle1 freeswitch[998]: Max-Forwards: 70 Jun 5 20:48:39 whistle1 freeswitch[998]: Contact: Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 1 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: Expires: 3600 Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: X-Lite Beta release 4.0 v3 stamp 55153 Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.615424 [WARNING] sofia_reg.c:1241 SIP auth challenge (REGISTER) on sofia profile 'sipinterface_1' for [xlite_1@whistle1.vinixtech.com] from ip 98.254.245.79 Jun 5 20:48:39 whistle1 freeswitch[998]: send 663 bytes to udp/[98.254.245.79]:29712 at 20:48:39.622444: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: SIP/2.0 401 Unauthorized Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-8ceec0207eecf032-1---d8754z-;rport=29712;received=98.254.245.79 Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" ;tag=e9K75Hy2ytStS Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 1 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: sip.2600hz.com Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Jun 5 20:48:39 whistle1 freeswitch[998]: Supported: precondition, path, replaces Jun 5 20:48:39 whistle1 freeswitch[998]: WWW-Authenticate: Digest realm="whistle1.vinixtech.com", nonce="8505e9af-aaea-4be9-80d4-0482104cdd2b", algorithm=MD5, qop="auth" Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: recv 856 bytes from udp/[98.254.245.79]:29712 at 20:48:39.702923: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: REGISTER sip:whistle1.vinixtech.com SIP/2.0 Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-7f19f93d27a11948-1---d8754z-;rport Jun 5 20:48:39 whistle1 freeswitch[998]: Max-Forwards: 70 Jun 5 20:48:39 whistle1 freeswitch[998]: Contact: Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 2 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: Expires: 3600 Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: X-Lite Beta release 4.0 v3 stamp 55153 Jun 5 20:48:39 whistle1 freeswitch[998]: Authorization: Digest username="xlite_1",realm="whistle1.vinixtech.com",nonce="8505e9af-aaea-4be9-80d4-0482104cdd2b",uri="sip:whistle1.vinixtech.com",response="55c181fcc6e8e9db7ab8169809834ceb",cnonce="64129a403055e1b5f8d19b6683738366",nc=00000001,qop=auth,algorithm=MD5 Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FETCH_USER(<0.200.0>): fetch directory: Id: <<"4e22063e-3978-4fb7-b8b8-670e64f4a5d7">> Lookup <0.7019.0> (Number 106) Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.697363 [DEBUG] mod_erlang_event.c:380 looking for bindings Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.697363 [DEBUG] mod_erlang_event.c:395 binding for domain in section directory with key name and value whistle1.vinixtech.com requested from node ecallmgr@whistle1.vinixtech.com Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SRV(<0.8564.3>): handle msg {"Auth-Domain":"whistle1.vinixtech.com","Auth-User":"xlite_1","Orig-IP":"98.254.245.79","From":"xlite_1@whistle1.vinixtech.com","To":"xlite_1@whistle1.vinixtech.com","Msg-ID":"4e22063e-3978-4fb7-b8b8-670e64f4a5d7","App-Version":"0.5.0","App-Name":"ecallmgr.auth","Event-Name":"auth_req","Event-Category":"directory","Server-ID":"amq.gen-6Q129eIA2lCKWJhJCiu/ZQ=="} Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: WORKER-F(<0.114.0>): Working for <0.7019.0> and sent {"Auth-Domain":"whistle1.vinixtech.com","Auth-User":"xlite_1","Orig-IP":"98.254.245.79","From":"xlite_1@whistle1.vinixtech.com","To":"xlite_1@whistle1.vinixtech.com","Msg-ID":"4e22063e-3978-4fb7-b8b8-670e64f4a5d7","App-Version":"0.5.0","App-Name":"ecallmgr.auth","Event-Name":"auth_req","Event-Category":"directory","Server-ID":"amq.gen-6Q129eIA2lCKWJhJCiu/ZQ=="} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG.auth: Looking up <<"xlite_1">> @ <<"whistle1.vinixtech.com">> Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: AuthInfo: {struct,[{<<"realm">>,<<"whistle1.vinixtech.com">>}, {<<"method">>,<<"password">>}, {<<"username">>,<<"xlite_1">>}, {<<"password">>,<<"Iq150l8a!">>}, {<<"invite_format">>,<<"username">>}, {<<"authorizing_id">>, <<"cbbacd85bf6fcf891220ac6d16005392">>}]} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SERVE(<0.8564.3>): Paylowd to amq.gen-6Q129eIA2lCKWJhJCiu/ZQ==: {"Custom-Channel-Vars":{"Direction":"outbound","Username":"xlite_1","Realm":"whistle1.vinixtech.com","Authorizing-ID":"cbbacd85bf6fcf891220ac6d16005392"},"Auth-Password":"Iq150l8a!","Auth-Method":"password","Msg-ID":"4e22063e-3978-4fb7-b8b8-670e64f4a5d7","Tenant-ID":"ignore","Access-Group":"ignore","App-Version":"0.4.2","App-Name":"registrar","Event-Name":"auth_resp","Event-Category":"directory","Server-ID":"registrar.queue"} Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: WORKER-B(<0.114.0>): Recv payload response (9 ms) Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.717636 [DEBUG] handle_msg.c:190 Got reply for 4e22063e-3978-4fb7-b8b8-670e64f4a5d7 Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.717636 [DEBUG] mod_erlang_event.c:466 got data
after 20 milliseconds from ecallmgr@whistle1.vinixtech.com for 4e22063e-3978-4fb7-b8b8-670e64f4a5d7! Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.717636 [DEBUG] mod_erlang_event.c:473 XML parsed OK! Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FS_AUTH(<0.200.0>): Lookup <0.7019.0> down Jun 5 20:48:39 whistle1 freeswitch[998]: send 644 bytes to udp/[98.254.245.79]:29712 at 20:48:39.721933: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: SIP/2.0 200 OK Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-7f19f93d27a11948-1---d8754z-;rport=29712;received=98.254.245.79 Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" ;tag=FjD07cF6U3FDN Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 2 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: Contact: ;expires=3600 Jun 5 20:48:39 whistle1 freeswitch[998]: Date: Sun, 05 Jun 2011 20:48:39 GMT Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: sip.2600hz.com Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Jun 5 20:48:39 whistle1 freeswitch[998]: Supported: precondition, path, replaces Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: recv 851 bytes from udp/[98.254.245.79]:29712 at 20:48:39.800644: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: REGISTER sip:whistle1.vinixtech.com SIP/2.0 Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-c5bcde36bd376d5b-1---d8754z-;rport Jun 5 20:48:39 whistle1 freeswitch[998]: Max-Forwards: 70 Jun 5 20:48:39 whistle1 freeswitch[998]: Contact: ;expires=0 Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 3 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: X-Lite Beta release 4.0 v3 stamp 55153 Jun 5 20:48:39 whistle1 freeswitch[998]: Authorization: Digest username="xlite_1",realm="whistle1.vinixtech.com",nonce="8505e9af-aaea-4be9-80d4-0482104cdd2b",uri="sip:whistle1.vinixtech.com",response="4029abf1b66b7cde99dd0d36c78dd8c2",cnonce="dccbaed1392fd43103cf22cf1e8aca75",nc=00000002,qop=auth,algorithm=MD5 Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.798776 [DEBUG] mod_erlang_event.c:380 looking for bindings Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FETCH_USER(<0.200.0>): fetch directory: Id: <<"d8179c02-4385-4e14-b8d3-db5b90d158f0">> Lookup <0.7022.0> (Number 107) Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.798776 [DEBUG] mod_erlang_event.c:395 binding for domain in section directory with key name and value whistle1.vinixtech.com requested from node ecallmgr@whistle1.vinixtech.com Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SRV(<0.8568.3>): handle msg {"Auth-Domain":"whistle1.vinixtech.com","Auth-User":"xlite_1","Orig-IP":"98.254.245.79","From":"xlite_1@whistle1.vinixtech.com","To":"xlite_1@whistle1.vinixtech.com","Msg-ID":"d8179c02-4385-4e14-b8d3-db5b90d158f0","App-Version":"0.5.0","App-Name":"ecallmgr.auth","Event-Name":"auth_req","Event-Category":"directory","Server-ID":"amq.gen-DPfmONJfChByX/O0CZmrjw=="} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG.auth: Looking up <<"xlite_1">> @ <<"whistle1.vinixtech.com">> Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: WORKER-F(<0.113.0>): Working for <0.7022.0> and sent {"Auth-Domain":"whistle1.vinixtech.com","Auth-User":"xlite_1","Orig-IP":"98.254.245.79","From":"xlite_1@whistle1.vinixtech.com","To":"xlite_1@whistle1.vinixtech.com","Msg-ID":"d8179c02-4385-4e14-b8d3-db5b90d158f0","App-Version":"0.5.0","App-Name":"ecallmgr.auth","Event-Name":"auth_req","Event-Category":"directory","Server-ID":"amq.gen-DPfmONJfChByX/O0CZmrjw=="} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: AuthInfo: {struct,[{<<"realm">>,<<"whistle1.vinixtech.com">>}, {<<"method">>,<<"password">>}, {<<"username">>,<<"xlite_1">>}, {<<"password">>,<<"Iq150l8a!">>}, {<<"invite_format">>,<<"username">>}, {<<"authorizing_id">>, <<"cbbacd85bf6fcf891220ac6d16005392">>}]} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SERVE(<0.8568.3>): Paylowd to amq.gen-DPfmONJfChByX/O0CZmrjw==: {"Custom-Channel-Vars":{"Direction":"outbound","Username":"xlite_1","Realm":"whistle1.vinixtech.com","Authorizing-ID":"cbbacd85bf6fcf891220ac6d16005392"},"Auth-Password":"Iq150l8a!","Auth-Method":"password","Msg-ID":"d8179c02-4385-4e14-b8d3-db5b90d158f0","Tenant-ID":"ignore","Access-Group":"ignore","App-Version":"0.4.2","App-Name":"registrar","Event-Name":"auth_resp","Event-Category":"directory","Server-ID":"registrar.queue"} Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: WORKER-B(<0.113.0>): Recv payload response (7 ms) Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.798776 [DEBUG] handle_msg.c:190 Got reply for d8179c02-4385-4e14-b8d3-db5b90d158f0 Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.798776 [DEBUG] mod_erlang_event.c:466 got data
after 0 milliseconds from ecallmgr@whistle1.vinixtech.com for d8179c02-4385-4e14-b8d3-db5b90d158f0! Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.798776 [DEBUG] mod_erlang_event.c:473 XML parsed OK! Jun 5 20:48:39 whistle1 freeswitch[998]: send 641 bytes to udp/[98.254.245.79]:29712 at 20:48:39.816079: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: SIP/2.0 200 OK Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-c5bcde36bd376d5b-1---d8754z-;rport=29712;received=98.254.245.79 Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" ;tag=gU6r97Z9rc6Zg Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 3 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: Contact: ;expires=0 Jun 5 20:48:39 whistle1 freeswitch[998]: Date: Sun, 05 Jun 2011 20:48:39 GMT Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FS_AUTH(<0.200.0>): Lookup <0.7022.0> down Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: sip.2600hz.com Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Jun 5 20:48:39 whistle1 freeswitch[998]: Supported: precondition, path, replaces Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FS_NODE(<0.202.0>): Evt: <<"CUSTOM">> Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FS_NODE.p_reg_evt(<0.7025.0>): {"FreeSWITCH-Hostname":"whistle1.vinixtech.com","Presence-Hosts":"whistle1.vinixtech.com","Profile-Name":"sipinterface_1","Call-ID":"YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM.","User-Agent":"X-Lite Beta release 4.0 v3 stamp 55153","Status":"Registered(UDP)","Realm":"whistle1.vinixtech.com","Username":"xlite_1","Network-Port":"29712","Network-IP":"98.254.245.79","To-Host":"whistle1.vinixtech.com","To-User":"xlite_1","Expires":"3600","RPid":"unknown","Contact":"\"1000\" ","From-Host":"whistle1.vinixtech.com","From-User":"xlite_1","Event-Timestamp":63474526119.0,"App-Version":"0.7.2","App-Name":"ecallmgr_fs_node","Event-Name":"reg_success","Event-Category":"directory","Server-ID":""} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SRV(<0.8572.3>): handle msg {"FreeSWITCH-Hostname":"whistle1.vinixtech.com","Presence-Hosts":"whistle1.vinixtech.com","Profile-Name":"sipinterface_1","Call-ID":"YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM.","User-Agent":"X-Lite Beta release 4.0 v3 stamp 55153","Status":"Registered(UDP)","Realm":"whistle1.vinixtech.com","Username":"xlite_1","Network-Port":"29712","Network-IP":"98.254.245.79","To-Host":"whistle1.vinixtech.com","To-User":"xlite_1","Expires":"3600","RPid":"unknown","Contact":"\"1000\" ","From-Host":"whistle1.vinixtech.com","From-User":"xlite_1","Event-Timestamp":63474526119.0,"App-Version":"0.7.2","App-Name":"ecallmgr_fs_node","Event-Name":"reg_success","Event-Category":"directory","Server-ID":""} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SRV: cache miss, rm old and save new <<"74479514c4d23525d78d2db81ede0f6e">> 63474529719 Jun 5 20:48:39 whistle1 freeswitch[998]: recv 856 bytes from udp/[98.254.245.79]:29712 at 20:48:39.894580: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: REGISTER sip:whistle1.vinixtech.com SIP/2.0 Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-0bc2ec60c081ba26-1---d8754z-;rport Jun 5 20:48:39 whistle1 freeswitch[998]: Max-Forwards: 70 Jun 5 20:48:39 whistle1 freeswitch[998]: Contact: Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 4 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: Expires: 3600 Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: X-Lite Beta release 4.0 v3 stamp 55153 Jun 5 20:48:39 whistle1 freeswitch[998]: Authorization: Digest username="xlite_1",realm="whistle1.vinixtech.com",nonce="8505e9af-aaea-4be9-80d4-0482104cdd2b",uri="sip:whistle1.vinixtech.com",response="f368a54323c53c7c7cde84cd4245a931",cnonce="808fa4f350f53f8c73b24a7b6c717743",nc=00000003,qop=auth,algorithm=MD5 Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FETCH_USER(<0.200.0>): fetch directory: Id: <<"e4d14993-0489-48fb-9bca-5474e9fd95b5">> Lookup <0.7028.0> (Number 108) Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SRV(<0.8578.3>): handle msg {"Auth-Domain":"whistle1.vinixtech.com","Auth-User":"xlite_1","Orig-IP":"98.254.245.79","From":"xlite_1@whistle1.vinixtech.com","To":"xlite_1@whistle1.vinixtech.com","Msg-ID":"e4d14993-0489-48fb-9bca-5474e9fd95b5","App-Version":"0.5.0","App-Name":"ecallmgr.auth","Event-Name":"auth_req","Event-Category":"directory","Server-ID":"amq.gen-LLuGdp23AF4s9u/ue90hVg=="} Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: WORKER-F(<0.112.0>): Working for <0.7028.0> and sent {"Auth-Domain":"whistle1.vinixtech.com","Auth-User":"xlite_1","Orig-IP":"98.254.245.79","From":"xlite_1@whistle1.vinixtech.com","To":"xlite_1@whistle1.vinixtech.com","Msg-ID":"e4d14993-0489-48fb-9bca-5474e9fd95b5","App-Version":"0.5.0","App-Name":"ecallmgr.auth","Event-Name":"auth_req","Event-Category":"directory","Server-ID":"amq.gen-LLuGdp23AF4s9u/ue90hVg=="} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG.auth: Looking up <<"xlite_1">> @ <<"whistle1.vinixtech.com">> Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.880962 [DEBUG] mod_erlang_event.c:380 looking for bindings Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.880962 [DEBUG] mod_erlang_event.c:395 binding for domain in section directory with key name and value whistle1.vinixtech.com requested from node ecallmgr@whistle1.vinixtech.com Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: AuthInfo: {struct,[{<<"realm">>,<<"whistle1.vinixtech.com">>}, {<<"method">>,<<"password">>}, {<<"username">>,<<"xlite_1">>}, {<<"password">>,<<"Iq150l8a!">>}, {<<"invite_format">>,<<"username">>}, {<<"authorizing_id">>, <<"cbbacd85bf6fcf891220ac6d16005392">>}]} Jun 5 20:48:39 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SERVE(<0.8578.3>): Paylowd to amq.gen-LLuGdp23AF4s9u/ue90hVg==: {"Custom-Channel-Vars":{"Direction":"outbound","Username":"xlite_1","Realm":"whistle1.vinixtech.com","Authorizing-ID":"cbbacd85bf6fcf891220ac6d16005392"},"Auth-Password":"Iq150l8a!","Auth-Method":"password","Msg-ID":"e4d14993-0489-48fb-9bca-5474e9fd95b5","Tenant-ID":"ignore","Access-Group":"ignore","App-Version":"0.4.2","App-Name":"registrar","Event-Name":"auth_resp","Event-Category":"directory","Server-ID":"registrar.queue"} Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: WORKER-B(<0.112.0>): Recv payload response (20 ms) Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.905619 [DEBUG] handle_msg.c:190 Got reply for e4d14993-0489-48fb-9bca-5474e9fd95b5 Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.905619 [DEBUG] mod_erlang_event.c:466 got data
after 24 milliseconds from ecallmgr@whistle1.vinixtech.com for e4d14993-0489-48fb-9bca-5474e9fd95b5! Jun 5 20:48:39 whistle1 freeswitch[998]: 2011-06-05 20:48:39.905619 [DEBUG] mod_erlang_event.c:473 XML parsed OK! Jun 5 20:48:39 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FS_AUTH(<0.200.0>): Lookup <0.7028.0> down Jun 5 20:48:39 whistle1 freeswitch[998]: send 644 bytes to udp/[98.254.245.79]:29712 at 20:48:39.925193: ------------------------------------------------------------------------ Jun 5 20:48:39 whistle1 freeswitch[998]: SIP/2.0 200 OK Jun 5 20:48:39 whistle1 freeswitch[998]: Via: SIP/2.0/UDP 192.168.1.105:29712;branch=z9hG4bK-d8754z-0bc2ec60c081ba26-1---d8754z-;rport=29712;received=98.254.245.79 Jun 5 20:48:39 whistle1 freeswitch[998]: From: "1000";tag=e40c4773 Jun 5 20:48:39 whistle1 freeswitch[998]: To: "1000" ;tag=H4ZHB3gDpNvjc Jun 5 20:48:39 whistle1 freeswitch[998]: Call-ID: YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM. Jun 5 20:48:39 whistle1 freeswitch[998]: CSeq: 4 REGISTER Jun 5 20:48:39 whistle1 freeswitch[998]: Contact: ;expires=3600 Jun 5 20:48:39 whistle1 freeswitch[998]: Date: Sun, 05 Jun 2011 20:48:39 GMT Jun 5 20:48:39 whistle1 freeswitch[998]: User-Agent: sip.2600hz.com Jun 5 20:48:39 whistle1 freeswitch[998]: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Jun 5 20:48:39 whistle1 freeswitch[998]: Supported: precondition, path, replaces Jun 5 20:48:39 whistle1 freeswitch[998]: Content-Length: 0 Jun 5 20:48:39 whistle1 freeswitch[998]: ------------------------------------------------------------------------ Jun 5 20:48:40 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FS_NODE(<0.202.0>): Evt: <<"CUSTOM">> Jun 5 20:48:40 whistle1 ecallmgr@whistle1.vinixtech.com[1333]: FS_NODE.p_reg_evt(<0.7031.0>): {"FreeSWITCH-Hostname":"whistle1.vinixtech.com","Presence-Hosts":"whistle1.vinixtech.com","Profile-Name":"sipinterface_1","Call-ID":"YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM.","User-Agent":"X-Lite Beta release 4.0 v3 stamp 55153","Status":"Registered(UDP)","Realm":"whistle1.vinixtech.com","Username":"xlite_1","Network-Port":"29712","Network-IP":"98.254.245.79","To-Host":"whistle1.vinixtech.com","To-User":"xlite_1","Expires":"3600","RPid":"unknown","Contact":"\"1000\" ","From-Host":"whistle1.vinixtech.com","From-User":"xlite_1","Event-Timestamp":63474526120.0,"App-Version":"0.7.2","App-Name":"ecallmgr_fs_node","Event-Name":"reg_success","Event-Category":"directory","Server-ID":""} Jun 5 20:48:40 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SRV(<0.8583.3>): handle msg {"FreeSWITCH-Hostname":"whistle1.vinixtech.com","Presence-Hosts":"whistle1.vinixtech.com","Profile-Name":"sipinterface_1","Call-ID":"YTI5ZGM0ZjllNjBjMzhjOTI2YWI0OWZmMThhOTIyOGM.","User-Agent":"X-Lite Beta release 4.0 v3 stamp 55153","Status":"Registered(UDP)","Realm":"whistle1.vinixtech.com","Username":"xlite_1","Network-Port":"29712","Network-IP":"98.254.245.79","To-Host":"whistle1.vinixtech.com","To-User":"xlite_1","Expires":"3600","RPid":"unknown","Contact":"\"1000\" ","From-Host":"whistle1.vinixtech.com","From-User":"xlite_1","Event-Timestamp":63474526120.0,"App-Version":"0.7.2","App-Name":"ecallmgr_fs_node","Event-Name":"reg_success","Event-Category":"directory","Server-ID":""} Jun 5 20:48:40 whistle1 whistle_apps@whistle1.vinixtech.com[1499]: REG_SRV: cache miss, rm old and save new <<"e56f72352f6cbe6130318240a74272cc">> 63474529720 ******************** BIGCOUCH LOG ************************* [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12853.1>] [bfe731a7] undefined - - 'GET' /sip_auth/_design/credentials/_view/lookup?key=%5B%22whistle1.vinixtech.com%22%2C%22xlite_1%22%5D 200 [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12853.1>] [bfe731a7] 127.0.0.1 localhost:5984 GET /sip_auth/_design/credentials/_view/lookup?key=%5B%22whistle1.vinixtech.com%22%2C%22xlite_1%22%5D 200 6 [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12867.1>] [ce6feb0a] undefined - - 'HEAD' /registrations/e56f72352f6cbe6130318240a74272cc 200 [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12867.1>] [ce6feb0a] 127.0.0.1 localhost:5984 HEAD /registrations/e56f72352f6cbe6130318240a74272cc 200 5 [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12896.1>] [e78825b0] undefined - - 'POST' /registrations/_bulk_docs 201 [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12896.1>] [e78825b0] 127.0.0.1 localhost:5984 POST /registrations/_bulk_docs 201 14 [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12898.1>] [ddeb9be9] undefined - - 'PUT' /registrations/d3a9de8b11a77af0156e4a801f1c4dce 201 [Sun, 05 Jun 2011 21:07:13 GMT] [info] [<0.12898.1>] [ddeb9be9] 127.0.0.1 localhost:5984 PUT /registrations/d3a9de8b11a77af0156e4a801f1c4dce 201 10